2022-05-06T21:45:19.406Z,1651873519.406 [Supervisor](DEBUG): Initializing supervisor. 2022-05-06T21:45:19.411Z,1651873519.411 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-05-06T21:45:19.412Z,1651873519.412 [SyncHandler](INFO): Protected caller Thread ID is 1867 2022-05-06T21:45:19.412Z,1651873519.412 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-05-06T21:45:19.413Z,1651873519.413 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-05-06T21:45:19.414Z,1651873519.414 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1868 2022-05-06T21:45:19.418Z,1651873519.418 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-05-06T21:45:19.437Z,1651873519.437 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-05-06T21:45:19.438Z,1651873519.438 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-05-06T21:45:19.438Z,1651873519.438 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1869 2022-05-06T21:45:19.442Z,1651873519.442 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-05-06T21:45:19.443Z,1651873519.443 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-05-06T21:45:19.443Z,1651873519.443 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1870 2022-05-06T21:45:19.446Z,1651873519.446 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-05-06T21:45:19.446Z,1651873519.446 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-05-06T21:45:19.447Z,1651873519.447 [logger ThreadHandler](INFO): Protected caller Thread ID is 1871 2022-05-06T21:45:19.450Z,1651873519.450 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-05-06T21:45:19.451Z,1651873519.451 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-05-06T21:45:19.453Z,1651873519.453 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-05-06T21:45:19.767Z,1651873519.767 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-05-06T21:45:19.768Z,1651873519.768 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-05-06T21:45:20.414Z,1651873520.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-05-06T21:45:20.415Z,1651873520.415 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-05-06T21:45:20.521Z,1651873520.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-05-06T21:45:20.521Z,1651873520.521 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-05-06T21:45:21.078Z,1651873521.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-05-06T21:45:21.079Z,1651873521.079 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-05-06T21:45:21.174Z,1651873521.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-05-06T21:45:21.174Z,1651873521.174 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-05-06T21:45:21.254Z,1651873521.254 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-05-06T21:45:21.622Z,1651873521.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-05-06T21:45:21.622Z,1651873521.622 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-05-06T21:45:21.840Z,1651873521.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-05-06T21:45:21.840Z,1651873521.840 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-05-06T21:45:21.966Z,1651873521.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-05-06T21:45:21.967Z,1651873521.967 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-05-06T21:45:22.190Z,1651873522.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-05-06T21:45:22.191Z,1651873522.191 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-05-06T21:45:22.665Z,1651873522.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-05-06T21:45:22.665Z,1651873522.665 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-05-06T21:45:23.074Z,1651873523.074 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-05-06T21:45:23.075Z,1651873523.075 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-05-06T21:45:23.415Z,1651873523.415 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-05-06T21:45:23.756Z,1651873523.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-05-06T21:45:23.757Z,1651873523.757 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-05-06T21:45:24.062Z,1651873524.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-05-06T21:45:24.063Z,1651873524.063 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-05-06T21:45:24.354Z,1651873524.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-05-06T21:45:24.356Z,1651873524.356 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2022-05-06T21:45:24.357Z,1651873524.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2022-05-06T21:45:24.462Z,1651873524.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2022-05-06T21:45:24.608Z,1651873524.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2022-05-06T21:45:24.692Z,1651873524.692 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2022-05-06T21:45:24.774Z,1651873524.774 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2022-05-06T21:45:24.884Z,1651873524.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2022-05-06T21:45:25.059Z,1651873525.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2022-05-06T21:45:25.323Z,1651873525.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-05-06T21:45:25.323Z,1651873525.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2022-05-06T21:45:25.454Z,1651873525.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2022-05-06T21:45:25.545Z,1651873525.545 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2022-05-06T21:45:25.648Z,1651873525.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2022-05-06T21:45:25.749Z,1651873525.749 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2022-05-06T21:45:25.749Z,1651873525.749 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-05-06T21:45:25.760Z,1651873525.760 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-05-06T21:45:25.861Z,1651873525.861 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-05-06T21:45:25.862Z,1651873525.862 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-05-06T21:45:25.872Z,1651873525.872 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-05-06T21:45:25.873Z,1651873525.873 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-05-06T21:45:25.927Z,1651873525.927 [DepthRateCalculator] Loaded 2022-05-06T21:45:25.927Z,1651873525.927 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-05-06T21:45:25.933Z,1651873525.933 [PitchRateCalculator] Loaded 2022-05-06T21:45:25.933Z,1651873525.933 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-05-06T21:45:25.943Z,1651873525.943 [SpeedCalculator] Loaded 2022-05-06T21:45:25.944Z,1651873525.944 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-05-06T21:45:25.949Z,1651873525.949 [YawRateCalculator] Loaded 2022-05-06T21:45:25.949Z,1651873525.949 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-05-06T21:45:25.969Z,1651873525.969 [ElevatorOffsetCalculator] Loaded 2022-05-06T21:45:25.969Z,1651873525.969 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-05-06T21:45:25.970Z,1651873525.970 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-05-06T21:45:25.970Z,1651873525.970 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-05-06T21:45:26.024Z,1651873526.024 [VerticalControl](DEBUG): Construct VerticalControl. 2022-05-06T21:45:26.079Z,1651873526.079 [VerticalControl] Loaded 2022-05-06T21:45:26.079Z,1651873526.079 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-05-06T21:45:26.082Z,1651873526.082 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-05-06T21:45:26.121Z,1651873526.121 [HorizontalControl] Loaded 2022-05-06T21:45:26.121Z,1651873526.121 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-05-06T21:45:26.123Z,1651873526.123 [SpeedControl](DEBUG): Construct SpeedControl. 2022-05-06T21:45:26.126Z,1651873526.126 [SpeedControl] Loaded 2022-05-06T21:45:26.126Z,1651873526.126 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-05-06T21:45:26.129Z,1651873526.129 [LoopControl](DEBUG): Construct LoopControl. 2022-05-06T21:45:26.130Z,1651873526.130 [LoopControl] Loaded 2022-05-06T21:45:26.130Z,1651873526.130 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-05-06T21:45:26.130Z,1651873526.130 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-05-06T21:45:26.131Z,1651873526.131 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-05-06T21:45:26.242Z,1651873526.242 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-05-06T21:45:26.243Z,1651873526.243 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-05-06T21:45:26.256Z,1651873526.256 [NavChart] Loaded 2022-05-06T21:45:26.256Z,1651873526.256 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-05-06T21:45:26.262Z,1651873526.262 [UniversalFixResidualReporter] Loaded 2022-05-06T21:45:26.262Z,1651873526.262 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-05-06T21:45:26.263Z,1651873526.263 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-05-06T21:45:26.263Z,1651873526.263 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-05-06T21:45:26.375Z,1651873526.375 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-05-06T21:45:26.376Z,1651873526.376 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-05-06T21:45:26.806Z,1651873526.806 [AHRS_M2] Loaded 2022-05-06T21:45:26.806Z,1651873526.806 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-05-06T21:45:26.841Z,1651873526.841 [BackseatComponent] Loaded 2022-05-06T21:45:26.841Z,1651873526.841 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2022-05-06T21:45:26.842Z,1651873526.842 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408DD4E0 2022-05-06T21:45:26.842Z,1651873526.842 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1954 2022-05-06T21:45:26.845Z,1651873526.845 [LcmUniversalReporter] Loaded 2022-05-06T21:45:26.845Z,1651873526.845 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2022-05-06T21:45:26.918Z,1651873526.918 [DataOverHttps] Loaded 2022-05-06T21:45:26.918Z,1651873526.918 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-05-06T21:45:26.919Z,1651873526.919 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0 2022-05-06T21:45:26.920Z,1651873526.920 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1955 2022-05-06T21:45:26.940Z,1651873526.940 [Depth_Keller] Loaded 2022-05-06T21:45:26.940Z,1651873526.940 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-05-06T21:45:26.945Z,1651873526.945 [DropWeight] Loaded 2022-05-06T21:45:26.945Z,1651873526.945 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2022-05-06T21:45:26.981Z,1651873526.981 [DVL_micro] Loaded 2022-05-06T21:45:26.981Z,1651873526.981 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2022-05-06T21:45:27.041Z,1651873527.041 [NAL9602] Loaded 2022-05-06T21:45:27.042Z,1651873527.042 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-05-06T21:45:27.070Z,1651873527.070 [Onboard] Loaded 2022-05-06T21:45:27.071Z,1651873527.071 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-05-06T21:45:27.072Z,1651873527.072 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093D4E0 2022-05-06T21:45:27.072Z,1651873527.072 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1956 2022-05-06T21:45:27.084Z,1651873527.084 [Power24vConverter] Loaded 2022-05-06T21:45:27.084Z,1651873527.084 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-05-06T21:45:27.097Z,1651873527.097 [Radio_Surface] Loaded 2022-05-06T21:45:27.097Z,1651873527.097 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-05-06T21:45:27.098Z,1651873527.098 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0 2022-05-06T21:45:27.098Z,1651873527.098 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1957 2022-05-06T21:45:27.185Z,1651873527.185 [DAT] Loaded 2022-05-06T21:45:27.185Z,1651873527.185 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2022-05-06T21:45:27.186Z,1651873527.186 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4099D4E0 2022-05-06T21:45:27.187Z,1651873527.187 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1958 2022-05-06T21:45:27.187Z,1651873527.187 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-05-06T21:45:27.188Z,1651873527.188 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-05-06T21:45:27.316Z,1651873527.316 [BuoyancyServo] Loaded 2022-05-06T21:45:27.316Z,1651873527.316 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-05-06T21:45:27.336Z,1651873527.336 [ElevatorServo] Loaded 2022-05-06T21:45:27.336Z,1651873527.336 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-05-06T21:45:27.355Z,1651873527.355 [MassServo] Loaded 2022-05-06T21:45:27.355Z,1651873527.355 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-05-06T21:45:27.374Z,1651873527.374 [RudderServo] Loaded 2022-05-06T21:45:27.374Z,1651873527.374 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-05-06T21:45:27.389Z,1651873527.389 [ThrusterHE] Loaded 2022-05-06T21:45:27.389Z,1651873527.389 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2022-05-06T21:45:27.389Z,1651873527.389 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-05-06T21:45:27.390Z,1651873527.390 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-05-06T21:45:27.450Z,1651873527.450 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-05-06T21:45:27.450Z,1651873527.450 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-05-06T21:45:27.478Z,1651873527.478 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-05-06T21:45:27.478Z,1651873527.478 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-05-06T21:45:27.800Z,1651873527.800 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-05-06T21:45:27.800Z,1651873527.800 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-05-06T21:45:27.975Z,1651873527.975 [CTD_Seabird] Loaded 2022-05-06T21:45:27.975Z,1651873527.975 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-05-06T21:45:27.977Z,1651873527.977 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B394E0 2022-05-06T21:45:27.977Z,1651873527.977 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1959 2022-05-06T21:45:28.009Z,1651873528.009 [ESPComponent] Loaded 2022-05-06T21:45:28.009Z,1651873528.009 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2022-05-06T21:45:28.029Z,1651873528.029 [PAR_Licor] Loaded 2022-05-06T21:45:28.029Z,1651873528.029 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-05-06T21:45:28.061Z,1651873528.061 [WetLabsBB2FL] Loaded 2022-05-06T21:45:28.061Z,1651873528.061 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-05-06T21:45:28.062Z,1651873528.062 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B694E0 2022-05-06T21:45:28.062Z,1651873528.062 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1960 2022-05-06T21:45:28.063Z,1651873528.063 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-05-06T21:45:28.064Z,1651873528.064 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-05-06T21:45:28.410Z,1651873528.410 [SBIT](DEBUG): Construct Startup Built In Test. 2022-05-06T21:45:28.451Z,1651873528.451 [SBIT] Loaded 2022-05-06T21:45:28.451Z,1651873528.451 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-05-06T21:45:28.486Z,1651873528.486 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-05-06T21:45:28.511Z,1651873528.511 [IBIT] Loaded 2022-05-06T21:45:28.512Z,1651873528.512 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-05-06T21:45:28.517Z,1651873528.517 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-05-06T21:45:28.746Z,1651873528.746 [CBIT] Loaded 2022-05-06T21:45:28.747Z,1651873528.747 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-05-06T21:45:28.747Z,1651873528.747 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-05-06T21:45:28.753Z,1651873528.753 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-05-06T21:45:28.756Z,1651873528.756 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-05-06T21:45:28.766Z,1651873528.766 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-05-06T21:45:28.767Z,1651873528.767 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0 2022-05-06T21:45:28.768Z,1651873528.768 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1961 2022-05-06T21:45:28.772Z,1651873528.772 [Supervisor](INFO): Main Thread ID is 1703 2022-05-06T21:45:28.772Z,1651873528.772 [Supervisor](DEBUG): Running supervisor. 2022-05-06T21:45:28.773Z,1651873528.773 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1962 2022-05-06T21:45:28.774Z,1651873528.774 [CommandExec](INFO): Initializing the command executive. 2022-05-06T21:45:28.775Z,1651873528.775 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1963 2022-05-06T21:45:28.777Z,1651873528.777 [controlThread ThreadHandler](INFO): Handler Thread ID is 1964 2022-05-06T21:45:28.778Z,1651873528.778 [controlThread](DEBUG): Initializing ControlThread 2022-05-06T21:45:28.778Z,1651873528.778 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-05-06T21:45:28.779Z,1651873528.779 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-05-06T21:45:28.779Z,1651873528.779 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-05-06T21:45:28.780Z,1651873528.780 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-05-06T21:45:28.780Z,1651873528.780 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-05-06T21:45:28.781Z,1651873528.781 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-05-06T21:45:28.782Z,1651873528.782 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-05-06T21:45:28.783Z,1651873528.783 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-05-06T21:45:28.783Z,1651873528.783 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-05-06T21:45:28.784Z,1651873528.784 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-05-06T21:45:28.785Z,1651873528.785 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-05-06T21:45:28.792Z,1651873528.792 [SBIT](INFO): Initialize SBIT Component. 2022-05-06T21:45:28.793Z,1651873528.793 [SBIT](IMPORTANT): git: 2022-04-25-37-g2a944135f 2022-05-06T21:45:28.793Z,1651873528.793 [SBIT](INFO): git hash: 2a944135f4713de004d013a6b17eee164aec7a5a 2022-05-06T21:45:28.793Z,1651873528.793 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-05-06T21:45:28.794Z,1651873528.794 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2022-05-06T21:45:28.795Z,1651873528.795 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2022-05-06T21:45:28.796Z,1651873528.796 [IBIT](INFO): Initialize IBIT Component. 2022-05-06T21:45:28.797Z,1651873528.797 [CBIT](DEBUG): Initialize CBIT Component. 2022-05-06T21:45:28.798Z,1651873528.798 [logger ThreadHandler](INFO): Handler Thread ID is 1965 2022-05-06T21:45:28.808Z,1651873528.808 [CBIT](DEBUG): Initialized mux pins. 2022-05-06T21:45:28.808Z,1651873528.808 [CBIT](DEBUG): Initializing the watchdog timer. 2022-05-06T21:45:28.816Z,1651873528.816 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1966 2022-05-06T21:45:28.828Z,1651873528.828 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1967 2022-05-06T21:45:28.829Z,1651873528.829 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-05-06T21:45:28.832Z,1651873528.832 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-05-06T21:45:28.832Z,1651873528.832 [CBIT](DEBUG): Initializing heartbeat. 2022-05-06T21:45:28.840Z,1651873528.840 [Onboard ThreadHandler](INFO): Handler Thread ID is 1968 2022-05-06T21:45:28.858Z,1651873528.858 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1969 2022-05-06T21:45:28.880Z,1651873528.880 [DAT ThreadHandler](INFO): Handler Thread ID is 1970 2022-05-06T21:45:28.881Z,1651873528.881 [DAT](INFO): Powering up 2022-05-06T21:45:28.881Z,1651873528.881 [DAT](DEBUG): Initializing DAT. 2022-05-06T21:45:28.885Z,1651873528.885 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1972 2022-05-06T21:45:28.886Z,1651873528.886 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-05-06T21:45:28.889Z,1651873528.889 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1974 2022-05-06T21:45:28.892Z,1651873528.892 [WetLabsBB2FL](INFO): Powering up 2022-05-06T21:45:28.894Z,1651873528.894 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1976 2022-05-06T21:45:28.897Z,1651873528.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-05-06T21:45:28.897Z,1651873528.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-05-06T21:45:28.897Z,1651873528.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-05-06T21:45:28.897Z,1651873528.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-05-06T21:45:28.897Z,1651873528.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-05-06T21:45:28.898Z,1651873528.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-05-06T21:45:28.898Z,1651873528.898 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-05-06T21:45:28.898Z,1651873528.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-05-06T21:45:28.898Z,1651873528.898 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000 2022-05-06T21:45:28.898Z,1651873528.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000 2022-05-06T21:45:28.904Z,1651873528.904 [CBIT](DEBUG): Deactivating GF circuits. 2022-05-06T21:45:28.904Z,1651873528.904 [CBIT](DEBUG): Deactivating emergency mode. 2022-05-06T21:45:28.940Z,1651873528.940 [CBIT](DEBUG): Backplane powered. 2022-05-06T21:45:28.941Z,1651873528.941 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-05-06T21:45:28.953Z,1651873528.953 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-05-06T21:45:28.977Z,1651873528.977 [MissionManager](DEBUG): 2022-05-06T21:45:28.978Z,1651873528.978 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-05-06T21:45:29.050Z,1651873529.050 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-05-06T21:45:29.051Z,1651873529.051 [Default:A.Wait](DEBUG): Construct Wait. 2022-05-06T21:45:29.066Z,1651873529.066 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-05-06T21:45:29.107Z,1651873529.107 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-05-06T21:45:29.138Z,1651873529.138 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-05-06T21:45:29.165Z,1651873529.165 [Default:E.Execute](DEBUG): Construct Execute. 2022-05-06T21:45:29.177Z,1651873529.177 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2022-05-06T21:45:29.181Z,1651873529.181 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-05-06T21:45:29.216Z,1651873529.216 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-05-06T21:45:29.264Z,1651873529.264 [Radio_Surface](INFO): Powering up 2022-05-06T21:45:29.281Z,1651873529.281 [Power24vConverter](INFO): Powering up. 2022-05-06T21:45:29.334Z,1651873529.334 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-05-06T21:45:29.340Z,1651873529.340 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-05-06T21:45:29.341Z,1651873529.341 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-05-06T21:45:29.348Z,1651873529.348 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-05-06T21:45:29.349Z,1651873529.349 [MassServo](DEBUG): Initializing EZServoServo. 2022-05-06T21:45:29.356Z,1651873529.356 [MassServo](DEBUG): Initializing MassServo. 2022-05-06T21:45:29.357Z,1651873529.357 [RudderServo](DEBUG): Initializing EZServoServo. 2022-05-06T21:45:29.364Z,1651873529.364 [RudderServo](DEBUG): Initializing RudderServo. 2022-05-06T21:45:29.365Z,1651873529.365 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-05-06T21:45:29.376Z,1651873529.376 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-05-06T21:45:29.613Z,1651873529.613 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-05-06T21:45:29.613Z,1651873529.613 [DropWeight] Hardware Fault, FailCount= 1 2022-05-06T21:45:29.613Z,1651873529.613 [DropWeight](ERROR): Hardware Fault 2022-05-06T21:45:29.643Z,1651873529.643 [CommandExec](FAULT): Scheduling is paused 2022-05-06T21:45:29.652Z,1651873529.652 [CBIT](INFO): Critical error at 20220506T214529 2022-05-06T21:45:29.652Z,1651873529.652 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2022-05-06T21:45:29.655Z,1651873529.655 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-05-06T21:45:29.655Z,1651873529.655 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-05-06T21:45:30.320Z,1651873530.320 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-05-06T21:45:30.320Z,1651873530.320 [RudderServo](FAULT): Rudder failed to initialize 2022-05-06T21:45:30.320Z,1651873530.320 [RudderServo] Communications Fault, FailCount= 1 2022-05-06T21:45:30.320Z,1651873530.320 [RudderServo](ERROR): Communications Fault 2022-05-06T21:45:30.322Z,1651873530.322 [CBIT](INFO): Critical error at 20220506T214529 2022-05-06T21:45:30.325Z,1651873530.325 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-05-06T21:45:30.570Z,1651873530.570 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-05-06T21:45:30.571Z,1651873530.571 [RudderServo](INFO): Powering down 2022-05-06T21:45:31.228Z,1651873531.228 [RudderServo](DEBUG): Initializing EZServoServo. 2022-05-06T21:45:31.353Z,1651873531.353 [RudderServo](DEBUG): Initializing RudderServo. 2022-05-06T21:45:31.357Z,1651873531.357 [CBIT](INFO): Clearing failed state for component RudderServo 2022-05-06T21:45:31.357Z,1651873531.357 [RudderServo] No Fault, FailCount= 1 2022-05-06T21:45:31.504Z,1651873531.504 [WetLabsBB2FL](INFO): Powering down 2022-05-06T21:45:34.985Z,1651873534.985 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-05-06T21:45:38.965Z,1651873538.965 [CBIT](CRITICAL): Environmental Failure. Press:14.731812 PSI. Humidity:47%. Temp:24 C. ABORTING MISSION 2022-05-06T21:45:39.363Z,1651873539.363 [CBIT](INFO): Critical error at 20220506T214538 2022-05-06T21:45:41.742Z,1651873541.742 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2022-05-06T21:45:41.743Z,1651873541.743 [DAT](INFO): commRate: 800 2022-05-06T21:45:43.256Z,1651873543.256 [DAT](INFO): Received command: PbubF 2022-05-06T21:45:43.337Z,1651873543.337 [CommandExec](IMPORTANT): got command burn 2022-05-06T21:45:43.337Z,1651873543.337 [CommandExec](FAULT): Incomplete syntax. Try: help burn 2022-05-06T21:45:43.808Z,1651873543.808 [DAT](INFO): entering command mode 2022-05-06T21:45:44.009Z,1651873544.009 [DAT](INFO): setting verbose to 3 2022-05-06T21:45:44.262Z,1651873544.262 [DAT](INFO): set verbose to 3 2022-05-06T21:45:44.262Z,1651873544.262 [DAT](INFO): setting DatVerbose to 27440 2022-05-06T21:45:44.514Z,1651873544.514 [DAT](INFO): set DatVerbose to 27440 2022-05-06T21:45:44.514Z,1651873544.514 [DAT](INFO): setting transmit power to 8 2022-05-06T21:45:44.766Z,1651873544.766 [DAT](INFO): set transmit power to 8 2022-05-06T21:45:44.766Z,1651873544.766 [DAT](INFO): setting local address to 8 2022-05-06T21:45:45.018Z,1651873545.018 [DAT](INFO): set local address to 8 2022-05-06T21:45:45.019Z,1651873545.019 [DAT](INFO): Setting time to: 21:45:45 And date to:5/6/2022 2022-05-06T21:45:45.269Z,1651873545.269 [DAT](INFO): Local DAT time set to Fri May 6, 2022 21:45:45 2022-05-06T21:45:49.453Z,1651873549.453 [SBIT](IMPORTANT): Beginning Startup BIT 2022-05-06T21:45:49.462Z,1651873549.462 [CBIT](IMPORTANT): Beginning ground fault scan 2022-05-06T21:45:57.076Z,1651873557.076 [NAL9602](INFO): Powering up NAL9602 2022-05-06T21:46:00.351Z,1651873560.351 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004352 CHAN A1 (24V): -0.002468 CHAN A2 (12V): -0.000146 CHAN A3 (5V): 0.000475 CHAN B0 (3.3V): 0.000799 CHAN B1 (3.15aV): 0.000232 CHAN B2 (3.15bV): 0.000325 CHAN B3 (GND): 0.001223 OPEN: -0.001318 Full Scale: +/- 1 mA 2022-05-06T21:46:02.788Z,1651873562.788 [SBIT](ERROR): Could not read massPosReader_. 2022-05-06T21:46:07.974Z,1651873567.974 [NAL9602](INFO): NAL9602 initialized 2022-05-06T21:46:29.438Z,1651873589.438 [SBIT](FAULT): Mass: EXPECTED:-0.012400 ACTUAL:-0.001067 2022-05-06T21:46:29.438Z,1651873589.438 [SBIT](FAULT): Control surface position failure. 2022-05-06T21:46:43.567Z,1651873603.567 [SBIT](CRITICAL): SBIT FAILED 2022-05-06T21:46:43.567Z,1651873603.567 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-05-06T21:46:43.568Z,1651873603.568 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2022-05-06T21:46:43.568Z,1651873603.568 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2022-05-06T21:46:43.568Z,1651873603.568 [SBIT](IMPORTANT): ESPComponent.processCompleteTimeout=120 minute; 2022-05-06T21:46:43.568Z,1651873603.568 [SBIT](IMPORTANT): Express linearApproximation CBIT.ampHoursUsed 1.000000 ampere_hour; 2022-05-06T21:46:43.568Z,1651873603.568 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.010000 practical_salinity_unit; 2022-05-06T21:46:43.568Z,1651873603.568 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2022-05-06T21:46:43.569Z,1651873603.569 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 0.200000 microgram_per_liter; 2022-05-06T21:46:43.569Z,1651873603.569 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=438.149052 cubic_centimeter; 2022-05-06T21:46:43.569Z,1651873603.569 [SBIT](IMPORTANT): VerticalControl.massDefault=0 centimeter; 2022-05-06T21:46:43.569Z,1651873603.569 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-62 millimeter; 2022-05-06T21:46:43.569Z,1651873603.569 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=32 millimeter; 2022-05-06T21:46:43.571Z,1651873603.571 [CBIT](INFO): Critical error at 20220506T214643 2022-05-06T21:46:43.966Z,1651873603.966 [MissionManager](IMPORTANT): Started mission Startup 2022-05-06T21:46:43.966Z,1651873603.966 [Startup] Running Loop=1 2022-05-06T21:46:43.966Z,1651873603.966 [Startup](DEBUG): Aggregate::initialize Startup 2022-05-06T21:46:43.967Z,1651873603.967 [Startup:A.GoToSurface] Running Loop=1 2022-05-06T21:46:43.967Z,1651873603.967 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-05-06T21:46:43.967Z,1651873603.967 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-05-06T21:46:43.968Z,1651873603.968 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-05-06T21:46:43.968Z,1651873603.968 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-05-06T21:46:43.968Z,1651873603.968 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-05-06T21:46:43.969Z,1651873603.969 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-05-06T21:46:43.970Z,1651873603.970 [Startup:StartupSatComms] Running Loop=1 2022-05-06T21:46:43.970Z,1651873603.970 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-05-06T21:46:43.970Z,1651873603.970 [Startup:StartupSatComms:A] Running Loop=1 2022-05-06T21:46:44.354Z,1651873604.354 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-05-06T21:47:03.692Z,1651873623.692 [CommandExec](IMPORTANT): got command get VerticalControl.buoyancyLimitLoCC 2022-05-06T21:47:03.701Z,1651873623.701 [CommandExec](IMPORTANT): VerticalControl.buoyancyLimitLoCC 50.000000 cc 2022-05-06T21:47:12.504Z,1651873632.504 [CommandExec](IMPORTANT): got command get VerticalControl.buoyancyLimitHiCC 2022-05-06T21:47:12.504Z,1651873632.504 [CommandExec](IMPORTANT): VerticalControl.buoyancyLimitHiCC 955.000000 cc 2022-05-06T21:47:35.239Z,1651873655.239 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position 2022-05-06T21:47:35.284Z,1651873655.284 [Reporter](INFO): platform_buoyancy_position 947.459229 cc 2022-05-06T21:47:44.143Z,1651873664.143 [Startup:StartupSatComms:A](INFO): Timed out from 2022-05-06T21:46:43.0Z 2022-05-06T21:47:44.143Z,1651873664.143 [Startup:StartupSatComms:A] Stopped 2022-05-06T21:47:44.144Z,1651873664.144 [Startup:StartupSatComms:B] Running Loop=1 2022-05-06T21:47:44.554Z,1651873664.554 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-05-06T21:47:47.789Z,1651873667.789 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -62 millimeter 2022-05-06T21:47:47.796Z,1651873667.796 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2022-05-06T21:47:47.802Z,1651873667.802 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-05-06T21:47:56.875Z,1651873676.875 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004923 2022-05-06T21:48:04.278Z,1651873684.278 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220506T211631/Courier0010.lzma 2022-05-06T21:48:05.278Z,1651873685.278 [DataOverHttps](INFO): Moved sent file to Logs/20220506T211631/Courier0010.lzma.bak 2022-05-06T21:48:05.278Z,1651873685.278 [DataOverHttps](INFO): SBD MOMSN=16726761 2022-05-06T21:48:22.640Z,1651873702.640 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20220506T214519/Courier0000.lzma 2022-05-06T21:48:23.642Z,1651873703.642 [DataOverHttps](INFO): Moved sent file to Logs/20220506T214519/Courier0000.lzma.bak 2022-05-06T21:48:23.642Z,1651873703.642 [DataOverHttps](INFO): SBD MOMSN=16726763 2022-05-06T21:48:27.294Z,1651873707.294 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 60 cubic_centimeter 2022-05-06T21:48:27.298Z,1651873707.298 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2022-05-06T21:48:27.479Z,1651873707.479 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-05-06T21:48:29.015Z,1651873709.015 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-05-06T21:48:29.015Z,1651873709.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-06T21:48:29.092Z,1651873709.092 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-06T21:48:29.539Z,1651873709.539 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-06T21:48:29.539Z,1651873709.539 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-05-06T21:48:29.541Z,1651873709.541 [Reporter](INFO): platform_buoyancy_position 960.217773 cc 2022-05-06T21:48:30.302Z,1651873710.302 [Reporter](INFO): platform_buoyancy_position 958.337524 cc 2022-05-06T21:48:30.718Z,1651873710.718 [Reporter](INFO): platform_buoyancy_position 948.936523 cc 2022-05-06T21:48:31.100Z,1651873711.100 [Reporter](INFO): platform_buoyancy_position 947.459229 cc 2022-05-06T21:48:31.511Z,1651873711.511 [Reporter](INFO): platform_buoyancy_position 943.161743 cc 2022-05-06T21:48:31.902Z,1651873711.902 [Reporter](INFO): platform_buoyancy_position 938.864136 cc 2022-05-06T21:48:32.290Z,1651873712.290 [Reporter](INFO): platform_buoyancy_position 934.432129 cc 2022-05-06T21:48:32.758Z,1651873712.758 [Reporter](INFO): platform_buoyancy_position 928.120117 cc 2022-05-06T21:48:33.128Z,1651873713.128 [Reporter](INFO): platform_buoyancy_position 923.822510 cc 2022-05-06T21:48:33.534Z,1651873713.534 [Reporter](INFO): platform_buoyancy_position 919.390625 cc 2022-05-06T21:48:33.934Z,1651873713.934 [Reporter](INFO): platform_buoyancy_position 913.078491 cc 2022-05-06T21:48:34.317Z,1651873714.317 [Reporter](INFO): platform_buoyancy_position 908.780884 cc 2022-05-06T21:48:34.734Z,1651873714.734 [Reporter](INFO): platform_buoyancy_position 902.200195 cc 2022-05-06T21:48:35.146Z,1651873715.146 [Reporter](INFO): platform_buoyancy_position 897.902588 cc 2022-05-06T21:48:35.570Z,1651873715.570 [Reporter](INFO): platform_buoyancy_position 892.799194 cc 2022-05-06T21:48:35.938Z,1651873715.938 [Reporter](INFO): platform_buoyancy_position 887.158569 cc 2022-05-06T21:48:36.354Z,1651873716.354 [Reporter](INFO): platform_buoyancy_position 882.860962 cc 2022-05-06T21:48:36.767Z,1651873716.767 [Reporter](INFO): platform_buoyancy_position 876.414551 cc 2022-05-06T21:48:37.161Z,1651873717.161 [MassServo](FAULT): Reading outside of valid range:-403378.000000 2022-05-06T21:48:37.166Z,1651873717.166 [Reporter](INFO): platform_buoyancy_position 871.982666 cc 2022-05-06T21:48:37.634Z,1651873717.634 [Reporter](INFO): platform_buoyancy_position 865.804932 cc 2022-05-06T21:48:38.042Z,1651873718.042 [Reporter](INFO): platform_buoyancy_position 861.372925 cc 2022-05-06T21:48:38.435Z,1651873718.435 [Reporter](INFO): platform_buoyancy_position 855.060913 cc 2022-05-06T21:48:38.830Z,1651873718.830 [Reporter](INFO): platform_buoyancy_position 850.629028 cc 2022-05-06T21:48:39.144Z,1651873719.144 [DataOverHttps](INFO): Sending 367 bytes from file Logs/20220506T211631/Express0011.lzma 2022-05-06T21:48:39.238Z,1651873719.238 [Reporter](INFO): platform_buoyancy_position 844.316895 cc 2022-05-06T21:48:39.618Z,1651873719.618 [Reporter](INFO): platform_buoyancy_position 840.019287 cc 2022-05-06T21:48:40.008Z,1651873720.008 [Reporter](INFO): platform_buoyancy_position 834.647217 cc 2022-05-06T21:48:40.142Z,1651873720.142 [DataOverHttps](INFO): Moved sent file to Logs/20220506T211631/Express0011.lzma.bak 2022-05-06T21:48:40.142Z,1651873720.142 [DataOverHttps](INFO): SBD MOMSN=16726777 2022-05-06T21:48:40.430Z,1651873720.430 [Reporter](INFO): platform_buoyancy_position 829.140991 cc 2022-05-06T21:48:40.806Z,1651873720.806 [Reporter](INFO): platform_buoyancy_position 824.306152 cc 2022-05-06T21:48:41.239Z,1651873721.239 [Reporter](INFO): platform_buoyancy_position 818.531250 cc 2022-05-06T21:48:41.638Z,1651873721.638 [Reporter](INFO): platform_buoyancy_position 813.965088 cc 2022-05-06T21:48:42.050Z,1651873722.050 [Reporter](INFO): platform_buoyancy_position 807.787292 cc 2022-05-06T21:48:42.470Z,1651873722.470 [Reporter](INFO): platform_buoyancy_position 801.878052 cc 2022-05-06T21:48:42.842Z,1651873722.842 [Reporter](INFO): platform_buoyancy_position 796.908997 cc 2022-05-06T21:48:43.250Z,1651873723.250 [Reporter](INFO): platform_buoyancy_position 792.477051 cc 2022-05-06T21:48:43.656Z,1651873723.656 [Reporter](INFO): platform_buoyancy_position 786.299255 cc 2022-05-06T21:48:44.078Z,1651873724.078 [Reporter](INFO): platform_buoyancy_position 781.867371 cc 2022-05-06T21:48:44.370Z,1651873724.370 [Startup:StartupSatComms:B](INFO): Timed out from 2022-05-06T21:47:44.1Z 2022-05-06T21:48:44.370Z,1651873724.370 [Startup:StartupSatComms:B] Stopped 2022-05-06T21:48:44.370Z,1651873724.370 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-05-06T21:48:44.370Z,1651873724.370 [Startup:StartupSatComms] Stopped 2022-05-06T21:48:44.370Z,1651873724.370 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-05-06T21:48:44.371Z,1651873724.371 [Startup](INFO): Completed Startup 2022-05-06T21:48:44.371Z,1651873724.371 [MissionManager](INFO): Startup is completed. 2022-05-06T21:48:44.371Z,1651873724.371 [MissionManager](INFO): Uninitializing Mission Startup 2022-05-06T21:48:44.371Z,1651873724.371 [Startup] Stopped 2022-05-06T21:48:44.372Z,1651873724.372 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-05-06T21:48:44.372Z,1651873724.372 [Startup:A.GoToSurface] Stopped 2022-05-06T21:48:44.372Z,1651873724.372 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-05-06T21:48:44.470Z,1651873724.470 [Reporter](INFO): platform_buoyancy_position 775.555298 cc 2022-05-06T21:48:44.764Z,1651873724.764 [MissionManager](IMPORTANT): Started mission Default 2022-05-06T21:48:44.765Z,1651873724.765 [Default] Running Loop=1 2022-05-06T21:48:44.765Z,1651873724.765 [Default](DEBUG): Aggregate::initialize Default 2022-05-06T21:48:44.765Z,1651873724.765 [Default:B.GoToSurface] Running Loop=1 2022-05-06T21:48:44.765Z,1651873724.765 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-05-06T21:48:44.765Z,1651873724.765 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-05-06T21:48:44.766Z,1651873724.766 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-05-06T21:48:44.766Z,1651873724.766 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-05-06T21:48:44.766Z,1651873724.766 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-05-06T21:48:44.766Z,1651873724.766 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-05-06T21:48:44.767Z,1651873724.767 [Default:A.Wait] Running Loop=1 2022-05-06T21:48:44.767Z,1651873724.767 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-05-06T21:48:44.867Z,1651873724.867 [Reporter](INFO): platform_buoyancy_position 771.123352 cc 2022-05-06T21:48:45.294Z,1651873725.294 [Reporter](INFO): platform_buoyancy_position 765.885681 cc 2022-05-06T21:48:45.666Z,1651873725.666 [Reporter](INFO): platform_buoyancy_position 760.513672 cc 2022-05-06T21:48:46.070Z,1651873726.070 [Reporter](INFO): platform_buoyancy_position 756.081787 cc 2022-05-06T21:48:46.502Z,1651873726.502 [Reporter](INFO): platform_buoyancy_position 749.769653 cc 2022-05-06T21:48:46.914Z,1651873726.914 [Reporter](INFO): platform_buoyancy_position 745.203491 cc 2022-05-06T21:48:47.287Z,1651873727.287 [Reporter](INFO): platform_buoyancy_position 738.891357 cc 2022-05-06T21:48:47.735Z,1651873727.735 [Reporter](INFO): platform_buoyancy_position 732.444946 cc 2022-05-06T21:48:48.122Z,1651873728.122 [Reporter](INFO): platform_buoyancy_position 728.281677 cc 2022-05-06T21:48:48.506Z,1651873728.506 [Reporter](INFO): platform_buoyancy_position 723.849792 cc 2022-05-06T21:48:48.922Z,1651873728.922 [Reporter](INFO): platform_buoyancy_position 717.403381 cc 2022-05-06T21:48:49.306Z,1651873729.306 [Reporter](INFO): platform_buoyancy_position 713.105774 cc 2022-05-06T21:48:49.722Z,1651873729.722 [Reporter](INFO): platform_buoyancy_position 706.659363 cc 2022-05-06T21:48:50.098Z,1651873730.098 [Reporter](INFO): platform_buoyancy_position 702.361755 cc 2022-05-06T21:48:50.506Z,1651873730.506 [Reporter](INFO): platform_buoyancy_position 696.049683 cc 2022-05-06T21:48:50.908Z,1651873730.908 [Reporter](INFO): platform_buoyancy_position 691.617737 cc 2022-05-06T21:48:51.318Z,1651873731.318 [Reporter](INFO): platform_buoyancy_position 685.305664 cc 2022-05-06T21:48:51.718Z,1651873731.718 [Reporter](INFO): platform_buoyancy_position 681.008057 cc 2022-05-06T21:48:52.130Z,1651873732.130 [Reporter](INFO): platform_buoyancy_position 675.636047 cc 2022-05-06T21:48:52.523Z,1651873732.523 [Reporter](INFO): platform_buoyancy_position 670.129761 cc 2022-05-06T21:48:52.940Z,1651873732.940 [Reporter](INFO): platform_buoyancy_position 663.951965 cc 2022-05-06T21:48:53.387Z,1651873733.387 [Reporter](INFO): platform_buoyancy_position 659.385742 cc 2022-05-06T21:48:53.805Z,1651873733.805 [Reporter](INFO): platform_buoyancy_position 653.073669 cc 2022-05-06T21:48:54.210Z,1651873734.210 [Reporter](INFO): platform_buoyancy_position 648.776062 cc 2022-05-06T21:48:54.574Z,1651873734.574 [Reporter](INFO): platform_buoyancy_position 644.344177 cc 2022-05-06T21:48:55.005Z,1651873735.005 [Reporter](INFO): platform_buoyancy_position 637.897766 cc 2022-05-06T21:48:55.403Z,1651873735.403 [Reporter](INFO): platform_buoyancy_position 633.600159 cc 2022-05-06T21:48:55.837Z,1651873735.837 [Reporter](INFO): platform_buoyancy_position 627.153748 cc 2022-05-06T21:48:56.195Z,1651873736.195 [Reporter](INFO): platform_buoyancy_position 622.856140 cc 2022-05-06T21:48:56.664Z,1651873736.664 [Reporter](INFO): platform_buoyancy_position 616.409790 cc 2022-05-06T21:48:57.038Z,1651873737.038 [Reporter](INFO): platform_buoyancy_position 611.574951 cc 2022-05-06T21:48:57.518Z,1651873737.518 [Reporter](INFO): platform_buoyancy_position 605.665771 cc 2022-05-06T21:48:57.832Z,1651873737.832 [Reporter](INFO): platform_buoyancy_position 601.502441 cc 2022-05-06T21:48:58.121Z,1651873738.121 [Default:A.Wait](INFO): Done Waiting. 2022-05-06T21:48:58.121Z,1651873738.121 [Default:A.Wait] Stopped 2022-05-06T21:48:58.121Z,1651873738.121 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-05-06T21:48:58.227Z,1651873738.227 [Reporter](INFO): platform_buoyancy_position 594.921753 cc 2022-05-06T21:48:58.501Z,1651873738.501 [Default:CheckIn] Running Loop=1 2022-05-06T21:48:58.501Z,1651873738.501 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-05-06T21:48:58.502Z,1651873738.502 [Default:CheckIn:Read_GPS] Running Loop=1 2022-05-06T21:48:58.606Z,1651873738.606 [Reporter](INFO): platform_buoyancy_position 590.624146 cc 2022-05-06T21:48:58.910Z,1651873738.910 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-05-06T21:48:59.027Z,1651873739.027 [Reporter](INFO): platform_buoyancy_position 584.580627 cc 2022-05-06T21:48:59.430Z,1651873739.430 [Reporter](INFO): platform_buoyancy_position 580.014465 cc 2022-05-06T21:48:59.834Z,1651873739.834 [Reporter](INFO): platform_buoyancy_position 575.179626 cc 2022-05-06T21:49:00.210Z,1651873740.210 [Reporter](INFO): platform_buoyancy_position 569.270447 cc 2022-05-06T21:49:00.634Z,1651873740.634 [Reporter](INFO): platform_buoyancy_position 564.569946 cc 2022-05-06T21:49:01.046Z,1651873741.046 [Reporter](INFO): platform_buoyancy_position 558.392151 cc 2022-05-06T21:49:01.122Z,1651873741.122 [DataOverHttps](INFO): Sending 1194 bytes from file Logs/20220506T214519/Express0001.lzma 2022-05-06T21:49:01.443Z,1651873741.443 [Reporter](INFO): platform_buoyancy_position 554.228882 cc 2022-05-06T21:49:01.677Z,1651873741.677 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -62 millimeter 2022-05-06T21:49:01.822Z,1651873741.822 [Reporter](INFO): platform_buoyancy_position 548.453979 cc 2022-05-06T21:49:02.122Z,1651873742.122 [DataOverHttps](INFO): Moved sent file to Logs/20220506T214519/Express0001.lzma.bak 2022-05-06T21:49:02.122Z,1651873742.122 [DataOverHttps](INFO): SBD MOMSN=16726798 2022-05-06T21:49:02.246Z,1651873742.246 [Reporter](INFO): platform_buoyancy_position 543.350525 cc 2022-05-06T21:49:02.615Z,1651873742.615 [Reporter](INFO): platform_buoyancy_position 539.052979 cc 2022-05-06T21:49:03.034Z,1651873743.034 [Reporter](INFO): platform_buoyancy_position 532.606567 cc 2022-05-06T21:49:03.446Z,1651873743.446 [Reporter](INFO): platform_buoyancy_position 528.308960 cc 2022-05-06T21:49:03.826Z,1651873743.826 [Reporter](INFO): platform_buoyancy_position 521.996826 cc 2022-05-06T21:49:04.272Z,1651873744.272 [Reporter](INFO): platform_buoyancy_position 515.550476 cc 2022-05-06T21:49:04.714Z,1651873744.714 [Reporter](INFO): platform_buoyancy_position 511.118500 cc 2022-05-06T21:49:05.094Z,1651873745.094 [Reporter](INFO): platform_buoyancy_position 506.820923 cc 2022-05-06T21:49:05.474Z,1651873745.474 [Reporter](INFO): platform_buoyancy_position 500.508881 cc 2022-05-06T21:49:05.874Z,1651873745.874 [Reporter](INFO): platform_buoyancy_position 496.076935 cc 2022-05-06T21:49:06.263Z,1651873746.263 [Reporter](INFO): platform_buoyancy_position 490.033478 cc 2022-05-06T21:49:06.671Z,1651873746.671 [Reporter](INFO): platform_buoyancy_position 485.467224 cc 2022-05-06T21:49:07.096Z,1651873747.096 [Reporter](INFO): platform_buoyancy_position 479.020874 cc 2022-05-06T21:49:07.479Z,1651873747.479 [Reporter](INFO): platform_buoyancy_position 474.723267 cc 2022-05-06T21:49:07.880Z,1651873747.880 [Reporter](INFO): platform_buoyancy_position 469.485535 cc 2022-05-06T21:49:08.302Z,1651873748.302 [Reporter](INFO): platform_buoyancy_position 463.979248 cc 2022-05-06T21:49:08.678Z,1651873748.678 [Reporter](INFO): platform_buoyancy_position 459.547363 cc 2022-05-06T21:49:09.142Z,1651873749.142 [Reporter](INFO): platform_buoyancy_position 453.100952 cc 2022-05-06T21:49:09.428Z,1651873749.428 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter 2022-05-06T21:49:09.429Z,1651873749.429 [CommandExec](IMPORTANT): platform_mass_position -3.699964 cm 2022-05-06T21:49:09.534Z,1651873749.534 [Reporter](INFO): platform_buoyancy_position 446.788818 cc 2022-05-06T21:49:09.910Z,1651873749.910 [Reporter](INFO): platform_buoyancy_position 442.356934 cc 2022-05-06T21:49:10.318Z,1651873750.318 [Reporter](INFO): platform_buoyancy_position 438.193665 cc 2022-05-06T21:49:10.750Z,1651873750.750 [Reporter](INFO): platform_buoyancy_position 431.612976 cc 2022-05-06T21:49:11.107Z,1651873751.107 [Reporter](INFO): platform_buoyancy_position 427.315369 cc 2022-05-06T21:49:11.510Z,1651873751.510 [Reporter](INFO): platform_buoyancy_position 421.003235 cc 2022-05-06T21:49:11.902Z,1651873751.902 [Reporter](INFO): platform_buoyancy_position 416.705627 cc 2022-05-06T21:49:12.326Z,1651873752.326 [Reporter](INFO): platform_buoyancy_position 410.124939 cc 2022-05-06T21:49:12.719Z,1651873752.719 [Reporter](INFO): platform_buoyancy_position 405.827332 cc 2022-05-06T21:49:13.139Z,1651873753.139 [Reporter](INFO): platform_buoyancy_position 399.515259 cc 2022-05-06T21:49:13.542Z,1651873753.542 [Reporter](INFO): platform_buoyancy_position 395.083374 cc 2022-05-06T21:49:13.939Z,1651873753.939 [Reporter](INFO): platform_buoyancy_position 390.785767 cc 2022-05-06T21:49:14.336Z,1651873754.336 [Reporter](INFO): platform_buoyancy_position 384.473633 cc 2022-05-06T21:49:14.790Z,1651873754.790 [Reporter](INFO): platform_buoyancy_position 378.027222 cc 2022-05-06T21:49:15.173Z,1651873755.173 [Reporter](INFO): platform_buoyancy_position 373.595337 cc 2022-05-06T21:49:15.575Z,1651873755.575 [Reporter](INFO): platform_buoyancy_position 367.283264 cc 2022-05-06T21:49:16.000Z,1651873756.000 [Reporter](INFO): platform_buoyancy_position 362.851318 cc 2022-05-06T21:49:16.369Z,1651873756.369 [Reporter](INFO): platform_buoyancy_position 358.419434 cc 2022-05-06T21:49:16.767Z,1651873756.767 [Reporter](INFO): platform_buoyancy_position 352.241638 cc 2022-05-06T21:49:17.167Z,1651873757.167 [Reporter](INFO): platform_buoyancy_position 347.944031 cc 2022-05-06T21:49:17.591Z,1651873757.591 [Reporter](INFO): platform_buoyancy_position 343.512146 cc 2022-05-06T21:49:17.980Z,1651873757.980 [Reporter](INFO): platform_buoyancy_position 337.200012 cc 2022-05-06T21:49:18.403Z,1651873758.403 [Reporter](INFO): platform_buoyancy_position 330.753662 cc 2022-05-06T21:49:18.774Z,1651873758.774 [Reporter](INFO): platform_buoyancy_position 326.456055 cc 2022-05-06T21:49:19.202Z,1651873759.202 [Reporter](INFO): platform_buoyancy_position 322.024109 cc 2022-05-06T21:49:19.618Z,1651873759.618 [Reporter](INFO): platform_buoyancy_position 315.577759 cc 2022-05-06T21:49:20.038Z,1651873760.038 [Reporter](INFO): platform_buoyancy_position 309.265625 cc 2022-05-06T21:49:20.428Z,1651873760.428 [Reporter](INFO): platform_buoyancy_position 304.833740 cc 2022-05-06T21:49:20.844Z,1651873760.844 [Reporter](INFO): platform_buoyancy_position 298.521637 cc 2022-05-06T21:49:21.244Z,1651873761.244 [Reporter](INFO): platform_buoyancy_position 294.089722 cc 2022-05-06T21:49:21.619Z,1651873761.619 [Reporter](INFO): platform_buoyancy_position 288.583435 cc 2022-05-06T21:49:22.014Z,1651873762.014 [Reporter](INFO): platform_buoyancy_position 283.345734 cc 2022-05-06T21:49:22.422Z,1651873762.422 [Reporter](INFO): platform_buoyancy_position 278.913849 cc 2022-05-06T21:49:22.842Z,1651873762.842 [Reporter](INFO): platform_buoyancy_position 272.601746 cc 2022-05-06T21:49:23.258Z,1651873763.258 [Reporter](INFO): platform_buoyancy_position 266.826843 cc 2022-05-06T21:49:23.373Z,1651873763.373 [CommandExec](IMPORTANT): got command get platform_mass_position millimeter 2022-05-06T21:49:23.374Z,1651873763.374 [CommandExec](IMPORTANT): platform_mass_position -36.999634 mm 2022-05-06T21:49:23.666Z,1651873763.666 [Reporter](INFO): platform_buoyancy_position 261.992035 cc 2022-05-06T21:49:24.054Z,1651873764.054 [Reporter](INFO): platform_buoyancy_position 257.425842 cc 2022-05-06T21:49:24.446Z,1651873764.446 [Reporter](INFO): platform_buoyancy_position 251.382324 cc 2022-05-06T21:49:24.847Z,1651873764.847 [Reporter](INFO): platform_buoyancy_position 246.681824 cc 2022-05-06T21:49:25.290Z,1651873765.290 [Reporter](INFO): platform_buoyancy_position 240.369720 cc 2022-05-06T21:49:25.679Z,1651873765.679 [Reporter](INFO): platform_buoyancy_position 236.072144 cc 2022-05-06T21:49:26.102Z,1651873766.102 [Reporter](INFO): platform_buoyancy_position 229.760040 cc 2022-05-06T21:49:26.482Z,1651873766.482 [Reporter](INFO): platform_buoyancy_position 225.328125 cc 2022-05-06T21:49:26.876Z,1651873766.876 [Reporter](INFO): platform_buoyancy_position 219.016022 cc 2022-05-06T21:49:27.262Z,1651873767.262 [Reporter](INFO): platform_buoyancy_position 214.584137 cc 2022-05-06T21:49:27.687Z,1651873767.687 [Reporter](INFO): platform_buoyancy_position 210.286530 cc 2022-05-06T21:49:28.078Z,1651873768.078 [Reporter](INFO): platform_buoyancy_position 203.974426 cc 2022-05-06T21:49:28.502Z,1651873768.502 [Reporter](INFO): platform_buoyancy_position 197.528015 cc 2022-05-06T21:49:28.918Z,1651873768.918 [Reporter](INFO): platform_buoyancy_position 193.096130 cc 2022-05-06T21:49:29.311Z,1651873769.311 [Reporter](INFO): platform_buoyancy_position 188.529938 cc 2022-05-06T21:49:29.722Z,1651873769.722 [Reporter](INFO): platform_buoyancy_position 182.486420 cc 2022-05-06T21:49:30.130Z,1651873770.130 [Reporter](INFO): platform_buoyancy_position 178.054535 cc 2022-05-06T21:49:30.546Z,1651873770.546 [Reporter](INFO): platform_buoyancy_position 171.608124 cc 2022-05-06T21:49:30.922Z,1651873770.922 [Reporter](INFO): platform_buoyancy_position 167.310516 cc 2022-05-06T21:49:31.378Z,1651873771.378 [Reporter](INFO): platform_buoyancy_position 160.998413 cc 2022-05-06T21:49:31.742Z,1651873771.742 [Reporter](INFO): platform_buoyancy_position 156.029327 cc 2022-05-06T21:49:32.127Z,1651873772.127 [Reporter](INFO): platform_buoyancy_position 150.388733 cc 2022-05-06T21:49:32.536Z,1651873772.536 [Reporter](INFO): platform_buoyancy_position 145.822540 cc 2022-05-06T21:49:32.950Z,1651873772.950 [Reporter](INFO): platform_buoyancy_position 141.390625 cc 2022-05-06T21:49:33.338Z,1651873773.338 [Reporter](INFO): platform_buoyancy_position 135.078522 cc 2022-05-06T21:49:33.739Z,1651873773.739 [Reporter](INFO): platform_buoyancy_position 130.512329 cc 2022-05-06T21:49:34.176Z,1651873774.176 [Reporter](INFO): platform_buoyancy_position 124.468819 cc 2022-05-06T21:49:34.539Z,1651873774.539 [Reporter](INFO): platform_buoyancy_position 120.036926 cc 2022-05-06T21:49:34.946Z,1651873774.946 [Reporter](INFO): platform_buoyancy_position 113.724823 cc 2022-05-06T21:49:35.351Z,1651873775.351 [Reporter](INFO): platform_buoyancy_position 109.427216 cc 2022-05-06T21:49:35.450Z,1651873775.450 [CommandExec](IMPORTANT): got command show variable MassServo 2022-05-06T21:49:35.499Z,1651873775.499 [CommandExec](IMPORTANT): MassServo.loadAtStartup (bool) 2022-05-06T21:49:35.499Z,1651873775.499 [CommandExec](IMPORTANT): MassServo.simulateHardware (bool) 2022-05-06T21:49:35.501Z,1651873775.501 [CommandExec](IMPORTANT): MassServo.accel (none) 2022-05-06T21:49:35.501Z,1651873775.501 [CommandExec](IMPORTANT): MassServo.currLimit (percent) 2022-05-06T21:49:35.502Z,1651873775.502 [CommandExec](IMPORTANT): MassServo.limitHi (count) 2022-05-06T21:49:35.502Z,1651873775.502 [CommandExec](IMPORTANT): MassServo.limitLo (count) 2022-05-06T21:49:35.502Z,1651873775.502 [CommandExec](IMPORTANT): MassServo.overloadTimeout (millisecond) 2022-05-06T21:49:35.503Z,1651873775.503 [CommandExec](IMPORTANT): MassServo.powerOnTimeout (second) 2022-05-06T21:49:35.503Z,1651873775.503 [CommandExec](IMPORTANT): MassServo.velocity (none) 2022-05-06T21:49:35.503Z,1651873775.503 [CommandExec](IMPORTANT): MassServo.deviationDistance (millimeter) 2022-05-06T21:49:35.504Z,1651873775.504 [CommandExec](IMPORTANT): MassServo.tksPerMM (count_per_millimeter) 2022-05-06T21:49:35.505Z,1651873775.505 [CommandExec](IMPORTANT): MassServo.totalTks (count) 2022-05-06T21:49:35.515Z,1651873775.515 [CommandExec](IMPORTANT): MassServo.loadControl (none) 2022-05-06T21:49:35.515Z,1651873775.515 [CommandExec](IMPORTANT): MassServo.uart (none) 2022-05-06T21:49:35.516Z,1651873775.516 [CommandExec](IMPORTANT): MassServo.baud (bit_per_second) 2022-05-06T21:49:35.589Z,1651873775.589 [CommandExec](IMPORTANT): MassServo.enableBroadcast (bool) 2022-05-06T21:49:35.590Z,1651873775.590 [CommandExec](IMPORTANT): MassServo.component_voltage (volt) 2022-05-06T21:49:35.590Z,1651873775.590 [CommandExec](IMPORTANT): MassServo.component_avgVoltage (volt) 2022-05-06T21:49:35.591Z,1651873775.591 [CommandExec](IMPORTANT): MassServo.component_current (milliampere) 2022-05-06T21:49:35.591Z,1651873775.591 [CommandExec](IMPORTANT): MassServo.component_avgCurrent (milliampere) 2022-05-06T21:49:35.591Z,1651873775.591 [CommandExec](IMPORTANT): MassServo.platform_mass_position (meter) 2022-05-06T21:49:35.787Z,1651873775.787 [Reporter](INFO): platform_buoyancy_position 102.846527 cc 2022-05-06T21:49:36.195Z,1651873776.195 [Reporter](INFO): platform_buoyancy_position 98.548920 cc 2022-05-06T21:49:36.586Z,1651873776.586 [Reporter](INFO): platform_buoyancy_position 92.236816 cc 2022-05-06T21:49:36.978Z,1651873776.978 [Reporter](INFO): platform_buoyancy_position 87.804932 cc 2022-05-06T21:49:37.366Z,1651873777.366 [Reporter](INFO): platform_buoyancy_position 83.238708 cc 2022-05-06T21:49:37.782Z,1651873777.782 [Reporter](INFO): platform_buoyancy_position 77.195221 cc 2022-05-06T21:49:38.170Z,1651873778.170 [Reporter](INFO): platform_buoyancy_position 72.763306 cc 2022-05-06T21:49:38.610Z,1651873778.610 [Reporter](INFO): platform_buoyancy_position 68.465729 cc 2022-05-06T21:50:24.692Z,1651873824.692 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -50 millimeter 2022-05-06T21:50:32.289Z,1651873832.289 [CommandExec](IMPORTANT): got command failComponent 2022-05-06T21:50:32.289Z,1651873832.289 [CommandExec](IMPORTANT): Failed components: 2022-05-06T21:50:32.289Z,1651873832.289 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2022-05-06T21:50:55.818Z,1651873855.818 [CommandExec](IMPORTANT): got command show variable limitAft 2022-05-06T21:50:55.842Z,1651873855.842 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitAft (millimeter) 2022-05-06T21:51:04.331Z,1651873864.331 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitAft 2022-05-06T21:51:04.332Z,1651873864.332 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitAft -62.000000 mm 2022-05-06T21:51:11.394Z,1651873871.394 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-05-06T21:51:15.490Z,1651873875.490 [CommandExec](IMPORTANT): got command failComponent hardware MassServo 2022-05-06T21:51:15.491Z,1651873875.491 [MassServo] Hardware Fault, FailCount= 1 2022-05-06T21:51:15.491Z,1651873875.491 [MassServo](ERROR): Hardware Fault 2022-05-06T21:51:15.491Z,1651873875.491 [CommandExec](IMPORTANT): MassServo failureMode is Hardware Fault 2022-05-06T21:51:15.509Z,1651873875.509 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-05-06T21:51:15.509Z,1651873875.509 [MassServo](INFO): Powering down 2022-05-06T21:51:15.518Z,1651873875.518 [CBIT](ERROR): Hardware Fault in component: MassServo 2022-05-06T21:51:16.268Z,1651873876.268 [MassServo](DEBUG): Initializing EZServoServo. 2022-05-06T21:51:16.400Z,1651873876.400 [MassServo](DEBUG): Initializing MassServo. 2022-05-06T21:51:16.408Z,1651873876.408 [CBIT](INFO): Clearing failed state for component MassServo 2022-05-06T21:51:16.408Z,1651873876.408 [MassServo] No Fault, FailCount= 1 2022-05-06T21:51:30.020Z,1651873890.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2022-05-06T21:51:30.020Z,1651873890.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-06T21:51:30.076Z,1651873890.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-06T21:51:30.440Z,1651873890.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-06T21:51:30.440Z,1651873890.440 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2022-05-06T21:52:24.532Z,1651873944.532 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error 2022-05-06T21:52:24.533Z,1651873944.533 [DVL_micro](ERROR): Failed to parse: :TS,00000000000,+21.1,0000WI,+00317,-02909,+00902,+00000,A 2022-05-06T21:52:36.123Z,1651873956.123 [CommandExec](IMPORTANT): got command report mod platform_mass_position 2022-05-06T21:52:36.283Z,1651873956.283 [Reporter](INFO): platform_mass_position -0.016897 m 2022-05-06T21:52:36.698Z,1651873956.698 [Reporter](INFO): platform_mass_position -0.017215 m 2022-05-06T21:52:37.118Z,1651873957.118 [Reporter](INFO): platform_mass_position -0.017552 m 2022-05-06T21:52:37.535Z,1651873957.535 [Reporter](INFO): platform_mass_position -0.017867 m 2022-05-06T21:52:37.930Z,1651873957.930 [Reporter](INFO): platform_mass_position -0.018189 m 2022-05-06T21:52:38.330Z,1651873958.330 [Reporter](INFO): platform_mass_position -0.018498 m 2022-05-06T21:52:38.710Z,1651873958.710 [Reporter](INFO): platform_mass_position -0.018799 m 2022-05-06T21:52:39.123Z,1651873959.123 [Reporter](INFO): platform_mass_position -0.019124 m 2022-05-06T21:52:39.514Z,1651873959.514 [Reporter](INFO): platform_mass_position -0.019428 m 2022-05-06T21:52:39.930Z,1651873959.930 [Reporter](INFO): platform_mass_position -0.019753 m 2022-05-06T21:52:40.338Z,1651873960.338 [Reporter](INFO): platform_mass_position -0.020069 m 2022-05-06T21:52:40.754Z,1651873960.754 [Reporter](INFO): platform_mass_position -0.020394 m 2022-05-06T21:52:41.166Z,1651873961.166 [Reporter](INFO): platform_mass_position -0.020717 m 2022-05-06T21:52:41.577Z,1651873961.577 [Reporter](INFO): platform_mass_position -0.021043 m 2022-05-06T21:52:41.964Z,1651873961.964 [Reporter](INFO): platform_mass_position -0.021332 m 2022-05-06T21:52:42.266Z,1651873962.266 [CommandExec](IMPORTANT): got command maintain clear 2022-05-06T21:52:42.371Z,1651873962.371 [Reporter](INFO): platform_mass_position -0.021668 m 2022-05-06T21:52:42.376Z,1651873962.376 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-05-06T21:52:42.776Z,1651873962.776 [Reporter](INFO): platform_mass_position -0.021961 m 2022-05-06T21:52:43.182Z,1651873963.182 [Reporter](INFO): platform_mass_position -0.022002 m 2022-05-06T21:52:43.573Z,1651873963.573 [Reporter](INFO): platform_mass_position -0.021713 m 2022-05-06T21:52:43.986Z,1651873963.986 [Reporter](INFO): platform_mass_position -0.021396 m 2022-05-06T21:52:44.446Z,1651873964.446 [Reporter](INFO): platform_buoyancy_position 62.019318 cc 2022-05-06T21:52:44.447Z,1651873964.447 [Reporter](INFO): platform_mass_position -0.021028 m 2022-05-06T21:52:44.798Z,1651873964.798 [Reporter](INFO): platform_mass_position -0.020751 m 2022-05-06T21:52:45.210Z,1651873965.210 [Reporter](INFO): platform_buoyancy_position 64.302429 cc 2022-05-06T21:52:45.211Z,1651873965.211 [Reporter](INFO): platform_mass_position -0.020429 m 2022-05-06T21:52:45.610Z,1651873965.610 [Reporter](INFO): platform_buoyancy_position 67.794220 cc 2022-05-06T21:52:45.611Z,1651873965.611 [Reporter](INFO): platform_mass_position -0.020119 m 2022-05-06T21:52:46.000Z,1651873966.000 [Reporter](INFO): platform_buoyancy_position 72.763306 cc 2022-05-06T21:52:46.001Z,1651873966.001 [Reporter](INFO): platform_mass_position -0.019811 m 2022-05-06T21:52:46.438Z,1651873966.438 [Reporter](INFO): platform_buoyancy_position 77.195221 cc 2022-05-06T21:52:46.439Z,1651873966.439 [Reporter](INFO): platform_mass_position -0.019466 m 2022-05-06T21:52:46.832Z,1651873966.832 [Reporter](INFO): platform_buoyancy_position 81.358521 cc 2022-05-06T21:52:46.832Z,1651873966.832 [Reporter](INFO): platform_mass_position -0.019159 m 2022-05-06T21:52:47.280Z,1651873967.280 [Reporter](INFO): platform_buoyancy_position 87.939209 cc 2022-05-06T21:52:47.280Z,1651873967.280 [Reporter](INFO): platform_mass_position -0.018808 m 2022-05-06T21:52:47.634Z,1651873967.634 [Reporter](INFO): platform_buoyancy_position 92.102509 cc 2022-05-06T21:52:47.635Z,1651873967.635 [Reporter](INFO): platform_mass_position -0.018529 m 2022-05-06T21:52:48.031Z,1651873968.031 [Reporter](INFO): platform_buoyancy_position 98.548920 cc 2022-05-06T21:52:48.032Z,1651873968.032 [Reporter](INFO): platform_mass_position -0.018225 m 2022-05-06T21:52:48.442Z,1651873968.442 [Reporter](INFO): platform_buoyancy_position 102.846527 cc 2022-05-06T21:52:48.443Z,1651873968.443 [Reporter](INFO): platform_mass_position -0.017896 m 2022-05-06T21:52:48.838Z,1651873968.838 [Reporter](INFO): platform_buoyancy_position 109.427216 cc 2022-05-06T21:52:48.839Z,1651873968.839 [Reporter](INFO): platform_mass_position -0.017586 m 2022-05-06T21:52:49.147Z,1651873969.147 [CommandExec](IMPORTANT): got command failComponent hardware MassServo 2022-05-06T21:52:49.147Z,1651873969.147 [MassServo] Hardware Fault, FailCount= 2 2022-05-06T21:52:49.147Z,1651873969.147 [MassServo](ERROR): Hardware Fault 2022-05-06T21:52:49.147Z,1651873969.147 [CommandExec](IMPORTANT): MassServo failureMode is Hardware Fault 2022-05-06T21:52:49.214Z,1651873969.214 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-05-06T21:52:49.215Z,1651873969.215 [MassServo](INFO): Powering down 2022-05-06T21:52:49.236Z,1651873969.236 [CBIT](ERROR): Hardware Fault in component: MassServo 2022-05-06T21:52:49.242Z,1651873969.242 [Reporter](INFO): platform_buoyancy_position 113.724823 cc 2022-05-06T21:52:49.623Z,1651873969.623 [Reporter](INFO): platform_buoyancy_position 120.036926 cc 2022-05-06T21:52:50.038Z,1651873970.038 [MassServo](DEBUG): Initializing EZServoServo. 2022-05-06T21:52:50.157Z,1651873970.157 [MassServo](DEBUG): Initializing MassServo. 2022-05-06T21:52:50.161Z,1651873970.161 [CBIT](INFO): Clearing failed state for component MassServo 2022-05-06T21:52:50.161Z,1651873970.161 [MassServo] No Fault, FailCount= 2 2022-05-06T21:52:50.163Z,1651873970.163 [Reporter](INFO): platform_buoyancy_position 124.468819 cc 2022-05-06T21:52:50.450Z,1651873970.450 [Reporter](INFO): platform_buoyancy_position 130.512329 cc 2022-05-06T21:52:50.826Z,1651873970.826 [Reporter](INFO): platform_buoyancy_position 135.212830 cc 2022-05-06T21:52:51.254Z,1651873971.254 [Reporter](INFO): platform_buoyancy_position 139.376129 cc 2022-05-06T21:52:51.630Z,1651873971.630 [Reporter](INFO): platform_buoyancy_position 145.822540 cc 2022-05-06T21:52:52.046Z,1651873972.046 [Reporter](INFO): platform_buoyancy_position 150.523041 cc 2022-05-06T21:52:52.491Z,1651873972.491 [Reporter](INFO): platform_buoyancy_position 156.700836 cc 2022-05-06T21:52:52.906Z,1651873972.906 [Reporter](INFO): platform_buoyancy_position 160.998413 cc 2022-05-06T21:52:53.270Z,1651873973.270 [Reporter](INFO): platform_buoyancy_position 167.310516 cc 2022-05-06T21:52:53.690Z,1651873973.690 [Reporter](INFO): platform_buoyancy_position 172.011017 cc 2022-05-06T21:52:54.082Z,1651873974.082 [Reporter](INFO): platform_buoyancy_position 178.054535 cc 2022-05-06T21:52:54.499Z,1651873974.499 [Reporter](INFO): platform_buoyancy_position 182.486420 cc 2022-05-06T21:52:54.905Z,1651873974.905 [Reporter](INFO): platform_buoyancy_position 188.798523 cc 2022-05-06T21:52:55.309Z,1651873975.309 [Reporter](INFO): platform_buoyancy_position 193.230438 cc 2022-05-06T21:52:55.726Z,1651873975.726 [Reporter](INFO): platform_buoyancy_position 199.676819 cc 2022-05-06T21:52:56.138Z,1651873976.138 [Reporter](INFO): platform_buoyancy_position 204.377319 cc 2022-05-06T21:52:56.522Z,1651873976.522 [Reporter](INFO): platform_buoyancy_position 210.152222 cc 2022-05-06T21:52:56.921Z,1651873976.921 [Reporter](INFO): platform_buoyancy_position 214.584137 cc 2022-05-06T21:52:57.318Z,1651873977.318 [Reporter](INFO): platform_buoyancy_position 219.016022 cc 2022-05-06T21:52:57.730Z,1651873977.730 [Reporter](INFO): platform_buoyancy_position 225.328125 cc 2022-05-06T21:52:58.134Z,1651873978.134 [Reporter](INFO): platform_buoyancy_position 230.028625 cc 2022-05-06T21:52:58.538Z,1651873978.538 [Reporter](INFO): platform_buoyancy_position 236.206421 cc 2022-05-06T21:52:58.950Z,1651873978.950 [Reporter](INFO): platform_buoyancy_position 240.772644 cc 2022-05-06T21:52:59.362Z,1651873979.362 [Reporter](INFO): platform_buoyancy_position 246.816147 cc 2022-05-06T21:52:59.746Z,1651873979.746 [Reporter](INFO): platform_buoyancy_position 251.248047 cc 2022-05-06T21:53:00.158Z,1651873980.158 [Reporter](INFO): platform_buoyancy_position 257.560120 cc 2022-05-06T21:53:00.554Z,1651873980.554 [Reporter](INFO): platform_buoyancy_position 261.992035 cc 2022-05-06T21:53:00.958Z,1651873980.958 [Reporter](INFO): platform_buoyancy_position 268.438446 cc 2022-05-06T21:53:01.358Z,1651873981.358 [Reporter](INFO): platform_buoyancy_position 272.736023 cc 2022-05-06T21:53:01.783Z,1651873981.783 [Reporter](INFO): platform_buoyancy_position 279.182434 cc 2022-05-06T21:53:02.186Z,1651873982.186 [Reporter](INFO): platform_buoyancy_position 283.480042 cc 2022-05-06T21:53:02.610Z,1651873982.610 [Reporter](INFO): platform_buoyancy_position 289.792145 cc 2022-05-06T21:53:02.974Z,1651873982.974 [Reporter](INFO): platform_buoyancy_position 294.089722 cc 2022-05-06T21:53:03.390Z,1651873983.390 [Reporter](INFO): platform_buoyancy_position 300.267548 cc 2022-05-06T21:53:03.801Z,1651873983.801 [Reporter](INFO): platform_buoyancy_position 304.968048 cc 2022-05-06T21:53:04.198Z,1651873984.198 [Reporter](INFO): platform_buoyancy_position 310.340027 cc 2022-05-06T21:53:04.629Z,1651873984.629 [Reporter](INFO): platform_buoyancy_position 315.712036 cc 2022-05-06T21:53:05.038Z,1651873985.038 [Reporter](INFO): platform_buoyancy_position 320.143921 cc 2022-05-06T21:53:05.458Z,1651873985.458 [Reporter](INFO): platform_buoyancy_position 326.456055 cc 2022-05-06T21:53:05.825Z,1651873985.825 [Reporter](INFO): platform_buoyancy_position 330.753662 cc 2022-05-06T21:53:06.207Z,1651873986.207 [Reporter](INFO): platform_buoyancy_position 337.065735 cc 2022-05-06T21:53:06.622Z,1651873986.622 [Reporter](INFO): platform_buoyancy_position 341.363342 cc 2022-05-06T21:53:07.026Z,1651873987.026 [Reporter](INFO): platform_buoyancy_position 346.466736 cc 2022-05-06T21:53:07.442Z,1651873987.442 [Reporter](INFO): platform_buoyancy_position 352.107361 cc 2022-05-06T21:53:07.862Z,1651873987.862 [Reporter](INFO): platform_buoyancy_position 356.404968 cc 2022-05-06T21:53:08.259Z,1651873988.259 [Reporter](INFO): platform_buoyancy_position 362.851318 cc 2022-05-06T21:53:08.642Z,1651873988.642 [Reporter](INFO): platform_buoyancy_position 367.148926 cc 2022-05-06T21:53:09.037Z,1651873989.037 [Reporter](INFO): platform_buoyancy_position 373.595337 cc 2022-05-06T21:53:09.450Z,1651873989.450 [Reporter](INFO): platform_buoyancy_position 378.564453 cc 2022-05-06T21:53:09.842Z,1651873989.842 [Reporter](INFO): platform_buoyancy_position 384.339355 cc 2022-05-06T21:53:10.262Z,1651873990.262 [Reporter](INFO): platform_buoyancy_position 388.771240 cc 2022-05-06T21:53:10.666Z,1651873990.666 [Reporter](INFO): platform_buoyancy_position 395.083374 cc 2022-05-06T21:53:11.081Z,1651873991.081 [Reporter](INFO): platform_buoyancy_position 399.918152 cc 2022-05-06T21:53:11.486Z,1651873991.486 [Reporter](INFO): platform_buoyancy_position 405.827332 cc 2022-05-06T21:53:11.861Z,1651873991.861 [Reporter](INFO): platform_buoyancy_position 410.259216 cc 2022-05-06T21:53:12.269Z,1651873992.269 [Reporter](INFO): platform_buoyancy_position 416.571350 cc 2022-05-06T21:53:12.678Z,1651873992.678 [Reporter](INFO): platform_buoyancy_position 421.003235 cc 2022-05-06T21:53:13.094Z,1651873993.094 [Reporter](INFO): platform_buoyancy_position 427.315369 cc 2022-05-06T21:53:13.494Z,1651873993.494 [Reporter](INFO): platform_buoyancy_position 431.612976 cc 2022-05-06T21:53:13.909Z,1651873993.909 [Reporter](INFO): platform_buoyancy_position 437.925049 cc 2022-05-06T21:53:14.338Z,1651873994.338 [Reporter](INFO): platform_buoyancy_position 442.491272 cc 2022-05-06T21:53:14.683Z,1651873994.683 [Reporter](INFO): platform_buoyancy_position 446.788818 cc 2022-05-06T21:53:15.099Z,1651873995.099 [Reporter](INFO): platform_buoyancy_position 453.235229 cc 2022-05-06T21:53:15.517Z,1651873995.517 [Reporter](INFO): platform_buoyancy_position 457.532837 cc 2022-05-06T21:53:15.914Z,1651873995.914 [Reporter](INFO): platform_buoyancy_position 463.979248 cc 2022-05-06T21:53:16.303Z,1651873996.303 [Reporter](INFO): platform_buoyancy_position 468.142578 cc 2022-05-06T21:53:16.746Z,1651873996.746 [Reporter](INFO): platform_buoyancy_position 474.588928 cc 2022-05-06T21:53:17.142Z,1651873997.142 [Reporter](INFO): platform_buoyancy_position 479.155151 cc 2022-05-06T21:53:17.542Z,1651873997.542 [Reporter](INFO): platform_buoyancy_position 485.332947 cc 2022-05-06T21:53:17.954Z,1651873997.954 [Reporter](INFO): platform_buoyancy_position 489.630554 cc 2022-05-06T21:53:18.330Z,1651873998.330 [Reporter](INFO): platform_buoyancy_position 494.062439 cc 2022-05-06T21:53:18.746Z,1651873998.746 [Reporter](INFO): platform_buoyancy_position 500.508881 cc 2022-05-06T21:53:19.142Z,1651873999.142 [Reporter](INFO): platform_buoyancy_position 506.418060 cc 2022-05-06T21:53:19.558Z,1651873999.558 [Reporter](INFO): platform_buoyancy_position 511.252838 cc 2022-05-06T21:53:20.269Z,1651874000.269 [MassServo](ERROR): getPosition uart error serial timeout 2022-05-06T21:53:20.270Z,1651874000.270 [MassServo](FAULT): Invalid EZ Servo response:"" 2022-05-06T21:53:20.271Z,1651874000.271 [MassServo] Communications Fault, FailCount= 3 2022-05-06T21:53:20.271Z,1651874000.271 [MassServo](ERROR): Communications Fault 2022-05-06T21:53:20.283Z,1651874000.283 [CBIT](ERROR): Communications Fault in component: MassServo 2022-05-06T21:53:20.285Z,1651874000.285 [Reporter](INFO): platform_buoyancy_position 517.564941 cc 2022-05-06T21:53:20.394Z,1651874000.394 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-05-06T21:53:20.394Z,1651874000.394 [MassServo](INFO): Powering down 2022-05-06T21:53:20.400Z,1651874000.400 [Reporter](INFO): platform_buoyancy_position 521.996826 cc 2022-05-06T21:53:20.743Z,1651874000.743 [Reporter](INFO): platform_buoyancy_position 526.160156 cc 2022-05-06T21:53:21.154Z,1651874001.154 [MassServo](DEBUG): Initializing EZServoServo. 2022-05-06T21:53:21.273Z,1651874001.273 [MassServo](DEBUG): Initializing MassServo. 2022-05-06T21:53:21.277Z,1651874001.277 [CBIT](INFO): Clearing failed state for component MassServo 2022-05-06T21:53:21.278Z,1651874001.278 [MassServo] No Fault, FailCount= 3 2022-05-06T21:53:21.279Z,1651874001.279 [Reporter](INFO): platform_buoyancy_position 532.606567 cc 2022-05-06T21:53:21.574Z,1651874001.574 [Reporter](INFO): platform_buoyancy_position 539.052979 cc 2022-05-06T21:53:21.966Z,1651874001.966 [Reporter](INFO): platform_buoyancy_position 543.484863 cc 2022-05-06T21:53:22.426Z,1651874002.426 [Reporter](INFO): platform_buoyancy_position 548.185364 cc 2022-05-06T21:53:22.802Z,1651874002.802 [Reporter](INFO): platform_buoyancy_position 554.228882 cc 2022-05-06T21:53:23.205Z,1651874003.205 [Reporter](INFO): platform_buoyancy_position 558.660767 cc 2022-05-06T21:53:23.590Z,1651874003.590 [Reporter](INFO): platform_buoyancy_position 564.838562 cc 2022-05-06T21:53:24.030Z,1651874004.030 [Reporter](INFO): platform_buoyancy_position 569.270447 cc 2022-05-06T21:53:24.401Z,1651874004.401 [Reporter](INFO): platform_buoyancy_position 575.582581 cc 2022-05-06T21:53:24.822Z,1651874004.822 [Reporter](INFO): platform_buoyancy_position 580.148743 cc 2022-05-06T21:53:25.222Z,1651874005.222 [Reporter](INFO): platform_buoyancy_position 586.326538 cc 2022-05-06T21:53:25.670Z,1651874005.670 [Reporter](INFO): platform_buoyancy_position 590.892761 cc 2022-05-06T21:53:26.054Z,1651874006.054 [Reporter](INFO): platform_buoyancy_position 597.070557 cc 2022-05-06T21:53:26.463Z,1651874006.463 [Reporter](INFO): platform_buoyancy_position 601.368164 cc 2022-05-06T21:53:26.729Z,1651874006.729 [CommandExec](IMPORTANT): got command report 2022-05-06T21:53:26.729Z,1651874006.729 [Reporter](INFO): platform_buoyancy_position reporting changes 2022-05-06T21:53:26.730Z,1651874006.730 [Reporter](INFO): platform_mass_position reporting changes 2022-05-06T21:53:26.880Z,1651874006.880 [Reporter](INFO): platform_buoyancy_position 607.814575 cc 2022-05-06T21:53:26.880Z,1651874006.880 [Reporter](INFO): platform_mass_position 0.000003 m 2022-05-06T21:53:27.326Z,1651874007.326 [Reporter](INFO): platform_buoyancy_position 612.246460 cc 2022-05-06T21:53:27.622Z,1651874007.622 [Reporter](INFO): platform_buoyancy_position 618.558533 cc 2022-05-06T21:53:28.046Z,1651874008.046 [Reporter](INFO): platform_buoyancy_position 622.856140 cc 2022-05-06T21:53:28.455Z,1651874008.455 [Reporter](INFO): platform_buoyancy_position 627.153748 cc 2022-05-06T21:53:28.874Z,1651874008.874 [Reporter](INFO): platform_buoyancy_position 633.600159 cc 2022-05-06T21:53:29.276Z,1651874009.276 [Reporter](INFO): platform_buoyancy_position 638.032043 cc 2022-05-06T21:53:29.638Z,1651874009.638 [Reporter](INFO): platform_buoyancy_position 644.344177 cc 2022-05-06T21:53:30.054Z,1651874010.054 [Reporter](INFO): platform_buoyancy_position 648.776062 cc 2022-05-06T21:53:30.483Z,1651874010.483 [Reporter](INFO): platform_buoyancy_position 655.222473 cc 2022-05-06T21:53:30.873Z,1651874010.873 [Reporter](INFO): platform_buoyancy_position 659.520081 cc 2022-05-06T21:53:31.275Z,1651874011.275 [Reporter](INFO): platform_buoyancy_position 665.832153 cc 2022-05-06T21:53:31.703Z,1651874011.703 [Reporter](INFO): platform_buoyancy_position 671.607056 cc 2022-05-06T21:53:32.108Z,1651874012.108 [Reporter](INFO): platform_buoyancy_position 676.710449 cc 2022-05-06T21:53:32.505Z,1651874012.505 [Reporter](INFO): platform_buoyancy_position 681.008057 cc 2022-05-06T21:53:32.882Z,1651874012.882 [Reporter](INFO): platform_buoyancy_position 687.320190 cc 2022-05-06T21:53:33.295Z,1651874013.295 [Reporter](INFO): platform_buoyancy_position 691.617737 cc 2022-05-06T21:53:33.686Z,1651874013.686 [Reporter](INFO): platform_buoyancy_position 698.198486 cc 2022-05-06T21:53:34.086Z,1651874014.086 [Reporter](INFO): platform_buoyancy_position 702.496094 cc 2022-05-06T21:53:34.506Z,1651874014.506 [Reporter](INFO): platform_buoyancy_position 708.808167 cc 2022-05-06T21:53:34.914Z,1651874014.914 [Reporter](INFO): platform_buoyancy_position 713.240051 cc 2022-05-06T21:53:35.314Z,1651874015.314 [Reporter](INFO): platform_buoyancy_position 719.552185 cc 2022-05-06T21:53:35.734Z,1651874015.734 [Reporter](INFO): platform_buoyancy_position 723.849792 cc 2022-05-06T21:53:36.098Z,1651874016.098 [Reporter](INFO): platform_buoyancy_position 728.415955 cc 2022-05-06T21:53:36.522Z,1651874016.522 [Reporter](INFO): platform_buoyancy_position 734.728088 cc 2022-05-06T21:53:36.914Z,1651874016.914 [Reporter](INFO): platform_buoyancy_position 739.025696 cc 2022-05-06T21:53:37.310Z,1651874017.310 [Reporter](INFO): platform_buoyancy_position 745.337769 cc 2022-05-06T21:53:37.747Z,1651874017.747 [Reporter](INFO): platform_buoyancy_position 749.903992 cc 2022-05-06T21:53:38.162Z,1651874018.162 [Reporter](INFO): platform_buoyancy_position 756.081787 cc 2022-05-06T21:53:38.630Z,1651874018.630 [Reporter](INFO): platform_buoyancy_position 762.662476 cc 2022-05-06T21:53:38.943Z,1651874018.943 [Reporter](INFO): platform_buoyancy_position 764.811279 cc 2022-05-06T21:53:39.354Z,1651874019.354 [Reporter](INFO): platform_buoyancy_position 771.123352 cc 2022-05-06T21:53:39.769Z,1651874019.769 [Reporter](INFO): platform_buoyancy_position 775.689575 cc 2022-05-06T21:53:40.175Z,1651874020.175 [Reporter](INFO): platform_buoyancy_position 781.867371 cc 2022-05-06T21:53:40.246Z,1651874020.246 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -20 millimeter 2022-05-06T21:53:40.247Z,1651874020.247 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2022-05-06T21:53:40.581Z,1651874020.581 [Reporter](INFO): platform_buoyancy_position 787.507996 cc 2022-05-06T21:53:40.586Z,1651874020.586 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-05-06T21:53:41.010Z,1651874021.010 [Reporter](INFO): platform_buoyancy_position 792.745667 cc 2022-05-06T21:53:41.414Z,1651874021.414 [Reporter](INFO): platform_buoyancy_position 798.520569 cc 2022-05-06T21:53:41.778Z,1651874021.778 [Reporter](INFO): platform_buoyancy_position 803.355347 cc 2022-05-06T21:53:42.198Z,1651874022.198 [Reporter](INFO): platform_buoyancy_position 808.861694 cc 2022-05-06T21:53:42.625Z,1651874022.625 [Reporter](INFO): platform_buoyancy_position 814.099365 cc 2022-05-06T21:53:43.022Z,1651874023.022 [Reporter](INFO): platform_buoyancy_position 818.531250 cc 2022-05-06T21:53:43.450Z,1651874023.450 [Reporter](INFO): platform_buoyancy_position 824.843384 cc 2022-05-06T21:53:43.451Z,1651874023.451 [Reporter](INFO): platform_mass_position -0.000308 m 2022-05-06T21:53:43.830Z,1651874023.830 [Reporter](INFO): platform_buoyancy_position 829.409546 cc 2022-05-06T21:53:43.831Z,1651874023.831 [Reporter](INFO): platform_mass_position -0.000608 m 2022-05-06T21:53:44.238Z,1651874024.238 [Reporter](INFO): platform_buoyancy_position 835.721680 cc 2022-05-06T21:53:44.238Z,1651874024.238 [Reporter](INFO): platform_mass_position -0.000923 m 2022-05-06T21:53:44.614Z,1651874024.614 [Reporter](INFO): platform_buoyancy_position 839.885010 cc 2022-05-06T21:53:44.615Z,1651874024.615 [Reporter](INFO): platform_mass_position -0.001221 m 2022-05-06T21:53:45.042Z,1651874025.042 [Reporter](INFO): platform_buoyancy_position 844.316895 cc 2022-05-06T21:53:45.043Z,1651874025.043 [Reporter](INFO): platform_mass_position -0.001550 m 2022-05-06T21:53:45.430Z,1651874025.430 [Reporter](INFO): platform_buoyancy_position 850.629028 cc 2022-05-06T21:53:45.431Z,1651874025.431 [Reporter](INFO): platform_mass_position -0.001856 m 2022-05-06T21:53:45.862Z,1651874025.862 [Reporter](INFO): platform_buoyancy_position 856.135254 cc 2022-05-06T21:53:45.863Z,1651874025.863 [Reporter](INFO): platform_mass_position -0.002192 m 2022-05-06T21:53:46.246Z,1651874026.246 [Reporter](INFO): platform_buoyancy_position 861.372925 cc 2022-05-06T21:53:46.247Z,1651874026.247 [Reporter](INFO): platform_mass_position -0.002493 m 2022-05-06T21:53:46.655Z,1651874026.655 [Reporter](INFO): platform_buoyancy_position 865.804932 cc 2022-05-06T21:53:46.655Z,1651874026.655 [Reporter](INFO): platform_mass_position -0.002819 m 2022-05-06T21:53:47.070Z,1651874027.070 [Reporter](INFO): platform_buoyancy_position 872.116943 cc 2022-05-06T21:53:47.071Z,1651874027.071 [Reporter](INFO): platform_mass_position -0.003139 m 2022-05-06T21:53:47.458Z,1651874027.458 [Reporter](INFO): platform_buoyancy_position 876.548828 cc 2022-05-06T21:53:47.459Z,1651874027.459 [Reporter](INFO): platform_mass_position -0.003449 m 2022-05-06T21:53:47.850Z,1651874027.850 [Reporter](INFO): platform_buoyancy_position 882.860962 cc 2022-05-06T21:53:47.851Z,1651874027.851 [Reporter](INFO): platform_mass_position -0.003750 m 2022-05-06T21:53:48.254Z,1651874028.254 [Reporter](INFO): platform_buoyancy_position 887.158569 cc 2022-05-06T21:53:48.255Z,1651874028.255 [Reporter](INFO): platform_mass_position -0.004073 m 2022-05-06T21:53:48.666Z,1651874028.666 [Reporter](INFO): platform_buoyancy_position 892.933472 cc 2022-05-06T21:53:48.666Z,1651874028.666 [Reporter](INFO): platform_mass_position -0.004396 m 2022-05-06T21:53:49.070Z,1651874029.070 [Reporter](INFO): platform_buoyancy_position 898.036865 cc 2022-05-06T21:53:49.070Z,1651874029.070 [Reporter](INFO): platform_mass_position -0.004709 m 2022-05-06T21:53:49.482Z,1651874029.482 [Reporter](INFO): platform_buoyancy_position 904.348999 cc 2022-05-06T21:53:49.483Z,1651874029.483 [Reporter](INFO): platform_mass_position -0.005036 m 2022-05-06T21:53:49.898Z,1651874029.898 [Reporter](INFO): platform_buoyancy_position 908.780884 cc 2022-05-06T21:53:49.899Z,1651874029.899 [Reporter](INFO): platform_mass_position -0.005362 m 2022-05-06T21:53:50.282Z,1651874030.282 [Reporter](INFO): platform_buoyancy_position 915.093018 cc 2022-05-06T21:53:50.283Z,1651874030.283 [Reporter](INFO): platform_mass_position -0.005664 m 2022-05-06T21:53:50.682Z,1651874030.682 [Reporter](INFO): platform_buoyancy_position 919.524902 cc 2022-05-06T21:53:50.683Z,1651874030.683 [Reporter](INFO): platform_mass_position -0.005976 m 2022-05-06T21:53:51.098Z,1651874031.098 [Reporter](INFO): platform_buoyancy_position 925.971313 cc 2022-05-06T21:53:51.099Z,1651874031.099 [Reporter](INFO): platform_mass_position -0.006302 m 2022-05-06T21:53:51.494Z,1651874031.494 [Reporter](INFO): platform_buoyancy_position 930.134644 cc 2022-05-06T21:53:51.495Z,1651874031.495 [Reporter](INFO): platform_mass_position -0.006607 m 2022-05-06T21:53:51.886Z,1651874031.886 [Reporter](INFO): platform_buoyancy_position 934.566528 cc 2022-05-06T21:53:51.887Z,1651874031.887 [Reporter](INFO): platform_mass_position -0.006920 m 2022-05-06T21:53:52.302Z,1651874032.302 [Reporter](INFO): platform_buoyancy_position 940.878540 cc 2022-05-06T21:53:52.303Z,1651874032.303 [Reporter](INFO): platform_mass_position -0.007246 m 2022-05-06T21:53:52.734Z,1651874032.734 [Reporter](INFO): platform_buoyancy_position 945.444824 cc 2022-05-06T21:53:52.735Z,1651874032.735 [Reporter](INFO): platform_mass_position -0.007587 m 2022-05-06T21:53:53.117Z,1651874033.117 [Reporter](INFO): platform_mass_position -0.007877 m 2022-05-06T21:53:53.526Z,1651874033.526 [Reporter](INFO): platform_mass_position -0.008206 m 2022-05-06T21:53:53.890Z,1651874033.890 [Reporter](INFO): platform_mass_position -0.008491 m 2022-05-06T21:53:54.299Z,1651874034.299 [Reporter](INFO): platform_mass_position -0.008796 m 2022-05-06T21:53:54.818Z,1651874034.818 [Reporter](INFO): platform_mass_position -0.009217 m 2022-05-06T21:53:55.099Z,1651874035.099 [Reporter](INFO): platform_mass_position -0.009432 m 2022-05-06T21:53:55.522Z,1651874035.522 [Reporter](INFO): platform_mass_position -0.009759 m 2022-05-06T21:53:55.933Z,1651874035.933 [Reporter](INFO): platform_mass_position -0.010082 m 2022-05-06T21:53:56.354Z,1651874036.354 [Reporter](INFO): platform_mass_position -0.010415 m 2022-05-06T21:53:56.723Z,1651874036.723 [Reporter](INFO): platform_mass_position -0.010699 m 2022-05-06T21:53:57.129Z,1651874037.129 [Reporter](INFO): platform_mass_position -0.011018 m 2022-05-06T21:53:57.527Z,1651874037.527 [Reporter](INFO): platform_mass_position -0.011343 m 2022-05-06T21:53:57.922Z,1651874037.922 [Reporter](INFO): platform_mass_position -0.011646 m 2022-05-06T21:53:58.337Z,1651874038.337 [Reporter](INFO): platform_mass_position -0.011963 m 2022-05-06T21:53:58.725Z,1651874038.725 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-05-06T21:48:58.5Z 2022-05-06T21:53:58.725Z,1651874038.725 [Default:CheckIn:Read_GPS] Stopped 2022-05-06T21:53:58.725Z,1651874038.725 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-05-06T21:53:58.778Z,1651874038.778 [Reporter](INFO): platform_mass_position -0.012323 m 2022-05-06T21:53:59.118Z,1651874039.118 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-05-06T21:53:59.162Z,1651874039.162 [Reporter](INFO): platform_mass_position -0.012619 m 2022-05-06T21:53:59.586Z,1651874039.586 [Reporter](INFO): platform_mass_position -0.012938 m 2022-05-06T21:53:59.946Z,1651874039.946 [Reporter](INFO): platform_mass_position -0.013229 m 2022-05-06T21:54:00.370Z,1651874040.370 [Reporter](INFO): platform_mass_position -0.013566 m 2022-05-06T21:54:00.772Z,1651874040.772 [Reporter](INFO): platform_mass_position -0.013884 m 2022-05-06T21:54:01.166Z,1651874041.166 [Reporter](INFO): platform_mass_position -0.014190 m 2022-05-06T21:54:01.575Z,1651874041.575 [Reporter](INFO): platform_mass_position -0.014515 m 2022-05-06T21:54:01.978Z,1651874041.978 [Reporter](INFO): platform_mass_position -0.014824 m 2022-05-06T21:54:02.401Z,1651874042.401 [Reporter](INFO): platform_mass_position -0.015154 m 2022-05-06T21:54:02.782Z,1651874042.782 [Reporter](INFO): platform_mass_position -0.015458 m 2022-05-06T21:54:03.190Z,1651874043.190 [Reporter](INFO): platform_mass_position -0.015775 m 2022-05-06T21:54:03.647Z,1651874043.647 [Reporter](INFO): platform_mass_position -0.016139 m 2022-05-06T21:54:04.035Z,1651874044.035 [Reporter](INFO): platform_mass_position -0.016436 m 2022-05-06T21:54:04.434Z,1651874044.434 [Reporter](INFO): platform_mass_position -0.016751 m 2022-05-06T21:54:04.830Z,1651874044.830 [Reporter](INFO): platform_mass_position -0.017066 m 2022-05-06T21:54:05.220Z,1651874045.220 [Reporter](INFO): platform_mass_position -0.017364 m 2022-05-06T21:54:05.622Z,1651874045.622 [Reporter](INFO): platform_mass_position -0.017685 m 2022-05-06T21:54:06.002Z,1651874046.002 [Reporter](INFO): platform_mass_position -0.017983 m 2022-05-06T21:54:06.421Z,1651874046.421 [Reporter](INFO): platform_mass_position -0.018304 m 2022-05-06T21:54:06.635Z,1651874046.635 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220506T214519/Courier0004.lzma 2022-05-06T21:54:06.830Z,1651874046.830 [Reporter](INFO): platform_mass_position -0.018623 m 2022-05-06T21:54:07.206Z,1651874047.206 [Reporter](INFO): platform_mass_position -0.018929 m 2022-05-06T21:54:07.638Z,1651874047.638 [DataOverHttps](INFO): Moved sent file to Logs/20220506T214519/Courier0004.lzma.bak 2022-05-06T21:54:07.639Z,1651874047.639 [DataOverHttps](INFO): SBD MOMSN=16727012 2022-05-06T21:54:07.662Z,1651874047.662 [Reporter](INFO): platform_mass_position -0.019272 m 2022-05-06T21:54:08.039Z,1651874048.039 [Reporter](INFO): platform_mass_position -0.019579 m 2022-05-06T21:54:08.451Z,1651874048.451 [Reporter](INFO): platform_mass_position -0.019898 m 2022-05-06T21:54:08.886Z,1651874048.886 [Reporter](INFO): platform_mass_position -0.020000 m 2022-05-06T21:54:16.228Z,1651874056.228 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -30 millimeter 2022-05-06T21:54:18.964Z,1651874058.964 [Reporter](INFO): platform_mass_position -0.020281 m 2022-05-06T21:54:19.377Z,1651874059.377 [Reporter](INFO): platform_mass_position -0.020607 m 2022-05-06T21:54:19.759Z,1651874059.759 [Reporter](INFO): platform_mass_position -0.020905 m 2022-05-06T21:54:20.167Z,1651874060.167 [Reporter](INFO): platform_mass_position -0.021218 m 2022-05-06T21:54:20.615Z,1651874060.615 [Reporter](INFO): platform_mass_position -0.021579 m 2022-05-06T21:54:20.970Z,1651874060.970 [Reporter](INFO): platform_mass_position -0.021851 m 2022-05-06T21:54:21.396Z,1651874061.396 [Reporter](INFO): platform_mass_position -0.022169 m 2022-05-06T21:54:21.794Z,1651874061.794 [Reporter](INFO): platform_mass_position -0.022497 m 2022-05-06T21:54:22.191Z,1651874062.191 [Reporter](INFO): platform_mass_position -0.022804 m 2022-05-06T21:54:22.622Z,1651874062.622 [Reporter](INFO): platform_mass_position -0.023150 m 2022-05-06T21:54:23.000Z,1651874063.000 [Reporter](INFO): platform_mass_position -0.023440 m 2022-05-06T21:54:23.437Z,1651874063.437 [Reporter](INFO): platform_mass_position -0.023785 m 2022-05-06T21:54:23.811Z,1651874063.811 [Reporter](INFO): platform_mass_position -0.024074 m 2022-05-06T21:54:24.238Z,1651874064.238 [Reporter](INFO): platform_mass_position -0.024413 m 2022-05-06T21:54:24.602Z,1651874064.602 [Reporter](INFO): platform_mass_position -0.024697 m 2022-05-06T21:54:25.018Z,1651874065.018 [Reporter](INFO): platform_mass_position -0.025024 m 2022-05-06T21:54:25.420Z,1651874065.420 [Reporter](INFO): platform_mass_position -0.025335 m 2022-05-06T21:54:25.819Z,1651874065.819 [Reporter](INFO): platform_mass_position -0.025658 m 2022-05-06T21:54:26.218Z,1651874066.218 [DataOverHttps](INFO): Sending 965 bytes from file Logs/20220506T214519/Express0005.lzma 2022-05-06T21:54:26.250Z,1651874066.250 [Reporter](INFO): platform_mass_position -0.025982 m 2022-05-06T21:54:26.618Z,1651874066.618 [Reporter](INFO): platform_mass_position -0.026282 m 2022-05-06T21:54:27.014Z,1651874067.014 [Reporter](INFO): platform_mass_position -0.026595 m 2022-05-06T21:54:27.218Z,1651874067.218 [DataOverHttps](INFO): Moved sent file to Logs/20220506T214519/Express0005.lzma.bak 2022-05-06T21:54:27.218Z,1651874067.218 [DataOverHttps](INFO): SBD MOMSN=16727018 2022-05-06T21:54:27.427Z,1651874067.427 [Reporter](INFO): platform_mass_position -0.026918 m 2022-05-06T21:54:27.814Z,1651874067.814 [Reporter](INFO): platform_mass_position -0.027219 m 2022-05-06T21:54:28.271Z,1651874068.271 [Reporter](INFO): platform_mass_position -0.027542 m 2022-05-06T21:54:28.666Z,1651874068.666 [Reporter](INFO): platform_mass_position -0.027880 m 2022-05-06T21:54:29.067Z,1651874069.067 [Reporter](INFO): platform_mass_position -0.028199 m 2022-05-06T21:54:29.524Z,1651874069.524 [Reporter](INFO): platform_mass_position -0.028552 m 2022-05-06T21:54:29.813Z,1651874069.813 [Default:CheckIn:Read_Iridium] Stopped 2022-05-06T21:54:29.813Z,1651874069.813 [Default:CheckIn:C.Wait] Running Loop=1 2022-05-06T21:54:29.813Z,1651874069.813 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-05-06T21:54:29.863Z,1651874069.863 [Reporter](INFO): platform_mass_position -0.028821 m 2022-05-06T21:54:30.265Z,1651874070.265 [Reporter](INFO): platform_mass_position -0.029134 m 2022-05-06T21:54:30.682Z,1651874070.682 [Reporter](INFO): platform_mass_position -0.029464 m 2022-05-06T21:54:31.021Z,1651874071.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2022-05-06T21:54:31.021Z,1651874071.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-06T21:54:31.068Z,1651874071.068 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-06T21:54:31.070Z,1651874071.070 [Reporter](INFO): platform_mass_position -0.029765 m 2022-05-06T21:54:31.480Z,1651874071.480 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-06T21:54:31.480Z,1651874071.480 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2022-05-06T21:54:31.483Z,1651874071.483 [Reporter](INFO): platform_mass_position -0.030000 m 2022-05-06T21:54:39.450Z,1651874079.450 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -40 millimeter 2022-05-06T21:54:41.622Z,1651874081.622 [Reporter](INFO): platform_mass_position -0.030294 m 2022-05-06T21:54:41.994Z,1651874081.994 [Reporter](INFO): platform_mass_position -0.030582 m 2022-05-06T21:54:42.404Z,1651874082.404 [Reporter](INFO): platform_mass_position -0.030904 m 2022-05-06T21:54:42.819Z,1651874082.819 [Reporter](INFO): platform_mass_position -0.031233 m 2022-05-06T21:54:43.195Z,1651874083.195 [Reporter](INFO): platform_mass_position -0.031521 m 2022-05-06T21:54:43.649Z,1651874083.649 [Reporter](INFO): platform_mass_position -0.031876 m 2022-05-06T21:54:44.046Z,1651874084.046 [Reporter](INFO): platform_mass_position -0.032194 m 2022-05-06T21:54:44.455Z,1651874084.455 [Reporter](INFO): platform_mass_position -0.032515 m 2022-05-06T21:54:44.842Z,1651874084.842 [Reporter](INFO): platform_mass_position -0.032818 m 2022-05-06T21:54:45.217Z,1651874085.217 [Reporter](INFO): platform_mass_position -0.033107 m 2022-05-06T21:54:45.622Z,1651874085.622 [Reporter](INFO): platform_mass_position -0.033426 m 2022-05-06T21:54:46.034Z,1651874086.034 [Reporter](INFO): platform_mass_position -0.033748 m 2022-05-06T21:54:46.438Z,1651874086.438 [Reporter](INFO): platform_mass_position -0.034071 m 2022-05-06T21:54:46.869Z,1651874086.869 [Reporter](INFO): platform_mass_position -0.034398 m 2022-05-06T21:54:47.278Z,1651874087.278 [Reporter](INFO): platform_mass_position -0.034727 m 2022-05-06T21:54:47.653Z,1651874087.653 [Reporter](INFO): platform_mass_position -0.035010 m 2022-05-06T21:54:48.050Z,1651874088.050 [Reporter](INFO): platform_mass_position -0.035332 m 2022-05-06T21:54:48.446Z,1651874088.446 [Reporter](INFO): platform_mass_position -0.035640 m 2022-05-06T21:54:48.854Z,1651874088.854 [Reporter](INFO): platform_mass_position -0.035955 m 2022-05-06T21:54:49.262Z,1651874089.262 [Reporter](INFO): platform_mass_position -0.036275 m 2022-05-06T21:54:49.690Z,1651874089.690 [Reporter](INFO): platform_mass_position -0.036618 m 2022-05-06T21:54:50.079Z,1651874090.079 [Reporter](INFO): platform_mass_position -0.036924 m 2022-05-06T21:54:50.518Z,1651874090.518 [Reporter](INFO): platform_mass_position -0.037000 m 2022-05-06T21:55:19.295Z,1651874119.295 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -45 millimeter 2022-05-06T21:55:24.293Z,1651874124.293 [CommandExec](IMPORTANT): got command show variable ticks 2022-05-06T21:55:29.342Z,1651874129.342 [CommandExec](IMPORTANT): got command show variable tick 2022-05-06T21:55:29.398Z,1651874129.398 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold (count) 2022-05-06T21:55:29.399Z,1651874129.399 [CommandExec](IMPORTANT): BPC1.batteryStickCommsTimeout (hour) 2022-05-06T21:55:29.478Z,1651874129.478 [CommandExec](IMPORTANT): Config/Battery.stick1 (none) 2022-05-06T21:55:29.479Z,1651874129.479 [CommandExec](IMPORTANT): Config/Battery.stick2 (none) 2022-05-06T21:55:29.479Z,1651874129.479 [CommandExec](IMPORTANT): Config/Battery.stick3 (none) 2022-05-06T21:55:29.479Z,1651874129.479 [CommandExec](IMPORTANT): Config/Battery.stick4 (none) 2022-05-06T21:55:29.480Z,1651874129.480 [CommandExec](IMPORTANT): Config/Battery.stick5 (none) 2022-05-06T21:55:29.500Z,1651874129.500 [CommandExec](IMPORTANT): Config/Battery.stick6 (none) 2022-05-06T21:55:29.501Z,1651874129.501 [CommandExec](IMPORTANT): Config/Battery.stick7 (none) 2022-05-06T21:55:29.501Z,1651874129.501 [CommandExec](IMPORTANT): Config/Battery.stick8 (none) 2022-05-06T21:55:29.502Z,1651874129.502 [CommandExec](IMPORTANT): Config/Battery.stick9 (none) 2022-05-06T21:55:29.502Z,1651874129.502 [CommandExec](IMPORTANT): Config/Battery.stick10 (none) 2022-05-06T21:55:29.502Z,1651874129.502 [CommandExec](IMPORTANT): Config/Battery.stick11 (none) 2022-05-06T21:55:29.503Z,1651874129.503 [CommandExec](IMPORTANT): Config/Battery.stick12 (none) 2022-05-06T21:55:29.503Z,1651874129.503 [CommandExec](IMPORTANT): Config/Battery.stick13 (none) 2022-05-06T21:55:29.503Z,1651874129.503 [CommandExec](IMPORTANT): Config/Battery.stick14 (none) 2022-05-06T21:55:29.504Z,1651874129.504 [CommandExec](IMPORTANT): Config/Battery.stick15 (none) 2022-05-06T21:55:29.504Z,1651874129.504 [CommandExec](IMPORTANT): Config/Battery.stick16 (none) 2022-05-06T21:55:29.505Z,1651874129.505 [CommandExec](IMPORTANT): Config/Battery.stick17 (none) 2022-05-06T21:55:29.505Z,1651874129.505 [CommandExec](IMPORTANT): Config/Battery.stick18 (none) 2022-05-06T21:55:29.505Z,1651874129.505 [CommandExec](IMPORTANT): Config/Battery.stick19 (none) 2022-05-06T21:55:29.506Z,1651874129.506 [CommandExec](IMPORTANT): Config/Battery.stick20 (none) 2022-05-06T21:55:29.506Z,1651874129.506 [CommandExec](IMPORTANT): Config/Battery.stick21 (none) 2022-05-06T21:55:29.507Z,1651874129.507 [CommandExec](IMPORTANT): Config/Battery.stick22 (none) 2022-05-06T21:55:29.507Z,1651874129.507 [CommandExec](IMPORTANT): Config/Battery.stick23 (none) 2022-05-06T21:55:29.507Z,1651874129.507 [CommandExec](IMPORTANT): Config/Battery.stick24 (none) 2022-05-06T21:55:29.508Z,1651874129.508 [CommandExec](IMPORTANT): Config/Battery.stick25 (none) 2022-05-06T21:55:29.508Z,1651874129.508 [CommandExec](IMPORTANT): Config/Battery.stick26 (none) 2022-05-06T21:55:29.509Z,1651874129.509 [CommandExec](IMPORTANT): Config/Battery.stick27 (none) 2022-05-06T21:55:29.509Z,1651874129.509 [CommandExec](IMPORTANT): Config/Battery.stick28 (none) 2022-05-06T21:55:29.509Z,1651874129.509 [CommandExec](IMPORTANT): Config/Battery.stick29 (none) 2022-05-06T21:55:29.510Z,1651874129.510 [CommandExec](IMPORTANT): Config/Battery.stick30 (none) 2022-05-06T21:55:29.510Z,1651874129.510 [CommandExec](IMPORTANT): Config/Battery.stick31 (none) 2022-05-06T21:55:29.510Z,1651874129.510 [CommandExec](IMPORTANT): Config/Battery.stick32 (none) 2022-05-06T21:55:29.511Z,1651874129.511 [CommandExec](IMPORTANT): Config/Battery.stick33 (none) 2022-05-06T21:55:29.511Z,1651874129.511 [CommandExec](IMPORTANT): Config/Battery.stick34 (none) 2022-05-06T21:55:29.512Z,1651874129.512 [CommandExec](IMPORTANT): Config/Battery.stick35 (none) 2022-05-06T21:55:29.513Z,1651874129.513 [CommandExec](IMPORTANT): Config/Battery.stick36 (none) 2022-05-06T21:55:29.513Z,1651874129.513 [CommandExec](IMPORTANT): Config/Battery.stick37 (none) 2022-05-06T21:55:29.514Z,1651874129.514 [CommandExec](IMPORTANT): Config/Battery.stick38 (none) 2022-05-06T21:55:29.514Z,1651874129.514 [CommandExec](IMPORTANT): Config/Battery.stick39 (none) 2022-05-06T21:55:29.515Z,1651874129.515 [CommandExec](IMPORTANT): Config/Battery.stick40 (none) 2022-05-06T21:55:29.515Z,1651874129.515 [CommandExec](IMPORTANT): Config/Battery.stick41 (none) 2022-05-06T21:55:29.515Z,1651874129.515 [CommandExec](IMPORTANT): Config/Battery.stick42 (none) 2022-05-06T21:55:29.516Z,1651874129.516 [CommandExec](IMPORTANT): Config/Battery.stick43 (none) 2022-05-06T21:55:29.516Z,1651874129.516 [CommandExec](IMPORTANT): Config/Battery.stick44 (none) 2022-05-06T21:55:29.517Z,1651874129.517 [CommandExec](IMPORTANT): Config/Battery.stick45 (none) 2022-05-06T21:55:29.517Z,1651874129.517 [CommandExec](IMPORTANT): Config/Battery.stick46 (none) 2022-05-06T21:55:29.517Z,1651874129.517 [CommandExec](IMPORTANT): Config/Battery.stick47 (none) 2022-05-06T21:55:29.518Z,1651874129.518 [CommandExec](IMPORTANT): Config/Battery.stick48 (none) 2022-05-06T21:55:29.518Z,1651874129.518 [CommandExec](IMPORTANT): Config/Battery.stick49 (none) 2022-05-06T21:55:29.518Z,1651874129.518 [CommandExec](IMPORTANT): Config/Battery.stick50 (none) 2022-05-06T21:55:29.519Z,1651874129.519 [CommandExec](IMPORTANT): Config/Battery.stick51 (none) 2022-05-06T21:55:29.519Z,1651874129.519 [CommandExec](IMPORTANT): Config/Battery.stick52 (none) 2022-05-06T21:55:29.520Z,1651874129.520 [CommandExec](IMPORTANT): Config/Battery.stick53 (none) 2022-05-06T21:55:29.520Z,1651874129.520 [CommandExec](IMPORTANT): Config/Battery.stick54 (none) 2022-05-06T21:55:29.521Z,1651874129.521 [CommandExec](IMPORTANT): Config/Battery.stick55 (none) 2022-05-06T21:55:29.521Z,1651874129.521 [CommandExec](IMPORTANT): Config/Battery.stick56 (none) 2022-05-06T21:55:29.521Z,1651874129.521 [CommandExec](IMPORTANT): Config/Battery.stick57 (none) 2022-05-06T21:55:29.522Z,1651874129.522 [CommandExec](IMPORTANT): Config/Battery.stick58 (none) 2022-05-06T21:55:29.522Z,1651874129.522 [CommandExec](IMPORTANT): Config/Battery.stick59 (none) 2022-05-06T21:55:29.522Z,1651874129.522 [CommandExec](IMPORTANT): Config/Battery.stick60 (none) 2022-05-06T21:55:29.523Z,1651874129.523 [CommandExec](IMPORTANT): Config/Battery.stick61 (none) 2022-05-06T21:55:29.523Z,1651874129.523 [CommandExec](IMPORTANT): Config/Battery.stick62 (none) 2022-05-06T21:55:35.090Z,1651874135.090 [CommandExec](IMPORTANT): got command show variable massServo 2022-05-06T21:55:35.138Z,1651874135.138 [CommandExec](IMPORTANT): MassServo.loadAtStartup (bool) 2022-05-06T21:55:35.138Z,1651874135.138 [CommandExec](IMPORTANT): MassServo.simulateHardware (bool) 2022-05-06T21:55:35.139Z,1651874135.139 [CommandExec](IMPORTANT): MassServo.accel (none) 2022-05-06T21:55:35.139Z,1651874135.139 [CommandExec](IMPORTANT): MassServo.currLimit (percent) 2022-05-06T21:55:35.139Z,1651874135.139 [CommandExec](IMPORTANT): MassServo.limitHi (count) 2022-05-06T21:55:35.140Z,1651874135.140 [CommandExec](IMPORTANT): MassServo.limitLo (count) 2022-05-06T21:55:35.140Z,1651874135.140 [CommandExec](IMPORTANT): MassServo.overloadTimeout (millisecond) 2022-05-06T21:55:35.141Z,1651874135.141 [CommandExec](IMPORTANT): MassServo.powerOnTimeout (second) 2022-05-06T21:55:35.141Z,1651874135.141 [CommandExec](IMPORTANT): MassServo.velocity (none) 2022-05-06T21:55:35.141Z,1651874135.141 [CommandExec](IMPORTANT): MassServo.deviationDistance (millimeter) 2022-05-06T21:55:35.142Z,1651874135.142 [CommandExec](IMPORTANT): MassServo.tksPerMM (count_per_millimeter) 2022-05-06T21:55:35.142Z,1651874135.142 [CommandExec](IMPORTANT): MassServo.totalTks (count) 2022-05-06T21:55:35.181Z,1651874135.181 [CommandExec](IMPORTANT): MassServo.loadControl (none) 2022-05-06T21:55:35.181Z,1651874135.181 [CommandExec](IMPORTANT): MassServo.uart (none) 2022-05-06T21:55:35.182Z,1651874135.182 [CommandExec](IMPORTANT): MassServo.baud (bit_per_second) 2022-05-06T21:55:35.294Z,1651874135.294 [CommandExec](IMPORTANT): MassServo.enableBroadcast (bool) 2022-05-06T21:55:35.294Z,1651874135.294 [CommandExec](IMPORTANT): MassServo.component_voltage (volt) 2022-05-06T21:55:35.295Z,1651874135.295 [CommandExec](IMPORTANT): MassServo.component_avgVoltage (volt) 2022-05-06T21:55:35.295Z,1651874135.295 [CommandExec](IMPORTANT): MassServo.component_current (milliampere) 2022-05-06T21:55:35.295Z,1651874135.295 [CommandExec](IMPORTANT): MassServo.component_avgCurrent (milliampere) 2022-05-06T21:55:35.296Z,1651874135.296 [CommandExec](IMPORTANT): MassServo.platform_mass_position (meter) 2022-05-06T21:55:51.441Z,1651874151.441 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2022-05-06T21:55:51.442Z,1651874151.442 [DAT](INFO): commRate: 800 2022-05-06T21:55:53.208Z,1651874153.208 [DAT](INFO): Received command: PbubI 2022-05-06T21:55:53.795Z,1651874153.795 [CommandExec](IMPORTANT): got command get MassServo.totalTks 2022-05-06T21:55:53.795Z,1651874153.795 [CommandExec](IMPORTANT): MassServo.totalTks 1105329 count 2022-05-06T21:55:53.797Z,1651874153.797 [CommandExec](IMPORTANT): got command burn 2022-05-06T21:55:53.797Z,1651874153.797 [CommandExec](FAULT): Incomplete syntax. Try: help burn 2022-05-06T21:55:59.313Z,1651874159.313 [CommandExec](IMPORTANT): got command burn off 2022-05-06T21:55:59.313Z,1651874159.313 [CommandExec](IMPORTANT): Deactivating dropweight wire 2022-05-06T21:56:09.591Z,1651874169.591 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-05-06T21:56:09.591Z,1651874169.591 [NAL9602] Data Fault, FailCount= 1 2022-05-06T21:56:09.591Z,1651874169.591 [NAL9602](ERROR): Data Fault 2022-05-06T21:56:09.693Z,1651874169.693 [CBIT](ERROR): Data Fault in component: NAL9602 2022-05-06T21:56:09.997Z,1651874169.997 [NAL9602](INFO): Powering down 2022-05-06T21:56:10.884Z,1651874170.884 [CBIT](INFO): Clearing failed state for component NAL9602 2022-05-06T21:56:10.884Z,1651874170.884 [NAL9602] No Fault, FailCount= 1 2022-05-06T21:56:40.302Z,1651874200.302 [NAL9602](INFO): Powering up NAL9602 2022-05-06T21:56:51.210Z,1651874211.210 [NAL9602](INFO): NAL9602 initialized 2022-05-06T21:56:59.696Z,1651874219.696 [DVL_micro](ERROR): only read 3 of 4 data items 2022-05-06T21:56:59.696Z,1651874219.696 [DVL_micro](ERROR): Failed to parse: :RD,+9999.999,+9999.99,+9999.99 2022-05-06T21:57:32.018Z,1651874252.018 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2022-05-06T21:57:32.018Z,1651874252.018 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-06T21:57:32.092Z,1651874252.092 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-06T21:57:32.504Z,1651874252.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-06T21:57:32.505Z,1651874252.505 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2022-05-06T21:58:52.937Z,1651874332.937 [DAT](INFO): unknown deviceResponse_: Lowpower 2022-05-06T21:58:58.529Z,1651874338.529 [CommandExec](IMPORTANT): got command configSet MassServo.limitLo -453367 count persist 2022-05-06T21:58:59.341Z,1651874339.341 [Reporter](INFO): platform_mass_position -0.037279 m 2022-05-06T21:58:59.756Z,1651874339.756 [Reporter](INFO): platform_mass_position -0.037605 m 2022-05-06T21:59:00.130Z,1651874340.130 [Reporter](INFO): platform_mass_position -0.037906 m 2022-05-06T21:59:00.534Z,1651874340.534 [Reporter](INFO): platform_mass_position -0.038222 m 2022-05-06T21:59:00.956Z,1651874340.956 [Reporter](INFO): platform_mass_position -0.038555 m 2022-05-06T21:59:01.374Z,1651874341.374 [Reporter](INFO): platform_mass_position -0.038871 m 2022-05-06T21:59:01.790Z,1651874341.790 [Reporter](INFO): platform_mass_position -0.039208 m 2022-05-06T21:59:02.162Z,1651874342.162 [Reporter](INFO): platform_mass_position -0.039495 m 2022-05-06T21:59:02.566Z,1651874342.566 [Reporter](INFO): platform_mass_position -0.039809 m 2022-05-06T21:59:02.970Z,1651874342.970 [Reporter](INFO): platform_mass_position -0.040134 m 2022-05-06T21:59:03.370Z,1651874343.370 [Reporter](INFO): platform_mass_position -0.040441 m 2022-05-06T21:59:03.798Z,1651874343.798 [Reporter](INFO): platform_mass_position -0.040782 m 2022-05-06T21:59:04.190Z,1651874344.190 [Reporter](INFO): platform_mass_position -0.041088 m 2022-05-06T21:59:04.650Z,1651874344.650 [Reporter](INFO): platform_mass_position -0.041442 m 2022-05-06T21:59:05.010Z,1651874345.010 [Reporter](INFO): platform_mass_position -0.041586 m 2022-05-06T21:59:19.879Z,1651874359.879 [DVL_micro](ERROR): only read 3 of 4 data items 2022-05-06T21:59:19.880Z,1651874359.880 [DVL_micro](ERROR): Failed to parse: :BI,+04846,+00192,+00614,+00000+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2022-05-06T21:59:30.418Z,1651874370.418 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-05-06T21:59:30.418Z,1651874370.418 [Default:CheckIn:C.Wait] Stopped 2022-05-06T21:59:30.418Z,1651874370.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-05-06T21:59:30.418Z,1651874370.418 [Default:CheckIn:D] Running Loop=1 2022-05-06T21:59:30.794Z,1651874370.794 [Default:CheckIn:D] Stopped 2022-05-06T21:59:30.794Z,1651874370.794 [Default:CheckIn:E] Running Loop=1 2022-05-06T21:59:31.209Z,1651874371.209 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.767156 min 2022-05-06T21:59:31.210Z,1651874371.210 [Default:CheckIn:E] Stopped 2022-05-06T21:59:31.210Z,1651874371.210 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-05-06T21:59:31.210Z,1651874371.210 [Default:CheckIn] Stopped 2022-05-06T21:59:31.210Z,1651874371.210 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-05-06T21:59:31.210Z,1651874371.210 [Default:CheckIn](INFO): Running loop #2 2022-05-06T21:59:31.210Z,1651874371.210 [Default:CheckIn] Running Loop=2 2022-05-06T21:59:31.210Z,1651874371.210 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-05-06T21:59:31.210Z,1651874371.210 [Default:CheckIn:Read_GPS] Running Loop=1 2022-05-06T21:59:32.227Z,1651874372.227 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -55 millimeter 2022-05-06T22:00:05.788Z,1651874405.788 [CommandExec](IMPORTANT): got command configSet MassServo.limitLo -473367 count persist 2022-05-06T22:00:06.426Z,1651874406.426 [Reporter](INFO): platform_mass_position -0.041864 m 2022-05-06T22:00:06.790Z,1651874406.790 [Reporter](INFO): platform_mass_position -0.042154 m 2022-05-06T22:00:07.210Z,1651874407.210 [Reporter](INFO): platform_mass_position -0.042473 m 2022-05-06T22:00:07.626Z,1651874407.626 [Reporter](INFO): platform_mass_position -0.042806 m 2022-05-06T22:00:08.062Z,1651874408.062 [Reporter](INFO): platform_mass_position -0.043144 m 2022-05-06T22:00:08.448Z,1651874408.448 [MassServo](FAULT): Reading outside of valid range:-473399.000000 2022-05-06T22:00:08.846Z,1651874408.846 [Reporter](INFO): platform_mass_position -0.043421 m 2022-05-06T22:00:23.522Z,1651874423.522 [CommandExec](IMPORTANT): got command configSet MassServo.limitLo -500000 count persist 2022-05-06T22:00:24.170Z,1651874424.170 [Reporter](INFO): platform_mass_position -0.043686 m 2022-05-06T22:00:24.630Z,1651874424.630 [Reporter](INFO): platform_mass_position -0.044016 m 2022-05-06T22:00:24.994Z,1651874424.994 [Reporter](INFO): platform_mass_position -0.044332 m 2022-05-06T22:00:25.395Z,1651874425.395 [Reporter](INFO): platform_mass_position -0.044647 m 2022-05-06T22:00:25.800Z,1651874425.800 [Reporter](INFO): platform_mass_position -0.044952 m 2022-05-06T22:00:26.210Z,1651874426.210 [Reporter](INFO): platform_mass_position -0.045284 m 2022-05-06T22:00:26.614Z,1651874426.614 [Reporter](INFO): platform_mass_position -0.045602 m 2022-05-06T22:00:27.054Z,1651874427.054 [Reporter](INFO): platform_mass_position -0.045863 m 2022-05-06T22:00:27.442Z,1651874427.442 [Reporter](INFO): platform_mass_position -0.045864 m 2022-05-06T22:00:29.864Z,1651874429.864 [CBIT](INFO): Clearing failed state for component DropWeight 2022-05-06T22:00:29.864Z,1651874429.864 [DropWeight] No Fault, FailCount= 1 2022-05-06T22:00:33.022Z,1651874433.022 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2022-05-06T22:00:33.022Z,1651874433.022 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-06T22:00:33.089Z,1651874433.089 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-06T22:00:33.487Z,1651874433.487 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-06T22:00:33.487Z,1651874433.487 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2022-05-06T22:01:07.154Z,1651874467.154 [CommandExec](IMPORTANT): got command configSet MassServo.limitLo -510000 count persist 2022-05-06T22:01:07.798Z,1651874467.798 [Reporter](INFO): platform_mass_position -0.046102 m 2022-05-06T22:01:08.214Z,1651874468.214 [Reporter](INFO): platform_mass_position -0.046428 m 2022-05-06T22:01:08.632Z,1651874468.632 [Reporter](INFO): platform_mass_position -0.046754 m 2022-05-06T22:01:09.070Z,1651874469.070 [Reporter](INFO): platform_mass_position -0.046781 m 2022-05-06T22:01:27.903Z,1651874487.903 [CommandExec](IMPORTANT): got command configSet MassServo.limitLo -550000 count persist 2022-05-06T22:01:28.435Z,1651874488.435 [Reporter](INFO): platform_mass_position -0.047046 m 2022-05-06T22:01:28.809Z,1651874488.809 [Reporter](INFO): platform_mass_position -0.047342 m 2022-05-06T22:01:29.221Z,1651874489.221 [Reporter](INFO): platform_mass_position -0.047665 m 2022-05-06T22:01:29.643Z,1651874489.643 [Reporter](INFO): platform_mass_position -0.048004 m 2022-05-06T22:01:30.035Z,1651874490.035 [Reporter](INFO): platform_mass_position -0.048311 m 2022-05-06T22:01:30.450Z,1651874490.450 [Reporter](INFO): platform_mass_position -0.048636 m 2022-05-06T22:01:30.834Z,1651874490.834 [Reporter](INFO): platform_mass_position -0.048926 m 2022-05-06T22:01:31.230Z,1651874491.230 [Reporter](INFO): platform_mass_position -0.049248 m 2022-05-06T22:01:31.665Z,1651874491.665 [Reporter](INFO): platform_mass_position -0.049580 m 2022-05-06T22:01:32.062Z,1651874492.062 [Reporter](INFO): platform_mass_position -0.049900 m 2022-05-06T22:01:32.498Z,1651874492.498 [Reporter](INFO): platform_mass_position -0.050235 m 2022-05-06T22:01:32.870Z,1651874492.870 [Reporter](INFO): platform_mass_position -0.050450 m 2022-05-06T22:02:04.504Z,1651874524.504 [CommandExec](IMPORTANT): got command configSet MassServo.limitLo -560000 count persist 2022-05-06T22:02:05.203Z,1651874525.203 [Reporter](INFO): platform_mass_position -0.050722 m 2022-05-06T22:02:05.622Z,1651874525.622 [Reporter](INFO): platform_mass_position -0.051054 m 2022-05-06T22:02:05.990Z,1651874525.990 [Reporter](INFO): platform_mass_position -0.051334 m 2022-05-06T22:02:06.422Z,1651874526.422 [Reporter](INFO): platform_mass_position -0.051367 m 2022-05-06T22:02:16.311Z,1651874536.311 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 50 millimeter 2022-05-06T22:02:16.890Z,1651874536.890 [Reporter](INFO): platform_mass_position -0.051106 m 2022-05-06T22:02:17.299Z,1651874537.299 [Reporter](INFO): platform_mass_position -0.050784 m 2022-05-06T22:02:17.716Z,1651874537.716 [Reporter](INFO): platform_mass_position -0.050457 m 2022-05-06T22:02:18.138Z,1651874538.138 [Reporter](INFO): platform_mass_position -0.050120 m 2022-05-06T22:02:18.507Z,1651874538.507 [Reporter](INFO): platform_mass_position -0.049828 m 2022-05-06T22:02:18.899Z,1651874538.899 [Reporter](INFO): platform_mass_position -0.049520 m 2022-05-06T22:02:19.314Z,1651874539.314 [Reporter](INFO): platform_mass_position -0.049196 m 2022-05-06T22:02:19.726Z,1651874539.726 [Reporter](INFO): platform_mass_position -0.048891 m 2022-05-06T22:02:20.118Z,1651874540.118 [Reporter](INFO): platform_mass_position -0.048573 m 2022-05-06T22:02:20.538Z,1651874540.538 [Reporter](INFO): platform_mass_position -0.048237 m 2022-05-06T22:02:20.974Z,1651874540.974 [Reporter](INFO): platform_mass_position -0.047895 m 2022-05-06T22:02:21.378Z,1651874541.378 [Reporter](INFO): platform_mass_position -0.047584 m 2022-05-06T22:02:21.752Z,1651874541.752 [Reporter](INFO): platform_mass_position -0.047295 m 2022-05-06T22:02:22.142Z,1651874542.142 [Reporter](INFO): platform_mass_position -0.046980 m 2022-05-06T22:02:22.542Z,1651874542.542 [Reporter](INFO): platform_mass_position -0.046671 m 2022-05-06T22:02:22.951Z,1651874542.951 [Reporter](INFO): platform_mass_position -0.046351 m 2022-05-06T22:02:23.362Z,1651874543.362 [Reporter](INFO): platform_mass_position -0.046031 m 2022-05-06T22:02:23.775Z,1651874543.775 [Reporter](INFO): platform_mass_position -0.045707 m 2022-05-06T22:02:24.170Z,1651874544.170 [Reporter](INFO): platform_mass_position -0.045389 m 2022-05-06T22:02:24.606Z,1651874544.606 [Reporter](INFO): platform_mass_position -0.045053 m 2022-05-06T22:02:25.016Z,1651874545.016 [Reporter](INFO): platform_mass_position -0.044729 m 2022-05-06T22:02:25.385Z,1651874545.385 [Reporter](INFO): platform_mass_position -0.044443 m 2022-05-06T22:02:25.827Z,1651874545.827 [Reporter](INFO): platform_mass_position -0.044090 m 2022-05-06T22:02:26.177Z,1651874546.177 [Reporter](INFO): platform_mass_position -0.043822 m 2022-05-06T22:02:26.654Z,1651874546.654 [Reporter](INFO): platform_mass_position -0.043446 m 2022-05-06T22:02:26.915Z,1651874546.915 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -50 millimeter 2022-05-06T22:02:27.038Z,1651874547.038 [Reporter](INFO): platform_mass_position -0.043160 m 2022-05-06T22:02:27.454Z,1651874547.454 [Reporter](INFO): platform_mass_position -0.043124 m 2022-05-06T22:02:27.813Z,1651874547.813 [Reporter](INFO): platform_mass_position -0.043375 m 2022-05-06T22:02:28.222Z,1651874548.222 [Reporter](INFO): platform_mass_position -0.043704 m 2022-05-06T22:02:28.654Z,1651874548.654 [Reporter](INFO): platform_mass_position -0.044042 m 2022-05-06T22:02:29.035Z,1651874549.035 [Reporter](INFO): platform_mass_position -0.044333 m 2022-05-06T22:02:29.438Z,1651874549.438 [Reporter](INFO): platform_mass_position -0.044657 m 2022-05-06T22:02:29.826Z,1651874549.826 [Reporter](INFO): platform_mass_position -0.044950 m 2022-05-06T22:02:30.241Z,1651874550.241 [Reporter](INFO): platform_mass_position -0.045270 m 2022-05-06T22:02:30.654Z,1651874550.654 [Reporter](INFO): platform_mass_position -0.045598 m 2022-05-06T22:02:31.072Z,1651874551.072 [Reporter](INFO): platform_mass_position -0.045933 m 2022-05-06T22:02:31.450Z,1651874551.450 [Reporter](INFO): platform_mass_position -0.046229 m 2022-05-06T22:02:31.831Z,1651874551.831 [Reporter](INFO): platform_mass_position -0.046532 m 2022-05-06T22:02:32.255Z,1651874552.255 [Reporter](INFO): platform_mass_position -0.046856 m 2022-05-06T22:02:32.662Z,1651874552.662 [Reporter](INFO): platform_mass_position -0.047184 m 2022-05-06T22:02:33.058Z,1651874553.058 [Reporter](INFO): platform_mass_position -0.047492 m 2022-05-06T22:02:33.471Z,1651874553.471 [Reporter](INFO): platform_mass_position -0.047818 m 2022-05-06T22:02:33.858Z,1651874553.858 [Reporter](INFO): platform_mass_position -0.048121 m 2022-05-06T22:02:34.254Z,1651874554.254 [Reporter](INFO): platform_mass_position -0.048426 m 2022-05-06T22:02:34.662Z,1651874554.662 [Reporter](INFO): platform_mass_position -0.048743 m 2022-05-06T22:02:35.078Z,1651874555.078 [Reporter](INFO): platform_mass_position -0.049070 m 2022-05-06T22:02:35.474Z,1651874555.474 [Reporter](INFO): platform_mass_position -0.049387 m 2022-05-06T22:02:35.922Z,1651874555.922 [Reporter](INFO): platform_mass_position -0.049737 m 2022-05-06T22:02:36.303Z,1651874556.303 [Reporter](INFO): platform_mass_position -0.050002 m 2022-05-06T22:02:46.686Z,1651874566.686 [CommandExec](IMPORTANT): got command maintain clear 2022-05-06T22:02:46.769Z,1651874566.769 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-05-06T22:02:49.202Z,1651874569.202 [Reporter](INFO): platform_mass_position -0.049757 m 2022-05-06T22:02:49.626Z,1651874569.626 [Reporter](INFO): platform_mass_position -0.049424 m 2022-05-06T22:02:50.029Z,1651874570.029 [Reporter](INFO): platform_mass_position -0.049105 m 2022-05-06T22:02:50.438Z,1651874570.438 [Reporter](INFO): platform_mass_position -0.048792 m 2022-05-06T22:02:50.810Z,1651874570.810 [Reporter](INFO): platform_mass_position -0.048493 m 2022-05-06T22:02:51.234Z,1651874571.234 [Reporter](INFO): platform_mass_position -0.048163 m 2022-05-06T22:02:51.654Z,1651874571.654 [Reporter](INFO): platform_mass_position -0.047832 m 2022-05-06T22:02:52.057Z,1651874572.057 [Reporter](INFO): platform_mass_position -0.047522 m 2022-05-06T22:02:52.454Z,1651874572.454 [Reporter](INFO): platform_mass_position -0.047202 m 2022-05-06T22:02:52.837Z,1651874572.837 [Reporter](INFO): platform_mass_position -0.046910 m 2022-05-06T22:02:53.257Z,1651874573.257 [Reporter](INFO): platform_mass_position -0.046586 m 2022-05-06T22:02:53.673Z,1651874573.673 [Reporter](INFO): platform_mass_position -0.046255 m 2022-05-06T22:02:54.098Z,1651874574.098 [Reporter](INFO): platform_mass_position -0.045920 m 2022-05-06T22:02:54.486Z,1651874574.486 [Reporter](INFO): platform_mass_position -0.045610 m 2022-05-06T22:02:54.871Z,1651874574.871 [Reporter](INFO): platform_mass_position -0.045319 m 2022-05-06T22:02:55.270Z,1651874575.270 [Reporter](INFO): platform_mass_position -0.044995 m 2022-05-06T22:02:55.666Z,1651874575.666 [Reporter](INFO): platform_mass_position -0.044691 m 2022-05-06T22:02:56.077Z,1651874576.077 [Reporter](INFO): platform_mass_position -0.044376 m 2022-05-06T22:02:56.498Z,1651874576.498 [Reporter](INFO): platform_mass_position -0.044046 m 2022-05-06T22:02:56.906Z,1651874576.906 [Reporter](INFO): platform_mass_position -0.043722 m 2022-05-06T22:02:57.302Z,1651874577.302 [Reporter](INFO): platform_mass_position -0.043403 m 2022-05-06T22:02:57.718Z,1651874577.718 [Reporter](INFO): platform_mass_position -0.043077 m 2022-05-06T22:02:58.099Z,1651874578.099 [Reporter](INFO): platform_mass_position -0.042789 m 2022-05-06T22:02:58.498Z,1651874578.498 [Reporter](INFO): platform_mass_position -0.042465 m 2022-05-06T22:02:58.919Z,1651874578.919 [Reporter](INFO): platform_mass_position -0.042137 m 2022-05-06T22:02:59.299Z,1651874579.299 [Reporter](INFO): platform_mass_position -0.041844 m 2022-05-06T22:02:59.743Z,1651874579.743 [Reporter](INFO): platform_mass_position -0.041508 m 2022-05-06T22:03:00.146Z,1651874580.146 [Reporter](INFO): platform_mass_position -0.041180 m 2022-05-06T22:03:00.549Z,1651874580.549 [Reporter](INFO): platform_mass_position -0.040866 m 2022-05-06T22:03:00.946Z,1651874580.946 [Reporter](INFO): platform_mass_position -0.040546 m 2022-05-06T22:03:01.367Z,1651874581.367 [Reporter](INFO): platform_mass_position -0.040220 m 2022-05-06T22:03:01.749Z,1651874581.749 [Reporter](INFO): platform_mass_position -0.039923 m 2022-05-06T22:03:02.156Z,1651874582.156 [Reporter](INFO): platform_mass_position -0.039603 m 2022-05-06T22:03:02.571Z,1651874582.571 [Reporter](INFO): platform_mass_position -0.039276 m 2022-05-06T22:03:02.993Z,1651874582.993 [Reporter](INFO): platform_mass_position -0.038950 m 2022-05-06T22:03:03.390Z,1651874583.390 [Reporter](INFO): platform_mass_position -0.038630 m 2022-05-06T22:03:03.762Z,1651874583.762 [Reporter](INFO): platform_mass_position -0.038339 m 2022-05-06T22:03:04.162Z,1651874584.162 [Reporter](INFO): platform_mass_position -0.038030 m 2022-05-06T22:03:04.570Z,1651874584.570 [Reporter](INFO): platform_mass_position -0.037715 m 2022-05-06T22:03:04.967Z,1651874584.967 [Reporter](INFO): platform_mass_position -0.037394 m 2022-05-06T22:03:05.362Z,1651874585.362 [Reporter](INFO): platform_mass_position -0.037091 m 2022-05-06T22:03:05.809Z,1651874585.809 [Reporter](INFO): platform_mass_position -0.036743 m 2022-05-06T22:03:06.198Z,1651874586.198 [Reporter](INFO): platform_mass_position -0.036436 m 2022-05-06T22:03:06.607Z,1651874586.607 [Reporter](INFO): platform_mass_position -0.036116 m 2022-05-06T22:03:06.971Z,1651874586.971 [Reporter](INFO): platform_mass_position -0.035823 m 2022-05-06T22:03:07.386Z,1651874587.386 [Reporter](INFO): platform_mass_position -0.035503 m 2022-05-06T22:03:07.533Z,1651874587.533 [CommandExec](IMPORTANT): got command report clear 2022-05-06T22:03:10.342Z,1651874590.342 [CommandExec](IMPORTANT): got command show variable limiHi 2022-05-06T22:03:15.982Z,1651874595.982 [CommandExec](IMPORTANT): got command show variable limitHi 2022-05-06T22:03:15.994Z,1651874595.994 [CommandExec](IMPORTANT): VerticalControl.buoyancyLimitHiCC (cubic_centimeter) 2022-05-06T22:03:16.039Z,1651874596.039 [CommandExec](IMPORTANT): BuoyancyServo.limitHi (count) 2022-05-06T22:03:16.041Z,1651874596.041 [CommandExec](IMPORTANT): DockingServo.limitHi (count) 2022-05-06T22:03:16.042Z,1651874596.042 [CommandExec](IMPORTANT): ElevatorServo.limitHi (count) 2022-05-06T22:03:16.043Z,1651874596.043 [CommandExec](IMPORTANT): MassServo.limitHi (count) 2022-05-06T22:03:16.044Z,1651874596.044 [CommandExec](IMPORTANT): RudderServo.limitHi (count) 2022-05-06T22:03:16.110Z,1651874596.110 [CommandExec](IMPORTANT): VeritcalControl.buoyancyLimitHiCC (cubic_centimeter) 2022-05-06T22:03:21.720Z,1651874601.720 [CommandExec](IMPORTANT): got command get MassServo.limitHi 2022-05-06T22:03:21.721Z,1651874601.721 [CommandExec](IMPORTANT): MassServo.limitHi 427054 count 2022-05-06T22:03:34.021Z,1651874614.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2022-05-06T22:03:34.021Z,1651874614.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-06T22:03:34.085Z,1651874614.085 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-06T22:03:34.468Z,1651874614.468 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-06T22:03:34.469Z,1651874614.469 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2022-05-06T22:04:31.402Z,1651874671.402 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-05-06T21:59:31.2Z 2022-05-06T22:04:31.403Z,1651874671.403 [Default:CheckIn:Read_GPS] Stopped 2022-05-06T22:04:31.403Z,1651874671.403 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-05-06T22:04:38.683Z,1651874678.683 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220506T214519/Courier0007.lzma 2022-05-06T22:04:39.686Z,1651874679.686 [DataOverHttps](INFO): Moved sent file to Logs/20220506T214519/Courier0007.lzma.bak 2022-05-06T22:04:39.686Z,1651874679.686 [DataOverHttps](INFO): SBD MOMSN=16727280 2022-05-06T22:04:57.474Z,1651874697.474 [DataOverHttps](INFO): Sending 1344 bytes from file Logs/20220506T214519/Express0008.lzma 2022-05-06T22:04:58.474Z,1651874698.474 [DataOverHttps](INFO): Moved sent file to Logs/20220506T214519/Express0008.lzma.bak 2022-05-06T22:04:58.474Z,1651874698.474 [DataOverHttps](INFO): SBD MOMSN=16727282 2022-05-06T22:05:00.879Z,1651874700.879 [Default:CheckIn:Read_Iridium] Stopped 2022-05-06T22:05:00.879Z,1651874700.879 [Default:CheckIn:C.Wait] Running Loop=1 2022-05-06T22:05:00.879Z,1651874700.879 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-05-06T22:06:01.624Z,1651874761.624 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2022-05-06T22:06:01.625Z,1651874761.625 [DAT](INFO): commRate: 800 2022-05-06T22:06:03.388Z,1651874763.388 [DAT](INFO): Received command: PbubL 2022-05-06T22:06:03.445Z,1651874763.445 [CommandExec](IMPORTANT): got command burn 2022-05-06T22:06:03.445Z,1651874763.445 [CommandExec](FAULT): Incomplete syntax. Try: help burn 2022-05-06T22:06:35.019Z,1651874795.019 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2022-05-06T22:06:35.019Z,1651874795.019 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-06T22:06:35.029Z,1651874795.029 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-06T22:06:35.457Z,1651874795.457 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-06T22:06:35.457Z,1651874795.457 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2022-05-06T22:06:53.566Z,1651874813.566 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-05-06T22:06:53.566Z,1651874813.566 [NAL9602] Data Fault, FailCount= 2 2022-05-06T22:06:53.566Z,1651874813.566 [NAL9602](ERROR): Data Fault 2022-05-06T22:06:53.588Z,1651874813.588 [CBIT](ERROR): Data Fault in component: NAL9602 2022-05-06T22:06:53.970Z,1651874813.970 [NAL9602](INFO): Powering down 2022-05-06T22:06:54.810Z,1651874814.810 [CBIT](INFO): Clearing failed state for component NAL9602 2022-05-06T22:06:54.811Z,1651874814.811 [NAL9602] No Fault, FailCount= 2 2022-05-06T22:07:24.274Z,1651874844.274 [NAL9602](INFO): Powering up NAL9602 2022-05-06T22:07:35.178Z,1651874855.178 [NAL9602](INFO): NAL9602 initialized 2022-05-06T22:08:30.126Z,1651874910.126 [DVL_micro](ERROR): only read 2 of 4 data items 2022-05-06T22:08:30.126Z,1651874910.126 [DVL_micro](ERROR): Failed to parse: :BI,04274,+0090,I 2022-05-06T22:08:44.776Z,1651874924.776 [CBIT](CRITICAL): Environmental Failure. Press:14.759327 PSI. Humidity:47%. Temp:24 C. ABORTING MISSION 2022-05-06T22:08:45.156Z,1651874925.156 [CBIT](INFO): Critical error at 20220506T220844 2022-05-06T22:09:03.177Z,1651874943.177 [DAT](INFO): unknown deviceResponse_: Lowpower 2022-05-06T22:09:36.008Z,1651874976.008 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2022-05-06T22:09:36.008Z,1651874976.008 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-06T22:09:36.018Z,1651874976.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-06T22:09:36.432Z,1651874976.432 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-06T22:09:36.432Z,1651874976.432 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2022-05-06T22:10:01.466Z,1651875001.466 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-05-06T22:10:01.466Z,1651875001.466 [Default:CheckIn:C.Wait] Stopped 2022-05-06T22:10:01.466Z,1651875001.466 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-05-06T22:10:01.466Z,1651875001.466 [Default:CheckIn:D] Running Loop=1 2022-05-06T22:10:01.888Z,1651875001.888 [Default:CheckIn:D] Stopped 2022-05-06T22:10:01.888Z,1651875001.888 [Default:CheckIn:E] Running Loop=1 2022-05-06T22:10:02.286Z,1651875002.286 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.285380 min 2022-05-06T22:10:02.287Z,1651875002.287 [Default:CheckIn:E] Stopped 2022-05-06T22:10:02.287Z,1651875002.287 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-05-06T22:10:02.287Z,1651875002.287 [Default:CheckIn] Stopped 2022-05-06T22:10:02.287Z,1651875002.287 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-05-06T22:10:02.287Z,1651875002.287 [Default:CheckIn](INFO): Running loop #3 2022-05-06T22:10:02.287Z,1651875002.287 [Default:CheckIn] Running Loop=3 2022-05-06T22:10:02.287Z,1651875002.287 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-05-06T22:10:02.287Z,1651875002.287 [Default:CheckIn:Read_GPS] Running Loop=1 2022-05-06T22:12:37.006Z,1651875157.006 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2022-05-06T22:12:37.006Z,1651875157.006 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-05-06T22:12:37.016Z,1651875157.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-05-06T22:12:37.435Z,1651875157.435 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-05-06T22:12:37.435Z,1651875157.435 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2022-05-06T22:13:12.948Z,1651875192.948 [DVL_micro](ERROR): Failed to parse: :T00000000,36,0000.0,00 2022-05-06T22:14:46.721Z,1651875286.721 [CommandExec](IMPORTANT): got command restart application 2022-05-06T22:14:47.724Z,1651875287.724 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:47.724Z,1651875287.724 [CommandExec](INFO): Uninitializing the command executive. 2022-05-06T22:14:47.724Z,1651875287.724 [CommandExec](INFO): Uninitializing the command scheduler. 2022-05-06T22:14:47.725Z,1651875287.725 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:47.804Z,1651875287.804 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-05-06T22:14:47.804Z,1651875287.804 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-05-06T22:14:47.804Z,1651875287.804 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:47.805Z,1651875287.805 [NavChartDb](INFO): Join timeout helper Thread ID is 2029 2022-05-06T22:14:48.092Z,1651875288.092 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:48.092Z,1651875288.092 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.104Z,1651875288.104 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-05-06T22:14:48.104Z,1651875288.104 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.105Z,1651875288.105 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2030 2022-05-06T22:14:48.145Z,1651875288.145 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:48.145Z,1651875288.145 [WetLabsBB2FL](INFO): Powering down 2022-05-06T22:14:48.146Z,1651875288.146 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.147Z,1651875288.147 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-05-06T22:14:48.147Z,1651875288.147 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.147Z,1651875288.147 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2031 2022-05-06T22:14:48.228Z,1651875288.228 [CTD_Seabird](INFO): Powering down 2022-05-06T22:14:48.240Z,1651875288.240 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:48.240Z,1651875288.240 [CTD_Seabird](INFO): Powering down 2022-05-06T22:14:48.252Z,1651875288.252 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.252Z,1651875288.252 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2022-05-06T22:14:48.252Z,1651875288.252 [DAT ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.253Z,1651875288.253 [DAT](INFO): Join timeout helper Thread ID is 2032 2022-05-06T22:14:48.396Z,1651875288.396 [DAT](INFO): Powering down 2022-05-06T22:14:48.469Z,1651875288.469 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:48.469Z,1651875288.469 [DAT](INFO): Powering down 2022-05-06T22:14:48.470Z,1651875288.470 [DAT ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.476Z,1651875288.476 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-05-06T22:14:48.476Z,1651875288.476 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.477Z,1651875288.477 [Radio_Surface](INFO): Join timeout helper Thread ID is 2033 2022-05-06T22:14:48.856Z,1651875288.856 [Radio_Surface](INFO): Powering down 2022-05-06T22:14:48.857Z,1651875288.857 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:48.858Z,1651875288.858 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.865Z,1651875288.865 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-05-06T22:14:48.865Z,1651875288.865 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:48.865Z,1651875288.865 [Onboard](INFO): Join timeout helper Thread ID is 2034 2022-05-06T22:14:49.188Z,1651875289.188 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-05-06T22:14:50.316Z,1651875290.316 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:50.317Z,1651875290.317 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:50.335Z,1651875290.335 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-05-06T22:14:50.335Z,1651875290.335 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:50.336Z,1651875290.336 [DataOverHttps](INFO): Join timeout helper Thread ID is 2035 2022-05-06T22:14:51.197Z,1651875291.197 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:51.197Z,1651875291.197 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.201Z,1651875291.201 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2022-05-06T22:14:51.201Z,1651875291.201 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.201Z,1651875291.201 [BackseatComponent](INFO): Join timeout helper Thread ID is 2036 2022-05-06T22:14:51.284Z,1651875291.284 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:51.285Z,1651875291.284 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.305Z,1651875291.305 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-05-06T22:14:51.305Z,1651875291.305 [logger ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.305Z,1651875291.305 [logger](INFO): Join timeout helper Thread ID is 2037 2022-05-06T22:14:51.314Z,1651875291.314 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:51.315Z,1651875291.315 [logger ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.316Z,1651875291.316 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-05-06T22:14:51.316Z,1651875291.316 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.316Z,1651875291.316 [CommandLine](INFO): Join timeout helper Thread ID is 2038 2022-05-06T22:14:51.376Z,1651875291.376 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:51.377Z,1651875291.377 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.397Z,1651875291.397 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-05-06T22:14:51.397Z,1651875291.397 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.397Z,1651875291.397 [CommandExec](INFO): Join timeout helper Thread ID is 2039 2022-05-06T22:14:51.398Z,1651875291.398 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-05-06T22:14:51.398Z,1651875291.398 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.399Z,1651875291.399 [controlThread](INFO): Join timeout helper Thread ID is 2040 2022-05-06T22:14:51.512Z,1651875291.512 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-05-06T22:14:51.512Z,1651875291.512 [controlThread](DEBUG): Uninitializing ControlThread 2022-05-06T22:14:51.513Z,1651875291.513 [AHRS_M2](INFO): Powering down 2022-05-06T22:14:51.656Z,1651875291.656 [DVL_micro](INFO): Powering down 2022-05-06T22:14:51.657Z,1651875291.657 [NAL9602](INFO): Powering down 2022-05-06T22:14:51.660Z,1651875291.660 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-05-06T22:14:51.661Z,1651875291.661 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-05-06T22:14:51.661Z,1651875291.661 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-05-06T22:14:51.662Z,1651875291.662 [MissionManager](INFO): Uninitializing Mission Default 2022-05-06T22:14:51.662Z,1651875291.662 [Default] Stopped 2022-05-06T22:14:51.662Z,1651875291.662 [Default](DEBUG): Aggregate::uninitialize Default 2022-05-06T22:14:51.662Z,1651875291.662 [Default:B.GoToSurface] Stopped 2022-05-06T22:14:51.662Z,1651875291.662 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-05-06T22:14:51.662Z,1651875291.662 [Default:CheckIn] Stopped 2022-05-06T22:14:51.662Z,1651875291.662 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-05-06T22:14:51.662Z,1651875291.662 [Default:CheckIn:Read_GPS] Stopped 2022-05-06T22:14:51.665Z,1651875291.665 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-05-06T22:14:51.666Z,1651875291.666 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-05-06T22:14:51.666Z,1651875291.666 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-05-06T22:14:51.666Z,1651875291.666 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-05-06T22:14:51.666Z,1651875291.666 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-05-06T22:14:51.666Z,1651875291.666 [BuoyancyServo](INFO): Powering down 2022-05-06T22:14:51.680Z,1651875291.680 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-05-06T22:14:51.680Z,1651875291.680 [ElevatorServo](INFO): Powering down 2022-05-06T22:14:51.681Z,1651875291.681 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-05-06T22:14:51.681Z,1651875291.681 [MassServo](INFO): Powering down 2022-05-06T22:14:51.682Z,1651875291.682 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-05-06T22:14:51.682Z,1651875291.682 [RudderServo](INFO): Powering down 2022-05-06T22:14:51.683Z,1651875291.683 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2022-05-06T22:14:51.683Z,1651875291.683 [ThrusterHE](INFO): Powering down 2022-05-06T22:14:51.684Z,1651875291.684 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-05-06T22:14:51.684Z,1651875291.684 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-05-06T22:14:51.685Z,1651875291.685 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-05-06T22:14:51.685Z,1651875291.685 [CBIT](DEBUG): Powering off loads. 2022-05-06T22:14:51.696Z,1651875291.696 [CBIT](DEBUG): Disabling WDT. 2022-05-06T22:14:51.708Z,1651875291.708 [CBIT](DEBUG): Opening all GF detection circuits. 2022-05-06T22:14:51.709Z,1651875291.709 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.751Z,1651875291.751 [DAT ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.756Z,1651875291.756 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.758Z,1651875291.758 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.765Z,1651875291.765 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.771Z,1651875291.771 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.820Z,1651875291.820 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.825Z,1651875291.825 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.847Z,1651875291.847 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-05-06T22:14:51.911Z,1651875291.911 [logger ThreadHandler](INFO): Thread cancelled.