2025-02-18T18:36:32.800Z,1739903792.800 [Supervisor](DEBUG): Initializing supervisor.
2025-02-18T18:36:32.805Z,1739903792.805 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-02-18T18:36:32.806Z,1739903792.806 [SyncHandler](INFO): Protected caller Thread ID is 606
2025-02-18T18:36:32.806Z,1739903792.806 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-02-18T18:36:32.807Z,1739903792.807 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-02-18T18:36:32.808Z,1739903792.808 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 607
2025-02-18T18:36:32.812Z,1739903792.812 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-02-18T18:36:32.829Z,1739903792.829 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-02-18T18:36:32.830Z,1739903792.830 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-02-18T18:36:32.830Z,1739903792.830 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 608
2025-02-18T18:36:32.834Z,1739903792.834 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-02-18T18:36:32.835Z,1739903792.835 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-02-18T18:36:32.835Z,1739903792.835 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 609
2025-02-18T18:36:32.837Z,1739903792.837 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-02-18T18:36:32.838Z,1739903792.838 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-02-18T18:36:32.839Z,1739903792.839 [logger ThreadHandler](INFO): Protected caller Thread ID is 610
2025-02-18T18:36:32.842Z,1739903792.842 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-02-18T18:36:32.843Z,1739903792.843 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-02-18T18:36:32.844Z,1739903792.844 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-02-18T18:36:33.075Z,1739903793.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-02-18T18:36:33.076Z,1739903793.076 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-02-18T18:36:33.293Z,1739903793.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-02-18T18:36:33.293Z,1739903793.293 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-02-18T18:36:33.397Z,1739903793.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-02-18T18:36:33.398Z,1739903793.398 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-02-18T18:36:33.582Z,1739903793.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-02-18T18:36:33.583Z,1739903793.583 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-02-18T18:36:34.059Z,1739903794.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-02-18T18:36:34.060Z,1739903794.060 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-02-18T18:36:34.176Z,1739903794.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-02-18T18:36:34.177Z,1739903794.177 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-02-18T18:36:34.447Z,1739903794.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-02-18T18:36:34.447Z,1739903794.447 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-02-18T18:36:34.660Z,1739903794.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-02-18T18:36:34.660Z,1739903794.660 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-02-18T18:36:35.184Z,1739903795.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-02-18T18:36:35.184Z,1739903795.184 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-02-18T18:36:35.591Z,1739903795.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-02-18T18:36:35.591Z,1739903795.591 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-02-18T18:36:35.680Z,1739903795.680 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-02-18T18:36:36.045Z,1739903796.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-02-18T18:36:36.046Z,1739903796.046 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-02-18T18:36:36.190Z,1739903796.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-02-18T18:36:36.190Z,1739903796.190 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-02-18T18:36:37.015Z,1739903797.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-02-18T18:36:37.016Z,1739903797.016 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-02-18T18:36:37.364Z,1739903797.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-02-18T18:36:37.364Z,1739903797.364 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-02-18T18:36:37.457Z,1739903797.457 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2025-02-18T18:36:37.458Z,1739903797.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2025-02-18T18:36:37.551Z,1739903797.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2025-02-18T18:36:37.664Z,1739903797.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2025-02-18T18:36:37.777Z,1739903797.777 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2025-02-18T18:36:37.874Z,1739903797.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2025-02-18T18:36:37.969Z,1739903797.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2025-02-18T18:36:38.081Z,1739903798.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2025-02-18T18:36:38.299Z,1739903798.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2025-02-18T18:36:38.469Z,1739903798.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2025-02-18T18:36:38.573Z,1739903798.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2025-02-18T18:36:38.841Z,1739903798.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-02-18T18:36:38.843Z,1739903798.843 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/root/
2025-02-18T18:36:38.843Z,1739903798.843 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-02-18T18:36:38.850Z,1739903798.850 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-02-18T18:36:39.204Z,1739903799.204 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-02-18T18:36:39.205Z,1739903799.205 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-02-18T18:36:39.695Z,1739903799.695 [AHRS_M2] Loaded
2025-02-18T18:36:39.695Z,1739903799.695 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-02-18T18:36:39.733Z,1739903799.733 [BackseatComponent] Loaded
2025-02-18T18:36:39.733Z,1739903799.733 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-02-18T18:36:39.734Z,1739903799.734 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408AB4E0
2025-02-18T18:36:39.734Z,1739903799.734 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 689
2025-02-18T18:36:39.737Z,1739903799.737 [LcmUniversalReporter] Loaded
2025-02-18T18:36:39.737Z,1739903799.737 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-02-18T18:36:39.743Z,1739903799.743 [BioacousticsDataBridge] Loaded
2025-02-18T18:36:39.744Z,1739903799.744 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-02-18T18:36:40.535Z,1739903800.535 [BPC1] Loaded
2025-02-18T18:36:40.535Z,1739903800.535 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-02-18T18:36:40.612Z,1739903800.612 [DataOverHttps] Loaded
2025-02-18T18:36:40.613Z,1739903800.613 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-02-18T18:36:40.614Z,1739903800.614 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408DB4E0
2025-02-18T18:36:40.614Z,1739903800.614 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 690
2025-02-18T18:36:40.636Z,1739903800.636 [Depth_Keller] Loaded
2025-02-18T18:36:40.636Z,1739903800.636 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-02-18T18:36:40.641Z,1739903800.641 [DropWeight] Loaded
2025-02-18T18:36:40.641Z,1739903800.641 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-02-18T18:36:40.703Z,1739903800.703 [NAL9602] Loaded
2025-02-18T18:36:40.704Z,1739903800.704 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-02-18T18:36:40.733Z,1739903800.733 [Onboard] Loaded
2025-02-18T18:36:40.733Z,1739903800.733 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-02-18T18:36:40.734Z,1739903800.734 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2025-02-18T18:36:40.734Z,1739903800.734 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 691
2025-02-18T18:36:40.748Z,1739903800.748 [Power24vConverter] Loaded
2025-02-18T18:36:40.748Z,1739903800.748 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-02-18T18:36:40.764Z,1739903800.764 [Radio_Surface] Loaded
2025-02-18T18:36:40.765Z,1739903800.765 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-02-18T18:36:40.766Z,1739903800.766 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2025-02-18T18:36:40.766Z,1739903800.766 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 692
2025-02-18T18:36:40.781Z,1739903800.781 [Sonardyne_Nano] Loaded
2025-02-18T18:36:40.781Z,1739903800.781 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-02-18T18:36:40.782Z,1739903800.782 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-02-18T18:36:40.782Z,1739903800.782 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-02-18T18:36:40.994Z,1739903800.994 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-02-18T18:36:40.995Z,1739903800.995 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-02-18T18:36:41.047Z,1739903801.047 [VerticalControl](DEBUG): Construct VerticalControl.
2025-02-18T18:36:41.105Z,1739903801.105 [VerticalControl] Loaded
2025-02-18T18:36:41.106Z,1739903801.106 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-02-18T18:36:41.108Z,1739903801.108 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-02-18T18:36:41.155Z,1739903801.155 [HorizontalControl] Loaded
2025-02-18T18:36:41.156Z,1739903801.156 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-02-18T18:36:41.158Z,1739903801.158 [SpeedControl](DEBUG): Construct SpeedControl.
2025-02-18T18:36:41.161Z,1739903801.161 [SpeedControl] Loaded
2025-02-18T18:36:41.161Z,1739903801.161 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-02-18T18:36:41.164Z,1739903801.164 [LoopControl](DEBUG): Construct LoopControl.
2025-02-18T18:36:41.164Z,1739903801.164 [LoopControl] Loaded
2025-02-18T18:36:41.165Z,1739903801.165 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-02-18T18:36:41.165Z,1739903801.165 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-02-18T18:36:41.166Z,1739903801.166 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-02-18T18:36:41.175Z,1739903801.175 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-02-18T18:36:41.176Z,1739903801.176 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-02-18T18:36:41.204Z,1739903801.204 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-02-18T18:36:41.205Z,1739903801.205 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-02-18T18:36:41.315Z,1739903801.315 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-02-18T18:36:41.315Z,1739903801.315 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-02-18T18:36:41.329Z,1739903801.329 [NavChart] Loaded
2025-02-18T18:36:41.329Z,1739903801.329 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-02-18T18:36:41.334Z,1739903801.334 [UniversalFixResidualReporter] Loaded
2025-02-18T18:36:41.335Z,1739903801.335 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-02-18T18:36:41.335Z,1739903801.335 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-02-18T18:36:41.335Z,1739903801.335 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-02-18T18:36:41.476Z,1739903801.476 [SBIT](DEBUG): Construct Startup Built In Test.
2025-02-18T18:36:41.485Z,1739903801.485 [SBIT] Loaded
2025-02-18T18:36:41.485Z,1739903801.485 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-02-18T18:36:41.488Z,1739903801.488 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-02-18T18:36:41.500Z,1739903801.500 [IBIT] Loaded
2025-02-18T18:36:41.500Z,1739903801.500 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-02-18T18:36:41.506Z,1739903801.506 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-02-18T18:36:41.607Z,1739903801.607 [CBIT] Loaded
2025-02-18T18:36:41.608Z,1739903801.608 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-02-18T18:36:41.608Z,1739903801.608 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-02-18T18:36:41.608Z,1739903801.608 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-02-18T18:36:41.837Z,1739903801.837 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-02-18T18:36:41.838Z,1739903801.838 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-02-18T18:36:41.971Z,1739903801.971 [BuoyancyServo] Loaded
2025-02-18T18:36:41.972Z,1739903801.972 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-02-18T18:36:41.992Z,1739903801.992 [ElevatorServo] Loaded
2025-02-18T18:36:41.992Z,1739903801.992 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-02-18T18:36:42.013Z,1739903802.013 [MassServo] Loaded
2025-02-18T18:36:42.013Z,1739903802.013 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-02-18T18:36:42.034Z,1739903802.034 [RudderServo] Loaded
2025-02-18T18:36:42.034Z,1739903802.034 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-02-18T18:36:42.050Z,1739903802.050 [ThrusterHE] Loaded
2025-02-18T18:36:42.051Z,1739903802.051 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-02-18T18:36:42.051Z,1739903802.051 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-02-18T18:36:42.052Z,1739903802.052 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-02-18T18:36:42.224Z,1739903802.224 [CTD_Seabird] Loaded
2025-02-18T18:36:42.224Z,1739903802.224 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-02-18T18:36:42.225Z,1739903802.225 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B714E0
2025-02-18T18:36:42.226Z,1739903802.226 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 693
2025-02-18T18:36:42.248Z,1739903802.248 [PAR_Licor] Loaded
2025-02-18T18:36:42.248Z,1739903802.248 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-02-18T18:36:42.292Z,1739903802.292 [WetLabsBB2FL] Loaded
2025-02-18T18:36:42.292Z,1739903802.292 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-02-18T18:36:42.293Z,1739903802.293 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BA14E0
2025-02-18T18:36:42.294Z,1739903802.294 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 694
2025-02-18T18:36:42.295Z,1739903802.295 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-02-18T18:36:42.295Z,1739903802.295 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-02-18T18:36:42.344Z,1739903802.344 [DepthRateCalculator] Loaded
2025-02-18T18:36:42.344Z,1739903802.344 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-02-18T18:36:42.349Z,1739903802.349 [PitchRateCalculator] Loaded
2025-02-18T18:36:42.349Z,1739903802.349 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-02-18T18:36:42.358Z,1739903802.358 [SpeedCalculator] Loaded
2025-02-18T18:36:42.359Z,1739903802.359 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-02-18T18:36:42.363Z,1739903802.363 [YawRateCalculator] Loaded
2025-02-18T18:36:42.363Z,1739903802.363 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-02-18T18:36:42.382Z,1739903802.382 [ElevatorOffsetCalculator] Loaded
2025-02-18T18:36:42.382Z,1739903802.382 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-02-18T18:36:42.383Z,1739903802.383 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-02-18T18:36:42.383Z,1739903802.383 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-02-18T18:36:42.440Z,1739903802.440 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-02-18T18:36:42.447Z,1739903802.447 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-02-18T18:36:42.449Z,1739903802.449 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-02-18T18:36:42.461Z,1739903802.461 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-02-18T18:36:42.462Z,1739903802.462 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C2E4E0
2025-02-18T18:36:42.462Z,1739903802.462 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 695
2025-02-18T18:36:42.466Z,1739903802.466 [Supervisor](INFO): Main Thread ID is 448
2025-02-18T18:36:42.466Z,1739903802.466 [Supervisor](DEBUG): Running supervisor.
2025-02-18T18:36:42.467Z,1739903802.467 [CommandExec ThreadHandler](INFO): Handler Thread ID is 696
2025-02-18T18:36:42.468Z,1739903802.468 [CommandExec](INFO): Initializing the command executive.
2025-02-18T18:36:42.469Z,1739903802.469 [CommandLine ThreadHandler](INFO): Handler Thread ID is 697
2025-02-18T18:36:42.471Z,1739903802.471 [controlThread ThreadHandler](INFO): Handler Thread ID is 698
2025-02-18T18:36:42.472Z,1739903802.472 [controlThread](DEBUG): Initializing ControlThread
2025-02-18T18:36:42.478Z,1739903802.478 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-02-18T18:36:42.480Z,1739903802.480 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-02-18T18:36:42.481Z,1739903802.481 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-02-18T18:36:42.481Z,1739903802.481 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-02-18T18:36:42.482Z,1739903802.482 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-02-18T18:36:42.483Z,1739903802.483 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-02-18T18:36:42.483Z,1739903802.483 [SBIT](INFO): Initialize SBIT Component.
2025-02-18T18:36:42.484Z,1739903802.484 [SBIT](IMPORTANT): git: 2025-02-18
2025-02-18T18:36:42.484Z,1739903802.484 [SBIT](INFO): git hash: 618ec5a8c5f60c900e5bed23ee92085c43718f8d
2025-02-18T18:36:42.484Z,1739903802.484 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-02-18T18:36:42.485Z,1739903802.485 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Dec 4 01:17:04 UTC 2024
2025-02-18T18:36:42.487Z,1739903802.487 [SBIT](INFO): Beginning SBIT in 60.000000 seconds.
2025-02-18T18:36:42.487Z,1739903802.487 [IBIT](INFO): Initialize IBIT Component.
2025-02-18T18:36:42.488Z,1739903802.488 [CBIT](DEBUG): Initialize CBIT Component.
2025-02-18T18:36:42.489Z,1739903802.489 [logger ThreadHandler](INFO): Handler Thread ID is 699
2025-02-18T18:36:42.500Z,1739903802.500 [CBIT](DEBUG): Initialized mux pins.
2025-02-18T18:36:42.500Z,1739903802.500 [CBIT](DEBUG): Initializing the watchdog timer.
2025-02-18T18:36:42.509Z,1739903802.509 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 700
2025-02-18T18:36:42.521Z,1739903802.521 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 701
2025-02-18T18:36:42.522Z,1739903802.522 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-02-18T18:36:42.524Z,1739903802.524 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-02-18T18:36:42.525Z,1739903802.525 [CBIT](DEBUG): Initializing heartbeat.
2025-02-18T18:36:42.541Z,1739903802.541 [Onboard ThreadHandler](INFO): Handler Thread ID is 702
2025-02-18T18:36:42.558Z,1739903802.558 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 703
2025-02-18T18:36:42.581Z,1739903802.581 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 704
2025-02-18T18:36:42.582Z,1739903802.582 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-02-18T18:36:42.586Z,1739903802.586 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 706
2025-02-18T18:36:42.589Z,1739903802.589 [WetLabsBB2FL](INFO): Powering up
2025-02-18T18:36:42.590Z,1739903802.590 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 708
2025-02-18T18:36:42.592Z,1739903802.592 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-02-18T18:36:42.593Z,1739903802.593 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-02-18T18:36:42.593Z,1739903802.593 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-02-18T18:36:42.593Z,1739903802.593 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-02-18T18:36:42.593Z,1739903802.593 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-02-18T18:36:42.593Z,1739903802.593 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-02-18T18:36:42.593Z,1739903802.593 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-02-18T18:36:42.594Z,1739903802.594 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-02-18T18:36:42.596Z,1739903802.596 [CBIT](DEBUG): Deactivating GF circuits.
2025-02-18T18:36:42.596Z,1739903802.596 [CBIT](DEBUG): Deactivating emergency mode.
2025-02-18T18:36:42.636Z,1739903802.636 [CBIT](DEBUG): Backplane powered.
2025-02-18T18:36:42.641Z,1739903802.641 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-02-18T18:36:42.641Z,1739903802.641 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-02-18T18:36:42.641Z,1739903802.641 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-02-18T18:36:42.642Z,1739903802.642 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-02-18T18:36:42.642Z,1739903802.642 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-02-18T18:36:42.643Z,1739903802.643 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-02-18T18:36:42.644Z,1739903802.644 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-02-18T18:36:42.644Z,1739903802.644 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-02-18T18:36:42.646Z,1739903802.646 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-02-18T18:36:42.725Z,1739903802.725 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-02-18T18:36:42.730Z,1739903802.730 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-18T18:36:42.778Z,1739903802.778 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2025-02-18T18:36:42.779Z,1739903802.779 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-02-18T18:36:42.779Z,1739903802.779 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-02-18T18:36:42.780Z,1739903802.780 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-02-18T18:36:42.964Z,1739903802.964 [Radio_Surface](INFO): Powering up
2025-02-18T18:36:43.068Z,1739903803.068 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-02-18T18:36:43.086Z,1739903803.086 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-02-18T18:36:43.087Z,1739903803.087 [Default:A.Wait](DEBUG): Construct Wait.
2025-02-18T18:36:43.097Z,1739903803.097 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-18T18:36:43.138Z,1739903803.138 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-02-18T18:36:43.141Z,1739903803.141 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-02-18T18:36:43.163Z,1739903803.163 [Default:E.Execute](DEBUG): Construct Execute.
2025-02-18T18:36:43.166Z,1739903803.166 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-02-18T18:36:43.184Z,1739903803.184 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2025-02-18T18:36:43.191Z,1739903803.191 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-02-18T18:36:43.263Z,1739903803.263 [Power24vConverter](INFO): Powering up.
2025-02-18T18:36:43.263Z,1739903803.263 [Sonardyne_Nano](INFO): Initializing.
2025-02-18T18:36:43.318Z,1739903803.318 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-02-18T18:36:43.325Z,1739903803.325 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-02-18T18:36:43.326Z,1739903803.326 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-02-18T18:36:43.333Z,1739903803.333 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-02-18T18:36:43.333Z,1739903803.333 [MassServo](DEBUG): Initializing EZServoServo.
2025-02-18T18:36:43.341Z,1739903803.341 [MassServo](DEBUG): Initializing MassServo.
2025-02-18T18:36:43.341Z,1739903803.341 [RudderServo](DEBUG): Initializing EZServoServo.
2025-02-18T18:36:43.349Z,1739903803.349 [RudderServo](DEBUG): Initializing RudderServo.
2025-02-18T18:36:43.349Z,1739903803.349 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-02-18T18:36:43.357Z,1739903803.357 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-02-18T18:36:44.696Z,1739903804.696 [WetLabsBB2FL](INFO): Powering down
2025-02-18T18:36:45.215Z,1739903805.215 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-02-18T18:36:46.513Z,1739903806.513 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409
2025-02-18T18:36:46.856Z,1739903806.856 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-02-18T18:36:49.620Z,1739903809.620 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-02-18T18:37:11.098Z,1739903831.098 [NAL9602](INFO): Powering up NAL9602
2025-02-18T18:37:22.010Z,1739903842.010 [NAL9602](INFO): NAL9602 initialized
2025-02-18T18:37:43.031Z,1739903863.031 [SBIT](IMPORTANT): Beginning Startup BIT
2025-02-18T18:37:43.035Z,1739903863.035 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-18T18:37:53.996Z,1739903873.996 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001036
CHAN A1 (24V): -0.001075
CHAN A2 (12V): 0.000406
CHAN A3 (5V): -0.002025
CHAN B0 (3.3V): -0.000699
CHAN B1 (3.15aV): -0.001072
CHAN B2 (3.15bV): -0.001461
CHAN B3 (GND): -0.002625
OPEN: 0.004962
Full Scale: +/- 1 mA
2025-02-18T18:37:55.998Z,1739903875.998 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T18:38:36.454Z,1739903916.454 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T18:38:36.837Z,1739903916.837 [SBIT](IMPORTANT): SBIT PASSED
2025-02-18T18:38:36.837Z,1739903916.837 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-02-18T18:38:36.838Z,1739903916.838 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2025-02-18T18:38:36.838Z,1739903916.838 [SBIT](IMPORTANT): DATMMP.loadAtStartup=0 bool;
2025-02-18T18:38:36.838Z,1739903916.838 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2025-02-18T18:38:36.839Z,1739903916.839 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.925 meter_per_second;
2025-02-18T18:38:36.839Z,1739903916.839 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=345.701638 cubic_centimeter;
2025-02-18T18:38:36.839Z,1739903916.839 [SBIT](IMPORTANT): VerticalControl.massDefault=10.247203 millimeter;
2025-02-18T18:38:37.230Z,1739903917.230 [MissionManager](IMPORTANT): Started mission Startup
2025-02-18T18:38:37.230Z,1739903917.230 [Startup] Running Loop=1
2025-02-18T18:38:37.230Z,1739903917.230 [Startup](DEBUG): Aggregate::initialize Startup
2025-02-18T18:38:37.230Z,1739903917.230 [Startup:A.GoToSurface] Running Loop=1
2025-02-18T18:38:37.231Z,1739903917.231 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-18T18:38:37.231Z,1739903917.231 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-18T18:38:37.232Z,1739903917.232 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-18T18:38:37.232Z,1739903917.232 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-18T18:38:37.232Z,1739903917.232 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-18T18:38:37.233Z,1739903917.233 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-18T18:38:37.233Z,1739903917.233 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-18T18:38:37.235Z,1739903917.235 [Startup:StartupSatComms] Running Loop=1
2025-02-18T18:38:37.235Z,1739903917.235 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-02-18T18:38:37.235Z,1739903917.235 [Startup:StartupSatComms:A] Running Loop=1
2025-02-18T18:38:37.679Z,1739903917.679 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-02-18T18:38:54.463Z,1739903934.463 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005039
2025-02-18T18:39:37.419Z,1739903977.419 [Startup:StartupSatComms:A](INFO): Timed out from 2025-02-18T18:38:37.2Z
2025-02-18T18:39:37.419Z,1739903977.419 [Startup:StartupSatComms:A] Stopped
2025-02-18T18:39:37.419Z,1739903977.419 [Startup:StartupSatComms:B] Running Loop=1
2025-02-18T18:39:37.818Z,1739903977.818 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-18T18:39:42.754Z,1739903982.754 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-02-18T18:39:42.754Z,1739903982.754 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T18:39:42.900Z,1739903982.900 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T18:39:43.298Z,1739903983.298 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T18:39:43.298Z,1739903983.298 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-02-18T18:39:43.570Z,1739903983.570 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T18:39:44.498Z,1739903984.498 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250218T183047/Courier0004.lzma
2025-02-18T18:39:45.498Z,1739903985.498 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183047/Courier0004.lzma.bak
2025-02-18T18:39:45.498Z,1739903985.498 [DataOverHttps](INFO): SBD MOMSN=23966059
2025-02-18T18:39:53.268Z,1739903993.268 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T18:39:56.092Z,1739903996.092 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T18:40:01.420Z,1739904001.420 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250218T183632/Courier0000.lzma
2025-02-18T18:40:02.422Z,1739904002.422 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0000.lzma.bak
2025-02-18T18:40:02.422Z,1739904002.422 [DataOverHttps](INFO): SBD MOMSN=23966061
2025-02-18T18:40:18.232Z,1739904018.232 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250218T183047/Express0005.lzma
2025-02-18T18:40:19.234Z,1739904019.234 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183047/Express0005.lzma.bak
2025-02-18T18:40:19.234Z,1739904019.234 [DataOverHttps](INFO): SBD MOMSN=23966064
2025-02-18T18:40:37.513Z,1739904037.513 [Startup:StartupSatComms:B](INFO): Timed out from 2025-02-18T18:39:37.4Z
2025-02-18T18:40:37.513Z,1739904037.513 [Startup:StartupSatComms:B] Stopped
2025-02-18T18:40:37.514Z,1739904037.514 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-02-18T18:40:37.514Z,1739904037.514 [Startup:StartupSatComms] Stopped
2025-02-18T18:40:37.514Z,1739904037.514 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-02-18T18:40:37.515Z,1739904037.515 [Startup](INFO): Completed Startup
2025-02-18T18:40:37.515Z,1739904037.515 [MissionManager](INFO): Startup is completed.
2025-02-18T18:40:37.515Z,1739904037.515 [MissionManager](INFO): Uninitializing Mission Startup
2025-02-18T18:40:37.515Z,1739904037.515 [Startup] Stopped
2025-02-18T18:40:37.515Z,1739904037.515 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-02-18T18:40:37.515Z,1739904037.515 [Startup:A.GoToSurface] Stopped
2025-02-18T18:40:37.515Z,1739904037.515 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-18T18:40:37.834Z,1739904037.834 [MissionManager](IMPORTANT): Started mission Default
2025-02-18T18:40:37.835Z,1739904037.835 [Default] Running Loop=1
2025-02-18T18:40:37.835Z,1739904037.835 [Default](DEBUG): Aggregate::initialize Default
2025-02-18T18:40:37.835Z,1739904037.835 [Default:B.GoToSurface] Running Loop=1
2025-02-18T18:40:37.835Z,1739904037.835 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-18T18:40:37.835Z,1739904037.835 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-18T18:40:37.835Z,1739904037.835 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-18T18:40:37.836Z,1739904037.836 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-18T18:40:37.836Z,1739904037.836 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-18T18:40:37.849Z,1739904037.849 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-18T18:40:37.849Z,1739904037.849 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-18T18:40:37.849Z,1739904037.849 [Default:A.Wait] Running Loop=1
2025-02-18T18:40:37.849Z,1739904037.849 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-18T18:40:41.058Z,1739904041.058 [DataOverHttps](INFO): Sending 807 bytes from file Logs/20250218T183632/Express0001.lzma
2025-02-18T18:40:42.046Z,1739904042.046 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0001.lzma.bak
2025-02-18T18:40:42.046Z,1739904042.046 [DataOverHttps](INFO): SBD MOMSN=23966069
2025-02-18T18:40:50.693Z,1739904050.693 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T18:40:51.083Z,1739904051.083 [Default:A.Wait](INFO): Done Waiting.
2025-02-18T18:40:51.083Z,1739904051.083 [Default:A.Wait] Stopped
2025-02-18T18:40:51.083Z,1739904051.083 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T18:40:51.495Z,1739904051.495 [Default:CheckIn] Running Loop=1
2025-02-18T18:40:51.495Z,1739904051.495 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T18:40:51.495Z,1739904051.495 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T18:40:51.859Z,1739904051.859 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-18T18:41:03.214Z,1739904063.214 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T18:41:34.275Z,1739904094.275 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T18:42:25.250Z,1739904145.250 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-02-18T18:42:43.766Z,1739904163.766 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-02-18T18:42:43.766Z,1739904163.766 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T18:42:43.924Z,1739904163.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T18:42:44.328Z,1739904164.328 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T18:42:44.328Z,1739904164.328 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-02-18T18:42:47.915Z,1739904167.915 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T18:42:59.153Z,1739904179.153 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T18:43:05.970Z,1739904185.970 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:43:19.302Z,1739904199.302 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:43:32.637Z,1739904212.637 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:43:35.114Z,1739904215.114 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T18:43:46.370Z,1739904226.370 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:44:00.105Z,1739904240.105 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:44:01.722Z,1739904241.722 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T18:44:13.842Z,1739904253.842 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:44:17.118Z,1739904257.118 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T18:44:27.578Z,1739904267.578 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:44:34.472Z,1739904274.472 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T18:44:41.313Z,1739904281.313 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:44:50.609Z,1739904290.609 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T18:44:54.646Z,1739904294.646 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:45:07.170Z,1739904307.170 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T18:45:07.983Z,1739904307.983 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:45:22.521Z,1739904322.521 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T18:45:23.733Z,1739904323.733 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T18:45:29.802Z,1739904329.802 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-18T18:45:29.802Z,1739904329.802 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-18T18:45:29.805Z,1739904329.805 [BPC1](INFO): Received data from all battery sticks.
2025-02-18T18:45:44.780Z,1739904344.780 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-02-18T18:45:44.780Z,1739904344.780 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T18:45:44.925Z,1739904344.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T18:45:45.324Z,1739904345.324 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T18:45:45.324Z,1739904345.324 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-02-18T18:45:51.646Z,1739904351.646 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-18T18:40:51.5Z
2025-02-18T18:45:51.646Z,1739904351.646 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T18:45:51.646Z,1739904351.646 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T18:45:52.046Z,1739904352.046 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-18T18:45:53.686Z,1739904353.686 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250218T183632/Courier0004.lzma
2025-02-18T18:45:54.626Z,1739904354.626 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0004.lzma.bak
2025-02-18T18:45:54.626Z,1739904354.626 [DataOverHttps](INFO): SBD MOMSN=23966088
2025-02-18T18:46:10.464Z,1739904370.464 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20250218T183632/Express0005.lzma
2025-02-18T18:46:11.466Z,1739904371.466 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0005.lzma.bak
2025-02-18T18:46:11.466Z,1739904371.466 [DataOverHttps](INFO): SBD MOMSN=23966090
2025-02-18T18:46:12.785Z,1739904372.785 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T18:46:12.785Z,1739904372.785 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T18:46:12.785Z,1739904372.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T18:46:44.273Z,1739904404.273 [Power24vConverter](INFO): Powering down.
2025-02-18T18:47:24.254Z,1739904444.254 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-18T18:47:24.254Z,1739904444.254 [NAL9602] Data Fault, FailCount= 1
2025-02-18T18:47:24.254Z,1739904444.254 [NAL9602](ERROR): Data Fault
2025-02-18T18:47:24.404Z,1739904444.404 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-18T18:47:24.662Z,1739904444.662 [NAL9602](INFO): Powering down
2025-02-18T18:47:25.612Z,1739904445.612 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-18T18:47:25.612Z,1739904445.612 [NAL9602] No Fault, FailCount= 1
2025-02-18T18:47:54.954Z,1739904474.954 [NAL9602](INFO): Powering up NAL9602
2025-02-18T18:48:05.866Z,1739904485.866 [NAL9602](INFO): NAL9602 initialized
2025-02-18T18:48:45.869Z,1739904525.869 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-02-18T18:48:45.869Z,1739904525.869 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T18:48:46.040Z,1739904526.040 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T18:48:46.440Z,1739904526.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T18:48:46.440Z,1739904526.440 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-02-18T18:51:13.355Z,1739904673.355 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T18:51:13.355Z,1739904673.355 [Default:CheckIn:C.Wait] Stopped
2025-02-18T18:51:13.355Z,1739904673.355 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T18:51:13.355Z,1739904673.355 [Default:CheckIn:D] Running Loop=1
2025-02-18T18:51:13.734Z,1739904673.734 [Default:CheckIn:D] Stopped
2025-02-18T18:51:13.734Z,1739904673.734 [Default:CheckIn:E] Running Loop=1
2025-02-18T18:51:14.132Z,1739904674.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.598318 min
2025-02-18T18:51:14.132Z,1739904674.132 [Default:CheckIn:E] Stopped
2025-02-18T18:51:14.132Z,1739904674.132 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T18:51:14.132Z,1739904674.132 [Default:CheckIn] Stopped
2025-02-18T18:51:14.132Z,1739904674.132 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T18:51:14.132Z,1739904674.132 [Default:CheckIn](INFO): Running loop #2
2025-02-18T18:51:14.132Z,1739904674.132 [Default:CheckIn] Running Loop=2
2025-02-18T18:51:14.133Z,1739904674.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T18:51:14.133Z,1739904674.133 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T18:51:46.861Z,1739904706.861 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-02-18T18:51:46.861Z,1739904706.861 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T18:51:47.005Z,1739904707.005 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T18:51:47.444Z,1739904707.444 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T18:51:47.444Z,1739904707.444 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-02-18T18:54:47.860Z,1739904887.860 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-02-18T18:54:47.860Z,1739904887.860 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T18:54:47.997Z,1739904887.997 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T18:54:48.425Z,1739904888.425 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T18:54:48.425Z,1739904888.425 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-02-18T18:56:14.310Z,1739904974.310 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-18T18:51:14.1Z
2025-02-18T18:56:14.310Z,1739904974.310 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T18:56:14.310Z,1739904974.310 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T18:56:21.711Z,1739904981.711 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250218T183632/Courier0007.lzma
2025-02-18T18:56:22.710Z,1739904982.710 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0007.lzma.bak
2025-02-18T18:56:22.710Z,1739904982.710 [DataOverHttps](INFO): SBD MOMSN=23966094
2025-02-18T18:56:38.488Z,1739904998.488 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20250218T183632/Express0008.lzma
2025-02-18T18:56:39.493Z,1739904999.493 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0008.lzma.bak
2025-02-18T18:56:39.493Z,1739904999.493 [DataOverHttps](INFO): SBD MOMSN=23966096
2025-02-18T18:56:41.006Z,1739905001.006 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T18:56:41.006Z,1739905001.006 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T18:56:41.006Z,1739905001.006 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T18:57:48.853Z,1739905068.853 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-02-18T18:57:48.853Z,1739905068.853 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T18:57:48.992Z,1739905068.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T18:57:49.408Z,1739905069.408 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T18:57:49.408Z,1739905069.408 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-02-18T18:58:08.234Z,1739905088.234 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-18T18:58:08.234Z,1739905088.234 [NAL9602] Data Fault, FailCount= 2
2025-02-18T18:58:08.234Z,1739905088.234 [NAL9602](ERROR): Data Fault
2025-02-18T18:58:08.404Z,1739905088.404 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-18T18:58:08.638Z,1739905088.638 [NAL9602](INFO): Powering down
2025-02-18T18:58:09.596Z,1739905089.596 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-18T18:58:09.596Z,1739905089.596 [NAL9602] No Fault, FailCount= 2
2025-02-18T18:58:38.934Z,1739905118.934 [NAL9602](INFO): Powering up NAL9602
2025-02-18T18:58:49.850Z,1739905129.850 [NAL9602](INFO): NAL9602 initialized
2025-02-18T19:00:49.850Z,1739905249.850 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-02-18T19:00:49.850Z,1739905249.850 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:00:49.985Z,1739905249.985 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:00:50.401Z,1739905250.401 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:00:50.401Z,1739905250.401 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-02-18T19:01:41.554Z,1739905301.554 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T19:01:41.554Z,1739905301.554 [Default:CheckIn:C.Wait] Stopped
2025-02-18T19:01:41.554Z,1739905301.554 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T19:01:41.554Z,1739905301.554 [Default:CheckIn:D] Running Loop=1
2025-02-18T19:01:41.958Z,1739905301.958 [Default:CheckIn:D] Stopped
2025-02-18T19:01:41.958Z,1739905301.958 [Default:CheckIn:E] Running Loop=1
2025-02-18T19:01:42.362Z,1739905302.362 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.068715 min
2025-02-18T19:01:42.362Z,1739905302.362 [Default:CheckIn:E] Stopped
2025-02-18T19:01:42.363Z,1739905302.363 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T19:01:42.363Z,1739905302.363 [Default:CheckIn] Stopped
2025-02-18T19:01:42.363Z,1739905302.363 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T19:01:42.363Z,1739905302.363 [Default:CheckIn](INFO): Running loop #3
2025-02-18T19:01:42.363Z,1739905302.363 [Default:CheckIn] Running Loop=3
2025-02-18T19:01:42.363Z,1739905302.363 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T19:01:42.363Z,1739905302.363 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T19:03:50.842Z,1739905430.842 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-02-18T19:03:50.842Z,1739905430.842 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:03:50.996Z,1739905430.996 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:03:51.398Z,1739905431.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:03:51.399Z,1739905431.399 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-02-18T19:06:42.535Z,1739905602.535 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-18T19:01:42.4Z
2025-02-18T19:06:42.535Z,1739905602.535 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T19:06:42.535Z,1739905602.535 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T19:06:50.172Z,1739905610.172 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250218T183632/Courier0010.lzma
2025-02-18T19:06:51.175Z,1739905611.175 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0010.lzma.bak
2025-02-18T19:06:51.175Z,1739905611.175 [DataOverHttps](INFO): SBD MOMSN=23966100
2025-02-18T19:06:51.825Z,1739905611.825 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-02-18T19:06:51.825Z,1739905611.825 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:06:51.969Z,1739905611.969 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:06:52.416Z,1739905612.416 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:06:52.416Z,1739905612.416 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-02-18T19:07:06.913Z,1739905626.913 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250218T183632/Express0011.lzma
2025-02-18T19:07:07.910Z,1739905627.910 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0011.lzma.bak
2025-02-18T19:07:07.910Z,1739905627.910 [DataOverHttps](INFO): SBD MOMSN=23966102
2025-02-18T19:07:09.223Z,1739905629.223 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T19:07:09.223Z,1739905629.223 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T19:07:09.223Z,1739905629.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T19:08:52.215Z,1739905732.215 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-18T19:08:52.215Z,1739905732.215 [NAL9602] Data Fault, FailCount= 3
2025-02-18T19:08:52.215Z,1739905732.215 [NAL9602](ERROR): Data Fault
2025-02-18T19:08:52.376Z,1739905732.376 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-18T19:08:52.614Z,1739905732.614 [NAL9602](INFO): Powering down
2025-02-18T19:08:53.569Z,1739905733.569 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-18T19:08:53.569Z,1739905733.569 [NAL9602] No Fault, FailCount= 3
2025-02-18T19:09:22.915Z,1739905762.915 [NAL9602](INFO): Powering up NAL9602
2025-02-18T19:09:33.827Z,1739905773.827 [NAL9602](INFO): NAL9602 initialized
2025-02-18T19:09:52.831Z,1739905792.831 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-02-18T19:09:52.831Z,1739905792.831 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:09:52.984Z,1739905792.984 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:09:53.388Z,1739905793.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:09:53.388Z,1739905793.388 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-02-18T19:12:09.805Z,1739905929.805 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T19:12:09.805Z,1739905929.805 [Default:CheckIn:C.Wait] Stopped
2025-02-18T19:12:09.805Z,1739905929.805 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T19:12:09.805Z,1739905929.805 [Default:CheckIn:D] Running Loop=1
2025-02-18T19:12:10.222Z,1739905930.222 [Default:CheckIn:D] Stopped
2025-02-18T19:12:10.222Z,1739905930.222 [Default:CheckIn:E] Running Loop=1
2025-02-18T19:12:10.637Z,1739905930.637 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.539795 min
2025-02-18T19:12:10.638Z,1739905930.638 [Default:CheckIn:E] Stopped
2025-02-18T19:12:10.638Z,1739905930.638 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T19:12:10.638Z,1739905930.638 [Default:CheckIn] Stopped
2025-02-18T19:12:10.638Z,1739905930.638 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T19:12:10.638Z,1739905930.638 [Default:CheckIn](INFO): Running loop #4
2025-02-18T19:12:10.638Z,1739905930.638 [Default:CheckIn] Running Loop=4
2025-02-18T19:12:10.638Z,1739905930.638 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T19:12:10.638Z,1739905930.638 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T19:12:53.838Z,1739905973.838 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-02-18T19:12:53.838Z,1739905973.838 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:12:53.988Z,1739905973.988 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:12:54.408Z,1739905974.408 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:12:54.408Z,1739905974.408 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-02-18T19:15:54.825Z,1739906154.825 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-02-18T19:15:54.825Z,1739906154.825 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:15:54.990Z,1739906154.990 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:15:55.388Z,1739906155.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:15:55.388Z,1739906155.388 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-02-18T19:17:10.798Z,1739906230.798 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-18T19:12:10.6Z
2025-02-18T19:17:10.798Z,1739906230.798 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T19:17:10.798Z,1739906230.798 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T19:17:18.364Z,1739906238.364 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250218T183632/Courier0013.lzma
2025-02-18T19:17:19.366Z,1739906239.366 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0013.lzma.bak
2025-02-18T19:17:19.366Z,1739906239.366 [DataOverHttps](INFO): SBD MOMSN=23966108
2025-02-18T19:17:35.036Z,1739906255.036 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250218T183632/Express0014.lzma
2025-02-18T19:17:36.038Z,1739906256.038 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0014.lzma.bak
2025-02-18T19:17:36.038Z,1739906256.038 [DataOverHttps](INFO): SBD MOMSN=23966110
2025-02-18T19:17:37.446Z,1739906257.446 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T19:17:37.446Z,1739906257.446 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T19:17:37.446Z,1739906257.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T19:18:55.839Z,1739906335.839 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-02-18T19:18:55.839Z,1739906335.839 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:18:55.972Z,1739906335.972 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:18:56.396Z,1739906336.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:18:56.396Z,1739906336.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-02-18T19:19:36.210Z,1739906376.210 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-18T19:19:36.210Z,1739906376.210 [NAL9602] Data Fault, FailCount= 4
2025-02-18T19:19:36.210Z,1739906376.210 [NAL9602](ERROR): Data Fault
2025-02-18T19:19:36.368Z,1739906376.368 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-18T19:19:36.614Z,1739906376.614 [NAL9602](INFO): Powering down
2025-02-18T19:19:37.580Z,1739906377.580 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-18T19:19:37.580Z,1739906377.580 [NAL9602] No Fault, FailCount= 4
2025-02-18T19:20:06.914Z,1739906406.914 [NAL9602](INFO): Powering up NAL9602
2025-02-18T19:20:17.826Z,1739906417.826 [NAL9602](INFO): NAL9602 initialized
2025-02-18T19:21:56.814Z,1739906516.814 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-02-18T19:21:56.814Z,1739906516.814 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:21:56.952Z,1739906516.952 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:21:57.356Z,1739906517.356 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:21:57.356Z,1739906517.356 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-02-18T19:22:38.027Z,1739906558.027 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T19:22:38.027Z,1739906558.027 [Default:CheckIn:C.Wait] Stopped
2025-02-18T19:22:38.028Z,1739906558.028 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T19:22:38.028Z,1739906558.028 [Default:CheckIn:D] Running Loop=1
2025-02-18T19:22:38.428Z,1739906558.428 [Default:CheckIn:D] Stopped
2025-02-18T19:22:38.428Z,1739906558.428 [Default:CheckIn:E] Running Loop=1
2025-02-18T19:22:38.826Z,1739906558.826 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.009884 min
2025-02-18T19:22:38.826Z,1739906558.826 [Default:CheckIn:E] Stopped
2025-02-18T19:22:38.827Z,1739906558.827 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T19:22:38.827Z,1739906558.827 [Default:CheckIn] Stopped
2025-02-18T19:22:38.827Z,1739906558.827 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T19:22:38.827Z,1739906558.827 [Default:CheckIn](INFO): Running loop #5
2025-02-18T19:22:38.827Z,1739906558.827 [Default:CheckIn] Running Loop=5
2025-02-18T19:22:38.827Z,1739906558.827 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T19:22:38.827Z,1739906558.827 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T19:24:57.801Z,1739906697.801 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-02-18T19:24:57.801Z,1739906697.801 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:24:57.944Z,1739906697.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:24:58.364Z,1739906698.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:24:58.364Z,1739906698.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-02-18T19:25:06.586Z,1739906706.586 [CommandExec](IMPORTANT): got command strobe off
2025-02-18T19:25:06.586Z,1739906706.586 [CommandExec](IMPORTANT): Deactivating strobe
2025-02-18T19:25:27.837Z,1739906727.837 [CommandExec](IMPORTANT): got command get platform_battery_charge
2025-02-18T19:25:27.837Z,1739906727.837 [CommandExec](FAULT): Element has no value
2025-02-18T19:25:48.334Z,1739906748.334 [CommandExec](IMPORTANT): got command show variable platform_battery_charge
2025-02-18T19:25:48.431Z,1739906748.431 [CommandExec](IMPORTANT): platform_battery_charge (unknown)
2025-02-18T19:25:48.431Z,1739906748.431 [CommandExec](IMPORTANT): platform_battery_charge_usage (unknown)
2025-02-18T19:25:48.614Z,1739906748.614 [CommandExec](IMPORTANT): BPC1.platform_battery_charge (ampere_hour)
2025-02-18T19:26:10.750Z,1739906770.750 [CommandExec](IMPORTANT): got command get BPC1.platform_battery_charge
2025-02-18T19:26:10.750Z,1739906770.750 [CommandExec](IMPORTANT): BPC1.platform_battery_charge 251.026993 Ah
2025-02-18T19:27:38.994Z,1739906858.994 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-18T19:22:38.8Z
2025-02-18T19:27:38.994Z,1739906858.994 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T19:27:38.994Z,1739906858.994 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T19:27:45.876Z,1739906865.876 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250218T183632/Courier0016.lzma
2025-02-18T19:27:46.878Z,1739906866.878 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0016.lzma.bak
2025-02-18T19:27:46.878Z,1739906866.878 [DataOverHttps](INFO): SBD MOMSN=23966118
2025-02-18T19:27:58.813Z,1739906878.813 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2025-02-18T19:27:58.813Z,1739906878.813 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:27:58.832Z,1739906878.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:27:59.249Z,1739906879.249 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:27:59.249Z,1739906879.249 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2025-02-18T19:28:01.417Z,1739906881.417 [DataOverHttps](INFO): Sending 365 bytes from file Logs/20250218T183632/Express0017.lzma
2025-02-18T19:28:02.418Z,1739906882.418 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0017.lzma.bak
2025-02-18T19:28:02.418Z,1739906882.418 [DataOverHttps](INFO): SBD MOMSN=23966120
2025-02-18T19:28:03.664Z,1739906883.664 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T19:28:03.664Z,1739906883.664 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T19:28:03.664Z,1739906883.664 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T19:28:22.925Z,1739906902.925 [CommandExec](IMPORTANT): got command burn on
2025-02-18T19:28:22.926Z,1739906902.926 [CommandExec](IMPORTANT): Activating dropweight wire
2025-02-18T19:28:38.325Z,1739906918.325 [CommandExec](IMPORTANT): got command burn off
2025-02-18T19:28:38.325Z,1739906918.325 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-02-18T19:30:20.202Z,1739907020.202 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-18T19:30:20.202Z,1739907020.202 [NAL9602] Data Fault, FailCount= 5
2025-02-18T19:30:20.202Z,1739907020.202 [NAL9602](ERROR): Data Fault
2025-02-18T19:30:20.220Z,1739907020.220 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-18T19:30:20.220Z,1739907020.220 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-02-18T19:30:20.586Z,1739907020.586 [NAL9602](INFO): Powering down
2025-02-18T19:30:20.630Z,1739907020.630 [CommandExec](FAULT): Scheduling is paused
2025-02-18T19:30:20.631Z,1739907020.631 [CBIT](INFO): Critical error at 20250218T193020
2025-02-18T19:30:20.631Z,1739907020.631 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-02-18T19:30:59.792Z,1739907059.792 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2025-02-18T19:30:59.792Z,1739907059.792 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:30:59.802Z,1739907059.802 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:31:00.230Z,1739907060.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:31:00.230Z,1739907060.230 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2025-02-18T19:32:20.204Z,1739907140.204 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-18T19:32:20.208Z,1739907140.208 [NAL9602] No Fault, FailCount= 5
2025-02-18T19:32:20.586Z,1739907140.586 [NAL9602](INFO): Powering up NAL9602
2025-02-18T19:32:31.483Z,1739907151.483 [NAL9602](INFO): NAL9602 initialized
2025-02-18T19:33:04.239Z,1739907184.239 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T19:33:04.239Z,1739907184.239 [Default:CheckIn:C.Wait] Stopped
2025-02-18T19:33:04.239Z,1739907184.239 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T19:33:04.239Z,1739907184.239 [Default:CheckIn:D] Running Loop=1
2025-02-18T19:33:04.640Z,1739907184.640 [Default:CheckIn:D] Stopped
2025-02-18T19:33:04.640Z,1739907184.640 [Default:CheckIn:E] Running Loop=1
2025-02-18T19:33:05.055Z,1739907185.055 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.446749 min
2025-02-18T19:33:05.055Z,1739907185.055 [Default:CheckIn:E] Stopped
2025-02-18T19:33:05.055Z,1739907185.055 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T19:33:05.055Z,1739907185.055 [Default:CheckIn] Stopped
2025-02-18T19:33:05.055Z,1739907185.055 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T19:33:05.056Z,1739907185.056 [Default:CheckIn](INFO): Running loop #6
2025-02-18T19:33:05.056Z,1739907185.056 [Default:CheckIn] Running Loop=6
2025-02-18T19:33:05.056Z,1739907185.056 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T19:33:05.056Z,1739907185.056 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T19:34:00.785Z,1739907240.785 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2025-02-18T19:34:00.785Z,1739907240.785 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:34:00.797Z,1739907240.797 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:34:01.209Z,1739907241.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:34:01.209Z,1739907241.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2025-02-18T19:36:07.947Z,1739907367.947 [CommandExec](IMPORTANT): got command get BPC1.platform_battery_charge
2025-02-18T19:36:07.947Z,1739907367.947 [CommandExec](IMPORTANT): BPC1.platform_battery_charge 251.026993 Ah
2025-02-18T19:37:01.799Z,1739907421.799 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2025-02-18T19:37:01.799Z,1739907421.799 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:37:01.810Z,1739907421.810 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:37:01.810Z,1739907421.810 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:38:05.213Z,1739907485.213 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-18T19:33:05.1Z
2025-02-18T19:38:05.213Z,1739907485.213 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T19:38:05.213Z,1739907485.213 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T19:38:12.568Z,1739907492.568 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20250218T183632/Courier0019.lzma
2025-02-18T19:38:13.570Z,1739907493.570 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0019.lzma.bak
2025-02-18T19:38:13.570Z,1739907493.570 [DataOverHttps](INFO): SBD MOMSN=23966134
2025-02-18T19:38:28.808Z,1739907508.808 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20250218T183632/Express0020.lzma
2025-02-18T19:38:29.810Z,1739907509.810 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0020.lzma.bak
2025-02-18T19:38:29.810Z,1739907509.810 [DataOverHttps](INFO): SBD MOMSN=23966137
2025-02-18T19:38:31.075Z,1739907511.075 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T19:38:31.075Z,1739907511.075 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T19:38:31.076Z,1739907511.076 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T19:42:32.670Z,1739907752.670 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-18T19:42:32.670Z,1739907752.670 [NAL9602] Data Fault, FailCount= 1
2025-02-18T19:42:32.670Z,1739907752.670 [NAL9602](ERROR): Data Fault
2025-02-18T19:42:32.707Z,1739907752.707 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-18T19:42:33.074Z,1739907753.074 [NAL9602](INFO): Powering down
2025-02-18T19:42:33.901Z,1739907753.901 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-18T19:42:33.901Z,1739907753.901 [NAL9602] No Fault, FailCount= 1
2025-02-18T19:43:03.374Z,1739907783.374 [NAL9602](INFO): Powering up NAL9602
2025-02-18T19:43:14.278Z,1739907794.278 [NAL9602](INFO): NAL9602 initialized
2025-02-18T19:43:31.682Z,1739907811.682 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T19:43:31.682Z,1739907811.682 [Default:CheckIn:C.Wait] Stopped
2025-02-18T19:43:31.682Z,1739907811.682 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T19:43:31.682Z,1739907811.682 [Default:CheckIn:D] Running Loop=1
2025-02-18T19:43:32.083Z,1739907812.083 [Default:CheckIn:D] Stopped
2025-02-18T19:43:32.083Z,1739907812.083 [Default:CheckIn:E] Running Loop=1
2025-02-18T19:43:32.490Z,1739907812.490 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.904134 min
2025-02-18T19:43:32.491Z,1739907812.491 [Default:CheckIn:E] Stopped
2025-02-18T19:43:32.491Z,1739907812.491 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T19:43:32.491Z,1739907812.491 [Default:CheckIn] Stopped
2025-02-18T19:43:32.491Z,1739907812.491 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T19:43:32.491Z,1739907812.491 [Default:CheckIn](INFO): Running loop #7
2025-02-18T19:43:32.491Z,1739907812.491 [Default:CheckIn] Running Loop=7
2025-02-18T19:43:32.491Z,1739907812.491 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T19:43:32.491Z,1739907812.491 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T19:46:49.217Z,1739908009.217 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-02-18T19:47:10.724Z,1739908030.724 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T19:47:51.077Z,1739908071.077 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T19:48:06.426Z,1739908086.426 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T19:48:18.142Z,1739908098.142 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T19:48:32.667Z,1739908112.667 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-18T19:43:32.5Z
2025-02-18T19:48:32.667Z,1739908112.667 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T19:48:32.667Z,1739908112.667 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T19:48:39.920Z,1739908119.920 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250218T183632/Courier0022.lzma
2025-02-18T19:48:40.922Z,1739908120.922 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0022.lzma.bak
2025-02-18T19:48:40.923Z,1739908120.923 [DataOverHttps](INFO): SBD MOMSN=23966149
2025-02-18T19:48:56.520Z,1739908136.520 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250218T183632/Express0023.lzma
2025-02-18T19:48:57.522Z,1739908137.522 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0023.lzma.bak
2025-02-18T19:48:57.523Z,1739908137.523 [DataOverHttps](INFO): SBD MOMSN=23966151
2025-02-18T19:48:58.947Z,1739908138.947 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T19:49:00.980Z,1739908140.980 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T19:49:00.980Z,1739908140.980 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T19:49:00.980Z,1739908140.980 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T19:49:38.541Z,1739908178.541 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T19:50:23.005Z,1739908223.005 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T19:51:04.531Z,1739908264.531 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:51:06.204Z,1739908266.204 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-02-18T19:51:18.266Z,1739908278.266 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:51:32.405Z,1739908292.405 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:51:46.548Z,1739908306.548 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:52:00.281Z,1739908320.281 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:52:01.937Z,1739908321.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:52:01.937Z,1739908321.937 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2025-02-18T19:52:13.613Z,1739908333.613 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:52:25.733Z,1739908345.733 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T19:52:28.161Z,1739908348.161 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:52:41.894Z,1739908361.894 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T19:52:42.701Z,1739908362.701 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:52:55.674Z,1739908375.674 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T19:52:58.458Z,1739908378.458 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T19:53:09.365Z,1739908389.365 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:53:15.022Z,1739908395.022 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T19:53:16.646Z,1739908396.646 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-18T19:53:16.646Z,1739908396.646 [NAL9602] Data Fault, FailCount= 2
2025-02-18T19:53:16.646Z,1739908396.646 [NAL9602](ERROR): Data Fault
2025-02-18T19:53:16.689Z,1739908396.689 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-18T19:53:17.043Z,1739908397.043 [NAL9602](INFO): Powering down
2025-02-18T19:53:17.879Z,1739908397.879 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-18T19:53:17.879Z,1739908397.879 [NAL9602] No Fault, FailCount= 2
2025-02-18T19:53:22.698Z,1739908402.698 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T19:53:28.771Z,1739908408.771 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-02-18T19:53:30.777Z,1739908410.777 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T19:53:36.038Z,1739908416.038 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-18T19:53:36.038Z,1739908416.038 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-18T19:53:36.041Z,1739908416.041 [BPC1](INFO): Received data from all battery sticks.
2025-02-18T19:53:47.350Z,1739908427.350 [NAL9602](INFO): Powering up NAL9602
2025-02-18T19:53:58.258Z,1739908438.258 [NAL9602](INFO): NAL9602 initialized
2025-02-18T19:54:01.497Z,1739908441.497 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T19:54:01.498Z,1739908441.498 [Default:CheckIn:C.Wait] Stopped
2025-02-18T19:54:01.498Z,1739908441.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T19:54:01.498Z,1739908441.498 [Default:CheckIn:D] Running Loop=1
2025-02-18T19:54:01.929Z,1739908441.929 [Default:CheckIn:D] Stopped
2025-02-18T19:54:01.929Z,1739908441.929 [Default:CheckIn:E] Running Loop=1
2025-02-18T19:54:02.320Z,1739908442.320 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.401571 min
2025-02-18T19:54:02.320Z,1739908442.320 [Default:CheckIn:E] Stopped
2025-02-18T19:54:02.320Z,1739908442.320 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T19:54:02.320Z,1739908442.320 [Default:CheckIn] Stopped
2025-02-18T19:54:02.320Z,1739908442.320 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T19:54:02.321Z,1739908442.321 [Default:CheckIn](INFO): Running loop #8
2025-02-18T19:54:02.321Z,1739908442.321 [Default:CheckIn] Running Loop=8
2025-02-18T19:54:02.321Z,1739908442.321 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T19:54:02.321Z,1739908442.321 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T19:55:02.514Z,1739908502.514 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-02-18T19:55:02.514Z,1739908502.514 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:55:02.525Z,1739908502.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:55:02.919Z,1739908502.919 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:55:02.919Z,1739908502.919 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-02-18T19:58:03.514Z,1739908683.514 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-02-18T19:58:03.514Z,1739908683.514 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T19:58:03.525Z,1739908683.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T19:58:03.907Z,1739908683.907 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T19:58:03.907Z,1739908683.907 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-02-18T19:59:02.495Z,1739908742.495 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-18T19:54:02.3Z
2025-02-18T19:59:02.495Z,1739908742.495 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T19:59:02.495Z,1739908742.495 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T19:59:09.404Z,1739908749.404 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250218T183632/Courier0025.lzma
2025-02-18T19:59:10.406Z,1739908750.406 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0025.lzma.bak
2025-02-18T19:59:10.406Z,1739908750.406 [DataOverHttps](INFO): SBD MOMSN=23966155
2025-02-18T19:59:25.769Z,1739908765.769 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250218T183632/Express0026.lzma
2025-02-18T19:59:26.770Z,1739908766.770 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0026.lzma.bak
2025-02-18T19:59:26.770Z,1739908766.770 [DataOverHttps](INFO): SBD MOMSN=23966157
2025-02-18T19:59:27.947Z,1739908767.947 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T19:59:27.947Z,1739908767.947 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T19:59:27.947Z,1739908767.947 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:01:04.494Z,1739908864.494 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-02-18T20:01:04.495Z,1739908864.495 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T20:01:04.525Z,1739908864.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T20:01:04.900Z,1739908864.900 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T20:01:04.900Z,1739908864.900 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-02-18T20:03:59.818Z,1739909039.818 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-18T20:03:59.818Z,1739909039.818 [NAL9602] Data Fault, FailCount= 3
2025-02-18T20:03:59.818Z,1739909039.818 [NAL9602](ERROR): Data Fault
2025-02-18T20:03:59.837Z,1739909039.837 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-18T20:04:00.214Z,1739909040.214 [NAL9602](INFO): Powering down
2025-02-18T20:04:01.042Z,1739909041.042 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-18T20:04:01.042Z,1739909041.042 [NAL9602] No Fault, FailCount= 3
2025-02-18T20:04:05.482Z,1739909045.482 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-02-18T20:04:05.482Z,1739909045.482 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T20:04:05.525Z,1739909045.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T20:04:05.942Z,1739909045.942 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T20:04:05.942Z,1739909045.942 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-02-18T20:04:28.499Z,1739909068.499 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T20:04:28.500Z,1739909068.500 [Default:CheckIn:C.Wait] Stopped
2025-02-18T20:04:28.500Z,1739909068.500 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:04:28.500Z,1739909068.500 [Default:CheckIn:D] Running Loop=1
2025-02-18T20:04:28.912Z,1739909068.912 [Default:CheckIn:D] Stopped
2025-02-18T20:04:28.912Z,1739909068.912 [Default:CheckIn:E] Running Loop=1
2025-02-18T20:04:29.313Z,1739909069.313 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.851286 min
2025-02-18T20:04:29.313Z,1739909069.313 [Default:CheckIn:E] Stopped
2025-02-18T20:04:29.313Z,1739909069.313 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T20:04:29.313Z,1739909069.313 [Default:CheckIn] Stopped
2025-02-18T20:04:29.313Z,1739909069.313 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T20:04:29.314Z,1739909069.314 [Default:CheckIn](INFO): Running loop #9
2025-02-18T20:04:29.314Z,1739909069.314 [Default:CheckIn] Running Loop=9
2025-02-18T20:04:29.314Z,1739909069.314 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T20:04:29.314Z,1739909069.314 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T20:04:30.519Z,1739909070.519 [NAL9602](INFO): Powering up NAL9602
2025-02-18T20:04:41.426Z,1739909081.426 [NAL9602](INFO): NAL9602 initialized
2025-02-18T20:07:06.577Z,1739909226.577 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-02-18T20:07:06.577Z,1739909226.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T20:07:06.682Z,1739909226.682 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T20:07:07.029Z,1739909227.029 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T20:07:07.029Z,1739909227.029 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-02-18T20:09:29.599Z,1739909369.599 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-18T20:04:29.3Z
2025-02-18T20:09:29.599Z,1739909369.599 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T20:09:29.599Z,1739909369.599 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T20:09:36.816Z,1739909376.816 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250218T183632/Courier0028.lzma
2025-02-18T20:09:37.818Z,1739909377.818 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0028.lzma.bak
2025-02-18T20:09:37.819Z,1739909377.819 [DataOverHttps](INFO): SBD MOMSN=23966161
2025-02-18T20:09:53.348Z,1739909393.348 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250218T183632/Express0029.lzma
2025-02-18T20:09:54.350Z,1739909394.350 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0029.lzma.bak
2025-02-18T20:09:54.350Z,1739909394.350 [DataOverHttps](INFO): SBD MOMSN=23966163
2025-02-18T20:09:55.881Z,1739909395.881 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T20:09:55.881Z,1739909395.881 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T20:09:55.881Z,1739909395.881 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:10:07.573Z,1739909407.573 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-02-18T20:10:07.573Z,1739909407.573 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T20:10:07.607Z,1739909407.607 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T20:10:08.016Z,1739909408.016 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T20:10:08.016Z,1739909408.016 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-02-18T20:13:08.577Z,1739909588.577 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-02-18T20:13:08.577Z,1739909588.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-18T20:13:08.597Z,1739909588.597 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-18T20:13:09.003Z,1739909589.003 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-18T20:13:09.003Z,1739909589.003 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-02-18T20:14:01.900Z,1739909641.900 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201358.00,A,3648.14612,N,12147.25088,W,0.700,41.18,180225,,,A*49
2025-02-18T20:14:01.903Z,1739909641.903 [NAL9602](INFO): GPS fix at 20250218T201358: (36.802435, -121.787515)
2025-02-18T20:14:02.362Z,1739909642.362 [DeadReckonUsingMultipleVelocitySources](INFO): Failure count cleared after critical for DeadReckonUsingMultipleVelocitySources
2025-02-18T20:14:34.221Z,1739909674.221 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:14:56.453Z,1739909696.453 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T20:14:56.453Z,1739909696.453 [Default:CheckIn:C.Wait] Stopped
2025-02-18T20:14:56.453Z,1739909696.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:14:56.454Z,1739909696.454 [Default:CheckIn:D] Running Loop=1
2025-02-18T20:14:56.859Z,1739909696.859 [Default:CheckIn:D] Stopped
2025-02-18T20:14:56.859Z,1739909696.859 [Default:CheckIn:E] Running Loop=1
2025-02-18T20:14:57.270Z,1739909697.270 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.317074 min
2025-02-18T20:14:57.270Z,1739909697.270 [Default:CheckIn:E] Stopped
2025-02-18T20:14:57.270Z,1739909697.270 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T20:14:57.270Z,1739909697.270 [Default:CheckIn] Stopped
2025-02-18T20:14:57.270Z,1739909697.270 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T20:14:57.271Z,1739909697.271 [Default:CheckIn](INFO): Running loop #10
2025-02-18T20:14:57.271Z,1739909697.271 [Default:CheckIn] Running Loop=10
2025-02-18T20:14:57.271Z,1739909697.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T20:14:57.271Z,1739909697.271 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T20:14:59.275Z,1739909699.275 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201455.00,A,3648.13759,N,12147.21237,W,1.691,64.50,180225,,,A*4B
2025-02-18T20:14:59.278Z,1739909699.278 [NAL9602](INFO): GPS fix at 20250218T201455: (36.802293, -121.786873)
2025-02-18T20:14:59.308Z,1739909699.308 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T20:14:59.308Z,1739909699.308 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T20:15:09.412Z,1739909709.412 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250218T183632/Courier0031.lzma
2025-02-18T20:15:10.414Z,1739909710.414 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0031.lzma.bak
2025-02-18T20:15:10.414Z,1739909710.414 [DataOverHttps](INFO): SBD MOMSN=23966167
2025-02-18T20:15:13.447Z,1739909713.447 [NAL9602](INFO): SBD MO Status=0, MOMSN=12664, MT Status=0, MTMSN=0
2025-02-18T20:15:13.447Z,1739909713.447 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:15:27.190Z,1739909727.190 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20250218T183632/Express0032.lzma
2025-02-18T20:15:28.190Z,1739909728.190 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0032.lzma.bak
2025-02-18T20:15:28.190Z,1739909728.190 [DataOverHttps](INFO): SBD MOMSN=23966170
2025-02-18T20:15:29.625Z,1739909729.625 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T20:15:29.625Z,1739909729.625 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T20:15:29.625Z,1739909729.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:15:44.153Z,1739909744.153 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:17:11.414Z,1739909831.414 [DataOverHttps](IMPORTANT): SBD MTMSN=20250218T201710
2025-02-18T20:17:19.068Z,1739909839.068 [DataOverHttps](INFO): Received command: ibit
2025-02-18T20:17:19.121Z,1739909839.121 [CommandExec](IMPORTANT): got command ibit
2025-02-18T20:17:19.151Z,1739909839.151 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-02-18T20:17:19.151Z,1739909839.151 [IBIT](IMPORTANT): Beginning control surface checks.
2025-02-18T20:17:19.154Z,1739909839.154 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-18T20:17:20.743Z,1739909840.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201720.00,A,3648.13598,N,12147.21203,W,0.039,70.64,180225,,,D*40
2025-02-18T20:17:20.745Z,1739909840.745 [NAL9602](INFO): GPS fix at 20250218T201720: (36.802266, -121.786867)
2025-02-18T20:17:22.041Z,1739909842.041 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2025-02-18T20:17:30.080Z,1739909850.080 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001773
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.000049
CHAN A3 (5V): -0.002157
CHAN B0 (3.3V): -0.000225
CHAN B1 (3.15aV): -0.001182
CHAN B2 (3.15bV): -0.001375
CHAN B3 (GND): -0.002367
OPEN: 0.004774
Full Scale: +/- 1 mA
2025-02-18T20:17:45.387Z,1739909865.387 [NAL9602](INFO): SBD MO Status=2, MOMSN=12665, MT Status=2, MTMSN=0
2025-02-18T20:17:45.387Z,1739909865.387 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-18T20:18:05.614Z,1739909885.614 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802265 Longitude: -121.786865
2025-02-18T20:18:06.009Z,1739909886.009 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.898000
2025-02-18T20:18:06.009Z,1739909886.009 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-02-18T20:18:06.009Z,1739909886.009 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-02-18T20:18:06.413Z,1739909886.413 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-02-18T20:18:06.414Z,1739909886.414 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-02-18T20:18:06.414Z,1739909886.414 [IBIT](IMPORTANT): Pressure:10.153406 PSI
2025-02-18T20:18:06.414Z,1739909886.414 [IBIT](IMPORTANT): Humidity:2.923941 %
2025-02-18T20:18:06.830Z,1739909886.830 [IBIT](IMPORTANT): Vehicle Pitch:-1.337456 degrees
2025-02-18T20:18:06.830Z,1739909886.830 [IBIT](IMPORTANT): Vehicle Roll:8.404163 degrees
2025-02-18T20:18:06.831Z,1739909886.831 [IBIT](IMPORTANT): Vehicle Heading:197.650284 degrees
2025-02-18T20:18:07.224Z,1739909887.224 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-02-18T20:18:07.224Z,1739909887.224 [IBIT](IMPORTANT): buoyancyNeutral: 345.701630 cc
2025-02-18T20:18:07.224Z,1739909887.224 [IBIT](IMPORTANT): massDefault: 1.024720 cm
2025-02-18T20:18:07.225Z,1739909887.225 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-02-18T20:18:07.225Z,1739909887.225 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-02-18T20:18:07.225Z,1739909887.225 [IBIT](IMPORTANT): IBIT PASSED
2025-02-18T20:18:07.615Z,1739909887.615 [NAL9602](INFO): SBD MO Status=0, MOMSN=12665, MT Status=0, MTMSN=0
2025-02-18T20:18:07.615Z,1739909887.615 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:18:07.615Z,1739909887.615 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2025-02-18T20:18:38.316Z,1739909918.316 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:20:30.240Z,1739910030.240 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T20:20:30.240Z,1739910030.240 [Default:CheckIn:C.Wait] Stopped
2025-02-18T20:20:30.240Z,1739910030.240 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:20:30.240Z,1739910030.240 [Default:CheckIn:D] Running Loop=1
2025-02-18T20:20:30.648Z,1739910030.648 [Default:CheckIn:D] Stopped
2025-02-18T20:20:30.648Z,1739910030.648 [Default:CheckIn:E] Running Loop=1
2025-02-18T20:20:31.047Z,1739910031.047 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.880233 min
2025-02-18T20:20:31.047Z,1739910031.047 [Default:CheckIn:E] Stopped
2025-02-18T20:20:31.047Z,1739910031.047 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T20:20:31.047Z,1739910031.047 [Default:CheckIn] Stopped
2025-02-18T20:20:31.047Z,1739910031.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T20:20:31.048Z,1739910031.048 [Default:CheckIn](INFO): Running loop #11
2025-02-18T20:20:31.048Z,1739910031.048 [Default:CheckIn] Running Loop=11
2025-02-18T20:20:31.048Z,1739910031.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T20:20:31.048Z,1739910031.048 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T20:20:33.062Z,1739910033.062 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202032.00,A,3648.13746,N,12147.20986,W,0.058,70.64,180225,,,D*46
2025-02-18T20:20:33.064Z,1739910033.064 [NAL9602](INFO): GPS fix at 20250218T202032: (36.802291, -121.786831)
2025-02-18T20:20:33.096Z,1739910033.096 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T20:20:33.096Z,1739910033.096 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T20:20:40.922Z,1739910040.922 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250218T183632/Courier0034.lzma
2025-02-18T20:20:41.922Z,1739910041.922 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0034.lzma.bak
2025-02-18T20:20:41.922Z,1739910041.922 [DataOverHttps](INFO): SBD MOMSN=23966177
2025-02-18T20:20:54.079Z,1739910054.079 [NAL9602](INFO): SBD MO Status=2, MOMSN=12666, MT Status=2, MTMSN=0
2025-02-18T20:20:54.079Z,1739910054.079 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-18T20:20:58.185Z,1739910058.185 [DataOverHttps](INFO): Sending 820 bytes from file Logs/20250218T183632/Express0035.lzma
2025-02-18T20:21:01.405Z,1739910061.405 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0035.lzma.bak
2025-02-18T20:21:01.405Z,1739910061.405 [DataOverHttps](INFO): SBD MOMSN=23966180
2025-02-18T20:21:04.195Z,1739910064.195 [NAL9602](INFO): SBD MO Status=0, MOMSN=12666, MT Status=0, MTMSN=0
2025-02-18T20:21:04.195Z,1739910064.195 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:21:20.408Z,1739910080.408 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250218T183632/Express0038.lzma
2025-02-18T20:21:21.410Z,1739910081.410 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0038.lzma.bak
2025-02-18T20:21:21.410Z,1739910081.410 [DataOverHttps](INFO): SBD MOMSN=23966206
2025-02-18T20:21:22.821Z,1739910082.821 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T20:21:22.821Z,1739910082.821 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T20:21:22.821Z,1739910082.821 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:21:34.898Z,1739910094.898 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:25:43.477Z,1739910343.477 [RudderServo](ERROR): getPosition uart error serial timeout
2025-02-18T20:25:43.477Z,1739910343.477 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2025-02-18T20:25:43.477Z,1739910343.477 [RudderServo] Communications Fault, FailCount= 1
2025-02-18T20:25:43.477Z,1739910343.477 [RudderServo](ERROR): Communications Fault
2025-02-18T20:25:43.486Z,1739910343.486 [CBIT](ERROR): Communications Fault in component: RudderServo
2025-02-18T20:25:43.808Z,1739910343.808 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-02-18T20:25:43.808Z,1739910343.808 [RudderServo](INFO): Powering down
2025-02-18T20:25:44.601Z,1739910344.601 [RudderServo](DEBUG): Initializing EZServoServo.
2025-02-18T20:25:44.721Z,1739910344.721 [RudderServo](DEBUG): Initializing RudderServo.
2025-02-18T20:25:44.725Z,1739910344.725 [CBIT](INFO): Clearing failed state for component RudderServo
2025-02-18T20:25:44.725Z,1739910344.725 [RudderServo] No Fault, FailCount= 1
2025-02-18T20:26:23.385Z,1739910383.385 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T20:26:23.385Z,1739910383.385 [Default:CheckIn:C.Wait] Stopped
2025-02-18T20:26:23.385Z,1739910383.385 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:26:23.385Z,1739910383.385 [Default:CheckIn:D] Running Loop=1
2025-02-18T20:26:23.802Z,1739910383.802 [Default:CheckIn:D] Stopped
2025-02-18T20:26:23.802Z,1739910383.802 [Default:CheckIn:E] Running Loop=1
2025-02-18T20:26:24.191Z,1739910384.191 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.766121 min
2025-02-18T20:26:24.191Z,1739910384.191 [Default:CheckIn:E] Stopped
2025-02-18T20:26:24.191Z,1739910384.191 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T20:26:24.191Z,1739910384.191 [Default:CheckIn] Stopped
2025-02-18T20:26:24.191Z,1739910384.191 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T20:26:24.191Z,1739910384.191 [Default:CheckIn](INFO): Running loop #12
2025-02-18T20:26:24.192Z,1739910384.192 [Default:CheckIn] Running Loop=12
2025-02-18T20:26:24.192Z,1739910384.192 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T20:26:24.192Z,1739910384.192 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T20:26:26.202Z,1739910386.202 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202625.00,A,3648.13912,N,12147.20938,W,0.078,70.64,180225,,,D*4E
2025-02-18T20:26:26.205Z,1739910386.205 [NAL9602](INFO): GPS fix at 20250218T202625: (36.802319, -121.786823)
2025-02-18T20:26:26.215Z,1739910386.215 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T20:26:26.215Z,1739910386.215 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T20:26:33.864Z,1739910393.864 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250218T183632/Courier0040.lzma
2025-02-18T20:26:34.866Z,1739910394.866 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0040.lzma.bak
2025-02-18T20:26:34.867Z,1739910394.867 [DataOverHttps](INFO): SBD MOMSN=23966209
2025-02-18T20:26:45.778Z,1739910405.778 [NAL9602](INFO): SBD MO Status=2, MOMSN=12667, MT Status=2, MTMSN=0
2025-02-18T20:26:45.778Z,1739910405.778 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-18T20:26:51.488Z,1739910411.488 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250218T183632/Express0041.lzma
2025-02-18T20:26:52.490Z,1739910412.490 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0041.lzma.bak
2025-02-18T20:26:52.491Z,1739910412.491 [DataOverHttps](INFO): SBD MOMSN=23966212
2025-02-18T20:26:53.881Z,1739910413.881 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T20:26:53.881Z,1739910413.881 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T20:26:53.881Z,1739910413.881 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:27:10.428Z,1739910430.428 [NAL9602](INFO): SBD MO Status=2, MOMSN=12667, MT Status=2, MTMSN=0
2025-02-18T20:27:10.428Z,1739910430.428 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-18T20:27:33.866Z,1739910453.866 [NAL9602](INFO): SBD MO Status=2, MOMSN=12667, MT Status=2, MTMSN=0
2025-02-18T20:27:33.866Z,1739910453.866 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-18T20:27:47.196Z,1739910467.196 [NAL9602](INFO): SBD MO Status=0, MOMSN=12667, MT Status=0, MTMSN=0
2025-02-18T20:27:47.196Z,1739910467.196 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:28:17.901Z,1739910497.901 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:31:54.610Z,1739910714.610 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T20:31:54.610Z,1739910714.610 [Default:CheckIn:C.Wait] Stopped
2025-02-18T20:31:54.610Z,1739910714.610 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:31:54.610Z,1739910714.610 [Default:CheckIn:D] Running Loop=1
2025-02-18T20:31:55.009Z,1739910715.009 [Default:CheckIn:D] Stopped
2025-02-18T20:31:55.010Z,1739910715.010 [Default:CheckIn:E] Running Loop=1
2025-02-18T20:31:55.410Z,1739910715.410 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.286247 min
2025-02-18T20:31:55.410Z,1739910715.410 [Default:CheckIn:E] Stopped
2025-02-18T20:31:55.410Z,1739910715.410 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T20:31:55.410Z,1739910715.410 [Default:CheckIn] Stopped
2025-02-18T20:31:55.410Z,1739910715.410 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T20:31:55.410Z,1739910715.410 [Default:CheckIn](INFO): Running loop #13
2025-02-18T20:31:55.410Z,1739910715.410 [Default:CheckIn] Running Loop=13
2025-02-18T20:31:55.410Z,1739910715.410 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T20:31:55.411Z,1739910715.411 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T20:31:57.429Z,1739910717.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203156.00,A,3648.13618,N,12147.21029,W,0.019,203.89,180225,,,D*73
2025-02-18T20:31:57.431Z,1739910717.431 [NAL9602](INFO): GPS fix at 20250218T203156: (36.802270, -121.786838)
2025-02-18T20:31:57.443Z,1739910717.443 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T20:31:57.444Z,1739910717.444 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T20:32:06.956Z,1739910726.956 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250218T183632/Courier0043.lzma
2025-02-18T20:32:07.958Z,1739910727.958 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0043.lzma.bak
2025-02-18T20:32:07.958Z,1739910727.958 [DataOverHttps](INFO): SBD MOMSN=23966217
2025-02-18T20:32:11.127Z,1739910731.127 [NAL9602](INFO): SBD MO Status=0, MOMSN=12668, MT Status=0, MTMSN=0
2025-02-18T20:32:11.127Z,1739910731.127 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:32:25.126Z,1739910745.126 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20250218T183632/Express0044.lzma
2025-02-18T20:32:26.127Z,1739910746.127 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0044.lzma.bak
2025-02-18T20:32:26.127Z,1739910746.127 [DataOverHttps](INFO): SBD MOMSN=23966220
2025-02-18T20:32:27.711Z,1739910747.711 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T20:32:27.711Z,1739910747.711 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T20:32:27.711Z,1739910747.711 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:32:41.824Z,1739910761.824 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:37:28.523Z,1739911048.523 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T20:37:28.523Z,1739911048.523 [Default:CheckIn:C.Wait] Stopped
2025-02-18T20:37:28.523Z,1739911048.523 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:37:28.523Z,1739911048.523 [Default:CheckIn:D] Running Loop=1
2025-02-18T20:37:28.935Z,1739911048.935 [Default:CheckIn:D] Stopped
2025-02-18T20:37:28.935Z,1739911048.935 [Default:CheckIn:E] Running Loop=1
2025-02-18T20:37:29.338Z,1739911049.338 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.851676 min
2025-02-18T20:37:29.338Z,1739911049.338 [Default:CheckIn:E] Stopped
2025-02-18T20:37:29.338Z,1739911049.338 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T20:37:29.338Z,1739911049.338 [Default:CheckIn] Stopped
2025-02-18T20:37:29.338Z,1739911049.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T20:37:29.338Z,1739911049.338 [Default:CheckIn](INFO): Running loop #14
2025-02-18T20:37:29.338Z,1739911049.338 [Default:CheckIn] Running Loop=14
2025-02-18T20:37:29.339Z,1739911049.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T20:37:29.339Z,1739911049.339 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T20:37:31.339Z,1739911051.339 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203730.00,A,3648.13597,N,12147.21095,W,0.058,167.26,180225,,,D*77
2025-02-18T20:37:31.342Z,1739911051.342 [NAL9602](INFO): GPS fix at 20250218T203730: (36.802266, -121.786849)
2025-02-18T20:37:31.352Z,1739911051.352 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T20:37:31.352Z,1739911051.352 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T20:37:38.846Z,1739911058.846 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250218T183632/Courier0046.lzma
2025-02-18T20:37:39.846Z,1739911059.846 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0046.lzma.bak
2025-02-18T20:37:39.846Z,1739911059.846 [DataOverHttps](INFO): SBD MOMSN=23966224
2025-02-18T20:37:45.555Z,1739911065.555 [NAL9602](INFO): SBD MO Status=0, MOMSN=12669, MT Status=0, MTMSN=0
2025-02-18T20:37:45.555Z,1739911065.555 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:37:56.192Z,1739911076.192 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250218T183632/Express0047.lzma
2025-02-18T20:37:57.195Z,1739911077.195 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0047.lzma.bak
2025-02-18T20:37:57.195Z,1739911077.195 [DataOverHttps](INFO): SBD MOMSN=23966227
2025-02-18T20:37:58.489Z,1739911078.489 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T20:37:58.490Z,1739911078.490 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T20:37:58.490Z,1739911078.490 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:38:16.249Z,1739911096.249 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:41:01.669Z,1739911261.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T20:42:58.906Z,1739911378.906 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T20:42:58.906Z,1739911378.906 [Default:CheckIn:C.Wait] Stopped
2025-02-18T20:42:58.906Z,1739911378.906 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:42:58.907Z,1739911378.907 [Default:CheckIn:D] Running Loop=1
2025-02-18T20:42:59.314Z,1739911379.314 [Default:CheckIn:D] Stopped
2025-02-18T20:42:59.315Z,1739911379.315 [Default:CheckIn:E] Running Loop=1
2025-02-18T20:42:59.727Z,1739911379.727 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.358000 min
2025-02-18T20:42:59.727Z,1739911379.727 [Default:CheckIn:E] Stopped
2025-02-18T20:42:59.727Z,1739911379.727 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T20:42:59.727Z,1739911379.727 [Default:CheckIn] Stopped
2025-02-18T20:42:59.728Z,1739911379.728 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T20:42:59.728Z,1739911379.728 [Default:CheckIn](INFO): Running loop #15
2025-02-18T20:42:59.728Z,1739911379.728 [Default:CheckIn] Running Loop=15
2025-02-18T20:42:59.728Z,1739911379.728 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T20:42:59.728Z,1739911379.728 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T20:43:01.731Z,1739911381.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204301.00,A,3648.13432,N,12147.19871,W,0.622,124.27,180225,,,D*7C
2025-02-18T20:43:01.738Z,1739911381.738 [NAL9602](INFO): GPS fix at 20250218T204301: (36.802239, -121.786645)
2025-02-18T20:43:01.758Z,1739911381.758 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T20:43:01.758Z,1739911381.758 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T20:43:08.491Z,1739911388.491 [NAL9602](INFO): SBD MO Status=0, MOMSN=12670, MT Status=0, MTMSN=0
2025-02-18T20:43:08.491Z,1739911388.491 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:43:15.905Z,1739911395.905 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250218T183632/Courier0049.lzma
2025-02-18T20:43:16.906Z,1739911396.906 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0049.lzma.bak
2025-02-18T20:43:16.906Z,1739911396.906 [DataOverHttps](INFO): SBD MOMSN=23966231
2025-02-18T20:43:37.407Z,1739911417.407 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250218T183632/Express0050.lzma
2025-02-18T20:43:38.408Z,1739911418.408 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0050.lzma.bak
2025-02-18T20:43:38.408Z,1739911418.408 [DataOverHttps](INFO): SBD MOMSN=23966234
2025-02-18T20:43:39.269Z,1739911419.269 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:43:42.134Z,1739911422.134 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T20:43:42.134Z,1739911422.134 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T20:43:42.134Z,1739911422.134 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:48:32.948Z,1739911712.948 [DataOverHttps](IMPORTANT): SBD MTMSN=20250218T204832
2025-02-18T20:48:40.872Z,1739911720.872 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 1 m;set transit.Speed 1.3 m/s" 1y4g5 1 2
2025-02-18T20:48:40.957Z,1739911720.957 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 1 m;set transit.Speed 1.3 m/s" 1y4g5 1 2.000000
2025-02-18T20:48:40.961Z,1739911720.961 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1y4g5
2025-02-18T20:48:40.971Z,1739911720.971 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='1y4g5'): "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 1 m;set transit.Speed 1.3 m/s", ASAP
2025-02-18T20:48:41.874Z,1739911721.874 [DataOverHttps](IMPORTANT): SBD MTMSN=20250218T204840
2025-02-18T20:48:42.925Z,1739911722.925 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-18T20:48:42.925Z,1739911722.925 [Default:CheckIn:C.Wait] Stopped
2025-02-18T20:48:42.925Z,1739911722.925 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:48:42.925Z,1739911722.925 [Default:CheckIn:D] Running Loop=1
2025-02-18T20:48:43.315Z,1739911723.315 [Default:CheckIn:D] Stopped
2025-02-18T20:48:43.315Z,1739911723.315 [Default:CheckIn:E] Running Loop=1
2025-02-18T20:48:43.720Z,1739911723.720 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.091341 min
2025-02-18T20:48:43.720Z,1739911723.720 [Default:CheckIn:E] Stopped
2025-02-18T20:48:43.720Z,1739911723.720 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-18T20:48:43.720Z,1739911723.720 [Default:CheckIn] Stopped
2025-02-18T20:48:43.720Z,1739911723.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T20:48:43.721Z,1739911723.721 [Default:CheckIn](INFO): Running loop #16
2025-02-18T20:48:43.721Z,1739911723.721 [Default:CheckIn] Running Loop=16
2025-02-18T20:48:43.721Z,1739911723.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T20:48:43.721Z,1739911723.721 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T20:48:45.731Z,1739911725.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204845.00,A,3648.29578,N,12147.12495,W,1.361,358.64,180225,,,A*74
2025-02-18T20:48:45.734Z,1739911725.734 [NAL9602](INFO): GPS fix at 20250218T204845: (36.804930, -121.785416)
2025-02-18T20:48:45.793Z,1739911725.793 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T20:48:45.793Z,1739911725.793 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T20:48:50.682Z,1739911730.682 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250218T183632/Courier0052.lzma
2025-02-18T20:48:50.684Z,1739911730.684 [DataOverHttps](INFO): Received command: sched asap "set transit.MaxDepth 2.5 m;set transit.MinOffshore 0.0001 km;set transit.MinAltitude 5 m;run" 1y4g5 2 2
2025-02-18T20:48:50.784Z,1739911730.784 [CommandExec](IMPORTANT): got command schedule asap "set transit.MaxDepth 2.5 m;set transit.MinOffshore 0.0001 km;set transit.MinAltitude 5 m;run" 1y4g5 2 2.000000
2025-02-18T20:48:50.784Z,1739911730.784 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1y4g5
2025-02-18T20:48:50.785Z,1739911730.785 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='1y4g5'): "set transit.MaxDepth 2.5 m;set transit.MinOffshore 0.0001 km;set transit.MinAltitude 5 m;run", ASAP
2025-02-18T20:48:50.929Z,1739911730.929 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-02-18T20:48:50.929Z,1739911730.929 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-02-18T20:48:50.929Z,1739911730.929 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-02-18T20:48:50.935Z,1739911730.935 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-02-18T20:48:51.998Z,1739911731.998 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0052.lzma.bak
2025-02-18T20:48:51.998Z,1739911731.998 [DataOverHttps](INFO): SBD MOMSN=23966240
2025-02-18T20:48:52.241Z,1739911732.241 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2025-02-18T20:48:52.246Z,1739911732.246 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-02-18T20:48:52.249Z,1739911732.249 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-02-18T20:48:52.253Z,1739911732.253 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-02-18T20:48:52.255Z,1739911732.255 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-02-18T20:48:52.259Z,1739911732.259 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-02-18T20:48:52.262Z,1739911732.262 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-02-18T20:48:52.265Z,1739911732.265 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-02-18T20:48:52.268Z,1739911732.268 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-02-18T20:48:52.271Z,1739911732.271 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-02-18T20:48:52.274Z,1739911732.274 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-02-18T20:48:52.277Z,1739911732.277 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-02-18T20:48:52.279Z,1739911732.279 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-02-18T20:48:52.321Z,1739911732.321 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-02-18T20:48:52.321Z,1739911732.321 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-18T20:48:52.321Z,1739911732.321 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-18T20:48:52.322Z,1739911732.322 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-18T20:48:52.799Z,1739911732.799 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-02-18T20:48:52.805Z,1739911732.805 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-02-18T20:48:52.809Z,1739911732.809 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-18T20:48:52.813Z,1739911732.813 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-18T20:48:52.816Z,1739911732.816 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-02-18T20:48:52.853Z,1739911732.853 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-18T20:48:52.856Z,1739911732.856 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-18T20:48:52.863Z,1739911732.863 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-02-18T20:48:52.878Z,1739911732.878 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-02-18T20:48:52.901Z,1739911732.901 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-18T20:48:52.966Z,1739911732.966 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-18T20:48:52.966Z,1739911732.966 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-18T20:48:52.967Z,1739911732.967 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-18T20:48:53.107Z,1739911733.107 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-02-18T20:48:53.121Z,1739911733.121 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-18T20:48:53.124Z,1739911733.124 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-18T20:48:53.130Z,1739911733.130 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-18T20:48:53.133Z,1739911733.133 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-18T20:48:53.134Z,1739911733.134 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-18T20:48:53.198Z,1739911733.198 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-18T20:48:53.220Z,1739911733.220 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-18T20:48:53.265Z,1739911733.265 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-18T20:48:53.265Z,1739911733.265 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-18T20:48:53.266Z,1739911733.266 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-18T20:48:53.303Z,1739911733.303 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-02-18T20:48:53.311Z,1739911733.311 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-02-18T20:48:53.318Z,1739911733.318 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-18T20:48:53.333Z,1739911733.333 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-18T20:48:53.333Z,1739911733.333 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-18T20:48:53.334Z,1739911733.334 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-18T20:48:53.576Z,1739911733.576 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-02-18T20:48:53.589Z,1739911733.589 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-02-18T20:48:53.591Z,1739911733.591 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-02-18T20:48:53.602Z,1739911733.602 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-02-18T20:48:53.609Z,1739911733.609 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-02-18T20:48:53.625Z,1739911733.625 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-18T20:48:53.653Z,1739911733.653 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-18T20:48:53.655Z,1739911733.655 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-18T20:48:53.659Z,1739911733.659 [transit:MassHold.Pitch](DEBUG): Construct.
2025-02-18T20:48:53.679Z,1739911733.679 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-02-18T20:48:53.697Z,1739911733.697 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-02-18T20:48:53.700Z,1739911733.700 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-18T20:48:53.761Z,1739911733.761 [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 transit {
"""
Vehicle transits to desired waypoint and can be commanded to use/hold
mass or buoyancy.
"""
arguments {
MissionTimeout = 60 minute
"""
Maximum duration of mission
"""
NeedCommsTime = 30 minute
"""
How often to surface for commumications
"""
Latitude = NaN degree
"""
Latitude of waypoint to seek. If set to NaN, uses latitude at mission
initialization.
"""
Longitude = NaN degree
"""
Longitude of waypoint to seek. If set to NaN, uses longitude at mission
initialization.
"""
Northings = 0 meter
"""
Northward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Eastings = 0 meter
"""
Eastward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Depth = 10 meter
"""
Depth of flat and level flight during the mission.
"""
Speed = 1 meter_per_second
"""
Speed of vehicle (relative to water) during the mission.
"""
MaxDepth = 20 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 1 kilometer
"""
Minimum distance offshore for the entire mission.
"""
MinAltitude = 7 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MassHold = true
"""
Set to True in order to hold mass at default position, False to allow
mass to run on its own.
"""
BuoyancyHold = true
"""
Set to True in order to hold buoyancy at neutral position, False to
allow buoyancy to run on its own.
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 1 second
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Buoyancy id="BuoyancyHold" {
run while ( BuoyancyHold )
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch id="MassHold" {
run while ( MassHold )
set massPosition = Control:VerticalControl.massDefault
}
aggregate Transit {
run in sequence
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
set northingsDelta = Northings
set eastingsDelta = Eastings
}
call id="PhoneHome" refId="NeedComms"
}
}
2025-02-18T20:48:53.762Z,1739911733.762 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-02-18T20:49:02.079Z,1739911742.079 [NAL9602](INFO): SBD MO Status=0, MOMSN=12671, MT Status=0, MTMSN=0
2025-02-18T20:49:02.079Z,1739911742.079 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:49:23.800Z,1739911763.800 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250218T183632/Courier0055.lzma
2025-02-18T20:49:24.810Z,1739911764.810 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0055.lzma.bak
2025-02-18T20:49:24.810Z,1739911764.810 [DataOverHttps](INFO): SBD MOMSN=23966245
2025-02-18T20:49:31.614Z,1739911771.614 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute
2025-02-18T20:49:31.615Z,1739911771.615 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 12 minute
2025-02-18T20:49:31.616Z,1739911771.616 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2025-02-18T20:49:31.656Z,1739911771.656 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2025-02-18T20:49:31.683Z,1739911771.683 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2025-02-18T20:49:31.683Z,1739911771.683 [CommandExec](IMPORTANT): got command set transit.Speed 1.3 meter_per_second
2025-02-18T20:49:32.578Z,1739911772.578 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:49:45.569Z,1739911785.569 [CommandExec](IMPORTANT): got command set transit.MaxDepth 2.5 meter
2025-02-18T20:49:45.570Z,1739911785.570 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.0001 kilometer
2025-02-18T20:49:45.571Z,1739911785.571 [CommandExec](IMPORTANT): got command set transit.MinAltitude 5 meter
2025-02-18T20:49:45.571Z,1739911785.571 [CommandExec](IMPORTANT): got command run
2025-02-18T20:49:45.597Z,1739911785.597 [CommandExec](IMPORTANT): Running
2025-02-18T20:49:45.935Z,1739911785.935 [Default] Stopped
2025-02-18T20:49:45.935Z,1739911785.935 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-18T20:49:45.935Z,1739911785.935 [Default:B.GoToSurface] Stopped
2025-02-18T20:49:45.935Z,1739911785.935 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-18T20:49:45.936Z,1739911785.936 [Default:CheckIn] Stopped
2025-02-18T20:49:45.936Z,1739911785.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T20:49:45.936Z,1739911785.936 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T20:49:45.936Z,1739911785.936 [MissionManager](IMPORTANT): Started mission transit
2025-02-18T20:49:45.936Z,1739911785.936 [transit] Running Loop=1
2025-02-18T20:49:45.936Z,1739911785.936 [transit](DEBUG): Aggregate::initialize transit
2025-02-18T20:49:45.937Z,1739911785.937 [transit:StandardEnvelopes] Running Loop=1
2025-02-18T20:49:45.937Z,1739911785.937 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-02-18T20:49:45.937Z,1739911785.937 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-18T20:49:45.937Z,1739911785.937 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-18T20:49:45.937Z,1739911785.937 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-18T20:49:45.937Z,1739911785.937 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-18T20:49:45.937Z,1739911785.937 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-18T20:49:45.937Z,1739911785.937 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-18T20:49:45.938Z,1739911785.938 [transit:BackseatDriver] Running Loop=1
2025-02-18T20:49:45.938Z,1739911785.938 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-02-18T20:49:45.938Z,1739911785.938 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-18T20:49:45.938Z,1739911785.938 [transit:PowerOnly] Running Loop=1
2025-02-18T20:49:45.938Z,1739911785.938 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-02-18T20:49:45.938Z,1739911785.938 [transit:PowerOnly:A] Running Loop=1
2025-02-18T20:49:45.938Z,1739911785.938 [transit:PowerOnly:B] Running Loop=1
2025-02-18T20:49:45.938Z,1739911785.938 [transit:PowerOnly:C] Running Loop=1
2025-02-18T20:49:45.938Z,1739911785.938 [transit:PowerOnly:D] Running Loop=1
2025-02-18T20:49:45.938Z,1739911785.938 [transit:PowerOnly:E.Wait] Running Loop=1
2025-02-18T20:49:45.938Z,1739911785.938 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:49:45.939Z,1739911785.939 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-18T20:49:45.939Z,1739911785.939 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-18T20:49:45.939Z,1739911785.939 [transit:MassHold.Pitch] Running Loop=1
2025-02-18T20:49:45.939Z,1739911785.939 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-02-18T20:49:45.939Z,1739911785.939 [transit:B] Running Loop=1
2025-02-18T20:49:45.940Z,1739911785.940 [transit:MassHold.Pitch] Running Loop=1
2025-02-18T20:49:45.956Z,1739911785.956 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-18T20:49:45.957Z,1739911785.957 [transit:PowerOnly] Running Loop=1
2025-02-18T20:49:45.957Z,1739911785.957 [transit:PowerOnly:D] Stopped
2025-02-18T20:49:45.957Z,1739911785.957 [transit:PowerOnly:C] Stopped
2025-02-18T20:49:45.957Z,1739911785.957 [transit:PowerOnly:B] Stopped
2025-02-18T20:49:45.957Z,1739911785.957 [transit:PowerOnly:A] Stopped
2025-02-18T20:49:45.957Z,1739911785.957 [transit:BackseatDriver] Running Loop=1
2025-02-18T20:49:45.958Z,1739911785.958 [transit:StandardEnvelopes] Running Loop=1
2025-02-18T20:49:45.958Z,1739911785.958 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-18T20:49:45.958Z,1739911785.958 [transit:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2025-02-18T20:49:45.978Z,1739911785.978 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-18T20:49:45.979Z,1739911785.979 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-18T20:49:45.980Z,1739911785.980 [transit:B] Stopped
2025-02-18T20:49:45.980Z,1739911785.980 [transit:C] Running Loop=1
2025-02-18T20:49:45.992Z,1739911785.992 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-02-18T20:49:45.993Z,1739911785.993 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-02-18T20:49:45.993Z,1739911785.993 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-02-18T20:49:46.357Z,1739911786.357 [transit:C] Stopped
2025-02-18T20:49:46.357Z,1739911786.357 [transit:E] Running Loop=1
2025-02-18T20:49:46.743Z,1739911786.743 [transit:E] Stopped
2025-02-18T20:49:46.744Z,1739911786.744 [transit:F] Running Loop=1
2025-02-18T20:49:47.167Z,1739911787.167 [transit:F] Stopped
2025-02-18T20:49:47.167Z,1739911787.167 [transit:G] Running Loop=1
2025-02-18T20:49:47.543Z,1739911787.543 [transit:G] Stopped
2025-02-18T20:49:47.543Z,1739911787.543 [transit:Transit] Running Loop=1
2025-02-18T20:49:47.543Z,1739911787.543 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-02-18T20:49:47.543Z,1739911787.543 [transit:Transit:A.Pitch] Running Loop=1
2025-02-18T20:49:47.543Z,1739911787.543 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-02-18T20:49:47.543Z,1739911787.543 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-18T20:49:47.544Z,1739911787.544 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-02-18T20:49:47.544Z,1739911787.544 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-02-18T20:49:47.544Z,1739911787.544 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-18T20:49:47.630Z,1739911787.630 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-18T20:49:47.631Z,1739911787.631 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-18T20:49:47.979Z,1739911787.979 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-18T20:49:47.979Z,1739911787.979 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-18T20:49:47.980Z,1739911787.980 [transit:Transit:A.Pitch] Running Loop=1
2025-02-18T20:49:51.253Z,1739911791.253 [DataOverHttps](INFO): Sending 380 bytes from file Logs/20250218T183632/Express0053.lzma
2025-02-18T20:49:52.254Z,1739911792.254 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0053.lzma.bak
2025-02-18T20:49:52.254Z,1739911792.254 [DataOverHttps](INFO): SBD MOMSN=23966247
2025-02-18T20:54:43.455Z,1739912083.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T20:54:56.363Z,1739912096.363 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T20:55:09.298Z,1739912109.298 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T20:55:22.632Z,1739912122.632 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T20:55:35.209Z,1739912135.209 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T20:55:48.139Z,1739912148.139 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T20:56:00.662Z,1739912160.662 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-18T20:56:09.690Z,1739912169.690 [DataOverHttps](IMPORTANT): SBD MTMSN=20250218T205608
2025-02-18T20:56:21.372Z,1739912181.372 [DataOverHttps](INFO): Received command: stop
2025-02-18T20:56:21.429Z,1739912181.429 [CommandExec](IMPORTANT): got command stop
2025-02-18T20:56:21.429Z,1739912181.429 [CommandExec](IMPORTANT): Scheduling is paused
2025-02-18T20:56:21.429Z,1739912181.429 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-02-18T20:56:21.745Z,1739912181.745 [MissionManager](INFO): MissionManager is completed.
2025-02-18T20:56:21.745Z,1739912181.745 [MissionManager](INFO): Uninitializing Mission transit
2025-02-18T20:56:21.745Z,1739912181.745 [transit] Stopped
2025-02-18T20:56:21.746Z,1739912181.746 [transit](DEBUG): Aggregate::uninitialize transit
2025-02-18T20:56:21.746Z,1739912181.746 [transit:StandardEnvelopes] Stopped
2025-02-18T20:56:21.746Z,1739912181.746 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-02-18T20:56:21.746Z,1739912181.746 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-18T20:56:21.746Z,1739912181.746 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-18T20:56:21.746Z,1739912181.746 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-18T20:56:21.746Z,1739912181.746 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-18T20:56:21.746Z,1739912181.746 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-18T20:56:21.746Z,1739912181.746 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-18T20:56:21.746Z,1739912181.746 [transit:BackseatDriver] Stopped
2025-02-18T20:56:21.746Z,1739912181.746 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-02-18T20:56:21.746Z,1739912181.746 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-02-18T20:56:21.746Z,1739912181.746 [transit:PowerOnly] Stopped
2025-02-18T20:56:21.747Z,1739912181.747 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-02-18T20:56:21.747Z,1739912181.747 [transit:PowerOnly:E.Wait] Stopped
2025-02-18T20:56:21.747Z,1739912181.747 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:56:21.747Z,1739912181.747 [transit:BuoyancyHold.Buoyancy] Stopped
2025-02-18T20:56:21.747Z,1739912181.747 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-18T20:56:21.747Z,1739912181.747 [transit:MassHold.Pitch] Stopped
2025-02-18T20:56:21.747Z,1739912181.747 [transit:Transit] Stopped
2025-02-18T20:56:21.747Z,1739912181.747 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-02-18T20:56:21.747Z,1739912181.747 [transit:Transit:A.Pitch] Stopped
2025-02-18T20:56:21.747Z,1739912181.747 [transit:Transit:B.SetSpeed] Stopped
2025-02-18T20:56:21.747Z,1739912181.747 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-02-18T20:56:21.747Z,1739912181.747 [transit:Transit:Wpt1.Waypoint] Stopped
2025-02-18T20:56:21.747Z,1739912181.747 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-18T20:56:22.126Z,1739912182.126 [MissionManager](IMPORTANT): Started mission Default
2025-02-18T20:56:22.127Z,1739912182.127 [Default] Running Loop=1
2025-02-18T20:56:22.127Z,1739912182.127 [Default](DEBUG): Aggregate::initialize Default
2025-02-18T20:56:22.127Z,1739912182.127 [Default:B.GoToSurface] Running Loop=1
2025-02-18T20:56:22.127Z,1739912182.127 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-18T20:56:22.127Z,1739912182.127 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-18T20:56:22.127Z,1739912182.127 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-18T20:56:22.128Z,1739912182.128 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-18T20:56:22.129Z,1739912182.129 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-18T20:56:22.130Z,1739912182.130 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-18T20:56:22.130Z,1739912182.130 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-18T20:56:22.130Z,1739912182.130 [Default:A.Wait] Running Loop=1
2025-02-18T20:56:22.130Z,1739912182.130 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:56:35.467Z,1739912195.467 [Default:A.Wait](INFO): Done Waiting.
2025-02-18T20:56:35.467Z,1739912195.467 [Default:A.Wait] Stopped
2025-02-18T20:56:35.467Z,1739912195.467 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T20:56:35.867Z,1739912195.867 [Default:CheckIn] Running Loop=1
2025-02-18T20:56:35.867Z,1739912195.867 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-18T20:56:35.867Z,1739912195.867 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-18T20:56:37.866Z,1739912197.866 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205637.00,A,3648.46797,N,12147.25352,W,3.343,207.46,180225,,,A*75
2025-02-18T20:56:37.869Z,1739912197.869 [NAL9602](INFO): GPS fix at 20250218T205637: (36.807800, -121.787559)
2025-02-18T20:56:37.885Z,1739912197.885 [UniversalFixResidualReporter](INFO): Fix residual: 10.5 %DT, over the last 502.6 m. Residual distance 52.6 m at bearing 166.5 degrees. Fix at (36.8078, -121.7876) with 371.8 m made good.
2025-02-18T20:56:37.886Z,1739912197.886 [Default:CheckIn:Read_GPS] Stopped
2025-02-18T20:56:37.886Z,1739912197.886 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-18T20:56:47.977Z,1739912207.977 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250218T183632/Courier0061.lzma
2025-02-18T20:56:48.820Z,1739912208.820 [NAL9602](INFO): SBD MO Status=0, MOMSN=12672, MT Status=0, MTMSN=0
2025-02-18T20:56:48.820Z,1739912208.820 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:56:48.979Z,1739912208.979 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0061.lzma.bak
2025-02-18T20:56:48.980Z,1739912208.980 [DataOverHttps](INFO): SBD MOMSN=23966257
2025-02-18T20:57:16.728Z,1739912236.728 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250218T183632/Express0056.lzma
2025-02-18T20:57:17.731Z,1739912237.731 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0056.lzma.bak
2025-02-18T20:57:17.731Z,1739912237.731 [DataOverHttps](INFO): SBD MOMSN=23966261
2025-02-18T20:57:19.438Z,1739912239.438 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T20:57:35.865Z,1739912255.865 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20250218T183632/Express0059.lzma
2025-02-18T20:57:36.866Z,1739912256.866 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0059.lzma.bak
2025-02-18T20:57:36.867Z,1739912256.867 [DataOverHttps](INFO): SBD MOMSN=23966263
2025-02-18T20:57:53.106Z,1739912273.106 [DataOverHttps](INFO): Sending 556 bytes from file Logs/20250218T183632/Express0062.lzma
2025-02-18T20:57:55.213Z,1739912275.213 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0062.lzma.bak
2025-02-18T20:57:55.213Z,1739912275.213 [DataOverHttps](INFO): SBD MOMSN=23966269
2025-02-18T20:57:55.479Z,1739912275.479 [Default:CheckIn:Read_Iridium] Stopped
2025-02-18T20:57:55.480Z,1739912275.480 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-18T20:57:55.480Z,1739912275.480 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-18T20:59:07.859Z,1739912347.859 [DataOverHttps](IMPORTANT): SBD MTMSN=20250218T205907
2025-02-18T20:59:21.519Z,1739912361.519 [DataOverHttps](INFO): Received command: ibit
2025-02-18T20:59:21.542Z,1739912361.542 [CommandExec](IMPORTANT): got command ibit
2025-02-18T20:59:21.861Z,1739912361.861 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-02-18T20:59:21.861Z,1739912361.861 [IBIT](IMPORTANT): Beginning control surface checks.
2025-02-18T20:59:21.865Z,1739912361.865 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-18T20:59:23.469Z,1739912363.469 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205922.00,A,3648.39373,N,12147.40148,W,3.227,261.50,180225,,,A*76
2025-02-18T20:59:23.471Z,1739912363.471 [NAL9602](INFO): GPS fix at 20250218T205922: (36.806562, -121.790025)
2025-02-18T20:59:24.749Z,1739912364.749 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2025-02-18T20:59:32.780Z,1739912372.780 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000952
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): 0.000257
CHAN A3 (5V): -0.002056
CHAN B0 (3.3V): -0.001584
CHAN B1 (3.15aV): -0.001381
CHAN B2 (3.15bV): -0.001291
CHAN B3 (GND): -0.002046
OPEN: 0.005073
Full Scale: +/- 1 mA
2025-02-18T20:59:36.381Z,1739912376.381 [NAL9602](INFO): SBD MO Status=0, MOMSN=12673, MT Status=0, MTMSN=0
2025-02-18T20:59:36.381Z,1739912376.381 [NAL9602](INFO): No messages in MT queue
2025-02-18T20:59:37.582Z,1739912377.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205936.00,A,3648.38963,N,12147.41572,W,4.665,232.97,180225,,,A*7D
2025-02-18T20:59:37.584Z,1739912377.584 [NAL9602](INFO): GPS fix at 20250218T205936: (36.806494, -121.790262)
2025-02-18T20:59:40.815Z,1739912380.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205940.00,A,3648.38749,N,12147.41876,W,1.788,226.57,180225,,,A*7D
2025-02-18T20:59:40.817Z,1739912380.817 [NAL9602](INFO): GPS fix at 20250218T205940: (36.806458, -121.790313)
2025-02-18T20:59:44.074Z,1739912384.074 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205943.00,A,3648.38588,N,12147.42106,W,3.752,234.56,180225,,,A*7B
2025-02-18T20:59:44.076Z,1739912384.076 [NAL9602](INFO): GPS fix at 20250218T205943: (36.806431, -121.790351)
2025-02-18T20:59:46.881Z,1739912386.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205946.00,A,3648.38498,N,12147.42210,W,0.622,227.81,180225,,,A*77
2025-02-18T20:59:46.883Z,1739912386.883 [NAL9602](INFO): GPS fix at 20250218T205946: (36.806416, -121.790368)
2025-02-18T20:59:50.152Z,1739912390.152 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205949.00,A,3648.38342,N,12147.42474,W,2.663,223.72,180225,,,A*73
2025-02-18T20:59:50.155Z,1739912390.155 [NAL9602](INFO): GPS fix at 20250218T205949: (36.806390, -121.790412)
2025-02-18T20:59:52.956Z,1739912392.956 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205952.00,A,3648.38182,N,12147.42582,W,2.624,204.46,180225,,,A*7E
2025-02-18T20:59:52.958Z,1739912392.958 [NAL9602](INFO): GPS fix at 20250218T205952: (36.806364, -121.790430)
2025-02-18T20:59:55.767Z,1739912395.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205955.00,A,3648.38062,N,12147.42944,W,3.032,233.50,180225,,,A*73
2025-02-18T20:59:55.770Z,1739912395.770 [NAL9602](INFO): GPS fix at 20250218T205955: (36.806344, -121.790491)
2025-02-18T20:59:58.997Z,1739912398.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205958.00,A,3648.38102,N,12147.43212,W,2.527,262.71,180225,,,A*77
2025-02-18T20:59:58.999Z,1739912398.999 [NAL9602](INFO): GPS fix at 20250218T205958: (36.806350, -121.790535)
2025-02-18T21:00:01.836Z,1739912401.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210001.00,A,3648.38083,N,12147.43457,W,1.886,272.83,180225,,,A*70
2025-02-18T21:00:01.840Z,1739912401.840 [NAL9602](INFO): GPS fix at 20250218T210001: (36.806347, -121.790576)
2025-02-18T21:00:05.058Z,1739912405.058 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210004.00,A,3648.38080,N,12147.43689,W,1.341,267.17,180225,,,A*7E
2025-02-18T21:00:05.069Z,1739912405.069 [NAL9602](INFO): GPS fix at 20250218T210004: (36.806347, -121.790615)
2025-02-18T21:00:07.884Z,1739912407.884 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210007.00,A,3648.38063,N,12147.43924,W,2.255,260.76,180225,,,A*7F
2025-02-18T21:00:07.886Z,1739912407.886 [NAL9602](INFO): GPS fix at 20250218T210007: (36.806344, -121.790654)
2025-02-18T21:00:07.917Z,1739912407.917 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.806343 Longitude: -121.790657
2025-02-18T21:00:08.366Z,1739912408.366 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 265.115997
Voltage: 15.779000
2025-02-18T21:00:08.366Z,1739912408.366 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-02-18T21:00:08.367Z,1739912408.367 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-02-18T21:00:08.718Z,1739912408.718 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-02-18T21:00:08.718Z,1739912408.718 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-02-18T21:00:08.719Z,1739912408.719 [IBIT](IMPORTANT): Pressure:9.932270 PSI
2025-02-18T21:00:08.719Z,1739912408.719 [IBIT](IMPORTANT): Humidity:1.892321 %
2025-02-18T21:00:09.136Z,1739912409.136 [IBIT](IMPORTANT): Vehicle Pitch:-4.047883 degrees
2025-02-18T21:00:09.137Z,1739912409.137 [IBIT](IMPORTANT): Vehicle Roll:-15.631405 degrees
2025-02-18T21:00:09.137Z,1739912409.137 [IBIT](IMPORTANT): Vehicle Heading:277.697113 degrees
2025-02-18T21:00:09.537Z,1739912409.537 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-02-18T21:00:09.537Z,1739912409.537 [IBIT](IMPORTANT): buoyancyNeutral: 345.701630 cc
2025-02-18T21:00:09.537Z,1739912409.537 [IBIT](IMPORTANT): massDefault: 1.024720 cm
2025-02-18T21:00:09.537Z,1739912409.537 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-02-18T21:00:09.538Z,1739912409.538 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-02-18T21:00:09.538Z,1739912409.538 [IBIT](IMPORTANT): IBIT PASSED
2025-02-18T21:00:40.651Z,1739912440.651 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T21:01:06.515Z,1739912466.515 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-18T21:01:08.964Z,1739912468.964 [BPC1](FAULT): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-18T21:01:08.973Z,1739912468.973 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-18T21:01:08.975Z,1739912468.975 [BPC1](INFO): Received data from all battery sticks.
2025-02-18T21:01:25.239Z,1739912485.239 [DataOverHttps](IMPORTANT): SBD MTMSN=20250218T210124
2025-02-18T21:01:35.669Z,1739912495.669 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 1 m;set transit.Speed 1.3 m/s" 1y51e 1 2
2025-02-18T21:01:35.703Z,1739912495.703 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 1 m;set transit.Speed 1.3 m/s" 1y51e 1 2.000000
2025-02-18T21:01:35.704Z,1739912495.704 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1y51e
2025-02-18T21:01:35.705Z,1739912495.705 [CommandExec](IMPORTANT): Scheduled #3 (#1 of 2 with id='1y51e'): "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 1 m;set transit.Speed 1.3 m/s", ASAP
2025-02-18T21:01:36.670Z,1739912496.670 [DataOverHttps](IMPORTANT): SBD MTMSN=20250218T210135
2025-02-18T21:01:47.204Z,1739912507.204 [DataOverHttps](INFO): Received command: sched asap "set transit.MaxDepth 2.5 m;set transit.MinOffshore 0.0001 km;set transit.MinAltitude 5 m;run" 1y51e 2 2
2025-02-18T21:01:47.273Z,1739912507.273 [CommandExec](IMPORTANT): got command schedule asap "set transit.MaxDepth 2.5 m;set transit.MinOffshore 0.0001 km;set transit.MinAltitude 5 m;run" 1y51e 2 2.000000
2025-02-18T21:01:47.273Z,1739912507.273 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1y51e
2025-02-18T21:01:47.274Z,1739912507.274 [CommandExec](IMPORTANT): Scheduled #4 (#2 of 2 with id='1y51e'): "set transit.MaxDepth 2.5 m;set transit.MinOffshore 0.0001 km;set transit.MinAltitude 5 m;run", ASAP
2025-02-18T21:01:47.386Z,1739912507.386 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-02-18T21:01:47.388Z,1739912507.388 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-02-18T21:01:47.388Z,1739912507.388 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-02-18T21:01:47.389Z,1739912507.389 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-02-18T21:01:48.672Z,1739912508.672 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2025-02-18T21:01:48.713Z,1739912508.713 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-02-18T21:01:48.715Z,1739912508.715 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-02-18T21:01:48.734Z,1739912508.734 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-02-18T21:01:48.736Z,1739912508.736 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-02-18T21:01:48.742Z,1739912508.742 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-02-18T21:01:48.776Z,1739912508.776 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-02-18T21:01:48.779Z,1739912508.779 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-02-18T21:01:48.813Z,1739912508.813 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-02-18T21:01:48.816Z,1739912508.816 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-02-18T21:01:48.826Z,1739912508.826 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-02-18T21:01:48.837Z,1739912508.837 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-02-18T21:01:48.839Z,1739912508.839 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-02-18T21:01:48.850Z,1739912508.850 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-02-18T21:01:48.850Z,1739912508.850 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-18T21:01:48.850Z,1739912508.850 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-18T21:01:48.851Z,1739912508.851 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-18T21:01:49.395Z,1739912509.395 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-02-18T21:01:49.401Z,1739912509.401 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-02-18T21:01:49.403Z,1739912509.403 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-18T21:01:49.414Z,1739912509.414 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-18T21:01:49.416Z,1739912509.416 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-02-18T21:01:49.418Z,1739912509.418 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-18T21:01:49.420Z,1739912509.420 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-18T21:01:49.423Z,1739912509.423 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-02-18T21:01:49.425Z,1739912509.425 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-02-18T21:01:49.480Z,1739912509.480 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-18T21:01:49.594Z,1739912509.594 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-18T21:01:49.594Z,1739912509.594 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-18T21:01:49.610Z,1739912509.610 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-18T21:01:49.813Z,1739912509.813 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-02-18T21:01:49.817Z,1739912509.817 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-18T21:01:49.820Z,1739912509.820 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-18T21:01:49.823Z,1739912509.823 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-18T21:01:49.825Z,1739912509.825 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-18T21:01:49.826Z,1739912509.826 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-18T21:01:49.841Z,1739912509.841 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-18T21:01:49.893Z,1739912509.893 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-18T21:01:49.958Z,1739912509.958 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-18T21:01:49.958Z,1739912509.958 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-18T21:01:49.959Z,1739912509.959 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-18T21:01:50.036Z,1739912510.036 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-02-18T21:01:50.051Z,1739912510.051 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-02-18T21:01:50.058Z,1739912510.058 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-18T21:01:50.073Z,1739912510.073 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-18T21:01:50.073Z,1739912510.073 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-18T21:01:50.075Z,1739912510.075 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-18T21:01:50.370Z,1739912510.370 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-02-18T21:01:50.386Z,1739912510.386 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-02-18T21:01:50.392Z,1739912510.392 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-02-18T21:01:50.394Z,1739912510.394 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-02-18T21:01:50.399Z,1739912510.399 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-02-18T21:01:50.410Z,1739912510.410 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-18T21:01:50.447Z,1739912510.447 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-18T21:01:50.461Z,1739912510.461 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-18T21:01:50.464Z,1739912510.464 [transit:MassHold.Pitch](DEBUG): Construct.
2025-02-18T21:01:50.490Z,1739912510.490 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-02-18T21:01:50.507Z,1739912510.507 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-02-18T21:01:50.514Z,1739912510.514 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-18T21:01:50.567Z,1739912510.567 [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 transit {
"""
Vehicle transits to desired waypoint and can be commanded to use/hold
mass or buoyancy.
"""
arguments {
MissionTimeout = 60 minute
"""
Maximum duration of mission
"""
NeedCommsTime = 30 minute
"""
How often to surface for commumications
"""
Latitude = NaN degree
"""
Latitude of waypoint to seek. If set to NaN, uses latitude at mission
initialization.
"""
Longitude = NaN degree
"""
Longitude of waypoint to seek. If set to NaN, uses longitude at mission
initialization.
"""
Northings = 0 meter
"""
Northward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Eastings = 0 meter
"""
Eastward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Depth = 10 meter
"""
Depth of flat and level flight during the mission.
"""
Speed = 1 meter_per_second
"""
Speed of vehicle (relative to water) during the mission.
"""
MaxDepth = 20 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 1 kilometer
"""
Minimum distance offshore for the entire mission.
"""
MinAltitude = 7 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MassHold = true
"""
Set to True in order to hold mass at default position, False to allow
mass to run on its own.
"""
BuoyancyHold = true
"""
Set to True in order to hold buoyancy at neutral position, False to
allow buoyancy to run on its own.
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 1 second
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Buoyancy id="BuoyancyHold" {
run while ( BuoyancyHold )
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch id="MassHold" {
run while ( MassHold )
set massPosition = Control:VerticalControl.massDefault
}
aggregate Transit {
run in sequence
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
set northingsDelta = Northings
set eastingsDelta = Eastings
}
call id="PhoneHome" refId="NeedComms"
}
}
2025-02-18T21:01:50.568Z,1739912510.568 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-02-18T21:02:07.252Z,1739912527.252 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute
2025-02-18T21:02:07.253Z,1739912527.253 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 12 minute
2025-02-18T21:02:07.254Z,1739912527.254 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2025-02-18T21:02:07.254Z,1739912527.254 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2025-02-18T21:02:07.255Z,1739912527.255 [CommandExec](IMPORTANT): got command set transit.Depth 1 meter
2025-02-18T21:02:07.256Z,1739912527.256 [CommandExec](IMPORTANT): got command set transit.Speed 1.3 meter_per_second
2025-02-18T21:02:18.486Z,1739912538.486 [CommandExec](IMPORTANT): got command set transit.MaxDepth 2.5 meter
2025-02-18T21:02:18.486Z,1739912538.486 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.0001 kilometer
2025-02-18T21:02:18.487Z,1739912538.487 [CommandExec](IMPORTANT): got command set transit.MinAltitude 5 meter
2025-02-18T21:02:18.488Z,1739912538.488 [CommandExec](IMPORTANT): got command run
2025-02-18T21:02:18.521Z,1739912538.521 [CommandExec](IMPORTANT): Running
2025-02-18T21:02:18.684Z,1739912538.684 [Default] Stopped
2025-02-18T21:02:18.684Z,1739912538.684 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-18T21:02:18.684Z,1739912538.684 [Default:B.GoToSurface] Stopped
2025-02-18T21:02:18.684Z,1739912538.684 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-18T21:02:18.684Z,1739912538.684 [Default:CheckIn] Stopped
2025-02-18T21:02:18.684Z,1739912538.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-18T21:02:18.685Z,1739912538.685 [Default:CheckIn:C.Wait] Stopped
2025-02-18T21:02:18.685Z,1739912538.685 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-18T21:02:18.685Z,1739912538.685 [MissionManager](IMPORTANT): Started mission transit
2025-02-18T21:02:18.685Z,1739912538.685 [transit] Running Loop=1
2025-02-18T21:02:18.685Z,1739912538.685 [transit](DEBUG): Aggregate::initialize transit
2025-02-18T21:02:18.685Z,1739912538.685 [transit:StandardEnvelopes] Running Loop=1
2025-02-18T21:02:18.685Z,1739912538.685 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-02-18T21:02:18.685Z,1739912538.685 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-18T21:02:18.686Z,1739912538.686 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-18T21:02:18.686Z,1739912538.686 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-18T21:02:18.686Z,1739912538.686 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-18T21:02:18.686Z,1739912538.686 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-18T21:02:18.686Z,1739912538.686 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-18T21:02:18.686Z,1739912538.686 [transit:BackseatDriver] Running Loop=1
2025-02-18T21:02:18.686Z,1739912538.686 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-02-18T21:02:18.686Z,1739912538.686 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-18T21:02:18.686Z,1739912538.686 [transit:PowerOnly] Running Loop=1
2025-02-18T21:02:18.687Z,1739912538.687 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-02-18T21:02:18.687Z,1739912538.687 [transit:PowerOnly:A] Running Loop=1
2025-02-18T21:02:18.687Z,1739912538.687 [transit:PowerOnly:B] Running Loop=1
2025-02-18T21:02:18.687Z,1739912538.687 [transit:PowerOnly:C] Running Loop=1
2025-02-18T21:02:18.687Z,1739912538.687 [transit:PowerOnly:D] Running Loop=1
2025-02-18T21:02:18.687Z,1739912538.687 [transit:PowerOnly:E.Wait] Running Loop=1
2025-02-18T21:02:18.687Z,1739912538.687 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-18T21:02:18.687Z,1739912538.687 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-18T21:02:18.687Z,1739912538.687 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-18T21:02:18.687Z,1739912538.687 [transit:MassHold.Pitch] Running Loop=1
2025-02-18T21:02:18.687Z,1739912538.687 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-02-18T21:02:18.688Z,1739912538.688 [transit:B] Running Loop=1
2025-02-18T21:02:18.688Z,1739912538.688 [transit:MassHold.Pitch] Running Loop=1
2025-02-18T21:02:18.689Z,1739912538.689 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-18T21:02:18.689Z,1739912538.689 [transit:PowerOnly] Running Loop=1
2025-02-18T21:02:18.689Z,1739912538.689 [transit:PowerOnly:D] Stopped
2025-02-18T21:02:18.689Z,1739912538.689 [transit:PowerOnly:C] Stopped
2025-02-18T21:02:18.689Z,1739912538.689 [transit:PowerOnly:B] Stopped
2025-02-18T21:02:18.689Z,1739912538.689 [transit:PowerOnly:A] Stopped
2025-02-18T21:02:18.689Z,1739912538.689 [transit:BackseatDriver] Running Loop=1
2025-02-18T21:02:18.690Z,1739912538.690 [transit:StandardEnvelopes] Running Loop=1
2025-02-18T21:02:18.690Z,1739912538.690 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-18T21:02:18.690Z,1739912538.690 [transit:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2025-02-18T21:02:18.698Z,1739912538.698 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-18T21:02:18.699Z,1739912538.699 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-18T21:02:18.699Z,1739912538.699 [transit:B] Stopped
2025-02-18T21:02:18.699Z,1739912538.699 [transit:C] Running Loop=1
2025-02-18T21:02:19.095Z,1739912539.095 [transit:C] Stopped
2025-02-18T21:02:19.096Z,1739912539.096 [transit:E] Running Loop=1
2025-02-18T21:02:19.486Z,1739912539.486 [transit:E] Stopped
2025-02-18T21:02:19.486Z,1739912539.486 [transit:F] Running Loop=1
2025-02-18T21:02:19.895Z,1739912539.895 [transit:F] Stopped
2025-02-18T21:02:19.895Z,1739912539.895 [transit:G] Running Loop=1
2025-02-18T21:02:20.300Z,1739912540.300 [transit:G] Stopped
2025-02-18T21:02:20.300Z,1739912540.300 [transit:Transit] Running Loop=1
2025-02-18T21:02:20.301Z,1739912540.301 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-02-18T21:02:20.301Z,1739912540.301 [transit:Transit:A.Pitch] Running Loop=1
2025-02-18T21:02:20.301Z,1739912540.301 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-02-18T21:02:20.301Z,1739912540.301 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-18T21:02:20.301Z,1739912540.301 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-02-18T21:02:20.301Z,1739912540.301 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-02-18T21:02:20.301Z,1739912540.301 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-18T21:02:20.303Z,1739912540.303 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-18T21:02:20.303Z,1739912540.303 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-18T21:02:20.699Z,1739912540.699 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-18T21:02:20.700Z,1739912540.700 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-18T21:02:20.700Z,1739912540.700 [transit:Transit:A.Pitch] Running Loop=1
2025-02-18T21:03:39.433Z,1739912619.433 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:04:10.609Z,1739912650.609 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:06:15.501Z,1739912775.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:06:46.639Z,1739912806.639 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:09:21.661Z,1739912961.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:09:52.785Z,1739912992.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:10:23.909Z,1739913023.909 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:10:55.033Z,1739913055.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:11:26.173Z,1739913086.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:11:29.185Z,1739913089.185 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759460
2025-02-18T21:12:09.276Z,1739913129.276 [transit:NeedComms] Running Loop=1
2025-02-18T21:12:09.277Z,1739913129.277 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-02-18T21:12:09.277Z,1739913129.277 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-02-18T21:12:09.277Z,1739913129.277 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-18T21:12:09.277Z,1739913129.277 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-18T21:12:09.277Z,1739913129.277 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-18T21:12:09.278Z,1739913129.278 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-18T21:12:09.278Z,1739913129.278 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-18T21:12:09.278Z,1739913129.278 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-18T21:12:09.279Z,1739913129.279 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-18T21:12:09.279Z,1739913129.279 [transit:NeedComms:A] Running Loop=1
2025-02-18T21:12:09.281Z,1739913129.281 [transit:NeedComms:A](INFO): last time_fix was: 1739912407.000000 second since 1970/01/01T00:00:00Z
2025-02-18T21:12:09.281Z,1739913129.281 [transit:NeedComms:A] Stopped
2025-02-18T21:12:09.667Z,1739913129.667 [transit:NeedComms:C] Running Loop=1
2025-02-18T21:12:10.119Z,1739913130.119 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-18T21:12:11.727Z,1739913131.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211211.00,A,3648.23218,N,12147.88923,W,1.808,251.93,180225,,,A*77
2025-02-18T21:12:11.730Z,1739913131.730 [NAL9602](INFO): GPS fix at 20250218T211211: (36.803870, -121.798154)
2025-02-18T21:12:11.742Z,1739913131.742 [UniversalFixResidualReporter](INFO): Fix residual: 18.2 %DT, over the last 729.5 m. Residual distance 132.9 m at bearing -17.6 degrees. Fix at (36.8039, -121.7982) with 722.2 m made good.
2025-02-18T21:12:11.746Z,1739913131.746 [transit:NeedComms:C] Stopped
2025-02-18T21:12:11.746Z,1739913131.746 [transit:NeedComms:D] Running Loop=1
2025-02-18T21:12:12.153Z,1739913132.153 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-18T21:12:27.421Z,1739913147.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:12:45.693Z,1739913165.693 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T21:12:58.545Z,1739913178.545 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:13:29.669Z,1739913209.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:13:58.969Z,1739913238.968 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003035
2025-02-18T21:14:06.328Z,1739913246.328 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20250218T183632/Courier0064.lzma
2025-02-18T21:14:07.331Z,1739913247.331 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0064.lzma.bak
2025-02-18T21:14:07.331Z,1739913247.331 [DataOverHttps](INFO): SBD MOMSN=23966296
2025-02-18T21:14:44.752Z,1739913284.752 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250218T183632/Courier0070.lzma
2025-02-18T21:14:45.754Z,1739913285.754 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Courier0070.lzma.bak
2025-02-18T21:14:45.755Z,1739913285.755 [DataOverHttps](INFO): SBD MOMSN=23966299
2025-02-18T21:15:12.828Z,1739913312.828 [DataOverHttps](INFO): Sending 1359 bytes from file Logs/20250218T183632/Express0066.lzma
2025-02-18T21:15:13.602Z,1739913313.602 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0066.lzma.bak
2025-02-18T21:15:13.603Z,1739913313.603 [DataOverHttps](INFO): SBD MOMSN=23966301
2025-02-18T21:15:46.153Z,1739913346.153 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20250218T183632/Express0071.lzma
2025-02-18T21:15:47.006Z,1739913347.006 [DataOverHttps](INFO): Moved sent file to Logs/20250218T183632/Express0071.lzma.bak
2025-02-18T21:15:47.006Z,1739913347.006 [DataOverHttps](INFO): SBD MOMSN=23966351
2025-02-18T21:15:48.869Z,1739913348.869 [transit:NeedComms:D] Stopped
2025-02-18T21:15:48.882Z,1739913348.882 [transit:NeedComms:E] Running Loop=1
2025-02-18T21:15:49.264Z,1739913349.264 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-18T21:15:50.811Z,1739913350.811 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211550.00,A,3648.21795,N,12147.89177,W,0.019,156.44,180225,,,A*78
2025-02-18T21:15:51.020Z,1739913351.020 [NAL9602](INFO): GPS fix at 20250218T211550: (36.803632, -121.798196)
2025-02-18T21:15:51.065Z,1739913351.065 [transit:NeedComms:E] Stopped
2025-02-18T21:15:51.066Z,1739913351.066 [transit:NeedComms](INFO): Completed transit:NeedComms
2025-02-18T21:15:51.066Z,1739913351.066 [transit:NeedComms] Stopped
2025-02-18T21:15:51.066Z,1739913351.066 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-02-18T21:15:51.066Z,1739913351.066 [transit:NeedComms:B.GoToSurface] Stopped
2025-02-18T21:15:51.066Z,1739913351.066 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-18T21:15:55.888Z,1739913355.888 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-02-18T21:16:09.400Z,1739913369.400 [NAL9602](INFO): SBD MO Status=0, MOMSN=12674, MT Status=0, MTMSN=0
2025-02-18T21:16:09.400Z,1739913369.400 [NAL9602](INFO): No messages in MT queue
2025-02-18T21:16:27.186Z,1739913387.186 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.30 m/s, pitch 20.00 deg, mass-position 10.25 mm (1 active estimators).
2025-02-18T21:16:39.953Z,1739913399.953 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-18T21:19:53.876Z,1739913593.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:20:25.029Z,1739913625.029 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:20:56.158Z,1739913656.158 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:21:27.321Z,1739913687.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:21:58.445Z,1739913718.445 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:22:12.501Z,1739913732.501 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.698959
2025-02-18T21:22:59.706Z,1739913779.706 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:23:30.837Z,1739913810.837 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:24:01.961Z,1739913841.961 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:24:33.085Z,1739913873.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:25:04.209Z,1739913904.209 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:25:35.333Z,1739913935.333 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:26:06.457Z,1739913966.457 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:26:19.212Z,1739913979.212 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-02-18T21:26:19.212Z,1739913979.212 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-02-18T21:26:19.213Z,1739913979.213 [ThrusterHE] Communications Fault, FailCount= 1
2025-02-18T21:26:19.213Z,1739913979.213 [ThrusterHE](ERROR): Communications Fault
2025-02-18T21:26:19.233Z,1739913979.233 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-02-18T21:26:19.487Z,1739913979.487 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-02-18T21:26:19.487Z,1739913979.487 [ThrusterHE](INFO): Powering down
2025-02-18T21:26:20.422Z,1739913980.422 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-02-18T21:26:20.541Z,1739913980.541 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-02-18T21:26:20.545Z,1739913980.545 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-02-18T21:26:20.545Z,1739913980.545 [ThrusterHE] No Fault, FailCount= 1
2025-02-18T21:26:27.848Z,1739913987.848 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-02-18T21:26:27.937Z,1739913987.937 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-02-18T21:26:27.937Z,1739913987.937 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-02-18T21:26:27.937Z,1739913987.937 [ThrusterHE] Communications Fault, FailCount= 2
2025-02-18T21:26:27.937Z,1739913987.937 [ThrusterHE](ERROR): Communications Fault
2025-02-18T21:26:27.941Z,1739913987.941 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-02-18T21:26:28.018Z,1739913988.018 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-02-18T21:26:28.018Z,1739913988.018 [ThrusterHE](INFO): Powering down
2025-02-18T21:26:28.934Z,1739913988.934 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-02-18T21:26:29.053Z,1739913989.053 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-02-18T21:26:29.057Z,1739913989.057 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-02-18T21:26:29.057Z,1739913989.057 [ThrusterHE] No Fault, FailCount= 2
2025-02-18T21:26:37.581Z,1739913997.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:27:08.711Z,1739914028.711 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:27:39.834Z,1739914059.834 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-18T21:27:52.222Z,1739914072.222 [transit:NeedComms] Running Loop=1
2025-02-18T21:27:52.222Z,1739914072.222 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-02-18T21:27:52.222Z,1739914072.222 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-02-18T21:27:52.222Z,1739914072.222 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-18T21:27:52.223Z,1739914072.223 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-18T21:27:52.223Z,1739914072.223 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-18T21:27:52.223Z,1739914072.223 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-18T21:27:52.224Z,1739914072.224 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-18T21:27:52.282Z,1739914072.282 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-18T21:27:52.283Z,1739914072.283 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-18T21:27:52.286Z,1739914072.286 [transit:NeedComms:A] Running Loop=1
2025-02-18T21:27:52.297Z,1739914072.297 [transit:NeedComms:A](INFO): last time_fix was: 1739913350.000000 second since 1970/01