2023-03-31T20:45:02.792Z,1680295502.792 [Supervisor](DEBUG): Initializing supervisor. 2023-03-31T20:45:02.797Z,1680295502.797 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-03-31T20:45:02.798Z,1680295502.798 [SyncHandler](INFO): Protected caller Thread ID is 2981 2023-03-31T20:45:02.798Z,1680295502.798 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-03-31T20:45:02.799Z,1680295502.799 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-03-31T20:45:02.799Z,1680295502.799 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2982 2023-03-31T20:45:02.804Z,1680295502.804 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-03-31T20:45:02.821Z,1680295502.821 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-03-31T20:45:02.822Z,1680295502.822 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-03-31T20:45:02.822Z,1680295502.822 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2983 2023-03-31T20:45:02.827Z,1680295502.827 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-03-31T20:45:02.828Z,1680295502.828 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-03-31T20:45:02.828Z,1680295502.828 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2984 2023-03-31T20:45:02.830Z,1680295502.830 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-03-31T20:45:02.831Z,1680295502.831 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-03-31T20:45:02.831Z,1680295502.831 [logger ThreadHandler](INFO): Protected caller Thread ID is 2985 2023-03-31T20:45:02.835Z,1680295502.835 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-03-31T20:45:02.835Z,1680295502.835 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-03-31T20:45:02.837Z,1680295502.837 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-03-31T20:45:03.046Z,1680295503.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-03-31T20:45:03.047Z,1680295503.047 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-03-31T20:45:03.152Z,1680295503.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-03-31T20:45:03.152Z,1680295503.152 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-03-31T20:45:03.245Z,1680295503.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-03-31T20:45:03.246Z,1680295503.246 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-03-31T20:45:03.694Z,1680295503.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-03-31T20:45:03.694Z,1680295503.694 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-03-31T20:45:04.033Z,1680295504.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-03-31T20:45:04.033Z,1680295504.033 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-03-31T20:45:04.358Z,1680295504.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-03-31T20:45:04.358Z,1680295504.358 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-03-31T20:45:04.536Z,1680295504.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-03-31T20:45:04.536Z,1680295504.536 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-03-31T20:45:04.614Z,1680295504.614 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-03-31T20:45:04.693Z,1680295504.693 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-03-31T20:45:05.078Z,1680295505.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-03-31T20:45:05.078Z,1680295505.078 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-03-31T20:45:05.356Z,1680295505.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-03-31T20:45:05.357Z,1680295505.357 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-03-31T20:45:05.859Z,1680295505.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-03-31T20:45:05.860Z,1680295505.860 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-03-31T20:45:06.438Z,1680295506.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-03-31T20:45:06.439Z,1680295506.439 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-03-31T20:45:06.674Z,1680295506.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-03-31T20:45:06.674Z,1680295506.674 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-03-31T20:45:06.810Z,1680295506.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-03-31T20:45:06.811Z,1680295506.811 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-03-31T20:45:07.565Z,1680295507.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-03-31T20:45:07.567Z,1680295507.567 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2023-03-31T20:45:07.568Z,1680295507.568 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2023-03-31T20:45:07.650Z,1680295507.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2023-03-31T20:45:07.823Z,1680295507.823 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2023-03-31T20:45:08.090Z,1680295508.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-03-31T20:45:08.090Z,1680295508.090 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2023-03-31T20:45:08.210Z,1680295508.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2023-03-31T20:45:08.307Z,1680295508.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2023-03-31T20:45:08.398Z,1680295508.398 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2023-03-31T20:45:08.502Z,1680295508.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2023-03-31T20:45:08.595Z,1680295508.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2023-03-31T20:45:08.679Z,1680295508.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2023-03-31T20:45:08.786Z,1680295508.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2023-03-31T20:45:08.941Z,1680295508.941 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2023-03-31T20:45:08.941Z,1680295508.941 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-03-31T20:45:08.951Z,1680295508.951 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-03-31T20:45:09.004Z,1680295509.004 [DepthRateCalculator] Loaded 2023-03-31T20:45:09.004Z,1680295509.004 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-03-31T20:45:09.010Z,1680295509.010 [PitchRateCalculator] Loaded 2023-03-31T20:45:09.010Z,1680295509.010 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-03-31T20:45:09.020Z,1680295509.020 [SpeedCalculator] Loaded 2023-03-31T20:45:09.020Z,1680295509.020 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-03-31T20:45:09.025Z,1680295509.025 [YawRateCalculator] Loaded 2023-03-31T20:45:09.026Z,1680295509.026 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-03-31T20:45:09.047Z,1680295509.047 [ElevatorOffsetCalculator] Loaded 2023-03-31T20:45:09.047Z,1680295509.047 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-03-31T20:45:09.047Z,1680295509.047 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-03-31T20:45:09.048Z,1680295509.048 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-03-31T20:45:09.075Z,1680295509.075 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-03-31T20:45:09.076Z,1680295509.076 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-03-31T20:45:09.086Z,1680295509.086 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-03-31T20:45:09.087Z,1680295509.087 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-03-31T20:45:09.554Z,1680295509.554 [AHRS_M2] Loaded 2023-03-31T20:45:09.554Z,1680295509.554 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-03-31T20:45:09.589Z,1680295509.589 [BackseatComponent] Loaded 2023-03-31T20:45:09.589Z,1680295509.589 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-03-31T20:45:09.591Z,1680295509.591 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408474E0 2023-03-31T20:45:09.591Z,1680295509.591 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3067 2023-03-31T20:45:09.594Z,1680295509.594 [LcmUniversalReporter] Loaded 2023-03-31T20:45:09.594Z,1680295509.594 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-03-31T20:45:10.411Z,1680295510.411 [BPC1] Loaded 2023-03-31T20:45:10.411Z,1680295510.411 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-03-31T20:45:10.530Z,1680295510.530 [DataOverHttps] Loaded 2023-03-31T20:45:10.531Z,1680295510.531 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-03-31T20:45:10.532Z,1680295510.532 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408774E0 2023-03-31T20:45:10.532Z,1680295510.532 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3068 2023-03-31T20:45:10.553Z,1680295510.553 [Depth_Keller] Loaded 2023-03-31T20:45:10.553Z,1680295510.553 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-03-31T20:45:10.558Z,1680295510.558 [DropWeight] Loaded 2023-03-31T20:45:10.558Z,1680295510.558 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-03-31T20:45:10.578Z,1680295510.578 [MultiRay] Loaded 2023-03-31T20:45:10.578Z,1680295510.578 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2023-03-31T20:45:10.641Z,1680295510.641 [NAL9602] Loaded 2023-03-31T20:45:10.641Z,1680295510.641 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-03-31T20:45:10.671Z,1680295510.671 [Onboard] Loaded 2023-03-31T20:45:10.671Z,1680295510.671 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-03-31T20:45:10.672Z,1680295510.672 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408A74E0 2023-03-31T20:45:10.673Z,1680295510.673 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3069 2023-03-31T20:45:10.679Z,1680295510.679 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2023-03-31T20:45:10.690Z,1680295510.690 [PowerOnly] Loaded 2023-03-31T20:45:10.690Z,1680295510.690 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-03-31T20:45:10.702Z,1680295510.702 [Power24vConverter] Loaded 2023-03-31T20:45:10.702Z,1680295510.702 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-03-31T20:45:10.717Z,1680295510.717 [Radio_Surface] Loaded 2023-03-31T20:45:10.718Z,1680295510.718 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-03-31T20:45:10.719Z,1680295510.719 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408D74E0 2023-03-31T20:45:10.719Z,1680295510.719 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3070 2023-03-31T20:45:10.742Z,1680295510.742 [Waterlinked] Loaded 2023-03-31T20:45:10.743Z,1680295510.743 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2023-03-31T20:45:10.743Z,1680295510.743 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-03-31T20:45:10.743Z,1680295510.743 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-03-31T20:45:10.856Z,1680295510.856 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-03-31T20:45:10.856Z,1680295510.856 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-03-31T20:45:10.898Z,1680295510.898 [DeadReckonUsingSpeedCalculator] Loaded 2023-03-31T20:45:10.898Z,1680295510.898 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-03-31T20:45:10.912Z,1680295510.912 [NavChart] Loaded 2023-03-31T20:45:10.912Z,1680295510.912 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-03-31T20:45:10.918Z,1680295510.918 [UniversalFixResidualReporter] Loaded 2023-03-31T20:45:10.918Z,1680295510.918 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-03-31T20:45:10.918Z,1680295510.918 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-03-31T20:45:10.919Z,1680295510.919 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-03-31T20:45:11.526Z,1680295511.526 [SBIT](DEBUG): Construct Startup Built In Test. 2023-03-31T20:45:11.612Z,1680295511.612 [SBIT] Loaded 2023-03-31T20:45:11.612Z,1680295511.612 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-03-31T20:45:11.615Z,1680295511.615 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-03-31T20:45:11.648Z,1680295511.648 [IBIT] Loaded 2023-03-31T20:45:11.649Z,1680295511.649 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-03-31T20:45:11.654Z,1680295511.654 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-03-31T20:45:11.757Z,1680295511.757 [CBIT] Loaded 2023-03-31T20:45:11.757Z,1680295511.757 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-03-31T20:45:11.758Z,1680295511.758 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-03-31T20:45:11.758Z,1680295511.758 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-03-31T20:45:12.113Z,1680295512.113 [CTD_Seabird] Loaded 2023-03-31T20:45:12.114Z,1680295512.114 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-03-31T20:45:12.115Z,1680295512.115 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0 2023-03-31T20:45:12.115Z,1680295512.115 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3071 2023-03-31T20:45:12.135Z,1680295512.135 [PAR_Licor] Loaded 2023-03-31T20:45:12.136Z,1680295512.136 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-03-31T20:45:12.167Z,1680295512.167 [WetLabsBB2FL] Loaded 2023-03-31T20:45:12.167Z,1680295512.167 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-03-31T20:45:12.168Z,1680295512.168 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0 2023-03-31T20:45:12.169Z,1680295512.169 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3072 2023-03-31T20:45:12.169Z,1680295512.169 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-03-31T20:45:12.170Z,1680295512.170 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-03-31T20:45:12.313Z,1680295512.313 [BuoyancyServo] Loaded 2023-03-31T20:45:12.314Z,1680295512.314 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-03-31T20:45:12.335Z,1680295512.335 [ElevatorServo] Loaded 2023-03-31T20:45:12.335Z,1680295512.335 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-03-31T20:45:12.356Z,1680295512.356 [MassServo] Loaded 2023-03-31T20:45:12.357Z,1680295512.357 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-03-31T20:45:12.378Z,1680295512.378 [RudderServo] Loaded 2023-03-31T20:45:12.378Z,1680295512.378 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-03-31T20:45:12.394Z,1680295512.394 [ThrusterHE] Loaded 2023-03-31T20:45:12.395Z,1680295512.395 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-03-31T20:45:12.395Z,1680295512.395 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-03-31T20:45:12.396Z,1680295512.396 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-03-31T20:45:12.451Z,1680295512.451 [VerticalControl](DEBUG): Construct VerticalControl. 2023-03-31T20:45:12.501Z,1680295512.501 [VerticalControl] Loaded 2023-03-31T20:45:12.501Z,1680295512.501 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-03-31T20:45:12.504Z,1680295512.504 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-03-31T20:45:12.541Z,1680295512.541 [HorizontalControl] Loaded 2023-03-31T20:45:12.541Z,1680295512.541 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-03-31T20:45:12.544Z,1680295512.544 [SpeedControl](DEBUG): Construct SpeedControl. 2023-03-31T20:45:12.545Z,1680295512.545 [SpeedControl] Loaded 2023-03-31T20:45:12.546Z,1680295512.546 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-03-31T20:45:12.549Z,1680295512.549 [LoopControl](DEBUG): Construct LoopControl. 2023-03-31T20:45:12.549Z,1680295512.549 [LoopControl] Loaded 2023-03-31T20:45:12.549Z,1680295512.549 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-03-31T20:45:12.550Z,1680295512.550 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-03-31T20:45:12.550Z,1680295512.550 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-03-31T20:45:12.655Z,1680295512.655 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-03-31T20:45:12.656Z,1680295512.656 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-03-31T20:45:12.819Z,1680295512.819 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-03-31T20:45:12.819Z,1680295512.819 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-03-31T20:45:13.151Z,1680295513.151 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-03-31T20:45:13.151Z,1680295513.151 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-03-31T20:45:13.211Z,1680295513.211 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-03-31T20:45:13.218Z,1680295513.218 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-03-31T20:45:13.220Z,1680295513.220 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-03-31T20:45:13.231Z,1680295513.231 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-03-31T20:45:13.232Z,1680295513.232 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BAE4E0 2023-03-31T20:45:13.233Z,1680295513.233 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3073 2023-03-31T20:45:13.237Z,1680295513.237 [Supervisor](INFO): Main Thread ID is 1756 2023-03-31T20:45:13.237Z,1680295513.237 [Supervisor](DEBUG): Running supervisor. 2023-03-31T20:45:13.238Z,1680295513.238 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3074 2023-03-31T20:45:13.238Z,1680295513.238 [CommandExec](INFO): Initializing the command executive. 2023-03-31T20:45:13.240Z,1680295513.240 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3075 2023-03-31T20:45:13.242Z,1680295513.242 [controlThread ThreadHandler](INFO): Handler Thread ID is 3076 2023-03-31T20:45:13.243Z,1680295513.243 [controlThread](DEBUG): Initializing ControlThread 2023-03-31T20:45:13.244Z,1680295513.244 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-03-31T20:45:13.244Z,1680295513.244 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-03-31T20:45:13.244Z,1680295513.244 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-03-31T20:45:13.245Z,1680295513.245 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-03-31T20:45:13.245Z,1680295513.245 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-03-31T20:45:13.251Z,1680295513.251 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T20:45:13.251Z,1680295513.251 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-03-31T20:45:13.252Z,1680295513.252 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-03-31T20:45:13.252Z,1680295513.252 [SBIT](INFO): Initialize SBIT Component. 2023-03-31T20:45:13.253Z,1680295513.253 [SBIT](IMPORTANT): git: 2023-03-31 2023-03-31T20:45:13.253Z,1680295513.253 [SBIT](INFO): git hash: cfd8cc3d23ebacdd66b2fa8c1315bb22f1803aa4 2023-03-31T20:45:13.253Z,1680295513.253 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-03-31T20:45:13.254Z,1680295513.254 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-03-31T20:45:13.255Z,1680295513.255 [SBIT](INFO): Beginning SBIT in 91.000000 seconds. 2023-03-31T20:45:13.256Z,1680295513.256 [IBIT](INFO): Initialize IBIT Component. 2023-03-31T20:45:13.257Z,1680295513.257 [CBIT](DEBUG): Initialize CBIT Component. 2023-03-31T20:45:13.258Z,1680295513.258 [logger ThreadHandler](INFO): Handler Thread ID is 3077 2023-03-31T20:45:13.268Z,1680295513.268 [CBIT](DEBUG): Initialized mux pins. 2023-03-31T20:45:13.268Z,1680295513.268 [CBIT](DEBUG): Initializing the watchdog timer. 2023-03-31T20:45:13.276Z,1680295513.276 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3078 2023-03-31T20:45:13.288Z,1680295513.288 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3079 2023-03-31T20:45:13.289Z,1680295513.289 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-03-31T20:45:13.292Z,1680295513.292 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-03-31T20:45:13.293Z,1680295513.293 [CBIT](DEBUG): Initializing heartbeat. 2023-03-31T20:45:13.300Z,1680295513.300 [Onboard ThreadHandler](INFO): Handler Thread ID is 3080 2023-03-31T20:45:13.324Z,1680295513.324 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3081 2023-03-31T20:45:13.341Z,1680295513.341 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3082 2023-03-31T20:45:13.342Z,1680295513.342 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-03-31T20:45:13.346Z,1680295513.346 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3084 2023-03-31T20:45:13.349Z,1680295513.349 [WetLabsBB2FL](INFO): Powering up 2023-03-31T20:45:13.350Z,1680295513.350 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3086 2023-03-31T20:45:13.352Z,1680295513.352 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-03-31T20:45:13.352Z,1680295513.352 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-03-31T20:45:13.352Z,1680295513.352 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-03-31T20:45:13.353Z,1680295513.353 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-03-31T20:45:13.353Z,1680295513.353 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-03-31T20:45:13.353Z,1680295513.353 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-03-31T20:45:13.353Z,1680295513.353 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-03-31T20:45:13.353Z,1680295513.353 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-03-31T20:45:13.364Z,1680295513.364 [CBIT](DEBUG): Deactivating GF circuits. 2023-03-31T20:45:13.364Z,1680295513.364 [CBIT](DEBUG): Deactivating emergency mode. 2023-03-31T20:45:13.404Z,1680295513.404 [CBIT](DEBUG): Backplane powered. 2023-03-31T20:45:13.410Z,1680295513.410 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-03-31T20:45:13.411Z,1680295513.411 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-03-31T20:45:13.412Z,1680295513.412 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-03-31T20:45:13.413Z,1680295513.413 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-03-31T20:45:13.413Z,1680295513.413 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-03-31T20:45:13.427Z,1680295513.427 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-03-31T20:45:13.468Z,1680295513.468 [MissionManager](DEBUG): 2023-03-31T20:45:13.469Z,1680295513.469 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-03-31T20:45:13.530Z,1680295513.530 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-03-31T20:45:13.531Z,1680295513.531 [Default:A.Wait](DEBUG): Construct Wait. 2023-03-31T20:45:13.533Z,1680295513.533 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-03-31T20:45:13.557Z,1680295513.557 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-03-31T20:45:13.559Z,1680295513.559 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-03-31T20:45:13.597Z,1680295513.597 [Default:E.Execute](DEBUG): Construct Execute. 2023-03-31T20:45:13.601Z,1680295513.601 [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 2023-03-31T20:45:13.622Z,1680295513.622 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-03-31T20:45:13.640Z,1680295513.640 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-03-31T20:45:13.715Z,1680295513.715 [MultiRay](INFO): Powering up MultiRay Lights 2023-03-31T20:45:13.728Z,1680295513.728 [Radio_Surface](INFO): Powering up 2023-03-31T20:45:13.749Z,1680295513.749 [Power24vConverter](INFO): Powering up. 2023-03-31T20:45:13.785Z,1680295513.785 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-03-31T20:45:13.792Z,1680295513.792 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-03-31T20:45:13.793Z,1680295513.793 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-03-31T20:45:13.800Z,1680295513.800 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-03-31T20:45:13.801Z,1680295513.801 [MassServo](DEBUG): Initializing EZServoServo. 2023-03-31T20:45:13.809Z,1680295513.809 [MassServo](DEBUG): Initializing MassServo. 2023-03-31T20:45:13.810Z,1680295513.810 [RudderServo](DEBUG): Initializing EZServoServo. 2023-03-31T20:45:13.817Z,1680295513.817 [RudderServo](DEBUG): Initializing RudderServo. 2023-03-31T20:45:13.818Z,1680295513.818 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-03-31T20:45:13.824Z,1680295513.824 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-03-31T20:45:14.060Z,1680295514.060 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-03-31T20:45:14.060Z,1680295514.060 [DropWeight] Hardware Fault, FailCount= 1 2023-03-31T20:45:14.060Z,1680295514.060 [DropWeight](ERROR): Hardware Fault 2023-03-31T20:45:14.122Z,1680295514.122 [CommandExec](FAULT): Scheduling is paused 2023-03-31T20:45:14.123Z,1680295514.123 [CBIT](INFO): Critical error at 20230331T204514 2023-03-31T20:45:14.123Z,1680295514.123 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-03-31T20:45:14.129Z,1680295514.129 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-03-31T20:45:14.129Z,1680295514.129 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-03-31T20:45:14.462Z,1680295514.462 [CBIT](INFO): Critical error at 20230331T204514 2023-03-31T20:45:15.960Z,1680295515.960 [WetLabsBB2FL](INFO): Powering down 2023-03-31T20:45:19.501Z,1680295519.501 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-03-31T20:45:20.372Z,1680295520.372 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-03-31T20:45:40.702Z,1680295540.702 [NAL9602](INFO): Powering up NAL9602 2023-03-31T20:45:41.923Z,1680295541.923 [Waterlinked](FAULT): Could not initialize. 2023-03-31T20:45:41.923Z,1680295541.923 [Waterlinked] Communications Fault, FailCount= 1 2023-03-31T20:45:41.923Z,1680295541.923 [Waterlinked](ERROR): Communications Fault 2023-03-31T20:45:41.965Z,1680295541.965 [CBIT](ERROR): Communications Fault in component: Waterlinked 2023-03-31T20:45:42.388Z,1680295542.388 [Waterlinked](INFO): Powering down 2023-03-31T20:45:43.184Z,1680295543.184 [CBIT](INFO): Clearing failed state for component Waterlinked 2023-03-31T20:45:43.184Z,1680295543.184 [Waterlinked] No Fault, FailCount= 1 2023-03-31T20:45:51.618Z,1680295551.618 [NAL9602](INFO): NAL9602 initialized 2023-03-31T20:46:17.556Z,1680295577.556 [Waterlinked](INFO): Powering down 2023-03-31T20:46:44.957Z,1680295604.957 [SBIT](IMPORTANT): Beginning Startup BIT 2023-03-31T20:46:44.962Z,1680295604.962 [CBIT](IMPORTANT): Beginning ground fault scan 2023-03-31T20:46:55.897Z,1680295615.897 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003540 CHAN A1 (24V): -0.000002 CHAN A2 (12V): -0.005279 CHAN A3 (5V): -0.002140 CHAN B0 (3.3V): -0.001001 CHAN B1 (3.15aV): -0.001132 CHAN B2 (3.15bV): -0.000988 CHAN B3 (GND): -0.000360 OPEN: 0.004897 Full Scale: +/- 1 mA 2023-03-31T20:47:39.093Z,1680295659.093 [SBIT](IMPORTANT): SBIT PASSED 2023-03-31T20:47:39.094Z,1680295659.094 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-03-31T20:47:39.094Z,1680295659.094 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2023-03-31T20:47:39.095Z,1680295659.095 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute; 2023-03-31T20:47:39.095Z,1680295659.095 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-03-31T20:47:39.095Z,1680295659.095 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-03-31T20:47:39.095Z,1680295659.095 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_shape 10.000000 count_per_second; 2023-03-31T20:47:39.095Z,1680295659.095 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_slope 0.200000 count_per_millimeter; 2023-03-31T20:47:39.095Z,1680295659.095 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=236.612869 cubic_centimeter; 2023-03-31T20:47:39.095Z,1680295659.095 [SBIT](IMPORTANT): VerticalControl.massDefault=17.883769 millimeter; 2023-03-31T20:47:39.510Z,1680295659.510 [MissionManager](IMPORTANT): Started mission Startup 2023-03-31T20:47:39.510Z,1680295659.510 [Startup] Running Loop=1 2023-03-31T20:47:39.510Z,1680295659.510 [Startup](DEBUG): Aggregate::initialize Startup 2023-03-31T20:47:39.511Z,1680295659.511 [Startup:A.GoToSurface] Running Loop=1 2023-03-31T20:47:39.511Z,1680295659.511 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-03-31T20:47:39.511Z,1680295659.511 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-03-31T20:47:39.512Z,1680295659.512 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-03-31T20:47:39.512Z,1680295659.512 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-03-31T20:47:39.513Z,1680295659.513 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-03-31T20:47:39.513Z,1680295659.513 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-03-31T20:47:39.514Z,1680295659.514 [Startup:StartupSatComms] Running Loop=1 2023-03-31T20:47:39.514Z,1680295659.514 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-03-31T20:47:39.514Z,1680295659.514 [Startup:StartupSatComms:A] Running Loop=1 2023-03-31T20:47:39.971Z,1680295659.971 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-03-31T20:47:40.094Z,1680295660.094 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005982 2023-03-31T20:48:13.462Z,1680295693.462 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-03-31T20:48:13.462Z,1680295693.462 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T20:48:13.471Z,1680295693.471 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-03-31T20:48:13.471Z,1680295693.471 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T20:48:13.535Z,1680295693.535 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T20:48:13.535Z,1680295693.535 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T20:48:13.875Z,1680295693.875 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T20:48:13.875Z,1680295693.875 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-03-31T20:48:13.876Z,1680295693.876 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T20:48:13.876Z,1680295693.876 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-03-31T20:48:14.236Z,1680295694.236 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T20:48:39.687Z,1680295719.687 [Startup:StartupSatComms:A](INFO): Timed out from 2023-03-31T20:47:39.5Z 2023-03-31T20:48:39.687Z,1680295719.687 [Startup:StartupSatComms:A] Stopped 2023-03-31T20:48:39.687Z,1680295719.687 [Startup:StartupSatComms:B] Running Loop=1 2023-03-31T20:48:40.092Z,1680295720.092 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-03-31T20:48:46.832Z,1680295726.832 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230331T203130/Courier0007.lzma 2023-03-31T20:48:47.834Z,1680295727.834 [DataOverHttps](INFO): Moved sent file to Logs/20230331T203130/Courier0007.lzma.bak 2023-03-31T20:48:47.834Z,1680295727.834 [DataOverHttps](INFO): SBD MOMSN=17884168 2023-03-31T20:49:03.448Z,1680295743.448 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20230331T204502/Courier0000.lzma 2023-03-31T20:49:04.450Z,1680295744.450 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Courier0000.lzma.bak 2023-03-31T20:49:04.450Z,1680295744.450 [DataOverHttps](INFO): SBD MOMSN=17884170 2023-03-31T20:49:19.944Z,1680295759.944 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20230331T203130/Express0008.lzma 2023-03-31T20:49:20.946Z,1680295760.946 [DataOverHttps](INFO): Moved sent file to Logs/20230331T203130/Express0008.lzma.bak 2023-03-31T20:49:20.946Z,1680295760.946 [DataOverHttps](INFO): SBD MOMSN=17884175 2023-03-31T20:49:36.386Z,1680295776.386 [DataOverHttps](INFO): Sending 893 bytes from file Logs/20230331T204502/Express0001.lzma 2023-03-31T20:49:37.386Z,1680295777.386 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Express0001.lzma.bak 2023-03-31T20:49:37.386Z,1680295777.386 [DataOverHttps](INFO): SBD MOMSN=17884179 2023-03-31T20:49:38.684Z,1680295778.684 [Startup:StartupSatComms:B] Stopped 2023-03-31T20:49:38.684Z,1680295778.684 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-03-31T20:49:38.684Z,1680295778.684 [Startup:StartupSatComms] Stopped 2023-03-31T20:49:38.685Z,1680295778.685 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-03-31T20:49:38.685Z,1680295778.685 [Startup](INFO): Completed Startup 2023-03-31T20:49:38.685Z,1680295778.685 [MissionManager](INFO): Startup is completed. 2023-03-31T20:49:38.686Z,1680295778.686 [MissionManager](INFO): Uninitializing Mission Startup 2023-03-31T20:49:38.686Z,1680295778.686 [Startup] Stopped 2023-03-31T20:49:38.686Z,1680295778.686 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-03-31T20:49:38.686Z,1680295778.686 [Startup:A.GoToSurface] Stopped 2023-03-31T20:49:38.686Z,1680295778.686 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-03-31T20:49:39.103Z,1680295779.103 [MissionManager](IMPORTANT): Started mission Default 2023-03-31T20:49:39.103Z,1680295779.103 [Default] Running Loop=1 2023-03-31T20:49:39.103Z,1680295779.103 [Default](DEBUG): Aggregate::initialize Default 2023-03-31T20:49:39.103Z,1680295779.103 [Default:B.GoToSurface] Running Loop=1 2023-03-31T20:49:39.103Z,1680295779.103 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-03-31T20:49:39.104Z,1680295779.104 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-03-31T20:49:39.104Z,1680295779.104 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-03-31T20:49:39.104Z,1680295779.104 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-03-31T20:49:39.105Z,1680295779.105 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-03-31T20:49:39.105Z,1680295779.105 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-03-31T20:49:39.105Z,1680295779.105 [Default:A.Wait] Running Loop=1 2023-03-31T20:49:39.105Z,1680295779.105 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-03-31T20:49:52.407Z,1680295792.407 [Default:A.Wait](INFO): Done Waiting. 2023-03-31T20:49:52.407Z,1680295792.407 [Default:A.Wait] Stopped 2023-03-31T20:49:52.407Z,1680295792.407 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-03-31T20:49:52.812Z,1680295792.812 [Default:CheckIn] Running Loop=1 2023-03-31T20:49:52.812Z,1680295792.812 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-31T20:49:52.812Z,1680295792.812 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-31T20:49:53.275Z,1680295793.275 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-03-31T20:50:21.033Z,1680295821.033 [DataOverHttps](IMPORTANT): SBD MTMSN=20230331T205020 2023-03-31T20:50:28.292Z,1680295828.292 [DataOverHttps](INFO): Received command: burn on 2023-03-31T20:50:28.305Z,1680295828.305 [CommandExec](IMPORTANT): got command burn on 2023-03-31T20:50:28.305Z,1680295828.305 [CommandExec](IMPORTANT): Activating dropweight wire 2023-03-31T20:50:55.027Z,1680295855.027 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-03-31T20:51:04.878Z,1680295864.878 [DataOverHttps](IMPORTANT): SBD MTMSN=20230331T205103 2023-03-31T20:51:12.152Z,1680295872.152 [DataOverHttps](INFO): Received command: burn off 2023-03-31T20:51:12.166Z,1680295872.166 [CommandExec](IMPORTANT): got command burn off 2023-03-31T20:51:12.166Z,1680295872.166 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-03-31T20:51:14.428Z,1680295874.428 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-03-31T20:51:14.428Z,1680295874.428 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T20:51:14.429Z,1680295874.429 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-03-31T20:51:14.429Z,1680295874.429 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T20:51:14.441Z,1680295874.441 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T20:51:14.441Z,1680295874.441 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T20:51:14.858Z,1680295874.858 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T20:51:14.858Z,1680295874.858 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-03-31T20:51:14.858Z,1680295874.858 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T20:51:14.858Z,1680295874.858 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-03-31T20:51:15.253Z,1680295875.253 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T20:52:41.281Z,1680295961.281 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-03-31T20:52:41.284Z,1680295961.284 [BPC1](INFO): Received data from all battery sticks. 2023-03-31T20:53:22.300Z,1680296002.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-31T20:53:24.309Z,1680296004.309 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251709 2023-03-31T20:53:57.444Z,1680296037.444 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-31T20:54:15.421Z,1680296055.421 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-03-31T20:54:15.421Z,1680296055.421 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T20:54:15.422Z,1680296055.422 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2023-03-31T20:54:15.422Z,1680296055.422 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T20:54:15.433Z,1680296055.433 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T20:54:15.434Z,1680296055.434 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T20:54:15.837Z,1680296055.837 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T20:54:15.837Z,1680296055.837 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-03-31T20:54:15.838Z,1680296055.838 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T20:54:15.838Z,1680296055.838 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2023-03-31T20:54:16.242Z,1680296056.242 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T20:54:32.584Z,1680296072.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-31T20:54:52.995Z,1680296092.995 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-31T20:49:52.8Z 2023-03-31T20:54:52.995Z,1680296092.995 [Default:CheckIn:Read_GPS] Stopped 2023-03-31T20:54:52.995Z,1680296092.995 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-31T20:54:53.400Z,1680296093.400 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-03-31T20:55:07.736Z,1680296107.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-31T20:55:25.305Z,1680296125.305 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2023-03-31T20:55:25.384Z,1680296125.384 [NAL9602](ERROR): received: +CSQ:0 OK 2023-03-31T20:55:42.876Z,1680296142.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-31T20:55:43.497Z,1680296143.497 [Power24vConverter](INFO): Powering down. 2023-03-31T20:55:54.393Z,1680296154.393 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-03-31T20:55:54.394Z,1680296154.394 [NAL9602] Data Fault, FailCount= 1 2023-03-31T20:55:54.394Z,1680296154.394 [NAL9602](ERROR): Data Fault 2023-03-31T20:55:54.411Z,1680296154.411 [CBIT](ERROR): Data Fault in component: NAL9602 2023-03-31T20:55:54.799Z,1680296154.799 [NAL9602](INFO): Powering down 2023-03-31T20:55:55.615Z,1680296155.615 [CBIT](INFO): Clearing failed state for component NAL9602 2023-03-31T20:55:55.615Z,1680296155.615 [NAL9602] No Fault, FailCount= 1 2023-03-31T20:56:14.682Z,1680296174.682 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB2 2023-03-31T20:56:17.394Z,1680296177.394 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadC3 2023-03-31T20:56:18.029Z,1680296178.029 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-03-31T20:56:25.110Z,1680296185.110 [NAL9602](INFO): Powering up NAL9602 2023-03-31T20:56:30.220Z,1680296190.220 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003215 2023-03-31T20:56:36.014Z,1680296196.014 [NAL9602](INFO): NAL9602 initialized 2023-03-31T20:56:37.479Z,1680296197.479 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230331T204502/Courier0004.lzma 2023-03-31T20:56:38.498Z,1680296198.498 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Courier0004.lzma.bak 2023-03-31T20:56:38.498Z,1680296198.498 [DataOverHttps](INFO): SBD MOMSN=17884239 2023-03-31T20:56:38.593Z,1680296198.593 [CommandExec](IMPORTANT): got command failComponent 2023-03-31T20:56:38.593Z,1680296198.593 [CommandExec](IMPORTANT): Failed components: 2023-03-31T20:56:38.593Z,1680296198.593 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2023-03-31T20:56:47.748Z,1680296207.748 [CommandExec](IMPORTANT): got command configSet list 2023-03-31T20:56:47.748Z,1680296207.748 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-03-31T20:56:47.749Z,1680296207.749 [CommandExec](IMPORTANT): DAT.loadAtStartup=0 bool; 2023-03-31T20:56:47.749Z,1680296207.749 [CommandExec](IMPORTANT): ESPComponent.sampleTimeout=2 minute; 2023-03-31T20:56:47.749Z,1680296207.749 [CommandExec](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-03-31T20:56:47.750Z,1680296207.750 [CommandExec](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-03-31T20:56:47.750Z,1680296207.750 [CommandExec](IMPORTANT): Express linearApproximation _.ayeris_particle_shape 10.000000 count_per_second; 2023-03-31T20:56:47.750Z,1680296207.750 [CommandExec](IMPORTANT): Express linearApproximation _.ayeris_particle_slope 0.200000 count_per_millimeter; 2023-03-31T20:56:47.750Z,1680296207.750 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=236.612869 cubic_centimeter; 2023-03-31T20:56:47.750Z,1680296207.750 [CommandExec](IMPORTANT): VerticalControl.massDefault=17.883769 millimeter; 2023-03-31T20:56:54.112Z,1680296214.112 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230331T204502/Courier0007.lzma 2023-03-31T20:56:55.114Z,1680296215.114 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Courier0007.lzma.bak 2023-03-31T20:56:55.114Z,1680296215.114 [DataOverHttps](INFO): SBD MOMSN=17884241 2023-03-31T20:56:55.858Z,1680296215.858 [CommandExec](IMPORTANT): got command show variable waterlink 2023-03-31T20:56:55.877Z,1680296215.877 [CommandExec](IMPORTANT): Waterlinked.loadControl (none) 2023-03-31T20:56:55.878Z,1680296215.878 [CommandExec](IMPORTANT): Waterlinked.uart (none) 2023-03-31T20:56:55.878Z,1680296215.878 [CommandExec](IMPORTANT): Waterlinked.baud (bit_per_second) 2023-03-31T20:56:55.967Z,1680296215.967 [CommandExec](IMPORTANT): Waterlinked.loadAtStartup (bool) 2023-03-31T20:56:55.968Z,1680296215.968 [CommandExec](IMPORTANT): Waterlinked.simulateHardware (bool) 2023-03-31T20:56:55.982Z,1680296215.982 [CommandExec](IMPORTANT): Waterlinked.enableBroadcast (bool) 2023-03-31T20:56:55.983Z,1680296215.983 [CommandExec](IMPORTANT): Waterlinked.component_voltage (volt) 2023-03-31T20:56:55.983Z,1680296215.983 [CommandExec](IMPORTANT): Waterlinked.component_avgVoltage (volt) 2023-03-31T20:56:55.983Z,1680296215.983 [CommandExec](IMPORTANT): Waterlinked.component_current (milliampere) 2023-03-31T20:56:55.984Z,1680296215.984 [CommandExec](IMPORTANT): Waterlinked.component_avgCurrent (milliampere) 2023-03-31T20:56:55.984Z,1680296215.984 [CommandExec](IMPORTANT): Waterlinked.height_above_sea_floor (meter) 2023-03-31T20:56:55.985Z,1680296215.985 [CommandExec](IMPORTANT): Waterlinked.platform_velocity_wrt_ground (meter_per_second) 2023-03-31T20:56:55.985Z,1680296215.985 [CommandExec](IMPORTANT): Waterlinked.platform_x_velocity_wrt_ground (meter_per_second) 2023-03-31T20:56:55.986Z,1680296215.986 [CommandExec](IMPORTANT): Waterlinked.platform_y_velocity_wrt_ground (meter_per_second) 2023-03-31T20:56:55.986Z,1680296215.986 [CommandExec](IMPORTANT): Waterlinked.platform_z_velocity_wrt_ground (meter_per_second) 2023-03-31T20:56:55.986Z,1680296215.986 [CommandExec](IMPORTANT): Waterlinked.BottomVelocityFlag (count) 2023-03-31T20:57:05.259Z,1680296225.259 [CommandExec](IMPORTANT): got command get Waterlinked.loadAtStartup 2023-03-31T20:57:05.259Z,1680296225.259 [CommandExec](IMPORTANT): Waterlinked.loadAtStartup 1 bool 2023-03-31T20:57:10.660Z,1680296230.660 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20230331T204502/Express0005.lzma 2023-03-31T20:57:11.662Z,1680296231.662 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Express0005.lzma.bak 2023-03-31T20:57:11.662Z,1680296231.662 [DataOverHttps](INFO): SBD MOMSN=17884243 2023-03-31T20:57:12.416Z,1680296232.416 [CommandExec](IMPORTANT): got command get Waterlinked.simulateHardware 2023-03-31T20:57:12.417Z,1680296232.417 [CommandExec](IMPORTANT): Waterlinked.simulateHardware 0 bool 2023-03-31T20:57:16.451Z,1680296236.451 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-03-31T20:57:16.452Z,1680296236.452 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T20:57:16.457Z,1680296236.457 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2023-03-31T20:57:16.457Z,1680296236.457 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T20:57:16.478Z,1680296236.478 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T20:57:16.478Z,1680296236.478 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T20:57:16.881Z,1680296236.881 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T20:57:16.881Z,1680296236.881 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-03-31T20:57:16.882Z,1680296236.882 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T20:57:16.882Z,1680296236.882 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2023-03-31T20:57:17.240Z,1680296237.240 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T20:57:27.187Z,1680296247.187 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230331T204502/Express0008.lzma 2023-03-31T20:57:28.190Z,1680296248.190 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Express0008.lzma.bak 2023-03-31T20:57:28.190Z,1680296248.190 [DataOverHttps](INFO): SBD MOMSN=17884253 2023-03-31T20:57:29.390Z,1680296249.390 [Default:CheckIn:Read_Iridium] Stopped 2023-03-31T20:57:29.390Z,1680296249.390 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-31T20:57:29.390Z,1680296249.390 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-31T21:00:14.231Z,1680296414.231 [CBIT](INFO): Clearing failed state for component DropWeight 2023-03-31T21:00:14.231Z,1680296414.231 [DropWeight] No Fault, FailCount= 1 2023-03-31T21:00:17.427Z,1680296417.427 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-03-31T21:00:17.427Z,1680296417.427 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:00:17.428Z,1680296417.428 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2023-03-31T21:00:17.428Z,1680296417.428 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:00:17.473Z,1680296417.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:00:17.474Z,1680296417.474 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:00:17.831Z,1680296417.831 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:00:17.831Z,1680296417.831 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-03-31T21:00:17.831Z,1680296417.831 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:00:17.831Z,1680296417.831 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2023-03-31T21:00:18.230Z,1680296418.230 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:02:29.994Z,1680296549.994 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-31T21:02:29.995Z,1680296549.995 [Default:CheckIn:C.Wait] Stopped 2023-03-31T21:02:29.995Z,1680296549.995 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-31T21:02:29.995Z,1680296549.995 [Default:CheckIn:D] Running Loop=1 2023-03-31T21:02:30.402Z,1680296550.402 [Default:CheckIn:D] Stopped 2023-03-31T21:02:30.402Z,1680296550.402 [Default:CheckIn:E] Running Loop=1 2023-03-31T21:02:30.807Z,1680296550.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.854986 min 2023-03-31T21:02:30.807Z,1680296550.807 [Default:CheckIn:E] Stopped 2023-03-31T21:02:30.807Z,1680296550.807 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-31T21:02:30.807Z,1680296550.807 [Default:CheckIn] Stopped 2023-03-31T21:02:30.807Z,1680296550.807 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-31T21:02:30.807Z,1680296550.807 [Default:CheckIn](INFO): Running loop #2 2023-03-31T21:02:30.807Z,1680296550.807 [Default:CheckIn] Running Loop=2 2023-03-31T21:02:30.807Z,1680296550.807 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-31T21:02:30.808Z,1680296550.808 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-31T21:03:18.506Z,1680296598.506 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-03-31T21:03:18.506Z,1680296598.506 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:03:18.507Z,1680296598.507 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2023-03-31T21:03:18.507Z,1680296598.507 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:03:18.518Z,1680296598.518 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:03:18.518Z,1680296598.518 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:03:18.894Z,1680296598.894 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:03:18.894Z,1680296598.894 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-03-31T21:03:18.894Z,1680296598.894 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:03:18.894Z,1680296598.894 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2023-03-31T21:03:19.289Z,1680296599.289 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:06:19.494Z,1680296779.494 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-03-31T21:06:19.494Z,1680296779.494 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:06:19.495Z,1680296779.495 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2023-03-31T21:06:19.495Z,1680296779.495 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:06:19.544Z,1680296779.544 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:06:19.545Z,1680296779.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:06:19.888Z,1680296779.888 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:06:19.888Z,1680296779.888 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-03-31T21:06:19.889Z,1680296779.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:06:19.889Z,1680296779.889 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2023-03-31T21:06:20.291Z,1680296780.291 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:06:37.654Z,1680296797.654 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-03-31T21:06:37.654Z,1680296797.654 [NAL9602] Data Fault, FailCount= 2 2023-03-31T21:06:37.654Z,1680296797.654 [NAL9602](ERROR): Data Fault 2023-03-31T21:06:37.675Z,1680296797.675 [CBIT](ERROR): Data Fault in component: NAL9602 2023-03-31T21:06:38.051Z,1680296798.051 [NAL9602](INFO): Powering down 2023-03-31T21:06:38.898Z,1680296798.898 [CBIT](INFO): Clearing failed state for component NAL9602 2023-03-31T21:06:38.898Z,1680296798.898 [NAL9602] No Fault, FailCount= 2 2023-03-31T21:07:08.358Z,1680296828.358 [NAL9602](INFO): Powering up NAL9602 2023-03-31T21:07:19.266Z,1680296839.266 [NAL9602](INFO): NAL9602 initialized 2023-03-31T21:07:30.991Z,1680296850.991 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-31T21:02:30.8Z 2023-03-31T21:07:30.991Z,1680296850.991 [Default:CheckIn:Read_GPS] Stopped 2023-03-31T21:07:30.991Z,1680296850.991 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-31T21:07:36.471Z,1680296856.471 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230331T204502/Courier0010.lzma 2023-03-31T21:07:37.476Z,1680296857.476 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Courier0010.lzma.bak 2023-03-31T21:07:37.476Z,1680296857.476 [DataOverHttps](INFO): SBD MOMSN=17884654 2023-03-31T21:07:52.829Z,1680296872.829 [DataOverHttps](INFO): Sending 816 bytes from file Logs/20230331T204502/Express0011.lzma 2023-03-31T21:07:53.830Z,1680296873.830 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Express0011.lzma.bak 2023-03-31T21:07:53.830Z,1680296873.830 [DataOverHttps](INFO): SBD MOMSN=17884656 2023-03-31T21:07:55.230Z,1680296875.230 [Default:CheckIn:Read_Iridium] Stopped 2023-03-31T21:07:55.230Z,1680296875.230 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-31T21:07:55.230Z,1680296875.230 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-31T21:09:20.465Z,1680296960.465 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2023-03-31T21:09:20.465Z,1680296960.465 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:09:20.466Z,1680296960.466 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2023-03-31T21:09:20.466Z,1680296960.466 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:09:20.477Z,1680296960.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:09:20.477Z,1680296960.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:09:20.893Z,1680296960.893 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:09:20.894Z,1680296960.894 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2023-03-31T21:09:20.894Z,1680296960.894 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:09:20.894Z,1680296960.894 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2023-03-31T21:09:21.291Z,1680296961.291 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:12:21.465Z,1680297141.465 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2023-03-31T21:12:21.465Z,1680297141.465 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:12:21.466Z,1680297141.466 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2023-03-31T21:12:21.466Z,1680297141.466 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:12:21.477Z,1680297141.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:12:21.477Z,1680297141.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:12:21.894Z,1680297141.894 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:12:21.894Z,1680297141.894 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2023-03-31T21:12:21.894Z,1680297141.894 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:12:21.894Z,1680297141.894 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2023-03-31T21:12:22.294Z,1680297142.294 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:12:55.827Z,1680297175.827 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-31T21:12:55.827Z,1680297175.827 [Default:CheckIn:C.Wait] Stopped 2023-03-31T21:12:55.827Z,1680297175.827 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-31T21:12:55.827Z,1680297175.827 [Default:CheckIn:D] Running Loop=1 2023-03-31T21:12:56.234Z,1680297176.234 [Default:CheckIn:D] Stopped 2023-03-31T21:12:56.234Z,1680297176.234 [Default:CheckIn:E] Running Loop=1 2023-03-31T21:12:56.649Z,1680297176.649 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.285518 min 2023-03-31T21:12:56.649Z,1680297176.649 [Default:CheckIn:E] Stopped 2023-03-31T21:12:56.650Z,1680297176.650 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-31T21:12:56.650Z,1680297176.650 [Default:CheckIn] Stopped 2023-03-31T21:12:56.650Z,1680297176.650 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-31T21:12:56.650Z,1680297176.650 [Default:CheckIn](INFO): Running loop #3 2023-03-31T21:12:56.650Z,1680297176.650 [Default:CheckIn] Running Loop=3 2023-03-31T21:12:56.650Z,1680297176.650 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-31T21:12:56.650Z,1680297176.650 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-31T21:15:22.461Z,1680297322.461 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2023-03-31T21:15:22.461Z,1680297322.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:15:22.462Z,1680297322.462 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2023-03-31T21:15:22.462Z,1680297322.462 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:15:22.472Z,1680297322.472 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:15:22.473Z,1680297322.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:15:22.892Z,1680297322.892 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:15:22.892Z,1680297322.892 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2023-03-31T21:15:22.892Z,1680297322.892 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:15:22.892Z,1680297322.892 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2023-03-31T21:15:23.281Z,1680297323.281 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:17:21.630Z,1680297441.630 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-03-31T21:17:21.630Z,1680297441.630 [NAL9602] Data Fault, FailCount= 3 2023-03-31T21:17:21.630Z,1680297441.630 [NAL9602](ERROR): Data Fault 2023-03-31T21:17:21.665Z,1680297441.665 [CBIT](ERROR): Data Fault in component: NAL9602 2023-03-31T21:17:22.029Z,1680297442.029 [NAL9602](INFO): Powering down 2023-03-31T21:17:22.905Z,1680297442.905 [CBIT](INFO): Clearing failed state for component NAL9602 2023-03-31T21:17:22.905Z,1680297442.905 [NAL9602] No Fault, FailCount= 3 2023-03-31T21:17:52.339Z,1680297472.339 [NAL9602](INFO): Powering up NAL9602 2023-03-31T21:17:56.786Z,1680297476.786 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-31T21:12:56.7Z 2023-03-31T21:17:56.786Z,1680297476.786 [Default:CheckIn:Read_GPS] Stopped 2023-03-31T21:17:56.786Z,1680297476.786 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-31T21:18:00.202Z,1680297480.202 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230331T204502/Courier0013.lzma 2023-03-31T21:18:01.198Z,1680297481.198 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Courier0013.lzma.bak 2023-03-31T21:18:01.198Z,1680297481.198 [DataOverHttps](INFO): SBD MOMSN=17885073 2023-03-31T21:18:03.254Z,1680297483.254 [NAL9602](INFO): NAL9602 initialized 2023-03-31T21:18:16.588Z,1680297496.588 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20230331T204502/Express0014.lzma 2023-03-31T21:18:17.590Z,1680297497.590 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Express0014.lzma.bak 2023-03-31T21:18:17.590Z,1680297497.590 [DataOverHttps](INFO): SBD MOMSN=17885075 2023-03-31T21:18:18.641Z,1680297498.641 [Default:CheckIn:Read_Iridium] Stopped 2023-03-31T21:18:18.641Z,1680297498.641 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-31T21:18:18.641Z,1680297498.641 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-31T21:18:23.484Z,1680297503.484 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2023-03-31T21:18:23.484Z,1680297503.484 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:18:23.485Z,1680297503.485 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2023-03-31T21:18:23.485Z,1680297503.485 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:18:23.508Z,1680297503.508 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:18:23.508Z,1680297503.508 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:18:23.904Z,1680297503.904 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:18:23.904Z,1680297503.904 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2023-03-31T21:18:23.905Z,1680297503.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:18:23.905Z,1680297503.905 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2023-03-31T21:18:24.273Z,1680297504.273 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:21:24.476Z,1680297684.476 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2023-03-31T21:21:24.476Z,1680297684.476 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:21:24.477Z,1680297684.477 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2023-03-31T21:21:24.477Z,1680297684.477 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:21:24.489Z,1680297684.489 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:21:24.489Z,1680297684.489 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:21:24.869Z,1680297684.869 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:21:24.869Z,1680297684.869 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2023-03-31T21:21:24.870Z,1680297684.870 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:21:24.870Z,1680297684.870 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2023-03-31T21:21:25.264Z,1680297685.264 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:23:19.222Z,1680297799.222 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-31T21:23:19.222Z,1680297799.222 [Default:CheckIn:C.Wait] Stopped 2023-03-31T21:23:19.222Z,1680297799.222 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-31T21:23:19.222Z,1680297799.222 [Default:CheckIn:D] Running Loop=1 2023-03-31T21:23:19.611Z,1680297799.611 [Default:CheckIn:D] Stopped 2023-03-31T21:23:19.611Z,1680297799.611 [Default:CheckIn:E] Running Loop=1 2023-03-31T21:23:20.002Z,1680297800.002 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.675138 min 2023-03-31T21:23:20.002Z,1680297800.002 [Default:CheckIn:E] Stopped 2023-03-31T21:23:20.002Z,1680297800.002 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-31T21:23:20.003Z,1680297800.003 [Default:CheckIn] Stopped 2023-03-31T21:23:20.003Z,1680297800.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-31T21:23:20.003Z,1680297800.003 [Default:CheckIn](INFO): Running loop #4 2023-03-31T21:23:20.003Z,1680297800.003 [Default:CheckIn] Running Loop=4 2023-03-31T21:23:20.003Z,1680297800.003 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-31T21:23:20.003Z,1680297800.003 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-31T21:24:25.444Z,1680297865.444 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2023-03-31T21:24:25.444Z,1680297865.444 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:24:25.445Z,1680297865.445 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2023-03-31T21:24:25.445Z,1680297865.445 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:24:25.497Z,1680297865.497 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:24:25.497Z,1680297865.497 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:24:25.876Z,1680297865.876 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:24:25.877Z,1680297865.877 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2023-03-31T21:24:25.877Z,1680297865.877 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:24:25.877Z,1680297865.877 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2023-03-31T21:24:26.266Z,1680297866.266 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:27:22.264Z,1680298042.264 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 5 meter 2023-03-31T21:27:22.268Z,1680298042.268 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2023-03-31T21:27:22.437Z,1680298042.437 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-03-31T21:27:23.207Z,1680298043.207 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -27.00 mm (1 active estimators). 2023-03-31T21:27:26.437Z,1680298046.437 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2023-03-31T21:27:26.437Z,1680298046.437 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:27:26.438Z,1680298046.438 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2023-03-31T21:27:26.438Z,1680298046.438 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:27:26.518Z,1680298046.518 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:27:26.518Z,1680298046.518 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:27:26.979Z,1680298046.979 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:27:26.980Z,1680298046.980 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2023-03-31T21:27:26.980Z,1680298046.980 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:27:26.980Z,1680298046.980 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2023-03-31T21:27:27.265Z,1680298047.265 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:27:50.905Z,1680298070.905 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 0 revolution_per_minute 2023-03-31T21:27:50.908Z,1680298070.908 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2023-03-31T21:27:51.223Z,1680298071.223 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-03-31T21:27:55.714Z,1680298075.714 [CommandExec](IMPORTANT): got command get depth 2023-03-31T21:27:55.715Z,1680298075.715 [CommandExec](IMPORTANT): depth 5.000000 m 2023-03-31T21:28:00.394Z,1680298080.394 [CommandExec](IMPORTANT): got command show variable waterlink 2023-03-31T21:28:00.408Z,1680298080.408 [CommandExec](IMPORTANT): Waterlinked.loadControl (none) 2023-03-31T21:28:00.410Z,1680298080.410 [CommandExec](IMPORTANT): Waterlinked.uart (none) 2023-03-31T21:28:00.413Z,1680298080.413 [CommandExec](IMPORTANT): Waterlinked.baud (bit_per_second) 2023-03-31T21:28:00.558Z,1680298080.558 [CommandExec](IMPORTANT): Waterlinked.loadAtStartup (bool) 2023-03-31T21:28:00.558Z,1680298080.558 [CommandExec](IMPORTANT): Waterlinked.simulateHardware (bool) 2023-03-31T21:28:00.580Z,1680298080.580 [CommandExec](IMPORTANT): Waterlinked.enableBroadcast (bool) 2023-03-31T21:28:00.580Z,1680298080.580 [CommandExec](IMPORTANT): Waterlinked.component_voltage (volt) 2023-03-31T21:28:00.581Z,1680298080.581 [CommandExec](IMPORTANT): Waterlinked.component_avgVoltage (volt) 2023-03-31T21:28:00.581Z,1680298080.581 [CommandExec](IMPORTANT): Waterlinked.component_current (milliampere) 2023-03-31T21:28:00.582Z,1680298080.582 [CommandExec](IMPORTANT): Waterlinked.component_avgCurrent (milliampere) 2023-03-31T21:28:00.582Z,1680298080.582 [CommandExec](IMPORTANT): Waterlinked.height_above_sea_floor (meter) 2023-03-31T21:28:00.583Z,1680298080.583 [CommandExec](IMPORTANT): Waterlinked.platform_velocity_wrt_ground (meter_per_second) 2023-03-31T21:28:00.583Z,1680298080.583 [CommandExec](IMPORTANT): Waterlinked.platform_x_velocity_wrt_ground (meter_per_second) 2023-03-31T21:28:00.584Z,1680298080.584 [CommandExec](IMPORTANT): Waterlinked.platform_y_velocity_wrt_ground (meter_per_second) 2023-03-31T21:28:00.585Z,1680298080.585 [CommandExec](IMPORTANT): Waterlinked.platform_z_velocity_wrt_ground (meter_per_second) 2023-03-31T21:28:00.585Z,1680298080.585 [CommandExec](IMPORTANT): Waterlinked.BottomVelocityFlag (count) 2023-03-31T21:28:06.830Z,1680298086.830 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-03-31T21:28:06.830Z,1680298086.830 [NAL9602] Data Fault, FailCount= 4 2023-03-31T21:28:06.830Z,1680298086.830 [NAL9602](ERROR): Data Fault 2023-03-31T21:28:06.898Z,1680298086.898 [CBIT](ERROR): Data Fault in component: NAL9602 2023-03-31T21:28:07.230Z,1680298087.230 [NAL9602](INFO): Powering down 2023-03-31T21:28:08.153Z,1680298088.153 [CBIT](INFO): Clearing failed state for component NAL9602 2023-03-31T21:28:08.153Z,1680298088.153 [NAL9602] No Fault, FailCount= 4 2023-03-31T21:28:11.467Z,1680298091.467 [CommandExec](IMPORTANT): got command report touch Waterlinked.component_voltage 2023-03-31T21:28:20.175Z,1680298100.175 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-31T21:23:20.0Z 2023-03-31T21:28:20.175Z,1680298100.175 [Default:CheckIn:Read_GPS] Stopped 2023-03-31T21:28:20.175Z,1680298100.175 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-03-31T21:28:22.600Z,1680298102.600 [Default:B.GoToSurface](ERROR): Pitch down timeout. Pitch: -0.41 2023-03-31T21:28:25.743Z,1680298105.743 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230331T204502/Courier0016.lzma 2023-03-31T21:28:26.746Z,1680298106.746 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Courier0016.lzma.bak 2023-03-31T21:28:26.746Z,1680298106.746 [DataOverHttps](INFO): SBD MOMSN=17885482 2023-03-31T21:28:37.534Z,1680298117.534 [NAL9602](INFO): Powering up NAL9602 2023-03-31T21:28:42.204Z,1680298122.204 [DataOverHttps](INFO): Sending 577 bytes from file Logs/20230331T204502/Express0017.lzma 2023-03-31T21:28:43.206Z,1680298123.206 [DataOverHttps](INFO): Moved sent file to Logs/20230331T204502/Express0017.lzma.bak 2023-03-31T21:28:43.206Z,1680298123.206 [DataOverHttps](INFO): SBD MOMSN=17885484 2023-03-31T21:28:44.410Z,1680298124.410 [Default:CheckIn:Read_Iridium] Stopped 2023-03-31T21:28:44.410Z,1680298124.410 [Default:CheckIn:C.Wait] Running Loop=1 2023-03-31T21:28:44.410Z,1680298124.410 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-03-31T21:28:48.438Z,1680298128.438 [NAL9602](INFO): NAL9602 initialized 2023-03-31T21:29:49.146Z,1680298189.146 [CommandExec](IMPORTANT): got command show variable surfaceThreshold 2023-03-31T21:29:49.201Z,1680298189.201 [CommandExec](IMPORTANT): VerticalControl.surfaceThreshold (meter) 2023-03-31T21:29:49.256Z,1680298189.256 [CommandExec](IMPORTANT): AcousticModem_Benthos_ATM900.surfaceThreshold (meter) 2023-03-31T21:29:49.259Z,1680298189.259 [CommandExec](IMPORTANT): DAT.surfaceThreshold (meter) 2023-03-31T21:29:49.263Z,1680298189.263 [CommandExec](IMPORTANT): Micromodem.surfaceThreshold (meter) 2023-03-31T21:29:55.637Z,1680298195.637 [CommandExec](IMPORTANT): got command get VerticalControl.surfaceThreshold 2023-03-31T21:29:55.637Z,1680298195.637 [CommandExec](IMPORTANT): VerticalControl.surfaceThreshold 1.000000 m 2023-03-31T21:30:03.802Z,1680298203.802 [CommandExec](IMPORTANT): got command failComponent hardware Waterlinked 2023-03-31T21:30:03.802Z,1680298203.802 [Waterlinked] Hardware Fault, FailCount= 1 2023-03-31T21:30:03.802Z,1680298203.802 [Waterlinked](ERROR): Hardware Fault 2023-03-31T21:30:03.802Z,1680298203.802 [CommandExec](IMPORTANT): Waterlinked failureMode is Hardware Fault 2023-03-31T21:30:04.021Z,1680298204.021 [CBIT](ERROR): Hardware Fault in component: Waterlinked 2023-03-31T21:30:04.021Z,1680298204.021 [CBIT](INFO): Clearing failed state for component Waterlinked 2023-03-31T21:30:04.021Z,1680298204.021 [Waterlinked] No Fault, FailCount= 1 2023-03-31T21:30:04.813Z,1680298204.813 [Power24vConverter](INFO): Powering up. 2023-03-31T21:30:14.532Z,1680298214.532 [CommandExec](IMPORTANT): got command fileExec 2023-03-31T21:30:14.533Z,1680298214.533 [CommandExec](FAULT): Incomplete syntax. Try: help fileExec 2023-03-31T21:30:15.365Z,1680298215.365 [CommandExec](IMPORTANT): got command failComponent 2023-03-31T21:30:15.365Z,1680298215.365 [CommandExec](IMPORTANT): Failed components: 2023-03-31T21:30:15.365Z,1680298215.365 [CommandExec](IMPORTANT): No failed Components. 2023-03-31T21:30:27.426Z,1680298227.426 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2023-03-31T21:30:27.426Z,1680298227.426 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:30:27.427Z,1680298227.427 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15 2023-03-31T21:30:27.427Z,1680298227.427 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:30:27.495Z,1680298227.495 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:30:27.496Z,1680298227.496 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:30:27.850Z,1680298227.850 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:30:27.850Z,1680298227.850 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2023-03-31T21:30:27.850Z,1680298227.850 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:30:27.850Z,1680298227.850 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15 2023-03-31T21:30:28.293Z,1680298228.293 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:30:30.712Z,1680298230.712 [CommandExec](IMPORTANT): got command get depth 2023-03-31T21:30:30.713Z,1680298230.713 [CommandExec](IMPORTANT): depth 0.118123 m 2023-03-31T21:30:30.733Z,1680298230.733 [Reporter](INFO): Waterlinked.component_voltage 15.888480 V 2023-03-31T21:30:33.226Z,1680298233.226 [Reporter](INFO): Waterlinked.component_voltage 15.888480 V 2023-03-31T21:30:35.750Z,1680298235.750 [Reporter](INFO): Waterlinked.component_voltage 15.863340 V 2023-03-31T21:30:38.026Z,1680298238.026 [Reporter](INFO): Waterlinked.component_voltage 16.089600 V 2023-03-31T21:30:38.412Z,1680298238.412 [Waterlinked](INFO): Powering down 2023-03-31T21:30:38.465Z,1680298238.465 [Reporter](INFO): Waterlinked.component_voltage 0.000000 V 2023-03-31T21:30:46.160Z,1680298246.160 [CommandExec](IMPORTANT): got command get depth 2023-03-31T21:30:46.161Z,1680298246.161 [CommandExec](IMPORTANT): depth 5.000000 m 2023-03-31T21:30:54.605Z,1680298254.605 [CommandExec](IMPORTANT): got command show best depth 2023-03-31T21:30:54.605Z,1680298254.605 [CommandExec](IMPORTANT): depth best is Depth_Keller.depth 2023-03-31T21:30:58.049Z,1680298258.049 [CommandExec](IMPORTANT): got command get depth 2023-03-31T21:30:58.049Z,1680298258.049 [CommandExec](IMPORTANT): depth 5.000000 m 2023-03-31T21:31:35.499Z,1680298295.499 [CommandExec](IMPORTANT): got command maintain servo Depth_Keller.depth 5 meter 2023-03-31T21:31:51.173Z,1680298311.173 [CommandExec](IMPORTANT): got command maintain control Depth_Keller.depth 5 meter 2023-03-31T21:32:01.071Z,1680298321.071 [CommandExec](IMPORTANT): got command configSet list 2023-03-31T21:32:01.071Z,1680298321.071 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-03-31T21:32:01.072Z,1680298321.072 [CommandExec](IMPORTANT): DAT.loadAtStartup=0 bool; 2023-03-31T21:32:01.072Z,1680298321.072 [CommandExec](IMPORTANT): ESPComponent.sampleTimeout=2 minute; 2023-03-31T21:32:01.073Z,1680298321.073 [CommandExec](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-03-31T21:32:01.073Z,1680298321.073 [CommandExec](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-03-31T21:32:01.073Z,1680298321.073 [CommandExec](IMPORTANT): Express linearApproximation _.ayeris_particle_shape 10.000000 count_per_second; 2023-03-31T21:32:01.073Z,1680298321.073 [CommandExec](IMPORTANT): Express linearApproximation _.ayeris_particle_slope 0.200000 count_per_millimeter; 2023-03-31T21:32:01.073Z,1680298321.073 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=236.612869 cubic_centimeter; 2023-03-31T21:32:01.073Z,1680298321.073 [CommandExec](IMPORTANT): VerticalControl.massDefault=17.883769 millimeter; 2023-03-31T21:32:14.174Z,1680298334.174 [CommandExec](IMPORTANT): got command failComponent hardware Waterlinked 2023-03-31T21:32:14.174Z,1680298334.174 [Waterlinked] Hardware Fault, FailCount= 1 2023-03-31T21:32:14.174Z,1680298334.174 [Waterlinked](ERROR): Hardware Fault 2023-03-31T21:32:14.174Z,1680298334.174 [CommandExec](IMPORTANT): Waterlinked failureMode is Hardware Fault 2023-03-31T21:32:14.517Z,1680298334.517 [CBIT](ERROR): Hardware Fault in component: Waterlinked 2023-03-31T21:32:14.517Z,1680298334.517 [CBIT](INFO): Clearing failed state for component Waterlinked 2023-03-31T21:32:14.517Z,1680298334.517 [Waterlinked] No Fault, FailCount= 1 2023-03-31T21:32:20.512Z,1680298340.512 [CommandExec](IMPORTANT): got command failComponent 2023-03-31T21:32:20.513Z,1680298340.513 [CommandExec](IMPORTANT): Failed components: 2023-03-31T21:32:20.513Z,1680298340.513 [CommandExec](IMPORTANT): No failed Components. 2023-03-31T21:32:40.885Z,1680298360.885 [Reporter](INFO): Waterlinked.component_voltage 16.089600 V 2023-03-31T21:32:43.266Z,1680298363.266 [Reporter](INFO): Waterlinked.component_voltage 15.989040 V 2023-03-31T21:32:45.642Z,1680298365.642 [Reporter](INFO): Waterlinked.component_voltage 16.089600 V 2023-03-31T21:32:47.029Z,1680298367.029 [CommandExec](IMPORTANT): got command get depth 2023-03-31T21:32:47.030Z,1680298367.030 [CommandExec](IMPORTANT): depth 5.000000 m 2023-03-31T21:32:48.063Z,1680298368.063 [Reporter](INFO): Waterlinked.component_voltage 15.888480 V 2023-03-31T21:32:48.905Z,1680298368.905 [Waterlinked](INFO): Powering down 2023-03-31T21:32:48.931Z,1680298368.931 [Reporter](INFO): Waterlinked.component_voltage 0.000000 V 2023-03-31T21:33:28.465Z,1680298408.465 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2023-03-31T21:33:28.465Z,1680298408.465 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-03-31T21:33:28.466Z,1680298408.466 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16 2023-03-31T21:33:28.466Z,1680298408.466 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-03-31T21:33:28.478Z,1680298408.478 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-03-31T21:33:28.479Z,1680298408.479 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-03-31T21:33:28.877Z,1680298408.877 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-03-31T21:33:28.877Z,1680298408.877 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2023-03-31T21:33:28.877Z,1680298408.877 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-03-31T21:33:28.877Z,1680298408.877 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16 2023-03-31T21:33:29.260Z,1680298409.260 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-03-31T21:33:30.246Z,1680298410.246 [CommandExec](IMPORTANT): got command maintain clear 2023-03-31T21:33:30.506Z,1680298410.506 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-03-31T21:33:32.762Z,1680298412.762 [CommandExec](IMPORTANT): got command get depth 2023-03-31T21:33:32.762Z,1680298412.762 [CommandExec](IMPORTANT): depth 0.118508 m 2023-03-31T21:33:45.003Z,1680298425.003 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-03-31T21:33:45.003Z,1680298425.003 [Default:CheckIn:C.Wait] Stopped 2023-03-31T21:33:45.003Z,1680298425.003 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-03-31T21:33:45.003Z,1680298425.003 [Default:CheckIn:D] Running Loop=1 2023-03-31T21:33:45.410Z,1680298425.410 [Default:CheckIn:D] Stopped 2023-03-31T21:33:45.410Z,1680298425.410 [Default:CheckIn:E] Running Loop=1 2023-03-31T21:33:45.828Z,1680298425.828 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.105123 min 2023-03-31T21:33:45.828Z,1680298425.828 [Default:CheckIn:E] Stopped 2023-03-31T21:33:45.828Z,1680298425.828 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-03-31T21:33:45.828Z,1680298425.828 [Default:CheckIn] Stopped 2023-03-31T21:33:45.828Z,1680298425.828 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-31T21:33:45.828Z,1680298425.828 [Default:CheckIn](INFO): Running loop #5 2023-03-31T21:33:45.829Z,1680298425.829 [Default:CheckIn] Running Loop=5 2023-03-31T21:33:45.829Z,1680298425.829 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-03-31T21:33:45.829Z,1680298425.829 [Default:CheckIn:Read_GPS] Running Loop=1 2023-03-31T21:33:56.282Z,1680298436.282 [CommandExec](IMPORTANT): got command configSet VerticalControl.surfaceThreshold 0 meter persist 2023-03-31T21:34:05.969Z,1680298445.969 [CommandExec](IMPORTANT): got command get VerticalControl.surfaceThreshold 2023-03-31T21:34:05.969Z,1680298445.969 [CommandExec](IMPORTANT): VerticalControl.surfaceThreshold 0.000000 m 2023-03-31T21:34:14.721Z,1680298454.721 [CommandExec](IMPORTANT): got command report 2023-03-31T21:34:14.721Z,1680298454.721 [Reporter](INFO): Waterlinked.component_voltage reporting when touched 2023-03-31T21:34:17.811Z,1680298457.811 [CommandExec](IMPORTANT): got command get depth 2023-03-31T21:34:17.811Z,1680298457.811 [CommandExec](IMPORTANT): depth 0.120432 m 2023-03-31T21:34:29.302Z,1680298469.302 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 3 meter 2023-03-31T21:34:29.303Z,1680298469.303 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2023-03-31T21:34:29.498Z,1680298469.498 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-03-31T21:34:32.873Z,1680298472.873 [CommandExec](IMPORTANT): got command get depth 2023-03-31T21:34:32.874Z,1680298472.874 [CommandExec](IMPORTANT): depth 3.000000 m 2023-03-31T21:34:35.577Z,1680298475.577 [CommandExec](IMPORTANT): got command restart application 2023-03-31T21:34:36.588Z,1680298476.588 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:36.588Z,1680298476.588 [CommandExec](INFO): Uninitializing the command executive. 2023-03-31T21:34:36.588Z,1680298476.588 [CommandExec](INFO): Uninitializing the command scheduler. 2023-03-31T21:34:36.589Z,1680298476.589 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:36.643Z,1680298476.643 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-03-31T21:34:36.643Z,1680298476.643 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-03-31T21:34:36.643Z,1680298476.643 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:36.644Z,1680298476.644 [NavChartDb](INFO): Join timeout helper Thread ID is 3194 2023-03-31T21:34:36.876Z,1680298476.876 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:36.877Z,1680298476.877 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:36.893Z,1680298476.893 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-03-31T21:34:36.893Z,1680298476.893 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:36.894Z,1680298476.894 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3195 2023-03-31T21:34:37.333Z,1680298477.333 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:37.333Z,1680298477.333 [WetLabsBB2FL](INFO): Powering down 2023-03-31T21:34:37.333Z,1680298477.333 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:37.340Z,1680298477.340 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-03-31T21:34:37.340Z,1680298477.340 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:37.340Z,1680298477.340 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3196 2023-03-31T21:34:37.872Z,1680298477.872 [CTD_Seabird](INFO): Powering down 2023-03-31T21:34:37.884Z,1680298477.884 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:37.884Z,1680298477.884 [CTD_Seabird](INFO): Powering down 2023-03-31T21:34:37.896Z,1680298477.896 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:37.916Z,1680298477.916 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-03-31T21:34:37.916Z,1680298477.916 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:37.917Z,1680298477.917 [Radio_Surface](INFO): Join timeout helper Thread ID is 3197 2023-03-31T21:34:38.056Z,1680298478.056 [Radio_Surface](INFO): Powering down 2023-03-31T21:34:38.057Z,1680298478.057 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:38.057Z,1680298478.057 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:38.058Z,1680298478.058 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-03-31T21:34:38.076Z,1680298478.076 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-03-31T21:34:38.076Z,1680298478.076 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:38.076Z,1680298478.076 [Onboard](INFO): Join timeout helper Thread ID is 3198 2023-03-31T21:34:40.752Z,1680298480.752 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:40.752Z,1680298480.753 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:40.765Z,1680298480.765 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-03-31T21:34:40.765Z,1680298480.765 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:40.766Z,1680298480.766 [DataOverHttps](INFO): Join timeout helper Thread ID is 3200 2023-03-31T21:34:41.068Z,1680298481.068 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:41.069Z,1680298481.069 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.093Z,1680298481.093 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-03-31T21:34:41.093Z,1680298481.093 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.094Z,1680298481.094 [BackseatComponent](INFO): Join timeout helper Thread ID is 3201 2023-03-31T21:34:41.172Z,1680298481.172 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:41.173Z,1680298481.173 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.174Z,1680298481.174 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-03-31T21:34:41.174Z,1680298481.174 [logger ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.175Z,1680298481.175 [logger](INFO): Join timeout helper Thread ID is 3202 2023-03-31T21:34:41.217Z,1680298481.217 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:41.217Z,1680298481.217 [logger ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.233Z,1680298481.233 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-03-31T21:34:41.233Z,1680298481.233 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.234Z,1680298481.234 [CommandLine](INFO): Join timeout helper Thread ID is 3203 2023-03-31T21:34:41.280Z,1680298481.280 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:41.281Z,1680298481.281 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.293Z,1680298481.293 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-03-31T21:34:41.293Z,1680298481.293 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.294Z,1680298481.294 [CommandExec](INFO): Join timeout helper Thread ID is 3204 2023-03-31T21:34:41.296Z,1680298481.296 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-03-31T21:34:41.296Z,1680298481.296 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.297Z,1680298481.297 [controlThread](INFO): Join timeout helper Thread ID is 3205 2023-03-31T21:34:41.317Z,1680298481.317 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-03-31T21:34:41.317Z,1680298481.317 [controlThread](DEBUG): Uninitializing ControlThread 2023-03-31T21:34:41.317Z,1680298481.317 [AHRS_M2](INFO): Powering down 2023-03-31T21:34:41.389Z,1680298481.389 [MultiRay](INFO): Powering down. 2023-03-31T21:34:41.390Z,1680298481.390 [NAL9602](INFO): Powering down 2023-03-31T21:34:41.391Z,1680298481.391 [Waterlinked](INFO): Powering down 2023-03-31T21:34:41.393Z,1680298481.393 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-03-31T21:34:41.394Z,1680298481.394 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-03-31T21:34:41.394Z,1680298481.394 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-03-31T21:34:41.395Z,1680298481.395 [MissionManager](INFO): Uninitializing Mission Default 2023-03-31T21:34:41.395Z,1680298481.395 [Default] Stopped 2023-03-31T21:34:41.395Z,1680298481.395 [Default](DEBUG): Aggregate::uninitialize Default 2023-03-31T21:34:41.395Z,1680298481.395 [Default:B.GoToSurface] Stopped 2023-03-31T21:34:41.395Z,1680298481.395 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-03-31T21:34:41.395Z,1680298481.395 [Default:CheckIn] Stopped 2023-03-31T21:34:41.395Z,1680298481.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-03-31T21:34:41.395Z,1680298481.395 [Default:CheckIn:Read_GPS] Stopped 2023-03-31T21:34:41.398Z,1680298481.398 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-03-31T21:34:41.399Z,1680298481.399 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-03-31T21:34:41.399Z,1680298481.399 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-03-31T21:34:41.399Z,1680298481.399 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-03-31T21:34:41.399Z,1680298481.399 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-03-31T21:34:41.400Z,1680298481.400 [BuoyancyServo](INFO): Powering down 2023-03-31T21:34:41.412Z,1680298481.412 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-03-31T21:34:41.412Z,1680298481.412 [ElevatorServo](INFO): Powering down 2023-03-31T21:34:41.413Z,1680298481.413 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-03-31T21:34:41.413Z,1680298481.413 [MassServo](INFO): Powering down 2023-03-31T21:34:41.414Z,1680298481.414 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-03-31T21:34:41.414Z,1680298481.414 [RudderServo](INFO): Powering down 2023-03-31T21:34:41.415Z,1680298481.415 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-03-31T21:34:41.415Z,1680298481.415 [ThrusterHE](INFO): Powering down 2023-03-31T21:34:41.416Z,1680298481.416 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-03-31T21:34:41.416Z,1680298481.416 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-03-31T21:34:41.417Z,1680298481.417 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-03-31T21:34:41.417Z,1680298481.417 [CBIT](DEBUG): Powering off loads. 2023-03-31T21:34:41.428Z,1680298481.428 [CBIT](DEBUG): Disabling WDT. 2023-03-31T21:34:41.440Z,1680298481.440 [CBIT](DEBUG): Opening all GF detection circuits. 2023-03-31T21:34:41.441Z,1680298481.441 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.471Z,1680298481.471 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.475Z,1680298481.475 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.481Z,1680298481.481 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.525Z,1680298481.525 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.575Z,1680298481.575 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.578Z,1680298481.578 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.867Z,1680298481.867 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-03-31T21:34:41.912Z,1680298481.912 [logger ThreadHandler](INFO): Thread cancelled.