2023-02-16T18:59:33.630Z,1676573973.630 [Supervisor](DEBUG): Initializing supervisor. 2023-02-16T18:59:33.635Z,1676573973.635 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-02-16T18:59:33.636Z,1676573973.636 [SyncHandler](INFO): Protected caller Thread ID is 9012 2023-02-16T18:59:33.636Z,1676573973.636 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-02-16T18:59:33.637Z,1676573973.637 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-02-16T18:59:33.638Z,1676573973.638 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9013 2023-02-16T18:59:33.642Z,1676573973.642 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-02-16T18:59:33.659Z,1676573973.659 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-02-16T18:59:33.660Z,1676573973.660 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-02-16T18:59:33.661Z,1676573973.661 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9015 2023-02-16T18:59:33.665Z,1676573973.665 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-02-16T18:59:33.666Z,1676573973.666 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-02-16T18:59:33.666Z,1676573973.666 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9016 2023-02-16T18:59:33.668Z,1676573973.668 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-02-16T18:59:33.669Z,1676573973.669 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-02-16T18:59:33.670Z,1676573973.670 [logger ThreadHandler](INFO): Protected caller Thread ID is 9017 2023-02-16T18:59:33.673Z,1676573973.673 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-02-16T18:59:33.674Z,1676573973.674 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-02-16T18:59:33.675Z,1676573973.675 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-02-16T18:59:34.207Z,1676573974.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-02-16T18:59:34.208Z,1676573974.208 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-02-16T18:59:34.413Z,1676573974.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-02-16T18:59:34.413Z,1676573974.413 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-02-16T18:59:34.493Z,1676573974.493 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-02-16T18:59:34.705Z,1676573974.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-02-16T18:59:34.706Z,1676573974.706 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-02-16T18:59:34.785Z,1676573974.785 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-02-16T18:59:34.880Z,1676573974.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-02-16T18:59:34.880Z,1676573974.880 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-02-16T18:59:35.206Z,1676573975.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-02-16T18:59:35.207Z,1676573975.207 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-02-16T18:59:35.337Z,1676573975.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-02-16T18:59:35.338Z,1676573975.338 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-02-16T18:59:35.810Z,1676573975.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-02-16T18:59:35.811Z,1676573975.811 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-02-16T18:59:35.989Z,1676573975.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-02-16T18:59:35.989Z,1676573975.989 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-02-16T18:59:36.393Z,1676573976.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-02-16T18:59:36.393Z,1676573976.393 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-02-16T18:59:37.643Z,1676573977.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-02-16T18:59:37.644Z,1676573977.644 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-02-16T18:59:37.754Z,1676573977.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-02-16T18:59:37.754Z,1676573977.754 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-02-16T18:59:38.481Z,1676573978.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-02-16T18:59:38.481Z,1676573978.481 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-02-16T18:59:38.803Z,1676573978.803 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-02-16T18:59:38.804Z,1676573978.804 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-02-16T18:59:39.018Z,1676573979.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-02-16T18:59:39.020Z,1676573979.020 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2023-02-16T18:59:39.021Z,1676573979.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2023-02-16T18:59:39.203Z,1676573979.203 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2023-02-16T18:59:39.286Z,1676573979.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2023-02-16T18:59:39.417Z,1676573979.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2023-02-16T18:59:39.502Z,1676573979.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2023-02-16T18:59:39.763Z,1676573979.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-02-16T18:59:39.764Z,1676573979.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2023-02-16T18:59:39.866Z,1676573979.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2023-02-16T18:59:39.975Z,1676573979.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2023-02-16T18:59:40.085Z,1676573980.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2023-02-16T18:59:40.224Z,1676573980.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2023-02-16T18:59:40.322Z,1676573980.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2023-02-16T18:59:40.415Z,1676573980.415 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2023-02-16T18:59:40.416Z,1676573980.416 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-02-16T18:59:40.427Z,1676573980.427 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-02-16T18:59:40.441Z,1676573980.441 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-02-16T18:59:40.441Z,1676573980.441 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-02-16T18:59:40.562Z,1676573980.562 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-02-16T18:59:40.562Z,1676573980.562 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-02-16T18:59:40.623Z,1676573980.623 [VerticalControl](DEBUG): Construct VerticalControl. 2023-02-16T18:59:40.682Z,1676573980.682 [VerticalControl] Loaded 2023-02-16T18:59:40.682Z,1676573980.682 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-02-16T18:59:40.685Z,1676573980.685 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-02-16T18:59:40.726Z,1676573980.726 [HorizontalControl] Loaded 2023-02-16T18:59:40.726Z,1676573980.726 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-02-16T18:59:40.728Z,1676573980.728 [SpeedControl](DEBUG): Construct SpeedControl. 2023-02-16T18:59:40.731Z,1676573980.731 [SpeedControl] Loaded 2023-02-16T18:59:40.731Z,1676573980.731 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-02-16T18:59:40.734Z,1676573980.734 [LoopControl](DEBUG): Construct LoopControl. 2023-02-16T18:59:40.734Z,1676573980.734 [LoopControl] Loaded 2023-02-16T18:59:40.735Z,1676573980.735 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-02-16T18:59:40.735Z,1676573980.735 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-02-16T18:59:40.736Z,1676573980.736 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-02-16T18:59:40.915Z,1676573980.915 [BuoyancyServo] Loaded 2023-02-16T18:59:40.915Z,1676573980.915 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-02-16T18:59:40.935Z,1676573980.935 [ElevatorServo] Loaded 2023-02-16T18:59:40.935Z,1676573980.935 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-02-16T18:59:40.954Z,1676573980.954 [MassServo] Loaded 2023-02-16T18:59:40.955Z,1676573980.955 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-02-16T18:59:40.973Z,1676573980.973 [RudderServo] Loaded 2023-02-16T18:59:40.973Z,1676573980.973 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-02-16T18:59:40.987Z,1676573980.987 [ThrusterHE] Loaded 2023-02-16T18:59:40.988Z,1676573980.988 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-02-16T18:59:40.988Z,1676573980.988 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-02-16T18:59:40.989Z,1676573980.989 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-02-16T18:59:41.053Z,1676573981.053 [DepthRateCalculator] Loaded 2023-02-16T18:59:41.054Z,1676573981.054 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-02-16T18:59:41.058Z,1676573981.058 [PitchRateCalculator] Loaded 2023-02-16T18:59:41.059Z,1676573981.059 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-02-16T18:59:41.068Z,1676573981.068 [SpeedCalculator] Loaded 2023-02-16T18:59:41.068Z,1676573981.068 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-02-16T18:59:41.073Z,1676573981.073 [YawRateCalculator] Loaded 2023-02-16T18:59:41.073Z,1676573981.073 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-02-16T18:59:41.091Z,1676573981.091 [ElevatorOffsetCalculator] Loaded 2023-02-16T18:59:41.091Z,1676573981.091 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-02-16T18:59:41.092Z,1676573981.092 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-02-16T18:59:41.092Z,1676573981.092 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-02-16T18:59:41.222Z,1676573981.222 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-02-16T18:59:41.222Z,1676573981.222 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-02-16T18:59:41.236Z,1676573981.236 [NavChart] Loaded 2023-02-16T18:59:41.237Z,1676573981.237 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-02-16T18:59:41.242Z,1676573981.242 [UniversalFixResidualReporter] Loaded 2023-02-16T18:59:41.242Z,1676573981.242 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-02-16T18:59:41.243Z,1676573981.243 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-02-16T18:59:41.243Z,1676573981.243 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-02-16T18:59:43.170Z,1676573983.170 [AHRS_M2] Loaded 2023-02-16T18:59:43.171Z,1676573983.171 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-02-16T18:59:43.297Z,1676573983.297 [BackseatComponent] Loaded 2023-02-16T18:59:43.297Z,1676573983.297 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-02-16T18:59:43.300Z,1676573983.300 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E04E0 2023-02-16T18:59:43.300Z,1676573983.300 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9099 2023-02-16T18:59:43.303Z,1676573983.303 [LcmUniversalReporter] Loaded 2023-02-16T18:59:43.303Z,1676573983.303 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-02-16T18:59:44.225Z,1676573984.225 [BPC1] Loaded 2023-02-16T18:59:44.225Z,1676573984.225 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-02-16T18:59:44.297Z,1676573984.297 [DataOverHttps] Loaded 2023-02-16T18:59:44.297Z,1676573984.297 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-02-16T18:59:44.298Z,1676573984.298 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409104E0 2023-02-16T18:59:44.299Z,1676573984.299 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9100 2023-02-16T18:59:44.319Z,1676573984.319 [Depth_Keller] Loaded 2023-02-16T18:59:44.320Z,1676573984.320 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-02-16T18:59:44.324Z,1676573984.324 [DropWeight] Loaded 2023-02-16T18:59:44.325Z,1676573984.325 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-02-16T18:59:44.343Z,1676573984.343 [MultiRay] Loaded 2023-02-16T18:59:44.344Z,1676573984.344 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2023-02-16T18:59:44.405Z,1676573984.405 [NAL9602] Loaded 2023-02-16T18:59:44.406Z,1676573984.406 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-02-16T18:59:44.436Z,1676573984.436 [Onboard] Loaded 2023-02-16T18:59:44.436Z,1676573984.436 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-02-16T18:59:44.437Z,1676573984.437 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409404E0 2023-02-16T18:59:44.437Z,1676573984.437 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9101 2023-02-16T18:59:44.449Z,1676573984.449 [Power24vConverter] Loaded 2023-02-16T18:59:44.449Z,1676573984.449 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-02-16T18:59:44.463Z,1676573984.463 [Radio_Surface] Loaded 2023-02-16T18:59:44.463Z,1676573984.463 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-02-16T18:59:44.464Z,1676573984.464 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409704E0 2023-02-16T18:59:44.465Z,1676573984.465 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9102 2023-02-16T18:59:44.557Z,1676573984.557 [DAT] Loaded 2023-02-16T18:59:44.557Z,1676573984.557 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-02-16T18:59:44.558Z,1676573984.558 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0 2023-02-16T18:59:44.558Z,1676573984.558 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9103 2023-02-16T18:59:44.559Z,1676573984.559 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-02-16T18:59:44.560Z,1676573984.560 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-02-16T18:59:44.636Z,1676573984.636 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-02-16T18:59:44.636Z,1676573984.636 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-02-16T18:59:44.816Z,1676573984.816 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-02-16T18:59:44.816Z,1676573984.816 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-02-16T18:59:44.854Z,1676573984.854 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-02-16T18:59:44.854Z,1676573984.854 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-02-16T18:59:45.059Z,1676573985.059 [CTD_Seabird] Loaded 2023-02-16T18:59:45.059Z,1676573985.059 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-02-16T18:59:45.060Z,1676573985.060 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ACB4E0 2023-02-16T18:59:45.060Z,1676573985.060 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9104 2023-02-16T18:59:45.093Z,1676573985.093 [ESPComponent] Loaded 2023-02-16T18:59:45.093Z,1676573985.093 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-02-16T18:59:45.113Z,1676573985.113 [PAR_Licor] Loaded 2023-02-16T18:59:45.113Z,1676573985.113 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-02-16T18:59:45.144Z,1676573985.144 [WetLabsBB2FL] Loaded 2023-02-16T18:59:45.144Z,1676573985.144 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-02-16T18:59:45.145Z,1676573985.145 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0 2023-02-16T18:59:45.146Z,1676573985.146 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9105 2023-02-16T18:59:45.146Z,1676573985.146 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-02-16T18:59:45.147Z,1676573985.147 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-02-16T18:59:45.494Z,1676573985.494 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-02-16T18:59:45.494Z,1676573985.494 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-02-16T18:59:45.654Z,1676573985.654 [SBIT](DEBUG): Construct Startup Built In Test. 2023-02-16T18:59:45.664Z,1676573985.664 [SBIT] Loaded 2023-02-16T18:59:45.665Z,1676573985.665 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-02-16T18:59:45.667Z,1676573985.667 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-02-16T18:59:45.680Z,1676573985.680 [IBIT] Loaded 2023-02-16T18:59:45.680Z,1676573985.680 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-02-16T18:59:45.686Z,1676573985.686 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-02-16T18:59:45.790Z,1676573985.790 [CBIT] Loaded 2023-02-16T18:59:45.790Z,1676573985.790 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-02-16T18:59:45.791Z,1676573985.791 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-02-16T18:59:45.797Z,1676573985.797 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-02-16T18:59:45.800Z,1676573985.800 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-02-16T18:59:45.811Z,1676573985.811 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-02-16T18:59:45.812Z,1676573985.812 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BDE4E0 2023-02-16T18:59:45.812Z,1676573985.812 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9106 2023-02-16T18:59:45.816Z,1676573985.816 [Supervisor](INFO): Main Thread ID is 2769 2023-02-16T18:59:45.817Z,1676573985.817 [Supervisor](DEBUG): Running supervisor. 2023-02-16T18:59:45.817Z,1676573985.817 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9107 2023-02-16T18:59:45.818Z,1676573985.818 [CommandExec](INFO): Initializing the command executive. 2023-02-16T18:59:45.819Z,1676573985.819 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9108 2023-02-16T18:59:45.821Z,1676573985.821 [controlThread ThreadHandler](INFO): Handler Thread ID is 9109 2023-02-16T18:59:45.822Z,1676573985.822 [controlThread](DEBUG): Initializing ControlThread 2023-02-16T18:59:45.823Z,1676573985.823 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-02-16T18:59:45.824Z,1676573985.824 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-02-16T18:59:45.825Z,1676573985.825 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-02-16T18:59:45.826Z,1676573985.826 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-02-16T18:59:45.827Z,1676573985.827 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-02-16T18:59:45.827Z,1676573985.827 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-02-16T18:59:45.828Z,1676573985.828 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-02-16T18:59:45.828Z,1676573985.828 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-02-16T18:59:45.828Z,1676573985.828 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-02-16T18:59:45.829Z,1676573985.829 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-02-16T18:59:45.830Z,1676573985.830 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-02-16T18:59:45.836Z,1676573985.836 [SBIT](INFO): Initialize SBIT Component. 2023-02-16T18:59:45.836Z,1676573985.836 [SBIT](IMPORTANT): git: 2023-02-10 2023-02-16T18:59:45.836Z,1676573985.836 [SBIT](INFO): git hash: c406eb0a7e3d06ad2f4805df3abb847da8df30bc 2023-02-16T18:59:45.837Z,1676573985.837 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-02-16T18:59:45.838Z,1676573985.838 [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 2023-02-16T18:59:45.839Z,1676573985.839 [SBIT](INFO): Beginning SBIT in 99.000000 seconds. 2023-02-16T18:59:45.840Z,1676573985.840 [IBIT](INFO): Initialize IBIT Component. 2023-02-16T18:59:45.841Z,1676573985.841 [CBIT](DEBUG): Initialize CBIT Component. 2023-02-16T18:59:45.842Z,1676573985.842 [logger ThreadHandler](INFO): Handler Thread ID is 9110 2023-02-16T18:59:45.855Z,1676573985.855 [CBIT](DEBUG): Initialized mux pins. 2023-02-16T18:59:45.855Z,1676573985.855 [CBIT](DEBUG): Initializing the watchdog timer. 2023-02-16T18:59:45.860Z,1676573985.860 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9111 2023-02-16T18:59:45.868Z,1676573985.868 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9112 2023-02-16T18:59:45.869Z,1676573985.869 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-02-16T18:59:45.876Z,1676573985.876 [Onboard ThreadHandler](INFO): Handler Thread ID is 9113 2023-02-16T18:59:45.880Z,1676573985.880 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-02-16T18:59:45.881Z,1676573985.881 [CBIT](DEBUG): Initializing heartbeat. 2023-02-16T18:59:45.896Z,1676573985.896 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9114 2023-02-16T18:59:45.912Z,1676573985.912 [DAT ThreadHandler](INFO): Handler Thread ID is 9115 2023-02-16T18:59:45.912Z,1676573985.912 [DAT](INFO): Powering up 2023-02-16T18:59:45.913Z,1676573985.913 [DAT](DEBUG): Initializing DAT. 2023-02-16T18:59:45.917Z,1676573985.917 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9117 2023-02-16T18:59:45.917Z,1676573985.917 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-02-16T18:59:45.921Z,1676573985.921 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9119 2023-02-16T18:59:45.924Z,1676573985.924 [WetLabsBB2FL](INFO): Powering up 2023-02-16T18:59:45.925Z,1676573985.925 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9121 2023-02-16T18:59:45.932Z,1676573985.932 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-02-16T18:59:45.933Z,1676573985.933 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-02-16T18:59:45.933Z,1676573985.933 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-02-16T18:59:45.933Z,1676573985.933 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-02-16T18:59:45.933Z,1676573985.933 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-02-16T18:59:45.933Z,1676573985.933 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-02-16T18:59:45.933Z,1676573985.933 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-02-16T18:59:45.934Z,1676573985.934 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-02-16T18:59:45.951Z,1676573985.951 [CBIT](DEBUG): Deactivating GF circuits. 2023-02-16T18:59:45.952Z,1676573985.952 [CBIT](DEBUG): Deactivating emergency mode. 2023-02-16T18:59:45.987Z,1676573985.987 [CBIT](DEBUG): Backplane powered. 2023-02-16T18:59:45.988Z,1676573985.988 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-02-16T18:59:46.008Z,1676573986.008 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-02-16T18:59:46.020Z,1676573986.020 [MissionManager](DEBUG): 2023-02-16T18:59:46.020Z,1676573986.020 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-02-16T18:59:46.112Z,1676573986.112 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-02-16T18:59:46.113Z,1676573986.113 [Default:A.Wait](DEBUG): Construct Wait. 2023-02-16T18:59:46.116Z,1676573986.116 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-02-16T18:59:46.180Z,1676573986.180 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-02-16T18:59:46.182Z,1676573986.182 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-02-16T18:59:46.204Z,1676573986.204 [Default:E.Execute](DEBUG): Construct Execute. 2023-02-16T18:59:46.208Z,1676573986.208 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-02-16T18:59:46.219Z,1676573986.219 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,ESPComponent,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, 2023-02-16T18:59:46.232Z,1676573986.232 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-02-16T18:59:46.299Z,1676573986.299 [Radio_Surface](INFO): Powering up 2023-02-16T18:59:46.305Z,1676573986.305 [MultiRay](INFO): Powering up MultiRay Lights 2023-02-16T18:59:46.348Z,1676573986.348 [Power24vConverter](INFO): Powering up. 2023-02-16T18:59:46.408Z,1676573986.408 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-02-16T18:59:46.416Z,1676573986.416 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-02-16T18:59:46.417Z,1676573986.417 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-02-16T18:59:46.424Z,1676573986.424 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-02-16T18:59:46.425Z,1676573986.425 [MassServo](DEBUG): Initializing EZServoServo. 2023-02-16T18:59:46.432Z,1676573986.432 [MassServo](DEBUG): Initializing MassServo. 2023-02-16T18:59:46.432Z,1676573986.432 [RudderServo](DEBUG): Initializing EZServoServo. 2023-02-16T18:59:46.440Z,1676573986.440 [RudderServo](DEBUG): Initializing RudderServo. 2023-02-16T18:59:46.441Z,1676573986.441 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-02-16T18:59:46.448Z,1676573986.448 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-02-16T18:59:47.388Z,1676573987.388 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2023-02-16T18:59:47.388Z,1676573987.388 [RudderServo](FAULT): Rudder failed to initialize 2023-02-16T18:59:47.389Z,1676573987.389 [RudderServo] Communications Fault, FailCount= 1 2023-02-16T18:59:47.389Z,1676573987.389 [RudderServo](ERROR): Communications Fault 2023-02-16T18:59:47.398Z,1676573987.398 [CBIT](ERROR): Communications Fault in component: RudderServo 2023-02-16T18:59:47.824Z,1676573987.824 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-02-16T18:59:47.825Z,1676573987.825 [RudderServo](INFO): Powering down 2023-02-16T18:59:48.036Z,1676573988.036 [WetLabsBB2FL](INFO): Powering down 2023-02-16T18:59:48.267Z,1676573988.267 [RudderServo](DEBUG): Initializing EZServoServo. 2023-02-16T18:59:48.388Z,1676573988.388 [RudderServo](DEBUG): Initializing RudderServo. 2023-02-16T18:59:48.392Z,1676573988.392 [CBIT](INFO): Clearing failed state for component RudderServo 2023-02-16T18:59:48.392Z,1676573988.392 [RudderServo] No Fault, FailCount= 1 2023-02-16T18:59:52.020Z,1676573992.020 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-02-16T18:59:52.948Z,1676573992.948 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-02-16T19:00:00.782Z,1676574000.782 [DAT](INFO): commRate: 800 2023-02-16T19:00:02.847Z,1676574002.847 [DAT](INFO): entering command mode 2023-02-16T19:00:03.049Z,1676574003.049 [DAT](INFO): setting verbose to 3 2023-02-16T19:00:03.302Z,1676574003.302 [DAT](INFO): set verbose to 3 2023-02-16T19:00:03.302Z,1676574003.302 [DAT](INFO): setting DatVerbose to 27440 2023-02-16T19:00:03.553Z,1676574003.553 [DAT](INFO): set DatVerbose to 27440 2023-02-16T19:00:03.553Z,1676574003.553 [DAT](INFO): setting transmit power to 8 2023-02-16T19:00:03.805Z,1676574003.805 [DAT](INFO): set transmit power to 8 2023-02-16T19:00:03.805Z,1676574003.805 [DAT](INFO): setting local address to 5 2023-02-16T19:00:04.057Z,1676574004.057 [DAT](INFO): set local address to 5 2023-02-16T19:00:04.058Z,1676574004.058 [DAT](INFO): Setting time to: 19:0:4 And date to:2/16/2023 2023-02-16T19:00:04.309Z,1676574004.309 [DAT](INFO): Local DAT time set to Thu Feb 16, 2023 19:00:04 2023-02-16T19:00:14.513Z,1676574014.513 [NAL9602](INFO): Powering up NAL9602 2023-02-16T19:00:25.425Z,1676574025.425 [NAL9602](INFO): NAL9602 initialized 2023-02-16T19:01:02.968Z,1676574062.968 [CommandExec](IMPORTANT): got command failComponent 2023-02-16T19:01:02.968Z,1676574062.968 [CommandExec](IMPORTANT): Failed components: 2023-02-16T19:01:02.968Z,1676574062.968 [CommandExec](IMPORTANT): No failed Components. 2023-02-16T19:01:25.648Z,1676574085.648 [SBIT](IMPORTANT): Beginning Startup BIT 2023-02-16T19:01:25.653Z,1676574085.653 [CBIT](IMPORTANT): Beginning ground fault scan 2023-02-16T19:01:36.549Z,1676574096.549 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006910 CHAN A1 (24V): -0.007834 CHAN A2 (12V): -0.004311 CHAN A3 (5V): -0.002904 CHAN B0 (3.3V): -0.001314 CHAN B1 (3.15aV): -0.000969 CHAN B2 (3.15bV): -0.001943 CHAN B3 (GND): -0.000038 OPEN: 0.004811 Full Scale: +/- 1 mA 2023-02-16T19:02:13.957Z,1676574133.957 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004468 2023-02-16T19:02:14.110Z,1676574134.110 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:02:19.794Z,1676574139.794 [SBIT](IMPORTANT): SBIT PASSED 2023-02-16T19:02:19.794Z,1676574139.794 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-02-16T19:02:19.795Z,1676574139.795 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2023-02-16T19:02:19.796Z,1676574139.796 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool; 2023-02-16T19:02:19.796Z,1676574139.796 [SBIT](IMPORTANT): DAT.verbosity=1 count; 2023-02-16T19:02:19.796Z,1676574139.796 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2023-02-16T19:02:19.796Z,1676574139.796 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute; 2023-02-16T19:02:19.796Z,1676574139.796 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2023-02-16T19:02:19.796Z,1676574139.796 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-02-16T19:02:19.796Z,1676574139.796 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2023-02-16T19:02:19.796Z,1676574139.796 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree; 2023-02-16T19:02:19.797Z,1676574139.797 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=324.282117 cubic_centimeter; 2023-02-16T19:02:19.797Z,1676574139.797 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter; 2023-02-16T19:02:20.189Z,1676574140.189 [MissionManager](IMPORTANT): Started mission Startup 2023-02-16T19:02:20.190Z,1676574140.190 [Startup] Running Loop=1 2023-02-16T19:02:20.190Z,1676574140.190 [Startup](DEBUG): Aggregate::initialize Startup 2023-02-16T19:02:20.190Z,1676574140.190 [Startup:A.GoToSurface] Running Loop=1 2023-02-16T19:02:20.190Z,1676574140.190 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-02-16T19:02:20.191Z,1676574140.191 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-02-16T19:02:20.216Z,1676574140.216 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-02-16T19:02:20.220Z,1676574140.220 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-02-16T19:02:20.220Z,1676574140.220 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-02-16T19:02:20.220Z,1676574140.220 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-02-16T19:02:20.222Z,1676574140.222 [Startup:StartupSatComms] Running Loop=1 2023-02-16T19:02:20.222Z,1676574140.222 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-02-16T19:02:20.222Z,1676574140.222 [Startup:StartupSatComms:A] Running Loop=1 2023-02-16T19:02:20.574Z,1676574140.574 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-02-16T19:02:27.037Z,1676574147.037 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:02:40.758Z,1676574160.758 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:02:46.068Z,1676574166.068 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-02-16T19:02:46.068Z,1676574166.068 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-16T19:02:46.135Z,1676574166.135 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-16T19:02:46.523Z,1676574166.523 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-16T19:02:46.524Z,1676574166.524 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-02-16T19:02:54.090Z,1676574174.090 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:03:07.422Z,1676574187.422 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:03:20.386Z,1676574200.386 [Startup:StartupSatComms:A](INFO): Timed out from 2023-02-16T19:02:20.2Z 2023-02-16T19:03:20.386Z,1676574200.386 [Startup:StartupSatComms:A] Stopped 2023-02-16T19:03:20.386Z,1676574200.386 [Startup:StartupSatComms:B] Running Loop=1 2023-02-16T19:03:20.786Z,1676574200.786 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-02-16T19:03:21.670Z,1676574201.670 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:03:23.768Z,1676574203.768 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230215T200333/Courier0393.lzma 2023-02-16T19:03:24.538Z,1676574204.538 [DataOverHttps](INFO): Moved sent file to Logs/20230215T200333/Courier0393.lzma.bak 2023-02-16T19:03:24.538Z,1676574204.538 [DataOverHttps](INFO): SBD MOMSN=17478572 2023-02-16T19:03:35.002Z,1676574215.002 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:03:40.751Z,1676574220.751 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230216T185933/Courier0000.lzma 2023-02-16T19:03:41.753Z,1676574221.753 [DataOverHttps](INFO): Moved sent file to Logs/20230216T185933/Courier0000.lzma.bak 2023-02-16T19:03:41.753Z,1676574221.753 [DataOverHttps](INFO): SBD MOMSN=17478574 2023-02-16T19:03:48.738Z,1676574228.738 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:03:57.328Z,1676574237.328 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20230215T200333/Express0394.lzma 2023-02-16T19:03:58.329Z,1676574238.329 [DataOverHttps](INFO): Moved sent file to Logs/20230215T200333/Express0394.lzma.bak 2023-02-16T19:03:58.330Z,1676574238.330 [DataOverHttps](INFO): SBD MOMSN=17478577 2023-02-16T19:04:02.473Z,1676574242.473 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:04:14.897Z,1676574254.897 [DataOverHttps](INFO): Sending 1024 bytes from file Logs/20230216T185933/Express0001.lzma 2023-02-16T19:04:15.897Z,1676574255.897 [DataOverHttps](INFO): Moved sent file to Logs/20230216T185933/Express0001.lzma.bak 2023-02-16T19:04:15.897Z,1676574255.897 [DataOverHttps](INFO): SBD MOMSN=17478581 2023-02-16T19:04:16.214Z,1676574256.214 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:04:17.441Z,1676574257.441 [Startup:StartupSatComms:B] Stopped 2023-02-16T19:04:17.441Z,1676574257.441 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-02-16T19:04:17.441Z,1676574257.441 [Startup:StartupSatComms] Stopped 2023-02-16T19:04:17.441Z,1676574257.441 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-02-16T19:04:17.442Z,1676574257.442 [Startup](INFO): Completed Startup 2023-02-16T19:04:17.442Z,1676574257.442 [MissionManager](INFO): Startup is completed. 2023-02-16T19:04:17.442Z,1676574257.442 [MissionManager](INFO): Uninitializing Mission Startup 2023-02-16T19:04:17.442Z,1676574257.442 [Startup] Stopped 2023-02-16T19:04:17.442Z,1676574257.442 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-02-16T19:04:17.442Z,1676574257.442 [Startup:A.GoToSurface] Stopped 2023-02-16T19:04:17.442Z,1676574257.442 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-02-16T19:04:17.841Z,1676574257.841 [MissionManager](IMPORTANT): Started mission Default 2023-02-16T19:04:17.842Z,1676574257.842 [Default] Running Loop=1 2023-02-16T19:04:17.842Z,1676574257.842 [Default](DEBUG): Aggregate::initialize Default 2023-02-16T19:04:17.842Z,1676574257.842 [Default:B.GoToSurface] Running Loop=1 2023-02-16T19:04:17.842Z,1676574257.842 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-02-16T19:04:17.842Z,1676574257.842 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-02-16T19:04:17.843Z,1676574257.843 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-02-16T19:04:17.843Z,1676574257.843 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-02-16T19:04:17.843Z,1676574257.843 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-02-16T19:04:17.844Z,1676574257.844 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-02-16T19:04:17.844Z,1676574257.844 [Default:A.Wait] Running Loop=1 2023-02-16T19:04:17.844Z,1676574257.844 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-02-16T19:04:29.946Z,1676574269.946 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T19:04:31.172Z,1676574271.172 [Default:A.Wait](INFO): Done Waiting. 2023-02-16T19:04:31.172Z,1676574271.172 [Default:A.Wait] Stopped 2023-02-16T19:04:31.172Z,1676574271.172 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-02-16T19:04:31.582Z,1676574271.582 [Default:CheckIn] Running Loop=1 2023-02-16T19:04:31.582Z,1676574271.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-16T19:04:31.582Z,1676574271.582 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-16T19:04:31.981Z,1676574271.981 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-02-16T19:05:20.449Z,1676574320.449 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2023-02-16T19:05:29.338Z,1676574329.338 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-16T19:05:47.205Z,1676574347.205 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-02-16T19:05:47.205Z,1676574347.205 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-16T19:05:47.216Z,1676574347.216 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-16T19:05:47.594Z,1676574347.594 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-16T19:05:47.594Z,1676574347.594 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-02-16T19:06:17.384Z,1676574377.384 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2023-02-16T19:06:17.384Z,1676574377.384 [CommandExec](IMPORTANT): Onboard.Pressure 10.824743 psi 2023-02-16T19:06:17.385Z,1676574377.385 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2023-02-16T19:06:17.385Z,1676574377.385 [CommandExec](IMPORTANT): Onboard.Humidity 9.717983 % 2023-02-16T19:06:24.994Z,1676574384.994 [CommandExec](IMPORTANT): got command get Onboard.Temperature 2023-02-16T19:06:24.994Z,1676574384.994 [CommandExec](FAULT): Incomplete syntax. Try: help get 2023-02-16T19:06:28.964Z,1676574388.964 [CommandExec](IMPORTANT): got command get Onboard.Temperature 2023-02-16T19:06:28.964Z,1676574388.964 [CommandExec](IMPORTANT): Onboard.Temperature 23.494074 degC 2023-02-16T19:06:38.644Z,1676574398.644 [CommandExec](IMPORTANT): got command failComponent 2023-02-16T19:06:38.644Z,1676574398.644 [CommandExec](IMPORTANT): Failed components: 2023-02-16T19:06:38.644Z,1676574398.644 [CommandExec](IMPORTANT): No failed Components. 2023-02-16T19:07:09.531Z,1676574429.531 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5. 2023-02-16T19:07:09.534Z,1676574429.534 [BPC1](INFO): Received data from all battery sticks. 2023-02-16T19:08:10.950Z,1676574490.950 [CommandExec](IMPORTANT): got command restart application 2023-02-16T19:08:11.952Z,1676574491.952 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-02-16T19:08:11.952Z,1676574491.952 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:11.952Z,1676574491.952 [CommandExec](INFO): Uninitializing the command executive. 2023-02-16T19:08:11.953Z,1676574491.953 [CommandExec](INFO): Uninitializing the command scheduler. 2023-02-16T19:08:11.953Z,1676574491.953 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:11.996Z,1676574491.996 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-02-16T19:08:11.996Z,1676574491.996 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-02-16T19:08:11.996Z,1676574491.996 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:11.997Z,1676574491.997 [NavChartDb](INFO): Join timeout helper Thread ID is 9159 2023-02-16T19:08:12.229Z,1676574492.229 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:12.229Z,1676574492.229 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:12.236Z,1676574492.236 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-02-16T19:08:12.236Z,1676574492.236 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:12.237Z,1676574492.237 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9160 2023-02-16T19:08:12.657Z,1676574492.657 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:12.658Z,1676574492.658 [WetLabsBB2FL](INFO): Powering down 2023-02-16T19:08:12.661Z,1676574492.661 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:12.668Z,1676574492.668 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-02-16T19:08:12.668Z,1676574492.668 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:12.668Z,1676574492.668 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9161 2023-02-16T19:08:12.980Z,1676574492.980 [CTD_Seabird](INFO): Powering down 2023-02-16T19:08:12.992Z,1676574492.992 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:12.992Z,1676574492.992 [CTD_Seabird](INFO): Powering down 2023-02-16T19:08:13.005Z,1676574493.005 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:13.012Z,1676574493.012 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-02-16T19:08:13.012Z,1676574493.012 [DAT ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:13.013Z,1676574493.013 [DAT](INFO): Join timeout helper Thread ID is 9162 2023-02-16T19:08:13.216Z,1676574493.216 [DAT](INFO): Powering down 2023-02-16T19:08:13.289Z,1676574493.289 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:13.289Z,1676574493.289 [DAT](INFO): Powering down 2023-02-16T19:08:13.290Z,1676574493.290 [DAT ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:13.312Z,1676574493.312 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-02-16T19:08:13.312Z,1676574493.312 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:13.313Z,1676574493.313 [Radio_Surface](INFO): Join timeout helper Thread ID is 9163 2023-02-16T19:08:13.454Z,1676574493.454 [Radio_Surface](INFO): Powering down 2023-02-16T19:08:13.457Z,1676574493.457 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:13.457Z,1676574493.457 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:13.477Z,1676574493.477 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-02-16T19:08:13.477Z,1676574493.477 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:13.478Z,1676574493.478 [Onboard](INFO): Join timeout helper Thread ID is 9164 2023-02-16T19:08:13.845Z,1676574493.845 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-02-16T19:08:16.280Z,1676574496.280 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:16.280Z,1676574496.280 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:16.294Z,1676574496.294 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-02-16T19:08:16.294Z,1676574496.294 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:16.295Z,1676574496.295 [DataOverHttps](INFO): Join timeout helper Thread ID is 9165 2023-02-16T19:08:16.856Z,1676574496.856 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:16.856Z,1676574496.856 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:16.869Z,1676574496.869 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-02-16T19:08:16.869Z,1676574496.869 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:16.873Z,1676574496.873 [BackseatComponent](INFO): Join timeout helper Thread ID is 9166 2023-02-16T19:08:17.024Z,1676574497.024 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:17.025Z,1676574497.025 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.033Z,1676574497.033 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-02-16T19:08:17.033Z,1676574497.033 [logger ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.033Z,1676574497.033 [logger](INFO): Join timeout helper Thread ID is 9167 2023-02-16T19:08:17.058Z,1676574497.058 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:17.059Z,1676574497.059 [logger ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.061Z,1676574497.061 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-02-16T19:08:17.061Z,1676574497.061 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.061Z,1676574497.061 [CommandLine](INFO): Join timeout helper Thread ID is 9168 2023-02-16T19:08:17.148Z,1676574497.148 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:17.149Z,1676574497.149 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.161Z,1676574497.161 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-02-16T19:08:17.161Z,1676574497.161 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.161Z,1676574497.161 [CommandExec](INFO): Join timeout helper Thread ID is 9169 2023-02-16T19:08:17.163Z,1676574497.163 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-02-16T19:08:17.163Z,1676574497.163 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.163Z,1676574497.163 [controlThread](INFO): Join timeout helper Thread ID is 9170 2023-02-16T19:08:17.360Z,1676574497.360 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T19:08:17.360Z,1676574497.360 [controlThread](DEBUG): Uninitializing ControlThread 2023-02-16T19:08:17.361Z,1676574497.361 [AHRS_M2](INFO): Powering down 2023-02-16T19:08:17.433Z,1676574497.433 [MultiRay](INFO): Powering down. 2023-02-16T19:08:17.434Z,1676574497.434 [NAL9602](INFO): Powering down 2023-02-16T19:08:17.436Z,1676574497.436 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-02-16T19:08:17.437Z,1676574497.437 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-02-16T19:08:17.437Z,1676574497.437 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-02-16T19:08:17.438Z,1676574497.438 [MissionManager](INFO): Uninitializing Mission Default 2023-02-16T19:08:17.438Z,1676574497.438 [Default] Stopped 2023-02-16T19:08:17.438Z,1676574497.438 [Default](DEBUG): Aggregate::uninitialize Default 2023-02-16T19:08:17.438Z,1676574497.438 [Default:B.GoToSurface] Stopped 2023-02-16T19:08:17.438Z,1676574497.438 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-02-16T19:08:17.438Z,1676574497.438 [Default:CheckIn] Stopped 2023-02-16T19:08:17.438Z,1676574497.438 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-16T19:08:17.438Z,1676574497.438 [Default:CheckIn:Read_GPS] Stopped 2023-02-16T19:08:17.441Z,1676574497.441 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-02-16T19:08:17.441Z,1676574497.441 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-02-16T19:08:17.442Z,1676574497.442 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-02-16T19:08:17.442Z,1676574497.442 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-02-16T19:08:17.442Z,1676574497.442 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-02-16T19:08:17.442Z,1676574497.442 [BuoyancyServo](INFO): Powering down 2023-02-16T19:08:17.456Z,1676574497.456 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-02-16T19:08:17.456Z,1676574497.456 [ElevatorServo](INFO): Powering down 2023-02-16T19:08:17.457Z,1676574497.457 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-02-16T19:08:17.457Z,1676574497.457 [MassServo](INFO): Powering down 2023-02-16T19:08:17.458Z,1676574497.458 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-02-16T19:08:17.458Z,1676574497.458 [RudderServo](INFO): Powering down 2023-02-16T19:08:17.459Z,1676574497.459 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-02-16T19:08:17.459Z,1676574497.459 [ThrusterHE](INFO): Powering down 2023-02-16T19:08:17.460Z,1676574497.460 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-02-16T19:08:17.460Z,1676574497.460 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-02-16T19:08:17.461Z,1676574497.461 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-02-16T19:08:17.461Z,1676574497.461 [CBIT](DEBUG): Powering off loads. 2023-02-16T19:08:17.472Z,1676574497.472 [CBIT](DEBUG): Disabling WDT. 2023-02-16T19:08:17.484Z,1676574497.484 [CBIT](DEBUG): Opening all GF detection circuits. 2023-02-16T19:08:17.485Z,1676574497.485 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.539Z,1676574497.539 [DAT ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.544Z,1676574497.544 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.546Z,1676574497.546 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.552Z,1676574497.552 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.595Z,1676574497.595 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.632Z,1676574497.632 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.636Z,1676574497.636 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.668Z,1676574497.668 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-16T19:08:17.729Z,1676574497.729 [logger ThreadHandler](INFO): Thread cancelled.