2021-11-18T19:37:51.021Z,1637264271.021 [Supervisor](DEBUG): Initializing supervisor.
2021-11-18T19:37:51.026Z,1637264271.026 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-11-18T19:37:51.026Z,1637264271.026 [SyncHandler](INFO): Protected caller Thread ID is 1149
2021-11-18T19:37:51.027Z,1637264271.027 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-11-18T19:37:51.028Z,1637264271.028 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-11-18T19:37:51.029Z,1637264271.029 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1150
2021-11-18T19:37:51.033Z,1637264271.033 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-11-18T19:37:51.051Z,1637264271.051 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-11-18T19:37:51.052Z,1637264271.052 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-11-18T19:37:51.053Z,1637264271.053 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1151
2021-11-18T19:37:51.057Z,1637264271.057 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-11-18T19:37:51.058Z,1637264271.058 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-11-18T19:37:51.058Z,1637264271.058 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1152
2021-11-18T19:37:51.060Z,1637264271.060 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-11-18T19:37:51.061Z,1637264271.061 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-11-18T19:37:51.062Z,1637264271.062 [logger ThreadHandler](INFO): Protected caller Thread ID is 1153
2021-11-18T19:37:51.066Z,1637264271.066 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-11-18T19:37:51.066Z,1637264271.066 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-11-18T19:37:51.068Z,1637264271.068 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-11-18T19:37:51.292Z,1637264271.292 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-11-18T19:37:51.293Z,1637264271.293 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-11-18T19:37:51.374Z,1637264271.374 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-11-18T19:37:51.868Z,1637264271.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-11-18T19:37:51.869Z,1637264271.869 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-11-18T19:37:52.185Z,1637264272.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-11-18T19:37:52.186Z,1637264272.186 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-11-18T19:37:52.281Z,1637264272.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-11-18T19:37:52.281Z,1637264272.281 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-11-18T19:37:52.635Z,1637264272.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-11-18T19:37:52.636Z,1637264272.636 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-11-18T19:37:52.828Z,1637264272.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-11-18T19:37:52.828Z,1637264272.828 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-11-18T19:37:53.271Z,1637264273.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-11-18T19:37:53.271Z,1637264273.271 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-11-18T19:37:53.398Z,1637264273.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-11-18T19:37:53.398Z,1637264273.398 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-11-18T19:37:53.477Z,1637264273.477 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-11-18T19:37:54.122Z,1637264274.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-11-18T19:37:54.123Z,1637264274.123 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-11-18T19:37:54.598Z,1637264274.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-11-18T19:37:54.598Z,1637264274.598 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-11-18T19:37:54.902Z,1637264274.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-11-18T19:37:54.902Z,1637264274.902 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-11-18T19:37:55.015Z,1637264275.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-11-18T19:37:55.015Z,1637264275.015 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-11-18T19:37:55.163Z,1637264275.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-11-18T19:37:55.163Z,1637264275.163 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-11-18T19:37:55.384Z,1637264275.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-11-18T19:37:55.386Z,1637264275.386 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-11-18T19:37:55.387Z,1637264275.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-11-18T19:37:55.477Z,1637264275.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-11-18T19:37:55.562Z,1637264275.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-11-18T19:37:55.661Z,1637264275.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-11-18T19:37:55.743Z,1637264275.743 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-11-18T19:37:55.837Z,1637264275.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-11-18T19:37:55.937Z,1637264275.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-11-18T19:37:56.115Z,1637264276.115 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-11-18T19:37:56.191Z,1637264276.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-11-18T19:37:56.335Z,1637264276.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-11-18T19:37:56.469Z,1637264276.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-11-18T19:37:56.582Z,1637264276.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-11-18T19:37:56.838Z,1637264276.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-11-18T19:37:56.839Z,1637264276.839 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2021-11-18T19:37:56.840Z,1637264276.840 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-11-18T19:37:56.852Z,1637264276.852 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-11-18T19:37:56.906Z,1637264276.906 [VerticalControl](DEBUG): Construct VerticalControl.
2021-11-18T19:37:56.968Z,1637264276.968 [VerticalControl] Loaded
2021-11-18T19:37:56.968Z,1637264276.968 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-11-18T19:37:56.971Z,1637264276.971 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-11-18T19:37:57.009Z,1637264277.009 [HorizontalControl] Loaded
2021-11-18T19:37:57.009Z,1637264277.009 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-11-18T19:37:57.012Z,1637264277.012 [SpeedControl](DEBUG): Construct SpeedControl.
2021-11-18T19:37:57.015Z,1637264277.015 [SpeedControl] Loaded
2021-11-18T19:37:57.015Z,1637264277.015 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-11-18T19:37:57.018Z,1637264277.018 [LoopControl](DEBUG): Construct LoopControl.
2021-11-18T19:37:57.018Z,1637264277.018 [LoopControl] Loaded
2021-11-18T19:37:57.018Z,1637264277.018 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-11-18T19:37:57.019Z,1637264277.019 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-11-18T19:37:57.019Z,1637264277.019 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-11-18T19:37:57.030Z,1637264277.030 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-11-18T19:37:57.030Z,1637264277.030 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-11-18T19:37:57.133Z,1637264277.133 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-11-18T19:37:57.134Z,1637264277.134 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-11-18T19:37:57.328Z,1637264277.328 [BuoyancyServo] Loaded
2021-11-18T19:37:57.328Z,1637264277.328 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-11-18T19:37:57.347Z,1637264277.347 [ElevatorServo] Loaded
2021-11-18T19:37:57.348Z,1637264277.348 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-11-18T19:37:57.366Z,1637264277.366 [MassServo] Loaded
2021-11-18T19:37:57.367Z,1637264277.367 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-11-18T19:37:57.384Z,1637264277.384 [RudderServo] Loaded
2021-11-18T19:37:57.385Z,1637264277.385 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-11-18T19:37:57.402Z,1637264277.402 [ThrusterServo] Loaded
2021-11-18T19:37:57.402Z,1637264277.402 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-11-18T19:37:57.403Z,1637264277.403 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-11-18T19:37:57.403Z,1637264277.403 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-11-18T19:37:57.485Z,1637264277.485 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-11-18T19:37:57.485Z,1637264277.485 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-11-18T19:37:57.498Z,1637264277.498 [NavChart] Loaded
2021-11-18T19:37:57.499Z,1637264277.499 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-11-18T19:37:57.504Z,1637264277.504 [UniversalFixResidualReporter] Loaded
2021-11-18T19:37:57.504Z,1637264277.504 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-11-18T19:37:57.505Z,1637264277.505 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-11-18T19:37:57.506Z,1637264277.506 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-11-18T19:37:57.564Z,1637264277.564 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-11-18T19:37:57.565Z,1637264277.565 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-11-18T19:37:57.943Z,1637264277.943 [AHRS_M2] Loaded
2021-11-18T19:37:57.943Z,1637264277.943 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-11-18T19:37:58.726Z,1637264278.726 [BPC1] Loaded
2021-11-18T19:37:58.727Z,1637264278.727 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-11-18T19:37:58.746Z,1637264278.746 [Depth_Keller] Loaded
2021-11-18T19:37:58.747Z,1637264278.747 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-11-18T19:37:58.751Z,1637264278.751 [DropWeight] Loaded
2021-11-18T19:37:58.751Z,1637264278.751 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-11-18T19:37:58.809Z,1637264278.809 [NAL9602] Loaded
2021-11-18T19:37:58.810Z,1637264278.810 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-11-18T19:37:58.838Z,1637264278.838 [Onboard] Loaded
2021-11-18T19:37:58.838Z,1637264278.838 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-11-18T19:37:58.840Z,1637264278.840 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2021-11-18T19:37:58.840Z,1637264278.840 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1239
2021-11-18T19:37:58.853Z,1637264278.853 [Power24vConverter] Loaded
2021-11-18T19:37:58.853Z,1637264278.853 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-11-18T19:37:58.867Z,1637264278.867 [Radio_Surface] Loaded
2021-11-18T19:37:58.867Z,1637264278.867 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-11-18T19:37:58.868Z,1637264278.868 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2021-11-18T19:37:58.868Z,1637264278.868 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1240
2021-11-18T19:37:58.949Z,1637264278.949 [DAT] Loaded
2021-11-18T19:37:58.949Z,1637264278.949 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-11-18T19:37:58.950Z,1637264278.950 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-11-18T19:37:58.950Z,1637264278.950 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-11-18T19:37:58.992Z,1637264278.992 [DepthRateCalculator] Loaded
2021-11-18T19:37:58.993Z,1637264278.993 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-11-18T19:37:58.997Z,1637264278.997 [PitchRateCalculator] Loaded
2021-11-18T19:37:58.998Z,1637264278.998 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-11-18T19:37:59.007Z,1637264279.007 [SpeedCalculator] Loaded
2021-11-18T19:37:59.007Z,1637264279.007 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-11-18T19:37:59.021Z,1637264279.021 [TempGradientCalculator] Loaded
2021-11-18T19:37:59.021Z,1637264279.021 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-11-18T19:37:59.025Z,1637264279.025 [YawRateCalculator] Loaded
2021-11-18T19:37:59.026Z,1637264279.026 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-11-18T19:37:59.044Z,1637264279.044 [ElevatorOffsetCalculator] Loaded
2021-11-18T19:37:59.044Z,1637264279.044 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-11-18T19:37:59.044Z,1637264279.044 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-11-18T19:37:59.045Z,1637264279.045 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-11-18T19:37:59.177Z,1637264279.177 [CANONSampler] Loaded
2021-11-18T19:37:59.177Z,1637264279.177 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2021-11-18T19:37:59.235Z,1637264279.235 [CTD_Seabird] Loaded
2021-11-18T19:37:59.235Z,1637264279.235 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-11-18T19:37:59.236Z,1637264279.236 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0
2021-11-18T19:37:59.237Z,1637264279.237 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1241
2021-11-18T19:37:59.257Z,1637264279.257 [PAR_Licor] Loaded
2021-11-18T19:37:59.257Z,1637264279.257 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-11-18T19:37:59.288Z,1637264279.288 [WetLabsBB2FL] Loaded
2021-11-18T19:37:59.289Z,1637264279.289 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-11-18T19:37:59.290Z,1637264279.290 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2021-11-18T19:37:59.290Z,1637264279.290 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1242
2021-11-18T19:37:59.291Z,1637264279.291 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-11-18T19:37:59.291Z,1637264279.291 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-11-18T19:37:59.319Z,1637264279.319 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-11-18T19:37:59.319Z,1637264279.319 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-11-18T19:37:59.642Z,1637264279.642 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-11-18T19:37:59.642Z,1637264279.642 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-11-18T19:37:59.985Z,1637264279.985 [SBIT](DEBUG): Construct Startup Built In Test.
2021-11-18T19:37:59.994Z,1637264279.994 [SBIT] Loaded
2021-11-18T19:37:59.994Z,1637264279.994 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-11-18T19:37:59.997Z,1637264279.997 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-11-18T19:38:00.010Z,1637264280.010 [IBIT] Loaded
2021-11-18T19:38:00.010Z,1637264280.010 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-11-18T19:38:00.017Z,1637264280.017 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-11-18T19:38:00.342Z,1637264280.342 [CBIT] Loaded
2021-11-18T19:38:00.342Z,1637264280.342 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-11-18T19:38:00.343Z,1637264280.343 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-11-18T19:38:00.343Z,1637264280.343 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-11-18T19:38:00.439Z,1637264280.439 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-11-18T19:38:00.446Z,1637264280.446 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-11-18T19:38:00.448Z,1637264280.448 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-11-18T19:38:00.459Z,1637264280.459 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-11-18T19:38:00.460Z,1637264280.460 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B104E0
2021-11-18T19:38:00.460Z,1637264280.460 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1243
2021-11-18T19:38:00.465Z,1637264280.465 [Supervisor](INFO): Main Thread ID is 833
2021-11-18T19:38:00.465Z,1637264280.465 [Supervisor](DEBUG): Running supervisor.
2021-11-18T19:38:00.466Z,1637264280.466 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1244
2021-11-18T19:38:00.466Z,1637264280.466 [CommandExec](INFO): Initializing the command executive.
2021-11-18T19:38:00.468Z,1637264280.468 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1245
2021-11-18T19:38:00.470Z,1637264280.470 [controlThread ThreadHandler](INFO): Handler Thread ID is 1246
2021-11-18T19:38:00.471Z,1637264280.471 [controlThread](DEBUG): Initializing ControlThread
2021-11-18T19:38:00.471Z,1637264280.471 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-11-18T19:38:00.474Z,1637264280.474 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-11-18T19:38:00.474Z,1637264280.474 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-11-18T19:38:00.475Z,1637264280.475 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-11-18T19:38:00.477Z,1637264280.477 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-11-18T19:38:00.477Z,1637264280.477 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-11-18T19:38:00.482Z,1637264280.482 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-11-18T19:38:00.482Z,1637264280.482 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-11-18T19:38:00.482Z,1637264280.482 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-11-18T19:38:00.483Z,1637264280.483 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-11-18T19:38:00.483Z,1637264280.483 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-11-18T19:38:00.484Z,1637264280.484 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-11-18T19:38:00.488Z,1637264280.488 [SBIT](INFO): Initialize SBIT Component.
2021-11-18T19:38:00.489Z,1637264280.489 [SBIT](IMPORTANT): git: 2021-10-11a
2021-11-18T19:38:00.489Z,1637264280.489 [SBIT](INFO): git hash: 36f3a97de7a122c995f034a375e3cefac6406048
2021-11-18T19:38:00.489Z,1637264280.489 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-11-18T19:38:00.490Z,1637264280.490 [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
2021-11-18T19:38:00.492Z,1637264280.492 [SBIT](INFO): Beginning SBIT in 84.000000 seconds.
2021-11-18T19:38:00.492Z,1637264280.492 [IBIT](INFO): Initialize IBIT Component.
2021-11-18T19:38:00.493Z,1637264280.493 [CBIT](DEBUG): Initialize CBIT Component.
2021-11-18T19:38:00.494Z,1637264280.494 [logger ThreadHandler](INFO): Handler Thread ID is 1247
2021-11-18T19:38:00.508Z,1637264280.508 [CBIT](DEBUG): Initialized mux pins.
2021-11-18T19:38:00.508Z,1637264280.508 [CBIT](DEBUG): Initializing the watchdog timer.
2021-11-18T19:38:00.512Z,1637264280.512 [Onboard ThreadHandler](INFO): Handler Thread ID is 1248
2021-11-18T19:38:00.533Z,1637264280.533 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-11-18T19:38:00.533Z,1637264280.533 [CBIT](DEBUG): Initializing heartbeat.
2021-11-18T19:38:00.534Z,1637264280.534 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1249
2021-11-18T19:38:00.543Z,1637264280.543 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1250
2021-11-18T19:38:00.544Z,1637264280.544 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-11-18T19:38:00.548Z,1637264280.548 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1252
2021-11-18T19:38:00.552Z,1637264280.552 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1254
2021-11-18T19:38:00.555Z,1637264280.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-11-18T19:38:00.556Z,1637264280.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-11-18T19:38:00.556Z,1637264280.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-11-18T19:38:00.556Z,1637264280.556 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-11-18T19:38:00.557Z,1637264280.557 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-11-18T19:38:00.557Z,1637264280.557 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-11-18T19:38:00.557Z,1637264280.557 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-11-18T19:38:00.557Z,1637264280.557 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-11-18T19:38:00.557Z,1637264280.557 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-11-18T19:38:00.558Z,1637264280.558 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-11-18T19:38:00.558Z,1637264280.558 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-11-18T19:38:00.558Z,1637264280.558 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-11-18T19:38:00.558Z,1637264280.558 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-11-18T19:38:00.558Z,1637264280.558 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-11-18T19:38:00.559Z,1637264280.559 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-11-18T19:38:00.559Z,1637264280.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-11-18T19:38:00.573Z,1637264280.573 [WetLabsBB2FL](INFO): Powering up
2021-11-18T19:38:00.604Z,1637264280.604 [CBIT](DEBUG): Deactivating GF circuits.
2021-11-18T19:38:00.604Z,1637264280.604 [CBIT](DEBUG): Deactivating emergency mode.
2021-11-18T19:38:00.640Z,1637264280.640 [CBIT](DEBUG): Backplane powered.
2021-11-18T19:38:00.641Z,1637264280.641 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-11-18T19:38:00.654Z,1637264280.654 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-18T19:38:00.674Z,1637264280.674 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2021-11-18T19:38:00.675Z,1637264280.675 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-11-18T19:38:00.758Z,1637264280.758 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-11-18T19:38:00.759Z,1637264280.759 [Default:A.Wait](DEBUG): Construct Wait.
2021-11-18T19:38:00.778Z,1637264280.778 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-11-18T19:38:00.821Z,1637264280.821 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-11-18T19:38:00.824Z,1637264280.824 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-11-18T19:38:00.850Z,1637264280.850 [Default:E.Execute](DEBUG): Construct Execute.
2021-11-18T19:38:00.853Z,1637264280.853 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P1M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>1</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2021-11-18T19:38:00.858Z,1637264280.858 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,DAT,CANONSampler,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,Reporter,LogSplitter,
2021-11-18T19:38:00.878Z,1637264280.878 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-11-18T19:38:00.940Z,1637264280.940 [Radio_Surface](INFO): Powering up
2021-11-18T19:38:00.969Z,1637264280.969 [Depth_Keller](ERROR): Pressure reading out of range: 915.616455 decibar
2021-11-18T19:38:00.970Z,1637264280.970 [Power24vConverter](INFO): Powering up.
2021-11-18T19:38:00.970Z,1637264280.970 [DAT](INFO): Powering up
2021-11-18T19:38:00.971Z,1637264280.971 [DAT](DEBUG): Initializing DAT.
2021-11-18T19:38:00.988Z,1637264280.988 [CANONSampler](INFO): Powering down
2021-11-18T19:38:00.997Z,1637264280.997 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-11-18T19:38:01.036Z,1637264281.036 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-11-18T19:38:01.044Z,1637264281.044 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-11-18T19:38:01.045Z,1637264281.045 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-11-18T19:38:01.053Z,1637264281.053 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-11-18T19:38:01.054Z,1637264281.054 [MassServo](DEBUG): Initializing EZServoServo.
2021-11-18T19:38:01.064Z,1637264281.064 [MassServo](DEBUG): Initializing MassServo.
2021-11-18T19:38:01.065Z,1637264281.065 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-18T19:38:01.072Z,1637264281.072 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-18T19:38:01.073Z,1637264281.073 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-11-18T19:38:01.080Z,1637264281.080 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-11-18T19:38:01.290Z,1637264281.290 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2021-11-18T19:38:01.290Z,1637264281.290 [DropWeight] Hardware Fault, FailCount= 1
2021-11-18T19:38:01.290Z,1637264281.290 [DropWeight](ERROR): Hardware Fault
2021-11-18T19:38:01.340Z,1637264281.340 [CommandExec](FAULT): Scheduling is paused
2021-11-18T19:38:01.340Z,1637264281.340 [CBIT](INFO): Critical error at 20211118T193801
2021-11-18T19:38:01.340Z,1637264281.340 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-11-18T19:38:01.343Z,1637264281.343 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-11-18T19:38:01.343Z,1637264281.343 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-11-18T19:38:02.016Z,1637264282.016 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-11-18T19:38:02.016Z,1637264282.016 [RudderServo](FAULT): Rudder failed to initialize
2021-11-18T19:38:02.016Z,1637264282.016 [RudderServo] Communications Fault, FailCount= 1
2021-11-18T19:38:02.016Z,1637264282.016 [RudderServo](ERROR): Communications Fault
2021-11-18T19:38:02.114Z,1637264282.114 [CBIT](INFO): Critical error at 20211118T193801
2021-11-18T19:38:02.116Z,1637264282.116 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-11-18T19:38:02.283Z,1637264282.283 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-11-18T19:38:02.283Z,1637264282.283 [RudderServo](INFO): Powering down
2021-11-18T19:38:02.954Z,1637264282.954 [RudderServo](DEBUG): Initializing EZServoServo.
2021-11-18T19:38:03.073Z,1637264283.073 [RudderServo](DEBUG): Initializing RudderServo.
2021-11-18T19:38:03.077Z,1637264283.077 [CBIT](INFO): Clearing failed state for component RudderServo
2021-11-18T19:38:03.077Z,1637264283.077 [RudderServo] No Fault, FailCount= 1
2021-11-18T19:38:05.840Z,1637264285.840 [WetLabsBB2FL] Communications Fault, FailCount= 1
2021-11-18T19:38:05.840Z,1637264285.840 [WetLabsBB2FL](ERROR): Communications Fault
2021-11-18T19:38:05.904Z,1637264285.904 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2021-11-18T19:38:06.340Z,1637264286.340 [WetLabsBB2FL](INFO): Powering down
2021-11-18T19:38:06.000Z,1637264287.000 [Radio_Surface](INFO): Powering down
2021-11-18T19:38:07.501Z,1637264287.501 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2021-11-18T19:38:07.501Z,1637264287.501 [WetLabsBB2FL] No Fault, FailCount= 1
2021-11-18T19:38:07.553Z,1637264287.553 [WetLabsBB2FL](INFO): Powering up
2021-11-18T19:38:12.844Z,1637264292.844 [WetLabsBB2FL] Communications Fault, FailCount= 2
2021-11-18T19:38:12.844Z,1637264292.844 [WetLabsBB2FL](ERROR): Communications Fault
2021-11-18T19:38:13.100Z,1637264293.100 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2021-11-18T19:38:13.344Z,1637264293.344 [WetLabsBB2FL](INFO): Powering down
2021-11-18T19:38:13.592Z,1637264293.592 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2021-11-18T19:38:13.592Z,1637264293.592 [CTD_Seabird] Communications Fault, FailCount= 1
2021-11-18T19:38:13.592Z,1637264293.592 [CTD_Seabird](ERROR): Communications Fault
2021-11-18T19:38:13.920Z,1637264293.920 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-11-18T19:38:13.997Z,1637264293.997 [CTD_Seabird](INFO): Powering down
2021-11-18T19:38:14.324Z,1637264294.324 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2021-11-18T19:38:14.324Z,1637264294.324 [WetLabsBB2FL] No Fault, FailCount= 2
2021-11-18T19:38:14.557Z,1637264294.557 [WetLabsBB2FL](INFO): Powering up
2021-11-18T19:38:15.120Z,1637264295.120 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-11-18T19:38:15.120Z,1637264295.120 [CTD_Seabird] No Fault, FailCount= 1
2021-11-18T19:38:15.340Z,1637264295.340 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-11-18T19:38:19.848Z,1637264299.848 [WetLabsBB2FL] Communications Fault, FailCount= 3
2021-11-18T19:38:19.848Z,1637264299.848 [WetLabsBB2FL](ERROR): Communications Fault
2021-11-18T19:38:19.972Z,1637264299.972 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2021-11-18T19:38:20.348Z,1637264300.348 [WetLabsBB2FL](INFO): Powering down
2021-11-18T19:38:21.181Z,1637264301.181 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2021-11-18T19:38:21.181Z,1637264301.181 [WetLabsBB2FL] No Fault, FailCount= 3
2021-11-18T19:38:21.562Z,1637264301.562 [WetLabsBB2FL](INFO): Powering up
2021-11-18T19:38:25.851Z,1637264305.851 [CommandExec](IMPORTANT): got command get depth
2021-11-18T19:38:25.852Z,1637264305.852 [CommandExec](IMPORTANT): depth 93.370506 m
2021-11-18T19:38:26.854Z,1637264306.854 [WetLabsBB2FL] Communications Fault, FailCount= 4
2021-11-18T19:38:26.854Z,1637264306.854 [WetLabsBB2FL](ERROR): Communications Fault
2021-11-18T19:38:26.855Z,1637264306.855 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2021-11-18T19:38:27.352Z,1637264307.352 [WetLabsBB2FL](INFO): Powering down
2021-11-18T19:38:28.392Z,1637264308.392 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2021-11-18T19:38:28.392Z,1637264308.392 [CTD_Seabird] Communications Fault, FailCount= 2
2021-11-18T19:38:28.392Z,1637264308.392 [C