2024-08-28T20:45:40.688Z,1724877940.688 [Supervisor](DEBUG): Initializing supervisor.
2024-08-28T20:45:40.692Z,1724877940.692 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-08-28T20:45:40.692Z,1724877940.692 [SyncHandler](INFO): Protected caller Thread ID is 1782
2024-08-28T20:45:40.693Z,1724877940.693 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-08-28T20:45:40.694Z,1724877940.694 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-08-28T20:45:40.694Z,1724877940.694 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1783
2024-08-28T20:45:40.698Z,1724877940.698 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-08-28T20:45:40.715Z,1724877940.715 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-08-28T20:45:40.716Z,1724877940.716 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-08-28T20:45:40.716Z,1724877940.716 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1784
2024-08-28T20:45:40.721Z,1724877940.721 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-08-28T20:45:40.722Z,1724877940.722 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-08-28T20:45:40.722Z,1724877940.722 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1785
2024-08-28T20:45:40.724Z,1724877940.724 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-08-28T20:45:40.725Z,1724877940.725 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-08-28T20:45:40.725Z,1724877940.725 [logger ThreadHandler](INFO): Protected caller Thread ID is 1786
2024-08-28T20:45:40.729Z,1724877940.729 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-08-28T20:45:40.729Z,1724877940.729 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-08-28T20:45:40.731Z,1724877940.731 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-08-28T20:45:40.972Z,1724877940.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-08-28T20:45:40.973Z,1724877940.973 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-08-28T20:45:41.200Z,1724877941.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-08-28T20:45:41.201Z,1724877941.201 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-08-28T20:45:41.309Z,1724877941.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-08-28T20:45:41.309Z,1724877941.309 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-08-28T20:45:41.511Z,1724877941.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-08-28T20:45:41.511Z,1724877941.511 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-08-28T20:45:42.029Z,1724877942.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-08-28T20:45:42.030Z,1724877942.030 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-08-28T20:45:42.151Z,1724877942.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-08-28T20:45:42.152Z,1724877942.152 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-08-28T20:45:42.443Z,1724877942.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-08-28T20:45:42.443Z,1724877942.443 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-08-28T20:45:42.669Z,1724877942.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-08-28T20:45:42.669Z,1724877942.669 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-08-28T20:45:43.229Z,1724877943.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-08-28T20:45:43.229Z,1724877943.229 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-08-28T20:45:43.665Z,1724877943.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-08-28T20:45:43.666Z,1724877943.666 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-08-28T20:45:43.758Z,1724877943.758 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-08-28T20:45:44.142Z,1724877944.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-08-28T20:45:44.143Z,1724877944.143 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-08-28T20:45:44.296Z,1724877944.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-08-28T20:45:44.297Z,1724877944.297 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-08-28T20:45:45.186Z,1724877945.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-08-28T20:45:45.186Z,1724877945.186 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-08-28T20:45:45.563Z,1724877945.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-08-28T20:45:45.564Z,1724877945.564 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-08-28T20:45:45.660Z,1724877945.660 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2024-08-28T20:45:45.661Z,1724877945.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2024-08-28T20:45:45.757Z,1724877945.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2024-08-28T20:45:45.877Z,1724877945.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2024-08-28T20:45:46.011Z,1724877946.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg
2024-08-28T20:45:46.131Z,1724877946.131 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2024-08-28T20:45:46.240Z,1724877946.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2024-08-28T20:45:46.338Z,1724877946.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2024-08-28T20:45:46.455Z,1724877946.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2024-08-28T20:45:46.600Z,1724877946.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2024-08-28T20:45:46.792Z,1724877946.792 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2024-08-28T20:45:46.902Z,1724877946.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2024-08-28T20:45:47.179Z,1724877947.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-08-28T20:45:47.180Z,1724877947.180 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/root/
2024-08-28T20:45:47.181Z,1724877947.181 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-08-28T20:45:47.191Z,1724877947.191 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-08-28T20:45:47.519Z,1724877947.519 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-08-28T20:45:47.520Z,1724877947.520 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-08-28T20:45:48.047Z,1724877948.047 [AHRS_M2] Loaded
2024-08-28T20:45:48.047Z,1724877948.047 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-08-28T20:45:48.854Z,1724877948.854 [BPC1] Loaded
2024-08-28T20:45:48.854Z,1724877948.854 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-08-28T20:45:48.939Z,1724877948.939 [DATMMP] Loaded
2024-08-28T20:45:48.939Z,1724877948.939 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-08-28T20:45:48.940Z,1724877948.940 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408A94E0
2024-08-28T20:45:48.941Z,1724877948.941 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1868
2024-08-28T20:45:49.013Z,1724877949.013 [DataOverHttps] Loaded
2024-08-28T20:45:49.014Z,1724877949.014 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-08-28T20:45:49.015Z,1724877949.015 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D94E0
2024-08-28T20:45:49.015Z,1724877949.015 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1869
2024-08-28T20:45:49.037Z,1724877949.037 [Depth_Keller] Loaded
2024-08-28T20:45:49.037Z,1724877949.037 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-08-28T20:45:49.100Z,1724877949.100 [NAL9602] Loaded
2024-08-28T20:45:49.100Z,1724877949.100 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-08-28T20:45:49.130Z,1724877949.130 [Onboard] Loaded
2024-08-28T20:45:49.130Z,1724877949.130 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-08-28T20:45:49.131Z,1724877949.131 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409094E0
2024-08-28T20:45:49.131Z,1724877949.131 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1870
2024-08-28T20:45:49.145Z,1724877949.145 [Power24vConverter] Loaded
2024-08-28T20:45:49.145Z,1724877949.145 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-08-28T20:45:49.161Z,1724877949.161 [Radio_Surface] Loaded
2024-08-28T20:45:49.161Z,1724877949.161 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-08-28T20:45:49.162Z,1724877949.162 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409394E0
2024-08-28T20:45:49.163Z,1724877949.163 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1871
2024-08-28T20:45:49.178Z,1724877949.178 [Sonardyne_Nano] Loaded
2024-08-28T20:45:49.178Z,1724877949.178 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-08-28T20:45:49.178Z,1724877949.178 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-08-28T20:45:49.179Z,1724877949.179 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-08-28T20:45:49.284Z,1724877949.284 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-08-28T20:45:49.284Z,1724877949.284 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-08-28T20:45:49.334Z,1724877949.334 [VerticalControl](DEBUG): Construct VerticalControl.
2024-08-28T20:45:49.394Z,1724877949.394 [VerticalControl] Loaded
2024-08-28T20:45:49.394Z,1724877949.394 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-08-28T20:45:49.397Z,1724877949.397 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-08-28T20:45:49.444Z,1724877949.444 [HorizontalControl] Loaded
2024-08-28T20:45:49.444Z,1724877949.444 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-08-28T20:45:49.447Z,1724877949.447 [SpeedControl](DEBUG): Construct SpeedControl.
2024-08-28T20:45:49.450Z,1724877949.450 [SpeedControl] Loaded
2024-08-28T20:45:49.450Z,1724877949.450 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-08-28T20:45:49.453Z,1724877949.453 [LoopControl](DEBUG): Construct LoopControl.
2024-08-28T20:45:49.453Z,1724877949.453 [LoopControl] Loaded
2024-08-28T20:45:49.453Z,1724877949.453 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-08-28T20:45:49.454Z,1724877949.454 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-08-28T20:45:49.454Z,1724877949.454 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-08-28T20:45:49.464Z,1724877949.464 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-08-28T20:45:49.465Z,1724877949.465 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-08-28T20:45:49.491Z,1724877949.491 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-08-28T20:45:49.491Z,1724877949.491 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-08-28T20:45:49.610Z,1724877949.610 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-08-28T20:45:49.610Z,1724877949.610 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-08-28T20:45:49.652Z,1724877949.652 [DeadReckonUsingSpeedCalculator] Loaded
2024-08-28T20:45:49.652Z,1724877949.652 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-08-28T20:45:49.694Z,1724877949.694 [DeadReckonWithRespectToSeafloor] Loaded
2024-08-28T20:45:49.694Z,1724877949.694 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2024-08-28T20:45:49.707Z,1724877949.707 [NavChart] Loaded
2024-08-28T20:45:49.708Z,1724877949.708 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-08-28T20:45:49.713Z,1724877949.713 [UniversalFixResidualReporter] Loaded
2024-08-28T20:45:49.714Z,1724877949.714 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-08-28T20:45:49.714Z,1724877949.714 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-08-28T20:45:49.715Z,1724877949.715 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-08-28T20:45:49.849Z,1724877949.849 [SBIT](DEBUG): Construct Startup Built In Test.
2024-08-28T20:45:49.858Z,1724877949.858 [SBIT] Loaded
2024-08-28T20:45:49.858Z,1724877949.858 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-08-28T20:45:49.861Z,1724877949.861 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-08-28T20:45:49.874Z,1724877949.874 [IBIT] Loaded
2024-08-28T20:45:49.874Z,1724877949.874 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-08-28T20:45:49.880Z,1724877949.880 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-08-28T20:45:49.985Z,1724877949.985 [CBIT] Loaded
2024-08-28T20:45:49.985Z,1724877949.985 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-08-28T20:45:49.986Z,1724877949.986 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-08-28T20:45:49.986Z,1724877949.986 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-08-28T20:45:50.152Z,1724877950.152 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-08-28T20:45:50.153Z,1724877950.153 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-08-28T20:45:50.281Z,1724877950.281 [BuoyancyServo] Loaded
2024-08-28T20:45:50.282Z,1724877950.282 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-08-28T20:45:50.304Z,1724877950.304 [ElevatorServo] Loaded
2024-08-28T20:45:50.304Z,1724877950.304 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-08-28T20:45:50.325Z,1724877950.325 [MassServo] Loaded
2024-08-28T20:45:50.326Z,1724877950.326 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-08-28T20:45:50.425Z,1724877950.425 [RudderServo] Loaded
2024-08-28T20:45:50.425Z,1724877950.425 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-08-28T20:45:50.442Z,1724877950.442 [ThrusterHE] Loaded
2024-08-28T20:45:50.442Z,1724877950.442 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-08-28T20:45:50.443Z,1724877950.443 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-08-28T20:45:50.443Z,1724877950.443 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-08-28T20:45:50.586Z,1724877950.586 [ESPComponent] Loaded
2024-08-28T20:45:50.586Z,1724877950.586 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-08-28T20:45:50.608Z,1724877950.608 [PAR_Licor] Loaded
2024-08-28T20:45:50.609Z,1724877950.609 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-08-28T20:45:50.609Z,1724877950.609 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-08-28T20:45:50.610Z,1724877950.610 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-08-28T20:45:50.658Z,1724877950.658 [DepthRateCalculator] Loaded
2024-08-28T20:45:50.659Z,1724877950.659 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-08-28T20:45:50.663Z,1724877950.663 [PitchRateCalculator] Loaded
2024-08-28T20:45:50.664Z,1724877950.664 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-08-28T20:45:50.673Z,1724877950.673 [SpeedCalculator] Loaded
2024-08-28T20:45:50.673Z,1724877950.673 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-08-28T20:45:50.677Z,1724877950.677 [YawRateCalculator] Loaded
2024-08-28T20:45:50.677Z,1724877950.677 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-08-28T20:45:50.696Z,1724877950.696 [ElevatorOffsetCalculator] Loaded
2024-08-28T20:45:50.696Z,1724877950.696 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-08-28T20:45:50.697Z,1724877950.697 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-08-28T20:45:50.697Z,1724877950.697 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-08-28T20:45:50.765Z,1724877950.765 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-08-28T20:45:50.771Z,1724877950.771 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-08-28T20:45:50.774Z,1724877950.774 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-08-28T20:45:50.785Z,1724877950.785 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-08-28T20:45:50.786Z,1724877950.786 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC24E0
2024-08-28T20:45:50.786Z,1724877950.786 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1872
2024-08-28T20:45:50.791Z,1724877950.791 [Supervisor](INFO): Main Thread ID is 1654
2024-08-28T20:45:50.791Z,1724877950.791 [Supervisor](DEBUG): Running supervisor.
2024-08-28T20:45:50.791Z,1724877950.791 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1873
2024-08-28T20:45:50.792Z,1724877950.792 [CommandExec](INFO): Initializing the command executive.
2024-08-28T20:45:50.794Z,1724877950.794 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1874
2024-08-28T20:45:50.797Z,1724877950.797 [controlThread ThreadHandler](INFO): Handler Thread ID is 1875
2024-08-28T20:45:50.797Z,1724877950.797 [controlThread](DEBUG): Initializing ControlThread
2024-08-28T20:45:50.801Z,1724877950.801 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-08-28T20:45:50.802Z,1724877950.802 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-08-28T20:45:50.803Z,1724877950.803 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-08-28T20:45:50.803Z,1724877950.803 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-08-28T20:45:50.804Z,1724877950.804 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-08-28T20:45:50.805Z,1724877950.805 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-08-28T20:45:50.805Z,1724877950.805 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-08-28T20:45:50.805Z,1724877950.805 [SBIT](INFO): Initialize SBIT Component.
2024-08-28T20:45:50.806Z,1724877950.806 [SBIT](IMPORTANT): git: 2024-08-12-95-g598b95f60
2024-08-28T20:45:50.806Z,1724877950.806 [SBIT](INFO): git hash: 598b95f6033d24136933a3d5d1ddac5d4570ac95
2024-08-28T20:45:50.806Z,1724877950.806 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-08-28T20:45:50.807Z,1724877950.807 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-08-28T20:45:50.809Z,1724877950.809 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2024-08-28T20:45:50.809Z,1724877950.809 [IBIT](INFO): Initialize IBIT Component.
2024-08-28T20:45:50.810Z,1724877950.810 [CBIT](DEBUG): Initialize CBIT Component.
2024-08-28T20:45:50.811Z,1724877950.811 [logger ThreadHandler](INFO): Handler Thread ID is 1876
2024-08-28T20:45:50.824Z,1724877950.824 [CBIT](DEBUG): Initialized mux pins.
2024-08-28T20:45:50.824Z,1724877950.824 [CBIT](DEBUG): Initializing the watchdog timer.
2024-08-28T20:45:50.828Z,1724877950.828 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1877
2024-08-28T20:45:50.829Z,1724877950.829 [DATMMP](INFO): Start
2024-08-28T20:45:50.829Z,1724877950.829 [DATMMP](INFO): Powering up
2024-08-28T20:45:50.829Z,1724877950.829 [DATMMP](DEBUG): Initializing DATMMP.
2024-08-28T20:45:50.833Z,1724877950.833 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1879
2024-08-28T20:45:50.835Z,1724877950.835 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-08-28T20:45:50.845Z,1724877950.845 [Onboard ThreadHandler](INFO): Handler Thread ID is 1880
2024-08-28T20:45:50.849Z,1724877950.849 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-08-28T20:45:50.849Z,1724877950.849 [CBIT](DEBUG): Initializing heartbeat.
2024-08-28T20:45:50.865Z,1724877950.865 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1881
2024-08-28T20:45:50.880Z,1724877950.880 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1882
2024-08-28T20:45:50.883Z,1724877950.883 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2024-08-28T20:45:50.883Z,1724877950.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2024-08-28T20:45:50.883Z,1724877950.883 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2024-08-28T20:45:50.883Z,1724877950.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2024-08-28T20:45:50.883Z,1724877950.883 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-08-28T20:45:50.884Z,1724877950.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-08-28T20:45:50.884Z,1724877950.884 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-08-28T20:45:50.884Z,1724877950.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-08-28T20:45:50.884Z,1724877950.884 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2024-08-28T20:45:50.885Z,1724877950.885 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2024-08-28T20:45:50.885Z,1724877950.885 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2024-08-28T20:45:50.885Z,1724877950.885 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2024-08-28T20:45:50.920Z,1724877950.920 [CBIT](DEBUG): Deactivating GF circuits.
2024-08-28T20:45:50.920Z,1724877950.920 [CBIT](DEBUG): Deactivating emergency mode.
2024-08-28T20:45:50.960Z,1724877950.960 [CBIT](DEBUG): Backplane powered.
2024-08-28T20:45:50.965Z,1724877950.965 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-08-28T20:45:50.965Z,1724877950.965 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-08-28T20:45:50.965Z,1724877950.965 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-08-28T20:45:50.966Z,1724877950.966 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-08-28T20:45:50.966Z,1724877950.966 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-08-28T20:45:50.967Z,1724877950.967 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-08-28T20:45:50.968Z,1724877950.968 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-08-28T20:45:50.968Z,1724877950.968 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-08-28T20:45:50.970Z,1724877950.970 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-08-28T20:45:51.016Z,1724877951.016 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-08-28T20:45:51.021Z,1724877951.021 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-28T20:45:51.054Z,1724877951.054 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-08-28T20:45:51.054Z,1724877951.054 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-08-28T20:45:51.055Z,1724877951.055 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-08-28T20:45:51.056Z,1724877951.056 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-08-28T20:45:51.080Z,1724877951.080 [DATMMP](INFO): Starting
2024-08-28T20:45:51.272Z,1724877951.272 [Radio_Surface](INFO): Powering up
2024-08-28T20:45:51.274Z,1724877951.274 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-08-28T20:45:51.279Z,1724877951.279 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-08-28T20:45:51.293Z,1724877951.293 [Default:A.Wait](DEBUG): Construct Wait.
2024-08-28T20:45:51.295Z,1724877951.295 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-28T20:45:51.307Z,1724877951.307 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-08-28T20:45:51.329Z,1724877951.329 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-08-28T20:45:51.332Z,1724877951.332 [DATMMP](INFO): Starting
2024-08-28T20:45:51.387Z,1724877951.387 [Default:E.Execute](DEBUG): Construct Execute.
2024-08-28T20:45:51.390Z,1724877951.390 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-08-28T20:45:51.395Z,1724877951.395 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,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,Reporter,LogSplitter,
2024-08-28T20:45:51.402Z,1724877951.402 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-08-28T20:45:51.459Z,1724877951.459 [Power24vConverter](INFO): Powering up.
2024-08-28T20:45:51.459Z,1724877951.459 [Sonardyne_Nano](INFO): Initializing.
2024-08-28T20:45:51.514Z,1724877951.514 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-08-28T20:45:51.521Z,1724877951.521 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-08-28T20:45:51.521Z,1724877951.521 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-28T20:45:51.528Z,1724877951.528 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-28T20:45:51.529Z,1724877951.529 [MassServo](DEBUG): Initializing EZServoServo.
2024-08-28T20:45:51.536Z,1724877951.536 [MassServo](DEBUG): Initializing MassServo.
2024-08-28T20:45:51.537Z,1724877951.537 [RudderServo](DEBUG): Initializing EZServoServo.
2024-08-28T20:45:51.544Z,1724877951.544 [RudderServo](DEBUG): Initializing RudderServo.
2024-08-28T20:45:51.545Z,1724877951.545 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-08-28T20:45:51.552Z,1724877951.552 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-08-28T20:45:51.584Z,1724877951.584 [DATMMP](INFO): Starting
2024-08-28T20:45:51.836Z,1724877951.836 [DATMMP](INFO): Starting
2024-08-28T20:45:52.088Z,1724877952.088 [DATMMP](INFO): Starting
2024-08-28T20:45:52.340Z,1724877952.340 [DATMMP](INFO): Starting
2024-08-28T20:45:52.592Z,1724877952.592 [DATMMP](INFO): Starting
2024-08-28T20:45:52.844Z,1724877952.844 [DATMMP](INFO): Starting
2024-08-28T20:45:53.096Z,1724877953.096 [DATMMP](INFO): Starting
2024-08-28T20:45:53.349Z,1724877953.349 [DATMMP](INFO): Starting
2024-08-28T20:45:53.422Z,1724877953.422 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-08-28T20:45:53.600Z,1724877953.600 [DATMMP](INFO): Starting
2024-08-28T20:45:53.852Z,1724877953.852 [DATMMP](INFO): Starting
2024-08-28T20:45:54.104Z,1724877954.104 [DATMMP](INFO): Starting
2024-08-28T20:45:54.356Z,1724877954.356 [DATMMP](INFO): Starting
2024-08-28T20:45:54.608Z,1724877954.608 [DATMMP](INFO): Starting
2024-08-28T20:45:54.708Z,1724877954.708 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409
2024-08-28T20:45:54.860Z,1724877954.860 [DATMMP](INFO): Starting
2024-08-28T20:45:55.112Z,1724877955.112 [DATMMP](INFO): Starting
2024-08-28T20:45:55.364Z,1724877955.364 [DATMMP](INFO): Starting
2024-08-28T20:45:55.616Z,1724877955.616 [DATMMP](INFO): Starting
2024-08-28T20:45:55.871Z,1724877955.871 [DATMMP](INFO): Starting
2024-08-28T20:45:56.124Z,1724877956.124 [DATMMP](INFO): Starting
2024-08-28T20:45:56.376Z,1724877956.376 [DATMMP](INFO): Starting
2024-08-28T20:45:56.628Z,1724877956.628 [DATMMP](INFO): Starting
2024-08-28T20:45:56.880Z,1724877956.880 [DATMMP](INFO): Starting
2024-08-28T20:45:57.132Z,1724877957.132 [DATMMP](INFO): Starting
2024-08-28T20:45:57.269Z,1724877957.269 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-08-28T20:45:57.384Z,1724877957.384 [DATMMP](INFO): Starting
2024-08-28T20:45:57.636Z,1724877957.636 [DATMMP](INFO): Starting
2024-08-28T20:45:57.888Z,1724877957.888 [DATMMP](INFO): Starting
2024-08-28T20:45:58.140Z,1724877958.140 [DATMMP](INFO): Starting
2024-08-28T20:45:58.392Z,1724877958.392 [DATMMP](INFO): Starting
2024-08-28T20:45:58.644Z,1724877958.644 [DATMMP](INFO): Starting
2024-08-28T20:45:58.896Z,1724877958.896 [DATMMP](INFO): Starting
2024-08-28T20:45:59.148Z,1724877959.148 [DATMMP](INFO): Starting
2024-08-28T20:45:59.400Z,1724877959.400 [DATMMP](INFO): Starting
2024-08-28T20:45:59.652Z,1724877959.652 [DATMMP](INFO): Starting
2024-08-28T20:45:59.905Z,1724877959.905 [DATMMP](INFO): Starting
2024-08-28T20:46:00.156Z,1724877960.156 [DATMMP](INFO): Starting
2024-08-28T20:46:00.408Z,1724877960.408 [DATMMP](INFO): Starting
2024-08-28T20:46:00.660Z,1724877960.660 [DATMMP](INFO): Starting
2024-08-28T20:46:00.912Z,1724877960.912 [DATMMP](INFO): Starting
2024-08-28T20:46:01.164Z,1724877961.164 [DATMMP](INFO): Starting
2024-08-28T20:46:01.416Z,1724877961.416 [DATMMP](INFO): Starting
2024-08-28T20:46:01.668Z,1724877961.668 [DATMMP](INFO): Starting
2024-08-28T20:46:01.920Z,1724877961.920 [DATMMP](INFO): Starting
2024-08-28T20:46:02.172Z,1724877962.172 [DATMMP](INFO): Starting
2024-08-28T20:46:02.424Z,1724877962.424 [DATMMP](INFO): Starting
2024-08-28T20:46:02.424Z,1724877962.424 [DATMMP](INFO): DAT read:
2024-08-28T20:46:02.425Z,1724877962.425 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-08-28T20:46:02.676Z,1724877962.676 [DATMMP](INFO): Starting
2024-08-28T20:46:02.928Z,1724877962.928 [DATMMP](INFO): Starting
2024-08-28T20:46:03.180Z,1724877963.180 [DATMMP](INFO): Starting
2024-08-28T20:46:03.432Z,1724877963.432 [DATMMP](INFO): Starting
2024-08-28T20:46:03.684Z,1724877963.684 [DATMMP](INFO): Starting
2024-08-28T20:46:03.936Z,1724877963.936 [DATMMP](INFO): Starting
2024-08-28T20:46:03.937Z,1724877963.937 [DATMMP](INFO): DAT read: MF Frequency Band
2024-08-28T20:46:03.938Z,1724877963.938 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-08-28T20:46:03.938Z,1724877963.938 [DATMMP](INFO): DAT read: Jul 28 2000 20:45:58
2024-08-28T20:46:04.188Z,1724877964.188 [DATMMP](INFO): Starting
2024-08-28T20:46:04.440Z,1724877964.440 [DATMMP](INFO): Starting
2024-08-28T20:46:04.693Z,1724877964.693 [DATMMP](INFO): Starting
2024-08-28T20:46:04.944Z,1724877964.944 [DATMMP](INFO): Starting
2024-08-28T20:46:04.945Z,1724877964.945 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-08-28T20:46:04.946Z,1724877964.946 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-08-28T20:46:04.946Z,1724877964.946 [DATMMP](INFO): commRate: 800
2024-08-28T20:46:05.196Z,1724877965.196 [DATMMP](INFO): Starting
2024-08-28T20:46:05.448Z,1724877965.448 [DATMMP](INFO): Starting
2024-08-28T20:46:05.700Z,1724877965.700 [DATMMP](INFO): Starting
2024-08-28T20:46:05.952Z,1724877965.952 [DATMMP](INFO): Starting
2024-08-28T20:46:06.204Z,1724877966.204 [DATMMP](INFO): Starting
2024-08-28T20:46:06.456Z,1724877966.456 [DATMMP](INFO): Starting
2024-08-28T20:46:06.708Z,1724877966.708 [DATMMP](INFO): Starting
2024-08-28T20:46:06.960Z,1724877966.960 [DATMMP](INFO): Starting
2024-08-28T20:46:07.012Z,1724877967.012 [DATMMP](INFO): entering command mode
2024-08-28T20:46:07.212Z,1724877967.212 [DATMMP](INFO): Starting
2024-08-28T20:46:07.212Z,1724877967.212 [DATMMP](INFO): DAT read:
2024-08-28T20:46:07.213Z,1724877967.213 [DATMMP](INFO): DAT read: user:1>
2024-08-28T20:46:07.213Z,1724877967.213 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-08-28T20:46:07.213Z,1724877967.213 [DATMMP](INFO): entering MMP mode
2024-08-28T20:46:07.464Z,1724877967.464 [DATMMP](INFO): Starting
2024-08-28T20:46:07.465Z,1724877967.465 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-28T20:46:07.466Z,1724877967.466 [DATMMP](INFO): status rx: x1
2024-08-28T20:46:07.467Z,1724877967.467 [DATMMP](INFO): MMP status: 1: started
2024-08-28T20:46:07.467Z,1724877967.467 [DATMMP](INFO): Received message type: status
2024-08-28T20:46:07.467Z,1724877967.467 [DATMMP](INFO): Handled
2024-08-28T20:46:07.716Z,1724877967.716 [DATMMP](INFO): Starting
2024-08-28T20:46:07.717Z,1724877967.717 [DATMMP](INFO): Sent Tx Power Config 8
2024-08-28T20:46:07.717Z,1724877967.717 [DATMMP](INFO): Setting local address: 2
2024-08-28T20:46:07.719Z,1724877967.719 [DATMMP](INFO): Setting time to: 20:46:7 And date to:8/28/2024
2024-08-28T20:46:07.968Z,1724877967.968 [DATMMP](INFO): Starting
2024-08-28T20:46:07.968Z,1724877967.968 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-28T20:46:07.969Z,1724877967.969 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-08-28T20:46:07.969Z,1724877967.969 [DATMMP](INFO): Received message type: mdmpower
2024-08-28T20:46:07.969Z,1724877967.969 [DATMMP](INFO): Handled
2024-08-28T20:46:08.220Z,1724877968.220 [DATMMP](INFO): Starting
2024-08-28T20:46:08.220Z,1724877968.220 [DATMMP](INFO): GSXN notify for xid: 10
2024-08-28T20:46:08.221Z,1724877968.221 [DATMMP](INFO): sreg_txpower rx: x8
2024-08-28T20:46:08.221Z,1724877968.221 [DATMMP](INFO): Received message type: sreg_txpower
2024-08-28T20:46:08.221Z,1724877968.221 [DATMMP](INFO): Match for : sreg_txpower
2024-08-28T20:46:08.221Z,1724877968.221 [DATMMP](INFO): ACK
2024-08-28T20:46:08.472Z,1724877968.472 [DATMMP](INFO): Starting
2024-08-28T20:46:08.472Z,1724877968.472 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-28T20:46:08.473Z,1724877968.473 [DATMMP](INFO): mdmlocal rx: x2 x0 x0 x0
2024-08-28T20:46:08.473Z,1724877968.473 [DATMMP](INFO): Received message type: mdmlocal
2024-08-28T20:46:08.473Z,1724877968.473 [DATMMP](INFO): Handled
2024-08-28T20:46:08.724Z,1724877968.724 [DATMMP](INFO): Starting
2024-08-28T20:46:08.724Z,1724877968.724 [DATMMP](INFO): GSXN notify for xid: 11
2024-08-28T20:46:08.725Z,1724877968.725 [DATMMP](INFO): sreg_locaddr rx: x2
2024-08-28T20:46:08.725Z,1724877968.725 [DATMMP](INFO): Received message type: sreg_locaddr
2024-08-28T20:46:08.725Z,1724877968.725 [DATMMP](INFO): Match for : sreg_locaddr
2024-08-28T20:46:08.725Z,1724877968.725 [DATMMP](INFO): ACK
2024-08-28T20:46:08.976Z,1724877968.976 [DATMMP](INFO): Starting
2024-08-28T20:46:08.976Z,1724877968.976 [DATMMP](INFO): GSXN notify for xid: 12
2024-08-28T20:46:08.977Z,1724877968.977 [DATMMP](INFO): timedate rx: x0 x14 x2E x7 x7 x1C x7C x0
2024-08-28T20:46:08.978Z,1724877968.978 [DATMMP](INFO): Received message type: timedate
2024-08-28T20:46:08.978Z,1724877968.978 [DATMMP](INFO): Match for : timedate
2024-08-28T20:46:08.978Z,1724877968.978 [DATMMP](INFO): ACK
2024-08-28T20:46:09.228Z,1724877969.228 [DATMMP](INFO): Starting
2024-08-28T20:46:09.228Z,1724877969.228 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-28T20:46:09.229Z,1724877969.229 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-08-28T20:46:09.229Z,1724877969.229 [DATMMP](INFO): Received message type: sys_verb
2024-08-28T20:46:09.229Z,1724877969.229 [DATMMP](INFO): Handled
2024-08-28T20:46:09.480Z,1724877969.480 [DATMMP](INFO): Starting
2024-08-28T20:46:09.480Z,1724877969.480 [DATMMP](INFO): GSXN notify for xid: 13
2024-08-28T20:46:09.481Z,1724877969.481 [DATMMP](INFO): sreg_verbosity rx: x0
2024-08-28T20:46:09.481Z,1724877969.481 [DATMMP](INFO): Received message type: sreg_verbosity
2024-08-28T20:46:09.481Z,1724877969.481 [DATMMP](INFO): Match for : sreg_verbosity
2024-08-28T20:46:09.481Z,1724877969.481 [DATMMP](INFO): ACK
2024-08-28T20:46:09.732Z,1724877969.732 [DATMMP](INFO): Starting
2024-08-28T20:46:09.732Z,1724877969.732 [DATMMP](INFO): GSXN notify for xid: 14
2024-08-28T20:46:09.733Z,1724877969.733 [DATMMP](INFO): version rx: x2 x5
2024-08-28T20:46:09.733Z,1724877969.733 [DATMMP](INFO): DatMMPVersion: 2.5
2024-08-28T20:46:09.733Z,1724877969.733 [DATMMP](INFO): Received message type: version
2024-08-28T20:46:09.734Z,1724877969.734 [DATMMP](INFO): Match for : version
2024-08-28T20:46:09.734Z,1724877969.734 [DATMMP](INFO): ACK
2024-08-28T20:46:09.734Z,1724877969.734 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-08-28T20:46:11.219Z,1724877971.219 [SBIT](IMPORTANT): Beginning Startup BIT
2024-08-28T20:46:11.223Z,1724877971.223 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-28T20:46:13.264Z,1724877973.264 [ElevatorServo](FAULT): Overload Error
2024-08-28T20:46:13.264Z,1724877973.264 [ElevatorServo] Hardware Fault, FailCount= 1
2024-08-28T20:46:13.264Z,1724877973.264 [ElevatorServo](ERROR): Hardware Fault
2024-08-28T20:46:13.376Z,1724877973.376 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-08-28T20:46:13.653Z,1724877973.653 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-08-28T20:46:13.653Z,1724877973.653 [ElevatorServo](INFO): Powering down
2024-08-28T20:46:14.450Z,1724877974.450 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-28T20:46:14.569Z,1724877974.569 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-28T20:46:14.630Z,1724877974.630 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-08-28T20:46:14.630Z,1724877974.630 [ElevatorServo] No Fault, FailCount= 1
2024-08-28T20:46:19.281Z,1724877979.281 [NAL9602](INFO): Powering up NAL9602
2024-08-28T20:46:19.736Z,1724877979.736 [ElevatorServo](FAULT): Overload Error
2024-08-28T20:46:19.736Z,1724877979.736 [ElevatorServo] Hardware Fault, FailCount= 2
2024-08-28T20:46:19.736Z,1724877979.736 [ElevatorServo](ERROR): Hardware Fault
2024-08-28T20:46:19.793Z,1724877979.793 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-08-28T20:46:20.107Z,1724877980.107 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-08-28T20:46:20.107Z,1724877980.107 [ElevatorServo](INFO): Powering down
2024-08-28T20:46:20.918Z,1724877980.918 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-28T20:46:21.033Z,1724877981.033 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-28T20:46:21.065Z,1724877981.065 [CBIT](INFO): Clearing failed state for component ElevatorServo
2024-08-28T20:46:21.065Z,1724877981.065 [ElevatorServo] No Fault, FailCount= 2
2024-08-28T20:46:22.220Z,1724877982.220 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.037937
CHAN A1 (24V): 0.055228
CHAN A2 (12V): 0.000886
CHAN A3 (5V): -0.002016
CHAN B0 (3.3V): -0.000308
CHAN B1 (3.15aV): -0.001238
CHAN B2 (3.15bV): -0.001587
CHAN B3 (GND): -0.002107
OPEN: 0.004631
Full Scale: +/- 1 mA
2024-08-28T20:46:24.368Z,1724877984.368 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-28T20:46:24.369Z,1724877984.369 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-08-28T20:46:24.369Z,1724877984.369 [DATMMP](INFO): Received message type: timesync
2024-08-28T20:46:24.369Z,1724877984.369 [DATMMP](INFO): Handled
2024-08-28T20:46:24.602Z,1724877984.602 [SBIT](FAULT): Elevator: EXPECTED:15.000000 ACTUAL:-0.512464
2024-08-28T20:46:26.196Z,1724877986.196 [ElevatorServo](FAULT): Overload Error
2024-08-28T20:46:26.196Z,1724877986.196 [ElevatorServo] Hardware Fault, FailCount= 3
2024-08-28T20:46:26.196Z,1724877986.196 [ElevatorServo](ERROR): Hardware Fault
2024-08-28T20:46:26.260Z,1724877986.260 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2024-08-28T20:46:26.263Z,1724877986.263 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2024-08-28T20:46:26.574Z,1724877986.574 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-08-28T20:46:26.574Z,1724877986.574 [ElevatorServo](INFO): Powering down
2024-08-28T20:46:26.694Z,1724877986.694 [CommandExec](FAULT): Scheduling is paused
2024-08-28T20:46:26.694Z,1724877986.694 [CBIT](INFO): Critical error at 20240828T204626
2024-08-28T20:46:26.695Z,1724877986.695 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-08-28T20:46:27.374Z,1724877987.374 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-28T20:46:27.489Z,1724877987.489 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-28T20:46:30.193Z,1724877990.193 [NAL9602](INFO): NAL9602 initialized
2024-08-28T20:46:34.200Z,1724877994.200 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-28T20:46:34.201Z,1724877994.201 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-08-28T20:46:34.201Z,1724877994.201 [DATMMP](INFO): Received message type: timesync
2024-08-28T20:46:34.202Z,1724877994.202 [DATMMP](INFO): Handled
2024-08-28T20:46:51.227Z,1724878011.227 [SBIT](ERROR): Could not read elevatorAngleReader_.
2024-08-28T20:46:51.227Z,1724878011.227 [SBIT](FAULT): Control surface position failure.
2024-08-28T20:47:04.620Z,1724878024.620 [SBIT](ERROR): Could not read elevatorAngleReader_.
2024-08-28T20:47:04.620Z,1724878024.620 [SBIT](FAULT): Control surface position failure.
2024-08-28T20:47:05.060Z,1724878025.060 [SBIT](CRITICAL): SBIT FAILED
2024-08-28T20:47:05.060Z,1724878025.060 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-08-28T20:47:05.061Z,1724878025.061 [SBIT](IMPORTANT):
2024-08-28T20:47:05.061Z,1724878025.061 [SBIT](IMPORTANT): CBIT.vehicleOpen=1 bool;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=-0.1 meter;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2024-08-28T20:47:05.062Z,1724878025.062 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;
2024-08-28T20:47:05.064Z,1724878025.064 [CBIT](INFO): Critical error at 20240828T204705
2024-08-28T20:47:05.395Z,1724878025.395 [MissionManager](IMPORTANT): Started mission Startup
2024-08-28T20:47:05.396Z,1724878025.396 [Startup] Running Loop=1
2024-08-28T20:47:05.396Z,1724878025.396 [Startup](DEBUG): Aggregate::initialize Startup
2024-08-28T20:47:05.396Z,1724878025.396 [Startup:A.GoToSurface] Running Loop=1
2024-08-28T20:47:05.396Z,1724878025.396 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-28T20:47:05.397Z,1724878025.397 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-28T20:47:05.397Z,1724878025.397 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-28T20:47:05.397Z,1724878025.397 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-28T20:47:05.398Z,1724878025.398 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-28T20:47:05.398Z,1724878025.398 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-28T20:47:05.398Z,1724878025.398 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-28T20:47:05.411Z,1724878025.411 [Startup:StartupSatComms] Running Loop=1
2024-08-28T20:47:05.411Z,1724878025.411 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-08-28T20:47:05.411Z,1724878025.411 [Startup:StartupSatComms:A] Running Loop=1
2024-08-28T20:47:05.783Z,1724878025.783 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-08-28T20:47:31.985Z,1724878051.985 [CommandExec](IMPORTANT): got command strobe
2024-08-28T20:47:31.985Z,1724878051.985 [CommandExec](FAULT): Incomplete syntax. Try: help strobe
2024-08-28T20:47:39.121Z,1724878059.121 [CommandLine](INFO): End of History
2024-08-28T20:47:39.433Z,1724878059.433 [CommandLine](INFO): End of History
2024-08-28T20:47:39.721Z,1724878059.721 [CommandLine](INFO): End of History
2024-08-28T20:47:39.985Z,1724878059.985 [CommandLine](INFO): End of History
2024-08-28T20:47:40.250Z,1724878060.250 [CommandLine](INFO): End of History
2024-08-28T20:47:40.521Z,1724878060.521 [CommandLine](INFO): End of History
2024-08-28T20:47:40.777Z,1724878060.777 [CommandLine](INFO): End of History
2024-08-28T20:47:41.049Z,1724878061.049 [CommandLine](INFO): End of History
2024-08-28T20:47:41.312Z,1724878061.312 [CommandLine](INFO): End of History
2024-08-28T20:47:41.602Z,1724878061.602 [CommandLine](INFO): End of History
2024-08-28T20:47:41.865Z,1724878061.865 [CommandLine](INFO): End of History
2024-08-28T20:48:02.843Z,1724878082.843 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005082
2024-08-28T20:48:05.546Z,1724878085.546 [Startup:StartupSatComms:A](INFO): Timed out from 2024-08-28T20:47:05.4Z
2024-08-28T20:48:05.547Z,1724878085.547 [Startup:StartupSatComms:A] Stopped
2024-08-28T20:48:05.547Z,1724878085.547 [Startup:StartupSatComms:B] Running Loop=1
2024-08-28T20:48:05.984Z,1724878085.984 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-28T20:48:12.088Z,1724878092.088 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-28T20:48:13.209Z,1724878093.209 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240823T201245/Courier0018.lzma
2024-08-28T20:48:13.296Z,1724878093.296 [CommandLine](INFO): End of History
2024-08-28T20:48:13.545Z,1724878093.545 [CommandLine](INFO): End of History
2024-08-28T20:48:13.801Z,1724878093.801 [CommandLine](INFO): End of History
2024-08-28T20:48:14.033Z,1724878094.033 [CommandLine](INFO): End of History
2024-08-28T20:48:14.210Z,1724878094.210 [DataOverHttps](INFO): Moved sent file to Logs/20240823T201245/Courier0018.lzma.bak
2024-08-28T20:48:14.210Z,1724878094.210 [DataOverHttps](INFO): SBD MOMSN=23083217
2024-08-28T20:48:14.266Z,1724878094.266 [CommandLine](INFO): End of History
2024-08-28T20:48:14.515Z,1724878094.515 [CommandLine](INFO): End of History
2024-08-28T20:48:14.793Z,1724878094.793 [CommandLine](INFO): End of History
2024-08-28T20:48:15.153Z,1724878095.153 [CommandLine](INFO): End of History
2024-08-28T20:48:15.423Z,1724878095.423 [CommandLine](INFO): End of History
2024-08-28T20:48:15.672Z,1724878095.672 [CommandLine](INFO): End of History
2024-08-28T20:48:15.913Z,1724878095.913 [CommandLine](INFO): End of History
2024-08-28T20:48:16.136Z,1724878096.136 [CommandLine](INFO): End of History
2024-08-28T20:48:16.657Z,1724878096.657 [CommandLine](INFO): End of History
2024-08-28T20:48:16.842Z,1724878096.842 [CommandLine](INFO): End of History
2024-08-28T20:48:17.096Z,1724878097.096 [CommandLine](INFO): End of History
2024-08-28T20:48:17.368Z,1724878097.368 [CommandLine](INFO): End of History
2024-08-28T20:48:17.608Z,1724878097.608 [CommandLine](INFO): End of History
2024-08-28T20:48:17.824Z,1724878097.824 [CommandLine](INFO): End of History
2024-08-28T20:48:18.113Z,1724878098.113 [CommandLine](INFO): End of History
2024-08-28T20:48:18.584Z,1724878098.584 [CommandLine](INFO): End of History
2024-08-28T20:48:20.884Z,1724878100.884 [CommandLine](INFO): End of History
2024-08-28T20:48:21.128Z,1724878101.128 [CommandLine](INFO): End of History
2024-08-28T20:48:21.408Z,1724878101.408 [CommandLine](INFO): End of History
2024-08-28T20:48:30.116Z,1724878110.116 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240828T204431/Courier0000.lzma
2024-08-28T20:48:31.118Z,1724878111.118 [DataOverHttps](INFO): Moved sent file to Logs/20240828T204431/Courier0000.lzma.bak
2024-08-28T20:48:31.118Z,1724878111.118 [DataOverHttps](INFO): SBD MOMSN=23083219
2024-08-28T20:48:46.720Z,1724878126.720 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20240828T204540/Courier0000.lzma
2024-08-28T20:48:47.943Z,1724878127.943 [DataOverHttps](INFO): Moved sent file to Logs/20240828T204540/Courier0000.lzma.bak
2024-08-28T20:48:47.944Z,1724878127.944 [DataOverHttps](INFO): SBD MOMSN=23083223
2024-08-28T20:48:51.196Z,1724878131.196 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-08-28T20:48:51.196Z,1724878131.196 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-28T20:48:51.197Z,1724878131.197 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-08-28T20:48:51.197Z,1724878131.197 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-08-28T20:48:51.198Z,1724878131.198 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-08-28T20:48:51.198Z,1724878131.198 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-08-28T20:48:51.258Z,1724878131.258 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-28T20:48:51.259Z,1724878131.259 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-08-28T20:48:51.261Z,1724878131.261 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-08-28T20:48:51.610Z,1724878131.610 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-28T20:48:51.610Z,1724878131.610 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-08-28T20:48:51.611Z,1724878131.611 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-08-28T20:48:51.611Z,1724878131.611 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-08-28T20:48:51.612Z,1724878131.612 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-08-28T20:48:51.612Z,1724878131.612 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-08-28T20:48:52.009Z,1724878132.009 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-08-28T20:49:05.209Z,1724878145.209 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20240823T201245/Express0019.lzma
2024-08-28T20:49:05.799Z,1724878145.799 [Startup:StartupSatComms:B](INFO): Timed out from 2024-08-28T20:48:05.5Z
2024-08-28T20:49:05.799Z,1724878145.799 [Startup:StartupSatComms:B] Stopped
2024-08-28T20:49:05.799Z,1724878145.799 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-08-28T20:49:05.799Z,1724878145.799 [Startup:StartupSatComms] Stopped
2024-08-28T20:49:05.799Z,1724878145.799 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-08-28T20:49:05.801Z,1724878145.801 [Startup](INFO): Completed Startup
2024-08-28T20:49:05.801Z,1724878145.801 [MissionManager](INFO): Startup is completed.
2024-08-28T20:49:05.801Z,1724878145.801 [MissionManager](INFO): Uninitializing Mission Startup
2024-08-28T20:49:05.801Z,1724878145.801 [Startup] Stopped
2024-08-28T20:49:05.801Z,1724878145.801 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-08-28T20:49:05.801Z,1724878145.801 [Startup:A.GoToSurface] Stopped
2024-08-28T20:49:05.801Z,1724878145.801 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-28T20:49:06.210Z,1724878146.210 [DataOverHttps](INFO): Moved sent file to Logs/20240823T201245/Express0019.lzma.bak
2024-08-28T20:49:06.210Z,1724878146.210 [DataOverHttps](INFO): SBD MOMSN=23083228
2024-08-28T20:49:06.227Z,1724878146.227 [MissionManager](IMPORTANT): Started mission Default
2024-08-28T20:49:06.227Z,1724878146.227 [Default] Running Loop=1
2024-08-28T20:49:06.227Z,1724878146.227 [Default](DEBUG): Aggregate::initialize Default
2024-08-28T20:49:06.227Z,1724878146.227 [Default:B.GoToSurface] Running Loop=1
2024-08-28T20:49:06.227Z,1724878146.227 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-28T20:49:06.227Z,1724878146.227 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-28T20:49:06.228Z,1724878146.228 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-28T20:49:06.228Z,1724878146.228 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-28T20:49:06.229Z,1724878146.229 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-28T20:49:06.229Z,1724878146.229 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-28T20:49:06.229Z,1724878146.229 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-28T20:49:06.229Z,1724878146.229 [Default:A.Wait] Running Loop=1
2024-08-28T20:49:06.229Z,1724878146.229 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-08-28T20:49:13.537Z,1724878153.537 [CommandExec](IMPORTANT): got command strobe off
2024-08-28T20:49:13.537Z,1724878153.537 [CommandExec](IMPORTANT): Deactivating strobe
2024-08-28T20:49:19.572Z,1724878159.572 [Default:A.Wait](INFO): Done Waiting.
2024-08-28T20:49:19.573Z,1724878159.573 [Default:A.Wait] Stopped
2024-08-28T20:49:19.573Z,1724878159.573 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-08-28T20:49:19.926Z,1724878159.926 [Default:CheckIn] Running Loop=1
2024-08-28T20:49:19.926Z,1724878159.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-28T20:49:19.926Z,1724878159.926 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-28T20:49:19.988Z,1724878159.988 [CommandExec](IMPORTANT): got command failComponent
2024-08-28T20:49:19.989Z,1724878159.989 [CommandExec](IMPORTANT): Failed components:
2024-08-28T20:49:19.989Z,1724878159.989 [CommandExec](IMPORTANT): ElevatorServo: Hardware Fault
2024-08-28T20:49:20.320Z,1724878160.320 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-08-28T20:49:30.961Z,1724878170.961 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-28T20:49:39.574Z,1724878179.574 [CommandExec](IMPORTANT): got command get platform_elevator_angle degree
2024-08-28T20:49:39.574Z,1724878179.574 [CommandExec](FAULT): Element has no value
2024-08-28T20:49:59.927Z,1724878199.927 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-28T20:50:27.081Z,1724878227.081 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-28T20:51:33.241Z,1724878293.241 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-08-28T20:51:35.729Z,1724878295.729 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-28T20:51:49.392Z,1724878309.392 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-08-28T20:51:52.241Z,1724878312.241 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-08-28T20:51:52.241Z,1724878312.241 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-28T20:51:52.242Z,1724878312.242 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-08-28T20:51:52.242Z,1724878312.242 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-08-28T20:51:52.243Z,1724878312.243 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-08-28T20:51:52.243Z,1724878312.243 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-08-28T20:51:52.253Z,1724878312.253 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-28T20:51:52.253Z,1724878312.253 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-08-28T20:51:52.254Z,1724878312.254 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-08-28T20:51:52.660Z,1724878312.660 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-28T20:51:52.660Z,1724878312.660 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-08-28T20:51:52.660Z,1724878312.660 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-08-28T20:51:52.660Z,1724878312.660 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-08-28T20:51:52.661Z,1724878312.661 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-08-28T20:51:52.661Z,1724878312.661 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-08-28T20:51:53.043Z,1724878313.043 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-08-28T20:52:02.385Z,1724878322.385 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-28T20:52:03.937Z,1724878323.937 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-08-28T20:52:17.269Z,1724878337.269 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-08-28T20:52:28.824Z,1724878348.824 [CommandExec](IMPORTANT): got command failComponent
2024-08-28T20:52:28.824Z,1724878348.824 [CommandExec](IMPORTANT): Failed components:
2024-08-28T20:52:28.825Z,1724878348.825 [CommandExec](IMPORTANT): ElevatorServo: Hardware Fault
2024-08-28T20:52:30.196Z,1724878350.196 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-08-28T20:52:33.089Z,1724878353.089 [CommandExec](IMPORTANT): got command restart application
2024-08-28T20:52:34.092Z,1724878354.092 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-28T20:52:34.092Z,1724878354.092 [CommandExec](INFO): Uninitializing the command executive.
2024-08-28T20:52:34.092Z,1724878354.092 [CommandExec](INFO): Uninitializing the command scheduler.
2024-08-28T20:52:34.092Z,1724878354.092 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:34.124Z,1724878354.124 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-08-28T20:52:34.124Z,1724878354.124 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-08-28T20:52:34.124Z,1724878354.124 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:34.125Z,1724878354.125 [NavChartDb](INFO): Join timeout helper Thread ID is 1905
2024-08-28T20:52:34.380Z,1724878354.380 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-28T20:52:34.380Z,1724878354.380 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:34.388Z,1724878354.388 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-08-28T20:52:34.388Z,1724878354.388 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:34.388Z,1724878354.388 [Radio_Surface](INFO): Join timeout helper Thread ID is 1906
2024-08-28T20:52:34.512Z,1724878354.512 [Radio_Surface](INFO): Powering down
2024-08-28T20:52:34.513Z,1724878354.513 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-28T20:52:34.513Z,1724878354.513 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:34.528Z,1724878354.528 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-08-28T20:52:34.528Z,1724878354.528 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:34.528Z,1724878354.528 [Onboard](INFO): Join timeout helper Thread ID is 1907
2024-08-28T20:52:35.040Z,1724878355.040 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-08-28T20:52:36.168Z,1724878356.168 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-28T20:52:36.168Z,1724878356.168 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:36.176Z,1724878356.176 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-08-28T20:52:36.176Z,1724878356.176 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:36.176Z,1724878356.176 [DataOverHttps](INFO): Join timeout helper Thread ID is 1908
2024-08-28T20:52:37.048Z,1724878357.048 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-28T20:52:37.049Z,1724878357.049 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.061Z,1724878357.061 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-08-28T20:52:37.061Z,1724878357.061 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.062Z,1724878357.062 [DATMMP](INFO): Join timeout helper Thread ID is 1909
2024-08-28T20:52:37.062Z,1724878357.062 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-28T20:52:37.062Z,1724878357.062 [DATMMP](INFO): uninitialize
2024-08-28T20:52:37.062Z,1724878357.062 [DATMMP](INFO): Powering down
2024-08-28T20:52:37.143Z,1724878357.143 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.157Z,1724878357.157 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-08-28T20:52:37.157Z,1724878357.157 [logger ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.157Z,1724878357.157 [logger](INFO): Join timeout helper Thread ID is 1910
2024-08-28T20:52:37.158Z,1724878357.158 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-28T20:52:37.158Z,1724878357.158 [logger ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.159Z,1724878357.159 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-08-28T20:52:37.159Z,1724878357.159 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.160Z,1724878357.160 [CommandLine](INFO): Join timeout helper Thread ID is 1911
2024-08-28T20:52:37.168Z,1724878357.168 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-28T20:52:37.168Z,1724878357.168 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.180Z,1724878357.180 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-08-28T20:52:37.181Z,1724878357.181 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.181Z,1724878357.181 [CommandExec](INFO): Join timeout helper Thread ID is 1912
2024-08-28T20:52:37.182Z,1724878357.182 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-08-28T20:52:37.182Z,1724878357.182 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.183Z,1724878357.183 [controlThread](INFO): Join timeout helper Thread ID is 1913
2024-08-28T20:52:37.460Z,1724878357.460 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-28T20:52:37.460Z,1724878357.460 [controlThread](DEBUG): Uninitializing ControlThread
2024-08-28T20:52:37.461Z,1724878357.461 [AHRS_M2](INFO): Powering down
2024-08-28T20:52:37.533Z,1724878357.533 [NAL9602](INFO): Powering down
2024-08-28T20:52:37.534Z,1724878357.534 [Sonardyne_Nano](INFO): Powering down
2024-08-28T20:52:37.653Z,1724878357.653 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-08-28T20:52:37.654Z,1724878357.654 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-08-28T20:52:37.654Z,1724878357.654 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-08-28T20:52:37.655Z,1724878357.655 [MissionManager](INFO): Uninitializing Mission Default
2024-08-28T20:52:37.655Z,1724878357.655 [Default] Stopped
2024-08-28T20:52:37.655Z,1724878357.655 [Default](DEBUG): Aggregate::uninitialize Default
2024-08-28T20:52:37.655Z,1724878357.655 [Default:B.GoToSurface] Stopped
2024-08-28T20:52:37.655Z,1724878357.655 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-28T20:52:37.655Z,1724878357.655 [Default:CheckIn] Stopped
2024-08-28T20:52:37.655Z,1724878357.655 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-28T20:52:37.655Z,1724878357.655 [Default:CheckIn:Read_GPS] Stopped
2024-08-28T20:52:37.658Z,1724878357.658 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-08-28T20:52:37.658Z,1724878357.658 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-08-28T20:52:37.659Z,1724878357.659 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-08-28T20:52:37.659Z,1724878357.659 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-08-28T20:52:37.659Z,1724878357.659 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-08-28T20:52:37.659Z,1724878357.659 [BuoyancyServo](INFO): Powering down
2024-08-28T20:52:37.672Z,1724878357.672 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-08-28T20:52:37.672Z,1724878357.672 [ElevatorServo](INFO): Powering down
2024-08-28T20:52:37.673Z,1724878357.673 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-08-28T20:52:37.673Z,1724878357.673 [MassServo](INFO): Powering down
2024-08-28T20:52:37.674Z,1724878357.674 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-08-28T20:52:37.674Z,1724878357.674 [RudderServo](INFO): Powering down
2024-08-28T20:52:37.674Z,1724878357.674 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-08-28T20:52:37.675Z,1724878357.675 [ThrusterHE](INFO): Powering down
2024-08-28T20:52:37.675Z,1724878357.675 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-08-28T20:52:37.676Z,1724878357.676 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-08-28T20:52:37.676Z,1724878357.676 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-08-28T20:52:37.676Z,1724878357.676 [CBIT](DEBUG): Powering off loads.
2024-08-28T20:52:37.688Z,1724878357.688 [CBIT](DEBUG): Disabling WDT.
2024-08-28T20:52:37.700Z,1724878357.700 [CBIT](DEBUG): Opening all GF detection circuits.
2024-08-28T20:52:37.701Z,1724878357.701 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.733Z,1724878357.733 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.735Z,1724878357.735 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.741Z,1724878357.741 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:37.747Z,1724878357.747 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:38.009Z,1724878358.009 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-28T20:52:38.056Z,1724878358.056 [logger ThreadHandler](INFO): Thread cancelled.