2024-05-08T18:47:28.348Z,1715194048.348 [Supervisor](DEBUG): Initializing supervisor. 2024-05-08T18:47:28.352Z,1715194048.352 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-05-08T18:47:28.353Z,1715194048.353 [SyncHandler](INFO): Protected caller Thread ID is 829 2024-05-08T18:47:28.353Z,1715194048.353 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-05-08T18:47:28.354Z,1715194048.354 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-05-08T18:47:28.355Z,1715194048.355 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 830 2024-05-08T18:47:28.359Z,1715194048.359 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-05-08T18:47:28.377Z,1715194048.377 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-05-08T18:47:28.378Z,1715194048.378 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-05-08T18:47:28.379Z,1715194048.379 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 831 2024-05-08T18:47:28.383Z,1715194048.383 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-05-08T18:47:28.384Z,1715194048.384 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-05-08T18:47:28.384Z,1715194048.384 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 832 2024-05-08T18:47:28.387Z,1715194048.387 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-05-08T18:47:28.388Z,1715194048.388 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-05-08T18:47:28.388Z,1715194048.388 [logger ThreadHandler](INFO): Protected caller Thread ID is 833 2024-05-08T18:47:28.392Z,1715194048.392 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-05-08T18:47:28.392Z,1715194048.392 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-05-08T18:47:28.398Z,1715194048.398 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-05-08T18:47:28.506Z,1715194048.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-05-08T18:47:28.508Z,1715194048.508 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-05-08T18:47:28.762Z,1715194048.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-05-08T18:47:28.764Z,1715194048.764 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-05-08T18:47:29.062Z,1715194049.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-05-08T18:47:29.063Z,1715194049.063 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-05-08T18:47:29.187Z,1715194049.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-05-08T18:47:29.189Z,1715194049.189 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-05-08T18:47:29.998Z,1715194049.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-05-08T18:47:29.999Z,1715194049.999 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-05-08T18:47:30.555Z,1715194050.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-05-08T18:47:30.557Z,1715194050.557 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-05-08T18:47:31.138Z,1715194051.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-05-08T18:47:31.139Z,1715194051.139 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-05-08T18:47:31.591Z,1715194051.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-05-08T18:47:31.789Z,1715194051.789 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-05-08T18:47:32.268Z,1715194052.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-05-08T18:47:32.268Z,1715194052.268 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-05-08T18:47:32.676Z,1715194052.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-05-08T18:47:32.676Z,1715194052.676 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-05-08T18:47:32.934Z,1715194052.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-05-08T18:47:32.935Z,1715194052.935 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-05-08T18:47:33.092Z,1715194053.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-05-08T18:47:33.092Z,1715194053.092 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-05-08T18:47:33.187Z,1715194053.187 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-05-08T18:47:33.278Z,1715194053.278 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-05-08T18:47:33.486Z,1715194053.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-05-08T18:47:33.488Z,1715194053.488 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-05-08T18:47:33.740Z,1715194053.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-05-08T18:47:33.742Z,1715194053.742 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2024-05-08T18:47:33.746Z,1715194053.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2024-05-08T18:47:33.842Z,1715194053.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2024-05-08T18:47:34.120Z,1715194054.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-05-08T18:47:34.120Z,1715194054.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2024-05-08T18:47:34.228Z,1715194054.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2024-05-08T18:47:34.409Z,1715194054.409 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2024-05-08T18:47:34.523Z,1715194054.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2024-05-08T18:47:34.648Z,1715194054.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2024-05-08T18:47:34.776Z,1715194054.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2024-05-08T18:47:34.897Z,1715194054.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2024-05-08T18:47:35.077Z,1715194055.077 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2024-05-08T18:47:35.188Z,1715194055.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2024-05-08T18:47:35.300Z,1715194055.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2024-05-08T18:47:35.402Z,1715194055.402 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2024-05-08T18:47:35.408Z,1715194055.408 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-05-08T18:47:35.633Z,1715194055.633 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-05-08T18:47:35.634Z,1715194055.634 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-05-08T18:47:35.827Z,1715194055.827 [BuoyancyServo] Loaded 2024-05-08T18:47:35.828Z,1715194055.828 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-05-08T18:47:35.849Z,1715194055.849 [ElevatorServo] Loaded 2024-05-08T18:47:35.849Z,1715194055.849 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-05-08T18:47:35.870Z,1715194055.870 [MassServo] Loaded 2024-05-08T18:47:35.870Z,1715194055.870 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-05-08T18:47:35.891Z,1715194055.891 [RudderServo] Loaded 2024-05-08T18:47:35.891Z,1715194055.891 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-05-08T18:47:35.907Z,1715194055.907 [ThrusterHE] Loaded 2024-05-08T18:47:35.908Z,1715194055.908 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-05-08T18:47:35.908Z,1715194055.908 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-05-08T18:47:35.910Z,1715194055.910 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-05-08T18:47:36.109Z,1715194056.109 [SBIT](DEBUG): Construct Startup Built In Test. 2024-05-08T18:47:36.124Z,1715194056.124 [SBIT] Loaded 2024-05-08T18:47:36.124Z,1715194056.124 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-05-08T18:47:36.127Z,1715194056.127 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-05-08T18:47:36.148Z,1715194056.148 [IBIT] Loaded 2024-05-08T18:47:36.149Z,1715194056.149 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-05-08T18:47:36.154Z,1715194056.154 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-05-08T18:47:36.374Z,1715194056.374 [CBIT] Loaded 2024-05-08T18:47:36.374Z,1715194056.374 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-05-08T18:47:36.374Z,1715194056.374 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-05-08T18:47:36.375Z,1715194056.375 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-05-08T18:47:36.598Z,1715194056.598 [CTD_Seabird] Loaded 2024-05-08T18:47:36.599Z,1715194056.599 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-05-08T18:47:36.600Z,1715194056.600 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0 2024-05-08T18:47:36.600Z,1715194056.600 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 919 2024-05-08T18:47:36.632Z,1715194056.632 [ESPComponent] Loaded 2024-05-08T18:47:36.633Z,1715194056.633 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-05-08T18:47:36.652Z,1715194056.652 [PAR_Licor] Loaded 2024-05-08T18:47:36.653Z,1715194056.653 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-05-08T18:47:36.696Z,1715194056.696 [WetLabsBB2FL] Loaded 2024-05-08T18:47:36.696Z,1715194056.696 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-05-08T18:47:36.697Z,1715194056.697 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0 2024-05-08T18:47:36.698Z,1715194056.698 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 920 2024-05-08T18:47:36.698Z,1715194056.698 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-05-08T18:47:36.699Z,1715194056.699 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-05-08T18:47:36.948Z,1715194056.948 [DepthRateCalculator] Loaded 2024-05-08T18:47:36.948Z,1715194056.948 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-05-08T18:47:36.954Z,1715194056.954 [PitchRateCalculator] Loaded 2024-05-08T18:47:36.954Z,1715194056.954 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-05-08T18:47:36.964Z,1715194056.964 [SpeedCalculator] Loaded 2024-05-08T18:47:36.965Z,1715194056.965 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-05-08T18:47:36.970Z,1715194056.970 [YawRateCalculator] Loaded 2024-05-08T18:47:36.970Z,1715194056.970 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-05-08T18:47:36.991Z,1715194056.991 [ElevatorOffsetCalculator] Loaded 2024-05-08T18:47:36.991Z,1715194056.991 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-05-08T18:47:36.992Z,1715194056.992 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-05-08T18:47:36.992Z,1715194056.992 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-05-08T18:47:37.138Z,1715194057.138 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-05-08T18:47:37.139Z,1715194057.139 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-05-08T18:47:37.183Z,1715194057.183 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-05-08T18:47:37.184Z,1715194057.184 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-05-08T18:47:37.323Z,1715194057.323 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-05-08T18:47:37.324Z,1715194057.324 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-05-08T18:47:37.338Z,1715194057.338 [NavChart] Loaded 2024-05-08T18:47:37.338Z,1715194057.338 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-05-08T18:47:37.344Z,1715194057.344 [UniversalFixResidualReporter] Loaded 2024-05-08T18:47:37.344Z,1715194057.344 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-05-08T18:47:37.345Z,1715194057.345 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-05-08T18:47:37.345Z,1715194057.345 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-05-08T18:47:38.187Z,1715194058.187 [AHRS_M2] Loaded 2024-05-08T18:47:38.187Z,1715194058.187 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-05-08T18:47:38.436Z,1715194058.436 [BackseatComponent] Loaded 2024-05-08T18:47:38.437Z,1715194058.437 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-05-08T18:47:38.438Z,1715194058.438 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0 2024-05-08T18:47:38.438Z,1715194058.438 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921 2024-05-08T18:47:38.441Z,1715194058.441 [LcmUniversalReporter] Loaded 2024-05-08T18:47:38.441Z,1715194058.441 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-05-08T18:47:39.174Z,1715194059.174 [BPC1] Loaded 2024-05-08T18:47:39.174Z,1715194059.174 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-05-08T18:47:39.316Z,1715194059.316 [DataOverHttps] Loaded 2024-05-08T18:47:39.316Z,1715194059.316 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-05-08T18:47:39.317Z,1715194059.317 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0 2024-05-08T18:47:39.318Z,1715194059.318 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922 2024-05-08T18:47:39.338Z,1715194059.338 [Depth_Keller] Loaded 2024-05-08T18:47:39.339Z,1715194059.339 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-05-08T18:47:39.344Z,1715194059.344 [DropWeight] Loaded 2024-05-08T18:47:39.344Z,1715194059.344 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-05-08T18:47:39.383Z,1715194059.383 [DVL_micro] Loaded 2024-05-08T18:47:39.384Z,1715194059.384 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2024-05-08T18:47:39.445Z,1715194059.445 [NAL9602] Loaded 2024-05-08T18:47:39.445Z,1715194059.445 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-05-08T18:47:39.472Z,1715194059.472 [Onboard] Loaded 2024-05-08T18:47:39.472Z,1715194059.472 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-05-08T18:47:39.473Z,1715194059.473 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AB34E0 2024-05-08T18:47:39.473Z,1715194059.473 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923 2024-05-08T18:47:39.486Z,1715194059.486 [Power24vConverter] Loaded 2024-05-08T18:47:39.486Z,1715194059.486 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-05-08T18:47:39.499Z,1715194059.499 [Radio_Surface] Loaded 2024-05-08T18:47:39.500Z,1715194059.500 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-05-08T18:47:39.501Z,1715194059.501 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AE34E0 2024-05-08T18:47:39.501Z,1715194059.501 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924 2024-05-08T18:47:39.597Z,1715194059.597 [DAT] Loaded 2024-05-08T18:47:39.597Z,1715194059.597 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-05-08T18:47:39.598Z,1715194059.598 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B134E0 2024-05-08T18:47:39.598Z,1715194059.598 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925 2024-05-08T18:47:39.599Z,1715194059.599 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-05-08T18:47:39.600Z,1715194059.600 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-05-08T18:47:39.684Z,1715194059.684 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-05-08T18:47:39.686Z,1715194059.686 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-05-08T18:47:39.760Z,1715194059.760 [VerticalControl](DEBUG): Construct VerticalControl. 2024-05-08T18:47:39.812Z,1715194059.812 [VerticalControl] Loaded 2024-05-08T18:47:39.813Z,1715194059.813 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-05-08T18:47:39.816Z,1715194059.816 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-05-08T18:47:39.861Z,1715194059.861 [HorizontalControl] Loaded 2024-05-08T18:47:39.861Z,1715194059.861 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-05-08T18:47:39.863Z,1715194059.863 [SpeedControl](DEBUG): Construct SpeedControl. 2024-05-08T18:47:39.865Z,1715194059.865 [SpeedControl] Loaded 2024-05-08T18:47:39.866Z,1715194059.866 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-05-08T18:47:39.868Z,1715194059.868 [LoopControl](DEBUG): Construct LoopControl. 2024-05-08T18:47:39.869Z,1715194059.869 [LoopControl] Loaded 2024-05-08T18:47:39.869Z,1715194059.869 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-05-08T18:47:39.870Z,1715194059.870 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-05-08T18:47:39.870Z,1715194059.870 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-05-08T18:47:39.886Z,1715194059.886 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-05-08T18:47:39.887Z,1715194059.887 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-05-08T18:47:40.303Z,1715194060.303 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-05-08T18:47:40.310Z,1715194060.310 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-05-08T18:47:40.313Z,1715194060.313 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-05-08T18:47:40.324Z,1715194060.324 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-05-08T18:47:40.325Z,1715194060.325 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0 2024-05-08T18:47:40.326Z,1715194060.326 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926 2024-05-08T18:47:40.330Z,1715194060.330 [Supervisor](INFO): Main Thread ID is 822 2024-05-08T18:47:40.330Z,1715194060.330 [Supervisor](DEBUG): Running supervisor. 2024-05-08T18:47:40.331Z,1715194060.331 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927 2024-05-08T18:47:40.331Z,1715194060.331 [CommandExec](INFO): Initializing the command executive. 2024-05-08T18:47:40.333Z,1715194060.333 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928 2024-05-08T18:47:40.335Z,1715194060.335 [controlThread ThreadHandler](INFO): Handler Thread ID is 929 2024-05-08T18:47:40.335Z,1715194060.335 [controlThread](DEBUG): Initializing ControlThread 2024-05-08T18:47:40.337Z,1715194060.337 [SBIT](INFO): Initialize SBIT Component. 2024-05-08T18:47:40.338Z,1715194060.338 [SBIT](IMPORTANT): git: 2024-05-06 2024-05-08T18:47:40.338Z,1715194060.338 [SBIT](INFO): git hash: 5fc11fab0683feef1094efea7282b340ac202615 2024-05-08T18:47:40.338Z,1715194060.338 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-05-08T18:47:40.340Z,1715194060.340 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-05-08T18:47:40.341Z,1715194060.341 [SBIT](INFO): Beginning SBIT in 107.000000 seconds. 2024-05-08T18:47:40.341Z,1715194060.341 [IBIT](INFO): Initialize IBIT Component. 2024-05-08T18:47:40.342Z,1715194060.342 [CBIT](DEBUG): Initialize CBIT Component. 2024-05-08T18:47:40.343Z,1715194060.343 [logger ThreadHandler](INFO): Handler Thread ID is 930 2024-05-08T18:47:40.354Z,1715194060.354 [CBIT](DEBUG): Initialized mux pins. 2024-05-08T18:47:40.354Z,1715194060.354 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2024-05-08T18:47:40.354Z,1715194060.354 [CBIT](DEBUG): Initializing the watchdog timer. 2024-05-08T18:47:40.362Z,1715194060.362 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 931 2024-05-08T18:47:40.363Z,1715194060.363 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-05-08T18:47:40.367Z,1715194060.367 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 933 2024-05-08T18:47:40.370Z,1715194060.370 [WetLabsBB2FL](INFO): Powering up 2024-05-08T18:47:40.371Z,1715194060.371 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935 2024-05-08T18:47:40.378Z,1715194060.378 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2024-05-08T18:47:40.378Z,1715194060.378 [CBIT](DEBUG): Initializing heartbeat. 2024-05-08T18:47:40.379Z,1715194060.379 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2024-05-08T18:47:40.381Z,1715194060.381 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-05-08T18:47:40.394Z,1715194060.394 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2024-05-08T18:47:40.411Z,1715194060.411 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2024-05-08T18:47:40.430Z,1715194060.430 [DAT ThreadHandler](INFO): Handler Thread ID is 939 2024-05-08T18:47:40.435Z,1715194060.435 [DAT](INFO): Powering up 2024-05-08T18:47:40.435Z,1715194060.435 [DAT](DEBUG): Initializing DAT. 2024-05-08T18:47:40.438Z,1715194060.438 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941 2024-05-08T18:47:40.445Z,1715194060.445 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-05-08T18:47:40.445Z,1715194060.445 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-05-08T18:47:40.445Z,1715194060.445 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-05-08T18:47:40.445Z,1715194060.445 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-05-08T18:47:40.446Z,1715194060.446 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-05-08T18:47:40.446Z,1715194060.446 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-05-08T18:47:40.446Z,1715194060.446 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-05-08T18:47:40.446Z,1715194060.446 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-05-08T18:47:40.450Z,1715194060.450 [CBIT](DEBUG): Deactivating GF circuits. 2024-05-08T18:47:40.450Z,1715194060.450 [CBIT](DEBUG): Deactivating emergency mode. 2024-05-08T18:47:40.490Z,1715194060.490 [CBIT](DEBUG): Backplane powered. 2024-05-08T18:47:40.494Z,1715194060.494 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-05-08T18:47:40.494Z,1715194060.494 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-05-08T18:47:40.495Z,1715194060.495 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-05-08T18:47:40.495Z,1715194060.495 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-05-08T18:47:40.495Z,1715194060.495 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-05-08T18:47:40.497Z,1715194060.497 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-05-08T18:47:40.497Z,1715194060.497 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-05-08T18:47:40.504Z,1715194060.504 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-05-08T18:47:40.510Z,1715194060.510 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-05-08T18:47:40.510Z,1715194060.510 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-05-08T18:47:40.511Z,1715194060.511 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-05-08T18:47:40.512Z,1715194060.512 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-05-08T18:47:40.512Z,1715194060.512 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-05-08T18:47:40.513Z,1715194060.513 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-05-08T18:47:40.519Z,1715194060.519 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-05-08T18:47:40.604Z,1715194060.604 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-05-08T18:47:40.608Z,1715194060.608 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-08T18:47:40.645Z,1715194060.645 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-05-08T18:47:40.646Z,1715194060.646 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-05-08T18:47:40.646Z,1715194060.646 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-05-08T18:47:40.655Z,1715194060.655 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-05-08T18:47:40.818Z,1715194060.818 [Radio_Surface](INFO): Powering up 2024-05-08T18:47:40.984Z,1715194060.984 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-05-08T18:47:41.010Z,1715194061.010 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-05-08T18:47:41.012Z,1715194061.012 [Default:A.Wait](DEBUG): Construct Wait. 2024-05-08T18:47:41.022Z,1715194061.022 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-08T18:47:41.049Z,1715194061.049 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-05-08T18:47:41.072Z,1715194061.072 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-05-08T18:47:41.094Z,1715194061.094 [Default:E.Execute](DEBUG): Construct Execute. 2024-05-08T18:47:41.097Z,1715194061.097 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-05-08T18:47:41.102Z,1715194061.102 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-05-08T18:47:41.112Z,1715194061.112 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-05-08T18:47:41.235Z,1715194061.235 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m 2024-05-08T18:47:41.243Z,1715194061.243 [Power24vConverter](INFO): Powering up. 2024-05-08T18:47:41.258Z,1715194061.258 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-05-08T18:47:41.320Z,1715194061.320 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-05-08T18:47:41.326Z,1715194061.326 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-05-08T18:47:41.327Z,1715194061.327 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-05-08T18:47:41.334Z,1715194061.334 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-05-08T18:47:41.335Z,1715194061.335 [MassServo](DEBUG): Initializing EZServoServo. 2024-05-08T18:47:41.342Z,1715194061.342 [MassServo](DEBUG): Initializing MassServo. 2024-05-08T18:47:41.343Z,1715194061.343 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-08T18:47:41.350Z,1715194061.350 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-08T18:47:41.351Z,1715194061.351 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-05-08T18:47:41.358Z,1715194061.358 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-05-08T18:47:41.360Z,1715194061.360 [CommandExec](FAULT): Scheduling is paused 2024-05-08T18:47:41.360Z,1715194061.360 [CBIT](INFO): Critical error at 20240508T184740 2024-05-08T18:47:41.360Z,1715194061.360 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-05-08T18:47:41.557Z,1715194061.557 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-05-08T18:47:41.557Z,1715194061.557 [DropWeight] Hardware Fault, FailCount= 1 2024-05-08T18:47:41.557Z,1715194061.557 [DropWeight](ERROR): Hardware Fault 2024-05-08T18:47:41.581Z,1715194061.581 [CBIT](INFO): Critical error at 20240508T184741 2024-05-08T18:47:41.592Z,1715194061.592 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-05-08T18:47:41.592Z,1715194061.592 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-05-08T18:47:41.944Z,1715194061.944 [CBIT](INFO): Critical error at 20240508T184741 2024-05-08T18:47:43.570Z,1715194063.570 [ThrusterHE](FAULT): Thruster failed to initialize 2024-05-08T18:47:43.570Z,1715194063.570 [ThrusterHE] Communications Fault, FailCount= 1 2024-05-08T18:47:43.570Z,1715194063.570 [ThrusterHE](ERROR): Communications Fault 2024-05-08T18:47:43.574Z,1715194063.574 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2024-05-08T18:47:44.078Z,1715194064.078 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-05-08T18:47:44.078Z,1715194064.078 [ThrusterHE](INFO): Powering down 2024-05-08T18:47:44.466Z,1715194064.466 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:44.842Z,1715194064.842 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:45.125Z,1715194065.125 [CBIT](INFO): Clearing failed state for component ThrusterHE 2024-05-08T18:47:45.125Z,1715194065.125 [ThrusterHE] No Fault, FailCount= 1 2024-05-08T18:47:45.474Z,1715194065.474 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:45.587Z,1715194065.587 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-05-08T18:47:45.594Z,1715194065.594 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-05-08T18:47:45.658Z,1715194065.658 [WetLabsBB2FL] Communications Fault, FailCount= 1 2024-05-08T18:47:45.658Z,1715194065.658 [WetLabsBB2FL](ERROR): Communications Fault 2024-05-08T18:47:45.938Z,1715194065.938 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:46.074Z,1715194066.074 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2024-05-08T18:47:46.158Z,1715194066.158 [WetLabsBB2FL](INFO): Powering down 2024-05-08T18:47:46.410Z,1715194066.410 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:46.870Z,1715194066.870 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:47.069Z,1715194067.069 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2024-05-08T18:47:47.070Z,1715194067.070 [WetLabsBB2FL] No Fault, FailCount= 1 2024-05-08T18:47:47.371Z,1715194067.371 [WetLabsBB2FL](INFO): Powering up 2024-05-08T18:47:47.422Z,1715194067.422 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:47.611Z,1715194067.611 [ThrusterHE](FAULT): Thruster failed to initialize 2024-05-08T18:47:47.611Z,1715194067.611 [ThrusterHE] Communications Fault, FailCount= 2 2024-05-08T18:47:47.611Z,1715194067.611 [ThrusterHE](ERROR): Communications Fault 2024-05-08T18:47:47.615Z,1715194067.615 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2024-05-08T18:47:47.958Z,1715194067.958 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:48.026Z,1715194068.026 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-05-08T18:47:48.027Z,1715194068.027 [ThrusterHE](INFO): Powering down 2024-05-08T18:47:48.358Z,1715194068.358 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:48.782Z,1715194068.782 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:48.977Z,1715194068.977 [CBIT](INFO): Clearing failed state for component ThrusterHE 2024-05-08T18:47:48.977Z,1715194068.977 [ThrusterHE] No Fault, FailCount= 2 2024-05-08T18:47:49.318Z,1715194069.318 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:49.387Z,1715194069.387 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-05-08T18:47:49.394Z,1715194069.394 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-05-08T18:47:49.762Z,1715194069.762 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:50.190Z,1715194070.190 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:50.602Z,1715194070.602 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:50.670Z,1715194070.670 [CBIT](CRITICAL): Environmental Failure. Press:14.639530 PSI. Humidity:41%. Temp:24 C. ABORTING MISSION 2024-05-08T18:47:51.014Z,1715194071.014 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:51.088Z,1715194071.088 [CBIT](INFO): Critical error at 20240508T184750 2024-05-08T18:47:51.458Z,1715194071.458 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:51.882Z,1715194071.882 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:52.314Z,1715194072.314 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:52.538Z,1715194072.538 [DAT](INFO): DAT read: 2024-05-08T18:47:52.540Z,1715194072.540 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-05-08T18:47:52.662Z,1715194072.662 [WetLabsBB2FL] Communications Fault, FailCount= 2 2024-05-08T18:47:52.662Z,1715194072.662 [WetLabsBB2FL](ERROR): Communications Fault 2024-05-08T18:47:52.742Z,1715194072.742 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:52.819Z,1715194072.819 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2024-05-08T18:47:53.158Z,1715194073.158 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:53.162Z,1715194073.162 [WetLabsBB2FL](INFO): Powering down 2024-05-08T18:47:53.414Z,1715194073.414 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2024-05-08T18:47:53.414Z,1715194073.414 [CTD_Seabird] Communications Fault, FailCount= 1 2024-05-08T18:47:53.414Z,1715194073.414 [CTD_Seabird](ERROR): Communications Fault 2024-05-08T18:47:53.570Z,1715194073.570 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:53.658Z,1715194073.658 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2024-05-08T18:47:53.819Z,1715194073.819 [CTD_Seabird](INFO): Powering down 2024-05-08T18:47:54.010Z,1715194074.010 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:54.010Z,1715194074.010 [AHRS_M2](FAULT): Failed to initialize within timeout. 2024-05-08T18:47:54.010Z,1715194074.010 [AHRS_M2] Communications Fault, FailCount= 1 2024-05-08T18:47:54.010Z,1715194074.010 [AHRS_M2](ERROR): Communications Fault 2024-05-08T18:47:54.050Z,1715194074.050 [DAT](INFO): DAT read: MF Frequency Band 2024-05-08T18:47:54.052Z,1715194074.052 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-05-08T18:47:54.052Z,1715194074.052 [DAT](INFO): DAT read: May 8 2024 18:47:49 2024-05-08T18:47:54.096Z,1715194074.096 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2024-05-08T18:47:54.096Z,1715194074.096 [WetLabsBB2FL] No Fault, FailCount= 2 2024-05-08T18:47:54.096Z,1715194074.096 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2024-05-08T18:47:54.119Z,1715194074.119 [AHRS_M2](INFO): Powering down 2024-05-08T18:47:54.375Z,1715194074.375 [WetLabsBB2FL](INFO): Powering up 2024-05-08T18:47:55.090Z,1715194075.090 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2024-05-08T18:47:55.090Z,1715194075.090 [CTD_Seabird] No Fault, FailCount= 1 2024-05-08T18:47:55.090Z,1715194075.090 [CBIT](INFO): Clearing failed state for component AHRS_M2 2024-05-08T18:47:55.090Z,1715194075.090 [AHRS_M2] No Fault, FailCount= 1 2024-05-08T18:47:55.162Z,1715194075.162 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-05-08T18:47:55.311Z,1715194075.311 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-05-08T18:47:55.312Z,1715194075.312 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-05-08T18:47:55.312Z,1715194075.312 [DAT](INFO): commRate: 1200 2024-05-08T18:47:55.326Z,1715194075.326 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-05-08T18:47:57.378Z,1715194077.378 [DAT](INFO): entering command mode 2024-05-08T18:47:57.578Z,1715194077.578 [DAT](INFO): DAT read: 2024-05-08T18:47:57.579Z,1715194077.579 [DAT](INFO): DAT read: user:1> 2024-05-08T18:47:57.579Z,1715194077.579 [DAT](INFO): setting verbose to 3 2024-05-08T18:47:57.830Z,1715194077.830 [DAT](INFO): DAT read: user:1> 2024-05-08T18:47:57.832Z,1715194077.832 [DAT](INFO): DAT read: Verbose | 3 2024-05-08T18:47:57.832Z,1715194077.832 [DAT](INFO): set verbose to 3 2024-05-08T18:47:57.832Z,1715194077.832 [DAT](INFO): setting DatVerbose to 27440 2024-05-08T18:47:58.082Z,1715194078.082 [DAT](INFO): DAT read: user:2> 2024-05-08T18:47:58.083Z,1715194078.083 [DAT](INFO): DAT read: DatVerbose | 27440 2024-05-08T18:47:58.083Z,1715194078.083 [DAT](INFO): set DatVerbose to 27440 2024-05-08T18:47:58.084Z,1715194078.084 [DAT](INFO): setting transmit power to 8 2024-05-08T18:47:58.334Z,1715194078.334 [DAT](INFO): DAT read: user:3> 2024-05-08T18:47:58.339Z,1715194078.339 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-05-08T18:47:58.339Z,1715194078.339 [DAT](INFO): set transmit power to 8 2024-05-08T18:47:58.339Z,1715194078.339 [DAT](INFO): setting local address to 5 2024-05-08T18:47:58.599Z,1715194078.599 [DAT](INFO): DAT read: user:4> 2024-05-08T18:47:58.600Z,1715194078.600 [DAT](INFO): DAT read: LocalAddr | 5 2024-05-08T18:47:58.600Z,1715194078.600 [DAT](INFO): set local address to 5 2024-05-08T18:47:58.601Z,1715194078.601 [DAT](INFO): Setting time to: 18:47:58 And date to:5/8/2024 2024-05-08T18:47:58.850Z,1715194078.850 [DAT](INFO): DAT read: user:5> 2024-05-08T18:47:58.851Z,1715194078.851 [DAT](INFO): DAT read: Wed May 8, 2024 18:47:58 2024-05-08T18:47:58.851Z,1715194078.851 [DAT](INFO): Local DAT time set to Wed May 8, 2024 18:47:58 2024-05-08T18:47:58.878Z,1715194078.878 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:58.884Z,1715194078.884 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-05-08T18:47:58.884Z,1715194078.884 [DVL_micro] Communications Fault, FailCount= 1 2024-05-08T18:47:58.884Z,1715194078.884 [DVL_micro](ERROR): Communications Fault 2024-05-08T18:47:58.955Z,1715194078.955 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-05-08T18:47:59.302Z,1715194079.302 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:59.402Z,1715194079.402 [DVL_micro](INFO): Powering down 2024-05-08T18:47:59.666Z,1715194079.666 [WetLabsBB2FL] Communications Fault, FailCount= 3 2024-05-08T18:47:59.666Z,1715194079.666 [WetLabsBB2FL](ERROR): Communications Fault 2024-05-08T18:47:59.846Z,1715194079.846 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:47:59.914Z,1715194079.914 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2024-05-08T18:48:00.166Z,1715194080.166 [WetLabsBB2FL](INFO): Powering down 2024-05-08T18:48:00.274Z,1715194080.274 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:00.335Z,1715194080.335 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-05-08T18:48:00.335Z,1715194080.335 [DVL_micro] No Fault, FailCount= 1 2024-05-08T18:48:00.678Z,1715194080.678 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:01.110Z,1715194081.110 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:01.170Z,1715194081.170 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2024-05-08T18:48:01.170Z,1715194081.170 [WetLabsBB2FL] No Fault, FailCount= 3 2024-05-08T18:48:01.379Z,1715194081.379 [WetLabsBB2FL](INFO): Powering up 2024-05-08T18:48:01.542Z,1715194081.542 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:01.962Z,1715194081.962 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:02.378Z,1715194082.378 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:02.794Z,1715194082.794 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:03.206Z,1715194083.206 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:03.627Z,1715194083.627 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:04.046Z,1715194084.046 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:04.462Z,1715194084.462 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:04.866Z,1715194084.866 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:05.310Z,1715194085.310 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:05.754Z,1715194085.754 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:06.166Z,1715194086.166 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:06.594Z,1715194086.594 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:06.678Z,1715194086.678 [WetLabsBB2FL] Communications Fault, FailCount= 4 2024-05-08T18:48:06.678Z,1715194086.678 [WetLabsBB2FL](ERROR): Communications Fault 2024-05-08T18:48:07.030Z,1715194087.030 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:07.114Z,1715194087.114 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2024-05-08T18:48:07.178Z,1715194087.178 [WetLabsBB2FL](INFO): Powering down 2024-05-08T18:48:07.458Z,1715194087.458 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:07.870Z,1715194087.870 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:08.214Z,1715194088.214 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2024-05-08T18:48:08.214Z,1715194088.214 [CTD_Seabird] Communications Fault, FailCount= 2 2024-05-08T18:48:08.214Z,1715194088.214 [CTD_Seabird](ERROR): Communications Fault 2024-05-08T18:48:08.278Z,1715194088.278 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:08.278Z,1715194088.278 [AHRS_M2](FAULT): Failed to initialize within timeout. 2024-05-08T18:48:08.278Z,1715194088.278 [AHRS_M2] Communications Fault, FailCount= 2 2024-05-08T18:48:08.278Z,1715194088.278 [AHRS_M2](ERROR): Communications Fault 2024-05-08T18:48:08.366Z,1715194088.366 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2024-05-08T18:48:08.366Z,1715194088.366 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2024-05-08T18:48:08.366Z,1715194088.366 [WetLabsBB2FL] No Fault, FailCount= 4 2024-05-08T18:48:08.367Z,1715194088.367 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2024-05-08T18:48:08.380Z,1715194088.380 [AHRS_M2](INFO): Powering down 2024-05-08T18:48:08.391Z,1715194088.391 [WetLabsBB2FL](INFO): Powering up 2024-05-08T18:48:08.618Z,1715194088.618 [CTD_Seabird](INFO): Powering down 2024-05-08T18:48:09.254Z,1715194089.254 [CBIT](INFO): Clearing failed state for component AHRS_M2 2024-05-08T18:48:09.254Z,1715194089.254 [AHRS_M2] No Fault, FailCount= 2 2024-05-08T18:48:09.586Z,1715194089.586 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-05-08T18:48:09.597Z,1715194089.597 [NAL9602](INFO): Powering up NAL9602 2024-05-08T18:48:09.667Z,1715194089.667 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2024-05-08T18:48:09.667Z,1715194089.667 [CTD_Seabird] No Fault, FailCount= 2 2024-05-08T18:48:09.958Z,1715194089.958 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-05-08T18:48:12.479Z,1715194092.479 [CommandExec](IMPORTANT): got command restart system 2024-05-08T18:48:13.134Z,1715194093.134 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:13.554Z,1715194093.554 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:13.686Z,1715194093.686 [WetLabsBB2FL] Communications Fault, FailCount= 5 2024-05-08T18:48:13.686Z,1715194093.686 [WetLabsBB2FL](ERROR): Communications Fault 2024-05-08T18:48:14.002Z,1715194094.002 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:14.066Z,1715194094.066 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2024-05-08T18:48:14.067Z,1715194094.067 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 2024-05-08T18:48:14.090Z,1715194094.090 [WetLabsBB2FL](INFO): Powering down 2024-05-08T18:48:14.426Z,1715194094.426 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:14.599Z,1715194094.599 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-08T18:48:14.600Z,1715194094.600 [CommandExec](INFO): Uninitializing the command executive. 2024-05-08T18:48:14.601Z,1715194094.601 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-08T18:48:14.602Z,1715194094.602 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:14.766Z,1715194094.766 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-08T18:48:14.766Z,1715194094.766 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-08T18:48:14.766Z,1715194094.766 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:14.767Z,1715194094.767 [NavChartDb](INFO): Join timeout helper Thread ID is 965 2024-05-08T18:48:14.894Z,1715194094.894 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:15.298Z,1715194095.298 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:15.718Z,1715194095.718 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:16.142Z,1715194096.142 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:16.479Z,1715194096.479 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-08T18:48:16.479Z,1715194096.479 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:16.482Z,1715194096.482 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-08T18:48:16.482Z,1715194096.482 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:16.482Z,1715194096.482 [DAT](INFO): Join timeout helper Thread ID is 966 2024-05-08T18:48:16.498Z,1715194096.498 [DAT](INFO): Powering down 2024-05-08T18:48:16.554Z,1715194096.554 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:16.570Z,1715194096.570 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-08T18:48:16.571Z,1715194096.571 [DAT](INFO): Powering down 2024-05-08T18:48:16.578Z,1715194096.578 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:16.582Z,1715194096.582 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-05-08T18:48:16.582Z,1715194096.582 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:16.582Z,1715194096.582 [Radio_Surface](INFO): Join timeout helper Thread ID is 967 2024-05-08T18:48:16.782Z,1715194096.782 [Radio_Surface](INFO): Powering down 2024-05-08T18:48:16.783Z,1715194096.783 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-08T18:48:16.783Z,1715194096.783 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:16.786Z,1715194096.786 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-05-08T18:48:16.786Z,1715194096.786 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:16.786Z,1715194096.786 [Onboard](INFO): Join timeout helper Thread ID is 968 2024-05-08T18:48:16.974Z,1715194096.974 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:17.370Z,1715194097.370 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:17.786Z,1715194097.786 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:18.198Z,1715194098.198 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:18.207Z,1715194098.207 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-05-08T18:48:18.207Z,1715194098.207 [DVL_micro] Communications Fault, FailCount= 2 2024-05-08T18:48:18.207Z,1715194098.207 [DVL_micro](ERROR): Communications Fault 2024-05-08T18:48:18.263Z,1715194098.263 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-05-08T18:48:18.614Z,1715194098.614 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:18.718Z,1715194098.718 [DVL_micro](INFO): Powering down 2024-05-08T18:48:19.114Z,1715194099.114 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:19.554Z,1715194099.554 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:19.615Z,1715194099.615 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-05-08T18:48:19.615Z,1715194099.615 [DVL_micro] No Fault, FailCount= 2 2024-05-08T18:48:19.974Z,1715194099.974 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:20.390Z,1715194100.390 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-05-08T18:48:20.426Z,1715194100.426 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-08T18:48:20.426Z,1715194100.426 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:20.446Z,1715194100.446 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-05-08T18:48:20.446Z,1715194100.446 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:20.446Z,1715194100.446 [DataOverHttps](INFO): Join timeout helper Thread ID is 971 2024-05-08T18:48:20.542Z,1715194100.542 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-08T18:48:20.542Z,1715194100.542 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:20.559Z,1715194100.559 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-05-08T18:48:20.559Z,1715194100.559 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:20.559Z,1715194100.559 [BackseatComponent](INFO): Join timeout helper Thread ID is 972 2024-05-08T18:48:20.602Z,1715194100.602 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-08T18:48:20.602Z,1715194100.602 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:20.614Z,1715194100.614 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-05-08T18:48:20.615Z,1715194100.615 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-08T18:48:20.615Z,1715194100.615 [WetLabs