2024-04-23T22:26:39.032Z,1713911199.032 [Supervisor](DEBUG): Initializing supervisor.
2024-04-23T22:26:39.036Z,1713911199.036 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-04-23T22:26:39.036Z,1713911199.036 [SyncHandler](INFO): Protected caller Thread ID is 8644
2024-04-23T22:26:39.037Z,1713911199.037 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-04-23T22:26:39.038Z,1713911199.038 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-04-23T22:26:39.038Z,1713911199.038 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8645
2024-04-23T22:26:39.042Z,1713911199.042 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-04-23T22:26:39.059Z,1713911199.059 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-04-23T22:26:39.060Z,1713911199.060 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-04-23T22:26:39.061Z,1713911199.061 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8646
2024-04-23T22:26:39.065Z,1713911199.065 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-04-23T22:26:39.066Z,1713911199.066 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-04-23T22:26:39.066Z,1713911199.066 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8647
2024-04-23T22:26:39.068Z,1713911199.068 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-04-23T22:26:39.069Z,1713911199.069 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-04-23T22:26:39.070Z,1713911199.070 [logger ThreadHandler](INFO): Protected caller Thread ID is 8648
2024-04-23T22:26:39.073Z,1713911199.073 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-04-23T22:26:39.073Z,1713911199.073 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-04-23T22:26:39.075Z,1713911199.075 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-04-23T22:26:39.352Z,1713911199.352 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-04-23T22:26:39.353Z,1713911199.353 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-04-23T22:26:39.446Z,1713911199.446 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-04-23T22:26:39.996Z,1713911199.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-04-23T22:26:39.996Z,1713911199.996 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-04-23T22:26:40.357Z,1713911200.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-04-23T22:26:40.358Z,1713911200.358 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-04-23T22:26:40.466Z,1713911200.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-04-23T22:26:40.466Z,1713911200.466 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-04-23T22:26:40.896Z,1713911200.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-04-23T22:26:40.897Z,1713911200.897 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-04-23T22:26:41.125Z,1713911201.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-04-23T22:26:41.125Z,1713911201.125 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-04-23T22:26:41.720Z,1713911201.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-04-23T22:26:41.721Z,1713911201.721 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-04-23T22:26:41.869Z,1713911201.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-04-23T22:26:41.870Z,1713911201.870 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-04-23T22:26:41.961Z,1713911201.961 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-04-23T22:26:42.791Z,1713911202.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-04-23T22:26:42.791Z,1713911202.791 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-04-23T22:26:43.394Z,1713911203.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-04-23T22:26:43.394Z,1713911203.394 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-04-23T22:26:43.953Z,1713911203.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-04-23T22:26:43.954Z,1713911203.954 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-04-23T22:26:44.112Z,1713911204.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-04-23T22:26:44.112Z,1713911204.112 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-04-23T22:26:44.353Z,1713911204.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-04-23T22:26:44.353Z,1713911204.353 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-04-23T22:26:44.616Z,1713911204.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-04-23T22:26:44.618Z,1713911204.618 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-04-23T22:26:44.619Z,1713911204.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-04-23T22:26:44.721Z,1713911204.721 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-04-23T22:26:44.820Z,1713911204.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-04-23T22:26:44.933Z,1713911204.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-04-23T22:26:45.029Z,1713911205.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-04-23T22:26:45.144Z,1713911205.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-04-23T22:26:45.249Z,1713911205.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-04-23T22:26:45.374Z,1713911205.374 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-04-23T22:26:45.534Z,1713911205.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-04-23T22:26:45.686Z,1713911205.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-04-23T22:26:45.808Z,1713911205.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-04-23T22:26:46.081Z,1713911206.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-04-23T22:26:46.083Z,1713911206.083 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2024-04-23T22:26:46.083Z,1713911206.083 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2024-04-23T22:26:46.089Z,1713911206.089 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-04-23T22:26:46.141Z,1713911206.141 [VerticalControl](DEBUG): Construct VerticalControl.
2024-04-23T22:26:46.200Z,1713911206.200 [VerticalControl] Loaded
2024-04-23T22:26:46.201Z,1713911206.201 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-04-23T22:26:46.203Z,1713911206.203 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-04-23T22:26:46.245Z,1713911206.245 [HorizontalControl] Loaded
2024-04-23T22:26:46.245Z,1713911206.245 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-04-23T22:26:46.248Z,1713911206.248 [SpeedControl](DEBUG): Construct SpeedControl.
2024-04-23T22:26:46.251Z,1713911206.251 [SpeedControl] Loaded
2024-04-23T22:26:46.251Z,1713911206.251 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-04-23T22:26:46.254Z,1713911206.254 [LoopControl](DEBUG): Construct LoopControl.
2024-04-23T22:26:46.254Z,1713911206.254 [LoopControl] Loaded
2024-04-23T22:26:46.254Z,1713911206.254 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-04-23T22:26:46.255Z,1713911206.255 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-04-23T22:26:46.255Z,1713911206.255 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-04-23T22:26:46.265Z,1713911206.265 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-04-23T22:26:46.266Z,1713911206.266 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-04-23T22:26:46.437Z,1713911206.437 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-04-23T22:26:46.437Z,1713911206.437 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-04-23T22:26:46.590Z,1713911206.590 [BuoyancyServo] Loaded
2024-04-23T22:26:46.590Z,1713911206.590 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-04-23T22:26:46.609Z,1713911206.609 [ElevatorServo] Loaded
2024-04-23T22:26:46.610Z,1713911206.610 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-04-23T22:26:46.628Z,1713911206.628 [MassServo] Loaded
2024-04-23T22:26:46.628Z,1713911206.628 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-04-23T22:26:46.646Z,1713911206.646 [RudderServo] Loaded
2024-04-23T22:26:46.647Z,1713911206.647 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-04-23T22:26:46.661Z,1713911206.661 [ThrusterHE] Loaded
2024-04-23T22:26:46.661Z,1713911206.661 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-04-23T22:26:46.662Z,1713911206.662 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-04-23T22:26:46.662Z,1713911206.662 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-04-23T22:26:46.775Z,1713911206.775 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-04-23T22:26:46.775Z,1713911206.775 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-04-23T22:26:46.789Z,1713911206.789 [NavChart] Loaded
2024-04-23T22:26:46.789Z,1713911206.789 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-04-23T22:26:46.794Z,1713911206.794 [UniversalFixResidualReporter] Loaded
2024-04-23T22:26:46.795Z,1713911206.795 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-04-23T22:26:46.795Z,1713911206.795 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-04-23T22:26:46.796Z,1713911206.796 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-04-23T22:26:46.851Z,1713911206.851 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-04-23T22:26:46.851Z,1713911206.851 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-04-23T22:26:47.278Z,1713911207.278 [AHRS_M2] Loaded
2024-04-23T22:26:47.278Z,1713911207.278 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-04-23T22:26:48.085Z,1713911208.085 [BPC1] Loaded
2024-04-23T22:26:48.085Z,1713911208.085 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-04-23T22:26:48.160Z,1713911208.160 [DataOverHttps] Loaded
2024-04-23T22:26:48.160Z,1713911208.160 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-04-23T22:26:48.161Z,1713911208.161 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2024-04-23T22:26:48.161Z,1713911208.161 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8730
2024-04-23T22:26:48.182Z,1713911208.182 [Depth_Keller] Loaded
2024-04-23T22:26:48.182Z,1713911208.182 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-04-23T22:26:48.187Z,1713911208.187 [DropWeight] Loaded
2024-04-23T22:26:48.188Z,1713911208.188 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-04-23T22:26:48.249Z,1713911208.249 [NAL9602] Loaded
2024-04-23T22:26:48.249Z,1713911208.249 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-04-23T22:26:48.279Z,1713911208.279 [Onboard] Loaded
2024-04-23T22:26:48.279Z,1713911208.279 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-04-23T22:26:48.280Z,1713911208.280 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409604E0
2024-04-23T22:26:48.280Z,1713911208.280 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8731
2024-04-23T22:26:48.293Z,1713911208.293 [Power24vConverter] Loaded
2024-04-23T22:26:48.294Z,1713911208.294 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-04-23T22:26:48.307Z,1713911208.307 [Radio_Surface] Loaded
2024-04-23T22:26:48.307Z,1713911208.307 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-04-23T22:26:48.308Z,1713911208.308 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409904E0
2024-04-23T22:26:48.308Z,1713911208.308 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8732
2024-04-23T22:26:48.322Z,1713911208.322 [Sonardyne_Nano] Loaded
2024-04-23T22:26:48.322Z,1713911208.322 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-04-23T22:26:48.413Z,1713911208.413 [DAT] Loaded
2024-04-23T22:26:48.413Z,1713911208.413 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-04-23T22:26:48.414Z,1713911208.414 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C04E0
2024-04-23T22:26:48.415Z,1713911208.415 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8733
2024-04-23T22:26:48.415Z,1713911208.415 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-04-23T22:26:48.416Z,1713911208.416 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-04-23T22:26:48.615Z,1713911208.615 [DepthRateCalculator] Loaded
2024-04-23T22:26:48.615Z,1713911208.615 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-04-23T22:26:48.620Z,1713911208.620 [PitchRateCalculator] Loaded
2024-04-23T22:26:48.620Z,1713911208.620 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-04-23T22:26:48.671Z,1713911208.671 [SpeedCalculator] Loaded
2024-04-23T22:26:48.672Z,1713911208.672 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-04-23T22:26:48.676Z,1713911208.676 [YawRateCalculator] Loaded
2024-04-23T22:26:48.676Z,1713911208.676 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-04-23T22:26:48.695Z,1713911208.695 [ElevatorOffsetCalculator] Loaded
2024-04-23T22:26:48.695Z,1713911208.695 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-04-23T22:26:48.696Z,1713911208.696 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-04-23T22:26:48.696Z,1713911208.696 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-04-23T22:26:49.026Z,1713911209.026 [CANONSampler] Loaded
2024-04-23T22:26:49.027Z,1713911209.027 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-04-23T22:26:49.249Z,1713911209.249 [CTD_Seabird] Loaded
2024-04-23T22:26:49.249Z,1713911209.249 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-04-23T22:26:49.250Z,1713911209.250 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7C4E0
2024-04-23T22:26:49.251Z,1713911209.251 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8734
2024-04-23T22:26:49.270Z,1713911209.270 [PAR_Licor] Loaded
2024-04-23T22:26:49.271Z,1713911209.271 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-04-23T22:26:49.314Z,1713911209.314 [WetLabsBB2FL] Loaded
2024-04-23T22:26:49.314Z,1713911209.314 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-04-23T22:26:49.315Z,1713911209.315 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAC4E0
2024-04-23T22:26:49.316Z,1713911209.316 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8735
2024-04-23T22:26:49.316Z,1713911209.316 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-04-23T22:26:49.317Z,1713911209.317 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-04-23T22:26:49.408Z,1713911209.408 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-04-23T22:26:49.408Z,1713911209.408 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-04-23T22:26:49.744Z,1713911209.744 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-04-23T22:26:49.745Z,1713911209.745 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-04-23T22:26:49.888Z,1713911209.888 [SBIT](DEBUG): Construct Startup Built In Test.
2024-04-23T22:26:49.896Z,1713911209.896 [SBIT] Loaded
2024-04-23T22:26:49.897Z,1713911209.897 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-04-23T22:26:49.900Z,1713911209.900 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-04-23T22:26:49.912Z,1713911209.912 [IBIT] Loaded
2024-04-23T22:26:49.913Z,1713911209.913 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-04-23T22:26:49.918Z,1713911209.918 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-04-23T22:26:50.023Z,1713911210.023 [CBIT] Loaded
2024-04-23T22:26:50.023Z,1713911210.023 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-04-23T22:26:50.023Z,1713911210.023 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-04-23T22:26:50.024Z,1713911210.024 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-04-23T22:26:50.137Z,1713911210.137 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-04-23T22:26:50.143Z,1713911210.143 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-04-23T22:26:50.146Z,1713911210.146 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-04-23T22:26:50.157Z,1713911210.157 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-04-23T22:26:50.158Z,1713911210.158 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0
2024-04-23T22:26:50.158Z,1713911210.158 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8736
2024-04-23T22:26:50.163Z,1713911210.163 [Supervisor](INFO): Main Thread ID is 7407
2024-04-23T22:26:50.163Z,1713911210.163 [Supervisor](DEBUG): Running supervisor.
2024-04-23T22:26:50.163Z,1713911210.163 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8737
2024-04-23T22:26:50.164Z,1713911210.164 [CommandExec](INFO): Initializing the command executive.
2024-04-23T22:26:50.166Z,1713911210.166 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8738
2024-04-23T22:26:50.169Z,1713911210.169 [controlThread ThreadHandler](INFO): Handler Thread ID is 8739
2024-04-23T22:26:50.169Z,1713911210.169 [controlThread](DEBUG): Initializing ControlThread
2024-04-23T22:26:50.170Z,1713911210.170 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-04-23T22:26:50.172Z,1713911210.172 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-04-23T22:26:50.173Z,1713911210.173 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-04-23T22:26:50.173Z,1713911210.173 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-04-23T22:26:50.175Z,1713911210.175 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-04-23T22:26:50.175Z,1713911210.175 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-04-23T22:26:50.180Z,1713911210.180 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-04-23T22:26:50.181Z,1713911210.181 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-04-23T22:26:50.181Z,1713911210.181 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-04-23T22:26:50.181Z,1713911210.181 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-04-23T22:26:50.181Z,1713911210.181 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-04-23T22:26:50.187Z,1713911210.187 [SBIT](INFO): Initialize SBIT Component.
2024-04-23T22:26:50.187Z,1713911210.187 [SBIT](IMPORTANT): git: 2024-04-12
2024-04-23T22:26:50.187Z,1713911210.187 [SBIT](INFO): git hash: d6923c042de9d8b8dc285b2f6044b9806cc7806b
2024-04-23T22:26:50.188Z,1713911210.188 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-04-23T22:26:50.189Z,1713911210.189 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-04-23T22:26:50.190Z,1713911210.190 [SBIT](INFO): Beginning SBIT in 29.000000 seconds.
2024-04-23T22:26:50.190Z,1713911210.190 [IBIT](INFO): Initialize IBIT Component.
2024-04-23T22:26:50.191Z,1713911210.191 [CBIT](DEBUG): Initialize CBIT Component.
2024-04-23T22:26:50.192Z,1713911210.192 [logger ThreadHandler](INFO): Handler Thread ID is 8740
2024-04-23T22:26:50.202Z,1713911210.202 [CBIT](DEBUG): Initialized mux pins.
2024-04-23T22:26:50.202Z,1713911210.202 [CBIT](DEBUG): Initializing the watchdog timer.
2024-04-23T22:26:50.211Z,1713911210.211 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8741
2024-04-23T22:26:50.213Z,1713911210.213 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-04-23T22:26:50.223Z,1713911210.223 [Onboard ThreadHandler](INFO): Handler Thread ID is 8742
2024-04-23T22:26:50.227Z,1713911210.227 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-04-23T22:26:50.228Z,1713911210.228 [CBIT](DEBUG): Initializing heartbeat.
2024-04-23T22:26:50.243Z,1713911210.243 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8743
2024-04-23T22:26:50.255Z,1713911210.255 [DAT ThreadHandler](INFO): Handler Thread ID is 8744
2024-04-23T22:26:50.255Z,1713911210.255 [DAT](INFO): Powering up
2024-04-23T22:26:50.256Z,1713911210.256 [DAT](DEBUG): Initializing DAT.
2024-04-23T22:26:50.260Z,1713911210.260 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8746
2024-04-23T22:26:50.260Z,1713911210.260 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-04-23T22:26:50.264Z,1713911210.264 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8748
2024-04-23T22:26:50.267Z,1713911210.267 [WetLabsBB2FL](INFO): Powering up
2024-04-23T22:26:50.269Z,1713911210.269 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8750
2024-04-23T22:26:50.272Z,1713911210.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-04-23T22:26:50.272Z,1713911210.272 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-04-23T22:26:50.273Z,1713911210.273 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-04-23T22:26:50.273Z,1713911210.273 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-04-23T22:26:50.273Z,1713911210.273 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-04-23T22:26:50.273Z,1713911210.273 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-04-23T22:26:50.273Z,1713911210.273 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-04-23T22:26:50.273Z,1713911210.273 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-04-23T22:26:50.298Z,1713911210.298 [CBIT](DEBUG): Deactivating GF circuits.
2024-04-23T22:26:50.299Z,1713911210.299 [CBIT](DEBUG): Deactivating emergency mode.
2024-04-23T22:26:50.338Z,1713911210.338 [CBIT](DEBUG): Backplane powered.
2024-04-23T22:26:50.339Z,1713911210.339 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-04-23T22:26:50.340Z,1713911210.340 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-04-23T22:26:50.340Z,1713911210.340 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-04-23T22:26:50.341Z,1713911210.341 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-04-23T22:26:50.382Z,1713911210.382 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-04-23T22:26:50.395Z,1713911210.395 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-23T22:26:50.436Z,1713911210.436 [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-04-23T22:26:50.436Z,1713911210.436 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-04-23T22:26:50.436Z,1713911210.436 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-04-23T22:26:50.437Z,1713911210.437 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-04-23T22:26:50.646Z,1713911210.646 [Radio_Surface](INFO): Powering up
2024-04-23T22:26:50.767Z,1713911210.767 [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-04-23T22:26:50.773Z,1713911210.773 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-04-23T22:26:50.791Z,1713911210.791 [Default:A.Wait](DEBUG): Construct Wait.
2024-04-23T22:26:50.793Z,1713911210.793 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-23T22:26:50.854Z,1713911210.854 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-04-23T22:26:50.856Z,1713911210.856 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-04-23T22:26:50.886Z,1713911210.886 [Default:E.Execute](DEBUG): Construct Execute.
2024-04-23T22:26:50.889Z,1713911210.889 [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-04-23T22:26:50.894Z,1713911210.894 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-04-23T22:26:50.903Z,1713911210.903 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-04-23T22:26:50.975Z,1713911210.975 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m
2024-04-23T22:26:50.976Z,1713911210.976 [Power24vConverter](INFO): Powering up.
2024-04-23T22:26:50.977Z,1713911210.977 [Sonardyne_Nano](INFO): Initializing.
2024-04-23T22:26:50.995Z,1713911210.995 [CANONSampler](INFO): Powering down
2024-04-23T22:26:51.003Z,1713911211.003 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-04-23T22:26:51.032Z,1713911211.032 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-04-23T22:26:51.039Z,1713911211.039 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-04-23T22:26:51.040Z,1713911211.040 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-04-23T22:26:51.047Z,1713911211.047 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-04-23T22:26:51.048Z,1713911211.048 [MassServo](DEBUG): Initializing EZServoServo.
2024-04-23T22:26:51.055Z,1713911211.055 [MassServo](DEBUG): Initializing MassServo.
2024-04-23T22:26:51.055Z,1713911211.055 [RudderServo](DEBUG): Initializing EZServoServo.
2024-04-23T22:26:51.063Z,1713911211.063 [RudderServo](DEBUG): Initializing RudderServo.
2024-04-23T22:26:51.063Z,1713911211.063 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-04-23T22:26:51.071Z,1713911211.071 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-04-23T22:26:51.336Z,1713911211.336 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-04-23T22:26:51.336Z,1713911211.336 [DropWeight] Hardware Fault, FailCount= 1
2024-04-23T22:26:51.336Z,1713911211.336 [DropWeight](ERROR): Hardware Fault
2024-04-23T22:26:51.377Z,1713911211.377 [CommandExec](FAULT): Scheduling is paused
2024-04-23T22:26:51.377Z,1713911211.377 [CBIT](INFO): Critical error at 20240423T222651
2024-04-23T22:26:51.377Z,1713911211.377 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-04-23T22:26:51.396Z,1713911211.396 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-04-23T22:26:51.396Z,1713911211.396 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-04-23T22:26:51.734Z,1713911211.734 [CBIT](INFO): Critical error at 20240423T222651
2024-04-23T22:26:52.374Z,1713911212.374 [WetLabsBB2FL](INFO): Powering down
2024-04-23T22:26:56.792Z,1713911216.792 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-04-23T22:27:02.855Z,1713911222.855 [DAT](INFO): DAT read:
2024-04-23T22:27:02.856Z,1713911222.856 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-04-23T22:27:04.623Z,1713911224.623 [DAT](INFO): DAT read: MF Frequency Band
2024-04-23T22:27:04.624Z,1713911224.624 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-04-23T22:27:04.624Z,1713911224.624 [DAT](INFO): DAT read: Apr 23 2024 22:26:58
2024-04-23T22:27:05.879Z,1713911225.879 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-04-23T22:27:05.881Z,1713911225.881 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-04-23T22:27:05.881Z,1713911225.881 [DAT](INFO): commRate: 1200
2024-04-23T22:27:07.947Z,1713911227.947 [DAT](INFO): entering command mode
2024-04-23T22:27:08.147Z,1713911228.147 [DAT](INFO): DAT read:
2024-04-23T22:27:08.147Z,1713911228.147 [DAT](INFO): DAT read: uer:1>
2024-04-23T22:27:08.148Z,1713911228.148 [DAT](INFO): unknown deviceResponse_: uer:1>
2024-04-23T22:27:08.148Z,1713911228.148 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:08.399Z,1713911228.399 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:08.651Z,1713911228.651 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:08.903Z,1713911228.903 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:09.155Z,1713911229.155 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:09.407Z,1713911229.407 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:09.659Z,1713911229.659 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:09.911Z,1713911229.911 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:10.163Z,1713911230.163 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:10.415Z,1713911230.415 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:10.667Z,1713911230.667 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:10.919Z,1713911230.919 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:11.171Z,1713911231.171 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:11.423Z,1713911231.423 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:11.675Z,1713911231.675 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:11.927Z,1713911231.927 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:12.179Z,1713911232.179 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:12.431Z,1713911232.431 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:12.683Z,1713911232.683 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:12.935Z,1713911232.935 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:13.187Z,1713911233.187 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:13.439Z,1713911233.439 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:13.691Z,1713911233.691 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:13.943Z,1713911233.943 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:14.195Z,1713911234.195 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:14.447Z,1713911234.447 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:14.699Z,1713911234.699 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:14.951Z,1713911234.951 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:15.203Z,1713911235.203 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:15.455Z,1713911235.455 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:15.707Z,1713911235.707 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:15.961Z,1713911235.961 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:16.211Z,1713911236.211 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:16.463Z,1713911236.463 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:16.715Z,1713911236.715 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:16.967Z,1713911236.967 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:17.219Z,1713911237.219 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:17.471Z,1713911237.471 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:17.723Z,1713911237.723 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:17.975Z,1713911237.975 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:18.227Z,1713911238.227 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:18.380Z,1713911238.380 [NAL9602](INFO): Powering up NAL9602
2024-04-23T22:27:18.479Z,1713911238.479 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:18.731Z,1713911238.731 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:18.983Z,1713911238.983 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:19.235Z,1713911239.235 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:19.487Z,1713911239.487 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:19.634Z,1713911239.634 [SBIT](IMPORTANT): Beginning Startup BIT
2024-04-23T22:27:19.642Z,1713911239.642 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-23T22:27:19.739Z,1713911239.739 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:19.991Z,1713911239.991 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:20.243Z,1713911240.243 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:20.495Z,1713911240.495 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:20.747Z,1713911240.747 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:20.999Z,1713911240.999 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:21.251Z,1713911241.251 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:21.503Z,1713911241.503 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:21.755Z,1713911241.755 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:22.007Z,1713911242.007 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:22.259Z,1713911242.259 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:22.512Z,1713911242.512 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:22.763Z,1713911242.763 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:23.015Z,1713911243.015 [DAT](DEBUG): checking for command mode acknowledgment
2024-04-23T22:27:23.015Z,1713911243.015 [DAT](FAULT): failed to enter command mode
2024-04-23T22:27:23.318Z,1713911243.318 [DAT](INFO): entering command mode
2024-04-23T22:27:23.519Z,1713911243.519 [DAT](INFO): DAT read: user:1>
2024-04-23T22:27:23.520Z,1713911243.520 [DAT](INFO): DAT read: Command '+++' not found
2024-04-23T22:27:23.520Z,1713911243.520 [DAT](INFO): DAT read: Error
2024-04-23T22:27:23.521Z,1713911243.521 [DAT](INFO): setting verbose to 3
2024-04-23T22:27:23.771Z,1713911243.771 [DAT](INFO): DAT read: user:2>
2024-04-23T22:27:23.772Z,1713911243.772 [DAT](INFO): DAT read: Verbose | 3
2024-04-23T22:27:23.772Z,1713911243.772 [DAT](INFO): set verbose to 3
2024-04-23T22:27:23.773Z,1713911243.773 [DAT](INFO): setting DatVerbose to 27440
2024-04-23T22:27:24.023Z,1713911244.023 [DAT](INFO): DAT read: user:3>
2024-04-23T22:27:24.024Z,1713911244.024 [DAT](INFO): DAT read: DatVerbose | 27440
2024-04-23T22:27:24.024Z,1713911244.024 [DAT](INFO): set DatVerbose to 27440
2024-04-23T22:27:24.025Z,1713911244.025 [DAT](INFO): setting transmit power to 8
2024-04-23T22:27:24.275Z,1713911244.275 [DAT](INFO): DAT read: user:4>
2024-04-23T22:27:24.276Z,1713911244.276 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-04-23T22:27:24.276Z,1713911244.276 [DAT](INFO): set transmit power to 8
2024-04-23T22:27:24.277Z,1713911244.277 [DAT](INFO): setting local address to 8
2024-04-23T22:27:24.527Z,1713911244.527 [DAT](INFO): DAT read: user:5>
2024-04-23T22:27:24.528Z,1713911244.528 [DAT](INFO): DAT read: LocalAddr | 8
2024-04-23T22:27:24.528Z,1713911244.528 [DAT](INFO): set local address to 8
2024-04-23T22:27:24.529Z,1713911244.529 [DAT](INFO): Setting time to: 22:27:24 And date to:4/23/2024
2024-04-23T22:27:24.779Z,1713911244.779 [DAT](INFO): DAT read: user:6>
2024-04-23T22:27:24.780Z,1713911244.780 [DAT](INFO): DAT read: Tue Apr 23, 2024 22:27:24
2024-04-23T22:27:24.780Z,1713911244.780 [DAT](INFO): Local DAT time set to Tue Apr 23, 2024 22:27:24
2024-04-23T22:27:29.288Z,1713911249.288 [NAL9602](INFO): NAL9602 initialized
2024-04-23T22:27:30.581Z,1713911250.581 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002982
CHAN A1 (24V): 0.000643
CHAN A2 (12V): -0.001929
CHAN A3 (5V): -0.001639
CHAN B0 (3.3V): -0.000062
CHAN B1 (3.15aV): -0.000816
CHAN B2 (3.15bV): -0.000667
CHAN B3 (GND): -0.000142
OPEN: -0.000200
Full Scale: +/- 1 mA
2024-04-23T22:27:57.165Z,1713911277.165 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-23T22:28:10.090Z,1713911290.090 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-23T22:28:13.757Z,1713911293.757 [SBIT](IMPORTANT): SBIT PASSED
2024-04-23T22:28:13.757Z,1713911293.757 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-23T22:28:13.758Z,1713911293.758 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-04-23T22:28:13.758Z,1713911293.758 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-04-23T22:28:13.758Z,1713911293.758 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=116.716525 cubic_centimeter;
2024-04-23T22:28:13.758Z,1713911293.758 [SBIT](IMPORTANT): VerticalControl.massDefault=2.369992 millimeter;
2024-04-23T22:28:14.172Z,1713911294.172 [MissionManager](IMPORTANT): Started mission Startup
2024-04-23T22:28:14.173Z,1713911294.173 [Startup] Running Loop=1
2024-04-23T22:28:14.173Z,1713911294.173 [Startup](DEBUG): Aggregate::initialize Startup
2024-04-23T22:28:14.173Z,1713911294.173 [Startup:A.GoToSurface] Running Loop=1
2024-04-23T22:28:14.173Z,1713911294.173 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-23T22:28:14.174Z,1713911294.174 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-23T22:28:14.174Z,1713911294.174 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-23T22:28:14.175Z,1713911294.175 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-23T22:28:14.175Z,1713911294.175 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-23T22:28:14.175Z,1713911294.175 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-23T22:28:14.177Z,1713911294.177 [Startup:StartupSatComms] Running Loop=1
2024-04-23T22:28:14.177Z,1713911294.177 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-04-23T22:28:14.177Z,1713911294.177 [Startup:StartupSatComms:A] Running Loop=1
2024-04-23T22:28:14.565Z,1713911294.565 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-04-23T22:28:22.612Z,1713911302.612 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-23T22:28:35.540Z,1713911315.540 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-23T22:28:48.872Z,1713911328.872 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-23T22:29:01.397Z,1713911341.397 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-23T22:29:13.920Z,1713911353.920 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-23T22:29:14.367Z,1713911354.367 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-23T22:28:14.2Z
2024-04-23T22:29:14.367Z,1713911354.367 [Startup:StartupSatComms:A] Stopped
2024-04-23T22:29:14.367Z,1713911354.367 [Startup:StartupSatComms:B] Running Loop=1
2024-04-23T22:29:14.788Z,1713911354.788 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-23T22:29:50.299Z,1713911390.299 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-04-23T22:29:50.300Z,1713911390.300 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:29:50.340Z,1713911390.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:29:50.711Z,1713911390.711 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:29:50.711Z,1713911390.711 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-04-23T22:30:14.555Z,1713911414.555 [Startup:StartupSatComms:B](INFO): Timed out from 2024-04-23T22:29:14.4Z
2024-04-23T22:30:14.555Z,1713911414.555 [Startup:StartupSatComms:B] Stopped
2024-04-23T22:30:14.556Z,1713911414.556 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-04-23T22:30:14.556Z,1713911414.556 [Startup:StartupSatComms] Stopped
2024-04-23T22:30:14.556Z,1713911414.556 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-04-23T22:30:14.557Z,1713911414.557 [Startup](INFO): Completed Startup
2024-04-23T22:30:14.557Z,1713911414.557 [MissionManager](INFO): Startup is completed.
2024-04-23T22:30:14.557Z,1713911414.557 [MissionManager](INFO): Uninitializing Mission Startup
2024-04-23T22:30:14.557Z,1713911414.557 [Startup] Stopped
2024-04-23T22:30:14.557Z,1713911414.557 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-04-23T22:30:14.557Z,1713911414.557 [Startup:A.GoToSurface] Stopped
2024-04-23T22:30:14.557Z,1713911414.557 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-23T22:30:14.947Z,1713911414.947 [MissionManager](IMPORTANT): Started mission Default
2024-04-23T22:30:14.948Z,1713911414.948 [Default] Running Loop=1
2024-04-23T22:30:14.948Z,1713911414.948 [Default](DEBUG): Aggregate::initialize Default
2024-04-23T22:30:14.948Z,1713911414.948 [Default:B.GoToSurface] Running Loop=1
2024-04-23T22:30:14.948Z,1713911414.948 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-23T22:30:14.948Z,1713911414.948 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-23T22:30:14.948Z,1713911414.948 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-23T22:30:14.949Z,1713911414.949 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-23T22:30:14.949Z,1713911414.949 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-23T22:30:14.949Z,1713911414.949 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-23T22:30:14.950Z,1713911414.950 [Default:A.Wait] Running Loop=1
2024-04-23T22:30:14.950Z,1713911414.950 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-04-23T22:30:27.971Z,1713911427.971 [Default:A.Wait](INFO): Done Waiting.
2024-04-23T22:30:27.972Z,1713911427.972 [Default:A.Wait] Stopped
2024-04-23T22:30:27.972Z,1713911427.972 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T22:30:28.124Z,1713911428.124 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004195
2024-04-23T22:30:28.394Z,1713911428.394 [Default:CheckIn] Running Loop=1
2024-04-23T22:30:28.395Z,1713911428.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T22:30:28.395Z,1713911428.395 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T22:30:28.773Z,1713911428.773 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-04-23T22:31:56.437Z,1713911516.437 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-04-23T22:31:56.448Z,1713911516.448 [BPC1](INFO): Received data from all battery sticks.
2024-04-23T22:32:32.788Z,1713911552.788 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-23T22:32:51.408Z,1713911571.408 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-04-23T22:32:51.408Z,1713911571.408 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:32:51.419Z,1713911571.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:32:51.826Z,1713911571.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:32:51.826Z,1713911571.826 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-04-23T22:35:28.556Z,1713911728.556 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-23T22:30:28.4Z
2024-04-23T22:35:28.556Z,1713911728.556 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T22:35:28.556Z,1713911728.556 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-23T22:35:28.975Z,1713911728.975 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-23T22:35:35.506Z,1713911735.506 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T185814/Courier0088.lzma
2024-04-23T22:35:36.509Z,1713911736.509 [DataOverHttps](INFO): Moved sent file to Logs/20240423T185814/Courier0088.lzma.bak
2024-04-23T22:35:36.509Z,1713911736.509 [DataOverHttps](INFO): SBD MOMSN=19424565
2024-04-23T22:35:52.130Z,1713911752.130 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20240423T222638/Courier0000.lzma
2024-04-23T22:35:52.407Z,1713911752.407 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-04-23T22:35:52.407Z,1713911752.407 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:35:52.418Z,1713911752.418 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:35:52.807Z,1713911752.807 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:35:52.808Z,1713911752.808 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-04-23T22:35:53.132Z,1713911753.132 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Courier0000.lzma.bak
2024-04-23T22:35:53.132Z,1713911753.132 [DataOverHttps](INFO): SBD MOMSN=19424567
2024-04-23T22:36:08.922Z,1713911768.922 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240423T222638/Courier0004.lzma
2024-04-23T22:36:09.924Z,1713911769.924 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Courier0004.lzma.bak
2024-04-23T22:36:09.924Z,1713911769.924 [DataOverHttps](INFO): SBD MOMSN=19424572
2024-04-23T22:36:28.006Z,1713911788.006 [DataOverHttps](INFO): Sending 111 bytes from file Logs/20240423T185814/Express0089.lzma
2024-04-23T22:36:29.009Z,1713911789.009 [DataOverHttps](INFO): Moved sent file to Logs/20240423T185814/Express0089.lzma.bak
2024-04-23T22:36:29.009Z,1713911789.009 [DataOverHttps](INFO): SBD MOMSN=19424574
2024-04-23T22:36:44.603Z,1713911804.603 [DataOverHttps](INFO): Sending 799 bytes from file Logs/20240423T222638/Express0001.lzma
2024-04-23T22:36:45.604Z,1713911805.604 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Express0001.lzma.bak
2024-04-23T22:36:45.604Z,1713911805.604 [DataOverHttps](INFO): SBD MOMSN=19424577
2024-04-23T22:37:03.830Z,1713911823.830 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20240423T222638/Express0005.lzma
2024-04-23T22:37:04.832Z,1713911824.832 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Express0005.lzma.bak
2024-04-23T22:37:04.832Z,1713911824.832 [DataOverHttps](INFO): SBD MOMSN=19424595
2024-04-23T22:37:06.035Z,1713911826.035 [Default:CheckIn:Read_Iridium] Stopped
2024-04-23T22:37:06.036Z,1713911826.036 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-23T22:37:06.036Z,1713911826.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T22:37:31.460Z,1713911851.460 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-23T22:37:31.460Z,1713911851.460 [NAL9602] Data Fault, FailCount= 1
2024-04-23T22:37:31.460Z,1713911851.460 [NAL9602](ERROR): Data Fault
2024-04-23T22:37:31.515Z,1713911851.515 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-23T22:37:31.860Z,1713911851.860 [NAL9602](INFO): Powering down
2024-04-23T22:37:32.710Z,1713911852.710 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-23T22:37:32.711Z,1713911852.711 [NAL9602] No Fault, FailCount= 1
2024-04-23T22:38:02.164Z,1713911882.164 [NAL9602](INFO): Powering up NAL9602
2024-04-23T22:38:13.072Z,1713911893.072 [NAL9602](INFO): NAL9602 initialized
2024-04-23T22:38:53.477Z,1713911933.477 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-04-23T22:38:53.477Z,1713911933.477 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:38:53.496Z,1713911933.496 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:38:53.921Z,1713911933.921 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:38:53.922Z,1713911933.922 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-04-23T22:41:51.671Z,1713912111.671 [CBIT](INFO): Clearing failed state for component DropWeight
2024-04-23T22:41:51.671Z,1713912111.671 [DropWeight] No Fault, FailCount= 1
2024-04-23T22:41:54.475Z,1713912114.475 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-04-23T22:41:54.475Z,1713912114.475 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:41:54.486Z,1713912114.486 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:41:54.885Z,1713912114.885 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:41:54.885Z,1713912114.885 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-04-23T22:42:06.600Z,1713912126.600 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-23T22:42:06.600Z,1713912126.600 [Default:CheckIn:C.Wait] Stopped
2024-04-23T22:42:06.600Z,1713912126.600 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T22:42:06.600Z,1713912126.600 [Default:CheckIn:D] Running Loop=1
2024-04-23T22:42:07.000Z,1713912127.000 [Default:CheckIn:D] Stopped
2024-04-23T22:42:07.000Z,1713912127.000 [Default:CheckIn:E] Running Loop=1
2024-04-23T22:42:07.404Z,1713912127.404 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.867545 min
2024-04-23T22:42:07.405Z,1713912127.405 [Default:CheckIn:E] Stopped
2024-04-23T22:42:07.405Z,1713912127.405 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-23T22:42:07.405Z,1713912127.405 [Default:CheckIn] Stopped
2024-04-23T22:42:07.405Z,1713912127.405 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T22:42:07.405Z,1713912127.405 [Default:CheckIn](INFO): Running loop #2
2024-04-23T22:42:07.405Z,1713912127.405 [Default:CheckIn] Running Loop=2
2024-04-23T22:42:07.405Z,1713912127.405 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T22:42:07.405Z,1713912127.405 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T22:42:58.800Z,1713912178.800 [CommandExec](IMPORTANT): got command configSet list
2024-04-23T22:42:58.800Z,1713912178.800 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-23T22:42:58.801Z,1713912178.801 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-04-23T22:42:58.801Z,1713912178.801 [CommandExec](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-04-23T22:42:58.801Z,1713912178.801 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=116.716525 cubic_centimeter;
2024-04-23T22:42:58.801Z,1713912178.801 [CommandExec](IMPORTANT): VerticalControl.massDefault=2.369992 millimeter;
2024-04-23T22:44:55.496Z,1713912295.496 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-04-23T22:44:55.496Z,1713912295.496 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:44:55.524Z,1713912295.524 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:44:55.911Z,1713912295.911 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:44:55.911Z,1713912295.911 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-04-23T22:47:07.644Z,1713912427.644 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-23T22:42:07.4Z
2024-04-23T22:47:07.644Z,1713912427.644 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T22:47:07.644Z,1713912427.644 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-23T22:47:17.678Z,1713912437.678 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T222638/Courier0007.lzma
2024-04-23T22:47:18.680Z,1713912438.680 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Courier0007.lzma.bak
2024-04-23T22:47:18.680Z,1713912438.680 [DataOverHttps](INFO): SBD MOMSN=19424624
2024-04-23T22:47:34.203Z,1713912454.203 [DataOverHttps](INFO): Sending 388 bytes from file Logs/20240423T222638/Express0008.lzma
2024-04-23T22:47:35.200Z,1713912455.200 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Express0008.lzma.bak
2024-04-23T22:47:35.200Z,1713912455.200 [DataOverHttps](INFO): SBD MOMSN=19424626
2024-04-23T22:47:36.712Z,1713912456.712 [Default:CheckIn:Read_Iridium] Stopped
2024-04-23T22:47:36.712Z,1713912456.712 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-23T22:47:36.712Z,1713912456.712 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T22:47:56.502Z,1713912476.502 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-04-23T22:47:56.502Z,1713912476.502 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:47:56.513Z,1713912476.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:47:56.913Z,1713912476.913 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:47:56.913Z,1713912476.913 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-04-23T22:48:15.480Z,1713912495.480 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-23T22:48:15.480Z,1713912495.480 [NAL9602] Data Fault, FailCount= 2
2024-04-23T22:48:15.480Z,1713912495.480 [NAL9602](ERROR): Data Fault
2024-04-23T22:48:15.525Z,1713912495.525 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-23T22:48:15.884Z,1713912495.884 [NAL9602](INFO): Powering down
2024-04-23T22:48:16.718Z,1713912496.718 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-23T22:48:16.718Z,1713912496.718 [NAL9602] No Fault, FailCount= 2
2024-04-23T22:48:46.184Z,1713912526.184 [NAL9602](INFO): Powering up NAL9602
2024-04-23T22:48:57.092Z,1713912537.092 [NAL9602](INFO): NAL9602 initialized
2024-04-23T22:50:57.509Z,1713912657.509 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-04-23T22:50:57.509Z,1713912657.509 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:50:57.519Z,1713912657.519 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:50:57.933Z,1713912657.933 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:50:57.933Z,1713912657.933 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-04-23T22:52:37.308Z,1713912757.308 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-23T22:52:37.308Z,1713912757.308 [Default:CheckIn:C.Wait] Stopped
2024-04-23T22:52:37.308Z,1713912757.308 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T22:52:37.308Z,1713912757.308 [Default:CheckIn:D] Running Loop=1
2024-04-23T22:52:37.708Z,1713912757.708 [Default:CheckIn:D] Stopped
2024-04-23T22:52:37.708Z,1713912757.708 [Default:CheckIn:E] Running Loop=1
2024-04-23T22:52:38.117Z,1713912758.117 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.379333 min
2024-04-23T22:52:38.117Z,1713912758.117 [Default:CheckIn:E] Stopped
2024-04-23T22:52:38.117Z,1713912758.117 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-23T22:52:38.117Z,1713912758.117 [Default:CheckIn] Stopped
2024-04-23T22:52:38.117Z,1713912758.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T22:52:38.117Z,1713912758.117 [Default:CheckIn](INFO): Running loop #3
2024-04-23T22:52:38.117Z,1713912758.117 [Default:CheckIn] Running Loop=3
2024-04-23T22:52:38.117Z,1713912758.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T22:52:38.118Z,1713912758.118 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T22:53:58.507Z,1713912838.507 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-04-23T22:53:58.507Z,1713912838.507 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:53:58.518Z,1713912838.518 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:53:58.914Z,1713912838.914 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:53:58.914Z,1713912838.914 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-04-23T22:56:59.511Z,1713913019.511 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-04-23T22:56:59.511Z,1713913019.511 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T22:56:59.521Z,1713913019.521 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T22:56:59.933Z,1713913019.933 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T22:56:59.933Z,1713913019.933 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-04-23T22:57:38.316Z,1713913058.316 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-23T22:52:38.1Z
2024-04-23T22:57:38.316Z,1713913058.316 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T22:57:38.316Z,1713913058.316 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-23T22:57:45.030Z,1713913065.030 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T222638/Courier0010.lzma
2024-04-23T22:57:46.032Z,1713913066.032 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Courier0010.lzma.bak
2024-04-23T22:57:46.033Z,1713913066.033 [DataOverHttps](INFO): SBD MOMSN=19424651
2024-04-23T22:58:01.842Z,1713913081.842 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240423T222638/Express0011.lzma
2024-04-23T22:58:02.844Z,1713913082.844 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Express0011.lzma.bak
2024-04-23T22:58:02.844Z,1713913082.844 [DataOverHttps](INFO): SBD MOMSN=19424653
2024-04-23T22:58:04.180Z,1713913084.180 [Default:CheckIn:Read_Iridium] Stopped
2024-04-23T22:58:04.180Z,1713913084.180 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-23T22:58:04.181Z,1713913084.181 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T22:58:58.272Z,1713913138.272 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-23T22:58:58.272Z,1713913138.272 [NAL9602] Data Fault, FailCount= 3
2024-04-23T22:58:58.272Z,1713913138.272 [NAL9602](ERROR): Data Fault
2024-04-23T22:58:58.293Z,1713913138.293 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-23T22:58:58.676Z,1713913138.676 [NAL9602](INFO): Powering down
2024-04-23T22:58:59.522Z,1713913139.522 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-23T22:58:59.522Z,1713913139.522 [NAL9602] No Fault, FailCount= 3
2024-04-23T22:59:28.976Z,1713913168.976 [NAL9602](INFO): Powering up NAL9602
2024-04-23T22:59:39.888Z,1713913179.888 [NAL9602](INFO): NAL9602 initialized
2024-04-23T23:00:00.495Z,1713913200.495 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-04-23T23:00:00.495Z,1713913200.495 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:00:00.506Z,1713913200.506 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:00:00.915Z,1713913200.915 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:00:00.915Z,1713913200.915 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-04-23T23:03:01.541Z,1713913381.541 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-04-23T23:03:01.542Z,1713913381.542 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:03:01.552Z,1713913381.552 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:03:01.964Z,1713913381.964 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:03:01.964Z,1713913381.964 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-04-23T23:03:04.772Z,1713913384.772 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-23T23:03:04.772Z,1713913384.772 [Default:CheckIn:C.Wait] Stopped
2024-04-23T23:03:04.772Z,1713913384.772 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:03:04.772Z,1713913384.772 [Default:CheckIn:D] Running Loop=1
2024-04-23T23:03:05.171Z,1713913385.171 [Default:CheckIn:D] Stopped
2024-04-23T23:03:05.172Z,1713913385.172 [Default:CheckIn:E] Running Loop=1
2024-04-23T23:03:05.578Z,1713913385.578 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.837063 min
2024-04-23T23:03:05.578Z,1713913385.578 [Default:CheckIn:E] Stopped
2024-04-23T23:03:05.578Z,1713913385.578 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-23T23:03:05.578Z,1713913385.578 [Default:CheckIn] Stopped
2024-04-23T23:03:05.578Z,1713913385.578 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T23:03:05.578Z,1713913385.578 [Default:CheckIn](INFO): Running loop #4
2024-04-23T23:03:05.578Z,1713913385.578 [Default:CheckIn] Running Loop=4
2024-04-23T23:03:05.579Z,1713913385.579 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T23:03:05.579Z,1713913385.579 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T23:06:02.541Z,1713913562.541 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-04-23T23:06:02.541Z,1713913562.541 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:06:02.551Z,1713913562.551 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:06:02.965Z,1713913562.965 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:06:02.965Z,1713913562.965 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-04-23T23:08:05.756Z,1713913685.756 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-23T23:03:05.6Z
2024-04-23T23:08:05.756Z,1713913685.756 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T23:08:05.756Z,1713913685.756 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-23T23:08:13.174Z,1713913693.174 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T222638/Courier0013.lzma
2024-04-23T23:08:14.177Z,1713913694.177 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Courier0013.lzma.bak
2024-04-23T23:08:14.177Z,1713913694.177 [DataOverHttps](INFO): SBD MOMSN=19424703
2024-04-23T23:08:29.806Z,1713913709.806 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240423T222638/Express0014.lzma
2024-04-23T23:08:30.812Z,1713913710.812 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Express0014.lzma.bak
2024-04-23T23:08:30.812Z,1713913710.812 [DataOverHttps](INFO): SBD MOMSN=19424705
2024-04-23T23:08:32.036Z,1713913712.036 [Default:CheckIn:Read_Iridium] Stopped
2024-04-23T23:08:32.036Z,1713913712.036 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-23T23:08:32.036Z,1713913712.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:09:03.524Z,1713913743.524 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2024-04-23T23:09:03.524Z,1713913743.524 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:09:03.563Z,1713913743.563 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:09:03.935Z,1713913743.935 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:09:03.935Z,1713913743.935 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2024-04-23T23:09:41.488Z,1713913781.488 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-23T23:09:41.488Z,1713913781.488 [NAL9602] Data Fault, FailCount= 4
2024-04-23T23:09:41.488Z,1713913781.488 [NAL9602](ERROR): Data Fault
2024-04-23T23:09:41.526Z,1713913781.526 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-23T23:09:41.886Z,1713913781.886 [NAL9602](INFO): Powering down
2024-04-23T23:09:42.735Z,1713913782.735 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-23T23:09:42.735Z,1713913782.735 [NAL9602] No Fault, FailCount= 4
2024-04-23T23:10:12.228Z,1713913812.228 [NAL9602](INFO): Powering up NAL9602
2024-04-23T23:10:23.136Z,1713913823.136 [NAL9602](INFO): NAL9602 initialized
2024-04-23T23:12:04.558Z,1713913924.558 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2024-04-23T23:12:04.558Z,1713913924.558 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:12:04.569Z,1713913924.569 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:12:04.984Z,1713913924.984 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:12:04.984Z,1713913924.984 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2024-04-23T23:13:32.627Z,1713914012.627 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-23T23:13:32.628Z,1713914012.628 [Default:CheckIn:C.Wait] Stopped
2024-04-23T23:13:32.628Z,1713914012.628 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:13:32.628Z,1713914012.628 [Default:CheckIn:D] Running Loop=1
2024-04-23T23:13:33.051Z,1713914013.051 [Default:CheckIn:D] Stopped
2024-04-23T23:13:33.051Z,1713914013.051 [Default:CheckIn:E] Running Loop=1
2024-04-23T23:13:33.442Z,1713914013.442 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.301725 min
2024-04-23T23:13:33.442Z,1713914013.442 [Default:CheckIn:E] Stopped
2024-04-23T23:13:33.442Z,1713914013.442 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-23T23:13:33.443Z,1713914013.443 [Default:CheckIn] Stopped
2024-04-23T23:13:33.443Z,1713914013.443 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T23:13:33.444Z,1713914013.444 [Default:CheckIn](INFO): Running loop #5
2024-04-23T23:13:33.444Z,1713914013.444 [Default:CheckIn] Running Loop=5
2024-04-23T23:13:33.445Z,1713914013.445 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T23:13:33.445Z,1713914013.445 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T23:15:05.548Z,1713914105.548 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2024-04-23T23:15:05.548Z,1713914105.548 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:15:05.559Z,1713914105.559 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:15:05.970Z,1713914105.970 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:15:05.970Z,1713914105.970 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2024-04-23T23:18:06.540Z,1713914286.540 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2024-04-23T23:18:06.540Z,1713914286.540 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:18:06.551Z,1713914286.551 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:18:06.962Z,1713914286.962 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:18:06.962Z,1713914286.962 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2024-04-23T23:18:33.611Z,1713914313.611 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-23T23:13:33.4Z
2024-04-23T23:18:33.611Z,1713914313.611 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T23:18:33.611Z,1713914313.611 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-23T23:18:40.338Z,1713914320.338 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T222638/Courier0016.lzma
2024-04-23T23:18:41.340Z,1713914321.340 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Courier0016.lzma.bak
2024-04-23T23:18:41.340Z,1713914321.340 [DataOverHttps](INFO): SBD MOMSN=19424759
2024-04-23T23:18:56.902Z,1713914336.902 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20240423T222638/Express0017.lzma
2024-04-23T23:18:57.904Z,1713914337.904 [DataOverHttps](INFO): Moved sent file to Logs/20240423T222638/Express0017.lzma.bak
2024-04-23T23:18:57.904Z,1713914337.904 [DataOverHttps](INFO): SBD MOMSN=19424764
2024-04-23T23:18:59.069Z,1713914339.069 [Default:CheckIn:Read_Iridium] Stopped
2024-04-23T23:18:59.069Z,1713914339.069 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-23T23:18:59.069Z,1713914339.069 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:19:11.791Z,1713914351.791 [CommandExec](IMPORTANT): got command failComponent
2024-04-23T23:19:11.791Z,1713914351.791 [CommandExec](IMPORTANT): Failed components:
2024-04-23T23:19:11.791Z,1713914351.791 [CommandExec](IMPORTANT): No failed Components.
2024-04-23T23:19:17.512Z,1713914357.512 [CommandExec](IMPORTANT): got command restart system
2024-04-23T23:19:19.567Z,1713914359.567 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:19.567Z,1713914359.567 [CommandExec](INFO): Uninitializing the command executive.
2024-04-23T23:19:19.567Z,1713914359.567 [CommandExec](INFO): Uninitializing the command scheduler.
2024-04-23T23:19:19.567Z,1713914359.567 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:19.570Z,1713914359.570 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-04-23T23:19:19.571Z,1713914359.571 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-04-23T23:19:19.571Z,1713914359.571 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:19.572Z,1713914359.572 [NavChartDb](INFO): Join timeout helper Thread ID is 8981
2024-04-23T23:19:19.803Z,1713914359.803 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:19.803Z,1713914359.803 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:19.822Z,1713914359.822 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-04-23T23:19:19.822Z,1713914359.822 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:19.823Z,1713914359.823 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8982
2024-04-23T23:19:20.143Z,1713914360.143 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:20.143Z,1713914360.143 [WetLabsBB2FL](INFO): Powering down
2024-04-23T23:19:20.144Z,1713914360.144 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:20.154Z,1713914360.154 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-04-23T23:19:20.154Z,1713914360.154 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:20.155Z,1713914360.155 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8983
2024-04-23T23:19:20.594Z,1713914360.594 [CTD_Seabird](INFO): Powering down
2024-04-23T23:19:20.606Z,1713914360.606 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:20.606Z,1713914360.606 [CTD_Seabird](INFO): Powering down
2024-04-23T23:19:20.619Z,1713914360.619 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:20.622Z,1713914360.622 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-04-23T23:19:20.622Z,1713914360.622 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:20.623Z,1713914360.623 [DAT](INFO): Powering down
2024-04-23T23:19:20.624Z,1713914360.624 [DAT](INFO): Join timeout helper Thread ID is 8984
2024-04-23T23:19:20.695Z,1713914360.695 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:20.696Z,1713914360.696 [DAT](INFO): Powering down
2024-04-23T23:19:20.696Z,1713914360.696 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:20.702Z,1713914360.702 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-04-23T23:19:20.702Z,1713914360.702 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:20.703Z,1713914360.703 [Radio_Surface](INFO): Join timeout helper Thread ID is 8985
2024-04-23T23:19:21.078Z,1713914361.078 [Radio_Surface](INFO): Powering down
2024-04-23T23:19:21.079Z,1713914361.079 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:21.080Z,1713914361.080 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:21.091Z,1713914361.091 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-04-23T23:19:21.091Z,1713914361.091 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:21.092Z,1713914361.092 [Onboard](INFO): Join timeout helper Thread ID is 8986
2024-04-23T23:19:21.243Z,1713914361.243 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-04-23T23:19:22.935Z,1713914362.935 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:22.935Z,1713914362.935 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:22.951Z,1713914362.951 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-04-23T23:19:22.951Z,1713914362.951 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:22.952Z,1713914362.952 [DataOverHttps](INFO): Join timeout helper Thread ID is 8987
2024-04-23T23:19:23.250Z,1713914363.250 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:23.251Z,1713914363.251 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.283Z,1713914363.283 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-04-23T23:19:23.283Z,1713914363.283 [logger ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.283Z,1713914363.283 [logger](INFO): Join timeout helper Thread ID is 8988
2024-04-23T23:19:23.323Z,1713914363.323 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:23.323Z,1713914363.323 [logger ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.339Z,1713914363.339 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-04-23T23:19:23.339Z,1713914363.339 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.340Z,1713914363.340 [CommandLine](INFO): Join timeout helper Thread ID is 8989
2024-04-23T23:19:23.435Z,1713914363.435 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:23.435Z,1713914363.435 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.439Z,1713914363.439 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-04-23T23:19:23.439Z,1713914363.439 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.440Z,1713914363.440 [CommandExec](INFO): Join timeout helper Thread ID is 8990
2024-04-23T23:19:23.441Z,1713914363.441 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-04-23T23:19:23.441Z,1713914363.441 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.441Z,1713914363.441 [controlThread](INFO): Join timeout helper Thread ID is 8991
2024-04-23T23:19:23.675Z,1713914363.675 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:19:23.675Z,1713914363.675 [controlThread](DEBUG): Uninitializing ControlThread
2024-04-23T23:19:23.675Z,1713914363.675 [AHRS_M2](INFO): Powering down
2024-04-23T23:19:23.747Z,1713914363.747 [NAL9602](INFO): Powering down
2024-04-23T23:19:23.749Z,1713914363.749 [Sonardyne_Nano](INFO): Powering down
2024-04-23T23:19:23.868Z,1713914363.868 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-04-23T23:19:23.869Z,1713914363.869 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-04-23T23:19:23.869Z,1713914363.869 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-04-23T23:19:23.870Z,1713914363.870 [MissionManager](INFO): Uninitializing Mission Default
2024-04-23T23:19:23.870Z,1713914363.870 [Default] Stopped
2024-04-23T23:19:23.870Z,1713914363.870 [Default](DEBUG): Aggregate::uninitialize Default
2024-04-23T23:19:23.870Z,1713914363.870 [Default:B.GoToSurface] Stopped
2024-04-23T23:19:23.870Z,1713914363.870 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-23T23:19:23.870Z,1713914363.870 [Default:CheckIn] Stopped
2024-04-23T23:19:23.871Z,1713914363.871 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T23:19:23.871Z,1713914363.871 [Default:CheckIn:C.Wait] Stopped
2024-04-23T23:19:23.871Z,1713914363.871 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:19:23.874Z,1713914363.874 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-04-23T23:19:23.874Z,1713914363.874 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-04-23T23:19:23.874Z,1713914363.875 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-04-23T23:19:23.875Z,1713914363.875 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-04-23T23:19:23.875Z,1713914363.875 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-04-23T23:19:23.875Z,1713914363.875 [BuoyancyServo](INFO): Powering down
2024-04-23T23:19:23.887Z,1713914363.887 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-04-23T23:19:23.887Z,1713914363.887 [ElevatorServo](INFO): Powering down
2024-04-23T23:19:23.888Z,1713914363.888 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-04-23T23:19:23.888Z,1713914363.888 [MassServo](INFO): Powering down
2024-04-23T23:19:23.889Z,1713914363.889 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-04-23T23:19:23.889Z,1713914363.889 [RudderServo](INFO): Powering down
2024-04-23T23:19:23.890Z,1713914363.890 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-04-23T23:19:23.890Z,1713914363.890 [ThrusterHE](INFO): Powering down
2024-04-23T23:19:23.891Z,1713914363.891 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-04-23T23:19:23.891Z,1713914363.891 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-04-23T23:19:23.891Z,1713914363.891 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-04-23T23:19:23.891Z,1713914363.891 [CBIT](DEBUG): Powering off loads.
2024-04-23T23:19:23.902Z,1713914363.902 [CBIT](DEBUG): Disabling WDT.
2024-04-23T23:19:23.914Z,1713914363.914 [CBIT](DEBUG): Opening all GF detection circuits.
2024-04-23T23:19:23.915Z,1713914363.915 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.960Z,1713914363.960 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.966Z,1713914363.966 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.968Z,1713914363.968 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:23.973Z,1713914363.973 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:24.050Z,1713914364.050 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:24.055Z,1713914364.055 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:24.099Z,1713914364.099 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:19:24.164Z,1713914364.164 [logger ThreadHandler](INFO): Thread cancelled.