2023-01-26T16:56:14.686Z,1674752174.686 [Supervisor](DEBUG): Initializing supervisor. 2023-01-26T16:56:14.691Z,1674752174.691 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-01-26T16:56:14.692Z,1674752174.692 [SyncHandler](INFO): Protected caller Thread ID is 2772 2023-01-26T16:56:14.693Z,1674752174.693 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-01-26T16:56:14.694Z,1674752174.694 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-01-26T16:56:14.694Z,1674752174.694 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2773 2023-01-26T16:56:14.699Z,1674752174.699 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-01-26T16:56:14.717Z,1674752174.717 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-01-26T16:56:14.718Z,1674752174.718 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-01-26T16:56:14.718Z,1674752174.718 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2774 2023-01-26T16:56:14.722Z,1674752174.722 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-01-26T16:56:14.723Z,1674752174.723 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-01-26T16:56:14.724Z,1674752174.724 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2775 2023-01-26T16:56:14.726Z,1674752174.726 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-01-26T16:56:14.727Z,1674752174.727 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-01-26T16:56:14.727Z,1674752174.727 [logger ThreadHandler](INFO): Protected caller Thread ID is 2776 2023-01-26T16:56:14.731Z,1674752174.731 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-01-26T16:56:14.731Z,1674752174.731 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-01-26T16:56:14.733Z,1674752174.733 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-01-26T16:56:15.017Z,1674752175.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-01-26T16:56:15.018Z,1674752175.018 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-01-26T16:56:15.126Z,1674752175.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-01-26T16:56:15.127Z,1674752175.127 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-01-26T16:56:15.223Z,1674752175.223 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-01-26T16:56:15.224Z,1674752175.224 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-01-26T16:56:15.690Z,1674752175.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-01-26T16:56:15.690Z,1674752175.690 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-01-26T16:56:16.039Z,1674752176.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-01-26T16:56:16.040Z,1674752176.040 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-01-26T16:56:16.384Z,1674752176.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-01-26T16:56:16.385Z,1674752176.385 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-01-26T16:56:16.578Z,1674752176.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-01-26T16:56:16.579Z,1674752176.579 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-01-26T16:56:16.660Z,1674752176.660 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-01-26T16:56:16.742Z,1674752176.742 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-01-26T16:56:17.143Z,1674752177.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-01-26T16:56:17.143Z,1674752177.143 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-01-26T16:56:17.443Z,1674752177.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-01-26T16:56:17.443Z,1674752177.443 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-01-26T16:56:18.353Z,1674752178.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-01-26T16:56:18.353Z,1674752178.353 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-01-26T16:56:18.571Z,1674752178.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-01-26T16:56:18.571Z,1674752178.571 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-01-26T16:56:18.818Z,1674752178.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-01-26T16:56:18.819Z,1674752178.819 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-01-26T16:56:18.959Z,1674752178.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-01-26T16:56:18.959Z,1674752178.959 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-01-26T16:56:19.738Z,1674752179.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-01-26T16:56:19.740Z,1674752179.740 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2023-01-26T16:56:19.741Z,1674752179.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2023-01-26T16:56:19.826Z,1674752179.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2023-01-26T16:56:20.011Z,1674752180.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2023-01-26T16:56:20.276Z,1674752180.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-01-26T16:56:20.277Z,1674752180.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2023-01-26T16:56:20.402Z,1674752180.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2023-01-26T16:56:20.504Z,1674752180.504 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2023-01-26T16:56:20.599Z,1674752180.599 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2023-01-26T16:56:20.714Z,1674752180.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2023-01-26T16:56:20.810Z,1674752180.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2023-01-26T16:56:20.898Z,1674752180.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2023-01-26T16:56:20.999Z,1674752180.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2023-01-26T16:56:21.163Z,1674752181.163 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2023-01-26T16:56:21.163Z,1674752181.163 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-01-26T16:56:21.176Z,1674752181.176 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-01-26T16:56:21.228Z,1674752181.228 [DepthRateCalculator] Loaded 2023-01-26T16:56:21.228Z,1674752181.228 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-01-26T16:56:21.234Z,1674752181.234 [PitchRateCalculator] Loaded 2023-01-26T16:56:21.234Z,1674752181.234 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-01-26T16:56:21.244Z,1674752181.244 [SpeedCalculator] Loaded 2023-01-26T16:56:21.244Z,1674752181.244 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-01-26T16:56:21.249Z,1674752181.249 [YawRateCalculator] Loaded 2023-01-26T16:56:21.249Z,1674752181.249 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-01-26T16:56:21.270Z,1674752181.270 [ElevatorOffsetCalculator] Loaded 2023-01-26T16:56:21.271Z,1674752181.271 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-01-26T16:56:21.271Z,1674752181.271 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-01-26T16:56:21.272Z,1674752181.272 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-01-26T16:56:21.299Z,1674752181.299 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-01-26T16:56:21.299Z,1674752181.299 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-01-26T16:56:21.310Z,1674752181.310 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-01-26T16:56:21.310Z,1674752181.310 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-01-26T16:56:21.762Z,1674752181.762 [AHRS_M2] Loaded 2023-01-26T16:56:21.763Z,1674752181.763 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-01-26T16:56:21.798Z,1674752181.798 [BackseatComponent] Loaded 2023-01-26T16:56:21.798Z,1674752181.798 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-01-26T16:56:21.799Z,1674752181.799 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4083F4E0 2023-01-26T16:56:21.799Z,1674752181.799 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2858 2023-01-26T16:56:21.802Z,1674752181.802 [LcmUniversalReporter] Loaded 2023-01-26T16:56:21.802Z,1674752181.802 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-01-26T16:56:22.602Z,1674752182.602 [BPC1] Loaded 2023-01-26T16:56:22.603Z,1674752182.603 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-01-26T16:56:22.943Z,1674752182.943 [DataOverHttps] Loaded 2023-01-26T16:56:22.943Z,1674752182.943 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-01-26T16:56:22.944Z,1674752182.944 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0 2023-01-26T16:56:22.945Z,1674752182.945 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2859 2023-01-26T16:56:22.965Z,1674752182.965 [Depth_Keller] Loaded 2023-01-26T16:56:22.965Z,1674752182.965 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-01-26T16:56:23.191Z,1674752183.191 [NAL9602] Loaded 2023-01-26T16:56:23.192Z,1674752183.192 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-01-26T16:56:23.221Z,1674752183.221 [Onboard] Loaded 2023-01-26T16:56:23.221Z,1674752183.221 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-01-26T16:56:23.222Z,1674752183.222 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4089F4E0 2023-01-26T16:56:23.223Z,1674752183.223 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2860 2023-01-26T16:56:23.229Z,1674752183.229 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2023-01-26T16:56:23.239Z,1674752183.239 [PowerOnly] Loaded 2023-01-26T16:56:23.240Z,1674752183.240 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-01-26T16:56:23.251Z,1674752183.251 [Power24vConverter] Loaded 2023-01-26T16:56:23.252Z,1674752183.252 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-01-26T16:56:23.291Z,1674752183.291 [Radio_Surface] Loaded 2023-01-26T16:56:23.291Z,1674752183.291 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-01-26T16:56:23.364Z,1674752183.364 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408CF4E0 2023-01-26T16:56:23.365Z,1674752183.365 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2861 2023-01-26T16:56:23.472Z,1674752183.472 [Waterlinked] Loaded 2023-01-26T16:56:23.485Z,1674752183.485 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2023-01-26T16:56:23.485Z,1674752183.485 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-01-26T16:56:23.485Z,1674752183.485 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-01-26T16:56:23.639Z,1674752183.639 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-01-26T16:56:23.640Z,1674752183.640 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-01-26T16:56:23.681Z,1674752183.681 [DeadReckonUsingSpeedCalculator] Loaded 2023-01-26T16:56:23.681Z,1674752183.681 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-01-26T16:56:23.694Z,1674752183.694 [NavChart] Loaded 2023-01-26T16:56:23.695Z,1674752183.695 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-01-26T16:56:23.700Z,1674752183.700 [UniversalFixResidualReporter] Loaded 2023-01-26T16:56:23.701Z,1674752183.701 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-01-26T16:56:23.701Z,1674752183.701 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-01-26T16:56:23.702Z,1674752183.702 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-01-26T16:56:23.846Z,1674752183.846 [SBIT](DEBUG): Construct Startup Built In Test. 2023-01-26T16:56:23.860Z,1674752183.860 [SBIT] Loaded 2023-01-26T16:56:23.861Z,1674752183.861 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-01-26T16:56:23.863Z,1674752183.863 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-01-26T16:56:23.876Z,1674752183.876 [IBIT] Loaded 2023-01-26T16:56:23.876Z,1674752183.876 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-01-26T16:56:23.882Z,1674752183.882 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-01-26T16:56:23.983Z,1674752183.983 [CBIT] Loaded 2023-01-26T16:56:23.984Z,1674752183.984 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-01-26T16:56:23.984Z,1674752183.984 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-01-26T16:56:23.985Z,1674752183.985 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-01-26T16:56:24.162Z,1674752184.162 [CTD_Seabird] Loaded 2023-01-26T16:56:24.162Z,1674752184.162 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-01-26T16:56:24.163Z,1674752184.163 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409D34E0 2023-01-26T16:56:24.164Z,1674752184.164 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2862 2023-01-26T16:56:24.184Z,1674752184.184 [PAR_Licor] Loaded 2023-01-26T16:56:24.184Z,1674752184.184 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-01-26T16:56:24.215Z,1674752184.215 [WetLabsBB2FL] Loaded 2023-01-26T16:56:24.215Z,1674752184.215 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-01-26T16:56:24.216Z,1674752184.216 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A034E0 2023-01-26T16:56:24.217Z,1674752184.217 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2863 2023-01-26T16:56:24.218Z,1674752184.218 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-01-26T16:56:24.218Z,1674752184.218 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-01-26T16:56:24.355Z,1674752184.355 [BuoyancyServo] Loaded 2023-01-26T16:56:24.355Z,1674752184.355 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-01-26T16:56:24.377Z,1674752184.377 [ElevatorServo] Loaded 2023-01-26T16:56:24.377Z,1674752184.377 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-01-26T16:56:24.398Z,1674752184.398 [MassServo] Loaded 2023-01-26T16:56:24.398Z,1674752184.398 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-01-26T16:56:24.418Z,1674752184.418 [RudderServo] Loaded 2023-01-26T16:56:24.419Z,1674752184.419 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-01-26T16:56:24.436Z,1674752184.436 [ThrusterHE] Loaded 2023-01-26T16:56:24.436Z,1674752184.436 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-01-26T16:56:24.436Z,1674752184.436 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-01-26T16:56:24.437Z,1674752184.437 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-01-26T16:56:24.491Z,1674752184.491 [VerticalControl](DEBUG): Construct VerticalControl. 2023-01-26T16:56:24.541Z,1674752184.541 [VerticalControl] Loaded 2023-01-26T16:56:24.542Z,1674752184.542 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-01-26T16:56:24.545Z,1674752184.545 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-01-26T16:56:24.582Z,1674752184.582 [HorizontalControl] Loaded 2023-01-26T16:56:24.582Z,1674752184.582 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-01-26T16:56:24.585Z,1674752184.585 [SpeedControl](DEBUG): Construct SpeedControl. 2023-01-26T16:56:24.586Z,1674752184.586 [SpeedControl] Loaded 2023-01-26T16:56:24.587Z,1674752184.587 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-01-26T16:56:24.590Z,1674752184.590 [LoopControl](DEBUG): Construct LoopControl. 2023-01-26T16:56:24.590Z,1674752184.590 [LoopControl] Loaded 2023-01-26T16:56:24.590Z,1674752184.590 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-01-26T16:56:24.591Z,1674752184.591 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-01-26T16:56:24.591Z,1674752184.591 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-01-26T16:56:24.695Z,1674752184.695 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-01-26T16:56:24.695Z,1674752184.695 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-01-26T16:56:24.850Z,1674752184.850 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-01-26T16:56:24.850Z,1674752184.850 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-01-26T16:56:25.164Z,1674752185.164 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-01-26T16:56:25.164Z,1674752185.164 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-01-26T16:56:25.223Z,1674752185.223 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-01-26T16:56:25.229Z,1674752185.229 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-01-26T16:56:25.234Z,1674752185.234 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-01-26T16:56:25.245Z,1674752185.245 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-01-26T16:56:25.246Z,1674752185.246 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA64E0 2023-01-26T16:56:25.246Z,1674752185.246 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2864 2023-01-26T16:56:25.251Z,1674752185.251 [Supervisor](INFO): Main Thread ID is 8762 2023-01-26T16:56:25.251Z,1674752185.251 [Supervisor](DEBUG): Running supervisor. 2023-01-26T16:56:25.251Z,1674752185.251 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2865 2023-01-26T16:56:25.252Z,1674752185.252 [CommandExec](INFO): Initializing the command executive. 2023-01-26T16:56:25.254Z,1674752185.254 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2866 2023-01-26T16:56:25.256Z,1674752185.256 [controlThread ThreadHandler](INFO): Handler Thread ID is 2867 2023-01-26T16:56:25.256Z,1674752185.256 [controlThread](DEBUG): Initializing ControlThread 2023-01-26T16:56:25.257Z,1674752185.257 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-01-26T16:56:25.258Z,1674752185.258 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-01-26T16:56:25.258Z,1674752185.258 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-01-26T16:56:25.258Z,1674752185.258 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-01-26T16:56:25.259Z,1674752185.259 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-01-26T16:56:25.262Z,1674752185.262 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T16:56:25.263Z,1674752185.263 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-01-26T16:56:25.263Z,1674752185.263 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-01-26T16:56:25.264Z,1674752185.264 [SBIT](INFO): Initialize SBIT Component. 2023-01-26T16:56:25.264Z,1674752185.264 [SBIT](IMPORTANT): git: 2023-01-26 2023-01-26T16:56:25.264Z,1674752185.264 [SBIT](INFO): git hash: fbcd350f88251f9a867f3fca05699dec8f096e0d 2023-01-26T16:56:25.265Z,1674752185.265 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-01-26T16:56:25.266Z,1674752185.266 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-01-26T16:56:25.267Z,1674752185.267 [SBIT](INFO): Beginning SBIT in 95.000000 seconds. 2023-01-26T16:56:25.268Z,1674752185.268 [IBIT](INFO): Initialize IBIT Component. 2023-01-26T16:56:25.269Z,1674752185.269 [CBIT](DEBUG): Initialize CBIT Component. 2023-01-26T16:56:25.270Z,1674752185.270 [logger ThreadHandler](INFO): Handler Thread ID is 2868 2023-01-26T16:56:25.280Z,1674752185.280 [CBIT](DEBUG): Initialized mux pins. 2023-01-26T16:56:25.281Z,1674752185.281 [CBIT](DEBUG): Initializing the watchdog timer. 2023-01-26T16:56:25.289Z,1674752185.289 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2869 2023-01-26T16:56:25.301Z,1674752185.301 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2870 2023-01-26T16:56:25.302Z,1674752185.302 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-01-26T16:56:25.305Z,1674752185.305 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-01-26T16:56:25.305Z,1674752185.305 [CBIT](DEBUG): Initializing heartbeat. 2023-01-26T16:56:25.313Z,1674752185.313 [Onboard ThreadHandler](INFO): Handler Thread ID is 2871 2023-01-26T16:56:25.330Z,1674752185.330 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2872 2023-01-26T16:56:25.353Z,1674752185.353 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2873 2023-01-26T16:56:25.354Z,1674752185.354 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-01-26T16:56:25.358Z,1674752185.358 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2875 2023-01-26T16:56:25.361Z,1674752185.361 [WetLabsBB2FL](INFO): Powering up 2023-01-26T16:56:25.362Z,1674752185.362 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2877 2023-01-26T16:56:25.369Z,1674752185.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-01-26T16:56:25.370Z,1674752185.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-01-26T16:56:25.370Z,1674752185.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-01-26T16:56:25.370Z,1674752185.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-01-26T16:56:25.370Z,1674752185.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-01-26T16:56:25.370Z,1674752185.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-01-26T16:56:25.370Z,1674752185.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-01-26T16:56:25.370Z,1674752185.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-01-26T16:56:25.376Z,1674752185.376 [CBIT](DEBUG): Deactivating GF circuits. 2023-01-26T16:56:25.377Z,1674752185.377 [CBIT](DEBUG): Deactivating emergency mode. 2023-01-26T16:56:25.412Z,1674752185.412 [CBIT](DEBUG): Backplane powered. 2023-01-26T16:56:25.418Z,1674752185.418 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-01-26T16:56:25.420Z,1674752185.420 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-01-26T16:56:25.421Z,1674752185.421 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-01-26T16:56:25.421Z,1674752185.421 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-01-26T16:56:25.422Z,1674752185.422 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-01-26T16:56:25.451Z,1674752185.451 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T16:56:25.489Z,1674752185.489 [MissionManager](DEBUG): 2023-01-26T16:56:25.490Z,1674752185.490 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-01-26T16:56:25.552Z,1674752185.552 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-01-26T16:56:25.562Z,1674752185.562 [Default:A.Wait](DEBUG): Construct Wait. 2023-01-26T16:56:25.564Z,1674752185.564 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-01-26T16:56:25.580Z,1674752185.580 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-01-26T16:56:25.609Z,1674752185.609 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-01-26T16:56:25.615Z,1674752185.615 [Default:E.Execute](DEBUG): Construct Execute. 2023-01-26T16:56:25.630Z,1674752185.630 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-01-26T16:56:25.635Z,1674752185.635 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-01-26T16:56:25.653Z,1674752185.653 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-01-26T16:56:25.713Z,1674752185.713 [Power24vConverter](INFO): Powering up. 2023-01-26T16:56:25.745Z,1674752185.745 [Radio_Surface](INFO): Powering up 2023-01-26T16:56:25.752Z,1674752185.752 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-01-26T16:56:25.754Z,1674752185.754 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-01-26T16:56:25.755Z,1674752185.755 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-01-26T16:56:25.761Z,1674752185.761 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-01-26T16:56:25.762Z,1674752185.762 [MassServo](DEBUG): Initializing EZServoServo. 2023-01-26T16:56:25.769Z,1674752185.769 [MassServo](DEBUG): Initializing MassServo. 2023-01-26T16:56:25.770Z,1674752185.770 [RudderServo](DEBUG): Initializing EZServoServo. 2023-01-26T16:56:25.777Z,1674752185.777 [RudderServo](DEBUG): Initializing RudderServo. 2023-01-26T16:56:25.778Z,1674752185.778 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-01-26T16:56:25.785Z,1674752185.785 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-01-26T16:56:27.973Z,1674752187.973 [WetLabsBB2FL](INFO): Powering down 2023-01-26T16:56:31.513Z,1674752191.513 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-01-26T16:56:32.385Z,1674752192.385 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-01-26T16:56:53.534Z,1674752213.534 [NAL9602](INFO): Powering up NAL9602 2023-01-26T16:56:57.431Z,1674752217.431 [CommandExec](IMPORTANT): got command show variable waterlinked 2023-01-26T16:56:57.523Z,1674752217.523 [CommandExec](IMPORTANT): Waterlinked.loadControl (none) 2023-01-26T16:56:57.523Z,1674752217.523 [CommandExec](IMPORTANT): Waterlinked.uart (none) 2023-01-26T16:56:57.524Z,1674752217.524 [CommandExec](IMPORTANT): Waterlinked.baud (bit_per_second) 2023-01-26T16:56:57.672Z,1674752217.672 [CommandExec](IMPORTANT): Waterlinked.loadAtStartup (bool) 2023-01-26T16:56:57.677Z,1674752217.677 [CommandExec](IMPORTANT): Waterlinked.simulateHardware (bool) 2023-01-26T16:56:57.747Z,1674752217.747 [CommandExec](IMPORTANT): Waterlinked.enableBroadcast (bool) 2023-01-26T16:56:57.748Z,1674752217.748 [CommandExec](IMPORTANT): Waterlinked.component_voltage (volt) 2023-01-26T16:56:57.753Z,1674752217.753 [CommandExec](IMPORTANT): Waterlinked.component_avgVoltage (volt) 2023-01-26T16:56:57.754Z,1674752217.754 [CommandExec](IMPORTANT): Waterlinked.component_current (milliampere) 2023-01-26T16:56:57.754Z,1674752217.754 [CommandExec](IMPORTANT): Waterlinked.component_avgCurrent (milliampere) 2023-01-26T16:56:57.754Z,1674752217.754 [CommandExec](IMPORTANT): Waterlinked.height_above_sea_floor (meter) 2023-01-26T16:56:57.755Z,1674752217.755 [CommandExec](IMPORTANT): Waterlinked.platform_velocity_wrt_ground (meter_per_second) 2023-01-26T16:56:57.755Z,1674752217.755 [CommandExec](IMPORTANT): Waterlinked.platform_x_velocity_wrt_ground (meter_per_second) 2023-01-26T16:56:57.755Z,1674752217.755 [CommandExec](IMPORTANT): Waterlinked.platform_y_velocity_wrt_ground (meter_per_second) 2023-01-26T16:56:57.756Z,1674752217.756 [CommandExec](IMPORTANT): Waterlinked.platform_z_velocity_wrt_ground (meter_per_second) 2023-01-26T16:56:57.761Z,1674752217.761 [CommandExec](IMPORTANT): Waterlinked.BottomVelocityFlag (count) 2023-01-26T16:57:04.431Z,1674752224.431 [NAL9602](INFO): NAL9602 initialized 2023-01-26T16:57:05.661Z,1674752225.661 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-01-26T16:57:08.557Z,1674752228.557 [Waterlinked](INFO): Powering down 2023-01-26T16:57:16.214Z,1674752236.214 [CommandExec](IMPORTANT): got command get Waterlinked.component_voltage 2023-01-26T16:57:16.215Z,1674752236.215 [CommandExec](IMPORTANT): Waterlinked.component_voltage 0.000000 V 2023-01-26T16:57:24.115Z,1674752244.115 [CommandExec](IMPORTANT): got command report touch Waterlinked.component_voltage 2023-01-26T16:57:30.061Z,1674752250.061 [CommandExec](IMPORTANT): got command failComponent hardware Waterlinked 2023-01-26T16:57:30.062Z,1674752250.062 [Waterlinked] Hardware Fault, FailCount= 1 2023-01-26T16:57:30.062Z,1674752250.062 [Waterlinked](ERROR): Hardware Fault 2023-01-26T16:57:30.062Z,1674752250.062 [CommandExec](IMPORTANT): Waterlinked failureMode is Hardware Fault 2023-01-26T16:57:30.320Z,1674752250.320 [CBIT](ERROR): Hardware Fault in component: Waterlinked 2023-01-26T16:57:30.320Z,1674752250.320 [CBIT](INFO): Clearing failed state for component Waterlinked 2023-01-26T16:57:30.320Z,1674752250.320 [Waterlinked] No Fault, FailCount= 1 2023-01-26T16:57:55.775Z,1674752275.775 [Reporter](INFO): Waterlinked.component_voltage 16.919220 V 2023-01-26T16:57:58.199Z,1674752278.199 [Reporter](INFO): Waterlinked.component_voltage 16.919220 V 2023-01-26T16:58:00.687Z,1674752280.687 [SBIT](IMPORTANT): Beginning Startup BIT 2023-01-26T16:58:00.696Z,1674752280.696 [CBIT](IMPORTANT): Beginning ground fault scan 2023-01-26T16:58:00.697Z,1674752280.697 [Reporter](INFO): Waterlinked.component_voltage 16.743240 V 2023-01-26T16:58:03.178Z,1674752283.178 [Reporter](INFO): Waterlinked.component_voltage 16.567261 V 2023-01-26T16:58:05.715Z,1674752285.715 [Reporter](INFO): Waterlinked.component_voltage 16.516981 V 2023-01-26T16:58:08.040Z,1674752288.040 [Reporter](INFO): Waterlinked.component_voltage 16.768381 V 2023-01-26T16:58:10.362Z,1674752290.362 [Reporter](INFO): Waterlinked.component_voltage 16.768381 V 2023-01-26T16:58:11.629Z,1674752291.629 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.002731 CHAN A1 (24V): 0.002997 CHAN A2 (12V): -0.005649 CHAN A3 (5V): -0.003014 CHAN B0 (3.3V): -0.002041 CHAN B1 (3.15aV): -0.003707 CHAN B2 (3.15bV): -0.002376 CHAN B3 (GND): -0.001228 OPEN: 0.003704 Full Scale: +/- 1 mA 2023-01-26T16:58:12.902Z,1674752292.902 [Reporter](INFO): Waterlinked.component_voltage 16.441561 V 2023-01-26T16:58:15.192Z,1674752295.192 [Reporter](INFO): Waterlinked.component_voltage 16.843800 V 2023-01-26T16:58:16.045Z,1674752296.045 [Waterlinked](INFO): Powering down 2023-01-26T16:58:16.206Z,1674752296.206 [Reporter](INFO): Waterlinked.component_voltage 0.000000 V 2023-01-26T16:58:33.386Z,1674752313.386 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-01-26T16:58:53.184Z,1674752333.184 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004753 2023-01-26T16:58:54.768Z,1674752334.768 [SBIT](IMPORTANT): SBIT PASSED 2023-01-26T16:58:54.768Z,1674752334.768 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-01-26T16:58:54.769Z,1674752334.769 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour; 2023-01-26T16:58:54.769Z,1674752334.769 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2023-01-26T16:58:54.769Z,1674752334.769 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2023-01-26T16:58:54.770Z,1674752334.770 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2023-01-26T16:58:54.770Z,1674752334.770 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-01-26T16:58:54.770Z,1674752334.770 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-01-26T16:58:54.770Z,1674752334.770 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_shape 10.000000 count_per_second; 2023-01-26T16:58:54.770Z,1674752334.770 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_slope 0.200000 count_per_millimeter; 2023-01-26T16:58:54.770Z,1674752334.770 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=274.275554 cubic_centimeter; 2023-01-26T16:58:54.770Z,1674752334.770 [SBIT](IMPORTANT): VerticalControl.massDefault=18.860602 millimeter; 2023-01-26T16:58:54.770Z,1674752334.770 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=1 bool; 2023-01-26T16:58:55.175Z,1674752335.175 [MissionManager](IMPORTANT): Started mission Startup 2023-01-26T16:58:55.175Z,1674752335.175 [Startup] Running Loop=1 2023-01-26T16:58:55.175Z,1674752335.175 [Startup](DEBUG): Aggregate::initialize Startup 2023-01-26T16:58:55.175Z,1674752335.175 [Startup:A.GoToSurface] Running Loop=1 2023-01-26T16:58:55.175Z,1674752335.175 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T16:58:55.176Z,1674752335.176 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T16:58:55.176Z,1674752335.176 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T16:58:55.177Z,1674752335.177 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T16:58:55.177Z,1674752335.177 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T16:58:55.177Z,1674752335.177 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T16:58:55.179Z,1674752335.179 [Startup:StartupSatComms] Running Loop=1 2023-01-26T16:58:55.179Z,1674752335.179 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-01-26T16:58:55.179Z,1674752335.179 [Startup:StartupSatComms:A] Running Loop=1 2023-01-26T16:58:55.560Z,1674752335.560 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-01-26T16:59:25.473Z,1674752365.473 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-01-26T16:59:25.475Z,1674752365.475 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T16:59:25.489Z,1674752365.489 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-01-26T16:59:25.489Z,1674752365.489 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T16:59:25.536Z,1674752365.536 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T16:59:25.537Z,1674752365.537 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T16:59:25.888Z,1674752365.888 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T16:59:25.888Z,1674752365.888 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-01-26T16:59:25.893Z,1674752365.893 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T16:59:25.893Z,1674752365.893 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-01-26T16:59:26.269Z,1674752366.269 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T16:59:55.362Z,1674752395.362 [Startup:StartupSatComms:A](INFO): Timed out from 2023-01-26T16:58:55.2Z 2023-01-26T16:59:55.362Z,1674752395.362 [Startup:StartupSatComms:A] Stopped 2023-01-26T16:59:55.362Z,1674752395.362 [Startup:StartupSatComms:B] Running Loop=1 2023-01-26T16:59:55.783Z,1674752395.783 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-01-26T17:00:42.344Z,1674752442.344 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-01-26T17:00:55.728Z,1674752455.728 [Startup:StartupSatComms:B](INFO): Timed out from 2023-01-26T16:59:55.4Z 2023-01-26T17:00:55.728Z,1674752455.728 [Startup:StartupSatComms:B] Stopped 2023-01-26T17:00:55.728Z,1674752455.728 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-01-26T17:00:55.728Z,1674752455.728 [Startup:StartupSatComms] Stopped 2023-01-26T17:00:55.728Z,1674752455.728 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-01-26T17:00:55.765Z,1674752455.765 [Startup](INFO): Completed Startup 2023-01-26T17:00:55.766Z,1674752455.766 [MissionManager](INFO): Startup is completed. 2023-01-26T17:00:55.766Z,1674752455.766 [MissionManager](INFO): Uninitializing Mission Startup 2023-01-26T17:00:55.766Z,1674752455.766 [Startup] Stopped 2023-01-26T17:00:55.766Z,1674752455.766 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-01-26T17:00:55.766Z,1674752455.766 [Startup:A.GoToSurface] Stopped 2023-01-26T17:00:55.766Z,1674752455.766 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-01-26T17:00:56.056Z,1674752456.056 [MissionManager](IMPORTANT): Started mission Default 2023-01-26T17:00:56.065Z,1674752456.065 [Default] Running Loop=1 2023-01-26T17:00:56.065Z,1674752456.065 [Default](DEBUG): Aggregate::initialize Default 2023-01-26T17:00:56.065Z,1674752456.065 [Default:B.GoToSurface] Running Loop=1 2023-01-26T17:00:56.065Z,1674752456.065 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-01-26T17:00:56.065Z,1674752456.065 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-01-26T17:00:56.065Z,1674752456.065 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-01-26T17:00:56.066Z,1674752456.066 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-01-26T17:00:56.066Z,1674752456.066 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-01-26T17:00:56.066Z,1674752456.066 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-01-26T17:00:56.067Z,1674752456.067 [Default:A.Wait] Running Loop=1 2023-01-26T17:00:56.067Z,1674752456.067 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-01-26T17:01:09.416Z,1674752469.416 [Default:A.Wait](INFO): Done Waiting. 2023-01-26T17:01:09.416Z,1674752469.416 [Default:A.Wait] Stopped 2023-01-26T17:01:09.416Z,1674752469.416 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-01-26T17:01:09.798Z,1674752469.798 [Default:CheckIn] Running Loop=1 2023-01-26T17:01:09.798Z,1674752469.798 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-01-26T17:01:09.798Z,1674752469.798 [Default:CheckIn:Read_GPS] Running Loop=1 2023-01-26T17:01:10.167Z,1674752470.167 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-01-26T17:01:43.317Z,1674752503.317 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-01-26T17:01:56.247Z,1674752516.247 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-01-26T17:02:07.914Z,1674752527.914 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-01-26T17:02:26.511Z,1674752546.511 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-01-26T17:02:26.511Z,1674752546.511 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:02:26.512Z,1674752546.512 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-01-26T17:02:26.512Z,1674752546.512 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:02:26.523Z,1674752546.523 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:02:26.523Z,1674752546.523 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:02:26.922Z,1674752546.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:02:26.922Z,1674752546.922 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-01-26T17:02:26.922Z,1674752546.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:02:26.922Z,1674752546.922 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-01-26T17:02:27.310Z,1674752547.310 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:02:44.726Z,1674752564.726 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-01-26T17:03:25.160Z,1674752605.160 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-01-26T17:04:12.367Z,1674752652.367 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-01-26T17:04:12.375Z,1674752652.375 [BPC1](INFO): Received data from all battery sticks. 2023-01-26T17:05:20.581Z,1674752720.581 [CommandExec](IMPORTANT): got command failComponent 2023-01-26T17:05:20.581Z,1674752720.581 [CommandExec](IMPORTANT): Failed components: 2023-01-26T17:05:20.581Z,1674752720.581 [CommandExec](IMPORTANT): No failed Components. 2023-01-26T17:05:23.390Z,1674752723.390 [CommandExec](IMPORTANT): got command show stack 2023-01-26T17:05:23.390Z,1674752723.390 [CommandExec](IMPORTANT): Behavior Stack: 2023-01-26T17:05:23.390Z,1674752723.390 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2023-01-26T17:05:23.390Z,1674752723.390 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS 2023-01-26T17:05:27.506Z,1674752727.506 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-01-26T17:05:27.506Z,1674752727.506 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:05:27.507Z,1674752727.507 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2023-01-26T17:05:27.507Z,1674752727.507 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:05:27.518Z,1674752727.518 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:05:27.518Z,1674752727.518 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:05:27.858Z,1674752727.858 [CommandExec](IMPORTANT): got command get latitude 2023-01-26T17:05:27.858Z,1674752727.858 [CommandExec](FAULT): Element has no value 2023-01-26T17:05:27.932Z,1674752727.932 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:05:27.933Z,1674752727.933 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-01-26T17:05:27.933Z,1674752727.933 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:05:27.933Z,1674752727.933 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2023-01-26T17:05:28.304Z,1674752728.304 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:06:09.916Z,1674752769.916 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-01-26T17:01:09.8Z 2023-01-26T17:06:09.916Z,1674752769.916 [Default:CheckIn:Read_GPS] Stopped 2023-01-26T17:06:09.916Z,1674752769.916 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-01-26T17:06:10.324Z,1674752770.324 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-01-26T17:06:25.288Z,1674752785.288 [CBIT](FAULT): Could not open coulomb counter log file 2023-01-26T17:07:05.682Z,1674752825.682 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-01-26T17:07:05.682Z,1674752825.682 [NAL9602] Data Fault, FailCount= 1 2023-01-26T17:07:05.682Z,1674752825.682 [NAL9602](ERROR): Data Fault 2023-01-26T17:07:05.701Z,1674752825.701 [CBIT](ERROR): Data Fault in component: NAL9602 2023-01-26T17:07:06.082Z,1674752826.082 [NAL9602](INFO): Powering down 2023-01-26T17:07:06.923Z,1674752826.923 [CBIT](INFO): Clearing failed state for component NAL9602 2023-01-26T17:07:06.923Z,1674752826.923 [NAL9602] No Fault, FailCount= 1 2023-01-26T17:07:09.314Z,1674752829.314 [Power24vConverter](INFO): Powering down. 2023-01-26T17:07:36.382Z,1674752856.382 [NAL9602](INFO): Powering up NAL9602 2023-01-26T17:07:47.294Z,1674752867.294 [NAL9602](INFO): NAL9602 initialized 2023-01-26T17:08:28.514Z,1674752908.514 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-01-26T17:08:28.514Z,1674752908.514 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:08:28.515Z,1674752908.515 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2023-01-26T17:08:28.515Z,1674752908.515 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:08:28.526Z,1674752908.526 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:08:28.526Z,1674752908.526 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:08:28.939Z,1674752908.939 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:08:28.939Z,1674752908.939 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-01-26T17:08:28.939Z,1674752908.939 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:08:28.939Z,1674752908.939 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2023-01-26T17:08:29.317Z,1674752909.317 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:11:29.567Z,1674753089.567 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-01-26T17:11:29.567Z,1674753089.567 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-01-26T17:11:29.568Z,1674753089.568 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2023-01-26T17:11:29.568Z,1674753089.568 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-01-26T17:11:29.579Z,1674753089.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-01-26T17:11:29.580Z,1674753089.580 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-01-26T17:11:29.983Z,1674753089.983 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-01-26T17:11:29.983Z,1674753089.983 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-01-26T17:11:29.983Z,1674753089.983 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-01-26T17:11:29.983Z,1674753089.983 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2023-01-26T17:11:30.384Z,1674753090.384 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-01-26T17:13:44.454Z,1674753224.454 [CommandExec](IMPORTANT): got command restart system 2023-01-26T17:13:46.549Z,1674753226.549 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-01-26T17:13:46.549Z,1674753226.549 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-01-26T17:13:46.549Z,1674753226.549 [CommandExec](INFO): Uninitializing the command executive. 2023-01-26T17:13:46.550Z,1674753226.550 [CommandExec](INFO): Uninitializing the command scheduler. 2023-01-26T17:13:46.550Z,1674753226.550 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-01-26T17:13:46.601Z,1674753226.601 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-01-26T17:13:46.601Z,1674753226.601 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-01-26T17:13:46.601Z,1674753226.601 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-01-26T17