2024-05-15T17:02:44.189Z,1715792564.189 [Supervisor](DEBUG): Initializing supervisor.
2024-05-15T17:02:44.195Z,1715792564.195 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-05-15T17:02:44.196Z,1715792564.196 [SyncHandler](INFO): Protected caller Thread ID is 1706
2024-05-15T17:02:44.196Z,1715792564.196 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-05-15T17:02:44.197Z,1715792564.197 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-05-15T17:02:44.198Z,1715792564.198 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1707
2024-05-15T17:02:44.202Z,1715792564.202 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-05-15T17:02:44.221Z,1715792564.221 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-05-15T17:02:44.222Z,1715792564.222 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-05-15T17:02:44.223Z,1715792564.223 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1708
2024-05-15T17:02:44.227Z,1715792564.227 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-05-15T17:02:44.232Z,1715792564.232 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-05-15T17:02:44.232Z,1715792564.232 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1709
2024-05-15T17:02:44.234Z,1715792564.234 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-05-15T17:02:44.235Z,1715792564.235 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-05-15T17:02:44.236Z,1715792564.236 [logger ThreadHandler](INFO): Protected caller Thread ID is 1710
2024-05-15T17:02:44.240Z,1715792564.240 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-05-15T17:02:44.240Z,1715792564.240 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-05-15T17:02:44.242Z,1715792564.242 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-05-15T17:02:44.417Z,1715792564.417 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-05-15T17:02:44.417Z,1715792564.417 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-05-15T17:02:45.247Z,1715792565.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-05-15T17:02:45.248Z,1715792565.248 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-05-15T17:02:45.505Z,1715792565.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-05-15T17:02:45.506Z,1715792565.506 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-05-15T17:02:45.598Z,1715792565.598 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-05-15T17:02:45.746Z,1715792565.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-05-15T17:02:45.747Z,1715792565.747 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-05-15T17:02:45.842Z,1715792565.842 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-05-15T17:02:45.965Z,1715792565.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-05-15T17:02:45.966Z,1715792565.966 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-05-15T17:02:46.400Z,1715792566.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-05-15T17:02:46.401Z,1715792566.401 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-05-15T17:02:46.983Z,1715792566.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-05-15T17:02:46.984Z,1715792566.984 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-05-15T17:02:47.229Z,1715792567.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-05-15T17:02:47.229Z,1715792567.229 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-05-15T17:02:47.443Z,1715792567.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-05-15T17:02:47.444Z,1715792567.444 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-05-15T17:02:48.046Z,1715792568.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-05-15T17:02:48.047Z,1715792568.047 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-05-15T17:02:48.772Z,1715792568.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-05-15T17:02:48.773Z,1715792568.773 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-05-15T17:02:49.028Z,1715792569.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-05-15T17:02:49.029Z,1715792569.029 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-05-15T17:02:49.439Z,1715792569.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-05-15T17:02:49.440Z,1715792569.440 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-05-15T17:02:49.824Z,1715792569.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-05-15T17:02:49.826Z,1715792569.826 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2024-05-15T17:02:49.827Z,1715792569.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2024-05-15T17:02:49.925Z,1715792569.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2024-05-15T17:02:50.111Z,1715792570.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2024-05-15T17:02:50.235Z,1715792570.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2024-05-15T17:02:50.336Z,1715792570.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2024-05-15T17:02:50.447Z,1715792570.447 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2024-05-15T17:02:50.577Z,1715792570.577 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2024-05-15T17:02:50.860Z,1715792570.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-05-15T17:02:50.861Z,1715792570.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2024-05-15T17:02:50.971Z,1715792570.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2024-05-15T17:02:51.079Z,1715792571.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2024-05-15T17:02:51.240Z,1715792571.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2024-05-15T17:02:51.356Z,1715792571.356 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2024-05-15T17:02:51.356Z,1715792571.356 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-05-15T17:02:51.373Z,1715792571.373 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-05-15T17:02:51.494Z,1715792571.494 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-05-15T17:02:51.495Z,1715792571.495 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-05-15T17:02:51.550Z,1715792571.550 [VerticalControl](DEBUG): Construct VerticalControl.
2024-05-15T17:02:51.610Z,1715792571.610 [VerticalControl] Loaded
2024-05-15T17:02:51.611Z,1715792571.611 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-05-15T17:02:51.614Z,1715792571.614 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-05-15T17:02:51.661Z,1715792571.661 [HorizontalControl] Loaded
2024-05-15T17:02:51.661Z,1715792571.661 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-05-15T17:02:51.664Z,1715792571.664 [SpeedControl](DEBUG): Construct SpeedControl.
2024-05-15T17:02:51.667Z,1715792571.667 [SpeedControl] Loaded
2024-05-15T17:02:51.667Z,1715792571.667 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-05-15T17:02:51.670Z,1715792571.670 [LoopControl](DEBUG): Construct LoopControl.
2024-05-15T17:02:51.670Z,1715792571.670 [LoopControl] Loaded
2024-05-15T17:02:51.670Z,1715792571.670 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-05-15T17:02:51.671Z,1715792571.671 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-05-15T17:02:51.671Z,1715792571.671 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-05-15T17:02:51.724Z,1715792571.724 [DepthRateCalculator] Loaded
2024-05-15T17:02:51.724Z,1715792571.724 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-05-15T17:02:51.729Z,1715792571.729 [PitchRateCalculator] Loaded
2024-05-15T17:02:51.729Z,1715792571.729 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-05-15T17:02:51.739Z,1715792571.739 [SpeedCalculator] Loaded
2024-05-15T17:02:51.739Z,1715792571.739 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-05-15T17:02:51.744Z,1715792571.744 [YawRateCalculator] Loaded
2024-05-15T17:02:51.744Z,1715792571.744 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-05-15T17:02:51.762Z,1715792571.762 [ElevatorOffsetCalculator] Loaded
2024-05-15T17:02:51.762Z,1715792571.762 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-05-15T17:02:51.763Z,1715792571.763 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-05-15T17:02:51.763Z,1715792571.763 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-05-15T17:02:51.792Z,1715792571.792 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-05-15T17:02:51.793Z,1715792571.793 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-05-15T17:02:51.852Z,1715792571.852 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-05-15T17:02:51.852Z,1715792571.852 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-05-15T17:02:52.211Z,1715792572.211 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-05-15T17:02:52.212Z,1715792572.212 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-05-15T17:02:52.394Z,1715792572.394 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-05-15T17:02:52.395Z,1715792572.395 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-05-15T17:02:53.156Z,1715792573.156 [AHRS_M2] Loaded
2024-05-15T17:02:53.156Z,1715792573.156 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-05-15T17:02:53.582Z,1715792573.582 [BackseatComponent] Loaded
2024-05-15T17:02:53.582Z,1715792573.582 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-05-15T17:02:53.598Z,1715792573.598 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C34E0
2024-05-15T17:02:53.599Z,1715792573.599 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1792
2024-05-15T17:02:53.627Z,1715792573.627 [LcmUniversalReporter] Loaded
2024-05-15T17:02:53.628Z,1715792573.628 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-05-15T17:02:54.583Z,1715792574.583 [BPC1] Loaded
2024-05-15T17:02:54.583Z,1715792574.583 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-05-15T17:02:54.891Z,1715792574.891 [DataOverHttps] Loaded
2024-05-15T17:02:54.892Z,1715792574.892 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-05-15T17:02:54.893Z,1715792574.893 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F34E0
2024-05-15T17:02:54.893Z,1715792574.893 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1793
2024-05-15T17:02:54.920Z,1715792574.920 [Depth_Keller] Loaded
2024-05-15T17:02:54.920Z,1715792574.920 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-05-15T17:02:54.985Z,1715792574.985 [NAL9602] Loaded
2024-05-15T17:02:54.986Z,1715792574.986 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-05-15T17:02:55.016Z,1715792575.016 [Onboard] Loaded
2024-05-15T17:02:55.017Z,1715792575.017 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-05-15T17:02:55.018Z,1715792575.018 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A234E0
2024-05-15T17:02:55.018Z,1715792575.018 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1794
2024-05-15T17:02:55.032Z,1715792575.032 [Power24vConverter] Loaded
2024-05-15T17:02:55.033Z,1715792575.033 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-05-15T17:02:55.048Z,1715792575.048 [Radio_Surface] Loaded
2024-05-15T17:02:55.048Z,1715792575.048 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-05-15T17:02:55.049Z,1715792575.049 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0
2024-05-15T17:02:55.050Z,1715792575.050 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1795
2024-05-15T17:02:55.098Z,1715792575.098 [RDI_Pathfinder] Loaded
2024-05-15T17:02:55.099Z,1715792575.099 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2024-05-15T17:02:55.195Z,1715792575.195 [DAT] Loaded
2024-05-15T17:02:55.195Z,1715792575.195 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-05-15T17:02:55.196Z,1715792575.196 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0
2024-05-15T17:02:55.197Z,1715792575.197 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1796
2024-05-15T17:02:55.197Z,1715792575.197 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-05-15T17:02:55.198Z,1715792575.198 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-05-15T17:02:55.312Z,1715792575.312 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-05-15T17:02:55.313Z,1715792575.313 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-05-15T17:02:55.327Z,1715792575.327 [NavChart] Loaded
2024-05-15T17:02:55.327Z,1715792575.327 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-05-15T17:02:55.333Z,1715792575.333 [UniversalFixResidualReporter] Loaded
2024-05-15T17:02:55.333Z,1715792575.333 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-05-15T17:02:55.333Z,1715792575.333 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-05-15T17:02:55.334Z,1715792575.334 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-05-15T17:02:55.487Z,1715792575.487 [SBIT](DEBUG): Construct Startup Built In Test.
2024-05-15T17:02:55.496Z,1715792575.496 [SBIT] Loaded
2024-05-15T17:02:55.497Z,1715792575.497 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-05-15T17:02:55.500Z,1715792575.500 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-05-15T17:02:55.512Z,1715792575.512 [IBIT] Loaded
2024-05-15T17:02:55.513Z,1715792575.513 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-05-15T17:02:55.518Z,1715792575.518 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-05-15T17:02:55.623Z,1715792575.623 [CBIT] Loaded
2024-05-15T17:02:55.624Z,1715792575.624 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-05-15T17:02:55.624Z,1715792575.624 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-05-15T17:02:55.624Z,1715792575.624 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-05-15T17:02:55.765Z,1715792575.765 [BuoyancyServo] Loaded
2024-05-15T17:02:55.765Z,1715792575.765 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-05-15T17:02:55.789Z,1715792575.789 [ElevatorServo] Loaded
2024-05-15T17:02:55.789Z,1715792575.789 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-05-15T17:02:55.821Z,1715792575.821 [DockingStepper] Loaded
2024-05-15T17:02:55.821Z,1715792575.821 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2024-05-15T17:02:55.843Z,1715792575.843 [MassServo] Loaded
2024-05-15T17:02:55.844Z,1715792575.844 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-05-15T17:02:55.864Z,1715792575.864 [RudderServo] Loaded
2024-05-15T17:02:55.865Z,1715792575.865 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-05-15T17:02:55.884Z,1715792575.884 [ThrusterServo] Loaded
2024-05-15T17:02:55.884Z,1715792575.884 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2024-05-15T17:02:55.885Z,1715792575.885 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-05-15T17:02:55.885Z,1715792575.885 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-05-15T17:02:55.896Z,1715792575.896 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-05-15T17:02:55.896Z,1715792575.896 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-05-15T17:02:56.087Z,1715792576.087 [CTD_Seabird] Loaded
2024-05-15T17:02:56.087Z,1715792576.087 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-05-15T17:02:56.088Z,1715792576.088 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD04E0
2024-05-15T17:02:56.089Z,1715792576.089 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1797
2024-05-15T17:02:56.125Z,1715792576.125 [ESPComponent] Loaded
2024-05-15T17:02:56.125Z,1715792576.125 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-05-15T17:02:56.148Z,1715792576.148 [PAR_Licor] Loaded
2024-05-15T17:02:56.148Z,1715792576.148 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-05-15T17:02:56.194Z,1715792576.194 [WetLabsBB2FL] Loaded
2024-05-15T17:02:56.194Z,1715792576.194 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-05-15T17:02:56.195Z,1715792576.195 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C004E0
2024-05-15T17:02:56.195Z,1715792576.195 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1798
2024-05-15T17:02:56.217Z,1715792576.217 [WetLabsUBAT] Loaded
2024-05-15T17:02:56.217Z,1715792576.217 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2024-05-15T17:02:56.218Z,1715792576.218 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C304E0
2024-05-15T17:02:56.218Z,1715792576.218 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1799
2024-05-15T17:02:56.219Z,1715792576.219 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-05-15T17:02:56.226Z,1715792576.226 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-05-15T17:02:56.228Z,1715792576.228 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-05-15T17:02:56.240Z,1715792576.240 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-05-15T17:02:56.241Z,1715792576.241 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C604E0
2024-05-15T17:02:56.242Z,1715792576.242 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1800
2024-05-15T17:02:56.246Z,1715792576.246 [Supervisor](INFO): Main Thread ID is 1704
2024-05-15T17:02:56.246Z,1715792576.246 [Supervisor](DEBUG): Running supervisor.
2024-05-15T17:02:56.247Z,1715792576.247 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1801
2024-05-15T17:02:56.248Z,1715792576.248 [CommandExec](INFO): Initializing the command executive.
2024-05-15T17:02:56.249Z,1715792576.249 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1802
2024-05-15T17:02:56.251Z,1715792576.251 [controlThread ThreadHandler](INFO): Handler Thread ID is 1803
2024-05-15T17:02:56.252Z,1715792576.252 [controlThread](DEBUG): Initializing ControlThread
2024-05-15T17:02:56.252Z,1715792576.252 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-05-15T17:02:56.254Z,1715792576.254 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-05-15T17:02:56.255Z,1715792576.255 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-05-15T17:02:56.255Z,1715792576.255 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-05-15T17:02:56.256Z,1715792576.256 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-05-15T17:02:56.256Z,1715792576.256 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-05-15T17:02:56.256Z,1715792576.256 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-05-15T17:02:56.257Z,1715792576.257 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-05-15T17:02:56.257Z,1715792576.257 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-05-15T17:02:56.262Z,1715792576.262 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-05-15T17:02:56.263Z,1715792576.263 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-05-15T17:02:56.263Z,1715792576.263 [SBIT](INFO): Initialize SBIT Component.
2024-05-15T17:02:56.264Z,1715792576.264 [SBIT](IMPORTANT): git: 2024-04-25-2-g619b88340
2024-05-15T17:02:56.264Z,1715792576.264 [SBIT](INFO): git hash: 619b883405649f3bdef85d3060c4ab3a116f807e
2024-05-15T17:02:56.264Z,1715792576.264 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-05-15T17:02:56.265Z,1715792576.265 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-05-15T17:02:56.266Z,1715792576.266 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2024-05-15T17:02:56.267Z,1715792576.267 [IBIT](INFO): Initialize IBIT Component.
2024-05-15T17:02:56.268Z,1715792576.268 [CBIT](DEBUG): Initialize CBIT Component.
2024-05-15T17:02:56.269Z,1715792576.269 [logger ThreadHandler](INFO): Handler Thread ID is 1804
2024-05-15T17:02:56.281Z,1715792576.281 [CBIT](DEBUG): Initialized mux pins.
2024-05-15T17:02:56.281Z,1715792576.281 [CBIT](DEBUG): Initializing the watchdog timer.
2024-05-15T17:02:56.286Z,1715792576.286 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1805
2024-05-15T17:02:56.294Z,1715792576.294 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1806
2024-05-15T17:02:56.295Z,1715792576.295 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-05-15T17:02:56.305Z,1715792576.305 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-05-15T17:02:56.306Z,1715792576.306 [CBIT](DEBUG): Initializing heartbeat.
2024-05-15T17:02:56.310Z,1715792576.310 [Onboard ThreadHandler](INFO): Handler Thread ID is 1807
2024-05-15T17:02:56.327Z,1715792576.327 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1808
2024-05-15T17:02:56.350Z,1715792576.350 [DAT ThreadHandler](INFO): Handler Thread ID is 1809
2024-05-15T17:02:56.351Z,1715792576.351 [DAT](INFO): Powering up
2024-05-15T17:02:56.351Z,1715792576.351 [DAT](DEBUG): Initializing DAT.
2024-05-15T17:02:56.363Z,1715792576.363 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1811
2024-05-15T17:02:56.363Z,1715792576.363 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-05-15T17:02:56.367Z,1715792576.367 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1813
2024-05-15T17:02:56.374Z,1715792576.374 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1815
2024-05-15T17:02:56.377Z,1715792576.377 [CBIT](DEBUG): Deactivating GF circuits.
2024-05-15T17:02:56.377Z,1715792576.377 [CBIT](DEBUG): Deactivating emergency mode.
2024-05-15T17:02:56.378Z,1715792576.378 [WetLabsBB2FL](INFO): Powering up
2024-05-15T17:02:56.380Z,1715792576.380 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1817
2024-05-15T17:02:56.382Z,1715792576.382 [WetLabsUBAT](INFO): Powering up
2024-05-15T17:02:56.389Z,1715792576.389 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-05-15T17:02:56.389Z,1715792576.389 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-05-15T17:02:56.390Z,1715792576.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-05-15T17:02:56.390Z,1715792576.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-05-15T17:02:56.390Z,1715792576.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-05-15T17:02:56.390Z,1715792576.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-05-15T17:02:56.390Z,1715792576.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-05-15T17:02:56.390Z,1715792576.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-05-15T17:02:56.417Z,1715792576.417 [CBIT](DEBUG): Backplane powered.
2024-05-15T17:02:56.422Z,1715792576.422 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-05-15T17:02:56.423Z,1715792576.423 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-05-15T17:02:56.423Z,1715792576.423 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-05-15T17:02:56.426Z,1715792576.426 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-05-15T17:02:56.499Z,1715792576.499 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-05-15T17:02:56.504Z,1715792576.504 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-15T17:02:56.552Z,1715792576.552 [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-05-15T17:02:56.553Z,1715792576.553 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-05-15T17:02:56.553Z,1715792576.553 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-05-15T17:02:56.558Z,1715792576.558 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-05-15T17:02:56.733Z,1715792576.733 [Radio_Surface](INFO): Powering up
2024-05-15T17:02:56.880Z,1715792576.880 [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-05-15T17:02:56.901Z,1715792576.901 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-05-15T17:02:56.902Z,1715792576.902 [Default:A.Wait](DEBUG): Construct Wait.
2024-05-15T17:02:56.919Z,1715792576.919 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-15T17:02:56.951Z,1715792576.951 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-05-15T17:02:56.954Z,1715792576.954 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-05-15T17:02:56.976Z,1715792576.976 [Default:E.Execute](DEBUG): Construct Execute.
2024-05-15T17:02:56.979Z,1715792576.979 [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-05-15T17:02:56.997Z,1715792576.997 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-05-15T17:02:57.004Z,1715792577.004 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-05-15T17:02:57.104Z,1715792577.104 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2024-05-15T17:02:57.105Z,1715792577.105 [Power24vConverter](INFO): Powering up.
2024-05-15T17:02:57.106Z,1715792577.106 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2024-05-15T17:02:57.150Z,1715792577.150 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-05-15T17:02:57.171Z,1715792577.171 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-05-15T17:02:57.178Z,1715792577.178 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-05-15T17:02:57.179Z,1715792577.179 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-05-15T17:02:57.186Z,1715792577.186 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-05-15T17:02:57.186Z,1715792577.186 [DockingStepper](INFO): Start
2024-05-15T17:02:57.187Z,1715792577.187 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-15T17:02:57.194Z,1715792577.194 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-15T17:02:57.194Z,1715792577.194 [MassServo](DEBUG): Initializing EZServoServo.
2024-05-15T17:02:57.201Z,1715792577.201 [MassServo](DEBUG): Initializing MassServo.
2024-05-15T17:02:57.202Z,1715792577.202 [RudderServo](DEBUG): Initializing EZServoServo.
2024-05-15T17:02:57.209Z,1715792577.209 [RudderServo](DEBUG): Initializing RudderServo.
2024-05-15T17:02:57.210Z,1715792577.210 [ThrusterServo](DEBUG): Initializing EZServoServo.
2024-05-15T17:02:57.217Z,1715792577.217 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2024-05-15T17:02:57.482Z,1715792577.482 [DockingStepper](INFO): Starting
2024-05-15T17:02:57.833Z,1715792577.833 [DockingStepper](INFO): Starting
2024-05-15T17:02:58.354Z,1715792578.354 [DockingStepper](INFO): Starting
2024-05-15T17:02:58.402Z,1715792578.402 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50
2024-05-15T17:02:58.677Z,1715792578.677 [DockingStepper](INFO): Starting
2024-05-15T17:02:58.981Z,1715792578.981 [WetLabsBB2FL](INFO): Powering down
2024-05-15T17:02:59.083Z,1715792579.083 [DockingStepper](INFO): Starting
2024-05-15T17:02:59.463Z,1715792579.463 [DockingStepper](INFO): Starting
2024-05-15T17:02:59.914Z,1715792579.914 [DockingStepper](INFO): Starting
2024-05-15T17:02:59.938Z,1715792579.938 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-15T17:03:00.371Z,1715792580.371 [DockingStepper](INFO): Starting
2024-05-15T17:03:00.778Z,1715792580.778 [DockingStepper](INFO): Commanding negative:9038
2024-05-15T17:03:02.107Z,1715792582.107 [DockingStepper](INFO): Pause
2024-05-15T17:03:05.629Z,1715792585.629 [WetLabsUBAT](INFO): Powering down
2024-05-15T17:03:09.202Z,1715792589.202 [DAT](INFO): DAT read:
2024-05-15T17:03:09.203Z,1715792589.203 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-05-15T17:03:10.966Z,1715792590.966 [DAT](INFO): DAT read: MF Frequency Band
2024-05-15T17:03:10.967Z,1715792590.967 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2024-05-15T17:03:10.968Z,1715792590.968 [DAT](INFO): DAT read: May 15 2024 00:20:23
2024-05-15T17:03:11.364Z,1715792591.364 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005251
2024-05-15T17:03:11.974Z,1715792591.974 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-05-15T17:03:11.975Z,1715792591.975 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-05-15T17:03:11.976Z,1715792591.976 [DAT](INFO): commRate: 800
2024-05-15T17:03:14.041Z,1715792594.041 [DAT](INFO): entering command mode
2024-05-15T17:03:14.242Z,1715792594.242 [DAT](INFO): DAT read:
2024-05-15T17:03:14.242Z,1715792594.242 [DAT](INFO): DAT read: user:1>
2024-05-15T17:03:14.243Z,1715792594.243 [DAT](INFO): setting verbose to 3
2024-05-15T17:03:14.494Z,1715792594.494 [DAT](INFO): DAT read: user:1>
2024-05-15T17:03:14.495Z,1715792594.495 [DAT](INFO): DAT read: Verbose | 3
2024-05-15T17:03:14.495Z,1715792594.495 [DAT](INFO): set verbose to 3
2024-05-15T17:03:14.495Z,1715792594.495 [DAT](INFO): setting DatVerbose to 27440
2024-05-15T17:03:14.748Z,1715792594.748 [DAT](INFO): DAT read: user:2>
2024-05-15T17:03:14.749Z,1715792594.749 [DAT](INFO): DAT read: DatVerbose | 27440
2024-05-15T17:03:14.749Z,1715792594.749 [DAT](INFO): set DatVerbose to 27440
2024-05-15T17:03:14.749Z,1715792594.749 [DAT](INFO): setting transmit power to 8
2024-05-15T17:03:14.998Z,1715792594.998 [DAT](INFO): DAT read: user:3>
2024-05-15T17:03:14.999Z,1715792594.999 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-05-15T17:03:14.999Z,1715792594.999 [DAT](INFO): set transmit power to 8
2024-05-15T17:03:14.999Z,1715792594.999 [DAT](INFO): setting local address to 10
2024-05-15T17:03:15.250Z,1715792595.250 [DAT](INFO): DAT read: user:4>
2024-05-15T17:03:15.251Z,1715792595.251 [DAT](INFO): DAT read: LocalAddr | 10
2024-05-15T17:03:15.251Z,1715792595.251 [DAT](INFO): set local address to 10
2024-05-15T17:03:15.252Z,1715792595.252 [DAT](INFO): Setting time to: 17:3:15 And date to:5/15/2024
2024-05-15T17:03:15.502Z,1715792595.502 [DAT](INFO): DAT read: user:5>
2024-05-15T17:03:15.503Z,1715792595.503 [DAT](INFO): DAT read: Wed May 15, 2024 17:03:15
2024-05-15T17:03:15.503Z,1715792595.503 [DAT](INFO): Local DAT time set to Wed May 15, 2024 17:03:15
2024-05-15T17:03:25.047Z,1715792605.047 [NAL9602](INFO): Powering up NAL9602
2024-05-15T17:03:35.955Z,1715792615.955 [NAL9602](INFO): NAL9602 initialized
2024-05-15T17:04:03.830Z,1715792643.830 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:04:19.182Z,1715792659.182 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:04:34.534Z,1715792674.534 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:04:35.768Z,1715792675.768 [SBIT](IMPORTANT): Beginning Startup BIT
2024-05-15T17:04:35.816Z,1715792675.816 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-15T17:04:46.806Z,1715792686.806 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007051
CHAN A1 (24V): 0.004945
CHAN A2 (12V): -0.007164
CHAN A3 (5V): -0.002651
CHAN B0 (3.3V): 0.000297
CHAN B1 (3.15aV): -0.000128
CHAN B2 (3.15bV): 0.000298
CHAN B3 (GND): 0.001594
OPEN: 0.005592
Full Scale: +/- 1 mA
2024-05-15T17:04:49.483Z,1715792689.483 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:05:04.834Z,1715792704.834 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:05:20.187Z,1715792720.187 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:05:29.904Z,1715792729.904 [SBIT](IMPORTANT): SBIT PASSED
2024-05-15T17:05:29.904Z,1715792729.904 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-05-15T17:05:29.906Z,1715792729.906 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;
2024-05-15T17:05:29.906Z,1715792729.906 [SBIT](IMPORTANT): DockingStepper.closedValue=16500 count;
2024-05-15T17:05:29.906Z,1715792729.906 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=1 bool;
2024-05-15T17:05:29.906Z,1715792729.906 [SBIT](IMPORTANT): DockingStepper.openValue=7500 count;
2024-05-15T17:05:29.906Z,1715792729.906 [SBIT](IMPORTANT): DockingStepper.simulateHardware=0 bool;
2024-05-15T17:05:29.906Z,1715792729.906 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2024-05-15T17:05:29.906Z,1715792729.906 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2024-05-15T17:05:29.906Z,1715792729.906 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute;
2024-05-15T17:05:29.907Z,1715792729.907 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2024-05-15T17:05:29.907Z,1715792729.907 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;
2024-05-15T17:05:29.907Z,1715792729.907 [SBIT](IMPORTANT): Express linearApproximation downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;
2024-05-15T17:05:29.907Z,1715792729.907 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;
2024-05-15T17:05:29.907Z,1715792729.907 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=700 cubic_centimeter;
2024-05-15T17:05:29.907Z,1715792729.907 [SBIT](IMPORTANT): VerticalControl.kpDepth=0.09 radian_per_meter;
2024-05-15T17:05:29.907Z,1715792729.907 [SBIT](IMPORTANT): VerticalControl.kpPitchElevator=0.5 none;
2024-05-15T17:05:29.907Z,1715792729.907 [SBIT](IMPORTANT): VerticalControl.massDefault=-19.999430 millimeter;
2024-05-15T17:05:29.907Z,1715792729.907 [SBIT](IMPORTANT): VerticalControl.stopDepthExcursion=20 meter;
2024-05-15T17:05:30.328Z,1715792730.328 [MissionManager](IMPORTANT): Started mission Startup
2024-05-15T17:05:30.328Z,1715792730.328 [Startup] Running Loop=1
2024-05-15T17:05:30.328Z,1715792730.328 [Startup](DEBUG): Aggregate::initialize Startup
2024-05-15T17:05:30.328Z,1715792730.328 [Startup:A.GoToSurface] Running Loop=1
2024-05-15T17:05:30.328Z,1715792730.328 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-15T17:05:30.334Z,1715792730.334 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-15T17:05:30.335Z,1715792730.335 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-15T17:05:30.335Z,1715792730.335 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-15T17:05:30.336Z,1715792730.336 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-15T17:05:30.336Z,1715792730.336 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-15T17:05:30.343Z,1715792730.343 [Startup:StartupSatComms] Running Loop=1
2024-05-15T17:05:30.343Z,1715792730.343 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-05-15T17:05:30.343Z,1715792730.343 [Startup:StartupSatComms:A] Running Loop=1
2024-05-15T17:05:30.738Z,1715792730.738 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-05-15T17:05:56.581Z,1715792756.581 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-05-15T17:05:56.581Z,1715792756.581 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-15T17:05:56.693Z,1715792756.693 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-15T17:05:57.074Z,1715792757.074 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-15T17:05:57.074Z,1715792757.074 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-05-15T17:06:30.512Z,1715792790.512 [Startup:StartupSatComms:A](INFO): Timed out from 2024-05-15T17:05:30.3Z
2024-05-15T17:06:30.512Z,1715792790.512 [Startup:StartupSatComms:A] Stopped
2024-05-15T17:06:30.512Z,1715792790.512 [Startup:StartupSatComms:B] Running Loop=1
2024-05-15T17:06:30.943Z,1715792790.943 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-15T17:06:37.115Z,1715792797.115 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20240514T173041/Courier0112.lzma
2024-05-15T17:06:38.115Z,1715792798.115 [DataOverHttps](INFO): Moved sent file to Logs/20240514T173041/Courier0112.lzma.bak
2024-05-15T17:06:38.116Z,1715792798.116 [DataOverHttps](INFO): SBD MOMSN=21022407
2024-05-15T17:06:53.910Z,1715792813.910 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20240514T173041/Courier0115.lzma
2024-05-15T17:06:54.911Z,1715792814.911 [DataOverHttps](INFO): Moved sent file to Logs/20240514T173041/Courier0115.lzma.bak
2024-05-15T17:06:54.912Z,1715792814.912 [DataOverHttps](INFO): SBD MOMSN=21022412
2024-05-15T17:07:12.437Z,1715792832.437 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240515T170244/Courier0000.lzma
2024-05-15T17:07:13.439Z,1715792833.439 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0000.lzma.bak
2024-05-15T17:07:13.439Z,1715792833.439 [DataOverHttps](INFO): SBD MOMSN=21022418
2024-05-15T17:07:29.932Z,1715792849.932 [DataOverHttps](INFO): Sending 800 bytes from file Logs/20240514T173041/Express0113.lzma
2024-05-15T17:07:30.756Z,1715792850.756 [Startup:StartupSatComms:B](INFO): Timed out from 2024-05-15T17:06:30.5Z
2024-05-15T17:07:30.756Z,1715792850.756 [Startup:StartupSatComms:B] Stopped
2024-05-15T17:07:30.756Z,1715792850.756 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-05-15T17:07:30.756Z,1715792850.756 [Startup:StartupSatComms] Stopped
2024-05-15T17:07:30.756Z,1715792850.756 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-05-15T17:07:30.757Z,1715792850.757 [Startup](INFO): Completed Startup
2024-05-15T17:07:30.757Z,1715792850.757 [MissionManager](INFO): Startup is completed.
2024-05-15T17:07:30.758Z,1715792850.758 [MissionManager](INFO): Uninitializing Mission Startup
2024-05-15T17:07:30.758Z,1715792850.758 [Startup] Stopped
2024-05-15T17:07:30.758Z,1715792850.758 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-05-15T17:07:30.758Z,1715792850.758 [Startup:A.GoToSurface] Stopped
2024-05-15T17:07:30.758Z,1715792850.758 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-15T17:07:30.931Z,1715792850.931 [DataOverHttps](INFO): Moved sent file to Logs/20240514T173041/Express0113.lzma.bak
2024-05-15T17:07:30.932Z,1715792850.932 [DataOverHttps](INFO): SBD MOMSN=21022426
2024-05-15T17:07:31.119Z,1715792851.119 [MissionManager](IMPORTANT): Started mission Default
2024-05-15T17:07:31.120Z,1715792851.120 [Default] Running Loop=1
2024-05-15T17:07:31.120Z,1715792851.120 [Default](DEBUG): Aggregate::initialize Default
2024-05-15T17:07:31.120Z,1715792851.120 [Default:B.GoToSurface] Running Loop=1
2024-05-15T17:07:31.120Z,1715792851.120 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-15T17:07:31.120Z,1715792851.120 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-15T17:07:31.120Z,1715792851.120 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-15T17:07:31.121Z,1715792851.121 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-15T17:07:31.121Z,1715792851.121 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-15T17:07:31.121Z,1715792851.121 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-15T17:07:31.122Z,1715792851.122 [Default:A.Wait] Running Loop=1
2024-05-15T17:07:31.122Z,1715792851.122 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:07:44.441Z,1715792864.441 [Default:A.Wait](INFO): Done Waiting.
2024-05-15T17:07:44.442Z,1715792864.442 [Default:A.Wait] Stopped
2024-05-15T17:07:44.442Z,1715792864.442 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-05-15T17:07:44.867Z,1715792864.867 [Default:CheckIn] Running Loop=1
2024-05-15T17:07:44.867Z,1715792864.867 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-15T17:07:44.867Z,1715792864.867 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-15T17:07:45.240Z,1715792865.240 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-05-15T17:07:53.707Z,1715792873.707 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:08:00.183Z,1715792880.183 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:08:13.508Z,1715792893.508 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:08:26.435Z,1715792906.435 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:08:39.366Z,1715792919.366 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:08:39.374Z,1715792919.374 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-15T17:08:52.291Z,1715792932.291 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:08:57.558Z,1715792937.558 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-05-15T17:08:57.558Z,1715792937.558 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-15T17:08:57.569Z,1715792937.569 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-15T17:08:57.971Z,1715792937.971 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-15T17:08:57.971Z,1715792937.971 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-05-15T17:09:05.219Z,1715792945.219 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:09:06.048Z,1715792946.048 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170905.00,A,3648.15638,N,12147.28452,W,0.680,156.82,150524,,,A*7F
2024-05-15T17:09:06.071Z,1715792946.071 [NAL9602](INFO): GPS fix at 20240515T170905: (36.802606, -121.788075)
2024-05-15T17:09:06.083Z,1715792946.083 [Default:CheckIn:Read_GPS] Stopped
2024-05-15T17:09:06.083Z,1715792946.083 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-15T17:09:06.466Z,1715792946.466 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-15T17:09:16.572Z,1715792956.572 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20240515T170244/Courier0004.lzma
2024-05-15T17:09:17.571Z,1715792957.571 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0004.lzma.bak
2024-05-15T17:09:17.571Z,1715792957.571 [DataOverHttps](INFO): SBD MOMSN=21022463
2024-05-15T17:09:18.317Z,1715792958.317 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:09:30.840Z,1715792970.840 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:09:35.467Z,1715792975.467 [DataOverHttps](INFO): Sending 519 bytes from file Logs/20240514T173041/Express0116.lzma
2024-05-15T17:09:36.467Z,1715792976.467 [DataOverHttps](INFO): Moved sent file to Logs/20240514T173041/Express0116.lzma.bak
2024-05-15T17:09:36.468Z,1715792976.468 [DataOverHttps](INFO): SBD MOMSN=21022466
2024-05-15T17:09:38.519Z,1715792978.519 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-15T17:09:43.364Z,1715792983.364 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:09:54.660Z,1715792994.660 [DataOverHttps](INFO): Sending 1137 bytes from file Logs/20240515T170244/Express0001.lzma
2024-05-15T17:09:55.651Z,1715792995.651 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0001.lzma.bak
2024-05-15T17:09:55.651Z,1715792995.651 [DataOverHttps](INFO): SBD MOMSN=21022492
2024-05-15T17:09:56.372Z,1715792996.372 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:10:09.704Z,1715793009.704 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:10:13.901Z,1715793013.901 [DataOverHttps](INFO): Sending 323 bytes from file Logs/20240515T170244/Express0005.lzma
2024-05-15T17:10:14.903Z,1715793014.903 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0005.lzma.bak
2024-05-15T17:10:14.903Z,1715793014.903 [DataOverHttps](INFO): SBD MOMSN=21022522
2024-05-15T17:10:17.811Z,1715793017.811 [Default:CheckIn:Read_Iridium] Stopped
2024-05-15T17:10:17.811Z,1715793017.811 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-15T17:10:17.811Z,1715793017.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:10:55.266Z,1715793055.266 [CommandExec](IMPORTANT): got command ubat
2024-05-15T17:10:55.266Z,1715793055.266 [CommandExec](FAULT): Incomplete syntax. Try: help ubat
2024-05-15T17:11:00.571Z,1715793060.571 [CommandExec](IMPORTANT): got command failComponent
2024-05-15T17:11:00.572Z,1715793060.572 [CommandExec](IMPORTANT): Failed components:
2024-05-15T17:11:00.573Z,1715793060.573 [CommandExec](IMPORTANT): No failed Components.
2024-05-15T17:11:02.546Z,1715793062.546 [CommandExec](IMPORTANT): got command show stack
2024-05-15T17:11:02.547Z,1715793062.547 [CommandExec](IMPORTANT): Behavior Stack:
2024-05-15T17:11:02.547Z,1715793062.547 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2024-05-15T17:11:02.547Z,1715793062.547 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:C.Wait
2024-05-15T17:12:50.100Z,1715793170.100 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:12:56.160Z,1715793176.160 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:13:09.493Z,1715793189.493 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:13:22.823Z,1715793202.823 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:13:36.156Z,1715793216.156 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:13:49.084Z,1715793229.084 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:14:01.608Z,1715793241.608 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:14:07.147Z,1715793247.147 [CommandExec](IMPORTANT): got command docking arm
2024-05-15T17:14:07.147Z,1715793247.147 [CommandExec](IMPORTANT): Switching docking moudle to ARM.
2024-05-15T17:14:07.687Z,1715793247.687 [DockingStepper](INFO): Resume
2024-05-15T17:14:08.107Z,1715793248.107 [DockingStepper](INFO): Resuming
2024-05-15T17:14:08.521Z,1715793248.521 [DockingStepper](INFO): Start
2024-05-15T17:14:08.521Z,1715793248.521 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-15T17:14:08.640Z,1715793248.640 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-15T17:14:08.949Z,1715793248.949 [DockingStepper](INFO): Starting
2024-05-15T17:14:09.308Z,1715793249.308 [DockingStepper](INFO): Starting
2024-05-15T17:14:09.774Z,1715793249.774 [DockingStepper](INFO): Starting
2024-05-15T17:14:09.831Z,1715793249.831 [DockingStepper](INFO): Init string: /1z15007aE13561N3m50
2024-05-15T17:14:10.138Z,1715793250.138 [DockingStepper](INFO): Starting
2024-05-15T17:14:10.547Z,1715793250.547 [DockingStepper](INFO): Starting
2024-05-15T17:14:10.985Z,1715793250.985 [DockingStepper](INFO): Starting
2024-05-15T17:14:11.339Z,1715793251.339 [DockingStepper](INFO): Starting
2024-05-15T17:14:11.366Z,1715793251.366 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-15T17:14:11.730Z,1715793251.730 [DockingStepper](INFO): Starting
2024-05-15T17:14:12.132Z,1715793252.132 [DockingStepper](INFO): Changing to mode: 2
2024-05-15T17:14:12.132Z,1715793252.132 [DockingStepper](INFO): Armed mode.
2024-05-15T17:14:12.182Z,1715793252.182 [DockingStepper](INFO): Commanding negative:46330
2024-05-15T17:14:14.536Z,1715793254.536 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:14:27.596Z,1715793267.596 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:14:40.934Z,1715793280.934 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:14:53.852Z,1715793293.852 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:15:06.780Z,1715793306.780 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:15:18.516Z,1715793318.516 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-15T17:15:18.516Z,1715793318.516 [Default:CheckIn:C.Wait] Stopped
2024-05-15T17:15:18.516Z,1715793318.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-15T17:15:18.516Z,1715793318.516 [Default:CheckIn:D] Running Loop=1
2024-05-15T17:15:18.935Z,1715793318.935 [Default:CheckIn:D] Stopped
2024-05-15T17:15:18.935Z,1715793318.935 [Default:CheckIn:E] Running Loop=1
2024-05-15T17:15:19.329Z,1715793319.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.796917 min
2024-05-15T17:15:19.329Z,1715793319.329 [Default:CheckIn:E] Stopped
2024-05-15T17:15:19.329Z,1715793319.329 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-15T17:15:19.330Z,1715793319.330 [Default:CheckIn] Stopped
2024-05-15T17:15:19.330Z,1715793319.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-15T17:15:19.330Z,1715793319.330 [Default:CheckIn](INFO): Running loop #2
2024-05-15T17:15:19.330Z,1715793319.330 [Default:CheckIn] Running Loop=2
2024-05-15T17:15:19.330Z,1715793319.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-15T17:15:19.330Z,1715793319.330 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-15T17:15:21.327Z,1715793321.327 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171520.00,A,3648.16427,N,12147.28361,W,0.175,130.26,150524,,,A*7E
2024-05-15T17:15:21.329Z,1715793321.329 [NAL9602](INFO): GPS fix at 20240515T171520: (36.802738, -121.788060)
2024-05-15T17:15:21.340Z,1715793321.340 [Default:CheckIn:Read_GPS] Stopped
2024-05-15T17:15:21.340Z,1715793321.340 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-15T17:15:29.545Z,1715793329.545 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240515T170244/Courier0007.lzma
2024-05-15T17:15:30.547Z,1715793330.547 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0007.lzma.bak
2024-05-15T17:15:30.547Z,1715793330.547 [DataOverHttps](INFO): SBD MOMSN=21022532
2024-05-15T17:15:51.677Z,1715793351.677 [DataOverHttps](INFO): Sending 412 bytes from file Logs/20240515T170244/Express0008.lzma
2024-05-15T17:15:52.136Z,1715793352.136 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-05-15T17:15:52.209Z,1715793352.209 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-05-15T17:15:52.680Z,1715793352.680 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0008.lzma.bak
2024-05-15T17:15:52.680Z,1715793352.680 [DataOverHttps](INFO): SBD MOMSN=21022535
2024-05-15T17:15:56.002Z,1715793356.002 [Default:CheckIn:Read_Iridium] Stopped
2024-05-15T17:15:56.002Z,1715793356.002 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-15T17:15:56.002Z,1715793356.002 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:16:04.625Z,1715793364.625 [CommandExec](IMPORTANT): got command docking standby
2024-05-15T17:16:04.626Z,1715793364.626 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY.
2024-05-15T17:16:04.752Z,1715793364.752 [DockingStepper](INFO): Changing to mode: 1
2024-05-15T17:16:04.752Z,1715793364.752 [DockingStepper](INFO): Standby mode.
2024-05-15T17:16:04.801Z,1715793364.801 [DockingStepper](INFO): Commanding positive:55387
2024-05-15T17:17:30.711Z,1715793450.711 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:17:36.364Z,1715793456.364 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:17:49.697Z,1715793469.697 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:18:02.669Z,1715793482.669 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:18:15.212Z,1715793495.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:18:27.674Z,1715793507.674 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:18:40.716Z,1715793520.716 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:18:47.587Z,1715793527.587 [NAL9602](INFO): SBD MO Status=2, MOMSN=37336, MT Status=2, MTMSN=0
2024-05-15T17:18:47.587Z,1715793527.587 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:18:53.240Z,1715793533.240 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:19:06.174Z,1715793546.174 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:19:19.096Z,1715793559.096 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:19:32.036Z,1715793572.036 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:19:45.368Z,1715793585.368 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:20:07.255Z,1715793607.255 [NAL9602](INFO): SBD MO Status=2, MOMSN=37336, MT Status=2, MTMSN=0
2024-05-15T17:20:07.255Z,1715793607.255 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:20:23.434Z,1715793623.434 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-15T17:20:54.118Z,1715793654.118 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-15T17:20:56.673Z,1715793656.673 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-15T17:20:56.677Z,1715793656.677 [Default:CheckIn:C.Wait] Stopped
2024-05-15T17:20:56.677Z,1715793656.677 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-15T17:20:56.677Z,1715793656.677 [Default:CheckIn:D] Running Loop=1
2024-05-15T17:20:57.206Z,1715793657.206 [Default:CheckIn:D] Stopped
2024-05-15T17:20:57.206Z,1715793657.206 [Default:CheckIn:E] Running Loop=1
2024-05-15T17:20:57.513Z,1715793657.513 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.434776 min
2024-05-15T17:20:57.513Z,1715793657.513 [Default:CheckIn:E] Stopped
2024-05-15T17:20:57.513Z,1715793657.513 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-15T17:20:57.513Z,1715793657.513 [Default:CheckIn] Stopped
2024-05-15T17:20:57.524Z,1715793657.524 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-15T17:20:57.525Z,1715793657.525 [Default:CheckIn](INFO): Running loop #3
2024-05-15T17:20:57.525Z,1715793657.525 [Default:CheckIn] Running Loop=3
2024-05-15T17:20:57.525Z,1715793657.525 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-15T17:20:57.541Z,1715793657.541 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-15T17:20:59.552Z,1715793659.552 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172058.00,A,3648.16388,N,12147.27469,W,0.778,48.09,150524,,,A*4D
2024-05-15T17:20:59.554Z,1715793659.554 [NAL9602](INFO): GPS fix at 20240515T172058: (36.802731, -121.787912)
2024-05-15T17:20:59.590Z,1715793659.590 [Default:CheckIn:Read_GPS] Stopped
2024-05-15T17:20:59.590Z,1715793659.590 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-15T17:21:08.525Z,1715793668.525 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240515T170244/Courier0010.lzma
2024-05-15T17:21:09.527Z,1715793669.527 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0010.lzma.bak
2024-05-15T17:21:09.527Z,1715793669.527 [DataOverHttps](INFO): SBD MOMSN=21022555
2024-05-15T17:21:29.773Z,1715793689.773 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20240515T170244/Express0011.lzma
2024-05-15T17:21:30.186Z,1715793690.186 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-05-15T17:21:30.269Z,1715793690.269 [NAL9602](ERROR): received:
+CSQ:0
OK336, 2, 0, 0, 0
OK
2024-05-15T17:21:30.780Z,1715793690.780 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0011.lzma.bak
2024-05-15T17:21:30.780Z,1715793690.780 [DataOverHttps](INFO): SBD MOMSN=21022558
2024-05-15T17:21:33.873Z,1715793693.873 [Default:CheckIn:Read_Iridium] Stopped
2024-05-15T17:21:33.873Z,1715793693.873 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-15T17:21:33.877Z,1715793693.877 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:22:36.848Z,1715793756.848 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:22:49.373Z,1715793769.373 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:22:50.997Z,1715793770.997 [RDI_Pathfinder](ERROR): only read 0 of 4 data items for beam range. Device response is::RA, 0. +0.00, 20.00,1183.97
2024-05-15T17:23:02.301Z,1715793782.301 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:23:14.826Z,1715793794.826 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:23:27.348Z,1715793807.348 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:23:40.681Z,1715793820.681 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:23:52.803Z,1715793832.803 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:24:06.132Z,1715793846.132 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:24:19.464Z,1715793859.464 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:24:32.796Z,1715793872.796 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:24:45.724Z,1715793885.724 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:26:01.313Z,1715793961.313 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-15T17:26:32.029Z,1715793992.029 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-15T17:26:34.471Z,1715793994.471 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-15T17:26:34.471Z,1715793994.471 [Default:CheckIn:C.Wait] Stopped
2024-05-15T17:26:34.471Z,1715793994.471 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-15T17:26:34.471Z,1715793994.471 [Default:CheckIn:D] Running Loop=1
2024-05-15T17:26:34.892Z,1715793994.892 [Default:CheckIn:D] Stopped
2024-05-15T17:26:34.892Z,1715793994.892 [Default:CheckIn:E] Running Loop=1
2024-05-15T17:26:35.266Z,1715793995.266 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.062878 min
2024-05-15T17:26:35.266Z,1715793995.266 [Default:CheckIn:E] Stopped
2024-05-15T17:26:35.267Z,1715793995.267 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-15T17:26:35.267Z,1715793995.267 [Default:CheckIn] Stopped
2024-05-15T17:26:35.267Z,1715793995.267 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-15T17:26:35.267Z,1715793995.267 [Default:CheckIn](INFO): Running loop #4
2024-05-15T17:26:35.267Z,1715793995.267 [Default:CheckIn] Running Loop=4
2024-05-15T17:26:35.267Z,1715793995.267 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-15T17:26:35.267Z,1715793995.267 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-15T17:26:37.280Z,1715793997.280 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172636.00,A,3648.16421,N,12147.28667,W,0.330,48.09,150524,,,A*4C
2024-05-15T17:26:37.282Z,1715793997.282 [NAL9602](INFO): GPS fix at 20240515T172636: (36.802737, -121.788111)
2024-05-15T17:26:37.294Z,1715793997.294 [Default:CheckIn:Read_GPS] Stopped
2024-05-15T17:26:37.294Z,1715793997.294 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-15T17:26:46.553Z,1715794006.553 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240515T170244/Courier0013.lzma
2024-05-15T17:26:47.555Z,1715794007.555 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0013.lzma.bak
2024-05-15T17:26:47.555Z,1715794007.555 [DataOverHttps](INFO): SBD MOMSN=21022570
2024-05-15T17:27:06.461Z,1715794026.461 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20240515T170244/Express0014.lzma
2024-05-15T17:27:07.463Z,1715794027.463 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0014.lzma.bak
2024-05-15T17:27:07.463Z,1715794027.463 [DataOverHttps](INFO): SBD MOMSN=21022573
2024-05-15T17:27:07.589Z,1715794027.589 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:27:07.990Z,1715794027.990 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-05-15T17:27:08.069Z,1715794028.069 [NAL9602](ERROR): received:
+CSQ:0
OK336, 2, 0, 0, 0
OK
2024-05-15T17:27:10.422Z,1715794030.422 [Default:CheckIn:Read_Iridium] Stopped
2024-05-15T17:27:10.422Z,1715794030.422 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-15T17:27:10.422Z,1715794030.422 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:27:13.943Z,1715794033.943 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:27:26.809Z,1715794046.809 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:27:38.928Z,1715794058.928 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:27:45.395Z,1715794065.395 [NAL9602](INFO): SBD MO Status=2, MOMSN=37336, MT Status=2, MTMSN=0
2024-05-15T17:27:45.395Z,1715794065.395 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:27:51.856Z,1715794071.856 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:28:04.399Z,1715794084.399 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:28:17.727Z,1715794097.727 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:28:30.241Z,1715794110.241 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:28:43.572Z,1715794123.572 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:28:56.098Z,1715794136.098 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:29:09.532Z,1715794149.532 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:29:22.460Z,1715794162.460 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-15T17:29:40.244Z,1715794180.244 [NAL9602](INFO): SBD MO Status=2, MOMSN=37336, MT Status=2, MTMSN=0
2024-05-15T17:29:40.244Z,1715794180.244 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:30:23.978Z,1715794223.978 [NAL9602](INFO): SBD MO Status=0, MOMSN=37336, MT Status=0, MTMSN=0
2024-05-15T17:30:23.979Z,1715794223.979 [NAL9602](INFO): No messages in MT queue
2024-05-15T17:30:54.648Z,1715794254.648 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-15T17:32:11.074Z,1715794331.074 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-15T17:32:11.074Z,1715794331.074 [Default:CheckIn:C.Wait] Stopped
2024-05-15T17:32:11.075Z,1715794331.075 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-15T17:32:11.075Z,1715794331.075 [Default:CheckIn:D] Running Loop=1
2024-05-15T17:32:11.480Z,1715794331.480 [Default:CheckIn:D] Stopped
2024-05-15T17:32:11.480Z,1715794331.480 [Default:CheckIn:E] Running Loop=1
2024-05-15T17:32:11.883Z,1715794331.883 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.672677 min
2024-05-15T17:32:11.883Z,1715794331.883 [Default:CheckIn:E] Stopped
2024-05-15T17:32:11.883Z,1715794331.883 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-15T17:32:11.883Z,1715794331.883 [Default:CheckIn] Stopped
2024-05-15T17:32:11.883Z,1715794331.883 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-15T17:32:11.884Z,1715794331.884 [Default:CheckIn](INFO): Running loop #5
2024-05-15T17:32:11.884Z,1715794331.884 [Default:CheckIn] Running Loop=5
2024-05-15T17:32:11.884Z,1715794331.884 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-15T17:32:11.884Z,1715794331.884 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-15T17:32:13.900Z,1715794333.900 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173213.00,A,3648.16685,N,12147.28196,W,0.272,48.09,150524,,,A*4C
2024-05-15T17:32:13.902Z,1715794333.902 [NAL9602](INFO): GPS fix at 20240515T173213: (36.802781, -121.788033)
2024-05-15T17:32:13.941Z,1715794333.941 [Default:CheckIn:Read_GPS] Stopped
2024-05-15T17:32:13.941Z,1715794333.941 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-15T17:32:16.321Z,1715794336.321 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:32:23.093Z,1715794343.093 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240515T170244/Courier0016.lzma
2024-05-15T17:32:24.095Z,1715794344.095 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0016.lzma.bak
2024-05-15T17:32:24.095Z,1715794344.095 [DataOverHttps](INFO): SBD MOMSN=21022584
2024-05-15T17:32:29.655Z,1715794349.655 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:32:42.992Z,1715794362.992 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:32:43.545Z,1715794363.545 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240515T170244/Express0017.lzma
2024-05-15T17:32:44.547Z,1715794364.547 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0017.lzma.bak
2024-05-15T17:32:44.547Z,1715794364.547 [DataOverHttps](INFO): SBD MOMSN=21022587
2024-05-15T17:32:47.465Z,1715794367.465 [Default:CheckIn:Read_Iridium] Stopped
2024-05-15T17:32:47.465Z,1715794367.465 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-15T17:32:47.465Z,1715794367.465 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:32:55.912Z,1715794375.912 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:33:08.442Z,1715794388.442 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:33:21.084Z,1715794401.084 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:33:34.416Z,1715794414.416 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:33:46.946Z,1715794426.946 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-15T17:33:57.445Z,1715794437.445 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 4.
2024-05-15T17:33:57.448Z,1715794437.448 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-05-15T17:34:33.004Z,1715794473.004 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:34:33.004Z,1715794473.004 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:34:53.199Z,1715794493.199 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:34:53.199Z,1715794493.199 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:35:36.496Z,1715794536.496 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:35:36.496Z,1715794536.496 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:36:03.563Z,1715794563.563 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:36:03.563Z,1715794563.563 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:36:48.411Z,1715794608.411 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:36:48.412Z,1715794608.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:37:05.374Z,1715794625.374 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:37:05.375Z,1715794625.375 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:37:16.282Z,1715794636.282 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-15T17:37:46.990Z,1715794666.990 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-15T17:37:48.207Z,1715794668.207 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-15T17:37:48.208Z,1715794668.208 [Default:CheckIn:C.Wait] Stopped
2024-05-15T17:37:48.208Z,1715794668.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-15T17:37:48.208Z,1715794668.208 [Default:CheckIn:D] Running Loop=1
2024-05-15T17:37:48.669Z,1715794668.669 [Default:CheckIn:D] Stopped
2024-05-15T17:37:48.669Z,1715794668.669 [Default:CheckIn:E] Running Loop=1
2024-05-15T17:37:49.040Z,1715794669.040 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.292497 min
2024-05-15T17:37:49.040Z,1715794669.040 [Default:CheckIn:E] Stopped
2024-05-15T17:37:49.041Z,1715794669.041 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-15T17:37:49.041Z,1715794669.041 [Default:CheckIn] Stopped
2024-05-15T17:37:49.041Z,1715794669.041 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-15T17:37:49.041Z,1715794669.041 [Default:CheckIn](INFO): Running loop #6
2024-05-15T17:37:49.041Z,1715794669.041 [Default:CheckIn] Running Loop=6
2024-05-15T17:37:49.041Z,1715794669.041 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-15T17:37:49.041Z,1715794669.041 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-15T17:37:51.027Z,1715794671.027 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173750.00,A,3648.16519,N,12147.28053,W,0.544,0.00,150524,,,A*77
2024-05-15T17:37:51.029Z,1715794671.029 [NAL9602](INFO): GPS fix at 20240515T173750: (36.802753, -121.788009)
2024-05-15T17:37:51.040Z,1715794671.040 [Default:CheckIn:Read_GPS] Stopped
2024-05-15T17:37:51.040Z,1715794671.040 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-15T17:38:00.078Z,1715794680.078 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20240515T170244/Courier0019.lzma
2024-05-15T17:38:01.079Z,1715794681.079 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0019.lzma.bak
2024-05-15T17:38:01.079Z,1715794681.079 [DataOverHttps](INFO): SBD MOMSN=21022596
2024-05-15T17:38:19.078Z,1715794699.078 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20240515T170244/Express0020.lzma
2024-05-15T17:38:20.079Z,1715794700.079 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0020.lzma.bak
2024-05-15T17:38:20.080Z,1715794700.080 [DataOverHttps](INFO): SBD MOMSN=21022599
2024-05-15T17:38:21.830Z,1715794701.830 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-05-15T17:38:21.905Z,1715794701.905 [NAL9602](ERROR): received:
+CSQ:1
OK337, 2, 0, 0, 0
OK
2024-05-15T17:38:23.066Z,1715794703.066 [Default:CheckIn:Read_Iridium] Stopped
2024-05-15T17:38:23.066Z,1715794703.066 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-15T17:38:23.066Z,1715794703.066 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:42:27.669Z,1715794947.669 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:42:27.669Z,1715794947.669 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:42:44.279Z,1715794964.279 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:42:44.280Z,1715794964.280 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:42:53.167Z,1715794973.167 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-15T17:43:23.489Z,1715795003.489 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-15T17:43:23.489Z,1715795003.489 [Default:CheckIn:C.Wait] Stopped
2024-05-15T17:43:23.489Z,1715795003.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-15T17:43:23.489Z,1715795003.489 [Default:CheckIn:D] Running Loop=1
2024-05-15T17:43:23.869Z,1715795003.869 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-15T17:43:23.900Z,1715795003.900 [Default:CheckIn:D] Stopped
2024-05-15T17:43:23.900Z,1715795003.900 [Default:CheckIn:E] Running Loop=1
2024-05-15T17:43:24.284Z,1715795004.284 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.879679 min
2024-05-15T17:43:24.284Z,1715795004.284 [Default:CheckIn:E] Stopped
2024-05-15T17:43:24.284Z,1715795004.284 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-15T17:43:24.284Z,1715795004.284 [Default:CheckIn] Stopped
2024-05-15T17:43:24.285Z,1715795004.285 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-15T17:43:24.285Z,1715795004.285 [Default:CheckIn](INFO): Running loop #7
2024-05-15T17:43:24.285Z,1715795004.285 [Default:CheckIn] Running Loop=7
2024-05-15T17:43:24.285Z,1715795004.285 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-15T17:43:24.286Z,1715795004.286 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-15T17:43:26.304Z,1715795006.304 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174325.00,A,3648.16052,N,12147.28194,W,0.194,0.00,150524,,,A*7F
2024-05-15T17:43:26.306Z,1715795006.306 [NAL9602](INFO): GPS fix at 20240515T174325: (36.802675, -121.788032)
2024-05-15T17:43:26.317Z,1715795006.317 [Default:CheckIn:Read_GPS] Stopped
2024-05-15T17:43:26.317Z,1715795006.317 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-15T17:43:35.513Z,1715795015.513 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240515T170244/Courier0022.lzma
2024-05-15T17:43:36.516Z,1715795016.516 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0022.lzma.bak
2024-05-15T17:43:36.516Z,1715795016.516 [DataOverHttps](INFO): SBD MOMSN=21022609
2024-05-15T17:43:55.393Z,1715795035.393 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20240515T170244/Express0023.lzma
2024-05-15T17:43:56.395Z,1715795036.395 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0023.lzma.bak
2024-05-15T17:43:56.395Z,1715795036.395 [DataOverHttps](INFO): SBD MOMSN=21022612
2024-05-15T17:43:57.005Z,1715795037.005 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-05-15T17:43:57.078Z,1715795037.078 [NAL9602](ERROR): received:
+CSQ:1
OK337, 2, 0, 0, 0
OK
2024-05-15T17:43:59.466Z,1715795039.466 [Default:CheckIn:Read_Iridium] Stopped
2024-05-15T17:43:59.466Z,1715795039.466 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-15T17:43:59.466Z,1715795039.466 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:44:42.255Z,1715795082.255 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:44:42.255Z,1715795082.255 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:46:47.899Z,1715795207.899 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:46:47.899Z,1715795207.899 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:47:09.720Z,1715795229.720 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:47:09.720Z,1715795229.720 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:48:28.501Z,1715795308.501 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-15T17:48:59.199Z,1715795339.199 [NAL9602](INFO): Not Powering down - fast GPS
2024-05-15T17:49:00.016Z,1715795340.016 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-15T17:49:00.016Z,1715795340.016 [Default:CheckIn:C.Wait] Stopped
2024-05-15T17:49:00.016Z,1715795340.016 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-15T17:49:00.016Z,1715795340.016 [Default:CheckIn:D] Running Loop=1
2024-05-15T17:49:00.421Z,1715795340.421 [Default:CheckIn:D] Stopped
2024-05-15T17:49:00.421Z,1715795340.421 [Default:CheckIn:E] Running Loop=1
2024-05-15T17:49:00.845Z,1715795340.845 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.488350 min
2024-05-15T17:49:00.845Z,1715795340.845 [Default:CheckIn:E] Stopped
2024-05-15T17:49:00.845Z,1715795340.845 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-15T17:49:00.845Z,1715795340.845 [Default:CheckIn] Stopped
2024-05-15T17:49:00.845Z,1715795340.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-15T17:49:00.846Z,1715795340.846 [Default:CheckIn](INFO): Running loop #8
2024-05-15T17:49:00.846Z,1715795340.846 [Default:CheckIn] Running Loop=8
2024-05-15T17:49:00.846Z,1715795340.846 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-15T17:49:00.846Z,1715795340.846 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-15T17:49:02.850Z,1715795342.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174902.00,A,3648.16756,N,12147.28680,W,0.097,104.07,150524,,,A*71
2024-05-15T17:49:02.852Z,1715795342.852 [NAL9602](INFO): GPS fix at 20240515T174902: (36.802793, -121.788113)
2024-05-15T17:49:02.884Z,1715795342.884 [Default:CheckIn:Read_GPS] Stopped
2024-05-15T17:49:02.884Z,1715795342.884 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-15T17:49:11.865Z,1715795351.865 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240515T170244/Courier0025.lzma
2024-05-15T17:49:12.867Z,1715795352.867 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Courier0025.lzma.bak
2024-05-15T17:49:12.867Z,1715795352.867 [DataOverHttps](INFO): SBD MOMSN=21022622
2024-05-15T17:49:31.558Z,1715795371.558 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20240515T170244/Express0026.lzma
2024-05-15T17:49:32.559Z,1715795372.559 [DataOverHttps](INFO): Moved sent file to Logs/20240515T170244/Express0026.lzma.bak
2024-05-15T17:49:32.559Z,1715795372.559 [DataOverHttps](INFO): SBD MOMSN=21022625
2024-05-15T17:49:33.615Z,1715795373.615 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-05-15T17:49:33.693Z,1715795373.693 [NAL9602](ERROR): received:
+CSQ:0
OK337, 2, 0, 0, 0
OK
2024-05-15T17:49:35.662Z,1715795375.662 [Default:CheckIn:Read_Iridium] Stopped
2024-05-15T17:49:35.662Z,1715795375.662 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-15T17:49:35.663Z,1715795375.663 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:50:30.999Z,1715795430.999 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:50:30.999Z,1715795430.999 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:51:10.660Z,1715795470.660 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:51:10.661Z,1715795470.661 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:51:36.075Z,1715795496.075 [NAL9602](INFO): SBD MO Status=2, MOMSN=37337, MT Status=2, MTMSN=0
2024-05-15T17:51:36.076Z,1715795496.076 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-05-15T17:51:58.296Z,1715795518.296 [NAL9602](INFO): SBD MO Status=0, MOMSN=37337, MT Status=0, MTMSN=0
2024-05-15T17:51:58.296Z,1715795518.296 [NAL9602](INFO): No messages in MT queue
2024-05-15T17:52:15.407Z,1715795535.407 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/OnDock.tl
2024-05-15T17:52:15.408Z,1715795535.408 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/OnDock.tl
2024-05-15T17:52:15.408Z,1715795535.408 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/OnDock.tl
2024-05-15T17:52:15.411Z,1715795535.411 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/OnDock.tl
2024-05-15T17:52:15.965Z,1715795535.965 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/OnDock.tl translated into:
8
2
1
5
2
30
5
Restarting DHCP...
! /mnt/mmc/LRAUV/Tools/dhcp.sh
Restarting logs
restart logs
! ifconfig
Mission ending, breaking off. Range:
.
2024-05-15T17:52:15.972Z,1715795535.972 [MissionManager](INFO): DefineArg OnDock.DockedTime = 8.000000 h
2024-05-15T17:52:15.976Z,1715795535.976 [MissionManager](INFO): DefineArg OnDock.DetachedTimeout = 300.000000 s
2024-05-15T17:52:15.981Z,1715795535.981 [MissionManager](INFO): DefineArg OnDock.RangeTimeout = 600.000000 s
2024-05-15T17:52:15.984Z,1715795535.984 [MissionManager](INFO): DefineArg OnDock.TrackingUpdatePeriod = 2.000000 min
2024-05-15T17:52:15.988Z,1715795535.988 [MissionManager](INFO): DefineArg OnDock.NumberOfPings = 1.000000 count
2024-05-15T17:52:15.992Z,1715795535.992 [MissionManager](INFO): DefineArg OnDock.TransponderCode = 201 count
2024-05-15T17:52:16.020Z,1715795536.020 [MissionManager](INFO): DefineArg OnDock.MaxDepth = 215.000000 m
2024-05-15T17:52:16.028Z,1715795536.028 [MissionManager](INFO): DefineArg OnDock.MinAltitude = 5.000000 m
2024-05-15T17:52:16.079Z,1715795536.079 [MissionManager](INFO): DefineArg OnDock.MinOffshore = 2.000000 km
2024-05-15T17:52:16.098Z,1715795536.098 [MissionManager](INFO): DefineArg OnDock.StayOnDock = 0 bool
2024-05-15T17:52:16.109Z,1715795536.109 [MissionManager](INFO): DefineArg OnDock.IfconfigInterval = 30.000000 min
2024-05-15T17:52:16.129Z,1715795536.129 [MissionManager](INFO): DefineArg OnDock.MissionTimeout = 8.000000 h
2024-05-15T17:52:16.136Z,1715795536.136 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2024-05-15T17:52:16.137Z,1715795536.137 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2024-05-15T17:52:16.142Z,1715795536.142 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2024-05-15T17:52:16.408Z,1715795536.408 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2024-05-15T17:52:16.418Z,1715795536.418 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinAltitude = 5.000000 m
2024-05-15T17:52:16.430Z,1715795536.430 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2024-05-15T17:52:16.442Z,1715795536.442 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepth = 200.000000 m
2024-05-15T17:52:16.482Z,1715795536.482 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinOffshore = 2000.000000 m
2024-05-15T17:52:16.483Z,1715795536.483 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2024-05-15T17:52:16.516Z,1715795536.516 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2024-05-15T17:52:16.554Z,1715795536.554 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2024-05-15T17:52:16.653Z,1715795536.653 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-05-15T17:52:16.654Z,1715795536.654 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2024-05-15T17:52:16.657Z,1715795536.657 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2024-05-15T17:52:16.708Z,1715795536.708 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2024-05-15T17:52:16.716Z,1715795536.716 [MissionManager](INFO): DefineArg OnDock:BackseatDriver.EnableBackseat = 0 bool
2024-05-15T17:52:16.723Z,1715795536.723 [OnDock:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-05-15T17:52:16.726Z,1715795536.726 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2024-05-15T17:52:16.727Z,1715795536.727 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2024-05-15T17:52:16.728Z,1715795536.728 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2024-05-15T17:52:17.018Z,1715795537.018 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2024-05-15T17:52:17.035Z,1715795537.035 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleAll = 0 bool
2024-05-15T17:52:17.038Z,1715795537.038 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad1 = 0 bool
2024-05-15T17:52:17.050Z,1715795537.050 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad2 = 0 bool
2024-05-15T17:52:17.055Z,1715795537.055 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad3 = 0 bool
2024-05-15T17:52:17.068Z,1715795537.068 [MissionManager](INFO): DefineArg OnDock:PowerOnly.EnabledPowerOnly = 0 bool
2024-05-15T17:52:17.099Z,1715795537.099 [OnDock:PowerOnly:E.Wait](DEBUG): Construct Wait.
2024-05-15T17:52:17.251Z,1715795537.251 [OnDock:restartDHCP:B.Execute](DEBUG): Construct Execute.
2024-05-15T17:52:17.290Z,1715795537.290 [OnDock:L.Execute](DEBUG): Construct Execute.
2024-05-15T17:52:17.302Z,1715795537.302 [OnDock:PeriodicCheck:CheckIP:A.Execute](DEBUG): Construct Execute.
2024-05-15T17:52:17.304Z,1715795537.304 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Construct Wait.
2024-05-15T17:52:17.314Z,1715795537.314 [OnDock:O.Docked](DEBUG): Construct.
2024-05-15T17:52:17.314Z,1715795537.314 [OnDock:O.Docked](DEBUG): Initializing internal variables to default values.
2024-05-15T17:52:17.373Z,1715795537.373 [OnDock:Detach:B.Undock](DEBUG): Construct.
2024-05-15T17:52:17.382Z,1715795537.382 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values.
2024-05-15T17:52:17.438Z,1715795537.438 [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 {
"""
Wait on dock, communicating when possible
"""
arguments {
DockedTime = 8 hour
"""
Time to stay on dock. Mission timeout will be five minutes longer.
"""
DetachedTimeout = Dock:Docked.detachTimeout
"""
Time duration limit for determining if the vehicle is detached from the
dock.
"""
RangeTimeout = Dock:Docked.rangeTimeout
"""
Maximum allowed time without acoustic range.
"""
TrackingUpdatePeriod = 2 minute
"""
How long to wait between acoustic queries.
"""
NumberOfPings = 1 count
"""
Number of pings requested each time.
"""
TransponderCode = Dock:Dock.transponderCode
"""
Transponder Address.
"""
MaxDepth = BIT:CBIT.stopDepth
"""
Maximum depth while docked.
"""
MinAltitude = 5 meter
"""
Minimum altitude
"""
MinOffshore = 2 km
"""
Minimum offshore
"""
StayOnDock = false
"""
If true, don't detach from dock at end of mission
"""
IfconfigInterval = 30 minute
"""
How long to wait between ifconfig runs
"""
# Mission variables (do not change)
MissionTimeout = DockedTime
"""
Mission timeout (local variable, do not change)
"""
}
timeout duration=MissionTimeout
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
# End docking 5 minutes before end of mission to allow clean undock
assign in sequence MissionTimeout = DockedTime + 5 minute
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
# Send data when connection exists
readData {
while (
Sensor:DataOverHttps.connectionStatus == true
)
Universal:platform_communications
}
behavior Estimation:TrackAcousticContact {
run in parallel
set contactLabelSetting = TransponderCode
set numberOfSamplesSetting = NumberOfPings
set updatePeriodSetting = TrackingUpdatePeriod
}
aggregate restartDHCP {
run when ( called )
syslog important "Restarting DHCP..."
behavior Guidance:Execute {
run in sequence
set command = "! /mnt/mmc/LRAUV/Tools/dhcp.sh"
}
}
syslog important "Restarting logs"
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
# Kick DHCP again, in case it took us a while to close the arm in Docked
call refId="restartDHCP"
aggregate PeriodicCheck {
run in parallel
aggregate CheckIP {
run in sequence
behavior Guidance:Execute {
run in sequence
set command = "! ifconfig"
}
behavior Guidance:Wait {
run in sequence
set duration = IfconfigInterval
}
}
}
behavior Dock:Docked {
run in sequence
timeout duration=DockedTime
set detachTimeout = DetachedTimeout
set rangeTimeout = RangeTimeout
}
# Undock cleanly, instead of relying on DefaultWithUndock to come next
aggregate Detach {
run in sequence
break if ( StayOnDock == true )
syslog important "Mission ending, breaking off. Range: "
+ Estimation:TrackAcousticContact.range_to_contact~meter + "."
behavior Dock:Undock {
run in sequence
}
# Get our 10.89 IP back once we're on the surface
call refId="restartDHCP"
}
}
2024-05-15T17:52:17.441Z,1715795537.441 [CommandExec](IMPORTANT): Running ./Missions/Engineering/OnDock.tl
2024-05-15T17:52:17.710Z,1715795537.710 [Default] Stopped
2024-05-15T17:52:17.710Z,1715795537.710 [Default](DEBUG): Aggregate::uninitialize Default
2024-05-15T17:52:17.710Z,1715795537.710 [Default:B.GoToSurface] Stopped
2024-05-15T17:52:17.710Z,1715795537.710 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-15T17:52:17.710Z,1715795537.710 [Default:CheckIn] Stopped
2024-05-15T17:52:17.710Z,1715795537.710 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-15T17:52:17.710Z,1715795537.710 [Default:CheckIn:C.Wait] Stopped
2024-05-15T17:52:17.710Z,1715795537.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-15T17:52:17.710Z,1715795537.710 [MissionManager](IMPORTANT): Started mission OnDock
2024-05-15T17:52:17.711Z,1715795537.711 [OnDock] Running Loop=1
2024-05-15T17:52:17.711Z,1715795537.711 [OnDock](DEBUG): Aggregate::initialize OnDock
2024-05-15T17:52:17.711Z,1715795537.711 [OnDock:StandardEnvelopes] Running Loop=1
2024-05-15T17:52:17.711Z,1715795537.711 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes
2024-05-15T17:52:17.711Z,1715795537.711 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-05-15T17:52:17.711Z,1715795537.711 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2024-05-15T17:52:17.711Z,1715795537.711 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:BackseatDriver] Running Loop=1
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:PowerOnly] Running Loop=1
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:PowerOnly:A] Running Loop=1
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:PowerOnly:B] Running Loop=1
2024-05-15T17:52:17.712Z,1715795537.712 [OnDock:PowerOnly:C] Running Loop=1
2024-05-15T17:52:17.713Z,1715795537.713 [OnDock:PowerOnly:D] Running Loop=1
2024-05-15T17:52:17.713Z,1715795537.713 [OnDock:PowerOnly:E.Wait] Running Loop=1
2024-05-15T17:52:17.713Z,1715795537.713 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:52:17.713Z,1715795537.713 [OnDock:H] Running Loop=1
2024-05-15T17:52:17.713Z,1715795537.713 [OnDock:I.] Running Loop=1
2024-05-15T17:52:17.713Z,1715795537.713 [OnDock:I.](INFO): Initializing TrackAcousticContact.
2024-05-15T17:52:17.714Z,1715795537.714 [OnDock:PeriodicCheck] Running Loop=1
2024-05-15T17:52:17.714Z,1715795537.714 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck
2024-05-15T17:52:17.714Z,1715795537.714 [OnDock:PeriodicCheck:CheckIP] Running Loop=1
2024-05-15T17:52:17.714Z,1715795537.714 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP
2024-05-15T17:52:17.714Z,1715795537.714 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1
2024-05-15T17:52:17.714Z,1715795537.714 [OnDock:B] Running Loop=1
2024-05-15T17:52:17.715Z,1715795537.715 [OnDock:PeriodicCheck] Running Loop=1
2024-05-15T17:52:17.715Z,1715795537.715 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig
2024-05-15T17:52:17.715Z,1715795537.715 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped
2024-05-15T17:52:17.715Z,1715795537.715 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1
2024-05-15T17:52:17.715Z,1715795537.715 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component.
2024-05-15T17:52:17.715Z,1715795537.715 [OnDock:I.] Running Loop=1
2024-05-15T17:52:17.716Z,1715795537.716 [OnDock:H] Running Loop=1
2024-05-15T17:52:17.717Z,1715795537.717 [OnDock:H](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-15T17:52:17.717Z,1715795537.717 [OnDock:PowerOnly] Running Loop=1
2024-05-15T17:52:17.717Z,1715795537.717 [OnDock:PowerOnly:D] Stopped
2024-05-15T17:52:17.717Z,1715795537.717 [OnDock:PowerOnly:C] Stopped
2024-05-15T17:52:17.717Z,1715795537.717 [OnDock:PowerOnly:B] Stopped
2024-05-15T17:52:17.717Z,1715795537.717 [OnDock:PowerOnly:A] Stopped
2024-05-15T17:52:17.717Z,1715795537.717 [OnDock:BackseatDriver] Running Loop=1
2024-05-15T17:52:17.718Z,1715795537.718 [OnDock:B] Stopped
2024-05-15T17:52:17.718Z,1715795537.718 [OnDock:C] Running Loop=1
2024-05-15T17:52:17.718Z,1715795537.718 [OnDock:StandardEnvelopes] Running Loop=1
2024-05-15T17:52:17.718Z,1715795537.718 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-05-15T17:52:17.719Z,1715795537.719 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2024-05-15T17:52:17.727Z,1715795537.727 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-05-15T17:52:17.727Z,1715795537.727 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-05-15T17:52:17.732Z,1715795537.732 [HorizontalControl](DEBUG): kpHeading = 0.400000
2024-05-15T17:52:17.732Z,1715795537.732 [HorizontalControl](DEBUG): kiHeading = 0.001000
2024-05-15T17:52:17.733Z,1715795537.733 [HorizontalControl](DEBUG): kdHeading = 0.050000
2024-05-15T17:52:17.758Z,1715795537.758 [CommandExec](IMPORTANT): got command ! ifconfig
2024-05-15T17:52:18.068Z,1715795538.068 [CommandExec](IMPORTANT): eth0 Link encap:Ethernet HWaddr 50:2D:F4:07:45:4B
inet addr:10.89.10.2 Bcast:10.89.255.255 Mask:255.255.0.0
UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
RX packets:294367 errors:0 dropped:0 overruns:0 frame:0
TX packets:522679 errors:0 dropped:0 overruns:0 carrier:0
collisions:1822 txqueuelen:1000
RX bytes:45507591 (43.3 Mb) TX bytes:717073167 (683.8 Mb)
Interrupt:29
lo Link encap:Local Loopback
inet addr:127.0.0.1 Mask:255.0.0.0
UP LOOPBACK RUNNING MULTICAST MTU:16436 Metric:1
RX packets:703 errors:0 dropped:0 overruns:0 frame:0
TX packets:703 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:58661 (57.2 Kb) TX bytes:58661 (57.2 Kb)
2024-05-15T17:52:18.751Z,1715795538.751 [OnDock:C] Stopped
2024-05-15T17:52:18.751Z,1715795538.751 [OnDock:D] Running Loop=1
2024-05-15T17:52:19.106Z,1715795539.106 [OnDock:D] Stopped
2024-05-15T17:52:19.106Z,1715795539.106 [OnDock:E] Running Loop=1
2024-05-15T17:52:19.480Z,1715795539.480 [OnDock:E] Stopped
2024-05-15T17:52:19.480Z,1715795539.480 [OnDock:K] Running Loop=1
2024-05-15T17:52:19.876Z,1715795539.876 [OnDock:K](IMPORTANT): Restarting logs
2024-05-15T17:52:19.876Z,1715795539.876 [OnDock:K] Stopped
2024-05-15T17:52:19.876Z,1715795539.876 [OnDock:L.Execute] Running Loop=1
2024-05-15T17:52:20.273Z,1715795540.273 [OnDock:L.Execute](INFO): Executing command restart logs
2024-05-15T17:52:20.274Z,1715795540.274 [OnDock:L.Execute] Stopped
2024-05-15T17:52:20.274Z,1715795540.274 [OnDock:M] Running Loop=1
2024-05-15T17:52:20.274Z,1715795540.274 [OnDock:M](DEBUG): Aggregate::initialize OnDock:M