2024-01-31T23:44:02.824Z,1706744642.824 [Supervisor](DEBUG): Initializing supervisor. 2024-01-31T23:44:02.829Z,1706744642.829 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-01-31T23:44:02.829Z,1706744642.829 [SyncHandler](INFO): Protected caller Thread ID is 837 2024-01-31T23:44:02.830Z,1706744642.830 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-01-31T23:44:02.831Z,1706744642.831 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-01-31T23:44:02.831Z,1706744642.831 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2024-01-31T23:44:02.835Z,1706744642.835 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-01-31T23:44:02.852Z,1706744642.852 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-01-31T23:44:02.853Z,1706744642.853 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-01-31T23:44:02.854Z,1706744642.854 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2024-01-31T23:44:02.858Z,1706744642.858 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-01-31T23:44:02.859Z,1706744642.859 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-01-31T23:44:02.859Z,1706744642.859 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2024-01-31T23:44:02.861Z,1706744642.861 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-01-31T23:44:02.862Z,1706744642.862 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-01-31T23:44:02.862Z,1706744642.862 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2024-01-31T23:44:02.866Z,1706744642.866 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-01-31T23:44:02.866Z,1706744642.866 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-01-31T23:44:02.870Z,1706744642.870 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-01-31T23:44:03.175Z,1706744643.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-01-31T23:44:03.175Z,1706744643.175 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-01-31T23:44:03.255Z,1706744643.255 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-01-31T23:44:03.359Z,1706744643.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-01-31T23:44:03.361Z,1706744643.361 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-01-31T23:44:03.486Z,1706744643.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-01-31T23:44:03.487Z,1706744643.487 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-01-31T23:44:03.827Z,1706744643.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-01-31T23:44:03.828Z,1706744643.828 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-01-31T23:44:03.922Z,1706744643.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-01-31T23:44:03.923Z,1706744643.923 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-01-31T23:44:04.295Z,1706744644.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-01-31T23:44:04.297Z,1706744644.297 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-01-31T23:44:04.498Z,1706744644.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-01-31T23:44:04.499Z,1706744644.499 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-01-31T23:44:04.577Z,1706744644.577 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-01-31T23:44:04.793Z,1706744644.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-01-31T23:44:04.793Z,1706744644.793 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-01-31T23:44:05.002Z,1706744645.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-01-31T23:44:05.003Z,1706744645.003 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-01-31T23:44:05.509Z,1706744645.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-01-31T23:44:05.510Z,1706744645.510 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-01-31T23:44:05.689Z,1706744645.689 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-01-31T23:44:05.690Z,1706744645.690 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-01-31T23:44:05.960Z,1706744645.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-01-31T23:44:05.961Z,1706744645.961 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-01-31T23:44:06.777Z,1706744646.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-01-31T23:44:06.777Z,1706744646.777 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-01-31T23:44:07.272Z,1706744647.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-01-31T23:44:07.274Z,1706744647.274 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2024-01-31T23:44:07.276Z,1706744647.276 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2024-01-31T23:44:07.371Z,1706744647.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2024-01-31T23:44:07.488Z,1706744647.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2024-01-31T23:44:07.571Z,1706744647.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2024-01-31T23:44:07.677Z,1706744647.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2024-01-31T23:44:07.790Z,1706744647.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2024-01-31T23:44:08.049Z,1706744648.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-01-31T23:44:08.050Z,1706744648.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2024-01-31T23:44:08.161Z,1706744648.161 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2024-01-31T23:44:08.253Z,1706744648.253 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2024-01-31T23:44:08.355Z,1706744648.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2024-01-31T23:44:08.483Z,1706744648.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2024-01-31T23:44:08.568Z,1706744648.568 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-01-31T23:44:08.583Z,1706744648.583 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-01-31T23:44:08.712Z,1706744648.712 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-01-31T23:44:08.713Z,1706744648.713 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-01-31T23:44:08.781Z,1706744648.781 [VerticalControl](DEBUG): Construct VerticalControl. 2024-01-31T23:44:08.840Z,1706744648.840 [VerticalControl] Loaded 2024-01-31T23:44:08.841Z,1706744648.841 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-01-31T23:44:08.843Z,1706744648.843 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-01-31T23:44:08.884Z,1706744648.884 [HorizontalControl] Loaded 2024-01-31T23:44:08.884Z,1706744648.884 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-01-31T23:44:08.887Z,1706744648.887 [SpeedControl](DEBUG): Construct SpeedControl. 2024-01-31T23:44:08.890Z,1706744648.890 [SpeedControl] Loaded 2024-01-31T23:44:08.890Z,1706744648.890 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-01-31T23:44:08.893Z,1706744648.893 [LoopControl](DEBUG): Construct LoopControl. 2024-01-31T23:44:08.893Z,1706744648.893 [LoopControl] Loaded 2024-01-31T23:44:08.893Z,1706744648.893 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-01-31T23:44:08.894Z,1706744648.894 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-01-31T23:44:08.894Z,1706744648.894 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-01-31T23:44:08.976Z,1706744648.976 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-01-31T23:44:08.976Z,1706744648.976 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-01-31T23:44:09.738Z,1706744649.738 [AHRS_M2] Loaded 2024-01-31T23:44:09.738Z,1706744649.738 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-01-31T23:44:09.988Z,1706744649.988 [BackseatComponent] Loaded 2024-01-31T23:44:09.988Z,1706744649.988 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-01-31T23:44:09.989Z,1706744649.989 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408844E0 2024-01-31T23:44:09.990Z,1706744649.990 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923 2024-01-31T23:44:09.993Z,1706744649.993 [LcmUniversalReporter] Loaded 2024-01-31T23:44:09.993Z,1706744649.993 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-01-31T23:44:10.805Z,1706744650.805 [BPC1] Loaded 2024-01-31T23:44:10.805Z,1706744650.805 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-01-31T23:44:10.949Z,1706744650.949 [DataOverHttps] Loaded 2024-01-31T23:44:10.950Z,1706744650.950 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-01-31T23:44:10.951Z,1706744650.951 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B44E0 2024-01-31T23:44:10.951Z,1706744650.951 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2024-01-31T23:44:10.971Z,1706744650.971 [Depth_Keller] Loaded 2024-01-31T23:44:10.972Z,1706744650.972 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-01-31T23:44:10.977Z,1706744650.977 [DropWeight] Loaded 2024-01-31T23:44:10.977Z,1706744650.977 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-01-31T23:44:11.039Z,1706744651.039 [NAL9602] Loaded 2024-01-31T23:44:11.039Z,1706744651.039 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-01-31T23:44:11.068Z,1706744651.068 [Onboard] Loaded 2024-01-31T23:44:11.068Z,1706744651.068 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-01-31T23:44:11.069Z,1706744651.069 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E44E0 2024-01-31T23:44:11.070Z,1706744651.070 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2024-01-31T23:44:11.082Z,1706744651.082 [Power24vConverter] Loaded 2024-01-31T23:44:11.082Z,1706744651.082 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-01-31T23:44:11.095Z,1706744651.095 [Radio_Surface] Loaded 2024-01-31T23:44:11.095Z,1706744651.095 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-01-31T23:44:11.096Z,1706744651.096 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409144E0 2024-01-31T23:44:11.097Z,1706744651.097 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2024-01-31T23:44:11.110Z,1706744651.110 [Sonardyne_Nano] Loaded 2024-01-31T23:44:11.110Z,1706744651.110 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-01-31T23:44:11.200Z,1706744651.200 [DAT] Loaded 2024-01-31T23:44:11.201Z,1706744651.201 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-01-31T23:44:11.202Z,1706744651.202 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409444E0 2024-01-31T23:44:11.202Z,1706744651.202 [DAT ThreadHandler](INFO): Protected caller Thread ID is 927 2024-01-31T23:44:11.225Z,1706744651.225 [Waterlinked] Loaded 2024-01-31T23:44:11.225Z,1706744651.225 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2024-01-31T23:44:11.225Z,1706744651.225 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-01-31T23:44:11.226Z,1706744651.226 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-01-31T23:44:11.241Z,1706744651.241 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-01-31T23:44:11.242Z,1706744651.242 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-01-31T23:44:11.284Z,1706744651.284 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-01-31T23:44:11.286Z,1706744651.286 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-01-31T23:44:11.454Z,1706744651.454 [SBIT](DEBUG): Construct Startup Built In Test. 2024-01-31T23:44:11.463Z,1706744651.463 [SBIT] Loaded 2024-01-31T23:44:11.464Z,1706744651.464 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-01-31T23:44:11.467Z,1706744651.467 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-01-31T23:44:11.479Z,1706744651.479 [IBIT] Loaded 2024-01-31T23:44:11.480Z,1706744651.480 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-01-31T23:44:11.485Z,1706744651.485 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-01-31T23:44:11.589Z,1706744651.589 [CBIT] Loaded 2024-01-31T23:44:11.589Z,1706744651.589 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-01-31T23:44:11.589Z,1706744651.589 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-01-31T23:44:11.590Z,1706744651.590 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-01-31T23:44:11.960Z,1706744651.960 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-01-31T23:44:11.961Z,1706744651.961 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-01-31T23:44:12.029Z,1706744652.029 [DepthRateCalculator] Loaded 2024-01-31T23:44:12.030Z,1706744652.030 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-01-31T23:44:12.034Z,1706744652.034 [PitchRateCalculator] Loaded 2024-01-31T23:44:12.035Z,1706744652.035 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-01-31T23:44:12.045Z,1706744652.045 [SpeedCalculator] Loaded 2024-01-31T23:44:12.045Z,1706744652.045 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-01-31T23:44:12.049Z,1706744652.049 [YawRateCalculator] Loaded 2024-01-31T23:44:12.050Z,1706744652.050 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-01-31T23:44:12.069Z,1706744652.069 [ElevatorOffsetCalculator] Loaded 2024-01-31T23:44:12.069Z,1706744652.069 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-01-31T23:44:12.069Z,1706744652.069 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-01-31T23:44:12.070Z,1706744652.070 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-01-31T23:44:12.250Z,1706744652.250 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-01-31T23:44:12.251Z,1706744652.251 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-01-31T23:44:12.386Z,1706744652.386 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-01-31T23:44:12.386Z,1706744652.386 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-01-31T23:44:12.429Z,1706744652.429 [DeadReckonUsingSpeedCalculator] Loaded 2024-01-31T23:44:12.429Z,1706744652.429 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-01-31T23:44:12.472Z,1706744652.472 [DeadReckonWithRespectToSeafloor] Loaded 2024-01-31T23:44:12.472Z,1706744652.472 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2024-01-31T23:44:12.485Z,1706744652.485 [NavChart] Loaded 2024-01-31T23:44:12.486Z,1706744652.486 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-01-31T23:44:12.491Z,1706744652.491 [UniversalFixResidualReporter] Loaded 2024-01-31T23:44:12.491Z,1706744652.491 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-01-31T23:44:12.492Z,1706744652.492 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-01-31T23:44:12.493Z,1706744652.493 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-01-31T23:44:12.650Z,1706744652.650 [BuoyancyServo] Loaded 2024-01-31T23:44:12.650Z,1706744652.650 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-01-31T23:44:12.671Z,1706744652.671 [ElevatorServo] Loaded 2024-01-31T23:44:12.671Z,1706744652.671 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-01-31T23:44:12.691Z,1706744652.691 [MassServo] Loaded 2024-01-31T23:44:12.691Z,1706744652.691 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-01-31T23:44:12.710Z,1706744652.710 [RudderServo] Loaded 2024-01-31T23:44:12.710Z,1706744652.710 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-01-31T23:44:12.725Z,1706744652.725 [ThrusterHE] Loaded 2024-01-31T23:44:12.725Z,1706744652.725 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-01-31T23:44:12.726Z,1706744652.726 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-01-31T23:44:12.726Z,1706744652.726 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-01-31T23:44:12.944Z,1706744652.944 [CTD_Seabird] Loaded 2024-01-31T23:44:12.945Z,1706744652.945 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-01-31T23:44:12.946Z,1706744652.946 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9E4E0 2024-01-31T23:44:12.946Z,1706744652.946 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928 2024-01-31T23:44:12.980Z,1706744652.980 [ESPComponent] Loaded 2024-01-31T23:44:12.980Z,1706744652.980 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-01-31T23:44:13.000Z,1706744653.000 [PAR_Licor] Loaded 2024-01-31T23:44:13.000Z,1706744653.000 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-01-31T23:44:13.041Z,1706744653.041 [WetLabsBB2FL] Loaded 2024-01-31T23:44:13.041Z,1706744653.041 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-01-31T23:44:13.042Z,1706744653.042 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCE4E0 2024-01-31T23:44:13.042Z,1706744653.042 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929 2024-01-31T23:44:13.043Z,1706744653.043 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-01-31T23:44:13.049Z,1706744653.049 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-01-31T23:44:13.052Z,1706744653.052 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-01-31T23:44:13.063Z,1706744653.063 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-01-31T23:44:13.065Z,1706744653.065 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFE4E0 2024-01-31T23:44:13.065Z,1706744653.065 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930 2024-01-31T23:44:13.069Z,1706744653.069 [Supervisor](INFO): Main Thread ID is 828 2024-01-31T23:44:13.069Z,1706744653.069 [Supervisor](DEBUG): Running supervisor. 2024-01-31T23:44:13.070Z,1706744653.070 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931 2024-01-31T23:44:13.070Z,1706744653.070 [CommandExec](INFO): Initializing the command executive. 2024-01-31T23:44:13.072Z,1706744653.072 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932 2024-01-31T23:44:13.074Z,1706744653.074 [controlThread ThreadHandler](INFO): Handler Thread ID is 933 2024-01-31T23:44:13.074Z,1706744653.074 [controlThread](DEBUG): Initializing ControlThread 2024-01-31T23:44:13.075Z,1706744653.075 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-01-31T23:44:13.077Z,1706744653.077 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-01-31T23:44:13.078Z,1706744653.078 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-01-31T23:44:13.078Z,1706744653.078 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-01-31T23:44:13.082Z,1706744653.082 [SBIT](INFO): Initialize SBIT Component. 2024-01-31T23:44:13.083Z,1706744653.083 [SBIT](IMPORTANT): git: 2023-10-24_A 2024-01-31T23:44:13.083Z,1706744653.083 [SBIT](INFO): git hash: dfa22713be92246460bd2ec0e2f51182051bcc4a 2024-01-31T23:44:13.083Z,1706744653.083 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-01-31T23:44:13.084Z,1706744653.084 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2024-01-31T23:44:13.085Z,1706744653.085 [SBIT](INFO): Beginning SBIT in 74.000000 seconds. 2024-01-31T23:44:13.086Z,1706744653.086 [IBIT](INFO): Initialize IBIT Component. 2024-01-31T23:44:13.087Z,1706744653.087 [CBIT](DEBUG): Initialize CBIT Component. 2024-01-31T23:44:13.088Z,1706744653.088 [logger ThreadHandler](INFO): Handler Thread ID is 934 2024-01-31T23:44:13.100Z,1706744653.100 [CBIT](DEBUG): Initialized mux pins. 2024-01-31T23:44:13.100Z,1706744653.100 [CBIT](DEBUG): Initializing the watchdog timer. 2024-01-31T23:44:13.108Z,1706744653.108 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935 2024-01-31T23:44:13.120Z,1706744653.120 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2024-01-31T23:44:13.122Z,1706744653.122 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-01-31T23:44:13.124Z,1706744653.124 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-01-31T23:44:13.124Z,1706744653.124 [CBIT](DEBUG): Initializing heartbeat. 2024-01-31T23:44:13.132Z,1706744653.132 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2024-01-31T23:44:13.150Z,1706744653.150 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2024-01-31T23:44:13.173Z,1706744653.173 [DAT ThreadHandler](INFO): Handler Thread ID is 939 2024-01-31T23:44:13.173Z,1706744653.173 [DAT](INFO): Powering up 2024-01-31T23:44:13.174Z,1706744653.174 [DAT](DEBUG): Initializing DAT. 2024-01-31T23:44:13.177Z,1706744653.177 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941 2024-01-31T23:44:13.178Z,1706744653.178 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-01-31T23:44:13.181Z,1706744653.181 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943 2024-01-31T23:44:13.184Z,1706744653.184 [WetLabsBB2FL](INFO): Powering up 2024-01-31T23:44:13.185Z,1706744653.185 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945 2024-01-31T23:44:13.193Z,1706744653.193 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2024-01-31T23:44:13.193Z,1706744653.193 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2024-01-31T23:44:13.193Z,1706744653.193 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2024-01-31T23:44:13.193Z,1706744653.193 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2024-01-31T23:44:13.194Z,1706744653.194 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-01-31T23:44:13.194Z,1706744653.194 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-01-31T23:44:13.194Z,1706744653.194 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-01-31T23:44:13.194Z,1706744653.194 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-01-31T23:44:13.194Z,1706744653.194 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2024-01-31T23:44:13.194Z,1706744653.194 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2024-01-31T23:44:13.195Z,1706744653.195 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2024-01-31T23:44:13.195Z,1706744653.195 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2024-01-31T23:44:13.196Z,1706744653.196 [CBIT](DEBUG): Deactivating GF circuits. 2024-01-31T23:44:13.196Z,1706744653.196 [CBIT](DEBUG): Deactivating emergency mode. 2024-01-31T23:44:13.236Z,1706744653.236 [CBIT](DEBUG): Backplane powered. 2024-01-31T23:44:13.236Z,1706744653.236 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-01-31T23:44:13.237Z,1706744653.237 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-01-31T23:44:13.237Z,1706744653.237 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-01-31T23:44:13.237Z,1706744653.237 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-01-31T23:44:13.238Z,1706744653.238 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-01-31T23:44:13.239Z,1706744653.239 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-01-31T23:44:13.240Z,1706744653.240 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-01-31T23:44:13.240Z,1706744653.240 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-01-31T23:44:13.245Z,1706744653.245 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2024-01-31T23:44:13.295Z,1706744653.295 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-01-31T23:44:13.326Z,1706744653.326 [MissionManager](DEBUG): 2024-01-31T23:44:13.327Z,1706744653.327 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2024-01-31T23:44:13.444Z,1706744653.444 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-01-31T23:44:13.445Z,1706744653.445 [Default:A.Wait](DEBUG): Construct Wait. 2024-01-31T23:44:13.464Z,1706744653.464 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-01-31T23:44:13.487Z,1706744653.487 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-01-31T23:44:13.490Z,1706744653.490 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-01-31T23:44:13.510Z,1706744653.510 [Default:E.Execute](DEBUG): Construct Execute. 2024-01-31T23:44:13.533Z,1706744653.533 [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 2024-01-31T23:44:13.552Z,1706744653.552 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-01-31T23:44:13.556Z,1706744653.556 [Radio_Surface](INFO): Powering up 2024-01-31T23:44:13.559Z,1706744653.559 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-01-31T23:44:13.757Z,1706744653.757 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2024-01-31T23:44:13.758Z,1706744653.758 [Power24vConverter](INFO): Powering up. 2024-01-31T23:44:13.759Z,1706744653.759 [Sonardyne_Nano](INFO): Initializing. 2024-01-31T23:44:13.790Z,1706744653.790 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-01-31T23:44:13.850Z,1706744653.850 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-01-31T23:44:13.856Z,1706744653.856 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-01-31T23:44:13.857Z,1706744653.857 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-01-31T23:44:13.864Z,1706744653.864 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-01-31T23:44:13.865Z,1706744653.865 [MassServo](DEBUG): Initializing EZServoServo. 2024-01-31T23:44:13.872Z,1706744653.872 [MassServo](DEBUG): Initializing MassServo. 2024-01-31T23:44:13.873Z,1706744653.873 [RudderServo](DEBUG): Initializing EZServoServo. 2024-01-31T23:44:13.880Z,1706744653.880 [RudderServo](DEBUG): Initializing RudderServo. 2024-01-31T23:44:13.881Z,1706744653.881 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-01-31T23:44:13.888Z,1706744653.888 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-01-31T23:44:14.005Z,1706744654.005 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-01-31T23:44:14.005Z,1706744654.005 [DropWeight] Hardware Fault, FailCount= 1 2024-01-31T23:44:14.005Z,1706744654.005 [DropWeight](ERROR): Hardware Fault 2024-01-31T23:44:14.048Z,1706744654.048 [CommandExec](FAULT): Scheduling is paused 2024-01-31T23:44:14.048Z,1706744654.048 [CBIT](INFO): Critical error at 20240131T234414 2024-01-31T23:44:14.049Z,1706744654.049 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-01-31T23:44:14.051Z,1706744654.051 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-01-31T23:44:14.060Z,1706744654.060 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-01-31T23:44:14.369Z,1706744654.369 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.397150 decibar, -1.386848 m 2024-01-31T23:44:14.394Z,1706744654.394 [CBIT](INFO): Critical error at 20240131T234414 2024-01-31T23:44:15.292Z,1706744655.292 [WetLabsBB2FL](INFO): Powering down 2024-01-31T23:44:15.986Z,1706744655.986 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-01-31T23:44:17.116Z,1706744657.116 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-01-31T23:44:17.532Z,1706744657.532 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-01-31T23:44:18.076Z,1706744658.076 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2024-01-31T23:44:18.085Z,1706744658.085 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2024-01-31T23:44:19.617Z,1706744659.617 [Radio_Surface](INFO): Powering down 2024-01-31T23:44:23.336Z,1706744663.336 [CBIT](CRITICAL): Environmental Failure. Press:14.583750 PSI. Humidity:34%. Temp:26 C. ABORTING MISSION 2024-01-31T23:44:23.734Z,1706744663.734 [CBIT](INFO): Critical error at 20240131T234423 2024-01-31T23:44:41.421Z,1706744681.421 [NAL9602](INFO): Powering up NAL9602 2024-01-31T23:44:41.848Z,1706744681.848 [Waterlinked](ERROR): only read 3 of 5 data items 2024-01-31T23:44:52.329Z,1706744692.329 [NAL9602](INFO): NAL9602 initialized 2024-01-31T23:44:53.964Z,1706744693.964 [Radio_Surface](INFO): Powering up 2024-01-31T23:44:54.032Z,1706744694.032 [Waterlinked](INFO): Powering down 2024-01-31T23:44:55.969Z,1706744695.969 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.539158 decibar, -0.535181 m 2024-01-31T23:44:57.985Z,1706744697.985 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 5 samples 2024-01-31T23:44:57.985Z,1706744697.985 [Depth_Keller] Data Fault, FailCount= 1 2024-01-31T23:44:57.985Z,1706744697.985 [Depth_Keller](ERROR): Data Fault 2024-01-31T23:44:58.004Z,1706744698.004 [CBIT](ERROR): Data Fault in component: Depth_Keller 2024-01-31T23:44:58.005Z,1706744698.005 [CBIT](INFO): Clearing failed state for component Depth_Keller 2024-01-31T23:44:58.005Z,1706744698.005 [Depth_Keller] No Fault, FailCount= 1 2024-01-31T23:44:58.798Z,1706744698.798 [Depth_Keller](ERROR): Pressure or depth reading out of range: -2.447181 decibar, -2.429143 m 2024-01-31T23:45:00.024Z,1706744700.024 [Radio_Surface](INFO): Powering down 2024-01-31T23:45:00.816Z,1706744700.816 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 5 samples 2024-01-31T23:45:00.816Z,1706744700.816 [Depth_Keller] Data Fault, FailCount= 2 2024-01-31T23:45:00.816Z,1706744700.816 [Depth_Keller](ERROR): Data Fault 2024-01-31T23:45:00.865Z,1706744700.865 [CBIT](ERROR): Data Fault in component: Depth_Keller 2024-01-31T23:45:00.865Z,1706744700.865 [CBIT](INFO): Clearing failed state for component Depth_Keller 2024-01-31T23:45:00.865Z,1706744700.865 [Depth_Keller] No Fault, FailCount= 2 2024-01-31T23:45:01.629Z,1706744701.629 [Depth_Keller](ERROR): Pressure or depth reading out of range: -4.414546 decibar, -4.382027 m 2024-01-31T23:45:03.649Z,1706744703.649 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 5 samples 2024-01-31T23:45:03.649Z,1706744703.649 [Depth_Keller] Data Fault, FailCount= 3 2024-01-31T23:45:03.649Z,1706744703.649 [Depth_Keller](ERROR): Data Fault 2024-01-31T23:45:03.668Z,1706744703.668 [CBIT](ERROR): Data Fault in component: Depth_Keller 2024-01-31T23:45:03.668Z,1706744703.668 [CBIT](CRITICAL): Data Fault in component: Depth_Keller 2024-01-31T23:45:04.077Z,1706744704.077 [CBIT](INFO): Critical error at 20240131T234503 2024-01-31T23:45:07.429Z,1706744707.429 [CTD_Seabird](ERROR): Failed to parse device response: 2024-01-31T23:45:08.913Z,1706744708.913 [Radio_Surface](INFO): Powering up 2024-01-31T23:45:13.429Z,1706744713.429 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2024-01-31T23:45:13.429Z,1706744713.429 [DAT] Communications Fault, FailCount= 1 2024-01-31T23:45:13.429Z,1706744713.429 [DAT](ERROR): Communications Fault 2024-01-31T23:45:13.772Z,1706744713.772 [CBIT](ERROR): Communications Fault in component: DAT 2024-01-31T23:45:13.832Z,1706744713.832 [DAT](INFO): Powering down 2024-01-31T23:45:14.984Z,1706744714.984 [CBIT](INFO): Clearing failed state for component DAT 2024-01-31T23:45:14.984Z,1706744714.984 [DAT] No Fault, FailCount= 1 2024-01-31T23:45:16.884Z,1706744716.884 [DAT](INFO): Powering up 2024-01-31T23:45:16.884Z,1706744716.884 [DAT](DEBUG): Initializing DAT. 2024-01-31T23:45:18.398Z,1706744718.398 [CommandExec](IMPORTANT): got command show stack 2024-01-31T23:45:18.398Z,1706744718.398 [CommandExec](IMPORTANT): Behavior Stack: 2024-01-31T23:45:18.399Z,1706744718.399 [MissionManager](IMPORTANT): Mission loaded, but not running. 2024-01-31T23:45:27.518Z,1706744727.518 [SBIT](IMPORTANT): Beginning Startup BIT 2024-01-31T23:45:27.523Z,1706744727.523 [CBIT](IMPORTANT): Beginning ground fault scan 2024-01-31T23:45:28.514Z,1706744728.514 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:28.517Z,1706744728.517 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:29.518Z,1706744729.518 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:29.519Z,1706744729.519 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:30.521Z,1706744730.521 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:30.522Z,1706744730.522 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:31.526Z,1706744731.526 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:31.526Z,1706744731.526 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:32.529Z,1706744732.529 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:32.530Z,1706744732.530 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:33.534Z,1706744733.534 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:33.534Z,1706744733.534 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:34.537Z,1706744734.537 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:34.538Z,1706744734.538 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:35.541Z,1706744735.541 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:35.542Z,1706744735.542 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:36.548Z,1706744736.548 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:36.549Z,1706744736.549 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:37.550Z,1706744737.550 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:37.551Z,1706744737.551 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:38.554Z,1706744738.554 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2024-01-31T23:45:38.555Z,1706744738.555 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:45:38.564Z,1706744738.564 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000300 CHAN A1 (24V): 0.000106 CHAN A2 (12V): 0.000295 CHAN A3 (5V): -0.001182 CHAN B0 (3.3V): -0.000013 CHAN B1 (3.15aV): 0.000454 CHAN B2 (3.15bV): -0.000142 CHAN B3 (GND): -0.000780 OPEN: 0.006659 Full Scale: +/- 1 mA 2024-01-31T23:46:16.905Z,1706744776.905 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2024-01-31T23:46:16.905Z,1706744776.905 [DAT] Communications Fault, FailCount= 2 2024-01-31T23:46:16.905Z,1706744776.905 [DAT](ERROR): Communications Fault 2024-01-31T23:46:17.204Z,1706744777.204 [CBIT](ERROR): Communications Fault in component: DAT 2024-01-31T23:46:17.308Z,1706744777.308 [DAT](INFO): Powering down 2024-01-31T23:46:18.417Z,1706744778.417 [CBIT](INFO): Clearing failed state for component DAT 2024-01-31T23:46:18.417Z,1706744778.417 [DAT] No Fault, FailCount= 2 2024-01-31T23:46:20.356Z,1706744780.356 [DAT](INFO): Powering up 2024-01-31T23:46:20.356Z,1706744780.356 [DAT](DEBUG): Initializing DAT. 2024-01-31T23:46:21.228Z,1706744781.228 [SBIT](IMPORTANT): SBIT PASSED 2024-01-31T23:46:21.228Z,1706744781.228 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-01-31T23:46:21.229Z,1706744781.229 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=25 count; 2024-01-31T23:46:21.229Z,1706744781.229 [SBIT](IMPORTANT): Express mostRecent BPC1.platform_battery_voltage; 2024-01-31T23:46:21.229Z,1706744781.229 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2024-01-31T23:46:21.229Z,1706744781.229 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 10.000000 count_per_second; 2024-01-31T23:46:21.229Z,1706744781.229 [SBIT](IMPORTANT): Express none platform_pitch_angle 5.000000 degree; 2024-01-31T23:46:21.229Z,1706744781.229 [SBIT](IMPORTANT): Express none platform_roll_angle 1.000000 degree; 2024-01-31T23:46:21.230Z,1706744781.230 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour; 2024-01-31T23:46:21.230Z,1706744781.230 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2024-01-31T23:46:21.230Z,1706744781.230 [SBIT](IMPORTANT): PowerOnly.sampleTime1=1 second; 2024-01-31T23:46:21.230Z,1706744781.230 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=185.760373 cubic_centimeter; 2024-01-31T23:46:21.230Z,1706744781.230 [SBIT](IMPORTANT): VerticalControl.massDefault=13.630082 millimeter; 2024-01-31T23:46:21.643Z,1706744781.643 [MissionManager](IMPORTANT): Started mission Startup 2024-01-31T23:46:21.643Z,1706744781.643 [Startup] Running Loop=1 2024-01-31T23:46:21.643Z,1706744781.643 [Startup](DEBUG): Aggregate::initialize Startup 2024-01-31T23:46:21.643Z,1706744781.643 [Startup:A.GoToSurface] Running Loop=1 2024-01-31T23:46:21.643Z,1706744781.643 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-01-31T23:46:21.644Z,1706744781.644 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-01-31T23:46:21.644Z,1706744781.644 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-01-31T23:46:21.645Z,1706744781.645 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-01-31T23:46:21.645Z,1706744781.645 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-01-31T23:46:21.645Z,1706744781.645 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-01-31T23:46:21.647Z,1706744781.647 [Startup:StartupSatComms] Running Loop=1 2024-01-31T23:46:21.647Z,1706744781.647 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-01-31T23:46:21.647Z,1706744781.647 [Startup:StartupSatComms:A] Running Loop=1 2024-01-31T23:46:22.045Z,1706744782.045 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-01-31T23:47:13.333Z,1706744833.333 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-01-31T23:47:13.333Z,1706744833.333 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-01-31T23:47:13.334Z,1706744833.334 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-01-31T23:47:13.335Z,1706744833.335 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-01-31T23:47:13.336Z,1706744833.336 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-01-31T23:47:13.336Z,1706744833.336 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-01-31T23:47:13.380Z,1706744833.380 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-01-31T23:47:13.380Z,1706744833.380 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-01-31T23:47:13.381Z,1706744833.381 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-01-31T23:47:13.749Z,1706744833.749 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-01-31T23:47:13.749Z,1706744833.749 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-01-31T23:47:13.750Z,1706744833.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-01-31T23:47:13.750Z,1706744833.750 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-01-31T23:47:13.750Z,1706744833.750 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-01-31T23:47:13.750Z,1706744833.750 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-01-31T23:47:14.146Z,1706744834.146 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-01-31T23:47:20.389Z,1706744840.389 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2024-01-31T23:47:20.389Z,1706744840.389 [DAT] Communications Fault, FailCount= 3 2024-01-31T23:47:20.389Z,1706744840.389 [DAT](ERROR): Communications Fault 2024-01-31T23:47:20.621Z,1706744840.621 [CBIT](ERROR): Communications Fault in component: DAT 2024-01-31T23:47:20.792Z,1706744840.792 [DAT](INFO): Powering down 2024-01-31T23:47:21.831Z,1706744841.831 [Startup:StartupSatComms:A](INFO): Timed out from 2024-01-31T23:46:21.6Z 2024-01-31T23:47:21.831Z,1706744841.831 [Startup:StartupSatComms:A] Stopped 2024-01-31T23:47:21.831Z,1706744841.831 [Startup:StartupSatComms:B] Running Loop=1 2024-01-31T23:47:21.865Z,1706744841.865 [CBIT](INFO): Clearing failed state for component DAT 2024-01-31T23:47:21.865Z,1706744841.865 [DAT] No Fault, FailCount= 3 2024-01-31T23:47:22.248Z,1706744842.248 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-01-31T23:47:23.852Z,1706744843.852 [DAT](INFO): Powering up 2024-01-31T23:47:23.852Z,1706744843.852 [DAT](DEBUG): Initializing DAT. 2024-01-31T23:47:46.262Z,1706744866.262 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004941 2024-01-31T23:47:54.307Z,1706744874.307 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240131T005403/Courier0013.lzma 2024-01-31T23:47:54.968Z,1706744874.968 [CTD_Seabird](ERROR): Salinity reading out of range: 0.000290 psu 2024-01-31T23:47:54.969Z,1706744874.969 [CTD_Seabird](INFO): some bad data, not updating bins 2024-01-31T23:47:55.308Z,1706744875.308 [DataOverHttps](INFO): Moved sent file to Logs/20240131T005403/Courier0013.lzma.bak 2024-01-31T23:47:55.308Z,1706744875.308 [DataOverHttps](INFO): SBD MOMSN=19252313 2024-01-31T23:47:56.671Z,1706744876.671 [CommandExec](IMPORTANT): got command restart system 2024-01-31T23:47:58.730Z,1706744878.730 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-31T23:47:58.730Z,170674