2023-09-14T21:17:12.994Z,1694726232.994 [Supervisor](DEBUG): Initializing supervisor.
2023-09-14T21:17:12.998Z,1694726232.998 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-14T21:17:12.998Z,1694726232.998 [SyncHandler](INFO): Protected caller Thread ID is 836
2023-09-14T21:17:12.999Z,1694726232.999 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-14T21:17:12.000Z,1694726233.000 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-14T21:17:13.000Z,1694726233.000 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2023-09-14T21:17:13.004Z,1694726233.004 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-14T21:17:13.022Z,1694726233.022 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-14T21:17:13.023Z,1694726233.023 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-14T21:17:13.023Z,1694726233.023 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2023-09-14T21:17:13.027Z,1694726233.027 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-14T21:17:13.028Z,1694726233.028 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-14T21:17:13.028Z,1694726233.028 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2023-09-14T21:17:13.030Z,1694726233.030 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-14T21:17:13.031Z,1694726233.031 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-14T21:17:13.032Z,1694726233.032 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2023-09-14T21:17:13.035Z,1694726233.035 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-14T21:17:13.036Z,1694726233.036 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-14T21:17:13.040Z,1694726233.040 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-14T21:17:13.252Z,1694726233.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-14T21:17:13.253Z,1694726233.253 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-14T21:17:13.357Z,1694726233.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-14T21:17:13.359Z,1694726233.359 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-14T21:17:13.453Z,1694726233.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-14T21:17:13.453Z,1694726233.453 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-14T21:17:13.908Z,1694726233.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-14T21:17:13.909Z,1694726233.909 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-14T21:17:14.243Z,1694726234.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-14T21:17:14.244Z,1694726234.244 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-14T21:17:14.560Z,1694726234.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-14T21:17:14.561Z,1694726234.561 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-14T21:17:14.753Z,1694726234.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-14T21:17:14.754Z,1694726234.754 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-14T21:17:14.833Z,1694726234.833 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-14T21:17:14.913Z,1694726234.913 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-14T21:17:15.296Z,1694726235.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-14T21:17:15.298Z,1694726235.298 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-14T21:17:15.566Z,1694726235.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-14T21:17:15.568Z,1694726235.568 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-14T21:17:16.068Z,1694726236.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-14T21:17:16.070Z,1694726236.070 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-14T21:17:16.386Z,1694726236.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-14T21:17:16.387Z,1694726236.387 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-14T21:17:16.891Z,1694726236.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-14T21:17:16.892Z,1694726236.892 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-14T21:17:17.028Z,1694726237.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-14T21:17:17.029Z,1694726237.029 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-14T21:17:17.813Z,1694726237.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-14T21:17:17.815Z,1694726237.815 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2023-09-14T21:17:17.817Z,1694726237.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2023-09-14T21:17:17.903Z,1694726237.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2023-09-14T21:17:18.031Z,1694726238.031 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2023-09-14T21:17:18.296Z,1694726238.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-14T21:17:18.298Z,1694726238.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2023-09-14T21:17:18.419Z,1694726238.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2023-09-14T21:17:18.518Z,1694726238.518 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2023-09-14T21:17:18.610Z,1694726238.610 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2023-09-14T21:17:18.716Z,1694726238.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2023-09-14T21:17:18.810Z,1694726238.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2023-09-14T21:17:18.895Z,1694726238.895 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2023-09-14T21:17:18.994Z,1694726238.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2023-09-14T21:17:19.153Z,1694726239.153 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-09-14T21:17:19.156Z,1694726239.156 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-14T21:17:19.226Z,1694726239.226 [DepthRateCalculator] Loaded
2023-09-14T21:17:19.226Z,1694726239.226 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-14T21:17:19.232Z,1694726239.232 [PitchRateCalculator] Loaded
2023-09-14T21:17:19.232Z,1694726239.232 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-14T21:17:19.242Z,1694726239.242 [SpeedCalculator] Loaded
2023-09-14T21:17:19.243Z,1694726239.243 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-14T21:17:19.247Z,1694726239.247 [YawRateCalculator] Loaded
2023-09-14T21:17:19.248Z,1694726239.248 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-14T21:17:19.269Z,1694726239.269 [ElevatorOffsetCalculator] Loaded
2023-09-14T21:17:19.270Z,1694726239.270 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-14T21:17:19.270Z,1694726239.270 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-14T21:17:19.272Z,1694726239.272 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-14T21:17:19.314Z,1694726239.314 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-14T21:17:19.315Z,1694726239.315 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-14T21:17:19.331Z,1694726239.331 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-14T21:17:19.331Z,1694726239.331 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-14T21:17:20.094Z,1694726240.094 [AHRS_M2] Loaded
2023-09-14T21:17:20.095Z,1694726240.095 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-14T21:17:20.344Z,1694726240.344 [BackseatComponent] Loaded
2023-09-14T21:17:20.344Z,1694726240.344 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-14T21:17:20.345Z,1694726240.345 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0
2023-09-14T21:17:20.345Z,1694726240.345 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2023-09-14T21:17:20.348Z,1694726240.348 [LcmUniversalReporter] Loaded
2023-09-14T21:17:20.348Z,1694726240.348 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-14T21:17:21.148Z,1694726241.148 [BPC1] Loaded
2023-09-14T21:17:21.149Z,1694726241.149 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-14T21:17:21.288Z,1694726241.288 [DataOverHttps] Loaded
2023-09-14T21:17:21.289Z,1694726241.289 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-14T21:17:21.290Z,1694726241.290 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0
2023-09-14T21:17:21.290Z,1694726241.290 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2023-09-14T21:17:21.310Z,1694726241.310 [Depth_Keller] Loaded
2023-09-14T21:17:21.310Z,1694726241.310 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-14T21:17:21.315Z,1694726241.315 [DropWeight] Loaded
2023-09-14T21:17:21.316Z,1694726241.316 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-09-14T21:17:21.378Z,1694726241.378 [NAL9602] Loaded
2023-09-14T21:17:21.378Z,1694726241.378 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-14T21:17:21.407Z,1694726241.407 [Onboard] Loaded
2023-09-14T21:17:21.408Z,1694726241.408 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-14T21:17:21.409Z,1694726241.409 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0
2023-09-14T21:17:21.409Z,1694726241.409 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2023-09-14T21:17:21.415Z,1694726241.415 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2023-09-14T21:17:21.426Z,1694726241.426 [PowerOnly] Loaded
2023-09-14T21:17:21.427Z,1694726241.427 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-09-14T21:17:21.438Z,1694726241.438 [Power24vConverter] Loaded
2023-09-14T21:17:21.439Z,1694726241.439 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-14T21:17:21.453Z,1694726241.453 [Radio_Surface] Loaded
2023-09-14T21:17:21.453Z,1694726241.453 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-14T21:17:21.454Z,1694726241.454 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0
2023-09-14T21:17:21.454Z,1694726241.454 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2023-09-14T21:17:21.477Z,1694726241.477 [Waterlinked] Loaded
2023-09-14T21:17:21.477Z,1694726241.477 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2023-09-14T21:17:21.478Z,1694726241.478 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-14T21:17:21.478Z,1694726241.478 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-14T21:17:22.119Z,1694726242.119 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-09-14T21:17:22.120Z,1694726242.120 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-09-14T21:17:22.162Z,1694726242.162 [DeadReckonUsingSpeedCalculator] Loaded
2023-09-14T21:17:22.162Z,1694726242.162 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-09-14T21:17:22.176Z,1694726242.176 [NavChart] Loaded
2023-09-14T21:17:22.176Z,1694726242.176 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-14T21:17:22.182Z,1694726242.182 [UniversalFixResidualReporter] Loaded
2023-09-14T21:17:22.182Z,1694726242.182 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-14T21:17:22.182Z,1694726242.182 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-14T21:17:22.184Z,1694726242.184 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-14T21:17:22.354Z,1694726242.354 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-14T21:17:22.369Z,1694726242.369 [SBIT] Loaded
2023-09-14T21:17:22.369Z,1694726242.369 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-14T21:17:22.372Z,1694726242.372 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-14T21:17:22.385Z,1694726242.385 [IBIT] Loaded
2023-09-14T21:17:22.385Z,1694726242.385 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-14T21:17:22.391Z,1694726242.391 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-14T21:17:22.494Z,1694726242.494 [CBIT] Loaded
2023-09-14T21:17:22.495Z,1694726242.495 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-14T21:17:22.495Z,1694726242.495 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-14T21:17:22.496Z,1694726242.496 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-14T21:17:22.716Z,1694726242.716 [CTD_Seabird] Loaded
2023-09-14T21:17:22.716Z,1694726242.716 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-14T21:17:22.717Z,1694726242.717 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0
2023-09-14T21:17:22.717Z,1694726242.717 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2023-09-14T21:17:22.737Z,1694726242.737 [PAR_Licor] Loaded
2023-09-14T21:17:22.738Z,1694726242.738 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-14T21:17:22.778Z,1694726242.778 [WetLabsBB2FL] Loaded
2023-09-14T21:17:22.779Z,1694726242.779 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-14T21:17:22.780Z,1694726242.780 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2023-09-14T21:17:22.780Z,1694726242.780 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2023-09-14T21:17:22.781Z,1694726242.781 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-14T21:17:22.781Z,1694726242.781 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-14T21:17:23.181Z,1694726243.181 [BuoyancyServo] Loaded
2023-09-14T21:17:23.182Z,1694726243.182 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-14T21:17:23.213Z,1694726243.213 [ElevatorServo] Loaded
2023-09-14T21:17:23.214Z,1694726243.214 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-14T21:17:23.235Z,1694726243.235 [MassServo] Loaded
2023-09-14T21:17:23.236Z,1694726243.236 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-14T21:17:23.256Z,1694726243.256 [RudderServo] Loaded
2023-09-14T21:17:23.256Z,1694726243.256 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-14T21:17:23.272Z,1694726243.272 [ThrusterHE] Loaded
2023-09-14T21:17:23.272Z,1694726243.272 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-14T21:17:23.273Z,1694726243.273 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-14T21:17:23.273Z,1694726243.273 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-14T21:17:23.341Z,1694726243.341 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-14T21:17:23.393Z,1694726243.393 [VerticalControl] Loaded
2023-09-14T21:17:23.393Z,1694726243.393 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-14T21:17:23.396Z,1694726243.396 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-14T21:17:23.433Z,1694726243.433 [HorizontalControl] Loaded
2023-09-14T21:17:23.434Z,1694726243.434 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-14T21:17:23.436Z,1694726243.436 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-14T21:17:23.438Z,1694726243.438 [SpeedControl] Loaded
2023-09-14T21:17:23.438Z,1694726243.438 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-14T21:17:23.441Z,1694726243.441 [LoopControl](DEBUG): Construct LoopControl.
2023-09-14T21:17:23.441Z,1694726243.441 [LoopControl] Loaded
2023-09-14T21:17:23.442Z,1694726243.442 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-14T21:17:23.442Z,1694726243.442 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-14T21:17:23.443Z,1694726243.443 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-14T21:17:23.569Z,1694726243.569 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-14T21:17:23.570Z,1694726243.570 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-14T21:17:23.749Z,1694726243.749 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-14T21:17:23.750Z,1694726243.750 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-14T21:17:24.110Z,1694726244.110 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-14T21:17:24.110Z,1694726244.110 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-14T21:17:24.193Z,1694726244.193 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-14T21:17:24.199Z,1694726244.199 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-14T21:17:24.202Z,1694726244.202 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-14T21:17:24.213Z,1694726244.213 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-14T21:17:24.214Z,1694726244.214 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCA4E0
2023-09-14T21:17:24.214Z,1694726244.214 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2023-09-14T21:17:24.219Z,1694726244.219 [Supervisor](INFO): Main Thread ID is 829
2023-09-14T21:17:24.219Z,1694726244.219 [Supervisor](DEBUG): Running supervisor.
2023-09-14T21:17:24.219Z,1694726244.219 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2023-09-14T21:17:24.220Z,1694726244.220 [CommandExec](INFO): Initializing the command executive.
2023-09-14T21:17:24.221Z,1694726244.221 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2023-09-14T21:17:24.223Z,1694726244.223 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2023-09-14T21:17:24.224Z,1694726244.224 [controlThread](DEBUG): Initializing ControlThread
2023-09-14T21:17:24.225Z,1694726244.225 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-14T21:17:24.225Z,1694726244.225 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-14T21:17:24.225Z,1694726244.225 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-14T21:17:24.226Z,1694726244.226 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-14T21:17:24.226Z,1694726244.226 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-14T21:17:24.234Z,1694726244.234 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-14T21:17:24.235Z,1694726244.235 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-14T21:17:24.235Z,1694726244.235 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-14T21:17:24.236Z,1694726244.236 [SBIT](INFO): Initialize SBIT Component.
2023-09-14T21:17:24.236Z,1694726244.236 [SBIT](IMPORTANT): git: 2023-08-21
2023-09-14T21:17:24.236Z,1694726244.236 [SBIT](INFO): git hash: a90fa4c688a1aaa9675a08dc769bbd0e140c3a66
2023-09-14T21:17:24.237Z,1694726244.237 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-14T21:17:24.238Z,1694726244.238 [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
2023-09-14T21:17:24.239Z,1694726244.239 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2023-09-14T21:17:24.239Z,1694726244.239 [IBIT](INFO): Initialize IBIT Component.
2023-09-14T21:17:24.241Z,1694726244.241 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-14T21:17:24.241Z,1694726244.241 [logger ThreadHandler](INFO): Handler Thread ID is 933
2023-09-14T21:17:24.252Z,1694726244.252 [CBIT](DEBUG): Initialized mux pins.
2023-09-14T21:17:24.252Z,1694726244.252 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2023-09-14T21:17:24.252Z,1694726244.252 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-14T21:17:24.260Z,1694726244.260 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2023-09-14T21:17:24.272Z,1694726244.272 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2023-09-14T21:17:24.273Z,1694726244.273 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-14T21:17:24.276Z,1694726244.276 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-14T21:17:24.276Z,1694726244.276 [CBIT](DEBUG): Initializing heartbeat.
2023-09-14T21:17:24.284Z,1694726244.284 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2023-09-14T21:17:24.302Z,1694726244.302 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2023-09-14T21:17:24.325Z,1694726244.325 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938
2023-09-14T21:17:24.325Z,1694726244.325 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-14T21:17:24.329Z,1694726244.329 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940
2023-09-14T21:17:24.332Z,1694726244.332 [WetLabsBB2FL](INFO): Powering up
2023-09-14T21:17:24.334Z,1694726244.334 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942
2023-09-14T21:17:24.341Z,1694726244.341 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-09-14T21:17:24.341Z,1694726244.341 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-09-14T21:17:24.341Z,1694726244.341 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-09-14T21:17:24.341Z,1694726244.341 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-09-14T21:17:24.341Z,1694726244.341 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-09-14T21:17:24.342Z,1694726244.342 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-09-14T21:17:24.342Z,1694726244.342 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-09-14T21:17:24.342Z,1694726244.342 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-09-14T21:17:24.348Z,1694726244.348 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-14T21:17:24.348Z,1694726244.348 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-14T21:17:24.388Z,1694726244.388 [CBIT](DEBUG): Backplane powered.
2023-09-14T21:17:24.393Z,1694726244.393 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-14T21:17:24.395Z,1694726244.395 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-14T21:17:24.396Z,1694726244.396 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-14T21:17:24.396Z,1694726244.396 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-14T21:17:24.397Z,1694726244.397 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-14T21:17:24.433Z,1694726244.433 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-14T21:17:24.461Z,1694726244.461 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-09-14T21:17:24.462Z,1694726244.462 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-14T21:17:24.525Z,1694726244.525 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-14T21:17:24.526Z,1694726244.526 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-14T21:17:24.529Z,1694726244.529 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-14T21:17:24.548Z,1694726244.548 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-14T21:17:24.550Z,1694726244.550 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-14T21:17:24.573Z,1694726244.573 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-14T21:17:24.576Z,1694726244.576 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-09-14T21:17:24.591Z,1694726244.591 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Waterlinked,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,
2023-09-14T21:17:24.608Z,1694726244.608 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-14T21:17:24.708Z,1694726244.708 [Radio_Surface](INFO): Powering up
2023-09-14T21:17:24.737Z,1694726244.737 [Depth_Keller](ERROR): Pressure or depth reading out of range: 816.065491 decibar, 0.000000 m
2023-09-14T21:17:24.739Z,1694726244.739 [Power24vConverter](INFO): Powering up.
2023-09-14T21:17:24.758Z,1694726244.758 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-09-14T21:17:24.779Z,1694726244.779 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-14T21:17:24.784Z,1694726244.784 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-14T21:17:24.785Z,1694726244.785 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-14T21:17:24.792Z,1694726244.792 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-14T21:17:24.793Z,1694726244.793 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-14T21:17:24.800Z,1694726244.800 [MassServo](DEBUG): Initializing MassServo.
2023-09-14T21:17:24.801Z,1694726244.801 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-14T21:17:24.808Z,1694726244.808 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-14T21:17:24.809Z,1694726244.809 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-14T21:17:24.816Z,1694726244.816 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-14T21:17:26.944Z,1694726246.944 [WetLabsBB2FL](INFO): Powering down
2023-09-14T21:17:30.469Z,1694726250.469 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-14T21:17:51.673Z,1694726271.673 [NAL9602](INFO): Powering up NAL9602
2023-09-14T21:17:52.886Z,1694726272.886 [Waterlinked](FAULT): Could not initialize.
2023-09-14T21:17:52.886Z,1694726272.886 [Waterlinked] Communications Fault, FailCount= 1
2023-09-14T21:17:52.886Z,1694726272.886 [Waterlinked](ERROR): Communications Fault
2023-09-14T21:17:52.899Z,1694726272.899 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-14T21:17:52.903Z,1694726272.903 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-09-14T21:17:52.904Z,1694726272.904 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-14T21:17:53.356Z,1694726273.356 [Waterlinked](INFO): Powering down
2023-09-14T21:17:54.153Z,1694726274.153 [CBIT](INFO): Clearing failed state for component Waterlinked
2023-09-14T21:17:54.153Z,1694726274.153 [Waterlinked] No Fault, FailCount= 1
2023-09-14T21:18:02.582Z,1694726282.582 [NAL9602](INFO): NAL9602 initialized
2023-09-14T21:18:03.872Z,1694726283.872 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006886
CHAN A1 (24V): 0.000710
CHAN A2 (12V): -0.005497
CHAN A3 (5V): -0.002787
CHAN B0 (3.3V): -0.001391
CHAN B1 (3.15aV): -0.001384
CHAN B2 (3.15bV): -0.001797
CHAN B3 (GND): -0.000459
OPEN: 0.006416
Full Scale: +/- 1 mA
2023-09-14T21:18:22.783Z,1694726302.783 [Waterlinked](FAULT): Could not initialize.
2023-09-14T21:18:22.783Z,1694726302.783 [Waterlinked] Communications Fault, FailCount= 2
2023-09-14T21:18:22.783Z,1694726302.783 [Waterlinked](ERROR): Communications Fault
2023-09-14T21:18:22.905Z,1694726302.905 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-09-14T21:18:23.248Z,1694726303.248 [Waterlinked](INFO): Powering down
2023-09-14T21:18:24.015Z,1694726304.015 [CBIT](INFO): Clearing failed state for component Waterlinked
2023-09-14T21:18:24.015Z,1694726304.015 [Waterlinked] No Fault, FailCount= 2
2023-09-14T21:18:24.399Z,1694726304.399 [NAL9602](INFO): SBD MO Status=0, MOMSN=13549, MT Status=0, MTMSN=0
2023-09-14T21:18:24.399Z,1694726304.399 [NAL9602](INFO): No messages in MT queue
2023-09-14T21:18:47.036Z,1694726327.036 [SBIT](IMPORTANT): SBIT PASSED
2023-09-14T21:18:47.036Z,1694726327.036 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-09-14T21:18:47.435Z,1694726327.435 [MissionManager](IMPORTANT): Started mission Startup
2023-09-14T21:18:47.435Z,1694726327.435 [Startup] Running Loop=1
2023-09-14T21:18:47.435Z,1694726327.435 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-14T21:18:47.435Z,1694726327.435 [Startup:A.GoToSurface] Running Loop=1
2023-09-14T21:18:47.435Z,1694726327.435 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-14T21:18:47.436Z,1694726327.436 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-14T21:18:47.436Z,1694726327.436 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-14T21:18:47.437Z,1694726327.437 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-14T21:18:47.437Z,1694726327.437 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-14T21:18:47.437Z,1694726327.437 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-14T21:18:47.440Z,1694726327.440 [Startup:StartupSatComms] Running Loop=1
2023-09-14T21:18:47.440Z,1694726327.440 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-14T21:18:47.440Z,1694726327.440 [Startup:StartupSatComms:A] Running Loop=1
2023-09-14T21:18:47.842Z,1694726327.842 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-14T21:18:52.675Z,1694726332.675 [Waterlinked](FAULT): Could not initialize.
2023-09-14T21:18:52.675Z,1694726332.675 [Waterlinked] Communications Fault, FailCount= 3
2023-09-14T21:18:52.675Z,1694726332.675 [Waterlinked](ERROR): Communications Fault
2023-09-14T21:18:52.692Z,1694726332.692 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-09-14T21:18:52.693Z,1694726332.693 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2023-09-14T21:18:53.160Z,1694726333.160 [Waterlinked](INFO): Powering down
2023-09-14T21:18:53.182Z,1694726333.182 [CommandExec](FAULT): Scheduling is paused
2023-09-14T21:18:53.182Z,1694726333.182 [CBIT](INFO): Critical error at 20230914T211852
2023-09-14T21:18:53.182Z,1694726333.182 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-09-14T21:19:47.633Z,1694726387.633 [Startup:StartupSatComms:A](INFO): Timed out from 2023-09-14T21:18:47.4Z
2023-09-14T21:19:47.633Z,1694726387.633 [Startup:StartupSatComms:A] Stopped
2023-09-14T21:19:47.633Z,1694726387.633 [Startup:StartupSatComms:B] Running Loop=1
2023-09-14T21:19:48.034Z,1694726388.034 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-14T21:20:24.569Z,1694726424.569 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-09-14T21:20:24.569Z,1694726424.569 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-14T21:20:24.570Z,1694726424.570 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-09-14T21:20:24.570Z,1694726424.570 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-14T21:20:24.581Z,1694726424.581 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-14T21:20:24.581Z,1694726424.581 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-14T21:20:24.976Z,1694726424.976 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-14T21:20:24.977Z,1694726424.977 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-09-14T21:20:24.977Z,1694726424.977 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-14T21:20:24.977Z,1694726424.977 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-09-14T21:20:25.365Z,1694726425.365 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-14T21:20:47.754Z,1694726447.754 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-14T21:19:47.6Z
2023-09-14T21:20:47.754Z,1694726447.754 [Startup:StartupSatComms:B] Stopped
2023-09-14T21:20:47.754Z,1694726447.754 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-14T21:20:47.754Z,1694726447.754 [Startup:StartupSatComms] Stopped
2023-09-14T21:20:47.754Z,1694726447.754 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-14T21:20:47.755Z,1694726447.755 [Startup](INFO): Completed Startup
2023-09-14T21:20:47.755Z,1694726447.755 [MissionManager](INFO): Startup is completed.
2023-09-14T21:20:47.780Z,1694726447.780 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-14T21:20:47.780Z,1694726447.780 [Startup] Stopped
2023-09-14T21:20:47.780Z,1694726447.780 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-14T21:20:47.780Z,1694726447.780 [Startup:A.GoToSurface] Stopped
2023-09-14T21:20:47.780Z,1694726447.780 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-14T21:20:48.155Z,1694726448.155 [MissionManager](IMPORTANT): Started mission Default
2023-09-14T21:20:48.155Z,1694726448.155 [Default] Running Loop=1
2023-09-14T21:20:48.155Z,1694726448.155 [Default](DEBUG): Aggregate::initialize Default
2023-09-14T21:20:48.155Z,1694726448.155 [Default:B.GoToSurface] Running Loop=1
2023-09-14T21:20:48.155Z,1694726448.155 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-14T21:20:48.155Z,1694726448.155 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-14T21:20:48.156Z,1694726448.156 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-14T21:20:48.156Z,1694726448.156 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-14T21:20:48.157Z,1694726448.157 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-14T21:20:48.157Z,1694726448.157 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-14T21:20:48.157Z,1694726448.157 [Default:A.Wait] Running Loop=1
2023-09-14T21:20:48.157Z,1694726448.157 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-14T21:20:57.398Z,1694726457.398 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004758
2023-09-14T21:21:01.530Z,1694726461.530 [Default:A.Wait](INFO): Done Waiting.
2023-09-14T21:21:01.530Z,1694726461.530 [Default:A.Wait] Stopped
2023-09-14T21:21:01.531Z,1694726461.531 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-14T21:21:01.930Z,1694726461.930 [Default:CheckIn] Running Loop=1
2023-09-14T21:21:01.930Z,1694726461.930 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-14T21:21:01.930Z,1694726461.930 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-14T21:21:02.331Z,1694726462.331 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-14T21:21:52.880Z,1694726512.880 [CBIT](INFO): Clearing failed state for component Waterlinked
2023-09-14T21:21:52.880Z,1694726512.880 [Waterlinked] No Fault, FailCount= 3
2023-09-14T21:22:01.695Z,1694726521.695 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212149.00,A,3648.15964,N,12147.28377,W,0.117,323.90,140923,,,A*7E
2023-09-14T21:22:01.705Z,1694726521.705 [NAL9602](INFO): GPS fix at 20230914T212149: (36.802661, -121.788063)
2023-09-14T21:22:01.783Z,1694726521.783 [Default:CheckIn:Read_GPS] Stopped
2023-09-14T21:22:01.783Z,1694726521.783 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-14T21:22:02.130Z,1694726522.130 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-14T21:22:11.175Z,1694726531.175 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230913T230039/Courier0363.lzma
2023-09-14T21:22:12.178Z,1694726532.178 [DataOverHttps](INFO): Moved sent file to Logs/20230913T230039/Courier0363.lzma.bak
2023-09-14T21:22:12.178Z,1694726532.178 [DataOverHttps](INFO): SBD MOMSN=18769668
2023-09-14T21:22:21.594Z,1694726541.594 [Waterlinked](FAULT): Could not initialize.
2023-09-14T21:22:21.594Z,1694726541.594 [Waterlinked] Communications Fault, FailCount= 1
2023-09-14T21:22:21.594Z,1694726541.594 [Waterlinked](ERROR): Communications Fault
2023-09-14T21:22:21.639Z,1694726541.639 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-09-14T21:22:22.068Z,1694726542.068 [Waterlinked](INFO): Powering down
2023-09-14T21:22:22.837Z,1694726542.837 [CBIT](INFO): Clearing failed state for component Waterlinked
2023-09-14T21:22:22.837Z,1694726542.837 [Waterlinked] No Fault, FailCount= 1
2023-09-14T21:22:30.329Z,1694726550.329 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230914T211106/Courier0000.lzma
2023-09-14T21:22:31.018Z,1694726551.018 [DataOverHttps](INFO): Moved sent file to Logs/20230914T211106/Courier0000.lzma.bak
2023-09-14T21:22:31.018Z,1694726551.018 [DataOverHttps](INFO): SBD MOMSN=18769672
2023-09-14T21:22:34.516Z,1694726554.516 [NAL9602](INFO): Not Powering down - fast GPS
2023-09-14T21:22:48.219Z,1694726568.219 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230914T211106/Courier0004.lzma
2023-09-14T21:22:49.222Z,1694726569.222 [DataOverHttps](INFO): Moved sent file to Logs/20230914T211106/Courier0004.lzma.bak
2023-09-14T21:22:49.222Z,1694726569.222 [DataOverHttps](INFO): SBD MOMSN=18769679
2023-09-14T21:22:51.486Z,1694726571.486 [Waterlinked](FAULT): Could not initialize.
2023-09-14T21:22:51.486Z,1694726571.486 [Waterlinked] Communications Fault, FailCount= 2
2023-09-14T21:22:51.486Z,1694726571.486 [Waterlinked](ERROR): Communications Fault
2023-09-14T21:22:51.539Z,1694726571.539 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-09-14T21:22:51.956Z,1694726571.956 [Waterlinked](INFO): Powering down
2023-09-14T21:22:52.733Z,1694726572.733 [CBIT](INFO): Clearing failed state for component Waterlinked
2023-09-14T21:22:52.733Z,1694726572.733 [Waterlinked] No Fault, FailCount= 2
2023-09-14T21:23:06.300Z,1694726586.300 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20230914T211712/Courier0000.lzma
2023-09-14T21:23:07.302Z,1694726587.302 [DataOverHttps](INFO): Moved sent file to Logs/20230914T211712/Courier0000.lzma.bak
2023-09-14T21:23:07.302Z,1694726587.302 [DataOverHttps](INFO): SBD MOMSN=18769682
2023-09-14T21:23:21.453Z,1694726601.453 [Waterlinked](FAULT): Could not initialize.
2023-09-14T21:23:21.453Z,1694726601.453 [Waterlinked] Communications Fault, FailCount= 3
2023-09-14T21:23:21.454Z,1694726601.454 [Waterlinked](ERROR): Communications Fault
2023-09-14T21:23:21.474Z,1694726601.474 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-09-14T21:23:21.474Z,1694726601.474 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2023-09-14T21:23:21.868Z,1694726601.868 [Waterlinked](INFO): Powering down
2023-09-14T21:23:21.921Z,1694726601.921 [CBIT](INFO): Critical error at 20230914T212321
2023-09-14T21:23:26.156Z,1694726606.156 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20230914T211712/Courier0004.lzma
2023-09-14T21:23:27.158Z,1694726607.158 [DataOverHttps](INFO): Moved sent file to Logs/20230914T211712/Courier0004.lzma.bak
2023-09-14T21:23:27.158Z,1694726607.158 [DataOverHttps](INFO): SBD MOMSN=18769686
2023-09-14T21:23:49.103Z,1694726629.103 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-14T21:23:49.123Z,1694726629.123 [BPC1](INFO): Received data from all battery sticks.
2023-09-14T21:23:55.573Z,1694726635.573 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230913T230039/Express0364.lzma
2023-09-14T21:23:56.579Z,1694726636.579 [DataOverHttps](INFO): Moved sent file to Logs/20230913T230039/Express0364.lzma.bak
2023-09-14T21:23:56.579Z,1694726636.579 [DataOverHttps](INFO): SBD MOMSN=18769693
2023-09-14T21:24:18.943Z,1694726658.943 [DataOverHttps](INFO): Sending 951 bytes from file Logs/20230914T211106/Express0001.lzma
2023-09-14T21:24:19.941Z,1694726659.941 [DataOverHttps](INFO): Moved sent file to Logs/20230914T211106/Express0001.lzma.bak
2023-09-14T21:24:19.942Z,1694726659.942 [DataOverHttps](INFO): SBD MOMSN=18769695
2023-09-14T21:24:39.515Z,1694726679.515 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230914T211106/Express0005.lzma
2023-09-14T21:24:40.518Z,1694726680.518 [DataOverHttps](INFO): Moved sent file to Logs/20230914T211106/Express0005.lzma.bak
2023-09-14T21:24:40.518Z,1694726680.518 [DataOverHttps](INFO): SBD MOMSN=18769723
2023-09-14T21:24:58.497Z,1694726698.497 [DataOverHttps](INFO): Sending 687 bytes from file Logs/20230914T211712/Express0001.lzma
2023-09-14T21:24:59.498Z,1694726699.498 [DataOverHttps](INFO): Moved sent file to Logs/20230914T211712/Express0001.lzma.bak
2023-09-14T21:24:59.498Z,1694726699.498 [DataOverHttps](INFO): SBD MOMSN=18769725
2023-09-14T21:25:17.481Z,1694726717.481 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20230914T211712/Express0005.lzma
2023-09-14T21:25:18.482Z,1694726718.482 [DataOverHttps](INFO): Moved sent file to Logs/20230914T211712/Express0005.lzma.bak
2023-09-14T21:25:18.482Z,1694726718.482 [DataOverHttps](INFO): SBD MOMSN=18769741
2023-09-14T21:25:22.042Z,1694726722.042 [Default:CheckIn:Read_Iridium] Stopped
2023-09-14T21:25:22.042Z,1694726722.042 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-14T21:25:22.042Z,1694726722.042 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-14T21:26:21.615Z,1694726781.615 [CBIT](INFO): Clearing failed state for component Waterlinked
2023-09-14T21:26:21.615Z,1694726781.615 [Waterlinked] No Fault, FailCount= 3
2023-09-14T21:26:50.162Z,1694726810.162 [Waterlinked](FAULT): Could not initialize.
2023-09-14T21:26:50.163Z,1694726810.163 [Waterlinked] Communications Fault, FailCount= 1
2023-09-14T21:26:50.163Z,1694726810.163 [Waterlinked](ERROR): Communications Fault
2023-09-14T21:26:50.198Z,1694726810.198 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-09-14T21:26:50.609Z,1694726810.609 [Waterlinked](INFO): Powering down
2023-09-14T21:26:51.396Z,1694726811.396 [CBIT](INFO): Clearing failed state for component Waterlinked
2023-09-14T21:26:51.397Z,1694726811.397 [Waterlinked] No Fault, FailCount= 1
2023-09-14T21:27:20.046Z,1694726840.046 [Waterlinked](FAULT): Could not initialize.
2023-09-14T21:27:20.046Z,1694726840.046 [Waterlinked] Communications Fault, FailCount= 2
2023-09-14T21:27:20.046Z,1694726840.046 [Waterlinked](ERROR): Communications Fault
2023-09-14T21:27:20.090Z,1694726840.090 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-09-14T21:27:20.516Z,1694726840.516 [Waterlinked](INFO): Powering down
2023-09-14T21:27:21.318Z,1694726841.318 [CBIT](INFO): Clearing failed state for component Waterlinked
2023-09-14T21:27:21.318Z,1694726841.318 [Waterlinked] No Fault, FailCount= 2
2023-09-14T21:27:49.942Z,1694726869.942 [Waterlinked](FAULT): Could not initialize.
2023-09-14T21:27:49.942Z,1694726869.942 [Waterlinked] Communications Fault, FailCount= 3
2023-09-14T21:27:49.942Z,1694726869.942 [Waterlinked](ERROR): Communications Fault
2023-09-14T21:27:49.967Z,1694726869.967 [CBIT](ERROR): Communications Fault in component: Waterlinked
2023-09-14T21:27:49.967Z,1694726869.967 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2023-09-14T21:27:50.416Z,1694726870.416 [Waterlinked](INFO): Powering down
2023-09-14T21:27:50.452Z,1694726870.452 [CBIT](INFO): Critical error at 20230914T212749
2023-09-14T21:27:54.384Z,1694726874.384 [Power24vConverter](INFO): Powering down.
2023-09-14T21:27:59.052Z,1694726879.052 [CommandExec](IMPORTANT): got command configSet Waterlinked.loadAtStartup 0 bool persist
2023-09-14T21:27:59.052Z,1694726879.052 [CommandExec](IMPORTANT): configSet Waterlinked.loadAtStartup requires a restart to take effect.
2023-09-14T21:27:59.053Z,1694726879.053 [CommandExec](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown.
2023-09-14T21:27:59.053Z,1694726879.053 [CommandExec](IMPORTANT): got command restart system
2023-09-14T21:28:01.320Z,1694726881.320 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:01.320Z,1694726881.320 [CommandExec](INFO): Uninitializing the command executive.
2023-09-14T21:28:01.320Z,1694726881.320 [CommandExec](INFO): Uninitializing the command scheduler.
2023-09-14T21:28:01.321Z,1694726881.321 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:01.400Z,1694726881.400 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-09-14T21:28:01.400Z,1694726881.400 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-09-14T21:28:01.400Z,1694726881.400 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:01.401Z,1694726881.401 [NavChartDb](INFO): Join timeout helper Thread ID is 1017
2023-09-14T21:28:01.508Z,1694726881.508 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:01.508Z,1694726881.508 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:01.509Z,1694726881.509 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-09-14T21:28:01.509Z,1694726881.509 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:01.510Z,1694726881.510 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1018
2023-09-14T21:28:01.712Z,1694726881.712 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:01.712Z,1694726881.712 [WetLabsBB2FL](INFO): Powering down
2023-09-14T21:28:01.713Z,1694726881.713 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:01.724Z,1694726881.724 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-09-14T21:28:01.724Z,1694726881.724 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:01.724Z,1694726881.724 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1019
2023-09-14T21:28:02.088Z,1694726882.088 [CTD_Seabird](INFO): Powering down
2023-09-14T21:28:02.136Z,1694726882.136 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:02.136Z,1694726882.136 [CTD_Seabird](INFO): Powering down
2023-09-14T21:28:02.160Z,1694726882.160 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:02.180Z,1694726882.180 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-09-14T21:28:02.180Z,1694726882.180 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:02.180Z,1694726882.180 [Radio_Surface](INFO): Join timeout helper Thread ID is 1020
2023-09-14T21:28:02.252Z,1694726882.252 [Radio_Surface](INFO): Powering down
2023-09-14T21:28:02.253Z,1694726882.253 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:02.253Z,1694726882.253 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:02.254Z,1694726882.254 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-09-14T21:28:02.254Z,1694726882.254 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:02.254Z,1694726882.254 [Onboard](INFO): Join timeout helper Thread ID is 1021
2023-09-14T21:28:04.816Z,1694726884.816 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:04.816Z,1694726884.816 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:04.833Z,1694726884.833 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-09-14T21:28:04.833Z,1694726884.833 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:04.833Z,1694726884.833 [DataOverHttps](INFO): Join timeout helper Thread ID is 1022
2023-09-14T21:28:05.696Z,1694726885.696 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:05.700Z,1694726885.700 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:05.702Z,1694726885.702 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-09-14T21:28:05.702Z,1694726885.702 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:05.702Z,1694726885.702 [BackseatComponent](INFO): Join timeout helper Thread ID is 1023
2023-09-14T21:28:05.904Z,1694726885.904 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:05.905Z,1694726885.905 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:05.924Z,1694726885.924 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-09-14T21:28:05.925Z,1694726885.925 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:05.925Z,1694726885.925 [logger](INFO): Join timeout helper Thread ID is 1024
2023-09-14T21:28:05.936Z,1694726885.936 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:05.936Z,1694726885.936 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:05.944Z,1694726885.944 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-09-14T21:28:05.945Z,1694726885.945 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:05.945Z,1694726885.945 [CommandLine](INFO): Join timeout helper Thread ID is 1025
2023-09-14T21:28:06.004Z,1694726886.004 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:06.004Z,1694726886.004 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:06.024Z,1694726886.024 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-09-14T21:28:06.025Z,1694726886.025 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:06.025Z,1694726886.025 [CommandExec](INFO): Join timeout helper Thread ID is 1026
2023-09-14T21:28:06.026Z,1694726886.026 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-09-14T21:28:06.026Z,1694726886.026 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-14T21:28:06.027Z,1694726886.027 [controlThread](INFO): Join timeout helper Thread ID is 1027
2023-09-14T21:28:06.152Z,1694726886.152 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-14T21:28:06.152Z,1694726886.152 [controlThread](DEBUG): Uninitializing ControlThread
2023-09-14T21:28:06.153Z,1694726886.153 [AHRS_M2](INFO): Powering down
2023-09-14T21:28:06.225Z,1694726886.225 [NAL9602](INFO): Powering down
2023-09-14T21:28:06.226Z,1694726886.226 [Waterlinked](INFO): Powering down
2023-09-14T21:28:06.228Z,1694726886.228 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-09-14T21:28:06.229Z,1694726886.229 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-09-14T21:28:06.229Z,1694726886.229 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-09-14T21:28:06.230Z,1694726886.230 [MissionManager](INFO): Uninitializing Mission Default
2023-09-14T21:28:06.230Z,1694726886.230 [Default] Stopped
2023-09-14T21:28:06.230Z,1694