2021-12-07T00:08:27.160Z,1638835707.160 [Supervisor](DEBUG): Initializing supervisor. 2021-12-07T00:08:27.165Z,1638835707.165 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-12-07T00:08:27.165Z,1638835707.165 [SyncHandler](INFO): Protected caller Thread ID is 4358 2021-12-07T00:08:27.166Z,1638835707.166 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-12-07T00:08:27.167Z,1638835707.167 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-12-07T00:08:27.167Z,1638835707.167 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4359 2021-12-07T00:08:27.171Z,1638835707.171 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-12-07T00:08:27.190Z,1638835707.190 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2021-12-07T00:08:27.191Z,1638835707.191 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-12-07T00:08:27.191Z,1638835707.191 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4360 2021-12-07T00:08:27.195Z,1638835707.195 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-12-07T00:08:27.196Z,1638835707.196 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-12-07T00:08:27.197Z,1638835707.197 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4361 2021-12-07T00:08:27.199Z,1638835707.199 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-12-07T00:08:27.200Z,1638835707.200 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2021-12-07T00:08:27.200Z,1638835707.200 [logger ThreadHandler](INFO): Protected caller Thread ID is 4362 2021-12-07T00:08:27.204Z,1638835707.204 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-12-07T00:08:27.204Z,1638835707.204 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-12-07T00:08:27.206Z,1638835707.206 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-12-07T00:08:27.314Z,1638835707.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-12-07T00:08:27.315Z,1638835707.315 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-12-07T00:08:27.907Z,1638835707.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-12-07T00:08:27.907Z,1638835707.907 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-12-07T00:08:28.115Z,1638835708.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-12-07T00:08:28.116Z,1638835708.116 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-12-07T00:08:28.193Z,1638835708.193 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-12-07T00:08:28.314Z,1638835708.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-12-07T00:08:28.315Z,1638835708.315 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-12-07T00:08:28.394Z,1638835708.394 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-12-07T00:08:28.500Z,1638835708.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-12-07T00:08:28.500Z,1638835708.500 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-12-07T00:08:28.854Z,1638835708.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-12-07T00:08:28.855Z,1638835708.855 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-12-07T00:08:29.390Z,1638835709.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-12-07T00:08:29.391Z,1638835709.391 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-12-07T00:08:29.539Z,1638835709.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-12-07T00:08:29.540Z,1638835709.540 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-12-07T00:08:29.723Z,1638835709.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-12-07T00:08:29.724Z,1638835709.724 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-12-07T00:08:30.172Z,1638835710.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-12-07T00:08:30.173Z,1638835710.173 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-12-07T00:08:30.406Z,1638835710.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-12-07T00:08:30.407Z,1638835710.407 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-12-07T00:08:30.607Z,1638835710.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-12-07T00:08:30.607Z,1638835710.607 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-12-07T00:08:30.957Z,1638835710.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-12-07T00:08:30.958Z,1638835710.958 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-12-07T00:08:31.294Z,1638835711.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-12-07T00:08:31.296Z,1638835711.296 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2021-12-07T00:08:31.297Z,1638835711.297 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2021-12-07T00:08:31.379Z,1638835711.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2021-12-07T00:08:31.530Z,1638835711.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2021-12-07T00:08:31.637Z,1638835711.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2021-12-07T00:08:31.722Z,1638835711.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2021-12-07T00:08:31.815Z,1638835711.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2021-12-07T00:08:32.026Z,1638835712.026 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2021-12-07T00:08:32.907Z,1638835712.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-12-07T00:08:32.908Z,1638835712.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2021-12-07T00:08:33.064Z,1638835713.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2021-12-07T00:08:33.455Z,1638835713.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2021-12-07T00:08:33.590Z,1638835713.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2021-12-07T00:08:33.688Z,1638835713.688 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2021-12-07T00:08:33.700Z,1638835713.700 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-12-07T00:08:33.799Z,1638835713.799 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-12-07T00:08:33.800Z,1638835713.800 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-12-07T00:08:33.855Z,1638835713.855 [VerticalControl](DEBUG): Construct VerticalControl. 2021-12-07T00:08:33.917Z,1638835713.917 [VerticalControl] Loaded 2021-12-07T00:08:33.918Z,1638835713.918 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-12-07T00:08:33.920Z,1638835713.920 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-12-07T00:08:33.958Z,1638835713.958 [HorizontalControl] Loaded 2021-12-07T00:08:33.959Z,1638835713.959 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-12-07T00:08:33.961Z,1638835713.961 [SpeedControl](DEBUG): Construct SpeedControl. 2021-12-07T00:08:33.964Z,1638835713.964 [SpeedControl] Loaded 2021-12-07T00:08:33.965Z,1638835713.965 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-12-07T00:08:33.967Z,1638835713.967 [LoopControl](DEBUG): Construct LoopControl. 2021-12-07T00:08:33.968Z,1638835713.968 [LoopControl] Loaded 2021-12-07T00:08:33.968Z,1638835713.968 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-12-07T00:08:33.968Z,1638835713.968 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-12-07T00:08:33.969Z,1638835713.969 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-12-07T00:08:34.012Z,1638835714.012 [DepthRateCalculator] Loaded 2021-12-07T00:08:34.012Z,1638835714.012 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-12-07T00:08:34.017Z,1638835714.017 [PitchRateCalculator] Loaded 2021-12-07T00:08:34.017Z,1638835714.017 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-12-07T00:08:34.027Z,1638835714.027 [SpeedCalculator] Loaded 2021-12-07T00:08:34.028Z,1638835714.028 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-12-07T00:08:34.041Z,1638835714.041 [TempGradientCalculator] Loaded 2021-12-07T00:08:34.041Z,1638835714.041 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-12-07T00:08:34.046Z,1638835714.046 [YawRateCalculator] Loaded 2021-12-07T00:08:34.046Z,1638835714.046 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-12-07T00:08:34.063Z,1638835714.063 [ElevatorOffsetCalculator] Loaded 2021-12-07T00:08:34.063Z,1638835714.063 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-12-07T00:08:34.064Z,1638835714.064 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-12-07T00:08:34.064Z,1638835714.064 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-12-07T00:08:34.093Z,1638835714.093 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-12-07T00:08:34.094Z,1638835714.094 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-12-07T00:08:34.152Z,1638835714.152 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-12-07T00:08:34.153Z,1638835714.153 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-12-07T00:08:34.484Z,1638835714.484 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-12-07T00:08:34.485Z,1638835714.485 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-12-07T00:08:34.591Z,1638835714.591 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-12-07T00:08:34.591Z,1638835714.591 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-12-07T00:08:34.983Z,1638835714.983 [AHRS_M2] Loaded 2021-12-07T00:08:34.983Z,1638835714.983 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-12-07T00:08:35.013Z,1638835715.013 [BackseatComponent] Loaded 2021-12-07T00:08:35.013Z,1638835715.013 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2021-12-07T00:08:35.014Z,1638835715.014 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409474E0 2021-12-07T00:08:35.015Z,1638835715.015 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4444 2021-12-07T00:08:35.018Z,1638835715.018 [LcmUniversalReporter] Loaded 2021-12-07T00:08:35.018Z,1638835715.018 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2021-12-07T00:08:35.806Z,1638835715.806 [BPC1] Loaded 2021-12-07T00:08:35.806Z,1638835715.806 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-12-07T00:08:35.878Z,1638835715.878 [DataOverHttps] Loaded 2021-12-07T00:08:35.878Z,1638835715.878 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-12-07T00:08:35.879Z,1638835715.879 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409774E0 2021-12-07T00:08:35.879Z,1638835715.879 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4445 2021-12-07T00:08:35.899Z,1638835715.899 [Depth_Keller] Loaded 2021-12-07T00:08:35.899Z,1638835715.899 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-12-07T00:08:35.904Z,1638835715.904 [DropWeight] Loaded 2021-12-07T00:08:35.904Z,1638835715.904 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-12-07T00:08:35.963Z,1638835715.963 [NAL9602] Loaded 2021-12-07T00:08:35.964Z,1638835715.964 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-12-07T00:08:35.994Z,1638835715.994 [Onboard] Loaded 2021-12-07T00:08:35.994Z,1638835715.994 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-12-07T00:08:35.995Z,1638835715.995 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409A74E0 2021-12-07T00:08:35.995Z,1638835715.995 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4446 2021-12-07T00:08:36.008Z,1638835716.008 [Power24vConverter] Loaded 2021-12-07T00:08:36.008Z,1638835716.008 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2021-12-07T00:08:36.021Z,1638835716.021 [Radio_Surface] Loaded 2021-12-07T00:08:36.022Z,1638835716.022 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-12-07T00:08:36.023Z,1638835716.023 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409D74E0 2021-12-07T00:08:36.023Z,1638835716.023 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4447 2021-12-07T00:08:36.066Z,1638835716.066 [RDI_Pathfinder] Loaded 2021-12-07T00:08:36.066Z,1638835716.066 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2021-12-07T00:08:36.147Z,1638835716.147 [DAT] Loaded 2021-12-07T00:08:36.147Z,1638835716.147 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-12-07T00:08:36.148Z,1638835716.148 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-12-07T00:08:36.148Z,1638835716.148 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-12-07T00:08:36.228Z,1638835716.228 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-12-07T00:08:36.228Z,1638835716.228 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-12-07T00:08:36.242Z,1638835716.242 [NavChart] Loaded 2021-12-07T00:08:36.242Z,1638835716.242 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-12-07T00:08:36.248Z,1638835716.248 [UniversalFixResidualReporter] Loaded 2021-12-07T00:08:36.248Z,1638835716.248 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-12-07T00:08:36.249Z,1638835716.249 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-12-07T00:08:36.249Z,1638835716.249 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-12-07T00:08:36.398Z,1638835716.398 [SBIT](DEBUG): Construct Startup Built In Test. 2021-12-07T00:08:36.408Z,1638835716.408 [SBIT] Loaded 2021-12-07T00:08:36.408Z,1638835716.408 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-12-07T00:08:36.411Z,1638835716.411 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-12-07T00:08:36.424Z,1638835716.424 [IBIT] Loaded 2021-12-07T00:08:36.425Z,1638835716.425 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-12-07T00:08:36.430Z,1638835716.430 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-12-07T00:08:36.530Z,1638835716.530 [CBIT] Loaded 2021-12-07T00:08:36.530Z,1638835716.530 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-12-07T00:08:36.531Z,1638835716.531 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-12-07T00:08:36.531Z,1638835716.531 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-12-07T00:08:36.663Z,1638835716.663 [BuoyancyServo] Loaded 2021-12-07T00:08:36.664Z,1638835716.664 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-12-07T00:08:36.684Z,1638835716.684 [ElevatorServo] Loaded 2021-12-07T00:08:36.684Z,1638835716.684 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-12-07T00:08:36.703Z,1638835716.703 [MassServo] Loaded 2021-12-07T00:08:36.704Z,1638835716.704 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-12-07T00:08:36.722Z,1638835716.722 [RudderServo] Loaded 2021-12-07T00:08:36.723Z,1638835716.723 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-12-07T00:08:36.741Z,1638835716.741 [ThrusterServo] Loaded 2021-12-07T00:08:36.741Z,1638835716.741 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-12-07T00:08:36.742Z,1638835716.742 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-12-07T00:08:36.743Z,1638835716.743 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-12-07T00:08:36.753Z,1638835716.753 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-12-07T00:08:36.754Z,1638835716.754 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-12-07T00:08:36.928Z,1638835716.928 [CTD_Seabird] Loaded 2021-12-07T00:08:36.929Z,1638835716.929 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-12-07T00:08:36.930Z,1638835716.930 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B104E0 2021-12-07T00:08:36.930Z,1638835716.930 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4448 2021-12-07T00:08:36.950Z,1638835716.950 [PAR_Licor] Loaded 2021-12-07T00:08:36.951Z,1638835716.951 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-12-07T00:08:36.983Z,1638835716.983 [WetLabsBB2FL] Loaded 2021-12-07T00:08:36.983Z,1638835716.983 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-12-07T00:08:36.984Z,1638835716.984 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B404E0 2021-12-07T00:08:36.985Z,1638835716.985 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4449 2021-12-07T00:08:37.005Z,1638835717.005 [WetLabsUBAT] Loaded 2021-12-07T00:08:37.006Z,1638835717.006 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2021-12-07T00:08:37.007Z,1638835717.007 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B704E0 2021-12-07T00:08:37.007Z,1638835717.007 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 4450 2021-12-07T00:08:37.008Z,1638835717.008 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-12-07T00:08:37.022Z,1638835717.022 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-12-07T00:08:37.045Z,1638835717.045 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-12-07T00:08:37.075Z,1638835717.075 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-12-07T00:08:37.076Z,1638835717.076 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA04E0 2021-12-07T00:08:37.077Z,1638835717.077 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4451 2021-12-07T00:08:37.082Z,1638835717.082 [Supervisor](INFO): Main Thread ID is 4357 2021-12-07T00:08:37.082Z,1638835717.082 [Supervisor](DEBUG): Running supervisor. 2021-12-07T00:08:37.083Z,1638835717.083 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4452 2021-12-07T00:08:37.083Z,1638835717.083 [CommandExec](INFO): Initializing the command executive. 2021-12-07T00:08:37.091Z,1638835717.091 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4453 2021-12-07T00:08:37.094Z,1638835717.094 [controlThread ThreadHandler](INFO): Handler Thread ID is 4454 2021-12-07T00:08:37.095Z,1638835717.095 [controlThread](DEBUG): Initializing ControlThread 2021-12-07T00:08:37.095Z,1638835717.095 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-12-07T00:08:37.098Z,1638835717.098 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-12-07T00:08:37.098Z,1638835717.098 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-12-07T00:08:37.099Z,1638835717.099 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-12-07T00:08:37.099Z,1638835717.099 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-12-07T00:08:37.100Z,1638835717.100 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-12-07T00:08:37.100Z,1638835717.100 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-12-07T00:08:37.100Z,1638835717.100 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-12-07T00:08:37.101Z,1638835717.101 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-12-07T00:08:37.101Z,1638835717.101 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-12-07T00:08:37.108Z,1638835717.108 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-12-07T00:08:37.109Z,1638835717.109 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-12-07T00:08:37.109Z,1638835717.109 [SBIT](INFO): Initialize SBIT Component. 2021-12-07T00:08:37.110Z,1638835717.110 [SBIT](IMPORTANT): git: 2021-11-29 2021-12-07T00:08:37.110Z,1638835717.110 [SBIT](INFO): git hash: f462e1af5d4560b0531ff1e41adede0f51e7b106 2021-12-07T00:08:37.110Z,1638835717.110 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-12-07T00:08:37.111Z,1638835717.111 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2021-12-07T00:08:37.113Z,1638835717.113 [SBIT](INFO): Beginning SBIT in 52.000000 seconds. 2021-12-07T00:08:37.113Z,1638835717.113 [IBIT](INFO): Initialize IBIT Component. 2021-12-07T00:08:37.114Z,1638835717.114 [CBIT](DEBUG): Initialize CBIT Component. 2021-12-07T00:08:37.128Z,1638835717.128 [CBIT](DEBUG): Initialized mux pins. 2021-12-07T00:08:37.128Z,1638835717.128 [CBIT](DEBUG): Initializing the watchdog timer. 2021-12-07T00:08:37.137Z,1638835717.137 [logger ThreadHandler](INFO): Handler Thread ID is 4455 2021-12-07T00:08:37.156Z,1638835717.156 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-12-07T00:08:37.157Z,1638835717.157 [CBIT](DEBUG): Initializing heartbeat. 2021-12-07T00:08:37.161Z,1638835717.161 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4456 2021-12-07T00:08:37.167Z,1638835717.167 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4457 2021-12-07T00:08:37.168Z,1638835717.168 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-12-07T00:08:37.173Z,1638835717.173 [Onboard ThreadHandler](INFO): Handler Thread ID is 4458 2021-12-07T00:08:37.212Z,1638835717.212 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4459 2021-12-07T00:08:37.214Z,1638835717.214 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4460 2021-12-07T00:08:37.214Z,1638835717.214 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-12-07T00:08:37.218Z,1638835717.218 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4462 2021-12-07T00:08:37.221Z,1638835717.221 [WetLabsBB2FL](INFO): Powering up 2021-12-07T00:08:37.222Z,1638835717.222 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 4464 2021-12-07T00:08:37.225Z,1638835717.225 [WetLabsUBAT](INFO): Powering up 2021-12-07T00:08:37.226Z,1638835717.226 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4466 2021-12-07T00:08:37.229Z,1638835717.229 [CBIT](DEBUG): Deactivating GF circuits. 2021-12-07T00:08:37.229Z,1638835717.229 [CBIT](DEBUG): Deactivating emergency mode. 2021-12-07T00:08:37.232Z,1638835717.232 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-12-07T00:08:37.232Z,1638835717.232 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-12-07T00:08:37.232Z,1638835717.232 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-12-07T00:08:37.233Z,1638835717.233 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-12-07T00:08:37.233Z,1638835717.233 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-12-07T00:08:37.233Z,1638835717.233 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-12-07T00:08:37.233Z,1638835717.233 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-12-07T00:08:37.234Z,1638835717.234 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-12-07T00:08:37.234Z,1638835717.234 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-12-07T00:08:37.234Z,1638835717.234 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-12-07T00:08:37.234Z,1638835717.234 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-12-07T00:08:37.234Z,1638835717.234 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-12-07T00:08:37.235Z,1638835717.235 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-12-07T00:08:37.235Z,1638835717.235 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-12-07T00:08:37.235Z,1638835717.235 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-12-07T00:08:37.235Z,1638835717.235 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-12-07T00:08:37.264Z,1638835717.264 [CBIT](DEBUG): Backplane powered. 2021-12-07T00:08:37.269Z,1638835717.269 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-12-07T00:08:37.297Z,1638835717.297 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-12-07T00:08:37.334Z,1638835717.334 [MissionManager](DEBUG): 2021-12-07T00:08:37.335Z,1638835717.335 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-12-07T00:08:37.404Z,1638835717.404 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-12-07T00:08:37.421Z,1638835717.421 [Default:A.Wait](DEBUG): Construct Wait. 2021-12-07T00:08:37.424Z,1638835717.424 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-12-07T00:08:37.468Z,1638835717.468 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-12-07T00:08:37.470Z,1638835717.470 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-12-07T00:08:37.488Z,1638835717.488 [Default:E.Execute](DEBUG): Construct Execute. 2021-12-07T00:08:37.492Z,1638835717.492 [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 2021-12-07T00:08:37.512Z,1638835717.512 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2021-12-07T00:08:37.532Z,1638835717.532 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-12-07T00:08:37.599Z,1638835717.599 [Power24vConverter](INFO): Powering up. 2021-12-07T00:08:37.609Z,1638835717.609 [DAT](INFO): Powering up 2021-12-07T00:08:37.609Z,1638835717.609 [DAT](DEBUG): Initializing DAT. 2021-12-07T00:08:37.616Z,1638835717.616 [Radio_Surface](INFO): Powering up 2021-12-07T00:08:37.686Z,1638835717.686 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-12-07T00:08:37.693Z,1638835717.693 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-12-07T00:08:37.694Z,1638835717.694 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-12-07T00:08:37.701Z,1638835717.701 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-12-07T00:08:37.702Z,1638835717.702 [MassServo](DEBUG): Initializing EZServoServo. 2021-12-07T00:08:37.713Z,1638835717.713 [MassServo](DEBUG): Initializing MassServo. 2021-12-07T00:08:37.713Z,1638835717.713 [RudderServo](DEBUG): Initializing EZServoServo. 2021-12-07T00:08:37.721Z,1638835717.721 [RudderServo](DEBUG): Initializing RudderServo. 2021-12-07T00:08:37.722Z,1638835717.722 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-12-07T00:08:37.729Z,1638835717.729 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-12-07T00:08:38.660Z,1638835718.660 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2021-12-07T00:08:38.660Z,1638835718.660 [RudderServo](FAULT): Rudder failed to initialize 2021-12-07T00:08:38.661Z,1638835718.661 [RudderServo] Communications Fault, FailCount= 1 2021-12-07T00:08:38.661Z,1638835718.661 [RudderServo](ERROR): Communications Fault 2021-12-07T00:08:38.761Z,1638835718.761 [CBIT](ERROR): Communications Fault in component: RudderServo 2021-12-07T00:08:38.931Z,1638835718.931 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-12-07T00:08:38.932Z,1638835718.932 [RudderServo](INFO): Powering down 2021-12-07T00:08:39.597Z,1638835719.597 [RudderServo](DEBUG): Initializing EZServoServo. 2021-12-07T00:08:39.717Z,1638835719.717 [RudderServo](DEBUG): Initializing RudderServo. 2021-12-07T00:08:39.722Z,1638835719.722 [CBIT](INFO): Clearing failed state for component RudderServo 2021-12-07T00:08:39.722Z,1638835719.722 [RudderServo] No Fault, FailCount= 1 2021-12-07T00:08:39.832Z,1638835719.832 [WetLabsBB2FL](INFO): Powering down 2021-12-07T00:08:44.249Z,1638835724.249 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-12-07T00:08:46.476Z,1638835726.476 [WetLabsUBAT](INFO): Powering down 2021-12-07T00:08:50.810Z,1638835730.810 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004733 2021-12-07T00:08:51.704Z,1638835731.704 [DAT](INFO): commRate: 800 2021-12-07T00:08:53.723Z,1638835733.723 [DAT](INFO): entering command mode 2021-12-07T00:08:54.127Z,1638835734.127 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:54.531Z,1638835734.531 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:54.939Z,1638835734.939 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:55.359Z,1638835735.359 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:55.743Z,1638835735.743 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:56.147Z,1638835736.147 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:56.551Z,1638835736.551 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:56.955Z,1638835736.955 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:57.429Z,1638835737.429 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:57.762Z,1638835737.762 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:58.176Z,1638835738.176 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:58.571Z,1638835738.571 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:58.974Z,1638835738.974 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:59.404Z,1638835739.404 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:08:59.783Z,1638835739.783 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:09:00.191Z,1638835740.191 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:09:00.591Z,1638835740.591 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:09:00.995Z,1638835740.995 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:09:01.419Z,1638835741.419 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:09:01.803Z,1638835741.803 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:09:02.228Z,1638835742.228 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:09:02.610Z,1638835742.610 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:09:03.015Z,1638835743.015 [DAT](DEBUG): checking for command mode acknowledgment 2021-12-07T00:09: