2024-09-27T19:57:59.367Z,1727467079.367 [Supervisor](DEBUG): Initializing supervisor.
2024-09-27T19:57:59.371Z,1727467079.371 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-09-27T19:57:59.372Z,1727467079.372 [SyncHandler](INFO): Protected caller Thread ID is 837
2024-09-27T19:57:59.372Z,1727467079.372 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-09-27T19:57:59.373Z,1727467079.373 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-09-27T19:57:59.374Z,1727467079.374 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2024-09-27T19:57:59.377Z,1727467079.377 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-09-27T19:57:59.395Z,1727467079.395 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-09-27T19:57:59.396Z,1727467079.396 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-09-27T19:57:59.396Z,1727467079.396 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2024-09-27T19:57:59.400Z,1727467079.400 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-09-27T19:57:59.401Z,1727467079.401 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-09-27T19:57:59.402Z,1727467079.402 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2024-09-27T19:57:59.404Z,1727467079.404 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-09-27T19:57:59.405Z,1727467079.405 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-09-27T19:57:59.405Z,1727467079.405 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2024-09-27T19:57:59.409Z,1727467079.409 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-09-27T19:57:59.409Z,1727467079.409 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-09-27T19:57:59.413Z,1727467079.413 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-09-27T19:57:59.765Z,1727467079.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-09-27T19:57:59.766Z,1727467079.766 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-09-27T19:57:59.859Z,1727467079.859 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-09-27T19:57:59.977Z,1727467079.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-09-27T19:57:59.978Z,1727467079.978 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-09-27T19:58:00.119Z,1727467080.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-09-27T19:58:00.120Z,1727467080.120 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-09-27T19:58:00.497Z,1727467080.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-09-27T19:58:00.498Z,1727467080.498 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-09-27T19:58:00.606Z,1727467080.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-09-27T19:58:00.606Z,1727467080.606 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-09-27T19:58:01.042Z,1727467081.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-09-27T19:58:01.043Z,1727467081.043 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-09-27T19:58:01.271Z,1727467081.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-09-27T19:58:01.273Z,1727467081.273 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-09-27T19:58:01.364Z,1727467081.364 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-09-27T19:58:01.619Z,1727467081.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-09-27T19:58:01.621Z,1727467081.621 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-09-27T19:58:01.946Z,1727467081.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-09-27T19:58:01.947Z,1727467081.947 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-09-27T19:58:02.493Z,1727467082.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-09-27T19:58:02.494Z,1727467082.494 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-09-27T19:58:02.699Z,1727467082.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-09-27T19:58:02.722Z,1727467082.722 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-09-27T19:58:03.091Z,1727467083.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-09-27T19:58:03.093Z,1727467083.093 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-09-27T19:58:03.981Z,1727467083.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-09-27T19:58:03.981Z,1727467083.981 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-09-27T19:58:04.590Z,1727467084.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-09-27T19:58:04.592Z,1727467084.592 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2024-09-27T19:58:04.595Z,1727467084.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2024-09-27T19:58:04.706Z,1727467084.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2024-09-27T19:58:04.835Z,1727467084.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2024-09-27T19:58:04.930Z,1727467084.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2024-09-27T19:58:05.050Z,1727467085.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2024-09-27T19:58:05.149Z,1727467085.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2024-09-27T19:58:05.416Z,1727467085.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-09-27T19:58:05.417Z,1727467085.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2024-09-27T19:58:05.539Z,1727467085.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2024-09-27T19:58:05.650Z,1727467085.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2024-09-27T19:58:05.765Z,1727467085.765 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2024-09-27T19:58:05.920Z,1727467085.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2024-09-27T19:58:06.018Z,1727467086.018 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2024-09-27T19:58:06.018Z,1727467086.018 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-09-27T19:58:06.042Z,1727467086.042 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-09-27T19:58:06.181Z,1727467086.181 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-09-27T19:58:06.182Z,1727467086.182 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-09-27T19:58:06.251Z,1727467086.251 [VerticalControl](DEBUG): Construct VerticalControl.
2024-09-27T19:58:06.310Z,1727467086.310 [VerticalControl] Loaded
2024-09-27T19:58:06.310Z,1727467086.310 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-09-27T19:58:06.313Z,1727467086.313 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-09-27T19:58:06.359Z,1727467086.359 [HorizontalControl] Loaded
2024-09-27T19:58:06.360Z,1727467086.360 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-09-27T19:58:06.362Z,1727467086.362 [SpeedControl](DEBUG): Construct SpeedControl.
2024-09-27T19:58:06.365Z,1727467086.365 [SpeedControl] Loaded
2024-09-27T19:58:06.365Z,1727467086.365 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-09-27T19:58:06.368Z,1727467086.368 [LoopControl](DEBUG): Construct LoopControl.
2024-09-27T19:58:06.368Z,1727467086.368 [LoopControl] Loaded
2024-09-27T19:58:06.369Z,1727467086.369 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-09-27T19:58:06.369Z,1727467086.369 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-09-27T19:58:06.370Z,1727467086.370 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-09-27T19:58:06.465Z,1727467086.465 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-09-27T19:58:06.465Z,1727467086.465 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-09-27T19:58:07.278Z,1727467087.278 [AHRS_M2] Loaded
2024-09-27T19:58:07.278Z,1727467087.278 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-09-27T19:58:07.530Z,1727467087.530 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4
2024-09-27T19:58:07.531Z,1727467087.531 [BackseatComponent] Loaded
2024-09-27T19:58:07.531Z,1727467087.531 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-09-27T19:58:07.532Z,1727467087.532 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2024-09-27T19:58:07.532Z,1727467087.532 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924
2024-09-27T19:58:07.535Z,1727467087.535 [LcmUniversalReporter] Loaded
2024-09-27T19:58:07.535Z,1727467087.535 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-09-27T19:58:08.332Z,1727467088.332 [BPC1] Loaded
2024-09-27T19:58:08.332Z,1727467088.332 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-09-27T19:58:08.432Z,1727467088.432 [DATMMP] Loaded
2024-09-27T19:58:08.433Z,1727467088.433 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-09-27T19:58:08.433Z,1727467088.433 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2024-09-27T19:58:08.434Z,1727467088.434 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 925
2024-09-27T19:58:08.573Z,1727467088.573 [DataOverHttps] Loaded
2024-09-27T19:58:08.573Z,1727467088.573 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-09-27T19:58:08.574Z,1727467088.574 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2024-09-27T19:58:08.574Z,1727467088.574 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926
2024-09-27T19:58:08.597Z,1727467088.597 [Depth_Keller] Loaded
2024-09-27T19:58:08.597Z,1727467088.597 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-09-27T19:58:08.602Z,1727467088.602 [DropWeight] Loaded
2024-09-27T19:58:08.602Z,1727467088.602 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-09-27T19:58:08.665Z,1727467088.665 [NAL9602] Loaded
2024-09-27T19:58:08.665Z,1727467088.665 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-09-27T19:58:08.694Z,1727467088.694 [Onboard] Loaded
2024-09-27T19:58:08.694Z,1727467088.694 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-09-27T19:58:08.695Z,1727467088.695 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2024-09-27T19:58:08.696Z,1727467088.696 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927
2024-09-27T19:58:08.709Z,1727467088.709 [Power24vConverter] Loaded
2024-09-27T19:58:08.710Z,1727467088.710 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-09-27T19:58:08.725Z,1727467088.725 [Radio_Surface] Loaded
2024-09-27T19:58:08.725Z,1727467088.725 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-09-27T19:58:08.726Z,1727467088.726 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2024-09-27T19:58:08.726Z,1727467088.726 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928
2024-09-27T19:58:08.741Z,1727467088.741 [Sonardyne_Nano] Loaded
2024-09-27T19:58:08.741Z,1727467088.741 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-09-27T19:58:08.765Z,1727467088.765 [Waterlinked] Loaded
2024-09-27T19:58:08.765Z,1727467088.765 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-09-27T19:58:08.766Z,1727467088.766 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-09-27T19:58:08.766Z,1727467088.766 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-09-27T19:58:08.782Z,1727467088.782 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-09-27T19:58:08.782Z,1727467088.782 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-09-27T19:58:08.824Z,1727467088.824 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-09-27T19:58:08.825Z,1727467088.825 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-09-27T19:58:08.991Z,1727467088.991 [SBIT](DEBUG): Construct Startup Built In Test.
2024-09-27T19:58:08.000Z,1727467089.000 [SBIT] Loaded
2024-09-27T19:58:09.000Z,1727467089.000 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-09-27T19:58:09.003Z,1727467089.003 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-09-27T19:58:09.015Z,1727467089.015 [IBIT] Loaded
2024-09-27T19:58:09.016Z,1727467089.016 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-09-27T19:58:09.021Z,1727467089.021 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-09-27T19:58:09.125Z,1727467089.125 [CBIT] Loaded
2024-09-27T19:58:09.125Z,1727467089.125 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-09-27T19:58:09.125Z,1727467089.125 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-09-27T19:58:09.126Z,1727467089.126 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-09-27T19:58:09.518Z,1727467089.518 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-09-27T19:58:09.518Z,1727467089.518 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-09-27T19:58:09.586Z,1727467089.586 [DepthRateCalculator] Loaded
2024-09-27T19:58:09.587Z,1727467089.587 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-09-27T19:58:09.591Z,1727467089.591 [PitchRateCalculator] Loaded
2024-09-27T19:58:09.592Z,1727467089.592 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-09-27T19:58:09.602Z,1727467089.602 [SpeedCalculator] Loaded
2024-09-27T19:58:09.602Z,1727467089.602 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-09-27T19:58:09.606Z,1727467089.606 [YawRateCalculator] Loaded
2024-09-27T19:58:09.607Z,1727467089.607 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-09-27T19:58:09.626Z,1727467089.626 [ElevatorOffsetCalculator] Loaded
2024-09-27T19:58:09.626Z,1727467089.626 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-09-27T19:58:09.627Z,1727467089.627 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-09-27T19:58:09.628Z,1727467089.628 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-09-27T19:58:09.837Z,1727467089.837 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-09-27T19:58:09.838Z,1727467089.838 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-09-27T19:58:09.971Z,1727467089.971 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-09-27T19:58:09.972Z,1727467089.972 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-09-27T19:58:10.014Z,1727467090.014 [DeadReckonUsingSpeedCalculator] Loaded
2024-09-27T19:58:10.014Z,1727467090.014 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-09-27T19:58:10.056Z,1727467090.056 [DeadReckonWithRespectToSeafloor] Loaded
2024-09-27T19:58:10.056Z,1727467090.056 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2024-09-27T19:58:10.069Z,1727467090.069 [NavChart] Loaded
2024-09-27T19:58:10.070Z,1727467090.070 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-09-27T19:58:10.075Z,1727467090.075 [UniversalFixResidualReporter] Loaded
2024-09-27T19:58:10.075Z,1727467090.075 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-09-27T19:58:10.076Z,1727467090.076 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-09-27T19:58:10.076Z,1727467090.076 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-09-27T19:58:10.238Z,1727467090.238 [BuoyancyServo] Loaded
2024-09-27T19:58:10.238Z,1727467090.238 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-09-27T19:58:10.260Z,1727467090.260 [ElevatorServo] Loaded
2024-09-27T19:58:10.261Z,1727467090.261 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-09-27T19:58:10.282Z,1727467090.282 [MassServo] Loaded
2024-09-27T19:58:10.282Z,1727467090.282 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-09-27T19:58:10.303Z,1727467090.303 [RudderServo] Loaded
2024-09-27T19:58:10.303Z,1727467090.303 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-09-27T19:58:10.319Z,1727467090.319 [ThrusterHE] Loaded
2024-09-27T19:58:10.319Z,1727467090.319 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-09-27T19:58:10.320Z,1727467090.320 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-09-27T19:58:10.321Z,1727467090.321 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-09-27T19:58:10.557Z,1727467090.557 [CTD_Seabird] Loaded
2024-09-27T19:58:10.557Z,1727467090.557 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-09-27T19:58:10.559Z,1727467090.559 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BF24E0
2024-09-27T19:58:10.559Z,1727467090.559 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 929
2024-09-27T19:58:10.594Z,1727467090.594 [ESPComponent] Loaded
2024-09-27T19:58:10.595Z,1727467090.595 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-09-27T19:58:10.617Z,1727467090.617 [PAR_Licor] Loaded
2024-09-27T19:58:10.617Z,1727467090.617 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-09-27T19:58:10.661Z,1727467090.661 [WetLabsBB2FL] Loaded
2024-09-27T19:58:10.661Z,1727467090.661 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-09-27T19:58:10.662Z,1727467090.662 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0
2024-09-27T19:58:10.663Z,1727467090.663 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 930
2024-09-27T19:58:10.664Z,1727467090.664 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-09-27T19:58:10.670Z,1727467090.670 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-09-27T19:58:10.673Z,1727467090.673 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-09-27T19:58:10.684Z,1727467090.684 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-09-27T19:58:10.685Z,1727467090.685 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-09-27T19:58:10.685Z,1727467090.685 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931
2024-09-27T19:58:10.689Z,1727467090.690 [Supervisor](INFO): Main Thread ID is 828
2024-09-27T19:58:10.690Z,1727467090.690 [Supervisor](DEBUG): Running supervisor.
2024-09-27T19:58:10.690Z,1727467090.690 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932
2024-09-27T19:58:10.691Z,1727467090.691 [CommandExec](INFO): Initializing the command executive.
2024-09-27T19:58:10.692Z,1727467090.692 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933
2024-09-27T19:58:10.694Z,1727467090.694 [controlThread ThreadHandler](INFO): Handler Thread ID is 934
2024-09-27T19:58:10.695Z,1727467090.695 [controlThread](DEBUG): Initializing ControlThread
2024-09-27T19:58:10.696Z,1727467090.696 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-09-27T19:58:10.697Z,1727467090.697 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-09-27T19:58:10.698Z,1727467090.698 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-09-27T19:58:10.698Z,1727467090.698 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-09-27T19:58:10.702Z,1727467090.702 [SBIT](INFO): Initialize SBIT Component.
2024-09-27T19:58:10.702Z,1727467090.702 [SBIT](IMPORTANT): git: 2024-09-16_D
2024-09-27T19:58:10.703Z,1727467090.703 [SBIT](INFO): git hash: 0de9f8b4fab077d10194b536abee104cbdf12c92
2024-09-27T19:58:10.703Z,1727467090.703 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-09-27T19:58:10.704Z,1727467090.704 [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-27T19:58:10.705Z,1727467090.705 [SBIT](INFO): Beginning SBIT in 73.000000 seconds.
2024-09-27T19:58:10.706Z,1727467090.706 [IBIT](INFO): Initialize IBIT Component.
2024-09-27T19:58:10.706Z,1727467090.706 [CBIT](DEBUG): Initialize CBIT Component.
2024-09-27T19:58:10.707Z,1727467090.707 [logger ThreadHandler](INFO): Handler Thread ID is 935
2024-09-27T19:58:10.720Z,1727467090.720 [CBIT](DEBUG): Initialized mux pins.
2024-09-27T19:58:10.720Z,1727467090.720 [CBIT](DEBUG): Initializing the watchdog timer.
2024-09-27T19:58:10.728Z,1727467090.728 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 936
2024-09-27T19:58:10.740Z,1727467090.740 [DATMMP ThreadHandler](INFO): Handler Thread ID is 937
2024-09-27T19:58:10.741Z,1727467090.741 [DATMMP](INFO): Start
2024-09-27T19:58:10.741Z,1727467090.741 [DATMMP](INFO): Powering up
2024-09-27T19:58:10.741Z,1727467090.741 [DATMMP](DEBUG): Initializing DATMMP.
2024-09-27T19:58:10.744Z,1727467090.744 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-09-27T19:58:10.744Z,1727467090.744 [CBIT](DEBUG): Initializing heartbeat.
2024-09-27T19:58:10.746Z,1727467090.746 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939
2024-09-27T19:58:10.748Z,1727467090.748 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-09-27T19:58:10.757Z,1727467090.757 [Onboard ThreadHandler](INFO): Handler Thread ID is 940
2024-09-27T19:58:10.774Z,1727467090.774 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941
2024-09-27T19:58:10.800Z,1727467090.800 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 942
2024-09-27T19:58:10.800Z,1727467090.800 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-09-27T19:58:10.802Z,1727467090.802 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 944
2024-09-27T19:58:10.809Z,1727467090.809 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 946
2024-09-27T19:58:10.813Z,1727467090.813 [WetLabsBB2FL](INFO): Powering up
2024-09-27T19:58:10.816Z,1727467090.816 [CBIT](DEBUG): Deactivating GF circuits.
2024-09-27T19:58:10.816Z,1727467090.816 [CBIT](DEBUG): Deactivating emergency mode.
2024-09-27T19:58:10.817Z,1727467090.817 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2024-09-27T19:58:10.817Z,1727467090.817 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2024-09-27T19:58:10.817Z,1727467090.817 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2024-09-27T19:58:10.818Z,1727467090.818 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2024-09-27T19:58:10.818Z,1727467090.818 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-09-27T19:58:10.818Z,1727467090.818 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-09-27T19:58:10.818Z,1727467090.818 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-09-27T19:58:10.818Z,1727467090.818 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-09-27T19:58:10.818Z,1727467090.818 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2024-09-27T19:58:10.819Z,1727467090.819 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2024-09-27T19:58:10.819Z,1727467090.819 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2024-09-27T19:58:10.819Z,1727467090.819 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2024-09-27T19:58:10.856Z,1727467090.856 [CBIT](DEBUG): Backplane powered.
2024-09-27T19:58:10.856Z,1727467090.856 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-09-27T19:58:10.857Z,1727467090.857 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-09-27T19:58:10.857Z,1727467090.857 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-09-27T19:58:10.857Z,1727467090.857 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-09-27T19:58:10.858Z,1727467090.858 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-09-27T19:58:10.859Z,1727467090.859 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-09-27T19:58:10.860Z,1727467090.860 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-09-27T19:58:10.860Z,1727467090.860 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-09-27T19:58:10.864Z,1727467090.864 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-09-27T19:58:10.865Z,1727467090.865 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-09-27T19:58:10.865Z,1727467090.865 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-09-27T19:58:10.870Z,1727467090.870 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-09-27T19:58:10.942Z,1727467090.942 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-09-27T19:58:10.946Z,1727467090.946 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-27T19:58:10.990Z,1727467090.990 [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-27T19:58:10.990Z,1727467090.990 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-09-27T19:58:10.990Z,1727467090.990 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-09-27T19:58:10.992Z,1727467090.992 [DATMMP](INFO): Starting
2024-09-27T19:58:10.996Z,1727467090.996 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-09-27T19:58:11.180Z,1727467091.180 [Radio_Surface](INFO): Powering up
2024-09-27T19:58:11.244Z,1727467091.244 [DATMMP](INFO): Starting
2024-09-27T19:58:11.290Z,1727467091.290 [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-27T19:58:11.326Z,1727467091.326 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-09-27T19:58:11.327Z,1727467091.327 [Default:A.Wait](DEBUG): Construct Wait.
2024-09-27T19:58:11.343Z,1727467091.343 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-27T19:58:11.359Z,1727467091.359 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-09-27T19:58:11.393Z,1727467091.393 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-09-27T19:58:11.399Z,1727467091.399 [Default:E.Execute](DEBUG): Construct Execute.
2024-09-27T19:58:11.402Z,1727467091.402 [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-27T19:58:11.417Z,1727467091.417 [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-27T19:58:11.424Z,1727467091.424 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-09-27T19:58:11.496Z,1727467091.496 [DATMMP](INFO): Starting
2024-09-27T19:58:11.621Z,1727467091.621 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2024-09-27T19:58:11.622Z,1727467091.622 [Power24vConverter](INFO): Powering up.
2024-09-27T19:58:11.623Z,1727467091.623 [Sonardyne_Nano](INFO): Initializing.
2024-09-27T19:58:11.696Z,1727467091.696 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-09-27T19:58:11.729Z,1727467091.729 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-09-27T19:58:11.736Z,1727467091.736 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-09-27T19:58:11.737Z,1727467091.737 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-09-27T19:58:11.744Z,1727467091.744 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-09-27T19:58:11.745Z,1727467091.745 [MassServo](DEBUG): Initializing EZServoServo.
2024-09-27T19:58:11.749Z,1727467091.749 [DATMMP](INFO): Starting
2024-09-27T19:58:11.752Z,1727467091.752 [MassServo](DEBUG): Initializing MassServo.
2024-09-27T19:58:11.753Z,1727467091.753 [RudderServo](DEBUG): Initializing EZServoServo.
2024-09-27T19:58:11.760Z,1727467091.760 [RudderServo](DEBUG): Initializing RudderServo.
2024-09-27T19:58:11.761Z,1727467091.761 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-09-27T19:58:11.768Z,1727467091.768 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-09-27T19:58:11.893Z,1727467091.893 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-09-27T19:58:11.894Z,1727467091.894 [DropWeight] Hardware Fault, FailCount= 1
2024-09-27T19:58:11.894Z,1727467091.894 [DropWeight](ERROR): Hardware Fault
2024-09-27T19:58:11.937Z,1727467091.937 [CommandExec](FAULT): Scheduling is paused
2024-09-27T19:58:11.938Z,1727467091.938 [CBIT](INFO): Critical error at 20240927T195811
2024-09-27T19:58:11.938Z,1727467091.938 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-09-27T19:58:11.949Z,1727467091.949 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-09-27T19:58:11.949Z,1727467091.949 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-09-27T19:58:11.000Z,1727467092.000 [DATMMP](INFO): Starting
2024-09-27T19:58:12.252Z,1727467092.252 [DATMMP](INFO): Starting
2024-09-27T19:58:12.255Z,1727467092.255 [CBIT](INFO): Critical error at 20240927T195811
2024-09-27T19:58:12.504Z,1727467092.504 [DATMMP](INFO): Starting
2024-09-27T19:58:12.757Z,1727467092.757 [DATMMP](INFO): Starting
2024-09-27T19:58:12.912Z,1727467092.912 [WetLabsBB2FL](INFO): Powering down
2024-09-27T19:58:13.008Z,1727467093.008 [DATMMP](INFO): Starting
2024-09-27T19:58:13.260Z,1727467093.260 [DATMMP](INFO): Starting
2024-09-27T19:58:13.512Z,1727467093.512 [DATMMP](INFO): Starting
2024-09-27T19:58:13.764Z,1727467093.764 [DATMMP](INFO): Starting
2024-09-27T19:58:13.850Z,1727467093.850 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-09-27T19:58:14.016Z,1727467094.016 [DATMMP](INFO): Starting
2024-09-27T19:58:14.268Z,1727467094.268 [DATMMP](INFO): Starting
2024-09-27T19:58:14.520Z,1727467094.520 [DATMMP](INFO): Starting
2024-09-27T19:58:14.772Z,1727467094.772 [DATMMP](INFO): Starting
2024-09-27T19:58:15.024Z,1727467095.024 [DATMMP](INFO): Starting
2024-09-27T19:58:15.100Z,1727467095.100 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2024-09-27T19:58:15.276Z,1727467095.276 [DATMMP](INFO): Starting
2024-09-27T19:58:15.528Z,1727467095.528 [DATMMP](INFO): Starting
2024-09-27T19:58:15.781Z,1727467095.781 [DATMMP](INFO): Starting
2024-09-27T19:58:16.033Z,1727467096.033 [DATMMP](INFO): Starting
2024-09-27T19:58:16.285Z,1727467096.285 [DATMMP](INFO): Starting
2024-09-27T19:58:16.536Z,1727467096.536 [DATMMP](INFO): Starting
2024-09-27T19:58:16.788Z,1727467096.788 [DATMMP](INFO): Starting
2024-09-27T19:58:17.040Z,1727467097.040 [DATMMP](INFO): Starting
2024-09-27T19:58:17.292Z,1727467097.292 [DATMMP](INFO): Starting
2024-09-27T19:58:17.349Z,1727467097.349 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-09-27T19:58:17.544Z,1727467097.544 [DATMMP](INFO): Starting
2024-09-27T19:58:17.796Z,1727467097.796 [DATMMP](INFO): Starting
2024-09-27T19:58:18.048Z,1727467098.048 [DATMMP](INFO): Starting
2024-09-27T19:58:18.300Z,1727467098.300 [DATMMP](INFO): Starting
2024-09-27T19:58:18.552Z,1727467098.552 [DATMMP](INFO): Starting
2024-09-27T19:58:18.805Z,1727467098.805 [DATMMP](INFO): Starting
2024-09-27T19:58:19.056Z,1727467099.056 [DATMMP](INFO): Starting
2024-09-27T19:58:19.308Z,1727467099.308 [DATMMP](INFO): Starting
2024-09-27T19:58:19.560Z,1727467099.560 [DATMMP](INFO): Starting
2024-09-27T19:58:19.812Z,1727467099.812 [DATMMP](INFO): Starting
2024-09-27T19:58:20.064Z,1727467100.064 [DATMMP](INFO): Starting
2024-09-27T19:58:20.316Z,1727467100.316 [DATMMP](INFO): Starting
2024-09-27T19:58:20.568Z,1727467100.568 [DATMMP](INFO): Starting
2024-09-27T19:58:20.820Z,1727467100.820 [DATMMP](INFO): Starting
2024-09-27T19:58:21.072Z,1727467101.072 [DATMMP](INFO): Starting
2024-09-27T19:58:21.324Z,1727467101.324 [DATMMP](INFO): Starting
2024-09-27T19:58:21.576Z,1727467101.576 [DATMMP](INFO): Starting
2024-09-27T19:58:21.828Z,1727467101.828 [DATMMP](INFO): Starting
2024-09-27T19:58:22.080Z,1727467102.080 [DATMMP](INFO): Starting
2024-09-27T19:58:22.332Z,1727467102.332 [DATMMP](INFO): Starting
2024-09-27T19:58:22.584Z,1727467102.584 [DATMMP](INFO): Starting
2024-09-27T19:58:22.836Z,1727467102.836 [DATMMP](INFO): Starting
2024-09-27T19:58:23.088Z,1727467103.088 [DATMMP](INFO): Starting
2024-09-27T19:58:23.340Z,1727467103.340 [DATMMP](INFO): Starting
2024-09-27T19:58:23.592Z,1727467103.592 [DATMMP](INFO): Starting
2024-09-27T19:58:23.844Z,1727467103.844 [DATMMP](INFO): Starting
2024-09-27T19:58:23.844Z,1727467103.844 [DATMMP](INFO): DAT read:
2024-09-27T19:58:23.845Z,1727467103.845 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-09-27T19:58:24.096Z,1727467104.096 [DATMMP](INFO): Starting
2024-09-27T19:58:24.348Z,1727467104.348 [DATMMP](INFO): Starting
2024-09-27T19:58:24.600Z,1727467104.600 [DATMMP](INFO): Starting
2024-09-27T19:58:24.852Z,1727467104.852 [DATMMP](INFO): Starting
2024-09-27T19:58:25.104Z,1727467105.104 [DATMMP](INFO): Starting
2024-09-27T19:58:25.356Z,1727467105.356 [DATMMP](INFO): Starting
2024-09-27T19:58:25.357Z,1727467105.357 [DATMMP](INFO): DAT read: MF Frequency Band
2024-09-27T19:58:25.358Z,1727467105.358 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-09-27T19:58:25.359Z,1727467105.359 [DATMMP](INFO): DAT read: Aug 27 2000 19:58:21
2024-09-27T19:58:25.610Z,1727467105.610 [DATMMP](INFO): Starting
2024-09-27T19:58:25.860Z,1727467105.860 [DATMMP](INFO): Starting
2024-09-27T19:58:26.112Z,1727467106.112 [DATMMP](INFO): Starting
2024-09-27T19:58:26.364Z,1727467106.364 [DATMMP](INFO): Starting
2024-09-27T19:58:26.616Z,1727467106.616 [DATMMP](INFO): Starting
2024-09-27T19:58:26.617Z,1727467106.617 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-09-27T19:58:26.618Z,1727467106.618 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-09-27T19:58:26.618Z,1727467106.618 [DATMMP](INFO): commRate: 800
2024-09-27T19:58:26.868Z,1727467106.868 [DATMMP](INFO): Starting
2024-09-27T19:58:27.120Z,1727467107.120 [DATMMP](INFO): Starting
2024-09-27T19:58:27.372Z,1727467107.372 [DATMMP](INFO): Starting
2024-09-27T19:58:27.624Z,1727467107.624 [DATMMP](INFO): Starting
2024-09-27T19:58:27.876Z,1727467107.876 [DATMMP](INFO): Starting
2024-09-27T19:58:28.128Z,1727467108.128 [DATMMP](INFO): Starting
2024-09-27T19:58:28.380Z,1727467108.380 [DATMMP](INFO): Starting
2024-09-27T19:58:28.632Z,1727467108.632 [DATMMP](INFO): Starting
2024-09-27T19:58:28.684Z,1727467108.684 [DATMMP](INFO): entering command mode
2024-09-27T19:58:28.884Z,1727467108.884 [DATMMP](INFO): Starting
2024-09-27T19:58:28.884Z,1727467108.884 [DATMMP](INFO): DAT read:
2024-09-27T19:58:28.885Z,1727467108.885 [DATMMP](INFO): DAT read: user:1>
2024-09-27T19:58:28.885Z,1727467108.885 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-09-27T19:58:28.885Z,1727467108.885 [DATMMP](INFO): entering MMP mode
2024-09-27T19:58:29.136Z,1727467109.136 [DATMMP](INFO): Starting
2024-09-27T19:58:29.137Z,1727467109.137 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-27T19:58:29.138Z,1727467109.138 [DATMMP](INFO): status rx: x1
2024-09-27T19:58:29.139Z,1727467109.139 [DATMMP](INFO): MMP status: 1: started
2024-09-27T19:58:29.139Z,1727467109.139 [DATMMP](INFO): Received message type: status
2024-09-27T19:58:29.139Z,1727467109.139 [DATMMP](INFO): Handled
2024-09-27T19:58:29.388Z,1727467109.388 [DATMMP](INFO): Starting
2024-09-27T19:58:29.389Z,1727467109.389 [DATMMP](INFO): Sent Tx Power Config 8
2024-09-27T19:58:29.389Z,1727467109.389 [DATMMP](INFO): Setting local address: 1
2024-09-27T19:58:29.391Z,1727467109.391 [DATMMP](INFO): Setting time to: 19:58:29 And date to:9/27/2024
2024-09-27T19:58:29.640Z,1727467109.640 [DATMMP](INFO): Starting
2024-09-27T19:58:29.640Z,1727467109.640 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-27T19:58:29.641Z,1727467109.641 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-09-27T19:58:29.641Z,1727467109.641 [DATMMP](INFO): Received message type: mdmpower
2024-09-27T19:58:29.642Z,1727467109.642 [DATMMP](INFO): Handled
2024-09-27T19:58:29.892Z,1727467109.892 [DATMMP](INFO): Starting
2024-09-27T19:58:29.892Z,1727467109.892 [DATMMP](INFO): GSXN notify for xid: 10
2024-09-27T19:58:29.893Z,1727467109.893 [DATMMP](INFO): sreg_txpower rx: x8
2024-09-27T19:58:29.893Z,1727467109.893 [DATMMP](INFO): Received message type: sreg_txpower
2024-09-27T19:58:29.893Z,1727467109.893 [DATMMP](INFO): Match for : sreg_txpower
2024-09-27T19:58:29.894Z,1727467109.894 [DATMMP](INFO): ACK
2024-09-27T19:58:30.144Z,1727467110.144 [DATMMP](INFO): Starting
2024-09-27T19:58:30.145Z,1727467110.145 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-27T19:58:30.145Z,1727467110.145 [DATMMP](INFO): mdmlocal rx: x1 x0 x0 x0
2024-09-27T19:58:30.146Z,1727467110.146 [DATMMP](INFO): Received message type: mdmlocal
2024-09-27T19:58:30.146Z,1727467110.146 [DATMMP](INFO): Handled
2024-09-27T19:58:30.396Z,1727467110.396 [DATMMP](INFO): Starting
2024-09-27T19:58:30.396Z,1727467110.396 [DATMMP](INFO): GSXN notify for xid: 11
2024-09-27T19:58:30.397Z,1727467110.397 [DATMMP](INFO): sreg_locaddr rx: x1
2024-09-27T19:58:30.397Z,1727467110.397 [DATMMP](INFO): Received message type: sreg_locaddr
2024-09-27T19:58:30.397Z,1727467110.397 [DATMMP](INFO): Match for : sreg_locaddr
2024-09-27T19:58:30.397Z,1727467110.397 [DATMMP](INFO): ACK
2024-09-27T19:58:30.648Z,1727467110.648 [DATMMP](INFO): Starting
2024-09-27T19:58:30.648Z,1727467110.648 [DATMMP](INFO): GSXN notify for xid: 12
2024-09-27T19:58:30.650Z,1727467110.650 [DATMMP](INFO): timedate rx: x0 x13 x3A x1D x8 x1B x7C x0
2024-09-27T19:58:30.650Z,1727467110.650 [DATMMP](INFO): Received message type: timedate
2024-09-27T19:58:30.650Z,1727467110.650 [DATMMP](INFO): Match for : timedate
2024-09-27T19:58:30.650Z,1727467110.650 [DATMMP](INFO): ACK
2024-09-27T19:58:30.900Z,1727467110.900 [DATMMP](INFO): Starting
2024-09-27T19:58:30.900Z,1727467110.900 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-27T19:58:30.901Z,1727467110.901 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-09-27T19:58:30.901Z,1727467110.901 [DATMMP](INFO): Received message type: sys_verb
2024-09-27T19:58:30.901Z,1727467110.901 [DATMMP](INFO): Handled
2024-09-27T19:58:31.152Z,1727467111.152 [DATMMP](INFO): Starting
2024-09-27T19:58:31.152Z,1727467111.152 [DATMMP](INFO): GSXN notify for xid: 13
2024-09-27T19:58:31.153Z,1727467111.153 [DATMMP](INFO): sreg_verbosity rx: x0
2024-09-27T19:58:31.153Z,1727467111.153 [DATMMP](INFO): Received message type: sreg_verbosity
2024-09-27T19:58:31.153Z,1727467111.153 [DATMMP](INFO): Match for : sreg_verbosity
2024-09-27T19:58:31.154Z,1727467111.154 [DATMMP](INFO): ACK
2024-09-27T19:58:31.404Z,1727467111.404 [DATMMP](INFO): Starting
2024-09-27T19:58:31.404Z,1727467111.404 [DATMMP](INFO): GSXN notify for xid: 14
2024-09-27T19:58:31.405Z,1727467111.405 [DATMMP](INFO): version rx: x2 x5
2024-09-27T19:58:31.405Z,1727467111.405 [DATMMP](INFO): DatMMPVersion: 2.5
2024-09-27T19:58:31.405Z,1727467111.405 [DATMMP](INFO): Received message type: version
2024-09-27T19:58:31.406Z,1727467111.406 [DATMMP](INFO): Match for : version
2024-09-27T19:58:31.406Z,1727467111.406 [DATMMP](INFO): ACK
2024-09-27T19:58:31.406Z,1727467111.406 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-09-27T19:58:38.945Z,1727467118.945 [NAL9602](INFO): Powering up NAL9602
2024-09-27T19:58:45.779Z,1727467125.779 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-27T19:58:45.783Z,1727467125.783 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-09-27T19:58:45.783Z,1727467125.783 [DATMMP](INFO): Received message type: timesync
2024-09-27T19:58:45.785Z,1727467125.785 [DATMMP](INFO): Handled
2024-09-27T19:58:45.924Z,1727467125.924 [Waterlinked](INFO): Powering down
2024-09-27T19:58:49.811Z,1727467129.811 [NAL9602](INFO): NAL9602 initialized
2024-09-27T19:58:55.628Z,1727467135.628 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-27T19:58:55.629Z,1727467135.629 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-09-27T19:58:55.629Z,1727467135.629 [DATMMP](INFO): Received message type: timesync
2024-09-27T19:58:55.630Z,1727467135.630 [DATMMP](INFO): Handled
2024-09-27T19:59:14.459Z,1727467154.459 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-27T19:59:16.580Z,1727467156.580 [CommandExec](IMPORTANT): got command quit
2024-09-27T19:59:17.584Z,1727467157.584 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:17.584Z,1727467157.584 [CommandExec](INFO): Uninitializing the command executive.
2024-09-27T19:59:17.584Z,1727467157.584 [CommandExec](INFO): Uninitializing the command scheduler.
2024-09-27T19:59:17.584Z,1727467157.584 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:17.596Z,1727467157.596 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-09-27T19:59:17.596Z,1727467157.596 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-09-27T19:59:17.596Z,1727467157.596 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:17.597Z,1727467157.597 [NavChartDb](INFO): Join timeout helper Thread ID is 957
2024-09-27T19:59:17.928Z,1727467157.928 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:17.928Z,1727467157.928 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:17.936Z,1727467157.936 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-09-27T19:59:17.936Z,1727467157.936 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:17.936Z,1727467157.936 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 958
2024-09-27T19:59:18.356Z,1727467158.356 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:18.356Z,1727467158.356 [WetLabsBB2FL](INFO): Powering down
2024-09-27T19:59:18.357Z,1727467158.357 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:18.360Z,1727467158.360 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-09-27T19:59:18.360Z,1727467158.360 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:18.360Z,1727467158.360 [CTD_Seabird](INFO): Join timeout helper Thread ID is 959
2024-09-27T19:59:19.116Z,1727467159.116 [CTD_Seabird](INFO): Powering down
2024-09-27T19:59:19.128Z,1727467159.128 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:19.128Z,1727467159.128 [CTD_Seabird](INFO): Powering down
2024-09-27T19:59:19.140Z,1727467159.140 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:19.148Z,1727467159.148 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-09-27T19:59:19.148Z,1727467159.148 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:19.148Z,1727467159.148 [Radio_Surface](INFO): Join timeout helper Thread ID is 961
2024-09-27T19:59:19.460Z,1727467159.460 [Radio_Surface](INFO): Powering down
2024-09-27T19:59:19.461Z,1727467159.461 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:19.461Z,1727467159.461 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:19.480Z,1727467159.480 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-09-27T19:59:19.480Z,1727467159.480 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:19.480Z,1727467159.480 [Onboard](INFO): Join timeout helper Thread ID is 962
2024-09-27T19:59:20.816Z,1727467160.816 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:20.816Z,1727467160.816 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:20.837Z,1727467160.837 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-09-27T19:59:20.837Z,1727467160.837 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:20.838Z,1727467160.838 [DataOverHttps](INFO): Join timeout helper Thread ID is 963
2024-09-27T19:59:21.024Z,1727467161.024 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:21.025Z,1727467161.025 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.030Z,1727467161.030 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-09-27T19:59:21.031Z,1727467161.031 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.031Z,1727467161.031 [DATMMP](INFO): Join timeout helper Thread ID is 964
2024-09-27T19:59:21.088Z,1727467161.088 [DATMMP](INFO): Stop
2024-09-27T19:59:21.088Z,1727467161.088 [DATMMP](INFO): uninitialize
2024-09-27T19:59:21.088Z,1727467161.088 [DATMMP](INFO): Powering down
2024-09-27T19:59:21.169Z,1727467161.169 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:21.169Z,1727467161.169 [DATMMP](INFO): uninitialize
2024-09-27T19:59:21.169Z,1727467161.169 [DATMMP](INFO): Powering down
2024-09-27T19:59:21.170Z,1727467161.170 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.173Z,1727467161.173 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-09-27T19:59:21.173Z,1727467161.173 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.173Z,1727467161.173 [BackseatComponent](INFO): Join timeout helper Thread ID is 965
2024-09-27T19:59:21.368Z,1727467161.368 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:21.368Z,1727467161.368 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.385Z,1727467161.385 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-09-27T19:59:21.385Z,1727467161.385 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.385Z,1727467161.385 [logger](INFO): Join timeout helper Thread ID is 966
2024-09-27T19:59:21.396Z,1727467161.396 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:21.396Z,1727467161.396 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.404Z,1727467161.404 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-09-27T19:59:21.405Z,1727467161.405 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.405Z,1727467161.405 [CommandLine](INFO): Join timeout helper Thread ID is 967
2024-09-27T19:59:21.420Z,1727467161.420 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:21.420Z,1727467161.420 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.425Z,1727467161.425 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-09-27T19:59:21.425Z,1727467161.425 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.425Z,1727467161.425 [CommandExec](INFO): Join timeout helper Thread ID is 968
2024-09-27T19:59:21.426Z,1727467161.426 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-09-27T19:59:21.426Z,1727467161.426 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.427Z,1727467161.427 [controlThread](INFO): Join timeout helper Thread ID is 969
2024-09-27T19:59:21.712Z,1727467161.712 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-27T19:59:21.712Z,1727467161.712 [controlThread](DEBUG): Uninitializing ControlThread
2024-09-27T19:59:21.713Z,1727467161.713 [AHRS_M2](INFO): Powering down
2024-09-27T19:59:21.785Z,1727467161.785 [NAL9602](INFO): Powering down
2024-09-27T19:59:21.786Z,1727467161.786 [Sonardyne_Nano](INFO): Powering down
2024-09-27T19:59:21.904Z,1727467161.904 [Waterlinked](INFO): Powering down
2024-09-27T19:59:21.906Z,1727467161.906 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-09-27T19:59:21.907Z,1727467161.907 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-09-27T19:59:21.907Z,1727467161.907 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-09-27T19:59:21.908Z,1727467161.908 [MissionManager](INFO): Uninitializing Mission Default
2024-09-27T19:59:21.911Z,1727467161.911 [MissionManager](INFO): Uninitializing Mission Startup
2024-09-27T19:59:21.912Z,1727467161.912 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-09-27T19:59:21.913Z,1727467161.913 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-09-27T19:59:21.913Z,1727467161.913 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-09-27T19:59:21.913Z,1727467161.913 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-09-27T19:59:21.913Z,1727467161.913 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-09-27T19:59:21.914Z,1727467161.914 [BuoyancyServo](INFO): Powering down
2024-09-27T19:59:21.928Z,1727467161.928 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-09-27T19:59:21.928Z,1727467161.928 [ElevatorServo](INFO): Powering down
2024-09-27T19:59:21.929Z,1727467161.929 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-09-27T19:59:21.929Z,1727467161.929 [MassServo](INFO): Powering down
2024-09-27T19:59:21.930Z,1727467161.930 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-09-27T19:59:21.930Z,1727467161.930 [RudderServo](INFO): Powering down
2024-09-27T19:59:21.931Z,1727467161.931 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-09-27T19:59:21.931Z,1727467161.931 [ThrusterHE](INFO): Powering down
2024-09-27T19:59:21.932Z,1727467161.932 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-09-27T19:59:21.932Z,1727467161.932 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-09-27T19:59:21.932Z,1727467161.932 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-09-27T19:59:21.932Z,1727467161.932 [CBIT](DEBUG): Powering off loads.
2024-09-27T19:59:21.944Z,1727467161.944 [CBIT](DEBUG): Disabling WDT.
2024-09-27T19:59:21.956Z,1727467161.956 [CBIT](DEBUG): Opening all GF detection circuits.
2024-09-27T19:59:21.957Z,1727467161.957 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.986Z,1727467161.986 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.989Z,1727467161.989 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:21.994Z,1727467161.994 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:22.000Z,1727467162.000 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:22.041Z,1727467162.041 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:22.247Z,1727467162.247 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:22.253Z,1727467162.253 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:22.261Z,1727467162.261 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-27T19:59:22.324Z,1727467162.324 [logger ThreadHandler](INFO): Thread cancelled.