2025-05-15T16:15:17.644Z,1747325717.644 [Supervisor](DEBUG): Initializing supervisor. 2025-05-15T16:15:17.648Z,1747325717.648 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-05-15T16:15:17.649Z,1747325717.649 [SyncHandler](INFO): Protected caller Thread ID is 4832 2025-05-15T16:15:17.649Z,1747325717.649 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-05-15T16:15:17.650Z,1747325717.650 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-05-15T16:15:17.650Z,1747325717.650 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4833 2025-05-15T16:15:17.654Z,1747325717.654 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-05-15T16:15:17.675Z,1747325717.675 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-05-15T16:15:17.676Z,1747325717.676 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-05-15T16:15:17.676Z,1747325717.676 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4834 2025-05-15T16:15:17.681Z,1747325717.681 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-05-15T16:15:17.682Z,1747325717.682 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-05-15T16:15:17.682Z,1747325717.682 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4835 2025-05-15T16:15:17.684Z,1747325717.684 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-05-15T16:15:17.685Z,1747325717.685 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-05-15T16:15:17.686Z,1747325717.686 [logger ThreadHandler](INFO): Protected caller Thread ID is 4836 2025-05-15T16:15:17.690Z,1747325717.690 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-05-15T16:15:17.690Z,1747325717.690 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-05-15T16:15:17.692Z,1747325717.692 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-05-15T16:15:17.964Z,1747325717.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-05-15T16:15:17.964Z,1747325717.964 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-05-15T16:15:18.055Z,1747325718.055 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-05-15T16:15:18.584Z,1747325718.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-05-15T16:15:18.584Z,1747325718.584 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-05-15T16:15:18.935Z,1747325718.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-05-15T16:15:18.936Z,1747325718.936 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-05-15T16:15:19.070Z,1747325719.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-05-15T16:15:19.070Z,1747325719.070 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-05-15T16:15:19.636Z,1747325719.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-05-15T16:15:19.637Z,1747325719.637 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-05-15T16:15:19.875Z,1747325719.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-05-15T16:15:19.876Z,1747325719.876 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-05-15T16:15:20.410Z,1747325720.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-05-15T16:15:20.410Z,1747325720.410 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-05-15T16:15:20.557Z,1747325720.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-05-15T16:15:20.558Z,1747325720.558 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-05-15T16:15:20.647Z,1747325720.647 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-05-15T16:15:21.492Z,1747325721.492 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-05-15T16:15:21.492Z,1747325721.492 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-05-15T16:15:21.879Z,1747325721.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-05-15T16:15:21.880Z,1747325721.880 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-05-15T16:15:22.086Z,1747325722.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-05-15T16:15:22.087Z,1747325722.087 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-05-15T16:15:22.210Z,1747325722.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-05-15T16:15:22.211Z,1747325722.211 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-05-15T16:15:22.453Z,1747325722.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-05-15T16:15:22.453Z,1747325722.453 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-05-15T16:15:22.720Z,1747325722.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-05-15T16:15:22.722Z,1747325722.722 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2025-05-15T16:15:22.722Z,1747325722.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2025-05-15T16:15:22.820Z,1747325722.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2025-05-15T16:15:22.934Z,1747325722.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2025-05-15T16:15:23.029Z,1747325723.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2025-05-15T16:15:23.144Z,1747325723.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2025-05-15T16:15:23.248Z,1747325723.248 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2025-05-15T16:15:23.377Z,1747325723.377 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2025-05-15T16:15:23.541Z,1747325723.541 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2025-05-15T16:15:23.701Z,1747325723.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2025-05-15T16:15:23.822Z,1747325723.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2025-05-15T16:15:24.113Z,1747325724.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-05-15T16:15:24.114Z,1747325724.114 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-05-15T16:15:24.127Z,1747325724.127 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-05-15T16:15:24.262Z,1747325724.262 [VerticalControl](DEBUG): Construct VerticalControl. 2025-05-15T16:15:24.330Z,1747325724.330 [VerticalControl] Loaded 2025-05-15T16:15:24.331Z,1747325724.331 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-05-15T16:15:24.333Z,1747325724.333 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-05-15T16:15:24.634Z,1747325724.634 [HorizontalControl] Loaded 2025-05-15T16:15:24.634Z,1747325724.634 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-05-15T16:15:24.637Z,1747325724.637 [SpeedControl](DEBUG): Construct SpeedControl. 2025-05-15T16:15:24.656Z,1747325724.656 [SpeedControl] Loaded 2025-05-15T16:15:24.656Z,1747325724.656 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-05-15T16:15:24.659Z,1747325724.659 [LoopControl](DEBUG): Construct LoopControl. 2025-05-15T16:15:24.660Z,1747325724.660 [LoopControl] Loaded 2025-05-15T16:15:24.660Z,1747325724.660 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-05-15T16:15:24.660Z,1747325724.660 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-05-15T16:15:24.661Z,1747325724.661 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-05-15T16:15:24.681Z,1747325724.681 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-05-15T16:15:24.681Z,1747325724.681 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-05-15T16:15:25.150Z,1747325725.150 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-05-15T16:15:25.151Z,1747325725.151 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-05-15T16:15:25.440Z,1747325725.440 [BuoyancyServo] Loaded 2025-05-15T16:15:25.440Z,1747325725.440 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-05-15T16:15:25.462Z,1747325725.462 [ElevatorServo] Loaded 2025-05-15T16:15:25.463Z,1747325725.463 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-05-15T16:15:25.484Z,1747325725.484 [MassServo] Loaded 2025-05-15T16:15:25.484Z,1747325725.484 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-05-15T16:15:25.505Z,1747325725.505 [RudderServo] Loaded 2025-05-15T16:15:25.505Z,1747325725.505 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-05-15T16:15:25.522Z,1747325725.522 [ThrusterHE] Loaded 2025-05-15T16:15:25.522Z,1747325725.522 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-05-15T16:15:25.523Z,1747325725.523 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-05-15T16:15:25.523Z,1747325725.523 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-05-15T16:15:25.637Z,1747325725.637 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-05-15T16:15:25.637Z,1747325725.637 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-05-15T16:15:25.651Z,1747325725.651 [NavChart] Loaded 2025-05-15T16:15:25.651Z,1747325725.651 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-05-15T16:15:25.657Z,1747325725.657 [UniversalFixResidualReporter] Loaded 2025-05-15T16:15:25.657Z,1747325725.657 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-05-15T16:15:25.658Z,1747325725.658 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-05-15T16:15:25.658Z,1747325725.658 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-05-15T16:15:25.718Z,1747325725.718 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-05-15T16:15:25.719Z,1747325725.719 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-05-15T16:15:26.202Z,1747325726.202 [AHRS_M2] Loaded 2025-05-15T16:15:26.202Z,1747325726.202 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-05-15T16:15:27.018Z,1747325727.018 [BPC1] Loaded 2025-05-15T16:15:27.018Z,1747325727.018 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-05-15T16:15:27.118Z,1747325727.118 [DAT] Loaded 2025-05-15T16:15:27.118Z,1747325727.118 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-05-15T16:15:27.120Z,1747325727.120 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094B4E0 2025-05-15T16:15:27.120Z,1747325727.120 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4915 2025-05-15T16:15:27.268Z,1747325727.268 [DataOverHttps] Loaded 2025-05-15T16:15:27.268Z,1747325727.268 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-05-15T16:15:27.271Z,1747325727.271 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097B4E0 2025-05-15T16:15:27.272Z,1747325727.272 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4916 2025-05-15T16:15:27.340Z,1747325727.340 [Depth_Keller] Loaded 2025-05-15T16:15:27.340Z,1747325727.340 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-05-15T16:15:27.357Z,1747325727.357 [DropWeight] Loaded 2025-05-15T16:15:27.357Z,1747325727.357 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-05-15T16:15:27.588Z,1747325727.588 [NAL9602] Loaded 2025-05-15T16:15:27.589Z,1747325727.589 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-05-15T16:15:27.619Z,1747325727.619 [Onboard] Loaded 2025-05-15T16:15:27.619Z,1747325727.619 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-05-15T16:15:27.620Z,1747325727.620 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0 2025-05-15T16:15:27.620Z,1747325727.620 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4917 2025-05-15T16:15:27.634Z,1747325727.634 [Power24vConverter] Loaded 2025-05-15T16:15:27.635Z,1747325727.635 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-05-15T16:15:27.651Z,1747325727.651 [Radio_Surface] Loaded 2025-05-15T16:15:27.651Z,1747325727.651 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-05-15T16:15:27.652Z,1747325727.652 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0 2025-05-15T16:15:27.652Z,1747325727.652 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4918 2025-05-15T16:15:27.668Z,1747325727.668 [Sonardyne_Nano] Loaded 2025-05-15T16:15:27.668Z,1747325727.668 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-05-15T16:15:27.669Z,1747325727.669 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-05-15T16:15:27.669Z,1747325727.669 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-05-15T16:15:27.721Z,1747325727.721 [DepthRateCalculator] Loaded 2025-05-15T16:15:27.722Z,1747325727.722 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-05-15T16:15:27.727Z,1747325727.727 [PitchRateCalculator] Loaded 2025-05-15T16:15:27.727Z,1747325727.727 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-05-15T16:15:27.736Z,1747325727.736 [SpeedCalculator] Loaded 2025-05-15T16:15:27.736Z,1747325727.736 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-05-15T16:15:27.740Z,1747325727.740 [YawRateCalculator] Loaded 2025-05-15T16:15:27.740Z,1747325727.740 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-05-15T16:15:27.760Z,1747325727.760 [ElevatorOffsetCalculator] Loaded 2025-05-15T16:15:27.760Z,1747325727.760 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-05-15T16:15:27.760Z,1747325727.760 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-05-15T16:15:27.761Z,1747325727.761 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-05-15T16:15:27.891Z,1747325727.891 [CANONSampler] Loaded 2025-05-15T16:15:27.891Z,1747325727.891 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2025-05-15T16:15:27.955Z,1747325727.955 [CTD_Seabird] Loaded 2025-05-15T16:15:27.956Z,1747325727.956 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-05-15T16:15:27.957Z,1747325727.957 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A964E0 2025-05-15T16:15:27.957Z,1747325727.957 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4919 2025-05-15T16:15:27.979Z,1747325727.979 [PAR_Licor] Loaded 2025-05-15T16:15:27.980Z,1747325727.980 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-05-15T16:15:28.025Z,1747325728.025 [WetLabsBB2FL] Loaded 2025-05-15T16:15:28.025Z,1747325728.025 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-05-15T16:15:28.026Z,1747325728.026 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC64E0 2025-05-15T16:15:28.027Z,1747325728.027 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4920 2025-05-15T16:15:28.028Z,1747325728.028 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-05-15T16:15:28.028Z,1747325728.028 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-05-15T16:15:28.058Z,1747325728.058 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-05-15T16:15:28.059Z,1747325728.059 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-05-15T16:15:28.411Z,1747325728.411 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-05-15T16:15:28.412Z,1747325728.412 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-05-15T16:15:28.574Z,1747325728.574 [SBIT](DEBUG): Construct Startup Built In Test. 2025-05-15T16:15:28.583Z,1747325728.583 [SBIT] Loaded 2025-05-15T16:15:28.584Z,1747325728.584 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-05-15T16:15:28.586Z,1747325728.586 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-05-15T16:15:28.599Z,1747325728.599 [IBIT] Loaded 2025-05-15T16:15:28.600Z,1747325728.600 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-05-15T16:15:28.605Z,1747325728.605 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-05-15T16:15:28.686Z,1747325728.686 [CBIT] Loaded 2025-05-15T16:15:28.686Z,1747325728.686 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-05-15T16:15:28.720Z,1747325728.720 [GFScanner] Loaded 2025-05-15T16:15:28.720Z,1747325728.720 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-05-15T16:15:28.721Z,1747325728.721 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-05-15T16:15:28.721Z,1747325728.721 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-05-15T16:15:28.856Z,1747325728.856 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-05-15T16:15:28.862Z,1747325728.862 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-05-15T16:15:28.865Z,1747325728.865 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-05-15T16:15:28.876Z,1747325728.876 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-05-15T16:15:28.877Z,1747325728.877 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-05-15T16:15:28.878Z,1747325728.878 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4921 2025-05-15T16:15:28.882Z,1747325728.882 [Supervisor](INFO): Main Thread ID is 4831 2025-05-15T16:15:28.882Z,1747325728.882 [Supervisor](DEBUG): Running supervisor. 2025-05-15T16:15:28.883Z,1747325728.883 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4922 2025-05-15T16:15:28.883Z,1747325728.883 [CommandExec](INFO): Initializing the command executive. 2025-05-15T16:15:28.885Z,1747325728.885 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4923 2025-05-15T16:15:28.887Z,1747325728.887 [controlThread ThreadHandler](INFO): Handler Thread ID is 4924 2025-05-15T16:15:28.887Z,1747325728.887 [controlThread](DEBUG): Initializing ControlThread 2025-05-15T16:15:28.888Z,1747325728.888 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-05-15T16:15:28.890Z,1747325728.890 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-05-15T16:15:28.891Z,1747325728.891 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-05-15T16:15:28.891Z,1747325728.891 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-05-15T16:15:28.893Z,1747325728.893 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-05-15T16:15:28.893Z,1747325728.893 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-05-15T16:15:28.896Z,1747325728.896 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-05-15T16:15:28.896Z,1747325728.896 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-05-15T16:15:28.897Z,1747325728.897 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-05-15T16:15:28.897Z,1747325728.897 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-05-15T16:15:28.897Z,1747325728.897 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-05-15T16:15:28.899Z,1747325728.899 [SBIT](INFO): Initialize SBIT Component. 2025-05-15T16:15:28.900Z,1747325728.900 [SBIT](IMPORTANT): git: 2025-04-02-2-g1635786d1 2025-05-15T16:15:28.900Z,1747325728.900 [SBIT](INFO): git hash: 1635786d188a1b3aba7073e6184020eb8609012e 2025-05-15T16:15:28.900Z,1747325728.900 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-05-15T16:15:28.901Z,1747325728.901 [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-05-15T16:15:28.902Z,1747325728.902 [SBIT](INFO): Beginning SBIT in 62.000000 seconds. 2025-05-15T16:15:28.904Z,1747325728.904 [IBIT](INFO): Initialize IBIT Component. 2025-05-15T16:15:28.905Z,1747325728.905 [CBIT](DEBUG): Initialize CBIT Component. 2025-05-15T16:15:28.906Z,1747325728.906 [logger ThreadHandler](INFO): Handler Thread ID is 4925 2025-05-15T16:15:28.920Z,1747325728.920 [CBIT](DEBUG): Initialized mux pins. 2025-05-15T16:15:28.920Z,1747325728.920 [CBIT](DEBUG): Initializing the watchdog timer. 2025-05-15T16:15:28.924Z,1747325728.924 [DAT ThreadHandler](INFO): Handler Thread ID is 4926 2025-05-15T16:15:28.924Z,1747325728.924 [DAT](INFO): Powering up 2025-05-15T16:15:28.925Z,1747325728.925 [DAT](DEBUG): Initializing DAT. 2025-05-15T16:15:28.929Z,1747325728.929 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4928 2025-05-15T16:15:28.930Z,1747325728.930 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-05-15T16:15:28.940Z,1747325728.940 [Onboard ThreadHandler](INFO): Handler Thread ID is 4929 2025-05-15T16:15:28.944Z,1747325728.944 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-05-15T16:15:28.945Z,1747325728.945 [CBIT](DEBUG): Initializing heartbeat. 2025-05-15T16:15:28.961Z,1747325728.961 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4930 2025-05-15T16:15:28.976Z,1747325728.976 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4931 2025-05-15T16:15:28.976Z,1747325728.976 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-05-15T16:15:28.980Z,1747325728.980 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4933 2025-05-15T16:15:28.984Z,1747325728.984 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4935 2025-05-15T16:15:28.987Z,1747325728.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-05-15T16:15:28.988Z,1747325728.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-05-15T16:15:28.988Z,1747325728.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-05-15T16:15:28.988Z,1747325728.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-05-15T16:15:28.988Z,1747325728.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-05-15T16:15:28.988Z,1747325728.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-05-15T16:15:28.989Z,1747325728.989 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-05-15T16:15:28.989Z,1747325728.989 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-05-15T16:15:29.005Z,1747325729.005 [WetLabsBB2FL](INFO): Powering up 2025-05-15T16:15:29.015Z,1747325729.015 [CBIT](DEBUG): Deactivating emergency mode. 2025-05-15T16:15:29.055Z,1747325729.055 [CBIT](DEBUG): Backplane powered. 2025-05-15T16:15:29.055Z,1747325729.055 [GFScanner](DEBUG): Initializing GFScanner 2025-05-15T16:15:29.056Z,1747325729.056 [GFScanner](DEBUG): Deactivating GF circuits. 2025-05-15T16:15:29.060Z,1747325729.060 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-05-15T16:15:29.061Z,1747325729.061 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-05-15T16:15:29.061Z,1747325729.061 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-05-15T16:15:29.062Z,1747325729.062 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-05-15T16:15:29.125Z,1747325729.125 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-05-15T16:15:29.145Z,1747325729.145 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-05-15T16:15:29.166Z,1747325729.166 [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-05-15T16:15:29.191Z,1747325729.191 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-05-15T16:15:29.191Z,1747325729.191 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-05-15T16:15:29.192Z,1747325729.192 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-05-15T16:15:29.363Z,1747325729.363 [Radio_Surface](INFO): Powering up 2025-05-15T16:15:29.589Z,1747325729.589 [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-05-15T16:15:29.594Z,1747325729.594 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-05-15T16:15:29.612Z,1747325729.612 [Default:A.Wait](DEBUG): Construct Wait. 2025-05-15T16:15:29.615Z,1747325729.615 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-05-15T16:15:29.663Z,1747325729.663 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-05-15T16:15:29.666Z,1747325729.666 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-05-15T16:15:29.688Z,1747325729.688 [Default:E.Execute](DEBUG): Construct Execute. 2025-05-15T16:15:29.691Z,1747325729.691 [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-05-15T16:15:29.704Z,1747325729.704 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter, 2025-05-15T16:15:29.716Z,1747325729.716 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-05-15T16:15:29.792Z,1747325729.792 [Depth_Keller](INFO): Initializing. 2025-05-15T16:15:29.793Z,1747325729.793 [Power24vConverter](INFO): Powering up. 2025-05-15T16:15:29.794Z,1747325729.794 [Sonardyne_Nano](INFO): Initializing. 2025-05-15T16:15:29.807Z,1747325729.807 [CANONSampler](INFO): Powering down 2025-05-15T16:15:29.831Z,1747325729.831 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-05-15T16:15:29.858Z,1747325729.858 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-05-15T16:15:29.863Z,1747325729.863 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-05-15T16:15:29.865Z,1747325729.865 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-05-15T16:15:29.871Z,1747325729.871 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-05-15T16:15:29.872Z,1747325729.872 [MassServo](DEBUG): Initializing EZServoServo. 2025-05-15T16:15:29.879Z,1747325729.879 [MassServo](DEBUG): Initializing MassServo. 2025-05-15T16:15:29.880Z,1747325729.880 [RudderServo](DEBUG): Initializing EZServoServo. 2025-05-15T16:15:29.888Z,1747325729.888 [RudderServo](DEBUG): Initializing RudderServo. 2025-05-15T16:15:29.888Z,1747325729.888 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-15T16:15:29.895Z,1747325729.895 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-15T16:15:31.092Z,1747325731.092 [WetLabsBB2FL](INFO): Powering down 2025-05-15T16:15:31.734Z,1747325731.734 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-05-15T16:15:33.380Z,1747325733.380 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109 2025-05-15T16:15:33.783Z,1747325733.783 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-05-15T16:15:35.484Z,1747325735.484 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-05-15T16:15:40.516Z,1747325740.516 [DAT](INFO): DAT read: 2025-05-15T16:15:40.517Z,1747325740.517 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-05-15T16:15:42.028Z,1747325742.028 [DAT](INFO): DAT read: MF Frequency Band 2025-05-15T16:15:42.029Z,1747325742.029 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-05-15T16:15:42.030Z,1747325742.030 [DAT](INFO): DAT read: May 14 2025 22:50:34 2025-05-15T16:15:43.077Z,1747325743.077 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004720 2025-05-15T16:15:43.288Z,1747325743.288 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-05-15T16:15:43.289Z,1747325743.289 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2025-05-15T16:15:43.290Z,1747325743.290 [DAT](INFO): commRate: 1200 2025-05-15T16:15:45.355Z,1747325745.355 [DAT](INFO): entering command mode 2025-05-15T16:15:45.556Z,1747325745.556 [DAT](INFO): DAT read: 2025-05-15T16:15:45.556Z,1747325745.556 [DAT](INFO): DAT read: user:1> 2025-05-15T16:15:45.557Z,1747325745.557 [DAT](INFO): setting verbose to 3 2025-05-15T16:15:45.808Z,1747325745.808 [DAT](INFO): DAT read: user:1> 2025-05-15T16:15:45.809Z,1747325745.809 [DAT](INFO): DAT read: Verbose | 3 2025-05-15T16:15:45.809Z,1747325745.809 [DAT](INFO): set verbose to 3 2025-05-15T16:15:45.810Z,1747325745.810 [DAT](INFO): setting DatVerbose to 27440 2025-05-15T16:15:46.060Z,1747325746.060 [DAT](INFO): DAT read: user:2> 2025-05-15T16:15:46.061Z,1747325746.061 [DAT](INFO): DAT read: DatVerbose | 27440 2025-05-15T16:15:46.061Z,1747325746.061 [DAT](INFO): set DatVerbose to 27440 2025-05-15T16:15:46.062Z,1747325746.062 [DAT](INFO): setting transmit power to 8 2025-05-15T16:15:46.312Z,1747325746.312 [DAT](INFO): DAT read: user:3> 2025-05-15T16:15:46.313Z,1747325746.313 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-05-15T16:15:46.313Z,1747325746.313 [DAT](INFO): set transmit power to 8 2025-05-15T16:15:46.313Z,1747325746.313 [DAT](INFO): setting local address to 8 2025-05-15T16:15:46.564Z,1747325746.564 [DAT](INFO): DAT read: user:4> 2025-05-15T16:15:46.565Z,1747325746.565 [DAT](INFO): DAT read: LocalAddr | 8 2025-05-15T16:15:46.565Z,1747325746.565 [DAT](INFO): set local address to 8 2025-05-15T16:15:46.566Z,1747325746.566 [DAT](INFO): Setting time to: 16:15:46 And date to:5/15/2025 2025-05-15T16:15:46.816Z,1747325746.816 [DAT](INFO): DAT read: user:5> 2025-05-15T16:15:46.817Z,1747325746.817 [DAT](INFO): DAT read: Thu May 15, 2025 16:15:46 2025-05-15T16:15:46.817Z,1747325746.817 [DAT](INFO): Local DAT time set to Thu May 15, 2025 16:15:46 2025-05-15T16:15:57.325Z,1747325757.325 [