2023-10-02T21:54:39.081Z,1696283679.081 [Supervisor](DEBUG): Initializing supervisor.
2023-10-02T21:54:39.085Z,1696283679.085 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-10-02T21:54:39.086Z,1696283679.086 [SyncHandler](INFO): Protected caller Thread ID is 4690
2023-10-02T21:54:39.086Z,1696283679.086 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-10-02T21:54:39.087Z,1696283679.087 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-10-02T21:54:39.087Z,1696283679.087 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4691
2023-10-02T21:54:39.091Z,1696283679.091 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-10-02T21:54:39.108Z,1696283679.108 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-10-02T21:54:39.109Z,1696283679.109 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-10-02T21:54:39.110Z,1696283679.110 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4692
2023-10-02T21:54:39.114Z,1696283679.114 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-10-02T21:54:39.115Z,1696283679.115 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-10-02T21:54:39.115Z,1696283679.115 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4693
2023-10-02T21:54:39.117Z,1696283679.117 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-10-02T21:54:39.118Z,1696283679.118 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-10-02T21:54:39.118Z,1696283679.118 [logger ThreadHandler](INFO): Protected caller Thread ID is 4694
2023-10-02T21:54:39.122Z,1696283679.122 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-10-02T21:54:39.122Z,1696283679.122 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-10-02T21:54:39.124Z,1696283679.124 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-10-02T21:54:39.427Z,1696283679.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-10-02T21:54:39.427Z,1696283679.427 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-10-02T21:54:39.507Z,1696283679.507 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-10-02T21:54:39.612Z,1696283679.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-10-02T21:54:39.613Z,1696283679.613 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-10-02T21:54:39.737Z,1696283679.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-10-02T21:54:39.738Z,1696283679.738 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-10-02T21:54:40.063Z,1696283680.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-10-02T21:54:40.064Z,1696283680.064 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-10-02T21:54:40.159Z,1696283680.159 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-10-02T21:54:40.160Z,1696283680.160 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-10-02T21:54:40.531Z,1696283680.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-10-02T21:54:40.531Z,1696283680.531 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-10-02T21:54:40.731Z,1696283680.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-10-02T21:54:40.732Z,1696283680.732 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-10-02T21:54:40.811Z,1696283680.811 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-10-02T21:54:41.026Z,1696283681.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-10-02T21:54:41.027Z,1696283681.027 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-10-02T21:54:41.235Z,1696283681.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-10-02T21:54:41.236Z,1696283681.236 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-10-02T21:54:41.719Z,1696283681.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-10-02T21:54:41.720Z,1696283681.720 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-10-02T21:54:41.909Z,1696283681.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-10-02T21:54:41.909Z,1696283681.909 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-10-02T21:54:42.179Z,1696283682.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-10-02T21:54:42.180Z,1696283682.180 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-10-02T21:54:42.965Z,1696283682.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-10-02T21:54:42.966Z,1696283682.966 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-10-02T21:54:43.459Z,1696283683.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-10-02T21:54:43.461Z,1696283683.461 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2023-10-02T21:54:43.462Z,1696283683.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2023-10-02T21:54:43.557Z,1696283683.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2023-10-02T21:54:43.673Z,1696283683.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2023-10-02T21:54:43.756Z,1696283683.756 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2023-10-02T21:54:43.871Z,1696283683.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2023-10-02T21:54:43.983Z,1696283683.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2023-10-02T21:54:44.241Z,1696283684.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-10-02T21:54:44.241Z,1696283684.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2023-10-02T21:54:44.350Z,1696283684.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2023-10-02T21:54:44.452Z,1696283684.452 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2023-10-02T21:54:44.553Z,1696283684.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2023-10-02T21:54:44.681Z,1696283684.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2023-10-02T21:54:44.766Z,1696283684.766 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-10-02T21:54:44.773Z,1696283684.773 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-10-02T21:54:44.871Z,1696283684.871 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-10-02T21:54:44.872Z,1696283684.872 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-10-02T21:54:44.921Z,1696283684.921 [VerticalControl](DEBUG): Construct VerticalControl.
2023-10-02T21:54:44.979Z,1696283684.979 [VerticalControl] Loaded
2023-10-02T21:54:44.980Z,1696283684.980 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-10-02T21:54:44.982Z,1696283684.982 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-10-02T21:54:45.022Z,1696283685.022 [HorizontalControl] Loaded
2023-10-02T21:54:45.023Z,1696283685.023 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-10-02T21:54:45.025Z,1696283685.025 [SpeedControl](DEBUG): Construct SpeedControl.
2023-10-02T21:54:45.028Z,1696283685.028 [SpeedControl] Loaded
2023-10-02T21:54:45.028Z,1696283685.028 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-10-02T21:54:45.031Z,1696283685.031 [LoopControl](DEBUG): Construct LoopControl.
2023-10-02T21:54:45.031Z,1696283685.031 [LoopControl] Loaded
2023-10-02T21:54:45.032Z,1696283685.032 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-10-02T21:54:45.032Z,1696283685.032 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-10-02T21:54:45.032Z,1696283685.032 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-10-02T21:54:45.087Z,1696283685.087 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-10-02T21:54:45.087Z,1696283685.087 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-10-02T21:54:45.500Z,1696283685.500 [AHRS_M2] Loaded
2023-10-02T21:54:45.500Z,1696283685.500 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-10-02T21:54:45.535Z,1696283685.535 [BackseatComponent] Loaded
2023-10-02T21:54:45.535Z,1696283685.535 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-10-02T21:54:45.537Z,1696283685.537 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408814E0
2023-10-02T21:54:45.537Z,1696283685.537 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4776
2023-10-02T21:54:45.540Z,1696283685.540 [LcmUniversalReporter] Loaded
2023-10-02T21:54:45.540Z,1696283685.540 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-10-02T21:54:46.339Z,1696283686.339 [BPC1] Loaded
2023-10-02T21:54:46.340Z,1696283686.340 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-10-02T21:54:46.411Z,1696283686.411 [DataOverHttps] Loaded
2023-10-02T21:54:46.412Z,1696283686.412 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-10-02T21:54:46.413Z,1696283686.413 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2023-10-02T21:54:46.413Z,1696283686.413 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4777
2023-10-02T21:54:46.460Z,1696283686.460 [Depth_Keller] Loaded
2023-10-02T21:54:46.460Z,1696283686.460 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-10-02T21:54:46.465Z,1696283686.465 [DropWeight] Loaded
2023-10-02T21:54:46.465Z,1696283686.465 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-10-02T21:54:46.573Z,1696283686.573 [NAL9602] Loaded
2023-10-02T21:54:46.574Z,1696283686.574 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-10-02T21:54:46.603Z,1696283686.603 [Onboard] Loaded
2023-10-02T21:54:46.603Z,1696283686.603 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-10-02T21:54:46.604Z,1696283686.604 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2023-10-02T21:54:46.605Z,1696283686.605 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4779
2023-10-02T21:54:46.616Z,1696283686.616 [Power24vConverter] Loaded
2023-10-02T21:54:46.617Z,1696283686.617 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-10-02T21:54:46.630Z,1696283686.630 [Radio_Surface] Loaded
2023-10-02T21:54:46.630Z,1696283686.630 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-10-02T21:54:46.631Z,1696283686.631 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409114E0
2023-10-02T21:54:46.631Z,1696283686.631 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4780
2023-10-02T21:54:46.722Z,1696283686.722 [DAT] Loaded
2023-10-02T21:54:46.722Z,1696283686.722 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-10-02T21:54:46.723Z,1696283686.723 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409414E0
2023-10-02T21:54:46.724Z,1696283686.724 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4781
2023-10-02T21:54:46.749Z,1696283686.749 [Waterlinked] Loaded
2023-10-02T21:54:46.749Z,1696283686.749 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2023-10-02T21:54:46.749Z,1696283686.749 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-10-02T21:54:46.750Z,1696283686.750 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-10-02T21:54:46.760Z,1696283686.760 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-10-02T21:54:46.760Z,1696283686.760 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-10-02T21:54:46.787Z,1696283686.787 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-10-02T21:54:46.788Z,1696283686.788 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-10-02T21:54:46.922Z,1696283686.922 [SBIT](DEBUG): Construct Startup Built In Test.
2023-10-02T21:54:46.930Z,1696283686.930 [SBIT] Loaded
2023-10-02T21:54:46.931Z,1696283686.931 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-10-02T21:54:46.933Z,1696283686.933 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-10-02T21:54:46.946Z,1696283686.946 [IBIT] Loaded
2023-10-02T21:54:46.946Z,1696283686.946 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-10-02T21:54:46.952Z,1696283686.952 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-10-02T21:54:47.054Z,1696283687.054 [CBIT] Loaded
2023-10-02T21:54:47.054Z,1696283687.054 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-10-02T21:54:47.054Z,1696283687.054 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-10-02T21:54:47.055Z,1696283687.055 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-10-02T21:54:47.349Z,1696283687.349 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-10-02T21:54:47.350Z,1696283687.350 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-10-02T21:54:47.399Z,1696283687.399 [DepthRateCalculator] Loaded
2023-10-02T21:54:47.399Z,1696283687.399 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-10-02T21:54:47.404Z,1696283687.404 [PitchRateCalculator] Loaded
2023-10-02T21:54:47.404Z,1696283687.404 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-10-02T21:54:47.414Z,1696283687.414 [SpeedCalculator] Loaded
2023-10-02T21:54:47.414Z,1696283687.414 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-10-02T21:54:47.418Z,1696283687.418 [YawRateCalculator] Loaded
2023-10-02T21:54:47.419Z,1696283687.419 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-10-02T21:54:47.437Z,1696283687.437 [ElevatorOffsetCalculator] Loaded
2023-10-02T21:54:47.437Z,1696283687.437 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-10-02T21:54:47.438Z,1696283687.438 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-10-02T21:54:47.438Z,1696283687.438 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-10-02T21:54:47.585Z,1696283687.585 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-10-02T21:54:47.585Z,1696283687.585 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-10-02T21:54:47.693Z,1696283687.693 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-10-02T21:54:47.693Z,1696283687.693 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-10-02T21:54:47.736Z,1696283687.736 [DeadReckonUsingSpeedCalculator] Loaded
2023-10-02T21:54:47.736Z,1696283687.736 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-10-02T21:54:47.779Z,1696283687.779 [DeadReckonWithRespectToSeafloor] Loaded
2023-10-02T21:54:47.779Z,1696283687.779 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2023-10-02T21:54:47.792Z,1696283687.792 [NavChart] Loaded
2023-10-02T21:54:47.792Z,1696283687.792 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-10-02T21:54:47.798Z,1696283687.798 [UniversalFixResidualReporter] Loaded
2023-10-02T21:54:47.798Z,1696283687.798 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-10-02T21:54:47.799Z,1696283687.799 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-10-02T21:54:47.799Z,1696283687.799 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-10-02T21:54:47.926Z,1696283687.926 [BuoyancyServo] Loaded
2023-10-02T21:54:47.927Z,1696283687.927 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-10-02T21:54:47.947Z,1696283687.947 [ElevatorServo] Loaded
2023-10-02T21:54:47.947Z,1696283687.947 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-10-02T21:54:47.967Z,1696283687.967 [MassServo] Loaded
2023-10-02T21:54:47.967Z,1696283687.967 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-10-02T21:54:47.986Z,1696283687.986 [RudderServo] Loaded
2023-10-02T21:54:47.986Z,1696283687.986 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-10-02T21:54:48.000Z,1696283688.000 [ThrusterHE] Loaded
2023-10-02T21:54:48.000Z,1696283688.000 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-10-02T21:54:48.001Z,1696283688.001 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-10-02T21:54:48.001Z,1696283688.001 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-10-02T21:54:48.171Z,1696283688.171 [CTD_Seabird] Loaded
2023-10-02T21:54:48.171Z,1696283688.171 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-10-02T21:54:48.172Z,1696283688.172 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9B4E0
2023-10-02T21:54:48.173Z,1696283688.173 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4782
2023-10-02T21:54:48.205Z,1696283688.205 [ESPComponent] Loaded
2023-10-02T21:54:48.206Z,1696283688.206 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-10-02T21:54:48.225Z,1696283688.225 [PAR_Licor] Loaded
2023-10-02T21:54:48.226Z,1696283688.226 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-10-02T21:54:48.266Z,1696283688.266 [WetLabsBB2FL] Loaded
2023-10-02T21:54:48.266Z,1696283688.266 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-10-02T21:54:48.267Z,1696283688.267 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-10-02T21:54:48.268Z,1696283688.268 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4783
2023-10-02T21:54:48.268Z,1696283688.268 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-10-02T21:54:48.275Z,1696283688.275 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-10-02T21:54:48.277Z,1696283688.277 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-10-02T21:54:48.288Z,1696283688.288 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-10-02T21:54:48.289Z,1696283688.289 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFB4E0
2023-10-02T21:54:48.290Z,1696283688.290 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4784
2023-10-02T21:54:48.294Z,1696283688.294 [Supervisor](INFO): Main Thread ID is 4689
2023-10-02T21:54:48.294Z,1696283688.294 [Supervisor](DEBUG): Running supervisor.
2023-10-02T21:54:48.295Z,1696283688.295 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4785
2023-10-02T21:54:48.295Z,1696283688.295 [CommandExec](INFO): Initializing the command executive.
2023-10-02T21:54:48.297Z,1696283688.297 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4786
2023-10-02T21:54:48.299Z,1696283688.299 [controlThread ThreadHandler](INFO): Handler Thread ID is 4787
2023-10-02T21:54:48.299Z,1696283688.299 [controlThread](DEBUG): Initializing ControlThread
2023-10-02T21:54:48.300Z,1696283688.300 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-10-02T21:54:48.302Z,1696283688.302 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-10-02T21:54:48.303Z,1696283688.303 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-10-02T21:54:48.303Z,1696283688.303 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-10-02T21:54:48.308Z,1696283688.308 [SBIT](INFO): Initialize SBIT Component.
2023-10-02T21:54:48.309Z,1696283688.309 [SBIT](IMPORTANT): git: 2023-09-18-3-g7ee55e0de
2023-10-02T21:54:48.309Z,1696283688.309 [SBIT](INFO): git hash: 7ee55e0ded82548f5c252ba0d9a41930617440e4
2023-10-02T21:54:48.309Z,1696283688.309 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-10-02T21:54:48.311Z,1696283688.311 [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-10-02T21:54:48.312Z,1696283688.312 [SBIT](INFO): Beginning SBIT in 60.000000 seconds.
2023-10-02T21:54:48.312Z,1696283688.312 [IBIT](INFO): Initialize IBIT Component.
2023-10-02T21:54:48.313Z,1696283688.313 [CBIT](DEBUG): Initialize CBIT Component.
2023-10-02T21:54:48.314Z,1696283688.314 [logger ThreadHandler](INFO): Handler Thread ID is 4788
2023-10-02T21:54:48.326Z,1696283688.326 [CBIT](DEBUG): Initialized mux pins.
2023-10-02T21:54:48.326Z,1696283688.326 [CBIT](DEBUG): Initializing the watchdog timer.
2023-10-02T21:54:48.335Z,1696283688.335 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4789
2023-10-02T21:54:48.347Z,1696283688.347 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4790
2023-10-02T21:54:48.348Z,1696283688.348 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-10-02T21:54:48.351Z,1696283688.351 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-10-02T21:54:48.351Z,1696283688.351 [CBIT](DEBUG): Initializing heartbeat.
2023-10-02T21:54:48.359Z,1696283688.359 [Onboard ThreadHandler](INFO): Handler Thread ID is 4791
2023-10-02T21:54:48.376Z,1696283688.376 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4792
2023-10-02T21:54:48.399Z,1696283688.399 [DAT ThreadHandler](INFO): Handler Thread ID is 4793
2023-10-02T21:54:48.400Z,1696283688.400 [DAT](INFO): Powering up
2023-10-02T21:54:48.400Z,1696283688.400 [DAT](DEBUG): Initializing DAT.
2023-10-02T21:54:48.404Z,1696283688.404 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4795
2023-10-02T21:54:48.404Z,1696283688.404 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-10-02T21:54:48.408Z,1696283688.408 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4797
2023-10-02T21:54:48.411Z,1696283688.411 [WetLabsBB2FL](INFO): Powering up
2023-10-02T21:54:48.412Z,1696283688.412 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4799
2023-10-02T21:54:48.415Z,1696283688.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2023-10-02T21:54:48.415Z,1696283688.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2023-10-02T21:54:48.415Z,1696283688.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2023-10-02T21:54:48.416Z,1696283688.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2023-10-02T21:54:48.416Z,1696283688.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-10-02T21:54:48.416Z,1696283688.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-10-02T21:54:48.416Z,1696283688.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-10-02T21:54:48.416Z,1696283688.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-10-02T21:54:48.416Z,1696283688.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2023-10-02T21:54:48.416Z,1696283688.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2023-10-02T21:54:48.417Z,1696283688.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2023-10-02T21:54:48.417Z,1696283688.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2023-10-02T21:54:48.422Z,1696283688.422 [CBIT](DEBUG): Deactivating GF circuits.
2023-10-02T21:54:48.422Z,1696283688.422 [CBIT](DEBUG): Deactivating emergency mode.
2023-10-02T21:54:48.462Z,1696283688.462 [CBIT](DEBUG): Backplane powered.
2023-10-02T21:54:48.463Z,1696283688.463 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-10-02T21:54:48.463Z,1696283688.463 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-10-02T21:54:48.463Z,1696283688.463 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-10-02T21:54:48.464Z,1696283688.464 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-10-02T21:54:48.464Z,1696283688.464 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-10-02T21:54:48.465Z,1696283688.465 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-10-02T21:54:48.466Z,1696283688.466 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-10-02T21:54:48.466Z,1696283688.466 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-10-02T21:54:48.471Z,1696283688.471 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-10-02T21:54:48.497Z,1696283688.497 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-10-02T21:54:48.523Z,1696283688.523 [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-10-02T21:54:48.523Z,1696283688.523 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-10-02T21:54:48.568Z,1696283688.568 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-10-02T21:54:48.570Z,1696283688.570 [Default:A.Wait](DEBUG): Construct Wait.
2023-10-02T21:54:48.572Z,1696283688.572 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-10-02T21:54:48.610Z,1696283688.610 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-10-02T21:54:48.613Z,1696283688.613 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-10-02T21:54:48.643Z,1696283688.643 [Default:E.Execute](DEBUG): Construct Execute.
2023-10-02T21:54:48.646Z,1696283688.646 [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-10-02T21:54:48.666Z,1696283688.666 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Waterlinked,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-10-02T21:54:48.673Z,1696283688.673 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-10-02T21:54:48.720Z,1696283688.720 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2023-10-02T21:54:48.721Z,1696283688.721 [Power24vConverter](INFO): Powering up.
2023-10-02T21:54:48.730Z,1696283688.730 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-10-02T21:54:48.756Z,1696283688.756 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-10-02T21:54:48.767Z,1696283688.767 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-10-02T21:54:48.768Z,1696283688.768 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-10-02T21:54:48.775Z,1696283688.775 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-10-02T21:54:48.776Z,1696283688.776 [MassServo](DEBUG): Initializing EZServoServo.
2023-10-02T21:54:48.782Z,1696283688.782 [Radio_Surface](INFO): Powering up
2023-10-02T21:54:48.784Z,1696283688.784 [MassServo](DEBUG): Initializing MassServo.
2023-10-02T21:54:48.784Z,1696283688.784 [RudderServo](DEBUG): Initializing EZServoServo.
2023-10-02T21:54:48.791Z,1696283688.791 [RudderServo](DEBUG): Initializing RudderServo.
2023-10-02T21:54:48.791Z,1696283688.791 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-10-02T21:54:48.799Z,1696283688.799 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-10-02T21:54:49.120Z,1696283689.120 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-10-02T21:54:49.120Z,1696283689.120 [DropWeight] Hardware Fault, FailCount= 1
2023-10-02T21:54:49.120Z,1696283689.120 [DropWeight](ERROR): Hardware Fault
2023-10-02T21:54:49.146Z,1696283689.146 [CommandExec](FAULT): Scheduling is paused
2023-10-02T21:54:49.147Z,1696283689.147 [CBIT](INFO): Critical error at 20231002T215449
2023-10-02T21:54:49.147Z,1696283689.147 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-10-02T21:54:49.149Z,1696283689.149 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-10-02T21:54:49.150Z,1696283689.150 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-10-02T21:54:49.507Z,1696283689.507 [CBIT](INFO): Critical error at 20231002T215449
2023-10-02T21:54:50.518Z,1696283690.518 [WetLabsBB2FL](INFO): Powering down
2023-10-02T21:54:54.616Z,1696283694.616 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-10-02T21:54:58.227Z,1696283698.227 [DAT](INFO): DAT read: 

2023-10-02T21:54:58.229Z,1696283698.229 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-10-02T21:54:59.033Z,1696283699.033 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004736
2023-10-02T21:54:59.487Z,1696283699.487 [DAT](INFO): DAT read: MF Frequency Band

2023-10-02T21:54:59.489Z,1696283699.489 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.10.9

2023-10-02T21:54:59.489Z,1696283699.489 [DAT](INFO): DAT read: Oct  2 2023 20:18:02 

2023-10-02T21:55:00.243Z,1696283700.243 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-10-02T21:55:00.245Z,1696283700.245 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-10-02T21:55:00.245Z,1696283700.245 [DAT](INFO): commRate: 800
2023-10-02T21:55:02.310Z,1696283702.310 [DAT](INFO): entering command mode
2023-10-02T21:55:02.511Z,1696283702.511 [DAT](INFO): DAT read: 

2023-10-02T21:55:02.511Z,1696283702.511 [DAT](INFO): DAT read: user:1>

2023-10-02T21:55:02.512Z,1696283702.512 [DAT](INFO): setting verbose to 3
2023-10-02T21:55:02.763Z,1696283702.763 [DAT](INFO): DAT read: user:1>

2023-10-02T21:55:02.764Z,1696283702.764 [DAT](INFO): DAT read: Verbose         | 3           

2023-10-02T21:55:02.765Z,1696283702.765 [DAT](INFO): set verbose to 3
2023-10-02T21:55:02.765Z,1696283702.765 [DAT](INFO): setting DatVerbose to 27440
2023-10-02T21:55:03.015Z,1696283703.015 [DAT](INFO): DAT read: user:2>

2023-10-02T21:55:03.016Z,1696283703.016 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-10-02T21:55:03.016Z,1696283703.016 [DAT](INFO): set DatVerbose to 27440
2023-10-02T21:55:03.017Z,1696283703.017 [DAT](INFO): setting transmit power to 8
2023-10-02T21:55:03.267Z,1696283703.267 [DAT](INFO): DAT read: user:3>

2023-10-02T21:55:03.268Z,1696283703.268 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-10-02T21:55:03.268Z,1696283703.268 [DAT](INFO): set transmit power to 8
2023-10-02T21:55:03.269Z,1696283703.269 [DAT](INFO): setting local address to 1
2023-10-02T21:55:03.519Z,1696283703.519 [DAT](INFO): DAT read: user:4>

2023-10-02T21:55:03.520Z,1696283703.520 [DAT](INFO): DAT read: LocalAddr       | 1           

2023-10-02T21:55:03.520Z,1696283703.520 [DAT](INFO): set local address to 1
2023-10-02T21:55:03.522Z,1696283703.522 [DAT](INFO): Setting time to: 21:55:3 And date to:10/2/2023
2023-10-02T21:55:03.771Z,1696283703.771 [DAT](INFO): DAT read: user:5>

2023-10-02T21:55:03.772Z,1696283703.772 [DAT](INFO): DAT read: Mon Oct 2, 2023  21:55:03  

2023-10-02T21:55:03.772Z,1696283703.772 [DAT](INFO): Local DAT time set to Mon Oct 2, 2023  21:55:03  

2023-10-02T21:55:03.773Z,1696283703.773 [DAT](INFO): DAT read: Ok

2023-10-02T21:55:03.773Z,1696283703.773 [DAT](INFO): unknown deviceResponse_: Ok

2023-10-02T21:55:16.961Z,1696283716.961 [NAL9602](INFO): Powering up NAL9602
2023-10-02T21:55:27.881Z,1696283727.881 [NAL9602](INFO): NAL9602 initialized
2023-10-02T21:55:49.005Z,1696283749.005 [SBIT](IMPORTANT): Beginning Startup BIT
2023-10-02T21:55:49.010Z,1696283749.010 [CBIT](IMPORTANT): Beginning ground fault scan
2023-10-02T21:56:01.266Z,1696283761.266 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000810
CHAN A1 (24V): 0.000313
CHAN A2 (12V): -0.000084
CHAN A3 (5V): -0.001503
CHAN B0 (3.3V): 0.000238
CHAN B1 (3.15aV): 0.000314
CHAN B2 (3.15bV): -0.000102
CHAN B3 (GND): 0.000135
OPEN: 0.006859
Full Scale: +/- 1 mA
2023-10-02T21:56:07.093Z,1696283767.093 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error
2023-10-02T21:56:14.387Z,1696283774.387 [CommandExec](IMPORTANT): got command failComponent
2023-10-02T21:56:14.387Z,1696283774.387 [CommandExec](IMPORTANT): Failed components:
2023-10-02T21:56:14.387Z,1696283774.387 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-10-02T21:56:31.343Z,1696283791.343 [Waterlinked](INFO): Powering down
2023-10-02T21:56:42.578Z,1696283802.578 [SBIT](IMPORTANT): SBIT PASSED
2023-10-02T21:56:42.579Z,1696283802.579 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-10-02T21:56:42.579Z,1696283802.579 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2023-10-02T21:56:42.580Z,1696283802.580 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool;

2023-10-02T21:56:42.580Z,1696283802.580 [SBIT](IMPORTANT): CBIT.gf24Offset=158.5 microampere;

2023-10-02T21:56:42.580Z,1696283802.580 [SBIT](IMPORTANT): PowerOnly.sampleTime1=1 second;

2023-10-02T21:56:42.580Z,1696283802.580 [SBIT](IMPORTANT): Rowe_600.loadAtStartup=0 bool;

2023-10-02T21:56:42.580Z,1696283802.580 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=159.679458 cubic_centimeter;

2023-10-02T21:56:42.580Z,1696283802.580 [SBIT](IMPORTANT): VerticalControl.massDefault=10.000082 millimeter;

2023-10-02T21:56:42.987Z,1696283802.987 [MissionManager](IMPORTANT): Started mission Startup
2023-10-02T21:56:42.988Z,1696283802.988 [Startup] Running Loop=1
2023-10-02T21:56:42.988Z,1696283802.988 [Startup](DEBUG): Aggregate::initialize Startup
2023-10-02T21:56:42.988Z,1696283802.988 [Startup:A.GoToSurface] Running Loop=1
2023-10-02T21:56:42.988Z,1696283802.988 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-10-02T21:56:42.989Z,1696283802.989 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-10-02T21:56:42.989Z,1696283802.989 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-10-02T21:56:42.989Z,1696283802.989 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-10-02T21:56:42.990Z,1696283802.990 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-10-02T21:56:42.990Z,1696283802.990 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-10-02T21:56:42.992Z,1696283802.992 [Startup:StartupSatComms] Running Loop=1
2023-10-02T21:56:42.992Z,1696283802.992 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-10-02T21:56:42.992Z,1696283802.992 [Startup:StartupSatComms:A] Running Loop=1
2023-10-02T21:56:43.406Z,1696283803.406 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-10-02T21:57:02.675Z,1696283822.675 [CommandExec](IMPORTANT): got command fileExec
2023-10-02T21:57:02.675Z,1696283822.675 [CommandExec](FAULT): Incomplete syntax. Try: help fileExec
2023-10-02T21:57:04.131Z,1696283824.131 [CommandExec](IMPORTANT): got command ibit
2023-10-02T21:57:04.417Z,1696283824.417 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-10-02T21:57:04.417Z,1696283824.417 [IBIT](IMPORTANT): Beginning control surface checks.
2023-10-02T21:57:04.420Z,1696283824.420 [CBIT](IMPORTANT): Beginning ground fault scan
2023-10-02T21:57:06.007Z,1696283826.007 [CommandExec](IMPORTANT): got command failComponent
2023-10-02T21:57:06.007Z,1696283826.007 [CommandExec](IMPORTANT): Failed components:
2023-10-02T21:57:06.007Z,1696283826.007 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-10-02T21:57:15.436Z,1696283835.436 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000662
CHAN A1 (24V): -0.000180
CHAN A2 (12V): -0.000056
CHAN A3 (5V): -0.001365
CHAN B0 (3.3V): -0.000263
CHAN B1 (3.15aV): -0.000016
CHAN B2 (3.15bV): 0.000355
CHAN B3 (GND): -0.000491
OPEN: 0.006659
Full Scale: +/- 1 mA
2023-10-02T21:57:15.804Z,1696283835.804 [CommandExec](IMPORTANT): got command failComponent none DropWeight
2023-10-02T21:57:15.804Z,1696283835.804 [DropWeight] No Fault, FailCount= 1
2023-10-02T21:57:15.804Z,1696283835.804 [CommandExec](IMPORTANT): DropWeight failureMode is No Fault
2023-10-02T21:57:19.951Z,1696283839.951 [CommandExec](IMPORTANT): got command failComponent
2023-10-02T21:57:19.951Z,1696283839.951 [CommandExec](IMPORTANT): Failed components:
2023-10-02T21:57:19.951Z,1696283839.951 [CommandExec](IMPORTANT): No failed Components.
2023-10-02T21:57:25.464Z,1696283845.464 [CommandExec](IMPORTANT): got command restart application
2023-10-02T21:57:26.466Z,1696283846.466 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:26.467Z,1696283846.467 [CommandExec](INFO): Uninitializing the command executive.
2023-10-02T21:57:26.467Z,1696283846.467 [CommandExec](INFO): Uninitializing the command scheduler.
2023-10-02T21:57:26.467Z,1696283846.467 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:26.578Z,1696283846.578 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-10-02T21:57:26.578Z,1696283846.578 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-10-02T21:57:26.579Z,1696283846.579 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:26.579Z,1696283846.579 [NavChartDb](INFO): Join timeout helper Thread ID is 4814
2023-10-02T21:57:26.898Z,1696283846.898 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:26.899Z,1696283846.899 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:26.916Z,1696283846.916 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-10-02T21:57:26.916Z,1696283846.916 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:26.917Z,1696283846.917 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4815
2023-10-02T21:57:27.314Z,1696283847.314 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:27.315Z,1696283847.315 [WetLabsBB2FL](INFO): Powering down
2023-10-02T21:57:27.315Z,1696283847.315 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:27.322Z,1696283847.322 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-10-02T21:57:27.322Z,1696283847.322 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:27.323Z,1696283847.323 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4816
2023-10-02T21:57:28.066Z,1696283848.066 [CTD_Seabird](INFO): Powering down
2023-10-02T21:57:28.078Z,1696283848.078 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:28.079Z,1696283848.079 [CTD_Seabird](INFO): Powering down
2023-10-02T21:57:28.090Z,1696283848.090 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.094Z,1696283848.094 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-10-02T21:57:28.094Z,1696283848.094 [DAT ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.095Z,1696283848.095 [DAT](INFO): Join timeout helper Thread ID is 4817
2023-10-02T21:57:28.292Z,1696283848.292 [DAT](INFO): Powering down
2023-10-02T21:57:28.363Z,1696283848.363 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:28.364Z,1696283848.364 [DAT](INFO): Powering down
2023-10-02T21:57:28.364Z,1696283848.364 [DAT ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.378Z,1696283848.378 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-10-02T21:57:28.378Z,1696283848.378 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.379Z,1696283848.379 [Radio_Surface](INFO): Join timeout helper Thread ID is 4818
2023-10-02T21:57:28.399Z,1696283848.399 [Radio_Surface](INFO): Powering down
2023-10-02T21:57:28.400Z,1696283848.400 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:28.400Z,1696283848.400 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.401Z,1696283848.401 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-10-02T21:57:28.401Z,1696283848.401 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.402Z,1696283848.402 [Onboard](INFO): Join timeout helper Thread ID is 4819
2023-10-02T21:57:28.510Z,1696283848.510 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:28.511Z,1696283848.511 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.515Z,1696283848.515 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-10-02T21:57:28.515Z,1696283848.515 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.516Z,1696283848.516 [DataOverHttps](INFO): Join timeout helper Thread ID is 4820
2023-10-02T21:57:28.874Z,1696283848.874 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:28.878Z,1696283848.878 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.879Z,1696283848.879 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-10-02T21:57:28.879Z,1696283848.879 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.880Z,1696283848.880 [BackseatComponent](INFO): Join timeout helper Thread ID is 4821
2023-10-02T21:57:28.880Z,1696283848.880 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:28.880Z,1696283848.880 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.882Z,1696283848.882 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-10-02T21:57:28.882Z,1696283848.882 [logger ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.882Z,1696283848.882 [logger](INFO): Join timeout helper Thread ID is 4822
2023-10-02T21:57:28.890Z,1696283848.890 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:28.891Z,1696283848.891 [logger ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.904Z,1696283848.904 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-10-02T21:57:28.904Z,1696283848.904 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.904Z,1696283848.904 [CommandLine](INFO): Join timeout helper Thread ID is 4823
2023-10-02T21:57:28.918Z,1696283848.918 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:28.919Z,1696283848.919 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.923Z,1696283848.923 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-10-02T21:57:28.923Z,1696283848.923 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.923Z,1696283848.923 [CommandExec](INFO): Join timeout helper Thread ID is 4824
2023-10-02T21:57:28.925Z,1696283848.925 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-10-02T21:57:28.925Z,1696283848.925 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:28.925Z,1696283848.925 [controlThread](INFO): Join timeout helper Thread ID is 4825
2023-10-02T21:57:29.006Z,1696283849.006 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-10-02T21:57:29.007Z,1696283849.007 [controlThread](DEBUG): Uninitializing ControlThread
2023-10-02T21:57:29.007Z,1696283849.007 [AHRS_M2](INFO): Powering down
2023-10-02T21:57:29.080Z,1696283849.080 [NAL9602](INFO): Powering down
2023-10-02T21:57:29.081Z,1696283849.081 [Waterlinked](INFO): Powering down
2023-10-02T21:57:29.084Z,1696283849.084 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-10-02T21:57:29.085Z,1696283849.085 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-10-02T21:57:29.085Z,1696283849.085 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-10-02T21:57:29.086Z,1696283849.086 [MissionManager](INFO): Uninitializing Mission Default
2023-10-02T21:57:29.088Z,1696283849.088 [MissionManager](INFO): Uninitializing Mission Startup
2023-10-02T21:57:29.088Z,1696283849.088 [Startup] Stopped
2023-10-02T21:57:29.089Z,1696283849.089 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-10-02T21:57:29.089Z,1696283849.089 [Startup:A.GoToSurface] Stopped
2023-10-02T21:57:29.089Z,1696283849.089 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-10-02T21:57:29.089Z,1696283849.089 [Startup:StartupSatComms] Stopped
2023-10-02T21:57:29.089Z,1696283849.089 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-10-02T21:57:29.089Z,1696283849.089 [Startup:StartupSatComms:A] Stopped
2023-10-02T21:57:29.091Z,1696283849.091 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-10-02T21:57:29.091Z,1696283849.091 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-10-02T21:57:29.091Z,1696283849.091 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-10-02T21:57:29.091Z,1696283849.091 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-10-02T21:57:29.092Z,1696283849.092 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-10-02T21:57:29.092Z,1696283849.092 [BuoyancyServo](INFO): Powering down
2023-10-02T21:57:29.106Z,1696283849.106 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-10-02T21:57:29.107Z,1696283849.107 [ElevatorServo](INFO): Powering down
2023-10-02T21:57:29.107Z,1696283849.107 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-10-02T21:57:29.108Z,1696283849.108 [MassServo](INFO): Powering down
2023-10-02T21:57:29.108Z,1696283849.108 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-10-02T21:57:29.108Z,1696283849.108 [RudderServo](INFO): Powering down
2023-10-02T21:57:29.109Z,1696283849.109 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-10-02T21:57:29.109Z,1696283849.109 [ThrusterHE](INFO): Powering down
2023-10-02T21:57:29.110Z,1696283849.110 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-10-02T21:57:29.111Z,1696283849.111 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-10-02T21:57:29.111Z,1696283849.111 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-10-02T21:57:29.111Z,1696283849.111 [CBIT](DEBUG): Powering off loads.
2023-10-02T21:57:29.122Z,1696283849.122 [CBIT](DEBUG): Disabling WDT.
2023-10-02T21:57:29.134Z,1696283849.134 [CBIT](DEBUG): Opening all GF detection circuits.
2023-10-02T21:57:29.135Z,1696283849.135 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:29.163Z,1696283849.163 [DAT ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:29.168Z,1696283849.168 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:29.170Z,1696283849.170 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:29.176Z,1696283849.176 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:29.219Z,1696283849.219 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:29.423Z,1696283849.423 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:29.428Z,1696283849.428 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:29.436Z,1696283849.436 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-10-02T21:57:29.482Z,1696283849.482 [logger ThreadHandler](INFO): Thread cancelled.