1980-12-11T22:00:47.399Z,345420047.399 [Supervisor](DEBUG): Initializing supervisor. 1980-12-11T22:00:47.403Z,345420047.403 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 1980-12-11T22:00:47.404Z,345420047.404 [SyncHandler](INFO): Protected caller Thread ID is 837 1980-12-11T22:00:47.404Z,345420047.404 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 1980-12-11T22:00:47.405Z,345420047.405 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 1980-12-11T22:00:47.406Z,345420047.406 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 1980-12-11T22:00:47.410Z,345420047.410 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 1980-12-11T22:00:47.430Z,345420047.430 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 1980-12-11T22:00:47.431Z,345420047.431 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 1980-12-11T22:00:47.431Z,345420047.431 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 1980-12-11T22:00:47.435Z,345420047.435 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 1980-12-11T22:00:47.437Z,345420047.437 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 1980-12-11T22:00:47.437Z,345420047.437 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 1980-12-11T22:00:47.439Z,345420047.439 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 1980-12-11T22:00:47.440Z,345420047.440 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 1980-12-11T22:00:47.441Z,345420047.441 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 1980-12-11T22:00:47.444Z,345420047.444 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 1980-12-11T22:00:47.445Z,345420047.445 [Supervisor](INFO): Looking for Config files in directory: Config/ 1980-12-11T22:00:47.449Z,345420047.449 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 1980-12-11T22:00:47.850Z,345420047.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 1980-12-11T22:00:47.852Z,345420047.852 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 1980-12-11T22:00:48.125Z,345420048.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 1980-12-11T22:00:48.127Z,345420048.127 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 1980-12-11T22:00:48.640Z,345420048.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 1980-12-11T22:00:48.642Z,345420048.642 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 1980-12-11T22:00:48.888Z,345420048.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 1980-12-11T22:00:48.889Z,345420048.889 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 1980-12-11T22:00:49.121Z,345420049.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 1980-12-11T22:00:49.123Z,345420049.123 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 1980-12-11T22:00:49.947Z,345420049.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 1980-12-11T22:00:49.948Z,345420049.948 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 1980-12-11T22:00:50.530Z,345420050.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 1980-12-11T22:00:50.543Z,345420050.543 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 1980-12-11T22:00:50.837Z,345420050.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 1980-12-11T22:00:50.837Z,345420050.837 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 1980-12-11T22:00:50.927Z,345420050.927 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 1980-12-11T22:00:51.118Z,345420051.118 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 1980-12-11T22:00:51.118Z,345420051.118 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 1980-12-11T22:00:51.225Z,345420051.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 1980-12-11T22:00:51.227Z,345420051.227 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 1980-12-11T22:00:51.457Z,345420051.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 1980-12-11T22:00:51.457Z,345420051.457 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 1980-12-11T22:00:51.606Z,345420051.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 1980-12-11T22:00:51.608Z,345420051.608 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 1980-12-11T22:00:51.709Z,345420051.709 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 1980-12-11T22:00:51.710Z,345420051.710 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 1980-12-11T22:00:52.156Z,345420052.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 1980-12-11T22:00:52.157Z,345420052.157 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 1980-12-11T22:00:52.831Z,345420052.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 1980-12-11T22:00:52.833Z,345420052.833 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/ 1980-12-11T22:00:52.835Z,345420052.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg 1980-12-11T22:00:52.952Z,345420052.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg 1980-12-11T22:00:53.234Z,345420053.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 1980-12-11T22:00:53.234Z,345420053.234 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg 1980-12-11T22:00:53.346Z,345420053.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg 1980-12-11T22:00:53.451Z,345420053.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg 1980-12-11T22:00:53.570Z,345420053.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg 1980-12-11T22:00:53.714Z,345420053.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg 1980-12-11T22:00:53.823Z,345420053.823 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg 1980-12-11T22:00:53.940Z,345420053.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg 1980-12-11T22:00:54.034Z,345420054.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg 1980-12-11T22:00:54.157Z,345420054.157 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg 1980-12-11T22:00:54.252Z,345420054.252 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/root/ 1980-12-11T22:00:54.253Z,345420054.253 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 1980-12-11T22:00:54.267Z,345420054.267 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 1980-12-11T22:00:54.284Z,345420054.284 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 1980-12-11T22:00:54.284Z,345420054.284 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 1980-12-11T22:00:54.429Z,345420054.429 [DeadReckonUsingMultipleVelocitySources] Loaded 1980-12-11T22:00:54.429Z,345420054.429 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 1980-12-11T22:00:54.470Z,345420054.470 [DeadReckonWithRespectToSeafloor] Loaded 1980-12-11T22:00:54.470Z,345420054.470 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 1980-12-11T22:00:54.484Z,345420054.484 [NavChart] Loaded 1980-12-11T22:00:54.484Z,345420054.484 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 1980-12-11T22:00:54.490Z,345420054.490 [UniversalFixResidualReporter] Loaded 1980-12-11T22:00:54.490Z,345420054.490 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 1980-12-11T22:00:54.491Z,345420054.491 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 1980-12-11T22:00:54.491Z,345420054.491 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 1980-12-11T22:00:55.528Z,345420055.528 [AHRS_M2] Loaded 1980-12-11T22:00:55.528Z,345420055.528 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 1980-12-11T22:00:55.782Z,345420055.782 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC3 1980-12-11T22:00:55.783Z,345420055.783 [BackseatComponent] Loaded 1980-12-11T22:00:55.783Z,345420055.783 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 1980-12-11T22:00:55.785Z,345420055.785 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4085E4E0 1980-12-11T22:00:55.785Z,345420055.785 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924 1980-12-11T22:00:55.788Z,345420055.788 [LcmUniversalReporter] Loaded 1980-12-11T22:00:55.788Z,345420055.788 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 1980-12-11T22:00:56.626Z,345420056.626 [BPC1] Loaded 1980-12-11T22:00:56.626Z,345420056.626 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 1980-12-11T22:00:56.725Z,345420056.725 [DAT] Loaded 1980-12-11T22:00:56.725Z,345420056.725 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 1980-12-11T22:00:56.726Z,345420056.726 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4088E4E0 1980-12-11T22:00:56.726Z,345420056.726 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925 1980-12-11T22:00:57.056Z,345420057.056 [DataOverHttps] Loaded 1980-12-11T22:00:57.056Z,345420057.056 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 1980-12-11T22:00:57.057Z,345420057.057 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BE4E0 1980-12-11T22:00:57.058Z,345420057.058 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926 1980-12-11T22:00:57.081Z,345420057.081 [DepthKeller33X] Loaded 1980-12-11T22:00:57.081Z,345420057.081 [ComponentRegistry](DEBUG): SyncComponent "DepthKeller33X" handled in the control thread. 1980-12-11T22:00:57.086Z,345420057.086 [DropWeight] Loaded 1980-12-11T22:00:57.086Z,345420057.086 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 1980-12-11T22:00:57.151Z,345420057.151 [NAL9602] Loaded 1980-12-11T22:00:57.151Z,345420057.151 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 1980-12-11T22:00:57.181Z,345420057.181 [Onboard] Loaded 1980-12-11T22:00:57.181Z,345420057.181 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 1980-12-11T22:00:57.182Z,345420057.182 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408EE4E0 1980-12-11T22:00:57.183Z,345420057.183 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927 1980-12-11T22:00:57.197Z,345420057.197 [Power24vConverter] Loaded 1980-12-11T22:00:57.197Z,345420057.197 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 1980-12-11T22:00:57.214Z,345420057.214 [Radio_Surface] Loaded 1980-12-11T22:00:57.215Z,345420057.215 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 1980-12-11T22:00:57.216Z,345420057.216 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091E4E0 1980-12-11T22:00:57.216Z,345420057.216 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928 1980-12-11T22:00:57.263Z,345420057.263 [RDI_Pathfinder] Loaded 1980-12-11T22:00:57.263Z,345420057.263 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 1980-12-11T22:00:57.279Z,345420057.279 [Sonardyne_Nano] Loaded 1980-12-11T22:00:57.279Z,345420057.279 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 1980-12-11T22:00:57.279Z,345420057.279 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 1980-12-11T22:00:57.280Z,345420057.280 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 1980-12-11T22:00:57.491Z,345420057.491 [DepthRateCalculator] Loaded 1980-12-11T22:00:57.491Z,345420057.491 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 1980-12-11T22:00:57.505Z,345420057.505 [PitchRateCalculator] Loaded 1980-12-11T22:00:57.505Z,345420057.505 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 1980-12-11T22:00:57.557Z,345420057.557 [SpeedCalculator] Loaded 1980-12-11T22:00:57.558Z,345420057.558 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 1980-12-11T22:00:57.601Z,345420057.601 [YawRateCalculator] Loaded 1980-12-11T22:00:57.602Z,345420057.602 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 1980-12-11T22:00:57.625Z,345420057.625 [ElevatorOffsetCalculator] Loaded 1980-12-11T22:00:57.625Z,345420057.625 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 1980-12-11T22:00:57.625Z,345420057.625 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 1980-12-11T22:00:57.626Z,345420057.626 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 1980-12-11T22:00:58.360Z,345420058.360 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 1980-12-11T22:00:58.361Z,345420058.361 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 1980-12-11T22:00:58.533Z,345420058.533 [BuoyancyServo] Loaded 1980-12-11T22:00:58.534Z,345420058.534 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 1980-12-11T22:00:58.558Z,345420058.558 [ElevatorServo] Loaded 1980-12-11T22:00:58.558Z,345420058.558 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 1980-12-11T22:00:58.582Z,345420058.582 [MassServo] Loaded 1980-12-11T22:00:58.582Z,345420058.582 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 1980-12-11T22:00:58.605Z,345420058.605 [RudderServo] Loaded 1980-12-11T22:00:58.605Z,345420058.605 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 1980-12-11T22:00:58.624Z,345420058.624 [ThrusterHE] Loaded 1980-12-11T22:00:58.624Z,345420058.624 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 1980-12-11T22:00:58.624Z,345420058.624 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 1980-12-11T22:00:58.625Z,345420058.625 [Module Loader](DEBUG): Loading Module at Modules/Science.so 1980-12-11T22:00:58.852Z,345420058.852 [CTD_Seabird] Loaded 1980-12-11T22:00:58.853Z,345420058.853 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 1980-12-11T22:00:58.854Z,345420058.854 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0 1980-12-11T22:00:58.854Z,345420058.854 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 929 1980-12-11T22:00:58.875Z,345420058.875 [RBRTridente] Loaded 1980-12-11T22:00:58.876Z,345420058.876 [ComponentRegistry](DEBUG): Component "RBRTridente" handled in its own thread. 1980-12-11T22:00:58.877Z,345420058.877 [RBRTridente ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0 1980-12-11T22:00:58.877Z,345420058.877 [RBRTridente ThreadHandler](INFO): Protected caller Thread ID is 930 1980-12-11T22:00:58.878Z,345420058.878 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 1980-12-11T22:00:58.879Z,345420058.879 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 1980-12-11T22:00:59.051Z,345420059.051 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 1980-12-11T22:00:59.052Z,345420059.052 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 1980-12-11T22:00:59.276Z,345420059.276 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 1980-12-11T22:00:59.277Z,345420059.277 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 1980-12-11T22:00:59.693Z,345420059.693 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 1980-12-11T22:00:59.694Z,345420059.694 [Module Loader](DEBUG): Loading Module at Modules/Control.so 1980-12-11T22:00:59.769Z,345420059.769 [VerticalControl](DEBUG): Construct VerticalControl. 1980-12-11T22:00:59.823Z,345420059.823 [VerticalControl] Loaded 1980-12-11T22:00:59.824Z,345420059.824 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 1980-12-11T22:00:59.827Z,345420059.827 [HorizontalControl](DEBUG): Construct HorizontalControl. 1980-12-11T22:00:59.875Z,345420059.875 [HorizontalControl] Loaded 1980-12-11T22:00:59.876Z,345420059.876 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 1980-12-11T22:00:59.878Z,345420059.878 [SpeedControl](DEBUG): Construct SpeedControl. 1980-12-11T22:00:59.880Z,345420059.880 [SpeedControl] Loaded 1980-12-11T22:00:59.880Z,345420059.880 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 1980-12-11T22:00:59.883Z,345420059.883 [LoopControl](DEBUG): Construct LoopControl. 1980-12-11T22:00:59.884Z,345420059.884 [LoopControl] Loaded 1980-12-11T22:00:59.884Z,345420059.884 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 1980-12-11T22:00:59.884Z,345420059.884 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 1980-12-11T22:00:59.885Z,345420059.885 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 1980-12-11T22:01:00.085Z,345420060.085 [SBIT](DEBUG): Construct Startup Built In Test. 1980-12-11T22:01:00.094Z,345420060.094 [SBIT] Loaded 1980-12-11T22:01:00.094Z,345420060.094 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 1980-12-11T22:01:00.097Z,345420060.097 [IBIT](DEBUG): Construct Initiated Built In Test. 1980-12-11T22:01:00.110Z,345420060.110 [IBIT] Loaded 1980-12-11T22:01:00.110Z,345420060.110 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 1980-12-11T22:01:00.116Z,345420060.116 [CBIT](DEBUG): Construct Continuous Built In Test. 1980-12-11T22:01:00.200Z,345420060.200 [CBIT] Loaded 1980-12-11T22:01:00.200Z,345420060.200 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 1980-12-11T22:01:00.233Z,345420060.233 [GFScanner] Loaded 1980-12-11T22:01:00.234Z,345420060.234 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 1980-12-11T22:01:00.234Z,345420060.234 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 1980-12-11T22:01:00.235Z,345420060.235 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 1980-12-11T22:01:00.284Z,345420060.284 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 1980-12-11T22:01:00.290Z,345420060.290 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 1980-12-11T22:01:00.293Z,345420060.293 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 1980-12-11T22:01:00.305Z,345420060.305 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 1980-12-11T22:01:00.306Z,345420060.306 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0 1980-12-11T22:01:00.306Z,345420060.306 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931 1980-12-11T22:01:00.311Z,345420060.311 [Supervisor](INFO): Main Thread ID is 828 1980-12-11T22:01:00.311Z,345420060.311 [Supervisor](DEBUG): Running supervisor. 1980-12-11T22:01:00.311Z,345420060.311 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932 1980-12-11T22:01:00.312Z,345420060.312 [CommandExec](INFO): Initializing the command executive. 1980-12-11T22:01:00.314Z,345420060.314 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933 1980-12-11T22:01:00.316Z,345420060.316 [controlThread ThreadHandler](INFO): Handler Thread ID is 934 1980-12-11T22:01:00.316Z,345420060.316 [controlThread](DEBUG): Initializing ControlThread 1980-12-11T22:01:00.318Z,345420060.318 [NavChart](DEBUG): Initialize NavChart Navigation. 1980-12-11T22:01:00.318Z,345420060.318 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 1980-12-11T22:01:00.321Z,345420060.321 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 1980-12-11T22:01:00.322Z,345420060.322 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 1980-12-11T22:01:00.322Z,345420060.322 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 1980-12-11T22:01:00.322Z,345420060.322 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 1980-12-11T22:01:00.323Z,345420060.323 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 1980-12-11T22:01:00.324Z,345420060.324 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 1980-12-11T22:01:00.326Z,345420060.326 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 1980-12-11T22:01:00.326Z,345420060.326 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 1980-12-11T22:01:00.327Z,345420060.327 [LoopControl](DEBUG): Initialize LoopControlComponent. 1980-12-11T22:01:00.327Z,345420060.327 [SBIT](INFO): Initialize SBIT Component. 1980-12-11T22:01:00.328Z,345420060.328 [SBIT](IMPORTANT): git: 2025-09-11 1980-12-11T22:01:00.328Z,345420060.328 [SBIT](INFO): git hash: 809a1152453d4a7797fa86b43c86e80bc3a54567 1980-12-11T22:01:00.328Z,345420060.328 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 1980-12-11T22:01:00.329Z,345420060.329 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Mon Nov 27 01:04:18 PST 2023 1980-12-11T22:01:00.330Z,345420060.330 [SBIT](INFO): Beginning SBIT in 95.000000 seconds. 1980-12-11T22:01:00.331Z,345420060.331 [IBIT](INFO): Initialize IBIT Component. 1980-12-11T22:01:00.332Z,345420060.332 [CBIT](DEBUG): Initialize CBIT Component. 1980-12-11T22:01:00.333Z,345420060.333 [logger ThreadHandler](INFO): Handler Thread ID is 935 1980-12-11T22:01:00.344Z,345420060.344 [CBIT](DEBUG): Initialized mux pins. 1980-12-11T22:01:00.344Z,345420060.344 [CBIT](DEBUG): Initializing the watchdog timer. 1980-12-11T22:01:00.352Z,345420060.352 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 936 1980-12-11T22:01:00.368Z,345420060.368 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 1980-12-11T22:01:00.369Z,345420060.369 [CBIT](DEBUG): Initializing heartbeat. 1980-12-11T22:01:00.369Z,345420060.369 [DAT ThreadHandler](INFO): Handler Thread ID is 937 1980-12-11T22:01:00.370Z,345420060.370 [DAT](INFO): Powering up 1980-12-11T22:01:00.370Z,345420060.370 [DAT](DEBUG): Initializing DAT. 1980-12-11T22:01:00.373Z,345420060.373 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939 1980-12-11T22:01:00.375Z,345420060.375 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 1980-12-11T22:01:00.393Z,345420060.393 [Onboard ThreadHandler](INFO): Handler Thread ID is 940 1980-12-11T22:01:00.410Z,345420060.410 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941 1980-12-11T22:01:00.428Z,345420060.428 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 942 1980-12-11T22:01:00.429Z,345420060.429 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 1980-12-11T22:01:00.433Z,345420060.433 [RBRTridente ThreadHandler](INFO): Handler Thread ID is 944 1980-12-11T22:01:00.434Z,345420060.434 [RBRTridente](INFO): Start 1980-12-11T22:01:00.436Z,345420060.436 [RBRTridente](INFO): Powering up 1980-12-11T22:01:00.438Z,345420060.438 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 946 1980-12-11T22:01:00.440Z,345420060.440 [CBIT](DEBUG): Deactivating emergency mode. 1980-12-11T22:01:00.447Z,345420060.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 1980-12-11T22:01:00.447Z,345420060.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 1980-12-11T22:01:00.447Z,345420060.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 1980-12-11T22:01:00.447Z,345420060.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 1980-12-11T22:01:00.448Z,345420060.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 1980-12-11T22:01:00.448Z,345420060.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 1980-12-11T22:01:00.448Z,345420060.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 1980-12-11T22:01:00.448Z,345420060.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 1980-12-11T22:01:00.480Z,345420060.480 [CBIT](DEBUG): Backplane powered. 1980-12-11T22:01:00.480Z,345420060.480 [GFScanner](DEBUG): Initializing GFScanner 1980-12-11T22:01:00.480Z,345420060.480 [GFScanner](DEBUG): Deactivating GF circuits. 1980-12-11T22:01:00.485Z,345420060.485 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 1980-12-11T22:01:00.486Z,345420060.486 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 1980-12-11T22:01:00.486Z,345420060.486 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 1980-12-11T22:01:00.491Z,345420060.491 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 1980-12-11T22:01:00.565Z,345420060.565 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 1980-12-11T22:01:00.569Z,345420060.569 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 1980-12-11T22:01:00.615Z,345420060.615 [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 } } } 1980-12-11T22:01:00.616Z,345420060.616 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 1980-12-11T22:01:00.616Z,345420060.616 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 1980-12-11T22:01:00.628Z,345420060.628 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 1980-12-11T22:01:00.816Z,345420060.816 [Radio_Surface](INFO): Powering up 1980-12-11T22:01:00.836Z,345420060.836 [RBRTridente](INFO): Starting 1980-12-11T22:01:00.971Z,345420060.971 [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 1980-12-11T22:01:00.993Z,345420060.993 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 1980-12-11T22:01:00.994Z,345420060.994 [Default:A.Wait](DEBUG): Construct Wait. 1980-12-11T22:01:00.997Z,345420060.997 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 1980-12-11T22:01:01.044Z,345420061.044 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 1980-12-11T22:01:01.046Z,345420061.046 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 1980-12-11T22:01:01.069Z,345420061.069 [Default:E.Execute](DEBUG): Construct Execute. 1980-12-11T22:01:01.088Z,345420061.088 [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" } } 1980-12-11T22:01:01.093Z,345420061.093 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DepthKeller33X,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 1980-12-11T22:01:01.116Z,345420061.116 [AHRS_M2](DEBUG): Initializing AHRS_M2. 1980-12-11T22:01:01.180Z,345420061.180 [DepthKeller33X](INFO): Initializing. 1980-12-11T22:01:01.197Z,345420061.197 [Power24vConverter](INFO): Powering up. 1980-12-11T22:01:01.197Z,345420061.197 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 1980-12-11T22:01:01.205Z,345420061.205 [Sonardyne_Nano](INFO): Initializing. 1980-12-11T22:01:01.224Z,345420061.224 [DepthRateCalculator](ERROR): Depth measurement is not active 1980-12-11T22:01:01.240Z,345420061.240 [RBRTridente](INFO): Starting 1980-12-11T22:01:01.253Z,345420061.253 [BuoyancyServo](DEBUG): Initializing EZServoServo. 1980-12-11T22:01:01.260Z,345420061.260 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 1980-12-11T22:01:01.261Z,345420061.261 [ElevatorServo](DEBUG): Initializing EZServoServo. 1980-12-11T22:01:01.268Z,345420061.268 [ElevatorServo](DEBUG): Initializing ElevatorServo. 1980-12-11T22:01:01.269Z,345420061.269 [MassServo](DEBUG): Initializing EZServoServo. 1980-12-11T22:01:01.276Z,345420061.276 [MassServo](DEBUG): Initializing MassServo. 1980-12-11T22:01:01.277Z,345420061.277 [RudderServo](DEBUG): Initializing EZServoServo. 1980-12-11T22:01:01.284Z,345420061.284 [RudderServo](DEBUG): Initializing RudderServo. 1980-12-11T22:01:01.285Z,345420061.285 [ThrusterHE](DEBUG): Initializing EZServoServo. 1980-12-11T22:01:01.292Z,345420061.292 [ThrusterHE](DEBUG): Initializing ThrusterHE. 1980-12-11T22:01:01.552Z,345420061.552 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 1980-12-11T22:01:01.553Z,345420061.553 [DropWeight] Hardware Fault, FailCount= 1 1980-12-11T22:01:01.553Z,345420061.553 [DropWeight](ERROR): Hardware Fault 1980-12-11T22:01:01.554Z,345420061.554 [DepthRateCalculator](ERROR): Depth measurement is not active 1980-12-11T22:01:01.571Z,345420061.571 [CommandExec](FAULT): Scheduling is paused 1980-12-11T22:01:01.580Z,345420061.580 [CBIT](INFO): Critical error at 19801211T220101 1980-12-11T22:01:01.580Z,345420061.580 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 1980-12-11T22:01:01.583Z,345420061.583 [CBIT](ERROR): Hardware Fault in component: DropWeight 1980-12-11T22:01:01.583Z,345420061.583 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 1980-12-11T22:01:01.644Z,345420061.644 [RBRTridente](INFO): Starting 1980-12-11T22:01:01.909Z,345420061.909 [DepthRateCalculator](ERROR): Depth measurement is not active 1980-12-11T22:01:01.918Z,345420061.918 [CBIT](INFO): Critical error at 19801211T220101 1980-12-11T22:01:02.048Z,345420062.048 [RBRTridente](INFO): Starting 1980-12-11T22:01:02.312Z,345420062.312 [DepthRateCalculator](ERROR): Depth measurement is not active 1980-12-11T22:01:02.453Z,345420062.453 [RBRTridente](INFO): Starting 1980-12-11T22:01:02.737Z,345420062.737 [DepthRateCalculator](ERROR): Depth measurement is not active 1980-12-11T22:01:02.857Z,345420062.857 [RBRTridente](INFO): Starting 1980-12-11T22:01:03.260Z,345420063.260 [RBRTridente](INFO): Starting 1980-12-11T22:01:03.664Z,345420063.664 [RBRTridente](INFO): Starting 1980-12-11T22:01:04.071Z,345420064.071 [RBRTridente](INFO): Starting 1980-12-11T22:01:04.472Z,345420064.472 [RBRTridente](INFO): Starting 1980-12-11T22:01:04.652Z,345420064.652 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:04.876Z,345420064.876 [RBRTridente](INFO): Starting 1980-12-11T22:01:05.060Z,345420065.060 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:05.280Z,345420065.280 [RBRTridente](INFO): Starting 1980-12-11T22:01:05.532Z,345420065.532 [RBRTridente] Communications Fault, FailCount= 1 1980-12-11T22:01:05.532Z,345420065.532 [RBRTridente](ERROR): Communications Fault 1980-12-11T22:01:05.628Z,345420065.628 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:05.730Z,345420065.730 [CBIT](ERROR): Communications Fault in component: RBRTridente 1980-12-11T22:01:05.936Z,345420065.936 [RBRTridente](INFO): Stop 1980-12-11T22:01:06.009Z,345420066.009 [RBRTridente](INFO): Powering down 1980-12-11T22:01:06.064Z,345420066.064 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:06.412Z,345420066.412 [RBRTridente](INFO): Stopping 1980-12-11T22:01:06.556Z,345420066.556 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:06.996Z,345420066.996 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:07.073Z,345420067.073 [RudderServo](FAULT): Overload Error 1980-12-11T22:01:07.073Z,345420067.073 [RudderServo] Hardware Fault, FailCount= 1 1980-12-11T22:01:07.073Z,345420067.073 [RudderServo](ERROR): Hardware Fault 1980-12-11T22:01:07.244Z,345420067.244 [CBIT](ERROR): Hardware Fault in component: RudderServo 1980-12-11T22:01:07.244Z,345420067.244 [CBIT](INFO): Clearing failed state for component RBRTridente 1980-12-11T22:01:07.244Z,345420067.244 [RBRTridente] No Fault, FailCount= 1 1980-12-11T22:01:07.572Z,345420067.572 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:07.617Z,345420067.617 [RudderServo](DEBUG): Uninitialize Rudder Servo. 1980-12-11T22:01:07.617Z,345420067.617 [RudderServo](INFO): Powering down 1980-12-11T22:01:07.624Z,345420067.624 [RBRTridente](INFO): Start 1980-12-11T22:01:07.625Z,345420067.625 [RBRTridente](INFO): Powering up 1980-12-11T22:01:08.029Z,345420068.029 [RBRTridente](INFO): Starting 1980-12-11T22:01:08.076Z,345420068.076 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:08.432Z,345420068.432 [RBRTridente](INFO): Starting 1980-12-11T22:01:08.496Z,345420068.496 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:08.549Z,345420068.549 [RudderServo](DEBUG): Initializing EZServoServo. 1980-12-11T22:01:08.669Z,345420068.669 [RudderServo](DEBUG): Initializing RudderServo. 1980-12-11T22:01:08.674Z,345420068.674 [CBIT](INFO): Clearing failed state for component RudderServo 1980-12-11T22:01:08.674Z,345420068.674 [RudderServo] No Fault, FailCount= 1 1980-12-11T22:01:08.836Z,345420068.836 [RBRTridente](INFO): Starting 1980-12-11T22:01:09.012Z,345420069.012 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:09.240Z,345420069.240 [RBRTridente](INFO): Starting 1980-12-11T22:01:09.552Z,345420069.552 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:09.644Z,345420069.644 [RBRTridente](INFO): Starting 1980-12-11T22:01:10.032Z,345420070.032 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:10.053Z,345420070.053 [RBRTridente](INFO): Starting 1980-12-11T22:01:10.456Z,345420070.456 [RBRTridente](INFO): Starting 1980-12-11T22:01:10.460Z,345420070.460 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:10.860Z,345420070.860 [RBRTridente](INFO): Starting 1980-12-11T22:01:10.888Z,345420070.888 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:11.264Z,345420071.264 [RBRTridente](INFO): Starting 1980-12-11T22:01:11.308Z,345420071.308 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:11.669Z,345420071.669 [RBRTridente](INFO): Starting 1980-12-11T22:01:11.756Z,345420071.756 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:12.072Z,345420072.072 [RBRTridente](INFO): Starting 1980-12-11T22:01:12.185Z,345420072.185 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:12.470Z,345420072.470 [DAT](INFO): DAT read: 1980-12-11T22:01:12.473Z,345420072.473 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 1980-12-11T22:01:12.477Z,345420072.477 [RBRTridente](INFO): Starting 1980-12-11T22:01:12.728Z,345420072.728 [RBRTridente] Communications Fault, FailCount= 2 1980-12-11T22:01:12.728Z,345420072.728 [RBRTridente](ERROR): Communications Fault 1980-12-11T22:01:12.856Z,345420072.856 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:13.089Z,345420073.089 [CBIT](ERROR): Communications Fault in component: RBRTridente 1980-12-11T22:01:13.133Z,345420073.133 [RBRTridente](INFO): Stop 1980-12-11T22:01:13.204Z,345420073.204 [RBRTridente](INFO): Powering down 1980-12-11T22:01:13.412Z,345420073.412 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:13.480Z,345420073.480 [CTD_Seabird](FAULT): Failed to initialize within timeout. 1980-12-11T22:01:13.481Z,345420073.481 [CTD_Seabird] Communications Fault, FailCount= 1 1980-12-11T22:01:13.481Z,345420073.481 [CTD_Seabird](ERROR): Communications Fault 1980-12-11T22:01:13.608Z,345420073.608 [RBRTridente](INFO): Stopping 1980-12-11T22:01:13.652Z,345420073.652 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 1980-12-11T22:01:13.885Z,345420073.885 [CTD_Seabird](INFO): Powering down 1980-12-11T22:01:13.989Z,345420073.989 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:13.989Z,345420073.989 [AHRS_M2](FAULT): Failed to initialize within timeout. 1980-12-11T22:01:13.990Z,345420073.990 [AHRS_M2] Communications Fault, FailCount= 1 1980-12-11T22:01:13.990Z,345420073.990 [AHRS_M2](ERROR): Communications Fault 1980-12-11T22:01:14.112Z,345420074.112 [RudderServo](FAULT): Overload Error 1980-12-11T22:01:14.113Z,345420074.113 [RudderServo] Hardware Fault, FailCount= 2 1980-12-11T22:01:14.113Z,345420074.113 [RudderServo](ERROR): Hardware Fault 1980-12-11T22:01:14.165Z,345420074.165 [CommandExec](IMPORTANT): got command quit 1980-12-11T22:01:14.224Z,345420074.224 [CBIT](ERROR): Communications Fault in component: AHRS_M2 1980-12-11T22:01:14.224Z,345420074.224 [CBIT](ERROR): Hardware Fault in component: RudderServo 1980-12-11T22:01:14.225Z,345420074.225 [CBIT](INFO): Clearing failed state for component RBRTridente 1980-12-11T22:01:14.225Z,345420074.225 [RBRTridente] No Fault, FailCount= 2 1980-12-11T22:01:14.234Z,345420074.234 [DAT](INFO): DAT read: MF Frequency Band 1980-12-11T22:01:14.235Z,345420074.235 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.0 1980-12-11T22:01:14.236Z,345420074.236 [DAT](INFO): DAT read: Oct 7 2025 22:06:44 1980-12-11T22:01:14.238Z,345420074.238 [AHRS_M2](INFO): Powering down 1980-12-11T22:01:14.361Z,345420074.361 [RudderServo](DEBUG): Uninitialize Rudder Servo. 1980-12-11T22:01:14.361Z,345420074.361 [RudderServo](INFO): Powering down 1980-12-11T22:01:14.416Z,345420074.416 [RBRTridente](INFO): Start 1980-12-11T22:01:14.417Z,345420074.417 [RBRTridente](INFO): Powering up 1980-12-11T22:01:14.821Z,345420074.821 [RBRTridente](INFO): Starting 1980-12-11T22:01:15.082Z,345420075.082 [RudderServo](DEBUG): Initializing EZServoServo. 1980-12-11T22:01:15.168Z,345420075.168 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:15.168Z,345420075.168 [CommandExec](INFO): Uninitializing the command executive. 1980-12-11T22:01:15.168Z,345420075.168 [CommandExec](INFO): Uninitializing the command scheduler. 1980-12-11T22:01:15.169Z,345420075.169 [CommandExec ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:15.201Z,345420075.201 [RudderServo](DEBUG): Initializing RudderServo. 1980-12-11T22:01:15.206Z,345420075.206 [CBIT](INFO): Clearing failed state for component AHRS_M2 1980-12-11T22:01:15.206Z,345420075.206 [AHRS_M2] No Fault, FailCount= 1 1980-12-11T22:01:15.207Z,345420075.207 [CBIT](INFO): Clearing failed state for component RudderServo 1980-12-11T22:01:15.207Z,345420075.207 [RudderServo] No Fault, FailCount= 2 1980-12-11T22:01:15.207Z,345420075.207 [CBIT](INFO): Clearing failed state for component CTD_Seabird 1980-12-11T22:01:15.207Z,345420075.207 [CTD_Seabird] No Fault, FailCount= 1 1980-12-11T22:01:15.224Z,345420075.224 [RBRTridente](INFO): Starting 1980-12-11T22:01:15.229Z,345420075.229 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 1980-12-11T22:01:15.241Z,345420075.241 [DAT](INFO): DAT read: Features enabled [Bearing] 1980-12-11T22:01:15.242Z,345420075.242 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 1980-12-11T22:01:15.242Z,345420075.242 [DAT](INFO): commRate: 600 1980-12-11T22:01:15.336Z,345420075.336 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 1980-12-11T22:01:15.336Z,345420075.336 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 1980-12-11T22:01:15.336Z,345420075.336 [NavChartDb ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:15.337Z,345420075.337 [NavChartDb](INFO): Join timeout helper Thread ID is 961 1980-12-11T22:01:15.442Z,345420075.442 [AHRS_M2](DEBUG): Initializing AHRS_M2. 1980-12-11T22:01:15.628Z,345420075.628 [RBRTridente](INFO): Starting 1980-12-11T22:01:15.791Z,345420075.791 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:15.793Z,345420075.793 [NavChartDb ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:15.812Z,345420075.812 [ComponentRegistry](INFO): Shutting down RBRTridente ThreadHandler 1980-12-11T22:01:15.812Z,345420075.812 [RBRTridente ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:15.813Z,345420075.813 [RBRTridente](INFO): Join timeout helper Thread ID is 963 1980-12-11T22:01:15.884Z,345420075.884 [RBRTridente ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:15.956Z,345420075.956 [RBRTridente](INFO): Powering down 1980-12-11T22:01:15.957Z,345420075.957 [RBRTridente ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:15.968Z,345420075.968 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 1980-12-11T22:01:15.968Z,345420075.968 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:15.968Z,345420075.968 [CTD_Seabird](INFO): Join timeout helper Thread ID is 965 1980-12-11T22:01:16.232Z,345420076.232 [CTD_Seabird](INFO): Powering down 1980-12-11T22:01:16.360Z,345420076.360 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:16.360Z,345420076.360 [CTD_Seabird](INFO): Powering down 1980-12-11T22:01:16.372Z,345420076.372 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:16.388Z,345420076.388 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 1980-12-11T22:01:16.388Z,345420076.388 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:16.388Z,345420076.388 [Radio_Surface](INFO): Join timeout helper Thread ID is 966 1980-12-11T22:01:16.572Z,345420076.572 [Radio_Surface](INFO): Powering down 1980-12-11T22:01:16.573Z,345420076.573 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:16.573Z,345420076.573 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:16.580Z,345420076.580 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 1980-12-11T22:01:16.580Z,345420076.580 [Onboard ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:16.580Z,345420076.580 [Onboard](INFO): Join timeout helper Thread ID is 967 1980-12-11T22:01:17.312Z,345420077.312 [DAT](INFO): entering command mode 1980-12-11T22:01:17.513Z,345420077.513 [DAT](INFO): DAT read: 1980-12-11T22:01:17.514Z,345420077.514 [DAT](INFO): DAT read: user:1> 1980-12-11T22:01:17.514Z,345420077.514 [DAT](INFO): setting verbose to 3 1980-12-11T22:01:17.765Z,345420077.765 [DAT](INFO): DAT read: user:1> 1980-12-11T22:01:17.766Z,345420077.766 [DAT](INFO): DAT read: Verbose | 3 1980-12-11T22:01:17.766Z,345420077.766 [DAT](INFO): set verbose to 3 1980-12-11T22:01:17.766Z,345420077.766 [DAT](INFO): setting DatVerbose to 27440 1980-12-11T22:01:18.017Z,345420078.017 [DAT](INFO): DAT read: user:2> 1980-12-11T22:01:18.018Z,345420078.018 [DAT](INFO): DAT read: DatVerbose | 27440 1980-12-11T22:01:18.018Z,345420078.018 [DAT](INFO): set DatVerbose to 27440 1980-12-11T22:01:18.019Z,345420078.019 [DAT](INFO): setting transmit power to 8 1980-12-11T22:01:18.269Z,345420078.269 [DAT](INFO): DAT read: user:3> 1980-12-11T22:01:18.270Z,345420078.270 [DAT](INFO): DAT read: TxPower | 8 (Max) 1980-12-11T22:01:18.270Z,345420078.270 [DAT](INFO): set transmit power to 8 1980-12-11T22:01:18.270Z,345420078.270 [DAT](INFO): setting local address to 3 1980-12-11T22:01:18.521Z,345420078.521 [DAT](INFO): DAT read: user:4> 1980-12-11T22:01:18.521Z,345420078.521 [DAT](INFO): DAT read: LocalAddr | 3 1980-12-11T22:01:18.522Z,345420078.522 [DAT](INFO): set local address to 3 1980-12-11T22:01:18.523Z,345420078.523 [DAT](INFO): Setting time to: 22:1:18 And date to:12/11/1980 1980-12-11T22:01:18.773Z,345420078.773 [DAT](INFO): DAT read: user:5> 1980-12-11T22:01:18.774Z,345420078.774 [DAT](INFO): DAT read: Thu Dec 11, 1980 22:01:18 1980-12-11T22:01:18.774Z,345420078.774 [DAT](INFO): Local DAT time set to Thu Dec 11, 1980 22:01:18 1980-12-11T22:01:19.008Z,345420079.008 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:19.452Z,345420079.452 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:19.880Z,345420079.880 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:19.997Z,345420079.997 [RudderServo](FAULT): Overload Error 1980-12-11T22:01:19.997Z,345420079.997 [RudderServo] Hardware Fault, FailCount= 3 1980-12-11T22:01:19.997Z,345420079.997 [RudderServo](ERROR): Hardware Fault 1980-12-11T22:01:20.108Z,345420080.108 [CBIT](ERROR): Hardware Fault in component: RudderServo 1980-12-11T22:01:20.109Z,345420080.109 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 1980-12-11T22:01:20.408Z,345420080.408 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:20.408Z,345420080.408 [Onboard ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:20.421Z,345420080.421 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 1980-12-11T22:01:20.421Z,345420080.421 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:20.421Z,345420080.421 [DataOverHttps](INFO): Join timeout helper Thread ID is 968 1980-12-11T22:01:20.444Z,345420080.444 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:20.452Z,345420080.452 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:20.452Z,345420080.452 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:20.461Z,345420080.461 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 1980-12-11T22:01:20.461Z,345420080.461 [DAT ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:20.461Z,345420080.461 [DAT](INFO): Join timeout helper Thread ID is 969 1980-12-11T22:01:20.497Z,345420080.497 [RudderServo](DEBUG): Uninitialize Rudder Servo. 1980-12-11T22:01:20.498Z,345420080.498 [RudderServo](INFO): Powering down 1980-12-11T22:01:20.501Z,345420080.501 [CBIT](INFO): Critical error at 19801211T220120 1980-12-11T22:01:20.536Z,345420080.536 [DAT](INFO): Powering down 1980-12-11T22:01:20.604Z,345420080.604 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:20.605Z,345420080.605 [DAT](INFO): Powering down 1980-12-11T22:01:20.609Z,345420080.609 [DAT ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:20.613Z,345420080.613 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 1980-12-11T22:01:20.613Z,345420080.613 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:20.613Z,345420080.613 [BackseatComponent](INFO): Join timeout helper Thread ID is 970 1980-12-11T22:01:20.776Z,345420080.776 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:20.776Z,345420080.776 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:20.793Z,345420080.793 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 1980-12-11T22:01:20.793Z,345420080.793 [logger ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:20.793Z,345420080.793 [logger](INFO): Join timeout helper Thread ID is 971 1980-12-11T22:01:20.857Z,345420080.857 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:21.122Z,345420081.122 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:21.122Z,345420081.122 [logger ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.141Z,345420081.141 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 1980-12-11T22:01:21.141Z,345420081.141 [CommandLine ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.142Z,345420081.142 [CommandLine](INFO): Join timeout helper Thread ID is 972 1980-12-11T22:01:21.220Z,345420081.220 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:21.221Z,345420081.221 [CommandLine ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.233Z,345420081.233 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 1980-12-11T22:01:21.233Z,345420081.233 [CommandExec ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.233Z,345420081.233 [CommandExec](INFO): Join timeout helper Thread ID is 973 1980-12-11T22:01:21.234Z,345420081.234 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 1980-12-11T22:01:21.234Z,345420081.234 [controlThread ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.235Z,345420081.235 [controlThread](INFO): Join timeout helper Thread ID is 974 1980-12-11T22:01:21.288Z,345420081.288 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 1980-12-11T22:01:21.333Z,345420081.333 [RudderServo](DEBUG): Initializing EZServoServo. 1980-12-11T22:01:21.453Z,345420081.453 [RudderServo](DEBUG): Initializing RudderServo. 1980-12-11T22:01:21.459Z,345420081.459 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 1980-12-11T22:01:21.459Z,345420081.459 [controlThread](DEBUG): Uninitializing ControlThread 1980-12-11T22:01:21.459Z,345420081.459 [AHRS_M2](INFO): Powering down 1980-12-11T22:01:21.533Z,345420081.533 [DepthKeller33X](INFO): Powering down 1980-12-11T22:01:21.629Z,345420081.629 [NAL9602](INFO): Powering down 1980-12-11T22:01:21.700Z,345420081.700 [RDI_Pathfinder](INFO): Powering down 1980-12-11T22:01:21.701Z,345420081.701 [Sonardyne_Nano](INFO): Powering down 1980-12-11T22:01:21.820Z,345420081.820 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 1980-12-11T22:01:21.821Z,345420081.821 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 1980-12-11T22:01:21.822Z,345420081.822 [NavChart](DEBUG): Uninitialize NavChart Navigation. 1980-12-11T22:01:21.822Z,345420081.822 [MissionManager](INFO): Uninitializing Mission Default 1980-12-11T22:01:21.825Z,345420081.825 [MissionManager](INFO): Uninitializing Mission Startup 1980-12-11T22:01:21.827Z,345420081.827 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 1980-12-11T22:01:21.827Z,345420081.827 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 1980-12-11T22:01:21.827Z,345420081.827 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 1980-12-11T22:01:21.827Z,345420081.827 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 1980-12-11T22:01:21.828Z,345420081.828 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 1980-12-11T22:01:21.828Z,345420081.828 [BuoyancyServo](INFO): Powering down 1980-12-11T22:01:21.840Z,345420081.840 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 1980-12-11T22:01:21.840Z,345420081.840 [ElevatorServo](INFO): Powering down 1980-12-11T22:01:21.841Z,345420081.841 [MassServo](DEBUG): Uninitialize Mass Servo. 1980-12-11T22:01:21.841Z,345420081.841 [MassServo](INFO): Powering down 1980-12-11T22:01:21.842Z,345420081.842 [RudderServo](DEBUG): Uninitialize Rudder Servo. 1980-12-11T22:01:21.842Z,345420081.842 [RudderServo](INFO): Powering down 1980-12-11T22:01:21.843Z,345420081.843 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 1980-12-11T22:01:21.843Z,345420081.843 [ThrusterHE](INFO): Powering down 1980-12-11T22:01:21.844Z,345420081.844 [SBIT](DEBUG): Uninitialize SBIT Component. 1980-12-11T22:01:21.844Z,345420081.844 [IBIT](DEBUG): Uninitialize IBIT Component. 1980-12-11T22:01:21.844Z,345420081.844 [CBIT](DEBUG): Uninitialize CBIT Component. 1980-12-11T22:01:21.844Z,345420081.844 [CBIT](DEBUG): Powering off loads. 1980-12-11T22:01:21.856Z,345420081.856 [CBIT](DEBUG): Disabling WDT. 1980-12-11T22:01:21.868Z,345420081.868 [GFScanner](DEBUG): Uninitialize GFScanner component. 1980-12-11T22:01:21.868Z,345420081.868 [GFScanner](DEBUG): Opening all GF detection circuits. 1980-12-11T22:01:21.869Z,345420081.869 [controlThread ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.888Z,345420081.888 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.891Z,345420081.891 [Onboard ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.897Z,345420081.897 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.903Z,345420081.903 [DAT ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:21.944Z,345420081.944 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:22.347Z,345420082.347 [RBRTridente ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:22.348Z,345420082.348 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:22.389Z,345420082.389 [GFScanner](DEBUG): Uninitialize GFScanner component. 1980-12-11T22:01:22.389Z,345420082.389 [GFScanner](DEBUG): Opening all GF detection circuits. 1980-12-11T22:01:22.408Z,345420082.408 [NavChartDb ThreadHandler](INFO): Thread cancelled. 1980-12-11T22:01:22.457Z,345420082.457 [logger ThreadHandler](INFO): Thread cancelled.