2023-10-03T19:30:51.627Z,1696361451.627 [Supervisor](DEBUG): Initializing supervisor. 2023-10-03T19:30:51.632Z,1696361451.632 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-10-03T19:30:51.632Z,1696361451.632 [SyncHandler](INFO): Protected caller Thread ID is 979 2023-10-03T19:30:51.633Z,1696361451.633 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-10-03T19:30:51.634Z,1696361451.634 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-10-03T19:30:51.634Z,1696361451.634 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 980 2023-10-03T19:30:51.638Z,1696361451.638 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-10-03T19:30:51.656Z,1696361451.656 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-10-03T19:30:51.657Z,1696361451.657 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-10-03T19:30:51.657Z,1696361451.657 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 981 2023-10-03T19:30:51.661Z,1696361451.661 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-10-03T19:30:51.662Z,1696361451.662 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-10-03T19:30:51.663Z,1696361451.663 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 982 2023-10-03T19:30:51.665Z,1696361451.665 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-10-03T19:30:51.665Z,1696361451.665 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-10-03T19:30:51.666Z,1696361451.666 [logger ThreadHandler](INFO): Protected caller Thread ID is 983 2023-10-03T19:30:51.669Z,1696361451.669 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-10-03T19:30:51.670Z,1696361451.670 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-10-03T19:30:51.672Z,1696361451.672 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-10-03T19:30:51.974Z,1696361451.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-10-03T19:30:51.975Z,1696361451.975 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-10-03T19:30:52.054Z,1696361452.054 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-10-03T19:30:52.158Z,1696361452.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-10-03T19:30:52.159Z,1696361452.159 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-10-03T19:30:52.284Z,1696361452.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-10-03T19:30:52.284Z,1696361452.284 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-10-03T19:30:52.609Z,1696361452.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-10-03T19:30:52.610Z,1696361452.610 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-10-03T19:30:52.704Z,1696361452.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-10-03T19:30:52.704Z,1696361452.704 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-10-03T19:30:53.087Z,1696361453.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-10-03T19:30:53.088Z,1696361453.088 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-10-03T19:30:53.288Z,1696361453.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-10-03T19:30:53.289Z,1696361453.289 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-10-03T19:30:53.367Z,1696361453.367 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-10-03T19:30:53.582Z,1696361453.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-10-03T19:30:53.582Z,1696361453.582 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-10-03T19:30:53.790Z,1696361453.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-10-03T19:30:53.791Z,1696361453.791 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-10-03T19:30:54.289Z,1696361454.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-10-03T19:30:54.290Z,1696361454.290 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-10-03T19:30:54.469Z,1696361454.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-10-03T19:30:54.469Z,1696361454.469 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-10-03T19:30:54.738Z,1696361454.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-10-03T19:30:54.739Z,1696361454.739 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-10-03T19:30:55.526Z,1696361455.526 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-10-03T19:30:55.526Z,1696361455.526 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-10-03T19:30:56.028Z,1696361456.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-10-03T19:30:56.031Z,1696361456.031 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2023-10-03T19:30:56.031Z,1696361456.031 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2023-10-03T19:30:56.145Z,1696361456.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2023-10-03T19:30:56.260Z,1696361456.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2023-10-03T19:30:56.343Z,1696361456.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2023-10-03T19:30:56.450Z,1696361456.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2023-10-03T19:30:56.547Z,1696361456.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2023-10-03T19:30:56.805Z,1696361456.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-10-03T19:30:56.806Z,1696361456.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2023-10-03T19:30:56.914Z,1696361456.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2023-10-03T19:30:57.006Z,1696361457.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2023-10-03T19:30:57.107Z,1696361457.107 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2023-10-03T19:30:57.235Z,1696361457.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2023-10-03T19:30:57.320Z,1696361457.320 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/ 2023-10-03T19:30:57.320Z,1696361457.320 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-10-03T19:30:57.330Z,1696361457.330 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-10-03T19:30:57.430Z,1696361457.430 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-10-03T19:30:57.431Z,1696361457.431 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-10-03T19:30:57.481Z,1696361457.481 [VerticalControl](DEBUG): Construct VerticalControl. 2023-10-03T19:30:57.542Z,1696361457.542 [VerticalControl] Loaded 2023-10-03T19:30:57.542Z,1696361457.542 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-10-03T19:30:57.545Z,1696361457.545 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-10-03T19:30:57.586Z,1696361457.586 [HorizontalControl] Loaded 2023-10-03T19:30:57.586Z,1696361457.586 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-10-03T19:30:57.588Z,1696361457.588 [SpeedControl](DEBUG): Construct SpeedControl. 2023-10-03T19:30:57.591Z,1696361457.591 [SpeedControl] Loaded 2023-10-03T19:30:57.592Z,1696361457.592 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-10-03T19:30:57.594Z,1696361457.594 [LoopControl](DEBUG): Construct LoopControl. 2023-10-03T19:30:57.595Z,1696361457.595 [LoopControl] Loaded 2023-10-03T19:30:57.595Z,1696361457.595 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-10-03T19:30:57.596Z,1696361457.596 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-10-03T19:30:57.596Z,1696361457.596 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-10-03T19:30:57.651Z,1696361457.651 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-10-03T19:30:57.652Z,1696361457.652 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-10-03T19:30:58.095Z,1696361458.095 [AHRS_M2] Loaded 2023-10-03T19:30:58.095Z,1696361458.095 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-10-03T19:30:58.130Z,1696361458.130 [BackseatComponent] Loaded 2023-10-03T19:30:58.131Z,1696361458.131 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-10-03T19:30:58.132Z,1696361458.132 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408814E0 2023-10-03T19:30:58.132Z,1696361458.132 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1065 2023-10-03T19:30:58.135Z,1696361458.135 [LcmUniversalReporter] Loaded 2023-10-03T19:30:58.135Z,1696361458.135 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-10-03T19:30:58.939Z,1696361458.939 [BPC1] Loaded 2023-10-03T19:30:58.940Z,1696361458.940 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-10-03T19:30:59.012Z,1696361459.012 [DataOverHttps] Loaded 2023-10-03T19:30:59.012Z,1696361459.012 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-10-03T19:30:59.013Z,1696361459.013 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0 2023-10-03T19:30:59.014Z,1696361459.014 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1066 2023-10-03T19:30:59.034Z,1696361459.034 [Depth_Keller] Loaded 2023-10-03T19:30:59.035Z,1696361459.035 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-10-03T19:30:59.040Z,1696361459.040 [DropWeight] Loaded 2023-10-03T19:30:59.040Z,1696361459.040 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-10-03T19:30:59.102Z,1696361459.102 [NAL9602] Loaded 2023-10-03T19:30:59.102Z,1696361459.102 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-10-03T19:30:59.131Z,1696361459.131 [Onboard] Loaded 2023-10-03T19:30:59.132Z,1696361459.132 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-10-03T19:30:59.133Z,1696361459.133 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0 2023-10-03T19:30:59.133Z,1696361459.133 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1067 2023-10-03T19:30:59.145Z,1696361459.145 [Power24vConverter] Loaded 2023-10-03T19:30:59.145Z,1696361459.145 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-10-03T19:30:59.158Z,1696361459.158 [Radio_Surface] Loaded 2023-10-03T19:30:59.158Z,1696361459.158 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-10-03T19:30:59.159Z,1696361459.159 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409114E0 2023-10-03T19:30:59.160Z,1696361459.160 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1068 2023-10-03T19:30:59.251Z,1696361459.251 [DAT] Loaded 2023-10-03T19:30:59.251Z,1696361459.251 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-10-03T19:30:59.252Z,1696361459.252 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409414E0 2023-10-03T19:30:59.252Z,1696361459.252 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1069 2023-10-03T19:30:59.275Z,1696361459.275 [Waterlinked] Loaded 2023-10-03T19:30:59.275Z,1696361459.275 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2023-10-03T19:30:59.276Z,1696361459.276 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-10-03T19:30:59.276Z,1696361459.276 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-10-03T19:30:59.286Z,1696361459.286 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-10-03T19:30:59.287Z,1696361459.287 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-10-03T19:30:59.313Z,1696361459.313 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-10-03T19:30:59.313Z,1696361459.313 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-10-03T19:30:59.451Z,1696361459.451 [SBIT](DEBUG): Construct Startup Built In Test. 2023-10-03T19:30:59.461Z,1696361459.461 [SBIT] Loaded 2023-10-03T19:30:59.461Z,1696361459.461 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-10-03T19:30:59.464Z,1696361459.464 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-10-03T19:30:59.476Z,1696361459.476 [IBIT] Loaded 2023-10-03T19:30:59.477Z,1696361459.477 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-10-03T19:30:59.483Z,1696361459.483 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-10-03T19:30:59.587Z,1696361459.587 [CBIT] Loaded 2023-10-03T19:30:59.587Z,1696361459.587 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-10-03T19:30:59.587Z,1696361459.587 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-10-03T19:30:59.588Z,1696361459.588 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-10-03T19:30:59.889Z,1696361459.889 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-10-03T19:30:59.890Z,1696361459.890 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-10-03T19:30:59.939Z,1696361459.939 [DepthRateCalculator] Loaded 2023-10-03T19:30:59.939Z,1696361459.939 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-10-03T19:30:59.944Z,1696361459.944 [PitchRateCalculator] Loaded 2023-10-03T19:30:59.944Z,1696361459.944 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-10-03T19:30:59.955Z,1696361459.955 [SpeedCalculator] Loaded 2023-10-03T19:30:59.955Z,1696361459.955 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-10-03T19:30:59.959Z,1696361459.959 [YawRateCalculator] Loaded 2023-10-03T19:30:59.959Z,1696361459.959 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-10-03T19:30:59.978Z,1696361459.978 [ElevatorOffsetCalculator] Loaded 2023-10-03T19:30:59.978Z,1696361459.978 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-10-03T19:30:59.979Z,1696361459.979 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-10-03T19:30:59.979Z,1696361459.979 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-10-03T19:31:00.130Z,1696361460.130 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-10-03T19:31:00.130Z,1696361460.130 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-10-03T19:31:00.240Z,1696361460.240 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-10-03T19:31:00.240Z,1696361460.240 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-10-03T19:31:00.284Z,1696361460.284 [DeadReckonUsingSpeedCalculator] Loaded 2023-10-03T19:31:00.284Z,1696361460.284 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-10-03T19:31:00.327Z,1696361460.327 [DeadReckonWithRespectToSeafloor] Loaded 2023-10-03T19:31:00.328Z,1696361460.328 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2023-10-03T19:31:00.341Z,1696361460.341 [NavChart] Loaded 2023-10-03T19:31:00.341Z,1696361460.341 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-10-03T19:31:00.347Z,1696361460.347 [UniversalFixResidualReporter] Loaded 2023-10-03T19:31:00.347Z,1696361460.347 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-10-03T19:31:00.348Z,1696361460.348 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-10-03T19:31:00.348Z,1696361460.348 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-10-03T19:31:00.478Z,1696361460.478 [BuoyancyServo] Loaded 2023-10-03T19:31:00.478Z,1696361460.478 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-10-03T19:31:00.499Z,1696361460.499 [ElevatorServo] Loaded 2023-10-03T19:31:00.499Z,1696361460.499 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-10-03T19:31:00.519Z,1696361460.519 [MassServo] Loaded 2023-10-03T19:31:00.519Z,1696361460.519 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-10-03T19:31:00.538Z,1696361460.538 [RudderServo] Loaded 2023-10-03T19:31:00.538Z,1696361460.538 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-10-03T19:31:00.553Z,1696361460.553 [ThrusterHE] Loaded 2023-10-03T19:31:00.553Z,1696361460.553 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-10-03T19:31:00.554Z,1696361460.554 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-10-03T19:31:00.554Z,1696361460.554 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-10-03T19:31:00.726Z,1696361460.726 [CTD_Seabird] Loaded 2023-10-03T19:31:00.726Z,1696361460.726 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-10-03T19:31:00.727Z,1696361460.727 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9B4E0 2023-10-03T19:31:00.728Z,1696361460.728 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1070 2023-10-03T19:31:00.761Z,1696361460.761 [ESPComponent] Loaded 2023-10-03T19:31:00.761Z,1696361460.761 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-10-03T19:31:00.782Z,1696361460.782 [PAR_Licor] Loaded 2023-10-03T19:31:00.782Z,1696361460.782 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-10-03T19:31:00.823Z,1696361460.823 [WetLabsBB2FL] Loaded 2023-10-03T19:31:00.823Z,1696361460.823 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-10-03T19:31:00.824Z,1696361460.824 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0 2023-10-03T19:31:00.824Z,1696361460.824 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1071 2023-10-03T19:31:00.825Z,1696361460.825 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-10-03T19:31:00.832Z,1696361460.832 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-10-03T19:31:00.834Z,1696361460.834 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-10-03T19:31:00.846Z,1696361460.846 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-10-03T19:31:00.847Z,1696361460.847 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFB4E0 2023-10-03T19:31:00.847Z,1696361460.847 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1072 2023-10-03T19:31:00.851Z,1696361460.851 [Supervisor](INFO): Main Thread ID is 818 2023-10-03T19:31:00.852Z,1696361460.852 [Supervisor](DEBUG): Running supervisor. 2023-10-03T19:31:00.852Z,1696361460.852 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1073 2023-10-03T19:31:00.853Z,1696361460.853 [CommandExec](INFO): Initializing the command executive. 2023-10-03T19:31:00.854Z,1696361460.854 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1074 2023-10-03T19:31:00.857Z,1696361460.857 [controlThread ThreadHandler](INFO): Handler Thread ID is 1075 2023-10-03T19:31:00.858Z,1696361460.858 [controlThread](DEBUG): Initializing ControlThread 2023-10-03T19:31:00.859Z,1696361460.859 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-10-03T19:31:00.861Z,1696361460.861 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-10-03T19:31:00.861Z,1696361460.861 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-10-03T19:31:00.862Z,1696361460.862 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-10-03T19:31:00.868Z,1696361460.868 [SBIT](INFO): Initialize SBIT Component. 2023-10-03T19:31:00.868Z,1696361460.868 [SBIT](IMPORTANT): git: 2023-09-18-3-g7ee55e0de 2023-10-03T19:31:00.869Z,1696361460.869 [SBIT](INFO): git hash: 7ee55e0ded82548f5c252ba0d9a41930617440e4 2023-10-03T19:31:00.869Z,1696361460.869 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-10-03T19:31:00.870Z,1696361460.870 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-10-03T19:31:00.871Z,1696361460.871 [SBIT](INFO): Beginning SBIT in 45.000000 seconds. 2023-10-03T19:31:00.872Z,1696361460.872 [IBIT](INFO): Initialize IBIT Component. 2023-10-03T19:31:00.873Z,1696361460.873 [CBIT](DEBUG): Initialize CBIT Component. 2023-10-03T19:31:00.874Z,1696361460.874 [logger ThreadHandler](INFO): Handler Thread ID is 1076 2023-10-03T19:31:00.886Z,1696361460.886 [CBIT](DEBUG): Initialized mux pins. 2023-10-03T19:31:00.886Z,1696361460.886 [CBIT](DEBUG): Initializing the watchdog timer. 2023-10-03T19:31:00.894Z,1696361460.894 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1077 2023-10-03T19:31:00.906Z,1696361460.906 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1078 2023-10-03T19:31:00.907Z,1696361460.907 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-10-03T19:31:00.910Z,1696361460.910 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-10-03T19:31:00.910Z,1696361460.910 [CBIT](DEBUG): Initializing heartbeat. 2023-10-03T19:31:00.918Z,1696361460.918 [Onboard ThreadHandler](INFO): Handler Thread ID is 1079 2023-10-03T19:31:00.936Z,1696361460.936 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1080 2023-10-03T19:31:00.955Z,1696361460.955 [DAT ThreadHandler](INFO): Handler Thread ID is 1081 2023-10-03T19:31:00.955Z,1696361460.955 [DAT](INFO): Powering up 2023-10-03T19:31:00.956Z,1696361460.956 [DAT](DEBUG): Initializing DAT. 2023-10-03T19:31:00.959Z,1696361460.959 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1083 2023-10-03T19:31:00.960Z,1696361460.960 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-10-03T19:31:00.963Z,1696361460.963 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1085 2023-10-03T19:31:00.966Z,1696361460.966 [WetLabsBB2FL](INFO): Powering up 2023-10-03T19:31:00.968Z,1696361460.968 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1087 2023-10-03T19:31:00.971Z,1696361460.971 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2023-10-03T19:31:00.971Z,1696361460.971 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2023-10-03T19:31:00.971Z,1696361460.971 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2023-10-03T19:31:00.971Z,1696361460.971 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2023-10-03T19:31:00.971Z,1696361460.971 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-10-03T19:31:00.971Z,1696361460.971 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-10-03T19:31:00.972Z,1696361460.972 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-10-03T19:31:00.972Z,1696361460.972 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-10-03T19:31:00.972Z,1696361460.972 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2023-10-03T19:31:00.972Z,1696361460.972 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2023-10-03T19:31:00.972Z,1696361460.972 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2023-10-03T19:31:00.972Z,1696361460.972 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2023-10-03T19:31:00.982Z,1696361460.982 [CBIT](DEBUG): Deactivating GF circuits. 2023-10-03T19:31:00.982Z,1696361460.982 [CBIT](DEBUG): Deactivating emergency mode. 2023-10-03T19:31:01.022Z,1696361461.022 [CBIT](DEBUG): Backplane powered. 2023-10-03T19:31:01.022Z,1696361461.022 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-10-03T19:31:01.023Z,1696361461.023 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-10-03T19:31:01.023Z,1696361461.023 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-10-03T19:31:01.023Z,1696361461.023 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-10-03T19:31:01.023Z,1696361461.023 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-10-03T19:31:01.025Z,1696361461.025 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T19:31:01.025Z,1696361461.025 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-10-03T19:31:01.026Z,1696361461.026 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-10-03T19:31:01.031Z,1696361461.031 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-10-03T19:31:01.090Z,1696361461.090 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-03T19:31:01.113Z,1696361461.113 [MissionManager](DEBUG): 2023-10-03T19:31:01.114Z,1696361461.114 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-10-03T19:31:01.168Z,1696361461.168 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-10-03T19:31:01.169Z,1696361461.169 [Default:A.Wait](DEBUG): Construct Wait. 2023-10-03T19:31:01.172Z,1696361461.172 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-03T19:31:01.216Z,1696361461.216 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-10-03T19:31:01.237Z,1696361461.237 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-10-03T19:31:01.243Z,1696361461.243 [Default:E.Execute](DEBUG): Construct Execute. 2023-10-03T19:31:01.267Z,1696361461.267 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-10-03T19:31:01.272Z,1696361461.272 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-10-03T19:31:01.278Z,1696361461.278 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-10-03T19:31:01.332Z,1696361461.332 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2023-10-03T19:31:01.333Z,1696361461.333 [Power24vConverter](INFO): Powering up. 2023-10-03T19:31:01.348Z,1696361461.348 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-10-03T19:31:01.350Z,1696361461.350 [Radio_Surface](INFO): Powering up 2023-10-03T19:31:01.378Z,1696361461.378 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-10-03T19:31:01.386Z,1696361461.386 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-10-03T19:31:01.387Z,1696361461.387 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-10-03T19:31:01.394Z,1696361461.394 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-10-03T19:31:01.395Z,1696361461.395 [MassServo](DEBUG): Initializing EZServoServo. 2023-10-03T19:31:01.402Z,1696361461.402 [MassServo](DEBUG): Initializing MassServo. 2023-10-03T19:31:01.403Z,1696361461.403 [RudderServo](DEBUG): Initializing EZServoServo. 2023-10-03T19:31:01.410Z,1696361461.410 [RudderServo](DEBUG): Initializing RudderServo. 2023-10-03T19:31:01.411Z,1696361461.411 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-10-03T19:31:01.418Z,1696361461.418 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-10-03T19:31:01.726Z,1696361461.726 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-10-03T19:31:01.726Z,1696361461.726 [DropWeight] Hardware Fault, FailCount= 1 2023-10-03T19:31:01.726Z,1696361461.726 [DropWeight](ERROR): Hardware Fault 2023-10-03T19:31:01.762Z,1696361461.762 [CommandExec](FAULT): Scheduling is paused 2023-10-03T19:31:01.763Z,1696361461.763 [CBIT](INFO): Critical error at 20231003T193101 2023-10-03T19:31:01.763Z,1696361461.763 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-10-03T19:31:01.765Z,1696361461.765 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-10-03T19:31:01.768Z,1696361461.768 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-10-03T19:31:02.122Z,1696361462.122 [CBIT](INFO): Critical error at 20231003T193101 2023-10-03T19:31:03.074Z,1696361463.074 [WetLabsBB2FL](INFO): Powering down 2023-10-03T19:31:07.159Z,1696361467.159 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-10-03T19:31:08.002Z,1696361468.002 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-10-03T19:31:10.534Z,1696361470.534 [DAT](INFO): DAT read: 2023-10-03T19:31:10.536Z,1696361470.536 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-10-03T19:31:11.795Z,1696361471.795 [DAT](INFO): DAT read: MF Frequency Band 2023-10-03T19:31:11.796Z,1696361471.796 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.10.9 2023-10-03T19:31:11.797Z,1696361471.797 [DAT](INFO): DAT read: Oct 3 2023 19:31:04 2023-10-03T19:31:12.551Z,1696361472.551 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-10-03T19:31:12.552Z,1696361472.552 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-10-03T19:31:12.553Z,1696361472.553 [DAT](INFO): commRate: 800 2023-10-03T19:31:14.618Z,1696361474.618 [DAT](INFO): entering command mode 2023-10-03T19:31:14.818Z,1696361474.818 [DAT](INFO): DAT read: 2023-10-03T19:31:14.819Z,1696361474.819 [DAT](INFO): DAT read: user:1> 2023-10-03T19:31:14.819Z,1696361474.819 [DAT](INFO): setting verbose to 3 2023-10-03T19:31:15.071Z,1696361475.071 [DAT](INFO): DAT read: user:1> 2023-10-03T19:31:15.072Z,1696361475.072 [DAT](INFO): DAT read: Verbose | 3 2023-10-03T19:31:15.072Z,1696361475.072 [DAT](INFO): set verbose to 3 2023-10-03T19:31:15.073Z,1696361475.073 [DAT](INFO): setting DatVerbose to 27440 2023-10-03T19:31:15.323Z,1696361475.323 [DAT](INFO): DAT read: user:2> 2023-10-03T19:31:15.324Z,1696361475.324 [DAT](INFO): DAT read: DatVerbose | 27440 2023-10-03T19:31:15.324Z,1696361475.324 [DAT](INFO): set DatVerbose to 27440 2023-10-03T19:31:15.324Z,1696361475.324 [DAT](INFO): setting transmit power to 8 2023-10-03T19:31:15.575Z,1696361475.575 [DAT](INFO): DAT read: user:3> 2023-10-03T19:31:15.576Z,1696361475.576 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-10-03T19:31:15.576Z,1696361475.576 [DAT](INFO): set transmit power to 8 2023-10-03T19:31:15.576Z,1696361475.576 [DAT](INFO): setting local address to 1 2023-10-03T19:31:15.827Z,1696361475.827 [DAT](INFO): DAT read: user:4> 2023-10-03T19:31:15.828Z,1696361475.828 [DAT](INFO): DAT read: LocalAddr | 1 2023-10-03T19:31:15.828Z,1696361475.828 [DAT](INFO): set local address to 1 2023-10-03T19:31:15.829Z,1696361475.829 [DAT](INFO): Setting time to: 19:31:15 And date to:10/3/2023 2023-10-03T19:31:16.079Z,1696361476.079 [DAT](INFO): DAT read: user:5> 2023-10-03T19:31:16.080Z,1696361476.080 [DAT](INFO): DAT read: Tue Oct 3, 2023 19:31:15 2023-10-03T19:31:16.080Z,1696361476.080 [DAT](INFO): Local DAT time set to Tue Oct 3, 2023 19:31:15 2023-10-03T19:31:16.080Z,1696361476.080 [DAT](INFO): DAT read: Ok 2023-10-03T19:31:16.080Z,1696361476.080 [DAT](INFO): unknown deviceResponse_: Ok 2023-10-03T19:31:29.163Z,1696361489.163 [NAL9602](INFO): Powering up NAL9602 2023-10-03T19:31:36.098Z,1696361496.098 [Waterlinked](INFO): Powering down 2023-10-03T19:31:40.071Z,1696361500.071 [NAL9602](INFO): NAL9602 initialized 2023-10-03T19:31:46.559Z,1696361506.559 [SBIT](IMPORTANT): Beginning Startup BIT 2023-10-03T19:31:46.563Z,1696361506.563 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-03T19:31:57.551Z,1696361517.551 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000550 CHAN A1 (24V): 0.000413 CHAN A2 (12V): 0.000021 CHAN A3 (5V): -0.001724 CHAN B0 (3.3V): -0.000457 CHAN B1 (3.15aV): 0.000186 CHAN B2 (3.15bV): 0.000305 CHAN B3 (GND): -0.000221 OPEN: 0.007289 Full Scale: +/- 1 mA 2023-10-03T19:32:40.695Z,1696361560.695 [SBIT](IMPORTANT): SBIT PASSED 2023-10-03T19:32:40.695Z,1696361560.695 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-10-03T19:32:40.696Z,1696361560.696 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2023-10-03T19:32:40.696Z,1696361560.696 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool; 2023-10-03T19:32:40.696Z,1696361560.696 [SBIT](IMPORTANT): CBIT.gf24Offset=158.5 microampere; 2023-10-03T19:32:40.696Z,1696361560.696 [SBIT](IMPORTANT): PowerOnly.sampleTime1=1 second; 2023-10-03T19:32:40.696Z,1696361560.696 [SBIT](IMPORTANT): Rowe_600.loadAtStartup=0 bool; 2023-10-03T19:32:40.697Z,1696361560.697 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=341.201628 cubic_centimeter; 2023-10-03T19:32:40.697Z,1696361560.697 [SBIT](IMPORTANT): VerticalControl.massDefault=6.353459 millimeter; 2023-10-03T19:32:41.148Z,1696361561.148 [MissionManager](IMPORTANT): Started mission Startup 2023-10-03T19:32:41.148Z,1696361561.148 [Startup] Running Loop=1 2023-10-03T19:32:41.148Z,1696361561.148 [Startup](DEBUG): Aggregate::initialize Startup 2023-10-03T19:32:41.149Z,1696361561.149 [Startup:A.GoToSurface] Running Loop=1 2023-10-03T19:32:41.149Z,1696361561.149 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-03T19:32:41.149Z,1696361561.149 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-03T19:32:41.150Z,1696361561.150 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-03T19:32:41.150Z,1696361561.150 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-03T19:32:41.151Z,1696361561.151 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-03T19:32:41.151Z,1696361561.151 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-03T19:32:41.152Z,1696361561.152 [Startup:StartupSatComms] Running Loop=1 2023-10-03T19:32:41.152Z,1696361561.152 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-10-03T19:32:41.152Z,1696361561.152 [Startup:StartupSatComms:A] Running Loop=1 2023-10-03T19:32:41.510Z,1696361561.510 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-10-03T19:33:01.281Z,1696361581.281 [NAL9602](INFO): SBD MO Status=2, MOMSN=7639, MT Status=2, MTMSN=0 2023-10-03T19:33:01.281Z,1696361581.281 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-03T19:33:39.656Z,1696361619.656 [NAL9602](INFO): SBD MO Status=2, MOMSN=7639, MT Status=2, MTMSN=0 2023-10-03T19:33:39.657Z,1696361619.657 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-03T19:33:41.294Z,1696361621.294 [Startup:StartupSatComms:A](INFO): Timed out from 2023-10-03T19:32:41.2Z 2023-10-03T19:33:41.294Z,1696361621.294 [Startup:StartupSatComms:A] Stopped 2023-10-03T19:33:41.294Z,1696361621.294 [Startup:StartupSatComms:B] Running Loop=1 2023-10-03T19:33:41.686Z,1696361621.686 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-10-03T19:33:55.409Z,1696361635.409 [NAL9602](INFO): SBD MO Status=2, MOMSN=7639, MT Status=2, MTMSN=0 2023-10-03T19:33:55.409Z,1696361635.409 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-03T19:34:01.089Z,1696361641.089 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-10-03T19:34:01.089Z,1696361641.089 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-03T19:34:01.090Z,1696361641.090 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-10-03T19:34:01.090Z,1696361641.090 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-03T19:34:01.091Z,1696361641.091 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T19:34:01.092Z,1696361641.092 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T19:34:01.127Z,1696361641.127 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-03T19:34:01.127Z,1696361641.127 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-03T19:34:01.127Z,1696361641.127 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T19:34:01.495Z,1696361641.495 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-03T19:34:01.495Z,1696361641.495 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-10-03T19:34:01.496Z,1696361641.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-03T19:34:01.496Z,1696361641.496 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-10-03T19:34:01.496Z,1696361641.496 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T19:34:01.496Z,1696361641.496 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T19:34:01.883Z,1696361641.883 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T19:34:41.504Z,1696361681.504 [Startup:StartupSatComms:B](INFO): Timed out from 2023-10-03T19:33:41.3Z 2023-10-03T19:34:41.504Z,1696361681.504 [Startup:StartupSatComms:B] Stopped 2023-10-03T19:34:41.504Z,1696361681.504 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-10-03T19:34:41.504Z,1696361681.504 [Startup:StartupSatComms] Stopped 2023-10-03T19:34:41.504Z,1696361681.504 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-10-03T19:34:41.505Z,1696361681.505 [Startup](INFO): Completed Startup 2023-10-03T19:34:41.505Z,1696361681.505 [MissionManager](INFO): Startup is completed. 2023-10-03T19:34:41.505Z,1696361681.505 [MissionManager](INFO): Uninitializing Mission Startup 2023-10-03T19:34:41.505Z,1696361681.505 [Startup] Stopped 2023-10-03T19:34:41.505Z,1696361681.505 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-10-03T19:34:41.506Z,1696361681.506 [Startup:A.GoToSurface] Stopped 2023-10-03T19:34:41.510Z,1696361681.510 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-03T19:34:41.892Z,1696361681.892 [MissionManager](IMPORTANT): Started mission Default 2023-10-03T19:34:41.892Z,1696361681.892 [Default] Running Loop=1 2023-10-03T19:34:41.893Z,1696361681.893 [Default](DEBUG): Aggregate::initialize Default 2023-10-03T19:34:41.893Z,1696361681.893 [Default:B.GoToSurface] Running Loop=1 2023-10-03T19:34:41.893Z,1696361681.893 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-03T19:34:41.893Z,1696361681.893 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-03T19:34:41.893Z,1696361681.893 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-03T19:34:41.898Z,1696361681.898 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-03T19:34:41.898Z,1696361681.898 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-03T19:34:41.899Z,1696361681.899 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-03T19:34:41.899Z,1696361681.899 [Default:A.Wait] Running Loop=1 2023-10-03T19:34:41.899Z,1696361681.899 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-10-03T19:34:46.275Z,1696361686.275 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004101 2023-10-03T19:34:55.209Z,1696361695.209 [Default:A.Wait](INFO): Done Waiting. 2023-10-03T19:34:55.209Z,1696361695.209 [Default:A.Wait] Stopped 2023-10-03T19:34:55.209Z,1696361695.209 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T19:34:55.626Z,1696361695.626 [Default:CheckIn] Running Loop=1 2023-10-03T19:34:55.626Z,1696361695.626 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T19:34:55.626Z,1696361695.626 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T19:34:56.017Z,1696361696.017 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-10-03T19:35:40.864Z,1696361740.864 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2023-10-03T19:35:42.010Z,1696361742.010 [CBIT](CRITICAL): Run-time exception #7 2023-10-03T19:35:42.011Z,1696361742.011 [CBIT] Software Fault, FailCount= 1 2023-10-03T19:35:42.011Z,1696361742.011 [CBIT](ERROR): Software Fault 2023-10-03T19:35:42.022Z,1696361742.022 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-10-03T19:35:42.023Z,1696361742.023 [SyncHandler](INFO): Protected caller Thread ID is 1233 2023-10-03T19:35:42.076Z,1696361742.076 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-10-03T19:35:42.546Z,1696361742.546 [ElevatorServo](ERROR): getPosition uart error serial timeout 2023-10-03T19:35:42.546Z,1696361742.546 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2023-10-03T19:35:42.546Z,1696361742.546 [ElevatorServo] Communications Fault, FailCount= 1 2023-10-03T19:35:42.546Z,1696361742.546 [ElevatorServo](ERROR): Communications Fault 2023-10-03T19:35:42.870Z,1696361742.870 [MassServo](FAULT): Mass Shifter uart error: serial timeout 2023-10-03T19:35:42.870Z,1696361742.870 [MassServo] Communications Fault, FailCount= 1 2023-10-03T19:35:42.870Z,1696361742.870 [MassServo](ERROR): Communications Fault 2023-10-03T19:35:42.872Z,1696361742.872 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-10-03T19:35:42.872Z,1696361742.872 [CBIT](DEBUG): Powering off loads. 2023-10-03T19:35:42.904Z,1696361742.904 [CBIT](DEBUG): Disabling WDT. 2023-10-03T19:35:42.918Z,1696361742.918 [CBIT](DEBUG): Opening all GF detection circuits. 2023-10-03T19:35:43.348Z,1696361743.348 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-10-03T19:35:43.411Z,1696361743.411 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-10-03T19:35:43.411Z,1696361743.411 [ElevatorServo](INFO): Powering down 2023-10-03T19:35:43.412Z,1696361743.412 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-10-03T19:35:43.412Z,1696361743.412 [MassServo](INFO): Powering down 2023-10-03T19:35:43.980Z,1696361743.980 [NAL9602](FAULT): LCB fault: Current Limiter Activated. 2023-10-03T19:35:43.980Z,1696361743.980 [NAL9602] Hardware Fault, FailCount= 1 2023-10-03T19:35:43.980Z,1696361743.980 [NAL9602](ERROR): Hardware Fault 2023-10-03T19:35:44.084Z,1696361744.084 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-10-03T19:35:44.084Z,1696361744.084 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-10-03T19:35:44.199Z,1696361744.199 [Radio_Surface](FAULT): LCB fault: Current Limiter Activated. 2023-10-03T19:35:44.199Z,1696361744.199 [Radio_Surface] Hardware Fault, FailCount= 1 2023-10-03T19:35:44.199Z,1696361744.199 [Radio_Surface](ERROR): Hardware Fault 2023-10-03T19:35:44.323Z,1696361744.323 [NAL9602](INFO): Powering down 2023-10-03T19:35:44.602Z,1696361744.602 [Radio_Surface](INFO): Powering down 2023-10-03T19:35:44.735Z,1696361744.735 [NAL9602](FAULT): LCB fault: Current Limiter Activated. 2023-10-03T19:35:45.006Z,1696361745.006 [Radio_Surface](FAULT): LCB fault: Current Limiter Ac