2024-04-11T17:49:41.083Z,1712857781.083 [Supervisor](DEBUG): Initializing supervisor. 2024-04-11T17:49:41.087Z,1712857781.087 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-04-11T17:49:41.088Z,1712857781.088 [SyncHandler](INFO): Protected caller Thread ID is 837 2024-04-11T17:49:41.088Z,1712857781.088 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-04-11T17:49:41.089Z,1712857781.089 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-04-11T17:49:41.089Z,1712857781.089 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2024-04-11T17:49:41.093Z,1712857781.093 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-04-11T17:49:41.111Z,1712857781.111 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-04-11T17:49:41.112Z,1712857781.112 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-04-11T17:49:41.112Z,1712857781.112 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2024-04-11T17:49:41.116Z,1712857781.116 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-04-11T17:49:41.117Z,1712857781.117 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-04-11T17:49:41.118Z,1712857781.118 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2024-04-11T17:49:41.120Z,1712857781.120 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-04-11T17:49:41.121Z,1712857781.121 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-04-11T17:49:41.121Z,1712857781.121 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2024-04-11T17:49:41.125Z,1712857781.125 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-04-11T17:49:41.125Z,1712857781.125 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-04-11T17:49:41.129Z,1712857781.129 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-04-11T17:49:41.463Z,1712857781.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-04-11T17:49:41.464Z,1712857781.464 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-04-11T17:49:41.546Z,1712857781.546 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-04-11T17:49:41.652Z,1712857781.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-04-11T17:49:41.654Z,1712857781.654 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-04-11T17:49:41.784Z,1712857781.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-04-11T17:49:41.785Z,1712857781.785 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-04-11T17:49:42.131Z,1712857782.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-04-11T17:49:42.131Z,1712857782.131 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-04-11T17:49:42.228Z,1712857782.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-04-11T17:49:42.230Z,1712857782.230 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-04-11T17:49:42.650Z,1712857782.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-04-11T17:49:42.652Z,1712857782.652 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-04-11T17:49:42.880Z,1712857782.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-04-11T17:49:42.880Z,1712857782.880 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-04-11T17:49:42.960Z,1712857782.960 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-04-11T17:49:43.197Z,1712857783.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-04-11T17:49:43.198Z,1712857783.198 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-04-11T17:49:43.420Z,1712857783.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-04-11T17:49:43.420Z,1712857783.420 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-04-11T17:49:43.941Z,1712857783.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-04-11T17:49:43.942Z,1712857783.942 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-04-11T17:49:44.138Z,1712857784.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-04-11T17:49:44.138Z,1712857784.138 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-04-11T17:49:44.429Z,1712857784.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-04-11T17:49:44.429Z,1712857784.429 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-04-11T17:49:45.275Z,1712857785.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-04-11T17:49:45.276Z,1712857785.276 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-04-11T17:49:45.805Z,1712857785.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-04-11T17:49:45.807Z,1712857785.807 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2024-04-11T17:49:45.809Z,1712857785.809 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2024-04-11T17:49:45.910Z,1712857785.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2024-04-11T17:49:46.029Z,1712857786.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2024-04-11T17:49:46.113Z,1712857786.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2024-04-11T17:49:46.222Z,1712857786.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2024-04-11T17:49:46.309Z,1712857786.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2024-04-11T17:49:46.567Z,1712857786.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-04-11T17:49:46.568Z,1712857786.568 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2024-04-11T17:49:46.677Z,1712857786.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2024-04-11T17:49:46.772Z,1712857786.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2024-04-11T17:49:46.877Z,1712857786.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2024-04-11T17:49:47.011Z,1712857787.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2024-04-11T17:49:47.097Z,1712857787.097 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2024-04-11T17:49:47.102Z,1712857787.102 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-04-11T17:49:47.248Z,1712857787.248 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-04-11T17:49:47.250Z,1712857787.250 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-04-11T17:49:47.321Z,1712857787.321 [VerticalControl](DEBUG): Construct VerticalControl. 2024-04-11T17:49:47.384Z,1712857787.384 [VerticalControl] Loaded 2024-04-11T17:49:47.384Z,1712857787.384 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-04-11T17:49:47.387Z,1712857787.387 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-04-11T17:49:47.429Z,1712857787.429 [HorizontalControl] Loaded 2024-04-11T17:49:47.430Z,1712857787.430 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-04-11T17:49:47.432Z,1712857787.432 [SpeedControl](DEBUG): Construct SpeedControl. 2024-04-11T17:49:47.435Z,1712857787.435 [SpeedControl] Loaded 2024-04-11T17:49:47.436Z,1712857787.436 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-04-11T17:49:47.438Z,1712857787.438 [LoopControl](DEBUG): Construct LoopControl. 2024-04-11T17:49:47.439Z,1712857787.439 [LoopControl] Loaded 2024-04-11T17:49:47.439Z,1712857787.439 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-04-11T17:49:47.439Z,1712857787.439 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-04-11T17:49:47.440Z,1712857787.440 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-04-11T17:49:47.522Z,1712857787.522 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-04-11T17:49:47.523Z,1712857787.523 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-04-11T17:49:48.295Z,1712857788.295 [AHRS_M2] Loaded 2024-04-11T17:49:48.296Z,1712857788.296 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-04-11T17:49:48.545Z,1712857788.545 [BackseatComponent] Loaded 2024-04-11T17:49:48.546Z,1712857788.546 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-04-11T17:49:48.547Z,1712857788.547 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408924E0 2024-04-11T17:49:48.547Z,1712857788.547 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923 2024-04-11T17:49:48.550Z,1712857788.550 [LcmUniversalReporter] Loaded 2024-04-11T17:49:48.550Z,1712857788.550 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-04-11T17:49:49.361Z,1712857789.361 [BPC1] Loaded 2024-04-11T17:49:49.362Z,1712857789.362 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-04-11T17:49:49.504Z,1712857789.504 [DataOverHttps] Loaded 2024-04-11T17:49:49.504Z,1712857789.504 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-04-11T17:49:49.506Z,1712857789.506 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0 2024-04-11T17:49:49.506Z,1712857789.506 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2024-04-11T17:49:49.530Z,1712857789.530 [Depth_Keller] Loaded 2024-04-11T17:49:49.530Z,1712857789.530 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-04-11T17:49:49.539Z,1712857789.539 [DropWeight] Loaded 2024-04-11T17:49:49.540Z,1712857789.540 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-04-11T17:49:49.609Z,1712857789.609 [NAL9602] Loaded 2024-04-11T17:49:49.610Z,1712857789.610 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-04-11T17:49:49.639Z,1712857789.639 [Onboard] Loaded 2024-04-11T17:49:49.639Z,1712857789.639 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-04-11T17:49:49.640Z,1712857789.640 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0 2024-04-11T17:49:49.640Z,1712857789.640 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2024-04-11T17:49:49.652Z,1712857789.652 [Power24vConverter] Loaded 2024-04-11T17:49:49.653Z,1712857789.653 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-04-11T17:49:49.666Z,1712857789.666 [Radio_Surface] Loaded 2024-04-11T17:49:49.666Z,1712857789.666 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-04-11T17:49:49.667Z,1712857789.667 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409224E0 2024-04-11T17:49:49.667Z,1712857789.667 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2024-04-11T17:49:49.681Z,1712857789.681 [Sonardyne_Nano] Loaded 2024-04-11T17:49:49.682Z,1712857789.682 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-04-11T17:49:49.773Z,1712857789.773 [DAT] Loaded 2024-04-11T17:49:49.774Z,1712857789.774 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-04-11T17:49:49.774Z,1712857789.774 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409524E0 2024-04-11T17:49:49.775Z,1712857789.775 [DAT ThreadHandler](INFO): Protected caller Thread ID is 927 2024-04-11T17:49:49.797Z,1712857789.797 [Waterlinked] Loaded 2024-04-11T17:49:49.797Z,1712857789.797 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2024-04-11T17:49:49.798Z,1712857789.798 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-04-11T17:49:49.798Z,1712857789.798 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-04-11T17:49:49.814Z,1712857789.814 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-04-11T17:49:49.814Z,1712857789.814 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-04-11T17:49:49.857Z,1712857789.857 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-04-11T17:49:49.857Z,1712857789.857 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-04-11T17:49:50.030Z,1712857790.030 [SBIT](DEBUG): Construct Startup Built In Test. 2024-04-11T17:49:50.040Z,1712857790.040 [SBIT] Loaded 2024-04-11T17:49:50.040Z,1712857790.040 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-04-11T17:49:50.043Z,1712857790.043 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-04-11T17:49:50.056Z,1712857790.056 [IBIT] Loaded 2024-04-11T17:49:50.056Z,1712857790.056 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-04-11T17:49:50.062Z,1712857790.062 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-04-11T17:49:50.166Z,1712857790.166 [CBIT] Loaded 2024-04-11T17:49:50.166Z,1712857790.166 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-04-11T17:49:50.167Z,1712857790.167 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-04-11T17:49:50.167Z,1712857790.167 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-04-11T17:49:50.568Z,1712857790.568 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-04-11T17:49:50.569Z,1712857790.569 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-04-11T17:49:50.638Z,1712857790.638 [DepthRateCalculator] Loaded 2024-04-11T17:49:50.638Z,1712857790.638 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-04-11T17:49:50.643Z,1712857790.643 [PitchRateCalculator] Loaded 2024-04-11T17:49:50.644Z,1712857790.644 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-04-11T17:49:50.654Z,1712857790.654 [SpeedCalculator] Loaded 2024-04-11T17:49:50.654Z,1712857790.654 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-04-11T17:49:50.659Z,1712857790.659 [YawRateCalculator] Loaded 2024-04-11T17:49:50.659Z,1712857790.659 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-04-11T17:49:50.678Z,1712857790.678 [ElevatorOffsetCalculator] Loaded 2024-04-11T17:49:50.678Z,1712857790.678 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-04-11T17:49:50.678Z,1712857790.678 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-04-11T17:49:50.679Z,1712857790.679 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-04-11T17:49:50.861Z,1712857790.861 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-04-11T17:49:50.862Z,1712857790.862 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-04-11T17:49:50.999Z,1712857790.999 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-04-11T17:49:50.999Z,1712857790.999 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-04-11T17:49:51.041Z,1712857791.041 [DeadReckonUsingSpeedCalculator] Loaded 2024-04-11T17:49:51.042Z,1712857791.042 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-04-11T17:49:51.084Z,1712857791.084 [DeadReckonWithRespectToSeafloor] Loaded 2024-04-11T17:49:51.084Z,1712857791.084 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2024-04-11T17:49:51.098Z,1712857791.098 [NavChart] Loaded 2024-04-11T17:49:51.098Z,1712857791.098 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-04-11T17:49:51.104Z,1712857791.104 [UniversalFixResidualReporter] Loaded 2024-04-11T17:49:51.104Z,1712857791.104 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-04-11T17:49:51.104Z,1712857791.104 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-04-11T17:49:51.105Z,1712857791.105 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-04-11T17:49:51.266Z,1712857791.266 [BuoyancyServo] Loaded 2024-04-11T17:49:51.267Z,1712857791.267 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-04-11T17:49:51.287Z,1712857791.287 [ElevatorServo] Loaded 2024-04-11T17:49:51.287Z,1712857791.287 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-04-11T17:49:51.307Z,1712857791.307 [MassServo] Loaded 2024-04-11T17:49:51.308Z,1712857791.308 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-04-11T17:49:51.327Z,1712857791.327 [RudderServo] Loaded 2024-04-11T17:49:51.327Z,1712857791.327 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-04-11T17:49:51.342Z,1712857791.342 [ThrusterHE] Loaded 2024-04-11T17:49:51.342Z,1712857791.342 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-04-11T17:49:51.342Z,1712857791.342 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-04-11T17:49:51.343Z,1712857791.343 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-04-11T17:49:51.563Z,1712857791.563 [CTD_Seabird] Loaded 2024-04-11T17:49:51.563Z,1712857791.563 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-04-11T17:49:51.564Z,1712857791.564 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC34E0 2024-04-11T17:49:51.565Z,1712857791.565 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928 2024-04-11T17:49:51.598Z,1712857791.598 [ESPComponent] Loaded 2024-04-11T17:49:51.598Z,1712857791.598 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-04-11T17:49:51.618Z,1712857791.618 [PAR_Licor] Loaded 2024-04-11T17:49:51.619Z,1712857791.619 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-04-11T17:49:51.659Z,1712857791.659 [WetLabsBB2FL] Loaded 2024-04-11T17:49:51.660Z,1712857791.660 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-04-11T17:49:51.661Z,1712857791.661 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF34E0 2024-04-11T17:49:51.661Z,1712857791.661 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929 2024-04-11T17:49:51.662Z,1712857791.662 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-04-11T17:49:51.668Z,1712857791.668 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-04-11T17:49:51.671Z,1712857791.671 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-04-11T17:49:51.682Z,1712857791.682 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-04-11T17:49:51.683Z,1712857791.683 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C234E0 2024-04-11T17:49:51.683Z,1712857791.683 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930 2024-04-11T17:49:51.688Z,1712857791.688 [Supervisor](INFO): Main Thread ID is 828 2024-04-11T17:49:51.688Z,1712857791.688 [Supervisor](DEBUG): Running supervisor. 2024-04-11T17:49:51.689Z,1712857791.689 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931 2024-04-11T17:49:51.689Z,1712857791.689 [CommandExec](INFO): Initializing the command executive. 2024-04-11T17:49:51.691Z,1712857791.691 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932 2024-04-11T17:49:51.693Z,1712857791.693 [controlThread ThreadHandler](INFO): Handler Thread ID is 933 2024-04-11T17:49:51.693Z,1712857791.693 [controlThread](DEBUG): Initializing ControlThread 2024-04-11T17:49:51.694Z,1712857791.694 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-04-11T17:49:51.696Z,1712857791.696 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-04-11T17:49:51.696Z,1712857791.696 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-04-11T17:49:51.697Z,1712857791.697 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-04-11T17:49:51.703Z,1712857791.703 [SBIT](INFO): Initialize SBIT Component. 2024-04-11T17:49:51.703Z,1712857791.703 [SBIT](IMPORTANT): git: 2024-03-18 2024-04-11T17:49:51.704Z,1712857791.704 [SBIT](INFO): git hash: d75f12dcd22668161297bf4aa5fb732dee116921 2024-04-11T17:49:51.704Z,1712857791.704 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-04-11T17:49:51.705Z,1712857791.705 [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-04-11T17:49:51.706Z,1712857791.706 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2024-04-11T17:49:51.707Z,1712857791.707 [IBIT](INFO): Initialize IBIT Component. 2024-04-11T17:49:51.708Z,1712857791.708 [CBIT](DEBUG): Initialize CBIT Component. 2024-04-11T17:49:51.709Z,1712857791.709 [logger ThreadHandler](INFO): Handler Thread ID is 934 2024-04-11T17:49:51.721Z,1712857791.721 [CBIT](DEBUG): Initialized mux pins. 2024-04-11T17:49:51.721Z,1712857791.721 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2024-04-11T17:49:51.721Z,1712857791.721 [CBIT](DEBUG): Initializing the watchdog timer. 2024-04-11T17:49:51.730Z,1712857791.730 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935 2024-04-11T17:49:51.741Z,1712857791.741 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2024-04-11T17:49:51.743Z,1712857791.743 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-04-11T17:49:51.745Z,1712857791.745 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-04-11T17:49:51.750Z,1712857791.750 [CBIT](FAULT): Found backtrace file: 1712857461.238684 [CBIT]: Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b3564] ./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1b2fb0] 2024-04-11T17:49:51.752Z,1712857791.752 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2024-04-11T17:49:51.756Z,1712857791.756 [CBIT](FAULT): Renaming backtrace file to Data/backtrace5 2024-04-11T17:49:51.760Z,1712857791.760 [CBIT](DEBUG): Initializing heartbeat. 2024-04-11T17:49:51.789Z,1712857791.789 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2024-04-11T17:49:51.809Z,1712857791.809 [DAT ThreadHandler](INFO): Handler Thread ID is 939 2024-04-11T17:49:51.810Z,1712857791.810 [DAT](INFO): Powering up 2024-04-11T17:49:51.810Z,1712857791.810 [DAT](DEBUG): Initializing DAT. 2024-04-11T17:49:51.814Z,1712857791.814 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941 2024-04-11T17:49:51.815Z,1712857791.815 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-04-11T17:49:51.818Z,1712857791.818 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943 2024-04-11T17:49:51.821Z,1712857791.821 [WetLabsBB2FL](INFO): Powering up 2024-04-11T17:49:51.823Z,1712857791.823 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945 2024-04-11T17:49:51.830Z,1712857791.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2024-04-11T17:49:51.830Z,1712857791.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2024-04-11T17:49:51.830Z,1712857791.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2024-04-11T17:49:51.831Z,1712857791.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2024-04-11T17:49:51.831Z,1712857791.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-04-11T17:49:51.831Z,1712857791.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-04-11T17:49:51.831Z,1712857791.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-04-11T17:49:51.831Z,1712857791.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-04-11T17:49:51.831Z,1712857791.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2024-04-11T17:49:51.831Z,1712857791.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2024-04-11T17:49:51.832Z,1712857791.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2024-04-11T17:49:51.832Z,1712857791.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2024-04-11T17:49:51.833Z,1712857791.833 [CBIT](DEBUG): Deactivating GF circuits. 2024-04-11T17:49:51.833Z,1712857791.833 [CBIT](DEBUG): Deactivating emergency mode. 2024-04-11T17:49:51.873Z,1712857791.873 [CBIT](DEBUG): Backplane powered. 2024-04-11T17:49:51.873Z,1712857791.873 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-04-11T17:49:51.874Z,1712857791.874 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-04-11T17:49:51.874Z,1712857791.874 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-04-11T17:49:51.874Z,1712857791.874 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-04-11T17:49:51.875Z,1712857791.875 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-04-11T17:49:51.876Z,1712857791.876 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-11T17:49:51.877Z,1712857791.877 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-04-11T17:49:51.877Z,1712857791.877 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-04-11T17:49:51.882Z,1712857791.882 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-04-11T17:49:51.882Z,1712857791.882 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-04-11T17:49:51.924Z,1712857791.924 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-11T17:49:51.962Z,1712857791.962 [MissionManager](DEBUG): mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-04-11T17:49:51.963Z,1712857791.963 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-04-11T17:49:51.963Z,1712857791.963 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-04-11T17:49:52.050Z,1712857792.050 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-04-11T17:49:52.052Z,1712857792.052 [Default:A.Wait](DEBUG): Construct Wait. 2024-04-11T17:49:52.069Z,1712857792.069 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-11T17:49:52.096Z,1712857792.096 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-04-11T17:49:52.099Z,1712857792.099 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-04-11T17:49:52.109Z,1712857792.109 [Default:E.Execute](DEBUG): Construct Execute. 2024-04-11T17:49:52.131Z,1712857792.131 [MissionManager](DEBUG): mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-04-11T17:49:52.136Z,1712857792.136 [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-04-11T17:49:52.157Z,1712857792.157 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-04-11T17:49:52.193Z,1712857792.193 [Radio_Surface](INFO): Powering up 2024-04-11T17:49:52.323Z,1712857792.323 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2024-04-11T17:49:52.324Z,1712857792.324 [Power24vConverter](INFO): Powering up. 2024-04-11T17:49:52.325Z,1712857792.325 [Sonardyne_Nano](INFO): Initializing. 2024-04-11T17:49:52.363Z,1712857792.363 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-04-11T17:49:52.408Z,1712857792.408 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-04-11T17:49:52.413Z,1712857792.413 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-04-11T17:49:52.415Z,1712857792.415 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-04-11T17:49:52.421Z,1712857792.421 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-04-11T17:49:52.422Z,1712857792.422 [MassServo](DEBUG): Initializing EZServoServo. 2024-04-11T17:49:52.429Z,1712857792.429 [MassServo](DEBUG): Initializing MassServo. 2024-04-11T17:49:52.430Z,1712857792.430 [RudderServo](DEBUG): Initializing EZServoServo. 2024-04-11T17:49:52.437Z,1712857792.437 [RudderServo](DEBUG): Initializing RudderServo. 2024-04-11T17:49:52.438Z,1712857792.438 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-04-11T17:49:52.445Z,1712857792.445 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-04-11T17:49:53.929Z,1712857793.929 [WetLabsBB2FL](INFO): Powering down 2024-04-11T17:49:54.567Z,1712857794.567 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-04-11T17:49:55.810Z,1712857795.810 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2024-04-11T17:49:58.058Z,1712857798.058 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-04-11T17:50:04.677Z,1712857804.677 [DAT](INFO): DAT read: 2024-04-11T17:50:04.679Z,1712857804.679 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-04-11T17:50:06.190Z,1712857806.190 [DAT](INFO): DAT read: MF Frequency Band 2024-04-11T17:50:06.191Z,1712857806.191 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-04-11T17:50:06.192Z,1712857806.192 [DAT](INFO): DAT read: Apr 11 2024 17:49:52 2024-04-11T17:50:07.200Z,1712857807.200 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-04-11T17:50:07.450Z,1712857807.450 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-04-11T17:50:07.451Z,1712857807.451 [DAT](INFO): commRate: 800 2024-04-11T17:50:09.517Z,1712857809.517 [DAT](INFO): entering command mode 2024-04-11T17:50:09.717Z,1712857809.717 [DAT](INFO): DAT read: 2024-04-11T17:50:09.718Z,1712857809.718 [DAT](INFO): DAT read: user:1> 2024-04-11T17:50:09.718Z,1712857809.718 [DAT](INFO): setting verbose to 3 2024-04-11T17:50:09.970Z,1712857809.970 [DAT](INFO): DAT read: user:1> 2024-04-11T17:50:09.971Z,1712857809.971 [DAT](INFO): DAT read: Verbose | 3 2024-04-11T17:50:09.971Z,1712857809.971 [DAT](INFO): set verbose to 3 2024-04-11T17:50:09.971Z,1712857809.971 [DAT](INFO): setting DatVerbose to 27440 2024-04-11T17:50:10.222Z,1712857810.222 [DAT](INFO): DAT read: user:2> 2024-04-11T17:50:10.223Z,1712857810.223 [DAT](INFO): DAT read: DatVerbose | 27440 2024-04-11T17:50:10.223Z,1712857810.223 [DAT](INFO): set DatVerbose to 27440 2024-04-11T17:50:10.223Z,1712857810.223 [DAT](INFO): setting transmit power to 8 2024-04-11T17:50:10.474Z,1712857810.474 [DAT](INFO): DAT read: user:3> 2024-04-11T17:50:10.475Z,1712857810.475 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-04-11T17:50:10.475Z,1712857810.475 [DAT](INFO): set transmit power to 8 2024-04-11T17:50:10.475Z,1712857810.475 [DAT](INFO): setting local address to 1 2024-04-11T17:50:10.726Z,1712857810.726 [DAT](INFO): DAT read: user:4> 2024-04-11T17:50:10.727Z,1712857810.727 [DAT](INFO): DAT read: LocalAddr | 1 2024-04-11T17:50:10.727Z,1712857810.727 [DAT](INFO): set local address to 1 2024-04-11T17:50:10.728Z,1712857810.728 [DAT](INFO): Setting time to: 17:50:10 And date to:4/11/2024 2024-04-11T17:50:10.977Z,1712857810.977 [DAT](INFO): DAT read: user:5> 2024-04-11T17:50:10.978Z,1712857810.978 [DAT](INFO): DAT read: Thu Apr 11, 2024 17:50:10 2024-04-11T17:50:10.979Z,1712857810.979 [DAT](INFO): Local DAT time set to Thu Apr 11, 2024 17:50:10 2024-04-11T17:50:19.622Z,1712857819.622 [NAL9602](INFO): Powering up NAL9602 2024-04-11T17:50:26.562Z,1712857826.562 [Waterlinked](INFO): Powering down 2024-04-11T17:50:30.530Z,1712857830.530 [NAL9602](INFO): NAL9602 initialized 2024-04-11T17:50:59.206Z,1712857859.206 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:51:03.269Z,1712857863.269 [SBIT](IMPORTANT): Beginning Startup BIT 2024-04-11T17:51:03.297Z,1712857863.297 [CBIT](IMPORTANT): Beginning ground fault scan 2024-04-11T17:51:13.346Z,1712857873.346 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:51:14.357Z,1712857874.357 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000684 CHAN A1 (24V): 0.000245 CHAN A2 (12V): 0.000481 CHAN A3 (5V): -0.000869 CHAN B0 (3.3V): 0.000399 CHAN B1 (3.15aV): 0.000128 CHAN B2 (3.15bV): -0.000357 CHAN B3 (GND): -0.000189 OPEN: 0.007003 Full Scale: +/- 1 mA 2024-04-11T17:51:27.486Z,1712857887.486 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:51:41.626Z,1712857901.626 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:51:54.957Z,1712857914.957 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:51:57.002Z,1712857917.002 [SBIT](IMPORTANT): SBIT PASSED 2024-04-11T17:51:57.002Z,1712857917.002 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2024-04-11T17:51:57.400Z,1712857917.400 [MissionManager](IMPORTANT): Started mission Startup 2024-04-11T17:51:57.400Z,1712857917.400 [Startup] Running Loop=1 2024-04-11T17:51:57.400Z,1712857917.400 [Startup](DEBUG): Aggregate::initialize Startup 2024-04-11T17:51:57.400Z,1712857917.400 [Startup:A.GoToSurface] Running Loop=1 2024-04-11T17:51:57.400Z,1712857917.400 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-11T17:51:57.401Z,1712857917.401 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-11T17:51:57.402Z,1712857917.402 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-11T17:51:57.402Z,1712857917.402 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-11T17:51:57.402Z,1712857917.402 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-11T17:51:57.403Z,1712857917.403 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-11T17:51:57.404Z,1712857917.404 [Startup:StartupSatComms] Running Loop=1 2024-04-11T17:51:57.404Z,1712857917.404 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-04-11T17:51:57.404Z,1712857917.404 [Startup:StartupSatComms:A] Running Loop=1 2024-04-11T17:51:57.826Z,1712857917.826 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-04-11T17:52:08.290Z,1712857928.290 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:52:22.026Z,1712857942.026 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:52:31.410Z,1712857951.410 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-11T17:52:36.650Z,1712857956.650 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:52:50.788Z,1712857970.788 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:52:52.044Z,1712857972.044 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-04-11T17:52:52.044Z,1712857972.044 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-11T17:52:52.045Z,1712857972.045 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-04-11T17:52:52.045Z,1712857972.045 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-11T17:52:52.051Z,1712857972.051 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-04-11T17:52:52.051Z,1712857972.051 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-04-11T17:52:52.090Z,1712857972.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-11T17:52:52.090Z,1712857972.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-11T17:52:52.090Z,1712857972.090 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-04-11T17:52:52.466Z,1712857972.466 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-11T17:52:52.466Z,1712857972.466 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-04-11T17:52:52.466Z,1712857972.466 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-11T17:52:52.466Z,1712857972.466 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-04-11T17:52:52.467Z,1712857972.467 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-04-11T17:52:52.467Z,1712857972.467 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-04-11T17:52:52.824Z,1712857972.824 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-11T17:52:54.338Z,1712857974.338 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005049 2024-04-11T17:52:57.674Z,1712857977.674 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-11T17:51:57.4Z 2024-04-11T17:52:57.674Z,1712857977.674 [Startup:StartupSatComms:A] Stopped 2024-04-11T17:52:57.675Z,1712857977.675 [Startup:StartupSatComms:B] Running Loop=1 2024-04-11T17:52:58.161Z,1712857978.161 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-11T17:53:04.624Z,1712857984.624 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20240411T171327/Courier0010.lzma 2024-04-11T17:53:05.626Z,1712857985.626 [DataOverHttps](INFO): Moved sent file to Logs/20240411T171327/Courier0010.lzma.bak 2024-04-11T17:53:05.626Z,1712857985.626 [DataOverHttps](INFO): SBD MOMSN=19400075 2024-04-11T17:53:20.358Z,1712858000.358 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240411T171327/Courier0013.lzma 2024-04-11T17:53:21.349Z,1712858001.349 [DataOverHttps](INFO): Moved sent file to Logs/20240411T171327/Courier0013.lzma.bak 2024-04-11T17:53:21.349Z,1712858001.349 [DataOverHttps](INFO): SBD MOMSN=19400078 2024-04-11T17:53:37.058Z,1712858017.058 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240411T174406/Courier0000.lzma 2024-04-11T17:53:38.061Z,1712858018.061 [DataOverHttps](INFO): Moved sent file to Logs/20240411T174406/Courier0000.lzma.bak 2024-04-11T17:53:38.061Z,1712858018.061 [DataOverHttps](INFO): SBD MOMSN=19400080 2024-04-11T17:53:53.815Z,1712858033.815 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240411T174941/Courier0000.lzma 2024-04-11T17:53:54.817Z,1712858034.817 [DataOverHttps](INFO): Moved sent file to Logs/20240411T174941/Courier0000.lzma.bak 2024-04-11T17:53:54.817Z,1712858034.817 [DataOverHttps](INFO): SBD MOMSN=19400083 2024-04-11T17:53:57.885Z,1712858037.885 [Startup:StartupSatComms:B](INFO): Timed out from 2024-04-11T17:52:57.7Z 2024-04-11T17:53:57.885Z,1712858037.885 [Startup:StartupSatComms:B] Stopped 2024-04-11T17:53:57.885Z,1712858037.885 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-04-11T17:53:57.885Z,1712858037.885 [Startup:StartupSatComms] Stopped 2024-04-11T17:53:57.885Z,1712858037.885 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-04-11T17:53:57.886Z,1712858037.886 [Startup](INFO): Completed Startup 2024-04-11T17:53:57.886Z,1712858037.886 [MissionManager](INFO): Startup is completed. 2024-04-11T17:53:57.886Z,1712858037.886 [MissionManager](INFO): Uninitializing Mission Startup 2024-04-11T17:53:57.887Z,1712858037.887 [Startup] Stopped 2024-04-11T17:53:57.887Z,1712858037.887 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-04-11T17:53:57.891Z,1712858037.891 [Startup:A.GoToSurface] Stopped 2024-04-11T17:53:57.891Z,1712858037.891 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-11T17:53:58.290Z,1712858038.290 [MissionManager](IMPORTANT): Started mission Default 2024-04-11T17:53:58.290Z,1712858038.290 [Default] Running Loop=1 2024-04-11T17:53:58.290Z,1712858038.290 [Default](DEBUG): Aggregate::initialize Default 2024-04-11T17:53:58.290Z,1712858038.290 [Default:B.GoToSurface] Running Loop=1 2024-04-11T17:53:58.290Z,1712858038.290 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-11T17:53:58.290Z,1712858038.290 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-11T17:53:58.291Z,1712858038.291 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-11T17:53:58.295Z,1712858038.295 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-11T17:53:58.295Z,1712858038.295 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-11T17:53:58.296Z,1712858038.296 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-11T17:53:58.296Z,1712858038.296 [Default:A.Wait] Running Loop=1 2024-04-11T17:53:58.296Z,1712858038.296 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-04-11T17:54:10.496Z,1712858050.496 [DataOverHttps](INFO): Sending 407 bytes from file Logs/20240411T171327/Express0011.lzma 2024-04-11T17:54:11.497Z,1712858051.497 [DataOverHttps](INFO): Moved sent file to Logs/20240411T171327/Express0011.lzma.bak 2024-04-11T17:54:11.497Z,1712858051.497 [DataOverHttps](INFO): SBD MOMSN=19400086 2024-04-11T17:54:11.626Z,1712858051.626 [Default:A.Wait](INFO): Done Waiting. 2024-04-11T17:54:11.627Z,1712858051.627 [Default:A.Wait] Stopped 2024-04-11T17:54:11.627Z,1712858051.627 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-04-11T17:54:12.021Z,1712858052.021 [Default:CheckIn] Running Loop=1 2024-04-11T17:54:12.021Z,1712858052.021 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-11T17:54:12.021Z,1712858052.021 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-11T17:54:12.415Z,1712858052.415 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-04-11T17:55:30.783Z,1712858130.783 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:55:34.421Z,1712858134.421 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-11T17:55:45.720Z,1712858145.720 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:55:53.004Z,1712858153.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-04-11T17:55:53.004Z,1712858153.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-11T17:55:53.005Z,1712858153.005 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2024-04-11T17:55:53.006Z,1712858153.006 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-11T17:55:53.007Z,1712858153.007 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-04-11T17:55:53.007Z,1712858153.007 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-04-11T17:55:53.017Z,1712858153.017 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-11T17:55:53.017Z,1712858153.017 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-11T17:55:53.018Z,1712858153.018 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-04-11T17:55:53.469Z,1712858153.469 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-11T17:55:53.469Z,1712858153.469 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-04-11T17:55:53.470Z,1712858153.470 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-11T17:55:53.470Z,1712858153.470 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2024-04-11T17:55:53.470Z,1712858153.470 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-04-11T17:55:53.471Z,1712858153.471 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-04-11T17:55:53.836Z,1712858153.836 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-11T17:56:00.670Z,1712858160.670 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:56:15.621Z,1712858175.621 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:56:19.925Z,1712858179.925 [CommandExec](IMPORTANT): got command show variable thrusterHE 2024-04-11T17:56:19.977Z,1712858179.977 [CommandExec](IMPORTANT): ThrusterHE.loadAtStartup (bool) 2024-04-11T17:56:19.977Z,1712858179.977 [CommandExec](IMPORTANT): ThrusterHE.simulateHardware (bool) 2024-04-11T17:56:19.978Z,1712858179.978 [CommandExec](IMPORTANT): ThrusterHE.deviation (count) 2024-04-11T17:56:19.978Z,1712858179.978 [CommandExec](IMPORTANT): ThrusterHE.allowableBadVelocity (count) 2024-04-11T17:56:19.978Z,1712858179.978 [CommandExec](IMPORTANT): ThrusterHE.ratedSpeed (count) 2024-04-11T17:56:19.979Z,1712858179.979 [CommandExec](IMPORTANT): ThrusterHE.powerOnTimeout (second) 2024-04-11T17:56:20.064Z,1712858180.064 [CommandExec](IMPORTANT): ThrusterHE.loadControl (none) 2024-04-11T17:56:20.064Z,1712858180.064 [CommandExec](IMPORTANT): ThrusterHE.uart (none) 2024-04-11T17:56:20.064Z,1712858180.064 [CommandExec](IMPORTANT): ThrusterHE.baud (bit_per_second) 2024-04-11T17:56:20.254Z,1712858180.254 [CommandExec](IMPORTANT): ThrusterHE.enableBroadcast (bool) 2024-04-11T17:56:20.255Z,1712858180.255 [CommandExec](IMPORTANT): ThrusterHE.component_voltage (volt) 2024-04-11T17:56:20.256Z,1712858180.256 [CommandExec](IMPORTANT): ThrusterHE.component_avgVoltage (volt) 2024-04-11T17:56:20.256Z,1712858180.256 [CommandExec](IMPORTANT): ThrusterHE.component_current (milliampere) 2024-04-11T17:56:20.257Z,1712858180.257 [CommandExec](IMPORTANT): ThrusterHE.component_avgCurrent (milliampere) 2024-04-11T17:56:20.257Z,1712858180.257 [CommandExec](IMPORTANT): ThrusterHE.platform_propeller_rotation_rate (radian_per_second) 2024-04-11T17:56:31.025Z,1712858191.025 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:56:37.281Z,1712858197.281 [CommandExec](IMPORTANT): got command report mod ThrusterHE.component_current 2024-04-11T17:56:37.282Z,1712858197.282 [CommandExec](IMPORTANT): got command report mod ThrusterHE.component_avgCurrent 2024-04-11T17:56:37.466Z,1712858197.466 [Reporter](INFO): ThrusterHE.component_current 0.000000 mA 2024-04-11T17:56:37.468Z,1712858197.468 [Reporter](INFO): ThrusterHE.component_avgCurrent 0.000000 mA 2024-04-11T17:56:46.724Z,1712858206.724 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:56:48.261Z,1712858208.261 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 600 revolution_per_minute 2024-04-11T17:56:48.265Z,1712858208.265 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2024-04-11T17:56:48.397Z,1712858208.397 [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,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-04-11T17:56:52.841Z,1712858212.841 [Reporter](INFO): ThrusterHE.component_current 22.020000 mA 2024-04-11T17:56:52.841Z,1712858212.841 [Reporter](INFO): ThrusterHE.component_avgCurrent 14.680000 mA 2024-04-11T17:56:55.388Z,1712858215.388 [Reporter](INFO): ThrusterHE.component_current 231.210007 mA 2024-04-11T17:56:55.389Z,1712858215.389 [Reporter](INFO): ThrusterHE.component_avgCurrent 565.179993 mA 2024-04-11T17:56:57.764Z,1712858217.764 [Reporter](INFO): ThrusterHE.component_current 168.820007 mA 2024-04-11T17:56:57.765Z,1712858217.765 [Reporter](INFO): ThrusterHE.component_avgCurrent 198.180008 mA 2024-04-11T17:57:00.172Z,1712858220.172 [Reporter](INFO): ThrusterHE.component_current 201.850006 mA 2024-04-11T17:57:00.173Z,1712858220.173 [Reporter](INFO): ThrusterHE.component_avgCurrent 286.260010 mA 2024-04-11T17:57:01.676Z,1712858221.676 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:57:02.535Z,1712858222.535 [Reporter](INFO): ThrusterHE.component_current 253.230011 mA 2024-04-11T17:57:02.536Z,1712858222.536 [Reporter](INFO): ThrusterHE.component_avgCurrent 231.210007 mA 2024-04-11T17:57:05.016Z,1712858225.016 [Reporter](INFO): ThrusterHE.component_current 187.169998 mA 2024-04-11T17:57:05.017Z,1712858225.017 [Reporter](INFO): ThrusterHE.component_avgCurrent 220.200012 mA 2024-04-11T17:57:07.468Z,1712858227.468 [Reporter](INFO): ThrusterHE.component_current 289.929993 mA 2024-04-11T17:57:07.469Z,1712858227.469 [Reporter](INFO): ThrusterHE.component_avgCurrent 286.260010 mA 2024-04-11T17:57:09.489Z,1712858229.489 [CommandExec](IMPORTANT): got command maintain clear 2024-04-11T17:57:09.505Z,1712858229.505 [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-04-11T17:57:09.892Z,1712858229.892 [Reporter](INFO): ThrusterHE.component_current 344.980011 mA 2024-04-11T17:57:09.893Z,1712858229.893 [Reporter](INFO): ThrusterHE.component_avgCurrent 322.960022 mA 2024-04-11T17:57:10.348Z,1712858230.348 [Reporter](INFO): ThrusterHE.component_current 0.000000 mA 2024-04-11T17:57:10.349Z,1712858230.349 [Reporter](INFO): ThrusterHE.component_avgCurrent 0.000000 mA 2024-04-11T17:57:17.428Z,1712858237.428 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:57:25.008Z,1712858245.008 [CommandExec](IMPORTANT): got command quit 2024-04-11T17:57:26.011Z,1712858246.011 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-04-11T17:57:26.011Z,1712858246.011 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:26.011Z,1712858246.011 [CommandExec](INFO): Uninitializing the command executive. 2024-04-11T17:57:26.012Z,1712858246.012 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-11T17:57:26.012Z,1712858246.012 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:26.027Z,1712858246.027 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-11T17:57:26.027Z,1712858246.027 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-11T17:57:26.027Z,1712858246.027 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:26.028Z,1712858246.028 [NavChartDb](INFO): Join timeout helper Thread ID is 1005 2024-04-11T17:57:26.179Z,1712858246.179 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:26.180Z,1712858246.180 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:26.191Z,1712858246.191 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-11T17:57:26.192Z,1712858246.192 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:26.194Z,1712858246.194 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1006 2024-04-11T17:57:26.567Z,1712858246.567 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:26.567Z,1712858246.567 [WetLabsBB2FL](INFO): Powering down 2024-04-11T17:57:26.568Z,1712858246.568 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:26.571Z,1712858246.571 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-11T17:57:26.571Z,1712858246.571 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:26.571Z,1712858246.571 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1007 2024-04-11T17:57:27.239Z,1712858247.239 [CTD_Seabird](INFO): Powering down 2024-04-11T17:57:27.251Z,1712858247.251 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:27.251Z,1712858247.251 [CTD_Seabird](INFO): Powering down 2024-04-11T17:57:27.263Z,1712858247.263 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:27.267Z,1712858247.267 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-04-11T17:57:27.267Z,1712858247.267 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:27.267Z,1712858247.267 [DAT](INFO): Join timeout helper Thread ID is 1008 2024-04-11T17:57:27.431Z,1712858247.431 [DAT](INFO): Powering down 2024-04-11T17:57:27.504Z,1712858247.504 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:27.504Z,1712858247.504 [DAT](INFO): Powering down 2024-04-11T17:57:27.505Z,1712858247.505 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:27.519Z,1712858247.519 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-04-11T17:57:27.519Z,1712858247.519 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:27.519Z,1712858247.519 [Radio_Surface](INFO): Join timeout helper Thread ID is 1009 2024-04-11T17:57:27.687Z,1712858247.687 [Radio_Surface](INFO): Powering down 2024-04-11T17:57:27.688Z,1712858247.688 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:27.688Z,1712858247.688 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:27.704Z,1712858247.704 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-04-11T17:57:27.705Z,1712858247.705 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:27.705Z,1712858247.705 [Onboard](INFO): Join timeout helper Thread ID is 1010 2024-04-11T17:57:28.075Z,1712858248.075 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-04-11T17:57:32.611Z,1712858252.611 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:32.611Z,1712858252.611 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:32.628Z,1712858252.628 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-04-11T17:57:32.628Z,1712858252.628 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:32.628Z,1712858252.628 [DataOverHttps](INFO): Join timeout helper Thread ID is 1011 2024-04-11T17:57:32.780Z,1712858252.780 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-11T17:57:33.095Z,1712858253.095 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:33.095Z,1712858253.095 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.112Z,1712858253.112 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-04-11T17:57:33.112Z,1712858253.112 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.112Z,1712858253.112 [BackseatComponent](INFO): Join timeout helper Thread ID is 1012 2024-04-11T17:57:33.227Z,1712858253.227 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:33.227Z,1712858253.227 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.232Z,1712858253.232 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-04-11T17:57:33.232Z,1712858253.232 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.232Z,1712858253.232 [logger](INFO): Join timeout helper Thread ID is 1013 2024-04-11T17:57:33.271Z,1712858253.271 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:33.271Z,1712858253.271 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.289Z,1712858253.289 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-04-11T17:57:33.289Z,1712858253.289 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.289Z,1712858253.289 [CommandLine](INFO): Join timeout helper Thread ID is 1014 2024-04-11T17:57:33.339Z,1712858253.339 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:33.340Z,1712858253.340 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.348Z,1712858253.348 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-04-11T17:57:33.348Z,1712858253.348 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.348Z,1712858253.348 [CommandExec](INFO): Join timeout helper Thread ID is 1015 2024-04-11T17:57:33.350Z,1712858253.350 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-04-11T17:57:33.350Z,1712858253.350 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.350Z,1712858253.350 [controlThread](INFO): Join timeout helper Thread ID is 1016 2024-04-11T17:57:33.579Z,1712858253.579 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-11T17:57:33.579Z,1712858253.579 [controlThread](DEBUG): Uninitializing ControlThread 2024-04-11T17:57:33.580Z,1712858253.580 [AHRS_M2](INFO): Powering down 2024-04-11T17:57:33.652Z,1712858253.652 [NAL9602](INFO): Powering down 2024-04-11T17:57:33.654Z,1712858253.654 [Sonardyne_Nano](INFO): Powering down 2024-04-11T17:57:33.771Z,1712858253.771 [Waterlinked](INFO): Powering down 2024-04-11T17:57:33.774Z,1712858253.774 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-04-11T17:57:33.775Z,1712858253.775 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-04-11T17:57:33.775Z,1712858253.775 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-04-11T17:57:33.776Z,1712858253.776 [MissionManager](INFO): Uninitializing Mission Default 2024-04-11T17:57:33.776Z,1712858253.776 [Default] Stopped 2024-04-11T17:57:33.776Z,1712858253.776 [Default](DEBUG): Aggregate::uninitialize Default 2024-04-11T17:57:33.776Z,1712858253.776 [Default:B.GoToSurface] Stopped 2024-04-11T17:57:33.776Z,1712858253.776 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-11T17:57:33.776Z,1712858253.776 [Default:CheckIn] Stopped 2024-04-11T17:57:33.776Z,1712858253.776 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-11T17:57:33.777Z,1712858253.777 [Default:CheckIn:Read_GPS] Stopped 2024-04-11T17:57:33.779Z,1712858253.779 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-04-11T17:57:33.780Z,1712858253.780 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-04-11T17:57:33.780Z,1712858253.780 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-04-11T17:57:33.780Z,1712858253.780 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-04-11T17:57:33.780Z,1712858253.780 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-04-11T17:57:33.781Z,1712858253.781 [BuoyancyServo](INFO): Powering down 2024-04-11T17:57:33.795Z,1712858253.795 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-04-11T17:57:33.795Z,1712858253.795 [ElevatorServo](INFO): Powering down 2024-04-11T17:57:33.796Z,1712858253.796 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-04-11T17:57:33.796Z,1712858253.796 [MassServo](INFO): Powering down 2024-04-11T17:57:33.797Z,1712858253.797 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-04-11T17:57:33.797Z,1712858253.797 [RudderServo](INFO): Powering down 2024-04-11T17:57:33.798Z,1712858253.798 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-04-11T17:57:33.798Z,1712858253.798 [ThrusterHE](INFO): Powering down 2024-04-11T17:57:33.799Z,1712858253.799 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-04-11T17:57:33.799Z,1712858253.799 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-04-11T17:57:33.799Z,1712858253.799 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-04-11T17:57:33.800Z,1712858253.800 [CBIT](DEBUG): Powering off loads. 2024-04-11T17:57:33.811Z,1712858253.811 [CBIT](DEBUG): Disabling WDT. 2024-04-11T17:57:33.823Z,1712858253.823 [CBIT](DEBUG): Opening all GF detection circuits. 2024-04-11T17:57:33.824Z,1712858253.824 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.848Z,1712858253.848 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.854Z,1712858253.854 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.856Z,1712858253.856 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.862Z,1712858253.862 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.905Z,1712858253.905 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.992Z,1712858253.992 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:33.998Z,1712858253.998 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:34.006Z,1712858254.006 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-11T17:57:34.055Z,1712858254.055 [logger ThreadHandler](INFO): Thread cancelled.