2024-07-08T16:22:15.312Z,1720455735.312 [Supervisor](DEBUG): Initializing supervisor.
2024-07-08T16:22:15.316Z,1720455735.316 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-07-08T16:22:15.317Z,1720455735.317 [SyncHandler](INFO): Protected caller Thread ID is 8726
2024-07-08T16:22:15.317Z,1720455735.317 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-07-08T16:22:15.318Z,1720455735.318 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-07-08T16:22:15.318Z,1720455735.318 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8727
2024-07-08T16:22:15.322Z,1720455735.322 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-07-08T16:22:15.339Z,1720455735.339 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-07-08T16:22:15.340Z,1720455735.340 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-07-08T16:22:15.340Z,1720455735.340 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8728
2024-07-08T16:22:15.345Z,1720455735.345 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-07-08T16:22:15.346Z,1720455735.346 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-07-08T16:22:15.346Z,1720455735.346 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8729
2024-07-08T16:22:15.348Z,1720455735.348 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-07-08T16:22:15.349Z,1720455735.349 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-07-08T16:22:15.349Z,1720455735.349 [logger ThreadHandler](INFO): Protected caller Thread ID is 8730
2024-07-08T16:22:15.353Z,1720455735.353 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-07-08T16:22:15.353Z,1720455735.353 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-07-08T16:22:15.355Z,1720455735.355 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-07-08T16:22:15.906Z,1720455735.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-07-08T16:22:15.907Z,1720455735.907 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-07-08T16:22:16.137Z,1720455736.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-07-08T16:22:16.138Z,1720455736.138 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-07-08T16:22:16.231Z,1720455736.231 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-07-08T16:22:16.483Z,1720455736.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-07-08T16:22:16.483Z,1720455736.483 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-07-08T16:22:16.575Z,1720455736.575 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-07-08T16:22:16.683Z,1720455736.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-07-08T16:22:16.684Z,1720455736.684 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-07-08T16:22:17.046Z,1720455737.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-07-08T16:22:17.046Z,1720455737.046 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-07-08T16:22:17.193Z,1720455737.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-07-08T16:22:17.194Z,1720455737.194 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-07-08T16:22:17.750Z,1720455737.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-07-08T16:22:17.751Z,1720455737.751 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-07-08T16:22:17.955Z,1720455737.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-07-08T16:22:17.956Z,1720455737.956 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-07-08T16:22:18.617Z,1720455738.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-07-08T16:22:18.618Z,1720455738.618 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-07-08T16:22:18.924Z,1720455738.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-07-08T16:22:18.925Z,1720455738.925 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-07-08T16:22:19.047Z,1720455739.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-07-08T16:22:19.048Z,1720455739.048 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-07-08T16:22:19.908Z,1720455739.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-07-08T16:22:19.908Z,1720455739.908 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-07-08T16:22:20.282Z,1720455740.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-07-08T16:22:20.282Z,1720455740.282 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-07-08T16:22:20.529Z,1720455740.529 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-07-08T16:22:20.531Z,1720455740.531 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2024-07-08T16:22:20.532Z,1720455740.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2024-07-08T16:22:20.664Z,1720455740.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2024-07-08T16:22:20.760Z,1720455740.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2024-07-08T16:22:20.910Z,1720455740.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2024-07-08T16:22:21.009Z,1720455741.009 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2024-07-08T16:22:21.286Z,1720455741.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-07-08T16:22:21.287Z,1720455741.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2024-07-08T16:22:21.405Z,1720455741.405 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2024-07-08T16:22:21.525Z,1720455741.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2024-07-08T16:22:21.634Z,1720455741.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2024-07-08T16:22:21.797Z,1720455741.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2024-07-08T16:22:21.908Z,1720455741.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2024-07-08T16:22:22.007Z,1720455742.007 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-07-08T16:22:22.011Z,1720455742.011 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-07-08T16:22:22.022Z,1720455742.022 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-07-08T16:22:22.023Z,1720455742.023 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-07-08T16:22:22.133Z,1720455742.133 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-07-08T16:22:22.133Z,1720455742.133 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-07-08T16:22:22.186Z,1720455742.186 [VerticalControl](DEBUG): Construct VerticalControl.
2024-07-08T16:22:22.245Z,1720455742.245 [VerticalControl] Loaded
2024-07-08T16:22:22.245Z,1720455742.245 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-07-08T16:22:22.248Z,1720455742.248 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-07-08T16:22:22.295Z,1720455742.295 [HorizontalControl] Loaded
2024-07-08T16:22:22.295Z,1720455742.295 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-07-08T16:22:22.298Z,1720455742.298 [SpeedControl](DEBUG): Construct SpeedControl.
2024-07-08T16:22:22.301Z,1720455742.301 [SpeedControl] Loaded
2024-07-08T16:22:22.301Z,1720455742.301 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-07-08T16:22:22.303Z,1720455742.303 [LoopControl](DEBUG): Construct LoopControl.
2024-07-08T16:22:22.304Z,1720455742.304 [LoopControl] Loaded
2024-07-08T16:22:22.304Z,1720455742.304 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-07-08T16:22:22.304Z,1720455742.304 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-07-08T16:22:22.305Z,1720455742.305 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-07-08T16:22:22.460Z,1720455742.460 [BuoyancyServo] Loaded
2024-07-08T16:22:22.461Z,1720455742.461 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-07-08T16:22:22.482Z,1720455742.482 [ElevatorServo] Loaded
2024-07-08T16:22:22.483Z,1720455742.483 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-07-08T16:22:22.503Z,1720455742.503 [MassServo] Loaded
2024-07-08T16:22:22.503Z,1720455742.503 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-07-08T16:22:22.523Z,1720455742.523 [RudderServo] Loaded
2024-07-08T16:22:22.523Z,1720455742.523 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-07-08T16:22:22.539Z,1720455742.539 [ThrusterHE] Loaded
2024-07-08T16:22:22.540Z,1720455742.540 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-07-08T16:22:22.540Z,1720455742.540 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-07-08T16:22:22.541Z,1720455742.541 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-07-08T16:22:22.591Z,1720455742.591 [DepthRateCalculator] Loaded
2024-07-08T16:22:22.591Z,1720455742.591 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-07-08T16:22:22.596Z,1720455742.596 [PitchRateCalculator] Loaded
2024-07-08T16:22:22.596Z,1720455742.596 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-07-08T16:22:22.606Z,1720455742.606 [SpeedCalculator] Loaded
2024-07-08T16:22:22.606Z,1720455742.606 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-07-08T16:22:22.610Z,1720455742.610 [YawRateCalculator] Loaded
2024-07-08T16:22:22.610Z,1720455742.610 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-07-08T16:22:22.628Z,1720455742.628 [ElevatorOffsetCalculator] Loaded
2024-07-08T16:22:22.629Z,1720455742.629 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-07-08T16:22:22.629Z,1720455742.629 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-07-08T16:22:22.630Z,1720455742.630 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-07-08T16:22:22.740Z,1720455742.740 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-07-08T16:22:22.741Z,1720455742.741 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-07-08T16:22:22.754Z,1720455742.754 [NavChart] Loaded
2024-07-08T16:22:22.754Z,1720455742.754 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-07-08T16:22:22.760Z,1720455742.760 [UniversalFixResidualReporter] Loaded
2024-07-08T16:22:22.760Z,1720455742.760 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-07-08T16:22:22.760Z,1720455742.760 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-07-08T16:22:22.761Z,1720455742.761 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-07-08T16:22:23.193Z,1720455743.193 [AHRS_M2] Loaded
2024-07-08T16:22:23.193Z,1720455743.193 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-07-08T16:22:23.235Z,1720455743.235 [BackseatComponent] Loaded
2024-07-08T16:22:23.235Z,1720455743.235 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-07-08T16:22:23.236Z,1720455743.236 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2024-07-08T16:22:23.236Z,1720455743.236 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8812
2024-07-08T16:22:23.239Z,1720455743.239 [LcmUniversalReporter] Loaded
2024-07-08T16:22:23.239Z,1720455743.239 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-07-08T16:22:23.246Z,1720455743.246 [BioacousticsDataBridge] Loaded
2024-07-08T16:22:23.246Z,1720455743.246 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2024-07-08T16:22:24.078Z,1720455744.078 [BPC1] Loaded
2024-07-08T16:22:24.078Z,1720455744.078 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-07-08T16:22:24.149Z,1720455744.149 [DataOverHttps] Loaded
2024-07-08T16:22:24.149Z,1720455744.149 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-07-08T16:22:24.150Z,1720455744.150 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2024-07-08T16:22:24.151Z,1720455744.151 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8813
2024-07-08T16:22:24.174Z,1720455744.174 [Depth_Keller] Loaded
2024-07-08T16:22:24.174Z,1720455744.174 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-07-08T16:22:24.179Z,1720455744.179 [DropWeight] Loaded
2024-07-08T16:22:24.180Z,1720455744.180 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-07-08T16:22:24.243Z,1720455744.243 [NAL9602] Loaded
2024-07-08T16:22:24.243Z,1720455744.243 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-07-08T16:22:24.274Z,1720455744.274 [Onboard] Loaded
2024-07-08T16:22:24.274Z,1720455744.274 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-07-08T16:22:24.275Z,1720455744.275 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2024-07-08T16:22:24.275Z,1720455744.275 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8814
2024-07-08T16:22:24.289Z,1720455744.289 [Power24vConverter] Loaded
2024-07-08T16:22:24.289Z,1720455744.289 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-07-08T16:22:24.305Z,1720455744.305 [Radio_Surface] Loaded
2024-07-08T16:22:24.305Z,1720455744.305 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-07-08T16:22:24.306Z,1720455744.306 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0
2024-07-08T16:22:24.306Z,1720455744.306 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8815
2024-07-08T16:22:24.322Z,1720455744.322 [Sonardyne_Nano] Loaded
2024-07-08T16:22:24.322Z,1720455744.322 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-07-08T16:22:24.322Z,1720455744.322 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-07-08T16:22:24.323Z,1720455744.323 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-07-08T16:22:24.515Z,1720455744.515 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-07-08T16:22:24.515Z,1720455744.515 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-07-08T16:22:24.731Z,1720455744.731 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-07-08T16:22:24.731Z,1720455744.731 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-07-08T16:22:24.855Z,1720455744.855 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-07-08T16:22:24.856Z,1720455744.856 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-07-08T16:22:25.089Z,1720455745.089 [CTD_Seabird] Loaded
2024-07-08T16:22:25.089Z,1720455745.089 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-07-08T16:22:25.090Z,1720455745.090 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0
2024-07-08T16:22:25.090Z,1720455745.090 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8816
2024-07-08T16:22:25.112Z,1720455745.112 [PAR_Licor] Loaded
2024-07-08T16:22:25.112Z,1720455745.112 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-07-08T16:22:25.157Z,1720455745.157 [WetLabsBB2FL] Loaded
2024-07-08T16:22:25.157Z,1720455745.157 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-07-08T16:22:25.158Z,1720455745.158 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0
2024-07-08T16:22:25.159Z,1720455745.159 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8817
2024-07-08T16:22:25.159Z,1720455745.159 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-07-08T16:22:25.160Z,1720455745.160 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-07-08T16:22:25.497Z,1720455745.497 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-07-08T16:22:25.497Z,1720455745.497 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-07-08T16:22:25.638Z,1720455745.638 [SBIT](DEBUG): Construct Startup Built In Test.
2024-07-08T16:22:25.647Z,1720455745.647 [SBIT] Loaded
2024-07-08T16:22:25.647Z,1720455745.647 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-07-08T16:22:25.650Z,1720455745.650 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-07-08T16:22:25.662Z,1720455745.662 [IBIT] Loaded
2024-07-08T16:22:25.662Z,1720455745.662 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-07-08T16:22:25.668Z,1720455745.668 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-07-08T16:22:25.770Z,1720455745.770 [CBIT] Loaded
2024-07-08T16:22:25.770Z,1720455745.770 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-07-08T16:22:25.771Z,1720455745.771 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-07-08T16:22:25.777Z,1720455745.777 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-07-08T16:22:25.780Z,1720455745.780 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-07-08T16:22:25.791Z,1720455745.791 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-07-08T16:22:25.792Z,1720455745.792 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-08T16:22:25.793Z,1720455745.793 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8818
2024-07-08T16:22:25.797Z,1720455745.797 [Supervisor](INFO): Main Thread ID is 8558
2024-07-08T16:22:25.797Z,1720455745.797 [Supervisor](DEBUG): Running supervisor.
2024-07-08T16:22:25.798Z,1720455745.798 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8819
2024-07-08T16:22:25.798Z,1720455745.798 [CommandExec](INFO): Initializing the command executive.
2024-07-08T16:22:25.800Z,1720455745.800 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8820
2024-07-08T16:22:25.802Z,1720455745.802 [controlThread ThreadHandler](INFO): Handler Thread ID is 8821
2024-07-08T16:22:25.802Z,1720455745.802 [controlThread](DEBUG): Initializing ControlThread
2024-07-08T16:22:25.803Z,1720455745.803 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-07-08T16:22:25.805Z,1720455745.805 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-07-08T16:22:25.805Z,1720455745.805 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-07-08T16:22:25.806Z,1720455745.806 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-07-08T16:22:25.807Z,1720455745.807 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-07-08T16:22:25.808Z,1720455745.808 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-07-08T16:22:25.808Z,1720455745.808 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-07-08T16:22:25.808Z,1720455745.808 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-07-08T16:22:25.809Z,1720455745.809 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-07-08T16:22:25.810Z,1720455745.810 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-07-08T16:22:25.810Z,1720455745.810 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-07-08T16:22:25.817Z,1720455745.817 [SBIT](INFO): Initialize SBIT Component.
2024-07-08T16:22:25.818Z,1720455745.818 [SBIT](IMPORTANT): git: 2024-06-03
2024-07-08T16:22:25.818Z,1720455745.818 [SBIT](INFO): git hash: 7fa19da3a148abf4baea4b65318d99ca26f8c915
2024-07-08T16:22:25.818Z,1720455745.818 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-07-08T16:22:25.819Z,1720455745.819 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-07-08T16:22:25.820Z,1720455745.820 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2024-07-08T16:22:25.821Z,1720455745.821 [IBIT](INFO): Initialize IBIT Component.
2024-07-08T16:22:25.822Z,1720455745.822 [CBIT](DEBUG): Initialize CBIT Component.
2024-07-08T16:22:25.823Z,1720455745.823 [logger ThreadHandler](INFO): Handler Thread ID is 8822
2024-07-08T16:22:25.833Z,1720455745.833 [CBIT](DEBUG): Initialized mux pins.
2024-07-08T16:22:25.833Z,1720455745.833 [CBIT](DEBUG): Initializing the watchdog timer.
2024-07-08T16:22:25.842Z,1720455745.842 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8823
2024-07-08T16:22:25.854Z,1720455745.854 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8824
2024-07-08T16:22:25.855Z,1720455745.855 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-07-08T16:22:25.857Z,1720455745.857 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-07-08T16:22:25.858Z,1720455745.858 [CBIT](DEBUG): Initializing heartbeat.
2024-07-08T16:22:25.866Z,1720455745.866 [Onboard ThreadHandler](INFO): Handler Thread ID is 8825
2024-07-08T16:22:25.883Z,1720455745.883 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8826
2024-07-08T16:22:25.902Z,1720455745.902 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8827
2024-07-08T16:22:25.903Z,1720455745.903 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-07-08T16:22:25.907Z,1720455745.907 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8829
2024-07-08T16:22:25.910Z,1720455745.910 [WetLabsBB2FL](INFO): Powering up
2024-07-08T16:22:25.911Z,1720455745.911 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8831
2024-07-08T16:22:25.913Z,1720455745.913 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-07-08T16:22:25.914Z,1720455745.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-07-08T16:22:25.914Z,1720455745.914 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-07-08T16:22:25.914Z,1720455745.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-07-08T16:22:25.914Z,1720455745.914 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-07-08T16:22:25.914Z,1720455745.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-07-08T16:22:25.914Z,1720455745.914 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-07-08T16:22:25.914Z,1720455745.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-07-08T16:22:25.929Z,1720455745.929 [CBIT](DEBUG): Deactivating GF circuits.
2024-07-08T16:22:25.929Z,1720455745.929 [CBIT](DEBUG): Deactivating emergency mode.
2024-07-08T16:22:25.969Z,1720455745.969 [CBIT](DEBUG): Backplane powered.
2024-07-08T16:22:25.970Z,1720455745.970 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-07-08T16:22:25.971Z,1720455745.971 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-07-08T16:22:25.971Z,1720455745.971 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-07-08T16:22:25.972Z,1720455745.972 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-07-08T16:22:26.007Z,1720455746.007 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-07-08T16:22:26.012Z,1720455746.012 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-08T16:22:26.064Z,1720455746.064 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-07-08T16:22:26.064Z,1720455746.064 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-07-08T16:22:26.064Z,1720455746.064 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-07-08T16:22:26.066Z,1720455746.066 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-07-08T16:22:26.289Z,1720455746.289 [Radio_Surface](INFO): Powering up
2024-07-08T16:22:26.339Z,1720455746.339 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-07-08T16:22:26.364Z,1720455746.364 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-07-08T16:22:26.366Z,1720455746.366 [Default:A.Wait](DEBUG): Construct Wait.
2024-07-08T16:22:26.368Z,1720455746.368 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-08T16:22:26.412Z,1720455746.412 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-07-08T16:22:26.415Z,1720455746.415 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-07-08T16:22:26.433Z,1720455746.433 [Default:E.Execute](DEBUG): Construct Execute.
2024-07-08T16:22:26.448Z,1720455746.448 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-07-08T16:22:26.452Z,1720455746.452 [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,
2024-07-08T16:22:26.477Z,1720455746.477 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-07-08T16:22:26.548Z,1720455746.548 [Power24vConverter](INFO): Powering up.
2024-07-08T16:22:26.548Z,1720455746.548 [Sonardyne_Nano](INFO): Initializing.
2024-07-08T16:22:26.619Z,1720455746.619 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-07-08T16:22:26.626Z,1720455746.626 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-07-08T16:22:26.627Z,1720455746.627 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-07-08T16:22:26.634Z,1720455746.634 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-07-08T16:22:26.634Z,1720455746.634 [MassServo](DEBUG): Initializing EZServoServo.
2024-07-08T16:22:26.642Z,1720455746.642 [MassServo](DEBUG): Initializing MassServo.
2024-07-08T16:22:26.642Z,1720455746.642 [RudderServo](DEBUG): Initializing EZServoServo.
2024-07-08T16:22:26.650Z,1720455746.650 [RudderServo](DEBUG): Initializing RudderServo.
2024-07-08T16:22:26.650Z,1720455746.650 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-07-08T16:22:26.658Z,1720455746.658 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-07-08T16:22:28.017Z,1720455748.017 [WetLabsBB2FL](INFO): Powering down
2024-07-08T16:22:28.480Z,1720455748.480 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-07-08T16:22:29.766Z,1720455749.766 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2024-07-08T16:22:32.295Z,1720455752.295 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-07-08T16:22:46.296Z,1720455766.296 [SBIT](IMPORTANT): Beginning Startup BIT
2024-07-08T16:22:46.304Z,1720455766.304 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-08T16:22:54.339Z,1720455774.339 [NAL9602](INFO): Powering up NAL9602
2024-07-08T16:22:57.195Z,1720455777.195 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006404
CHAN A1 (24V): -0.007290
CHAN A2 (12V): -0.004263
CHAN A3 (5V): -0.002708
CHAN B0 (3.3V): -0.001086
CHAN B1 (3.15aV): -0.000861
CHAN B2 (3.15bV): -0.001381
CHAN B3 (GND): -0.000253
OPEN: 0.003825
Full Scale: +/- 1 mA
2024-07-08T16:23:05.251Z,1720455785.251 [NAL9602](INFO): NAL9602 initialized
2024-07-08T16:23:25.451Z,1720455805.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=5063, MT Status=0, MTMSN=0
2024-07-08T16:23:25.451Z,1720455805.451 [NAL9602](INFO): No messages in MT queue
2024-07-08T16:23:32.730Z,1720455812.730 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162331.00,A,3648.17659,N,12147.28581,W,0.233,0.00,080724,,,A*71
2024-07-08T16:23:32.733Z,1720455812.733 [NAL9602](INFO): GPS fix at 20240708T162331: (36.802943, -121.788097)
2024-07-08T16:23:40.417Z,1720455820.417 [SBIT](IMPORTANT): SBIT PASSED
2024-07-08T16:23:40.418Z,1720455820.418 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-08T16:23:40.418Z,1720455820.418 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;
2024-07-08T16:23:40.418Z,1720455820.418 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2024-07-08T16:23:40.419Z,1720455820.419 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-07-08T16:23:40.834Z,1720455820.834 [MissionManager](IMPORTANT): Started mission Startup
2024-07-08T16:23:40.834Z,1720455820.834 [Startup] Running Loop=1
2024-07-08T16:23:40.834Z,1720455820.834 [Startup](DEBUG): Aggregate::initialize Startup
2024-07-08T16:23:40.834Z,1720455820.834 [Startup:A.GoToSurface] Running Loop=1
2024-07-08T16:23:40.834Z,1720455820.834 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-08T16:23:40.835Z,1720455820.835 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-08T16:23:40.835Z,1720455820.835 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-08T16:23:40.835Z,1720455820.835 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-08T16:23:40.836Z,1720455820.836 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-08T16:23:40.836Z,1720455820.836 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-08T16:23:40.836Z,1720455820.836 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-08T16:23:40.842Z,1720455820.842 [Startup:StartupSatComms] Running Loop=1
2024-07-08T16:23:40.842Z,1720455820.842 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-07-08T16:23:40.842Z,1720455820.842 [Startup:StartupSatComms:A] Running Loop=1
2024-07-08T16:23:41.240Z,1720455821.240 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-07-08T16:23:42.828Z,1720455822.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162341.00,A,3648.17591,N,12147.28512,W,0.058,0.00,080724,,,A*74
2024-07-08T16:23:42.830Z,1720455822.830 [NAL9602](INFO): GPS fix at 20240708T162341: (36.802932, -121.788085)
2024-07-08T16:23:42.867Z,1720455822.867 [Startup:StartupSatComms:A] Stopped
2024-07-08T16:23:42.867Z,1720455822.867 [Startup:StartupSatComms:B] Running Loop=1
2024-07-08T16:23:43.277Z,1720455823.277 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-08T16:24:07.539Z,1720455847.539 [NAL9602](INFO): SBD MO Status=1, MOMSN=5064, MT Status=0, MTMSN=0
2024-07-08T16:24:07.593Z,1720455847.593 [NAL9602](INFO): Sent 39 bytes from file Logs/20240708T153257/Courier0031.lzma
2024-07-08T16:24:07.594Z,1720455847.594 [NAL9602](INFO): Packets left to send: 0
2024-07-08T16:24:28.052Z,1720455868.052 [NAL9602](INFO): SBD MO Status=1, MOMSN=5065, MT Status=0, MTMSN=0
2024-07-08T16:24:28.102Z,1720455868.102 [NAL9602](INFO): Sent 268 bytes from file Logs/20240708T162215/Courier0000.lzma
2024-07-08T16:24:28.102Z,1720455868.102 [NAL9602](INFO): Packets left to send: 0
2024-07-08T16:24:43.106Z,1720455883.106 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-08T16:23:42.9Z
2024-07-08T16:24:43.106Z,1720455883.106 [Startup:StartupSatComms:B] Stopped
2024-07-08T16:24:43.106Z,1720455883.106 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-07-08T16:24:43.106Z,1720455883.106 [Startup:StartupSatComms] Stopped
2024-07-08T16:24:43.106Z,1720455883.106 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-07-08T16:24:43.107Z,1720455883.107 [Startup](INFO): Completed Startup
2024-07-08T16:24:43.107Z,1720455883.107 [MissionManager](INFO): Startup is completed.
2024-07-08T16:24:43.107Z,1720455883.107 [MissionManager](INFO): Uninitializing Mission Startup
2024-07-08T16:24:43.107Z,1720455883.107 [Startup] Stopped
2024-07-08T16:24:43.107Z,1720455883.107 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-07-08T16:24:43.107Z,1720455883.107 [Startup:A.GoToSurface] Stopped
2024-07-08T16:24:43.107Z,1720455883.107 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-08T16:24:43.517Z,1720455883.517 [MissionManager](IMPORTANT): Started mission Default
2024-07-08T16:24:43.517Z,1720455883.517 [Default] Running Loop=1
2024-07-08T16:24:43.517Z,1720455883.517 [Default](DEBUG): Aggregate::initialize Default
2024-07-08T16:24:43.517Z,1720455883.517 [Default:B.GoToSurface] Running Loop=1
2024-07-08T16:24:43.517Z,1720455883.517 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-08T16:24:43.518Z,1720455883.518 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-08T16:24:43.518Z,1720455883.518 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-08T16:24:43.518Z,1720455883.518 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-08T16:24:43.519Z,1720455883.519 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-08T16:24:43.519Z,1720455883.519 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-08T16:24:43.519Z,1720455883.519 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-08T16:24:43.519Z,1720455883.519 [Default:A.Wait] Running Loop=1
2024-07-08T16:24:43.519Z,1720455883.519 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-07-08T16:24:53.768Z,1720455893.768 [NAL9602](INFO): SBD MO Status=2, MOMSN=5066, MT Status=2, MTMSN=0
2024-07-08T16:24:53.768Z,1720455893.768 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T16:24:56.587Z,1720455896.587 [Default:A.Wait](INFO): Done Waiting.
2024-07-08T16:24:56.587Z,1720455896.587 [Default:A.Wait] Stopped
2024-07-08T16:24:56.587Z,1720455896.587 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T16:24:56.999Z,1720455896.999 [Default:CheckIn] Running Loop=1
2024-07-08T16:24:56.999Z,1720455896.999 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T16:24:56.999Z,1720455896.999 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T16:24:57.394Z,1720455897.394 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-07-08T16:24:59.000Z,1720455899.000 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162457.00,A,3648.17419,N,12147.28529,W,0.019,0.00,080724,,,A*78
2024-07-08T16:24:59.003Z,1720455899.003 [NAL9602](INFO): GPS fix at 20240708T162457: (36.802903, -121.788088)
2024-07-08T16:24:59.038Z,1720455899.038 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T16:24:59.038Z,1720455899.038 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T16:24:59.431Z,1720455899.431 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-08T16:25:21.544Z,1720455921.544 [NAL9602](INFO): SBD MO Status=1, MOMSN=5066, MT Status=0, MTMSN=0
2024-07-08T16:25:21.598Z,1720455921.598 [NAL9602](INFO): Sent 73 bytes from file Logs/20240708T162215/Courier0004.lzma
2024-07-08T16:25:21.598Z,1720455921.598 [NAL9602](INFO): Packets left to send: 0
2024-07-08T16:25:49.115Z,1720455949.115 [DataOverHttps](IMPORTANT): SBD MTMSN=20240708T162548
2024-07-08T16:25:49.120Z,1720455949.120 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005009
2024-07-08T16:25:54.572Z,1720455954.572 [NAL9602](INFO): SBD MO Status=2, MOMSN=5067, MT Status=2, MTMSN=0
2024-07-08T16:25:54.572Z,1720455954.572 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T16:26:01.790Z,1720455961.790 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20240708T153257/Express0033.lzma
2024-07-08T16:26:01.793Z,1720455961.793 [DataOverHttps](INFO): Received command: configSet list
2024-07-08T16:26:01.818Z,1720455961.818 [CommandExec](IMPORTANT): got command configSet list
2024-07-08T16:26:01.818Z,1720455961.818 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-08T16:26:01.819Z,1720455961.819 [CommandExec](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;
2024-07-08T16:26:01.819Z,1720455961.819 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2024-07-08T16:26:01.820Z,1720455961.820 [CommandExec](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-07-08T16:26:02.972Z,1720455962.972 [DataOverHttps](INFO): Moved sent file to Logs/20240708T153257/Express0033.lzma.bak
2024-07-08T16:26:02.972Z,1720455962.972 [DataOverHttps](INFO): SBD MOMSN=21340732
2024-07-08T16:26:24.913Z,1720455984.913 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T162215/Courier0007.lzma
2024-07-08T16:26:25.274Z,1720455985.274 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T16:26:25.915Z,1720455985.915 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0007.lzma.bak
2024-07-08T16:26:25.915Z,1720455985.915 [DataOverHttps](INFO): SBD MOMSN=21340741
2024-07-08T16:26:46.926Z,1720456006.926 [DataOverHttps](INFO): Sending 760 bytes from file Logs/20240708T162215/Express0001.lzma
2024-07-08T16:26:47.927Z,1720456007.927 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0001.lzma.bak
2024-07-08T16:26:47.927Z,1720456007.927 [DataOverHttps](INFO): SBD MOMSN=21340743
2024-07-08T16:27:10.637Z,1720456030.637 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20240708T162215/Express0005.lzma
2024-07-08T16:27:11.639Z,1720456031.639 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0005.lzma.bak
2024-07-08T16:27:11.640Z,1720456031.640 [DataOverHttps](INFO): SBD MOMSN=21340759
2024-07-08T16:27:23.044Z,1720456043.044 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:27:33.069Z,1720456053.069 [DataOverHttps](INFO): Sending 310 bytes from file Logs/20240708T162215/Express0008.lzma
2024-07-08T16:27:34.071Z,1720456054.071 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0008.lzma.bak
2024-07-08T16:27:34.071Z,1720456054.071 [DataOverHttps](INFO): SBD MOMSN=21340762
2024-07-08T16:27:37.992Z,1720456057.992 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:27:40.450Z,1720456060.450 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T16:27:40.450Z,1720456060.450 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T16:27:40.450Z,1720456060.450 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T16:27:53.344Z,1720456073.344 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:27:55.370Z,1720456075.370 [DataOverHttps](IMPORTANT): SBD MTMSN=20240708T162754
2024-07-08T16:28:02.685Z,1720456082.685 [DataOverHttps](INFO): Received command: ibit
2024-07-08T16:28:02.782Z,1720456082.782 [CommandExec](IMPORTANT): got command ibit
2024-07-08T16:28:03.068Z,1720456083.068 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-07-08T16:28:03.068Z,1720456083.068 [IBIT](IMPORTANT): Beginning control surface checks.
2024-07-08T16:28:03.072Z,1720456083.072 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-08T16:28:04.661Z,1720456084.661 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162803.00,A,3648.17495,N,12147.28599,W,0.019,0.00,080724,,,A*7A
2024-07-08T16:28:04.663Z,1720456084.663 [NAL9602](INFO): GPS fix at 20240708T162803: (36.802916, -121.788100)
2024-07-08T16:28:09.567Z,1720456089.567 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:28:13.973Z,1720456093.973 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006012
CHAN A1 (24V): -0.006868
CHAN A2 (12V): -0.004642
CHAN A3 (5V): -0.003399
CHAN B0 (3.3V): -0.001440
CHAN B1 (3.15aV): -0.001050
CHAN B2 (3.15bV): -0.000938
CHAN B3 (GND): -0.000032
OPEN: 0.003891
Full Scale: +/- 1 mA
2024-07-08T16:28:24.856Z,1720456104.856 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:28:25.688Z,1720456105.688 [NAL9602](INFO): SBD MO Status=1, MOMSN=5067, MT Status=0, MTMSN=0
2024-07-08T16:28:25.688Z,1720456105.688 [NAL9602](INFO): No messages in MT queue
2024-07-08T16:28:26.882Z,1720456106.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162825.00,A,3648.17431,N,12147.28568,W,0.058,0.00,080724,,,A*7B
2024-07-08T16:28:26.884Z,1720456106.884 [NAL9602](INFO): GPS fix at 20240708T162825: (36.802905, -121.788095)
2024-07-08T16:28:29.708Z,1720456109.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162828.00,A,3648.17429,N,12147.28565,W,0.039,0.00,080724,,,A*75
2024-07-08T16:28:29.710Z,1720456109.710 [NAL9602](INFO): GPS fix at 20240708T162828: (36.802905, -121.788094)
2024-07-08T16:28:32.536Z,1720456112.536 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162831.00,A,3648.17428,N,12147.28566,W,0.019,0.00,080724,,,A*7D
2024-07-08T16:28:32.538Z,1720456112.538 [NAL9602](INFO): GPS fix at 20240708T162831: (36.802905, -121.788094)
2024-07-08T16:28:35.376Z,1720456115.376 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162834.00,A,3648.17429,N,12147.28568,W,0.000,0.00,080724,,,A*7F
2024-07-08T16:28:35.383Z,1720456115.383 [NAL9602](INFO): GPS fix at 20240708T162834: (36.802905, -121.788095)
2024-07-08T16:28:38.601Z,1720456118.601 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162837.00,A,3648.17428,N,12147.28569,W,0.019,0.00,080724,,,A*74
2024-07-08T16:28:38.603Z,1720456118.603 [NAL9602](INFO): GPS fix at 20240708T162837: (36.802905, -121.788095)
2024-07-08T16:28:40.212Z,1720456120.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:28:41.424Z,1720456121.424 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162840.00,A,3648.17425,N,12147.28570,W,0.019,0.00,080724,,,A*71
2024-07-08T16:28:41.426Z,1720456121.426 [NAL9602](INFO): GPS fix at 20240708T162840: (36.802904, -121.788095)
2024-07-08T16:28:44.656Z,1720456124.656 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162843.00,A,3648.17422,N,12147.28573,W,0.019,0.00,080724,,,A*76
2024-07-08T16:28:44.658Z,1720456124.658 [NAL9602](INFO): GPS fix at 20240708T162843: (36.802904, -121.788095)
2024-07-08T16:28:47.489Z,1720456127.489 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162846.00,A,3648.17410,N,12147.28582,W,0.019,0.00,080724,,,A*7C
2024-07-08T16:28:47.491Z,1720456127.491 [NAL9602](INFO): GPS fix at 20240708T162846: (36.802902, -121.788097)
2024-07-08T16:28:49.589Z,1720456129.589 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802902 Longitude: -121.788094
2024-07-08T16:28:49.939Z,1720456129.939 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2024-07-08T16:28:50.346Z,1720456130.346 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-07-08T16:28:50.347Z,1720456130.347 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-07-08T16:28:50.347Z,1720456130.347 [IBIT](IMPORTANT): Pressure:10.140203 PSI
2024-07-08T16:28:50.347Z,1720456130.347 [IBIT](IMPORTANT): Humidity:10.828959 %
2024-07-08T16:28:50.754Z,1720456130.754 [IBIT](IMPORTANT): Vehicle Pitch:-2.594298 degrees
2024-07-08T16:28:50.755Z,1720456130.755 [IBIT](IMPORTANT): Vehicle Roll:1.794148 degrees
2024-07-08T16:28:50.755Z,1720456130.755 [IBIT](IMPORTANT): Vehicle Heading:79.633850 degrees
2024-07-08T16:28:51.138Z,1720456131.138 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-07-08T16:28:51.138Z,1720456131.138 [IBIT](IMPORTANT): buoyancyNeutral: 200.000000 cc
2024-07-08T16:28:51.138Z,1720456131.138 [IBIT](IMPORTANT): massDefault: 0.000000 cm
2024-07-08T16:28:51.139Z,1720456131.139 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2024-07-08T16:28:51.139Z,1720456131.139 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-07-08T16:28:51.139Z,1720456131.139 [IBIT](IMPORTANT): IBIT PASSED
2024-07-08T16:28:55.559Z,1720456135.559 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:29:10.913Z,1720456150.913 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:29:20.206Z,1720456160.206 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T16:29:26.264Z,1720456166.264 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:29:41.212Z,1720456181.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T16:29:50.108Z,1720456190.108 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2024-07-08T16:29:50.111Z,1720456190.111 [BPC1](INFO): Received data from all battery sticks.
2024-07-08T16:32:27.543Z,1720456347.543 [Power24vConverter](INFO): Powering down.
2024-07-08T16:32:40.890Z,1720456360.890 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T16:32:40.890Z,1720456360.890 [Default:CheckIn:C.Wait] Stopped
2024-07-08T16:32:40.890Z,1720456360.890 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T16:32:40.890Z,1720456360.890 [Default:CheckIn:D] Running Loop=1
2024-07-08T16:32:41.292Z,1720456361.292 [Default:CheckIn:D] Stopped
2024-07-08T16:32:41.292Z,1720456361.292 [Default:CheckIn:E] Running Loop=1
2024-07-08T16:32:41.716Z,1720456361.716 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.962914 min
2024-07-08T16:32:41.716Z,1720456361.716 [Default:CheckIn:E] Stopped
2024-07-08T16:32:41.716Z,1720456361.716 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T16:32:41.716Z,1720456361.716 [Default:CheckIn] Stopped
2024-07-08T16:32:41.716Z,1720456361.716 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T16:32:41.717Z,1720456361.717 [Default:CheckIn](INFO): Running loop #2
2024-07-08T16:32:41.717Z,1720456361.717 [Default:CheckIn] Running Loop=2
2024-07-08T16:32:41.717Z,1720456361.717 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T16:32:41.717Z,1720456361.717 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T16:32:43.704Z,1720456363.704 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163242.00,A,3648.17411,N,12147.28441,W,0.078,0.00,080724,,,A*7B
2024-07-08T16:32:43.706Z,1720456363.706 [NAL9602](INFO): GPS fix at 20240708T163242: (36.802902, -121.788073)
2024-07-08T16:32:43.760Z,1720456363.760 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T16:32:43.760Z,1720456363.760 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T16:32:49.006Z,1720456369.006 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20240708T162215/Courier0010.lzma
2024-07-08T16:32:50.011Z,1720456370.011 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0010.lzma.bak
2024-07-08T16:32:50.011Z,1720456370.011 [DataOverHttps](INFO): SBD MOMSN=21340792
2024-07-08T16:33:10.814Z,1720456390.814 [DataOverHttps](INFO): Sending 799 bytes from file Logs/20240708T162215/Express0011.lzma
2024-07-08T16:33:11.815Z,1720456391.815 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0011.lzma.bak
2024-07-08T16:33:11.815Z,1720456391.815 [DataOverHttps](INFO): SBD MOMSN=21340795
2024-07-08T16:33:32.640Z,1720456412.640 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240708T162215/Express0014.lzma
2024-07-08T16:33:33.643Z,1720456413.643 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0014.lzma.bak
2024-07-08T16:33:33.643Z,1720456413.643 [DataOverHttps](INFO): SBD MOMSN=21340819
2024-07-08T16:33:39.586Z,1720456419.586 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T16:33:39.586Z,1720456419.586 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T16:33:39.586Z,1720456419.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T16:34:45.400Z,1720456485.400 [NAL9602](INFO): SBD MO Status=2, MOMSN=5068, MT Status=2, MTMSN=0
2024-07-08T16:34:45.400Z,1720456485.400 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T16:35:29.844Z,1720456529.844 [NAL9602](INFO): SBD MO Status=1, MOMSN=5068, MT Status=0, MTMSN=0
2024-07-08T16:35:29.844Z,1720456529.844 [NAL9602](INFO): No messages in MT queue
2024-07-08T16:36:00.546Z,1720456560.546 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T16:37:31.926Z,1720456651.926 [DataOverHttps](IMPORTANT): SBD MTMSN=20240708T163731
2024-07-08T16:37:39.189Z,1720456659.189 [DataOverHttps](INFO): Received command: strobe off
2024-07-08T16:37:39.246Z,1720456659.246 [CommandExec](IMPORTANT): got command strobe off
2024-07-08T16:37:39.247Z,1720456659.247 [CommandExec](IMPORTANT): Deactivating strobe
2024-07-08T16:38:40.208Z,1720456720.208 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T16:38:40.208Z,1720456720.208 [Default:CheckIn:C.Wait] Stopped
2024-07-08T16:38:40.208Z,1720456720.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T16:38:40.208Z,1720456720.208 [Default:CheckIn:D] Running Loop=1
2024-07-08T16:38:40.616Z,1720456720.616 [Default:CheckIn:D] Stopped
2024-07-08T16:38:40.616Z,1720456720.616 [Default:CheckIn:E] Running Loop=1
2024-07-08T16:38:41.018Z,1720456721.018 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.951654 min
2024-07-08T16:38:41.018Z,1720456721.018 [Default:CheckIn:E] Stopped
2024-07-08T16:38:41.019Z,1720456721.019 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T16:38:41.019Z,1720456721.019 [Default:CheckIn] Stopped
2024-07-08T16:38:41.019Z,1720456721.019 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T16:38:41.019Z,1720456721.019 [Default:CheckIn](INFO): Running loop #3
2024-07-08T16:38:41.019Z,1720456721.019 [Default:CheckIn] Running Loop=3
2024-07-08T16:38:41.019Z,1720456721.019 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T16:38:41.019Z,1720456721.019 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T16:38:43.024Z,1720456723.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163842.00,A,3648.17356,N,12147.28429,W,0.039,0.00,080724,,,A*7E
2024-07-08T16:38:43.026Z,1720456723.026 [NAL9602](INFO): GPS fix at 20240708T163842: (36.802893, -121.788072)
2024-07-08T16:38:43.036Z,1720456723.036 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T16:38:43.036Z,1720456723.036 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T16:38:53.252Z,1720456733.252 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T162215/Courier0016.lzma
2024-07-08T16:38:54.255Z,1720456734.255 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0016.lzma.bak
2024-07-08T16:38:54.255Z,1720456734.255 [DataOverHttps](INFO): SBD MOMSN=21340831
2024-07-08T16:39:02.416Z,1720456742.416 [NAL9602](INFO): SBD MO Status=1, MOMSN=5069, MT Status=0, MTMSN=0
2024-07-08T16:39:02.416Z,1720456742.416 [NAL9602](INFO): No messages in MT queue
2024-07-08T16:39:14.965Z,1720456754.965 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20240708T162215/Express0017.lzma
2024-07-08T16:39:15.967Z,1720456755.967 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0017.lzma.bak
2024-07-08T16:39:15.967Z,1720456755.967 [DataOverHttps](INFO): SBD MOMSN=21340834
2024-07-08T16:39:33.162Z,1720456773.162 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T16:39:37.320Z,1720456777.320 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240708T162215/Express0020.lzma
2024-07-08T16:39:38.326Z,1720456778.326 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0020.lzma.bak
2024-07-08T16:39:38.326Z,1720456778.326 [DataOverHttps](INFO): SBD MOMSN=21340844
2024-07-08T16:39:44.456Z,1720456784.456 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T16:39:44.456Z,1720456784.456 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T16:39:44.456Z,1720456784.456 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T16:44:45.028Z,1720457085.028 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T16:44:45.029Z,1720457085.029 [Default:CheckIn:C.Wait] Stopped
2024-07-08T16:44:45.029Z,1720457085.029 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T16:44:45.029Z,1720457085.029 [Default:CheckIn:D] Running Loop=1
2024-07-08T16:44:45.467Z,1720457085.467 [Default:CheckIn:D] Stopped
2024-07-08T16:44:45.467Z,1720457085.467 [Default:CheckIn:E] Running Loop=1
2024-07-08T16:44:45.868Z,1720457085.868 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.032495 min
2024-07-08T16:44:45.868Z,1720457085.868 [Default:CheckIn:E] Stopped
2024-07-08T16:44:45.869Z,1720457085.869 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T16:44:45.869Z,1720457085.869 [Default:CheckIn] Stopped
2024-07-08T16:44:45.869Z,1720457085.869 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T16:44:45.869Z,1720457085.869 [Default:CheckIn](INFO): Running loop #4
2024-07-08T16:44:45.869Z,1720457085.869 [Default:CheckIn] Running Loop=4
2024-07-08T16:44:45.869Z,1720457085.869 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T16:44:45.869Z,1720457085.869 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T16:44:47.840Z,1720457087.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164447.00,A,3648.17549,N,12147.28536,W,0.019,0.00,080724,,,A*75
2024-07-08T16:44:47.850Z,1720457087.850 [NAL9602](INFO): GPS fix at 20240708T164447: (36.802925, -121.788089)
2024-07-08T16:44:47.860Z,1720457087.860 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T16:44:47.860Z,1720457087.860 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T16:44:57.624Z,1720457097.624 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T162215/Courier0022.lzma
2024-07-08T16:44:58.627Z,1720457098.627 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0022.lzma.bak
2024-07-08T16:44:58.627Z,1720457098.627 [DataOverHttps](INFO): SBD MOMSN=21340864
2024-07-08T16:45:07.228Z,1720457107.228 [NAL9602](INFO): SBD MO Status=1, MOMSN=5070, MT Status=0, MTMSN=0
2024-07-08T16:45:07.228Z,1720457107.228 [NAL9602](INFO): No messages in MT queue
2024-07-08T16:45:19.281Z,1720457119.281 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240708T162215/Express0023.lzma
2024-07-08T16:45:20.283Z,1720457120.283 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0023.lzma.bak
2024-07-08T16:45:20.283Z,1720457120.283 [DataOverHttps](INFO): SBD MOMSN=21340867
2024-07-08T16:45:26.241Z,1720457126.241 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T16:45:26.245Z,1720457126.245 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T16:45:26.246Z,1720457126.246 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T16:45:37.934Z,1720457137.934 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T16:50:26.812Z,1720457426.812 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T16:50:26.812Z,1720457426.812 [Default:CheckIn:C.Wait] Stopped
2024-07-08T16:50:26.812Z,1720457426.812 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T16:50:26.812Z,1720457426.812 [Default:CheckIn:D] Running Loop=1
2024-07-08T16:50:27.234Z,1720457427.234 [Default:CheckIn:D] Stopped
2024-07-08T16:50:27.234Z,1720457427.234 [Default:CheckIn:E] Running Loop=1
2024-07-08T16:50:27.642Z,1720457427.642 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.728611 min
2024-07-08T16:50:27.642Z,1720457427.642 [Default:CheckIn:E] Stopped
2024-07-08T16:50:27.642Z,1720457427.642 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T16:50:27.642Z,1720457427.642 [Default:CheckIn] Stopped
2024-07-08T16:50:27.642Z,1720457427.642 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T16:50:27.643Z,1720457427.643 [Default:CheckIn](INFO): Running loop #5
2024-07-08T16:50:27.643Z,1720457427.643 [Default:CheckIn] Running Loop=5
2024-07-08T16:50:27.643Z,1720457427.643 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T16:50:27.643Z,1720457427.643 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T16:50:29.624Z,1720457429.624 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165029.00,A,3648.17587,N,12147.28513,W,0.019,0.00,080724,,,A*7D
2024-07-08T16:50:29.626Z,1720457429.626 [NAL9602](INFO): GPS fix at 20240708T165029: (36.802931, -121.788085)
2024-07-08T16:50:29.636Z,1720457429.636 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T16:50:29.636Z,1720457429.636 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T16:50:39.616Z,1720457439.616 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T162215/Courier0025.lzma
2024-07-08T16:50:40.619Z,1720457440.619 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0025.lzma.bak
2024-07-08T16:50:40.619Z,1720457440.619 [DataOverHttps](INFO): SBD MOMSN=21340887
2024-07-08T16:50:57.499Z,1720457457.499 [NAL9602](INFO): SBD MO Status=1, MOMSN=5071, MT Status=0, MTMSN=0
2024-07-08T16:50:57.499Z,1720457457.499 [NAL9602](INFO): No messages in MT queue
2024-07-08T16:51:01.588Z,1720457461.588 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240708T162215/Express0026.lzma
2024-07-08T16:51:02.590Z,1720457462.590 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0026.lzma.bak
2024-07-08T16:51:02.590Z,1720457462.590 [DataOverHttps](INFO): SBD MOMSN=21340894
2024-07-08T16:51:08.848Z,1720457468.848 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T16:51:08.848Z,1720457468.848 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T16:51:08.848Z,1720457468.848 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T16:51:28.197Z,1720457488.197 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T16:56:09.461Z,1720457769.461 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T16:56:09.461Z,1720457769.461 [Default:CheckIn:C.Wait] Stopped
2024-07-08T16:56:09.461Z,1720457769.461 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T16:56:09.461Z,1720457769.461 [Default:CheckIn:D] Running Loop=1
2024-07-08T16:56:09.820Z,1720457769.820 [Default:CheckIn:D] Stopped
2024-07-08T16:56:09.820Z,1720457769.820 [Default:CheckIn:E] Running Loop=1
2024-07-08T16:56:10.207Z,1720457770.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.438375 min
2024-07-08T16:56:10.208Z,1720457770.208 [Default:CheckIn:E] Stopped
2024-07-08T16:56:10.208Z,1720457770.208 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T16:56:10.208Z,1720457770.208 [Default:CheckIn] Stopped
2024-07-08T16:56:10.208Z,1720457770.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T16:56:10.208Z,1720457770.208 [Default:CheckIn](INFO): Running loop #6
2024-07-08T16:56:10.208Z,1720457770.208 [Default:CheckIn] Running Loop=6
2024-07-08T16:56:10.208Z,1720457770.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T16:56:10.208Z,1720457770.208 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T16:56:12.219Z,1720457772.219 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165611.00,A,3648.17339,N,12147.28380,W,0.039,0.00,080724,,,A*7D
2024-07-08T16:56:12.221Z,1720457772.221 [NAL9602](INFO): GPS fix at 20240708T165611: (36.802890, -121.788063)
2024-07-08T16:56:12.232Z,1720457772.232 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T16:56:12.232Z,1720457772.232 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T16:56:21.948Z,1720457781.948 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T162215/Courier0028.lzma
2024-07-08T16:56:22.950Z,1720457782.950 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0028.lzma.bak
2024-07-08T16:56:22.950Z,1720457782.950 [DataOverHttps](INFO): SBD MOMSN=21340905
2024-07-08T16:56:33.223Z,1720457793.223 [NAL9602](INFO): SBD MO Status=2, MOMSN=5072, MT Status=2, MTMSN=0
2024-07-08T16:56:33.223Z,1720457793.223 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T16:56:46.912Z,1720457806.912 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240708T162215/Express0029.lzma
2024-07-08T16:56:47.914Z,1720457807.914 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0029.lzma.bak
2024-07-08T16:56:47.915Z,1720457807.915 [DataOverHttps](INFO): SBD MOMSN=21340908
2024-07-08T16:56:53.852Z,1720457813.852 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T16:56:53.856Z,1720457813.856 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T16:56:53.857Z,1720457813.857 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T16:57:00.695Z,1720457820.695 [NAL9602](INFO): SBD MO Status=1, MOMSN=5072, MT Status=0, MTMSN=0
2024-07-08T16:57:00.695Z,1720457820.695 [NAL9602](INFO): No messages in MT queue
2024-07-08T16:57:31.474Z,1720457851.474 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T17:01:54.434Z,1720458114.434 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T17:01:54.434Z,1720458114.434 [Default:CheckIn:C.Wait] Stopped
2024-07-08T17:01:54.434Z,1720458114.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T17:01:54.434Z,1720458114.434 [Default:CheckIn:D] Running Loop=1
2024-07-08T17:01:54.860Z,1720458114.860 [Default:CheckIn:D] Stopped
2024-07-08T17:01:54.860Z,1720458114.860 [Default:CheckIn:E] Running Loop=1
2024-07-08T17:01:55.224Z,1720458115.224 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.189042 min
2024-07-08T17:01:55.224Z,1720458115.224 [Default:CheckIn:E] Stopped
2024-07-08T17:01:55.224Z,1720458115.224 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T17:01:55.224Z,1720458115.224 [Default:CheckIn] Stopped
2024-07-08T17:01:55.224Z,1720458115.224 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T17:01:55.225Z,1720458115.225 [Default:CheckIn](INFO): Running loop #7
2024-07-08T17:01:55.225Z,1720458115.225 [Default:CheckIn] Running Loop=7
2024-07-08T17:01:55.225Z,1720458115.225 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T17:01:55.225Z,1720458115.225 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T17:01:57.232Z,1720458117.232 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170156.00,A,3648.17532,N,12147.28382,W,0.058,0.00,080724,,,A*75
2024-07-08T17:01:57.234Z,1720458117.234 [NAL9602](INFO): GPS fix at 20240708T170156: (36.802922, -121.788064)
2024-07-08T17:01:57.244Z,1720458117.244 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T17:01:57.244Z,1720458117.244 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T17:02:06.928Z,1720458126.928 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240708T162215/Courier0031.lzma
2024-07-08T17:02:07.930Z,1720458127.930 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0031.lzma.bak
2024-07-08T17:02:07.930Z,1720458127.930 [DataOverHttps](INFO): SBD MOMSN=21340937
2024-07-08T17:02:25.510Z,1720458145.510 [NAL9602](INFO): SBD MO Status=1, MOMSN=5073, MT Status=0, MTMSN=0
2024-07-08T17:02:25.510Z,1720458145.510 [NAL9602](INFO): No messages in MT queue
2024-07-08T17:02:29.888Z,1720458149.888 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240708T162215/Express0032.lzma
2024-07-08T17:02:30.897Z,1720458150.897 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0032.lzma.bak
2024-07-08T17:02:30.897Z,1720458150.897 [DataOverHttps](INFO): SBD MOMSN=21340946
2024-07-08T17:02:37.284Z,1720458157.284 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T17:02:37.284Z,1720458157.284 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T17:02:37.284Z,1720458157.284 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T17:02:56.217Z,1720458176.217 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T17:07:37.774Z,1720458457.774 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T17:07:37.774Z,1720458457.774 [Default:CheckIn:C.Wait] Stopped
2024-07-08T17:07:37.779Z,1720458457.779 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T17:07:37.779Z,1720458457.779 [Default:CheckIn:D] Running Loop=1
2024-07-08T17:07:38.198Z,1720458458.198 [Default:CheckIn:D] Stopped
2024-07-08T17:07:38.198Z,1720458458.198 [Default:CheckIn:E] Running Loop=1
2024-07-08T17:07:38.581Z,1720458458.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.911344 min
2024-07-08T17:07:38.581Z,1720458458.581 [Default:CheckIn:E] Stopped
2024-07-08T17:07:38.581Z,1720458458.581 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T17:07:38.581Z,1720458458.581 [Default:CheckIn] Stopped
2024-07-08T17:07:38.581Z,1720458458.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T17:07:38.581Z,1720458458.581 [Default:CheckIn](INFO): Running loop #8
2024-07-08T17:07:38.581Z,1720458458.581 [Default:CheckIn] Running Loop=8
2024-07-08T17:07:38.581Z,1720458458.581 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T17:07:38.582Z,1720458458.582 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T17:07:40.582Z,1720458460.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170739.00,A,3648.17359,N,12147.28204,W,0.117,0.00,080724,,,A*74
2024-07-08T17:07:40.584Z,1720458460.584 [NAL9602](INFO): GPS fix at 20240708T170739: (36.802893, -121.788034)
2024-07-08T17:07:40.595Z,1720458460.595 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T17:07:40.595Z,1720458460.595 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T17:07:50.346Z,1720458470.346 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240708T162215/Courier0034.lzma
2024-07-08T17:07:51.349Z,1720458471.349 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0034.lzma.bak
2024-07-08T17:07:51.349Z,1720458471.349 [DataOverHttps](INFO): SBD MOMSN=21340957
2024-07-08T17:07:51.498Z,1720458471.498 [NAL9602](INFO): SBD MO Status=1, MOMSN=5074, MT Status=0, MTMSN=0
2024-07-08T17:07:51.498Z,1720458471.498 [NAL9602](INFO): No messages in MT queue
2024-07-08T17:08:12.010Z,1720458492.010 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240708T162215/Express0035.lzma
2024-07-08T17:08:13.013Z,1720458493.013 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0035.lzma.bak
2024-07-08T17:08:13.013Z,1720458493.013 [DataOverHttps](INFO): SBD MOMSN=21340960
2024-07-08T17:08:22.192Z,1720458502.192 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T17:08:23.451Z,1720458503.451 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T17:08:23.451Z,1720458503.451 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T17:08:23.451Z,1720458503.451 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T17:13:24.006Z,1720458804.006 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T17:13:24.006Z,1720458804.006 [Default:CheckIn:C.Wait] Stopped
2024-07-08T17:13:24.011Z,1720458804.011 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T17:13:24.011Z,1720458804.011 [Default:CheckIn:D] Running Loop=1
2024-07-08T17:13:24.412Z,1720458804.412 [Default:CheckIn:D] Stopped
2024-07-08T17:13:24.412Z,1720458804.412 [Default:CheckIn:E] Running Loop=1
2024-07-08T17:13:24.802Z,1720458804.802 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.681580 min
2024-07-08T17:13:24.802Z,1720458804.802 [Default:CheckIn:E] Stopped
2024-07-08T17:13:24.802Z,1720458804.802 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T17:13:24.802Z,1720458804.802 [Default:CheckIn] Stopped
2024-07-08T17:13:24.802Z,1720458804.802 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T17:13:24.802Z,1720458804.802 [Default:CheckIn](INFO): Running loop #9
2024-07-08T17:13:24.802Z,1720458804.802 [Default:CheckIn] Running Loop=9
2024-07-08T17:13:24.802Z,1720458804.802 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T17:13:24.802Z,1720458804.802 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T17:18:24.833Z,1720459104.833 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T17:13:24.8Z
2024-07-08T17:18:24.833Z,1720459104.833 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T17:18:24.834Z,1720459104.834 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T17:18:28.886Z,1720459108.886 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T17:18:34.504Z,1720459114.504 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T162215/Courier0037.lzma
2024-07-08T17:18:35.507Z,1720459115.507 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0037.lzma.bak
2024-07-08T17:18:35.507Z,1720459115.507 [DataOverHttps](INFO): SBD MOMSN=21340989
2024-07-08T17:18:57.437Z,1720459137.437 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240708T162215/Express0038.lzma
2024-07-08T17:18:58.439Z,1720459138.439 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0038.lzma.bak
2024-07-08T17:18:58.439Z,1720459138.439 [DataOverHttps](INFO): SBD MOMSN=21340991
2024-07-08T17:18:59.594Z,1720459139.594 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T17:19:04.860Z,1720459144.860 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T17:19:04.860Z,1720459144.860 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T17:19:04.860Z,1720459144.860 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T17:24:05.485Z,1720459445.485 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T17:24:05.485Z,1720459445.485 [Default:CheckIn:C.Wait] Stopped
2024-07-08T17:24:05.485Z,1720459445.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T17:24:05.489Z,1720459445.489 [Default:CheckIn:D] Running Loop=1
2024-07-08T17:24:05.879Z,1720459445.879 [Default:CheckIn:D] Stopped
2024-07-08T17:24:05.879Z,1720459445.879 [Default:CheckIn:E] Running Loop=1
2024-07-08T17:24:06.297Z,1720459446.297 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.372697 min
2024-07-08T17:24:06.297Z,1720459446.297 [Default:CheckIn:E] Stopped
2024-07-08T17:24:06.297Z,1720459446.297 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T17:24:06.298Z,1720459446.298 [Default:CheckIn] Stopped
2024-07-08T17:24:06.298Z,1720459446.298 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T17:24:06.298Z,1720459446.298 [Default:CheckIn](INFO): Running loop #10
2024-07-08T17:24:06.298Z,1720459446.298 [Default:CheckIn] Running Loop=10
2024-07-08T17:24:06.298Z,1720459446.298 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T17:24:06.298Z,1720459446.298 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T17:24:07.881Z,1720459447.881 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T17:24:07.882Z,1720459447.882 [NAL9602] Data Fault, FailCount= 1
2024-07-08T17:24:07.882Z,1720459447.882 [NAL9602](ERROR): Data Fault
2024-07-08T17:24:07.925Z,1720459447.925 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T17:24:08.286Z,1720459448.286 [NAL9602](INFO): Powering down
2024-07-08T17:24:09.121Z,1720459449.121 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T17:24:09.121Z,1720459449.121 [NAL9602] No Fault, FailCount= 1
2024-07-08T17:24:38.595Z,1720459478.595 [NAL9602](INFO): Powering up NAL9602
2024-07-08T17:24:49.494Z,1720459489.494 [NAL9602](INFO): NAL9602 initialized
2024-07-08T17:29:06.403Z,1720459746.403 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T17:24:06.3Z
2024-07-08T17:29:06.403Z,1720459746.403 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T17:29:06.403Z,1720459746.403 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T17:29:15.848Z,1720459755.848 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T162215/Courier0040.lzma
2024-07-08T17:29:16.851Z,1720459756.851 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0040.lzma.bak
2024-07-08T17:29:16.851Z,1720459756.851 [DataOverHttps](INFO): SBD MOMSN=21341019
2024-07-08T17:29:37.557Z,1720459777.557 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20240708T162215/Express0041.lzma
2024-07-08T17:29:38.559Z,1720459778.559 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0041.lzma.bak
2024-07-08T17:29:38.559Z,1720459778.559 [DataOverHttps](INFO): SBD MOMSN=21341021
2024-07-08T17:29:44.807Z,1720459784.807 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T17:29:44.807Z,1720459784.807 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T17:29:44.807Z,1720459784.807 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T17:29:52.034Z,1720459792.034 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T17:30:22.737Z,1720459822.737 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T17:34:45.357Z,1720460085.357 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T17:34:45.357Z,1720460085.357 [Default:CheckIn:C.Wait] Stopped
2024-07-08T17:34:45.357Z,1720460085.357 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T17:34:45.357Z,1720460085.357 [Default:CheckIn:D] Running Loop=1
2024-07-08T17:34:45.782Z,1720460085.782 [Default:CheckIn:D] Stopped
2024-07-08T17:34:45.782Z,1720460085.782 [Default:CheckIn:E] Running Loop=1
2024-07-08T17:34:46.194Z,1720460086.194 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.037744 min
2024-07-08T17:34:46.194Z,1720460086.194 [Default:CheckIn:E] Stopped
2024-07-08T17:34:46.194Z,1720460086.194 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T17:34:46.194Z,1720460086.194 [Default:CheckIn] Stopped
2024-07-08T17:34:46.194Z,1720460086.194 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T17:34:46.194Z,1720460086.194 [Default:CheckIn](INFO): Running loop #11
2024-07-08T17:34:46.195Z,1720460086.195 [Default:CheckIn] Running Loop=11
2024-07-08T17:34:46.195Z,1720460086.195 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T17:34:46.195Z,1720460086.195 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T17:34:47.759Z,1720460087.759 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:35:03.117Z,1720460103.117 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:35:18.059Z,1720460118.059 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:35:33.412Z,1720460133.412 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:35:48.764Z,1720460148.764 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:36:04.520Z,1720460164.520 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:36:19.885Z,1720460179.885 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:36:35.223Z,1720460195.223 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:36:50.171Z,1720460210.171 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:37:05.119Z,1720460225.119 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T17:37:12.414Z,1720460232.414 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2024-07-08T17:37:12.416Z,1720460232.416 [BPC1](INFO): Received data from all battery sticks.
2024-07-08T17:39:46.335Z,1720460386.335 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T17:34:46.2Z
2024-07-08T17:39:46.335Z,1720460386.335 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T17:39:46.335Z,1720460386.335 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T17:39:49.953Z,1720460389.953 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T17:39:54.884Z,1720460394.884 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240708T162215/Courier0043.lzma
2024-07-08T17:39:55.871Z,1720460395.871 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0043.lzma.bak
2024-07-08T17:39:55.871Z,1720460395.871 [DataOverHttps](INFO): SBD MOMSN=21341050
2024-07-08T17:40:17.780Z,1720460417.780 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240708T162215/Express0044.lzma
2024-07-08T17:40:18.783Z,1720460418.783 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0044.lzma.bak
2024-07-08T17:40:18.783Z,1720460418.783 [DataOverHttps](INFO): SBD MOMSN=21341052
2024-07-08T17:40:20.658Z,1720460420.658 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T17:40:25.128Z,1720460425.128 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T17:40:25.133Z,1720460425.133 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T17:40:25.133Z,1720460425.133 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T17:45:25.784Z,1720460725.784 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T17:45:25.784Z,1720460725.784 [Default:CheckIn:C.Wait] Stopped
2024-07-08T17:45:25.784Z,1720460725.784 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T17:45:25.784Z,1720460725.784 [Default:CheckIn:D] Running Loop=1
2024-07-08T17:45:26.192Z,1720460726.192 [Default:CheckIn:D] Stopped
2024-07-08T17:45:26.193Z,1720460726.193 [Default:CheckIn:E] Running Loop=1
2024-07-08T17:45:26.600Z,1720460726.600 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.711255 min
2024-07-08T17:45:26.600Z,1720460726.600 [Default:CheckIn:E] Stopped
2024-07-08T17:45:26.600Z,1720460726.600 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T17:45:26.600Z,1720460726.600 [Default:CheckIn] Stopped
2024-07-08T17:45:26.600Z,1720460726.600 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T17:45:26.600Z,1720460726.600 [Default:CheckIn](INFO): Running loop #12
2024-07-08T17:45:26.600Z,1720460726.601 [Default:CheckIn] Running Loop=12
2024-07-08T17:45:26.601Z,1720460726.601 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T17:45:26.601Z,1720460726.601 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T17:45:28.190Z,1720460728.190 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T17:45:28.190Z,1720460728.190 [NAL9602] Data Fault, FailCount= 2
2024-07-08T17:45:28.190Z,1720460728.190 [NAL9602](ERROR): Data Fault
2024-07-08T17:45:28.209Z,1720460728.209 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T17:45:28.598Z,1720460728.598 [NAL9602](INFO): Powering down
2024-07-08T17:45:29.420Z,1720460729.420 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T17:45:29.420Z,1720460729.420 [NAL9602] No Fault, FailCount= 2
2024-07-08T17:45:58.895Z,1720460758.895 [NAL9602](INFO): Powering up NAL9602
2024-07-08T17:46:09.801Z,1720460769.801 [NAL9602](INFO): NAL9602 initialized
2024-07-08T17:50:26.841Z,1720461026.841 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T17:45:26.6Z
2024-07-08T17:50:26.841Z,1720461026.841 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T17:50:26.841Z,1720461026.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T17:50:37.097Z,1720461037.097 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T162215/Courier0046.lzma
2024-07-08T17:50:38.100Z,1720461038.100 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0046.lzma.bak
2024-07-08T17:50:38.100Z,1720461038.100 [DataOverHttps](INFO): SBD MOMSN=21341087
2024-07-08T17:50:59.690Z,1720461059.690 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240708T162215/Express0047.lzma
2024-07-08T17:51:00.692Z,1720461060.692 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0047.lzma.bak
2024-07-08T17:51:00.692Z,1720461060.692 [DataOverHttps](INFO): SBD MOMSN=21341089
2024-07-08T17:51:06.858Z,1720461066.858 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T17:51:06.858Z,1720461066.858 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T17:51:06.862Z,1720461066.862 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T17:51:12.487Z,1720461072.487 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T17:51:43.186Z,1720461103.186 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T17:56:07.430Z,1720461367.430 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T17:56:07.431Z,1720461367.431 [Default:CheckIn:C.Wait] Stopped
2024-07-08T17:56:07.431Z,1720461367.431 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T17:56:07.431Z,1720461367.431 [Default:CheckIn:D] Running Loop=1
2024-07-08T17:56:07.831Z,1720461367.831 [Default:CheckIn:D] Stopped
2024-07-08T17:56:07.831Z,1720461367.831 [Default:CheckIn:E] Running Loop=1
2024-07-08T17:56:08.279Z,1720461368.279 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.405225 min
2024-07-08T17:56:08.280Z,1720461368.280 [Default:CheckIn:E] Stopped
2024-07-08T17:56:08.280Z,1720461368.280 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T17:56:08.280Z,1720461368.280 [Default:CheckIn] Stopped
2024-07-08T17:56:08.280Z,1720461368.280 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T17:56:08.280Z,1720461368.280 [Default:CheckIn](INFO): Running loop #13
2024-07-08T17:56:08.280Z,1720461368.280 [Default:CheckIn] Running Loop=13
2024-07-08T17:56:08.280Z,1720461368.280 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T17:56:08.280Z,1720461368.280 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T17:56:40.939Z,1720461400.939 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T17:56:41.014Z,1720461401.014 [NAL9602](ERROR): received:
+CSQ:0
OK74, 0, 0, 0, 0
OK
2024-07-08T18:01:08.400Z,1720461668.400 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T17:56:08.3Z
2024-07-08T18:01:08.400Z,1720461668.400 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T18:01:08.400Z,1720461668.400 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T18:01:12.829Z,1720461672.829 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T18:01:18.236Z,1720461678.236 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T162215/Courier0049.lzma
2024-07-08T18:01:19.238Z,1720461679.238 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0049.lzma.bak
2024-07-08T18:01:19.238Z,1720461679.239 [DataOverHttps](INFO): SBD MOMSN=21341120
2024-07-08T18:01:40.404Z,1720461700.404 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240708T162215/Express0050.lzma
2024-07-08T18:01:41.406Z,1720461701.406 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0050.lzma.bak
2024-07-08T18:01:41.407Z,1720461701.407 [DataOverHttps](INFO): SBD MOMSN=21341122
2024-07-08T18:01:43.550Z,1720461703.550 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T18:01:51.728Z,1720461711.728 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T18:01:51.728Z,1720461711.728 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T18:01:51.740Z,1720461711.740 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T18:06:52.312Z,1720462012.312 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T18:06:52.312Z,1720462012.312 [Default:CheckIn:C.Wait] Stopped
2024-07-08T18:06:52.312Z,1720462012.312 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T18:06:52.312Z,1720462012.312 [Default:CheckIn:D] Running Loop=1
2024-07-08T18:06:52.711Z,1720462012.711 [Default:CheckIn:D] Stopped
2024-07-08T18:06:52.711Z,1720462012.711 [Default:CheckIn:E] Running Loop=1
2024-07-08T18:06:53.115Z,1720462013.115 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.153239 min
2024-07-08T18:06:53.115Z,1720462013.115 [Default:CheckIn:E] Stopped
2024-07-08T18:06:53.116Z,1720462013.116 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T18:06:53.116Z,1720462013.116 [Default:CheckIn] Stopped
2024-07-08T18:06:53.116Z,1720462013.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T18:06:53.116Z,1720462013.116 [Default:CheckIn](INFO): Running loop #14
2024-07-08T18:06:53.116Z,1720462013.116 [Default:CheckIn] Running Loop=14
2024-07-08T18:06:53.116Z,1720462013.116 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T18:06:53.116Z,1720462013.116 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T18:06:54.718Z,1720462014.718 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T18:06:54.718Z,1720462014.718 [NAL9602] Data Fault, FailCount= 3
2024-07-08T18:06:54.718Z,1720462014.718 [NAL9602](ERROR): Data Fault
2024-07-08T18:06:54.738Z,1720462014.738 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T18:06:55.130Z,1720462015.130 [NAL9602](INFO): Powering down
2024-07-08T18:06:55.964Z,1720462015.964 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T18:06:55.964Z,1720462015.964 [NAL9602] No Fault, FailCount= 3
2024-07-08T18:07:25.437Z,1720462045.437 [NAL9602](INFO): Powering up NAL9602
2024-07-08T18:07:36.346Z,1720462056.346 [NAL9602](INFO): NAL9602 initialized
2024-07-08T18:11:53.316Z,1720462313.316 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T18:06:53.1Z
2024-07-08T18:11:53.317Z,1720462313.317 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T18:11:53.317Z,1720462313.317 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T18:12:09.671Z,1720462329.671 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T162215/Courier0052.lzma
2024-07-08T18:12:10.674Z,1720462330.674 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0052.lzma.bak
2024-07-08T18:12:10.674Z,1720462330.674 [DataOverHttps](INFO): SBD MOMSN=21341151
2024-07-08T18:12:38.212Z,1720462358.212 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20240708T162215/Express0053.lzma
2024-07-08T18:12:39.153Z,1720462359.153 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T18:12:39.218Z,1720462359.218 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0053.lzma.bak
2024-07-08T18:12:39.218Z,1720462359.218 [DataOverHttps](INFO): SBD MOMSN=21341153
2024-07-08T18:12:45.645Z,1720462365.645 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T18:12:45.645Z,1720462365.645 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T18:12:45.645Z,1720462365.645 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T18:13:09.861Z,1720462389.861 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T18:17:46.210Z,1720462666.210 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T18:17:46.211Z,1720462666.211 [Default:CheckIn:C.Wait] Stopped
2024-07-08T18:17:46.211Z,1720462666.211 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T18:17:46.211Z,1720462666.211 [Default:CheckIn:D] Running Loop=1
2024-07-08T18:17:46.608Z,1720462666.608 [Default:CheckIn:D] Stopped
2024-07-08T18:17:46.608Z,1720462666.608 [Default:CheckIn:E] Running Loop=1
2024-07-08T18:17:47.012Z,1720462667.012 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.051522 min
2024-07-08T18:17:47.012Z,1720462667.012 [Default:CheckIn:E] Stopped
2024-07-08T18:17:47.012Z,1720462667.012 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T18:17:47.012Z,1720462667.012 [Default:CheckIn] Stopped
2024-07-08T18:17:47.012Z,1720462667.012 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T18:17:47.012Z,1720462667.012 [Default:CheckIn](INFO): Running loop #15
2024-07-08T18:17:47.013Z,1720462667.013 [Default:CheckIn] Running Loop=15
2024-07-08T18:17:47.013Z,1720462667.013 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T18:17:47.013Z,1720462667.013 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T18:17:48.617Z,1720462668.617 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T18:17:48.617Z,1720462668.617 [NAL9602] Data Fault, FailCount= 4
2024-07-08T18:17:48.617Z,1720462668.617 [NAL9602](ERROR): Data Fault
2024-07-08T18:17:48.637Z,1720462668.637 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T18:17:49.021Z,1720462669.021 [NAL9602](INFO): Powering down
2024-07-08T18:17:49.848Z,1720462669.848 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T18:17:49.848Z,1720462669.848 [NAL9602] No Fault, FailCount= 4
2024-07-08T18:18:19.317Z,1720462699.317 [NAL9602](INFO): Powering up NAL9602
2024-07-08T18:18:30.226Z,1720462710.226 [NAL9602](INFO): NAL9602 initialized
2024-07-08T18:22:33.084Z,1720462953.084 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T18:22:47.235Z,1720462967.235 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T18:17:47.0Z
2024-07-08T18:22:47.235Z,1720462967.235 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T18:22:47.235Z,1720462967.235 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T18:22:57.352Z,1720462977.352 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T162215/Courier0055.lzma
2024-07-08T18:22:58.354Z,1720462978.354 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0055.lzma.bak
2024-07-08T18:22:58.354Z,1720462978.354 [DataOverHttps](INFO): SBD MOMSN=21341182
2024-07-08T18:23:18.992Z,1720462998.992 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20240708T162215/Express0056.lzma
2024-07-08T18:23:19.994Z,1720462999.994 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0056.lzma.bak
2024-07-08T18:23:19.994Z,1720462999.994 [DataOverHttps](INFO): SBD MOMSN=21341184
2024-07-08T18:23:28.067Z,1720463008.067 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T18:23:28.067Z,1720463008.067 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T18:23:28.067Z,1720463008.067 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T18:23:34.113Z,1720463014.113 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T18:24:04.797Z,1720463044.797 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T18:28:28.655Z,1720463308.655 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T18:28:28.655Z,1720463308.655 [Default:CheckIn:C.Wait] Stopped
2024-07-08T18:28:28.655Z,1720463308.655 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T18:28:28.655Z,1720463308.655 [Default:CheckIn:D] Running Loop=1
2024-07-08T18:28:29.049Z,1720463309.049 [Default:CheckIn:D] Stopped
2024-07-08T18:28:29.049Z,1720463309.049 [Default:CheckIn:E] Running Loop=1
2024-07-08T18:28:29.419Z,1720463309.419 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.758862 min
2024-07-08T18:28:29.419Z,1720463309.419 [Default:CheckIn:E] Stopped
2024-07-08T18:28:29.419Z,1720463309.419 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T18:28:29.419Z,1720463309.419 [Default:CheckIn] Stopped
2024-07-08T18:28:29.419Z,1720463309.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T18:28:29.419Z,1720463309.419 [Default:CheckIn](INFO): Running loop #16
2024-07-08T18:28:29.419Z,1720463309.419 [Default:CheckIn] Running Loop=16
2024-07-08T18:28:29.420Z,1720463309.420 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T18:28:29.420Z,1720463309.420 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T18:28:31.434Z,1720463311.434 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T18:28:31.434Z,1720463311.434 [NAL9602] Data Fault, FailCount= 5
2024-07-08T18:28:31.434Z,1720463311.434 [NAL9602](ERROR): Data Fault
2024-07-08T18:28:31.473Z,1720463311.473 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T18:28:31.473Z,1720463311.473 [CBIT](CRITICAL): Data Fault in component: NAL9602
2024-07-08T18:28:31.832Z,1720463311.832 [NAL9602](INFO): Powering down
2024-07-08T18:28:31.850Z,1720463311.850 [CommandExec](FAULT): Scheduling is paused
2024-07-08T18:28:31.851Z,1720463311.851 [CBIT](INFO): Critical error at 20240708T182831
2024-07-08T18:28:31.851Z,1720463311.851 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-07-08T18:30:31.443Z,1720463431.443 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T18:30:31.443Z,1720463431.443 [NAL9602] No Fault, FailCount= 5
2024-07-08T18:30:31.820Z,1720463431.820 [NAL9602](INFO): Powering up NAL9602
2024-07-08T18:30:42.729Z,1720463442.729 [NAL9602](INFO): NAL9602 initialized
2024-07-08T18:32:33.440Z,1720463553.440 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T18:33:29.612Z,1720463609.612 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T18:28:29.4Z
2024-07-08T18:33:29.612Z,1720463609.612 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T18:33:29.612Z,1720463609.612 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T18:33:39.108Z,1720463619.108 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20240708T162215/Courier0058.lzma
2024-07-08T18:33:40.090Z,1720463620.090 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0058.lzma.bak
2024-07-08T18:33:40.090Z,1720463620.090 [DataOverHttps](INFO): SBD MOMSN=21341217
2024-07-08T18:34:02.816Z,1720463642.816 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20240708T162215/Express0059.lzma
2024-07-08T18:34:03.818Z,1720463643.818 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0059.lzma.bak
2024-07-08T18:34:03.818Z,1720463643.818 [DataOverHttps](INFO): SBD MOMSN=21341220
2024-07-08T18:34:10.026Z,1720463650.026 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T18:34:10.026Z,1720463650.026 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T18:34:10.026Z,1720463650.026 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T18:35:45.341Z,1720463745.341 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T18:36:16.350Z,1720463776.350 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T18:39:10.498Z,1720463950.498 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T18:39:10.498Z,1720463950.498 [Default:CheckIn:C.Wait] Stopped
2024-07-08T18:39:10.498Z,1720463950.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T18:39:10.498Z,1720463950.498 [Default:CheckIn:D] Running Loop=1
2024-07-08T18:39:10.893Z,1720463950.893 [Default:CheckIn:D] Stopped
2024-07-08T18:39:10.893Z,1720463950.893 [Default:CheckIn:E] Running Loop=1
2024-07-08T18:39:11.301Z,1720463951.301 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.456258 min
2024-07-08T18:39:11.302Z,1720463951.302 [Default:CheckIn:E] Stopped
2024-07-08T18:39:11.302Z,1720463951.302 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T18:39:11.302Z,1720463951.302 [Default:CheckIn] Stopped
2024-07-08T18:39:11.302Z,1720463951.302 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T18:39:11.302Z,1720463951.302 [Default:CheckIn](INFO): Running loop #17
2024-07-08T18:39:11.302Z,1720463951.302 [Default:CheckIn] Running Loop=17
2024-07-08T18:39:11.302Z,1720463951.302 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T18:39:11.302Z,1720463951.302 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T18:39:44.003Z,1720463984.003 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T18:39:44.077Z,1720463984.077 [NAL9602](ERROR): received:
+CSQ:0
OK74, 0, 0, 0, 0
OK
2024-07-08T18:42:09.844Z,1720464129.844 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:42:17.116Z,1720464137.116 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:42:32.064Z,1720464152.064 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:42:33.688Z,1720464153.688 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T18:42:47.820Z,1720464167.820 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:43:03.981Z,1720464183.981 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:43:20.142Z,1720464200.142 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:43:35.495Z,1720464215.495 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:43:50.440Z,1720464230.440 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:44:05.791Z,1720464245.791 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:44:11.469Z,1720464251.469 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T18:39:11.3Z
2024-07-08T18:44:11.469Z,1720464251.469 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T18:44:11.470Z,1720464251.470 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T18:44:15.495Z,1720464255.495 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T18:44:20.740Z,1720464260.740 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T18:44:21.677Z,1720464261.677 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T162215/Courier0061.lzma
2024-07-08T18:44:22.675Z,1720464262.675 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0061.lzma.bak
2024-07-08T18:44:22.675Z,1720464262.675 [DataOverHttps](INFO): SBD MOMSN=21341253
2024-07-08T18:44:37.310Z,1720464277.310 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2024-07-08T18:44:37.312Z,1720464277.312 [BPC1](INFO): Received data from all battery sticks.
2024-07-08T18:44:42.208Z,1720464282.208 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240708T162215/Express0062.lzma
2024-07-08T18:44:43.215Z,1720464283.215 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0062.lzma.bak
2024-07-08T18:44:43.215Z,1720464283.215 [DataOverHttps](INFO): SBD MOMSN=21341255
2024-07-08T18:44:46.198Z,1720464286.198 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T18:44:49.556Z,1720464289.556 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T18:44:49.556Z,1720464289.556 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T18:44:49.556Z,1720464289.556 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T18:49:50.072Z,1720464590.072 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T18:49:50.073Z,1720464590.073 [Default:CheckIn:C.Wait] Stopped
2024-07-08T18:49:50.073Z,1720464590.073 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T18:49:50.073Z,1720464590.073 [Default:CheckIn:D] Running Loop=1
2024-07-08T18:49:50.467Z,1720464590.467 [Default:CheckIn:D] Stopped
2024-07-08T18:49:50.467Z,1720464590.467 [Default:CheckIn:E] Running Loop=1
2024-07-08T18:49:50.877Z,1720464590.877 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 145.115837 min
2024-07-08T18:49:50.877Z,1720464590.877 [Default:CheckIn:E] Stopped
2024-07-08T18:49:50.877Z,1720464590.877 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T18:49:50.877Z,1720464590.877 [Default:CheckIn] Stopped
2024-07-08T18:49:50.877Z,1720464590.877 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T18:49:50.877Z,1720464590.877 [Default:CheckIn](INFO): Running loop #18
2024-07-08T18:49:50.877Z,1720464590.877 [Default:CheckIn] Running Loop=18
2024-07-08T18:49:50.877Z,1720464590.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T18:49:50.877Z,1720464590.877 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T18:49:52.467Z,1720464592.467 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T18:49:52.467Z,1720464592.467 [NAL9602] Data Fault, FailCount= 1
2024-07-08T18:49:52.467Z,1720464592.467 [NAL9602](ERROR): Data Fault
2024-07-08T18:49:52.486Z,1720464592.486 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T18:49:52.870Z,1720464592.870 [NAL9602](INFO): Powering down
2024-07-08T18:49:53.700Z,1720464593.700 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T18:49:53.700Z,1720464593.700 [NAL9602] No Fault, FailCount= 1
2024-07-08T18:50:23.169Z,1720464623.169 [NAL9602](INFO): Powering up NAL9602
2024-07-08T18:50:34.086Z,1720464634.086 [NAL9602](INFO): NAL9602 initialized
2024-07-08T18:52:33.696Z,1720464753.696 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T18:54:51.077Z,1720464891.077 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T18:49:50.9Z
2024-07-08T18:54:51.077Z,1720464891.077 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T18:54:51.077Z,1720464891.077 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T18:55:00.516Z,1720464900.516 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240708T162215/Courier0064.lzma
2024-07-08T18:55:01.518Z,1720464901.518 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0064.lzma.bak
2024-07-08T18:55:01.519Z,1720464901.519 [DataOverHttps](INFO): SBD MOMSN=21341283
2024-07-08T18:55:22.261Z,1720464922.261 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20240708T162215/Express0065.lzma
2024-07-08T18:55:23.263Z,1720464923.263 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0065.lzma.bak
2024-07-08T18:55:23.263Z,1720464923.263 [DataOverHttps](INFO): SBD MOMSN=21341285
2024-07-08T18:55:29.460Z,1720464929.460 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T18:55:29.465Z,1720464929.465 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T18:55:29.465Z,1720464929.465 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T18:55:36.702Z,1720464936.702 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T18:55:58.793Z,1720464958.793 [CommandExec](IMPORTANT): got command quit
2024-07-08T18:55:59.797Z,1720464959.797 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:55:59.797Z,1720464959.797 [CommandExec](INFO): Uninitializing the command executive.
2024-07-08T18:55:59.797Z,1720464959.797 [CommandExec](INFO): Uninitializing the command scheduler.
2024-07-08T18:55:59.797Z,1720464959.797 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:55:59.841Z,1720464959.841 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-07-08T18:55:59.841Z,1720464959.841 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-07-08T18:55:59.841Z,1720464959.841 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:55:59.842Z,1720464959.842 [NavChartDb](INFO): Join timeout helper Thread ID is 8907
2024-07-08T18:55:59.897Z,1720464959.897 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:55:59.897Z,1720464959.897 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:55:59.901Z,1720464959.901 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-07-08T18:55:59.901Z,1720464959.901 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:55:59.901Z,1720464959.901 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8908
2024-07-08T18:56:00.373Z,1720464960.373 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:56:00.374Z,1720464960.374 [WetLabsBB2FL](INFO): Powering down
2024-07-08T18:56:00.374Z,1720464960.374 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:00.381Z,1720464960.381 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-07-08T18:56:00.381Z,1720464960.381 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:00.381Z,1720464960.381 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8909
2024-07-08T18:56:00.653Z,1720464960.653 [CTD_Seabird](INFO): Powering down
2024-07-08T18:56:00.667Z,1720464960.667 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:56:00.668Z,1720464960.668 [CTD_Seabird](INFO): Powering down
2024-07-08T18:56:00.681Z,1720464960.681 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:00.697Z,1720464960.697 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-07-08T18:56:00.697Z,1720464960.697 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:00.697Z,1720464960.697 [Radio_Surface](INFO): Join timeout helper Thread ID is 8910
2024-07-08T18:56:00.737Z,1720464960.737 [Radio_Surface](INFO): Powering down
2024-07-08T18:56:00.738Z,1720464960.738 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:56:00.738Z,1720464960.738 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:00.757Z,1720464960.757 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-07-08T18:56:00.757Z,1720464960.757 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:00.757Z,1720464960.757 [Onboard](INFO): Join timeout helper Thread ID is 8911
2024-07-08T18:56:01.377Z,1720464961.377 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-07-08T18:56:02.705Z,1720464962.705 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:56:02.705Z,1720464962.705 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:02.717Z,1720464962.717 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-07-08T18:56:02.718Z,1720464962.718 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:02.718Z,1720464962.718 [DataOverHttps](INFO): Join timeout helper Thread ID is 8912
2024-07-08T18:56:03.385Z,1720464963.385 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:56:03.385Z,1720464963.385 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:03.401Z,1720464963.401 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-07-08T18:56:03.401Z,1720464963.401 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:03.402Z,1720464963.402 [BackseatComponent](INFO): Join timeout helper Thread ID is 8913
2024-07-08T18:56:03.565Z,1720464963.565 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:56:03.565Z,1720464963.565 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:03.587Z,1720464963.587 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-07-08T18:56:03.587Z,1720464963.587 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:03.587Z,1720464963.587 [logger](INFO): Join timeout helper Thread ID is 8914
2024-07-08T18:56:03.593Z,1720464963.593 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:56:03.593Z,1720464963.593 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:03.594Z,1720464963.594 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-07-08T18:56:03.594Z,1720464963.594 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:03.595Z,1720464963.595 [CommandLine](INFO): Join timeout helper Thread ID is 8915
2024-07-08T18:56:03.693Z,1720464963.693 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:56:03.693Z,1720464963.693 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:03.713Z,1720464963.713 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-07-08T18:56:03.713Z,1720464963.713 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:03.714Z,1720464963.714 [CommandExec](INFO): Join timeout helper Thread ID is 8916
2024-07-08T18:56:03.715Z,1720464963.715 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-07-08T18:56:03.715Z,1720464963.715 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:03.715Z,1720464963.715 [controlThread](INFO): Join timeout helper Thread ID is 8917
2024-07-08T18:56:03.753Z,1720464963.753 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-08T18:56:03.753Z,1720464963.753 [controlThread](DEBUG): Uninitializing ControlThread
2024-07-08T18:56:03.753Z,1720464963.753 [AHRS_M2](INFO): Powering down
2024-07-08T18:56:03.826Z,1720464963.826 [NAL9602](INFO): Powering down
2024-07-08T18:56:03.827Z,1720464963.827 [Sonardyne_Nano](INFO): Powering down
2024-07-08T18:56:03.945Z,1720464963.945 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-07-08T18:56:03.946Z,1720464963.946 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-07-08T18:56:03.947Z,1720464963.947 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-07-08T18:56:03.947Z,1720464963.947 [MissionManager](INFO): Uninitializing Mission Default
2024-07-08T18:56:03.947Z,1720464963.947 [Default] Stopped
2024-07-08T18:56:03.947Z,1720464963.947 [Default](DEBUG): Aggregate::uninitialize Default
2024-07-08T18:56:03.947Z,1720464963.947 [Default:B.GoToSurface] Stopped
2024-07-08T18:56:03.947Z,1720464963.947 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-08T18:56:03.947Z,1720464963.947 [Default:CheckIn] Stopped
2024-07-08T18:56:03.948Z,1720464963.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T18:56:03.948Z,1720464963.948 [Default:CheckIn:C.Wait] Stopped
2024-07-08T18:56:03.948Z,1720464963.948 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T18:56:03.950Z,1720464963.950 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-07-08T18:56:03.951Z,1720464963.951 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-07-08T18:56:03.951Z,1720464963.951 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-07-08T18:56:03.951Z,1720464963.951 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-07-08T18:56:03.952Z,1720464963.952 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-07-08T18:56:03.952Z,1720464963.952 [BuoyancyServo](INFO): Powering down
2024-07-08T18:56:03.965Z,1720464963.965 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-07-08T18:56:03.965Z,1720464963.965 [ElevatorServo](INFO): Powering down
2024-07-08T18:56:03.966Z,1720464963.966 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-07-08T18:56:03.966Z,1720464963.966 [MassServo](INFO): Powering down
2024-07-08T18:56:03.967Z,1720464963.967 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-07-08T18:56:03.967Z,1720464963.967 [RudderServo](INFO): Powering down
2024-07-08T18:56:03.967Z,1720464963.967 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-07-08T18:56:03.968Z,1720464963.968 [ThrusterHE](INFO): Powering down
2024-07-08T18:56:03.968Z,1720464963.968 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-07-08T18:56:03.969Z,1720464963.969 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-07-08T18:56:03.969Z,1720464963.969 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-07-08T18:56:03.969Z,1720464963.969 [CBIT](DEBUG): Powering off loads.
2024-07-08T18:56:03.980Z,1720464963.980 [CBIT](DEBUG): Disabling WDT.
2024-07-08T18:56:03.992Z,1720464963.992 [CBIT](DEBUG): Opening all GF detection circuits.
2024-07-08T18:56:03.994Z,1720464963.994 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:04.054Z,1720464964.054 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:04.057Z,1720464964.057 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:04.063Z,1720464964.063 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:04.105Z,1720464964.105 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:04.144Z,1720464964.144 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:04.148Z,1720464964.148 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:04.181Z,1720464964.181 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-08T18:56:04.252Z,1720464964.252 [logger ThreadHandler](INFO): Thread cancelled.