2021-12-01T22:21:48.538Z,1638397308.538 [Supervisor](DEBUG): Initializing supervisor. 2021-12-01T22:21:48.544Z,1638397308.544 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-12-01T22:21:48.544Z,1638397308.544 [SyncHandler](INFO): Protected caller Thread ID is 3344 2021-12-01T22:21:48.545Z,1638397308.545 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-12-01T22:21:48.546Z,1638397308.546 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-12-01T22:21:48.546Z,1638397308.546 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3345 2021-12-01T22:21:48.551Z,1638397308.551 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-12-01T22:21:48.571Z,1638397308.571 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2021-12-01T22:21:48.572Z,1638397308.572 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-12-01T22:21:48.572Z,1638397308.572 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3346 2021-12-01T22:21:48.577Z,1638397308.577 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-12-01T22:21:48.578Z,1638397308.578 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-12-01T22:21:48.578Z,1638397308.578 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3347 2021-12-01T22:21:48.580Z,1638397308.580 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-12-01T22:21:48.581Z,1638397308.581 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2021-12-01T22:21:48.582Z,1638397308.582 [logger ThreadHandler](INFO): Protected caller Thread ID is 3348 2021-12-01T22:21:48.586Z,1638397308.586 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-12-01T22:21:48.586Z,1638397308.586 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-12-01T22:21:48.588Z,1638397308.588 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-12-01T22:21:48.683Z,1638397308.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-12-01T22:21:48.683Z,1638397308.683 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-12-01T22:21:49.186Z,1638397309.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-12-01T22:21:49.187Z,1638397309.187 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-12-01T22:21:49.421Z,1638397309.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-12-01T22:21:49.422Z,1638397309.422 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-12-01T22:21:49.525Z,1638397309.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-12-01T22:21:49.526Z,1638397309.526 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-12-01T22:21:50.344Z,1638397310.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-12-01T22:21:50.345Z,1638397310.345 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-12-01T22:21:51.306Z,1638397311.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-12-01T22:21:51.307Z,1638397311.307 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-12-01T22:21:52.525Z,1638397312.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-12-01T22:21:52.526Z,1638397312.526 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-12-01T22:21:53.393Z,1638397313.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-12-01T22:21:53.393Z,1638397313.393 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-12-01T22:21:54.056Z,1638397314.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-12-01T22:21:54.057Z,1638397314.057 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-12-01T22:21:54.852Z,1638397314.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-12-01T22:21:54.853Z,1638397314.853 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-12-01T22:21:55.487Z,1638397315.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-12-01T22:21:55.487Z,1638397315.487 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-12-01T22:21:56.053Z,1638397316.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-12-01T22:21:56.054Z,1638397316.054 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-12-01T22:21:56.454Z,1638397316.454 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-12-01T22:21:56.615Z,1638397316.615 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-12-01T22:21:56.976Z,1638397316.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-12-01T22:21:56.976Z,1638397316.976 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-12-01T22:21:57.375Z,1638397317.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-12-01T22:21:57.377Z,1638397317.377 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2021-12-01T22:21:57.378Z,1638397317.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2021-12-01T22:21:57.523Z,1638397317.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2021-12-01T22:21:58.042Z,1638397318.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-12-01T22:21:58.043Z,1638397318.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2021-12-01T22:21:58.262Z,1638397318.262 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2021-12-01T22:21:59.137Z,1638397319.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2021-12-01T22:21:59.554Z,1638397319.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2021-12-01T22:21:59.712Z,1638397319.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2021-12-01T22:21:59.963Z,1638397319.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2021-12-01T22:22:00.126Z,1638397320.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2021-12-01T22:22:00.421Z,1638397320.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2021-12-01T22:22:00.612Z,1638397320.612 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2021-12-01T22:22:00.792Z,1638397320.792 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-12-01T22:22:00.794Z,1638397320.794 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-12-01T22:22:01.025Z,1638397321.025 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-12-01T22:22:01.026Z,1638397321.026 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-12-01T22:22:01.344Z,1638397321.344 [BuoyancyServo] Loaded 2021-12-01T22:22:01.344Z,1638397321.344 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-12-01T22:22:01.389Z,1638397321.389 [ElevatorServo] Loaded 2021-12-01T22:22:01.390Z,1638397321.390 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-12-01T22:22:01.443Z,1638397321.443 [MassServo] Loaded 2021-12-01T22:22:01.443Z,1638397321.443 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-12-01T22:22:01.485Z,1638397321.485 [RudderServo] Loaded 2021-12-01T22:22:01.485Z,1638397321.485 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-12-01T22:22:01.517Z,1638397321.517 [ThrusterServo] Loaded 2021-12-01T22:22:01.517Z,1638397321.517 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-12-01T22:22:01.518Z,1638397321.518 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-12-01T22:22:01.518Z,1638397321.518 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-12-01T22:22:01.868Z,1638397321.868 [SBIT](DEBUG): Construct Startup Built In Test. 2021-12-01T22:22:01.905Z,1638397321.905 [SBIT] Loaded 2021-12-01T22:22:01.905Z,1638397321.905 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-12-01T22:22:01.908Z,1638397321.908 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-12-01T22:22:01.955Z,1638397321.955 [IBIT] Loaded 2021-12-01T22:22:01.955Z,1638397321.955 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-12-01T22:22:01.961Z,1638397321.961 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-12-01T22:22:02.451Z,1638397322.451 [CBIT] Loaded 2021-12-01T22:22:02.451Z,1638397322.451 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-12-01T22:22:02.452Z,1638397322.452 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-12-01T22:22:02.453Z,1638397322.453 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-12-01T22:22:02.883Z,1638397322.883 [CTD_Seabird] Loaded 2021-12-01T22:22:02.884Z,1638397322.884 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-12-01T22:22:02.896Z,1638397322.896 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406554E0 2021-12-01T22:22:02.896Z,1638397322.896 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3430 2021-12-01T22:22:02.951Z,1638397322.951 [ESPComponent] Loaded 2021-12-01T22:22:02.951Z,1638397322.951 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2021-12-01T22:22:03.001Z,1638397323.001 [PAR_Licor] Loaded 2021-12-01T22:22:03.001Z,1638397323.001 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-12-01T22:22:03.075Z,1638397323.075 [WetLabsBB2FL] Loaded 2021-12-01T22:22:03.076Z,1638397323.076 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-12-01T22:22:03.084Z,1638397323.084 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406854E0 2021-12-01T22:22:03.085Z,1638397323.085 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3431 2021-12-01T22:22:03.086Z,1638397323.086 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-12-01T22:22:03.086Z,1638397323.086 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-12-01T22:22:03.221Z,1638397323.220 [DepthRateCalculator] Loaded 2021-12-01T22:22:03.221Z,1638397323.221 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-12-01T22:22:03.226Z,1638397323.226 [PitchRateCalculator] Loaded 2021-12-01T22:22:03.226Z,1638397323.226 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-12-01T22:22:03.246Z,1638397323.246 [SpeedCalculator] Loaded 2021-12-01T22:22:03.246Z,1638397323.246 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-12-01T22:22:03.282Z,1638397323.282 [TempGradientCalculator] Loaded 2021-12-01T22:22:03.282Z,1638397323.282 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-12-01T22:22:03.287Z,1638397323.287 [YawRateCalculator] Loaded 2021-12-01T22:22:03.287Z,1638397323.287 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-12-01T22:22:03.332Z,1638397323.332 [ElevatorOffsetCalculator] Loaded 2021-12-01T22:22:03.332Z,1638397323.332 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-12-01T22:22:03.332Z,1638397323.332 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-12-01T22:22:03.333Z,1638397323.333 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-12-01T22:22:03.602Z,1638397323.602 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-12-01T22:22:03.603Z,1638397323.603 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-12-01T22:22:03.690Z,1638397323.690 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-12-01T22:22:03.690Z,1638397323.690 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-12-01T22:22:03.956Z,1638397323.956 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-12-01T22:22:03.957Z,1638397323.957 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-12-01T22:22:03.997Z,1638397323.997 [NavChart] Loaded 2021-12-01T22:22:03.997Z,1638397323.997 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-12-01T22:22:04.003Z,1638397324.003 [UniversalFixResidualReporter] Loaded 2021-12-01T22:22:04.003Z,1638397324.003 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-12-01T22:22:04.003Z,1638397324.003 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-12-01T22:22:04.024Z,1638397324.024 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-12-01T22:22:05.543Z,1638397325.543 [AHRS_M2] Loaded 2021-12-01T22:22:05.543Z,1638397325.543 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-12-01T22:22:07.198Z,1638397327.198 [BPC1] Loaded 2021-12-01T22:22:07.199Z,1638397327.199 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-12-01T22:22:07.245Z,1638397327.245 [Depth_Keller] Loaded 2021-12-01T22:22:07.246Z,1638397327.246 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-12-01T22:22:07.263Z,1638397327.263 [DropWeight] Loaded 2021-12-01T22:22:07.263Z,1638397327.263 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-12-01T22:22:07.347Z,1638397327.347 [DVL_micro] Loaded 2021-12-01T22:22:07.348Z,1638397327.348 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2021-12-01T22:22:07.511Z,1638397327.511 [NAL9602] Loaded 2021-12-01T22:22:07.511Z,1638397327.511 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-12-01T22:22:07.563Z,1638397327.563 [Onboard] Loaded 2021-12-01T22:22:07.564Z,1638397327.564 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-12-01T22:22:07.572Z,1638397327.572 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409DC4E0 2021-12-01T22:22:07.572Z,1638397327.572 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3433 2021-12-01T22:22:07.604Z,1638397327.604 [Power24vConverter] Loaded 2021-12-01T22:22:07.616Z,1638397327.616 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2021-12-01T22:22:07.640Z,1638397327.640 [Radio_Surface] Loaded 2021-12-01T22:22:07.640Z,1638397327.640 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-12-01T22:22:07.645Z,1638397327.645 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A0C4E0 2021-12-01T22:22:07.646Z,1638397327.646 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3434 2021-12-01T22:22:07.682Z,1638397327.682 [DAT](INFO): DAT constructor 2021-12-01T22:22:07.840Z,1638397327.840 [DAT] Loaded 2021-12-01T22:22:07.841Z,1638397327.841 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-12-01T22:22:07.841Z,1638397327.841 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-12-01T22:22:07.842Z,1638397327.842 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-12-01T22:22:07.993Z,1638397327.993 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-12-01T22:22:07.993Z,1638397327.993 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-12-01T22:22:08.112Z,1638397328.112 [VerticalControl](DEBUG): Construct VerticalControl. 2021-12-01T22:22:08.238Z,1638397328.238 [VerticalControl] Loaded 2021-12-01T22:22:08.238Z,1638397328.238 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-12-01T22:22:08.241Z,1638397328.241 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-12-01T22:22:08.303Z,1638397328.303 [HorizontalControl] Loaded 2021-12-01T22:22:08.303Z,1638397328.303 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-12-01T22:22:08.310Z,1638397328.310 [SpeedControl](DEBUG): Construct SpeedControl. 2021-12-01T22:22:08.312Z,1638397328.312 [SpeedControl] Loaded 2021-12-01T22:22:08.312Z,1638397328.312 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-12-01T22:22:08.315Z,1638397328.315 [LoopControl](DEBUG): Construct LoopControl. 2021-12-01T22:22:08.316Z,1638397328.316 [LoopControl] Loaded 2021-12-01T22:22:08.316Z,1638397328.316 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-12-01T22:22:08.317Z,1638397328.317 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-12-01T22:22:08.317Z,1638397328.317 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-12-01T22:22:08.328Z,1638397328.328 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-12-01T22:22:08.329Z,1638397328.329 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-12-01T22:22:08.683Z,1638397328.683 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-12-01T22:22:08.694Z,1638397328.694 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-12-01T22:22:08.697Z,1638397328.697 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-12-01T22:22:08.708Z,1638397328.708 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-12-01T22:22:08.709Z,1638397328.709 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B114E0 2021-12-01T22:22:08.710Z,1638397328.710 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3435 2021-12-01T22:22:08.715Z,1638397328.715 [Supervisor](INFO): Main Thread ID is 3343 2021-12-01T22:22:08.715Z,1638397328.715 [Supervisor](DEBUG): Running supervisor. 2021-12-01T22:22:08.715Z,1638397328.715 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3436 2021-12-01T22:22:08.716Z,1638397328.716 [CommandExec](INFO): Initializing the command executive. 2021-12-01T22:22:08.718Z,1638397328.718 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3437 2021-12-01T22:22:08.720Z,1638397328.720 [controlThread ThreadHandler](INFO): Handler Thread ID is 3438 2021-12-01T22:22:08.720Z,1638397328.720 [controlThread](DEBUG): Initializing ControlThread 2021-12-01T22:22:08.722Z,1638397328.722 [SBIT](INFO): Initialize SBIT Component. 2021-12-01T22:22:08.723Z,1638397328.723 [SBIT](IMPORTANT): git: 2021-11-29_Tethys-21-gfc6cb1248 2021-12-01T22:22:08.723Z,1638397328.723 [SBIT](INFO): git hash: fc6cb1248608f41a4a7449d887defc8de896d7bf 2021-12-01T22:22:08.723Z,1638397328.723 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-12-01T22:22:08.725Z,1638397328.725 [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-12-01T22:22:08.726Z,1638397328.726 [SBIT](INFO): Beginning SBIT in 59.000000 seconds. 2021-12-01T22:22:08.727Z,1638397328.727 [IBIT](INFO): Initialize IBIT Component. 2021-12-01T22:22:08.728Z,1638397328.728 [CBIT](DEBUG): Initialize CBIT Component. 2021-12-01T22:22:08.729Z,1638397328.729 [logger ThreadHandler](INFO): Handler Thread ID is 3439 2021-12-01T22:22:08.740Z,1638397328.740 [CBIT](DEBUG): Initialized mux pins. 2021-12-01T22:22:08.740Z,1638397328.740 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2021-12-01T22:22:08.740Z,1638397328.740 [CBIT](DEBUG): Initializing the watchdog timer. 2021-12-01T22:22:08.748Z,1638397328.748 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3440 2021-12-01T22:22:08.749Z,1638397328.749 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-12-01T22:22:08.753Z,1638397328.753 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3442 2021-12-01T22:22:08.756Z,1638397328.756 [WetLabsBB2FL](INFO): Powering up 2021-12-01T22:22:08.758Z,1638397328.758 [Onboard ThreadHandler](INFO): Handler Thread ID is 3444 2021-12-01T22:22:08.765Z,1638397328.765 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-12-01T22:22:08.765Z,1638397328.765 [CBIT](DEBUG): Initializing heartbeat. 2021-12-01T22:22:08.773Z,1638397328.773 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3445 2021-12-01T22:22:08.797Z,1638397328.797 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3446 2021-12-01T22:22:08.804Z,1638397328.804 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000 2021-12-01T22:22:08.804Z,1638397328.804 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000 2021-12-01T22:22:08.804Z,1638397328.804 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000 2021-12-01T22:22:08.804Z,1638397328.804 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000 2021-12-01T22:22:08.805Z,1638397328.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000 2021-12-01T22:22:08.805Z,1638397328.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000 2021-12-01T22:22:08.805Z,1638397328.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000 2021-12-01T22:22:08.805Z,1638397328.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000 2021-12-01T22:22:08.805Z,1638397328.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000 2021-12-01T22:22:08.806Z,1638397328.806 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000 2021-12-01T22:22:08.806Z,1638397328.806 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000 2021-12-01T22:22:08.806Z,1638397328.806 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000 2021-12-01T22:22:08.836Z,1638397328.836 [CBIT](DEBUG): Deactivating GF circuits. 2021-12-01T22:22:08.836Z,1638397328.836 [CBIT](DEBUG): Deactivating emergency mode. 2021-12-01T22:22:08.872Z,1638397328.872 [CBIT](DEBUG): Backplane powered. 2021-12-01T22:22:08.876Z,1638397328.876 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-12-01T22:22:08.877Z,1638397328.877 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-12-01T22:22:08.877Z,1638397328.877 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-12-01T22:22:08.877Z,1638397328.877 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-12-01T22:22:08.878Z,1638397328.878 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-12-01T22:22:08.878Z,1638397328.878 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-12-01T22:22:08.880Z,1638397328.880 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-12-01T22:22:08.880Z,1638397328.880 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-12-01T22:22:08.890Z,1638397328.890 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-12-01T22:22:08.900Z,1638397328.900 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-12-01T22:22:08.901Z,1638397328.901 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-12-01T22:22:08.902Z,1638397328.902 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-12-01T22:22:08.903Z,1638397328.903 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-12-01T22:22:08.951Z,1638397328.951 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-12-01T22:22:08.987Z,1638397328.987 [MissionManager](DEBUG): 2021-12-01T22:22:08.987Z,1638397328.987 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-12-01T22:22:09.076Z,1638397329.076 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-12-01T22:22:09.077Z,1638397329.077 [Default:A.Wait](DEBUG): Construct Wait. 2021-12-01T22:22:09.079Z,1638397329.079 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-12-01T22:22:09.149Z,1638397329.149 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-12-01T22:22:09.151Z,1638397329.151 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-12-01T22:22:09.173Z,1638397329.173 [Default:E.Execute](DEBUG): Construct Execute. 2021-12-01T22:22:09.177Z,1638397329.177 [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. 1 Default mission has been running for Restarting logs and Default mission. restart logs 2021-12-01T22:22:09.189Z,1638397329.189 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,DAT,PAR_Licor,Depth_Keller,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-12-01T22:22:09.198Z,1638397329.198 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-12-01T22:22:09.200Z,1638397329.200 [Radio_Surface](INFO): Powering up 2021-12-01T22:22:09.469Z,1638397329.469 [Power24vConverter](INFO): Powering up. 2021-12-01T22:22:09.470Z,1638397329.470 [DAT](INFO): Powering up 2021-12-01T22:22:09.470Z,1638397329.470 [DAT](DEBUG): Initializing DAT. 2021-12-01T22:22:09.526Z,1638397329.526 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:22:09.532Z,1638397329.532 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-12-01T22:22:09.533Z,1638397329.533 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:22:09.540Z,1638397329.540 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-12-01T22:22:09.541Z,1638397329.541 [MassServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:22:09.552Z,1638397329.552 [MassServo](DEBUG): Initializing MassServo. 2021-12-01T22:22:09.553Z,1638397329.553 [RudderServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:22:09.560Z,1638397329.560 [RudderServo](DEBUG): Initializing RudderServo. 2021-12-01T22:22:09.561Z,1638397329.561 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:22:09.568Z,1638397329.568 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-12-01T22:22:09.693Z,1638397329.693 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2021-12-01T22:22:09.693Z,1638397329.693 [DropWeight] Hardware Fault, FailCount= 1 2021-12-01T22:22:09.693Z,1638397329.693 [DropWeight](ERROR): Hardware Fault 2021-12-01T22:22:09.742Z,1638397329.742 [CommandExec](FAULT): Scheduling is paused 2021-12-01T22:22:09.743Z,1638397329.743 [CBIT](INFO): Critical error at 20211201T222209 2021-12-01T22:22:09.743Z,1638397329.743 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2021-12-01T22:22:09.745Z,1638397329.745 [CBIT](ERROR): Hardware Fault in component: DropWeight 2021-12-01T22:22:09.746Z,1638397329.746 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2021-12-01T22:22:10.042Z,1638397330.042 [CBIT](INFO): Critical error at 20211201T222209 2021-12-01T22:22:10.928Z,1638397330.928 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2021-12-01T22:22:11.024Z,1638397331.024 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2021-12-01T22:22:11.024Z,1638397331.024 [ThrusterServo](FAULT): Thruster failed to initialize 2021-12-01T22:22:11.024Z,1638397331.024 [ThrusterServo] Communications Fault, FailCount= 1 2021-12-01T22:22:11.024Z,1638397331.024 [ThrusterServo](ERROR): Communications Fault 2021-12-01T22:22:11.028Z,1638397331.028 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2021-12-01T22:22:11.105Z,1638397331.105 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-12-01T22:22:11.105Z,1638397331.105 [ThrusterServo](INFO): Powering down 2021-12-01T22:22:14.044Z,1638397334.044 [WetLabsBB2FL] Communications Fault, FailCount= 1 2021-12-01T22:22:14.044Z,1638397334.044 [WetLabsBB2FL](ERROR): Communications Fault 2021-12-01T22:22:14.544Z,1638397334.544 [WetLabsBB2FL](INFO): Powering down 2021-12-01T22:22:21.800Z,1638397341.800 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2021-12-01T22:22:21.800Z,1638397341.800 [CTD_Seabird] Communications Fault, FailCount= 1 2021-12-01T22:22:21.800Z,1638397341.800 [CTD_Seabird](ERROR): Communications Fault 2021-12-01T22:22:22.205Z,1638397342.205 [CTD_Seabird](INFO): Powering down 2021-12-01T22:22:41.988Z,1638397361.988 [CBIT](CRITICAL): Environmental Failure. Press:14.681411 PSI. Humidity:37%. Temp:24 C. ABORTING MISSION 2021-12-01T22:22:41.989Z,1638397361.989 [CBIT](INFO): Clearing failed state for component ThrusterServo 2021-12-01T22:22:41.989Z,1638397361.989 [ThrusterServo] No Fault, FailCount= 1 2021-12-01T22:22:41.989Z,1638397361.989 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2021-12-01T22:22:41.989Z,1638397361.989 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2021-12-01T22:22:41.989Z,1638397361.989 [CTD_Seabird] No Fault, FailCount= 1 2021-12-01T22:22:41.990Z,1638397361.990 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2021-12-01T22:22:41.990Z,1638397361.990 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2021-12-01T22:22:41.990Z,1638397361.990 [WetLabsBB2FL] No Fault, FailCount= 1 2021-12-01T22:22:42.017Z,1638397362.017 [WetLabsBB2FL](INFO): Powering up 2021-12-01T22:22:42.124Z,1638397362.124 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2021-12-01T22:22:42.124Z,1638397362.124 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-12-01T22:22:42.124Z,1638397362.124 [AHRS_M2] Communications Fault, FailCount= 1 2021-12-01T22:22:42.124Z,1638397362.124 [AHRS_M2](ERROR): Communications Fault 2021-12-01T22:22:42.131Z,1638397362.131 [NAL9602](INFO): Powering up NAL9602 2021-12-01T22:22:42.133Z,1638397362.133 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-12-01T22:22:42.137Z,1638397362.137 [DAT](INFO): DAT read: 2021-12-01T22:22:42.292Z,1638397362.292 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:22:42.293Z,1638397362.293 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-12-01T22:22:42.295Z,1638397362.295 [CBIT](INFO): Critical error at 20211201T222241 2021-12-01T22:22:42.298Z,1638397362.298 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-12-01T22:22:42.400Z,1638397362.401 [AHRS_M2](INFO): Powering down 2021-12-01T22:22:43.300Z,1638397363.300 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2021-12-01T22:22:43.624Z,1638397363.624 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2021-12-01T22:22:43.624Z,1638397363.624 [ThrusterServo](FAULT): Thruster failed to initialize 2021-12-01T22:22:43.624Z,1638397363.624 [ThrusterServo] Communications Fault, FailCount= 2 2021-12-01T22:22:43.624Z,1638397363.624 [ThrusterServo](ERROR): Communications Fault 2021-12-01T22:22:43.628Z,1638397363.628 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2021-12-01T22:22:43.000Z,1638397364.000 [MassServo](ERROR): isCommunicating uart error serial timeout 2021-12-01T22:22:44.000Z,1638397364.000 [MassServo](FAULT): Invalid EZ Servo response:"" 2021-12-01T22:22:44.000Z,1638397364.000 [MassServo] Communications Fault, FailCount= 1 2021-12-01T22:22:44.000Z,1638397364.000 [MassServo](ERROR): Communications Fault 2021-12-01T22:22:44.001Z,1638397364.001 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2021-12-01T22:22:44.001Z,1638397364.001 [MassServo] Hardware Fault, FailCount= 1 2021-12-01T22:22:44.001Z,1638397364.001 [MassServo](ERROR): Hardware Fault 2021-12-01T22:22:44.005Z,1638397364.005 [MassServo](FAULT): Invalid EZ Servo response:"02" 2021-12-01T22:22:44.006Z,1638397364.006 [MassServo] Communications Fault, FailCount= 1 2021-12-01T22:22:44.006Z,1638397364.006 [MassServo](ERROR): Communications Fault 2021-12-01T22:22:44.041Z,1638397364.041 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-12-01T22:22:44.041Z,1638397364.041 [ThrusterServo](INFO): Powering down 2021-12-01T22:22:44.047Z,1638397364.047 [CBIT](ERROR): Communications Fault in component: MassServo 2021-12-01T22:22:44.047Z,1638397364.047 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-12-01T22:22:44.047Z,1638397364.047 [AHRS_M2] No Fault, FailCount= 1 2021-12-01T22:22:44.055Z,1638397364.055 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-12-01T22:22:44.112Z,1638397364.112 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-12-01T22:22:44.113Z,1638397364.113 [MassServo](INFO): Powering down 2021-12-01T22:22:44.420Z,1638397364.420 [RudderServo](ERROR): getPosition uart error serial timeout 2021-12-01T22:22:44.420Z,1638397364.420 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2021-12-01T22:22:44.420Z,1638397364.420 [RudderServo] Communications Fault, FailCount= 1 2021-12-01T22:22:44.420Z,1638397364.420 [RudderServo](ERROR): Communications Fault 2021-12-01T22:22:44.424Z,1638397364.424 [CBIT](ERROR): Communications Fault in component: RudderServo 2021-12-01T22:22:44.502Z,1638397364.502 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-12-01T22:22:44.502Z,1638397364.502 [RudderServo](INFO): Powering down 2021-12-01T22:22:47.308Z,1638397367.308 [WetLabsBB2FL] Communications Fault, FailCount= 2 2021-12-01T22:22:47.308Z,1638397367.308 [WetLabsBB2FL](ERROR): Communications Fault 2021-12-01T22:22:47.812Z,1638397367.812 [WetLabsBB2FL](INFO): Powering down 2021-12-01T22:22:55.184Z,1638397375.184 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2021-12-01T22:22:55.184Z,1638397375.184 [CTD_Seabird] Communications Fault, FailCount= 2 2021-12-01T22:22:55.184Z,1638397375.184 [CTD_Seabird](ERROR): Communications Fault 2021-12-01T22:22:55.588Z,1638397375.588 [CTD_Seabird](INFO): Powering down 2021-12-01T22:23:14.624Z,1638397394.624 [CBIT](INFO): Clearing failed state for component ThrusterServo 2021-12-01T22:23:14.624Z,1638397394.624 [ThrusterServo] No Fault, FailCount= 2 2021-12-01T22:23:14.624Z,1638397394.624 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2021-12-01T22:23:14.624Z,1638397394.624 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2021-12-01T22:23:14.625Z,1638397394.625 [CTD_Seabird] No Fault, FailCount= 2 2021-12-01T22:23:14.625Z,1638397394.625 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2021-12-01T22:23:14.625Z,1638397394.625 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2021-12-01T22:23:14.625Z,1638397394.625 [WetLabsBB2FL] No Fault, FailCount= 2 2021-12-01T22:23:14.704Z,1638397394.704 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-12-01T22:23:14.740Z,1638397394.740 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2021-12-01T22:23:14.740Z,1638397394.740 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-12-01T22:23:14.740Z,1638397394.740 [AHRS_M2] Communications Fault, FailCount= 2 2021-12-01T22:23:14.740Z,1638397394.740 [AHRS_M2](ERROR): Communications Fault 2021-12-01T22:23:14.749Z,1638397394.749 [DVL_micro](FAULT): Failed to initialize within timeout. 2021-12-01T22:23:14.749Z,1638397394.749 [DVL_micro] Communications Fault, FailCount= 1 2021-12-01T22:23:14.749Z,1638397394.749 [DVL_micro](ERROR): Communications Fault 2021-12-01T22:23:14.751Z,1638397394.751 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-12-01T22:23:14.751Z,1638397394.751 [DAT] Communications Fault, FailCount= 1 2021-12-01T22:23:14.751Z,1638397394.751 [DAT](ERROR): Communications Fault 2021-12-01T22:23:14.882Z,1638397394.882 [WetLabsBB2FL](INFO): Powering up 2021-12-01T22:23:14.893Z,1638397394.893 [MassServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:23:15.013Z,1638397395.013 [MassServo](DEBUG): Initializing MassServo. 2021-12-01T22:23:15.014Z,1638397395.014 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:23:15.015Z,1638397395.015 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-12-01T22:23:15.016Z,1638397395.016 [SBIT](IMPORTANT): Beginning Startup BIT 2021-12-01T22:23:15.020Z,1638397395.020 [CBIT](INFO): Clearing failed state for component MassServo 2021-12-01T22:23:15.021Z,1638397395.021 [MassServo] No Fault, FailCount= 1 2021-12-01T22:23:15.021Z,1638397395.021 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-12-01T22:23:15.021Z,1638397395.021 [CBIT](ERROR): Communications Fault in component: DVL_micro 2021-12-01T22:23:15.022Z,1638397395.022 [CBIT](ERROR): Communications Fault in component: DAT 2021-12-01T22:23:15.022Z,1638397395.022 [CBIT](IMPORTANT): Beginning ground fault scan 2021-12-01T22:23:15.060Z,1638397395.060 [AHRS_M2](INFO): Powering down 2021-12-01T22:23:15.236Z,1638397395.236 [DVL_micro](INFO): Powering down 2021-12-01T22:23:15.252Z,1638397395.252 [DAT](INFO): Powering down 2021-12-01T22:23:15.461Z,1638397395.461 [RudderServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:23:15.581Z,1638397395.581 [RudderServo](DEBUG): Initializing RudderServo. 2021-12-01T22:23:15.900Z,1638397395.900 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2021-12-01T22:23:16.216Z,1638397396.216 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2021-12-01T22:23:16.216Z,1638397396.216 [ThrusterServo](FAULT): Thruster failed to initialize 2021-12-01T22:23:16.216Z,1638397396.216 [ThrusterServo] Communications Fault, FailCount= 3 2021-12-01T22:23:16.216Z,1638397396.216 [ThrusterServo](ERROR): Communications Fault 2021-12-01T22:23:16.220Z,1638397396.220 [CBIT](INFO): Clearing failed state for component RudderServo 2021-12-01T22:23:16.220Z,1638397396.220 [RudderServo] No Fault, FailCount= 1 2021-12-01T22:23:16.221Z,1638397396.221 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2021-12-01T22:23:16.287Z,1638397396.287 [NAL9602](INFO): NAL9602 initialized 2021-12-01T22:23:16.612Z,1638397396.612 [ElevatorServo](ERROR): getPosition uart error serial timeout 2021-12-01T22:23:16.612Z,1638397396.612 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2021-12-01T22:23:16.612Z,1638397396.612 [ElevatorServo] Communications Fault, FailCount= 1 2021-12-01T22:23:16.612Z,1638397396.612 [ElevatorServo](ERROR): Communications Fault 2021-12-01T22:23:16.669Z,1638397396.669 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-12-01T22:23:16.669Z,1638397396.669 [ThrusterServo](INFO): Powering down 2021-12-01T22:23:16.674Z,1638397396.674 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2021-12-01T22:23:16.719Z,1638397396.719 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-12-01T22:23:16.719Z,1638397396.719 [ElevatorServo](INFO): Powering down 2021-12-01T22:23:16.733Z,1638397396.733 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-12-01T22:23:16.733Z,1638397396.733 [AHRS_M2] No Fault, FailCount= 2 2021-12-01T22:23:16.734Z,1638397396.734 [CBIT](INFO): Clearing failed state for component DVL_micro 2021-12-01T22:23:16.734Z,1638397396.734 [DVL_micro] No Fault, FailCount= 1 2021-12-01T22:23:16.734Z,1638397396.734 [CBIT](INFO): Clearing failed state for component DAT 2021-12-01T22:23:16.734Z,1638397396.734 [DAT] No Fault, FailCount= 1 2021-12-01T22:23:17.093Z,1638397397.093 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-12-01T22:23:17.145Z,1638397397.145 [ElevatorServo](FAULT): LCB fault: Software Overcurrent. 2021-12-01T22:23:17.145Z,1638397397.145 [ElevatorServo] Hardware Fault, FailCount= 1 2021-12-01T22:23:17.145Z,1638397397.145 [ElevatorServo](ERROR): Hardware Fault 2021-12-01T22:23:20.172Z,1638397400.172 [WetLabsBB2FL] Communications Fault, FailCount= 3 2021-12-01T22:23:20.172Z,1638397400.172 [WetLabsBB2FL](ERROR): Communications Fault 2021-12-01T22:23:20.672Z,1638397400.672 [WetLabsBB2FL](INFO): Powering down 2021-12-01T22:23:27.756Z,1638397407.756 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2021-12-01T22:23:27.756Z,1638397407.756 [CTD_Seabird] Communications Fault, FailCount= 3 2021-12-01T22:23:27.756Z,1638397407.756 [CTD_Seabird](ERROR): Communications Fault 2021-12-01T22:23:28.160Z,1638397408.160 [CTD_Seabird](INFO): Powering down 2021-12-01T22:23:47.265Z,1638397427.265 [SBIT](ERROR): Could not read elevatorAngleReader_. 2021-12-01T22:23:47.265Z,1638397427.265 [SBIT](ERROR): Could not read rudderAngleReader_. 2021-12-01T22:23:47.265Z,1638397427.265 [SBIT](ERROR): Could not read massPosReader_. 2021-12-01T22:23:47.269Z,1638397427.269 [CBIT](INFO): Clearing failed state for component ThrusterServo 2021-12-01T22:23:47.269Z,1638397427.269 [ThrusterServo] No Fault, FailCount= 3 2021-12-01T22:23:47.269Z,1638397427.269 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2021-12-01T22:23:47.269Z,1638397427.269 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2021-12-01T22:23:47.270Z,1638397427.270 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2021-12-01T22:23:47.270Z,1638397427.270 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2021-12-01T22:23:47.270Z,1638397427.270 [WetLabsBB2FL] No Fault, FailCount= 3 2021-12-01T22:23:47.341Z,1638397427.341 [WetLabsBB2FL](INFO): Powering up 2021-12-01T22:23:47.672Z,1638397427.672 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2021-12-01T22:23:47.672Z,1638397427.672 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-12-01T22:23:47.672Z,1638397427.672 [AHRS_M2] Communications Fault, FailCount= 3 2021-12-01T22:23:47.672Z,1638397427.672 [AHRS_M2](ERROR): Communications Fault 2021-12-01T22:23:47.705Z,1638397427.705 [DAT](INFO): Powering up 2021-12-01T22:23:47.705Z,1638397427.705 [DAT](DEBUG): Initializing DAT. 2021-12-01T22:23:47.734Z,1638397427.734 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:23:47.849Z,1638397427.849 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-12-01T22:23:47.851Z,1638397427.851 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:23:47.851Z,1638397427.851 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-12-01T22:23:47.860Z,1638397427.860 [CBIT](INFO): Clearing failed state for component ElevatorServo 2021-12-01T22:23:47.860Z,1638397427.860 [ElevatorServo] No Fault, FailCount= 1 2021-12-01T22:23:47.861Z,1638397427.861 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-12-01T22:23:47.870Z,1638397427.870 [AHRS_M2](INFO): Powering down 2021-12-01T22:23:47.941Z,1638397427.941 [BPC1](ERROR): BPC1A buffer size (B10 bytes) exceeded the max device response limit. 2021-12-01T22:23:47.942Z,1638397427.942 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-12-01T22:23:47.942Z,1638397427.942 [BPC1](ERROR): BPC1B buffer size (973 bytes) exceeded the max device response limit. 2021-12-01T22:23:47.942Z,1638397427.942 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-12-01T22:23:49.184Z,1638397429.184 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2021-12-01T22:23:49.272Z,1638397429.272 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2021-12-01T22:23:49.272Z,1638397429.272 [ThrusterServo](FAULT): Thruster failed to initialize 2021-12-01T22:23:49.272Z,1638397429.272 [ThrusterServo] Communications Fault, FailCount= 4 2021-12-01T22:23:49.272Z,1638397429.272 [ThrusterServo](ERROR): Communications Fault 2021-12-01T22:23:49.277Z,1638397429.277 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2021-12-01T22:23:49.277Z,1638397429.277 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-12-01T22:23:49.277Z,1638397429.277 [AHRS_M2] No Fault, FailCount= 3 2021-12-01T22:23:49.366Z,1638397429.366 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-12-01T22:23:49.700Z,1638397429.700 [MassServo](ERROR): isCommunicating uart error serial timeout 2021-12-01T22:23:49.700Z,1638397429.700 [MassServo](FAULT): Invalid EZ Servo response:"" 2021-12-01T22:23:49.700Z,1638397429.700 [MassServo] Communications Fault, FailCount= 2 2021-12-01T22:23:49.700Z,1638397429.700 [MassServo](ERROR): Communications Fault 2021-12-01T22:23:49.700Z,1638397429.700 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2021-12-01T22:23:49.701Z,1638397429.701 [MassServo] Hardware Fault, FailCount= 2 2021-12-01T22:23:49.701Z,1638397429.701 [MassServo](ERROR): Hardware Fault 2021-12-01T22:23:49.705Z,1638397429.705 [MassServo](FAULT): Invalid EZ Servo response:"64" 2021-12-01T22:23:49.706Z,1638397429.706 [MassServo] Communications Fault, FailCount= 2 2021-12-01T22:23:49.706Z,1638397429.706 [MassServo](ERROR): Communications Fault 2021-12-01T22:23:49.741Z,1638397429.741 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-12-01T22:23:49.741Z,1638397429.741 [ThrusterServo](INFO): Powering down 2021-12-01T22:23:49.746Z,1638397429.746 [CBIT](ERROR): Communications Fault in component: MassServo 2021-12-01T22:23:49.822Z,1638397429.822 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-12-01T22:23:49.822Z,1638397429.822 [MassServo](INFO): Powering down 2021-12-01T22:23:50.144Z,1638397430.144 [RudderServo](ERROR): getPosition uart error serial timeout 2021-12-01T22:23:50.144Z,1638397430.144 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2021-12-01T22:23:50.144Z,1638397430.144 [RudderServo] Communications Fault, FailCount= 2 2021-12-01T22:23:50.144Z,1638397430.144 [RudderServo](ERROR): Communications Fault 2021-12-01T22:23:50.149Z,1638397430.149 [CBIT](ERROR): Communications Fault in component: RudderServo 2021-12-01T22:23:50.261Z,1638397430.261 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-12-01T22:23:50.261Z,1638397430.261 [RudderServo](INFO): Powering down 2021-12-01T22:23:52.637Z,1638397432.637 [WetLabsBB2FL] Communications Fault, FailCount= 4 2021-12-01T22:23:52.637Z,1638397432.637 [WetLabsBB2FL](ERROR): Communications Fault 2021-12-01T22:23:53.136Z,1638397433.136 [WetLabsBB2FL](INFO): Powering down 2021-12-01T22:24:20.381Z,1638397460.381 [SBIT](ERROR): Could not read elevatorAngleReader_. 2021-12-01T22:24:20.381Z,1638397460.381 [SBIT](ERROR): Could not read rudderAngleReader_. 2021-12-01T22:24:20.382Z,1638397460.382 [SBIT](ERROR): Could not read massPosReader_. 2021-12-01T22:24:20.382Z,1638397460.382 [SBIT](FAULT): Control surface position failure. 2021-12-01T22:24:20.385Z,1638397460.385 [CBIT](INFO): Clearing failed state for component ThrusterServo 2021-12-01T22:24:20.385Z,1638397460.385 [ThrusterServo] No Fault, FailCount= 4 2021-12-01T22:24:20.385Z,1638397460.385 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2021-12-01T22:24:20.386Z,1638397460.386 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2021-12-01T22:24:20.386Z,1638397460.386 [WetLabsBB2FL] No Fault, FailCount= 4 2021-12-01T22:24:20.552Z,1638397460.552 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2021-12-01T22:24:20.552Z,1638397460.552 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-12-01T22:24:20.552Z,1638397460.552 [AHRS_M2] Communications Fault, FailCount= 4 2021-12-01T22:24:20.552Z,1638397460.552 [AHRS_M2](ERROR): Communications Fault 2021-12-01T22:24:20.556Z,1638397460.556 [DVL_micro](FAULT): Failed to initialize within timeout. 2021-12-01T22:24:20.556Z,1638397460.556 [DVL_micro] Communications Fault, FailCount= 2 2021-12-01T22:24:20.556Z,1638397460.556 [DVL_micro](ERROR): Communications Fault 2021-12-01T22:24:20.580Z,1638397460.580 [DAT](INFO): DAT read: 2021-12-01T22:24:20.588Z,1638397460.588 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-12-01T22:24:20.588Z,1638397460.588 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-12-01T22:24:20.588Z,1638397460.588 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-12-01T22:24:20.613Z,1638397460.613 [WetLabsBB2FL](INFO): Powering up 2021-12-01T22:24:20.614Z,1638397460.614 [MassServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:24:20.733Z,1638397460.733 [MassServo](DEBUG): Initializing MassServo. 2021-12-01T22:24:20.734Z,1638397460.734 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:24:20.735Z,1638397460.735 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-12-01T22:24:20.740Z,1638397460.740 [CBIT](INFO): Clearing failed state for component MassServo 2021-12-01T22:24:20.740Z,1638397460.740 [MassServo] No Fault, FailCount= 2 2021-12-01T22:24:20.741Z,1638397460.741 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-12-01T22:24:20.741Z,1638397460.741 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-12-01T22:24:20.742Z,1638397460.742 [CBIT](ERROR): Communications Fault in component: DVL_micro 2021-12-01T22:24:20.880Z,1638397460.880 [AHRS_M2](INFO): Powering down 2021-12-01T22:24:20.953Z,1638397460.953 [BPC1](ERROR): BPC1A buffer size (B10 bytes) exceeded the max device response limit. 2021-12-01T22:24:20.953Z,1638397460.953 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-12-01T22:24:20.954Z,1638397460.954 [BPC1](ERROR): BPC1B buffer size (973 bytes) exceeded the max device response limit. 2021-12-01T22:24:20.954Z,1638397460.954 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-12-01T22:24:21.032Z,1638397461.032 [DVL_micro](INFO): Powering down 2021-12-01T22:24:21.133Z,1638397461.133 [RudderServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:24:21.253Z,1638397461.253 [RudderServo](DEBUG): Initializing RudderServo. 2021-12-01T22:24:21.580Z,1638397461.580 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2021-12-01T22:24:21.904Z,1638397461.904 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2021-12-01T22:24:21.904Z,1638397461.904 [ThrusterServo](FAULT): Thruster failed to initialize 2021-12-01T22:24:21.904Z,1638397461.904 [ThrusterServo] Communications Fault, FailCount= 5 2021-12-01T22:24:21.904Z,1638397461.904 [ThrusterServo](ERROR): Communications Fault 2021-12-01T22:24:21.908Z,1638397461.908 [CBIT](INFO): Clearing failed state for component RudderServo 2021-12-01T22:24:21.908Z,1638397461.908 [RudderServo] No Fault, FailCount= 2 2021-12-01T22:24:21.909Z,1638397461.909 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2021-12-01T22:24:21.909Z,1638397461.909 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo 2021-12-01T22:24:21.909Z,1638397461.909 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-12-01T22:24:21.909Z,1638397461.909 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-12-01T22:24:22.360Z,1638397462.360 [ElevatorServo](ERROR): getPosition uart error serial timeout 2021-12-01T22:24:22.360Z,1638397462.360 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2021-12-01T22:24:22.360Z,1638397462.360 [ElevatorServo] Communications Fault, FailCount= 2 2021-12-01T22:24:22.360Z,1638397462.360 [ElevatorServo](ERROR): Communications Fault 2021-12-01T22:24:22.421Z,1638397462.421 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-12-01T22:24:22.421Z,1638397462.421 [ThrusterServo](INFO): Powering down 2021-12-01T22:24:22.425Z,1638397462.425 [CBIT](INFO): Critical error at 20211201T222421 2021-12-01T22:24:22.427Z,1638397462.427 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2021-12-01T22:24:22.488Z,1638397462.488 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-12-01T22:24:22.488Z,1638397462.488 [ElevatorServo](INFO): Powering down 2021-12-01T22:24:22.494Z,1638397462.494 [CBIT](INFO): Clearing failed state for component AHRS_M2 2021-12-01T22:24:22.494Z,1638397462.494 [AHRS_M2] No Fault, FailCount= 4 2021-12-01T22:24:22.495Z,1638397462.495 [CBIT](INFO): Clearing failed state for component DVL_micro 2021-12-01T22:24:22.495Z,1638397462.495 [DVL_micro] No Fault, FailCount= 2 2021-12-01T22:24:22.840Z,1638397462.840 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-12-01T22:24:22.873Z,1638397462.873 [ElevatorServo](FAULT): LCB fault: Software Overcurrent. 2021-12-01T22:24:22.873Z,1638397462.873 [ElevatorServo] Hardware Fault, FailCount= 2 2021-12-01T22:24:22.873Z,1638397462.873 [ElevatorServo](ERROR): Hardware Fault 2021-12-01T22:24:25.904Z,1638397465.904 [WetLabsBB2FL] Communications Fault, FailCount= 5 2021-12-01T22:24:25.904Z,1638397465.904 [WetLabsBB2FL](ERROR): Communications Fault 2021-12-01T22:24:26.404Z,1638397466.404 [WetLabsBB2FL](INFO): Powering down 2021-12-01T22:24:52.993Z,1638397492.993 [SBIT](ERROR): Could not read elevatorAngleReader_. 2021-12-01T22:24:52.993Z,1638397492.993 [SBIT](ERROR): Could not read rudderAngleReader_. 2021-12-01T22:24:52.993Z,1638397492.993 [SBIT](ERROR): Could not read massPosReader_. 2021-12-01T22:24:52.993Z,1638397492.993 [SBIT](FAULT): Control surface position failure. 2021-12-01T22:24:52.997Z,1638397492.997 [CBIT](INFO): Clearing failed state for component ThrusterServo 2021-12-01T22:24:52.997Z,1638397492.997 [ThrusterServo] No Fault, FailCount= 5 2021-12-01T22:24:52.997Z,1638397492.997 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2021-12-01T22:24:52.998Z,1638397492.998 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 2021-12-01T22:24:53.408Z,1638397493.408 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2021-12-01T22:24:53.408Z,1638397493.408 [AHRS_M2](FAULT): Failed to initialize within timeout. 2021-12-01T22:24:53.408Z,1638397493.408 [AHRS_M2] Communications Fault, FailCount= 5 2021-12-01T22:24:53.408Z,1638397493.408 [AHRS_M2](ERROR): Communications Fault 2021-12-01T22:24:53.429Z,1638397493.429 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-12-01T22:24:53.429Z,1638397493.429 [DAT] Communications Fault, FailCount= 2 2021-12-01T22:24:53.429Z,1638397493.429 [DAT](ERROR): Communications Fault 2021-12-01T22:24:53.455Z,1638397493.455 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:24:53.573Z,1638397493.573 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-12-01T22:24:53.574Z,1638397493.574 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-12-01T22:24:53.575Z,1638397493.575 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-12-01T22:24:53.576Z,1638397493.576 [SBIT](CRITICAL): SBIT FAILED 2021-12-01T22:24:53.577Z,1638397493.577 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-12-01T22:24:53.578Z,1638397493.578 [CBIT](INFO): Critical error at 20211201T222453 2021-12-01T22:24:53.580Z,1638397493.580 [CBIT](INFO): Clearing failed state for component ElevatorServo 2021-12-01T22:24:53.581Z,1638397493.581 [ElevatorServo] No Fault, FailCount= 2 2021-12-01T22:24:53.581Z,1638397493.581 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2021-12-01T22:24:53.582Z,1638397493.582 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2021-12-01T22:24:53.582Z,1638397493.582 [CBIT](ERROR): Communications Fault in component: DAT 2021-12-01T22:24:53.590Z,1638397493.590 [AHRS_M2](INFO): Powering down 2021-12-01T22:24:53.657Z,1638397493.657 [BPC1](ERROR): BPC1A buffer size (B10 bytes) exceeded the max device response limit. 2021-12-01T22:24:53.657Z,1638397493.657 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-12-01T22:24:53.680Z,1638397493.680 [DAT](INFO): Powering down 2021-12-01T22:24:53.809Z,1638397493.809 [MissionManager](IMPORTANT): Started mission Startup 2021-12-01T22:24:53.809Z,1638397493.809 [Startup] Running Loop=1 2021-12-01T22:24:53.809Z,1638397493.809 [Startup](DEBUG): Aggregate::initialize Startup 2021-12-01T22:24:53.810Z,1638397493.810 [Startup:A.GoToSurface] Running Loop=1 2021-12-01T22:24:53.810Z,1638397493.810 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-12-01T22:24:53.810Z,1638397493.810 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-12-01T22:24:53.811Z,1638397493.811 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-12-01T22:24:53.811Z,1638397493.811 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-12-01T22:24:53.811Z,1638397493.811 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-12-01T22:24:53.812Z,1638397493.812 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-12-01T22:24:53.814Z,1638397493.814 [Startup:StartupSatComms] Running