2024-08-13T18:04:16.034Z,1723572256.034 [Supervisor](DEBUG): Initializing supervisor.
2024-08-13T18:04:16.038Z,1723572256.038 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-08-13T18:04:16.038Z,1723572256.038 [SyncHandler](INFO): Protected caller Thread ID is 1153
2024-08-13T18:04:16.039Z,1723572256.039 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-08-13T18:04:16.040Z,1723572256.040 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-08-13T18:04:16.040Z,1723572256.040 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1154
2024-08-13T18:04:16.044Z,1723572256.044 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-08-13T18:04:16.061Z,1723572256.061 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-08-13T18:04:16.062Z,1723572256.062 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-08-13T18:04:16.062Z,1723572256.062 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1155
2024-08-13T18:04:16.066Z,1723572256.066 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-08-13T18:04:16.067Z,1723572256.067 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-08-13T18:04:16.068Z,1723572256.068 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1156
2024-08-13T18:04:16.070Z,1723572256.070 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-08-13T18:04:16.070Z,1723572256.070 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-08-13T18:04:16.071Z,1723572256.071 [logger ThreadHandler](INFO): Protected caller Thread ID is 1157
2024-08-13T18:04:16.074Z,1723572256.074 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-08-13T18:04:16.075Z,1723572256.075 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-08-13T18:04:16.076Z,1723572256.076 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-08-13T18:04:16.481Z,1723572256.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-08-13T18:04:16.482Z,1723572256.482 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-08-13T18:04:17.244Z,1723572257.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-08-13T18:04:17.244Z,1723572257.244 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-08-13T18:04:17.365Z,1723572257.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-08-13T18:04:17.365Z,1723572257.365 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-08-13T18:04:17.929Z,1723572257.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-08-13T18:04:17.929Z,1723572257.929 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-08-13T18:04:18.038Z,1723572258.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-08-13T18:04:18.038Z,1723572258.038 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-08-13T18:04:18.132Z,1723572258.132 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-08-13T18:04:18.556Z,1723572258.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-08-13T18:04:18.556Z,1723572258.556 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-08-13T18:04:18.792Z,1723572258.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-08-13T18:04:18.793Z,1723572258.793 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-08-13T18:04:18.944Z,1723572258.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-08-13T18:04:18.944Z,1723572258.944 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-08-13T18:04:19.200Z,1723572259.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-08-13T18:04:19.200Z,1723572259.200 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-08-13T18:04:19.771Z,1723572259.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-08-13T18:04:19.771Z,1723572259.771 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-08-13T18:04:20.170Z,1723572260.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-08-13T18:04:20.170Z,1723572260.170 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-08-13T18:04:20.270Z,1723572260.270 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-08-13T18:04:20.915Z,1723572260.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-08-13T18:04:20.915Z,1723572260.915 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-08-13T18:04:21.302Z,1723572261.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-08-13T18:04:21.303Z,1723572261.303 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-08-13T18:04:21.616Z,1723572261.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-08-13T18:04:21.618Z,1723572261.618 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2024-08-13T18:04:21.619Z,1723572261.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2024-08-13T18:04:21.739Z,1723572261.739 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2024-08-13T18:04:21.902Z,1723572261.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2024-08-13T18:04:21.000Z,1723572262.000 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2024-08-13T18:04:22.095Z,1723572262.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2024-08-13T18:04:22.216Z,1723572262.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2024-08-13T18:04:22.313Z,1723572262.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2024-08-13T18:04:22.430Z,1723572262.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2024-08-13T18:04:22.705Z,1723572262.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-08-13T18:04:22.705Z,1723572262.705 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2024-08-13T18:04:22.851Z,1723572262.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2024-08-13T18:04:22.956Z,1723572262.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2024-08-13T18:04:23.061Z,1723572263.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2024-08-13T18:04:23.179Z,1723572263.179 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-08-13T18:04:23.190Z,1723572263.190 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-08-13T18:04:23.296Z,1723572263.296 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-08-13T18:04:23.297Z,1723572263.297 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-08-13T18:04:23.307Z,1723572263.307 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-08-13T18:04:23.307Z,1723572263.307 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-08-13T18:04:23.355Z,1723572263.355 [DepthRateCalculator] Loaded
2024-08-13T18:04:23.355Z,1723572263.355 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-08-13T18:04:23.360Z,1723572263.360 [PitchRateCalculator] Loaded
2024-08-13T18:04:23.361Z,1723572263.361 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-08-13T18:04:23.371Z,1723572263.371 [SpeedCalculator] Loaded
2024-08-13T18:04:23.371Z,1723572263.371 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-08-13T18:04:23.376Z,1723572263.376 [YawRateCalculator] Loaded
2024-08-13T18:04:23.376Z,1723572263.376 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-08-13T18:04:23.397Z,1723572263.397 [ElevatorOffsetCalculator] Loaded
2024-08-13T18:04:23.397Z,1723572263.397 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-08-13T18:04:23.398Z,1723572263.398 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-08-13T18:04:23.398Z,1723572263.398 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-08-13T18:04:23.447Z,1723572263.447 [VerticalControl](DEBUG): Construct VerticalControl.
2024-08-13T18:04:23.501Z,1723572263.501 [VerticalControl] Loaded
2024-08-13T18:04:23.501Z,1723572263.501 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-08-13T18:04:23.504Z,1723572263.504 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-08-13T18:04:23.549Z,1723572263.549 [HorizontalControl] Loaded
2024-08-13T18:04:23.549Z,1723572263.549 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-08-13T18:04:23.552Z,1723572263.552 [SpeedControl](DEBUG): Construct SpeedControl.
2024-08-13T18:04:23.554Z,1723572263.554 [SpeedControl] Loaded
2024-08-13T18:04:23.555Z,1723572263.555 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-08-13T18:04:23.557Z,1723572263.557 [LoopControl](DEBUG): Construct LoopControl.
2024-08-13T18:04:23.558Z,1723572263.558 [LoopControl] Loaded
2024-08-13T18:04:23.558Z,1723572263.558 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-08-13T18:04:23.558Z,1723572263.558 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-08-13T18:04:23.559Z,1723572263.559 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-08-13T18:04:23.666Z,1723572263.666 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-08-13T18:04:23.666Z,1723572263.666 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-08-13T18:04:23.679Z,1723572263.679 [NavChart] Loaded
2024-08-13T18:04:23.680Z,1723572263.680 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-08-13T18:04:23.685Z,1723572263.685 [UniversalFixResidualReporter] Loaded
2024-08-13T18:04:23.685Z,1723572263.685 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-08-13T18:04:23.686Z,1723572263.686 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-08-13T18:04:23.686Z,1723572263.686 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-08-13T18:04:23.851Z,1723572263.851 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-08-13T18:04:23.852Z,1723572263.852 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-08-13T18:04:24.315Z,1723572264.315 [AHRS_M2] Loaded
2024-08-13T18:04:24.315Z,1723572264.315 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-08-13T18:04:24.353Z,1723572264.353 [BackseatComponent] Loaded
2024-08-13T18:04:24.354Z,1723572264.354 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-08-13T18:04:24.355Z,1723572264.355 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409384E0
2024-08-13T18:04:24.355Z,1723572264.355 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1242
2024-08-13T18:04:24.358Z,1723572264.358 [LcmUniversalReporter] Loaded
2024-08-13T18:04:24.358Z,1723572264.358 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-08-13T18:04:25.174Z,1723572265.174 [BPC1] Loaded
2024-08-13T18:04:25.175Z,1723572265.175 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-08-13T18:04:25.263Z,1723572265.263 [DATMMP] Loaded
2024-08-13T18:04:25.271Z,1723572265.271 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-08-13T18:04:25.281Z,1723572265.281 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409694E0
2024-08-13T18:04:25.282Z,1723572265.282 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1243
2024-08-13T18:04:25.456Z,1723572265.456 [DataOverHttps] Loaded
2024-08-13T18:04:25.456Z,1723572265.456 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-08-13T18:04:25.458Z,1723572265.458 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2024-08-13T18:04:25.458Z,1723572265.458 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1244
2024-08-13T18:04:25.585Z,1723572265.585 [Depth_Keller] Loaded
2024-08-13T18:04:25.585Z,1723572265.585 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-08-13T18:04:25.717Z,1723572265.717 [DVL_micro] Loaded
2024-08-13T18:04:25.717Z,1723572265.717 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-08-13T18:04:26.017Z,1723572266.017 [NAL9602] Loaded
2024-08-13T18:04:26.017Z,1723572266.017 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-08-13T18:04:26.047Z,1723572266.047 [Onboard] Loaded
2024-08-13T18:04:26.047Z,1723572266.047 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-08-13T18:04:26.048Z,1723572266.048 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409C94E0
2024-08-13T18:04:26.048Z,1723572266.048 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1245
2024-08-13T18:04:26.062Z,1723572266.062 [Power24vConverter] Loaded
2024-08-13T18:04:26.062Z,1723572266.062 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-08-13T18:04:26.082Z,1723572266.082 [Radio_Surface] Loaded
2024-08-13T18:04:26.082Z,1723572266.082 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-08-13T18:04:26.083Z,1723572266.083 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F94E0
2024-08-13T18:04:26.084Z,1723572266.084 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1246
2024-08-13T18:04:26.099Z,1723572266.099 [Sonardyne_Nano] Loaded
2024-08-13T18:04:26.099Z,1723572266.099 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-08-13T18:04:26.100Z,1723572266.100 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-08-13T18:04:26.100Z,1723572266.100 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-08-13T18:04:26.227Z,1723572266.227 [BuoyancyServo] Loaded
2024-08-13T18:04:26.227Z,1723572266.227 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-08-13T18:04:26.249Z,1723572266.249 [ElevatorServo] Loaded
2024-08-13T18:04:26.249Z,1723572266.249 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-08-13T18:04:26.271Z,1723572266.271 [MassServo] Loaded
2024-08-13T18:04:26.271Z,1723572266.271 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-08-13T18:04:26.291Z,1723572266.291 [RudderServo] Loaded
2024-08-13T18:04:26.292Z,1723572266.292 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-08-13T18:04:26.307Z,1723572266.307 [ThrusterHE] Loaded
2024-08-13T18:04:26.308Z,1723572266.308 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-08-13T18:04:26.308Z,1723572266.308 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-08-13T18:04:26.308Z,1723572266.308 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-08-13T18:04:26.375Z,1723572266.375 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-08-13T18:04:26.375Z,1723572266.375 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-08-13T18:04:26.400Z,1723572266.400 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-08-13T18:04:26.401Z,1723572266.401 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-08-13T18:04:26.722Z,1723572266.722 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-08-13T18:04:26.722Z,1723572266.722 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-08-13T18:04:26.892Z,1723572266.892 [CTD_Seabird] Loaded
2024-08-13T18:04:26.892Z,1723572266.892 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-08-13T18:04:26.894Z,1723572266.894 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BB34E0
2024-08-13T18:04:26.894Z,1723572266.894 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1247
2024-08-13T18:04:26.930Z,1723572266.930 [ESPComponent] Loaded
2024-08-13T18:04:26.930Z,1723572266.930 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-08-13T18:04:26.951Z,1723572266.951 [PAR_Licor] Loaded
2024-08-13T18:04:26.952Z,1723572266.952 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-08-13T18:04:26.995Z,1723572266.995 [WetLabsBB2FL] Loaded
2024-08-13T18:04:26.995Z,1723572266.995 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-08-13T18:04:26.996Z,1723572266.996 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BE34E0
2024-08-13T18:04:26.997Z,1723572266.997 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1248
2024-08-13T18:04:26.997Z,1723572266.997 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-08-13T18:04:26.998Z,1723572266.998 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-08-13T18:04:27.132Z,1723572267.132 [SBIT](DEBUG): Construct Startup Built In Test.
2024-08-13T18:04:27.140Z,1723572267.140 [SBIT] Loaded
2024-08-13T18:04:27.141Z,1723572267.141 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-08-13T18:04:27.144Z,1723572267.144 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-08-13T18:04:27.156Z,1723572267.156 [IBIT] Loaded
2024-08-13T18:04:27.156Z,1723572267.156 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-08-13T18:04:27.162Z,1723572267.162 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-08-13T18:04:27.265Z,1723572267.265 [CBIT] Loaded
2024-08-13T18:04:27.265Z,1723572267.265 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-08-13T18:04:27.266Z,1723572267.266 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-08-13T18:04:27.272Z,1723572267.272 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-08-13T18:04:27.275Z,1723572267.275 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-08-13T18:04:27.286Z,1723572267.286 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-08-13T18:04:27.287Z,1723572267.287 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-08-13T18:04:27.287Z,1723572267.287 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1249
2024-08-13T18:04:27.292Z,1723572267.292 [Supervisor](INFO): Main Thread ID is 7713
2024-08-13T18:04:27.292Z,1723572267.292 [Supervisor](DEBUG): Running supervisor.
2024-08-13T18:04:27.292Z,1723572267.292 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1250
2024-08-13T18:04:27.293Z,1723572267.293 [CommandExec](INFO): Initializing the command executive.
2024-08-13T18:04:27.294Z,1723572267.294 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1251
2024-08-13T18:04:27.296Z,1723572267.296 [controlThread ThreadHandler](INFO): Handler Thread ID is 1252
2024-08-13T18:04:27.297Z,1723572267.297 [controlThread](DEBUG): Initializing ControlThread
2024-08-13T18:04:27.298Z,1723572267.298 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-08-13T18:04:27.298Z,1723572267.298 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-08-13T18:04:27.298Z,1723572267.298 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-08-13T18:04:27.299Z,1723572267.299 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-08-13T18:04:27.299Z,1723572267.299 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-08-13T18:04:27.300Z,1723572267.300 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-08-13T18:04:27.301Z,1723572267.301 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-08-13T18:04:27.302Z,1723572267.302 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-08-13T18:04:27.302Z,1723572267.302 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-08-13T18:04:27.303Z,1723572267.303 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-08-13T18:04:27.304Z,1723572267.304 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-08-13T18:04:27.310Z,1723572267.310 [SBIT](INFO): Initialize SBIT Component.
2024-08-13T18:04:27.310Z,1723572267.310 [SBIT](IMPORTANT): git: 2024-08-12-95-g598b95f60
2024-08-13T18:04:27.310Z,1723572267.310 [SBIT](INFO): git hash: 598b95f6033d24136933a3d5d1ddac5d4570ac95
2024-08-13T18:04:27.310Z,1723572267.310 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-08-13T18:04:27.312Z,1723572267.312 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-08-13T18:04:27.313Z,1723572267.313 [SBIT](INFO): Beginning SBIT in 68.000000 seconds.
2024-08-13T18:04:27.313Z,1723572267.313 [IBIT](INFO): Initialize IBIT Component.
2024-08-13T18:04:27.314Z,1723572267.314 [CBIT](DEBUG): Initialize CBIT Component.
2024-08-13T18:04:27.315Z,1723572267.315 [logger ThreadHandler](INFO): Handler Thread ID is 1253
2024-08-13T18:04:27.325Z,1723572267.325 [CBIT](DEBUG): Initialized mux pins.
2024-08-13T18:04:27.325Z,1723572267.325 [CBIT](DEBUG): Initializing the watchdog timer.
2024-08-13T18:04:27.334Z,1723572267.334 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1254
2024-08-13T18:04:27.346Z,1723572267.346 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1255
2024-08-13T18:04:27.346Z,1723572267.346 [DATMMP](INFO): Start
2024-08-13T18:04:27.346Z,1723572267.346 [DATMMP](INFO): Powering up
2024-08-13T18:04:27.347Z,1723572267.347 [DATMMP](DEBUG): Initializing DATMMP.
2024-08-13T18:04:27.349Z,1723572267.349 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-08-13T18:04:27.350Z,1723572267.350 [CBIT](DEBUG): Initializing heartbeat.
2024-08-13T18:04:27.351Z,1723572267.351 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1257
2024-08-13T18:04:27.353Z,1723572267.353 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-08-13T18:04:27.362Z,1723572267.362 [Onboard ThreadHandler](INFO): Handler Thread ID is 1258
2024-08-13T18:04:27.383Z,1723572267.383 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1259
2024-08-13T18:04:27.398Z,1723572267.398 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1260
2024-08-13T18:04:27.399Z,1723572267.399 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-08-13T18:04:27.402Z,1723572267.402 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1262
2024-08-13T18:04:27.406Z,1723572267.406 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:04:27.407Z,1723572267.407 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1264
2024-08-13T18:04:27.409Z,1723572267.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000
2024-08-13T18:04:27.409Z,1723572267.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000
2024-08-13T18:04:27.410Z,1723572267.410 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000
2024-08-13T18:04:27.410Z,1723572267.410 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000
2024-08-13T18:04:27.410Z,1723572267.410 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000
2024-08-13T18:04:27.410Z,1723572267.410 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000
2024-08-13T18:04:27.421Z,1723572267.421 [CBIT](DEBUG): Deactivating GF circuits.
2024-08-13T18:04:27.421Z,1723572267.421 [CBIT](DEBUG): Deactivating emergency mode.
2024-08-13T18:04:27.461Z,1723572267.461 [CBIT](DEBUG): Backplane powered.
2024-08-13T18:04:27.462Z,1723572267.462 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-08-13T18:04:27.463Z,1723572267.463 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-08-13T18:04:27.463Z,1723572267.463 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-08-13T18:04:27.464Z,1723572267.464 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-08-13T18:04:27.504Z,1723572267.504 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-08-13T18:04:27.508Z,1723572267.508 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-13T18:04:27.556Z,1723572267.556 [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-08-13T18:04:27.557Z,1723572267.557 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-08-13T18:04:27.557Z,1723572267.557 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-08-13T18:04:27.559Z,1723572267.559 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-08-13T18:04:27.597Z,1723572267.597 [DATMMP](INFO): Starting
2024-08-13T18:04:27.789Z,1723572267.789 [Radio_Surface](INFO): Powering up
2024-08-13T18:04:27.827Z,1723572267.827 [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-08-13T18:04:27.849Z,1723572267.849 [DATMMP](INFO): Starting
2024-08-13T18:04:27.850Z,1723572267.850 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-08-13T18:04:27.851Z,1723572267.851 [Default:A.Wait](DEBUG): Construct Wait.
2024-08-13T18:04:27.853Z,1723572267.853 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-13T18:04:27.894Z,1723572267.894 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-08-13T18:04:27.896Z,1723572267.896 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-08-13T18:04:27.918Z,1723572267.918 [Default:E.Execute](DEBUG): Construct Execute.
2024-08-13T18:04:27.921Z,1723572267.921 [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-08-13T18:04:27.933Z,1723572267.933 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-13T18:04:27.946Z,1723572267.946 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-08-13T18:04:28.023Z,1723572268.023 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1829.799438 decibar, 0.000000 m
2024-08-13T18:04:28.030Z,1723572268.030 [Power24vConverter](INFO): Powering up.
2024-08-13T18:04:28.031Z,1723572268.031 [Sonardyne_Nano](INFO): Initializing.
2024-08-13T18:04:28.069Z,1723572268.069 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-08-13T18:04:28.095Z,1723572268.095 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-08-13T18:04:28.101Z,1723572268.101 [DATMMP](INFO): Starting
2024-08-13T18:04:28.102Z,1723572268.102 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-08-13T18:04:28.103Z,1723572268.103 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-13T18:04:28.110Z,1723572268.110 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-13T18:04:28.110Z,1723572268.110 [MassServo](DEBUG): Initializing EZServoServo.
2024-08-13T18:04:28.118Z,1723572268.118 [MassServo](DEBUG): Initializing MassServo.
2024-08-13T18:04:28.118Z,1723572268.118 [RudderServo](DEBUG): Initializing EZServoServo.
2024-08-13T18:04:28.126Z,1723572268.126 [RudderServo](DEBUG): Initializing RudderServo.
2024-08-13T18:04:28.127Z,1723572268.127 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-08-13T18:04:28.134Z,1723572268.134 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-08-13T18:04:28.354Z,1723572268.354 [DATMMP](INFO): Starting
2024-08-13T18:04:28.605Z,1723572268.605 [DATMMP](INFO): Starting
2024-08-13T18:04:28.857Z,1723572268.857 [DATMMP](INFO): Starting
2024-08-13T18:04:29.109Z,1723572269.109 [DATMMP](INFO): Starting
2024-08-13T18:04:29.361Z,1723572269.361 [DATMMP](INFO): Starting
2024-08-13T18:04:29.613Z,1723572269.613 [DATMMP](INFO): Starting
2024-08-13T18:04:29.865Z,1723572269.865 [DATMMP](INFO): Starting
2024-08-13T18:04:29.964Z,1723572269.964 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-08-13T18:04:30.117Z,1723572270.117 [DATMMP](INFO): Starting
2024-08-13T18:04:30.369Z,1723572270.369 [DATMMP](INFO): Starting
2024-08-13T18:04:30.621Z,1723572270.621 [DATMMP](INFO): Starting
2024-08-13T18:04:30.875Z,1723572270.875 [DATMMP](INFO): Starting
2024-08-13T18:04:31.125Z,1723572271.125 [DATMMP](INFO): Starting
2024-08-13T18:04:31.263Z,1723572271.263 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2024-08-13T18:04:31.378Z,1723572271.378 [DATMMP](INFO): Starting
2024-08-13T18:04:31.629Z,1723572271.629 [DATMMP](INFO): Starting
2024-08-13T18:04:31.881Z,1723572271.881 [DATMMP](INFO): Starting
2024-08-13T18:04:32.134Z,1723572272.134 [DATMMP](INFO): Starting
2024-08-13T18:04:32.385Z,1723572272.385 [DATMMP](INFO): Starting
2024-08-13T18:04:32.638Z,1723572272.638 [DATMMP](INFO): Starting
2024-08-13T18:04:32.697Z,1723572272.697 [WetLabsBB2FL] Communications Fault, FailCount= 1
2024-08-13T18:04:32.697Z,1723572272.697 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:04:32.889Z,1723572272.889 [DATMMP](INFO): Starting
2024-08-13T18:04:33.045Z,1723572273.045 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:04:33.142Z,1723572273.142 [DATMMP](INFO): Starting
2024-08-13T18:04:33.197Z,1723572273.197 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:04:33.393Z,1723572273.393 [DATMMP](INFO): Starting
2024-08-13T18:04:33.645Z,1723572273.645 [DATMMP](INFO): Starting
2024-08-13T18:04:33.703Z,1723572273.703 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-08-13T18:04:33.897Z,1723572273.897 [DATMMP](INFO): Starting
2024-08-13T18:04:34.149Z,1723572274.149 [DATMMP](INFO): Starting
2024-08-13T18:04:34.265Z,1723572274.265 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2024-08-13T18:04:34.265Z,1723572274.265 [WetLabsBB2FL] No Fault, FailCount= 1
2024-08-13T18:04:34.401Z,1723572274.401 [DATMMP](INFO): Starting
2024-08-13T18:04:34.411Z,1723572274.411 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:04:34.438Z,1723572274.438 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-08-13T18:04:34.653Z,1723572274.653 [DATMMP](INFO): Starting
2024-08-13T18:04:34.905Z,1723572274.905 [DATMMP](INFO): Starting
2024-08-13T18:04:35.157Z,1723572275.157 [DATMMP](INFO): Starting
2024-08-13T18:04:35.409Z,1723572275.409 [DATMMP](INFO): Starting
2024-08-13T18:04:35.661Z,1723572275.661 [DATMMP](INFO): Starting
2024-08-13T18:04:35.913Z,1723572275.913 [DATMMP](INFO): Starting
2024-08-13T18:04:36.166Z,1723572276.166 [DATMMP](INFO): Starting
2024-08-13T18:04:36.417Z,1723572276.417 [DATMMP](INFO): Starting
2024-08-13T18:04:36.670Z,1723572276.670 [DATMMP](INFO): Starting
2024-08-13T18:04:36.921Z,1723572276.921 [DATMMP](INFO): Starting
2024-08-13T18:04:37.174Z,1723572277.174 [DATMMP](INFO): Starting
2024-08-13T18:04:37.426Z,1723572277.426 [DATMMP](INFO): Starting
2024-08-13T18:04:37.678Z,1723572277.678 [DATMMP](INFO): Starting
2024-08-13T18:04:37.929Z,1723572277.929 [DATMMP](INFO): Starting
2024-08-13T18:04:38.182Z,1723572278.182 [DATMMP](INFO): Starting
2024-08-13T18:04:38.433Z,1723572278.433 [DATMMP](INFO): Starting
2024-08-13T18:04:38.685Z,1723572278.685 [DATMMP](INFO): Starting
2024-08-13T18:04:38.938Z,1723572278.938 [DATMMP](INFO): Starting
2024-08-13T18:04:39.189Z,1723572279.189 [DATMMP](INFO): Starting
2024-08-13T18:04:39.441Z,1723572279.441 [DATMMP](INFO): Starting
2024-08-13T18:04:39.442Z,1723572279.442 [DATMMP](INFO): DAT read:
2024-08-13T18:04:39.443Z,1723572279.443 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-08-13T18:04:39.694Z,1723572279.694 [DATMMP](INFO): Starting
2024-08-13T18:04:39.702Z,1723572279.702 [WetLabsBB2FL] Communications Fault, FailCount= 2
2024-08-13T18:04:39.702Z,1723572279.702 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:04:39.777Z,1723572279.777 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:04:39.945Z,1723572279.945 [DATMMP](INFO): Starting
2024-08-13T18:04:40.198Z,1723572280.198 [DATMMP](INFO): Starting
2024-08-13T18:04:40.202Z,1723572280.202 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:04:40.451Z,1723572280.451 [DATMMP](INFO): Starting
2024-08-13T18:04:40.701Z,1723572280.701 [DATMMP](INFO): Starting
2024-08-13T18:04:40.953Z,1723572280.953 [DATMMP](INFO): Starting
2024-08-13T18:04:40.954Z,1723572280.954 [DATMMP](INFO): DAT read: MF Frequency Band
2024-08-13T18:04:40.955Z,1723572280.955 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-08-13T18:04:40.956Z,1723572280.956 [DATMMP](INFO): DAT read: Jul 13 2000 18:04:36
2024-08-13T18:04:41.205Z,1723572281.205 [DATMMP](INFO): Starting
2024-08-13T18:04:41.381Z,1723572281.381 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2024-08-13T18:04:41.382Z,1723572281.382 [WetLabsBB2FL] No Fault, FailCount= 2
2024-08-13T18:04:41.422Z,1723572281.422 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:04:41.458Z,1723572281.458 [DATMMP](INFO): Starting
2024-08-13T18:04:41.709Z,1723572281.709 [DATMMP](INFO): Starting
2024-08-13T18:04:41.961Z,1723572281.961 [DATMMP](INFO): Starting
2024-08-13T18:04:42.213Z,1723572282.213 [DATMMP](INFO): Starting
2024-08-13T18:04:42.214Z,1723572282.214 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-08-13T18:04:42.215Z,1723572282.215 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-08-13T18:04:42.216Z,1723572282.216 [DATMMP](INFO): commRate: 800
2024-08-13T18:04:42.465Z,1723572282.465 [DATMMP](INFO): Starting
2024-08-13T18:04:42.718Z,1723572282.718 [DATMMP](INFO): Starting
2024-08-13T18:04:42.971Z,1723572282.971 [DATMMP](INFO): Starting
2024-08-13T18:04:43.221Z,1723572283.221 [DATMMP](INFO): Starting
2024-08-13T18:04:43.473Z,1723572283.473 [DATMMP](INFO): Starting
2024-08-13T18:04:43.725Z,1723572283.725 [DATMMP](INFO): Starting
2024-08-13T18:04:43.977Z,1723572283.977 [DATMMP](INFO): Starting
2024-08-13T18:04:44.229Z,1723572284.229 [DATMMP](INFO): Starting
2024-08-13T18:04:44.281Z,1723572284.281 [DATMMP](INFO): entering command mode
2024-08-13T18:04:44.481Z,1723572284.481 [DATMMP](INFO): Starting
2024-08-13T18:04:44.482Z,1723572284.482 [DATMMP](INFO): DAT read:
2024-08-13T18:04:44.482Z,1723572284.482 [DATMMP](INFO): DAT read: user:1>
2024-08-13T18:04:44.482Z,1723572284.482 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-08-13T18:04:44.483Z,1723572284.483 [DATMMP](INFO): entering MMP mode
2024-08-13T18:04:44.733Z,1723572284.733 [DATMMP](INFO): Starting
2024-08-13T18:04:44.734Z,1723572284.734 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-13T18:04:44.736Z,1723572284.736 [DATMMP](INFO): status rx: x1
2024-08-13T18:04:44.736Z,1723572284.736 [DATMMP](INFO): MMP status: 1: started
2024-08-13T18:04:44.736Z,1723572284.736 [DATMMP](INFO): Received message type: status
2024-08-13T18:04:44.736Z,1723572284.736 [DATMMP](INFO): Handled
2024-08-13T18:04:44.985Z,1723572284.985 [DATMMP](INFO): Starting
2024-08-13T18:04:44.987Z,1723572284.987 [DATMMP](INFO): Sent Tx Power Config 8
2024-08-13T18:04:44.987Z,1723572284.987 [DATMMP](INFO): Setting local address: 11
2024-08-13T18:04:44.989Z,1723572284.989 [DATMMP](INFO): Setting time to: 18:4:44 And date to:8/13/2024
2024-08-13T18:04:45.237Z,1723572285.237 [DATMMP](INFO): Starting
2024-08-13T18:04:45.238Z,1723572285.238 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-13T18:04:45.239Z,1723572285.239 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-08-13T18:04:45.239Z,1723572285.239 [DATMMP](INFO): Received message type: mdmpower
2024-08-13T18:04:45.239Z,1723572285.239 [DATMMP](INFO): Handled
2024-08-13T18:04:45.489Z,1723572285.489 [DATMMP](INFO): Starting
2024-08-13T18:04:45.490Z,1723572285.490 [DATMMP](INFO): GSXN notify for xid: 10
2024-08-13T18:04:45.491Z,1723572285.491 [DATMMP](INFO): sreg_txpower rx: x8
2024-08-13T18:04:45.491Z,1723572285.491 [DATMMP](INFO): Received message type: sreg_txpower
2024-08-13T18:04:45.491Z,1723572285.491 [DATMMP](INFO): Match for : sreg_txpower
2024-08-13T18:04:45.491Z,1723572285.491 [DATMMP](INFO): ACK
2024-08-13T18:04:45.742Z,1723572285.742 [DATMMP](INFO): Starting
2024-08-13T18:04:45.743Z,1723572285.743 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-13T18:04:45.744Z,1723572285.744 [DATMMP](INFO): mdmlocal rx: xB x0 x0 x0
2024-08-13T18:04:45.745Z,1723572285.745 [DATMMP](INFO): Received message type: mdmlocal
2024-08-13T18:04:45.745Z,1723572285.745 [DATMMP](INFO): Handled
2024-08-13T18:04:45.993Z,1723572285.993 [DATMMP](INFO): Starting
2024-08-13T18:04:45.994Z,1723572285.994 [DATMMP](INFO): GSXN notify for xid: 11
2024-08-13T18:04:46.001Z,1723572286.001 [DATMMP](INFO): sreg_locaddr rx: xB
2024-08-13T18:04:46.002Z,1723572286.002 [DATMMP](INFO): Received message type: sreg_locaddr
2024-08-13T18:04:46.002Z,1723572286.002 [DATMMP](INFO): Match for : sreg_locaddr
2024-08-13T18:04:46.002Z,1723572286.002 [DATMMP](INFO): ACK
2024-08-13T18:04:46.246Z,1723572286.246 [DATMMP](INFO): Starting
2024-08-13T18:04:46.246Z,1723572286.246 [DATMMP](INFO): GSXN notify for xid: 12
2024-08-13T18:04:46.247Z,1723572286.247 [DATMMP](INFO): timedate rx: x0 x12 x4 x2C x7 xD x7C x0
2024-08-13T18:04:46.247Z,1723572286.247 [DATMMP](INFO): Received message type: timedate
2024-08-13T18:04:46.247Z,1723572286.247 [DATMMP](INFO): Match for : timedate
2024-08-13T18:04:46.248Z,1723572286.248 [DATMMP](INFO): ACK
2024-08-13T18:04:46.497Z,1723572286.497 [DATMMP](INFO): Starting
2024-08-13T18:04:46.498Z,1723572286.498 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-13T18:04:46.499Z,1723572286.499 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-08-13T18:04:46.499Z,1723572286.499 [DATMMP](INFO): Received message type: sys_verb
2024-08-13T18:04:46.499Z,1723572286.499 [DATMMP](INFO): Handled
2024-08-13T18:04:46.705Z,1723572286.705 [WetLabsBB2FL] Communications Fault, FailCount= 3
2024-08-13T18:04:46.705Z,1723572286.705 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:04:46.750Z,1723572286.750 [DATMMP](INFO): Starting
2024-08-13T18:04:46.750Z,1723572286.750 [DATMMP](INFO): GSXN notify for xid: 13
2024-08-13T18:04:46.751Z,1723572286.751 [DATMMP](INFO): sreg_verbosity rx: x0
2024-08-13T18:04:46.751Z,1723572286.751 [DATMMP](INFO): Received message type: sreg_verbosity
2024-08-13T18:04:46.751Z,1723572286.751 [DATMMP](INFO): Match for : sreg_verbosity
2024-08-13T18:04:46.751Z,1723572286.751 [DATMMP](INFO): ACK
2024-08-13T18:04:46.979Z,1723572286.979 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:04:47.001Z,1723572287.001 [DATMMP](INFO): Starting
2024-08-13T18:04:47.002Z,1723572287.002 [DATMMP](INFO): GSXN notify for xid: 14
2024-08-13T18:04:47.003Z,1723572287.003 [DATMMP](INFO): version rx: x2 x5
2024-08-13T18:04:47.003Z,1723572287.003 [DATMMP](INFO): DatMMPVersion: 2.5
2024-08-13T18:04:47.003Z,1723572287.003 [DATMMP](INFO): Received message type: version
2024-08-13T18:04:47.003Z,1723572287.003 [DATMMP](INFO): Match for : version
2024-08-13T18:04:47.003Z,1723572287.003 [DATMMP](INFO): ACK
2024-08-13T18:04:47.003Z,1723572287.003 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-08-13T18:04:47.206Z,1723572287.206 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:04:48.202Z,1723572288.202 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2024-08-13T18:04:48.202Z,1723572288.202 [WetLabsBB2FL] No Fault, FailCount= 3
2024-08-13T18:04:48.421Z,1723572288.421 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:04:53.709Z,1723572293.709 [WetLabsBB2FL] Communications Fault, FailCount= 4
2024-08-13T18:04:53.710Z,1723572293.710 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:04:53.879Z,1723572293.879 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:04:54.209Z,1723572294.209 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:04:55.066Z,1723572295.066 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2024-08-13T18:04:55.066Z,1723572295.066 [WetLabsBB2FL] No Fault, FailCount= 4
2024-08-13T18:04:55.423Z,1723572295.423 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:04:56.235Z,1723572296.235 [NAL9602](INFO): Powering up NAL9602
2024-08-13T18:05:00.713Z,1723572300.713 [WetLabsBB2FL] Communications Fault, FailCount= 5
2024-08-13T18:05:00.713Z,1723572300.713 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:05:00.726Z,1723572300.726 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:05:00.726Z,1723572300.726 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:05:01.118Z,1723572301.118 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-13T18:05:01.119Z,1723572301.119 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-08-13T18:05:01.119Z,1723572301.119 [DATMMP](INFO): Received message type: timesync
2024-08-13T18:05:01.119Z,1723572301.119 [DATMMP](INFO): Handled
2024-08-13T18:05:01.214Z,1723572301.214 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:05:07.149Z,1723572307.149 [NAL9602](INFO): NAL9602 initialized
2024-08-13T18:05:11.198Z,1723572311.198 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-13T18:05:11.199Z,1723572311.199 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-08-13T18:05:11.199Z,1723572311.199 [DATMMP](INFO): Received message type: timesync
2024-08-13T18:05:11.199Z,1723572311.199 [DATMMP](INFO): Handled
2024-08-13T18:05:35.850Z,1723572335.850 [SBIT](IMPORTANT): Beginning Startup BIT
2024-08-13T18:05:35.854Z,1723572335.854 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-13T18:05:46.782Z,1723572346.782 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-13T18:05:46.880Z,1723572346.880 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.003068
CHAN A1 (24V): -0.002822
CHAN A2 (12V): -0.000066
CHAN A3 (5V): 0.000252
CHAN B0 (3.3V): 0.000430
CHAN B1 (3.15aV): 0.000686
CHAN B2 (3.15bV): 0.000763
CHAN B3 (GND): 0.001267
OPEN: -0.001141
Full Scale: +/- 1 mA
2024-08-13T18:05:56.485Z,1723572356.485 [ElevatorServo](FAULT): Overload Error
2024-08-13T18:05:56.485Z,1723572356.485 [ElevatorServo] Hardware Fault, FailCount= 1
2024-08-13T18:05:56.485Z,1723572356.485 [ElevatorServo](ERROR): Hardware Fault
2024-08-13T18:05:56.602Z,1723572356.602 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-08-13T18:05:56.869Z,1723572356.869 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-08-13T18:05:56.869Z,1723572356.869 [ElevatorServo](INFO): Powering down
2024-08-13T18:05:57.675Z,1723572357.675 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-13T18:05:57.795Z,1723572357.795 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-13T18:05:57.826Z,1723572357.826 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-08-13T18:05:57.826Z,1723572357.826 [ElevatorServo] No Fault, FailCount= 1
2024-08-13T18:06:02.601Z,1723572362.601 [ElevatorServo](FAULT): Overload Error
2024-08-13T18:06:02.601Z,1723572362.601 [ElevatorServo] Hardware Fault, FailCount= 2
2024-08-13T18:06:02.601Z,1723572362.601 [ElevatorServo](ERROR): Hardware Fault
2024-08-13T18:06:02.638Z,1723572362.638 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-08-13T18:06:02.935Z,1723572362.935 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-08-13T18:06:02.935Z,1723572362.935 [ElevatorServo](INFO): Powering down
2024-08-13T18:06:03.353Z,1723572363.353 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-13T18:06:03.738Z,1723572363.738 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-13T18:06:03.858Z,1723572363.858 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-13T18:06:03.890Z,1723572363.890 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-08-13T18:06:03.890Z,1723572363.890 [ElevatorServo] No Fault, FailCount= 2
2024-08-13T18:06:08.989Z,1723572368.989 [ElevatorServo](FAULT): Overload Error
2024-08-13T18:06:08.989Z,1723572368.989 [ElevatorServo] Hardware Fault, FailCount= 3
2024-08-13T18:06:08.990Z,1723572368.990 [ElevatorServo](ERROR): Hardware Fault
2024-08-13T18:06:09.018Z,1723572369.018 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-08-13T18:06:09.019Z,1723572369.019 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2024-08-13T18:06:09.371Z,1723572369.371 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-08-13T18:06:09.371Z,1723572369.371 [ElevatorServo](INFO): Powering down
2024-08-13T18:06:09.374Z,1723572369.374 [CommandExec](FAULT): Scheduling is paused
2024-08-13T18:06:09.375Z,1723572369.375 [CBIT](INFO): Critical error at 20240813T180609
2024-08-13T18:06:09.375Z,1723572369.375 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-08-13T18:06:10.175Z,1723572370.175 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-13T18:06:10.294Z,1723572370.294 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-13T18:06:15.849Z,1723572375.849 [SBIT](ERROR): Could not read elevatorAngleReader_.
2024-08-13T18:06:15.849Z,1723572375.849 [SBIT](FAULT): Control surface position failure.
2024-08-13T18:06:20.204Z,1723572380.204 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005218
2024-08-13T18:06:25.959Z,1723572385.959 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-13T18:06:29.569Z,1723572389.569 [SBIT](ERROR): Could not read elevatorAngleReader_.
2024-08-13T18:06:29.569Z,1723572389.569 [SBIT](FAULT): Control surface position failure.
2024-08-13T18:06:29.972Z,1723572389.972 [SBIT](CRITICAL): SBIT FAILED
2024-08-13T18:06:29.972Z,1723572389.972 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-08-13T18:06:29.973Z,1723572389.973 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-08-13T18:06:29.973Z,1723572389.973 [SBIT](IMPORTANT): CBIT.vehicleOpen=1 bool;
2024-08-13T18:06:29.973Z,1723572389.973 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar;
2024-08-13T18:06:29.974Z,1723572389.974 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2024-08-13T18:06:29.974Z,1723572389.974 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-08-13T18:06:29.974Z,1723572389.974 [SBIT](IMPORTANT): ElevatorServo.currLimit=75 percent;
2024-08-13T18:06:29.974Z,1723572389.974 [SBIT](IMPORTANT): ElevatorServo.limitHi=16369 count;
2024-08-13T18:06:29.974Z,1723572389.974 [SBIT](IMPORTANT): ElevatorServo.limitLo=0 count;
2024-08-13T18:06:29.974Z,1723572389.974 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;
2024-08-13T18:06:29.974Z,1723572389.974 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-08-13T18:06:29.974Z,1723572389.974 [SBIT](IMPORTANT): Radio_Surface.loadAtStartup=1 bool;
2024-08-13T18:06:29.974Z,1723572389.974 [SBIT](IMPORTANT): VerticalControl.massDefault=12.059213 millimeter;
2024-08-13T18:06:29.976Z,1723572389.976 [CBIT](INFO): Critical error at 20240813T180629
2024-08-13T18:06:30.386Z,1723572390.386 [MissionManager](IMPORTANT): Started mission Startup
2024-08-13T18:06:30.386Z,1723572390.386 [Startup] Running Loop=1
2024-08-13T18:06:30.386Z,1723572390.386 [Startup](DEBUG): Aggregate::initialize Startup
2024-08-13T18:06:30.386Z,1723572390.386 [Startup:A.GoToSurface] Running Loop=1
2024-08-13T18:06:30.386Z,1723572390.386 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-13T18:06:30.387Z,1723572390.387 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-13T18:06:30.387Z,1723572390.387 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-13T18:06:30.388Z,1723572390.388 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-13T18:06:30.388Z,1723572390.388 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-13T18:06:30.388Z,1723572390.388 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-13T18:06:30.389Z,1723572390.389 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-13T18:06:30.390Z,1723572390.390 [Startup:StartupSatComms] Running Loop=1
2024-08-13T18:06:30.391Z,1723572390.391 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-08-13T18:06:30.391Z,1723572390.391 [Startup:StartupSatComms:A] Running Loop=1
2024-08-13T18:06:30.776Z,1723572390.776 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-08-13T18:07:27.370Z,1723572447.370 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-08-13T18:07:27.370Z,1723572447.370 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-13T18:07:27.392Z,1723572447.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-13T18:07:27.780Z,1723572447.780 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-13T18:07:27.780Z,1723572447.780 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-08-13T18:07:30.576Z,1723572450.576 [Startup:StartupSatComms:A](INFO): Timed out from 2024-08-13T18:06:30.4Z
2024-08-13T18:07:30.576Z,1723572450.576 [Startup:StartupSatComms:A] Stopped
2024-08-13T18:07:30.576Z,1723572450.576 [Startup:StartupSatComms:B] Running Loop=1
2024-08-13T18:07:31.011Z,1723572451.011 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-13T18:07:31.020Z,1723572451.020 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2024-08-13T18:07:31.020Z,1723572451.020 [WetLabsBB2FL] No Fault, FailCount= 5
2024-08-13T18:07:31.103Z,1723572451.103 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:07:31.983Z,1723572451.983 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-13T18:07:36.393Z,1723572456.393 [WetLabsBB2FL] Communications Fault, FailCount= 1
2024-08-13T18:07:36.393Z,1723572456.393 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:07:36.424Z,1723572456.424 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:07:36.889Z,1723572456.889 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:07:38.004Z,1723572458.004 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2024-08-13T18:07:38.004Z,1723572458.004 [WetLabsBB2FL] No Fault, FailCount= 1
2024-08-13T18:07:38.103Z,1723572458.103 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:07:39.633Z,1723572459.633 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20240813T175506/Courier0004.lzma
2024-08-13T18:07:40.635Z,1723572460.635 [DataOverHttps](INFO): Moved sent file to Logs/20240813T175506/Courier0004.lzma.bak
2024-08-13T18:07:40.636Z,1723572460.636 [DataOverHttps](INFO): SBD MOMSN=21496681
2024-08-13T18:07:43.401Z,1723572463.401 [WetLabsBB2FL] Communications Fault, FailCount= 2
2024-08-13T18:07:43.401Z,1723572463.401 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:07:43.652Z,1723572463.652 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:07:43.902Z,1723572463.902 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:07:45.030Z,1723572465.030 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2024-08-13T18:07:45.030Z,1723572465.030 [WetLabsBB2FL] No Fault, FailCount= 2
2024-08-13T18:07:45.115Z,1723572465.115 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:07:50.406Z,1723572470.406 [WetLabsBB2FL] Communications Fault, FailCount= 3
2024-08-13T18:07:50.406Z,1723572470.406 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:07:50.509Z,1723572470.509 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:07:50.910Z,1723572470.910 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:07:51.727Z,1723572471.727 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2024-08-13T18:07:51.727Z,1723572471.727 [WetLabsBB2FL] No Fault, FailCount= 3
2024-08-13T18:07:52.134Z,1723572472.134 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:07:57.417Z,1723572477.417 [WetLabsBB2FL] Communications Fault, FailCount= 4
2024-08-13T18:07:57.417Z,1723572477.417 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:07:57.445Z,1723572477.445 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:07:57.917Z,1723572477.917 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:07:59.112Z,1723572479.112 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2024-08-13T18:07:59.112Z,1723572479.112 [WetLabsBB2FL] No Fault, FailCount= 4
2024-08-13T18:07:59.131Z,1723572479.131 [WetLabsBB2FL](INFO): Powering up
2024-08-13T18:07:59.894Z,1723572479.894 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-13T18:08:01.480Z,1723572481.480 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20240813T180416/Courier0000.lzma
2024-08-13T18:08:02.479Z,1723572482.479 [DataOverHttps](INFO): Moved sent file to Logs/20240813T180416/Courier0000.lzma.bak
2024-08-13T18:08:02.479Z,1723572482.479 [DataOverHttps](INFO): SBD MOMSN=21496683
2024-08-13T18:08:04.421Z,1723572484.421 [WetLabsBB2FL] Communications Fault, FailCount= 5
2024-08-13T18:08:04.421Z,1723572484.421 [WetLabsBB2FL](ERROR): Communications Fault
2024-08-13T18:08:04.658Z,1723572484.658 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:08:04.658Z,1723572484.658 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2024-08-13T18:08:04.921Z,1723572484.921 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:08:13.980Z,1723572493.980 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-13T18:08:21.765Z,1723572501.765 [DataOverHttps](INFO): Sending 314 bytes from file Logs/20240813T175506/Express0005.lzma
2024-08-13T18:08:22.767Z,1723572502.767 [DataOverHttps](INFO): Moved sent file to Logs/20240813T175506/Express0005.lzma.bak
2024-08-13T18:08:22.767Z,1723572502.767 [DataOverHttps](INFO): SBD MOMSN=21496688
2024-08-13T18:08:30.934Z,1723572510.934 [Startup:StartupSatComms:B](INFO): Timed out from 2024-08-13T18:07:30.6Z
2024-08-13T18:08:30.934Z,1723572510.934 [Startup:StartupSatComms:B] Stopped
2024-08-13T18:08:30.934Z,1723572510.934 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-08-13T18:08:30.935Z,1723572510.935 [Startup:StartupSatComms] Stopped
2024-08-13T18:08:30.935Z,1723572510.935 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-08-13T18:08:30.936Z,1723572510.936 [Startup](INFO): Completed Startup
2024-08-13T18:08:30.936Z,1723572510.936 [MissionManager](INFO): Startup is completed.
2024-08-13T18:08:30.936Z,1723572510.936 [MissionManager](INFO): Uninitializing Mission Startup
2024-08-13T18:08:30.936Z,1723572510.936 [Startup] Stopped
2024-08-13T18:08:30.936Z,1723572510.936 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-08-13T18:08:30.936Z,1723572510.936 [Startup:A.GoToSurface] Stopped
2024-08-13T18:08:30.936Z,1723572510.936 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-13T18:08:31.308Z,1723572511.308 [MissionManager](IMPORTANT): Started mission Default
2024-08-13T18:08:31.308Z,1723572511.308 [Default] Running Loop=1
2024-08-13T18:08:31.309Z,1723572511.309 [Default](DEBUG): Aggregate::initialize Default
2024-08-13T18:08:31.309Z,1723572511.309 [Default:B.GoToSurface] Running Loop=1
2024-08-13T18:08:31.309Z,1723572511.309 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-13T18:08:31.313Z,1723572511.313 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-13T18:08:31.313Z,1723572511.313 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-13T18:08:31.314Z,1723572511.314 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-13T18:08:31.314Z,1723572511.314 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-13T18:08:31.314Z,1723572511.314 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-13T18:08:31.315Z,1723572511.315 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-13T18:08:31.315Z,1723572511.315 [Default:A.Wait] Running Loop=1
2024-08-13T18:08:31.315Z,1723572511.315 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-08-13T18:08:42.050Z,1723572522.050 [CommandExec](IMPORTANT): got command failComponent
2024-08-13T18:08:42.050Z,1723572522.050 [CommandExec](IMPORTANT): Failed components:
2024-08-13T18:08:42.051Z,1723572522.051 [CommandExec](IMPORTANT): ElevatorServo: Hardware Fault
2024-08-13T18:08:42.051Z,1723572522.051 [CommandExec](IMPORTANT): WetLabsBB2FL: Communications Fault
2024-08-13T18:08:42.515Z,1723572522.515 [DataOverHttps](INFO): Sending 1075 bytes from file Logs/20240813T180416/Express0001.lzma
2024-08-13T18:08:44.347Z,1723572524.347 [Default:A.Wait](INFO): Done Waiting.
2024-08-13T18:08:44.347Z,1723572524.347 [Default:A.Wait] Stopped
2024-08-13T18:08:44.347Z,1723572524.347 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-08-13T18:08:44.732Z,1723572524.732 [Default:CheckIn] Running Loop=1
2024-08-13T18:08:44.733Z,1723572524.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-13T18:08:44.733Z,1723572524.733 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-13T18:08:45.138Z,1723572525.138 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-08-13T18:08:45.518Z,1723572525.518 [DataOverHttps](INFO): Moved sent file to Logs/20240813T180416/Express0001.lzma.bak
2024-08-13T18:08:45.518Z,1723572525.518 [DataOverHttps](INFO): SBD MOMSN=21496698
2024-08-13T18:08:55.047Z,1723572535.047 [CommandExec](IMPORTANT): got command configSet ElevatorServo.loadAtStartup 0 bool persist
2024-08-13T18:08:55.047Z,1723572535.047 [CommandExec](IMPORTANT): configSet ElevatorServo.loadAtStartup requires a restart to take effect.
2024-08-13T18:09:07.574Z,1723572547.574 [CommandExec](IMPORTANT): got command configSet WetLabsBB2FL.loadAtStartup 0 bool persist
2024-08-13T18:09:07.574Z,1723572547.574 [CommandExec](IMPORTANT): configSet WetLabsBB2FL.loadAtStartup requires a restart to take effect.
2024-08-13T18:09:10.386Z,1723572550.386 [CommandExec](IMPORTANT): got command failComponent
2024-08-13T18:09:10.386Z,1723572550.386 [CommandExec](IMPORTANT): Failed components:
2024-08-13T18:09:10.386Z,1723572550.386 [CommandExec](IMPORTANT): ElevatorServo: Hardware Fault
2024-08-13T18:09:10.387Z,1723572550.387 [CommandExec](IMPORTANT): WetLabsBB2FL: Communications Fault
2024-08-13T18:09:15.485Z,1723572555.485 [CommandExec](IMPORTANT): got command restart application
2024-08-13T18:09:16.490Z,1723572556.490 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:16.490Z,1723572556.490 [CommandExec](INFO): Uninitializing the command executive.
2024-08-13T18:09:16.490Z,1723572556.490 [CommandExec](INFO): Uninitializing the command scheduler.
2024-08-13T18:09:16.490Z,1723572556.490 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:16.625Z,1723572556.625 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-08-13T18:09:16.625Z,1723572556.625 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-08-13T18:09:16.625Z,1723572556.625 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:16.626Z,1723572556.626 [NavChartDb](INFO): Join timeout helper Thread ID is 1306
2024-08-13T18:09:16.950Z,1723572556.950 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:16.950Z,1723572556.950 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:16.969Z,1723572556.969 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-08-13T18:09:16.969Z,1723572556.969 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:16.970Z,1723572556.970 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1307
2024-08-13T18:09:17.253Z,1723572557.253 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:17.254Z,1723572557.254 [WetLabsBB2FL](INFO): Powering down
2024-08-13T18:09:17.254Z,1723572557.254 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:17.261Z,1723572557.261 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-08-13T18:09:17.261Z,1723572557.261 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:17.262Z,1723572557.262 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1308
2024-08-13T18:09:17.625Z,1723572557.625 [CTD_Seabird](INFO): Powering down
2024-08-13T18:09:17.638Z,1723572557.638 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:17.638Z,1723572557.638 [CTD_Seabird](INFO): Powering down
2024-08-13T18:09:17.649Z,1723572557.649 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:17.665Z,1723572557.665 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-08-13T18:09:17.665Z,1723572557.665 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:17.666Z,1723572557.666 [Radio_Surface](INFO): Join timeout helper Thread ID is 1309
2024-08-13T18:09:17.905Z,1723572557.905 [Radio_Surface](INFO): Powering down
2024-08-13T18:09:17.906Z,1723572557.906 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:17.907Z,1723572557.907 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:17.917Z,1723572557.917 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-08-13T18:09:17.917Z,1723572557.917 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:17.918Z,1723572557.918 [Onboard](INFO): Join timeout helper Thread ID is 1310
2024-08-13T18:09:22.609Z,1723572562.609 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:22.610Z,1723572562.610 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:22.618Z,1723572562.618 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-08-13T18:09:22.618Z,1723572562.618 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:22.619Z,1723572562.619 [DataOverHttps](INFO): Join timeout helper Thread ID is 1311
2024-08-13T18:09:23.121Z,1723572563.121 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:23.125Z,1723572563.125 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.142Z,1723572563.142 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-08-13T18:09:23.142Z,1723572563.142 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.143Z,1723572563.143 [DATMMP](INFO): Join timeout helper Thread ID is 1312
2024-08-13T18:09:23.321Z,1723572563.321 [DATMMP](INFO): Stop
2024-08-13T18:09:23.321Z,1723572563.321 [DATMMP](INFO): uninitialize
2024-08-13T18:09:23.322Z,1723572563.322 [DATMMP](INFO): Powering down
2024-08-13T18:09:23.394Z,1723572563.394 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:23.395Z,1723572563.395 [DATMMP](INFO): uninitialize
2024-08-13T18:09:23.395Z,1723572563.395 [DATMMP](INFO): Powering down
2024-08-13T18:09:23.396Z,1723572563.396 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.406Z,1723572563.406 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-08-13T18:09:23.407Z,1723572563.407 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.407Z,1723572563.407 [BackseatComponent](INFO): Join timeout helper Thread ID is 1313
2024-08-13T18:09:23.582Z,1723572563.582 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:23.582Z,1723572563.582 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.602Z,1723572563.602 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-08-13T18:09:23.602Z,1723572563.602 [logger ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.603Z,1723572563.603 [logger](INFO): Join timeout helper Thread ID is 1314
2024-08-13T18:09:23.629Z,1723572563.629 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:23.630Z,1723572563.630 [logger ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.642Z,1723572563.642 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-08-13T18:09:23.642Z,1723572563.642 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.643Z,1723572563.643 [CommandLine](INFO): Join timeout helper Thread ID is 1315
2024-08-13T18:09:23.701Z,1723572563.701 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:23.702Z,1723572563.702 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.703Z,1723572563.703 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-08-13T18:09:23.703Z,1723572563.703 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.704Z,1723572563.704 [CommandExec](INFO): Join timeout helper Thread ID is 1316
2024-08-13T18:09:23.705Z,1723572563.705 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-08-13T18:09:23.705Z,1723572563.705 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:23.706Z,1723572563.706 [controlThread](INFO): Join timeout helper Thread ID is 1317
2024-08-13T18:09:23.861Z,1723572563.861 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-13T18:09:23.862Z,1723572563.862 [controlThread](DEBUG): Uninitializing ControlThread
2024-08-13T18:09:23.862Z,1723572563.862 [AHRS_M2](INFO): Powering down
2024-08-13T18:09:24.030Z,1723572564.030 [DVL_micro](INFO): Powering down
2024-08-13T18:09:24.030Z,1723572564.030 [NAL9602](INFO): Powering down
2024-08-13T18:09:24.032Z,1723572564.032 [Sonardyne_Nano](INFO): Powering down
2024-08-13T18:09:24.151Z,1723572564.151 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-08-13T18:09:24.152Z,1723572564.152 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-08-13T18:09:24.152Z,1723572564.152 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-08-13T18:09:24.153Z,1723572564.153 [MissionManager](INFO): Uninitializing Mission Default
2024-08-13T18:09:24.153Z,1723572564.153 [Default] Stopped
2024-08-13T18:09:24.153Z,1723572564.153 [Default](DEBUG): Aggregate::uninitialize Default
2024-08-13T18:09:24.153Z,1723572564.153 [Default:B.GoToSurface] Stopped
2024-08-13T18:09:24.153Z,1723572564.153 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-13T18:09:24.153Z,1723572564.153 [Default:CheckIn] Stopped
2024-08-13T18:09:24.154Z,1723572564.154 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-13T18:09:24.154Z,1723572564.154 [Default:CheckIn:Read_GPS] Stopped
2024-08-13T18:09:24.156Z,1723572564.156 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-08-13T18:09:24.157Z,1723572564.157 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-08-13T18:09:24.157Z,1723572564.157 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-08-13T18:09:24.157Z,1723572564.157 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-08-13T18:09:24.158Z,1723572564.158 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-08-13T18:09:24.158Z,1723572564.158 [BuoyancyServo](INFO): Powering down
2024-08-13T18:09:24.169Z,1723572564.169 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-08-13T18:09:24.170Z,1723572564.170 [ElevatorServo](INFO): Powering down
2024-08-13T18:09:24.170Z,1723572564.170 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-08-13T18:09:24.170Z,1723572564.170 [MassServo](INFO): Powering down
2024-08-13T18:09:24.171Z,1723572564.171 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-08-13T18:09:24.171Z,1723572564.171 [RudderServo](INFO): Powering down
2024-08-13T18:09:24.172Z,1723572564.172 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-08-13T18:09:24.172Z,1723572564.172 [ThrusterHE](INFO): Powering down
2024-08-13T18:09:24.173Z,1723572564.173 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-08-13T18:09:24.173Z,1723572564.173 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-08-13T18:09:24.174Z,1723572564.174 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-08-13T18:09:24.174Z,1723572564.174 [CBIT](DEBUG): Powering off loads.
2024-08-13T18:09:24.185Z,1723572564.185 [CBIT](DEBUG): Disabling WDT.
2024-08-13T18:09:24.197Z,1723572564.197 [CBIT](DEBUG): Opening all GF detection circuits.
2024-08-13T18:09:24.198Z,1723572564.198 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:24.243Z,1723572564.243 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:24.246Z,1723572564.246 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:24.253Z,1723572564.253 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:24.259Z,1723572564.259 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:24.299Z,1723572564.299 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:24.470Z,1723572564.470 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:24.476Z,1723572564.476 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:24.497Z,1723572564.497 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-13T18:09:24.545Z,1723572564.545 [logger ThreadHandler](INFO): Thread cancelled.