2023-08-02T21:59:09.727Z,1691013549.727 [Supervisor](DEBUG): Initializing supervisor. 2023-08-02T21:59:09.732Z,1691013549.732 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-08-02T21:59:09.733Z,1691013549.733 [SyncHandler](INFO): Protected caller Thread ID is 5303 2023-08-02T21:59:09.733Z,1691013549.733 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-08-02T21:59:09.734Z,1691013549.734 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-08-02T21:59:09.735Z,1691013549.735 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5304 2023-08-02T21:59:09.739Z,1691013549.739 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-08-02T21:59:09.758Z,1691013549.758 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-08-02T21:59:09.759Z,1691013549.759 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-08-02T21:59:09.759Z,1691013549.759 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5305 2023-08-02T21:59:09.764Z,1691013549.764 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-08-02T21:59:09.765Z,1691013549.765 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-08-02T21:59:09.765Z,1691013549.765 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5306 2023-08-02T21:59:09.767Z,1691013549.767 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-08-02T21:59:09.768Z,1691013549.768 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-08-02T21:59:09.768Z,1691013549.768 [logger ThreadHandler](INFO): Protected caller Thread ID is 5307 2023-08-02T21:59:09.772Z,1691013549.772 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-08-02T21:59:09.773Z,1691013549.773 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-08-02T21:59:09.774Z,1691013549.774 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-08-02T21:59:09.937Z,1691013549.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-08-02T21:59:09.938Z,1691013549.938 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-08-02T21:59:10.153Z,1691013550.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-08-02T21:59:10.154Z,1691013550.154 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-08-02T21:59:10.405Z,1691013550.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-08-02T21:59:10.406Z,1691013550.406 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-08-02T21:59:10.515Z,1691013550.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-08-02T21:59:10.515Z,1691013550.515 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-08-02T21:59:11.232Z,1691013551.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-08-02T21:59:11.233Z,1691013551.233 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-08-02T21:59:11.728Z,1691013551.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-08-02T21:59:11.728Z,1691013551.728 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-08-02T21:59:12.205Z,1691013552.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-08-02T21:59:12.205Z,1691013552.205 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-08-02T21:59:12.603Z,1691013552.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-08-02T21:59:12.604Z,1691013552.604 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-08-02T21:59:12.941Z,1691013552.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-08-02T21:59:12.941Z,1691013552.941 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-08-02T21:59:13.307Z,1691013553.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-08-02T21:59:13.307Z,1691013553.307 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-08-02T21:59:13.527Z,1691013553.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-08-02T21:59:13.527Z,1691013553.527 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-08-02T21:59:13.668Z,1691013553.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-08-02T21:59:13.669Z,1691013553.669 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-08-02T21:59:13.751Z,1691013553.751 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-08-02T21:59:13.856Z,1691013553.856 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-08-02T21:59:14.095Z,1691013554.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-08-02T21:59:14.096Z,1691013554.096 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-08-02T21:59:14.329Z,1691013554.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-08-02T21:59:14.331Z,1691013554.331 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2023-08-02T21:59:14.332Z,1691013554.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2023-08-02T21:59:14.542Z,1691013554.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2023-08-02T21:59:15.162Z,1691013555.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-08-02T21:59:15.163Z,1691013555.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2023-08-02T21:59:15.280Z,1691013555.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2023-08-02T21:59:15.438Z,1691013555.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2023-08-02T21:59:15.541Z,1691013555.541 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2023-08-02T21:59:15.654Z,1691013555.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2023-08-02T21:59:15.765Z,1691013555.765 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2023-08-02T21:59:15.869Z,1691013555.869 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2023-08-02T21:59:16.029Z,1691013556.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2023-08-02T21:59:16.126Z,1691013556.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2023-08-02T21:59:16.223Z,1691013556.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2023-08-02T21:59:16.326Z,1691013556.326 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2023-08-02T21:59:16.326Z,1691013556.326 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2023-08-02T21:59:16.328Z,1691013556.328 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-08-02T21:59:16.491Z,1691013556.491 [InternalEnvSim] Loaded 2023-08-02T21:59:16.491Z,1691013556.491 [ComponentRegistry](DEBUG): SyncComponent "InternalEnvSim" handled in the control thread. 2023-08-02T21:59:16.492Z,1691013556.492 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-08-02T21:59:16.492Z,1691013556.492 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-08-02T21:59:16.627Z,1691013556.627 [BuoyancyServo] Loaded 2023-08-02T21:59:16.628Z,1691013556.628 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-08-02T21:59:16.649Z,1691013556.649 [ElevatorServo] Loaded 2023-08-02T21:59:16.649Z,1691013556.649 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-08-02T21:59:16.670Z,1691013556.670 [MassServo] Loaded 2023-08-02T21:59:16.671Z,1691013556.671 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-08-02T21:59:16.691Z,1691013556.691 [RudderServo] Loaded 2023-08-02T21:59:16.691Z,1691013556.691 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-08-02T21:59:16.707Z,1691013556.707 [ThrusterHE] Loaded 2023-08-02T21:59:16.708Z,1691013556.708 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-08-02T21:59:16.708Z,1691013556.708 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-08-02T21:59:16.709Z,1691013556.709 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-08-02T21:59:16.851Z,1691013556.851 [SBIT](DEBUG): Construct Startup Built In Test. 2023-08-02T21:59:16.866Z,1691013556.866 [SBIT] Loaded 2023-08-02T21:59:16.867Z,1691013556.867 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-08-02T21:59:16.869Z,1691013556.869 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-08-02T21:59:16.890Z,1691013556.890 [IBIT] Loaded 2023-08-02T21:59:16.891Z,1691013556.891 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-08-02T21:59:16.897Z,1691013556.897 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-08-02T21:59:17.120Z,1691013557.120 [CBIT] Loaded 2023-08-02T21:59:17.120Z,1691013557.120 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-08-02T21:59:17.120Z,1691013557.120 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-08-02T21:59:17.121Z,1691013557.121 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-08-02T21:59:17.294Z,1691013557.294 [CTD_Seabird] Loaded 2023-08-02T21:59:17.294Z,1691013557.294 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-08-02T21:59:17.295Z,1691013557.295 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406674E0 2023-08-02T21:59:17.296Z,1691013557.296 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5393 2023-08-02T21:59:17.329Z,1691013557.329 [ESPComponent] Loaded 2023-08-02T21:59:17.330Z,1691013557.330 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-08-02T21:59:17.349Z,1691013557.349 [PAR_Licor] Loaded 2023-08-02T21:59:17.350Z,1691013557.350 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-08-02T21:59:17.391Z,1691013557.391 [WetLabsBB2FL] Loaded 2023-08-02T21:59:17.391Z,1691013557.391 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-08-02T21:59:17.392Z,1691013557.392 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406974E0 2023-08-02T21:59:17.392Z,1691013557.392 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5394 2023-08-02T21:59:17.423Z,1691013557.423 [WetLabsSeaOWL_UV_A] Loaded 2023-08-02T21:59:17.423Z,1691013557.423 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2023-08-02T21:59:17.425Z,1691013557.425 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406C74E0 2023-08-02T21:59:17.425Z,1691013557.425 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 5395 2023-08-02T21:59:17.426Z,1691013557.426 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-08-02T21:59:17.426Z,1691013557.426 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-08-02T21:59:17.478Z,1691013557.478 [DepthRateCalculator] Loaded 2023-08-02T21:59:17.478Z,1691013557.478 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-08-02T21:59:17.483Z,1691013557.483 [PitchRateCalculator] Loaded 2023-08-02T21:59:17.484Z,1691013557.484 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-08-02T21:59:17.494Z,1691013557.494 [SpeedCalculator] Loaded 2023-08-02T21:59:17.495Z,1691013557.495 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-08-02T21:59:17.500Z,1691013557.500 [YawRateCalculator] Loaded 2023-08-02T21:59:17.500Z,1691013557.500 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-08-02T21:59:17.520Z,1691013557.520 [ElevatorOffsetCalculator] Loaded 2023-08-02T21:59:17.520Z,1691013557.520 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-08-02T21:59:17.521Z,1691013557.521 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-08-02T21:59:17.521Z,1691013557.521 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-08-02T21:59:17.623Z,1691013557.623 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-08-02T21:59:17.623Z,1691013557.623 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-08-02T21:59:17.651Z,1691013557.651 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-08-02T21:59:17.651Z,1691013557.651 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-08-02T21:59:17.765Z,1691013557.765 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-08-02T21:59:17.765Z,1691013557.765 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-08-02T21:59:17.780Z,1691013557.780 [NavChart] Loaded 2023-08-02T21:59:17.780Z,1691013557.780 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-08-02T21:59:17.786Z,1691013557.786 [UniversalFixResidualReporter] Loaded 2023-08-02T21:59:17.786Z,1691013557.786 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-08-02T21:59:17.787Z,1691013557.787 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-08-02T21:59:17.787Z,1691013557.787 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-08-02T21:59:18.844Z,1691013558.844 [AHRS_M2] Loaded 2023-08-02T21:59:18.844Z,1691013558.844 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-08-02T21:59:19.063Z,1691013559.063 [BackseatComponent] Loaded 2023-08-02T21:59:19.064Z,1691013559.064 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-08-02T21:59:19.065Z,1691013559.065 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A5D4E0 2023-08-02T21:59:19.065Z,1691013559.065 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5396 2023-08-02T21:59:19.074Z,1691013559.074 [LcmUniversalReporter] Loaded 2023-08-02T21:59:19.075Z,1691013559.075 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-08-02T21:59:20.570Z,1691013560.570 [BPC1] Loaded 2023-08-02T21:59:20.570Z,1691013560.570 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-08-02T21:59:20.646Z,1691013560.646 [DataOverHttps] Loaded 2023-08-02T21:59:20.646Z,1691013560.646 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-08-02T21:59:20.647Z,1691013560.647 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A8D4E0 2023-08-02T21:59:20.647Z,1691013560.647 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5397 2023-08-02T21:59:20.670Z,1691013560.670 [Depth_Keller] Loaded 2023-08-02T21:59:20.670Z,1691013560.670 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-08-02T21:59:20.675Z,1691013560.675 [DropWeight] Loaded 2023-08-02T21:59:20.675Z,1691013560.675 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-08-02T21:59:20.716Z,1691013560.716 [DVL_micro] Loaded 2023-08-02T21:59:20.716Z,1691013560.716 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2023-08-02T21:59:20.778Z,1691013560.778 [NAL9602] Loaded 2023-08-02T21:59:20.778Z,1691013560.778 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-08-02T21:59:20.805Z,1691013560.805 [Onboard] Loaded 2023-08-02T21:59:20.806Z,1691013560.806 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-08-02T21:59:20.807Z,1691013560.807 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40ABD4E0 2023-08-02T21:59:20.807Z,1691013560.807 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5398 2023-08-02T21:59:20.820Z,1691013560.820 [Power24vConverter] Loaded 2023-08-02T21:59:20.820Z,1691013560.820 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-08-02T21:59:20.834Z,1691013560.834 [Radio_Surface] Loaded 2023-08-02T21:59:20.834Z,1691013560.834 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-08-02T21:59:20.835Z,1691013560.835 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AED4E0 2023-08-02T21:59:20.836Z,1691013560.836 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5399 2023-08-02T21:59:20.933Z,1691013560.933 [DAT] Loaded 2023-08-02T21:59:20.933Z,1691013560.933 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-08-02T21:59:20.934Z,1691013560.934 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B1D4E0 2023-08-02T21:59:20.934Z,1691013560.934 [DAT ThreadHandler](INFO): Protected caller Thread ID is 5400 2023-08-02T21:59:20.935Z,1691013560.935 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-08-02T21:59:20.936Z,1691013560.936 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-08-02T21:59:20.994Z,1691013560.994 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-08-02T21:59:20.994Z,1691013560.994 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-08-02T21:59:21.049Z,1691013561.049 [VerticalControl](DEBUG): Construct VerticalControl. 2023-08-02T21:59:21.101Z,1691013561.101 [VerticalControl] Loaded 2023-08-02T21:59:21.101Z,1691013561.101 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-08-02T21:59:21.104Z,1691013561.104 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-08-02T21:59:21.143Z,1691013561.143 [HorizontalControl] Loaded 2023-08-02T21:59:21.143Z,1691013561.143 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-08-02T21:59:21.145Z,1691013561.145 [SpeedControl](DEBUG): Construct SpeedControl. 2023-08-02T21:59:21.147Z,1691013561.147 [SpeedControl] Loaded 2023-08-02T21:59:21.147Z,1691013561.147 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-08-02T21:59:21.150Z,1691013561.150 [LoopControl](DEBUG): Construct LoopControl. 2023-08-02T21:59:21.151Z,1691013561.151 [LoopControl] Loaded 2023-08-02T21:59:21.151Z,1691013561.151 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-08-02T21:59:21.152Z,1691013561.152 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-08-02T21:59:21.152Z,1691013561.152 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-08-02T21:59:21.163Z,1691013561.163 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-08-02T21:59:21.163Z,1691013561.163 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-08-02T21:59:21.477Z,1691013561.477 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-08-02T21:59:21.484Z,1691013561.484 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-08-02T21:59:21.487Z,1691013561.487 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-08-02T21:59:21.498Z,1691013561.498 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-08-02T21:59:21.500Z,1691013561.500 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C294E0 2023-08-02T21:59:21.500Z,1691013561.500 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5401 2023-08-02T21:59:21.505Z,1691013561.505 [Supervisor](INFO): Main Thread ID is 5300 2023-08-02T21:59:21.505Z,1691013561.505 [Supervisor](DEBUG): Running supervisor. 2023-08-02T21:59:21.505Z,1691013561.505 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5402 2023-08-02T21:59:21.506Z,1691013561.506 [CommandExec](INFO): Initializing the command executive. 2023-08-02T21:59:21.508Z,1691013561.508 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5403 2023-08-02T21:59:21.511Z,1691013561.511 [controlThread ThreadHandler](INFO): Handler Thread ID is 5404 2023-08-02T21:59:21.512Z,1691013561.512 [controlThread](DEBUG): Initializing ControlThread 2023-08-02T21:59:21.513Z,1691013561.513 [InternalEnvSim](DEBUG): InternaEnvlSim initializing... 2023-08-02T21:59:21.539Z,1691013561.539 [logger ThreadHandler](INFO): Handler Thread ID is 5405 2023-08-02T21:59:21.580Z,1691013561.580 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5406 2023-08-02T21:59:21.581Z,1691013561.581 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-08-02T21:59:21.589Z,1691013561.589 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5408 2023-08-02T21:59:21.596Z,1691013561.596 [WetLabsBB2FL](INFO): Powering up 2023-08-02T21:59:21.597Z,1691013561.597 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 5410 2023-08-02T21:59:21.609Z,1691013561.609 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5411 2023-08-02T21:59:21.624Z,1691013561.624 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5412 2023-08-02T21:59:21.625Z,1691013561.625 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-08-02T21:59:21.640Z,1691013561.640 [Onboard ThreadHandler](INFO): Handler Thread ID is 5413 2023-08-02T21:59:21.670Z,1691013561.670 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5414 2023-08-02T21:59:21.685Z,1691013561.685 [DAT ThreadHandler](INFO): Handler Thread ID is 5415 2023-08-02T21:59:21.686Z,1691013561.686 [DAT](INFO): Powering up 2023-08-02T21:59:21.686Z,1691013561.686 [DAT](DEBUG): Initializing DAT. 2023-08-02T21:59:21.695Z,1691013561.695 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5417 2023-08-02T21:59:21.701Z,1691013561.701 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000 2023-08-02T21:59:21.702Z,1691013561.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000 2023-08-02T21:59:21.702Z,1691013561.702 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000 2023-08-02T21:59:21.702Z,1691013561.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000 2023-08-02T21:59:21.702Z,1691013561.702 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000 2023-08-02T21:59:21.702Z,1691013561.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000 2023-08-02T21:59:21.702Z,1691013561.702 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000 2023-08-02T21:59:21.703Z,1691013561.703 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000 2023-08-02T21:59:21.703Z,1691013561.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000 2023-08-02T21:59:21.703Z,1691013561.703 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000 2023-08-02T21:59:21.703Z,1691013561.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000 2023-08-02T21:59:21.703Z,1691013561.703 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000 2023-08-02T21:59:21.703Z,1691013561.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000 2023-08-02T21:59:21.704Z,1691013561.704 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000 2023-08-02T21:59:21.704Z,1691013561.704 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000 2023-08-02T21:59:21.704Z,1691013561.704 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000 2023-08-02T21:59:21.705Z,1691013561.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000 2023-08-02T21:59:21.705Z,1691013561.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000 2023-08-02T21:59:21.705Z,1691013561.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000 2023-08-02T21:59:21.705Z,1691013561.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000 2023-08-02T21:59:21.705Z,1691013561.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000 2023-08-02T21:59:21.705Z,1691013561.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000 2023-08-02T21:59:21.706Z,1691013561.706 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000 2023-08-02T21:59:21.706Z,1691013561.706 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000 2023-08-02T21:59:21.706Z,1691013561.706 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000 2023-08-02T21:59:21.706Z,1691013561.706 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000 2023-08-02T21:59:21.709Z,1691013561.709 [SBIT](INFO): Initialize SBIT Component. 2023-08-02T21:59:21.714Z,1691013561.714 [SBIT](IMPORTANT): git: 2023-08-02 2023-08-02T21:59:21.715Z,1691013561.715 [SBIT](INFO): git hash: 04bff54855c04b79675eb3036915d66bd94ee3d9 2023-08-02T21:59:21.717Z,1691013561.717 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-08-02T21:59:21.718Z,1691013561.718 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-08-02T21:59:21.719Z,1691013561.719 [SBIT](INFO): Beginning SBIT in 107.000000 seconds. 2023-08-02T21:59:21.720Z,1691013561.720 [IBIT](INFO): Initialize IBIT Component. 2023-08-02T21:59:21.721Z,1691013561.721 [CBIT](DEBUG): Initialize CBIT Component. 2023-08-02T21:59:21.736Z,1691013561.736 [CBIT](DEBUG): Initialized mux pins. 2023-08-02T21:59:21.736Z,1691013561.736 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2023-08-02T21:59:21.736Z,1691013561.736 [CBIT](DEBUG): Initializing the watchdog timer. 2023-08-02T21:59:21.760Z,1691013561.760 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-08-02T21:59:21.760Z,1691013561.760 [CBIT](DEBUG): Initializing heartbeat. 2023-08-02T21:59:21.832Z,1691013561.832 [CBIT](DEBUG): Deactivating GF circuits. 2023-08-02T21:59:21.832Z,1691013561.832 [CBIT](DEBUG): Deactivating emergency mode. 2023-08-02T21:59:21.872Z,1691013561.872 [CBIT](DEBUG): Backplane powered. 2023-08-02T21:59:21.876Z,1691013561.876 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-08-02T21:59:21.877Z,1691013561.877 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-08-02T21:59:21.877Z,1691013561.877 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-08-02T21:59:21.877Z,1691013561.877 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-08-02T21:59:21.878Z,1691013561.878 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-08-02T21:59:21.879Z,1691013561.879 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-08-02T21:59:21.879Z,1691013561.879 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-08-02T21:59:21.890Z,1691013561.890 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-08-02T21:59:21.892Z,1691013561.892 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-08-02T21:59:21.893Z,1691013561.893 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-08-02T21:59:21.893Z,1691013561.893 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-08-02T21:59:21.894Z,1691013561.894 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-08-02T21:59:21.957Z,1691013561.957 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-08-02T21:59:22.000Z,1691013562.000 [MissionManager](DEBUG): 2023-08-02T21:59:22.001Z,1691013562.001 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-08-02T21:59:22.072Z,1691013562.072 [Radio_Surface](INFO): Powering up 2023-08-02T21:59:22.097Z,1691013562.097 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-08-02T21:59:22.098Z,1691013562.098 [Default:A.Wait](DEBUG): Construct Wait. 2023-08-02T21:59:22.117Z,1691013562.117 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-08-02T21:59:22.157Z,1691013562.157 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-08-02T21:59:22.180Z,1691013562.180 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-08-02T21:59:22.186Z,1691013562.186 [Default:E.Execute](DEBUG): Construct Execute. 2023-08-02T21:59:22.201Z,1691013562.201 [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-08-02T21:59:22.206Z,1691013562.206 [controlThread](DEBUG): Component order: CycleStarter,InternalEnvSim,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-08-02T21:59:22.227Z,1691013562.227 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-08-02T21:59:22.297Z,1691013562.297 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.894409 decibar, 0.000000 m 2023-08-02T21:59:22.301Z,1691013562.301 [Power24vConverter](INFO): Powering up. 2023-08-02T21:59:22.313Z,1691013562.313 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-08-02T21:59:22.346Z,1691013562.346 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-08-02T21:59:22.352Z,1691013562.352 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-08-02T21:59:22.353Z,1691013562.353 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-08-02T21:59:22.360Z,1691013562.360 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-08-02T21:59:22.361Z,1691013562.361 [MassServo](DEBUG): Initializing EZServoServo. 2023-08-02T21:59:22.368Z,1691013562.368 [MassServo](DEBUG): Initializing MassServo. 2023-08-02T21:59:22.369Z,1691013562.369 [RudderServo](DEBUG): Initializing EZServoServo. 2023-08-02T21:59:22.376Z,1691013562.376 [RudderServo](DEBUG): Initializing RudderServo. 2023-08-02T21:59:22.377Z,1691013562.377 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-08-02T21:59:22.384Z,1691013562.384 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-08-02T21:59:23.696Z,1691013563.696 [WetLabsBB2FL](INFO): Powering down 2023-08-02T21:59:28.145Z,1691013568.145 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-08-02T21:59:33.780Z,1691013573.780 [DAT](INFO): DAT read: 2023-08-02T21:59:33.782Z,1691013573.782 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-08-02T21:59:35.293Z,1691013575.293 [DAT](INFO): DAT read: MF Frequency Band 2023-08-02T21:59:35.294Z,1691013575.294 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2023-08-02T21:59:35.294Z,1691013575.294 [DAT](INFO): DAT read: Aug 2 2023 21:59:29 2023-08-02T21:59:36.430Z,1691013576.430 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004264 2023-08-02T21:59:36.553Z,1691013576.553 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-08-02T21:59:36.554Z,1691013576.554 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2023-08-02T21:59:36.555Z,1691013576.555 [DAT](INFO): commRate: 1200 2023-08-02T21:59:37.179Z,1691013577.179 [DVL_micro](ERROR): Failed to parse: :WI,-00257,+01324,+00575,+00000,A 2023-08-02T21:59:38.620Z,1691013578.620 [DAT](INFO): entering command mode 2023-08-02T21:59:38.820Z,1691013578.820 [DAT](INFO): DAT read: 2023-08-02T21:59:38.821Z,1691013578.821 [DAT](INFO): DAT read: user:1> 2023-08-02T21:59:38.821Z,1691013578.821 [DAT](INFO): setting verbose to 3 2023-08-02T21:59:39.073Z,1691013579.073 [DAT](INFO): DAT read: user:1> 2023-08-02T21:59:39.074Z,1691013579.074 [DAT](INFO): DAT read: Verbose | 3 2023-08-02T21:59:39.074Z,1691013579.074 [DAT](INFO): set verbose to 3 2023-08-02T21:59:39.074Z,1691013579.074 [DAT](INFO): setting DatVerbose to 27440 2023-08-02T21:59:39.325Z,1691013579.325 [DAT](INFO): DAT read: user:2> 2023-08-02T21:59:39.326Z,1691013579.326 [DAT](INFO): DAT read: DatVerbose | 27440 2023-08-02T21:59:39.326Z,1691013579.326 [DAT](INFO): set DatVerbose to 27440 2023-08-02T21:59:39.326Z,1691013579.326 [DAT](INFO): setting transmit power to 8 2023-08-02T21:59:39.576Z,1691013579.576 [DAT](INFO): DAT read: user:3> 2023-08-02T21:59:39.577Z,1691013579.577 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-08-02T21:59:39.578Z,1691013579.578 [DAT](INFO): set transmit power to 8 2023-08-02T21:59:39.578Z,1691013579.578 [DAT](INFO): setting local address to 5 2023-08-02T21:59:39.829Z,1691013579.829 [DAT](INFO): DAT read: user:4> 2023-08-02T21:59:39.830Z,1691013579.830 [DAT](INFO): DAT read: LocalAddr | 5 2023-08-02T21:59:39.830Z,1691013579.830 [DAT](INFO): set local address to 5 2023-08-02T21:59:39.831Z,1691013579.831 [DAT](INFO): Setting time to: 21:59:39 And date to:8/2/2023 2023-08-02T21:59:40.081Z,1691013580.081 [DAT](INFO): DAT read: user:5> 2023-08-02T21:59:40.083Z,1691013580.083 [DAT](INFO): DAT read: Wed Aug 2, 2023 21:59:39 2023-08-02T21:59:40.083Z,1691013580.083 [DAT](INFO): Local DAT time set to Wed Aug 2, 2023 21:59:39 2023-08-02T21:59:50.935Z,1691013590.935 [NAL9602](INFO): Powering up NAL9602 2023-08-02T21:59:55.002Z,1691013595.002 [CommandExec](IMPORTANT): got command restart system 2023-08-02T21:59:57.232Z,1691013597.232 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-08-02T21:59:57.232Z,1691013597.232 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T21:59:57.232Z,1691013597.232 [CommandExec](INFO): Uninitializing the command executive. 2023-08-02T21:59:57.233Z,1691013597.233 [CommandExec](INFO): Uninitializing the command scheduler. 2023-08-02T21:59:57.233Z,1691013597.233 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-08-02T21:59:57.256Z,1691013597.256 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-08-02T21:59:57.256Z,1691013597.256 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-08-02T21:59:57.256Z,1691013597.256 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-08-02T21:59:57.257Z,1691013597.257 [NavChartDb](INFO): Join timeout helper Thread ID is 5431 2023-08-02T22:00:01.006Z,1691013601.006 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T22:00:01.006Z,1691013601.006 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-08-02T22:00:01.024Z,1691013601.024 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-08-02T22:00:01.024Z,1691013601.024 [DAT ThreadHandler](INFO): Thread cancelled. 2023-08-02T22:00:01.025Z,1691013601.025 [DAT](INFO): Join timeout helper Thread ID is 5432 2023-08-02T22:00:01.264Z,1691013601.264 [DAT](INFO): Powering down 2023-08-02T22:00:01.337Z,1691013601.337 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T22:00:01.337Z,1691013601.337 [DAT](INFO): Powering down 2023-08-02T22:00:01.338Z,1691013601.338 [DAT ThreadHandler](INFO): Thread cancelled. 2023-08-02T22:00:01.360Z,1691013601.360 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-08-02T22:00:01.360Z,1691013601.360 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-08-02T22:00:01.360Z,1691013601.360 [Radio_Surface](INFO): Join timeout helper Thread ID is 5433 2023-08-02T22:00:01.746Z,1691013601.746 [Radio_Surface](INFO): Powering down 2023-08-02T22:00:01.748Z,1691013601.748 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-02T22:00:01.748Z,1691013601.748 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-08-02T22:00:01.768Z,16910136