2024-09-10T22:53:40.888Z,1726008820.888 [Supervisor](DEBUG): Initializing supervisor.
2024-09-10T22:53:40.892Z,1726008820.892 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-09-10T22:53:40.893Z,1726008820.893 [SyncHandler](INFO): Protected caller Thread ID is 6264
2024-09-10T22:53:40.893Z,1726008820.893 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-09-10T22:53:40.894Z,1726008820.894 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-09-10T22:53:40.894Z,1726008820.894 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6265
2024-09-10T22:53:40.898Z,1726008820.898 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-09-10T22:53:40.915Z,1726008820.915 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-09-10T22:53:40.916Z,1726008820.916 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-09-10T22:53:40.916Z,1726008820.916 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6266
2024-09-10T22:53:40.921Z,1726008820.921 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-09-10T22:53:40.922Z,1726008820.922 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-09-10T22:53:40.922Z,1726008820.922 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6267
2024-09-10T22:53:40.924Z,1726008820.924 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-09-10T22:53:40.925Z,1726008820.925 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-09-10T22:53:40.925Z,1726008820.925 [logger ThreadHandler](INFO): Protected caller Thread ID is 6268
2024-09-10T22:53:40.929Z,1726008820.929 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-09-10T22:53:40.929Z,1726008820.929 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-09-10T22:53:40.931Z,1726008820.931 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-09-10T22:53:41.275Z,1726008821.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-09-10T22:53:41.275Z,1726008821.275 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-09-10T22:53:41.368Z,1726008821.368 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-09-10T22:53:41.488Z,1726008821.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-09-10T22:53:41.488Z,1726008821.488 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-09-10T22:53:41.629Z,1726008821.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-09-10T22:53:41.629Z,1726008821.629 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-09-10T22:53:41.985Z,1726008821.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-09-10T22:53:41.986Z,1726008821.986 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-09-10T22:53:42.094Z,1726008822.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-09-10T22:53:42.095Z,1726008822.095 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-09-10T22:53:42.524Z,1726008822.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-09-10T22:53:42.525Z,1726008822.525 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-09-10T22:53:42.752Z,1726008822.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-09-10T22:53:42.753Z,1726008822.753 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-09-10T22:53:42.843Z,1726008822.843 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-09-10T22:53:43.145Z,1726008823.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-09-10T22:53:43.146Z,1726008823.146 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-09-10T22:53:43.378Z,1726008823.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-09-10T22:53:43.379Z,1726008823.379 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-09-10T22:53:43.914Z,1726008823.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-09-10T22:53:43.914Z,1726008823.914 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-09-10T22:53:44.114Z,1726008824.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-09-10T22:53:44.114Z,1726008824.114 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-09-10T22:53:44.412Z,1726008824.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-09-10T22:53:44.412Z,1726008824.412 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-09-10T22:53:45.286Z,1726008825.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-09-10T22:53:45.287Z,1726008825.287 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-09-10T22:53:45.877Z,1726008825.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-09-10T22:53:45.879Z,1726008825.879 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2024-09-10T22:53:45.880Z,1726008825.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2024-09-10T22:53:45.999Z,1726008825.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2024-09-10T22:53:46.128Z,1726008826.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2024-09-10T22:53:46.227Z,1726008826.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2024-09-10T22:53:46.346Z,1726008826.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2024-09-10T22:53:46.445Z,1726008826.445 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2024-09-10T22:53:46.712Z,1726008826.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-09-10T22:53:46.712Z,1726008826.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2024-09-10T22:53:46.833Z,1726008826.833 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2024-09-10T22:53:46.943Z,1726008826.943 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2024-09-10T22:53:47.059Z,1726008827.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2024-09-10T22:53:47.214Z,1726008827.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2024-09-10T22:53:47.315Z,1726008827.315 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-09-10T22:53:47.331Z,1726008827.331 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-09-10T22:53:47.437Z,1726008827.437 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-09-10T22:53:47.437Z,1726008827.437 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-09-10T22:53:47.489Z,1726008827.489 [VerticalControl](DEBUG): Construct VerticalControl.
2024-09-10T22:53:47.551Z,1726008827.551 [VerticalControl] Loaded
2024-09-10T22:53:47.551Z,1726008827.551 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-09-10T22:53:47.554Z,1726008827.554 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-09-10T22:53:47.599Z,1726008827.599 [HorizontalControl] Loaded
2024-09-10T22:53:47.600Z,1726008827.600 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-09-10T22:53:47.602Z,1726008827.602 [SpeedControl](DEBUG): Construct SpeedControl.
2024-09-10T22:53:47.605Z,1726008827.605 [SpeedControl] Loaded
2024-09-10T22:53:47.605Z,1726008827.605 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-09-10T22:53:47.608Z,1726008827.608 [LoopControl](DEBUG): Construct LoopControl.
2024-09-10T22:53:47.608Z,1726008827.608 [LoopControl] Loaded
2024-09-10T22:53:47.609Z,1726008827.609 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-09-10T22:53:47.609Z,1726008827.609 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-09-10T22:53:47.610Z,1726008827.610 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-09-10T22:53:47.678Z,1726008827.678 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-09-10T22:53:47.678Z,1726008827.678 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-09-10T22:53:48.133Z,1726008828.133 [AHRS_M2] Loaded
2024-09-10T22:53:48.133Z,1726008828.133 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-09-10T22:53:48.171Z,1726008828.171 [BackseatComponent] Loaded
2024-09-10T22:53:48.171Z,1726008828.171 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-09-10T22:53:48.172Z,1726008828.172 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2024-09-10T22:53:48.172Z,1726008828.172 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6351
2024-09-10T22:53:48.175Z,1726008828.175 [LcmUniversalReporter] Loaded
2024-09-10T22:53:48.175Z,1726008828.175 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-09-10T22:53:48.987Z,1726008828.987 [BPC1] Loaded
2024-09-10T22:53:48.987Z,1726008828.987 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-09-10T22:53:49.072Z,1726008829.072 [DATMMP] Loaded
2024-09-10T22:53:49.072Z,1726008829.072 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-09-10T22:53:49.073Z,1726008829.073 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2024-09-10T22:53:49.073Z,1726008829.073 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 6352
2024-09-10T22:53:49.161Z,1726008829.161 [DataOverHttps] Loaded
2024-09-10T22:53:49.161Z,1726008829.161 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-09-10T22:53:49.162Z,1726008829.162 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2024-09-10T22:53:49.162Z,1726008829.162 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6354
2024-09-10T22:53:49.185Z,1726008829.185 [Depth_Keller] Loaded
2024-09-10T22:53:49.185Z,1726008829.185 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-09-10T22:53:49.190Z,1726008829.190 [DropWeight] Loaded
2024-09-10T22:53:49.190Z,1726008829.190 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-09-10T22:53:49.254Z,1726008829.254 [NAL9602] Loaded
2024-09-10T22:53:49.254Z,1726008829.254 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-09-10T22:53:49.283Z,1726008829.283 [Onboard] Loaded
2024-09-10T22:53:49.283Z,1726008829.283 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-09-10T22:53:49.284Z,1726008829.284 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2024-09-10T22:53:49.284Z,1726008829.284 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6355
2024-09-10T22:53:49.298Z,1726008829.298 [Power24vConverter] Loaded
2024-09-10T22:53:49.298Z,1726008829.298 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-09-10T22:53:49.313Z,1726008829.313 [Radio_Surface] Loaded
2024-09-10T22:53:49.313Z,1726008829.313 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-09-10T22:53:49.314Z,1726008829.314 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2024-09-10T22:53:49.314Z,1726008829.314 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6356
2024-09-10T22:53:49.329Z,1726008829.329 [Sonardyne_Nano] Loaded
2024-09-10T22:53:49.329Z,1726008829.329 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-09-10T22:53:49.353Z,1726008829.353 [Waterlinked] Loaded
2024-09-10T22:53:49.353Z,1726008829.353 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-09-10T22:53:49.353Z,1726008829.353 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-09-10T22:53:49.354Z,1726008829.354 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-09-10T22:53:49.364Z,1726008829.364 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-09-10T22:53:49.364Z,1726008829.364 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-09-10T22:53:49.390Z,1726008829.390 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-09-10T22:53:49.390Z,1726008829.390 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-09-10T22:53:49.523Z,1726008829.523 [SBIT](DEBUG): Construct Startup Built In Test.
2024-09-10T22:53:49.532Z,1726008829.532 [SBIT] Loaded
2024-09-10T22:53:49.532Z,1726008829.532 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-09-10T22:53:49.535Z,1726008829.535 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-09-10T22:53:49.547Z,1726008829.547 [IBIT] Loaded
2024-09-10T22:53:49.547Z,1726008829.547 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-09-10T22:53:49.553Z,1726008829.553 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-09-10T22:53:49.654Z,1726008829.654 [CBIT] Loaded
2024-09-10T22:53:49.655Z,1726008829.655 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-09-10T22:53:49.655Z,1726008829.655 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-09-10T22:53:49.655Z,1726008829.655 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-09-10T22:53:49.976Z,1726008829.976 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-09-10T22:53:49.976Z,1726008829.976 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-09-10T22:53:50.024Z,1726008830.024 [DepthRateCalculator] Loaded
2024-09-10T22:53:50.024Z,1726008830.024 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-09-10T22:53:50.029Z,1726008830.029 [PitchRateCalculator] Loaded
2024-09-10T22:53:50.030Z,1726008830.030 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-09-10T22:53:50.039Z,1726008830.039 [SpeedCalculator] Loaded
2024-09-10T22:53:50.040Z,1726008830.040 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-09-10T22:53:50.044Z,1726008830.044 [YawRateCalculator] Loaded
2024-09-10T22:53:50.044Z,1726008830.044 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-09-10T22:53:50.064Z,1726008830.064 [ElevatorOffsetCalculator] Loaded
2024-09-10T22:53:50.064Z,1726008830.064 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-09-10T22:53:50.064Z,1726008830.064 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-09-10T22:53:50.065Z,1726008830.065 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-09-10T22:53:50.232Z,1726008830.232 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-09-10T22:53:50.233Z,1726008830.233 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-09-10T22:53:50.343Z,1726008830.343 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-09-10T22:53:50.344Z,1726008830.344 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-09-10T22:53:50.386Z,1726008830.386 [DeadReckonUsingSpeedCalculator] Loaded
2024-09-10T22:53:50.386Z,1726008830.386 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-09-10T22:53:50.428Z,1726008830.428 [DeadReckonWithRespectToSeafloor] Loaded
2024-09-10T22:53:50.429Z,1726008830.429 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2024-09-10T22:53:50.442Z,1726008830.442 [NavChart] Loaded
2024-09-10T22:53:50.442Z,1726008830.442 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-09-10T22:53:50.447Z,1726008830.447 [UniversalFixResidualReporter] Loaded
2024-09-10T22:53:50.448Z,1726008830.448 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-09-10T22:53:50.448Z,1726008830.448 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-09-10T22:53:50.449Z,1726008830.449 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-09-10T22:53:50.652Z,1726008830.652 [BuoyancyServo] Loaded
2024-09-10T22:53:50.652Z,1726008830.652 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-09-10T22:53:50.674Z,1726008830.674 [ElevatorServo] Loaded
2024-09-10T22:53:50.675Z,1726008830.675 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-09-10T22:53:50.696Z,1726008830.696 [MassServo] Loaded
2024-09-10T22:53:50.696Z,1726008830.696 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-09-10T22:53:50.716Z,1726008830.716 [RudderServo] Loaded
2024-09-10T22:53:50.717Z,1726008830.717 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-09-10T22:53:50.733Z,1726008830.733 [ThrusterHE] Loaded
2024-09-10T22:53:50.733Z,1726008830.733 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-09-10T22:53:50.733Z,1726008830.733 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-09-10T22:53:50.734Z,1726008830.734 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-09-10T22:53:50.906Z,1726008830.906 [CTD_Seabird] Loaded
2024-09-10T22:53:50.906Z,1726008830.906 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-09-10T22:53:50.907Z,1726008830.907 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BF24E0
2024-09-10T22:53:50.907Z,1726008830.907 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6358
2024-09-10T22:53:50.943Z,1726008830.943 [ESPComponent] Loaded
2024-09-10T22:53:50.944Z,1726008830.944 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-09-10T22:53:50.965Z,1726008830.965 [PAR_Licor] Loaded
2024-09-10T22:53:50.965Z,1726008830.965 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-09-10T22:53:51.009Z,1726008831.009 [WetLabsBB2FL] Loaded
2024-09-10T22:53:51.010Z,1726008831.010 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-09-10T22:53:51.011Z,1726008831.011 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0
2024-09-10T22:53:51.011Z,1726008831.011 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6359
2024-09-10T22:53:51.012Z,1726008831.012 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-09-10T22:53:51.027Z,1726008831.027 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-09-10T22:53:51.032Z,1726008831.032 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-09-10T22:53:51.047Z,1726008831.047 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-09-10T22:53:51.048Z,1726008831.048 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-09-10T22:53:51.048Z,1726008831.048 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6360
2024-09-10T22:53:51.053Z,1726008831.053 [Supervisor](INFO): Main Thread ID is 6263
2024-09-10T22:53:51.053Z,1726008831.053 [Supervisor](DEBUG): Running supervisor.
2024-09-10T22:53:51.053Z,1726008831.053 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6361
2024-09-10T22:53:51.054Z,1726008831.054 [CommandExec](INFO): Initializing the command executive.
2024-09-10T22:53:51.055Z,1726008831.055 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6362
2024-09-10T22:53:51.059Z,1726008831.059 [controlThread ThreadHandler](INFO): Handler Thread ID is 6363
2024-09-10T22:53:51.059Z,1726008831.059 [controlThread](DEBUG): Initializing ControlThread
2024-09-10T22:53:51.060Z,1726008831.060 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-09-10T22:53:51.061Z,1726008831.061 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-09-10T22:53:51.062Z,1726008831.062 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-09-10T22:53:51.063Z,1726008831.063 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-09-10T22:53:51.068Z,1726008831.068 [SBIT](INFO): Initialize SBIT Component.
2024-09-10T22:53:51.068Z,1726008831.068 [SBIT](IMPORTANT): git: 2024-08-28_A
2024-09-10T22:53:51.068Z,1726008831.068 [SBIT](INFO): git hash: 19b1c3b1e3952ad2aa64ad64d76ff1fa7009b665
2024-09-10T22:53:51.069Z,1726008831.069 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-09-10T22:53:51.070Z,1726008831.070 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2024-09-10T22:53:51.071Z,1726008831.071 [SBIT](INFO): Beginning SBIT in 84.000000 seconds.
2024-09-10T22:53:51.071Z,1726008831.071 [IBIT](INFO): Initialize IBIT Component.
2024-09-10T22:53:51.072Z,1726008831.072 [CBIT](DEBUG): Initialize CBIT Component.
2024-09-10T22:53:51.073Z,1726008831.073 [logger ThreadHandler](INFO): Handler Thread ID is 6364
2024-09-10T22:53:51.086Z,1726008831.086 [CBIT](DEBUG): Initialized mux pins.
2024-09-10T22:53:51.086Z,1726008831.086 [CBIT](DEBUG): Initializing the watchdog timer.
2024-09-10T22:53:51.090Z,1726008831.090 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6365
2024-09-10T22:53:51.098Z,1726008831.098 [DATMMP ThreadHandler](INFO): Handler Thread ID is 6366
2024-09-10T22:53:51.099Z,1726008831.099 [DATMMP](INFO): Start
2024-09-10T22:53:51.099Z,1726008831.099 [DATMMP](INFO): Powering up
2024-09-10T22:53:51.099Z,1726008831.099 [DATMMP](DEBUG): Initializing DATMMP.
2024-09-10T22:53:51.103Z,1726008831.103 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6368
2024-09-10T22:53:51.105Z,1726008831.105 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-09-10T22:53:51.110Z,1726008831.110 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-09-10T22:53:51.110Z,1726008831.110 [CBIT](DEBUG): Initializing heartbeat.
2024-09-10T22:53:51.118Z,1726008831.118 [Onboard ThreadHandler](INFO): Handler Thread ID is 6369
2024-09-10T22:53:51.135Z,1726008831.135 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6370
2024-09-10T22:53:51.159Z,1726008831.159 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6371
2024-09-10T22:53:51.159Z,1726008831.159 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-09-10T22:53:51.163Z,1726008831.163 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6373
2024-09-10T22:53:51.166Z,1726008831.166 [WetLabsBB2FL](INFO): Powering up
2024-09-10T22:53:51.167Z,1726008831.167 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6375
2024-09-10T22:53:51.170Z,1726008831.170 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2024-09-10T22:53:51.170Z,1726008831.170 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2024-09-10T22:53:51.170Z,1726008831.170 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2024-09-10T22:53:51.170Z,1726008831.170 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2024-09-10T22:53:51.171Z,1726008831.171 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-09-10T22:53:51.171Z,1726008831.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-09-10T22:53:51.171Z,1726008831.171 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-09-10T22:53:51.171Z,1726008831.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-09-10T22:53:51.171Z,1726008831.171 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2024-09-10T22:53:51.171Z,1726008831.171 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2024-09-10T22:53:51.171Z,1726008831.171 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2024-09-10T22:53:51.172Z,1726008831.172 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2024-09-10T22:53:51.181Z,1726008831.181 [CBIT](DEBUG): Deactivating GF circuits.
2024-09-10T22:53:51.182Z,1726008831.182 [CBIT](DEBUG): Deactivating emergency mode.
2024-09-10T22:53:51.221Z,1726008831.221 [CBIT](DEBUG): Backplane powered.
2024-09-10T22:53:51.222Z,1726008831.222 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-09-10T22:53:51.222Z,1726008831.222 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-09-10T22:53:51.223Z,1726008831.223 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-09-10T22:53:51.223Z,1726008831.223 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-09-10T22:53:51.223Z,1726008831.223 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-09-10T22:53:51.225Z,1726008831.225 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-10T22:53:51.225Z,1726008831.225 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-09-10T22:53:51.226Z,1726008831.226 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-09-10T22:53:51.230Z,1726008831.230 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-09-10T22:53:51.231Z,1726008831.231 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-09-10T22:53:51.231Z,1726008831.231 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-09-10T22:53:51.232Z,1726008831.232 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-09-10T22:53:51.296Z,1726008831.296 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-09-10T22:53:51.301Z,1726008831.301 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-10T22:53:51.340Z,1726008831.340 [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-09-10T22:53:51.341Z,1726008831.341 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-09-10T22:53:51.341Z,1726008831.341 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-09-10T22:53:51.342Z,1726008831.342 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-09-10T22:53:51.350Z,1726008831.350 [DATMMP](INFO): Starting
2024-09-10T22:53:51.542Z,1726008831.542 [Radio_Surface](INFO): Powering up
2024-09-10T22:53:51.602Z,1726008831.602 [DATMMP](INFO): Starting
2024-09-10T22:53:51.631Z,1726008831.631 [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-09-10T22:53:51.649Z,1726008831.649 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-09-10T22:53:51.650Z,1726008831.650 [Default:A.Wait](DEBUG): Construct Wait.
2024-09-10T22:53:51.652Z,1726008831.652 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-10T22:53:51.717Z,1726008831.717 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-09-10T22:53:51.719Z,1726008831.719 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-09-10T22:53:51.741Z,1726008831.741 [Default:E.Execute](DEBUG): Construct Execute.
2024-09-10T22:53:51.744Z,1726008831.744 [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-09-10T22:53:51.758Z,1726008831.758 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-09-10T22:53:51.770Z,1726008831.770 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-09-10T22:53:51.835Z,1726008831.835 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2024-09-10T22:53:51.836Z,1726008831.836 [Power24vConverter](INFO): Powering up.
2024-09-10T22:53:51.837Z,1726008831.837 [Sonardyne_Nano](INFO): Initializing.
2024-09-10T22:53:51.854Z,1726008831.854 [DATMMP](INFO): Starting
2024-09-10T22:53:51.914Z,1726008831.914 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-09-10T22:53:51.942Z,1726008831.942 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-09-10T22:53:51.950Z,1726008831.950 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-09-10T22:53:51.951Z,1726008831.951 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-09-10T22:53:51.958Z,1726008831.958 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-09-10T22:53:51.959Z,1726008831.959 [MassServo](DEBUG): Initializing EZServoServo.
2024-09-10T22:53:51.966Z,1726008831.966 [MassServo](DEBUG): Initializing MassServo.
2024-09-10T22:53:51.967Z,1726008831.967 [RudderServo](DEBUG): Initializing EZServoServo.
2024-09-10T22:53:51.974Z,1726008831.974 [RudderServo](DEBUG): Initializing RudderServo.
2024-09-10T22:53:51.975Z,1726008831.975 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-09-10T22:53:51.982Z,1726008831.982 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-09-10T22:53:52.106Z,1726008832.106 [DATMMP](INFO): Starting
2024-09-10T22:53:52.214Z,1726008832.214 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-09-10T22:53:52.214Z,1726008832.214 [DropWeight] Hardware Fault, FailCount= 1
2024-09-10T22:53:52.214Z,1726008832.214 [DropWeight](ERROR): Hardware Fault
2024-09-10T22:53:52.240Z,1726008832.240 [CommandExec](FAULT): Scheduling is paused
2024-09-10T22:53:52.240Z,1726008832.240 [CBIT](INFO): Critical error at 20240910T225352
2024-09-10T22:53:52.240Z,1726008832.240 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-09-10T22:53:52.251Z,1726008832.251 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-09-10T22:53:52.251Z,1726008832.251 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-09-10T22:53:52.358Z,1726008832.358 [DATMMP](INFO): Starting
2024-09-10T22:53:52.604Z,1726008832.604 [CBIT](INFO): Critical error at 20240910T225352
2024-09-10T22:53:52.610Z,1726008832.610 [DATMMP](INFO): Starting
2024-09-10T22:53:52.862Z,1726008832.862 [DATMMP](INFO): Starting
2024-09-10T22:53:53.114Z,1726008833.114 [DATMMP](INFO): Starting
2024-09-10T22:53:53.274Z,1726008833.274 [WetLabsBB2FL](INFO): Powering down
2024-09-10T22:53:53.366Z,1726008833.366 [DATMMP](INFO): Starting
2024-09-10T22:53:53.618Z,1726008833.618 [DATMMP](INFO): Starting
2024-09-10T22:53:53.788Z,1726008833.788 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-09-10T22:53:53.870Z,1726008833.870 [DATMMP](INFO): Starting
2024-09-10T22:53:54.122Z,1726008834.122 [DATMMP](INFO): Starting
2024-09-10T22:53:54.374Z,1726008834.374 [DATMMP](INFO): Starting
2024-09-10T22:53:54.626Z,1726008834.626 [DATMMP](INFO): Starting
2024-09-10T22:53:54.878Z,1726008834.878 [DATMMP](INFO): Starting
2024-09-10T22:53:55.090Z,1726008835.090 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2024-09-10T22:53:55.130Z,1726008835.130 [DATMMP](INFO): Starting
2024-09-10T22:53:55.382Z,1726008835.382 [DATMMP](INFO): Starting
2024-09-10T22:53:55.634Z,1726008835.634 [DATMMP](INFO): Starting
2024-09-10T22:53:55.886Z,1726008835.886 [DATMMP](INFO): Starting
2024-09-10T22:53:56.138Z,1726008836.138 [DATMMP](INFO): Starting
2024-09-10T22:53:56.390Z,1726008836.390 [DATMMP](INFO): Starting
2024-09-10T22:53:56.642Z,1726008836.642 [DATMMP](INFO): Starting
2024-09-10T22:53:56.894Z,1726008836.894 [DATMMP](INFO): Starting
2024-09-10T22:53:57.146Z,1726008837.146 [DATMMP](INFO): Starting
2024-09-10T22:53:57.398Z,1726008837.398 [DATMMP](INFO): Starting
2024-09-10T22:53:57.650Z,1726008837.650 [DATMMP](INFO): Starting
2024-09-10T22:53:57.903Z,1726008837.903 [DATMMP](INFO): Starting
2024-09-10T22:53:58.154Z,1726008838.154 [DATMMP](INFO): Starting
2024-09-10T22:53:58.190Z,1726008838.190 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-09-10T22:53:58.406Z,1726008838.406 [DATMMP](INFO): Starting
2024-09-10T22:53:58.658Z,1726008838.658 [DATMMP](INFO): Starting
2024-09-10T22:53:58.910Z,1726008838.910 [DATMMP](INFO): Starting
2024-09-10T22:53:59.162Z,1726008839.162 [DATMMP](INFO): Starting
2024-09-10T22:53:59.414Z,1726008839.414 [DATMMP](INFO): Starting
2024-09-10T22:53:59.666Z,1726008839.666 [DATMMP](INFO): Starting
2024-09-10T22:53:59.919Z,1726008839.919 [DATMMP](INFO): Starting
2024-09-10T22:54:00.170Z,1726008840.170 [DATMMP](INFO): Starting
2024-09-10T22:54:00.422Z,1726008840.422 [DATMMP](INFO): Starting
2024-09-10T22:54:00.674Z,1726008840.674 [DATMMP](INFO): Starting
2024-09-10T22:54:00.926Z,1726008840.926 [DATMMP](INFO): Starting
2024-09-10T22:54:01.178Z,1726008841.178 [DATMMP](INFO): Starting
2024-09-10T22:54:01.430Z,1726008841.430 [DATMMP](INFO): Starting
2024-09-10T22:54:01.682Z,1726008841.682 [DATMMP](INFO): Starting
2024-09-10T22:54:01.934Z,1726008841.934 [DATMMP](INFO): Starting
2024-09-10T22:54:02.186Z,1726008842.186 [DATMMP](INFO): Starting
2024-09-10T22:54:02.438Z,1726008842.438 [DATMMP](INFO): Starting
2024-09-10T22:54:02.690Z,1726008842.690 [DATMMP](INFO): Starting
2024-09-10T22:54:02.942Z,1726008842.942 [DATMMP](INFO): Starting
2024-09-10T22:54:03.194Z,1726008843.194 [DATMMP](INFO): Starting
2024-09-10T22:54:03.446Z,1726008843.446 [DATMMP](INFO): Starting
2024-09-10T22:54:03.698Z,1726008843.698 [DATMMP](INFO): Starting
2024-09-10T22:54:03.950Z,1726008843.950 [DATMMP](INFO): Starting
2024-09-10T22:54:04.202Z,1726008844.202 [DATMMP](INFO): Starting
2024-09-10T22:54:04.202Z,1726008844.202 [DATMMP](INFO): DAT read:
2024-09-10T22:54:04.203Z,1726008844.203 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-09-10T22:54:04.454Z,1726008844.454 [DATMMP](INFO): Starting
2024-09-10T22:54:04.706Z,1726008844.706 [DATMMP](INFO): Starting
2024-09-10T22:54:04.958Z,1726008844.958 [DATMMP](INFO): Starting
2024-09-10T22:54:05.210Z,1726008845.210 [DATMMP](INFO): Starting
2024-09-10T22:54:05.229Z,1726008845.229 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004928
2024-09-10T22:54:05.462Z,1726008845.462 [DATMMP](INFO): Starting
2024-09-10T22:54:05.714Z,1726008845.714 [DATMMP](INFO): Starting
2024-09-10T22:54:05.966Z,1726008845.966 [DATMMP](INFO): Starting
2024-09-10T22:54:05.966Z,1726008845.966 [DATMMP](INFO): DAT read: MF Frequency Band
2024-09-10T22:54:05.967Z,1726008845.967 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-09-10T22:54:05.968Z,1726008845.968 [DATMMP](INFO): DAT read: Aug 10 2000 21:02:33
2024-09-10T22:54:06.218Z,1726008846.218 [DATMMP](INFO): Starting
2024-09-10T22:54:06.470Z,1726008846.470 [DATMMP](INFO): Starting
2024-09-10T22:54:06.722Z,1726008846.722 [DATMMP](INFO): Starting
2024-09-10T22:54:06.974Z,1726008846.974 [DATMMP](INFO): Starting
2024-09-10T22:54:06.975Z,1726008846.975 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-09-10T22:54:06.976Z,1726008846.976 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-09-10T22:54:06.976Z,1726008846.976 [DATMMP](INFO): commRate: 800
2024-09-10T22:54:07.226Z,1726008847.226 [DATMMP](INFO): Starting
2024-09-10T22:54:07.478Z,1726008847.478 [DATMMP](INFO): Starting
2024-09-10T22:54:07.730Z,1726008847.730 [DATMMP](INFO): Starting
2024-09-10T22:54:07.982Z,1726008847.982 [DATMMP](INFO): Starting
2024-09-10T22:54:08.234Z,1726008848.234 [DATMMP](INFO): Starting
2024-09-10T22:54:08.486Z,1726008848.486 [DATMMP](INFO): Starting
2024-09-10T22:54:08.738Z,1726008848.738 [DATMMP](INFO): Starting
2024-09-10T22:54:08.990Z,1726008848.990 [DATMMP](INFO): Starting
2024-09-10T22:54:09.042Z,1726008849.042 [DATMMP](INFO): entering command mode
2024-09-10T22:54:09.242Z,1726008849.242 [DATMMP](INFO): Starting
2024-09-10T22:54:09.242Z,1726008849.242 [DATMMP](INFO): DAT read:
2024-09-10T22:54:09.242Z,1726008849.242 [DATMMP](INFO): DAT read: user:1>
2024-09-10T22:54:09.243Z,1726008849.243 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-09-10T22:54:09.243Z,1726008849.243 [DATMMP](INFO): entering MMP mode
2024-09-10T22:54:09.494Z,1726008849.494 [DATMMP](INFO): Starting
2024-09-10T22:54:09.494Z,1726008849.494 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-10T22:54:09.496Z,1726008849.496 [DATMMP](INFO): status rx: x1
2024-09-10T22:54:09.496Z,1726008849.496 [DATMMP](INFO): MMP status: 1: started
2024-09-10T22:54:09.496Z,1726008849.496 [DATMMP](INFO): Received message type: status
2024-09-10T22:54:09.497Z,1726008849.497 [DATMMP](INFO): Handled
2024-09-10T22:54:09.746Z,1726008849.746 [DATMMP](INFO): Starting
2024-09-10T22:54:09.747Z,1726008849.747 [DATMMP](INFO): Sent Tx Power Config 8
2024-09-10T22:54:09.748Z,1726008849.748 [DATMMP](INFO): Setting local address: 1
2024-09-10T22:54:09.751Z,1726008849.751 [DATMMP](INFO): Setting time to: 22:54:9 And date to:9/10/2024
2024-09-10T22:54:09.998Z,1726008849.998 [DATMMP](INFO): Starting
2024-09-10T22:54:09.998Z,1726008849.998 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-10T22:54:09.999Z,1726008849.999 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-09-10T22:54:09.999Z,1726008849.999 [DATMMP](INFO): Received message type: mdmpower
2024-09-10T22:54:09.999Z,1726008849.999 [DATMMP](INFO): Handled
2024-09-10T22:54:10.250Z,1726008850.250 [DATMMP](INFO): Starting
2024-09-10T22:54:10.250Z,1726008850.250 [DATMMP](INFO): GSXN notify for xid: 10
2024-09-10T22:54:10.251Z,1726008850.251 [DATMMP](INFO): sreg_txpower rx: x8
2024-09-10T22:54:10.251Z,1726008850.251 [DATMMP](INFO): Received message type: sreg_txpower
2024-09-10T22:54:10.251Z,1726008850.251 [DATMMP](INFO): Match for : sreg_txpower
2024-09-10T22:54:10.251Z,1726008850.251 [DATMMP](INFO): ACK
2024-09-10T22:54:10.502Z,1726008850.502 [DATMMP](INFO): Starting
2024-09-10T22:54:10.502Z,1726008850.502 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-10T22:54:10.503Z,1726008850.503 [DATMMP](INFO): mdmlocal rx: x1 x0 x0 x0
2024-09-10T22:54:10.503Z,1726008850.503 [DATMMP](INFO): Received message type: mdmlocal
2024-09-10T22:54:10.503Z,1726008850.503 [DATMMP](INFO): Handled
2024-09-10T22:54:10.754Z,1726008850.754 [DATMMP](INFO): Starting
2024-09-10T22:54:10.754Z,1726008850.754 [DATMMP](INFO): GSXN notify for xid: 11
2024-09-10T22:54:10.755Z,1726008850.755 [DATMMP](INFO): sreg_locaddr rx: x1
2024-09-10T22:54:10.755Z,1726008850.755 [DATMMP](INFO): Received message type: sreg_locaddr
2024-09-10T22:54:10.755Z,1726008850.755 [DATMMP](INFO): Match for : sreg_locaddr
2024-09-10T22:54:10.755Z,1726008850.755 [DATMMP](INFO): ACK
2024-09-10T22:54:11.006Z,1726008851.006 [DATMMP](INFO): Starting
2024-09-10T22:54:11.006Z,1726008851.006 [DATMMP](INFO): GSXN notify for xid: 12
2024-09-10T22:54:11.007Z,1726008851.007 [DATMMP](INFO): timedate rx: x0 x16 x36 x9 x8 xA x7C x0
2024-09-10T22:54:11.007Z,1726008851.007 [DATMMP](INFO): Received message type: timedate
2024-09-10T22:54:11.008Z,1726008851.008 [DATMMP](INFO): Match for : timedate
2024-09-10T22:54:11.008Z,1726008851.008 [DATMMP](INFO): ACK
2024-09-10T22:54:11.258Z,1726008851.258 [DATMMP](INFO): Starting
2024-09-10T22:54:11.258Z,1726008851.258 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-10T22:54:11.259Z,1726008851.259 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-09-10T22:54:11.259Z,1726008851.259 [DATMMP](INFO): Received message type: sys_verb
2024-09-10T22:54:11.259Z,1726008851.259 [DATMMP](INFO): Handled
2024-09-10T22:54:11.510Z,1726008851.510 [DATMMP](INFO): Starting
2024-09-10T22:54:11.510Z,1726008851.510 [DATMMP](INFO): GSXN notify for xid: 13
2024-09-10T22:54:11.511Z,1726008851.511 [DATMMP](INFO): sreg_verbosity rx: x0
2024-09-10T22:54:11.511Z,1726008851.511 [DATMMP](INFO): Received message type: sreg_verbosity
2024-09-10T22:54:11.511Z,1726008851.511 [DATMMP](INFO): Match for : sreg_verbosity
2024-09-10T22:54:11.511Z,1726008851.511 [DATMMP](INFO): ACK
2024-09-10T22:54:11.762Z,1726008851.762 [DATMMP](INFO): Starting
2024-09-10T22:54:11.762Z,1726008851.762 [DATMMP](INFO): GSXN notify for xid: 14
2024-09-10T22:54:11.763Z,1726008851.763 [DATMMP](INFO): version rx: x2 x5
2024-09-10T22:54:11.763Z,1726008851.763 [DATMMP](INFO): DatMMPVersion: 2.5
2024-09-10T22:54:11.763Z,1726008851.763 [DATMMP](INFO): Received message type: version
2024-09-10T22:54:11.763Z,1726008851.763 [DATMMP](INFO): Match for : version
2024-09-10T22:54:11.763Z,1726008851.763 [DATMMP](INFO): ACK
2024-09-10T22:54:11.764Z,1726008851.764 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-09-10T22:54:19.302Z,1726008859.302 [NAL9602](INFO): Powering up NAL9602
2024-09-10T22:54:21.331Z,1726008861.331 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,220320195.0, +0.0, 0.0,1475.0, 0
2024-09-10T22:54:25.842Z,1726008865.842 [Waterlinked](INFO): Powering down
2024-09-10T22:54:26.142Z,1726008866.142 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-10T22:54:26.143Z,1726008866.143 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-09-10T22:54:26.143Z,1726008866.143 [DATMMP](INFO): Received message type: timesync
2024-09-10T22:54:26.143Z,1726008866.143 [DATMMP](INFO): Handled
2024-09-10T22:54:30.211Z,1726008870.211 [NAL9602](INFO): NAL9602 initialized
2024-09-10T22:54:36.222Z,1726008876.222 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-10T22:54:36.223Z,1726008876.223 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-09-10T22:54:36.223Z,1726008876.223 [DATMMP](INFO): Received message type: timesync
2024-09-10T22:54:36.223Z,1726008876.223 [DATMMP](INFO): Handled
2024-09-10T22:54:58.890Z,1726008898.890 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:55:15.510Z,1726008915.510 [SBIT](IMPORTANT): Beginning Startup BIT
2024-09-10T22:55:15.514Z,1726008915.514 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-10T22:55:26.589Z,1726008926.589 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015910
CHAN A1 (24V): 0.026026
CHAN A2 (12V): 0.013046
CHAN A3 (5V): 0.003338
CHAN B0 (3.3V): 0.003606
CHAN B1 (3.15aV): 0.003129
CHAN B2 (3.15bV): 0.003002
CHAN B3 (GND): -0.000622
OPEN: 0.008854
Full Scale: +/- 1 mA
2024-09-10T22:55:42.630Z,1726008942.630 [CommandExec](IMPORTANT): got command gfscan
2024-09-10T22:55:43.001Z,1726008943.001 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-10T22:55:53.865Z,1726008953.865 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.016477
CHAN A1 (24V): 0.026359
CHAN A2 (12V): 0.013335
CHAN A3 (5V): 0.003316
CHAN B0 (3.3V): 0.003484
CHAN B1 (3.15aV): 0.003200
CHAN B2 (3.15bV): 0.003320
CHAN B3 (GND): -0.000445
OPEN: 0.008814
Full Scale: +/- 1 mA
2024-09-10T22:56:09.614Z,1726008969.614 [SBIT](IMPORTANT): SBIT PASSED
2024-09-10T22:56:09.615Z,1726008969.615 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-09-10T22:56:09.616Z,1726008969.616 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2024-09-10T22:56:09.616Z,1726008969.616 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-09-10T22:56:09.616Z,1726008969.616 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-09-10T22:56:09.616Z,1726008969.616 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-09-10T22:56:09.616Z,1726008969.616 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-09-10T22:56:09.616Z,1726008969.616 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 10.000000 meter;
2024-09-10T22:56:09.616Z,1726008969.616 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 0.600000 count_per_second;
2024-09-10T22:56:09.616Z,1726008969.616 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 0.600000 count_per_second;
2024-09-10T22:56:09.616Z,1726008969.616 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_caseHumidity 10.000000 percent;
2024-09-10T22:56:09.617Z,1726008969.617 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_diatoms 30.000000 count_per_milliliter;
2024-09-10T22:56:09.617Z,1726008969.617 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_dinoflagellates 30.000000 count_per_milliliter;
2024-09-10T22:56:09.617Z,1726008969.617 [SBIT](IMPORTANT): Express linearApproximation _.predict_plankton_data_pct_dinos_sqerr 0.100000 none;
2024-09-10T22:56:09.617Z,1726008969.617 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2024-09-10T22:56:09.617Z,1726008969.617 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;
2024-09-10T22:56:09.617Z,1726008969.617 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-09-10T22:56:09.617Z,1726008969.617 [SBIT](IMPORTANT): Normal all _.predict_plankton_data_pct_dinos_sqerr;
2024-09-10T22:56:09.618Z,1726008969.618 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=244.404459 cubic_centimeter;
2024-09-10T22:56:09.618Z,1726008969.618 [SBIT](IMPORTANT): VerticalControl.massDefault=16.138818 millimeter;
2024-09-10T22:56:10.024Z,1726008970.024 [MissionManager](IMPORTANT): Started mission Startup
2024-09-10T22:56:10.025Z,1726008970.025 [Startup] Running Loop=1
2024-09-10T22:56:10.025Z,1726008970.025 [Startup](DEBUG): Aggregate::initialize Startup
2024-09-10T22:56:10.025Z,1726008970.025 [Startup:A.GoToSurface] Running Loop=1
2024-09-10T22:56:10.025Z,1726008970.025 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-10T22:56:10.026Z,1726008970.026 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-10T22:56:10.026Z,1726008970.026 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-10T22:56:10.026Z,1726008970.026 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-10T22:56:10.027Z,1726008970.027 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-10T22:56:10.027Z,1726008970.027 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-10T22:56:10.027Z,1726008970.027 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-10T22:56:10.030Z,1726008970.030 [Startup:StartupSatComms] Running Loop=1
2024-09-10T22:56:10.030Z,1726008970.030 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-09-10T22:56:10.031Z,1726008970.031 [Startup:StartupSatComms:A] Running Loop=1
2024-09-10T22:56:10.433Z,1726008970.433 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-09-10T22:56:22.523Z,1726008982.523 [CommandExec](IMPORTANT): got command strobe off
2024-09-10T22:56:22.523Z,1726008982.523 [CommandExec](IMPORTANT): Deactivating strobe
2024-09-10T22:56:51.634Z,1726009011.634 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-09-10T22:56:51.634Z,1726009011.634 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-10T22:56:51.635Z,1726009011.635 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-09-10T22:56:51.635Z,1726009011.635 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-09-10T22:56:51.637Z,1726009011.637 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-10T22:56:51.637Z,1726009011.637 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-10T22:56:51.729Z,1726009011.729 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-10T22:56:51.729Z,1726009011.729 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-09-10T22:56:51.730Z,1726009011.730 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-10T22:56:52.049Z,1726009012.049 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-10T22:56:52.049Z,1726009012.049 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-09-10T22:56:52.058Z,1726009012.058 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-09-10T22:56:52.058Z,1726009012.058 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-09-10T22:56:52.058Z,1726009012.058 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-10T22:56:52.058Z,1726009012.058 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-10T22:56:52.449Z,1726009012.449 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-10T22:57:00.678Z,1726009020.678 [CommandExec](IMPORTANT): got command gfscan
2024-09-10T22:57:00.926Z,1726009020.926 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-10T22:57:10.227Z,1726009030.227 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-10T22:56:10.0Z
2024-09-10T22:57:10.227Z,1726009030.227 [Startup:StartupSatComms:A] Stopped
2024-09-10T22:57:10.227Z,1726009030.227 [Startup:StartupSatComms:B] Running Loop=1
2024-09-10T22:57:10.640Z,1726009030.640 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-10T22:57:12.004Z,1726009032.004 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.017230
CHAN A1 (24V): -0.158008
CHAN A2 (12V): 0.013364
CHAN A3 (5V): 0.003579
CHAN B0 (3.3V): 0.002068
CHAN B1 (3.15aV): 0.003124
CHAN B2 (3.15bV): 0.002829
CHAN B3 (GND): 0.028559
OPEN: -0.004567
Full Scale: +/- 1 mA
2024-09-10T22:57:17.149Z,1726009037.149 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240910T185606/Courier0043.lzma
2024-09-10T22:57:18.152Z,1726009038.152 [DataOverHttps](INFO): Moved sent file to Logs/20240910T185606/Courier0043.lzma.bak
2024-09-10T22:57:18.152Z,1726009038.152 [DataOverHttps](INFO): SBD MOMSN=23156252
2024-09-10T22:57:26.907Z,1726009046.907 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:57:34.246Z,1726009054.246 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20240910T225340/Courier0000.lzma
2024-09-10T22:57:35.247Z,1726009055.247 [DataOverHttps](INFO): Moved sent file to Logs/20240910T225340/Courier0000.lzma.bak
2024-09-10T22:57:35.247Z,1726009055.247 [DataOverHttps](INFO): SBD MOMSN=23156254
2024-09-10T22:57:42.258Z,1726009062.258 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:57:51.190Z,1726009071.190 [DataOverHttps](INFO): Sending 454 bytes from file Logs/20240910T185606/Express0041.lzma
2024-09-10T22:57:52.350Z,1726009072.350 [CommandExec](IMPORTANT): got command gfscan
2024-09-10T22:57:52.426Z,1726009072.426 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-10T22:57:53.196Z,1726009073.196 [DataOverHttps](INFO): Moved sent file to Logs/20240910T185606/Express0041.lzma.bak
2024-09-10T22:57:53.196Z,1726009073.196 [DataOverHttps](INFO): SBD MOMSN=23156259
2024-09-10T22:57:57.313Z,1726009077.313 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:58:03.315Z,1726009083.315 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.088127
CHAN A1 (24V): 0.027012
CHAN A2 (12V): 0.012888
CHAN A3 (5V): 0.001575
CHAN B0 (3.3V): 0.003565
CHAN B1 (3.15aV): 0.060190
CHAN B2 (3.15bV): 0.003083
CHAN B3 (GND): -0.000198
OPEN: 0.104579
Full Scale: +/- 1 mA
2024-09-10T22:58:09.402Z,1726009089.402 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20240910T185606/Express0044.lzma
2024-09-10T22:58:10.404Z,1726009090.404 [DataOverHttps](INFO): Moved sent file to Logs/20240910T185606/Express0044.lzma.bak
2024-09-10T22:58:10.404Z,1726009090.404 [DataOverHttps](INFO): SBD MOMSN=23156269
2024-09-10T22:58:10.614Z,1726009090.614 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-10T22:57:10.2Z
2024-09-10T22:58:10.614Z,1726009090.614 [Startup:StartupSatComms:B] Stopped
2024-09-10T22:58:10.614Z,1726009090.614 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-09-10T22:58:10.614Z,1726009090.614 [Startup:StartupSatComms] Stopped
2024-09-10T22:58:10.614Z,1726009090.614 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-09-10T22:58:10.615Z,1726009090.615 [Startup](INFO): Completed Startup
2024-09-10T22:58:10.615Z,1726009090.615 [MissionManager](INFO): Startup is completed.
2024-09-10T22:58:10.615Z,1726009090.615 [MissionManager](INFO): Uninitializing Mission Startup
2024-09-10T22:58:10.615Z,1726009090.615 [Startup] Stopped
2024-09-10T22:58:10.615Z,1726009090.615 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-09-10T22:58:10.616Z,1726009090.616 [Startup:A.GoToSurface] Stopped
2024-09-10T22:58:10.616Z,1726009090.616 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-10T22:58:10.984Z,1726009090.984 [MissionManager](IMPORTANT): Started mission Default
2024-09-10T22:58:10.985Z,1726009090.985 [Default] Running Loop=1
2024-09-10T22:58:10.985Z,1726009090.985 [Default](DEBUG): Aggregate::initialize Default
2024-09-10T22:58:10.985Z,1726009090.985 [Default:B.GoToSurface] Running Loop=1
2024-09-10T22:58:10.985Z,1726009090.985 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-10T22:58:10.989Z,1726009090.989 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-10T22:58:10.990Z,1726009090.990 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-10T22:58:10.990Z,1726009090.990 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-10T22:58:10.990Z,1726009090.990 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-10T22:58:10.991Z,1726009090.991 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-10T22:58:10.991Z,1726009090.991 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-10T22:58:10.991Z,1726009090.991 [Default:A.Wait] Running Loop=1
2024-09-10T22:58:10.991Z,1726009090.991 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-10T22:58:12.154Z,1726009092.154 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:58:24.325Z,1726009104.325 [Default:A.Wait](INFO): Done Waiting.
2024-09-10T22:58:24.325Z,1726009104.325 [Default:A.Wait] Stopped
2024-09-10T22:58:24.325Z,1726009104.325 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-10T22:58:24.714Z,1726009104.714 [Default:CheckIn] Running Loop=1
2024-09-10T22:58:24.714Z,1726009104.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-10T22:58:24.714Z,1726009104.714 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-10T22:58:25.113Z,1726009105.113 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-09-10T22:58:27.506Z,1726009107.506 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:58:42.454Z,1726009122.454 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:58:45.958Z,1726009125.958 [CommandExec](IMPORTANT): got command gfscan
2024-09-10T22:58:46.146Z,1726009126.146 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-10T22:58:57.154Z,1726009137.154 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015828
CHAN A1 (24V): 0.025450
CHAN A2 (12V): 0.012302
CHAN A3 (5V): 0.003316
CHAN B0 (3.3V): 0.003650
CHAN B1 (3.15aV): 0.002465
CHAN B2 (3.15bV): 0.002529
CHAN B3 (GND): -0.000668
OPEN: -0.052111
Full Scale: +/- 1 mA
2024-09-10T22:58:57.402Z,1726009137.402 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:59:12.350Z,1726009152.350 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:59:27.703Z,1726009167.703 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T22:59:33.370Z,1726009173.370 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-10T22:59:42.247Z,1726009182.247 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-09-10T22:59:52.775Z,1726009192.775 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-09-10T22:59:52.775Z,1726009192.775 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-10T22:59:52.776Z,1726009192.776 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-09-10T22:59:52.776Z,1726009192.776 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-09-10T22:59:52.777Z,1726009192.777 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-10T22:59:52.777Z,1726009192.777 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-10T22:59:52.788Z,1726009192.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-10T22:59:52.788Z,1726009192.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-09-10T22:59:52.789Z,1726009192.789 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-10T22:59:53.193Z,1726009193.193 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-10T22:59:53.193Z,1726009193.193 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-09-10T22:59:53.194Z,1726009193.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-09-10T22:59:53.194Z,1726009193.194 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-09-10T22:59:53.194Z,1726009193.194 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-10T22:59:53.194Z,1726009193.194 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-10T22:59:53.591Z,1726009193.591 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-10T23:02:08.958Z,1726009328.958 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-10T23:02:18.282Z,1726009338.282 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2024-09-10T23:02:18.283Z,1726009338.283 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2024-09-10T23:02:18.291Z,1726009338.291 [BPC1](INFO): Received data from all battery sticks.
2024-09-10T23:02:53.891Z,1726009373.891 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-09-10T23:02:53.891Z,1726009373.891 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-10T23:02:53.892Z,1726009373.892 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2024-09-10T23:02:53.892Z,1726009373.892 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-09-10T23:02:53.906Z,1726009373.906 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2024-09-10T23:02:53.906Z,1726009373.906 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-10T23:02:53.929Z,1726009373.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-10T23:02:53.929Z,1726009373.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-09-10T23:02:53.982Z,1726009373.982 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-10T23:02:54.359Z,1726009374.359 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-10T23:02:54.359Z,1726009374.359 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-09-10T23:02:54.359Z,1726009374.359 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-09-10T23:02:54.359Z,1726009374.359 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2024-09-10T23:02:54.360Z,1726009374.360 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-10T23:02:54.360Z,1726009374.360 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2024-09-10T23:02:54.706Z,1726009374.706 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-10T23:03:24.990Z,1726009404.990 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-10T22:58:24.7Z
2024-09-10T23:03:24.990Z,1726009404.990 [Default:CheckIn:Read_GPS] Stopped
2024-09-10T23:03:24.990Z,1726009404.990 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-10T23:03:25.381Z,1726009405.381 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-10T23:03:32.237Z,1726009412.237 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240910T225340/Courier0004.lzma
2024-09-10T23:03:33.239Z,1726009413.239 [DataOverHttps](INFO): Moved sent file to Logs/20240910T225340/Courier0004.lzma.bak
2024-09-10T23:03:33.239Z,1726009413.239 [DataOverHttps](INFO): SBD MOMSN=23156314
2024-09-10T23:03:48.775Z,1726009428.775 [DataOverHttps](INFO): Sending 1214 bytes from file Logs/20240910T225340/Express0001.lzma
2024-09-10T23:03:49.776Z,1726009429.776 [DataOverHttps](INFO): Moved sent file to Logs/20240910T225340/Express0001.lzma.bak
2024-09-10T23:03:49.776Z,1726009429.776 [DataOverHttps](INFO): SBD MOMSN=23156316
2024-09-10T23:04:05.290Z,1726009445.290 [DataOverHttps](INFO): Sending 512 bytes from file Logs/20240910T225340/Express0005.lzma
2024-09-10T23:04:06.291Z,1726009446.291 [DataOverHttps](INFO): Moved sent file to Logs/20240910T225340/Express0005.lzma.bak
2024-09-10T23:04:06.292Z,1726009446.292 [DataOverHttps](INFO): SBD MOMSN=23156355
2024-09-10T23:04:07.809Z,1726009447.809 [Default:CheckIn:Read_Iridium] Stopped
2024-09-10T23:04:07.809Z,1726009447.809 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-10T23:04:07.809Z,1726009447.809 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-10T23:04:31.219Z,1726009471.219 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-09-10T23:04:31.219Z,1726009471.219 [NAL9602] Data Fault, FailCount= 1
2024-09-10T23:04:31.219Z,1726009471.219 [NAL9602](ERROR): Data Fault
2024-09-10T23:04:31.264Z,1726009471.264 [CBIT](ERROR): Data Fault in component: NAL9602
2024-09-10T23:04:31.623Z,1726009471.623 [NAL9602](INFO): Powering down
2024-09-10T23:04:32.453Z,1726009472.453 [CBIT](INFO): Clearing failed state for component NAL9602
2024-09-10T23:04:32.453Z,1726009472.453 [NAL9602] No Fault, FailCount= 1
2024-09-10T23:04:46.178Z,1726009486.178 [CommandExec](IMPORTANT): got command gfscan
2024-09-10T23:04:46.212Z,1726009486.212 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-10T23:04:57.123Z,1726009497.123 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015257
CHAN A1 (24V): 0.025579
CHAN A2 (12V): 0.012475
CHAN A3 (5V): 0.003032
CHAN B0 (3.3V): 0.003547
CHAN B1 (3.15aV): 0.003303
CHAN B2 (3.15bV): 0.002405
CHAN B3 (GND): -0.000018
OPEN: -0.053338
Full Scale: +/- 1 mA
2024-09-10T23:05:01.939Z,1726009501.939 [NAL9602](INFO): Powering up NAL9602
2024-09-10T23:05:12.851Z,1726009512.851 [NAL