2024-09-11T17:08:13.359Z,1726074493.359 [Supervisor](DEBUG): Initializing supervisor.
2024-09-11T17:08:13.363Z,1726074493.363 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-09-11T17:08:13.364Z,1726074493.364 [SyncHandler](INFO): Protected caller Thread ID is 838
2024-09-11T17:08:13.364Z,1726074493.364 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-09-11T17:08:13.365Z,1726074493.365 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-09-11T17:08:13.366Z,1726074493.366 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 839
2024-09-11T17:08:13.370Z,1726074493.370 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-09-11T17:08:13.387Z,1726074493.387 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-09-11T17:08:13.388Z,1726074493.388 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-09-11T17:08:13.388Z,1726074493.388 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 840
2024-09-11T17:08:13.392Z,1726074493.392 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-09-11T17:08:13.393Z,1726074493.393 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-09-11T17:08:13.394Z,1726074493.394 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 841
2024-09-11T17:08:13.396Z,1726074493.396 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-09-11T17:08:13.397Z,1726074493.397 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-09-11T17:08:13.397Z,1726074493.397 [logger ThreadHandler](INFO): Protected caller Thread ID is 842
2024-09-11T17:08:13.401Z,1726074493.401 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-09-11T17:08:13.401Z,1726074493.401 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-09-11T17:08:13.404Z,1726074493.404 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-09-11T17:08:13.747Z,1726074493.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-09-11T17:08:13.749Z,1726074493.749 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-09-11T17:08:13.841Z,1726074493.841 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-09-11T17:08:13.960Z,1726074493.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-09-11T17:08:13.960Z,1726074493.960 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-09-11T17:08:14.099Z,1726074494.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-09-11T17:08:14.100Z,1726074494.100 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-09-11T17:08:14.454Z,1726074494.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-09-11T17:08:14.455Z,1726074494.455 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-09-11T17:08:14.561Z,1726074494.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-09-11T17:08:14.563Z,1726074494.563 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-09-11T17:08:14.985Z,1726074494.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-09-11T17:08:14.987Z,1726074494.987 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-09-11T17:08:15.209Z,1726074495.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-09-11T17:08:15.210Z,1726074495.210 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-09-11T17:08:15.300Z,1726074495.300 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-09-11T17:08:15.547Z,1726074495.547 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-09-11T17:08:15.548Z,1726074495.548 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-09-11T17:08:15.779Z,1726074495.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-09-11T17:08:15.780Z,1726074495.780 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-09-11T17:08:16.335Z,1726074496.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-09-11T17:08:16.338Z,1726074496.338 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-09-11T17:08:16.536Z,1726074496.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-09-11T17:08:16.538Z,1726074496.538 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-09-11T17:08:16.849Z,1726074496.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-09-11T17:08:16.850Z,1726074496.850 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-09-11T17:08:17.718Z,1726074497.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-09-11T17:08:17.719Z,1726074497.719 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-09-11T17:08:18.295Z,1726074498.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-09-11T17:08:18.297Z,1726074498.297 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2024-09-11T17:08:18.300Z,1726074498.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2024-09-11T17:08:18.411Z,1726074498.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2024-09-11T17:08:18.538Z,1726074498.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2024-09-11T17:08:18.632Z,1726074498.632 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2024-09-11T17:08:18.750Z,1726074498.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2024-09-11T17:08:18.847Z,1726074498.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2024-09-11T17:08:19.114Z,1726074499.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-09-11T17:08:19.114Z,1726074499.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2024-09-11T17:08:19.233Z,1726074499.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2024-09-11T17:08:19.341Z,1726074499.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2024-09-11T17:08:19.456Z,1726074499.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2024-09-11T17:08:19.607Z,1726074499.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2024-09-11T17:08:19.703Z,1726074499.703 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-09-11T17:08:19.724Z,1726074499.724 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-09-11T17:08:19.860Z,1726074499.860 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-09-11T17:08:19.860Z,1726074499.860 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-09-11T17:08:19.928Z,1726074499.928 [VerticalControl](DEBUG): Construct VerticalControl.
2024-09-11T17:08:19.987Z,1726074499.987 [VerticalControl] Loaded
2024-09-11T17:08:19.987Z,1726074499.987 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-09-11T17:08:19.990Z,1726074499.990 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-09-11T17:08:20.037Z,1726074500.037 [HorizontalControl] Loaded
2024-09-11T17:08:20.037Z,1726074500.037 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-09-11T17:08:20.039Z,1726074500.039 [SpeedControl](DEBUG): Construct SpeedControl.
2024-09-11T17:08:20.042Z,1726074500.042 [SpeedControl] Loaded
2024-09-11T17:08:20.042Z,1726074500.042 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-09-11T17:08:20.045Z,1726074500.045 [LoopControl](DEBUG): Construct LoopControl.
2024-09-11T17:08:20.046Z,1726074500.046 [LoopControl] Loaded
2024-09-11T17:08:20.046Z,1726074500.046 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-09-11T17:08:20.046Z,1726074500.046 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-09-11T17:08:20.047Z,1726074500.047 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-09-11T17:08:20.142Z,1726074500.142 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-09-11T17:08:20.142Z,1726074500.142 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-09-11T17:08:20.946Z,1726074500.946 [AHRS_M2] Loaded
2024-09-11T17:08:20.947Z,1726074500.947 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-09-11T17:08:21.198Z,1726074501.198 [BackseatComponent] Loaded
2024-09-11T17:08:21.199Z,1726074501.199 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-09-11T17:08:21.200Z,1726074501.200 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2024-09-11T17:08:21.200Z,1726074501.200 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924
2024-09-11T17:08:21.203Z,1726074501.203 [LcmUniversalReporter] Loaded
2024-09-11T17:08:21.203Z,1726074501.203 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-09-11T17:08:22.029Z,1726074502.029 [BPC1] Loaded
2024-09-11T17:08:22.029Z,1726074502.029 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-09-11T17:08:22.113Z,1726074502.113 [DATMMP] Loaded
2024-09-11T17:08:22.113Z,1726074502.113 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-09-11T17:08:22.114Z,1726074502.114 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2024-09-11T17:08:22.115Z,1726074502.115 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 925
2024-09-11T17:08:22.254Z,1726074502.254 [DataOverHttps] Loaded
2024-09-11T17:08:22.254Z,1726074502.254 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-09-11T17:08:22.255Z,1726074502.255 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2024-09-11T17:08:22.255Z,1726074502.255 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926
2024-09-11T17:08:22.278Z,1726074502.278 [Depth_Keller] Loaded
2024-09-11T17:08:22.278Z,1726074502.278 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-09-11T17:08:22.283Z,1726074502.283 [DropWeight] Loaded
2024-09-11T17:08:22.283Z,1726074502.283 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-09-11T17:08:22.346Z,1726074502.346 [NAL9602] Loaded
2024-09-11T17:08:22.346Z,1726074502.346 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-09-11T17:08:22.376Z,1726074502.376 [Onboard] Loaded
2024-09-11T17:08:22.376Z,1726074502.376 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-09-11T17:08:22.377Z,1726074502.377 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2024-09-11T17:08:22.377Z,1726074502.377 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927
2024-09-11T17:08:22.391Z,1726074502.391 [Power24vConverter] Loaded
2024-09-11T17:08:22.391Z,1726074502.391 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-09-11T17:08:22.406Z,1726074502.406 [Radio_Surface] Loaded
2024-09-11T17:08:22.406Z,1726074502.406 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-09-11T17:08:22.407Z,1726074502.407 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2024-09-11T17:08:22.408Z,1726074502.408 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928
2024-09-11T17:08:22.423Z,1726074502.423 [Sonardyne_Nano] Loaded
2024-09-11T17:08:22.423Z,1726074502.423 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-09-11T17:08:22.446Z,1726074502.446 [Waterlinked] Loaded
2024-09-11T17:08:22.446Z,1726074502.446 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-09-11T17:08:22.447Z,1726074502.447 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-09-11T17:08:22.447Z,1726074502.447 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-09-11T17:08:22.463Z,1726074502.463 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-09-11T17:08:22.463Z,1726074502.463 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-09-11T17:08:22.505Z,1726074502.505 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-09-11T17:08:22.505Z,1726074502.505 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-09-11T17:08:22.670Z,1726074502.670 [SBIT](DEBUG): Construct Startup Built In Test.
2024-09-11T17:08:22.678Z,1726074502.678 [SBIT] Loaded
2024-09-11T17:08:22.679Z,1726074502.679 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-09-11T17:08:22.681Z,1726074502.681 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-09-11T17:08:22.708Z,1726074502.708 [IBIT] Loaded
2024-09-11T17:08:22.709Z,1726074502.709 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-09-11T17:08:22.714Z,1726074502.714 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-09-11T17:08:22.817Z,1726074502.817 [CBIT] Loaded
2024-09-11T17:08:22.817Z,1726074502.817 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-09-11T17:08:22.818Z,1726074502.818 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-09-11T17:08:22.818Z,1726074502.818 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-09-11T17:08:23.205Z,1726074503.205 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-09-11T17:08:23.206Z,1726074503.206 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-09-11T17:08:23.274Z,1726074503.274 [DepthRateCalculator] Loaded
2024-09-11T17:08:23.274Z,1726074503.274 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-09-11T17:08:23.279Z,1726074503.279 [PitchRateCalculator] Loaded
2024-09-11T17:08:23.279Z,1726074503.279 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-09-11T17:08:23.289Z,1726074503.289 [SpeedCalculator] Loaded
2024-09-11T17:08:23.290Z,1726074503.290 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-09-11T17:08:23.294Z,1726074503.294 [YawRateCalculator] Loaded
2024-09-11T17:08:23.294Z,1726074503.294 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-09-11T17:08:23.314Z,1726074503.314 [ElevatorOffsetCalculator] Loaded
2024-09-11T17:08:23.314Z,1726074503.314 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-09-11T17:08:23.314Z,1726074503.314 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-09-11T17:08:23.315Z,1726074503.315 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-09-11T17:08:23.524Z,1726074503.524 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-09-11T17:08:23.525Z,1726074503.525 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-09-11T17:08:23.659Z,1726074503.659 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-09-11T17:08:23.660Z,1726074503.660 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-09-11T17:08:23.702Z,1726074503.702 [DeadReckonUsingSpeedCalculator] Loaded
2024-09-11T17:08:23.702Z,1726074503.702 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-09-11T17:08:23.745Z,1726074503.745 [DeadReckonWithRespectToSeafloor] Loaded
2024-09-11T17:08:23.745Z,1726074503.745 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2024-09-11T17:08:23.758Z,1726074503.758 [NavChart] Loaded
2024-09-11T17:08:23.758Z,1726074503.758 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-09-11T17:08:23.764Z,1726074503.764 [UniversalFixResidualReporter] Loaded
2024-09-11T17:08:23.764Z,1726074503.764 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-09-11T17:08:23.765Z,1726074503.765 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-09-11T17:08:23.765Z,1726074503.765 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-09-11T17:08:23.925Z,1726074503.925 [BuoyancyServo] Loaded
2024-09-11T17:08:23.925Z,1726074503.925 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-09-11T17:08:23.948Z,1726074503.948 [ElevatorServo] Loaded
2024-09-11T17:08:23.948Z,1726074503.948 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-09-11T17:08:23.970Z,1726074503.970 [MassServo] Loaded
2024-09-11T17:08:23.970Z,1726074503.970 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-09-11T17:08:23.990Z,1726074503.990 [RudderServo] Loaded
2024-09-11T17:08:23.991Z,1726074503.991 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-09-11T17:08:24.007Z,1726074504.007 [ThrusterHE] Loaded
2024-09-11T17:08:24.007Z,1726074504.007 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-09-11T17:08:24.007Z,1726074504.007 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-09-11T17:08:24.008Z,1726074504.008 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-09-11T17:08:24.230Z,1726074504.230 [CTD_Seabird] Loaded
2024-09-11T17:08:24.230Z,1726074504.230 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-09-11T17:08:24.231Z,1726074504.231 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BF24E0
2024-09-11T17:08:24.231Z,1726074504.231 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 929
2024-09-11T17:08:24.267Z,1726074504.267 [ESPComponent] Loaded
2024-09-11T17:08:24.268Z,1726074504.268 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-09-11T17:08:24.289Z,1726074504.289 [PAR_Licor] Loaded
2024-09-11T17:08:24.290Z,1726074504.290 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-09-11T17:08:24.334Z,1726074504.334 [WetLabsBB2FL] Loaded
2024-09-11T17:08:24.334Z,1726074504.334 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-09-11T17:08:24.335Z,1726074504.335 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0
2024-09-11T17:08:24.335Z,1726074504.335 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 930
2024-09-11T17:08:24.336Z,1726074504.336 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-09-11T17:08:24.342Z,1726074504.342 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-09-11T17:08:24.345Z,1726074504.345 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-09-11T17:08:24.357Z,1726074504.357 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-09-11T17:08:24.358Z,1726074504.358 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-09-11T17:08:24.358Z,1726074504.358 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931
2024-09-11T17:08:24.363Z,1726074504.363 [Supervisor](INFO): Main Thread ID is 829
2024-09-11T17:08:24.363Z,1726074504.363 [Supervisor](DEBUG): Running supervisor.
2024-09-11T17:08:24.363Z,1726074504.363 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932
2024-09-11T17:08:24.364Z,1726074504.364 [CommandExec](INFO): Initializing the command executive.
2024-09-11T17:08:24.365Z,1726074504.365 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933
2024-09-11T17:08:24.367Z,1726074504.367 [controlThread ThreadHandler](INFO): Handler Thread ID is 934
2024-09-11T17:08:24.368Z,1726074504.368 [controlThread](DEBUG): Initializing ControlThread
2024-09-11T17:08:24.369Z,1726074504.369 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-09-11T17:08:24.370Z,1726074504.370 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-09-11T17:08:24.371Z,1726074504.371 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-09-11T17:08:24.371Z,1726074504.371 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-09-11T17:08:24.378Z,1726074504.378 [SBIT](INFO): Initialize SBIT Component.
2024-09-11T17:08:24.379Z,1726074504.379 [SBIT](IMPORTANT): git: 2024-08-28_A
2024-09-11T17:08:24.379Z,1726074504.379 [SBIT](INFO): git hash: 19b1c3b1e3952ad2aa64ad64d76ff1fa7009b665
2024-09-11T17:08:24.379Z,1726074504.379 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-09-11T17:08:24.380Z,1726074504.380 [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-11T17:08:24.381Z,1726074504.381 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2024-09-11T17:08:24.382Z,1726074504.382 [IBIT](INFO): Initialize IBIT Component.
2024-09-11T17:08:24.383Z,1726074504.383 [CBIT](DEBUG): Initialize CBIT Component.
2024-09-11T17:08:24.384Z,1726074504.384 [logger ThreadHandler](INFO): Handler Thread ID is 935
2024-09-11T17:08:24.396Z,1726074504.396 [CBIT](DEBUG): Initialized mux pins.
2024-09-11T17:08:24.396Z,1726074504.396 [CBIT](DEBUG): Initializing the watchdog timer.
2024-09-11T17:08:24.404Z,1726074504.404 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 936
2024-09-11T17:08:24.416Z,1726074504.416 [DATMMP ThreadHandler](INFO): Handler Thread ID is 937
2024-09-11T17:08:24.417Z,1726074504.417 [DATMMP](INFO): Start
2024-09-11T17:08:24.417Z,1726074504.417 [DATMMP](INFO): Powering up
2024-09-11T17:08:24.417Z,1726074504.417 [DATMMP](DEBUG): Initializing DATMMP.
2024-09-11T17:08:24.420Z,1726074504.420 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-09-11T17:08:24.420Z,1726074504.420 [CBIT](DEBUG): Initializing heartbeat.
2024-09-11T17:08:24.422Z,1726074504.422 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939
2024-09-11T17:08:24.424Z,1726074504.424 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-09-11T17:08:24.433Z,1726074504.433 [Onboard ThreadHandler](INFO): Handler Thread ID is 940
2024-09-11T17:08:24.450Z,1726074504.450 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941
2024-09-11T17:08:24.468Z,1726074504.468 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 942
2024-09-11T17:08:24.469Z,1726074504.469 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-09-11T17:08:24.473Z,1726074504.473 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 944
2024-09-11T17:08:24.476Z,1726074504.476 [WetLabsBB2FL](INFO): Powering up
2024-09-11T17:08:24.478Z,1726074504.478 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 946
2024-09-11T17:08:24.484Z,1726074504.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2024-09-11T17:08:24.485Z,1726074504.485 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2024-09-11T17:08:24.485Z,1726074504.485 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2024-09-11T17:08:24.485Z,1726074504.485 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2024-09-11T17:08:24.485Z,1726074504.485 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-09-11T17:08:24.485Z,1726074504.485 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-09-11T17:08:24.485Z,1726074504.485 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-09-11T17:08:24.486Z,1726074504.486 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-09-11T17:08:24.486Z,1726074504.486 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2024-09-11T17:08:24.486Z,1726074504.486 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2024-09-11T17:08:24.486Z,1726074504.486 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2024-09-11T17:08:24.486Z,1726074504.486 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2024-09-11T17:08:24.492Z,1726074504.492 [CBIT](DEBUG): Deactivating GF circuits.
2024-09-11T17:08:24.492Z,1726074504.492 [CBIT](DEBUG): Deactivating emergency mode.
2024-09-11T17:08:24.532Z,1726074504.532 [CBIT](DEBUG): Backplane powered.
2024-09-11T17:08:24.532Z,1726074504.532 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-09-11T17:08:24.533Z,1726074504.533 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-09-11T17:08:24.533Z,1726074504.533 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-09-11T17:08:24.533Z,1726074504.533 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-09-11T17:08:24.534Z,1726074504.534 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-09-11T17:08:24.535Z,1726074504.535 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-11T17:08:24.536Z,1726074504.536 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-09-11T17:08:24.536Z,1726074504.536 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-09-11T17:08:24.540Z,1726074504.540 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-09-11T17:08:24.541Z,1726074504.541 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-09-11T17:08:24.541Z,1726074504.541 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-09-11T17:08:24.552Z,1726074504.552 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-09-11T17:08:24.598Z,1726074504.598 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-09-11T17:08:24.619Z,1726074504.619 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-11T17:08:24.658Z,1726074504.658 [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-11T17:08:24.659Z,1726074504.659 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-09-11T17:08:24.659Z,1726074504.659 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-09-11T17:08:24.665Z,1726074504.665 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-09-11T17:08:24.668Z,1726074504.668 [DATMMP](INFO): Starting
2024-09-11T17:08:24.856Z,1726074504.856 [Radio_Surface](INFO): Powering up
2024-09-11T17:08:24.920Z,1726074504.920 [DATMMP](INFO): Starting
2024-09-11T17:08:24.944Z,1726074504.944 [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-11T17:08:24.966Z,1726074504.966 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-09-11T17:08:24.967Z,1726074504.967 [Default:A.Wait](DEBUG): Construct Wait.
2024-09-11T17:08:24.970Z,1726074504.970 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-11T17:08:25.014Z,1726074505.014 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-09-11T17:08:25.025Z,1726074505.025 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-09-11T17:08:25.030Z,1726074505.030 [Default:E.Execute](DEBUG): Construct Execute.
2024-09-11T17:08:25.050Z,1726074505.050 [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-11T17:08:25.054Z,1726074505.054 [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-11T17:08:25.081Z,1726074505.081 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-09-11T17:08:25.172Z,1726074505.172 [DATMMP](INFO): Starting
2024-09-11T17:08:25.262Z,1726074505.262 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2024-09-11T17:08:25.263Z,1726074505.263 [Power24vConverter](INFO): Powering up.
2024-09-11T17:08:25.263Z,1726074505.263 [Sonardyne_Nano](INFO): Initializing.
2024-09-11T17:08:25.298Z,1726074505.298 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-09-11T17:08:25.334Z,1726074505.334 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-09-11T17:08:25.340Z,1726074505.340 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-09-11T17:08:25.341Z,1726074505.341 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-09-11T17:08:25.348Z,1726074505.348 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-09-11T17:08:25.351Z,1726074505.351 [MassServo](DEBUG): Initializing EZServoServo.
2024-09-11T17:08:25.357Z,1726074505.357 [MassServo](DEBUG): Initializing MassServo.
2024-09-11T17:08:25.357Z,1726074505.357 [RudderServo](DEBUG): Initializing EZServoServo.
2024-09-11T17:08:25.364Z,1726074505.364 [RudderServo](DEBUG): Initializing RudderServo.
2024-09-11T17:08:25.365Z,1726074505.365 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-09-11T17:08:25.372Z,1726074505.372 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-09-11T17:08:25.424Z,1726074505.424 [DATMMP](INFO): Starting
2024-09-11T17:08:25.676Z,1726074505.676 [DATMMP](INFO): Starting
2024-09-11T17:08:25.928Z,1726074505.928 [DATMMP](INFO): Starting
2024-09-11T17:08:26.180Z,1726074506.180 [DATMMP](INFO): Starting
2024-09-11T17:08:26.433Z,1726074506.433 [DATMMP](INFO): Starting
2024-09-11T17:08:26.584Z,1726074506.584 [WetLabsBB2FL](INFO): Powering down
2024-09-11T17:08:26.684Z,1726074506.684 [DATMMP](INFO): Starting
2024-09-11T17:08:26.936Z,1726074506.936 [DATMMP](INFO): Starting
2024-09-11T17:08:27.188Z,1726074507.188 [DATMMP](INFO): Starting
2024-09-11T17:08:27.440Z,1726074507.440 [DATMMP](INFO): Starting
2024-09-11T17:08:27.487Z,1726074507.487 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-09-11T17:08:27.692Z,1726074507.692 [DATMMP](INFO): Starting
2024-09-11T17:08:27.944Z,1726074507.944 [DATMMP](INFO): Starting
2024-09-11T17:08:28.196Z,1726074508.196 [DATMMP](INFO): Starting
2024-09-11T17:08:28.448Z,1726074508.448 [DATMMP](INFO): Starting
2024-09-11T17:08:28.700Z,1726074508.700 [DATMMP](INFO): Starting
2024-09-11T17:08:28.724Z,1726074508.724 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2024-09-11T17:08:28.952Z,1726074508.952 [DATMMP](INFO): Starting
2024-09-11T17:08:29.204Z,1726074509.204 [DATMMP](INFO): Starting
2024-09-11T17:08:29.457Z,1726074509.457 [DATMMP](INFO): Starting
2024-09-11T17:08:29.708Z,1726074509.708 [DATMMP](INFO): Starting
2024-09-11T17:08:29.960Z,1726074509.960 [DATMMP](INFO): Starting
2024-09-11T17:08:30.212Z,1726074510.212 [DATMMP](INFO): Starting
2024-09-11T17:08:30.464Z,1726074510.464 [DATMMP](INFO): Starting
2024-09-11T17:08:30.716Z,1726074510.716 [DATMMP](INFO): Starting
2024-09-11T17:08:30.961Z,1726074510.961 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-09-11T17:08:30.968Z,1726074510.968 [DATMMP](INFO): Starting
2024-09-11T17:08:31.220Z,1726074511.220 [DATMMP](INFO): Starting
2024-09-11T17:08:31.472Z,1726074511.472 [DATMMP](INFO): Starting
2024-09-11T17:08:31.500Z,1726074511.500 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-09-11T17:08:31.724Z,1726074511.724 [DATMMP](INFO): Starting
2024-09-11T17:08:31.976Z,1726074511.976 [DATMMP](INFO): Starting
2024-09-11T17:08:32.228Z,1726074512.228 [DATMMP](INFO): Starting
2024-09-11T17:08:32.480Z,1726074512.480 [DATMMP](INFO): Starting
2024-09-11T17:08:32.732Z,1726074512.732 [DATMMP](INFO): Starting
2024-09-11T17:08:32.984Z,1726074512.984 [DATMMP](INFO): Starting
2024-09-11T17:08:33.236Z,1726074513.236 [DATMMP](INFO): Starting
2024-09-11T17:08:33.488Z,1726074513.488 [DATMMP](INFO): Starting
2024-09-11T17:08:33.740Z,1726074513.740 [DATMMP](INFO): Starting
2024-09-11T17:08:33.992Z,1726074513.992 [DATMMP](INFO): Starting
2024-09-11T17:08:34.244Z,1726074514.244 [DATMMP](INFO): Starting
2024-09-11T17:08:34.496Z,1726074514.496 [DATMMP](INFO): Starting
2024-09-11T17:08:34.748Z,1726074514.748 [DATMMP](INFO): Starting
2024-09-11T17:08:35.000Z,1726074515.000 [DATMMP](INFO): Starting
2024-09-11T17:08:35.252Z,1726074515.252 [DATMMP](INFO): Starting
2024-09-11T17:08:35.504Z,1726074515.504 [DATMMP](INFO): Starting
2024-09-11T17:08:35.756Z,1726074515.756 [DATMMP](INFO): Starting
2024-09-11T17:08:36.008Z,1726074516.008 [DATMMP](INFO): Starting
2024-09-11T17:08:36.260Z,1726074516.260 [DATMMP](INFO): Starting
2024-09-11T17:08:36.512Z,1726074516.512 [DATMMP](INFO): Starting
2024-09-11T17:08:36.764Z,1726074516.764 [DATMMP](INFO): Starting
2024-09-11T17:08:37.016Z,1726074517.016 [DATMMP](INFO): Starting
2024-09-11T17:08:37.268Z,1726074517.268 [DATMMP](INFO): Starting
2024-09-11T17:08:37.520Z,1726074517.520 [DATMMP](INFO): Starting
2024-09-11T17:08:37.520Z,1726074517.520 [DATMMP](INFO): DAT read:
2024-09-11T17:08:37.521Z,1726074517.521 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-09-11T17:08:37.772Z,1726074517.772 [DATMMP](INFO): Starting
2024-09-11T17:08:38.024Z,1726074518.024 [DATMMP](INFO): Starting
2024-09-11T17:08:38.276Z,1726074518.276 [DATMMP](INFO): Starting
2024-09-11T17:08:38.528Z,1726074518.528 [DATMMP](INFO): Starting
2024-09-11T17:08:38.780Z,1726074518.780 [DATMMP](INFO): Starting
2024-09-11T17:08:39.032Z,1726074519.032 [DATMMP](INFO): Starting
2024-09-11T17:08:39.033Z,1726074519.033 [DATMMP](INFO): DAT read: MF Frequency Band
2024-09-11T17:08:39.034Z,1726074519.034 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-09-11T17:08:39.034Z,1726074519.034 [DATMMP](INFO): DAT read: Sep 11 2024 17:08:34
2024-09-11T17:08:39.284Z,1726074519.284 [DATMMP](INFO): Starting
2024-09-11T17:08:39.536Z,1726074519.536 [DATMMP](INFO): Starting
2024-09-11T17:08:39.788Z,1726074519.788 [DATMMP](INFO): Starting
2024-09-11T17:08:40.040Z,1726074520.040 [DATMMP](INFO): Starting
2024-09-11T17:08:40.041Z,1726074520.041 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-09-11T17:08:40.042Z,1726074520.042 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-09-11T17:08:40.042Z,1726074520.042 [DATMMP](INFO): commRate: 800
2024-09-11T17:08:40.292Z,1726074520.292 [DATMMP](INFO): Starting
2024-09-11T17:08:40.544Z,1726074520.544 [DATMMP](INFO): Starting
2024-09-11T17:08:40.796Z,1726074520.796 [DATMMP](INFO): Starting
2024-09-11T17:08:41.048Z,1726074521.048 [DATMMP](INFO): Starting
2024-09-11T17:08:41.300Z,1726074521.300 [DATMMP](INFO): Starting
2024-09-11T17:08:41.552Z,1726074521.552 [DATMMP](INFO): Starting
2024-09-11T17:08:41.804Z,1726074521.804 [DATMMP](INFO): Starting
2024-09-11T17:08:42.056Z,1726074522.056 [DATMMP](INFO): Starting
2024-09-11T17:08:42.108Z,1726074522.108 [DATMMP](INFO): entering command mode
2024-09-11T17:08:42.308Z,1726074522.308 [DATMMP](INFO): Starting
2024-09-11T17:08:42.308Z,1726074522.308 [DATMMP](INFO): DAT read:
2024-09-11T17:08:42.309Z,1726074522.309 [DATMMP](INFO): DAT read: user:1>
2024-09-11T17:08:42.309Z,1726074522.309 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-09-11T17:08:42.309Z,1726074522.309 [DATMMP](INFO): entering MMP mode
2024-09-11T17:08:42.560Z,1726074522.560 [DATMMP](INFO): Starting
2024-09-11T17:08:42.561Z,1726074522.561 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-11T17:08:42.562Z,1726074522.562 [DATMMP](INFO): status rx: x1
2024-09-11T17:08:42.563Z,1726074522.563 [DATMMP](INFO): MMP status: 1: started
2024-09-11T17:08:42.563Z,1726074522.563 [DATMMP](INFO): Received message type: status
2024-09-11T17:08:42.563Z,1726074522.563 [DATMMP](INFO): Handled
2024-09-11T17:08:42.812Z,1726074522.812 [DATMMP](INFO): Starting
2024-09-11T17:08:42.813Z,1726074522.813 [DATMMP](INFO): Sent Tx Power Config 8
2024-09-11T17:08:42.814Z,1726074522.814 [DATMMP](INFO): Setting local address: 1
2024-09-11T17:08:42.815Z,1726074522.815 [DATMMP](INFO): Setting time to: 17:8:42 And date to:9/11/2024
2024-09-11T17:08:43.064Z,1726074523.064 [DATMMP](INFO): Starting
2024-09-11T17:08:43.064Z,1726074523.064 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-11T17:08:43.065Z,1726074523.065 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-09-11T17:08:43.065Z,1726074523.065 [DATMMP](INFO): Received message type: mdmpower
2024-09-11T17:08:43.066Z,1726074523.066 [DATMMP](INFO): Handled
2024-09-11T17:08:43.316Z,1726074523.316 [DATMMP](INFO): Starting
2024-09-11T17:08:43.317Z,1726074523.317 [DATMMP](INFO): GSXN notify for xid: 10
2024-09-11T17:08:43.317Z,1726074523.317 [DATMMP](INFO): sreg_txpower rx: x8
2024-09-11T17:08:43.318Z,1726074523.318 [DATMMP](INFO): Received message type: sreg_txpower
2024-09-11T17:08:43.318Z,1726074523.318 [DATMMP](INFO): Match for : sreg_txpower
2024-09-11T17:08:43.318Z,1726074523.318 [DATMMP](INFO): ACK
2024-09-11T17:08:43.568Z,1726074523.568 [DATMMP](INFO): Starting
2024-09-11T17:08:43.568Z,1726074523.568 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-11T17:08:43.569Z,1726074523.569 [DATMMP](INFO): mdmlocal rx: x1 x0 x0 x0
2024-09-11T17:08:43.569Z,1726074523.569 [DATMMP](INFO): Received message type: mdmlocal
2024-09-11T17:08:43.570Z,1726074523.570 [DATMMP](INFO): Handled
2024-09-11T17:08:43.820Z,1726074523.820 [DATMMP](INFO): Starting
2024-09-11T17:08:43.820Z,1726074523.820 [DATMMP](INFO): GSXN notify for xid: 11
2024-09-11T17:08:43.821Z,1726074523.821 [DATMMP](INFO): sreg_locaddr rx: x1
2024-09-11T17:08:43.821Z,1726074523.821 [DATMMP](INFO): Received message type: sreg_locaddr
2024-09-11T17:08:43.821Z,1726074523.821 [DATMMP](INFO): Match for : sreg_locaddr
2024-09-11T17:08:43.821Z,1726074523.821 [DATMMP](INFO): ACK
2024-09-11T17:08:44.072Z,1726074524.072 [DATMMP](INFO): Starting
2024-09-11T17:08:44.072Z,1726074524.072 [DATMMP](INFO): GSXN notify for xid: 12
2024-09-11T17:08:44.073Z,1726074524.073 [DATMMP](INFO): timedate rx: x0 x11 x8 x2A x8 xB x7C x0
2024-09-11T17:08:44.074Z,1726074524.074 [DATMMP](INFO): Received message type: timedate
2024-09-11T17:08:44.074Z,1726074524.074 [DATMMP](INFO): Match for : timedate
2024-09-11T17:08:44.074Z,1726074524.074 [DATMMP](INFO): ACK
2024-09-11T17:08:44.324Z,1726074524.324 [DATMMP](INFO): Starting
2024-09-11T17:08:44.325Z,1726074524.325 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-11T17:08:44.326Z,1726074524.326 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-09-11T17:08:44.326Z,1726074524.326 [DATMMP](INFO): Received message type: sys_verb
2024-09-11T17:08:44.326Z,1726074524.326 [DATMMP](INFO): Handled
2024-09-11T17:08:44.576Z,1726074524.576 [DATMMP](INFO): Starting
2024-09-11T17:08:44.576Z,1726074524.576 [DATMMP](INFO): GSXN notify for xid: 13
2024-09-11T17:08:44.577Z,1726074524.577 [DATMMP](INFO): sreg_verbosity rx: x0
2024-09-11T17:08:44.577Z,1726074524.577 [DATMMP](INFO): Received message type: sreg_verbosity
2024-09-11T17:08:44.577Z,1726074524.577 [DATMMP](INFO): Match for : sreg_verbosity
2024-09-11T17:08:44.577Z,1726074524.577 [DATMMP](INFO): ACK
2024-09-11T17:08:44.828Z,1726074524.828 [DATMMP](INFO): Starting
2024-09-11T17:08:44.828Z,1726074524.828 [DATMMP](INFO): GSXN notify for xid: 14
2024-09-11T17:08:44.829Z,1726074524.829 [DATMMP](INFO): version rx: x2 x5
2024-09-11T17:08:44.829Z,1726074524.829 [DATMMP](INFO): DatMMPVersion: 2.5
2024-09-11T17:08:44.829Z,1726074524.829 [DATMMP](INFO): Received message type: version
2024-09-11T17:08:44.830Z,1726074524.830 [DATMMP](INFO): Match for : version
2024-09-11T17:08:44.830Z,1726074524.830 [DATMMP](INFO): ACK
2024-09-11T17:08:44.830Z,1726074524.830 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-09-11T17:08:52.546Z,1726074532.546 [NAL9602](INFO): Powering up NAL9602
2024-09-11T17:08:58.199Z,1726074538.199 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-11T17:08:58.203Z,1726074538.203 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-09-11T17:08:58.212Z,1726074538.212 [DATMMP](INFO): Received message type: timesync
2024-09-11T17:08:58.212Z,1726074538.212 [DATMMP](INFO): Handled
2024-09-11T17:08:58.248Z,1726074538.248 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019555299, 0, 0.0,1475.0, 0
2024-09-11T17:08:59.485Z,1726074539.485 [Waterlinked](INFO): Powering down
2024-09-11T17:09:03.450Z,1726074543.450 [NAL9602](INFO): NAL9602 initialized
2024-09-11T17:09:08.289Z,1726074548.289 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-11T17:09:08.289Z,1726074548.289 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-09-11T17:09:08.290Z,1726074548.290 [DATMMP](INFO): Received message type: timesync
2024-09-11T17:09:08.290Z,1726074548.290 [DATMMP](INFO): Handled
2024-09-11T17:09:31.326Z,1726074571.326 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:09:42.629Z,1726074582.629 [BPC1](ERROR): BPC1B got IPBS message with 3 sticks (min is 7).
2024-09-11T17:09:42.631Z,1726074582.631 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2024-09-11T17:09:45.888Z,1726074585.888 [SBIT](IMPORTANT): Beginning Startup BIT
2024-09-11T17:09:45.893Z,1726074585.893 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-11T17:09:56.009Z,1726074596.009 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:09:56.939Z,1726074596.939 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.022446
CHAN A1 (24V): 0.010947
CHAN A2 (12V): 0.003815
CHAN A3 (5V): -0.000306
CHAN B0 (3.3V): 0.000067
CHAN B1 (3.15aV): -0.000178
CHAN B2 (3.15bV): 0.000102
CHAN B3 (GND): -0.000121
OPEN: 0.007254
Full Scale: +/- 1 mA
2024-09-11T17:10:08.939Z,1726074608.939 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:10:21.866Z,1726074621.866 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:10:33.943Z,1726074633.943 [BPC1](ERROR): BPC1B got IPBS message with 3 sticks (min is 7).
2024-09-11T17:10:33.944Z,1726074633.944 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2024-09-11T17:10:40.023Z,1726074640.023 [SBIT](IMPORTANT): SBIT PASSED
2024-09-11T17:10:40.023Z,1726074640.023 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-09-11T17:10:40.024Z,1726074640.024 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2024-09-11T17:10:40.024Z,1726074640.024 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-09-11T17:10:40.025Z,1726074640.025 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-09-11T17:10:40.025Z,1726074640.025 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-09-11T17:10:40.025Z,1726074640.025 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-09-11T17:10:40.025Z,1726074640.025 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 10.000000 meter;
2024-09-11T17:10:40.025Z,1726074640.025 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 0.600000 count_per_second;
2024-09-11T17:10:40.025Z,1726074640.025 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 0.600000 count_per_second;
2024-09-11T17:10:40.025Z,1726074640.025 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_caseHumidity 10.000000 percent;
2024-09-11T17:10:40.025Z,1726074640.025 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_diatoms 30.000000 count_per_milliliter;
2024-09-11T17:10:40.025Z,1726074640.025 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_dinoflagellates 30.000000 count_per_milliliter;
2024-09-11T17:10:40.026Z,1726074640.026 [SBIT](IMPORTANT): Express linearApproximation _.predict_plankton_data_pct_dinos_sqerr 0.100000 none;
2024-09-11T17:10:40.026Z,1726074640.026 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2024-09-11T17:10:40.026Z,1726074640.026 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;
2024-09-11T17:10:40.026Z,1726074640.026 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-09-11T17:10:40.026Z,1726074640.026 [SBIT](IMPORTANT): Normal all _.predict_plankton_data_pct_dinos_sqerr;
2024-09-11T17:10:40.026Z,1726074640.026 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=228.035603 cubic_centimeter;
2024-09-11T17:10:40.026Z,1726074640.026 [SBIT](IMPORTANT): VerticalControl.massDefault=15.371666 millimeter;
2024-09-11T17:10:40.448Z,1726074640.448 [MissionManager](IMPORTANT): Started mission Startup
2024-09-11T17:10:40.449Z,1726074640.449 [Startup] Running Loop=1
2024-09-11T17:10:40.449Z,1726074640.449 [Startup](DEBUG): Aggregate::initialize Startup
2024-09-11T17:10:40.449Z,1726074640.449 [Startup:A.GoToSurface] Running Loop=1
2024-09-11T17:10:40.449Z,1726074640.449 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-11T17:10:40.449Z,1726074640.449 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-11T17:10:40.450Z,1726074640.450 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-11T17:10:40.450Z,1726074640.450 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-11T17:10:40.451Z,1726074640.451 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-11T17:10:40.451Z,1726074640.451 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-11T17:10:40.451Z,1726074640.451 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-11T17:10:40.453Z,1726074640.453 [Startup:StartupSatComms] Running Loop=1
2024-09-11T17:10:40.453Z,1726074640.453 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-09-11T17:10:40.453Z,1726074640.453 [Startup:StartupSatComms:A] Running Loop=1
2024-09-11T17:10:40.853Z,1726074640.853 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-09-11T17:10:45.653Z,1726074645.653 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-09-11T17:10:45.653Z,1726074645.653 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2024-09-11T17:10:51.322Z,1726074651.322 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:10:56.295Z,1726074656.295 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005212
2024-09-11T17:11:02.316Z,1726074662.316 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:11:12.361Z,1726074672.361 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:11:22.495Z,1726074682.495 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:11:24.847Z,1726074684.847 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-09-11T17:11:24.847Z,1726074684.847 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-11T17:11:24.848Z,1726074684.848 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-09-11T17:11:24.848Z,1726074684.848 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-09-11T17:11:24.849Z,1726074684.849 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-09-11T17:11:24.849Z,1726074684.849 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-11T17:11:24.860Z,1726074684.860 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-11T17:11:24.860Z,1726074684.860 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-09-11T17:11:24.860Z,1726074684.860 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-11T17:11:25.272Z,1726074685.272 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-11T17:11:25.272Z,1726074685.272 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-09-11T17:11:25.273Z,1726074685.273 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-09-11T17:11:25.273Z,1726074685.273 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-09-11T17:11:25.274Z,1726074685.274 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-11T17:11:25.274Z,1726074685.274 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-09-11T17:11:25.654Z,1726074685.654 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-11T17:11:26.447Z,1726074686.447 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-09-11T17:11:26.448Z,1726074686.448 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2024-09-11T17:11:32.559Z,1726074692.559 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:11:37.810Z,1726074697.810 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:11:40.608Z,1726074700.608 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-11T17:10:40.5Z
2024-09-11T17:11:40.608Z,1726074700.608 [Startup:StartupSatComms:A] Stopped
2024-09-11T17:11:40.608Z,1726074700.608 [Startup:StartupSatComms:B] Running Loop=1
2024-09-11T17:11:41.021Z,1726074701.021 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-11T17:11:42.659Z,1726074702.659 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:11:47.737Z,1726074707.737 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240911T170217/Courier0004.lzma
2024-09-11T17:11:47.918Z,1726074707.918 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:11:48.739Z,1726074708.739 [DataOverHttps](INFO): Moved sent file to Logs/20240911T170217/Courier0004.lzma.bak
2024-09-11T17:11:48.740Z,1726074708.740 [DataOverHttps](INFO): SBD MOMSN=23163747
2024-09-11T17:11:52.787Z,1726074712.787 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:11:57.613Z,1726074717.613 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:02.887Z,1726074722.887 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:04.341Z,1726074724.341 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240911T170813/Courier0000.lzma
2024-09-11T17:12:05.343Z,1726074725.343 [DataOverHttps](INFO): Moved sent file to Logs/20240911T170813/Courier0000.lzma.bak
2024-09-11T17:12:05.343Z,1726074725.343 [DataOverHttps](INFO): SBD MOMSN=23163749
2024-09-11T17:12:06.902Z,1726074726.902 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:06.906Z,1726074726.906 [NAL9602](INFO): SBD MO Status=2, MOMSN=15999, MT Status=2, MTMSN=0
2024-09-11T17:12:06.906Z,1726074726.906 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-11T17:12:12.556Z,1726074732.556 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:17.054Z,1726074737.054 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:19.389Z,1726074739.389 [NAL9602](INFO): SBD MO Status=0, MOMSN=15999, MT Status=0, MTMSN=0
2024-09-11T17:12:19.389Z,1726074739.389 [NAL9602](INFO): No messages in MT queue
2024-09-11T17:12:20.917Z,1726074740.917 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20240911T170217/Express0005.lzma
2024-09-11T17:12:21.919Z,1726074741.919 [DataOverHttps](INFO): Moved sent file to Logs/20240911T170217/Express0005.lzma.bak
2024-09-11T17:12:21.919Z,1726074741.919 [DataOverHttps](INFO): SBD MOMSN=23163752
2024-09-11T17:12:22.655Z,1726074742.655 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:27.142Z,1726074747.142 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:32.755Z,1726074752.755 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:36.801Z,1726074756.801 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:37.494Z,1726074757.494 [DataOverHttps](INFO): Sending 1079 bytes from file Logs/20240911T170813/Express0001.lzma
2024-09-11T17:12:38.495Z,1726074758.495 [DataOverHttps](INFO): Moved sent file to Logs/20240911T170813/Express0001.lzma.bak
2024-09-11T17:12:38.495Z,1726074758.495 [DataOverHttps](INFO): SBD MOMSN=23163761
2024-09-11T17:12:39.626Z,1726074759.626 [Startup:StartupSatComms:B] Stopped
2024-09-11T17:12:39.626Z,1726074759.626 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-09-11T17:12:39.626Z,1726074759.626 [Startup:StartupSatComms] Stopped
2024-09-11T17:12:39.626Z,1726074759.626 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-09-11T17:12:39.627Z,1726074759.627 [Startup](INFO): Completed Startup
2024-09-11T17:12:39.627Z,1726074759.627 [MissionManager](INFO): Startup is completed.
2024-09-11T17:12:39.627Z,1726074759.627 [MissionManager](INFO): Uninitializing Mission Startup
2024-09-11T17:12:39.627Z,1726074759.627 [Startup] Stopped
2024-09-11T17:12:39.627Z,1726074759.627 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-09-11T17:12:39.627Z,1726074759.627 [Startup:A.GoToSurface] Stopped
2024-09-11T17:12:39.627Z,1726074759.627 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-11T17:12:40.017Z,1726074760.017 [MissionManager](IMPORTANT): Started mission Default
2024-09-11T17:12:40.017Z,1726074760.017 [Default] Running Loop=1
2024-09-11T17:12:40.017Z,1726074760.017 [Default](DEBUG): Aggregate::initialize Default
2024-09-11T17:12:40.017Z,1726074760.017 [Default:B.GoToSurface] Running Loop=1
2024-09-11T17:12:40.018Z,1726074760.018 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-11T17:12:40.018Z,1726074760.018 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-11T17:12:40.018Z,1726074760.018 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-11T17:12:40.018Z,1726074760.018 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-11T17:12:40.019Z,1726074760.019 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-11T17:12:40.019Z,1726074760.019 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-11T17:12:40.019Z,1726074760.019 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-11T17:12:40.019Z,1726074760.019 [Default:A.Wait] Running Loop=1
2024-09-11T17:12:40.020Z,1726074760.020 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-11T17:12:42.882Z,1726074762.882 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:46.903Z,1726074766.903 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:52.957Z,1726074772.957 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:12:53.340Z,1726074773.340 [Default:A.Wait](INFO): Done Waiting.
2024-09-11T17:12:53.340Z,1726074773.340 [Default:A.Wait] Stopped
2024-09-11T17:12:53.340Z,1726074773.340 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-11T17:12:53.748Z,1726074773.748 [Default:CheckIn] Running Loop=1
2024-09-11T17:12:53.748Z,1726074773.748 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-11T17:12:53.748Z,1726074773.748 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-11T17:12:54.166Z,1726074774.166 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-09-11T17:12:57.002Z,1726074777.002 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:13:03.458Z,1726074783.458 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:13:07.104Z,1726074787.104 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:13:10.279Z,1726074790.279 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-11T17:13:16.797Z,1726074796.797 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:13:25.222Z,1726074805.222 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-11T17:13:26.898Z,1726074806.898 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:13:36.595Z,1726074816.595 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:13:39.771Z,1726074819.771 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-11T17:13:46.694Z,1726074826.694 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:13:54.715Z,1726074834.715 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-11T17:14:01.636Z,1726074841.636 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:05.232Z,1726074845.232 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:07.647Z,1726074847.647 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:10.073Z,1726074850.073 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:10.074Z,1726074850.074 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-11T17:14:12.907Z,1726074852.907 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:15.354Z,1726074855.354 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:17.797Z,1726074857.797 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:20.623Z,1726074860.623 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:23.047Z,1726074863.047 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:24.816Z,1726074864.816 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:25.470Z,1726074865.470 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:25.994Z,1726074865.994 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-09-11T17:14:25.994Z,1726074865.994 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-11T17:14:25.995Z,1726074865.995 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-09-11T17:14:25.995Z,1726074865.995 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-09-11T17:14:25.997Z,1726074865.997 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-09-11T17:14:25.997Z,1726074865.997 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-09-11T17:14:26.048Z,1726074866.048 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-11T17:14:26.048Z,1726074866.048 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-09-11T17:14:26.049Z,1726074866.049 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-09-11T17:14:26.353Z,1726074866.353 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-11T17:14:26.353Z,1726074866.353 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-09-11T17:14:26.362Z,1726074866.362 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-09-11T17:14:26.362Z,1726074866.362 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-09-11T17:14:26.363Z,1726074866.363 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-09-11T17:14:26.363Z,1726074866.363 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-09-11T17:14:26.701Z,1726074866.701 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-11T17:14:27.895Z,1726074867.895 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:30.320Z,1726074870.320 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:33.150Z,1726074873.150 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:35.570Z,1726074875.570 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:37.996Z,1726074877.996 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:39.604Z,1726074879.604 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-11T17:14:40.833Z,1726074880.833 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:43.255Z,1726074883.255 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:45.668Z,1726074885.668 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:48.091Z,1726074888.091 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:50.924Z,1726074890.924 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:52.586Z,1726074892.586 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:53.746Z,1726074893.746 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:56.172Z,1726074896.172 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:14:58.595Z,1726074898.595 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:01.422Z,1726074901.422 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:03.848Z,1726074903.848 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:06.675Z,1726074906.675 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:06.676Z,1726074906.676 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-11T17:15:09.108Z,1726074909.108 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:11.927Z,1726074911.927 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:14.360Z,1726074914.360 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:16.779Z,1726074916.779 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:20.011Z,1726074920.011 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:21.623Z,1726074921.623 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-11T17:15:22.438Z,1726074922.438 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:24.859Z,1726074924.859 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:25.263Z,1726074925.263 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:27.688Z,1726074927.688 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:27.696Z,1726074927.696 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:30.111Z,1726074930.111 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:30.535Z,1726074930.535 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:32.938Z,1726074932.938 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:33.362Z,1726074933.362 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:35.361Z,1726074935.361 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:35.765Z,1726074935.765 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:38.187Z,1726074938.187 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:38.193Z,1726074938.193 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:40.611Z,1726074940.611 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:40.616Z,1726074940.616 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:43.439Z,1726074943.439 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:43.842Z,1726074943.842 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:45.863Z,1726074945.863 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:47.074Z,1726074947.074 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:48.287Z,1726074948.287 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:50.307Z,1726074950.307 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:51.131Z,1726074951.131 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:53.134Z,1726074953.134 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:53.551Z,1726074953.551 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:55.963Z,1726074955.963 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:55.968Z,1726074955.968 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:58.790Z,1726074958.790 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:15:58.795Z,1726074958.795 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:01.217Z,1726074961.217 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:01.618Z,1726074961.618 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:04.043Z,1726074964.043 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:04.446Z,1726074964.446 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:06.468Z,1726074966.468 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:07.274Z,1726074967.274 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:09.295Z,1726074969.295 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:10.106Z,1726074970.106 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:11.719Z,1726074971.719 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:12.931Z,1726074972.931 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:14.144Z,1726074974.144 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:15.759Z,1726074975.759 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:16.971Z,1726074976.971 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:18.995Z,1726074978.995 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:19.411Z,1726074979.411 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:21.818Z,1726074981.818 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:21.824Z,1726074981.824 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:24.647Z,1726074984.647 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:24.955Z,1726074984.955 [CommandExec](IMPORTANT): got command restart system
2024-09-11T17:16:27.009Z,1726074987.009 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-09-11T17:16:27.010Z,1726074987.010 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-11T17:16:27.010Z,1726074987.010 [CommandExec](INFO): Uninitializing the command executive.
2024-09-11T17:16:27.010Z,1726074987.010 [CommandExec](INFO): Uninitializing the command scheduler.
2024-09-11T17:16:27.010Z,1726074987.010 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-11T17:16:27.072Z,1726074987.072 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-11T17:16:27.113Z,1726074987.113 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-09-11T17:16:27.114Z,1726074987.114 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-09-11T17:16:27.114Z,1726074987.114 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-11T17:16:27.115Z,1726074987.115 [NavChartDb](INFO): Join timeout helper Thread ID is 1000
2024-09-11T17:16:27.226Z,1726074987.226 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-11T17:16:27.226Z,1726074987.226 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-11T17:16:27.241Z,1726074987.241 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-09-11T17:16:27.241Z,1726074987.241 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-11T17:16:27.242Z,1726074987.242 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1001
2024-09-11T17:16:27.710Z,1726074987.710 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-11T17:16:27.710Z,1726074987.710 [WetLabsBB2FL](INFO): Powering down
2024-09-11T17:16:27.711Z,1726074987.711 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-11T17:16:27.719Z,1726074987.719 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-09-11T17:16:27.719Z,1726074987.719 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-11T17:16:27.720Z,1726074987.720 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1002
2024-09-11T17:16:28.282Z,1726074988.282 [CTD_Seabird](INFO): Powering dow