2024-10-10T21:48:48.543Z,1728596928.543 [Supervisor](DEBUG): Initializing supervisor.
2024-10-10T21:48:48.547Z,1728596928.547 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-10-10T21:48:48.547Z,1728596928.547 [SyncHandler](INFO): Protected caller Thread ID is 837
2024-10-10T21:48:48.548Z,1728596928.548 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-10-10T21:48:48.549Z,1728596928.549 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-10-10T21:48:48.549Z,1728596928.549 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2024-10-10T21:48:48.553Z,1728596928.553 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-10-10T21:48:48.570Z,1728596928.570 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-10-10T21:48:48.571Z,1728596928.571 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-10-10T21:48:48.571Z,1728596928.571 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2024-10-10T21:48:48.576Z,1728596928.576 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-10-10T21:48:48.577Z,1728596928.577 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-10-10T21:48:48.577Z,1728596928.577 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2024-10-10T21:48:48.579Z,1728596928.579 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-10-10T21:48:48.580Z,1728596928.580 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-10-10T21:48:48.580Z,1728596928.580 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2024-10-10T21:48:48.584Z,1728596928.584 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-10-10T21:48:48.584Z,1728596928.584 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-10-10T21:48:48.588Z,1728596928.588 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-10-10T21:48:48.869Z,1728596928.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-10-10T21:48:48.871Z,1728596928.871 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-10-10T21:48:48.965Z,1728596928.965 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-10-10T21:48:49.525Z,1728596929.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-10-10T21:48:49.526Z,1728596929.526 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-10-10T21:48:49.892Z,1728596929.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-10-10T21:48:49.894Z,1728596929.894 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-10-10T21:48:50.002Z,1728596930.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-10-10T21:48:50.003Z,1728596930.003 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-10-10T21:48:50.433Z,1728596930.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-10-10T21:48:50.435Z,1728596930.435 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-10-10T21:48:50.665Z,1728596930.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-10-10T21:48:50.667Z,1728596930.667 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-10-10T21:48:51.231Z,1728596931.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-10-10T21:48:51.233Z,1728596931.233 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-10-10T21:48:51.381Z,1728596931.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-10-10T21:48:51.383Z,1728596931.383 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-10-10T21:48:51.475Z,1728596931.475 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-10-10T21:48:52.668Z,1728596932.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-10-10T21:48:52.668Z,1728596932.668 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-10-10T21:48:53.433Z,1728596933.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-10-10T21:48:53.433Z,1728596933.433 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-10-10T21:48:53.658Z,1728596933.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-10-10T21:48:53.658Z,1728596933.658 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-10-10T21:48:53.781Z,1728596933.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-10-10T21:48:53.782Z,1728596933.782 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-10-10T21:48:54.026Z,1728596934.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-10-10T21:48:54.028Z,1728596934.028 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-10-10T21:48:54.295Z,1728596934.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-10-10T21:48:54.297Z,1728596934.297 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-10-10T21:48:54.299Z,1728596934.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-10-10T21:48:54.407Z,1728596934.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-10-10T21:48:54.505Z,1728596934.505 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-10-10T21:48:54.620Z,1728596934.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-10-10T21:48:54.716Z,1728596934.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-10-10T21:48:54.832Z,1728596934.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-10-10T21:48:54.937Z,1728596934.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-10-10T21:48:55.066Z,1728596935.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-10-10T21:48:55.235Z,1728596935.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-10-10T21:48:55.399Z,1728596935.399 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-10-10T21:48:55.522Z,1728596935.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-10-10T21:48:55.796Z,1728596935.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-10-10T21:48:55.797Z,1728596935.797 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-10-10T21:48:55.813Z,1728596935.813 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-10-10T21:48:55.918Z,1728596935.918 [VerticalControl](DEBUG): Construct VerticalControl.
2024-10-10T21:48:55.978Z,1728596935.978 [VerticalControl] Loaded
2024-10-10T21:48:55.978Z,1728596935.978 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-10-10T21:48:55.981Z,1728596935.981 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-10-10T21:48:56.028Z,1728596936.028 [HorizontalControl] Loaded
2024-10-10T21:48:56.028Z,1728596936.028 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-10-10T21:48:56.030Z,1728596936.030 [SpeedControl](DEBUG): Construct SpeedControl.
2024-10-10T21:48:56.033Z,1728596936.033 [SpeedControl] Loaded
2024-10-10T21:48:56.033Z,1728596936.033 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-10-10T21:48:56.036Z,1728596936.036 [LoopControl](DEBUG): Construct LoopControl.
2024-10-10T21:48:56.037Z,1728596936.037 [LoopControl] Loaded
2024-10-10T21:48:56.037Z,1728596936.037 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-10-10T21:48:56.037Z,1728596936.037 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-10-10T21:48:56.039Z,1728596936.039 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-10-10T21:48:56.054Z,1728596936.054 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-10-10T21:48:56.056Z,1728596936.056 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-10-10T21:48:56.267Z,1728596936.267 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-10-10T21:48:56.267Z,1728596936.267 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-10-10T21:48:56.460Z,1728596936.460 [BuoyancyServo] Loaded
2024-10-10T21:48:56.460Z,1728596936.460 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-10-10T21:48:56.482Z,1728596936.482 [ElevatorServo] Loaded
2024-10-10T21:48:56.482Z,1728596936.482 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-10-10T21:48:56.503Z,1728596936.503 [MassServo] Loaded
2024-10-10T21:48:56.503Z,1728596936.503 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-10-10T21:48:56.523Z,1728596936.523 [RudderServo] Loaded
2024-10-10T21:48:56.523Z,1728596936.523 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-10-10T21:48:56.539Z,1728596936.539 [ThrusterHE] Loaded
2024-10-10T21:48:56.540Z,1728596936.540 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-10-10T21:48:56.540Z,1728596936.540 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-10-10T21:48:56.541Z,1728596936.541 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-10-10T21:48:56.677Z,1728596936.677 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-10-10T21:48:56.677Z,1728596936.677 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-10-10T21:48:56.691Z,1728596936.691 [NavChart] Loaded
2024-10-10T21:48:56.691Z,1728596936.691 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-10-10T21:48:56.697Z,1728596936.697 [UniversalFixResidualReporter] Loaded
2024-10-10T21:48:56.697Z,1728596936.697 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-10-10T21:48:56.697Z,1728596936.697 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-10-10T21:48:56.698Z,1728596936.698 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-10-10T21:48:56.796Z,1728596936.796 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-10-10T21:48:56.797Z,1728596936.797 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-10-10T21:48:57.591Z,1728596937.591 [AHRS_M2] Loaded
2024-10-10T21:48:57.592Z,1728596937.592 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-10-10T21:48:58.936Z,1728596938.936 [BPC1] Loaded
2024-10-10T21:48:58.936Z,1728596938.936 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-10-10T21:48:59.169Z,1728596939.169 [DATMMP] Loaded
2024-10-10T21:48:59.170Z,1728596939.170 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-10-10T21:48:59.171Z,1728596939.171 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 4094C4E0
2024-10-10T21:48:59.171Z,1728596939.171 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 923
2024-10-10T21:48:59.310Z,1728596939.310 [DataOverHttps] Loaded
2024-10-10T21:48:59.310Z,1728596939.310 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-10-10T21:48:59.311Z,1728596939.311 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097C4E0
2024-10-10T21:48:59.312Z,1728596939.312 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2024-10-10T21:48:59.334Z,1728596939.334 [Depth_Keller] Loaded
2024-10-10T21:48:59.335Z,1728596939.335 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-10-10T21:48:59.339Z,1728596939.339 [DropWeight] Loaded
2024-10-10T21:48:59.340Z,1728596939.340 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-10-10T21:48:59.403Z,1728596939.403 [NAL9602] Loaded
2024-10-10T21:48:59.403Z,1728596939.403 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-10-10T21:48:59.433Z,1728596939.433 [Onboard] Loaded
2024-10-10T21:48:59.433Z,1728596939.433 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-10-10T21:48:59.434Z,1728596939.434 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AC4E0
2024-10-10T21:48:59.435Z,1728596939.435 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2024-10-10T21:48:59.441Z,1728596939.441 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2024-10-10T21:48:59.454Z,1728596939.454 [PowerOnly] Loaded
2024-10-10T21:48:59.454Z,1728596939.454 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-10-10T21:48:59.468Z,1728596939.468 [Power24vConverter] Loaded
2024-10-10T21:48:59.468Z,1728596939.468 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-10-10T21:48:59.483Z,1728596939.483 [Radio_Surface] Loaded
2024-10-10T21:48:59.483Z,1728596939.483 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-10-10T21:48:59.484Z,1728596939.484 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DC4E0
2024-10-10T21:48:59.485Z,1728596939.485 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2024-10-10T21:48:59.486Z,1728596939.486 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-10-10T21:48:59.486Z,1728596939.486 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-10-10T21:48:59.555Z,1728596939.555 [DepthRateCalculator] Loaded
2024-10-10T21:48:59.556Z,1728596939.556 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-10-10T21:48:59.561Z,1728596939.561 [PitchRateCalculator] Loaded
2024-10-10T21:48:59.561Z,1728596939.561 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-10-10T21:48:59.570Z,1728596939.570 [SpeedCalculator] Loaded
2024-10-10T21:48:59.570Z,1728596939.570 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-10-10T21:48:59.574Z,1728596939.574 [YawRateCalculator] Loaded
2024-10-10T21:48:59.575Z,1728596939.575 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-10-10T21:48:59.593Z,1728596939.593 [ElevatorOffsetCalculator] Loaded
2024-10-10T21:48:59.593Z,1728596939.593 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-10-10T21:48:59.594Z,1728596939.594 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-10-10T21:48:59.594Z,1728596939.594 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-10-10T21:48:59.818Z,1728596939.818 [CTD_Seabird] Loaded
2024-10-10T21:48:59.818Z,1728596939.818 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-10-10T21:48:59.819Z,1728596939.819 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A984E0
2024-10-10T21:48:59.819Z,1728596939.819 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2024-10-10T21:48:59.843Z,1728596939.843 [PAR_Licor] Loaded
2024-10-10T21:48:59.843Z,1728596939.843 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-10-10T21:48:59.885Z,1728596939.885 [WetLabsBB2FL] Loaded
2024-10-10T21:48:59.886Z,1728596939.886 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-10-10T21:48:59.887Z,1728596939.887 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC84E0
2024-10-10T21:48:59.887Z,1728596939.887 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2024-10-10T21:48:59.888Z,1728596939.888 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-10-10T21:48:59.888Z,1728596939.888 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-10-10T21:48:59.932Z,1728596939.932 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-10-10T21:48:59.933Z,1728596939.933 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-10-10T21:49:00.330Z,1728596940.330 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-10-10T21:49:00.331Z,1728596940.331 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-10-10T21:49:00.503Z,1728596940.503 [SBIT](DEBUG): Construct Startup Built In Test.
2024-10-10T21:49:00.512Z,1728596940.512 [SBIT] Loaded
2024-10-10T21:49:00.513Z,1728596940.513 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-10-10T21:49:00.515Z,1728596940.515 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-10-10T21:49:00.528Z,1728596940.528 [IBIT] Loaded
2024-10-10T21:49:00.529Z,1728596940.529 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-10-10T21:49:00.535Z,1728596940.535 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-10-10T21:49:00.638Z,1728596940.638 [CBIT] Loaded
2024-10-10T21:49:00.639Z,1728596940.639 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-10-10T21:49:00.639Z,1728596940.639 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-10-10T21:49:00.640Z,1728596940.640 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-10-10T21:49:00.784Z,1728596940.784 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-10-10T21:49:00.790Z,1728596940.790 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-10-10T21:49:00.793Z,1728596940.793 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-10-10T21:49:00.804Z,1728596940.804 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-10-10T21:49:00.805Z,1728596940.805 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0
2024-10-10T21:49:00.805Z,1728596940.805 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2024-10-10T21:49:00.810Z,1728596940.810 [Supervisor](INFO): Main Thread ID is 830
2024-10-10T21:49:00.810Z,1728596940.810 [Supervisor](DEBUG): Running supervisor.
2024-10-10T21:49:00.811Z,1728596940.811 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2024-10-10T21:49:00.811Z,1728596940.811 [CommandExec](INFO): Initializing the command executive.
2024-10-10T21:49:00.813Z,1728596940.813 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2024-10-10T21:49:00.815Z,1728596940.815 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2024-10-10T21:49:00.815Z,1728596940.815 [controlThread](DEBUG): Initializing ControlThread
2024-10-10T21:49:00.816Z,1728596940.816 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-10-10T21:49:00.818Z,1728596940.818 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-10-10T21:49:00.818Z,1728596940.818 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-10-10T21:49:00.819Z,1728596940.819 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-10-10T21:49:00.821Z,1728596940.821 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-10-10T21:49:00.821Z,1728596940.821 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-10-10T21:49:00.826Z,1728596940.826 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-10-10T21:49:00.827Z,1728596940.827 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-10-10T21:49:00.827Z,1728596940.827 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-10-10T21:49:00.827Z,1728596940.827 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-10-10T21:49:00.828Z,1728596940.828 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-10-10T21:49:00.833Z,1728596940.833 [SBIT](INFO): Initialize SBIT Component.
2024-10-10T21:49:00.833Z,1728596940.833 [SBIT](IMPORTANT): git: 2024-09-16_D
2024-10-10T21:49:00.834Z,1728596940.834 [SBIT](INFO): git hash: 0de9f8b4fab077d10194b536abee104cbdf12c92
2024-10-10T21:49:00.834Z,1728596940.834 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-10-10T21:49:00.835Z,1728596940.835 [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-10-10T21:49:00.836Z,1728596940.836 [SBIT](INFO): Beginning SBIT in 27.000000 seconds.
2024-10-10T21:49:00.837Z,1728596940.837 [logger ThreadHandler](INFO): Handler Thread ID is 933
2024-10-10T21:49:00.853Z,1728596940.853 [IBIT](INFO): Initialize IBIT Component.
2024-10-10T21:49:00.854Z,1728596940.854 [CBIT](DEBUG): Initialize CBIT Component.
2024-10-10T21:49:00.861Z,1728596940.861 [DATMMP ThreadHandler](INFO): Handler Thread ID is 934
2024-10-10T21:49:00.862Z,1728596940.862 [DATMMP](INFO): Start
2024-10-10T21:49:00.862Z,1728596940.862 [DATMMP](INFO): Powering up
2024-10-10T21:49:00.862Z,1728596940.862 [DATMMP](DEBUG): Initializing DATMMP.
2024-10-10T21:49:00.865Z,1728596940.865 [CBIT](DEBUG): Initialized mux pins.
2024-10-10T21:49:00.865Z,1728596940.865 [CBIT](DEBUG): Initializing the watchdog timer.
2024-10-10T21:49:00.866Z,1728596940.866 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936
2024-10-10T21:49:00.868Z,1728596940.868 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-10-10T21:49:00.877Z,1728596940.877 [Onboard ThreadHandler](INFO): Handler Thread ID is 937
2024-10-10T21:49:00.889Z,1728596940.889 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-10-10T21:49:00.890Z,1728596940.890 [CBIT](DEBUG): Initializing heartbeat.
2024-10-10T21:49:00.899Z,1728596940.899 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938
2024-10-10T21:49:00.914Z,1728596940.914 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2024-10-10T21:49:00.915Z,1728596940.915 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-10-10T21:49:00.918Z,1728596940.918 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2024-10-10T21:49:00.921Z,1728596940.921 [WetLabsBB2FL](INFO): Powering up
2024-10-10T21:49:00.922Z,1728596940.922 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2024-10-10T21:49:00.930Z,1728596940.930 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-10-10T21:49:00.930Z,1728596940.930 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-10-10T21:49:00.931Z,1728596940.931 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-10-10T21:49:00.931Z,1728596940.931 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-10-10T21:49:00.931Z,1728596940.931 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-10-10T21:49:00.931Z,1728596940.931 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-10-10T21:49:00.931Z,1728596940.931 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-10-10T21:49:00.931Z,1728596940.931 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-10-10T21:49:00.961Z,1728596940.961 [CBIT](DEBUG): Deactivating GF circuits.
2024-10-10T21:49:00.961Z,1728596940.961 [CBIT](DEBUG): Deactivating emergency mode.
2024-10-10T21:49:01.000Z,1728596941.000 [CBIT](DEBUG): Backplane powered.
2024-10-10T21:49:01.001Z,1728596941.001 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-10-10T21:49:01.002Z,1728596941.002 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-10-10T21:49:01.002Z,1728596941.002 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-10-10T21:49:01.009Z,1728596941.009 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-10-10T21:49:01.049Z,1728596941.049 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-10-10T21:49:01.054Z,1728596941.054 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-10T21:49:01.101Z,1728596941.101 [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-10-10T21:49:01.102Z,1728596941.102 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-10-10T21:49:01.102Z,1728596941.102 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-10-10T21:49:01.107Z,1728596941.107 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-10-10T21:49:01.113Z,1728596941.113 [DATMMP](INFO): Starting
2024-10-10T21:49:01.317Z,1728596941.317 [Radio_Surface](INFO): Powering up
2024-10-10T21:49:01.365Z,1728596941.365 [DATMMP](INFO): Starting
2024-10-10T21:49:01.395Z,1728596941.395 [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-10-10T21:49:01.421Z,1728596941.421 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-10-10T21:49:01.423Z,1728596941.423 [Default:A.Wait](DEBUG): Construct Wait.
2024-10-10T21:49:01.425Z,1728596941.425 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-10T21:49:01.469Z,1728596941.469 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-10-10T21:49:01.472Z,1728596941.472 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-10-10T21:49:01.494Z,1728596941.494 [Default:E.Execute](DEBUG): Construct Execute.
2024-10-10T21:49:01.497Z,1728596941.497 [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-10-10T21:49:01.516Z,1728596941.516 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,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-10-10T21:49:01.523Z,1728596941.523 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-10-10T21:49:01.617Z,1728596941.617 [DATMMP](INFO): Starting
2024-10-10T21:49:01.680Z,1728596941.680 [PowerOnly](INFO): Powering up loadControl
2024-10-10T21:49:01.697Z,1728596941.697 [Power24vConverter](INFO): Powering up.
2024-10-10T21:49:01.737Z,1728596941.737 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-10-10T21:49:01.745Z,1728596941.745 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-10-10T21:49:01.747Z,1728596941.747 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-10-10T21:49:01.753Z,1728596941.753 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-10-10T21:49:01.754Z,1728596941.754 [MassServo](DEBUG): Initializing EZServoServo.
2024-10-10T21:49:01.761Z,1728596941.761 [MassServo](DEBUG): Initializing MassServo.
2024-10-10T21:49:01.762Z,1728596941.762 [RudderServo](DEBUG): Initializing EZServoServo.
2024-10-10T21:49:01.769Z,1728596941.769 [RudderServo](DEBUG): Initializing RudderServo.
2024-10-10T21:49:01.770Z,1728596941.770 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-10-10T21:49:01.781Z,1728596941.781 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-10-10T21:49:01.869Z,1728596941.869 [DATMMP](INFO): Starting
2024-10-10T21:49:01.958Z,1728596941.958 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-10-10T21:49:01.958Z,1728596941.958 [DropWeight] Hardware Fault, FailCount= 1
2024-10-10T21:49:01.958Z,1728596941.958 [DropWeight](ERROR): Hardware Fault
2024-10-10T21:49:01.987Z,1728596941.987 [CommandExec](FAULT): Scheduling is paused
2024-10-10T21:49:01.988Z,1728596941.988 [CBIT](INFO): Critical error at 20241010T214901
2024-10-10T21:49:01.988Z,1728596941.988 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-10-10T21:49:01.998Z,1728596941.998 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-10-10T21:49:01.999Z,1728596941.999 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-10-10T21:49:02.121Z,1728596942.121 [DATMMP](INFO): Starting
2024-10-10T21:49:02.355Z,1728596942.355 [CBIT](INFO): Critical error at 20241010T214901
2024-10-10T21:49:02.373Z,1728596942.373 [DATMMP](INFO): Starting
2024-10-10T21:49:02.625Z,1728596942.625 [DATMMP](INFO): Starting
2024-10-10T21:49:02.877Z,1728596942.877 [DATMMP](INFO): Starting
2024-10-10T21:49:03.129Z,1728596943.129 [DATMMP](INFO): Starting
2024-10-10T21:49:03.381Z,1728596943.381 [DATMMP](INFO): Starting
2024-10-10T21:49:03.533Z,1728596943.533 [WetLabsBB2FL](INFO): Powering down
2024-10-10T21:49:03.633Z,1728596943.633 [DATMMP](INFO): Starting
2024-10-10T21:49:03.885Z,1728596943.885 [DATMMP](INFO): Starting
2024-10-10T21:49:04.137Z,1728596944.137 [DATMMP](INFO): Starting
2024-10-10T21:49:04.389Z,1728596944.389 [DATMMP](INFO): Starting
2024-10-10T21:49:04.641Z,1728596944.641 [DATMMP](INFO): Starting
2024-10-10T21:49:04.893Z,1728596944.893 [DATMMP](INFO): Starting
2024-10-10T21:49:05.145Z,1728596945.145 [DATMMP](INFO): Starting
2024-10-10T21:49:05.397Z,1728596945.397 [DATMMP](INFO): Starting
2024-10-10T21:49:05.649Z,1728596945.649 [DATMMP](INFO): Starting
2024-10-10T21:49:05.901Z,1728596945.901 [DATMMP](INFO): Starting
2024-10-10T21:49:06.153Z,1728596946.153 [DATMMP](INFO): Starting
2024-10-10T21:49:06.409Z,1728596946.409 [DATMMP](INFO): Starting
2024-10-10T21:49:06.661Z,1728596946.661 [DATMMP](INFO): Starting
2024-10-10T21:49:06.913Z,1728596946.913 [DATMMP](INFO): Starting
2024-10-10T21:49:07.165Z,1728596947.165 [DATMMP](INFO): Starting
2024-10-10T21:49:07.290Z,1728596947.290 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-10-10T21:49:07.417Z,1728596947.417 [DATMMP](INFO): Starting
2024-10-10T21:49:07.669Z,1728596947.669 [DATMMP](INFO): Starting
2024-10-10T21:49:07.921Z,1728596947.921 [DATMMP](INFO): Starting
2024-10-10T21:49:08.173Z,1728596948.173 [DATMMP](INFO): Starting
2024-10-10T21:49:08.429Z,1728596948.429 [DATMMP](INFO): Starting
2024-10-10T21:49:08.681Z,1728596948.681 [DATMMP](INFO): Starting
2024-10-10T21:49:08.933Z,1728596948.933 [DATMMP](INFO): Starting
2024-10-10T21:49:09.185Z,1728596949.185 [DATMMP](INFO): Starting
2024-10-10T21:49:09.437Z,1728596949.437 [DATMMP](INFO): Starting
2024-10-10T21:49:09.689Z,1728596949.689 [DATMMP](INFO): Starting
2024-10-10T21:49:09.941Z,1728596949.941 [DATMMP](INFO): Starting
2024-10-10T21:49:10.193Z,1728596950.193 [DATMMP](INFO): Starting
2024-10-10T21:49:10.445Z,1728596950.445 [DATMMP](INFO): Starting
2024-10-10T21:49:10.697Z,1728596950.697 [DATMMP](INFO): Starting
2024-10-10T21:49:10.949Z,1728596950.949 [DATMMP](INFO): Starting
2024-10-10T21:49:11.201Z,1728596951.201 [DATMMP](INFO): Starting
2024-10-10T21:49:11.453Z,1728596951.453 [DATMMP](INFO): Starting
2024-10-10T21:49:11.705Z,1728596951.705 [DATMMP](INFO): Starting
2024-10-10T21:49:11.957Z,1728596951.957 [DATMMP](INFO): Starting
2024-10-10T21:49:12.209Z,1728596952.209 [DATMMP](INFO): Starting
2024-10-10T21:49:12.461Z,1728596952.461 [DATMMP](INFO): Starting
2024-10-10T21:49:12.461Z,1728596952.461 [DATMMP](INFO): DAT read:
2024-10-10T21:49:12.462Z,1728596952.462 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-10-10T21:49:12.713Z,1728596952.713 [DATMMP](INFO): Starting
2024-10-10T21:49:12.965Z,1728596952.965 [DATMMP](INFO): Starting
2024-10-10T21:49:13.217Z,1728596953.217 [DATMMP](INFO): Starting
2024-10-10T21:49:13.469Z,1728596953.469 [DATMMP](INFO): Starting
2024-10-10T21:49:13.721Z,1728596953.721 [DATMMP](INFO): Starting
2024-10-10T21:49:13.973Z,1728596953.973 [DATMMP](INFO): Starting
2024-10-10T21:49:13.973Z,1728596953.973 [DATMMP](INFO): DAT read: MF Frequency Band
2024-10-10T21:49:13.974Z,1728596953.974 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-10-10T21:49:13.975Z,1728596953.975 [DATMMP](INFO): DAT read: Sep 10 2000 21:49:07
2024-10-10T21:49:14.225Z,1728596954.225 [DATMMP](INFO): Starting
2024-10-10T21:49:14.477Z,1728596954.477 [DATMMP](INFO): Starting
2024-10-10T21:49:14.729Z,1728596954.729 [DATMMP](INFO): Starting
2024-10-10T21:49:14.981Z,1728596954.981 [DATMMP](INFO): Starting
2024-10-10T21:49:15.233Z,1728596955.233 [DATMMP](INFO): Starting
2024-10-10T21:49:15.233Z,1728596955.233 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-10-10T21:49:15.234Z,1728596955.234 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-10-10T21:49:15.235Z,1728596955.235 [DATMMP](INFO): commRate: 1200
2024-10-10T21:49:15.485Z,1728596955.485 [DATMMP](INFO): Starting
2024-10-10T21:49:15.737Z,1728596955.737 [DATMMP](INFO): Starting
2024-10-10T21:49:15.989Z,1728596955.989 [DATMMP](INFO): Starting
2024-10-10T21:49:16.241Z,1728596956.241 [DATMMP](INFO): Starting
2024-10-10T21:49:16.493Z,1728596956.493 [DATMMP](INFO): Starting
2024-10-10T21:49:16.745Z,1728596956.745 [DATMMP](INFO): Starting
2024-10-10T21:49:16.997Z,1728596956.997 [DATMMP](INFO): Starting
2024-10-10T21:49:17.249Z,1728596957.249 [DATMMP](INFO): Starting
2024-10-10T21:49:17.301Z,1728596957.301 [DATMMP](INFO): entering command mode
2024-10-10T21:49:17.501Z,1728596957.501 [DATMMP](INFO): Starting
2024-10-10T21:49:17.501Z,1728596957.501 [DATMMP](INFO): DAT read:
2024-10-10T21:49:17.501Z,1728596957.501 [DATMMP](INFO): DAT read: user:1>
2024-10-10T21:49:17.502Z,1728596957.502 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-10-10T21:49:17.502Z,1728596957.502 [DATMMP](INFO): entering MMP mode
2024-10-10T21:49:17.753Z,1728596957.753 [DATMMP](INFO): Starting
2024-10-10T21:49:17.753Z,1728596957.753 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-10T21:49:17.755Z,1728596957.755 [DATMMP](INFO): status rx: x1
2024-10-10T21:49:17.755Z,1728596957.755 [DATMMP](INFO): MMP status: 1: started
2024-10-10T21:49:17.756Z,1728596957.756 [DATMMP](INFO): Received message type: status
2024-10-10T21:49:17.756Z,1728596957.756 [DATMMP](INFO): Handled
2024-10-10T21:49:18.005Z,1728596958.005 [DATMMP](INFO): Starting
2024-10-10T21:49:18.006Z,1728596958.006 [DATMMP](INFO): Sent Tx Power Config 8
2024-10-10T21:49:18.006Z,1728596958.006 [DATMMP](INFO): Setting local address: 8
2024-10-10T21:49:18.008Z,1728596958.008 [DATMMP](INFO): Setting time to: 21:49:18 And date to:10/10/2024
2024-10-10T21:49:18.257Z,1728596958.257 [DATMMP](INFO): Starting
2024-10-10T21:49:18.257Z,1728596958.257 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-10T21:49:18.258Z,1728596958.258 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-10-10T21:49:18.258Z,1728596958.258 [DATMMP](INFO): Received message type: mdmpower
2024-10-10T21:49:18.258Z,1728596958.258 [DATMMP](INFO): Handled
2024-10-10T21:49:18.509Z,1728596958.509 [DATMMP](INFO): Starting
2024-10-10T21:49:18.509Z,1728596958.509 [DATMMP](INFO): GSXN notify for xid: 10
2024-10-10T21:49:18.510Z,1728596958.510 [DATMMP](INFO): sreg_txpower rx: x8
2024-10-10T21:49:18.510Z,1728596958.510 [DATMMP](INFO): Received message type: sreg_txpower
2024-10-10T21:49:18.510Z,1728596958.510 [DATMMP](INFO): Match for : sreg_txpower
2024-10-10T21:49:18.510Z,1728596958.510 [DATMMP](INFO): ACK
2024-10-10T21:49:18.761Z,1728596958.761 [DATMMP](INFO): Starting
2024-10-10T21:49:18.762Z,1728596958.762 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-10T21:49:18.763Z,1728596958.763 [DATMMP](INFO): mdmlocal rx: x8 x0 x0 x0
2024-10-10T21:49:18.763Z,1728596958.763 [DATMMP](INFO): Received message type: mdmlocal
2024-10-10T21:49:18.763Z,1728596958.763 [DATMMP](INFO): Handled
2024-10-10T21:49:19.013Z,1728596959.013 [DATMMP](INFO): Starting
2024-10-10T21:49:19.013Z,1728596959.013 [DATMMP](INFO): GSXN notify for xid: 11
2024-10-10T21:49:19.014Z,1728596959.014 [DATMMP](INFO): sreg_locaddr rx: x8
2024-10-10T21:49:19.014Z,1728596959.014 [DATMMP](INFO): Received message type: sreg_locaddr
2024-10-10T21:49:19.014Z,1728596959.014 [DATMMP](INFO): Match for : sreg_locaddr
2024-10-10T21:49:19.014Z,1728596959.014 [DATMMP](INFO): ACK
2024-10-10T21:49:19.265Z,1728596959.265 [DATMMP](INFO): Starting
2024-10-10T21:49:19.265Z,1728596959.265 [DATMMP](INFO): GSXN notify for xid: 12
2024-10-10T21:49:19.266Z,1728596959.266 [DATMMP](INFO): timedate rx: x0 x15 x31 x12 x9 xA x7C x0
2024-10-10T21:49:19.267Z,1728596959.267 [DATMMP](INFO): Received message type: timedate
2024-10-10T21:49:19.267Z,1728596959.267 [DATMMP](INFO): Match for : timedate
2024-10-10T21:49:19.267Z,1728596959.267 [DATMMP](INFO): ACK
2024-10-10T21:49:19.517Z,1728596959.517 [DATMMP](INFO): Starting
2024-10-10T21:49:19.517Z,1728596959.517 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-10T21:49:19.518Z,1728596959.518 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-10-10T21:49:19.518Z,1728596959.518 [DATMMP](INFO): Received message type: sys_verb
2024-10-10T21:49:19.518Z,1728596959.518 [DATMMP](INFO): Handled
2024-10-10T21:49:19.769Z,1728596959.769 [DATMMP](INFO): Starting
2024-10-10T21:49:19.769Z,1728596959.769 [DATMMP](INFO): GSXN notify for xid: 13
2024-10-10T21:49:19.770Z,1728596959.770 [DATMMP](INFO): sreg_verbosity rx: x0
2024-10-10T21:49:19.770Z,1728596959.770 [DATMMP](INFO): Received message type: sreg_verbosity
2024-10-10T21:49:19.770Z,1728596959.770 [DATMMP](INFO): Match for : sreg_verbosity
2024-10-10T21:49:19.770Z,1728596959.770 [DATMMP](INFO): ACK
2024-10-10T21:49:20.021Z,1728596960.021 [DATMMP](INFO): Starting
2024-10-10T21:49:20.021Z,1728596960.021 [DATMMP](INFO): GSXN notify for xid: 14
2024-10-10T21:49:20.022Z,1728596960.022 [DATMMP](INFO): version rx: x2 x5
2024-10-10T21:49:20.022Z,1728596960.022 [DATMMP](INFO): DatMMPVersion: 2.5
2024-10-10T21:49:20.022Z,1728596960.022 [DATMMP](INFO): Received message type: version
2024-10-10T21:49:20.023Z,1728596960.023 [DATMMP](INFO): Match for : version
2024-10-10T21:49:20.023Z,1728596960.023 [DATMMP](INFO): ACK
2024-10-10T21:49:20.023Z,1728596960.023 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-10-10T21:49:28.644Z,1728596968.644 [SBIT](IMPORTANT): Beginning Startup BIT
2024-10-10T21:49:28.652Z,1728596968.652 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-10T21:49:29.410Z,1728596969.410 [NAL9602](INFO): Powering up NAL9602
2024-10-10T21:49:34.389Z,1728596974.389 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-10T21:49:34.390Z,1728596974.390 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-10-10T21:49:34.390Z,1728596974.390 [DATMMP](INFO): Received message type: timesync
2024-10-10T21:49:34.390Z,1728596974.390 [DATMMP](INFO): Handled
2024-10-10T21:49:39.523Z,1728596979.523 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-10T21:49:39.983Z,1728596979.983 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-10T21:49:40.314Z,1728596980.314 [NAL9602](INFO): NAL9602 initialized
2024-10-10T21:49:44.217Z,1728596984.217 [DATMMP](INFO): GSXN notify for xid: 0
2024-10-10T21:49:44.218Z,1728596984.218 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-10-10T21:49:44.218Z,1728596984.218 [DATMMP](INFO): Received message type: timesync
2024-10-10T21:49:44.218Z,1728596984.218 [DATMMP](INFO): Handled
2024-10-10T21:49:50.958Z,1728596990.958 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-10T21:49:51.351Z,1728596991.351 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-10T21:50:02.180Z,1728597002.180 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-10T21:50:02.181Z,1728597002.181 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-10-10T21:50:22.751Z,1728597022.751 [SBIT](IMPORTANT): SBIT PASSED
2024-10-10T21:50:22.751Z,1728597022.751 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-10-10T21:50:22.752Z,1728597022.752 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=0 bool;
2024-10-10T21:50:22.752Z,1728597022.752 [SBIT](IMPORTANT): CANONSampler.simulateHardware=1 bool;
2024-10-10T21:50:22.752Z,1728597022.752 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-10-10T21:50:22.753Z,1728597022.753 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-10-10T21:50:22.753Z,1728597022.753 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-10-10T21:50:22.753Z,1728597022.753 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-10-10T21:50:22.753Z,1728597022.753 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2024-10-10T21:50:22.753Z,1728597022.753 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;
2024-10-10T21:50:22.753Z,1728597022.753 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=246.619564 cubic_centimeter;
2024-10-10T21:50:22.753Z,1728597022.753 [SBIT](IMPORTANT): VerticalControl.massDefault=1.964576 millimeter;
2024-10-10T21:50:23.160Z,1728597023.160 [MissionManager](IMPORTANT): Started mission Startup
2024-10-10T21:50:23.161Z,1728597023.161 [Startup] Running Loop=1
2024-10-10T21:50:23.161Z,1728597023.161 [Startup](DEBUG): Aggregate::initialize Startup
2024-10-10T21:50:23.161Z,1728597023.161 [Startup:A.GoToSurface] Running Loop=1
2024-10-10T21:50:23.161Z,1728597023.161 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-10T21:50:23.161Z,1728597023.161 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-10T21:50:23.162Z,1728597023.162 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-10T21:50:23.162Z,1728597023.162 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-10T21:50:23.162Z,1728597023.162 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-10T21:50:23.163Z,1728597023.163 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-10T21:50:23.163Z,1728597023.163 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-10T21:50:23.165Z,1728597023.165 [Startup:StartupSatComms] Running Loop=1
2024-10-10T21:50:23.165Z,1728597023.165 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-10-10T21:50:23.165Z,1728597023.165 [Startup:StartupSatComms:A] Running Loop=1
2024-10-10T21:50:23.571Z,1728597023.571 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-10-10T21:51:23.367Z,1728597083.367 [Startup:StartupSatComms:A](INFO): Timed out from 2024-10-10T21:50:23.2Z
2024-10-10T21:51:23.367Z,1728597083.367 [Startup:StartupSatComms:A] Stopped
2024-10-10T21:51:23.367Z,1728597083.367 [Startup:StartupSatComms:B] Running Loop=1
2024-10-10T21:51:23.765Z,1728597083.765 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-10T21:52:00.942Z,1728597120.942 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-10-10T21:52:00.942Z,1728597120.942 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-10T21:52:00.953Z,1728597120.953 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-10T21:52:01.353Z,1728597121.353 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-10T21:52:01.353Z,1728597121.353 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-10-10T21:52:23.557Z,1728597143.557 [Startup:StartupSatComms:B](INFO): Timed out from 2024-10-10T21:51:23.4Z
2024-10-10T21:52:23.557Z,1728597143.557 [Startup:StartupSatComms:B] Stopped
2024-10-10T21:52:23.557Z,1728597143.557 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-10-10T21:52:23.557Z,1728597143.557 [Startup:StartupSatComms] Stopped
2024-10-10T21:52:23.557Z,1728597143.557 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-10-10T21:52:23.558Z,1728597143.558 [Startup](INFO): Completed Startup
2024-10-10T21:52:23.558Z,1728597143.558 [MissionManager](INFO): Startup is completed.
2024-10-10T21:52:23.559Z,1728597143.559 [MissionManager](INFO): Uninitializing Mission Startup
2024-10-10T21:52:23.559Z,1728597143.559 [Startup] Stopped
2024-10-10T21:52:23.559Z,1728597143.559 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-10-10T21:52:23.559Z,1728597143.559 [Startup:A.GoToSurface] Stopped
2024-10-10T21:52:23.559Z,1728597143.559 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-10T21:52:23.947Z,1728597143.947 [MissionManager](IMPORTANT): Started mission Default
2024-10-10T21:52:23.947Z,1728597143.947 [Default] Running Loop=1
2024-10-10T21:52:23.947Z,1728597143.947 [Default](DEBUG): Aggregate::initialize Default
2024-10-10T21:52:23.947Z,1728597143.947 [Default:B.GoToSurface] Running Loop=1
2024-10-10T21:52:23.947Z,1728597143.947 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-10T21:52:23.947Z,1728597143.947 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-10T21:52:23.948Z,1728597143.948 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-10T21:52:23.948Z,1728597143.948 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-10T21:52:23.949Z,1728597143.949 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-10T21:52:23.949Z,1728597143.949 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-10T21:52:23.949Z,1728597143.949 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-10T21:52:23.949Z,1728597143.949 [Default:A.Wait] Running Loop=1
2024-10-10T21:52:23.949Z,1728597143.949 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-10-10T21:52:37.303Z,1728597157.303 [Default:A.Wait](INFO): Done Waiting.
2024-10-10T21:52:37.303Z,1728597157.303 [Default:A.Wait] Stopped
2024-10-10T21:52:37.304Z,1728597157.304 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-10-10T21:52:37.714Z,1728597157.714 [Default:CheckIn] Running Loop=1
2024-10-10T21:52:37.714Z,1728597157.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-10T21:52:37.714Z,1728597157.714 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-10T21:52:38.097Z,1728597158.097 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-10-10T21:54:44.142Z,1728597284.142 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-10T21:55:01.913Z,1728597301.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-10-10T21:55:01.913Z,1728597301.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-10T21:55:01.924Z,1728597301.924 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-10T21:55:02.337Z,1728597302.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-10T21:55:02.337Z,1728597302.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-10-10T21:55:30.174Z,1728597330.174 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:55:35.829Z,1728597335.829 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:55:49.167Z,1728597349.167 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:56:02.493Z,1728597362.493 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:56:15.825Z,1728597375.825 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:56:29.562Z,1728597389.562 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:56:43.298Z,1728597403.298 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:56:57.034Z,1728597417.034 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:57:10.366Z,1728597430.366 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:57:23.697Z,1728597443.697 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:57:37.841Z,1728597457.841 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:57:37.869Z,1728597457.869 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-10T21:52:37.7Z
2024-10-10T21:57:37.870Z,1728597457.870 [Default:CheckIn:Read_GPS] Stopped
2024-10-10T21:57:37.870Z,1728597457.870 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-10T21:57:38.288Z,1728597458.288 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-10T21:57:51.170Z,1728597471.170 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T21:58:02.922Z,1728597482.922 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-10-10T21:58:02.922Z,1728597482.922 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-10T21:58:02.933Z,1728597482.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-10T21:58:03.334Z,1728597483.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-10T21:58:03.335Z,1728597483.335 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-10-10T21:59:41.874Z,1728597581.874 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-10T21:59:41.874Z,1728597581.874 [NAL9602] Data Fault, FailCount= 1
2024-10-10T21:59:41.874Z,1728597581.874 [NAL9602](ERROR): Data Fault
2024-10-10T21:59:41.896Z,1728597581.896 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-10T21:59:42.284Z,1728597582.284 [NAL9602](INFO): Powering down
2024-10-10T21:59:43.107Z,1728597583.107 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-10T21:59:43.107Z,1728597583.107 [NAL9602] No Fault, FailCount= 1
2024-10-10T22:00:12.578Z,1728597612.578 [NAL9602](INFO): Powering up NAL9602
2024-10-10T22:00:23.494Z,1728597623.494 [NAL9602](INFO): NAL9602 initialized
2024-10-10T22:01:03.914Z,1728597663.914 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-10-10T22:01:03.914Z,1728597663.914 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-10T22:01:03.925Z,1728597663.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-10T22:01:04.487Z,1728597664.487 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-10T22:01:04.487Z,1728597664.487 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-10-10T22:04:02.072Z,1728597842.072 [CBIT](INFO): Clearing failed state for component DropWeight
2024-10-10T22:04:02.072Z,1728597842.072 [DropWeight] No Fault, FailCount= 1
2024-10-10T22:04:04.895Z,1728597844.895 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-10-10T22:04:04.895Z,1728597844.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-10T22:04:04.922Z,1728597844.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-10T22:04:05.335Z,1728597845.335 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-10T22:04:05.335Z,1728597845.335 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-10-10T22:05:10.011Z,1728597910.011 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:05:23.341Z,1728597923.341 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:05:37.077Z,1728597937.077 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:05:50.409Z,1728597950.409 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:06:03.741Z,1728597963.741 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:06:17.078Z,1728597977.078 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:06:30.409Z,1728597990.409 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:06:43.743Z,1728598003.743 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:06:57.474Z,1728598017.474 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:07:05.989Z,1728598025.989 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-10-10T22:07:05.989Z,1728598025.989 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-10T22:07:06.036Z,1728598026.036 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-10T22:07:06.559Z,1728598026.559 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-10T22:07:06.559Z,1728598026.559 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-10-10T22:07:10.806Z,1728598030.806 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:07:24.138Z,1728598044.138 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-10T22:08:35.211Z,1728598115.211 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004922
2024-10-10T22:08:42.512Z,1728598122.512 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20241010T153635/Courier0031.lzma
2024-10-10T22:08:43.515Z,1728598123.515 [DataOverHttps](INFO): Moved sent file to Logs/20241010T153635/Courier0031.lzma.bak
2024-10-10T22:08:43.515Z,1728598123.515 [DataOverHttps](INFO): SBD MOMSN=23284373
2024-10-10T22:08:59.258Z,1728598139.258 [DataOverHttps](INFO): Sending 432 bytes from file Logs/20241010T153635/Courier0034.lzma
2024-10-10T22:09:00.258Z,1728598140.258 [DataOverHttps](INFO): Moved sent file to Logs/20241010T153635/Courier0034.lzma.bak
2024-10-10T22:09:00.259Z,1728598140.259 [DataOverHttps](INFO): SBD MOMSN=23284375
2024-10-10T22:09:16.000Z,1728598156.000 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20241010T214848/Courier0000.lzma
2024-10-10T22:09:17.002Z,1728598157.002 [DataOverHttps](INFO): Moved sent file to Logs/20241010T214848/Courier0000.lzma.bak
2024-10-10T22:09:17.002Z,1728598157.002 [DataOverHttps](INFO): SBD MOMSN=23284401
2024-10-10T22:09:31.672Z,1728598171.672 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241010T214848/Courier0004.lzma
2024-10-10T22:09:32.674Z,1728598172.674 [DataOverHttps](INFO): Moved sent file to Logs/20241010T214848/Courier0004.lzma.bak
2024-10-10T22:09:32.674Z,1728598172.674 [DataOverHttps](INFO): SBD MOMSN=23284408
2024-10-10T22:09:47.317Z,1728598187.317 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20241010T153635/Express0032.lzma
2024-10-10T22:09:48.318Z,1728598188.318 [DataOverHttps](INFO): Moved sent file to Logs/20241010T153635/Express0032.lzma.bak
2024-10-10T22:09:48.319Z,1728598188.319 [DataOverHttps](INFO): SBD MOMSN=23284414
2024-10-10T22:10:03.855Z,1728598203.855 [DataOverHttps](INFO): Sending 933 bytes from file Logs/20241010T153635/Express0035.lzma
2024-10-10T22:10:04.855Z,1728598204.855 [DataOverHttps](INFO): Moved sent file to Logs/20241010T153635/Express0035.lzma.bak
2024-10-10T22:10:04.855Z,1728598204.855 [DataOverHttps](INFO): SBD MOMSN=23284417
2024-10-10T22:10:06.981Z,1728598206.981 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-10-10T22:10:06.981Z,1728598206.981 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-10T22:10:06.992Z,1728598206.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-10T22:10:07.402Z,1728598207.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-10T22:10:07.402Z,1728598207.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-10-10T22:10:25.194Z,1728598225.194 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-10T22:10:25.194Z,1728598225.194 [NAL9602] Data Fault, FailCount= 2
2024-10-10T22:10:25.194Z,1728598225.194 [NAL9602](ERROR): Data Fault
2024-10-10T22:10:25.279Z,1728598225.279 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-10T22:10:25.598Z,1728598225.598 [NAL9602](INFO): Powering down
2024-10-10T22:10:26.257Z,1728598226.257 [DataOverHttps](INFO): Sending 872 bytes from file Logs/20241010T214848/Express0001.lzma
2024-10-10T22:10:26.434Z,1728598226.434 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-10T22:10:26.434Z,1728598226.434 [NAL9602] No Fault, FailCount= 2
2024-10-10T22:10:27.254Z,1728598227.254 [DataOverHttps](INFO): Moved sent file to Logs/20241010T214848/Express0001.lzma.bak
2024-10-10T22:10:27.254Z,1728598227.254 [DataOverHttps](INFO): SBD MOMSN=23284493
2024-10-10T22:10:51.292Z,1728598251.292 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20241010T214848/Express0005.lzma
2024-10-10T22:10:52.294Z,1728598252.294 [DataOverHttps](INFO): Moved sent file to Logs/20241010T214848/Express0005.lzma.bak
2024-10-10T22:10:52.295Z,1728598252.295 [DataOverHttps](INFO): SBD MOMSN=23284522
2024-10-10T22:10:54.354Z,1728598254.354 [Default:CheckIn:Read_Iridium] Stopped
2024-10-10T22:10:54.355Z,1728598254.355 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-10T22:10:54.355Z,1728598254.355 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-10T22:10:55.898Z,1728598255.898 [NAL9602](INFO): Powering up NAL9602
2024-10-10T22:11:06.806Z,1728598266.806 [NAL9602](INFO): NAL9602 initialized
2024-10-10T22:12:41.274Z,1728598361.274 [CommandExec](IMPORTANT): got command burn off
2024-10-10T22:12:41.274Z,1728598361.274 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-10-10T22:12:44.707Z,1728598364.707 [CommandExec](IMPORTANT): got command burn off
2024-10-10T22:12:44.707Z,1728598364.707 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-10-10T22:12:51.158Z,1728598371.158 [CommandExec](IMPORTANT): got command restart system
2024-10-10T22:12:53.217Z,1728598373.217 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:53.217Z,1728598373.217 [CommandExec](INFO): Uninitializing the command executive.
2024-10-10T22:12:53.217Z,1728598373.217 [CommandExec](INFO): Uninitializing the command scheduler.
2024-10-10T22:12:53.218Z,1728598373.218 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:53.265Z,1728598373.265 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-10-10T22:12:53.265Z,1728598373.265 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-10-10T22:12:53.265Z,1728598373.265 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:53.266Z,1728598373.266 [NavChartDb](INFO): Join timeout helper Thread ID is 986
2024-10-10T22:12:53.329Z,1728598373.329 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:53.329Z,1728598373.329 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:53.337Z,1728598373.337 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-10-10T22:12:53.337Z,1728598373.337 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:53.337Z,1728598373.337 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 987
2024-10-10T22:12:53.573Z,1728598373.573 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:53.573Z,1728598373.573 [WetLabsBB2FL](INFO): Powering down
2024-10-10T22:12:53.574Z,1728598373.574 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:53.593Z,1728598373.593 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-10-10T22:12:53.593Z,1728598373.593 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:53.593Z,1728598373.593 [CTD_Seabird](INFO): Join timeout helper Thread ID is 988
2024-10-10T22:12:53.837Z,1728598373.837 [CTD_Seabird](INFO): Powering down
2024-10-10T22:12:53.860Z,1728598373.860 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:53.860Z,1728598373.860 [CTD_Seabird](INFO): Powering down
2024-10-10T22:12:53.873Z,1728598373.873 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:53.877Z,1728598373.877 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-10-10T22:12:53.877Z,1728598373.877 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:53.877Z,1728598373.877 [Radio_Surface](INFO): Join timeout helper Thread ID is 989
2024-10-10T22:12:54.041Z,1728598374.041 [Radio_Surface](INFO): Powering down
2024-10-10T22:12:54.042Z,1728598374.042 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:54.042Z,1728598374.042 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:54.045Z,1728598374.045 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-10-10T22:12:54.045Z,1728598374.045 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:54.045Z,1728598374.045 [Onboard](INFO): Join timeout helper Thread ID is 990
2024-10-10T22:12:54.837Z,1728598374.837 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-10-10T22:12:57.089Z,1728598377.089 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:57.089Z,1728598377.089 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.096Z,1728598377.096 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-10-10T22:12:57.096Z,1728598377.096 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.096Z,1728598377.096 [DataOverHttps](INFO): Join timeout helper Thread ID is 991
2024-10-10T22:12:57.849Z,1728598377.849 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:57.849Z,1728598377.849 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.881Z,1728598377.881 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-10-10T22:12:57.881Z,1728598377.881 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.881Z,1728598377.881 [DATMMP](INFO): Join timeout helper Thread ID is 992
2024-10-10T22:12:57.886Z,1728598377.886 [DATMMP](INFO): Stop
2024-10-10T22:12:57.886Z,1728598377.886 [DATMMP](INFO): uninitialize
2024-10-10T22:12:57.886Z,1728598377.886 [DATMMP](INFO): Powering down
2024-10-10T22:12:57.957Z,1728598377.957 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:57.958Z,1728598377.958 [DATMMP](INFO): uninitialize
2024-10-10T22:12:57.958Z,1728598377.958 [DATMMP](INFO): Powering down
2024-10-10T22:12:57.959Z,1728598377.959 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.971Z,1728598377.971 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-10-10T22:12:57.971Z,1728598377.971 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.971Z,1728598377.971 [logger](INFO): Join timeout helper Thread ID is 993
2024-10-10T22:12:57.972Z,1728598377.972 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:57.972Z,1728598377.972 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.981Z,1728598377.981 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-10-10T22:12:57.981Z,1728598377.981 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.982Z,1728598377.982 [CommandLine](INFO): Join timeout helper Thread ID is 994
2024-10-10T22:12:57.989Z,1728598377.989 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:57.989Z,1728598377.989 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.990Z,1728598377.990 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-10-10T22:12:57.991Z,1728598377.991 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.991Z,1728598377.991 [CommandExec](INFO): Join timeout helper Thread ID is 995
2024-10-10T22:12:57.997Z,1728598377.997 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-10-10T22:12:57.997Z,1728598377.997 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-10-10T22:12:57.998Z,1728598377.998 [controlThread](INFO): Join timeout helper Thread ID is 996
2024-10-10T22:12:58.057Z,1728598378.057 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-10T22:12:58.058Z,1728598378.058 [controlThread](DEBUG): Uninitializing ControlThread
2024-10-10T22:12:58.058Z,1728598378.058 [AHRS_M2](INFO): Powering down
2024-10-10T22:12:58.130Z,1728598378.130 [NAL9602](INFO): Powering down
2024-10-10T22:12:58.132Z,1728598378.132 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-10-10T22:12:58.133Z,1728598378.133 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-10-10T22:12:58.133Z,1728598378.133 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-10-10T22:12:58.134Z,1728598378.134 [MissionManager](INFO): Uninitializing Mission Default
2024-10-10T22:12:58.134Z,1728598378.134 [Default] Stopped
2024-10-10T22:12:58.134Z,1728598378.134 [Default](DEBUG): Aggregate::uninitialize Default
2024-10-10T22:12:58.134Z,1728598378.134 [Default:B.GoToSurface] Stopped
2024-10-10T22:12:58.134Z,1728598378.134 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-10T22:12:58.134Z,1728598378.134 [Default:CheckIn] Stopped
2024-10-10T22:12:58.134Z,1728598378.134 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-10T22:12:58.135Z,1728598378.135 [Default:CheckIn:C.Wait] Stopped
2024-10-10T22:12:58.135Z,1728598378.135 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-10T22:12:58.137Z,1728598378.137 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-10-10T22:12:58.138Z,1728598378.138 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-10-10T22:12:58.138Z,1728598378.138 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-10-10T22:12:58.138Z,1728598378.138 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-10-10T22:12:58.138Z,1728598378.138 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-10-10T22:12:58.139Z,1728598378.139 [BuoyancyServo](INFO): Powering down
2024-10-10T22:12:58.153Z,1728598378.153 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-10-10T22:12:58.153Z,1728598378.153 [ElevatorServo](INFO): Powering down
2024-10-10T22:12:58.154Z,1728598378.154 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-10-10T22:12:58.154Z,1728598378.154 [MassServo](INFO): Powering down
2024-10-10T22:12:58.155Z,1728598378.155 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-10-10T22:12:58.155Z,1728598378.155 [RudderServo](INFO): Powering down
2024-10-10T22:12:58.156Z,1728598378.156 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-10-10T22:12:58.156Z,1728598378.156 [ThrusterHE](INFO): Powering down
2024-10-10T22:12:58.157Z,1728598378.157 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-10-10T22:12:58.157Z,1728598378.157 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-10-10T22:12:58.157Z,1728598378.157 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-10-10T22:12:58.157Z,1728598378.157 [CBIT](DEBUG): Powering off loads.
2024-10-10T22:12:58.168Z,1728598378.168 [CBIT](DEBUG): Disabling WDT.
2024-10-10T22:12:58.180Z,1728598378.180 [CBIT](DEBUG): Opening all GF detection circuits.
2024-10-10T22:12:58.181Z,1728598378.181 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-10-10T2