2024-11-11T16:18:29.041Z,1731341909.041 [Supervisor](DEBUG): Initializing supervisor. 2024-11-11T16:18:29.046Z,1731341909.046 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-11-11T16:18:29.046Z,1731341909.046 [SyncHandler](INFO): Protected caller Thread ID is 7658 2024-11-11T16:18:29.047Z,1731341909.047 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-11-11T16:18:29.048Z,1731341909.048 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-11-11T16:18:29.048Z,1731341909.048 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7659 2024-11-11T16:18:29.052Z,1731341909.052 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-11-11T16:18:29.069Z,1731341909.069 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-11-11T16:18:29.070Z,1731341909.070 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-11-11T16:18:29.071Z,1731341909.071 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7660 2024-11-11T16:18:29.075Z,1731341909.075 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-11-11T16:18:29.076Z,1731341909.076 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-11-11T16:18:29.076Z,1731341909.076 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7661 2024-11-11T16:18:29.078Z,1731341909.078 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-11-11T16:18:29.079Z,1731341909.079 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-11-11T16:18:29.079Z,1731341909.079 [logger ThreadHandler](INFO): Protected caller Thread ID is 7662 2024-11-11T16:18:29.083Z,1731341909.083 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-11-11T16:18:29.083Z,1731341909.083 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-11-11T16:18:29.085Z,1731341909.085 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-11-11T16:18:29.360Z,1731341909.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-11-11T16:18:29.361Z,1731341909.361 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-11-11T16:18:29.454Z,1731341909.454 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-11-11T16:18:30.082Z,1731341910.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-11-11T16:18:30.082Z,1731341910.082 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-11-11T16:18:30.441Z,1731341910.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-11-11T16:18:30.441Z,1731341910.441 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-11-11T16:18:30.549Z,1731341910.549 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-11-11T16:18:30.550Z,1731341910.550 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-11-11T16:18:30.973Z,1731341910.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-11-11T16:18:30.973Z,1731341910.973 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-11-11T16:18:31.201Z,1731341911.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-11-11T16:18:31.201Z,1731341911.201 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-11-11T16:18:31.751Z,1731341911.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-11-11T16:18:31.752Z,1731341911.752 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-11-11T16:18:31.900Z,1731341911.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-11-11T16:18:31.900Z,1731341911.900 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-11-11T16:18:31.991Z,1731341911.991 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-11-11T16:18:32.843Z,1731341912.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-11-11T16:18:32.843Z,1731341912.843 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-11-11T16:18:33.247Z,1731341913.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-11-11T16:18:33.248Z,1731341913.248 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-11-11T16:18:33.446Z,1731341913.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-11-11T16:18:33.446Z,1731341913.446 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-11-11T16:18:33.569Z,1731341913.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-11-11T16:18:33.570Z,1731341913.570 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-11-11T16:18:33.911Z,1731341913.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-11-11T16:18:33.911Z,1731341913.911 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-11-11T16:18:34.788Z,1731341914.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-11-11T16:18:34.790Z,1731341914.790 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-11-11T16:18:34.790Z,1731341914.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-11-11T16:18:34.894Z,1731341914.894 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-11-11T16:18:34.992Z,1731341914.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-11-11T16:18:35.106Z,1731341915.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-11-11T16:18:35.201Z,1731341915.201 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-11-11T16:18:35.315Z,1731341915.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-11-11T16:18:35.420Z,1731341915.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-11-11T16:18:35.549Z,1731341915.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-11-11T16:18:35.716Z,1731341915.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-11-11T16:18:35.878Z,1731341915.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-11-11T16:18:35.999Z,1731341915.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-11-11T16:18:36.273Z,1731341916.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-11-11T16:18:36.275Z,1731341916.275 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2024-11-11T16:18:36.275Z,1731341916.275 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-11-11T16:18:36.284Z,1731341916.284 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-11-11T16:18:36.336Z,1731341916.336 [VerticalControl](DEBUG): Construct VerticalControl. 2024-11-11T16:18:36.394Z,1731341916.394 [VerticalControl] Loaded 2024-11-11T16:18:36.394Z,1731341916.394 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-11-11T16:18:36.397Z,1731341916.397 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-11-11T16:18:36.442Z,1731341916.442 [HorizontalControl] Loaded 2024-11-11T16:18:36.442Z,1731341916.442 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-11-11T16:18:36.445Z,1731341916.445 [SpeedControl](DEBUG): Construct SpeedControl. 2024-11-11T16:18:36.448Z,1731341916.448 [SpeedControl] Loaded 2024-11-11T16:18:36.448Z,1731341916.448 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-11-11T16:18:36.451Z,1731341916.451 [LoopControl](DEBUG): Construct LoopControl. 2024-11-11T16:18:36.451Z,1731341916.451 [LoopControl] Loaded 2024-11-11T16:18:36.451Z,1731341916.451 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-11-11T16:18:36.452Z,1731341916.452 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-11-11T16:18:36.452Z,1731341916.452 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-11-11T16:18:36.462Z,1731341916.462 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-11-11T16:18:36.463Z,1731341916.463 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-11-11T16:18:36.629Z,1731341916.629 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-11-11T16:18:36.629Z,1731341916.629 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-11-11T16:18:36.757Z,1731341916.757 [BuoyancyServo] Loaded 2024-11-11T16:18:36.758Z,1731341916.758 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-11-11T16:18:36.779Z,1731341916.779 [ElevatorServo] Loaded 2024-11-11T16:18:36.779Z,1731341916.779 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-11-11T16:18:36.800Z,1731341916.800 [MassServo] Loaded 2024-11-11T16:18:36.800Z,1731341916.800 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-11-11T16:18:36.820Z,1731341916.820 [RudderServo] Loaded 2024-11-11T16:18:36.820Z,1731341916.820 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-11-11T16:18:36.837Z,1731341916.837 [ThrusterHE] Loaded 2024-11-11T16:18:36.837Z,1731341916.837 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-11-11T16:18:36.837Z,1731341916.837 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-11-11T16:18:36.838Z,1731341916.838 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-11-11T16:18:36.947Z,1731341916.947 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-11-11T16:18:36.947Z,1731341916.947 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-11-11T16:18:36.960Z,1731341916.960 [NavChart] Loaded 2024-11-11T16:18:36.960Z,1731341916.960 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-11-11T16:18:36.966Z,1731341916.966 [UniversalFixResidualReporter] Loaded 2024-11-11T16:18:36.966Z,1731341916.966 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-11-11T16:18:36.966Z,1731341916.966 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-11-11T16:18:36.967Z,1731341916.967 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-11-11T16:18:37.024Z,1731341917.024 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-11-11T16:18:37.025Z,1731341917.025 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-11-11T16:18:37.503Z,1731341917.503 [AHRS_M2] Loaded 2024-11-11T16:18:37.503Z,1731341917.503 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-11-11T16:18:38.325Z,1731341918.325 [BPC1] Loaded 2024-11-11T16:18:38.326Z,1731341918.326 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-11-11T16:18:38.398Z,1731341918.398 [DataOverHttps] Loaded 2024-11-11T16:18:38.399Z,1731341918.399 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-11-11T16:18:38.460Z,1731341918.460 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0 2024-11-11T16:18:38.461Z,1731341918.461 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7745 2024-11-11T16:18:38.483Z,1731341918.483 [Depth_Keller] Loaded 2024-11-11T16:18:38.484Z,1731341918.484 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-11-11T16:18:38.489Z,1731341918.489 [DropWeight] Loaded 2024-11-11T16:18:38.489Z,1731341918.489 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-11-11T16:18:38.555Z,1731341918.555 [NAL9602] Loaded 2024-11-11T16:18:38.555Z,1731341918.555 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-11-11T16:18:38.586Z,1731341918.586 [Onboard] Loaded 2024-11-11T16:18:38.586Z,1731341918.586 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-11-11T16:18:38.587Z,1731341918.587 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0 2024-11-11T16:18:38.587Z,1731341918.587 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7746 2024-11-11T16:18:38.602Z,1731341918.602 [Power24vConverter] Loaded 2024-11-11T16:18:38.603Z,1731341918.603 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-11-11T16:18:38.617Z,1731341918.617 [Radio_Surface] Loaded 2024-11-11T16:18:38.618Z,1731341918.618 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-11-11T16:18:38.619Z,1731341918.619 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0 2024-11-11T16:18:38.619Z,1731341918.619 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7747 2024-11-11T16:18:38.634Z,1731341918.634 [Sonardyne_Nano] Loaded 2024-11-11T16:18:38.634Z,1731341918.634 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-11-11T16:18:38.635Z,1731341918.635 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-11-11T16:18:38.635Z,1731341918.635 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-11-11T16:18:38.687Z,1731341918.687 [DepthRateCalculator] Loaded 2024-11-11T16:18:38.687Z,1731341918.687 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-11-11T16:18:38.692Z,1731341918.692 [PitchRateCalculator] Loaded 2024-11-11T16:18:38.693Z,1731341918.693 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-11-11T16:18:38.701Z,1731341918.701 [SpeedCalculator] Loaded 2024-11-11T16:18:38.701Z,1731341918.701 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-11-11T16:18:38.705Z,1731341918.705 [YawRateCalculator] Loaded 2024-11-11T16:18:38.706Z,1731341918.706 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-11-11T16:18:38.724Z,1731341918.724 [ElevatorOffsetCalculator] Loaded 2024-11-11T16:18:38.724Z,1731341918.724 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-11-11T16:18:38.725Z,1731341918.725 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-11-11T16:18:38.726Z,1731341918.726 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-11-11T16:18:38.858Z,1731341918.858 [CANONSampler] Loaded 2024-11-11T16:18:38.858Z,1731341918.858 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-11-11T16:18:38.921Z,1731341918.921 [CTD_Seabird] Loaded 2024-11-11T16:18:38.921Z,1731341918.921 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-11-11T16:18:38.922Z,1731341918.922 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4D4E0 2024-11-11T16:18:38.923Z,1731341918.923 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7748 2024-11-11T16:18:38.945Z,1731341918.945 [PAR_Licor] Loaded 2024-11-11T16:18:38.945Z,1731341918.945 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-11-11T16:18:38.990Z,1731341918.990 [WetLabsBB2FL] Loaded 2024-11-11T16:18:38.990Z,1731341918.990 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-11-11T16:18:38.991Z,1731341918.991 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0 2024-11-11T16:18:38.992Z,1731341918.992 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7749 2024-11-11T16:18:38.993Z,1731341918.993 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-11-11T16:18:38.993Z,1731341918.993 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-11-11T16:18:39.090Z,1731341919.090 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-11-11T16:18:39.091Z,1731341919.091 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-11-11T16:18:40.235Z,1731341920.235 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-11-11T16:18:40.236Z,1731341920.236 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-11-11T16:18:40.371Z,1731341920.371 [SBIT](DEBUG): Construct Startup Built In Test. 2024-11-11T16:18:40.379Z,1731341920.379 [SBIT] Loaded 2024-11-11T16:18:40.380Z,1731341920.380 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-11-11T16:18:40.382Z,1731341920.382 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-11-11T16:18:40.395Z,1731341920.395 [IBIT] Loaded 2024-11-11T16:18:40.395Z,1731341920.395 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-11-11T16:18:40.401Z,1731341920.401 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-11-11T16:18:40.503Z,1731341920.503 [CBIT] Loaded 2024-11-11T16:18:40.503Z,1731341920.503 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-11-11T16:18:40.504Z,1731341920.504 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-11-11T16:18:40.504Z,1731341920.504 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-11-11T16:18:40.613Z,1731341920.613 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-11-11T16:18:40.619Z,1731341920.619 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-11-11T16:18:40.622Z,1731341920.622 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-11-11T16:18:40.633Z,1731341920.633 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-11-11T16:18:40.638Z,1731341920.638 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0 2024-11-11T16:18:40.639Z,1731341920.639 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7750 2024-11-11T16:18:40.643Z,1731341920.643 [Supervisor](INFO): Main Thread ID is 7657 2024-11-11T16:18:40.643Z,1731341920.643 [Supervisor](DEBUG): Running supervisor. 2024-11-11T16:18:40.644Z,1731341920.644 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7751 2024-11-11T16:18:40.644Z,1731341920.644 [CommandExec](INFO): Initializing the command executive. 2024-11-11T16:18:40.646Z,1731341920.646 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7752 2024-11-11T16:18:40.649Z,1731341920.649 [controlThread ThreadHandler](INFO): Handler Thread ID is 7753 2024-11-11T16:18:40.649Z,1731341920.649 [controlThread](DEBUG): Initializing ControlThread 2024-11-11T16:18:40.650Z,1731341920.650 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-11-11T16:18:40.651Z,1731341920.651 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-11-11T16:18:40.652Z,1731341920.652 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-11-11T16:18:40.653Z,1731341920.653 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-11-11T16:18:40.654Z,1731341920.654 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-11-11T16:18:40.655Z,1731341920.655 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-11-11T16:18:40.662Z,1731341920.662 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-11-11T16:18:40.662Z,1731341920.662 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-11-11T16:18:40.662Z,1731341920.662 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-11-11T16:18:40.662Z,1731341920.662 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-11-11T16:18:40.663Z,1731341920.663 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-11-11T16:18:40.668Z,1731341920.668 [SBIT](INFO): Initialize SBIT Component. 2024-11-11T16:18:40.668Z,1731341920.668 [SBIT](IMPORTANT): git: 2024-10-25 2024-11-11T16:18:40.668Z,1731341920.668 [SBIT](INFO): git hash: fb21fbef08ef638e26610dd09246b1b72c2e8aa9 2024-11-11T16:18:40.669Z,1731341920.669 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-11-11T16:18:40.670Z,1731341920.670 [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 2024-11-11T16:18:40.671Z,1731341920.671 [SBIT](INFO): Beginning SBIT in 45.000000 seconds. 2024-11-11T16:18:40.672Z,1731341920.672 [IBIT](INFO): Initialize IBIT Component. 2024-11-11T16:18:40.672Z,1731341920.672 [CBIT](DEBUG): Initialize CBIT Component. 2024-11-11T16:18:40.673Z,1731341920.673 [logger ThreadHandler](INFO): Handler Thread ID is 7754 2024-11-11T16:18:40.684Z,1731341920.684 [CBIT](DEBUG): Initialized mux pins. 2024-11-11T16:18:40.684Z,1731341920.684 [CBIT](DEBUG): Initializing the watchdog timer. 2024-11-11T16:18:40.692Z,1731341920.692 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7755 2024-11-11T16:18:40.694Z,1731341920.694 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-11-11T16:18:40.707Z,1731341920.707 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-11-11T16:18:40.708Z,1731341920.708 [CBIT](DEBUG): Initializing heartbeat. 2024-11-11T16:18:40.709Z,1731341920.709 [Onboard ThreadHandler](INFO): Handler Thread ID is 7756 2024-11-11T16:18:40.729Z,1731341920.729 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7757 2024-11-11T16:18:40.740Z,1731341920.740 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7758 2024-11-11T16:18:40.741Z,1731341920.741 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-11-11T16:18:40.745Z,1731341920.745 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7760 2024-11-11T16:18:40.749Z,1731341920.749 [WetLabsBB2FL](INFO): Powering up 2024-11-11T16:18:40.750Z,1731341920.750 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7762 2024-11-11T16:18:40.753Z,1731341920.753 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-11-11T16:18:40.753Z,1731341920.753 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-11-11T16:18:40.753Z,1731341920.753 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-11-11T16:18:40.753Z,1731341920.753 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-11-11T16:18:40.753Z,1731341920.753 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-11-11T16:18:40.754Z,1731341920.754 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-11-11T16:18:40.754Z,1731341920.754 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-11-11T16:18:40.754Z,1731341920.754 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-11-11T16:18:40.780Z,1731341920.780 [CBIT](DEBUG): Deactivating GF circuits. 2024-11-11T16:18:40.780Z,1731341920.780 [CBIT](DEBUG): Deactivating emergency mode. 2024-11-11T16:18:40.819Z,1731341920.819 [CBIT](DEBUG): Backplane powered. 2024-11-11T16:18:40.820Z,1731341920.820 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-11-11T16:18:40.821Z,1731341920.821 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-11-11T16:18:40.821Z,1731341920.821 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-11-11T16:18:40.822Z,1731341920.822 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-11-11T16:18:40.869Z,1731341920.869 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-11-11T16:18:40.873Z,1731341920.873 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-11-11T16:18:40.914Z,1731341920.914 [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 } } } 2024-11-11T16:18:40.915Z,1731341920.915 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-11-11T16:18:40.915Z,1731341920.915 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-11-11T16:18:40.916Z,1731341920.916 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-11-11T16:18:41.132Z,1731341921.132 [Radio_Surface](INFO): Powering up 2024-11-11T16:18:41.201Z,1731341921.201 [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 2024-11-11T16:18:41.215Z,1731341921.215 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-11-11T16:18:41.216Z,1731341921.216 [Default:A.Wait](DEBUG): Construct Wait. 2024-11-11T16:18:41.218Z,1731341921.218 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-11-11T16:18:41.274Z,1731341921.274 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-11-11T16:18:41.277Z,1731341921.277 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-11-11T16:18:41.299Z,1731341921.299 [Default:E.Execute](DEBUG): Construct Execute. 2024-11-11T16:18:41.302Z,1731341921.302 [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" } } 2024-11-11T16:18:41.311Z,1731341921.311 [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,Reporter,LogSplitter, 2024-11-11T16:18:41.320Z,1731341921.320 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-11-11T16:18:41.392Z,1731341921.392 [Power24vConverter](INFO): Powering up. 2024-11-11T16:18:41.392Z,1731341921.392 [Sonardyne_Nano](INFO): Initializing. 2024-11-11T16:18:41.408Z,1731341921.408 [CANONSampler](INFO): Powering down 2024-11-11T16:18:41.443Z,1731341921.443 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-11-11T16:18:41.448Z,1731341921.448 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-11-11T16:18:41.449Z,1731341921.449 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-11-11T16:18:41.456Z,1731341921.456 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-11-11T16:18:41.457Z,1731341921.457 [MassServo](DEBUG): Initializing EZServoServo. 2024-11-11T16:18:41.464Z,1731341921.464 [MassServo](DEBUG): Initializing MassServo. 2024-11-11T16:18:41.465Z,1731341921.465 [RudderServo](DEBUG): Initializing EZServoServo. 2024-11-11T16:18:41.472Z,1731341921.472 [RudderServo](DEBUG): Initializing RudderServo. 2024-11-11T16:18:41.473Z,1731341921.473 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-11-11T16:18:41.480Z,1731341921.480 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-11-11T16:18:43.339Z,1731341923.339 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-11-11T16:18:43.361Z,1731341923.361 [WetLabsBB2FL](INFO): Powering down 2024-11-11T16:18:44.984Z,1731341924.984 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109 2024-11-11T16:18:47.772Z,1731341927.772 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-11-11T16:18:53.371Z,1731341933.371 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005317 2024-11-11T16:19:08.797Z,1731341948.797 [NAL9602](INFO): Powering up NAL9602 2024-11-11T16:19:19.705Z,1731341959.705 [NAL9602](INFO): NAL9602 initialized 2024-11-11T16:19:26.209Z,1731341966.209 [SBIT](IMPORTANT): Beginning Startup BIT 2024-11-11T16:19:26.217Z,1731341966.217 [CBIT](IMPORTANT): Beginning ground fault scan 2024-11-11T16:19:35.489Z,1731341975.489 [CommandExec](IMPORTANT): got command strobe off 2024-11-11T16:19:35.489Z,1731341975.489 [CommandExec](IMPORTANT): Deactivating strobe 2024-11-11T16:19:37.098Z,1731341977.098 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-11-11T16:19:37.506Z,1731341977.506 [CBIT](IMPORTANT): Beginning ground fault scan 2024-11-11T16:19:48.437Z,1731341988.437 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-11-11T16:19:48.853Z,1731341988.853 [CBIT](IMPORTANT): Beginning ground fault scan 2024-11-11T16:19:59.743Z,1731341999.743 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-11-11T16:19:59.744Z,1731341999.744 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule. 2024-11-11T16:20:02.584Z,1731342002.584 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-11-11T16:20:20.322Z,1731342020.322 [SBIT](IMPORTANT): SBIT PASSED 2024-11-11T16:20:20.322Z,1731342020.322 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-11-11T16:20:20.323Z,1731342020.323 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count; 2024-11-11T16:20:20.324Z,1731342020.324 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-11-11T16:20:20.324Z,1731342020.324 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-11-11T16:20:20.324Z,1731342020.324 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-11-11T16:20:20.324Z,1731342020.324 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter; 2024-11-11T16:20:20.325Z,1731342020.325 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2024-11-11T16:20:20.325Z,1731342020.325 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=243.321146 cubic_centimeter; 2024-11-11T16:20:20.325Z,1731342020.325 [SBIT](IMPORTANT): VerticalControl.massDefault=6.260265 millimeter; 2024-11-11T16:20:20.742Z,1731342020.742 [MissionManager](IMPORTANT): Started mission Startup 2024-11-11T16:20:20.742Z,1731342020.742 [Startup] Running Loop=1 2024-11-11T16:20:20.742Z,1731342020.742 [Startup](DEBUG): Aggregate::initialize Startup 2024-11-11T16:20:20.742Z,1731342020.742 [Startup:A.GoToSurface] Running Loop=1 2024-11-11T16:20:20.743Z,1731342020.743 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-11-11T16:20:20.743Z,1731342020.743 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-11-11T16:20:20.763Z,1731342020.763 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-11-11T16:20:20.764Z,1731342020.764 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-11-11T16:20:20.764Z,1731342020.764 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-11-11T16:20:20.764Z,1731342020.764 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-11-11T16:20:20.765Z,1731342020.765 [Startup:A.GoToSurface](INFO): Recei