2023-09-27T18:20:50.048Z,1695838850.048 [Supervisor](DEBUG): Initializing supervisor.
2023-09-27T18:20:50.052Z,1695838850.052 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-27T18:20:50.053Z,1695838850.053 [SyncHandler](INFO): Protected caller Thread ID is 9716
2023-09-27T18:20:50.053Z,1695838850.053 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-27T18:20:50.054Z,1695838850.054 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-27T18:20:50.054Z,1695838850.054 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9717
2023-09-27T18:20:50.058Z,1695838850.058 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-27T18:20:50.077Z,1695838850.077 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-27T18:20:50.078Z,1695838850.078 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-27T18:20:50.078Z,1695838850.078 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9718
2023-09-27T18:20:50.082Z,1695838850.082 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-27T18:20:50.083Z,1695838850.083 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-27T18:20:50.084Z,1695838850.084 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9719
2023-09-27T18:20:50.086Z,1695838850.086 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-27T18:20:50.087Z,1695838850.087 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-27T18:20:50.087Z,1695838850.087 [logger ThreadHandler](INFO): Protected caller Thread ID is 9720
2023-09-27T18:20:50.091Z,1695838850.091 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-27T18:20:50.091Z,1695838850.091 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-27T18:20:50.093Z,1695838850.093 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-27T18:20:50.418Z,1695838850.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-27T18:20:50.418Z,1695838850.418 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-27T18:20:50.499Z,1695838850.499 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-27T18:20:50.718Z,1695838850.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-27T18:20:50.718Z,1695838850.718 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-27T18:20:51.046Z,1695838851.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-27T18:20:51.047Z,1695838851.047 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-27T18:20:51.387Z,1695838851.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-27T18:20:51.387Z,1695838851.387 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-27T18:20:51.484Z,1695838851.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-27T18:20:51.485Z,1695838851.485 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-27T18:20:51.876Z,1695838851.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-27T18:20:51.876Z,1695838851.876 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-27T18:20:52.085Z,1695838852.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-27T18:20:52.086Z,1695838852.086 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-27T18:20:52.166Z,1695838852.166 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-27T18:20:52.393Z,1695838852.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-27T18:20:52.393Z,1695838852.393 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-27T18:20:52.609Z,1695838852.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-27T18:20:52.610Z,1695838852.610 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-27T18:20:53.114Z,1695838853.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-27T18:20:53.114Z,1695838853.114 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-27T18:20:53.303Z,1695838853.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-27T18:20:53.303Z,1695838853.303 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-27T18:20:53.584Z,1695838853.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-27T18:20:53.584Z,1695838853.584 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-27T18:20:54.481Z,1695838854.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-27T18:20:54.482Z,1695838854.482 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-27T18:20:55.003Z,1695838855.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-27T18:20:55.005Z,1695838855.005 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2023-09-27T18:20:55.006Z,1695838855.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2023-09-27T18:20:55.115Z,1695838855.115 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2023-09-27T18:20:55.234Z,1695838855.234 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2023-09-27T18:20:55.322Z,1695838855.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2023-09-27T18:20:55.434Z,1695838855.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2023-09-27T18:20:55.534Z,1695838855.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2023-09-27T18:20:55.796Z,1695838855.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-27T18:20:55.797Z,1695838855.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2023-09-27T18:20:55.909Z,1695838855.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2023-09-27T18:20:56.004Z,1695838856.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2023-09-27T18:20:56.109Z,1695838856.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2023-09-27T18:20:56.233Z,1695838856.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2023-09-27T18:20:56.320Z,1695838856.320 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2023-09-27T18:20:56.320Z,1695838856.320 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-27T18:20:56.329Z,1695838856.329 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-27T18:20:56.425Z,1695838856.425 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-27T18:20:56.426Z,1695838856.426 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-27T18:20:56.475Z,1695838856.475 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-27T18:20:56.534Z,1695838856.534 [VerticalControl] Loaded
2023-09-27T18:20:56.534Z,1695838856.534 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-27T18:20:56.537Z,1695838856.537 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-27T18:20:56.578Z,1695838856.578 [HorizontalControl] Loaded
2023-09-27T18:20:56.578Z,1695838856.578 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-27T18:20:56.580Z,1695838856.580 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-27T18:20:56.583Z,1695838856.583 [SpeedControl] Loaded
2023-09-27T18:20:56.584Z,1695838856.584 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-27T18:20:56.586Z,1695838856.586 [LoopControl](DEBUG): Construct LoopControl.
2023-09-27T18:20:56.587Z,1695838856.587 [LoopControl] Loaded
2023-09-27T18:20:56.587Z,1695838856.587 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-27T18:20:56.587Z,1695838856.587 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-27T18:20:56.588Z,1695838856.588 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-27T18:20:56.641Z,1695838856.641 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-27T18:20:56.642Z,1695838856.642 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-27T18:20:57.060Z,1695838857.060 [AHRS_M2] Loaded
2023-09-27T18:20:57.061Z,1695838857.061 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-27T18:20:57.096Z,1695838857.096 [BackseatComponent] Loaded
2023-09-27T18:20:57.097Z,1695838857.097 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-27T18:20:57.098Z,1695838857.098 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408814E0
2023-09-27T18:20:57.098Z,1695838857.098 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9803
2023-09-27T18:20:57.101Z,1695838857.101 [LcmUniversalReporter] Loaded
2023-09-27T18:20:57.101Z,1695838857.101 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-27T18:20:57.908Z,1695838857.908 [BPC1] Loaded
2023-09-27T18:20:57.908Z,1695838857.908 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-27T18:20:57.983Z,1695838857.983 [DataOverHttps] Loaded
2023-09-27T18:20:57.983Z,1695838857.983 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-27T18:20:57.984Z,1695838857.984 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2023-09-27T18:20:57.984Z,1695838857.984 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9804
2023-09-27T18:20:58.007Z,1695838858.007 [Depth_Keller] Loaded
2023-09-27T18:20:58.007Z,1695838858.007 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-27T18:20:58.070Z,1695838858.070 [NAL9602] Loaded
2023-09-27T18:20:58.070Z,1695838858.070 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-27T18:20:58.101Z,1695838858.101 [Onboard] Loaded
2023-09-27T18:20:58.101Z,1695838858.101 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-27T18:20:58.102Z,1695838858.102 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2023-09-27T18:20:58.103Z,1695838858.103 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9805
2023-09-27T18:20:58.116Z,1695838858.116 [Power24vConverter] Loaded
2023-09-27T18:20:58.116Z,1695838858.116 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-27T18:20:58.129Z,1695838858.129 [Radio_Surface] Loaded
2023-09-27T18:20:58.129Z,1695838858.129 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-27T18:20:58.130Z,1695838858.130 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409114E0
2023-09-27T18:20:58.130Z,1695838858.130 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9806
2023-09-27T18:20:58.222Z,1695838858.222 [DAT] Loaded
2023-09-27T18:20:58.222Z,1695838858.222 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-09-27T18:20:58.223Z,1695838858.223 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409414E0
2023-09-27T18:20:58.224Z,1695838858.224 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9807
2023-09-27T18:20:58.224Z,1695838858.224 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-27T18:20:58.225Z,1695838858.225 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-27T18:20:58.235Z,1695838858.235 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-27T18:20:58.235Z,1695838858.235 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-27T18:20:58.261Z,1695838858.261 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-27T18:20:58.262Z,1695838858.262 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-27T18:20:58.391Z,1695838858.391 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-27T18:20:58.400Z,1695838858.400 [SBIT] Loaded
2023-09-27T18:20:58.401Z,1695838858.401 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-27T18:20:58.403Z,1695838858.403 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-27T18:20:58.416Z,1695838858.416 [IBIT] Loaded
2023-09-27T18:20:58.416Z,1695838858.416 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-27T18:20:58.422Z,1695838858.422 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-27T18:20:58.525Z,1695838858.525 [CBIT] Loaded
2023-09-27T18:20:58.525Z,1695838858.525 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-27T18:20:58.525Z,1695838858.525 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-27T18:20:58.526Z,1695838858.526 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-27T18:20:58.826Z,1695838858.826 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-27T18:20:58.827Z,1695838858.827 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-27T18:20:58.876Z,1695838858.876 [DepthRateCalculator] Loaded
2023-09-27T18:20:58.876Z,1695838858.876 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-27T18:20:58.881Z,1695838858.881 [PitchRateCalculator] Loaded
2023-09-27T18:20:58.881Z,1695838858.881 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-27T18:20:58.891Z,1695838858.891 [SpeedCalculator] Loaded
2023-09-27T18:20:58.892Z,1695838858.892 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-27T18:20:58.896Z,1695838858.896 [YawRateCalculator] Loaded
2023-09-27T18:20:58.896Z,1695838858.896 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-27T18:20:58.915Z,1695838858.915 [ElevatorOffsetCalculator] Loaded
2023-09-27T18:20:58.915Z,1695838858.915 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-27T18:20:58.915Z,1695838858.915 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-27T18:20:58.916Z,1695838858.916 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-27T18:20:59.056Z,1695838859.056 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-27T18:20:59.056Z,1695838859.056 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-27T18:20:59.166Z,1695838859.166 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-09-27T18:20:59.166Z,1695838859.166 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-09-27T18:20:59.209Z,1695838859.209 [DeadReckonUsingSpeedCalculator] Loaded
2023-09-27T18:20:59.210Z,1695838859.210 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-09-27T18:20:59.251Z,1695838859.251 [DeadReckonWithRespectToSeafloor] Loaded
2023-09-27T18:20:59.251Z,1695838859.251 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2023-09-27T18:20:59.266Z,1695838859.266 [NavChart] Loaded
2023-09-27T18:20:59.267Z,1695838859.267 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-27T18:20:59.272Z,1695838859.272 [UniversalFixResidualReporter] Loaded
2023-09-27T18:20:59.273Z,1695838859.273 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-27T18:20:59.273Z,1695838859.273 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-27T18:20:59.274Z,1695838859.274 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-27T18:20:59.403Z,1695838859.403 [BuoyancyServo] Loaded
2023-09-27T18:20:59.403Z,1695838859.403 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-27T18:20:59.424Z,1695838859.424 [ElevatorServo] Loaded
2023-09-27T18:20:59.424Z,1695838859.424 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-27T18:20:59.444Z,1695838859.444 [MassServo] Loaded
2023-09-27T18:20:59.444Z,1695838859.444 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-27T18:20:59.464Z,1695838859.464 [RudderServo] Loaded
2023-09-27T18:20:59.465Z,1695838859.465 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-27T18:20:59.480Z,1695838859.480 [ThrusterHE] Loaded
2023-09-27T18:20:59.480Z,1695838859.480 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-27T18:20:59.480Z,1695838859.480 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-27T18:20:59.481Z,1695838859.481 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-27T18:20:59.663Z,1695838859.663 [CTD_Seabird] Loaded
2023-09-27T18:20:59.664Z,1695838859.664 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-27T18:20:59.665Z,1695838859.665 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9B4E0
2023-09-27T18:20:59.665Z,1695838859.665 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9808
2023-09-27T18:20:59.699Z,1695838859.699 [ESPComponent] Loaded
2023-09-27T18:20:59.699Z,1695838859.699 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-09-27T18:20:59.719Z,1695838859.719 [PAR_Licor] Loaded
2023-09-27T18:20:59.719Z,1695838859.719 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-27T18:20:59.760Z,1695838859.760 [WetLabsBB2FL] Loaded
2023-09-27T18:20:59.760Z,1695838859.760 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-27T18:20:59.761Z,1695838859.761 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-09-27T18:20:59.762Z,1695838859.762 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9809
2023-09-27T18:20:59.762Z,1695838859.762 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-27T18:20:59.769Z,1695838859.769 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-27T18:20:59.771Z,1695838859.771 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-27T18:20:59.783Z,1695838859.783 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-27T18:20:59.784Z,1695838859.784 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFB4E0
2023-09-27T18:20:59.784Z,1695838859.784 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9810
2023-09-27T18:20:59.788Z,1695838859.788 [Supervisor](INFO): Main Thread ID is 9715
2023-09-27T18:20:59.789Z,1695838859.789 [Supervisor](DEBUG): Running supervisor.
2023-09-27T18:20:59.789Z,1695838859.789 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9811
2023-09-27T18:20:59.790Z,1695838859.790 [CommandExec](INFO): Initializing the command executive.
2023-09-27T18:20:59.791Z,1695838859.791 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9812
2023-09-27T18:20:59.793Z,1695838859.793 [controlThread ThreadHandler](INFO): Handler Thread ID is 9813
2023-09-27T18:20:59.794Z,1695838859.794 [controlThread](DEBUG): Initializing ControlThread
2023-09-27T18:20:59.795Z,1695838859.795 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-27T18:20:59.797Z,1695838859.797 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-27T18:20:59.797Z,1695838859.797 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-27T18:20:59.798Z,1695838859.798 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-27T18:20:59.801Z,1695838859.801 [SBIT](INFO): Initialize SBIT Component.
2023-09-27T18:20:59.801Z,1695838859.801 [SBIT](IMPORTANT): git: 2023-09-18-3-g7ee55e0de
2023-09-27T18:20:59.801Z,1695838859.801 [SBIT](INFO): git hash: 7ee55e0ded82548f5c252ba0d9a41930617440e4
2023-09-27T18:20:59.802Z,1695838859.802 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-27T18:20:59.803Z,1695838859.803 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-09-27T18:20:59.804Z,1695838859.804 [SBIT](INFO): Beginning SBIT in 60.000000 seconds.
2023-09-27T18:20:59.805Z,1695838859.805 [IBIT](INFO): Initialize IBIT Component.
2023-09-27T18:20:59.805Z,1695838859.805 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-27T18:20:59.806Z,1695838859.806 [logger ThreadHandler](INFO): Handler Thread ID is 9814
2023-09-27T18:20:59.816Z,1695838859.816 [CBIT](DEBUG): Initialized mux pins.
2023-09-27T18:20:59.816Z,1695838859.816 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-27T18:20:59.825Z,1695838859.825 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9815
2023-09-27T18:20:59.837Z,1695838859.837 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9816
2023-09-27T18:20:59.837Z,1695838859.837 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-27T18:20:59.840Z,1695838859.840 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-27T18:20:59.841Z,1695838859.841 [CBIT](DEBUG): Initializing heartbeat.
2023-09-27T18:20:59.849Z,1695838859.849 [Onboard ThreadHandler](INFO): Handler Thread ID is 9817
2023-09-27T18:20:59.866Z,1695838859.866 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9818
2023-09-27T18:20:59.886Z,1695838859.886 [DAT ThreadHandler](INFO): Handler Thread ID is 9819
2023-09-27T18:20:59.887Z,1695838859.887 [DAT](INFO): Powering up
2023-09-27T18:20:59.887Z,1695838859.887 [DAT](DEBUG): Initializing DAT.
2023-09-27T18:20:59.889Z,1695838859.889 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9821
2023-09-27T18:20:59.890Z,1695838859.890 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-27T18:20:59.894Z,1695838859.894 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9823
2023-09-27T18:20:59.897Z,1695838859.897 [WetLabsBB2FL](INFO): Powering up
2023-09-27T18:20:59.898Z,1695838859.898 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9825
2023-09-27T18:20:59.901Z,1695838859.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2023-09-27T18:20:59.901Z,1695838859.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2023-09-27T18:20:59.901Z,1695838859.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2023-09-27T18:20:59.901Z,1695838859.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2023-09-27T18:20:59.901Z,1695838859.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-09-27T18:20:59.902Z,1695838859.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-09-27T18:20:59.902Z,1695838859.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-09-27T18:20:59.902Z,1695838859.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-09-27T18:20:59.902Z,1695838859.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2023-09-27T18:20:59.902Z,1695838859.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2023-09-27T18:20:59.902Z,1695838859.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2023-09-27T18:20:59.903Z,1695838859.903 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2023-09-27T18:20:59.912Z,1695838859.912 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-27T18:20:59.912Z,1695838859.912 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-27T18:20:59.952Z,1695838859.952 [CBIT](DEBUG): Backplane powered.
2023-09-27T18:20:59.952Z,1695838859.952 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-27T18:20:59.953Z,1695838859.953 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-27T18:20:59.953Z,1695838859.953 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-27T18:20:59.953Z,1695838859.953 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-27T18:20:59.954Z,1695838859.954 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-27T18:20:59.955Z,1695838859.955 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-27T18:20:59.955Z,1695838859.955 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-27T18:20:59.956Z,1695838859.956 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-27T18:20:59.961Z,1695838859.961 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-27T18:20:59.978Z,1695838859.978 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-27T18:21:00.012Z,1695838860.012 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-09-27T18:21:00.025Z,1695838860.025 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-27T18:21:00.081Z,1695838860.081 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-27T18:21:00.083Z,1695838860.083 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-27T18:21:00.085Z,1695838860.085 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-27T18:21:00.108Z,1695838860.108 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-27T18:21:00.111Z,1695838860.111 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-27T18:21:00.136Z,1695838860.136 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-27T18:21:00.139Z,1695838860.139 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-09-27T18:21:00.159Z,1695838860.159 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-09-27T18:21:00.177Z,1695838860.177 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-27T18:21:00.230Z,1695838860.230 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2023-09-27T18:21:00.230Z,1695838860.230 [Power24vConverter](INFO): Powering up.
2023-09-27T18:21:00.238Z,1695838860.238 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-09-27T18:21:00.263Z,1695838860.263 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-27T18:21:00.268Z,1695838860.268 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-27T18:21:00.269Z,1695838860.269 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-27T18:21:00.272Z,1695838860.272 [Radio_Surface](INFO): Powering up
2023-09-27T18:21:00.280Z,1695838860.280 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-27T18:21:00.281Z,1695838860.281 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-27T18:21:00.288Z,1695838860.288 [MassServo](DEBUG): Initializing MassServo.
2023-09-27T18:21:00.289Z,1695838860.289 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-27T18:21:00.297Z,1695838860.297 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-27T18:21:00.298Z,1695838860.298 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-27T18:21:00.304Z,1695838860.304 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-27T18:21:02.004Z,1695838862.004 [WetLabsBB2FL](INFO): Powering down
2023-09-27T18:21:03.394Z,1695838863.394 [AHRS_M2](DEBUG): boresightMatrix m[ decimal 0 0 2 2 f0.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f0.0 ]m set drop

2023-09-27T18:21:03.797Z,1695838863.797 [AHRS_M2](DEBUG): chan0Format 2 set drop

2023-09-27T18:21:04.201Z,1695838864.201 [AHRS_M2](DEBUG): chan0Trigger 5 set drop

2023-09-27T18:21:04.605Z,1695838864.605 [AHRS_M2](DEBUG): chan0Enables array[ 0 15 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 ]array set drop

2023-09-27T18:21:04.605Z,1695838864.605 [AHRS_M2](DEBUG): chan0EnableBit pitch dvid@ set drop

2023-09-27T18:21:05.009Z,1695838865.009 [AHRS_M2](DEBUG): chan0EnableBit roll dvid@ set drop

2023-09-27T18:21:05.415Z,1695838865.415 [AHRS_M2](DEBUG): chan0EnableBit yaw dvid@ set drop

2023-09-27T18:21:05.817Z,1695838865.817 [AHRS_M2](DEBUG): chan0EnableBit magp dvid@ set drop

2023-09-27T18:21:06.221Z,1695838866.221 [AHRS_M2](DEBUG): chan0EnableBit accelp dvid@ set drop

2023-09-27T18:21:06.626Z,1695838866.626 [AHRS_M2](DEBUG): chan0EnableBit gyrop dvid@ set drop

2023-09-27T18:21:07.029Z,1695838867.029 [AHRS_M2](DEBUG): chan0EnableBit yawErrEst dvid@ set drop

2023-09-27T18:21:07.434Z,1695838867.434 [AHRS_M2](DEBUG): chan0EnableBit temperature dvid@ set drop

2023-09-27T18:21:07.837Z,1695838867.837 [AHRS_M2](DEBUG): chan0EnableBit magBufferActiveIndex dvid@ set drop

2023-09-27T18:21:08.241Z,1695838868.241 [AHRS_M2](DEBUG): chan0TriggerDivisor 40 set drop

2023-09-27T18:21:09.717Z,1695838869.717 [DAT](INFO): DAT read: 

2023-09-27T18:21:09.718Z,1695838869.718 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-09-27T18:21:10.227Z,1695838870.227 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004692
2023-09-27T18:21:10.977Z,1695838870.977 [DAT](INFO): DAT read: MF Frequency Band

2023-09-27T18:21:10.978Z,1695838870.978 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.10.9

2023-09-27T18:21:10.979Z,1695838870.979 [DAT](INFO): DAT read: Sep 27 2023 00:23:35 

2023-09-27T18:21:11.733Z,1695838871.733 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-09-27T18:21:11.735Z,1695838871.735 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-09-27T18:21:11.735Z,1695838871.735 [DAT](INFO): commRate: 800
2023-09-27T18:21:13.804Z,1695838873.804 [DAT](INFO): entering command mode
2023-09-27T18:21:14.005Z,1695838874.005 [DAT](INFO): DAT read: 

2023-09-27T18:21:14.005Z,1695838874.005 [DAT](INFO): DAT read: user:1>

2023-09-27T18:21:14.006Z,1695838874.006 [DAT](INFO): setting verbose to 3
2023-09-27T18:21:14.257Z,1695838874.257 [DAT](INFO): DAT read: user:1>

2023-09-27T18:21:14.258Z,1695838874.258 [DAT](INFO): DAT read: Verbose         | 3           

2023-09-27T18:21:14.258Z,1695838874.258 [DAT](INFO): set verbose to 3
2023-09-27T18:21:14.258Z,1695838874.258 [DAT](INFO): setting DatVerbose to 27440
2023-09-27T18:21:14.509Z,1695838874.509 [DAT](INFO): DAT read: user:2>

2023-09-27T18:21:14.510Z,1695838874.510 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-09-27T18:21:14.510Z,1695838874.510 [DAT](INFO): set DatVerbose to 27440
2023-09-27T18:21:14.511Z,1695838874.511 [DAT](INFO): setting transmit power to 8
2023-09-27T18:21:14.761Z,1695838874.761 [DAT](INFO): DAT read: user:3>

2023-09-27T18:21:14.762Z,1695838874.762 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-09-27T18:21:14.762Z,1695838874.762 [DAT](INFO): set transmit power to 8
2023-09-27T18:21:14.763Z,1695838874.763 [DAT](INFO): setting local address to 1
2023-09-27T18:21:15.013Z,1695838875.013 [DAT](INFO): DAT read: user:4>

2023-09-27T18:21:15.014Z,1695838875.014 [DAT](INFO): DAT read: LocalAddr       | 1           

2023-09-27T18:21:15.014Z,1695838875.014 [DAT](INFO): set local address to 1
2023-09-27T18:21:15.015Z,1695838875.015 [DAT](INFO): Setting time to: 18:21:15 And date to:9/27/2023
2023-09-27T18:21:15.265Z,1695838875.265 [DAT](INFO): DAT read: user:5>

2023-09-27T18:21:15.266Z,1695838875.266 [DAT](INFO): DAT read: Wed Sep 27, 2023  18:21:15  

2023-09-27T18:21:15.266Z,1695838875.266 [DAT](INFO): Local DAT time set to Wed Sep 27, 2023  18:21:15  

2023-09-27T18:21:15.266Z,1695838875.266 [DAT](INFO): DAT read: Ok

2023-09-27T18:21:15.267Z,1695838875.267 [DAT](INFO): unknown deviceResponse_: Ok

2023-09-27T18:21:18.341Z,1695838878.341 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-09-27T18:21:18.341Z,1695838878.341 [AHRS_M2] Data Fault, FailCount= 1
2023-09-27T18:21:18.341Z,1695838878.341 [AHRS_M2](ERROR): Data Fault
2023-09-27T18:21:18.415Z,1695838878.415 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-09-27T18:21:18.415Z,1695838878.415 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2023-09-27T18:21:18.802Z,1695838878.802 [CommandExec](FAULT): Scheduling is paused
2023-09-27T18:21:18.803Z,1695838878.803 [CBIT](INFO): Critical error at 20230927T182118
2023-09-27T18:21:18.803Z,1695838878.803 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-09-27T18:21:28.449Z,1695838888.449 [NAL9602](INFO): Powering up NAL9602
2023-09-27T18:21:39.358Z,1695838899.358 [NAL9602](INFO): NAL9602 initialized
2023-09-27T18:21:49.126Z,1695838909.126 [CommandExec](IMPORTANT): got command show variable AHRS_M2
2023-09-27T18:21:49.185Z,1695838909.185 [CommandExec](IMPORTANT): AHRS_M2.loadControl (none)
2023-09-27T18:21:49.186Z,1695838909.186 [CommandExec](IMPORTANT): AHRS_M2.uart (none)
2023-09-27T18:21:49.186Z,1695838909.186 [CommandExec](IMPORTANT): AHRS_M2.baud (bit_per_second)
2023-09-27T18:21:49.206Z,1695838909.206 [CommandExec](IMPORTANT): AHRS_M2.loadAtStartup (bool)
2023-09-27T18:21:49.206Z,1695838909.206 [CommandExec](IMPORTANT): AHRS_M2.simulateHardware (bool)
2023-09-27T18:21:49.207Z,1695838909.207 [CommandExec](IMPORTANT): AHRS_M2.boresightMatrix (none)
2023-09-27T18:21:49.207Z,1695838909.207 [CommandExec](IMPORTANT): AHRS_M2.magDeviation (degree)
2023-09-27T18:21:49.207Z,1695838909.207 [CommandExec](IMPORTANT): AHRS_M2.minNumPointsCal (count)
2023-09-27T18:21:49.208Z,1695838909.208 [CommandExec](IMPORTANT): AHRS_M2.power (watt)
2023-09-27T18:21:49.208Z,1695838909.208 [CommandExec](IMPORTANT): AHRS_M2.readAccelerations (bool)
2023-09-27T18:21:49.208Z,1695838909.208 [CommandExec](IMPORTANT): AHRS_M2.readAngularVelocities (bool)
2023-09-27T18:21:49.209Z,1695838909.209 [CommandExec](IMPORTANT): AHRS_M2.readMagnetics (bool)
2023-09-27T18:21:49.209Z,1695838909.209 [CommandExec](IMPORTANT): AHRS_M2.verbosity (count)
2023-09-27T18:21:49.326Z,1695838909.326 [CommandExec](IMPORTANT): AHRS_M2.enableBroadcast (bool)
2023-09-27T18:21:49.327Z,1695838909.327 [CommandExec](IMPORTANT): AHRS_M2.component_voltage (volt)
2023-09-27T18:21:49.327Z,1695838909.327 [CommandExec](IMPORTANT): AHRS_M2.component_avgVoltage (volt)
2023-09-27T18:21:49.327Z,1695838909.327 [CommandExec](IMPORTANT): AHRS_M2.component_current (milliampere)
2023-09-27T18:21:49.328Z,1695838909.328 [CommandExec](IMPORTANT): AHRS_M2.component_avgCurrent (milliampere)
2023-09-27T18:21:49.347Z,1695838909.347 [CommandExec](IMPORTANT): AHRS_M2.platform_magnetic_orientation (radian)
2023-09-27T18:21:49.347Z,1695838909.347 [CommandExec](IMPORTANT): AHRS_M2.platform_pitch_angle (radian)
2023-09-27T18:21:49.347Z,1695838909.347 [CommandExec](IMPORTANT): AHRS_M2.platform_roll_angle (radian)
2023-09-27T18:21:49.348Z,1695838909.348 [CommandExec](IMPORTANT): AHRS_M2.platform_orientation (radian)
2023-09-27T18:21:49.364Z,1695838909.364 [CommandExec](IMPORTANT): AHRS_M2.platform_orientation_matrix (none)
2023-09-27T18:21:49.365Z,1695838909.365 [CommandExec](IMPORTANT): AHRS_M2.calibration_state (count)
2023-09-27T18:21:49.365Z,1695838909.365 [CommandExec](IMPORTANT): AHRS_M2.orientation_error (radian)
2023-09-27T18:21:49.366Z,1695838909.366 [CommandExec](IMPORTANT): AHRS_M2.orientation (radian)
2023-09-27T18:21:49.366Z,1695838909.366 [CommandExec](IMPORTANT): AHRS_M2.temperature (celsius)
2023-09-27T18:21:49.366Z,1695838909.366 [CommandExec](IMPORTANT): AHRS_M2.acceleration_along_xyz (meter_per_second_squared)
2023-09-27T18:21:49.367Z,1695838909.367 [CommandExec](IMPORTANT): AHRS_M2.angular_velocity_along_xyz (radian_per_second)
2023-09-27T18:21:49.367Z,1695838909.367 [CommandExec](IMPORTANT): AHRS_M2.magnetic_field_along_xyz (milligauss)
2023-09-27T18:21:49.367Z,1695838909.367 [CommandExec](IMPORTANT): AHRS_M2.number_of_calbration_points (count)
2023-09-27T18:22:00.382Z,1695838920.382 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-27T18:22:00.387Z,1695838920.387 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-27T18:22:04.216Z,1695838924.216 [CommandExec](IMPORTANT): got command get AHRS_M2.loadControl
2023-09-27T18:22:04.217Z,1695838924.217 [CommandExec](IMPORTANT): AHRS_M2.loadControl /dev/loadC5
2023-09-27T18:22:11.473Z,1695838931.473 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.017423
CHAN A1 (24V): 0.051713
CHAN A2 (12V): 0.000372
CHAN A3 (5V): -0.001313
CHAN B0 (3.3V): 0.001247
CHAN B1 (3.15aV): -0.000414
CHAN B2 (3.15bV): 0.000151
CHAN B3 (GND): -0.000400
OPEN: 0.006497
Full Scale: +/- 1 mA
2023-09-27T18:22:15.892Z,1695838935.892 [CommandExec](IMPORTANT): got command get AHRS_M2.uart
2023-09-27T18:22:15.892Z,1695838935.892 [CommandExec](IMPORTANT): AHRS_M2.uart /dev/ttyC5
2023-09-27T18:22:23.439Z,1695838943.439 [CommandExec](IMPORTANT): got command get AHRS_M2.baud
2023-09-27T18:22:23.439Z,1695838943.439 [CommandExec](IMPORTANT): AHRS_M2.baud 115200.000000 bps
2023-09-27T18:22:33.118Z,1695838953.118 [CommandExec](IMPORTANT): got command show variable AHRS_M2
2023-09-27T18:22:33.229Z,1695838953.229 [CommandExec](IMPORTANT): AHRS_M2.loadControl (none)
2023-09-27T18:22:33.230Z,1695838953.230 [CommandExec](IMPORTANT): AHRS_M2.uart (none)
2023-09-27T18:22:33.230Z,1695838953.230 [CommandExec](IMPORTANT): AHRS_M2.baud (bit_per_second)
2023-09-27T18:22:33.290Z,1695838953.290 [CommandExec](IMPORTANT): AHRS_M2.loadAtStartup (bool)
2023-09-27T18:22:33.291Z,1695838953.291 [CommandExec](IMPORTANT): AHRS_M2.simulateHardware (bool)
2023-09-27T18:22:33.291Z,1695838953.291 [CommandExec](IMPORTANT): AHRS_M2.boresightMatrix (none)
2023-09-27T18:22:33.291Z,1695838953.291 [CommandExec](IMPORTANT): AHRS_M2.magDeviation (degree)
2023-09-27T18:22:33.291Z,1695838953.291 [CommandExec](IMPORTANT): AHRS_M2.minNumPointsCal (count)
2023-09-27T18:22:33.296Z,1695838953.296 [CommandExec](IMPORTANT): AHRS_M2.power (watt)
2023-09-27T18:22:33.296Z,1695838953.296 [CommandExec](IMPORTANT): AHRS_M2.readAccelerations (bool)
2023-09-27T18:22:33.297Z,1695838953.297 [CommandExec](IMPORTANT): AHRS_M2.readAngularVelocities (bool)
2023-09-27T18:22:33.297Z,1695838953.297 [CommandExec](IMPORTANT): AHRS_M2.readMagnetics (bool)
2023-09-27T18:22:33.297Z,1695838953.297 [CommandExec](IMPORTANT): AHRS_M2.verbosity (count)
2023-09-27T18:22:33.421Z,1695838953.421 [CommandExec](IMPORTANT): AHRS_M2.enableBroadcast (bool)
2023-09-27T18:22:33.421Z,1695838953.421 [CommandExec](IMPORTANT): AHRS_M2.component_voltage (volt)
2023-09-27T18:22:33.421Z,1695838953.421 [CommandExec](IMPORTANT): AHRS_M2.component_avgVoltage (volt)
2023-09-27T18:22:33.422Z,1695838953.422 [CommandExec](IMPORTANT): AHRS_M2.component_current (milliampere)
2023-09-27T18:22:33.422Z,1695838953.422 [CommandExec](IMPORTANT): AHRS_M2.component_avgCurrent (milliampere)
2023-09-27T18:22:33.422Z,1695838953.422 [CommandExec](IMPORTANT): AHRS_M2.platform_magnetic_orientation (radian)
2023-09-27T18:22:33.423Z,1695838953.423 [CommandExec](IMPORTANT): AHRS_M2.platform_pitch_angle (radian)
2023-09-27T18:22:33.423Z,1695838953.423 [CommandExec](IMPORTANT): AHRS_M2.platform_roll_angle (radian)
2023-09-27T18:22:33.423Z,1695838953.423 [CommandExec](IMPORTANT): AHRS_M2.platform_orientation (radian)
2023-09-27T18:22:33.424Z,1695838953.424 [CommandExec](IMPORTANT): AHRS_M2.platform_orientation_matrix (none)
2023-09-27T18:22:33.429Z,1695838953.429 [CommandExec](IMPORTANT): AHRS_M2.calibration_state (count)
2023-09-27T18:22:33.429Z,1695838953.429 [CommandExec](IMPORTANT): AHRS_M2.orientation_error (radian)
2023-09-27T18:22:33.430Z,1695838953.430 [CommandExec](IMPORTANT): AHRS_M2.orientation (radian)
2023-09-27T18:22:33.430Z,1695838953.430 [CommandExec](IMPORTANT): AHRS_M2.temperature (celsius)
2023-09-27T18:22:33.430Z,1695838953.430 [CommandExec](IMPORTANT): AHRS_M2.acceleration_along_xyz (meter_per_second_squared)
2023-09-27T18:22:33.431Z,1695838953.431 [CommandExec](IMPORTANT): AHRS_M2.angular_velocity_along_xyz (radian_per_second)
2023-09-27T18:22:33.431Z,1695838953.431 [CommandExec](IMPORTANT): AHRS_M2.magnetic_field_along_xyz (milligauss)
2023-09-27T18:22:33.431Z,1695838953.431 [CommandExec](IMPORTANT): AHRS_M2.number_of_calbration_points (count)
2023-09-27T18:22:54.114Z,1695838974.114 [SBIT](IMPORTANT): SBIT PASSED
2023-09-27T18:22:54.114Z,1695838974.114 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-27T18:22:54.115Z,1695838974.115 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2023-09-27T18:22:54.115Z,1695838974.115 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool;

2023-09-27T18:22:54.115Z,1695838974.115 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-09-27T18:22:54.115Z,1695838974.115 [SBIT](IMPORTANT): PowerOnly.sampleTime1=1 second;

2023-09-27T18:22:54.115Z,1695838974.115 [SBIT](IMPORTANT): Rowe_600.loadAtStartup=0 bool;

2023-09-27T18:22:54.115Z,1695838974.115 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=159.679458 cubic_centimeter;

2023-09-27T18:22:54.116Z,1695838974.116 [SBIT](IMPORTANT): VerticalControl.massDefault=10.000082 millimeter;

2023-09-27T18:22:54.116Z,1695838974.116 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;

2023-09-27T18:22:54.521Z,1695838974.521 [MissionManager](IMPORTANT): Started mission Startup
2023-09-27T18:22:54.521Z,1695838974.521 [Startup] Running Loop=1
2023-09-27T18:22:54.522Z,1695838974.522 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-27T18:22:54.522Z,1695838974.522 [Startup:A.GoToSurface] Running Loop=1
2023-09-27T18:22:54.522Z,1695838974.522 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-27T18:22:54.522Z,1695838974.522 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-27T18:22:54.523Z,1695838974.523 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-27T18:22:54.523Z,1695838974.523 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-27T18:22:54.523Z,1695838974.523 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-27T18:22:54.524Z,1695838974.524 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-27T18:22:54.525Z,1695838974.525 [Startup:StartupSatComms] Running Loop=1
2023-09-27T18:22:54.525Z,1695838974.525 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-27T18:22:54.525Z,1695838974.525 [Startup:StartupSatComms:A] Running Loop=1
2023-09-27T18:22:54.918Z,1695838974.918 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-27T18:22:59.425Z,1695838979.425 [CommandExec](IMPORTANT): got command failComponent
2023-09-27T18:22:59.425Z,1695838979.425 [CommandExec](IMPORTANT): Failed components:
2023-09-27T18:22:59.425Z,1695838979.425 [CommandExec](IMPORTANT): AHRS_M2: Data Fault
2023-09-27T18:23:00.166Z,1695838980.166 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-09-27T18:23:00.166Z,1695838980.166 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-09-27T18:23:00.166Z,1695838980.166 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-27T18:23:00.167Z,1695838980.167 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-09-27T18:23:00.167Z,1695838980.167 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-09-27T18:23:00.167Z,1695838980.167 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-27T18:23:00.169Z,1695838980.169 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-09-27T18:23:00.169Z,1695838980.169 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2023-09-27T18:23:00.169Z,1695838980.169 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-09-27T18:23:00.182Z,1695838980.182 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-27T18:23:00.182Z,1695838980.182 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-27T18:23:00.182Z,1695838980.182 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-09-27T18:23:00.587Z,1695838980.587 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-27T18:23:00.587Z,1695838980.587 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-09-27T18:23:00.587Z,1695838980.587 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-27T18:23:00.588Z,1695838980.588 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-09-27T18:23:00.588Z,1695838980.588 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-09-27T18:23:00.588Z,1695838980.588 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2023-09-27T18:23:01.004Z,1695838981.004 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-27T18:23:54.706Z,1695839034.706 [Startup:StartupSatComms:A](INFO): Timed out from 2023-09-27T18:22:54.5Z
2023-09-27T18:23:54.706Z,1695839034.706 [Startup:StartupSatComms:A] Stopped
2023-09-27T18:23:54.706Z,1695839034.706 [Startup:StartupSatComms:B] Running Loop=1
2023-09-27T18:23:55.111Z,1695839035.111 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-27T18:24:01.992Z,1695839041.992 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230926T225118/Courier0031.lzma
2023-09-27T18:24:02.994Z,1695839042.994 [DataOverHttps](INFO): Moved sent file to Logs/20230926T225118/Courier0031.lzma.bak
2023-09-27T18:24:02.994Z,1695839042.994 [DataOverHttps](INFO): SBD MOMSN=18910870
2023-09-27T18:24:15.113Z,1695839055.113 [CommandExec](IMPORTANT): got command quit
2023-09-27T18:24:16.116Z,1695839056.116 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:16.116Z,1695839056.116 [CommandExec](INFO): Uninitializing the command executive.
2023-09-27T18:24:16.116Z,1695839056.116 [CommandExec](INFO): Uninitializing the command scheduler.
2023-09-27T18:24:16.117Z,1695839056.117 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.204Z,1695839056.204 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-09-27T18:24:16.204Z,1695839056.204 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-09-27T18:24:16.204Z,1695839056.204 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.205Z,1695839056.205 [NavChartDb](INFO): Join timeout helper Thread ID is 9838
2023-09-27T18:24:16.356Z,1695839056.356 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:16.357Z,1695839056.357 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.372Z,1695839056.372 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-09-27T18:24:16.372Z,1695839056.372 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.373Z,1695839056.373 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9839
2023-09-27T18:24:16.493Z,1695839056.493 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:16.493Z,1695839056.493 [WetLabsBB2FL](INFO): Powering down
2023-09-27T18:24:16.494Z,1695839056.494 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.515Z,1695839056.515 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-09-27T18:24:16.515Z,1695839056.515 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.515Z,1695839056.515 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9840
2023-09-27T18:24:16.697Z,1695839056.697 [CTD_Seabird](INFO): Powering down
2023-09-27T18:24:16.708Z,1695839056.708 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:16.708Z,1695839056.708 [CTD_Seabird](INFO): Powering down
2023-09-27T18:24:16.720Z,1695839056.720 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.728Z,1695839056.728 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-09-27T18:24:16.728Z,1695839056.728 [DAT ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.729Z,1695839056.729 [DAT](INFO): Join timeout helper Thread ID is 9841
2023-09-27T18:24:16.876Z,1695839056.876 [DAT](INFO): Powering down
2023-09-27T18:24:16.957Z,1695839056.957 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:16.957Z,1695839056.957 [DAT](INFO): Powering down
2023-09-27T18:24:16.958Z,1695839056.958 [DAT ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.976Z,1695839056.976 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-09-27T18:24:16.976Z,1695839056.976 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:16.977Z,1695839056.977 [Radio_Surface](INFO): Join timeout helper Thread ID is 9842
2023-09-27T18:24:17.036Z,1695839057.036 [Radio_Surface](INFO): Powering down
2023-09-27T18:24:17.037Z,1695839057.037 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:17.038Z,1695839057.038 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:17.057Z,1695839057.057 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-09-27T18:24:17.057Z,1695839057.057 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:17.058Z,1695839057.058 [Onboard](INFO): Join timeout helper Thread ID is 9843
2023-09-27T18:24:20.008Z,1695839060.008 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:20.008Z,1695839060.008 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.013Z,1695839060.013 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-09-27T18:24:20.013Z,1695839060.013 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.013Z,1695839060.013 [DataOverHttps](INFO): Join timeout helper Thread ID is 9844
2023-09-27T18:24:20.576Z,1695839060.576 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:20.579Z,1695839060.579 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.581Z,1695839060.581 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-09-27T18:24:20.581Z,1695839060.581 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.581Z,1695839060.581 [BackseatComponent](INFO): Join timeout helper Thread ID is 9845
2023-09-27T18:24:20.582Z,1695839060.582 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:20.582Z,1695839060.582 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.583Z,1695839060.583 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-09-27T18:24:20.583Z,1695839060.583 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.584Z,1695839060.584 [logger](INFO): Join timeout helper Thread ID is 9846
2023-09-27T18:24:20.612Z,1695839060.612 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:20.613Z,1695839060.613 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.625Z,1695839060.625 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-09-27T18:24:20.625Z,1695839060.625 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.625Z,1695839060.625 [CommandLine](INFO): Join timeout helper Thread ID is 9847
2023-09-27T18:24:20.676Z,1695839060.676 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:20.677Z,1695839060.677 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.685Z,1695839060.685 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-09-27T18:24:20.685Z,1695839060.685 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.685Z,1695839060.685 [CommandExec](INFO): Join timeout helper Thread ID is 9848
2023-09-27T18:24:20.686Z,1695839060.686 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-09-27T18:24:20.687Z,1695839060.687 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:20.687Z,1695839060.687 [controlThread](INFO): Join timeout helper Thread ID is 9849
2023-09-27T18:24:20.952Z,1695839060.952 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-27T18:24:20.952Z,1695839060.952 [controlThread](DEBUG): Uninitializing ControlThread
2023-09-27T18:24:20.954Z,1695839060.954 [NAL9602](INFO): Powering down
2023-09-27T18:24:20.957Z,1695839060.957 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-09-27T18:24:20.957Z,1695839060.957 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-09-27T18:24:20.958Z,1695839060.958 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-09-27T18:24:20.958Z,1695839060.958 [MissionManager](INFO): Uninitializing Mission Default
2023-09-27T18:24:20.961Z,1695839060.961 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-27T18:24:20.961Z,1695839060.961 [Startup] Stopped
2023-09-27T18:24:20.961Z,1695839060.961 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-27T18:24:20.961Z,1695839060.961 [Startup:A.GoToSurface] Stopped
2023-09-27T18:24:20.961Z,1695839060.961 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-27T18:24:20.961Z,1695839060.961 [Startup:StartupSatComms] Stopped
2023-09-27T18:24:20.961Z,1695839060.961 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-27T18:24:20.961Z,1695839060.961 [Startup:StartupSatComms:B] Stopped
2023-09-27T18:24:20.963Z,1695839060.963 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-09-27T18:24:20.963Z,1695839060.963 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-09-27T18:24:20.963Z,1695839060.963 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-09-27T18:24:20.963Z,1695839060.963 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-09-27T18:24:20.964Z,1695839060.964 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-09-27T18:24:20.964Z,1695839060.964 [BuoyancyServo](INFO): Powering down
2023-09-27T18:24:20.976Z,1695839060.976 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-09-27T18:24:20.976Z,1695839060.976 [ElevatorServo](INFO): Powering down
2023-09-27T18:24:20.977Z,1695839060.977 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-09-27T18:24:20.977Z,1695839060.977 [MassServo](INFO): Powering down
2023-09-27T18:24:20.978Z,1695839060.978 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-09-27T18:24:20.978Z,1695839060.978 [RudderServo](INFO): Powering down
2023-09-27T18:24:20.979Z,1695839060.979 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-27T18:24:20.979Z,1695839060.979 [ThrusterHE](INFO): Powering down
2023-09-27T18:24:20.980Z,1695839060.980 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-09-27T18:24:20.980Z,1695839060.980 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-09-27T18:24:20.981Z,1695839060.981 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-09-27T18:24:20.981Z,1695839060.981 [CBIT](DEBUG): Powering off loads.
2023-09-27T18:24:20.992Z,1695839060.992 [CBIT](DEBUG): Disabling WDT.
2023-09-27T18:24:21.004Z,1695839061.004 [CBIT](DEBUG): Opening all GF detection circuits.
2023-09-27T18:24:21.005Z,1695839061.005 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:21.043Z,1695839061.043 [DAT ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:21.048Z,1695839061.048 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:21.050Z,1695839061.050 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:21.055Z,1695839061.055 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:21.098Z,1695839061.098 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:21.184Z,1695839061.184 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:21.189Z,1695839061.189 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:21.197Z,1695839061.197 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-27T18:24:21.243Z,1695839061.243 [logger ThreadHandler](INFO): Thread cancelled.