2024-11-07T21:38:37.187Z,1731015517.187 [Supervisor](DEBUG): Initializing supervisor.
2024-11-07T21:38:37.191Z,1731015517.191 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-11-07T21:38:37.191Z,1731015517.191 [SyncHandler](INFO): Protected caller Thread ID is 4762
2024-11-07T21:38:37.192Z,1731015517.192 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-11-07T21:38:37.192Z,1731015517.192 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-11-07T21:38:37.193Z,1731015517.193 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4763
2024-11-07T21:38:37.197Z,1731015517.197 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-11-07T21:38:37.214Z,1731015517.214 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-11-07T21:38:37.215Z,1731015517.215 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-11-07T21:38:37.215Z,1731015517.215 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4764
2024-11-07T21:38:37.219Z,1731015517.219 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-11-07T21:38:37.220Z,1731015517.220 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-11-07T21:38:37.220Z,1731015517.220 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4765
2024-11-07T21:38:37.222Z,1731015517.222 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-11-07T21:38:37.223Z,1731015517.223 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-11-07T21:38:37.224Z,1731015517.224 [logger ThreadHandler](INFO): Protected caller Thread ID is 4766
2024-11-07T21:38:37.227Z,1731015517.227 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-11-07T21:38:37.228Z,1731015517.228 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-11-07T21:38:37.229Z,1731015517.229 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-11-07T21:38:37.456Z,1731015517.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-11-07T21:38:37.457Z,1731015517.457 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-11-07T21:38:37.579Z,1731015517.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-11-07T21:38:37.579Z,1731015517.579 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-11-07T21:38:37.688Z,1731015517.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-11-07T21:38:37.688Z,1731015517.688 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-11-07T21:38:38.196Z,1731015518.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-11-07T21:38:38.196Z,1731015518.196 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-11-07T21:38:38.565Z,1731015518.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-11-07T21:38:38.566Z,1731015518.566 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-11-07T21:38:38.927Z,1731015518.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-11-07T21:38:38.928Z,1731015518.928 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-11-07T21:38:39.131Z,1731015519.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-11-07T21:38:39.131Z,1731015519.131 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-11-07T21:38:39.223Z,1731015519.223 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-11-07T21:38:39.317Z,1731015519.317 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-11-07T21:38:39.836Z,1731015519.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-11-07T21:38:39.836Z,1731015519.836 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-11-07T21:38:40.148Z,1731015520.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-11-07T21:38:40.149Z,1731015520.149 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-11-07T21:38:40.744Z,1731015520.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-11-07T21:38:40.744Z,1731015520.744 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-11-07T21:38:40.990Z,1731015520.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-11-07T21:38:40.990Z,1731015520.990 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-11-07T21:38:41.255Z,1731015521.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-11-07T21:38:41.255Z,1731015521.255 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-11-07T21:38:41.406Z,1731015521.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-11-07T21:38:41.406Z,1731015521.406 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-11-07T21:38:42.931Z,1731015522.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-11-07T21:38:42.932Z,1731015522.932 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2024-11-07T21:38:42.933Z,1731015522.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2024-11-07T21:38:43.030Z,1731015523.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2024-11-07T21:38:43.174Z,1731015523.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2024-11-07T21:38:43.447Z,1731015523.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-11-07T21:38:43.448Z,1731015523.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2024-11-07T21:38:43.586Z,1731015523.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2024-11-07T21:38:43.699Z,1731015523.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2024-11-07T21:38:43.804Z,1731015523.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2024-11-07T21:38:43.926Z,1731015523.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2024-11-07T21:38:44.034Z,1731015524.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2024-11-07T21:38:44.132Z,1731015524.132 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2024-11-07T21:38:44.235Z,1731015524.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2024-11-07T21:38:44.420Z,1731015524.420 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2024-11-07T21:38:44.421Z,1731015524.421 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-11-07T21:38:44.440Z,1731015524.440 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-11-07T21:38:44.490Z,1731015524.490 [DepthRateCalculator] Loaded
2024-11-07T21:38:44.490Z,1731015524.490 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-11-07T21:38:44.496Z,1731015524.496 [PitchRateCalculator] Loaded
2024-11-07T21:38:44.496Z,1731015524.496 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-11-07T21:38:44.506Z,1731015524.506 [SpeedCalculator] Loaded
2024-11-07T21:38:44.507Z,1731015524.507 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-11-07T21:38:44.511Z,1731015524.511 [YawRateCalculator] Loaded
2024-11-07T21:38:44.512Z,1731015524.512 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-11-07T21:38:44.532Z,1731015524.532 [ElevatorOffsetCalculator] Loaded
2024-11-07T21:38:44.533Z,1731015524.533 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-11-07T21:38:44.533Z,1731015524.533 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-11-07T21:38:44.534Z,1731015524.534 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-11-07T21:38:44.559Z,1731015524.559 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-11-07T21:38:44.560Z,1731015524.560 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-11-07T21:38:44.570Z,1731015524.570 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-11-07T21:38:44.570Z,1731015524.570 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-11-07T21:38:45.016Z,1731015525.016 [AHRS_M2] Loaded
2024-11-07T21:38:45.016Z,1731015525.016 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-11-07T21:38:45.053Z,1731015525.053 [BackseatComponent] Loaded
2024-11-07T21:38:45.053Z,1731015525.053 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-11-07T21:38:45.054Z,1731015525.054 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0
2024-11-07T21:38:45.055Z,1731015525.055 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4849
2024-11-07T21:38:45.057Z,1731015525.057 [LcmUniversalReporter] Loaded
2024-11-07T21:38:45.058Z,1731015525.058 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-11-07T21:38:45.846Z,1731015525.846 [BPC1] Loaded
2024-11-07T21:38:45.846Z,1731015525.846 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-11-07T21:38:45.928Z,1731015525.928 [DATMMP] Loaded
2024-11-07T21:38:45.928Z,1731015525.928 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-11-07T21:38:45.929Z,1731015525.929 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408A04E0
2024-11-07T21:38:45.930Z,1731015525.930 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 4850
2024-11-07T21:38:45.999Z,1731015525.999 [DataOverHttps] Loaded
2024-11-07T21:38:45.000Z,1731015526.000 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-11-07T21:38:46.001Z,1731015526.001 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2024-11-07T21:38:46.001Z,1731015526.001 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4851
2024-11-07T21:38:46.023Z,1731015526.023 [Depth_Keller] Loaded
2024-11-07T21:38:46.024Z,1731015526.024 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-11-07T21:38:46.028Z,1731015526.028 [DropWeight] Loaded
2024-11-07T21:38:46.028Z,1731015526.028 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-11-07T21:38:46.091Z,1731015526.091 [NAL9602] Loaded
2024-11-07T21:38:46.091Z,1731015526.091 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-11-07T21:38:46.120Z,1731015526.120 [Onboard] Loaded
2024-11-07T21:38:46.120Z,1731015526.120 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-11-07T21:38:46.121Z,1731015526.121 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2024-11-07T21:38:46.122Z,1731015526.122 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4852
2024-11-07T21:38:46.135Z,1731015526.135 [Power24vConverter] Loaded
2024-11-07T21:38:46.136Z,1731015526.136 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-11-07T21:38:46.151Z,1731015526.151 [Radio_Surface] Loaded
2024-11-07T21:38:46.151Z,1731015526.151 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-11-07T21:38:46.152Z,1731015526.152 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2024-11-07T21:38:46.153Z,1731015526.153 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4853
2024-11-07T21:38:46.168Z,1731015526.168 [Sonardyne_Nano] Loaded
2024-11-07T21:38:46.168Z,1731015526.168 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-11-07T21:38:46.191Z,1731015526.191 [Waterlinked] Loaded
2024-11-07T21:38:46.191Z,1731015526.191 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-11-07T21:38:46.192Z,1731015526.192 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-11-07T21:38:46.192Z,1731015526.192 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-11-07T21:38:46.299Z,1731015526.299 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-11-07T21:38:46.299Z,1731015526.299 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-11-07T21:38:46.340Z,1731015526.340 [DeadReckonUsingSpeedCalculator] Loaded
2024-11-07T21:38:46.340Z,1731015526.340 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-11-07T21:38:46.353Z,1731015526.353 [NavChart] Loaded
2024-11-07T21:38:46.353Z,1731015526.353 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-11-07T21:38:46.359Z,1731015526.359 [UniversalFixResidualReporter] Loaded
2024-11-07T21:38:46.359Z,1731015526.359 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-11-07T21:38:46.359Z,1731015526.359 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-11-07T21:38:46.360Z,1731015526.360 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-11-07T21:38:46.496Z,1731015526.496 [SBIT](DEBUG): Construct Startup Built In Test.
2024-11-07T21:38:46.510Z,1731015526.510 [SBIT] Loaded
2024-11-07T21:38:46.510Z,1731015526.510 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-11-07T21:38:46.513Z,1731015526.513 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-11-07T21:38:46.525Z,1731015526.525 [IBIT] Loaded
2024-11-07T21:38:46.525Z,1731015526.525 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-11-07T21:38:46.531Z,1731015526.531 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-11-07T21:38:46.632Z,1731015526.632 [CBIT] Loaded
2024-11-07T21:38:46.632Z,1731015526.632 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-11-07T21:38:46.632Z,1731015526.632 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-11-07T21:38:46.633Z,1731015526.633 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-11-07T21:38:46.763Z,1731015526.763 [CANONSampler] Loaded
2024-11-07T21:38:46.764Z,1731015526.764 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-11-07T21:38:46.826Z,1731015526.826 [CTD_Seabird] Loaded
2024-11-07T21:38:46.827Z,1731015526.827 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-11-07T21:38:46.828Z,1731015526.828 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A384E0
2024-11-07T21:38:46.828Z,1731015526.828 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4854
2024-11-07T21:38:46.850Z,1731015526.850 [PAR_Licor] Loaded
2024-11-07T21:38:46.850Z,1731015526.850 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-11-07T21:38:46.894Z,1731015526.894 [WetLabsBB2FL] Loaded
2024-11-07T21:38:46.895Z,1731015526.895 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-11-07T21:38:46.896Z,1731015526.896 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A684E0
2024-11-07T21:38:46.896Z,1731015526.896 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4855
2024-11-07T21:38:46.897Z,1731015526.897 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-11-07T21:38:46.897Z,1731015526.897 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-11-07T21:38:47.027Z,1731015527.027 [BuoyancyServo] Loaded
2024-11-07T21:38:47.027Z,1731015527.027 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-11-07T21:38:47.051Z,1731015527.051 [ElevatorServo] Loaded
2024-11-07T21:38:47.051Z,1731015527.051 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-11-07T21:38:47.074Z,1731015527.074 [MassServo] Loaded
2024-11-07T21:38:47.074Z,1731015527.074 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-11-07T21:38:47.096Z,1731015527.096 [RudderServo] Loaded
2024-11-07T21:38:47.096Z,1731015527.096 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-11-07T21:38:47.114Z,1731015527.114 [ThrusterHE] Loaded
2024-11-07T21:38:47.114Z,1731015527.114 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-11-07T21:38:47.114Z,1731015527.114 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-11-07T21:38:47.115Z,1731015527.115 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-11-07T21:38:47.165Z,1731015527.165 [VerticalControl](DEBUG): Construct VerticalControl.
2024-11-07T21:38:47.215Z,1731015527.215 [VerticalControl] Loaded
2024-11-07T21:38:47.215Z,1731015527.215 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-11-07T21:38:47.218Z,1731015527.218 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-11-07T21:38:47.261Z,1731015527.261 [HorizontalControl] Loaded
2024-11-07T21:38:47.261Z,1731015527.261 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-11-07T21:38:47.264Z,1731015527.264 [SpeedControl](DEBUG): Construct SpeedControl.
2024-11-07T21:38:47.265Z,1731015527.265 [SpeedControl] Loaded
2024-11-07T21:38:47.266Z,1731015527.266 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-11-07T21:38:47.268Z,1731015527.268 [LoopControl](DEBUG): Construct LoopControl.
2024-11-07T21:38:47.269Z,1731015527.269 [LoopControl] Loaded
2024-11-07T21:38:47.269Z,1731015527.269 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-11-07T21:38:47.270Z,1731015527.270 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-11-07T21:38:47.270Z,1731015527.270 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-11-07T21:38:47.376Z,1731015527.376 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-11-07T21:38:47.377Z,1731015527.377 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-11-07T21:38:47.543Z,1731015527.543 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-11-07T21:38:47.543Z,1731015527.543 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-11-07T21:38:47.869Z,1731015527.869 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-11-07T21:38:47.869Z,1731015527.869 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-11-07T21:38:48.179Z,1731015528.179 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-11-07T21:38:48.209Z,1731015528.209 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-11-07T21:38:48.212Z,1731015528.212 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-11-07T21:38:48.290Z,1731015528.290 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-11-07T21:38:48.291Z,1731015528.291 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-11-07T21:38:48.292Z,1731015528.292 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4856
2024-11-07T21:38:48.296Z,1731015528.296 [Supervisor](INFO): Main Thread ID is 4761
2024-11-07T21:38:48.296Z,1731015528.296 [Supervisor](DEBUG): Running supervisor.
2024-11-07T21:38:48.297Z,1731015528.297 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4857
2024-11-07T21:38:48.302Z,1731015528.302 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4858
2024-11-07T21:38:48.302Z,1731015528.302 [controlThread ThreadHandler](INFO): Handler Thread ID is 4859
2024-11-07T21:38:48.303Z,1731015528.303 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4860
2024-11-07T21:38:48.304Z,1731015528.304 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4861
2024-11-07T21:38:48.304Z,1731015528.304 [logger ThreadHandler](INFO): Handler Thread ID is 4862
2024-11-07T21:38:48.305Z,1731015528.305 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4863
2024-11-07T21:38:48.307Z,1731015528.307 [controlThread](DEBUG): Initializing ControlThread
2024-11-07T21:38:48.308Z,1731015528.308 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-11-07T21:38:48.308Z,1731015528.308 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-11-07T21:38:48.308Z,1731015528.308 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-11-07T21:38:48.309Z,1731015528.309 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-11-07T21:38:48.309Z,1731015528.309 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-11-07T21:38:48.316Z,1731015528.316 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-07T21:38:48.317Z,1731015528.317 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-11-07T21:38:48.317Z,1731015528.317 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-11-07T21:38:48.317Z,1731015528.317 [SBIT](INFO): Initialize SBIT Component.
2024-11-07T21:38:48.318Z,1731015528.318 [SBIT](IMPORTANT): git: 2024-09-17
2024-11-07T21:38:48.318Z,1731015528.318 [SBIT](INFO): git hash: 40e9ce87bff78b617219447905233bfcf6062b37
2024-11-07T21:38:48.318Z,1731015528.318 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-11-07T21:38:48.319Z,1731015528.319 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-11-07T21:38:48.321Z,1731015528.321 [SBIT](INFO): Beginning SBIT in 97.000000 seconds.
2024-11-07T21:38:48.321Z,1731015528.321 [IBIT](INFO): Initialize IBIT Component.
2024-11-07T21:38:48.322Z,1731015528.322 [CBIT](DEBUG): Initialize CBIT Component.
2024-11-07T21:38:48.333Z,1731015528.333 [CBIT](DEBUG): Initialized mux pins.
2024-11-07T21:38:48.334Z,1731015528.334 [CBIT](DEBUG): Initializing the watchdog timer.
2024-11-07T21:38:48.350Z,1731015528.350 [CommandExec](INFO): Initializing the command executive.
2024-11-07T21:38:48.358Z,1731015528.358 [CommandExec](INFO): Initializing the command executive.
2024-11-07T21:38:48.366Z,1731015528.366 [DATMMP ThreadHandler](INFO): Handler Thread ID is 4864
2024-11-07T21:38:48.367Z,1731015528.367 [DATMMP](INFO): Start
2024-11-07T21:38:48.367Z,1731015528.367 [DATMMP](INFO): Powering up
2024-11-07T21:38:48.367Z,1731015528.367 [DATMMP](DEBUG): Initializing DATMMP.
2024-11-07T21:38:48.368Z,1731015528.368 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4866
2024-11-07T21:38:48.369Z,1731015528.369 [Onboard ThreadHandler](INFO): Handler Thread ID is 4867
2024-11-07T21:38:48.374Z,1731015528.374 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-11-07T21:38:48.377Z,1731015528.377 [CBIT](DEBUG): Initializing heartbeat.
2024-11-07T21:38:48.404Z,1731015528.404 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-11-07T21:38:48.410Z,1731015528.410 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4868
2024-11-07T21:38:48.411Z,1731015528.411 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4869
2024-11-07T21:38:48.412Z,1731015528.412 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4870
2024-11-07T21:38:48.413Z,1731015528.413 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4871
2024-11-07T21:38:48.416Z,1731015528.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-11-07T21:38:48.417Z,1731015528.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-11-07T21:38:48.417Z,1731015528.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-11-07T21:38:48.417Z,1731015528.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-11-07T21:38:48.417Z,1731015528.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-11-07T21:38:48.417Z,1731015528.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-11-07T21:38:48.418Z,1731015528.418 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-11-07T21:38:48.418Z,1731015528.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-11-07T21:38:48.419Z,1731015528.419 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-11-07T21:38:48.446Z,1731015528.446 [WetLabsBB2FL](INFO): Powering up
2024-11-07T21:38:48.450Z,1731015528.450 [CBIT](DEBUG): Deactivating GF circuits.
2024-11-07T21:38:48.450Z,1731015528.450 [CBIT](DEBUG): Deactivating emergency mode.
2024-11-07T21:38:48.489Z,1731015528.489 [CBIT](DEBUG): Backplane powered.
2024-11-07T21:38:48.499Z,1731015528.499 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-11-07T21:38:48.501Z,1731015528.501 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-11-07T21:38:48.502Z,1731015528.502 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-11-07T21:38:48.502Z,1731015528.502 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-11-07T21:38:48.503Z,1731015528.503 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-11-07T21:38:48.504Z,1731015528.504 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-11-07T21:38:48.504Z,1731015528.504 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-11-07T21:38:48.505Z,1731015528.505 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-11-07T21:38:48.562Z,1731015528.562 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-11-07T21:38:48.566Z,1731015528.566 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-11-07T21:38:48.590Z,1731015528.590 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-11-07T21:38:48.591Z,1731015528.591 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-11-07T21:38:48.591Z,1731015528.591 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-11-07T21:38:48.592Z,1731015528.592 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-11-07T21:38:48.618Z,1731015528.618 [DATMMP](INFO): Starting
2024-11-07T21:38:48.792Z,1731015528.792 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-11-07T21:38:48.811Z,1731015528.811 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-11-07T21:38:48.813Z,1731015528.813 [Default:A.Wait](DEBUG): Construct Wait.
2024-11-07T21:38:48.814Z,1731015528.814 [Radio_Surface](INFO): Powering up
2024-11-07T21:38:48.823Z,1731015528.823 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-11-07T21:38:48.845Z,1731015528.845 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-11-07T21:38:48.847Z,1731015528.847 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-11-07T21:38:48.865Z,1731015528.865 [Default:E.Execute](DEBUG): Construct Execute.
2024-11-07T21:38:48.870Z,1731015528.870 [DATMMP](INFO): Starting
2024-11-07T21:38:48.873Z,1731015528.873 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-11-07T21:38:48.890Z,1731015528.890 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-11-07T21:38:48.896Z,1731015528.896 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-11-07T21:38:48.957Z,1731015528.957 [Power24vConverter](INFO): Powering up.
2024-11-07T21:38:48.958Z,1731015528.958 [Sonardyne_Nano](INFO): Initializing.
2024-11-07T21:38:48.986Z,1731015528.986 [CANONSampler](INFO): Powering down
2024-11-07T21:38:49.028Z,1731015529.028 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-11-07T21:38:49.034Z,1731015529.034 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-11-07T21:38:49.035Z,1731015529.035 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-11-07T21:38:49.042Z,1731015529.042 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-11-07T21:38:49.043Z,1731015529.043 [MassServo](DEBUG): Initializing EZServoServo.
2024-11-07T21:38:49.050Z,1731015529.050 [MassServo](DEBUG): Initializing MassServo.
2024-11-07T21:38:49.051Z,1731015529.051 [RudderServo](DEBUG): Initializing EZServoServo.
2024-11-07T21:38:49.058Z,1731015529.058 [RudderServo](DEBUG): Initializing RudderServo.
2024-11-07T21:38:49.059Z,1731015529.059 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-11-07T21:38:49.066Z,1731015529.066 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-11-07T21:38:49.122Z,1731015529.122 [DATMMP](INFO): Starting
2024-11-07T21:38:49.354Z,1731015529.354 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-11-07T21:38:49.354Z,1731015529.354 [DropWeight] Hardware Fault, FailCount= 1
2024-11-07T21:38:49.354Z,1731015529.354 [DropWeight](ERROR): Hardware Fault
2024-11-07T21:38:49.374Z,1731015529.374 [DATMMP](INFO): Starting
2024-11-07T21:38:49.381Z,1731015529.381 [CommandExec](FAULT): Scheduling is paused
2024-11-07T21:38:49.382Z,1731015529.382 [CBIT](INFO): Critical error at 20241107T213849
2024-11-07T21:38:49.382Z,1731015529.382 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-11-07T21:38:49.385Z,1731015529.385 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-11-07T21:38:49.394Z,1731015529.394 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-11-07T21:38:49.626Z,1731015529.626 [DATMMP](INFO): Starting
2024-11-07T21:38:49.736Z,1731015529.736 [CBIT](INFO): Critical error at 20241107T213849
2024-11-07T21:38:49.878Z,1731015529.878 [DATMMP](INFO): Starting
2024-11-07T21:38:50.130Z,1731015530.130 [DATMMP](INFO): Starting
2024-11-07T21:38:50.382Z,1731015530.382 [DATMMP](INFO): Starting
2024-11-07T21:38:50.634Z,1731015530.634 [DATMMP](INFO): Starting
2024-11-07T21:38:50.886Z,1731015530.886 [DATMMP](INFO): Starting
2024-11-07T21:38:50.920Z,1731015530.920 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-11-07T21:38:51.034Z,1731015531.034 [WetLabsBB2FL](INFO): Powering down
2024-11-07T21:38:51.138Z,1731015531.138 [DATMMP](INFO): Starting
2024-11-07T21:38:51.390Z,1731015531.390 [DATMMP](INFO): Starting
2024-11-07T21:38:51.642Z,1731015531.642 [DATMMP](INFO): Starting
2024-11-07T21:38:51.894Z,1731015531.894 [DATMMP](INFO): Starting
2024-11-07T21:38:52.147Z,1731015532.147 [DATMMP](INFO): Starting
2024-11-07T21:38:52.214Z,1731015532.214 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2105
2024-11-07T21:38:52.398Z,1731015532.398 [DATMMP](INFO): Starting
2024-11-07T21:38:52.564Z,1731015532.564 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-11-07T21:38:52.650Z,1731015532.650 [DATMMP](INFO): Starting
2024-11-07T21:38:52.902Z,1731015532.902 [DATMMP](INFO): Starting
2024-11-07T21:38:53.154Z,1731015533.154 [DATMMP](INFO): Starting
2024-11-07T21:38:53.407Z,1731015533.407 [DATMMP](INFO): Starting
2024-11-07T21:38:53.658Z,1731015533.658 [DATMMP](INFO): Starting
2024-11-07T21:38:53.910Z,1731015533.910 [DATMMP](INFO): Starting
2024-11-07T21:38:54.163Z,1731015534.163 [DATMMP](INFO): Starting
2024-11-07T21:38:54.413Z,1731015534.413 [DATMMP](INFO): Starting
2024-11-07T21:38:54.666Z,1731015534.666 [DATMMP](INFO): Starting
2024-11-07T21:38:54.794Z,1731015534.794 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-11-07T21:38:54.918Z,1731015534.918 [DATMMP](INFO): Starting
2024-11-07T21:38:55.170Z,1731015535.170 [DATMMP](INFO): Starting
2024-11-07T21:38:55.422Z,1731015535.422 [DATMMP](INFO): Starting
2024-11-07T21:38:55.674Z,1731015535.674 [DATMMP](INFO): Starting
2024-11-07T21:38:55.926Z,1731015535.926 [DATMMP](INFO): Starting
2024-11-07T21:38:56.179Z,1731015536.179 [DATMMP](INFO): Starting
2024-11-07T21:38:56.430Z,1731015536.430 [DATMMP](INFO): Starting
2024-11-07T21:38:56.682Z,1731015536.682 [DATMMP](INFO): Starting
2024-11-07T21:38:56.934Z,1731015536.934 [DATMMP](INFO): Starting
2024-11-07T21:38:57.186Z,1731015537.186 [DATMMP](INFO): Starting
2024-11-07T21:38:57.438Z,1731015537.438 [DATMMP](INFO): Starting
2024-11-07T21:38:57.689Z,1731015537.689 [DATMMP](INFO): Starting
2024-11-07T21:38:57.942Z,1731015537.942 [DATMMP](INFO): Starting
2024-11-07T21:38:58.194Z,1731015538.194 [DATMMP](INFO): Starting
2024-11-07T21:38:58.446Z,1731015538.446 [DATMMP](INFO): Starting
2024-11-07T21:38:58.698Z,1731015538.698 [DATMMP](INFO): Starting
2024-11-07T21:38:58.950Z,1731015538.950 [DATMMP](INFO): Starting
2024-11-07T21:38:59.202Z,1731015539.202 [DATMMP](INFO): Starting
2024-11-07T21:38:59.454Z,1731015539.454 [DATMMP](INFO): Starting
2024-11-07T21:38:59.706Z,1731015539.706 [DATMMP](INFO): Starting
2024-11-07T21:38:59.958Z,1731015539.958 [DATMMP](INFO): Starting
2024-11-07T21:39:00.210Z,1731015540.210 [DATMMP](INFO): Starting
2024-11-07T21:39:00.462Z,1731015540.462 [DATMMP](INFO): Starting
2024-11-07T21:39:00.714Z,1731015540.714 [DATMMP](INFO): Starting
2024-11-07T21:39:00.966Z,1731015540.966 [DATMMP](INFO): Starting
2024-11-07T21:39:00.966Z,1731015540.966 [DATMMP](INFO): DAT read:
2024-11-07T21:39:00.967Z,1731015540.967 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-11-07T21:39:01.218Z,1731015541.218 [DATMMP](INFO): Starting
2024-11-07T21:39:01.470Z,1731015541.470 [DATMMP](INFO): Starting
2024-11-07T21:39:01.722Z,1731015541.722 [DATMMP](INFO): Starting
2024-11-07T21:39:01.974Z,1731015541.974 [DATMMP](INFO): Starting
2024-11-07T21:39:02.226Z,1731015542.226 [DATMMP](INFO): Starting
2024-11-07T21:39:02.478Z,1731015542.478 [DATMMP](INFO): Starting
2024-11-07T21:39:02.730Z,1731015542.730 [DATMMP](INFO): Starting
2024-11-07T21:39:02.730Z,1731015542.730 [DATMMP](INFO): DAT read: MF Frequency Band
2024-11-07T21:39:02.732Z,1731015542.732 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-11-07T21:39:02.732Z,1731015542.732 [DATMMP](INFO): DAT read: Oct 7 2000 21:38:57
2024-11-07T21:39:02.982Z,1731015542.982 [DATMMP](INFO): Starting
2024-11-07T21:39:03.234Z,1731015543.234 [DATMMP](INFO): Starting
2024-11-07T21:39:03.485Z,1731015543.485 [DATMMP](INFO): Starting
2024-11-07T21:39:03.738Z,1731015543.738 [DATMMP](INFO): Starting
2024-11-07T21:39:03.738Z,1731015543.738 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-11-07T21:39:03.740Z,1731015543.740 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-11-07T21:39:03.740Z,1731015543.740 [DATMMP](INFO): commRate: 800
2024-11-07T21:39:03.990Z,1731015543.990 [DATMMP](INFO): Starting
2024-11-07T21:39:04.242Z,1731015544.242 [DATMMP](INFO): Starting
2024-11-07T21:39:04.494Z,1731015544.494 [DATMMP](INFO): Starting
2024-11-07T21:39:04.746Z,1731015544.746 [DATMMP](INFO): Starting
2024-11-07T21:39:04.998Z,1731015544.998 [DATMMP](INFO): Starting
2024-11-07T21:39:05.250Z,1731015545.250 [DATMMP](INFO): Starting
2024-11-07T21:39:05.502Z,1731015545.502 [DATMMP](INFO): Starting
2024-11-07T21:39:05.754Z,1731015545.754 [DATMMP](INFO): Starting
2024-11-07T21:39:05.806Z,1731015545.806 [DATMMP](INFO): entering command mode
2024-11-07T21:39:06.006Z,1731015546.006 [DATMMP](INFO): Starting
2024-11-07T21:39:06.006Z,1731015546.006 [DATMMP](INFO): DAT read:
2024-11-07T21:39:06.006Z,1731015546.006 [DATMMP](INFO): DAT read: user:1>
2024-11-07T21:39:06.007Z,1731015546.007 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-11-07T21:39:06.007Z,1731015546.007 [DATMMP](INFO): entering MMP mode
2024-11-07T21:39:06.258Z,1731015546.258 [DATMMP](INFO): Starting
2024-11-07T21:39:06.258Z,1731015546.258 [DATMMP](INFO): GSXN notify for xid: 0
2024-11-07T21:39:06.260Z,1731015546.260 [DATMMP](INFO): status rx: x1
2024-11-07T21:39:06.260Z,1731015546.260 [DATMMP](INFO): MMP status: 1: started
2024-11-07T21:39:06.260Z,1731015546.260 [DATMMP](INFO): Received message type: status
2024-11-07T21:39:06.261Z,1731015546.261 [DATMMP](INFO): Handled
2024-11-07T21:39:06.510Z,1731015546.510 [DATMMP](INFO): Starting
2024-11-07T21:39:06.511Z,1731015546.511 [DATMMP](INFO): Sent Tx Power Config 8
2024-11-07T21:39:06.511Z,1731015546.511 [DATMMP](INFO): Setting local address: 9
2024-11-07T21:39:06.513Z,1731015546.513 [DATMMP](INFO): Setting time to: 21:39:6 And date to:11/7/2024
2024-11-07T21:39:06.762Z,1731015546.762 [DATMMP](INFO): Starting
2024-11-07T21:39:06.762Z,1731015546.762 [DATMMP](INFO): GSXN notify for xid: 0
2024-11-07T21:39:06.763Z,1731015546.763 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-11-07T21:39:06.763Z,1731015546.763 [DATMMP](INFO): Received message type: mdmpower
2024-11-07T21:39:06.763Z,1731015546.763 [DATMMP](INFO): Handled
2024-11-07T21:39:07.014Z,1731015547.014 [DATMMP](INFO): Starting
2024-11-07T21:39:07.014Z,1731015547.014 [DATMMP](INFO): GSXN notify for xid: 10
2024-11-07T21:39:07.015Z,1731015547.015 [DATMMP](INFO): sreg_txpower rx: x8
2024-11-07T21:39:07.015Z,1731015547.015 [DATMMP](INFO): Received message type: sreg_txpower
2024-11-07T21:39:07.015Z,1731015547.015 [DATMMP](INFO): Match for : sreg_txpower
2024-11-07T21:39:07.015Z,1731015547.015 [DATMMP](INFO): ACK
2024-11-07T21:39:07.282Z,1731015547.282 [DATMMP](INFO): Starting
2024-11-07T21:39:07.282Z,1731015547.282 [DATMMP](INFO): GSXN notify for xid: 0
2024-11-07T21:39:07.283Z,1731015547.283 [DATMMP](INFO): mdmlocal rx: x9 x0 x0 x0
2024-11-07T21:39:07.283Z,1731015547.283 [DATMMP](INFO): Received message type: mdmlocal
2024-11-07T21:39:07.283Z,1731015547.283 [DATMMP](INFO): Handled
2024-11-07T21:39:07.534Z,1731015547.534 [DATMMP](INFO): Starting
2024-11-07T21:39:07.534Z,1731015547.534 [DATMMP](INFO): GSXN notify for xid: 11
2024-11-07T21:39:07.535Z,1731015547.535 [DATMMP](INFO): sreg_locaddr rx: x9
2024-11-07T21:39:07.535Z,1731015547.535 [DATMMP](INFO): Received message type: sreg_locaddr
2024-11-07T21:39:07.535Z,1731015547.535 [DATMMP](INFO): Match for : sreg_locaddr
2024-11-07T21:39:07.535Z,1731015547.535 [DATMMP](INFO): ACK
2024-11-07T21:39:07.786Z,1731015547.786 [DATMMP](INFO): Starting
2024-11-07T21:39:07.786Z,1731015547.786 [DATMMP](INFO): GSXN notify for xid: 12
2024-11-07T21:39:07.787Z,1731015547.787 [DATMMP](INFO): timedate rx: x0 x15 x27 x6 xA x7 x7C x0
2024-11-07T21:39:07.787Z,1731015547.787 [DATMMP](INFO): Received message type: timedate
2024-11-07T21:39:07.788Z,1731015547.788 [DATMMP](INFO): Match for : timedate
2024-11-07T21:39:07.788Z,1731015547.788 [DATMMP](INFO): ACK
2024-11-07T21:39:08.038Z,1731015548.038 [DATMMP](INFO): Starting
2024-11-07T21:39:08.038Z,1731015548.038 [DATMMP](INFO): GSXN notify for xid: 0
2024-11-07T21:39:08.039Z,1731015548.039 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-11-07T21:39:08.039Z,1731015548.039 [DATMMP](INFO): Received message type: sys_verb
2024-11-07T21:39:08.039Z,1731015548.039 [DATMMP](INFO): Handled
2024-11-07T21:39:08.290Z,1731015548.290 [DATMMP](INFO): Starting
2024-11-07T21:39:08.290Z,1731015548.290 [DATMMP](INFO): GSXN notify for xid: 13
2024-11-07T21:39:08.291Z,1731015548.291 [DATMMP](INFO): sreg_verbosity rx: x0
2024-11-07T21:39:08.291Z,1731015548.291 [DATMMP](INFO): Received message type: sreg_verbosity
2024-11-07T21:39:08.291Z,1731015548.291 [DATMMP](INFO): Match for : sreg_verbosity
2024-11-07T21:39:08.291Z,1731015548.291 [DATMMP](INFO): ACK
2024-11-07T21:39:08.542Z,1731015548.542 [DATMMP](INFO): Starting
2024-11-07T21:39:08.542Z,1731015548.542 [DATMMP](INFO): GSXN notify for xid: 14
2024-11-07T21:39:08.543Z,1731015548.543 [DATMMP](INFO): version rx: x2 x5
2024-11-07T21:39:08.543Z,1731015548.543 [DATMMP](INFO): DatMMPVersion: 2.5
2024-11-07T21:39:08.543Z,1731015548.543 [DATMMP](INFO): Received message type: version
2024-11-07T21:39:08.544Z,1731015548.544 [DATMMP](INFO): Match for : version
2024-11-07T21:39:08.544Z,1731015548.544 [DATMMP](INFO): ACK
2024-11-07T21:39:08.544Z,1731015548.544 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-11-07T21:39:16.379Z,1731015556.379 [NAL9602](INFO): Powering up NAL9602
2024-11-07T21:39:17.118Z,1731015557.118 [MassServo](ERROR): getPosition uart error serial timeout
2024-11-07T21:39:17.118Z,1731015557.118 [MassServo](FAULT): Invalid EZ Servo response:""
2024-11-07T21:39:17.118Z,1731015557.118 [MassServo] Communications Fault, FailCount= 1
2024-11-07T21:39:17.118Z,1731015557.118 [MassServo](ERROR): Communications Fault
2024-11-07T21:39:17.123Z,1731015557.123 [CBIT](ERROR): Communications Fault in component: MassServo
2024-11-07T21:39:17.228Z,1731015557.228 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-11-07T21:39:17.228Z,1731015557.228 [MassServo](INFO): Powering down
2024-11-07T21:39:18.020Z,1731015558.020 [MassServo](DEBUG): Initializing EZServoServo.
2024-11-07T21:39:18.138Z,1731015558.138 [MassServo](DEBUG): Initializing MassServo.
2024-11-07T21:39:18.143Z,1731015558.143 [CBIT](INFO): Clearing failed state for component MassServo
2024-11-07T21:39:18.143Z,1731015558.143 [MassServo] No Fault, FailCount= 1
2024-11-07T21:39:22.906Z,1731015562.906 [DATMMP](INFO): GSXN notify for xid: 0
2024-11-07T21:39:22.907Z,1731015562.907 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-11-07T21:39:22.907Z,1731015562.907 [DATMMP](INFO): Received message type: timesync
2024-11-07T21:39:22.907Z,1731015562.907 [DATMMP](INFO): Handled
2024-11-07T21:39:27.291Z,1731015567.291 [NAL9602](INFO): NAL9602 initialized
2024-11-07T21:39:32.986Z,1731015572.986 [DATMMP](INFO): GSXN notify for xid: 0
2024-11-07T21:39:32.988Z,1731015572.988 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-11-07T21:39:32.988Z,1731015572.988 [DATMMP](INFO): Received message type: timesync
2024-11-07T21:39:32.988Z,1731015572.988 [DATMMP](INFO): Handled
2024-11-07T21:40:14.084Z,1731015614.084 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005080
2024-11-07T21:40:26.013Z,1731015626.013 [SBIT](IMPORTANT): Beginning Startup BIT
2024-11-07T21:40:26.017Z,1731015626.017 [CBIT](IMPORTANT): Beginning ground fault scan
2024-11-07T21:40:38.395Z,1731015638.395 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006116
CHAN A1 (24V): 0.000228
CHAN A2 (12V): -0.005663
CHAN A3 (5V): -0.002767
CHAN B0 (3.3V): -0.001811
CHAN B1 (3.15aV): -0.001378
CHAN B2 (3.15bV): -0.001273
CHAN B3 (GND): -0.000850
OPEN: 0.004888
Full Scale: +/- 1 mA
2024-11-07T21:41:06.922Z,1731015666.922 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,23061814580587, 0.0, +0.0,475.0, 0
2024-11-07T21:41:20.040Z,1731015680.040 [SBIT](IMPORTANT): SBIT PASSED
2024-11-07T21:41:20.040Z,1731015680.040 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-11-07T21:41:20.041Z,1731015680.041 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;
2024-11-07T21:41:20.041Z,1731015680.041 [SBIT](IMPORTANT): CANONSampler.simulateHardware=1 bool;
2024-11-07T21:41:20.041Z,1731015680.041 [SBIT](IMPORTANT): CBIT.navErrorThreshold=250 percent;
2024-11-07T21:41:20.041Z,1731015680.041 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-11-07T21:41:20.041Z,1731015680.041 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-11-07T21:41:20.041Z,1731015680.041 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-11-07T21:41:20.042Z,1731015680.042 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2024-11-07T21:41:20.042Z,1731015680.042 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;
2024-11-07T21:41:20.042Z,1731015680.042 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=30 minute;
2024-11-07T21:41:20.042Z,1731015680.042 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2024-11-07T21:41:20.042Z,1731015680.042 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;
2024-11-07T21:41:20.042Z,1731015680.042 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;
2024-11-07T21:41:20.042Z,1731015680.042 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2024-11-07T21:41:20.042Z,1731015680.042 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2024-11-07T21:41:20.042Z,1731015680.042 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;
2024-11-07T21:41:20.043Z,1731015680.043 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 250.000000 count;
2024-11-07T21:41:20.043Z,1731015680.043 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2024-11-07T21:41:20.043Z,1731015680.043 [SBIT](IMPORTANT): Express none platform_average_current;
2024-11-07T21:41:20.043Z,1731015680.043 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=1 ampere_hour;
2024-11-07T21:41:20.043Z,1731015680.043 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-11-07T21:41:20.043Z,1731015680.043 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;
2024-11-07T21:41:20.043Z,1731015680.043 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=209.127836 cubic_centimeter;
2024-11-07T21:41:20.043Z,1731015680.043 [SBIT](IMPORTANT): VerticalControl.massDefault=19.324974 millimeter;
2024-11-07T21:41:20.043Z,1731015680.043 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=1 bool;
2024-11-07T21:41:20.568Z,1731015680.568 [MissionManager](IMPORTANT): Started mission Startup
2024-11-07T21:41:20.568Z,1731015680.568 [Startup] Running Loop=1
2024-11-07T21:41:20.569Z,1731015680.569 [Startup](DEBUG): Aggregate::initialize Startup
2024-11-07T21:41:20.569Z,1731015680.569 [Startup:A.GoToSurface] Running Loop=1
2024-11-07T21:41:20.569Z,1731015680.569 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-11-07T21:41:20.574Z,1731015680.574 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-11-07T21:41:20.574Z,1731015680.574 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-11-07T21:41:20.574Z,1731015680.574 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-11-07T21:41:20.575Z,1731015680.575 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-11-07T21:41:20.575Z,1731015680.575 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-11-07T21:41:20.575Z,1731015680.575 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-11-07T21:41:20.577Z,1731015680.577 [Startup:StartupSatComms] Running Loop=1
2024-11-07T21:41:20.577Z,1731015680.577 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-11-07T21:41:20.582Z,1731015680.582 [Startup:StartupSatComms:A] Running Loop=1
2024-11-07T21:41:20.819Z,1731015680.819 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-11-07T21:41:26.490Z,1731015686.490 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.00, +0.00+0.00, 0.07, 0.00
2024-11-07T21:41:41.511Z,1731015701.511 [CommandExec](IMPORTANT): got command failComponent
2024-11-07T21:41:41.512Z,1731015701.512 [CommandExec](IMPORTANT): Failed components:
2024-11-07T21:41:41.513Z,1731015701.513 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-11-07T21:41:45.252Z,1731015705.252 [CommandExec](IMPORTANT): got command failComponent
2024-11-07T21:41:45.252Z,1731015705.252 [CommandExec](IMPORTANT): Failed components:
2024-11-07T21:41:45.252Z,1731015705.252 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-11-07T21:41:48.379Z,1731015708.379 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-11-07T21:41:48.379Z,1731015708.379 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-07T21:41:48.380Z,1731015708.380 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-11-07T21:41:48.380Z,1731015708.380 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-11-07T21:41:48.453Z,1731015708.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-07T21:41:48.453Z,1731015708.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-11-07T21:41:48.863Z,1731015708.863 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-07T21:41:48.863Z,1731015708.863 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-11-07T21:41:48.870Z,1731015708.870 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-11-07T21:41:48.870Z,1731015708.870 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-11-07T21:41:49.220Z,1731015709.220 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-07T21:42:20.613Z,1731015740.613 [Startup:StartupSatComms:A](INFO): Timed out from 2024-11-07T21:41:20.6Z
2024-11-07T21:42:20.613Z,1731015740.613 [Startup:StartupSatComms:A] Stopped
2024-11-07T21:42:20.613Z,1731015740.613 [Startup:StartupSatComms:B] Running Loop=1
2024-11-07T21:42:21.011Z,1731015741.011 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-11-07T21:42:29.808Z,1731015749.808 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241107T211359/Courier0010.lzma
2024-11-07T21:42:30.799Z,1731015750.799 [DataOverHttps](INFO): Moved sent file to Logs/20241107T211359/Courier0010.lzma.bak
2024-11-07T21:42:30.799Z,1731015750.799 [DataOverHttps](INFO): SBD MOMSN=23382724
2024-11-07T21:42:37.443Z,1731015757.443 [Waterlinked](ERROR): only read 3 of 5 data items. Device response::BI, -1, +3, +0,A
2024-11-07T21:42:52.817Z,1731015772.817 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20241107T213837/Courier0000.lzma
2024-11-07T21:42:53.803Z,1731015773.803 [DataOverHttps](INFO): Moved sent file to Logs/20241107T213837/Courier0000.lzma.bak
2024-11-07T21:42:53.804Z,1731015773.804 [DataOverHttps](INFO): SBD MOMSN=23382730
2024-11-07T21:43:10.733Z,1731015790.733 [CommandExec](IMPORTANT): got command failComponent
2024-11-07T21:43:10.733Z,1731015790.733 [CommandExec](IMPORTANT): Failed components:
2024-11-07T21:43:10.734Z,1731015790.734 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-11-07T21:43:15.245Z,1731015795.245 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20241107T211359/Express0011.lzma
2024-11-07T21:43:16.306Z,1731015796.306 [DataOverHttps](INFO): Moved sent file to Logs/20241107T211359/Express0011.lzma.bak
2024-11-07T21:43:16.307Z,1731015796.307 [DataOverHttps](INFO): SBD MOMSN=23382735
2024-11-07T21:43:21.092Z,1731015801.092 [Startup:StartupSatComms:B](INFO): Timed out from 2024-11-07T21:42:20.6Z
2024-11-07T21:43:21.093Z,1731015801.093 [Startup:StartupSatComms:B] Stopped
2024-11-07T21:43:21.093Z,1731015801.093 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-11-07T21:43:21.093Z,1731015801.093 [Startup:StartupSatComms] Stopped
2024-11-07T21:43:21.093Z,1731015801.093 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-11-07T21:43:21.094Z,1731015801.094 [Startup](INFO): Completed Startup
2024-11-07T21:43:21.094Z,1731015801.094 [MissionManager](INFO): Startup is completed.
2024-11-07T21:43:21.094Z,1731015801.094 [MissionManager](INFO): Uninitializing Mission Startup
2024-11-07T21:43:21.094Z,1731015801.094 [Startup] Stopped
2024-11-07T21:43:21.094Z,1731015801.094 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-11-07T21:43:21.094Z,1731015801.094 [Startup:A.GoToSurface] Stopped
2024-11-07T21:43:21.095Z,1731015801.095 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-11-07T21:43:21.766Z,1731015801.766 [MissionManager](IMPORTANT): Started mission Default
2024-11-07T21:43:21.767Z,1731015801.767 [Default] Running Loop=1
2024-11-07T21:43:21.768Z,1731015801.768 [Default](DEBUG): Aggregate::initialize Default
2024-11-07T21:43:21.768Z,1731015801.768 [Default:B.GoToSurface] Running Loop=1
2024-11-07T21:43:21.769Z,1731015801.769 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-11-07T21:43:21.774Z,1731015801.774 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-11-07T21:43:21.776Z,1731015801.776 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-11-07T21:43:21.777Z,1731015801.777 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-11-07T21:43:21.782Z,1731015801.782 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-11-07T21:43:21.783Z,1731015801.783 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-11-07T21:43:21.783Z,1731015801.783 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-11-07T21:43:21.783Z,1731015801.783 [Default:A.Wait] Running Loop=1
2024-11-07T21:43:21.783Z,1731015801.783 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-11-07T21:43:31.615Z,1731015811.615 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,23061815003059, 0.0 0.0,1475.0, 0
2024-11-07T21:43:34.869Z,1731015814.869 [Default:A.Wait](INFO): Done Waiting.
2024-11-07T21:43:34.877Z,1731015814.877 [Default:A.Wait] Stopped
2024-11-07T21:43:34.878Z,1731015814.878 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-11-07T21:43:35.387Z,1731015815.387 [Default:CheckIn] Running Loop=1
2024-11-07T21:43:35.387Z,1731015815.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-11-07T21:43:35.387Z,1731015815.387 [Default:CheckIn:Read_GPS] Running Loop=1
2024-11-07T21:43:35.704Z,1731015815.704 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-11-07T21:43:37.404Z,1731015817.404 [DataOverHttps](INFO): Sending 1313 bytes from file Logs/20241107T213837/Express0001.lzma
2024-11-07T21:43:38.403Z,1731015818.403 [DataOverHttps](INFO): Moved sent file to Logs/20241107T213837/Express0001.lzma.bak
2024-11-07T21:43:38.403Z,1731015818.403 [DataOverHttps](INFO): SBD MOMSN=23382739
2024-11-07T21:43:52.246Z,1731015832.246 [Waterlinked](INFO): Powering down
2024-11-07T21:44:29.759Z,1731015869.759 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-11-07T21:44:49.555Z,1731015889.555 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-11-07T21:44:49.555Z,1731015889.555 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-11-07T21:44:49.556Z,1731015889.556 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-11-07T21:44:49.556Z,1731015889.556 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-11-07T21:44:49.599Z,1731015889.599 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-11-07T21:44:49.599Z,1731015889.599 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-11-07T21:44:49.965Z,1731015889.965 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-11-07T21:44:49.965Z,1731015889.965 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-11-07T21:44:49.965Z,1731015889.965 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-11-07T21:44:49.965Z,1731015889.965 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-11-07T21:44:50.361Z,1731015890.361 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-11-07T21:46:15.599Z,1731015975.599 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2024-11-07T21:46:15.602Z,1731015975.602 [BPC1](INFO): Received data from all battery sticks.
2024-11-07T21:46:27.202Z,1731015987.202 [CommandExec](IMPORTANT): got command failComponent
2024-11-07T21:46:27.202Z,1731015987.202 [CommandExec](IMPORTANT): Failed components:
2024-11-07T21:46:27.203Z,1731015987.203 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-11-07T21:46:29.910Z,1731015989.910 [CommandExec](IMPORTANT): got command failComponent
2024-11-07T21:46:29.910Z,1731015989.910 [CommandExec](IMPORTANT): Failed components:
2024-11-07T21:46:29.910Z,1731015989.910 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-11-07T21:46:31.886Z,1731015991.886 [CommandExec](IMPORTANT): got command quit
2024-11-07T21:46:32.890Z,1731015992.890 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:32.890Z,1731015992.890 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:32.890Z,1731015992.890 [CommandExec](INFO): Uninitializing the command executive.
2024-11-07T21:46:32.890Z,1731015992.890 [CommandExec](INFO): Uninitializing the command scheduler.
2024-11-07T21:46:32.891Z,1731015992.891 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:32.891Z,1731015992.891 [CommandExec](INFO): Uninitializing the command executive.
2024-11-07T21:46:32.891Z,1731015992.891 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:32.893Z,1731015992.893 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-11-07T21:46:32.894Z,1731015992.894 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-11-07T21:46:32.894Z,1731015992.894 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:32.894Z,1731015992.894 [NavChartDb](INFO): Join timeout helper Thread ID is 4897
2024-11-07T21:46:33.082Z,1731015993.082 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:33.082Z,1731015993.082 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:33.086Z,1731015993.086 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-11-07T21:46:33.086Z,1731015993.086 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:33.086Z,1731015993.086 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4898
2024-11-07T21:46:33.382Z,1731015993.382 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:33.382Z,1731015993.382 [WetLabsBB2FL](INFO): Powering down
2024-11-07T21:46:33.382Z,1731015993.382 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:33.394Z,1731015993.394 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-11-07T21:46:33.394Z,1731015993.394 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:33.394Z,1731015993.394 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4899
2024-11-07T21:46:34.370Z,1731015994.370 [CTD_Seabird](INFO): Powering down
2024-11-07T21:46:34.384Z,1731015994.384 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:34.393Z,1731015994.393 [CTD_Seabird](INFO): Powering down
2024-11-07T21:46:34.406Z,1731015994.406 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:34.421Z,1731015994.421 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-11-07T21:46:34.422Z,1731015994.422 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:34.422Z,1731015994.422 [Radio_Surface](INFO): Join timeout helper Thread ID is 4900
2024-11-07T21:46:34.522Z,1731015994.522 [Radio_Surface](INFO): Powering down
2024-11-07T21:46:34.523Z,1731015994.523 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:34.523Z,1731015994.523 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:34.542Z,1731015994.542 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-11-07T21:46:34.542Z,1731015994.542 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:34.542Z,1731015994.542 [Onboard](INFO): Join timeout helper Thread ID is 4901
2024-11-07T21:46:35.518Z,1731015995.518 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-11-07T21:46:38.762Z,1731015998.762 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:38.762Z,1731015998.762 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:38.774Z,1731015998.774 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-11-07T21:46:38.775Z,1731015998.775 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:38.775Z,1731015998.775 [DataOverHttps](INFO): Join timeout helper Thread ID is 4902
2024-11-07T21:46:39.534Z,1731015999.534 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:39.534Z,1731015999.534 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:39.554Z,1731015999.554 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-11-07T21:46:39.554Z,1731015999.554 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:39.555Z,1731015999.555 [DATMMP](INFO): Join timeout helper Thread ID is 4903
2024-11-07T21:46:39.782Z,1731015999.782 [DATMMP](INFO): Stop
2024-11-07T21:46:39.782Z,1731015999.782 [DATMMP](INFO): uninitialize
2024-11-07T21:46:39.782Z,1731015999.782 [DATMMP](INFO): Powering down
2024-11-07T21:46:39.854Z,1731015999.854 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:39.854Z,1731015999.854 [DATMMP](INFO): uninitialize
2024-11-07T21:46:39.854Z,1731015999.854 [DATMMP](INFO): Powering down
2024-11-07T21:46:39.855Z,1731015999.855 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:39.862Z,1731015999.862 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-11-07T21:46:39.862Z,1731015999.862 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:39.863Z,1731015999.863 [BackseatComponent](INFO): Join timeout helper Thread ID is 4904
2024-11-07T21:46:39.982Z,1731015999.982 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:39.982Z,1731015999.982 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:39.990Z,1731015999.990 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-11-07T21:46:39.990Z,1731015999.990 [logger ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:39.991Z,1731015999.991 [logger](INFO): Join timeout helper Thread ID is 4905
2024-11-07T21:46:39.998Z,1731015999.998 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:39.998Z,1731015999.998 [logger ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.010Z,1731016000.010 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-11-07T21:46:40.010Z,1731016000.010 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.011Z,1731016000.011 [CommandLine](INFO): Join timeout helper Thread ID is 4906
2024-11-07T21:46:40.046Z,1731016000.046 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:40.046Z,1731016000.046 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:40.047Z,1731016000.047 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.047Z,1731016000.047 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.050Z,1731016000.050 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-11-07T21:46:40.050Z,1731016000.050 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.051Z,1731016000.051 [CommandExec](INFO): Join timeout helper Thread ID is 4907
2024-11-07T21:46:40.052Z,1731016000.052 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-11-07T21:46:40.052Z,1731016000.052 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.052Z,1731016000.052 [controlThread](INFO): Join timeout helper Thread ID is 4908
2024-11-07T21:46:40.226Z,1731016000.226 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-11-07T21:46:40.226Z,1731016000.226 [controlThread](DEBUG): Uninitializing ControlThread
2024-11-07T21:46:40.226Z,1731016000.226 [AHRS_M2](INFO): Powering down
2024-11-07T21:46:40.298Z,1731016000.298 [NAL9602](INFO): Powering down
2024-11-07T21:46:40.300Z,1731016000.300 [Sonardyne_Nano](INFO): Powering down
2024-11-07T21:46:40.418Z,1731016000.418 [Waterlinked](INFO): Powering down
2024-11-07T21:46:40.419Z,1731016000.419 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-11-07T21:46:40.420Z,1731016000.420 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-11-07T21:46:40.421Z,1731016000.421 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-11-07T21:46:40.422Z,1731016000.422 [MissionManager](INFO): Uninitializing Mission Default
2024-11-07T21:46:40.422Z,1731016000.422 [Default] Stopped
2024-11-07T21:46:40.422Z,1731016000.422 [Default](DEBUG): Aggregate::uninitialize Default
2024-11-07T21:46:40.422Z,1731016000.422 [Default:B.GoToSurface] Stopped
2024-11-07T21:46:40.422Z,1731016000.422 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-11-07T21:46:40.422Z,1731016000.422 [Default:CheckIn] Stopped
2024-11-07T21:46:40.422Z,1731016000.422 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-11-07T21:46:40.422Z,1731016000.422 [Default:CheckIn:Read_GPS] Stopped
2024-11-07T21:46:40.425Z,1731016000.425 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-11-07T21:46:40.425Z,1731016000.425 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-11-07T21:46:40.426Z,1731016000.426 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-11-07T21:46:40.426Z,1731016000.426 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-11-07T21:46:40.426Z,1731016000.426 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-11-07T21:46:40.426Z,1731016000.426 [BuoyancyServo](INFO): Powering down
2024-11-07T21:46:40.437Z,1731016000.437 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-11-07T21:46:40.438Z,1731016000.438 [ElevatorServo](INFO): Powering down
2024-11-07T21:46:40.438Z,1731016000.438 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-11-07T21:46:40.438Z,1731016000.438 [MassServo](INFO): Powering down
2024-11-07T21:46:40.439Z,1731016000.439 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-11-07T21:46:40.439Z,1731016000.439 [RudderServo](INFO): Powering down
2024-11-07T21:46:40.440Z,1731016000.440 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-11-07T21:46:40.440Z,1731016000.440 [ThrusterHE](INFO): Powering down
2024-11-07T21:46:40.441Z,1731016000.441 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-11-07T21:46:40.441Z,1731016000.441 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-11-07T21:46:40.442Z,1731016000.442 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-11-07T21:46:40.442Z,1731016000.442 [CBIT](DEBUG): Powering off loads.
2024-11-07T21:46:40.453Z,1731016000.453 [CBIT](DEBUG): Disabling WDT.
2024-11-07T21:46:40.465Z,1731016000.465 [CBIT](DEBUG): Opening all GF detection circuits.
2024-11-07T21:46:40.466Z,1731016000.466 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.485Z,1731016000.485 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.488Z,1731016000.488 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.494Z,1731016000.494 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.500Z,1731016000.500 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.543Z,1731016000.543 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.592Z,1731016000.592 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.597Z,1731016000.597 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.650Z,1731016000.650 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-11-07T21:46:40.697Z,1731016000.697 [logger ThreadHandler](INFO): Thread cancelled.