2025-04-08T18:55:10.566Z,1744138510.566 [Supervisor](DEBUG): Initializing supervisor. 2025-04-08T18:55:10.571Z,1744138510.571 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-04-08T18:55:10.571Z,1744138510.571 [SyncHandler](INFO): Protected caller Thread ID is 829 2025-04-08T18:55:10.572Z,1744138510.572 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-04-08T18:55:10.573Z,1744138510.573 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-04-08T18:55:10.573Z,1744138510.573 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 830 2025-04-08T18:55:10.577Z,1744138510.577 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-04-08T18:55:10.597Z,1744138510.597 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-04-08T18:55:10.598Z,1744138510.598 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-04-08T18:55:10.598Z,1744138510.598 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 831 2025-04-08T18:55:10.603Z,1744138510.603 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-04-08T18:55:10.604Z,1744138510.604 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-04-08T18:55:10.604Z,1744138510.604 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 832 2025-04-08T18:55:10.606Z,1744138510.606 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-04-08T18:55:10.607Z,1744138510.607 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-04-08T18:55:10.608Z,1744138510.608 [logger ThreadHandler](INFO): Protected caller Thread ID is 833 2025-04-08T18:55:10.612Z,1744138510.612 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-04-08T18:55:10.612Z,1744138510.612 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-04-08T18:55:10.618Z,1744138510.618 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-04-08T18:55:10.724Z,1744138510.724 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-04-08T18:55:10.726Z,1744138510.726 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-04-08T18:55:10.963Z,1744138510.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-04-08T18:55:10.965Z,1744138510.965 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-04-08T18:55:11.242Z,1744138511.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-04-08T18:55:11.244Z,1744138511.244 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-04-08T18:55:11.362Z,1744138511.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-04-08T18:55:11.363Z,1744138511.363 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-04-08T18:55:12.147Z,1744138512.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-04-08T18:55:12.148Z,1744138512.148 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-04-08T18:55:12.673Z,1744138512.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-04-08T18:55:12.674Z,1744138512.674 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-04-08T18:55:13.247Z,1744138513.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-04-08T18:55:13.247Z,1744138513.247 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-04-08T18:55:13.686Z,1744138513.686 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-04-08T18:55:13.754Z,1744138513.754 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-04-08T18:55:14.382Z,1744138514.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-04-08T18:55:14.383Z,1744138514.383 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-04-08T18:55:14.774Z,1744138514.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-04-08T18:55:14.775Z,1744138514.775 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-04-08T18:55:15.014Z,1744138515.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-04-08T18:55:15.015Z,1744138515.015 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-04-08T18:55:15.167Z,1744138515.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-04-08T18:55:15.168Z,1744138515.168 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-04-08T18:55:15.261Z,1744138515.261 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-04-08T18:55:15.352Z,1744138515.352 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-04-08T18:55:15.556Z,1744138515.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-04-08T18:55:15.558Z,1744138515.558 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-04-08T18:55:15.814Z,1744138515.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-04-08T18:55:15.816Z,1744138515.816 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2025-04-08T18:55:15.818Z,1744138515.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2025-04-08T18:55:15.913Z,1744138515.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2025-04-08T18:55:16.192Z,1744138516.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-04-08T18:55:16.193Z,1744138516.193 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2025-04-08T18:55:16.287Z,1744138516.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2025-04-08T18:55:16.467Z,1744138516.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2025-04-08T18:55:16.578Z,1744138516.578 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2025-04-08T18:55:16.726Z,1744138516.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2025-04-08T18:55:16.849Z,1744138516.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2025-04-08T18:55:16.953Z,1744138516.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2025-04-08T18:55:17.124Z,1744138517.124 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2025-04-08T18:55:17.231Z,1744138517.231 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg 2025-04-08T18:55:17.327Z,1744138517.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2025-04-08T18:55:17.435Z,1744138517.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2025-04-08T18:55:17.532Z,1744138517.532 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-04-08T18:55:17.556Z,1744138517.556 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-04-08T18:55:17.778Z,1744138517.778 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-04-08T18:55:17.778Z,1744138517.778 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-04-08T18:55:17.973Z,1744138517.973 [BuoyancyServo] Loaded 2025-04-08T18:55:17.974Z,1744138517.974 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-04-08T18:55:17.997Z,1744138517.997 [ElevatorServo] Loaded 2025-04-08T18:55:17.997Z,1744138517.997 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-04-08T18:55:18.020Z,1744138518.020 [MassServo] Loaded 2025-04-08T18:55:18.020Z,1744138518.020 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-04-08T18:55:18.042Z,1744138518.042 [RudderServo] Loaded 2025-04-08T18:55:18.043Z,1744138518.043 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-04-08T18:55:18.061Z,1744138518.061 [ThrusterHE] Loaded 2025-04-08T18:55:18.061Z,1744138518.061 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-04-08T18:55:18.062Z,1744138518.062 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-04-08T18:55:18.063Z,1744138518.063 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-04-08T18:55:18.264Z,1744138518.264 [SBIT](DEBUG): Construct Startup Built In Test. 2025-04-08T18:55:18.279Z,1744138518.279 [SBIT] Loaded 2025-04-08T18:55:18.279Z,1744138518.279 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-04-08T18:55:18.282Z,1744138518.282 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-04-08T18:55:18.303Z,1744138518.303 [IBIT] Loaded 2025-04-08T18:55:18.304Z,1744138518.304 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-04-08T18:55:18.309Z,1744138518.309 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-04-08T18:55:18.502Z,1744138518.502 [CBIT] Loaded 2025-04-08T18:55:18.503Z,1744138518.503 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-04-08T18:55:18.534Z,1744138518.534 [GFScanner] Loaded 2025-04-08T18:55:18.535Z,1744138518.535 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-04-08T18:55:18.535Z,1744138518.535 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-04-08T18:55:18.536Z,1744138518.536 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-04-08T18:55:18.752Z,1744138518.752 [CTD_Seabird] Loaded 2025-04-08T18:55:18.752Z,1744138518.752 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-04-08T18:55:18.753Z,1744138518.753 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406834E0 2025-04-08T18:55:18.753Z,1744138518.753 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 921 2025-04-08T18:55:18.788Z,1744138518.788 [ESPComponent] Loaded 2025-04-08T18:55:18.789Z,1744138518.789 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-04-08T18:55:18.810Z,1744138518.810 [PAR_Licor] Loaded 2025-04-08T18:55:18.811Z,1744138518.811 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-04-08T18:55:18.856Z,1744138518.856 [WetLabsBB2FL] Loaded 2025-04-08T18:55:18.857Z,1744138518.857 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-04-08T18:55:18.857Z,1744138518.857 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B34E0 2025-04-08T18:55:18.858Z,1744138518.858 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 922 2025-04-08T18:55:18.859Z,1744138518.859 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-04-08T18:55:18.861Z,1744138518.861 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-04-08T18:55:19.306Z,1744138519.306 [DepthRateCalculator] Loaded 2025-04-08T18:55:19.306Z,1744138519.306 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-04-08T18:55:19.335Z,1744138519.335 [PitchRateCalculator] Loaded 2025-04-08T18:55:19.336Z,1744138519.336 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-04-08T18:55:19.423Z,1744138519.423 [SpeedCalculator] Loaded 2025-04-08T18:55:19.423Z,1744138519.423 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-04-08T18:55:19.428Z,1744138519.428 [YawRateCalculator] Loaded 2025-04-08T18:55:19.428Z,1744138519.428 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-04-08T18:55:19.448Z,1744138519.448 [ElevatorOffsetCalculator] Loaded 2025-04-08T18:55:19.448Z,1744138519.448 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-04-08T18:55:19.448Z,1744138519.448 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-04-08T18:55:19.449Z,1744138519.449 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-04-08T18:55:20.025Z,1744138520.025 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-04-08T18:55:20.026Z,1744138520.026 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-04-08T18:55:20.074Z,1744138520.074 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-04-08T18:55:20.076Z,1744138520.076 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-04-08T18:55:20.216Z,1744138520.216 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-04-08T18:55:20.216Z,1744138520.216 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-04-08T18:55:20.230Z,1744138520.230 [NavChart] Loaded 2025-04-08T18:55:20.231Z,1744138520.231 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-04-08T18:55:20.236Z,1744138520.236 [UniversalFixResidualReporter] Loaded 2025-04-08T18:55:20.237Z,1744138520.237 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-04-08T18:55:20.237Z,1744138520.237 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-04-08T18:55:20.238Z,1744138520.238 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-04-08T18:55:21.126Z,1744138521.126 [AHRS_M2] Loaded 2025-04-08T18:55:21.126Z,1744138521.126 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-04-08T18:55:21.379Z,1744138521.379 [BackseatComponent] Loaded 2025-04-08T18:55:21.379Z,1744138521.379 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-04-08T18:55:21.380Z,1744138521.380 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0 2025-04-08T18:55:21.380Z,1744138521.380 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923 2025-04-08T18:55:21.383Z,1744138521.383 [LcmUniversalReporter] Loaded 2025-04-08T18:55:21.384Z,1744138521.384 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-04-08T18:55:22.121Z,1744138522.121 [BPC1] Loaded 2025-04-08T18:55:22.122Z,1744138522.122 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-04-08T18:55:22.219Z,1744138522.219 [DAT] Loaded 2025-04-08T18:55:22.219Z,1744138522.219 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-04-08T18:55:22.220Z,1744138522.220 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0 2025-04-08T18:55:22.221Z,1744138522.221 [DAT ThreadHandler](INFO): Protected caller Thread ID is 924 2025-04-08T18:55:22.362Z,1744138522.362 [DataOverHttps] Loaded 2025-04-08T18:55:22.363Z,1744138522.363 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-04-08T18:55:22.364Z,1744138522.364 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0 2025-04-08T18:55:22.364Z,1744138522.364 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925 2025-04-08T18:55:22.388Z,1744138522.388 [Depth_Keller] Loaded 2025-04-08T18:55:22.388Z,1744138522.388 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-04-08T18:55:22.393Z,1744138522.393 [DropWeight] Loaded 2025-04-08T18:55:22.393Z,1744138522.393 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-04-08T18:55:22.435Z,1744138522.435 [DVL_micro] Loaded 2025-04-08T18:55:22.435Z,1744138522.435 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2025-04-08T18:55:22.498Z,1744138522.498 [NAL9602] Loaded 2025-04-08T18:55:22.498Z,1744138522.498 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-04-08T18:55:22.524Z,1744138522.524 [Onboard] Loaded 2025-04-08T18:55:22.525Z,1744138522.525 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-04-08T18:55:22.526Z,1744138522.526 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0 2025-04-08T18:55:22.526Z,1744138522.526 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926 2025-04-08T18:55:22.532Z,1744138522.532 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1 2025-04-08T18:55:22.546Z,1744138522.546 [PowerOnly] Loaded 2025-04-08T18:55:22.546Z,1744138522.546 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2025-04-08T18:55:22.560Z,1744138522.560 [Power24vConverter] Loaded 2025-04-08T18:55:22.561Z,1744138522.561 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-04-08T18:55:22.577Z,1744138522.577 [Radio_Surface] Loaded 2025-04-08T18:55:22.577Z,1744138522.577 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-04-08T18:55:22.578Z,1744138522.578 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B3F4E0 2025-04-08T18:55:22.579Z,1744138522.579 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927 2025-04-08T18:55:22.595Z,1744138522.595 [Sonardyne_Nano] Loaded 2025-04-08T18:55:22.595Z,1744138522.595 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-04-08T18:55:22.596Z,1744138522.596 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-04-08T18:55:22.596Z,1744138522.596 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-04-08T18:55:22.683Z,1744138522.683 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-04-08T18:55:22.684Z,1744138522.684 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-04-08T18:55:22.758Z,1744138522.758 [VerticalControl](DEBUG): Construct VerticalControl. 2025-04-08T18:55:22.811Z,1744138522.811 [VerticalControl] Loaded 2025-04-08T18:55:22.811Z,1744138522.811 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-04-08T18:55:22.814Z,1744138522.814 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-04-08T18:55:22.859Z,1744138522.859 [HorizontalControl] Loaded 2025-04-08T18:55:22.859Z,1744138522.859 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-04-08T18:55:22.862Z,1744138522.862 [SpeedControl](DEBUG): Construct SpeedControl. 2025-04-08T18:55:22.863Z,1744138522.863 [SpeedControl] Loaded 2025-04-08T18:55:22.864Z,1744138522.864 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-04-08T18:55:22.867Z,1744138522.867 [LoopControl](DEBUG): Construct LoopControl. 2025-04-08T18:55:22.867Z,1744138522.867 [LoopControl] Loaded 2025-04-08T18:55:22.867Z,1744138522.867 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-04-08T18:55:22.868Z,1744138522.868 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-04-08T18:55:22.868Z,1744138522.868 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-04-08T18:55:22.884Z,1744138522.884 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-04-08T18:55:22.885Z,1744138522.885 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-04-08T18:55:23.302Z,1744138523.302 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-04-08T18:55:23.309Z,1744138523.309 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-04-08T18:55:23.312Z,1744138523.312 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-04-08T18:55:23.323Z,1744138523.323 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-04-08T18:55:23.324Z,1744138523.324 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0 2025-04-08T18:55:23.325Z,1744138523.325 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928 2025-04-08T18:55:23.329Z,1744138523.329 [Supervisor](INFO): Main Thread ID is 822 2025-04-08T18:55:23.329Z,1744138523.329 [Supervisor](DEBUG): Running supervisor. 2025-04-08T18:55:23.330Z,1744138523.330 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929 2025-04-08T18:55:23.330Z,1744138523.330 [CommandExec](INFO): Initializing the command executive. 2025-04-08T18:55:23.332Z,1744138523.332 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930 2025-04-08T18:55:23.334Z,1744138523.334 [controlThread ThreadHandler](INFO): Handler Thread ID is 931 2025-04-08T18:55:23.334Z,1744138523.334 [controlThread](DEBUG): Initializing ControlThread 2025-04-08T18:55:23.336Z,1744138523.336 [SBIT](INFO): Initialize SBIT Component. 2025-04-08T18:55:23.337Z,1744138523.337 [SBIT](IMPORTANT): git: 2025-03-26-1-g1815535cc 2025-04-08T18:55:23.337Z,1744138523.337 [SBIT](INFO): git hash: 1815535cca105e464bdb7831f6233b5043f0283f 2025-04-08T18:55:23.337Z,1744138523.337 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-04-08T18:55:23.338Z,1744138523.338 [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 2025-04-08T18:55:23.340Z,1744138523.340 [SBIT](INFO): Beginning SBIT in 69.000000 seconds. 2025-04-08T18:55:23.340Z,1744138523.340 [IBIT](INFO): Initialize IBIT Component. 2025-04-08T18:55:23.341Z,1744138523.341 [CBIT](DEBUG): Initialize CBIT Component. 2025-04-08T18:55:23.342Z,1744138523.342 [logger ThreadHandler](INFO): Handler Thread ID is 932 2025-04-08T18:55:23.352Z,1744138523.352 [CBIT](DEBUG): Initialized mux pins. 2025-04-08T18:55:23.352Z,1744138523.352 [CBIT](DEBUG): Initializing the watchdog timer. 2025-04-08T18:55:23.360Z,1744138523.360 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 933 2025-04-08T18:55:23.361Z,1744138523.361 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-04-08T18:55:23.366Z,1744138523.365 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 935 2025-04-08T18:55:23.368Z,1744138523.368 [WetLabsBB2FL](INFO): Powering up 2025-04-08T18:55:23.369Z,1744138523.369 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937 2025-04-08T18:55:23.376Z,1744138523.376 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-04-08T18:55:23.376Z,1744138523.376 [CBIT](DEBUG): Initializing heartbeat. 2025-04-08T18:55:23.377Z,1744138523.377 [DAT ThreadHandler](INFO): Handler Thread ID is 938 2025-04-08T18:55:23.378Z,1744138523.378 [DAT](INFO): Powering up 2025-04-08T18:55:23.378Z,1744138523.378 [DAT](DEBUG): Initializing DAT. 2025-04-08T18:55:23.381Z,1744138523.381 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 940 2025-04-08T18:55:23.382Z,1744138523.382 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-04-08T18:55:23.404Z,1744138523.404 [Onboard ThreadHandler](INFO): Handler Thread ID is 941 2025-04-08T18:55:23.421Z,1744138523.421 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 942 2025-04-08T18:55:23.444Z,1744138523.444 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2025-04-08T18:55:23.448Z,1744138523.448 [CBIT](DEBUG): Deactivating emergency mode. 2025-04-08T18:55:23.455Z,1744138523.455 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-04-08T18:55:23.455Z,1744138523.455 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-04-08T18:55:23.455Z,1744138523.455 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-04-08T18:55:23.455Z,1744138523.455 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-04-08T18:55:23.455Z,1744138523.455 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-04-08T18:55:23.456Z,1744138523.456 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-04-08T18:55:23.456Z,1744138523.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-04-08T18:55:23.456Z,1744138523.456 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-04-08T18:55:23.488Z,1744138523.488 [CBIT](DEBUG): Backplane powered. 2025-04-08T18:55:23.488Z,1744138523.488 [GFScanner](DEBUG): Initializing GFScanner 2025-04-08T18:55:23.488Z,1744138523.488 [GFScanner](DEBUG): Deactivating GF circuits. 2025-04-08T18:55:23.496Z,1744138523.496 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-04-08T18:55:23.497Z,1744138523.497 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-04-08T18:55:23.497Z,1744138523.497 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-04-08T18:55:23.497Z,1744138523.497 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-04-08T18:55:23.498Z,1744138523.498 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-04-08T18:55:23.499Z,1744138523.499 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-04-08T18:55:23.499Z,1744138523.499 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-04-08T18:55:23.503Z,1744138523.503 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-04-08T18:55:23.509Z,1744138523.509 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-04-08T18:55:23.509Z,1744138523.509 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-04-08T18:55:23.510Z,1744138523.510 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-04-08T18:55:23.511Z,1744138523.511 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-04-08T18:55:23.511Z,1744138523.511 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-04-08T18:55:23.520Z,1744138523.520 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-04-08T18:55:23.525Z,1744138523.525 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-04-08T18:55:23.595Z,1744138523.595 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-04-08T18:55:23.616Z,1744138523.616 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-08T18:55:23.633Z,1744138523.633 [DAT](INFO): Using elevation to construct direction to contact in vehicle frame. 2025-04-08T18:55:23.663Z,1744138523.663 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2025-04-08T18:55:23.664Z,1744138523.664 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-04-08T18:55:23.664Z,1744138523.664 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-04-08T18:55:23.676Z,1744138523.676 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-04-08T18:55:23.828Z,1744138523.828 [Radio_Surface](INFO): Powering up 2025-04-08T18:55:23.976Z,1744138523.976 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-04-08T18:55:23.998Z,1744138523.998 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-04-08T18:55:23.999Z,1744138523.999 [Default:A.Wait](DEBUG): Construct Wait. 2025-04-08T18:55:24.001Z,1744138524.001 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-08T18:55:24.042Z,1744138524.042 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-04-08T18:55:24.045Z,1744138524.045 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-04-08T18:55:24.067Z,1744138524.067 [Default:E.Execute](DEBUG): Construct Execute. 2025-04-08T18:55:24.070Z,1744138524.070 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2025-04-08T18:55:24.080Z,1744138524.080 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,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,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-04-08T18:55:24.094Z,1744138524.094 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-04-08T18:55:24.248Z,1744138524.248 [Depth_Keller](INFO): Initializing. 2025-04-08T18:55:24.252Z,1744138524.252 [Power24vConverter](INFO): Powering up. 2025-04-08T18:55:24.253Z,1744138524.253 [Sonardyne_Nano](INFO): Initializing. 2025-04-08T18:55:24.280Z,1744138524.280 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-08T18:55:24.333Z,1744138524.333 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-08T18:55:24.340Z,1744138524.340 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-08T18:55:24.341Z,1744138524.341 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-04-08T18:55:24.348Z,1744138524.348 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-04-08T18:55:24.349Z,1744138524.349 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-08T18:55:24.356Z,1744138524.356 [MassServo](DEBUG): Initializing MassServo. 2025-04-08T18:55:24.357Z,1744138524.357 [RudderServo](DEBUG): Initializing EZServoServo. 2025-04-08T18:55:24.365Z,1744138524.365 [RudderServo](DEBUG): Initializing RudderServo. 2025-04-08T18:55:24.365Z,1744138524.365 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-04-08T18:55:24.372Z,1744138524.372 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-04-08T18:55:24.534Z,1744138524.534 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m 2025-04-08T18:55:24.535Z,1744138524.535 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-08T18:55:24.535Z,1744138524.535 [DropWeight] Hardware Fault, FailCount= 1 2025-04-08T18:55:24.535Z,1744138524.535 [DropWeight](ERROR): Hardware Fault 2025-04-08T18:55:24.542Z,1744138524.542 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-08T18:55:24.567Z,1744138524.567 [CommandExec](FAULT): Scheduling is paused 2025-04-08T18:55:24.567Z,1744138524.567 [CBIT](INFO): Critical error at 20250408T185524 2025-04-08T18:55:24.567Z,1744138524.567 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-04-08T18:55:24.578Z,1744138524.578 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-08T18:55:24.579Z,1744138524.579 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-08T18:55:24.927Z,1744138524.927 [CBIT](INFO): Critical error at 20250408T185524 2025-04-08T18:55:25.980Z,1744138525.980 [WetLabsBB2FL](INFO): Powering down 2025-04-08T18:55:26.519Z,1744138526.519 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-04-08T18:55:27.769Z,1744138527.769 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2025-04-08T18:55:29.985Z,1744138529.985 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-04-08T18:55:36.488Z,1744138536.488 [DAT](INFO): DAT read: 2025-04-08T18:55:36.490Z,1744138536.490 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-04-08T18:55:38.253Z,1744138538.253 [DAT](INFO): DAT read: MF Frequency Band 2025-04-08T18:55:38.254Z,1744138538.254 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-04-08T18:55:38.255Z,1744138538.255 [DAT](INFO): DAT read: Apr 8 2025 18:30:39 2025-04-08T18:55:39.261Z,1744138539.261 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-04-08T18:55:39.262Z,1744138539.262 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2025-04-08T18:55:39.262Z,1744138539.262 [DAT](INFO): commRate: 1200 2025-04-08T18:55:41.328Z,1744138541.328 [DAT](INFO): entering command mode 2025-04-08T18:55:41.529Z,1744138541.529 [DAT](INFO): DAT read: 2025-04-08T18:55:41.529Z,1744138541.529 [DAT](INFO): DAT read: user:1> 2025-04-08T18:55:41.530Z,1744138541.530 [DAT](INFO): setting verbose to 3 2025-04-08T18:55:41.781Z,1744138541.781 [DAT](INFO): DAT read: user:1> 2025-04-08T18:55:41.782Z,1744138541.782 [DAT](INFO): DAT read: Verbose | 3 2025-04-08T18:55:41.782Z,1744138541.782 [DAT](INFO): set verbose to 3 2025-04-08T18:55:41.782Z,1744138541.782 [DAT](INFO): setting DatVerbose to 27440 2025-04-08T18:55:42.033Z,1744138542.033 [DAT](INFO): DAT read: user:2> 2025-04-08T18:55:42.033Z,1744138542.033 [DAT](INFO): DAT read: DatVerbose | 27440 2025-04-08T18:55:42.034Z,1744138542.034 [DAT](INFO): set DatVerbose to 27440 2025-04-08T18:55:42.034Z,1744138542.034 [DAT](INFO): setting transmit power to 8 2025-04-08T18:55:42.284Z,1744138542.284 [DAT](INFO): DAT read: user:3> 2025-04-08T18:55:42.285Z,1744138542.285 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-04-08T18:55:42.286Z,1744138542.286 [DAT](INFO): set transmit power to 8 2025-04-08T18:55:42.286Z,1744138542.286 [DAT](INFO): setting local address to 5 2025-04-08T18:55:42.537Z,1744138542.537 [DAT](INFO): DAT read: user:4> 2025-04-08T18:55:42.539Z,1744138542.539 [DAT](INFO): DAT read: LocalAddr | 5 2025-04-08T18:55:42.539Z,1744138542.539 [DAT](INFO): set local address to 5 2025-04-08T18:55:42.541Z,1744138542.541 [DAT](INFO): Setting time to: 18:55:42 And date to:4/8/2025 2025-04-08T18:55:42.789Z,1744138542.789 [DAT](INFO): DAT read: user:5> 2025-04-08T18:55:42.789Z,1744138542.789 [DAT](INFO): DAT read: Tue Apr 8, 2025 18:55:42 2025-04-08T18:55:42.790Z,1744138542.790 [DAT](INFO): Local DAT time set to Tue Apr 8, 2025 18:55:42 2025-04-08T18:55:52.834Z,1744138552.834 [NAL9602](INFO): Powering up NAL9602 2025-04-08T18:55:58.546Z,1744138558.546 [DVL_micro](ERROR): only read 2 of 4 data items for beam range. Device response is::RD,+0000.35,+9999.9.33 2025-04-08T18:55:58.547Z,1744138558.547 [DVL_micro](ERROR): Failed to parse: :RD,+0000.35,+9999.9.33 2025-04-08T18:56:03.716Z,1744138563.716 [NAL9602](INFO): NAL9602 initialized 2025-04-08T18:56:32.826Z,1744138592.826 [SBIT](IMPORTANT): Beginning Startup BIT 2025-04-08T18:56:32.833Z,1744138592.833 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-08T18:56:37.329Z,1744138597.329 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.029460 CHAN A1 (24V): 0.018564 CHAN A2 (12V): -0.008469 CHAN A3 (5V): -0.039497 CHAN B0 (3.3V): -0.014320 CHAN B1 (3.15aV): -0.003466 CHAN B2 (3.15bV): -0.001868 CHAN B3 (GND): -0.018384 OPEN: 0.012354 Full Scale: +/- 1 mA 2025-04-08T18:56:57.059Z,1744138617.059 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-04-08T18:57:24.210Z,1744138644.210 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-04-08T18:57:26.271Z,1744138646.271 [SBIT](IMPORTANT): SBIT PASSED 2025-04-08T18:57:26.271Z,1744138646.271 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-04-08T18:57:26.295Z,1744138646.295 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2025-04-08T18:57:26.295Z,1744138646.295 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count; 2025-04-08T18:57:26.295Z,1744138646.295 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2025-04-08T18:57:26.295Z,1744138646.295 [SBIT](IMPORTANT): BuoyancyServo.loadAtStartup=1 bool; 2025-04-08T18:57:26.295Z,1744138646.295 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2025-04-08T18:57:26.296Z,1744138646.296 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2025-04-08T18:57:26.296Z,1744138646.296 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool; 2025-04-08T18:57:26.296Z,1744138646.296 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool; 2025-04-08T18:57:26.296Z,1744138646.296 [SBIT](IMPORTANT): ElevatorServo.loadAtStartup=1 bool; 2025-04-08T18:57:26.296Z,1744138646.296 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool; 2025-04-08T18:57:26.296Z,1744138646.296 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool; 2025-04-08T18:57:26.297Z,1744138646.297 [SBIT](IMPORTANT): MassServo.loadAtStartup=1 bool; 2025-04-08T18:57:26.297Z,1744138646.297 [SBIT](IMPORTANT): NAL9602.loadAtStartup=1 bool; 2025-04-08T18:57:26.297Z,1744138646.297 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2025-04-08T18:57:26.297Z,1744138646.297 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool; 2025-04-08T18:57:26.297Z,1744138646.297 [SBIT](IMPORTANT): Radio_Surface.loadAtStartup=1 bool; 2025-04-08T18:57:26.297Z,1744138646.297 [SBIT](IMPORTANT): RudderServo.loadAtStartup=1 bool; 2025-04-08T18:57:26.297Z,1744138646.297 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool; 2025-04-08T18:57:26.297Z,1744138646.297 [SBIT](IMPORTANT): ThrusterHE.loadAtStartup=1 bool; 2025-04-08T18:57:26.297Z,1744138646.297 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=209.04 cubic_centimeter; 2025-04-08T18:57:26.298Z,1744138646.298 [SBIT](IMPORTANT): VerticalControl.kdDepth=0.4 radian_per_second_per_meter; 2025-04-08T18:57:26.298Z,1744138646.298 [SBIT](IMPORTANT): VerticalControl.kpDepth=0.1 radian_per_meter; 2025-04-08T18:57:26.298Z,1744138646.298 [SBIT](IMPORTANT): VerticalControl.massDefault=12.4082 millimeter; 2025-04-08T18:57:26.298Z,1744138646.298 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=1 bool; 2025-04-08T18:57:26.682Z,1744138646.682 [MissionManager](IMPORTANT): Started mission Startup 2025-04-08T18:57:26.682Z,1744138646.682 [Startup] Running Loop=1 2025-04-08T18:57:26.682Z,1744138646.682 [Startup](DEBUG): Aggregate::initialize Startup 2025-04-08T18:57:26.683Z,1744138646.683 [Startup:A.GoToSurface] Running Loop=1 2025-04-08T18:57:26.683Z,1744138646.683 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-08T18:57:26.684Z,1744138646.684 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-08T18:57:26.685Z,1744138646.685 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-08T18:57:26.685Z,1744138646.685 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-08T18:57:26.686Z,1744138646.686 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-08T18:57:26.686Z,1744138646.686 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-08T18:57:26.686Z,1744138646.686 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-08T18:57:26.705Z,1744138646.705 [Startup:StartupSatComms] Running Loop=1 2025-04-08T18:57:26.706Z,1744138646.706 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-04-08T18:57:26.706Z,1744138646.706 [Startup:StartupSatComms:A] Running Loop=1 2025-04-08T18:57:27.094Z,1744138647.094 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-04-08T18:57:27.418Z,1744138647.418 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004534 2025-04-08T18:58:04.698Z,1744138684.698 [NAL9602](INFO): SBD MO Status=2, MOMSN=19213, MT Status=2, MTMSN=0 2025-04-08T18:58:04.698Z,1744138684.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-08T18:58:23.668Z,1744138703.668 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-04-08T18:58:23.668Z,1744138703.668 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-08T18:58:23.692Z,1744138703.692 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-08T18:58:24.033Z,1744138704.033 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-08T18:58:24.033Z,1744138704.033 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-04-08T18:58:26.846Z,1744138706.846 [Startup:StartupSatComms:A](INFO): Timed out from 2025-04-08T18:57:26.7Z 2025-04-08T18:58:26.847Z,1744138706.847 [Startup:StartupSatComms:A] Stopped 2025-04-08T18:58:26.847Z,1744138706.847 [Startup:StartupSatComms:B] Running Loop=1 2025-04-08T18:58:27.260Z,1744138707.260 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-08T18:58:34.944Z,1744138714.944 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250408T182643/Courier0010.lzma 2025-04-08T18:58:35.542Z,1744138715.542 [DataOverHttps](INFO): Moved sent file to Logs/20250408T182643/Courier0010.lzma.bak 2025-04-08T18:58:35.542Z,1744138715.542 [DataOverHttps](INFO): SBD MOMSN=24579207 2025-04-08T18:58:51.592Z,1744138731.592 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20250408T185510/Courier0000.lzma 2025-04-08T18:58:52.594Z,1744138732.594 [DataOverHttps](INFO): Moved sent file to Logs/20250408T185510/Courier0000.lzma.bak 2025-04-08T18:58:52.594Z,1744138732.594 [DataOverHttps](INFO): SBD MOMSN=24579217 2025-04-08T18:59:12.062Z,1744138752.062 [DataOverHttps](INFO): Sending 425 bytes from file Logs/20250408T182643/Express0011.lzma 2025-04-08T18:59:12.146Z,1744138752.146 [NAL9602](INFO): SBD MO Status=0, MOMSN=19213, MT Status=0, MTMSN=0 2025-04-08T18:59:12.146Z,1744138752.146 [NAL9602](INFO): No messages in MT queue 2025-04-08T18:59:13.062Z,1744138753.062 [DataOverHttps](INFO): Moved sent file to Logs/20250408T182643/Express0011.lzma.bak 2025-04-08T18:59:13.062Z,1744138753.062 [DataOverHttps](INFO): SBD MOMSN=24579265 2025-04-08T18:59:13.325Z,1744138753.325 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185845.00,A,3648.17155,N,12147.28707,W,0.564,302.59,080425,,,D*78 2025-04-08T18:59:13.330Z,1744138753.330 [NAL9602](INFO): GPS fix at 20250408T185845: (36.802859, -121.788118) 2025-04-08T18:59:27.084Z,1744138767.084 [Startup:StartupSatComms:B](INFO): Timed out from 2025-04-08T18:58:26.8Z 2025-04-08T18:59:27.084Z,1744138767.084 [Startup:StartupSatComms:B] Stopped 2025-04-08T18:59:27.084Z,1744138767.084 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-04-08T18:59:27.085Z,1744138767.085 [Startup:StartupSatComms] Stopped 2025-04-08T18:59:27.085Z,1744138767.085 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-04-08T18:59:27.086Z,1744138767.086 [Startup](INFO): Completed Startup 2025-04-08T18:59:27.086Z,1744138767.086 [MissionManager](INFO): Startup is completed. 2025-04-08T18:59:27.086Z,1744138767.086 [MissionManager](INFO): Uninitializing Mission Startup 2025-04-08T18:59:27.086Z,1744138767.086 [Startup] Stopped 2025-04-08T18:59:27.086Z,1744138767.086 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-04-08T18:59:27.086Z,1744138767.086 [Startup:A.GoToSurface] Stopped 2025-04-08T18:59:27.086Z,1744138767.086 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-08T18:59:27.537Z,1744138767.537 [MissionManager](IMPORTANT): Started mission Default 2025-04-08T18:59:27.537Z,1744138767.537 [Default] Running Loop=1 2025-04-08T18:59:27.537Z,1744138767.537 [Default](DEBUG): Aggregate::initialize Default 2025-04-08T18:59:27.537Z,1744138767.537 [Default:B.GoToSurface] Running Loop=1 2025-04-08T18:59:27.538Z,1744138767.538 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-08T18:59:27.538Z,1744138767.538 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-08T18:59:27.538Z,1744138767.538 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-08T18:59:27.539Z,1744138767.539 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-08T18:59:27.539Z,1744138767.539 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-08T18:59:27.548Z,1744138767.548 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-08T18:59:27.549Z,1744138767.549 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-08T18:59:27.549Z,1744138767.549 [Default:A.Wait] Running Loop=1 2025-04-08T18:59:27.549Z,1744138767.549 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-08T18:59:31.890Z,1744138771.890 [DataOverHttps](INFO): Sending 1069 bytes from file Logs/20250408T185510/Express0001.lzma 2025-04-08T18:59:32.890Z,1744138772.890 [DataOverHttps](INFO): Moved sent file to Logs/20250408T185510/Express0001.lzma.bak 2025-04-08T18:59:32.890Z,1744138772.890 [DataOverHttps](INFO): SBD MOMSN=24579281 2025-04-08T18:59:40.837Z,1744138780.837 [Default:A.Wait](INFO): Done Waiting. 2025-04-08T18:59:40.837Z,1744138780.837 [Default:A.Wait] Stopped 2025-04-08T18:59:40.837Z,1744138780.837 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-08T18:59:41.211Z,1744138781.211 [Default:CheckIn] Running Loop=1 2025-04-08T18:59:41.212Z,1744138781.212 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-08T18:59:41.212Z,1744138781.212 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-08T18:59:41.603Z,1744138781.603 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-08T18:59:43.202Z,1744138783.202 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185915.00,A,3648.16088,N,12147.27741,W,0.544,302.59,080425,,,D*73 2025-04-08T18:59:43.204Z,1744138783.204 [NAL9602](INFO): GPS fix at 20250408T185915: (36.802681, -121.787957) 2025-04-08T18:59:43.214Z,1744138783.214 [Default:CheckIn:Read_GPS] Stopped 2025-04-08T18:59:43.215Z,1744138783.215 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-08T18:59:43.733Z,1744138783.733 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-08T18:59:50.633Z,1744138790.633 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250408T185510/Courier0004.lzma 2025-04-08T18:59:51.634Z,1744138791.634 [DataOverHttps](INFO): Moved sent file to Logs/20250408T185510/Courier0004.lzma.bak 2025-04-08T18:59:51.634Z,1744138791.634 [DataOverHttps](INFO): SBD MOMSN=24579326 2025-04-08T19:00:10.024Z,1744138810.024 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20250408T185510/Express0005.lzma 2025-04-08T19:00:11.026Z,1744138811.026 [DataOverHttps](INFO): Moved sent file to Logs/20250408T185510/Express0005.lzma.bak 2025-04-08T19:00:11.026Z,1744138811.026 [DataOverHttps](INFO): SBD MOMSN=24579343 2025-04-08T19:00:12.807Z,1744138812.807 [Default:CheckIn:Read_Iridium] Stopped 2025-04-08T19:00:12.807Z,1744138812.807 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-08T19:00:12.807Z,1744138812.807 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-08T19:00:15.243Z,1744138815.243 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-08T19:01:13.985Z,1744138873.985 [DVL_micro](ERROR): Failed to parse: :BI,-00033,+00045,+00018,+00000,A 2025-04-08T19:01:53.827Z,1744138913.827 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2025-04-08T19:01:53.830Z,1744138913.830 [BPC1](INFO): Received data from all battery sticks. 2025-04-08T19:02:04.065Z,1744138924.065 [CommandExec](IMPORTANT): got command gfscan 2025-04-08T19:02:04.345Z,1744138924.345 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-08T19:02:08.772Z,1744138928.772 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.002116 CHAN A1 (24V): 0.009109 CHAN A2 (12V): -0.015961 CHAN A3 (5V): -0.032656 CHAN B0 (3.3V): -0.007829 CHAN B1 (3.15aV): -0.003819 CHAN B2 (3.15bV): -0.002180 CHAN B3 (GND): -0.004997 OPEN: 0.012201 Full Scale: +/- 1 mA 2025-04-08T19:02:58.055Z,1744138978.055 [DVL_micro](ERROR): Failed to parse: :RD,+0011.12,+0018.54,+0017.83,+0015.95 2025-04-08T19:04:37.474Z,1744139077.474 [CommandExec](IMPORTANT): got command gfscan 2025-04-08T19:04:37.487Z,1744139077.487 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-08T19:04:41.974Z,1744139081.974 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.000905 CHAN A1 (24V): 0.011566 CHAN A2 (12V): -0.015064 CHAN A3 (5V): -0.038176 CHAN B0 (3.3V): -0.007111 CHAN B1 (3.15aV): -0.003621 CHAN B2 (3.15bV): -0.002250 CHAN B3 (GND): -0.006056 OPEN: 0.012336 Full Scale: +/- 1 mA 2025-04-08T19:04:59.990Z,1744139099.990 [CommandExec](IMPORTANT): got command gfscan 2025-04-08T19:05:00.137Z,1744139100.137 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-08T19:05:04.578Z,1744139104.578 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.000016 CHAN A1 (24V): 0.010124 CHAN A2 (12V): -0.019256 CHAN A3 (5V): -0.035452 CHAN B0 (3.3V): -0.006997 CHAN B1 (3.15aV): -0.003513 CHAN B2 (3.15bV): -0.002129 CHAN B3 (GND): -0.005300 OPEN: 0.012337 Full Scale: +/- 1 mA 2025-04-08T19:05:13.444Z,1744139113.444 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-08T19:05:13.445Z,1744139113.445 [Default:CheckIn:C.Wait] Stopped 2025-04-08T19:05:13.445Z,1744139113.445 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-08T19:05:13.445Z,1744139113.445 [Default:CheckIn:D] Running Loop=1 2025-04-08T19:05:13.843Z,1744139113.843 [Default:CheckIn:D] Stopped 2025-04-08T19:05:13.843Z,1744139113.843 [Default:CheckIn:E] Running Loop=1 2025-04-08T19:05:14.235Z,1744139114.235 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.771764 min 2025-04-08T19:05:14.235Z,1744139114.235 [Default:CheckIn:E] Stopped 2025-04-08T19:05:14.235Z,1744139114.235 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-08T19:05:14.235Z,1744139114.235 [Default:CheckIn] Stopped 2025-04-08T19:05:14.235Z,1744139114.235 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-08T19:05:14.236Z,1744139114.236 [Default:CheckIn](INFO): Running loop #2 2025-04-08T19:05:14.236Z,1744139114.236 [Default:CheckIn] Running Loop=2 2025-04-08T19:05:14.236Z,1744139114.236 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-08T19:05:14.236Z,1744139114.236 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-08T19:05:16.249Z,1744139116.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190451.00,A,3648.16244,N,12147.28519,W,0.272,302.59,080425,,,D*7A 2025-04-08T19:05:16.251Z,1744139116.251 [NAL9602](INFO): GPS fix at 20250408T190451: (36.802707, -121.788087) 2025-04-08T19:05:16.262Z,1744139116.262 [Default:CheckIn:Read_GPS] Stopped 2025-04-08T19:05:16.262Z,1744139116.262 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-08T19:05:24.384Z,1744139124.384 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250408T185510/Courier0007.lzma 2025-04-08T19:05:25.386Z,1744139125.386 [DataOverHttps](INFO): Moved sent file to Logs/20250408T185510/Courier0007.lzma.bak 2025-04-08T19:05:25.386Z,1744139125.386 [DataOverHttps](INFO): SBD MOMSN=24580926 2025-04-08T19:05:42.017Z,1744139142.017 [DataOverHttps](INFO): Sending 607 bytes from file Logs/20250408T185510/Express0008.lzma 2025-04-08T19:05:43.018Z,1744139143.018 [DataOverHttps](INFO): Moved sent file to Logs/20250408T185510/Express0008.lzma.bak 2025-04-08T19:05:43.018Z,1744139143.018 [DataOverHttps](INFO): SBD MOMSN=24580963 2025-04-08T19:05:44.576Z,1744139144.576 [Default:CheckIn:Read_Iridium] Stopped 2025-04-08T19:05:44.576Z,1744139144.576 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-08T19:05:44.577Z,1744139144.577 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-08T19:06:29.801Z,1744139189.801 [CommandExec](IMPORTANT): got command gfscan 2025-04-08T19:06:29.836Z,1744139189.836 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-08T19:06:34.267Z,1744139194.267 [GFScanner](IMPORTANT):