2023-02-13T15:02:53.001Z,1676300573.001 [Supervisor](DEBUG): Initializing supervisor. 2023-02-13T15:02:53.005Z,1676300573.005 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-02-13T15:02:53.006Z,1676300573.006 [SyncHandler](INFO): Protected caller Thread ID is 837 2023-02-13T15:02:53.006Z,1676300573.006 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-02-13T15:02:53.007Z,1676300573.007 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-02-13T15:02:53.008Z,1676300573.008 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2023-02-13T15:02:53.012Z,1676300573.012 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-02-13T15:02:53.030Z,1676300573.030 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-02-13T15:02:53.031Z,1676300573.031 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-02-13T15:02:53.031Z,1676300573.031 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2023-02-13T15:02:53.035Z,1676300573.035 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-02-13T15:02:53.036Z,1676300573.036 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-02-13T15:02:53.037Z,1676300573.037 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2023-02-13T15:02:53.039Z,1676300573.039 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-02-13T15:02:53.040Z,1676300573.040 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-02-13T15:02:53.040Z,1676300573.040 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2023-02-13T15:02:53.044Z,1676300573.044 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-02-13T15:02:53.044Z,1676300573.044 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-02-13T15:02:53.048Z,1676300573.048 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-02-13T15:02:53.146Z,1676300573.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-02-13T15:02:53.148Z,1676300573.148 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-02-13T15:02:53.816Z,1676300573.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-02-13T15:02:53.818Z,1676300573.818 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-02-13T15:02:54.048Z,1676300574.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-02-13T15:02:54.050Z,1676300574.050 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-02-13T15:02:54.131Z,1676300574.131 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-02-13T15:02:54.262Z,1676300574.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-02-13T15:02:54.264Z,1676300574.264 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-02-13T15:02:54.346Z,1676300574.346 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-02-13T15:02:54.454Z,1676300574.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-02-13T15:02:54.456Z,1676300574.456 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-02-13T15:02:54.850Z,1676300574.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-02-13T15:02:54.850Z,1676300574.850 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-02-13T15:02:55.341Z,1676300575.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-02-13T15:02:55.342Z,1676300575.342 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-02-13T15:02:55.569Z,1676300575.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-02-13T15:02:55.570Z,1676300575.570 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-02-13T15:02:55.760Z,1676300575.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-02-13T15:02:55.762Z,1676300575.762 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-02-13T15:02:56.285Z,1676300576.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-02-13T15:02:56.793Z,1676300576.793 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-02-13T15:02:57.206Z,1676300577.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-02-13T15:02:57.207Z,1676300577.207 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-02-13T15:02:57.422Z,1676300577.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-02-13T15:02:57.424Z,1676300577.424 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-02-13T15:02:57.791Z,1676300577.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-02-13T15:02:57.792Z,1676300577.792 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-02-13T15:02:58.131Z,1676300578.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-02-13T15:02:58.133Z,1676300578.133 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2023-02-13T15:02:58.136Z,1676300578.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2023-02-13T15:02:58.219Z,1676300578.219 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2023-02-13T15:02:58.374Z,1676300578.374 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2023-02-13T15:02:58.483Z,1676300578.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2023-02-13T15:02:58.569Z,1676300578.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2023-02-13T15:02:58.664Z,1676300578.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2023-02-13T15:02:58.848Z,1676300578.848 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2023-02-13T15:02:59.115Z,1676300579.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-02-13T15:02:59.115Z,1676300579.115 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2023-02-13T15:02:59.210Z,1676300579.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2023-02-13T15:02:59.310Z,1676300579.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2023-02-13T15:02:59.450Z,1676300579.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2023-02-13T15:02:59.550Z,1676300579.550 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-02-13T15:02:59.569Z,1676300579.569 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-02-13T15:02:59.693Z,1676300579.693 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-02-13T15:02:59.694Z,1676300579.694 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-02-13T15:02:59.761Z,1676300579.761 [VerticalControl](DEBUG): Construct VerticalControl. 2023-02-13T15:02:59.823Z,1676300579.823 [VerticalControl] Loaded 2023-02-13T15:02:59.824Z,1676300579.824 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-02-13T15:02:59.827Z,1676300579.827 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-02-13T15:02:59.869Z,1676300579.869 [HorizontalControl] Loaded 2023-02-13T15:02:59.869Z,1676300579.869 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-02-13T15:02:59.871Z,1676300579.871 [SpeedControl](DEBUG): Construct SpeedControl. 2023-02-13T15:02:59.874Z,1676300579.874 [SpeedControl] Loaded 2023-02-13T15:02:59.875Z,1676300579.875 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-02-13T15:02:59.877Z,1676300579.877 [LoopControl](DEBUG): Construct LoopControl. 2023-02-13T15:02:59.878Z,1676300579.878 [LoopControl] Loaded 2023-02-13T15:02:59.878Z,1676300579.878 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-02-13T15:02:59.879Z,1676300579.879 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-02-13T15:02:59.879Z,1676300579.879 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-02-13T15:02:59.950Z,1676300579.950 [DepthRateCalculator] Loaded 2023-02-13T15:02:59.950Z,1676300579.950 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-02-13T15:02:59.955Z,1676300579.955 [PitchRateCalculator] Loaded 2023-02-13T15:02:59.955Z,1676300579.955 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-02-13T15:02:59.965Z,1676300579.965 [SpeedCalculator] Loaded 2023-02-13T15:02:59.966Z,1676300579.966 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-02-13T15:02:59.970Z,1676300579.970 [YawRateCalculator] Loaded 2023-02-13T15:02:59.970Z,1676300579.970 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-02-13T15:02:59.989Z,1676300579.989 [ElevatorOffsetCalculator] Loaded 2023-02-13T15:02:59.989Z,1676300579.989 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-02-13T15:02:59.989Z,1676300579.989 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-02-13T15:02:59.990Z,1676300579.990 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-02-13T15:03:00.032Z,1676300580.032 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-02-13T15:03:00.033Z,1676300580.033 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-02-13T15:03:00.116Z,1676300580.116 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-02-13T15:03:00.116Z,1676300580.116 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-02-13T15:03:00.672Z,1676300580.672 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-02-13T15:03:00.672Z,1676300580.672 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-02-13T15:03:00.894Z,1676300580.894 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-02-13T15:03:00.896Z,1676300580.896 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-02-13T15:03:01.653Z,1676300581.653 [AHRS_M2] Loaded 2023-02-13T15:03:01.653Z,1676300581.653 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-02-13T15:03:01.958Z,1676300581.958 [BackseatComponent] Loaded 2023-02-13T15:03:01.959Z,1676300581.959 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-02-13T15:03:02.018Z,1676300582.018 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409764E0 2023-02-13T15:03:02.019Z,1676300582.019 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923 2023-02-13T15:03:02.032Z,1676300582.032 [LcmUniversalReporter] Loaded 2023-02-13T15:03:02.033Z,1676300582.033 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-02-13T15:03:03.210Z,1676300583.210 [BPC1] Loaded 2023-02-13T15:03:03.210Z,1676300583.210 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-02-13T15:03:03.352Z,1676300583.352 [DataOverHttps] Loaded 2023-02-13T15:03:03.352Z,1676300583.352 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-02-13T15:03:03.353Z,1676300583.353 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A64E0 2023-02-13T15:03:03.354Z,1676300583.354 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2023-02-13T15:03:03.374Z,1676300583.374 [Depth_Keller] Loaded 2023-02-13T15:03:03.375Z,1676300583.375 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-02-13T15:03:03.379Z,1676300583.379 [DropWeight] Loaded 2023-02-13T15:03:03.380Z,1676300583.380 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-02-13T15:03:03.398Z,1676300583.398 [MultiRay] Loaded 2023-02-13T15:03:03.398Z,1676300583.398 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2023-02-13T15:03:03.461Z,1676300583.461 [NAL9602] Loaded 2023-02-13T15:03:03.461Z,1676300583.461 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-02-13T15:03:03.491Z,1676300583.491 [Onboard] Loaded 2023-02-13T15:03:03.491Z,1676300583.491 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-02-13T15:03:03.492Z,1676300583.492 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D64E0 2023-02-13T15:03:03.493Z,1676300583.493 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2023-02-13T15:03:03.505Z,1676300583.505 [Power24vConverter] Loaded 2023-02-13T15:03:03.505Z,1676300583.505 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-02-13T15:03:03.518Z,1676300583.518 [Radio_Surface] Loaded 2023-02-13T15:03:03.518Z,1676300583.518 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-02-13T15:03:03.519Z,1676300583.519 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A064E0 2023-02-13T15:03:03.520Z,1676300583.520 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2023-02-13T15:03:03.566Z,1676300583.566 [RDI_Pathfinder] Loaded 2023-02-13T15:03:03.566Z,1676300583.566 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2023-02-13T15:03:03.658Z,1676300583.658 [DAT] Loaded 2023-02-13T15:03:03.659Z,1676300583.659 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-02-13T15:03:03.660Z,1676300583.660 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A364E0 2023-02-13T15:03:03.660Z,1676300583.660 [DAT ThreadHandler](INFO): Protected caller Thread ID is 927 2023-02-13T15:03:03.661Z,1676300583.661 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-02-13T15:03:03.661Z,1676300583.661 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-02-13T15:03:03.798Z,1676300583.798 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-02-13T15:03:03.798Z,1676300583.798 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-02-13T15:03:03.812Z,1676300583.812 [NavChart] Loaded 2023-02-13T15:03:03.812Z,1676300583.812 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-02-13T15:03:03.818Z,1676300583.818 [UniversalFixResidualReporter] Loaded 2023-02-13T15:03:03.819Z,1676300583.819 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-02-13T15:03:03.819Z,1676300583.819 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-02-13T15:03:03.820Z,1676300583.820 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-02-13T15:03:03.987Z,1676300583.987 [SBIT](DEBUG): Construct Startup Built In Test. 2023-02-13T15:03:03.996Z,1676300583.996 [SBIT] Loaded 2023-02-13T15:03:03.996Z,1676300583.996 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-02-13T15:03:03.999Z,1676300583.999 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-02-13T15:03:04.012Z,1676300584.012 [IBIT] Loaded 2023-02-13T15:03:04.013Z,1676300584.013 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-02-13T15:03:04.018Z,1676300584.018 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-02-13T15:03:04.124Z,1676300584.124 [CBIT] Loaded 2023-02-13T15:03:04.124Z,1676300584.124 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-02-13T15:03:04.125Z,1676300584.125 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-02-13T15:03:04.126Z,1676300584.126 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-02-13T15:03:04.287Z,1676300584.287 [BuoyancyServo] Loaded 2023-02-13T15:03:04.287Z,1676300584.287 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-02-13T15:03:04.308Z,1676300584.308 [ElevatorServo] Loaded 2023-02-13T15:03:04.308Z,1676300584.308 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-02-13T15:03:04.329Z,1676300584.329 [MassServo] Loaded 2023-02-13T15:03:04.329Z,1676300584.329 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-02-13T15:03:04.347Z,1676300584.347 [RudderServo] Loaded 2023-02-13T15:03:04.348Z,1676300584.348 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-02-13T15:03:04.365Z,1676300584.365 [ThrusterServo] Loaded 2023-02-13T15:03:04.365Z,1676300584.365 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2023-02-13T15:03:04.366Z,1676300584.366 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-02-13T15:03:04.366Z,1676300584.366 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-02-13T15:03:04.382Z,1676300584.382 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-02-13T15:03:04.383Z,1676300584.383 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-02-13T15:03:04.599Z,1676300584.599 [CTD_Seabird] Loaded 2023-02-13T15:03:04.599Z,1676300584.599 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-02-13T15:03:04.600Z,1676300584.600 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B7E4E0 2023-02-13T15:03:04.600Z,1676300584.600 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928 2023-02-13T15:03:04.621Z,1676300584.621 [PAR_Licor] Loaded 2023-02-13T15:03:04.621Z,1676300584.621 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-02-13T15:03:04.653Z,1676300584.653 [WetLabsBB2FL] Loaded 2023-02-13T15:03:04.653Z,1676300584.653 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-02-13T15:03:04.654Z,1676300584.654 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BAE4E0 2023-02-13T15:03:04.655Z,1676300584.655 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929 2023-02-13T15:03:04.675Z,1676300584.675 [WetLabsUBAT] Loaded 2023-02-13T15:03:04.675Z,1676300584.675 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2023-02-13T15:03:04.676Z,1676300584.676 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BDE4E0 2023-02-13T15:03:04.676Z,1676300584.676 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 930 2023-02-13T15:03:04.677Z,1676300584.677 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-02-13T15:03:04.683Z,1676300584.683 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-02-13T15:03:04.686Z,1676300584.686 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-02-13T15:03:04.698Z,1676300584.698 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-02-13T15:03:04.699Z,1676300584.699 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C0E4E0 2023-02-13T15:03:04.699Z,1676300584.699 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931 2023-02-13T15:03:04.703Z,1676300584.703 [Supervisor](INFO): Main Thread ID is 830 2023-02-13T15:03:04.703Z,1676300584.703 [Supervisor](DEBUG): Running supervisor. 2023-02-13T15:03:04.704Z,1676300584.704 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932 2023-02-13T15:03:04.705Z,1676300584.705 [CommandExec](INFO): Initializing the command executive. 2023-02-13T15:03:04.706Z,1676300584.706 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933 2023-02-13T15:03:04.708Z,1676300584.708 [controlThread ThreadHandler](INFO): Handler Thread ID is 934 2023-02-13T15:03:04.708Z,1676300584.708 [controlThread](DEBUG): Initializing ControlThread 2023-02-13T15:03:04.709Z,1676300584.709 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-02-13T15:03:04.711Z,1676300584.711 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-02-13T15:03:04.712Z,1676300584.712 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-02-13T15:03:04.712Z,1676300584.712 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-02-13T15:03:04.713Z,1676300584.713 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-02-13T15:03:04.713Z,1676300584.713 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-02-13T15:03:04.713Z,1676300584.713 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-02-13T15:03:04.714Z,1676300584.714 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-02-13T15:03:04.714Z,1676300584.714 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-02-13T15:03:04.721Z,1676300584.721 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-02-13T15:03:04.721Z,1676300584.721 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-02-13T15:03:04.722Z,1676300584.722 [SBIT](INFO): Initialize SBIT Component. 2023-02-13T15:03:04.722Z,1676300584.722 [SBIT](IMPORTANT): git: 2023-02-10 2023-02-13T15:03:04.722Z,1676300584.722 [SBIT](INFO): git hash: c406eb0a7e3d06ad2f4805df3abb847da8df30bc 2023-02-13T15:03:04.723Z,1676300584.723 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-02-13T15:03:04.724Z,1676300584.724 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-02-13T15:03:04.725Z,1676300584.725 [SBIT](INFO): Beginning SBIT in 49.000000 seconds. 2023-02-13T15:03:04.725Z,1676300584.725 [IBIT](INFO): Initialize IBIT Component. 2023-02-13T15:03:04.726Z,1676300584.726 [CBIT](DEBUG): Initialize CBIT Component. 2023-02-13T15:03:04.727Z,1676300584.727 [logger ThreadHandler](INFO): Handler Thread ID is 935 2023-02-13T15:03:04.738Z,1676300584.738 [CBIT](DEBUG): Initialized mux pins. 2023-02-13T15:03:04.738Z,1676300584.738 [CBIT](DEBUG): Initializing the watchdog timer. 2023-02-13T15:03:04.747Z,1676300584.747 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 936 2023-02-13T15:03:04.759Z,1676300584.759 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937 2023-02-13T15:03:04.760Z,1676300584.760 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-02-13T15:03:04.762Z,1676300584.762 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-02-13T15:03:04.763Z,1676300584.763 [CBIT](DEBUG): Initializing heartbeat. 2023-02-13T15:03:04.771Z,1676300584.771 [Onboard ThreadHandler](INFO): Handler Thread ID is 938 2023-02-13T15:03:04.788Z,1676300584.788 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939 2023-02-13T15:03:04.811Z,1676300584.811 [DAT ThreadHandler](INFO): Handler Thread ID is 940 2023-02-13T15:03:04.812Z,1676300584.812 [DAT](INFO): Powering up 2023-02-13T15:03:04.812Z,1676300584.812 [DAT](DEBUG): Initializing DAT. 2023-02-13T15:03:04.816Z,1676300584.816 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 942 2023-02-13T15:03:04.816Z,1676300584.816 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-02-13T15:03:04.820Z,1676300584.820 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 944 2023-02-13T15:03:04.823Z,1676300584.823 [WetLabsBB2FL](INFO): Powering up 2023-02-13T15:03:04.824Z,1676300584.824 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 946 2023-02-13T15:03:04.827Z,1676300584.827 [WetLabsUBAT](INFO): Powering up 2023-02-13T15:03:04.828Z,1676300584.828 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 948 2023-02-13T15:03:04.834Z,1676300584.834 [CBIT](DEBUG): Deactivating GF circuits. 2023-02-13T15:03:04.835Z,1676300584.835 [CBIT](DEBUG): Deactivating emergency mode. 2023-02-13T15:03:04.837Z,1676300584.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-02-13T15:03:04.837Z,1676300584.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-02-13T15:03:04.837Z,1676300584.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-02-13T15:03:04.837Z,1676300584.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-02-13T15:03:04.837Z,1676300584.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-02-13T15:03:04.838Z,1676300584.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-02-13T15:03:04.838Z,1676300584.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-02-13T15:03:04.838Z,1676300584.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-02-13T15:03:04.874Z,1676300584.874 [CBIT](DEBUG): Backplane powered. 2023-02-13T15:03:04.879Z,1676300584.879 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-02-13T15:03:04.916Z,1676300584.916 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-02-13T15:03:04.944Z,1676300584.944 [MissionManager](DEBUG): 2023-02-13T15:03:04.945Z,1676300584.945 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-02-13T15:03:05.030Z,1676300585.030 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-02-13T15:03:05.042Z,1676300585.042 [Default:A.Wait](DEBUG): Construct Wait. 2023-02-13T15:03:05.065Z,1676300585.065 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-02-13T15:03:05.085Z,1676300585.085 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-02-13T15:03:05.108Z,1676300585.108 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-02-13T15:03:05.114Z,1676300585.114 [Default:E.Execute](DEBUG): Construct Execute. 2023-02-13T15:03:05.137Z,1676300585.137 [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-02-13T15:03:05.142Z,1676300585.142 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-02-13T15:03:05.155Z,1676300585.155 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-02-13T15:03:05.194Z,1676300585.194 [Radio_Surface](INFO): Powering up 2023-02-13T15:03:05.329Z,1676300585.329 [MultiRay](INFO): Powering up MultiRay Lights 2023-02-13T15:03:05.363Z,1676300585.363 [Power24vConverter](INFO): Powering up. 2023-02-13T15:03:05.364Z,1676300585.364 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2023-02-13T15:03:05.416Z,1676300585.416 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-02-13T15:03:05.423Z,1676300585.423 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-02-13T15:03:05.424Z,1676300585.424 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-02-13T15:03:05.431Z,1676300585.431 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-02-13T15:03:05.432Z,1676300585.432 [MassServo](DEBUG): Initializing EZServoServo. 2023-02-13T15:03:05.439Z,1676300585.439 [MassServo](DEBUG): Initializing MassServo. 2023-02-13T15:03:05.439Z,1676300585.439 [RudderServo](DEBUG): Initializing EZServoServo. 2023-02-13T15:03:05.447Z,1676300585.447 [RudderServo](DEBUG): Initializing RudderServo. 2023-02-13T15:03:05.447Z,1676300585.447 [ThrusterServo](DEBUG): Initializing EZServoServo. 2023-02-13T15:03:05.455Z,1676300585.455 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2023-02-13T15:03:05.582Z,1676300585.582 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-02-13T15:03:05.582Z,1676300585.582 [DropWeight] Hardware Fault, FailCount= 1 2023-02-13T15:03:05.582Z,1676300585.582 [DropWeight](ERROR): Hardware Fault 2023-02-13T15:03:05.696Z,1676300585.696 [CommandExec](FAULT): Scheduling is paused 2023-02-13T15:03:05.697Z,1676300585.697 [CBIT](INFO): Critical error at 20230213T150305 2023-02-13T15:03:05.697Z,1676300585.697 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-02-13T15:03:05.707Z,1676300585.707 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-02-13T15:03:05.708Z,1676300585.708 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-02-13T15:03:06.302Z,1676300586.302 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2023-02-13T15:03:06.302Z,1676300586.302 [RudderServo](FAULT): Rudder failed to initialize 2023-02-13T15:03:06.303Z,1676300586.303 [RudderServo] Communications Fault, FailCount= 1 2023-02-13T15:03:06.303Z,1676300586.303 [RudderServo](ERROR): Communications Fault 2023-02-13T15:03:06.401Z,1676300586.401 [CBIT](INFO): Critical error at 20230213T150305 2023-02-13T15:03:06.403Z,1676300586.403 [CBIT](ERROR): Communications Fault in component: RudderServo 2023-02-13T15:03:06.691Z,1676300586.691 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-02-13T15:03:06.691Z,1676300586.691 [RudderServo](INFO): Powering down 2023-02-13T15:03:07.238Z,1676300587.238 [RudderServo](DEBUG): Initializing EZServoServo. 2023-02-13T15:03:07.359Z,1676300587.359 [RudderServo](DEBUG): Initializing RudderServo. 2023-02-13T15:03:07.363Z,1676300587.363 [CBIT](INFO): Clearing failed state for component RudderServo 2023-02-13T15:03:07.364Z,1676300587.364 [RudderServo] No Fault, FailCount= 1 2023-02-13T15:03:07.434Z,1676300587.434 [WetLabsBB2FL](INFO): Powering down 2023-02-13T15:03:08.754Z,1676300588.754 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-02-13T15:03:09.282Z,1676300589.282 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-02-13T15:03:09.690Z,1676300589.690 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-02-13T15:03:16.907Z,1676300596.907 [DAT](INFO): DAT read: 2023-02-13T15:03:16.908Z,1676300596.908 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-02-13T15:03:18.419Z,1676300598.419 [DAT](INFO): DAT read: MF Frequency Band 2023-02-13T15:03:18.420Z,1676300598.420 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2023-02-13T15:03:18.421Z,1676300598.421 [DAT](INFO): DAT read: Feb 10 2023 21:07:07 2023-02-13T15:03:19.427Z,1676300599.427 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-02-13T15:03:19.429Z,1676300599.429 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-02-13T15:03:19.429Z,1676300599.429 [DAT](INFO): commRate: 800 2023-02-13T15:03:21.494Z,1676300601.494 [DAT](INFO): entering command mode 2023-02-13T15:03:21.695Z,1676300601.695 [DAT](INFO): DAT read: 2023-02-13T15:03:21.695Z,1676300601.695 [DAT](INFO): DAT read: user:1> 2023-02-13T15:03:21.696Z,1676300601.696 [DAT](INFO): setting verbose to 3 2023-02-13T15:03:21.947Z,1676300601.947 [DAT](INFO): DAT read: user:1> 2023-02-13T15:03:21.948Z,1676300601.948 [DAT](INFO): DAT read: Verbose | 3 2023-02-13T15:03:21.948Z,1676300601.948 [DAT](INFO): set verbose to 3 2023-02-13T15:03:21.949Z,1676300601.949 [DAT](INFO): setting DatVerbose to 27440 2023-02-13T15:03:22.199Z,1676300602.199 [DAT](INFO): DAT read: user:2> 2023-02-13T15:03:22.200Z,1676300602.200 [DAT](INFO): DAT read: DatVerbose | 27440 2023-02-13T15:03:22.200Z,1676300602.200 [DAT](INFO): set DatVerbose to 27440 2023-02-13T15:03:22.201Z,1676300602.201 [DAT](INFO): setting transmit power to 8 2023-02-13T15:03:22.451Z,1676300602.451 [DAT](INFO): DAT read: user:3> 2023-02-13T15:03:22.452Z,1676300602.452 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-02-13T15:03:22.452Z,1676300602.452 [DAT](INFO): set transmit power to 8 2023-02-13T15:03:22.453Z,1676300602.453 [DAT](INFO): setting local address to 9 2023-02-13T15:03:22.703Z,1676300602.703 [DAT](INFO): DAT read: user:4> 2023-02-13T15:03:22.704Z,1676300602.704 [DAT](INFO): DAT read: LocalAddr | 9 2023-02-13T15:03:22.704Z,1676300602.704 [DAT](INFO): set local address to 9 2023-02-13T15:03:22.706Z,1676300602.706 [DAT](INFO): Setting time to: 15:3:22 And date to:2/13/2023 2023-02-13T15:03:22.956Z,1676300602.956 [DAT](INFO): DAT read: user:5> 2023-02-13T15:03:22.959Z,1676300602.959 [DAT](INFO): DAT read: Mon Feb 13, 2023 15:03:22 2023-02-13T15:03:22.960Z,1676300602.960 [DAT](INFO): Local DAT time set to Mon Feb 13, 2023 15:03:22 2023-02-13T15:03:33.856Z,1676300613.856 [NAL9602](INFO): Powering up NAL9602 2023-02-13T15:03:34.963Z,1676300614.963 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup. 2023-02-13T15:03:34.963Z,1676300614.963 [WetLabsUBAT] Communications Fault, FailCount= 1 2023-02-13T15:03:34.963Z,1676300614.963 [WetLabsUBAT](ERROR): Communications Fault 2023-02-13T15:03:35.248Z,1676300615.248 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT 2023-02-13T15:03:35.494Z,1676300615.494 [WetLabsUBAT](INFO): Powering down 2023-02-13T15:03:36.323Z,1676300616.323 [CBIT](INFO): Clearing failed state for component WetLabsUBAT 2023-02-13T15:03:36.323Z,1676300616.323 [WetLabsUBAT] No Fault, FailCount= 1 2023-02-13T15:03:36.719Z,1676300616.719 [WetLabsUBAT](INFO): Powering up 2023-02-13T15:03:44.756Z,1676300624.756 [NAL9602](INFO): NAL9602 initialized 2023-02-13T15:03:45.975Z,1676300625.975 [WetLabsUBAT](INFO): Powering down 2023-02-13T15:03:54.471Z,1676300634.471 [SBIT](IMPORTANT): Beginning Startup BIT 2023-02-13T15:03:54.475Z,1676300634.475 [CBIT](IMPORTANT): Beginning ground fault scan 2023-02-13T15:04:05.428Z,1676300645.428 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.014665 CHAN A1 (24V): 0.001292 CHAN A2 (12V): -0.007757 CHAN A3 (5V): -0.002193 CHAN B0 (3.3V): 0.000161 CHAN B1 (3.15aV): 0.000054 CHAN B2 (3.15bV): -0.000484 CHAN B3 (GND): 0.002392 OPEN: 0.005288 Full Scale: +/- 1 mA 2023-02-13T15:04:48.613Z,1676300688.613 [SBIT](IMPORTANT): SBIT PASSED 2023-02-13T15:04:48.614Z,1676300688.614 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-02-13T15:04:48.615Z,1676300688.615 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2023-02-13T15:04:48.615Z,1676300688.615 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere; 2023-02-13T15:04:48.618Z,1676300688.618 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour; 2023-02-13T15:04:48.618Z,1676300688.618 [SBIT](IMPORTANT): DAT.baud=115200 bit_per_second; 2023-02-13T15:04:48.618Z,1676300688.618 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2023-02-13T15:04:48.618Z,1676300688.618 [SBIT](IMPORTANT): DAT.surfaceThreshold=0 meter; 2023-02-13T15:04:48.619Z,1676300688.619 [SBIT](IMPORTANT): DAT.verbosity=2 count; 2023-02-13T15:04:48.619Z,1676300688.619 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2023-02-13T15:04:48.619Z,1676300688.619 [SBIT](IMPORTANT): Express none _.adinos; 2023-02-13T15:04:48.619Z,1676300688.619 [SBIT](IMPORTANT): Express none _.aother; 2023-02-13T15:04:48.619Z,1676300688.619 [SBIT](IMPORTANT): Express linearApproximation _.dinoflagellates 10000000000.000000 none; 2023-02-13T15:04:48.619Z,1676300688.619 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=24 ampere_hour; 2023-02-13T15:04:48.619Z,1676300688.619 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt; 2023-02-13T15:04:48.619Z,1676300688.619 [SBIT](IMPORTANT): Power24vConverter.simulateHardware=0 bool; 2023-02-13T15:04:48.620Z,1676300688.620 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool; 2023-02-13T15:04:48.621Z,1676300688.621 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=172.109746 cubic_centimeter; 2023-02-13T15:04:48.621Z,1676300688.621 [SBIT](IMPORTANT): VerticalControl.massDefault=7.328833 millimeter; 2023-02-13T15:04:49.047Z,1676300689.047 [MissionManager](IMPORTANT): Started mission Startup 2023-02-13T15:04:49.047Z,1676300689.047 [Startup] Running Loop=1 2023-02-13T15:04:49.047Z,1676300689.047 [Startup](DEBUG): Aggregate::initialize Startup 2023-02-13T15:04:49.048Z,1676300689.048 [Startup:A.GoToSurface] Running Loop=1 2023-02-13T15:04:49.048Z,1676300689.048 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-02-13T15:04:49.048Z,1676300689.048 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-02-13T15:04:49.049Z,1676300689.049 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-02-13T15:04:49.049Z,1676300689.049 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-02-13T15:04:49.049Z,1676300689.049 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-02-13T15:04:49.050Z,1676300689.050 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-02-13T15:04:49.051Z,1676300689.051 [Startup:StartupSatComms] Running Loop=1 2023-02-13T15:04:49.051Z,1676300689.051 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-02-13T15:04:49.051Z,1676300689.051 [Startup:StartupSatComms:A] Running Loop=1 2023-02-13T15:04:49.418Z,1676300689.418 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-02-13T15:05:36.029Z,1676300736.029 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005071 2023-02-13T15:05:49.264Z,1676300749.264 [Startup:StartupSatComms:A](INFO): Timed out from 2023-02-13T15:04:49.1Z 2023-02-13T15:05:49.264Z,1676300749.264 [Startup:StartupSatComms:A] Stopped 2023-02-13T15:05:49.264Z,1676300749.264 [Startup:StartupSatComms:B] Running Loop=1 2023-02-13T15:05:49.615Z,1676300749.615 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-02-13T15:05:56.535Z,1676300756.535 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20230213T150252/Courier0000.lzma 2023-02-13T15:05:57.536Z,1676300757.536 [DataOverHttps](INFO): Moved sent file to Logs/20230213T150252/Courier0000.lzma.bak 2023-02-13T15:05:57.536Z,1676300757.536 [DataOverHttps](INFO): SBD MOMSN=17464318 2023-02-13T15:06:05.020Z,1676300765.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-02-13T15:06:05.020Z,1676300765.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-13T15:06:05.041Z,1676300765.041 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-13T15:06:05.420Z,1676300765.420 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-13T15:06:05.420Z,1676300765.420 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-02-13T15:06:13.766Z,1676300773.766 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230213T150054/Express0001.lzma 2023-02-13T15:06:14.769Z,1676300774.769 [DataOverHttps](INFO): Moved sent file to Logs/20230213T150054/Express0001.lzma.bak 2023-02-13T15:06:14.770Z,1676300774.770 [DataOverHttps](INFO): SBD MOMSN=17464323 2023-02-13T15:06:30.896Z,1676300790.896 [DataOverHttps](INFO): Sending 1143 bytes from file Logs/20230213T150252/Express0001.lzma 2023-02-13T15:06:31.897Z,1676300791.897 [DataOverHttps](INFO): Moved sent file to Logs/20230213T150252/Express0001.lzma.bak 2023-02-13T15:06:31.897Z,1676300791.897 [DataOverHttps](INFO): SBD MOMSN=17464326 2023-02-13T15:06:33.270Z,1676300793.270 [Startup:StartupSatComms:B] Stopped 2023-02-13T15:06:33.270Z,1676300793.270 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-02-13T15:06:33.271Z,1676300793.271 [Startup:StartupSatComms] Stopped 2023-02-13T15:06:33.271Z,1676300793.271 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-02-13T15:06:33.271Z,1676300793.271 [Startup](INFO): Completed Startup 2023-02-13T15:06:33.272Z,1676300793.272 [MissionManager](INFO): Startup is completed. 2023-02-13T15:06:33.272Z,1676300793.272 [MissionManager](INFO): Uninitializing Mission Startup 2023-02-13T15:06:33.272Z,1676300793.272 [Startup] Stopped 2023-02-13T15:06:33.272Z,1676300793.272 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-02-13T15:06:33.272Z,1676300793.272 [Startup:A.GoToSurface] Stopped 2023-02-13T15:06:33.272Z,1676300793.272 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-02-13T15:06:33.644Z,1676300793.644 [MissionManager](IMPORTANT): Started mission Default 2023-02-13T15:06:33.645Z,1676300793.645 [Default] Running Loop=1 2023-02-13T15:06:33.645Z,1676300793.645 [Default](DEBUG): Aggregate::initialize Default 2023-02-13T15:06:33.645Z,1676300793.645 [Default:B.GoToSurface] Running Loop=1 2023-02-13T15:06:33.645Z,1676300793.645 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-02-13T15:06:33.645Z,1676300793.645 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-02-13T15:06:33.645Z,1676300793.645 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-02-13T15:06:33.646Z,1676300793.646 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-02-13T15:06:33.646Z,1676300793.646 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-02-13T15:06:33.647Z,1676300793.647 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-02-13T15:06:33.647Z,1676300793.647 [Default:A.Wait] Running Loop=1 2023-02-13T15:06:33.647Z,1676300793.647 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-02-13T15:06:47.037Z,1676300807.037 [Default:A.Wait](INFO): Done Waiting. 2023-02-13T15:06:47.037Z,1676300807.037 [Default:A.Wait] Stopped 2023-02-13T15:06:47.037Z,1676300807.037 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-02-13T15:06:47.406Z,1676300807.406 [Default:CheckIn] Running Loop=1 2023-02-13T15:06:47.423Z,1676300807.423 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-13T15:06:47.423Z,1676300807.423 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-13T15:06:47.785Z,1676300807.785 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-02-13T15:08:48.176Z,1676300928.176 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-13T15:09:05.959Z,1676300945.959 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-02-13T15:09:05.959Z,1676300945.959 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-13T15:09:05.997Z,1676300945.997 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-13T15:09:06.405Z,1676300946.405 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-13T15:09:06.405Z,1676300946.405 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-02-13T15:11:02.305Z,1676301062.305 [BPC1](ERROR): Battery stick #29 (s/n: 01CD) reported OVER_TEMP_ALARM. Status code: 0x1717. 2023-02-13T15:11:02.306Z,1676301062.306 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2023-02-13T15:11:02.309Z,1676301062.309 [BPC1](INFO): Received data from all battery sticks. 2023-02-13T15:11:47.562Z,1676301107.562 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-13T15:06:47.4Z 2023-02-13T15:11:47.562Z,1676301107.562 [Default:CheckIn:Read_GPS] Stopped 2023-02-13T15:11:47.563Z,1676301107.563 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-13T15:11:47.970Z,1676301107.970 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-02-13T15:11:55.150Z,1676301115.150 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230213T150252/Courier0004.lzma 2023-02-13T15:11:56.152Z,1676301116.152 [DataOverHttps](INFO): Moved sent file to Logs/20230213T150252/Courier0004.lzma.bak 2023-02-13T15:11:56.152Z,1676301116.152 [DataOverHttps](INFO): SBD MOMSN=17464358 2023-02-13T15:12:06.980Z,1676301126.980 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-02-13T15:12:06.980Z,1676301126.980 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-13T15:12:07.051Z,1676301127.051 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-13T15:12:07.403Z,1676301127.403 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-13T15:12:07.403Z,1676301127.403 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-02-13T15:12:13.618Z,1676301133.618 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20230213T150252/Express0005.lzma 2023-02-13T15:12:14.620Z,1676301134.620 [DataOverHttps](INFO): Moved sent file to Logs/20230213T150252/Express0005.lzma.bak 2023-02-13T15:12:14.620Z,1676301134.620 [DataOverHttps](INFO): SBD MOMSN=17464360 2023-02-13T15:12:15.904Z,1676301135.904 [Default:CheckIn:Read_Iridium] Stopped 2023-02-13T15:12:15.904Z,1676301135.904 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-13T15:12:15.904Z,1676301135.904 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-13T15:13:45.576Z,1676301225.576 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-02-13T15:13:45.576Z,1676301225.576 [NAL9602] Data Fault, FailCount= 1 2023-02-13T15:13:45.576Z,1676301225.576 [NAL9602](ERROR): Data Fault 2023-02-13T15:13:45.598Z,1676301225.598 [CBIT](ERROR): Data Fault in component: NAL9602 2023-02-13T15:13:45.985Z,1676301225.985 [NAL9602](INFO): Powering down 2023-02-13T15:13:46.809Z,1676301226.809 [CBIT](INFO): Clearing failed state for component NAL9602 2023-02-13T15:13:46.809Z,1676301226.809 [NAL9602] No Fault, FailCount= 1 2023-02-13T15:14:16.284Z,1676301256.284 [NAL9602](INFO): Powering up NAL9602 2023-02-13T15:14:27.194Z,1676301267.194 [NAL9602](INFO): NAL9602 initialized 2023-02-13T15:14:53.109Z,1676301293.109 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2023-02-13T15:15:08.004Z,1676301308.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-02-13T15:15:08.004Z,1676301308.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-13T15:15:08.015Z,1676301308.015 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-13T15:15:08.419Z,1676301308.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-13T15:15:08.419Z,1676301308.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-02-13T15:17:16.484Z,1676301436.484 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-13T15:17:16.485Z,1676301436.485 [Default:CheckIn:C.Wait] Stopped 2023-02-13T15:17:16.485Z,1676301436.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-13T15:17:16.485Z,1676301436.485 [Default:CheckIn:D] Running Loop=1 2023-02-13T15:17:16.893Z,1676301436.893 [Default:CheckIn:D] Stopped 2023-02-13T15:17:16.894Z,1676301436.894 [Default:CheckIn:E] Running Loop=1 2023-02-13T15:17:17.319Z,1676301437.319 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.720814 min 2023-02-13T15:17:17.319Z,1676301437.319 [Default:CheckIn:E] Stopped 2023-02-13T15:17:17.319Z,1676301437.319 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-13T15:17:17.319Z,1676301437.319 [Default:CheckIn] Stopped 2023-02-13T15:17:17.319Z,1676301437.319 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-13T15:17:17.320Z,1676301437.320 [Default:CheckIn](INFO): Running loop #2 2023-02-13T15:17:17.320Z,1676301437.320 [Default:CheckIn] Running Loop=2 2023-02-13T15:17:17.320Z,1676301437.320 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-13T15:17:17.320Z,1676301437.320 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-13T15:18:05.806Z,1676301485.806 [CBIT](INFO): Clearing failed state for component DropWeight 2023-02-13T15:18:05.806Z,1676301485.806 [DropWeight] No Fault, FailCount= 1 2023-02-13T15:18:09.060Z,1676301489.060 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-02-13T15:18:09.060Z,1676301489.060 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-13T15:18:09.087Z,1676301489.087 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-13T15:18:09.457Z,1676301489.457 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-13T15:18:09.457Z,1676301489.457 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-02-13T15:21:10.020Z,1676301670.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-02-13T15:21:10.020Z,1676301670.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-13T15:21:10.061Z,1676301670.061 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-13T15:21:10.412Z,1676301670.412 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-13T15:21:10.412Z,1676301670.412 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-02-13T15:22:17.495Z,1676301737.495 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-13T15:17:17.3Z 2023-02-13T15:22:17.495Z,1676301737.495 [Default:CheckIn:Read_GPS] Stopped 2023-02-13T15:22:17.495Z,1676301737.495 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-13T15:22:18.293Z,1676301738.293 [Default:CheckIn:Read_Iridium] Stopped 2023-02-13T15:22:18.293Z,1676301738.293 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-13T15:22:18.293Z,1676301738.293 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-13T15:22:43.356Z,1676301763.356 [CommandExec](IMPORTANT): got command ! /etc/rc.d/settime restart 2023-02-13T15:24:10.992Z,1676301850.992 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-02-13T15:24:10.992Z,1676301850.992 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-13T15:24:11.004Z,1676301851.004 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-13T15:24:11.350Z,1676301851.350 [CommandExec](IMPORTANT): got command ! /etc/rc.d/init.d/settime restart 2023-02-13T15:24:11.508Z,1676301851.508 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-13T15:24:11.508Z,1676301851.508 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-02-13T15:24:14.091Z,1676301854.091 [CommandExec](IMPORTANT): settime stop or restart Setting hardware clock from system clock Mon Feb 13 15:24:11 2023 0.000000 seconds settime start or restart Setting clock frequency and tick values from nonvolatile memory. mode: 16386 -o offset: -1164 -f frequency: 794192 maxerror: 135479 esterror: 27205 status: 1 (PLL) -p timeconstant: 6 precision: 1 tolerance: 32768000 -t tick: 10000 time.tv_sec: 1676301851 time.tv_usec: 697016 return value: 0 (clock synchronized) ping returned from NTP server: 216.239.35.4 44968 55452.915 43011.0 0.0 -2347.7 91.6 794192 Time set from NTP server: 216.239.35.4 Setting hardware clock from system clock Mon Feb 13 15:24:13 2023 0.000000 seconds NTPD already running. 2023-02-13T15:24:30.350Z,1676301870.350 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-02-13T15:24:30.350Z,1676301870.350 [NAL9602] Data Fault, FailCount= 2 2023-02-13T15:24:30.350Z,1676301870.350 [NAL9602](ERROR): Data Fault 2023-02-13T15:24:30.376Z,1676301870.376 [CBIT](ERROR): Data Fault in component: NAL9602 2023-02-13T15:24:30.748Z,1676301870.748 [NAL9602](INFO): Powering down 2023-02-13T15:24:31.578Z,1676301871.578 [CBIT](INFO): Clearing failed state for component NAL9602 2023-02-13T15:24:31.578Z,1676301871.578 [NAL9602] No Fault, FailCount= 2 2023-02-13T15:25:01.057Z,1676301901.057 [NAL9602](INFO): Powering up NAL9602 2023-02-13T15:25:11.960Z,1676301911.960 [NAL9602](INFO): NAL9602 initialized 2023-02-13T15:25:37.067Z,1676301937.067 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2023-02-13T15:25:37.067Z,1676301937.067 [RDI_Pathfinder] Communications Fault, FailCount= 1 2023-02-13T15:25:37.067Z,1676301937.067 [RDI_Pathfinder](ERROR): Communications Fault 2023-02-13T15:25:37.117Z,1676301937.117 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2023-02-13T15:25:37.499Z,1676301937.499 [RDI_Pathfinder](INFO): Powering down 2023-02-13T15:25:38.262Z,1676301938.262 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2023-02-13T15:25:38.262Z,1676301938.262 [RDI_Pathfinder] No Fault, FailCount= 1 2023-02-13T15:25:38.625Z,1676301938.625 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2023-02-13T15:27:11.981Z,1676302031.981 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2023-02-13T15:27:11.982Z,1676302031.982 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-13T15:27:12.042Z,1676302032.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-13T15:27:12.398Z,1676302032.398 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-13T15:27:12.398Z,1676302032.398 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2023-02-13T15:27:18.825Z,1676302038.825 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-13T15:27:18.825Z,1676302038.825 [Default:CheckIn:C.Wait] Stopped 2023-02-13T15:27:18.825Z,1676302038.825 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-13T15:27:18.825Z,1676302038.825 [Default:CheckIn:D] Running Loop=1 2023-02-13T15:27:19.221Z,1676302039.221 [Default:CheckIn:D] Stopped 2023-02-13T15:27:19.222Z,1676302039.222 [Default:CheckIn:E] Running Loop=1 2023-02-13T15:27:19.647Z,1676302039.647 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.759613 min 2023-02-13T15:27:19.647Z,1676302039.647 [Default:CheckIn:E] Stopped 2023-02-13T15:27:19.647Z,1676302039.647 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-13T15:27:19.647Z,1676302039.647 [Default:CheckIn] Stopped 2023-02-13T15:27:19.647Z,1676302039.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-13T15:27:19.647Z,1676302039.647 [Default:CheckIn](INFO): Running loop #3 2023-02-13T15:27:19.647Z,1676302039.647 [Default:CheckIn] Running Loop=3 2023-02-13T15:27:19.648Z,1676302039.648 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-13T15:27:19.648Z,1676302039.648 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-13T15:28:58.017Z,1676302138.017 [CommandExec](IMPORTANT): got command burn on 2023-02-13T15:28:58.018Z,1676302138.018 [CommandExec](IMPORTANT): Activating dropweight wire 2023-02-13T15:28:59.010Z,1676302139.010 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-02-13T15:28:59.010Z,1676302139.010 [DropWeight] Hardware Fault, FailCount= 1 2023-02-13T15:28:59.010Z,1676302139.010 [DropWeight](ERROR): Hardware Fault 2023-02-13T15:28:59.026Z,1676302139.026 [CBIT](INFO): Critical error at 20230213T152859 2023-02-13T15:28:59.029Z,1676302139.029 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-02-13T15:28:59.029Z,1676302139.029 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-02-13T15:28:59.445Z,1676302139.445 [CBIT](INFO): Critical error at 20230213T152859 2023-02-13T15:29:37.515Z,1676302177.515 [CommandExec](IMPORTANT): got command burn off 2023-02-13T15:29:37.516Z,1676302177.516 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-02-13T15:30:12.973Z,1676302212.973 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2023-02-13T15:30:12.973Z,1676302212.973 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-13T15:30:12.984Z,1676302212.984 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-13T15:30:13.365Z,1676302213.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-13T15:30:13.365Z,1676302213.365 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2023-02-13T15:30:18.959Z,1676302218.959 [CommandExec](IMPORTANT): got command failComponent 2023-02-13T15:30:18.959Z,1676302218.959 [CommandExec](IMPORTANT): Failed components: 2023-02-13T15:30:18.959Z,1676302218.959 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2023-02-13T15:31:56.604Z,1676302316.604 [CommandExec](IMPORTANT): got command failComponent 2023-02-13T15:31:56.604Z,1676302316.604 [CommandExec](IMPORTANT): Failed components: 2023-02-13T15:31:56.604Z,1676302316.604 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2023-02-13T15:32:02.220Z,1676302322.220 [CommandExec](IMPORTANT): got command restart system 2023-02-13T15:32:04.347Z,1676302324.347 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:04.347Z,1676302324.347 [CommandExec](INFO): Uninitializing the command executive. 2023-02-13T15:32:04.347Z,1676302324.347 [CommandExec](INFO): Uninitializing the command scheduler. 2023-02-13T15:32:04.347Z,1676302324.347 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:04.510Z,1676302324.510 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-02-13T15:32:04.511Z,1676302324.511 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-02-13T15:32:04.511Z,1676302324.511 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:04.511Z,1676302324.511 [NavChartDb](INFO): Join timeout helper Thread ID is 1009 2023-02-13T15:32:04.619Z,1676302324.619 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:04.619Z,1676302324.619 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:04.635Z,1676302324.635 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2023-02-13T15:32:04.635Z,1676302324.635 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:04.635Z,1676302324.635 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1010 2023-02-13T15:32:04.719Z,1676302324.719 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:04.719Z,1676302324.719 [WetLabsUBAT](INFO): Powering down 2023-02-13T15:32:04.720Z,1676302324.720 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:04.738Z,1676302324.738 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-02-13T15:32:04.739Z,1676302324.739 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:04.739Z,1676302324.739 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1011 2023-02-13T15:32:04.907Z,1676302324.907 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:04.907Z,1676302324.907 [WetLabsBB2FL](INFO): Powering down 2023-02-13T15:32:04.908Z,1676302324.908 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:04.918Z,1676302324.918 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-02-13T15:32:04.918Z,1676302324.918 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:04.919Z,1676302324.919 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1012 2023-02-13T15:32:05.155Z,1676302325.155 [CTD_Seabird](INFO): Powering down 2023-02-13T15:32:05.167Z,1676302325.167 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:05.167Z,1676302325.167 [CTD_Seabird](INFO): Powering down 2023-02-13T15:32:05.179Z,1676302325.179 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:05.179Z,1676302325.179 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-02-13T15:32:05.179Z,1676302325.179 [DAT ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:05.179Z,1676302325.179 [DAT](INFO): Join timeout helper Thread ID is 1013 2023-02-13T15:32:05.367Z,1676302325.367 [DAT](INFO): Powering down 2023-02-13T15:32:05.439Z,1676302325.439 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:05.439Z,1676302325.439 [DAT](INFO): Powering down 2023-02-13T15:32:05.440Z,1676302325.440 [DAT ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:05.463Z,1676302325.463 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-02-13T15:32:05.463Z,1676302325.463 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:05.464Z,1676302325.464 [Radio_Surface](INFO): Join timeout helper Thread ID is 1014 2023-02-13T15:32:05.807Z,1676302325.807 [Radio_Surface](INFO): Powering down 2023-02-13T15:32:05.807Z,1676302325.807 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:05.808Z,1676302325.808 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:05.811Z,1676302325.811 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-02-13T15:32:05.811Z,1676302325.811 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:05.812Z,1676302325.812 [Onboard](INFO): Join timeout helper Thread ID is 1015 2023-02-13T15:32:06.195Z,1676302326.195 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:06.195Z,1676302326.195 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.199Z,1676302326.199 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-02-13T15:32:06.199Z,1676302326.199 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.200Z,1676302326.200 [DataOverHttps](INFO): Join timeout helper Thread ID is 1016 2023-02-13T15:32:06.662Z,1676302326.662 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:06.663Z,1676302326.663 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.667Z,1676302326.667 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-02-13T15:32:06.667Z,1676302326.667 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.668Z,1676302326.668 [BackseatComponent](INFO): Join timeout helper Thread ID is 1017 2023-02-13T15:32:06.803Z,1676302326.803 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:06.803Z,1676302326.803 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.807Z,1676302326.807 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-02-13T15:32:06.807Z,1676302326.807 [logger ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.808Z,1676302326.808 [logger](INFO): Join timeout helper Thread ID is 1018 2023-02-13T15:32:06.811Z,1676302326.811 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:06.811Z,1676302326.811 [logger ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.827Z,1676302326.827 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-02-13T15:32:06.827Z,1676302326.827 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.828Z,1676302326.828 [CommandLine](INFO): Join timeout helper Thread ID is 1019 2023-02-13T15:32:06.879Z,1676302326.879 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:06.879Z,1676302326.879 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.887Z,1676302326.887 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-02-13T15:32:06.887Z,1676302326.887 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.888Z,1676302326.888 [CommandExec](INFO): Join timeout helper Thread ID is 1020 2023-02-13T15:32:06.889Z,1676302326.889 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-02-13T15:32:06.889Z,1676302326.889 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:06.889Z,1676302326.889 [controlThread](INFO): Join timeout helper Thread ID is 1021 2023-02-13T15:32:07.255Z,1676302327.255 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-13T15:32:07.255Z,1676302327.255 [controlThread](DEBUG): Uninitializing ControlThread 2023-02-13T15:32:07.255Z,1676302327.255 [AHRS_M2](INFO): Powering down 2023-02-13T15:32:07.327Z,1676302327.327 [MultiRay](INFO): Powering down. 2023-02-13T15:32:07.328Z,1676302327.328 [NAL9602](INFO): Powering down 2023-02-13T15:32:07.399Z,1676302327.399 [RDI_Pathfinder](INFO): Powering down 2023-02-13T15:32:07.400Z,1676302327.400 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-02-13T15:32:07.401Z,1676302327.401 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-02-13T15:32:07.401Z,1676302327.401 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-02-13T15:32:07.402Z,1676302327.402 [MissionManager](INFO): Uninitializing Mission Default 2023-02-13T15:32:07.402Z,1676302327.402 [Default] Stopped 2023-02-13T15:32:07.402Z,1676302327.402 [Default](DEBUG): Aggregate::uninitialize Default 2023-02-13T15:32:07.402Z,1676302327.402 [Default:B.GoToSurface] Stopped 2023-02-13T15:32:07.402Z,1676302327.402 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-02-13T15:32:07.402Z,1676302327.402 [Default:CheckIn] Stopped 2023-02-13T15:32:07.402Z,1676302327.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-13T15:32:07.403Z,1676302327.403 [Default:CheckIn:Read_GPS] Stopped 2023-02-13T15:32:07.405Z,1676302327.405 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-02-13T15:32:07.406Z,1676302327.406 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-02-13T15:32:07.406Z,1676302327.406 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-02-13T15:32:07.407Z,1676302327.407 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-02-13T15:32:07.407Z,1676302327.407 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-02-13T15:32:07.407Z,1676302327.407 [BuoyancyServo](INFO): Powering down 2023-02-13T15:32:07.418Z,1676302327.418 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-02-13T15:32:07.419Z,1676302327.419 [ElevatorServo](INFO): Powering down 2023-02-13T15:32:07.420Z,1676302327.420 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-02-13T15:32:07.420Z,1676302327.420 [MassServo](INFO): Powering down 2023-02-13T15:32:07.421Z,1676302327.421 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-02-13T15:32:07.421Z,1676302327.421 [RudderServo](INFO): Powering down 2023-02-13T15:32:07.422Z,1676302327.422 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-02-13T15:32:07.422Z,1676302327.422 [ThrusterServo](INFO): Powering down 2023-02-13T15:32:07.423Z,1676302327.423 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-02-13T15:32:07.423Z,1676302327.423 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-02-13T15:32:07.423Z,1676302327.423 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-02-13T15:32:07.424Z,1676302327.424 [CBIT](DEBUG): Powering off loads. 2023-02-13T15:32:07.434Z,1676302327.434 [CBIT](DEBUG): Disabling WDT. 2023-02-13T15:32:07.446Z,1676302327.446 [CBIT](DEBUG): Opening all GF detection circuits. 2023-02-13T15:32:07.447Z,1676302327.447 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.513Z,1676302327.513 [DAT ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.521Z,1676302327.521 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.523Z,1676302327.523 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.530Z,1676302327.530 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.575Z,1676302327.575 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.636Z,1676302327.636 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.638Z,1676302327.638 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.641Z,1676302327.641 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.650Z,1676302327.650 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-13T15:32:07.712Z,1676302327.712 [logger ThreadHandler](INFO): Thread cancelled.