2022-12-02T21:12:12.292Z,1670015532.292 [Supervisor](DEBUG): Initializing supervisor. 2022-12-02T21:12:12.297Z,1670015532.297 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-12-02T21:12:12.297Z,1670015532.297 [SyncHandler](INFO): Protected caller Thread ID is 835 2022-12-02T21:12:12.298Z,1670015532.298 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-12-02T21:12:12.299Z,1670015532.299 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-12-02T21:12:12.299Z,1670015532.299 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836 2022-12-02T21:12:12.303Z,1670015532.303 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-12-02T21:12:12.322Z,1670015532.322 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-12-02T21:12:12.323Z,1670015532.323 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-12-02T21:12:12.323Z,1670015532.323 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837 2022-12-02T21:12:12.327Z,1670015532.327 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-12-02T21:12:12.328Z,1670015532.328 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-12-02T21:12:12.329Z,1670015532.329 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838 2022-12-02T21:12:12.331Z,1670015532.331 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-12-02T21:12:12.332Z,1670015532.332 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-12-02T21:12:12.333Z,1670015532.333 [logger ThreadHandler](INFO): Protected caller Thread ID is 839 2022-12-02T21:12:12.337Z,1670015532.337 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-12-02T21:12:12.337Z,1670015532.337 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-12-02T21:12:12.341Z,1670015532.341 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-12-02T21:12:12.781Z,1670015532.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-12-02T21:12:12.782Z,1670015532.782 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-12-02T21:12:12.984Z,1670015532.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-12-02T21:12:12.986Z,1670015532.986 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-12-02T21:12:13.067Z,1670015533.067 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-12-02T21:12:13.283Z,1670015533.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-12-02T21:12:13.285Z,1670015533.285 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-12-02T21:12:13.365Z,1670015533.365 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-12-02T21:12:13.460Z,1670015533.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-12-02T21:12:13.462Z,1670015533.462 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-12-02T21:12:13.800Z,1670015533.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-12-02T21:12:13.802Z,1670015533.802 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-12-02T21:12:13.931Z,1670015533.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-12-02T21:12:13.933Z,1670015533.933 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-12-02T21:12:14.405Z,1670015534.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-12-02T21:12:14.407Z,1670015534.407 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-12-02T21:12:14.586Z,1670015534.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-12-02T21:12:14.588Z,1670015534.588 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-12-02T21:12:14.975Z,1670015534.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-12-02T21:12:14.976Z,1670015534.976 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-12-02T21:12:15.247Z,1670015535.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-12-02T21:12:15.249Z,1670015535.249 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-12-02T21:12:15.357Z,1670015535.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-12-02T21:12:15.359Z,1670015535.359 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-12-02T21:12:16.573Z,1670015536.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-12-02T21:12:16.574Z,1670015536.574 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-12-02T21:12:16.896Z,1670015536.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-12-02T21:12:16.898Z,1670015536.898 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-12-02T21:12:17.111Z,1670015537.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-12-02T21:12:17.113Z,1670015537.113 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2022-12-02T21:12:17.117Z,1670015537.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2022-12-02T21:12:17.298Z,1670015537.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2022-12-02T21:12:17.382Z,1670015537.382 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2022-12-02T21:12:17.513Z,1670015537.513 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2022-12-02T21:12:17.613Z,1670015537.613 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2022-12-02T21:12:17.872Z,1670015537.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-12-02T21:12:17.873Z,1670015537.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2022-12-02T21:12:17.976Z,1670015537.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2022-12-02T21:12:18.086Z,1670015538.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2022-12-02T21:12:18.195Z,1670015538.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2022-12-02T21:12:18.335Z,1670015538.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2022-12-02T21:12:18.433Z,1670015538.433 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2022-12-02T21:12:18.524Z,1670015538.524 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-12-02T21:12:18.540Z,1670015538.540 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-12-02T21:12:18.556Z,1670015538.556 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-12-02T21:12:18.557Z,1670015538.557 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-12-02T21:12:18.681Z,1670015538.681 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-12-02T21:12:18.681Z,1670015538.681 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-12-02T21:12:18.748Z,1670015538.748 [VerticalControl](DEBUG): Construct VerticalControl. 2022-12-02T21:12:18.809Z,1670015538.809 [VerticalControl] Loaded 2022-12-02T21:12:18.809Z,1670015538.809 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-12-02T21:12:18.812Z,1670015538.812 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-12-02T21:12:18.853Z,1670015538.853 [HorizontalControl] Loaded 2022-12-02T21:12:18.853Z,1670015538.853 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-12-02T21:12:18.855Z,1670015538.855 [SpeedControl](DEBUG): Construct SpeedControl. 2022-12-02T21:12:18.859Z,1670015538.859 [SpeedControl] Loaded 2022-12-02T21:12:18.859Z,1670015538.859 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-12-02T21:12:18.861Z,1670015538.861 [LoopControl](DEBUG): Construct LoopControl. 2022-12-02T21:12:18.862Z,1670015538.862 [LoopControl] Loaded 2022-12-02T21:12:18.862Z,1670015538.862 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-12-02T21:12:18.863Z,1670015538.863 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-12-02T21:12:18.863Z,1670015538.863 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-12-02T21:12:19.044Z,1670015539.044 [BuoyancyServo] Loaded 2022-12-02T21:12:19.044Z,1670015539.044 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-12-02T21:12:19.063Z,1670015539.063 [ElevatorServo] Loaded 2022-12-02T21:12:19.064Z,1670015539.064 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-12-02T21:12:19.083Z,1670015539.083 [MassServo] Loaded 2022-12-02T21:12:19.083Z,1670015539.083 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-12-02T21:12:19.102Z,1670015539.102 [RudderServo] Loaded 2022-12-02T21:12:19.102Z,1670015539.102 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-12-02T21:12:19.117Z,1670015539.117 [ThrusterHE] Loaded 2022-12-02T21:12:19.117Z,1670015539.117 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2022-12-02T21:12:19.118Z,1670015539.118 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-12-02T21:12:19.118Z,1670015539.118 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-12-02T21:12:19.188Z,1670015539.188 [DepthRateCalculator] Loaded 2022-12-02T21:12:19.188Z,1670015539.188 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-12-02T21:12:19.193Z,1670015539.193 [PitchRateCalculator] Loaded 2022-12-02T21:12:19.193Z,1670015539.193 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-12-02T21:12:19.203Z,1670015539.203 [SpeedCalculator] Loaded 2022-12-02T21:12:19.203Z,1670015539.203 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-12-02T21:12:19.208Z,1670015539.208 [YawRateCalculator] Loaded 2022-12-02T21:12:19.208Z,1670015539.208 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-12-02T21:12:19.226Z,1670015539.226 [ElevatorOffsetCalculator] Loaded 2022-12-02T21:12:19.227Z,1670015539.227 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-12-02T21:12:19.227Z,1670015539.227 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-12-02T21:12:19.228Z,1670015539.228 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-12-02T21:12:19.366Z,1670015539.366 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-12-02T21:12:19.367Z,1670015539.367 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-12-02T21:12:19.380Z,1670015539.380 [NavChart] Loaded 2022-12-02T21:12:19.381Z,1670015539.381 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-12-02T21:12:19.386Z,1670015539.386 [UniversalFixResidualReporter] Loaded 2022-12-02T21:12:19.387Z,1670015539.387 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-12-02T21:12:19.387Z,1670015539.387 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-12-02T21:12:19.388Z,1670015539.388 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-12-02T21:12:20.107Z,1670015540.107 [AHRS_M2] Loaded 2022-12-02T21:12:20.107Z,1670015540.107 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-12-02T21:12:20.356Z,1670015540.356 [BackseatComponent] Loaded 2022-12-02T21:12:20.357Z,1670015540.357 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2022-12-02T21:12:20.358Z,1670015540.358 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408D14E0 2022-12-02T21:12:20.358Z,1670015540.358 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921 2022-12-02T21:12:20.361Z,1670015540.361 [LcmUniversalReporter] Loaded 2022-12-02T21:12:20.361Z,1670015540.361 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2022-12-02T21:12:21.190Z,1670015541.190 [BPC1] Loaded 2022-12-02T21:12:21.191Z,1670015541.191 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-12-02T21:12:21.334Z,1670015541.334 [DataOverHttps] Loaded 2022-12-02T21:12:21.335Z,1670015541.335 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-12-02T21:12:21.336Z,1670015541.336 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409014E0 2022-12-02T21:12:21.336Z,1670015541.336 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922 2022-12-02T21:12:21.357Z,1670015541.357 [Depth_Keller] Loaded 2022-12-02T21:12:21.357Z,1670015541.357 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-12-02T21:12:21.362Z,1670015541.362 [DropWeight] Loaded 2022-12-02T21:12:21.362Z,1670015541.362 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2022-12-02T21:12:21.424Z,1670015541.424 [NAL9602] Loaded 2022-12-02T21:12:21.424Z,1670015541.424 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-12-02T21:12:21.454Z,1670015541.454 [Onboard] Loaded 2022-12-02T21:12:21.454Z,1670015541.454 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-12-02T21:12:21.455Z,1670015541.455 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409314E0 2022-12-02T21:12:21.455Z,1670015541.455 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923 2022-12-02T21:12:21.467Z,1670015541.467 [Power24vConverter] Loaded 2022-12-02T21:12:21.468Z,1670015541.468 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-12-02T21:12:21.481Z,1670015541.481 [Radio_Surface] Loaded 2022-12-02T21:12:21.482Z,1670015541.482 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-12-02T21:12:21.483Z,1670015541.483 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409614E0 2022-12-02T21:12:21.483Z,1670015541.483 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924 2022-12-02T21:12:21.576Z,1670015541.576 [DAT] Loaded 2022-12-02T21:12:21.576Z,1670015541.576 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2022-12-02T21:12:21.577Z,1670015541.577 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409914E0 2022-12-02T21:12:21.577Z,1670015541.577 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925 2022-12-02T21:12:21.578Z,1670015541.578 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-12-02T21:12:21.578Z,1670015541.578 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-12-02T21:12:22.032Z,1670015542.032 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-12-02T21:12:22.033Z,1670015542.033 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-12-02T21:12:22.698Z,1670015542.698 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-12-02T21:12:22.698Z,1670015542.698 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-12-02T21:12:22.741Z,1670015542.741 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-12-02T21:12:22.742Z,1670015542.742 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-12-02T21:12:22.956Z,1670015542.956 [CTD_Seabird] Loaded 2022-12-02T21:12:22.957Z,1670015542.957 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-12-02T21:12:22.958Z,1670015542.958 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABC4E0 2022-12-02T21:12:22.958Z,1670015542.958 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926 2022-12-02T21:12:22.978Z,1670015542.978 [PAR_Licor] Loaded 2022-12-02T21:12:22.979Z,1670015542.979 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-12-02T21:12:23.010Z,1670015543.010 [WetLabsBB2FL] Loaded 2022-12-02T21:12:23.010Z,1670015543.010 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-12-02T21:12:23.011Z,1670015543.011 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEC4E0 2022-12-02T21:12:23.011Z,1670015543.011 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927 2022-12-02T21:12:23.012Z,1670015543.012 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-12-02T21:12:23.013Z,1670015543.013 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-12-02T21:12:23.370Z,1670015543.370 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-12-02T21:12:23.372Z,1670015543.372 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-12-02T21:12:23.541Z,1670015543.541 [SBIT](DEBUG): Construct Startup Built In Test. 2022-12-02T21:12:23.550Z,1670015543.550 [SBIT] Loaded 2022-12-02T21:12:23.550Z,1670015543.550 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-12-02T21:12:23.553Z,1670015543.553 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-12-02T21:12:23.567Z,1670015543.567 [IBIT] Loaded 2022-12-02T21:12:23.567Z,1670015543.567 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-12-02T21:12:23.573Z,1670015543.573 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-12-02T21:12:23.678Z,1670015543.678 [CBIT] Loaded 2022-12-02T21:12:23.678Z,1670015543.678 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-12-02T21:12:23.678Z,1670015543.678 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-12-02T21:12:23.685Z,1670015543.685 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-12-02T21:12:23.688Z,1670015543.688 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-12-02T21:12:23.699Z,1670015543.699 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-12-02T21:12:23.700Z,1670015543.700 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCF4E0 2022-12-02T21:12:23.701Z,1670015543.701 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928 2022-12-02T21:12:23.705Z,1670015543.705 [Supervisor](INFO): Main Thread ID is 830 2022-12-02T21:12:23.705Z,1670015543.705 [Supervisor](DEBUG): Running supervisor. 2022-12-02T21:12:23.706Z,1670015543.706 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929 2022-12-02T21:12:23.706Z,1670015543.706 [CommandExec](INFO): Initializing the command executive. 2022-12-02T21:12:23.708Z,1670015543.708 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930 2022-12-02T21:12:23.710Z,1670015543.710 [controlThread ThreadHandler](INFO): Handler Thread ID is 931 2022-12-02T21:12:23.710Z,1670015543.710 [controlThread](DEBUG): Initializing ControlThread 2022-12-02T21:12:23.711Z,1670015543.711 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-12-02T21:12:23.713Z,1670015543.713 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-12-02T21:12:23.713Z,1670015543.713 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-12-02T21:12:23.714Z,1670015543.714 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-12-02T21:12:23.715Z,1670015543.715 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-12-02T21:12:23.716Z,1670015543.716 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-12-02T21:12:23.716Z,1670015543.716 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-12-02T21:12:23.716Z,1670015543.716 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-12-02T21:12:23.717Z,1670015543.717 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-12-02T21:12:23.718Z,1670015543.718 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-12-02T21:12:23.718Z,1670015543.718 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-12-02T21:12:23.724Z,1670015543.724 [SBIT](INFO): Initialize SBIT Component. 2022-12-02T21:12:23.725Z,1670015543.725 [SBIT](IMPORTANT): git: 2022-11-28 2022-12-02T21:12:23.725Z,1670015543.725 [SBIT](INFO): git hash: ae900ceef0992f02fc7e77b26b775dfa5c801999 2022-12-02T21:12:23.725Z,1670015543.725 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-12-02T21:12:23.726Z,1670015543.726 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2022-12-02T21:12:23.727Z,1670015543.727 [SBIT](INFO): Beginning SBIT in 61.000000 seconds. 2022-12-02T21:12:23.728Z,1670015543.728 [IBIT](INFO): Initialize IBIT Component. 2022-12-02T21:12:23.729Z,1670015543.729 [CBIT](DEBUG): Initialize CBIT Component. 2022-12-02T21:12:23.730Z,1670015543.730 [logger ThreadHandler](INFO): Handler Thread ID is 932 2022-12-02T21:12:23.740Z,1670015543.740 [CBIT](DEBUG): Initialized mux pins. 2022-12-02T21:12:23.740Z,1670015543.740 [CBIT](DEBUG): Initializing the watchdog timer. 2022-12-02T21:12:23.748Z,1670015543.748 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933 2022-12-02T21:12:23.760Z,1670015543.760 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934 2022-12-02T21:12:23.761Z,1670015543.761 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-12-02T21:12:23.764Z,1670015543.764 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-12-02T21:12:23.764Z,1670015543.764 [CBIT](DEBUG): Initializing heartbeat. 2022-12-02T21:12:23.772Z,1670015543.772 [Onboard ThreadHandler](INFO): Handler Thread ID is 935 2022-12-02T21:12:23.801Z,1670015543.801 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936 2022-12-02T21:12:23.809Z,1670015543.809 [DAT ThreadHandler](INFO): Handler Thread ID is 937 2022-12-02T21:12:23.810Z,1670015543.810 [DAT](INFO): Powering up 2022-12-02T21:12:23.810Z,1670015543.810 [DAT](DEBUG): Initializing DAT. 2022-12-02T21:12:23.813Z,1670015543.813 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939 2022-12-02T21:12:23.814Z,1670015543.814 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-12-02T21:12:23.817Z,1670015543.817 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941 2022-12-02T21:12:23.820Z,1670015543.820 [WetLabsBB2FL](INFO): Powering up 2022-12-02T21:12:23.822Z,1670015543.822 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2022-12-02T21:12:23.829Z,1670015543.829 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-12-02T21:12:23.829Z,1670015543.829 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-12-02T21:12:23.829Z,1670015543.829 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-12-02T21:12:23.829Z,1670015543.829 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-12-02T21:12:23.830Z,1670015543.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-12-02T21:12:23.830Z,1670015543.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-12-02T21:12:23.830Z,1670015543.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-12-02T21:12:23.830Z,1670015543.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-12-02T21:12:23.836Z,1670015543.836 [CBIT](DEBUG): Deactivating GF circuits. 2022-12-02T21:12:23.836Z,1670015543.836 [CBIT](DEBUG): Deactivating emergency mode. 2022-12-02T21:12:23.872Z,1670015543.872 [CBIT](DEBUG): Backplane powered. 2022-12-02T21:12:23.873Z,1670015543.873 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-12-02T21:12:23.906Z,1670015543.906 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-12-02T21:12:23.946Z,1670015543.946 [MissionManager](DEBUG): 2022-12-02T21:12:23.947Z,1670015543.947 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-12-02T21:12:24.034Z,1670015544.034 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-12-02T21:12:24.052Z,1670015544.052 [Default:A.Wait](DEBUG): Construct Wait. 2022-12-02T21:12:24.054Z,1670015544.054 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-12-02T21:12:24.074Z,1670015544.074 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-12-02T21:12:24.101Z,1670015544.101 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-12-02T21:12:24.106Z,1670015544.106 [Default:E.Execute](DEBUG): Construct Execute. 2022-12-02T21:12:24.122Z,1670015544.122 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2022-12-02T21:12:24.132Z,1670015544.132 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-12-02T21:12:24.152Z,1670015544.152 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-12-02T21:12:24.204Z,1670015544.204 [Radio_Surface](INFO): Powering up 2022-12-02T21:12:24.305Z,1670015544.305 [Depth_Keller](ERROR): Pressure reading out of range: 1926.223755 decibar 2022-12-02T21:12:24.306Z,1670015544.306 [Power24vConverter](INFO): Powering up. 2022-12-02T21:12:24.314Z,1670015544.314 [DepthRateCalculator](ERROR): Depth measurement is not active 2022-12-02T21:12:24.342Z,1670015544.342 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-12-02T21:12:24.348Z,1670015544.348 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-12-02T21:12:24.349Z,1670015544.349 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-12-02T21:12:24.356Z,1670015544.356 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-12-02T21:12:24.357Z,1670015544.357 [MassServo](DEBUG): Initializing EZServoServo. 2022-12-02T21:12:24.364Z,1670015544.364 [MassServo](DEBUG): Initializing MassServo. 2022-12-02T21:12:24.365Z,1670015544.365 [RudderServo](DEBUG): Initializing EZServoServo. 2022-12-02T21:12:24.372Z,1670015544.372 [RudderServo](DEBUG): Initializing RudderServo. 2022-12-02T21:12:24.373Z,1670015544.373 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-12-02T21:12:24.380Z,1670015544.380 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-12-02T21:12:25.304Z,1670015545.304 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-12-02T21:12:25.305Z,1670015545.305 [RudderServo](FAULT): Rudder failed to initialize 2022-12-02T21:12:25.305Z,1670015545.305 [RudderServo] Communications Fault, FailCount= 1 2022-12-02T21:12:25.305Z,1670015545.305 [RudderServo](ERROR): Communications Fault 2022-12-02T21:12:25.308Z,1670015545.308 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-12-02T21:12:25.513Z,1670015545.513 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-12-02T21:12:25.513Z,1670015545.513 [RudderServo](INFO): Powering down 2022-12-02T21:12:25.928Z,1670015545.928 [WetLabsBB2FL](INFO): Powering down 2022-12-02T21:12:26.180Z,1670015546.180 [RudderServo](DEBUG): Initializing EZServoServo. 2022-12-02T21:12:26.301Z,1670015546.301 [RudderServo](DEBUG): Initializing RudderServo. 2022-12-02T21:12:26.305Z,1670015546.305 [CBIT](INFO): Clearing failed state for component RudderServo 2022-12-02T21:12:26.305Z,1670015546.305 [RudderServo] No Fault, FailCount= 1 2022-12-02T21:12:30.057Z,1670015550.057 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-12-02T21:12:33.905Z,1670015553.905 [CBIT](CRITICAL): Environmental Failure. Press:14.937733 PSI. Humidity:30%. Temp:23 C. ABORTING MISSION 2022-12-02T21:12:33.905Z,1670015553.905 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals 2022-12-02T21:12:34.307Z,1670015554.307 [CommandExec](FAULT): Scheduling is paused 2022-12-02T21:12:34.307Z,1670015554.307 [CBIT](INFO): Critical error at 20221202T211233 2022-12-02T21:12:35.904Z,1670015555.904 [DAT](INFO): DAT read: 2022-12-02T21:12:35.906Z,1670015555.906 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2022-12-02T21:12:37.669Z,1670015557.669 [DAT](INFO): DAT read: MF Frequency Band 2022-12-02T21:12:37.670Z,1670015557.670 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2022-12-02T21:12:37.670Z,1670015557.670 [DAT](INFO): DAT read: Dec 2 2022 21:35:59 2022-12-02T21:12:38.677Z,1670015558.677 [DAT](INFO): DAT read: Features enabled [Bearing] 2022-12-02T21:12:38.678Z,1670015558.678 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2022-12-02T21:12:38.679Z,1670015558.679 [DAT](INFO): commRate: 800 2022-12-02T21:12:40.744Z,1670015560.744 [DAT](INFO): entering command mode 2022-12-02T21:12:40.944Z,1670015560.944 [DAT](INFO): DAT read: 2022-12-02T21:12:40.945Z,1670015560.945 [DAT](INFO): DAT read: user:1> 2022-12-02T21:12:40.945Z,1670015560.945 [DAT](INFO): setting verbose to 3 2022-12-02T21:12:41.197Z,1670015561.197 [DAT](INFO): DAT read: user:1> 2022-12-02T21:12:41.198Z,1670015561.198 [DAT](INFO): DAT read: Verbose | 3 2022-12-02T21:12:41.198Z,1670015561.198 [DAT](INFO): set verbose to 3 2022-12-02T21:12:41.198Z,1670015561.198 [DAT](INFO): setting DatVerbose to 27440 2022-12-02T21:12:41.448Z,1670015561.448 [DAT](INFO): DAT read: user:2> 2022-12-02T21:12:41.449Z,1670015561.449 [DAT](INFO): DAT read: DatVerbose | 27440 2022-12-02T21:12:41.450Z,1670015561.450 [DAT](INFO): set DatVerbose to 27440 2022-12-02T21:12:41.450Z,1670015561.450 [DAT](INFO): setting transmit power to 8 2022-12-02T21:12:41.701Z,1670015561.701 [DAT](INFO): DAT read: user:3> 2022-12-02T21:12:41.701Z,1670015561.701 [DAT](INFO): DAT read: TxPower | 8 (Max) 2022-12-02T21:12:41.702Z,1670015561.702 [DAT](INFO): set transmit power to 8 2022-12-02T21:12:41.702Z,1670015561.702 [DAT](INFO): setting local address to 5 2022-12-02T21:12:41.952Z,1670015561.952 [DAT](INFO): DAT read: user:4> 2022-12-02T21:12:41.953Z,1670015561.953 [DAT](INFO): DAT read: LocalAddr | 5 2022-12-02T21:12:41.954Z,1670015561.954 [DAT](INFO): set local address to 5 2022-12-02T21:12:41.955Z,1670015561.955 [DAT](INFO): Setting time to: 21:12:41 And date to:12/2/2022 2022-12-02T21:12:42.205Z,1670015562.205 [DAT](INFO): DAT read: user:5> 2022-12-02T21:12:42.205Z,1670015562.205 [DAT](INFO): DAT read: Fri Dec 2, 2022 21:12:41 2022-12-02T21:12:42.206Z,1670015562.206 [DAT](INFO): Local DAT time set to Fri Dec 2, 2022 21:12:41 2022-12-02T21:12:51.618Z,1670015571.618 [NAL9602](INFO): Powering up NAL9602 2022-12-02T21:13:02.531Z,1670015582.531 [NAL9602](INFO): NAL9602 initialized 2022-12-02T21:13:25.179Z,1670015605.179 [SBIT](IMPORTANT): Beginning Startup BIT 2022-12-02T21:13:25.183Z,1670015605.183 [CBIT](IMPORTANT): Beginning ground fault scan 2022-12-02T21:13:36.097Z,1670015616.097 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.017292 CHAN A1 (24V): -0.007931 CHAN A2 (12V): -0.004831 CHAN A3 (5V): -0.003013 CHAN B0 (3.3V): -0.001249 CHAN B1 (3.15aV): -0.001035 CHAN B2 (3.15bV): -0.001026 CHAN B3 (GND): 0.000541 OPEN: 0.004525 Full Scale: +/- 1 mA 2022-12-02T21:13:38.082Z,1670015618.082 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:13:51.413Z,1670015631.413 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:14:04.745Z,1670015644.745 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:14:18.081Z,1670015658.081 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:14:19.332Z,1670015659.332 [SBIT](IMPORTANT): SBIT PASSED 2022-12-02T21:14:19.333Z,1670015659.333 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-12-02T21:14:19.333Z,1670015659.333 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2022-12-02T21:14:19.334Z,1670015659.334 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool; 2022-12-02T21:14:19.334Z,1670015659.334 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool; 2022-12-02T21:14:19.334Z,1670015659.334 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit; 2022-12-02T21:14:19.334Z,1670015659.334 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2022-12-02T21:14:19.334Z,1670015659.334 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2022-12-02T21:14:19.334Z,1670015659.334 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree; 2022-12-02T21:14:19.334Z,1670015659.334 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=30 ampere_hour; 2022-12-02T21:14:19.334Z,1670015659.334 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2022-12-02T21:14:19.335Z,1670015659.335 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=324.282117 cubic_centimeter; 2022-12-02T21:14:19.335Z,1670015659.335 [SBIT](IMPORTANT): VerticalControl.massDefault=10.355450 millimeter; 2022-12-02T21:14:19.726Z,1670015659.726 [MissionManager](IMPORTANT): Started mission Startup 2022-12-02T21:14:19.726Z,1670015659.726 [Startup] Running Loop=1 2022-12-02T21:14:19.727Z,1670015659.727 [Startup](DEBUG): Aggregate::initialize Startup 2022-12-02T21:14:19.727Z,1670015659.727 [Startup:A.GoToSurface] Running Loop=1 2022-12-02T21:14:19.727Z,1670015659.727 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-12-02T21:14:19.727Z,1670015659.727 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-12-02T21:14:19.728Z,1670015659.728 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-12-02T21:14:19.728Z,1670015659.728 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-12-02T21:14:19.729Z,1670015659.729 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-12-02T21:14:19.729Z,1670015659.729 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-12-02T21:14:19.730Z,1670015659.730 [Startup:StartupSatComms] Running Loop=1 2022-12-02T21:14:19.730Z,1670015659.730 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-12-02T21:14:19.731Z,1670015659.731 [Startup:StartupSatComms:A] Running Loop=1 2022-12-02T21:14:20.127Z,1670015660.127 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-12-02T21:14:31.467Z,1670015671.467 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:14:44.799Z,1670015684.799 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:14:51.599Z,1670015691.599 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005049 2022-12-02T21:38:20.002Z,1670017100.002 [BPC1](ERROR): Failed to receive data from all battery packs. 2022-12-02T21:38:20.002Z,1670017100.002 [BPC1] Communications Fault, FailCount= 1 2022-12-02T21:38:20.002Z,1670017100.002 [BPC1](ERROR): Communications Fault 2022-12-02T21:38:20.010Z,1670017100.010 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-12-02T21:38:20.040Z,1670017100.040 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-12-02T21:38:20.040Z,1670017100.040 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-12-02T21:38:20.042Z,1670017100.042 [Startup:StartupSatComms:A](INFO): Timed out from 2022-12-02T21:14:19.7Z 2022-12-02T21:38:20.043Z,1670017100.043 [Startup:StartupSatComms:A] Stopped 2022-12-02T21:38:20.043Z,1670017100.043 [Startup:StartupSatComms:B] Running Loop=1 2022-12-02T21:38:20.078Z,1670017100.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-12-02T21:38:20.078Z,1670017100.078 [CBIT](ERROR): Communications Fault in component: BPC1 2022-12-02T21:38:20.170Z,1670017100.170 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=1408.576294 2022-12-02T21:38:20.427Z,1670017100.427 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-12-02T21:38:20.461Z,1670017100.461 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-12-02T21:38:20.461Z,1670017100.461 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-12-02T21:38:20.818Z,1670017100.818 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-12-02T21:38:20.818Z,1670017100.818 [NAL9602] Data Fault, FailCount= 1 2022-12-02T21:38:20.818Z,1670017100.818 [NAL9602](ERROR): Data Fault 2022-12-02T21:38:20.839Z,1670017100.839 [CBIT](ERROR): Data Fault in component: NAL9602 2022-12-02T21:38:21.224Z,1670017101.224 [NAL9602](INFO): Powering down 2022-12-02T21:38:22.065Z,1670017102.065 [CBIT](INFO): Clearing failed state for component NAL9602 2022-12-02T21:38:22.065Z,1670017102.065 [NAL9602] No Fault, FailCount= 1 2022-12-02T21:38:51.541Z,1670017131.541 [NAL9602](INFO): Powering up NAL9602 2022-12-02T21:39:02.430Z,1670017142.430 [NAL9602](INFO): NAL9602 initialized 2022-12-02T21:39:02.490Z,1670017142.490 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003604 2022-12-02T21:39:09.782Z,1670017149.782 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20221201T195818/Courier0079.lzma 2022-12-02T21:39:10.783Z,1670017150.783 [DataOverHttps](INFO): Moved sent file to Logs/20221201T195818/Courier0079.lzma.bak 2022-12-02T21:39:10.783Z,1670017150.783 [DataOverHttps](INFO): SBD MOMSN=17377283 2022-12-02T21:39:20.231Z,1670017160.231 [Startup:StartupSatComms:B](INFO): Timed out from 2022-12-02T21:38:20.0Z 2022-12-02T21:39:20.231Z,1670017160.231 [Startup:StartupSatComms:B] Stopped 2022-12-02T21:39:20.232Z,1670017160.232 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-12-02T21:39:20.232Z,1670017160.232 [Startup:StartupSatComms] Stopped 2022-12-02T21:39:20.232Z,1670017160.232 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-12-02T21:39:20.233Z,1670017160.233 [Startup](INFO): Completed Startup 2022-12-02T21:39:20.233Z,1670017160.233 [MissionManager](INFO): Startup is completed. 2022-12-02T21:39:20.233Z,1670017160.233 [MissionManager](INFO): Uninitializing Mission Startup 2022-12-02T21:39:20.233Z,1670017160.233 [Startup] Stopped 2022-12-02T21:39:20.233Z,1670017160.233 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-12-02T21:39:20.233Z,1670017160.233 [Startup:A.GoToSurface] Stopped 2022-12-02T21:39:20.233Z,1670017160.233 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-12-02T21:39:20.621Z,1670017160.621 [MissionManager](IMPORTANT): Started mission Default 2022-12-02T21:39:20.622Z,1670017160.622 [Default] Running Loop=1 2022-12-02T21:39:20.622Z,1670017160.622 [Default](DEBUG): Aggregate::initialize Default 2022-12-02T21:39:20.622Z,1670017160.622 [Default:B.GoToSurface] Running Loop=1 2022-12-02T21:39:20.622Z,1670017160.622 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-12-02T21:39:20.622Z,1670017160.622 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-12-02T21:39:20.623Z,1670017160.623 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-12-02T21:39:20.623Z,1670017160.623 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-12-02T21:39:20.623Z,1670017160.623 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-12-02T21:39:20.623Z,1670017160.623 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-12-02T21:39:20.624Z,1670017160.624 [Default:A.Wait] Running Loop=1 2022-12-02T21:39:20.624Z,1670017160.624 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-12-02T21:39:28.144Z,1670017168.144 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20221202T211212/Courier0000.lzma 2022-12-02T21:39:29.146Z,1670017169.146 [DataOverHttps](INFO): Moved sent file to Logs/20221202T211212/Courier0000.lzma.bak 2022-12-02T21:39:29.146Z,1670017169.146 [DataOverHttps](INFO): SBD MOMSN=17377285 2022-12-02T21:39:33.950Z,1670017173.950 [Default:A.Wait](INFO): Done Waiting. 2022-12-02T21:39:33.951Z,1670017173.951 [Default:A.Wait] Stopped 2022-12-02T21:39:33.951Z,1670017173.951 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-12-02T21:39:34.372Z,1670017174.372 [Default:CheckIn] Running Loop=1 2022-12-02T21:39:34.372Z,1670017174.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-12-02T21:39:34.372Z,1670017174.372 [Default:CheckIn:Read_GPS] Running Loop=1 2022-12-02T21:39:34.779Z,1670017174.779 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-12-02T21:41:21.009Z,1670017281.009 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2022-12-02T21:41:21.009Z,1670017281.009 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-12-02T21:41:21.020Z,1670017281.020 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-12-02T21:41:21.442Z,1670017281.442 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-12-02T21:41:21.442Z,1670017281.442 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2022-12-02T21:44:22.021Z,1670017462.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2022-12-02T21:44:22.021Z,1670017462.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-12-02T21:44:22.031Z,1670017462.031 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-12-02T21:44:22.451Z,1670017462.451 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-12-02T21:44:22.451Z,1670017462.451 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2022-12-02T21:44:34.559Z,1670017474.559 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-12-02T21:39:34.4Z 2022-12-02T21:44:34.559Z,1670017474.559 [Default:CheckIn:Read_GPS] Stopped 2022-12-02T21:44:34.559Z,1670017474.559 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-12-02T21:44:34.947Z,1670017474.947 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-12-02T21:44:41.312Z,1670017481.312 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221202T211212/Courier0004.lzma 2022-12-02T21:44:42.314Z,1670017482.314 [DataOverHttps](INFO): Moved sent file to Logs/20221202T211212/Courier0004.lzma.bak 2022-12-02T21:44:42.315Z,1670017482.315 [DataOverHttps](INFO): SBD MOMSN=17377317 2022-12-02T21:44:57.760Z,1670017497.760 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221202T213323/Courier0000.lzma 2022-12-02T21:44:58.763Z,1670017498.763 [DataOverHttps](INFO): Moved sent file to Logs/20221202T213323/Courier0000.lzma.bak 2022-12-02T21:44:58.763Z,1670017498.763 [DataOverHttps](INFO): SBD MOMSN=17377322 2022-12-02T21:45:15.530Z,1670017515.530 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20221201T195818/Express0080.lzma 2022-12-02T21:45:16.531Z,1670017516.531 [DataOverHttps](INFO): Moved sent file to Logs/20221201T195818/Express0080.lzma.bak 2022-12-02T21:45:16.531Z,1670017516.531 [DataOverHttps](INFO): SBD MOMSN=17377327 2022-12-02T21:45:33.394Z,1670017533.394 [DataOverHttps](INFO): Sending 1111 bytes from file Logs/20221202T211212/Express0001.lzma 2022-12-02T21:45:34.394Z,1670017534.394 [DataOverHttps](INFO): Moved sent file to Logs/20221202T211212/Express0001.lzma.bak 2022-12-02T21:45:34.395Z,1670017534.395 [DataOverHttps](INFO): SBD MOMSN=17377332 2022-12-02T21:45:51.236Z,1670017551.236 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20221202T211212/Express0005.lzma 2022-12-02T21:45:52.238Z,1670017552.238 [DataOverHttps](INFO): Moved sent file to Logs/20221202T211212/Express0005.lzma.bak 2022-12-02T21:45:52.239Z,1670017552.239 [DataOverHttps](INFO): SBD MOMSN=17377360 2022-12-02T21:46:09.264Z,1670017569.264 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20221202T213323/Express0001.lzma 2022-12-02T21:46:10.266Z,1670017570.266 [DataOverHttps](INFO): Moved sent file to Logs/20221202T213323/Express0001.lzma.bak 2022-12-02T21:46:10.266Z,1670017570.266 [DataOverHttps](INFO): SBD MOMSN=17377366 2022-12-02T21:46:12.710Z,1670017572.710 [Default:CheckIn:Read_Iridium] Stopped 2022-12-02T21:46:12.710Z,1670017572.710 [Default:CheckIn:C.Wait] Running Loop=1 2022-12-02T21:46:12.710Z,1670017572.710 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-12-02T21:47:23.010Z,1670017643.010 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2022-12-02T21:47:23.010Z,1670017643.010 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-12-02T21:47:23.040Z,1670017643.040 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-12-02T21:47:23.410Z,1670017643.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-12-02T21:47:23.410Z,1670017643.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2022-12-02T21:48:21.204Z,1670017701.204 [CBIT](INFO): Clearing failed state for component BPC1 2022-12-02T21:48:21.204Z,1670017701.204 [BPC1] No Fault, FailCount= 1 2022-12-02T21:49:04.387Z,1670017744.387 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-12-02T21:49:04.387Z,1670017744.387 [NAL9602] Data Fault, FailCount= 2 2022-12-02T21:49:04.387Z,1670017744.387 [NAL9602](ERROR): Data Fault 2022-12-02T21:49:04.424Z,1670017744.424 [CBIT](ERROR): Data Fault in component: NAL9602 2022-12-02T21:49:04.794Z,1670017744.794 [NAL9602](INFO): Powering down 2022-12-02T21:49:05.620Z,1670017745.620 [CBIT](INFO): Clearing failed state for component NAL9602 2022-12-02T21:49:05.621Z,1670017745.621 [NAL9602] No Fault, FailCount= 2 2022-12-02T21:49:35.094Z,1670017775.094 [NAL9602](INFO): Powering up NAL9602 2022-12-02T21:49:46.002Z,1670017786.002 [NAL9602](INFO): NAL9602 initialized 2022-12-02T21:50:24.001Z,1670017824.001 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2022-12-02T21:50:24.001Z,1670017824.001 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-12-02T21:50:24.012Z,1670017824.012 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-12-02T21:50:24.415Z,1670017824.415 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-12-02T21:50:24.415Z,1670017824.415 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2022-12-02T21:50:49.457Z,1670017849.457 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:51:03.197Z,1670017863.197 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:51:13.327Z,1670017873.327 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-12-02T21:51:13.327Z,1670017873.327 [Default:CheckIn:C.Wait] Stopped 2022-12-02T21:51:13.328Z,1670017873.328 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-12-02T21:51:13.328Z,1670017873.328 [Default:CheckIn:D] Running Loop=1 2022-12-02T21:51:13.734Z,1670017873.734 [Default:CheckIn:D] Stopped 2022-12-02T21:51:13.734Z,1670017873.734 [Default:CheckIn:E] Running Loop=1 2022-12-02T21:51:14.110Z,1670017874.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.885201 min 2022-12-02T21:51:14.110Z,1670017874.110 [Default:CheckIn:E] Stopped 2022-12-02T21:51:14.110Z,1670017874.110 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-12-02T21:51:14.111Z,1670017874.111 [Default:CheckIn] Stopped 2022-12-02T21:51:14.111Z,1670017874.111 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-12-02T21:51:14.111Z,1670017874.111 [Default:CheckIn](INFO): Running loop #2 2022-12-02T21:51:14.111Z,1670017874.111 [Default:CheckIn] Running Loop=2 2022-12-02T21:51:14.111Z,1670017874.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-12-02T21:51:14.111Z,1670017874.111 [Default:CheckIn:Read_GPS] Running Loop=1 2022-12-02T21:51:16.545Z,1670017876.545 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:51:30.267Z,1670017890.267 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:51:43.603Z,1670017903.603 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:51:56.935Z,1670017916.935 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:52:00.602Z,1670017920.602 [CBIT](FAULT): WATER ALARM BOW. 2022-12-02T21:52:00.603Z,1670017920.603 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1 2022-12-02T21:52:01.419Z,1670017921.419 [CBIT](FAULT): WATER ALARM BOW. 2022-12-02T21:52:01.425Z,1670017921.425 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1 2022-12-02T21:52:01.838Z,1670017921.838 [CBIT](FAULT): WATER ALARM BOW. 2022-12-02T21:52:01.840Z,1670017921.840 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2 2022-12-02T21:52:02.214Z,1670017922.214 [CBIT](FAULT): WATER ALARM BOW. 2022-12-02T21:52:02.215Z,1670017922.215 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3 2022-12-02T21:52:02.618Z,1670017922.618 [CBIT](FAULT): WATER ALARM BOW. 2022-12-02T21:52:02.651Z,1670017922.651 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED 2022-12-02T21:52:02.651Z,1670017922.651 [CBIT](IMPORTANT): No ESP to power off. 2022-12-02T21:52:03.018Z,1670017923.018 [CBIT](INFO): Critical error at 20221202T215202 2022-12-02T21:52:10.263Z,1670017930.263 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:52:23.186Z,1670017943.186 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:52:36.521Z,1670017956.521 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:52:49.503Z,1670017969.503 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:53:02.771Z,1670017982.771 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:53:16.103Z,1670017996.103 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-12-02T21:53:24.999Z,1670018004.999 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2022-12-02T21:53:24.999Z,1670018004.999 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-12-02T21:53:25.009Z,1670018005.009 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-12-02T21:53:25.417Z,1670018005.417 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-12-02T21:53:25.417Z,1670018005.417 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2022-12-02T21:54:39.306Z,1670018079.306 [CommandExec]