2006-03-29T19:34:20.536Z,1143660860.536 [Supervisor](DEBUG): Initializing supervisor. 2006-03-29T19:34:20.549Z,1143660860.549 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2006-03-29T19:34:20.550Z,1143660860.550 [SyncHandler](INFO): Protected caller Thread ID is 831 2006-03-29T19:34:20.550Z,1143660860.550 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2006-03-29T19:34:20.561Z,1143660860.561 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2006-03-29T19:34:20.561Z,1143660860.561 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 832 2006-03-29T19:34:20.566Z,1143660860.566 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2006-03-29T19:34:20.610Z,1143660860.610 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2006-03-29T19:34:20.621Z,1143660860.621 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2006-03-29T19:34:20.621Z,1143660860.621 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 833 2006-03-29T19:34:20.637Z,1143660860.637 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2006-03-29T19:34:20.649Z,1143660860.649 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2006-03-29T19:34:20.649Z,1143660860.649 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 834 2006-03-29T19:34:20.652Z,1143660860.652 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2006-03-29T19:34:20.661Z,1143660860.661 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2006-03-29T19:34:20.661Z,1143660860.661 [logger ThreadHandler](INFO): Protected caller Thread ID is 835 2006-03-29T19:34:20.665Z,1143660860.665 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2006-03-29T19:34:20.666Z,1143660860.666 [Supervisor](INFO): Looking for Config files in directory: Config/ 2006-03-29T19:34:20.678Z,1143660860.678 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2006-03-29T19:34:21.337Z,1143660861.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2006-03-29T19:34:21.338Z,1143660861.338 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2006-03-29T19:34:22.248Z,1143660862.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2006-03-29T19:34:22.249Z,1143660862.249 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2006-03-29T19:34:22.368Z,1143660862.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2006-03-29T19:34:22.369Z,1143660862.369 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2006-03-29T19:34:22.894Z,1143660862.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2006-03-29T19:34:22.895Z,1143660862.895 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2006-03-29T19:34:22.998Z,1143660862.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2006-03-29T19:34:22.999Z,1143660862.999 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2006-03-29T19:34:23.098Z,1143660863.098 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2006-03-29T19:34:23.098Z,1143660863.098 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2006-03-29T19:34:23.508Z,1143660863.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2006-03-29T19:34:23.509Z,1143660863.509 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2006-03-29T19:34:23.733Z,1143660863.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2006-03-29T19:34:23.734Z,1143660863.734 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2006-03-29T19:34:23.878Z,1143660863.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2006-03-29T19:34:23.879Z,1143660863.879 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2006-03-29T19:34:24.130Z,1143660864.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2006-03-29T19:34:24.131Z,1143660864.131 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2006-03-29T19:34:24.680Z,1143660864.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2006-03-29T19:34:24.681Z,1143660864.681 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2006-03-29T19:34:25.066Z,1143660865.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2006-03-29T19:34:25.067Z,1143660865.067 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2006-03-29T19:34:25.156Z,1143660865.156 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2006-03-29T19:34:25.341Z,1143660865.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2006-03-29T19:34:25.342Z,1143660865.342 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2006-03-29T19:34:25.588Z,1143660865.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2006-03-29T19:34:25.589Z,1143660865.589 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2006-03-29T19:34:25.901Z,1143660865.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2006-03-29T19:34:25.903Z,1143660865.903 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2006-03-29T19:34:25.905Z,1143660865.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2006-03-29T19:34:26.010Z,1143660866.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2006-03-29T19:34:26.166Z,1143660866.166 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2006-03-29T19:34:26.260Z,1143660866.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2006-03-29T19:34:26.352Z,1143660866.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2006-03-29T19:34:26.470Z,1143660866.470 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg 2006-03-29T19:34:26.571Z,1143660866.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Dock.cfg 2006-03-29T19:34:26.694Z,1143660866.694 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2006-03-29T19:34:26.810Z,1143660866.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2006-03-29T19:34:27.085Z,1143660867.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2006-03-29T19:34:27.085Z,1143660867.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2006-03-29T19:34:27.224Z,1143660867.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2006-03-29T19:34:27.338Z,1143660867.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2006-03-29T19:34:27.860Z,1143660867.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2006-03-29T19:34:28.353Z,1143660868.353 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2006-03-29T19:34:28.355Z,1143660868.355 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2006-03-29T19:34:28.525Z,1143660868.525 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2006-03-29T19:34:28.526Z,1143660868.526 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2006-03-29T19:34:28.542Z,1143660868.542 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2006-03-29T19:34:28.543Z,1143660868.543 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2006-03-29T19:34:28.615Z,1143660868.615 [DepthRateCalculator] Loaded 2006-03-29T19:34:28.615Z,1143660868.615 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2006-03-29T19:34:28.621Z,1143660868.621 [PitchRateCalculator] Loaded 2006-03-29T19:34:28.621Z,1143660868.621 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2006-03-29T19:34:28.632Z,1143660868.632 [SpeedCalculator] Loaded 2006-03-29T19:34:28.632Z,1143660868.632 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2006-03-29T19:34:28.637Z,1143660868.637 [YawRateCalculator] Loaded 2006-03-29T19:34:28.637Z,1143660868.637 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2006-03-29T19:34:28.659Z,1143660868.659 [ElevatorOffsetCalculator] Loaded 2006-03-29T19:34:28.659Z,1143660868.659 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2006-03-29T19:34:28.659Z,1143660868.659 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2006-03-29T19:34:28.660Z,1143660868.660 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2006-03-29T19:34:28.732Z,1143660868.732 [VerticalControl](DEBUG): Construct VerticalControl. 2006-03-29T19:34:28.790Z,1143660868.790 [VerticalControl] Loaded 2006-03-29T19:34:28.790Z,1143660868.790 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2006-03-29T19:34:28.793Z,1143660868.793 [HorizontalControl](DEBUG): Construct HorizontalControl. 2006-03-29T19:34:28.840Z,1143660868.840 [HorizontalControl] Loaded 2006-03-29T19:34:28.840Z,1143660868.840 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2006-03-29T19:34:28.843Z,1143660868.843 [SpeedControl](DEBUG): Construct SpeedControl. 2006-03-29T19:34:28.846Z,1143660868.846 [SpeedControl] Loaded 2006-03-29T19:34:28.846Z,1143660868.846 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2006-03-29T19:34:28.848Z,1143660868.848 [LoopControl](DEBUG): Construct LoopControl. 2006-03-29T19:34:28.849Z,1143660868.849 [LoopControl] Loaded 2006-03-29T19:34:28.849Z,1143660868.849 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2006-03-29T19:34:28.850Z,1143660868.850 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2006-03-29T19:34:28.850Z,1143660868.850 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2006-03-29T19:34:28.989Z,1143660868.989 [DeadReckonUsingMultipleVelocitySources] Loaded 2006-03-29T19:34:28.989Z,1143660868.989 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2006-03-29T19:34:29.003Z,1143660869.003 [NavChart] Loaded 2006-03-29T19:34:29.003Z,1143660869.003 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2006-03-29T19:34:29.009Z,1143660869.009 [UniversalFixResidualReporter] Loaded 2006-03-29T19:34:29.009Z,1143660869.009 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2006-03-29T19:34:29.010Z,1143660869.010 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2006-03-29T19:34:29.010Z,1143660869.010 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2006-03-29T19:34:29.226Z,1143660869.226 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2006-03-29T19:34:29.227Z,1143660869.227 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2006-03-29T19:34:30.072Z,1143660870.072 [AHRS_M2] Loaded 2006-03-29T19:34:30.072Z,1143660870.072 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2006-03-29T19:34:30.326Z,1143660870.326 [BackseatComponent] Loaded 2006-03-29T19:34:30.326Z,1143660870.326 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2006-03-29T19:34:30.327Z,1143660870.327 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409414E0 2006-03-29T19:34:30.328Z,1143660870.328 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923 2006-03-29T19:34:30.331Z,1143660870.331 [LcmPublisher] Loaded 2006-03-29T19:34:30.332Z,1143660870.332 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2006-03-29T19:34:31.153Z,1143660871.153 [BPC1] Loaded 2006-03-29T19:34:31.154Z,1143660871.154 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2006-03-29T19:34:31.542Z,1143660871.542 [DAT] Loaded 2006-03-29T19:34:31.542Z,1143660871.542 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2006-03-29T19:34:31.543Z,1143660871.543 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409714E0 2006-03-29T19:34:31.544Z,1143660871.544 [DAT ThreadHandler](INFO): Protected caller Thread ID is 924 2006-03-29T19:34:31.812Z,1143660871.812 [DataOverHttps] Loaded 2006-03-29T19:34:31.812Z,1143660871.812 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2006-03-29T19:34:31.841Z,1143660871.841 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0 2006-03-29T19:34:31.842Z,1143660871.842 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925 2006-03-29T19:34:31.867Z,1143660871.867 [Depth_Keller] Loaded 2006-03-29T19:34:31.867Z,1143660871.867 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2006-03-29T19:34:31.872Z,1143660871.872 [DropWeight] Loaded 2006-03-29T19:34:31.873Z,1143660871.873 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2006-03-29T19:34:31.939Z,1143660871.939 [NAL9602] Loaded 2006-03-29T19:34:31.939Z,1143660871.939 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2006-03-29T19:34:31.969Z,1143660871.969 [Onboard] Loaded 2006-03-29T19:34:31.969Z,1143660871.969 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2006-03-29T19:34:31.970Z,1143660871.970 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D14E0 2006-03-29T19:34:31.971Z,1143660871.971 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926 2006-03-29T19:34:31.985Z,1143660871.985 [Power24vConverter] Loaded 2006-03-29T19:34:31.985Z,1143660871.985 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2006-03-29T19:34:32.001Z,1143660872.001 [Radio_Surface] Loaded 2006-03-29T19:34:32.002Z,1143660872.002 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2006-03-29T19:34:32.003Z,1143660872.003 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A014E0 2006-03-29T19:34:32.003Z,1143660872.003 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927 2006-03-29T19:34:32.050Z,1143660872.050 [RDI_Pathfinder] Loaded 2006-03-29T19:34:32.050Z,1143660872.050 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2006-03-29T19:34:32.065Z,1143660872.065 [Sonardyne_Nano] Loaded 2006-03-29T19:34:32.065Z,1143660872.065 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2006-03-29T19:34:32.066Z,1143660872.066 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2006-03-29T19:34:32.067Z,1143660872.067 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2006-03-29T19:34:32.229Z,1143660872.229 [BuoyancyServo] Loaded 2006-03-29T19:34:32.229Z,1143660872.229 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2006-03-29T19:34:32.253Z,1143660872.253 [ElevatorServo] Loaded 2006-03-29T19:34:32.253Z,1143660872.253 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2006-03-29T19:34:32.283Z,1143660872.283 [DockingStepper] Loaded 2006-03-29T19:34:32.283Z,1143660872.283 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2006-03-29T19:34:32.303Z,1143660872.303 [MassServo] Loaded 2006-03-29T19:34:32.303Z,1143660872.303 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2006-03-29T19:34:32.324Z,1143660872.324 [RudderServo] Loaded 2006-03-29T19:34:32.324Z,1143660872.324 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2006-03-29T19:34:32.342Z,1143660872.342 [ThrusterHE] Loaded 2006-03-29T19:34:32.342Z,1143660872.342 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2006-03-29T19:34:32.342Z,1143660872.342 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2006-03-29T19:34:32.343Z,1143660872.343 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2006-03-29T19:34:32.502Z,1143660872.502 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2006-03-29T19:34:32.503Z,1143660872.503 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2006-03-29T19:34:32.666Z,1143660872.666 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2006-03-29T19:34:32.666Z,1143660872.666 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2006-03-29T19:34:33.079Z,1143660873.079 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2006-03-29T19:34:33.079Z,1143660873.079 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2006-03-29T19:34:33.308Z,1143660873.308 [CTD_Seabird] Loaded 2006-03-29T19:34:33.308Z,1143660873.308 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2006-03-29T19:34:33.309Z,1143660873.309 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC34E0 2006-03-29T19:34:33.310Z,1143660873.310 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928 2006-03-29T19:34:33.346Z,1143660873.346 [ESPComponent] Loaded 2006-03-29T19:34:33.347Z,1143660873.347 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2006-03-29T19:34:33.369Z,1143660873.369 [PAR_Licor] Loaded 2006-03-29T19:34:33.369Z,1143660873.369 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2006-03-29T19:34:33.415Z,1143660873.415 [WetLabsBB2FL] Loaded 2006-03-29T19:34:33.415Z,1143660873.415 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2006-03-29T19:34:33.416Z,1143660873.416 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF34E0 2006-03-29T19:34:33.417Z,1143660873.417 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929 2006-03-29T19:34:33.418Z,1143660873.418 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2006-03-29T19:34:33.418Z,1143660873.418 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2006-03-29T19:34:33.614Z,1143660873.614 [SBIT](DEBUG): Construct Startup Built In Test. 2006-03-29T19:34:33.624Z,1143660873.624 [SBIT] Loaded 2006-03-29T19:34:33.624Z,1143660873.624 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2006-03-29T19:34:33.627Z,1143660873.627 [IBIT](DEBUG): Construct Initiated Built In Test. 2006-03-29T19:34:33.640Z,1143660873.640 [IBIT] Loaded 2006-03-29T19:34:33.640Z,1143660873.640 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2006-03-29T19:34:33.646Z,1143660873.646 [CBIT](DEBUG): Construct Continuous Built In Test. 2006-03-29T19:34:33.727Z,1143660873.727 [CBIT] Loaded 2006-03-29T19:34:33.728Z,1143660873.728 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2006-03-29T19:34:33.761Z,1143660873.761 [GFScanner] Loaded 2006-03-29T19:34:33.761Z,1143660873.761 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2006-03-29T19:34:33.761Z,1143660873.761 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2006-03-29T19:34:33.768Z,1143660873.768 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2006-03-29T19:34:33.771Z,1143660873.771 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2006-03-29T19:34:33.782Z,1143660873.782 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2006-03-29T19:34:33.783Z,1143660873.783 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6E4E0 2006-03-29T19:34:33.783Z,1143660873.783 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930 2006-03-29T19:34:33.788Z,1143660873.788 [Supervisor](INFO): Main Thread ID is 824 2006-03-29T19:34:33.788Z,1143660873.788 [Supervisor](DEBUG): Running supervisor. 2006-03-29T19:34:33.788Z,1143660873.788 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931 2006-03-29T19:34:33.789Z,1143660873.789 [CommandExec](INFO): Initializing the command executive. 2006-03-29T19:34:33.791Z,1143660873.791 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932 2006-03-29T19:34:33.793Z,1143660873.793 [controlThread ThreadHandler](INFO): Handler Thread ID is 933 2006-03-29T19:34:33.793Z,1143660873.793 [controlThread](DEBUG): Initializing ControlThread 2006-03-29T19:34:33.794Z,1143660873.794 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2006-03-29T19:34:33.794Z,1143660873.794 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2006-03-29T19:34:33.795Z,1143660873.795 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2006-03-29T19:34:33.795Z,1143660873.795 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2006-03-29T19:34:33.795Z,1143660873.795 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2006-03-29T19:34:33.796Z,1143660873.796 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2006-03-29T19:34:33.798Z,1143660873.798 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2006-03-29T19:34:33.798Z,1143660873.798 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2006-03-29T19:34:33.799Z,1143660873.799 [LoopControl](DEBUG): Initialize LoopControlComponent. 2006-03-29T19:34:33.800Z,1143660873.800 [NavChart](DEBUG): Initialize NavChart Navigation. 2006-03-29T19:34:33.800Z,1143660873.800 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2006-03-29T19:34:33.805Z,1143660873.805 [SBIT](INFO): Initialize SBIT Component. 2006-03-29T19:34:33.806Z,1143660873.806 [SBIT](IMPORTANT): git: 2025-11-06-2-g37d2ccc16 2006-03-29T19:34:33.806Z,1143660873.806 [SBIT](INFO): git hash: 37d2ccc16c3820cae3b4f7d56ca89ff0df066476 2006-03-29T19:34:33.806Z,1143660873.806 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2006-03-29T19:34:33.807Z,1143660873.807 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2006-03-29T19:34:33.808Z,1143660873.808 [SBIT](INFO): Beginning SBIT in 134.000000 seconds. 2006-03-29T19:34:33.809Z,1143660873.809 [IBIT](INFO): Initialize IBIT Component. 2006-03-29T19:34:33.810Z,1143660873.810 [CBIT](DEBUG): Initialize CBIT Component. 2006-03-29T19:34:33.811Z,1143660873.811 [logger ThreadHandler](INFO): Handler Thread ID is 934 2006-03-29T19:34:33.821Z,1143660873.821 [CBIT](DEBUG): Initialized mux pins. 2006-03-29T19:34:33.821Z,1143660873.821 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2006-03-29T19:34:33.821Z,1143660873.821 [CBIT](DEBUG): Initializing the watchdog timer. 2006-03-29T19:34:33.829Z,1143660873.829 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935 2006-03-29T19:34:33.841Z,1143660873.841 [DAT ThreadHandler](INFO): Handler Thread ID is 936 2006-03-29T19:34:33.842Z,1143660873.842 [DAT](INFO): Powering up 2006-03-29T19:34:33.842Z,1143660873.842 [DAT](DEBUG): Initializing DAT. 2006-03-29T19:34:33.845Z,1143660873.845 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2006-03-29T19:34:33.846Z,1143660873.846 [CBIT](DEBUG): Initializing heartbeat. 2006-03-29T19:34:33.847Z,1143660873.847 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938 2006-03-29T19:34:33.849Z,1143660873.849 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2006-03-29T19:34:33.858Z,1143660873.858 [Onboard ThreadHandler](INFO): Handler Thread ID is 939 2006-03-29T19:34:33.875Z,1143660873.875 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940 2006-03-29T19:34:33.894Z,1143660873.894 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941 2006-03-29T19:34:33.895Z,1143660873.895 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2006-03-29T19:34:33.898Z,1143660873.898 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943 2006-03-29T19:34:33.901Z,1143660873.901 [WetLabsBB2FL](INFO): Powering up 2006-03-29T19:34:33.903Z,1143660873.903 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945 2006-03-29T19:34:33.910Z,1143660873.910 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2006-03-29T19:34:33.910Z,1143660873.910 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2006-03-29T19:34:33.910Z,1143660873.910 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2006-03-29T19:34:33.911Z,1143660873.911 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2006-03-29T19:34:33.911Z,1143660873.911 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2006-03-29T19:34:33.911Z,1143660873.911 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2006-03-29T19:34:33.911Z,1143660873.911 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2006-03-29T19:34:33.911Z,1143660873.911 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2006-03-29T19:34:33.917Z,1143660873.917 [CBIT](DEBUG): Deactivating emergency mode. 2006-03-29T19:34:33.957Z,1143660873.957 [CBIT](DEBUG): Backplane powered. 2006-03-29T19:34:33.957Z,1143660873.957 [GFScanner](DEBUG): Initializing GFScanner 2006-03-29T19:34:33.958Z,1143660873.958 [GFScanner](DEBUG): Deactivating GF circuits. 2006-03-29T19:34:33.962Z,1143660873.962 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2006-03-29T19:34:33.963Z,1143660873.963 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2006-03-29T19:34:33.963Z,1143660873.963 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2006-03-29T19:34:33.968Z,1143660873.968 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2006-03-29T19:34:33.983Z,1143660873.983 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2006-03-29T19:34:33.983Z,1143660873.983 [MissionManager](ERROR): Top node has no child nodes: Mission 2006-03-29T19:34:33.984Z,1143660873.984 [MissionManager](ERROR): Could not load startup mission. 2006-03-29T19:34:33.984Z,1143660873.984 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2006-03-29T19:34:33.984Z,1143660873.984 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2006-03-29T19:34:33.987Z,1143660873.987 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2006-03-29T19:34:34.281Z,1143660874.281 [Radio_Surface](INFO): Powering up 2006-03-29T19:34:34.528Z,1143660874.528 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into: 0 6 10 1 3 30 Timed out while trying to detach from dock at current depth of . Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2006-03-29T19:34:34.551Z,1143660874.551 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2006-03-29T19:34:34.571Z,1143660874.571 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h 2006-03-29T19:34:34.575Z,1143660874.575 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2006-03-29T19:34:34.595Z,1143660874.595 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s 2006-03-29T19:34:34.608Z,1143660874.608 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2006-03-29T19:34:34.611Z,1143660874.611 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min 2006-03-29T19:34:34.613Z,1143660874.613 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2006-03-29T19:34:34.780Z,1143660874.780 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2006-03-29T19:34:34.780Z,1143660874.780 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2006-03-29T19:34:34.851Z,1143660874.851 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2006-03-29T19:34:34.905Z,1143660874.905 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2006-03-29T19:34:34.908Z,1143660874.908 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2006-03-29T19:34:34.926Z,1143660874.926 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2006-03-29T19:34:34.935Z,1143660874.935 [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 DefaultWithUndock { arguments { ElapsedSinceDefaultStarted = 0 minute DetachTimeout = 6 hour """ Maximum time duration for the vehicle to detach form the dock. """ TransponderCode = Dock:Dock.transponderCode """ Dock transponder address. """ TrackingUpdatePeriod = 10 second """ Time duration between acoustic range queries while the mission is detaching from the dock. """ NumberOfPings = 1 count """ Number of pings requested each time. """ DriftTimeout = 3 minute """ How long to allow vehicle to drift away from dock before retrying reverse """ } 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 = 30 second } aggregate LeaveDock { run in sequence timeout duration=DetachTimeout { syslog important "Timed out while trying to detach from dock at current depth of " + Universal:depth~meter + "." } # Acoustic tracking directives behavior Estimation:TrackAcousticContact { run while ( Universal:depth > Control:VerticalControl.surfaceThreshold ) set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } # Leave the dock behavior Dock:Undock { run in sequence set driftTimeout = DriftTimeout } } aggregate SurfaceDefault { run in sequence # This mission assumes open water so from here on it's the same old default that's always used 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=P2H { 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" } } 2006-03-29T19:34:34.940Z,1143660874.940 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter, 2006-03-29T19:34:34.965Z,1143660874.965 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2006-03-29T19:34:35.110Z,1143660875.110 [Depth_Keller](INFO): Initializing. 2006-03-29T19:34:35.111Z,1143660875.111 [Power24vConverter](INFO): Powering up. 2006-03-29T19:34:35.112Z,1143660875.112 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2006-03-29T19:34:35.114Z,1143660875.114 [Sonardyne_Nano](INFO): Initializing. 2006-03-29T19:34:35.146Z,1143660875.146 [DepthRateCalculator](ERROR): Depth measurement is not active 2006-03-29T19:34:35.173Z,1143660875.173 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2006-03-29T19:34:35.189Z,1143660875.189 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2006-03-29T19:34:35.190Z,1143660875.190 [ElevatorServo](DEBUG): Initializing EZServoServo. 2006-03-29T19:34:35.197Z,1143660875.197 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2006-03-29T19:34:35.198Z,1143660875.198 [DockingStepper](DEBUG): Initializing EZServoServo. 2006-03-29T19:34:35.205Z,1143660875.205 [DockingStepper](DEBUG): Initializing DockingStepper. 2006-03-29T19:34:35.206Z,1143660875.206 [MassServo](DEBUG): Initializing EZServoServo. 2006-03-29T19:34:35.213Z,1143660875.213 [MassServo](DEBUG): Initializing MassServo. 2006-03-29T19:34:35.214Z,1143660875.214 [RudderServo](DEBUG): Initializing EZServoServo. 2006-03-29T19:34:35.221Z,1143660875.221 [RudderServo](DEBUG): Initializing RudderServo. 2006-03-29T19:34:35.222Z,1143660875.222 [ThrusterHE](DEBUG): Initializing EZServoServo. 2006-03-29T19:34:35.229Z,1143660875.229 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2006-03-29T19:34:35.387Z,1143660875.387 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2006-03-29T19:34:35.387Z,1143660875.387 [DropWeight] Hardware Fault, FailCount= 1 2006-03-29T19:34:35.387Z,1143660875.387 [DropWeight](ERROR): Hardware Fault 2006-03-29T19:34:35.439Z,1143660875.439 [CommandExec](FAULT): Scheduling is paused 2006-03-29T19:34:35.440Z,1143660875.440 [CBIT](INFO): Critical error at 20060329T193435 2006-03-29T19:34:35.440Z,1143660875.440 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2006-03-29T19:34:35.456Z,1143660875.456 [CBIT](ERROR): Hardware Fault in component: DropWeight 2006-03-29T19:34:35.456Z,1143660875.456 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2006-03-29T19:34:35.775Z,1143660875.775 [CBIT](INFO): Critical error at 20060329T193435 2006-03-29T19:34:36.009Z,1143660876.009 [WetLabsBB2FL](INFO): Powering down 2006-03-29T19:34:37.375Z,1143660877.375 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2006-03-29T19:34:38.622Z,1143660878.622 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2006-03-29T19:34:39.533Z,1143660879.533 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11 2006-03-29T19:34:39.534Z,1143660879.534 [Radio_Surface](INFO): No local IP found 2006-03-29T19:34:40.886Z,1143660880.886 [ThrusterHE](ERROR): Zero Speed Commanded. 2006-03-29T19:34:45.438Z,1143660885.438 [DAT](INFO): DAT read: 2006-03-29T19:34:45.439Z,1143660885.439 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2006-03-29T19:34:46.950Z,1143660886.950 [DAT](INFO): DAT read: MF Frequency Band 2006-03-29T19:34:46.951Z,1143660886.951 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2006-03-29T19:34:46.952Z,1143660886.952 [DAT](INFO): DAT read: Mar 29 2006 19:34:41 2006-03-29T19:34:48.210Z,1143660888.210 [DAT](INFO): DAT read: Features enabled [Bearing] 2006-03-29T19:34:48.211Z,1143660888.211 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2006-03-29T19:34:48.212Z,1143660888.212 [DAT](INFO): commRate: 600 2006-03-29T19:34:50.277Z,1143660890.277 [DAT](INFO): entering command mode 2006-03-29T19:34:50.489Z,1143660890.489 [DAT](INFO): DAT read: 2006-03-29T19:34:50.489Z,1143660890.489 [DAT](INFO): DAT read: user:1> 2006-03-29T19:34:50.490Z,1143660890.490 [DAT](INFO): setting verbose to 3 2006-03-29T19:34:50.730Z,1143660890.730 [DAT](INFO): DAT read: user:1> 2006-03-29T19:34:50.731Z,1143660890.731 [DAT](INFO): DAT read: Verbose | 3 2006-03-29T19:34:50.731Z,1143660890.731 [DAT](INFO): set verbose to 3 2006-03-29T19:34:50.732Z,1143660890.732 [DAT](INFO): setting DatVerbose to 27440 2006-03-29T19:34:50.982Z,1143660890.982 [DAT](INFO): DAT read: user:2> 2006-03-29T19:34:50.983Z,1143660890.983 [DAT](INFO): DAT read: DatVerbose | 27440 2006-03-29T19:34:50.983Z,1143660890.983 [DAT](INFO): set DatVerbose to 27440 2006-03-29T19:34:50.983Z,1143660890.983 [DAT](INFO): setting transmit power to 8 2006-03-29T19:34:51.234Z,1143660891.234 [DAT](INFO): DAT read: user:3> 2006-03-29T19:34:51.235Z,1143660891.235 [DAT](INFO): DAT read: TxPower | 8 (Max) 2006-03-29T19:34:51.235Z,1143660891.235 [DAT](INFO): set transmit power to 8 2006-03-29T19:34:51.235Z,1143660891.235 [DAT](INFO): setting local address to 11 2006-03-29T19:34:51.486Z,1143660891.486 [DAT](INFO): DAT read: user:4> 2006-03-29T19:34:51.487Z,1143660891.487 [DAT](INFO): DAT read: LocalAddr | 11 2006-03-29T19:34:51.487Z,1143660891.487 [DAT](INFO): set local address to 11 2006-03-29T19:34:51.488Z,1143660891.488 [DAT](INFO): Setting time to: 19:34:51 And date to:3/29/2006 2006-03-29T19:34:51.738Z,1143660891.738 [DAT](INFO): DAT read: user:5> 2006-03-29T19:34:51.739Z,1143660891.739 [DAT](INFO): DAT read: Wed Mar 29, 2006 19:34:51 2006-03-29T19:34:51.739Z,1143660891.739 [DAT](INFO): Local DAT time set to Wed Mar 29, 2006 19:34:51 2006-03-29T19:35:02.062Z,1143660902.062 [NAL9602](INFO): Powering up NAL9602 2006-03-29T19:35:12.974Z,1143660912.974 [NAL9602](INFO): NAL9602 initialized 2006-03-29T19:36:28.666Z,1143660988.666 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2006-03-29T19:36:36.780Z,1143660996.780 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004921 2025-11-12T19:36:37.106Z,1762976197.106 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-11-12T19:36:37.106Z,1762976197.106 [BPC1](ERROR): Battery stick #62 (s/n: 0126) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-11-12T19:36:37.107Z,1762976197.107 [BPC1](INFO): Calculating totals. Valid battery stick count: 5. Valid reserve battery stick count: 0. 2025-11-12T19:36:37.158Z,1762976197.158 [BPC1](CRITICAL): Battery Capacity Below Threshold. 26.736000 Ah. 2025-11-12T19:36:37.179Z,1762976197.179 [BPC1](FAULT): Failed to receive data from 55 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30, 31, 32, 33, 34, 35, 36, 37, 38, 39, 40, 41, 42, 43, 44, 45, 46, 47, 48, 49, 50, 51, 52, 53, 54, 55. 2025-11-12T19:36:37.182Z,1762976197.182 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-11-12T19:36:37.184Z,1762976197.184 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-11-12T19:36:37.184Z,1762976197.184 [RDI_Pathfinder] Communications Fault, FailCount= 1 2025-11-12T19:36:37.195Z,1762976197.195 [RDI_Pathfinder](ERROR): Communications Fault 2025-11-12T19:36:37.199Z,1762976197.199 [Sonardyne_Nano](FAULT): Failed to receive valid data within the specified timeout. 2025-11-12T19:36:37.199Z,1762976197.199 [Sonardyne_Nano] Communications Fault, FailCount= 1 2025-11-12T19:36:37.200Z,1762976197.200 [Sonardyne_Nano](ERROR): Communications Fault 2025-11-12T19:36:37.299Z,1762976197.299 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-11-12T19:36:37.299Z,1762976197.299 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-11-12T19:36:37.310Z,1762976197.310 [CBIT](INFO): Critical error at 20251112T193637 2025-11-12T19:36:37.322Z,1762976197.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-11-12T19:36:37.322Z,1762976197.322 [CBIT](INFO): Clearing failed state for component DropWeight 2025-11-12T19:36:37.322Z,1762976197.322 [DropWeight] No Fault, FailCount= 1 2025-11-12T19:36:37.323Z,1762976197.323 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-11-12T19:36:37.323Z,1762976197.323 [CBIT](ERROR): Communications Fault in component: Sonardyne_Nano 2025-11-12T19:36:37.324Z,1762976197.324 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-11-12T19:36:37.428Z,1762976197.428 [RDI_Pathfinder](INFO): Powering down 2025-11-12T19:36:37.429Z,1762976197.429 [Sonardyne_Nano](INFO): Powering down 2025-11-12T19:36:37.480Z,1762976197.480 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=619315200.000000 2025-11-12T19:36:37.571Z,1762976197.571 [SBIT](IMPORTANT): Beginning Startup BIT 2025-11-12T19:36:37.575Z,1762976197.575 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-11-12T19:36:37.575Z,1762976197.575 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-11-12T19:36:37.750Z,1762976197.750 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-11-12T19:36:37.750Z,1762976197.750 [NAL9602] Data Fault, FailCount= 1 2025-11-12T19:36:37.750Z,1762976197.750 [NAL9602](ERROR): Data Fault 2025-11-12T19:36:37.922Z,1762976197.922 [CBIT](ERROR): Data Fault in component: NAL9602 2025-11-12T19:36:38.158Z,1762976198.158 [NAL9602](INFO): Powering down 2025-11-12T19:36:38.219Z,1762976198.219 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-11-12T19:36:38.220Z,1762976198.220 [RDI_Pathfinder] No Fault, FailCount= 1 2025-11-12T19:36:38.220Z,1762976198.220 [CBIT](INFO): Clearing failed state for component Sonardyne_Nano 2025-11-12T19:36:38.220Z,1762976198.220 [Sonardyne_Nano] No Fault, FailCount= 1 2025-11-12T19:36:38.480Z,1762976198.480 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-11-12T19:36:38.550Z,1762976198.550 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-11-12T19:36:38.551Z,1762976198.551 [Sonardyne_Nano](INFO): Initializing. 2025-11-12T19:36:39.385Z,1762976199.385 [CBIT](INFO): Clearing failed state for component NAL9602 2025-11-12T19:36:39.385Z,1762976199.385 [NAL9602] No Fault, FailCount= 1 2025-11-12T19:36:40.694Z,1762976200.694 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-11-12T19:36:41.637Z,1762976201.637 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.003382 CHAN A1 (24V): -0.000166 CHAN A2 (12V): 0.000329 CHAN A3 (5V): 0.000498 CHAN B0 (3.3V): 0.000004 CHAN B1 (3.15aV): 0.000483 CHAN B2 (3.15bV): 0.000277 CHAN B3 (GND): 0.000606 OPEN: 0.000183 Full Scale: +/- 1 mA 2025-11-12T19:36:46.025Z,1762976206.025 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003614 2025-11-12T19:36:47.024Z,1762976207.024 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-11-12T19:36:47.277Z,1762976207.277 [CommandExec](IMPORTANT): got command restart system 2025-11-12T19:36:49.336Z,1762976209.336 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-12T19:36:49.336Z,1762976209.336 [CommandExec](INFO): Uninitializing the command executive. 2025-11-12T19:36:49.337Z,1762976209.337 [CommandExec](INFO): Uninitializing the command scheduler. 2025-11-12T19:36:49.337Z,1762976209.337 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:49.379Z,1762976209.379 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-11-12T19:36:49.379Z,1762976209.379 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-11-12T19:36:49.380Z,1762976209.380 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:49.380Z,1762976209.380 [NavChartDb](INFO): Join timeout helper Thread ID is 973 2025-11-12T19:36:49.728Z,1762976209.728 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-12T19:36:49.729Z,1762976209.729 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:49.744Z,1762976209.744 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-11-12T19:36:49.744Z,1762976209.744 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:49.745Z,1762976209.745 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 974 2025-11-12T19:36:49.750Z,1762976209.750 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-12T19:36:49.751Z,1762976209.751 [WetLabsBB2FL](INFO): Powering down 2025-11-12T19:36:49.754Z,1762976209.754 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:49.764Z,1762976209.764 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-11-12T19:36:49.764Z,1762976209.764 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:49.765Z,1762976209.765 [CTD_Seabird](INFO): Join timeout helper Thread ID is 975 2025-11-12T19:36:50.144Z,1762976210.144 [CTD_Seabird](INFO): Powering down 2025-11-12T19:36:50.156Z,1762976210.156 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-12T19:36:50.156Z,1762976210.156 [CTD_Seabird](INFO): Powering down 2025-11-12T19:36:50.168Z,1762976210.168 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:50.169Z,1762976210.169 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-11-12T19:36:50.169Z,1762976210.169 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:50.169Z,1762976210.169 [Radio_Surface](INFO): Join timeout helper Thread ID is 976 2025-11-12T19:36:50.532Z,1762976210.532 [Radio_Surface](INFO): Powering down 2025-11-12T19:36:50.533Z,1762976210.533 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-12T19:36:50.534Z,1762976210.534 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:50.548Z,1762976210.548 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-11-12T19:36:50.548Z,1762976210.548 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:50.549Z,1762976210.549 [Onboard](INFO): Join timeout helper Thread ID is 977 2025-11-12T19:36:51.222Z,1762976211.222 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2025-11-12T19:36:51.222Z,1762976211.222 [RDI_Pathfinder] Data Fault, FailCount= 2 2025-11-12T19:36:51.222Z,1762976211.222 [RDI_Pathfinder](ERROR): Data Fault 2025-11-12T19:36:51.295Z,1762976211.295 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2025-11-12T19:36:51.700Z,1762976211.700 [RDI_Pathfinder](INFO): Powering down 2025-11-12T19:36:52.472Z,1762976212.472 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-11-12T19:36:52.472Z,1762976212.472 [RDI_Pathfinder] No Fault, FailCount= 2 2025-11-12T19:36:52.842Z,1762976212.842 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-11-12T19:36:53.668Z,1762976213.668 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-12T19:36:53.669Z,1762976213.669 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:53.686Z,1762976213.686 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-11-12T19:36:53.686Z,1762976213.686 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:53.686Z,1762976213.686 [DataOverHttps](INFO): Join timeout helper Thread ID is 982 2025-11-12T19:36:54.544Z,1762976214.544 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-12T19:36:54.547Z,1762976214.547 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-11-12T19:36:54.549Z,1762976214.549 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-11-12T1