2026-02-13T06:36:43.027Z,1770964603.027 [Supervisor](DEBUG): Initializing supervisor. 2026-02-13T06:36:43.032Z,1770964603.032 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2026-02-13T06:36:43.032Z,1770964603.032 [SyncHandler](INFO): Protected caller Thread ID is 837 2026-02-13T06:36:43.033Z,1770964603.033 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2026-02-13T06:36:43.034Z,1770964603.034 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2026-02-13T06:36:43.034Z,1770964603.034 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2026-02-13T06:36:43.038Z,1770964603.038 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2026-02-13T06:36:43.057Z,1770964603.057 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2026-02-13T06:36:43.058Z,1770964603.058 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2026-02-13T06:36:43.058Z,1770964603.058 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2026-02-13T06:36:43.063Z,1770964603.063 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2026-02-13T06:36:43.063Z,1770964603.063 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2026-02-13T06:36:43.064Z,1770964603.064 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2026-02-13T06:36:43.066Z,1770964603.066 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2026-02-13T06:36:43.067Z,1770964603.067 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2026-02-13T06:36:43.067Z,1770964603.067 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2026-02-13T06:36:43.071Z,1770964603.071 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2026-02-13T06:36:43.071Z,1770964603.071 [Supervisor](INFO): Looking for Config files in directory: Config/ 2026-02-13T06:36:43.075Z,1770964603.075 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2026-02-13T06:36:43.403Z,1770964603.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2026-02-13T06:36:43.403Z,1770964603.403 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2026-02-13T06:36:43.502Z,1770964603.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2026-02-13T06:36:43.503Z,1770964603.503 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2026-02-13T06:36:43.618Z,1770964603.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2026-02-13T06:36:43.618Z,1770964603.618 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2026-02-13T06:36:43.756Z,1770964603.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2026-02-13T06:36:43.758Z,1770964603.758 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2026-02-13T06:36:44.103Z,1770964604.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2026-02-13T06:36:44.104Z,1770964604.104 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2026-02-13T06:36:44.208Z,1770964604.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2026-02-13T06:36:44.210Z,1770964604.210 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2026-02-13T06:36:44.601Z,1770964604.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2026-02-13T06:36:44.603Z,1770964604.603 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2026-02-13T06:36:44.827Z,1770964604.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2026-02-13T06:36:44.829Z,1770964604.829 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2026-02-13T06:36:44.934Z,1770964604.934 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2026-02-13T06:36:45.175Z,1770964605.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2026-02-13T06:36:45.175Z,1770964605.175 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2026-02-13T06:36:45.395Z,1770964605.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2026-02-13T06:36:45.397Z,1770964605.397 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2026-02-13T06:36:45.933Z,1770964605.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2026-02-13T06:36:45.935Z,1770964605.935 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2026-02-13T06:36:46.119Z,1770964606.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2026-02-13T06:36:46.120Z,1770964606.120 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2026-02-13T06:36:46.406Z,1770964606.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2026-02-13T06:36:46.431Z,1770964606.431 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2026-02-13T06:36:47.270Z,1770964607.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2026-02-13T06:36:47.271Z,1770964607.271 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2026-02-13T06:36:47.820Z,1770964607.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2026-02-13T06:36:47.822Z,1770964607.822 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2026-02-13T06:36:47.825Z,1770964607.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2026-02-13T06:36:47.930Z,1770964607.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2026-02-13T06:36:48.065Z,1770964608.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2026-02-13T06:36:48.158Z,1770964608.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2026-02-13T06:36:48.272Z,1770964608.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2026-02-13T06:36:48.373Z,1770964608.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2026-02-13T06:36:48.637Z,1770964608.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2026-02-13T06:36:48.638Z,1770964608.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2026-02-13T06:36:48.769Z,1770964608.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2026-02-13T06:36:48.873Z,1770964608.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2026-02-13T06:36:49.019Z,1770964609.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2026-02-13T06:36:49.113Z,1770964609.113 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2026-02-13T06:36:49.124Z,1770964609.124 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2026-02-13T06:36:49.291Z,1770964609.291 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2026-02-13T06:36:49.292Z,1770964609.292 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2026-02-13T06:36:49.363Z,1770964609.363 [VerticalControl](DEBUG): Construct VerticalControl. 2026-02-13T06:36:49.423Z,1770964609.423 [VerticalControl] Loaded 2026-02-13T06:36:49.424Z,1770964609.424 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2026-02-13T06:36:49.426Z,1770964609.426 [HorizontalControl](DEBUG): Construct HorizontalControl. 2026-02-13T06:36:49.473Z,1770964609.473 [HorizontalControl] Loaded 2026-02-13T06:36:49.473Z,1770964609.473 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2026-02-13T06:36:49.476Z,1770964609.476 [SpeedControl](DEBUG): Construct SpeedControl. 2026-02-13T06:36:49.479Z,1770964609.479 [SpeedControl] Loaded 2026-02-13T06:36:49.479Z,1770964609.479 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2026-02-13T06:36:49.482Z,1770964609.482 [LoopControl](DEBUG): Construct LoopControl. 2026-02-13T06:36:49.482Z,1770964609.482 [LoopControl] Loaded 2026-02-13T06:36:49.482Z,1770964609.482 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2026-02-13T06:36:49.483Z,1770964609.483 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2026-02-13T06:36:49.483Z,1770964609.483 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2026-02-13T06:36:49.569Z,1770964609.569 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2026-02-13T06:36:49.569Z,1770964609.569 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2026-02-13T06:36:50.411Z,1770964610.411 [AHRS_M2] Loaded 2026-02-13T06:36:50.411Z,1770964610.411 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2026-02-13T06:36:50.664Z,1770964610.664 [BackseatComponent] Loaded 2026-02-13T06:36:50.664Z,1770964610.664 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2026-02-13T06:36:50.665Z,1770964610.665 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0 2026-02-13T06:36:50.666Z,1770964610.666 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920 2026-02-13T06:36:50.669Z,1770964610.669 [LcmPublisher] Loaded 2026-02-13T06:36:50.669Z,1770964610.669 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2026-02-13T06:36:51.476Z,1770964611.476 [BPC1] Loaded 2026-02-13T06:36:51.476Z,1770964611.476 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2026-02-13T06:36:51.589Z,1770964611.589 [DAT] Loaded 2026-02-13T06:36:51.589Z,1770964611.589 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2026-02-13T06:36:51.590Z,1770964611.590 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0 2026-02-13T06:36:51.590Z,1770964611.590 [DAT ThreadHandler](INFO): Protected caller Thread ID is 921 2026-02-13T06:36:51.731Z,1770964611.731 [DataOverHttps] Loaded 2026-02-13T06:36:51.731Z,1770964611.731 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2026-02-13T06:36:51.732Z,1770964611.732 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0 2026-02-13T06:36:51.733Z,1770964611.733 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922 2026-02-13T06:36:51.755Z,1770964611.755 [Depth_Keller] Loaded 2026-02-13T06:36:51.756Z,1770964611.756 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2026-02-13T06:36:51.761Z,1770964611.761 [DropWeight] Loaded 2026-02-13T06:36:51.761Z,1770964611.761 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2026-02-13T06:36:51.825Z,1770964611.825 [NAL9602] Loaded 2026-02-13T06:36:51.825Z,1770964611.825 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2026-02-13T06:36:51.854Z,1770964611.854 [Onboard] Loaded 2026-02-13T06:36:51.855Z,1770964611.855 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2026-02-13T06:36:51.855Z,1770964611.855 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094D4E0 2026-02-13T06:36:51.856Z,1770964611.856 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923 2026-02-13T06:36:51.870Z,1770964611.870 [Power24vConverter] Loaded 2026-02-13T06:36:51.870Z,1770964611.870 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2026-02-13T06:36:51.886Z,1770964611.886 [Radio_Surface] Loaded 2026-02-13T06:36:51.886Z,1770964611.886 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2026-02-13T06:36:51.887Z,1770964611.887 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097D4E0 2026-02-13T06:36:51.887Z,1770964611.887 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924 2026-02-13T06:36:51.902Z,1770964611.902 [Sonardyne_Nano] Loaded 2026-02-13T06:36:51.902Z,1770964611.902 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2026-02-13T06:36:51.903Z,1770964611.903 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2026-02-13T06:36:51.904Z,1770964611.904 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2026-02-13T06:36:51.919Z,1770964611.919 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2026-02-13T06:36:51.921Z,1770964611.921 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2026-02-13T06:36:51.968Z,1770964611.968 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2026-02-13T06:36:51.969Z,1770964611.969 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2026-02-13T06:36:52.161Z,1770964612.161 [SBIT](DEBUG): Construct Startup Built In Test. 2026-02-13T06:36:52.170Z,1770964612.170 [SBIT] Loaded 2026-02-13T06:36:52.170Z,1770964612.170 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2026-02-13T06:36:52.173Z,1770964612.173 [IBIT](DEBUG): Construct Initiated Built In Test. 2026-02-13T06:36:52.185Z,1770964612.185 [IBIT] Loaded 2026-02-13T06:36:52.186Z,1770964612.186 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2026-02-13T06:36:52.191Z,1770964612.191 [CBIT](DEBUG): Construct Continuous Built In Test. 2026-02-13T06:36:52.269Z,1770964612.269 [CBIT] Loaded 2026-02-13T06:36:52.269Z,1770964612.269 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2026-02-13T06:36:52.302Z,1770964612.302 [GFScanner] Loaded 2026-02-13T06:36:52.302Z,1770964612.302 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2026-02-13T06:36:52.303Z,1770964612.303 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2026-02-13T06:36:52.303Z,1770964612.303 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2026-02-13T06:36:52.699Z,1770964612.699 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2026-02-13T06:36:52.700Z,1770964612.700 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2026-02-13T06:36:52.768Z,1770964612.768 [DepthRateCalculator] Loaded 2026-02-13T06:36:52.769Z,1770964612.769 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2026-02-13T06:36:52.774Z,1770964612.774 [PitchRateCalculator] Loaded 2026-02-13T06:36:52.774Z,1770964612.774 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2026-02-13T06:36:52.784Z,1770964612.784 [SpeedCalculator] Loaded 2026-02-13T06:36:52.785Z,1770964612.785 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2026-02-13T06:36:52.789Z,1770964612.789 [YawRateCalculator] Loaded 2026-02-13T06:36:52.789Z,1770964612.789 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2026-02-13T06:36:52.808Z,1770964612.808 [ElevatorOffsetCalculator] Loaded 2026-02-13T06:36:52.808Z,1770964612.808 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2026-02-13T06:36:52.809Z,1770964612.809 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2026-02-13T06:36:52.809Z,1770964612.809 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2026-02-13T06:36:53.025Z,1770964613.025 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2026-02-13T06:36:53.026Z,1770964613.026 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2026-02-13T06:36:53.161Z,1770964613.161 [DeadReckonUsingMultipleVelocitySources] Loaded 2026-02-13T06:36:53.162Z,1770964613.162 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2026-02-13T06:36:53.175Z,1770964613.175 [NavChart] Loaded 2026-02-13T06:36:53.176Z,1770964613.176 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2026-02-13T06:36:53.181Z,1770964613.181 [UniversalFixResidualReporter] Loaded 2026-02-13T06:36:53.182Z,1770964613.182 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2026-02-13T06:36:53.182Z,1770964613.182 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2026-02-13T06:36:53.183Z,1770964613.183 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2026-02-13T06:36:53.355Z,1770964613.355 [BuoyancyServo] Loaded 2026-02-13T06:36:53.355Z,1770964613.355 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2026-02-13T06:36:53.378Z,1770964613.378 [ElevatorServo] Loaded 2026-02-13T06:36:53.379Z,1770964613.379 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2026-02-13T06:36:53.400Z,1770964613.400 [MassServo] Loaded 2026-02-13T06:36:53.401Z,1770964613.401 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2026-02-13T06:36:53.421Z,1770964613.421 [RudderServo] Loaded 2026-02-13T06:36:53.421Z,1770964613.421 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2026-02-13T06:36:53.438Z,1770964613.438 [ThrusterHE] Loaded 2026-02-13T06:36:53.438Z,1770964613.438 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2026-02-13T06:36:53.438Z,1770964613.438 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2026-02-13T06:36:53.439Z,1770964613.439 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2026-02-13T06:36:53.660Z,1770964613.660 [CTD_Seabird] Loaded 2026-02-13T06:36:53.660Z,1770964613.660 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2026-02-13T06:36:53.661Z,1770964613.661 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0D4E0 2026-02-13T06:36:53.661Z,1770964613.661 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 925 2026-02-13T06:36:53.698Z,1770964613.698 [ESPComponent] Loaded 2026-02-13T06:36:53.698Z,1770964613.698 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2026-02-13T06:36:53.720Z,1770964613.720 [PAR_Licor] Loaded 2026-02-13T06:36:53.720Z,1770964613.720 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2026-02-13T06:36:53.765Z,1770964613.765 [WetLabsBB2FL] Loaded 2026-02-13T06:36:53.765Z,1770964613.765 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2026-02-13T06:36:53.766Z,1770964613.766 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0 2026-02-13T06:36:53.767Z,1770964613.767 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 926 2026-02-13T06:36:53.767Z,1770964613.767 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2026-02-13T06:36:53.774Z,1770964613.774 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2026-02-13T06:36:53.777Z,1770964613.777 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2026-02-13T06:36:53.788Z,1770964613.788 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2026-02-13T06:36:53.789Z,1770964613.789 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0 2026-02-13T06:36:53.789Z,1770964613.789 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927 2026-02-13T06:36:53.793Z,1770964613.793 [Supervisor](INFO): Main Thread ID is 828 2026-02-13T06:36:53.793Z,1770964613.793 [Supervisor](DEBUG): Running supervisor. 2026-02-13T06:36:53.794Z,1770964613.794 [CommandExec ThreadHandler](INFO): Handler Thread ID is 928 2026-02-13T06:36:53.794Z,1770964613.794 [CommandExec](INFO): Initializing the command executive. 2026-02-13T06:36:53.796Z,1770964613.796 [CommandLine ThreadHandler](INFO): Handler Thread ID is 929 2026-02-13T06:36:53.798Z,1770964613.798 [controlThread ThreadHandler](INFO): Handler Thread ID is 930 2026-02-13T06:36:53.799Z,1770964613.799 [controlThread](DEBUG): Initializing ControlThread 2026-02-13T06:36:53.800Z,1770964613.800 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2026-02-13T06:36:53.801Z,1770964613.801 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2026-02-13T06:36:53.802Z,1770964613.802 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2026-02-13T06:36:53.802Z,1770964613.802 [LoopControl](DEBUG): Initialize LoopControlComponent. 2026-02-13T06:36:53.805Z,1770964613.805 [SBIT](INFO): Initialize SBIT Component. 2026-02-13T06:36:53.806Z,1770964613.806 [SBIT](IMPORTANT): git: 2026-02-11 2026-02-13T06:36:53.806Z,1770964613.806 [SBIT](INFO): git hash: 64907925c5962a23d968a2217012bd797213745d 2026-02-13T06:36:53.806Z,1770964613.806 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2026-02-13T06:36:53.807Z,1770964613.807 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2026-02-13T06:36:53.809Z,1770964613.809 [SBIT](INFO): Beginning SBIT in 36.000000 seconds. 2026-02-13T06:36:53.809Z,1770964613.809 [IBIT](INFO): Initialize IBIT Component. 2026-02-13T06:36:53.810Z,1770964613.810 [CBIT](DEBUG): Initialize CBIT Component. 2026-02-13T06:36:53.811Z,1770964613.811 [logger ThreadHandler](INFO): Handler Thread ID is 931 2026-02-13T06:36:53.824Z,1770964613.824 [CBIT](DEBUG): Initialized mux pins. 2026-02-13T06:36:53.824Z,1770964613.824 [CBIT](DEBUG): Initializing the watchdog timer. 2026-02-13T06:36:53.836Z,1770964613.836 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 932 2026-02-13T06:36:53.848Z,1770964613.848 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2026-02-13T06:36:53.848Z,1770964613.848 [CBIT](DEBUG): Initializing heartbeat. 2026-02-13T06:36:53.849Z,1770964613.849 [DAT ThreadHandler](INFO): Handler Thread ID is 933 2026-02-13T06:36:53.850Z,1770964613.850 [DAT](INFO): Powering up 2026-02-13T06:36:53.850Z,1770964613.850 [DAT](DEBUG): Initializing DAT. 2026-02-13T06:36:53.853Z,1770964613.853 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935 2026-02-13T06:36:53.855Z,1770964613.855 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2026-02-13T06:36:53.876Z,1770964613.876 [Onboard ThreadHandler](INFO): Handler Thread ID is 936 2026-02-13T06:36:53.894Z,1770964613.894 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937 2026-02-13T06:36:53.912Z,1770964613.912 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938 2026-02-13T06:36:53.913Z,1770964613.913 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2026-02-13T06:36:53.917Z,1770964613.917 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940 2026-02-13T06:36:53.920Z,1770964613.920 [CBIT](DEBUG): Deactivating emergency mode. 2026-02-13T06:36:53.921Z,1770964613.921 [WetLabsBB2FL](INFO): Powering up 2026-02-13T06:36:53.922Z,1770964613.922 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942 2026-02-13T06:36:53.928Z,1770964613.928 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2026-02-13T06:36:53.928Z,1770964613.928 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2026-02-13T06:36:53.929Z,1770964613.929 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2026-02-13T06:36:53.929Z,1770964613.929 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2026-02-13T06:36:53.929Z,1770964613.929 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2026-02-13T06:36:53.929Z,1770964613.929 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2026-02-13T06:36:53.929Z,1770964613.929 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2026-02-13T06:36:53.930Z,1770964613.930 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2026-02-13T06:36:53.960Z,1770964613.960 [CBIT](DEBUG): Backplane powered. 2026-02-13T06:36:53.960Z,1770964613.960 [GFScanner](DEBUG): Initializing GFScanner 2026-02-13T06:36:53.960Z,1770964613.960 [GFScanner](DEBUG): Deactivating GF circuits. 2026-02-13T06:36:53.965Z,1770964613.965 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2026-02-13T06:36:53.965Z,1770964613.965 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2026-02-13T06:36:53.965Z,1770964613.965 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2026-02-13T06:36:53.966Z,1770964613.966 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2026-02-13T06:36:53.966Z,1770964613.966 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2026-02-13T06:36:53.967Z,1770964613.967 [NavChart](DEBUG): Initialize NavChart Navigation. 2026-02-13T06:36:53.967Z,1770964613.967 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2026-02-13T06:36:53.980Z,1770964613.980 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2026-02-13T06:36:53.981Z,1770964613.981 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2026-02-13T06:36:53.981Z,1770964613.981 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2026-02-13T06:36:53.985Z,1770964613.985 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2026-02-13T06:36:54.057Z,1770964614.057 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2026-02-13T06:36:54.074Z,1770964614.074 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2026-02-13T06:36:54.138Z,1770964614.138 [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 } } } 2026-02-13T06:36:54.139Z,1770964614.139 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2026-02-13T06:36:54.139Z,1770964614.139 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2026-02-13T06:36:54.150Z,1770964614.150 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2026-02-13T06:36:54.300Z,1770964614.300 [Radio_Surface](INFO): Powering up 2026-02-13T06:36:54.425Z,1770964614.425 [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 2026-02-13T06:36:54.447Z,1770964614.447 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2026-02-13T06:36:54.456Z,1770964614.456 [Default:A.Wait](DEBUG): Construct Wait. 2026-02-13T06:36:54.458Z,1770964614.458 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2026-02-13T06:36:54.499Z,1770964614.499 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2026-02-13T06:36:54.502Z,1770964614.502 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2026-02-13T06:36:54.524Z,1770964614.524 [Default:E.Execute](DEBUG): Construct Execute. 2026-02-13T06:36:54.527Z,1770964614.527 [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" } } 2026-02-13T06:36:54.556Z,1770964614.556 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,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,LcmPublisher,Reporter,LogSplitter, 2026-02-13T06:36:54.563Z,1770964614.563 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2026-02-13T06:36:54.729Z,1770964614.729 [Depth_Keller](INFO): Initializing. 2026-02-13T06:36:54.730Z,1770964614.730 [Power24vConverter](INFO): Powering up. 2026-02-13T06:36:54.731Z,1770964614.731 [Sonardyne_Nano](INFO): Initializing. 2026-02-13T06:36:54.776Z,1770964614.776 [DepthRateCalculator](ERROR): Depth measurement is not active 2026-02-13T06:36:54.806Z,1770964614.806 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2026-02-13T06:36:54.812Z,1770964614.812 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2026-02-13T06:36:54.813Z,1770964614.813 [ElevatorServo](DEBUG): Initializing EZServoServo. 2026-02-13T06:36:54.820Z,1770964614.820 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2026-02-13T06:36:54.821Z,1770964614.821 [MassServo](DEBUG): Initializing EZServoServo. 2026-02-13T06:36:54.828Z,1770964614.828 [MassServo](DEBUG): Initializing MassServo. 2026-02-13T06:36:54.829Z,1770964614.829 [RudderServo](DEBUG): Initializing EZServoServo. 2026-02-13T06:36:54.836Z,1770964614.836 [RudderServo](DEBUG): Initializing RudderServo. 2026-02-13T06:36:54.837Z,1770964614.837 [ThrusterHE](DEBUG): Initializing EZServoServo. 2026-02-13T06:36:54.844Z,1770964614.844 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2026-02-13T06:36:55.001Z,1770964615.001 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2026-02-13T06:36:55.009Z,1770964615.009 [DepthRateCalculator](ERROR): Depth measurement is not active 2026-02-13T06:36:56.532Z,1770964616.532 [WetLabsBB2FL](INFO): Powering down 2026-02-13T06:36:56.990Z,1770964616.990 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2026-02-13T06:36:58.228Z,1770964618.228 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2026-02-13T06:37:00.437Z,1770964620.437 [ThrusterHE](ERROR): Zero Speed Commanded. 2026-02-13T06:37:06.953Z,1770964626.953 [DAT](INFO): DAT read: 2026-02-13T06:37:06.954Z,1770964626.954 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2026-02-13T06:37:08.718Z,1770964628.718 [DAT](INFO): DAT read: MF Frequency Band 2026-02-13T06:37:08.719Z,1770964628.719 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2026-02-13T06:37:08.720Z,1770964628.720 [DAT](INFO): DAT read: Feb 13 2026 06:37:04 2026-02-13T06:37:09.979Z,1770964629.979 [DAT](INFO): DAT read: Features enabled [Bearing] 2026-02-13T06:37:09.980Z,1770964629.980 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2026-02-13T06:37:09.980Z,1770964629.980 [DAT](INFO): commRate: 800 2026-02-13T06:37:12.048Z,1770964632.048 [DAT](INFO): entering command mode 2026-02-13T06:37:12.249Z,1770964632.249 [DAT](INFO): DAT read: 2026-02-13T06:37:12.249Z,1770964632.249 [DAT](INFO): DAT read: user:1> 2026-02-13T06:37:12.250Z,1770964632.250 [DAT](INFO): setting verbose to 3 2026-02-13T06:37:12.500Z,1770964632.500 [DAT](INFO): DAT read: user:1> 2026-02-13T06:37:12.501Z,1770964632.501 [DAT](INFO): DAT read: Verbose | 3 2026-02-13T06:37:12.502Z,1770964632.502 [DAT](INFO): set verbose to 3 2026-02-13T06:37:12.502Z,1770964632.502 [DAT](INFO): setting DatVerbose to 27440 2026-02-13T06:37:12.753Z,1770964632.753 [DAT](INFO): DAT read: user:2> 2026-02-13T06:37:12.754Z,1770964632.754 [DAT](INFO): DAT read: DatVerbose | 27440 2026-02-13T06:37:12.754Z,1770964632.754 [DAT](INFO): set DatVerbose to 27440 2026-02-13T06:37:12.755Z,1770964632.755 [DAT](INFO): setting transmit power to 8 2026-02-13T06:37:13.005Z,1770964633.005 [DAT](INFO): DAT read: user:3> 2026-02-13T06:37:13.005Z,1770964633.005 [DAT](INFO): DAT read: TxPower | 8 (Max) 2026-02-13T06:37:13.006Z,1770964633.006 [DAT](INFO): set transmit power to 8 2026-02-13T06:37:13.006Z,1770964633.006 [DAT](INFO): setting local address to 1 2026-02-13T06:37:13.257Z,1770964633.257 [DAT](INFO): DAT read: user:4> 2026-02-13T06:37:13.257Z,1770964633.257 [DAT](INFO): DAT read: LocalAddr | 1 2026-02-13T06:37:13.258Z,1770964633.258 [DAT](INFO): set local address to 1 2026-02-13T06:37:13.259Z,1770964633.259 [DAT](INFO): Setting time to: 6:37:13 And date to:2/13/2026 2026-02-13T06:37:13.509Z,1770964633.509 [DAT](INFO): DAT read: user:5> 2026-02-13T06:37:13.509Z,1770964633.509 [DAT](INFO): DAT read: Fri Feb 13, 2026 06:37:13 2026-02-13T06:37:13.510Z,1770964633.510 [DAT](INFO): Local DAT time set to Fri Feb 13, 2026 06:37:13 2026-02-13T06:37:22.046Z,1770964642.046 [NAL9602](INFO): Powering up NAL9602 2026-02-13T06:37:30.556Z,1770964650.556 [SBIT](IMPORTANT): Beginning Startup BIT 2026-02-13T06:37:30.561Z,1770964650.561 [GFScanner](IMPORTANT): Beginning ground fault scan 2026-02-13T06:37:32.954Z,1770964652.954 [NAL9602](INFO): NAL9602 initialized 2026-02-13T06:37:35.060Z,1770964655.060 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.002476 CHAN A1 (24V): 0.005706 CHAN A2 (12V): 0.002326 CHAN A3 (5V): -0.001191 CHAN B0 (3.3V): 0.000794 CHAN B1 (3.15aV): 0.000363 CHAN B2 (3.15bV): 0.000711 CHAN B3 (GND): -0.000301 OPEN: 0.006427 Full Scale: +/- 1 mA 2026-02-13T06:37:51.131Z,1770964671.131 [NAL9602](INFO): SBD MO Status=0, MOMSN=31446, MT Status=0, MTMSN=0 2026-02-13T06:37:51.131Z,1770964671.131 [NAL9602](INFO): No messages in MT queue 2026-02-13T06:38:24.285Z,1770964704.285 [SBIT](IMPORTANT): SBIT PASSED 2026-02-13T06:38:24.285Z,1770964704.285 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2026-02-13T06:38:24.286Z,1770964704.286 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=19 count; 2026-02-13T06:38:24.286Z,1770964704.286 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 2.000000 count_per_second; 2026-02-13T06:38:24.286Z,1770964704.286 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 100.000000 count_per_second; 2026-02-13T06:38:24.286Z,1770964704.286 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=120.493439 cubic_centimeter; 2026-02-13T06:38:24.286Z,1770964704.286 [SBIT](IMPORTANT): VerticalControl.massDefault=-4.064772 millimeter; 2026-02-13T06:38:24.286Z,1770964704.286 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2026-02-13T06:38:24.674Z,1770964704.674 [MissionManager](IMPORTANT): Started mission Startup 2026-02-13T06:38:24.674Z,1770964704.674 [Startup] Running Loop=1 2026-02-13T06:38:24.674Z,1770964704.674 [Startup](DEBUG): Aggregate::initialize Startup 2026-02-13T06:38:24.674Z,1770964704.674 [Startup:A.GoToSurface] Running Loop=1 2026-02-13T06:38:24.674Z,1770964704.674 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-02-13T06:38:24.675Z,1770964704.675 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-02-13T06:38:24.675Z,1770964704.675 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-02-13T06:38:24.676Z,1770964704.676 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-02-13T06:38:24.676Z,1770964704.676 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-02-13T06:38:24.677Z,1770964704.677 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-02-13T06:38:24.677Z,1770964704.677 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-02-13T06:38:24.678Z,1770964704.678 [Startup:StartupSatComms] Running Loop=1 2026-02-13T06:38:24.678Z,1770964704.678 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2026-02-13T06:38:24.678Z,1770964704.678 [Startup:StartupSatComms:A] Running Loop=1 2026-02-13T06:38:25.156Z,1770964705.156 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2026-02-13T06:38:25.477Z,1770964705.477 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,063830.00,A,3647.98972,N,12150.88434,W,0.972,60.81,130226,,,A*4A 2026-02-13T06:38:25.480Z,1770964705.480 [NAL9602](INFO): GPS fix at 20260213T063830: (36.799829, -121.848072) 2026-02-13T06:38:25.492Z,1770964705.492 [Startup:StartupSatComms:A] Stopped 2026-02-13T06:38:25.492Z,1770964705.492 [Startup:StartupSatComms:B] Running Loop=1 2026-02-13T06:38:25.932Z,1770964705.932 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-02-13T06:38:39.274Z,1770964719.274 [NAL9602](INFO): SBD MO Status=1, MOMSN=31447, MT Status=0, MTMSN=0 2026-02-13T06:38:39.396Z,1770964719.396 [NAL9602](INFO): Sent 18 bytes from file Logs/20260213T000323/Courier0099.lzma 2026-02-13T06:38:39.396Z,1770964719.396 [NAL9602](INFO): Packets left to send: 0 2026-02-13T06:38:52.275Z,1770964732.275 [NAL9602](INFO): SBD MO Status=1, MOMSN=31448, MT Status=0, MTMSN=0 2026-02-13T06:38:52.324Z,1770964732.324 [NAL9602](INFO): Sent 269 bytes from file Logs/20260213T063642/Courier0000.lzma 2026-02-13T06:38:52.324Z,1770964732.324 [NAL9602](INFO): Packets left to send: 0 2026-02-13T06:39:09.550Z,1770964749.550 [NAL9602](INFO): SBD MO Status=2, MOMSN=31449, MT Status=2, MTMSN=0 2026-02-13T06:39:09.550Z,1770964749.550 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-02-13T06:39:19.884Z,1770964759.884 [NAL9602](INFO): SBD MO Status=1, MOMSN=31449, MT Status=0, MTMSN=0 2026-02-13T06:39:19.944Z,1770964759.944 [NAL9602](INFO): Sent 18 bytes from file Logs/20260213T000323/Express0100.lzma 2026-02-13T06:39:19.944Z,1770964759.944 [NAL9602](INFO): Packets left to send: 0 2026-02-13T06:39:25.636Z,1770964765.636 [Startup:StartupSatComms:B](INFO): Timed out from 2026-02-13T06:38:25.5Z 2026-02-13T06:39:25.636Z,1770964765.636 [Startup:StartupSatComms:B] Stopped 2026-02-13T06:39:25.636Z,1770964765.636 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2026-02-13T06:39:25.636Z,1770964765.636 [Startup:StartupSatComms] Stopped 2026-02-13T06:39:25.636Z,1770964765.636 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2026-02-13T06:39:25.637Z,1770964765.637 [Startup](INFO): Completed Startup 2026-02-13T06:39:25.637Z,1770964765.637 [MissionManager](INFO): Startup is completed. 2026-02-13T06:39:25.637Z,1770964765.637 [MissionManager](INFO): Uninitializing Mission Startup 2026-02-13T06:39:25.638Z,1770964765.638 [Startup] Stopped 2026-02-13T06:39:25.638Z,1770964765.638 [Startup](DEBUG): Aggregate::uninitialize Startup 2026-02-13T06:39:25.638Z,1770964765.638 [Startup:A.GoToSurface] Stopped 2026-02-13T06:39:25.638Z,1770964765.638 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-02-13T06:39:26.043Z,1770964766.043 [MissionManager](IMPORTANT): Started mission Default 2026-02-13T06:39:26.043Z,1770964766.043 [Default] Running Loop=1 2026-02-13T06:39:26.043Z,1770964766.043 [Default](DEBUG): Aggregate::initialize Default 2026-02-13T06:39:26.043Z,1770964766.043 [Default:B.GoToSurface] Running Loop=1 2026-02-13T06:39:26.043Z,1770964766.043 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-02-13T06:39:26.044Z,1770964766.044 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-02-13T06:39:26.044Z,1770964766.044 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-02-13T06:39:26.044Z,1770964766.044 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-02-13T06:39:26.045Z,1770964766.045 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-02-13T06:39:26.045Z,1770964766.045 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-02-13T06:39:26.045Z,1770964766.045 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-02-13T06:39:26.046Z,1770964766.046 [Default:A.Wait] Running Loop=1 2026-02-13T06:39:26.046Z,1770964766.046 [Default:A.Wait](DEBUG): Initialize Wait Component. 2026-02-13T06:39:32.499Z,1770964772.499 [NAL9602](INFO): SBD MO Status=1, MOMSN=31450, MT Status=0, MTMSN=0 2026-02-13T06:39:32.552Z,1770964772.552 [NAL9602](INFO): Sent 332 bytes from file Logs/20260213T063642/Express0001.lzma 2026-02-13T06:39:32.552Z,1770964772.552 [NAL9602](INFO): Packets left to send: 2 2026-02-13T06:39:39.376Z,1770964779.376 [Default:A.Wait](INFO): Done Waiting. 2026-02-13T06:39:39.376Z,1770964779.376 [Default:A.Wait] Stopped 2026-02-13T06:39:39.376Z,1770964779.376 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2026-02-13T06:39:39.775Z,1770964779.775 [Default:CheckIn] Running Loop=1 2026-02-13T06:39:39.792Z,1770964779.792 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-02-13T06:39:39.792Z,1770964779.792 [Default:CheckIn:Read_GPS] Running Loop=1 2026-02-13T06:39:40.183Z,1770964780.183 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-02-13T06:39:41.818Z,1770964781.818 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,063946.00,A,3647.99681,N,12150.88809,W,1.011,10.11,130226,,,A*49 2026-02-13T06:39:41.821Z,1770964781.821 [NAL9602](INFO): GPS fix at 20260213T063946: (36.799947, -121.848135) 2026-02-13T06:39:41.833Z,1770964781.833 [Default:CheckIn:Read_GPS] Stopped 2026-02-13T06:39:41.833Z,1770964781.833 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-02-13T06:39:42.221Z,1770964782.221 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-02-13T06:40:03.664Z,1770964803.664 [NAL9602](INFO): SBD MO Status=1, MOMSN=31451, MT Status=0, MTMSN=0 2026-02-13T06:40:03.714Z,1770964803.714 [NAL9602](INFO): Sent 74 bytes from file Logs/20260213T063642/Courier0004.lzma 2026-02-13T06:40:03.714Z,1770964803.714 [NAL9602](INFO): Packets left to send: 0 2026-02-13T06:40:19.227Z,1770964819.227 [NAL9602](INFO): SBD MO Status=1, MOMSN=31452, MT Status=0, MTMSN=0 2026-02-13T06:40:19.280Z,1770964819.280 [NAL9602](INFO): Sent 332 bytes from file Logs/20260213T063642/Express0001.lzma 2026-02-13T06:40:19.280Z,1770964819.280 [NAL9602](INFO): Packets left to send: 1 2026-02-13T06:40:32.698Z,1770964832.698 [NAL9602](INFO): SBD MO Status=1, MOMSN=31453, MT Status=0, MTMSN=0 2026-02-13T06:40:32.756Z,1770964832.756 [NAL9602](INFO): Sent 294 bytes from file Logs/20260213T063642/Express0001.lzma 2026-02-13T06:40:32.756Z,1770964832.756 [NAL9602](INFO): Packets left to send: 0 2026-02-13T06:40:46.174Z,1770964846.174 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004311 2026-02-13T06:40:48.356Z,1770964848.356 [NAL9602](INFO): SBD MO Status=1, MOMSN=31454, MT Status=0, MTMSN=0 2026-02-13T06:40:48.404Z,1770964848.404 [NAL9602](INFO): Sent 243 bytes from file Logs/20260213T063642/Express0005.lzma 2026-02-13T06:40:48.404Z,1770964848.404 [NAL9602](INFO): Packets left to send: 0 2026-02-13T06:40:48.436Z,1770964848.436 [Default:CheckIn:Read_Iridium] Stopped 2026-02-13T06:40:48.436Z,1770964848.436 [Default:CheckIn:C.Wait] Running Loop=1 2026-02-13T06:40:48.436Z,1770964848.436 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-02-13T06:40:56.630Z,1770964856.630 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260213T063642/Express0005.lzma 2026-02-13T06:40:56.630Z,1770964856.630 [DataOverHttps](FAULT): Could not open file Logs/20260213T063642/Express0005.lzma 2026-02-13T06:41:05.402Z,1770964865.402 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260213T063642/Express0005.lzma 2026-02-13T06:41:05.402Z,1770964865.402 [DataOverHttps](FAULT): Could not open file Logs/20260213T063642/Express0005.lzma 2026-02-13T06:41:19.133Z,1770964879.133 [NAL9602](INFO): Not Powering down - fast GPS 2026-02-13T06:41:36.070Z,1770964896.070 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260213T063642/Express0005.lzma 2026-02-13T06:41:36.070Z,1770964896.070 [DataOverHttps](FAULT): Could not open file Logs/20260213T063642/Express0005.lzma 2026-02-13T06:41:37.074Z,1770964897.074 [DataOverHttps](IMPORTANT): SBD MTMSN=20260213T064142 2026-02-13T06:41:44.518Z,1770964904.518 [DataOverHttps](IMPORTANT): Could not stat file Logs/20260213T063642/Express0005.lzma