2024-05-14T17:26:12.519Z,1715707572.519 [Supervisor](DEBUG): Initializing supervisor. 2024-05-14T17:26:12.523Z,1715707572.523 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-05-14T17:26:12.523Z,1715707572.523 [SyncHandler](INFO): Protected caller Thread ID is 6645 2024-05-14T17:26:12.524Z,1715707572.524 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-05-14T17:26:12.525Z,1715707572.525 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-05-14T17:26:12.525Z,1715707572.525 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6646 2024-05-14T17:26:12.529Z,1715707572.529 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-05-14T17:26:12.547Z,1715707572.547 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-05-14T17:26:12.548Z,1715707572.548 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-05-14T17:26:12.548Z,1715707572.548 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6647 2024-05-14T17:26:12.552Z,1715707572.552 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-05-14T17:26:12.553Z,1715707572.553 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-05-14T17:26:12.554Z,1715707572.554 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6648 2024-05-14T17:26:12.556Z,1715707572.556 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-05-14T17:26:12.557Z,1715707572.557 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-05-14T17:26:12.557Z,1715707572.557 [logger ThreadHandler](INFO): Protected caller Thread ID is 6649 2024-05-14T17:26:12.561Z,1715707572.561 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-05-14T17:26:12.561Z,1715707572.561 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-05-14T17:26:12.565Z,1715707572.565 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-05-14T17:26:12.738Z,1715707572.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-05-14T17:26:12.739Z,1715707572.739 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-05-14T17:26:13.601Z,1715707573.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-05-14T17:26:13.602Z,1715707573.602 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-05-14T17:26:13.851Z,1715707573.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-05-14T17:26:13.853Z,1715707573.853 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-05-14T17:26:13.947Z,1715707573.947 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-05-14T17:26:14.094Z,1715707574.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-05-14T17:26:14.096Z,1715707574.096 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-05-14T17:26:14.190Z,1715707574.190 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-05-14T17:26:14.312Z,1715707574.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-05-14T17:26:14.314Z,1715707574.314 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-05-14T17:26:14.738Z,1715707574.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-05-14T17:26:14.740Z,1715707574.740 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-05-14T17:26:15.301Z,1715707575.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-05-14T17:26:15.302Z,1715707575.302 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-05-14T17:26:15.540Z,1715707575.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-05-14T17:26:15.542Z,1715707575.542 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-05-14T17:26:15.744Z,1715707575.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-05-14T17:26:15.746Z,1715707575.746 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-05-14T17:26:16.305Z,1715707576.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-05-14T17:26:16.306Z,1715707576.306 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-05-14T17:26:16.616Z,1715707576.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-05-14T17:26:16.617Z,1715707576.617 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-05-14T17:26:16.859Z,1715707576.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-05-14T17:26:16.861Z,1715707576.861 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-05-14T17:26:17.592Z,1715707577.592 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-05-14T17:26:17.593Z,1715707577.593 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-05-14T17:26:18.083Z,1715707578.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-05-14T17:26:18.085Z,1715707578.085 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2024-05-14T17:26:18.087Z,1715707578.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2024-05-14T17:26:18.185Z,1715707578.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2024-05-14T17:26:18.364Z,1715707578.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2024-05-14T17:26:18.486Z,1715707578.486 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2024-05-14T17:26:18.586Z,1715707578.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2024-05-14T17:26:18.695Z,1715707578.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2024-05-14T17:26:18.820Z,1715707578.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2024-05-14T17:26:19.096Z,1715707579.096 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-05-14T17:26:19.096Z,1715707579.096 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2024-05-14T17:26:19.205Z,1715707579.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2024-05-14T17:26:19.311Z,1715707579.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2024-05-14T17:26:19.468Z,1715707579.468 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2024-05-14T17:26:19.581Z,1715707579.581 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2024-05-14T17:26:19.585Z,1715707579.585 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-05-14T17:26:19.724Z,1715707579.724 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-05-14T17:26:19.724Z,1715707579.724 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-05-14T17:26:19.788Z,1715707579.788 [VerticalControl](DEBUG): Construct VerticalControl. 2024-05-14T17:26:19.848Z,1715707579.848 [VerticalControl] Loaded 2024-05-14T17:26:19.849Z,1715707579.849 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-05-14T17:26:19.851Z,1715707579.851 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-05-14T17:26:19.898Z,1715707579.898 [HorizontalControl] Loaded 2024-05-14T17:26:19.898Z,1715707579.898 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-05-14T17:26:19.900Z,1715707579.900 [SpeedControl](DEBUG): Construct SpeedControl. 2024-05-14T17:26:19.903Z,1715707579.903 [SpeedControl] Loaded 2024-05-14T17:26:19.903Z,1715707579.903 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-05-14T17:26:19.906Z,1715707579.906 [LoopControl](DEBUG): Construct LoopControl. 2024-05-14T17:26:19.907Z,1715707579.907 [LoopControl] Loaded 2024-05-14T17:26:19.907Z,1715707579.907 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-05-14T17:26:19.907Z,1715707579.907 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-05-14T17:26:19.908Z,1715707579.908 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-05-14T17:26:19.974Z,1715707579.974 [DepthRateCalculator] Loaded 2024-05-14T17:26:19.974Z,1715707579.974 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-05-14T17:26:19.979Z,1715707579.979 [PitchRateCalculator] Loaded 2024-05-14T17:26:19.979Z,1715707579.979 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-05-14T17:26:19.989Z,1715707579.989 [SpeedCalculator] Loaded 2024-05-14T17:26:19.989Z,1715707579.989 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-05-14T17:26:19.993Z,1715707579.993 [YawRateCalculator] Loaded 2024-05-14T17:26:19.993Z,1715707579.993 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-05-14T17:26:20.011Z,1715707580.011 [ElevatorOffsetCalculator] Loaded 2024-05-14T17:26:20.011Z,1715707580.011 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-05-14T17:26:20.012Z,1715707580.012 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-05-14T17:26:20.013Z,1715707580.013 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-05-14T17:26:20.050Z,1715707580.050 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-05-14T17:26:20.050Z,1715707580.050 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-05-14T17:26:20.126Z,1715707580.126 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-05-14T17:26:20.127Z,1715707580.127 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-05-14T17:26:20.502Z,1715707580.502 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-05-14T17:26:20.502Z,1715707580.502 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-05-14T17:26:20.700Z,1715707580.700 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-05-14T17:26:20.700Z,1715707580.700 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-05-14T17:26:21.275Z,1715707581.275 [AHRS_M2] Loaded 2024-05-14T17:26:21.276Z,1715707581.276 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-05-14T17:26:21.311Z,1715707581.311 [BackseatComponent] Loaded 2024-05-14T17:26:21.312Z,1715707581.312 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-05-14T17:26:21.313Z,1715707581.313 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C34E0 2024-05-14T17:26:21.313Z,1715707581.313 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6732 2024-05-14T17:26:21.316Z,1715707581.316 [LcmUniversalReporter] Loaded 2024-05-14T17:26:21.316Z,1715707581.316 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-05-14T17:26:22.119Z,1715707582.119 [BPC1] Loaded 2024-05-14T17:26:22.119Z,1715707582.119 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-05-14T17:26:22.194Z,1715707582.194 [DataOverHttps] Loaded 2024-05-14T17:26:22.195Z,1715707582.195 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-05-14T17:26:22.196Z,1715707582.196 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F34E0 2024-05-14T17:26:22.196Z,1715707582.196 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6733 2024-05-14T17:26:22.217Z,1715707582.217 [Depth_Keller] Loaded 2024-05-14T17:26:22.217Z,1715707582.217 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-05-14T17:26:22.222Z,1715707582.222 [DropWeight] Loaded 2024-05-14T17:26:22.222Z,1715707582.222 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-05-14T17:26:22.284Z,1715707582.284 [NAL9602] Loaded 2024-05-14T17:26:22.284Z,1715707582.284 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-05-14T17:26:22.313Z,1715707582.313 [Onboard] Loaded 2024-05-14T17:26:22.313Z,1715707582.313 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-05-14T17:26:22.314Z,1715707582.314 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A234E0 2024-05-14T17:26:22.315Z,1715707582.315 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6734 2024-05-14T17:26:22.321Z,1715707582.321 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1 2024-05-14T17:26:22.333Z,1715707582.333 [PowerOnly] Loaded 2024-05-14T17:26:22.333Z,1715707582.333 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-05-14T17:26:22.344Z,1715707582.344 [Power24vConverter] Loaded 2024-05-14T17:26:22.345Z,1715707582.345 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-05-14T17:26:22.358Z,1715707582.358 [Radio_Surface] Loaded 2024-05-14T17:26:22.358Z,1715707582.358 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-05-14T17:26:22.359Z,1715707582.359 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0 2024-05-14T17:26:22.359Z,1715707582.359 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6735 2024-05-14T17:26:22.404Z,1715707582.404 [RDI_Pathfinder] Loaded 2024-05-14T17:26:22.405Z,1715707582.405 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2024-05-14T17:26:22.497Z,1715707582.497 [DAT] Loaded 2024-05-14T17:26:22.497Z,1715707582.497 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-05-14T17:26:22.498Z,1715707582.498 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0 2024-05-14T17:26:22.499Z,1715707582.499 [DAT ThreadHandler](INFO): Protected caller Thread ID is 6736 2024-05-14T17:26:22.499Z,1715707582.499 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-05-14T17:26:22.500Z,1715707582.500 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-05-14T17:26:22.635Z,1715707582.635 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-05-14T17:26:22.635Z,1715707582.635 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-05-14T17:26:22.648Z,1715707582.648 [NavChart] Loaded 2024-05-14T17:26:22.649Z,1715707582.649 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-05-14T17:26:22.654Z,1715707582.654 [UniversalFixResidualReporter] Loaded 2024-05-14T17:26:22.655Z,1715707582.655 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-05-14T17:26:22.655Z,1715707582.655 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-05-14T17:26:22.656Z,1715707582.656 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-05-14T17:26:23.407Z,1715707583.407 [SBIT](DEBUG): Construct Startup Built In Test. 2024-05-14T17:26:23.416Z,1715707583.416 [SBIT] Loaded 2024-05-14T17:26:23.416Z,1715707583.416 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-05-14T17:26:23.419Z,1715707583.419 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-05-14T17:26:23.433Z,1715707583.433 [IBIT] Loaded 2024-05-14T17:26:23.433Z,1715707583.433 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-05-14T17:26:23.439Z,1715707583.439 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-05-14T17:26:23.544Z,1715707583.544 [CBIT] Loaded 2024-05-14T17:26:23.545Z,1715707583.545 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-05-14T17:26:23.545Z,1715707583.545 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-05-14T17:26:23.545Z,1715707583.545 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-05-14T17:26:23.698Z,1715707583.698 [BuoyancyServo] Loaded 2024-05-14T17:26:23.698Z,1715707583.698 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-05-14T17:26:23.723Z,1715707583.723 [ElevatorServo] Loaded 2024-05-14T17:26:23.723Z,1715707583.723 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-05-14T17:26:23.744Z,1715707583.744 [MassServo] Loaded 2024-05-14T17:26:23.744Z,1715707583.744 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-05-14T17:26:23.763Z,1715707583.763 [RudderServo] Loaded 2024-05-14T17:26:23.763Z,1715707583.763 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-05-14T17:26:23.780Z,1715707583.780 [ThrusterServo] Loaded 2024-05-14T17:26:23.781Z,1715707583.781 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2024-05-14T17:26:23.781Z,1715707583.781 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-05-14T17:26:23.782Z,1715707583.782 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-05-14T17:26:23.795Z,1715707583.795 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-05-14T17:26:23.796Z,1715707583.796 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-05-14T17:26:24.011Z,1715707584.011 [CTD_Seabird] Loaded 2024-05-14T17:26:24.011Z,1715707584.011 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-05-14T17:26:24.013Z,1715707584.013 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0 2024-05-14T17:26:24.013Z,1715707584.013 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6737 2024-05-14T17:26:24.033Z,1715707584.033 [PAR_Licor] Loaded 2024-05-14T17:26:24.033Z,1715707584.033 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-05-14T17:26:24.077Z,1715707584.077 [WetLabsBB2FL] Loaded 2024-05-14T17:26:24.077Z,1715707584.077 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-05-14T17:26:24.078Z,1715707584.078 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0 2024-05-14T17:26:24.079Z,1715707584.079 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6738 2024-05-14T17:26:24.098Z,1715707584.098 [WetLabsUBAT] Loaded 2024-05-14T17:26:24.098Z,1715707584.098 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2024-05-14T17:26:24.099Z,1715707584.099 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0 2024-05-14T17:26:24.100Z,1715707584.100 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 6739 2024-05-14T17:26:24.100Z,1715707584.100 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-05-14T17:26:24.106Z,1715707584.106 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-05-14T17:26:24.109Z,1715707584.109 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-05-14T17:26:24.121Z,1715707584.121 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-05-14T17:26:24.122Z,1715707584.122 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C674E0 2024-05-14T17:26:24.122Z,1715707584.122 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6740 2024-05-14T17:26:24.132Z,1715707584.132 [Supervisor](INFO): Main Thread ID is 6644 2024-05-14T17:26:24.132Z,1715707584.132 [Supervisor](DEBUG): Running supervisor. 2024-05-14T17:26:24.133Z,1715707584.133 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6741 2024-05-14T17:26:24.133Z,1715707584.133 [CommandExec](INFO): Initializing the command executive. 2024-05-14T17:26:24.135Z,1715707584.135 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6742 2024-05-14T17:26:24.139Z,1715707584.139 [controlThread ThreadHandler](INFO): Handler Thread ID is 6743 2024-05-14T17:26:24.139Z,1715707584.139 [controlThread](DEBUG): Initializing ControlThread 2024-05-14T17:26:24.140Z,1715707584.140 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-05-14T17:26:24.142Z,1715707584.142 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-05-14T17:26:24.142Z,1715707584.142 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-05-14T17:26:24.143Z,1715707584.143 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-05-14T17:26:24.143Z,1715707584.143 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-05-14T17:26:24.143Z,1715707584.143 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-05-14T17:26:24.144Z,1715707584.144 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-05-14T17:26:24.144Z,1715707584.144 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-05-14T17:26:24.145Z,1715707584.145 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-05-14T17:26:24.151Z,1715707584.151 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-05-14T17:26:24.151Z,1715707584.151 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-05-14T17:26:24.152Z,1715707584.152 [SBIT](INFO): Initialize SBIT Component. 2024-05-14T17:26:24.153Z,1715707584.153 [SBIT](IMPORTANT): git: 2024-05-07 2024-05-14T17:26:24.153Z,1715707584.153 [SBIT](INFO): git hash: caae5b805170f9c680da20e21004b1da522ccce4 2024-05-14T17:26:24.153Z,1715707584.153 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-05-14T17:26:24.154Z,1715707584.154 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-05-14T17:26:24.155Z,1715707584.155 [SBIT](INFO): Beginning SBIT in 46.000000 seconds. 2024-05-14T17:26:24.156Z,1715707584.156 [IBIT](INFO): Initialize IBIT Component. 2024-05-14T17:26:24.157Z,1715707584.157 [CBIT](DEBUG): Initialize CBIT Component. 2024-05-14T17:26:24.158Z,1715707584.158 [logger ThreadHandler](INFO): Handler Thread ID is 6744 2024-05-14T17:26:24.168Z,1715707584.168 [CBIT](DEBUG): Initialized mux pins. 2024-05-14T17:26:24.168Z,1715707584.168 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2024-05-14T17:26:24.168Z,1715707584.168 [CBIT](DEBUG): Initializing the watchdog timer. 2024-05-14T17:26:24.177Z,1715707584.177 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6745 2024-05-14T17:26:24.189Z,1715707584.189 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6746 2024-05-14T17:26:24.190Z,1715707584.190 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-05-14T17:26:24.192Z,1715707584.192 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-05-14T17:26:24.193Z,1715707584.193 [CBIT](DEBUG): Initializing heartbeat. 2024-05-14T17:26:24.201Z,1715707584.201 [Onboard ThreadHandler](INFO): Handler Thread ID is 6747 2024-05-14T17:26:24.218Z,1715707584.218 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6748 2024-05-14T17:26:24.248Z,1715707584.248 [DAT ThreadHandler](INFO): Handler Thread ID is 6749 2024-05-14T17:26:24.249Z,1715707584.249 [DAT](INFO): Powering up 2024-05-14T17:26:24.249Z,1715707584.249 [DAT](DEBUG): Initializing DAT. 2024-05-14T17:26:24.257Z,1715707584.257 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6751 2024-05-14T17:26:24.257Z,1715707584.257 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-05-14T17:26:24.261Z,1715707584.261 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6753 2024-05-14T17:26:24.264Z,1715707584.264 [CBIT](DEBUG): Deactivating GF circuits. 2024-05-14T17:26:24.264Z,1715707584.264 [CBIT](DEBUG): Deactivating emergency mode. 2024-05-14T17:26:24.265Z,1715707584.265 [WetLabsBB2FL](INFO): Powering up 2024-05-14T17:26:24.266Z,1715707584.266 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 6755 2024-05-14T17:26:24.269Z,1715707584.269 [WetLabsUBAT](INFO): Powering up 2024-05-14T17:26:24.270Z,1715707584.270 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6757 2024-05-14T17:26:24.279Z,1715707584.279 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-05-14T17:26:24.279Z,1715707584.279 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-05-14T17:26:24.279Z,1715707584.279 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-05-14T17:26:24.279Z,1715707584.279 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-05-14T17:26:24.279Z,1715707584.279 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-05-14T17:26:24.279Z,1715707584.279 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-05-14T17:26:24.280Z,1715707584.280 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-05-14T17:26:24.280Z,1715707584.280 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-05-14T17:26:24.304Z,1715707584.304 [CBIT](DEBUG): Backplane powered. 2024-05-14T17:26:24.309Z,1715707584.309 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-05-14T17:26:24.310Z,1715707584.310 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-05-14T17:26:24.310Z,1715707584.310 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-05-14T17:26:24.315Z,1715707584.315 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-05-14T17:26:24.385Z,1715707584.385 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-05-14T17:26:24.409Z,1715707584.409 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-14T17:26:24.429Z,1715707584.429 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission 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-05-14T17:26:24.430Z,1715707584.430 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-05-14T17:26:24.430Z,1715707584.430 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-05-14T17:26:24.445Z,1715707584.445 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-05-14T17:26:24.624Z,1715707584.624 [Radio_Surface](INFO): Powering up 2024-05-14T17:26:24.768Z,1715707584.768 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-05-14T17:26:24.797Z,1715707584.797 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-05-14T17:26:24.799Z,1715707584.799 [Default:A.Wait](DEBUG): Construct Wait. 2024-05-14T17:26:24.809Z,1715707584.809 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-14T17:26:24.829Z,1715707584.829 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-05-14T17:26:24.852Z,1715707584.852 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-05-14T17:26:24.865Z,1715707584.865 [Default:E.Execute](DEBUG): Construct Execute. 2024-05-14T17:26:24.868Z,1715707584.868 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission 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-05-14T17:26:24.897Z,1715707584.897 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-05-14T17:26:24.912Z,1715707584.912 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-05-14T17:26:24.978Z,1715707584.978 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m 2024-05-14T17:26:24.979Z,1715707584.979 [PowerOnly](INFO): Powering up loadControl 2024-05-14T17:26:24.997Z,1715707584.997 [Power24vConverter](INFO): Powering up. 2024-05-14T17:26:24.997Z,1715707584.997 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-05-14T17:26:25.010Z,1715707585.010 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-05-14T17:26:25.037Z,1715707585.037 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-05-14T17:26:25.044Z,1715707585.044 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-05-14T17:26:25.046Z,1715707585.046 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-05-14T17:26:25.052Z,1715707585.052 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-05-14T17:26:25.053Z,1715707585.053 [MassServo](DEBUG): Initializing EZServoServo. 2024-05-14T17:26:25.060Z,1715707585.060 [MassServo](DEBUG): Initializing MassServo. 2024-05-14T17:26:25.061Z,1715707585.061 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-14T17:26:25.069Z,1715707585.069 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-14T17:26:25.070Z,1715707585.070 [ThrusterServo](DEBUG): Initializing EZServoServo. 2024-05-14T17:26:25.076Z,1715707585.076 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2024-05-14T17:26:25.346Z,1715707585.346 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-05-14T17:26:25.346Z,1715707585.346 [DropWeight] Hardware Fault, FailCount= 1 2024-05-14T17:26:25.346Z,1715707585.346 [DropWeight](ERROR): Hardware Fault 2024-05-14T17:26:25.366Z,1715707585.366 [CommandExec](FAULT): Scheduling is paused 2024-05-14T17:26:25.367Z,1715707585.367 [CBIT](INFO): Critical error at 20240514T172625 2024-05-14T17:26:25.367Z,1715707585.367 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-05-14T17:26:25.378Z,1715707585.378 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-05-14T17:26:25.378Z,1715707585.378 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-05-14T17:26:25.734Z,1715707585.734 [CBIT](INFO): Critical error at 20240514T172625 2024-05-14T17:26:26.876Z,1715707586.876 [WetLabsBB2FL](INFO): Powering down 2024-05-14T17:26:37.857Z,1715707597.857 [DAT](INFO): DAT read: 2024-05-14T17:26:37.858Z,1715707597.858 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-05-14T17:26:38.592Z,1715707598.592 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006965 2024-05-14T17:26:39.621Z,1715707599.621 [DAT](INFO): DAT read: MF Frequency Band 2024-05-14T17:26:39.622Z,1715707599.622 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2024-05-14T17:26:39.623Z,1715707599.623 [DAT](INFO): DAT read: May 12 2024 17:47:14 2024-05-14T17:26:40.629Z,1715707600.629 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-05-14T17:26:40.630Z,1715707600.630 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-05-14T17:26:40.631Z,1715707600.631 [DAT](INFO): commRate: 800 2024-05-14T17:26:42.696Z,1715707602.696 [DAT](INFO): entering command mode 2024-05-14T17:26:42.897Z,1715707602.897 [DAT](INFO): DAT read: 2024-05-14T17:26:42.897Z,1715707602.897 [DAT](INFO): DAT read: user:1> 2024-05-14T17:26:42.898Z,1715707602.898 [DAT](INFO): setting verbose to 3 2024-05-14T17:26:43.149Z,1715707603.149 [DAT](INFO): DAT read: user:1> 2024-05-14T17:26:43.150Z,1715707603.150 [DAT](INFO): DAT read: Verbose | 3 2024-05-14T17:26:43.150Z,1715707603.150 [DAT](INFO): set verbose to 3 2024-05-14T17:26:43.150Z,1715707603.150 [DAT](INFO): setting DatVerbose to 27440 2024-05-14T17:26:43.401Z,1715707603.401 [DAT](INFO): DAT read: user:2> 2024-05-14T17:26:43.402Z,1715707603.402 [DAT](INFO): DAT read: DatVerbose | 27440 2024-05-14T17:26:43.402Z,1715707603.402 [DAT](INFO): set DatVerbose to 27440 2024-05-14T17:26:43.402Z,1715707603.402 [DAT](INFO): setting transmit power to 8 2024-05-14T17:26:43.653Z,1715707603.653 [DAT](INFO): DAT read: user:3> 2024-05-14T17:26:43.654Z,1715707603.654 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-05-14T17:26:43.654Z,1715707603.654 [DAT](INFO): set transmit power to 8 2024-05-14T17:26:43.654Z,1715707603.654 [DAT](INFO): setting local address to 10 2024-05-14T17:26:43.905Z,1715707603.905 [DAT](INFO): DAT read: user:4> 2024-05-14T17:26:43.905Z,1715707603.905 [DAT](INFO): DAT read: LocalAddr | 10 2024-05-14T17:26:43.906Z,1715707603.906 [DAT](INFO): set local address to 10 2024-05-14T17:26:43.907Z,1715707603.907 [DAT](INFO): Setting time to: 17:26:43 And date to:5/14/2024 2024-05-14T17:26:44.157Z,1715707604.157 [DAT](INFO): DAT read: user:5> 2024-05-14T17:26:44.158Z,1715707604.158 [DAT](INFO): DAT read: Tue May 14, 2024 17:26:43 2024-05-14T17:26:44.158Z,1715707604.158 [DAT](INFO): Local DAT time set to Tue May 14, 2024 17:26:43 2024-05-14T17:26:52.382Z,1715707612.382 [NAL9602](INFO): Powering up NAL9602 2024-05-14T17:26:54.448Z,1715707614.448 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup. 2024-05-14T17:26:54.448Z,1715707614.448 [WetLabsUBAT] Communications Fault, FailCount= 1 2024-05-14T17:26:54.448Z,1715707614.448 [WetLabsUBAT](ERROR): Communications Fault 2024-05-14T17:26:54.505Z,1715707614.505 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT 2024-05-14T17:26:54.948Z,1715707614.948 [WetLabsUBAT](INFO): Powering down 2024-05-14T17:26:56.076Z,1715707616.076 [CBIT](INFO): Clearing failed state for component WetLabsUBAT 2024-05-14T17:26:56.076Z,1715707616.076 [WetLabsUBAT] No Fault, FailCount= 1 2024-05-14T17:26:56.161Z,1715707616.161 [WetLabsUBAT](INFO): Powering up 2024-05-14T17:27:03.290Z,1715707623.290 [NAL9602](INFO): NAL9602 initialized 2024-05-14T17:27:10.659Z,1715707630.659 [SBIT](IMPORTANT): Beginning Startup BIT 2024-05-14T17:27:10.708Z,1715707630.708 [CBIT](IMPORTANT): Beginning ground fault scan 2024-05-14T17:27:21.562Z,1715707641.562 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.008767 CHAN A1 (24V): 0.001478 CHAN A2 (12V): -0.006426 CHAN A3 (5V): -0.001978 CHAN B0 (3.3V): 0.000120 CHAN B1 (3.15aV): -0.000161 CHAN B2 (3.15bV): 0.000366 CHAN B3 (GND): 0.001838 OPEN: 0.005100 Full Scale: +/- 1 mA 2024-05-14T17:27:26.272Z,1715707646.272 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup. 2024-05-14T17:27:26.272Z,1715707646.272 [WetLabsUBAT] Communications Fault, FailCount= 2 2024-05-14T17:27:26.272Z,1715707646.272 [WetLabsUBAT](ERROR): Communications Fault 2024-05-14T17:27:26.442Z,1715707646.442 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT 2024-05-14T17:27:26.772Z,1715707646.772 [WetLabsUBAT](INFO): Powering down 2024-05-14T17:27:27.644Z,1715707647.644 [CBIT](INFO): Clearing failed state for component WetLabsUBAT 2024-05-14T17:27:27.645Z,1715707647.645 [WetLabsUBAT] No Fault, FailCount= 2 2024-05-14T17:27:27.986Z,1715707647.986 [WetLabsUBAT](INFO): Powering up 2024-05-14T17:27:38.589Z,1715707658.589 [CommandExec](IMPORTANT): got command docking arm 2024-05-14T17:27:38.590Z,1715707658.590 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-14T17:27:52.597Z,1715707672.597 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-05-14T17:27:58.148Z,1715707678.148 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup. 2024-05-14T17:27:58.148Z,1715707678.148 [WetLabsUBAT] Communications Fault, FailCount= 3 2024-05-14T17:27:58.148Z,1715707678.148 [WetLabsUBAT](ERROR): Communications Fault 2024-05-14T17:27:58.389Z,1715707678.389 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT 2024-05-14T17:27:58.648Z,1715707678.648 [WetLabsUBAT](INFO): Powering down 2024-05-14T17:27:59.473Z,1715707679.473 [CBIT](INFO): Clearing failed state for component WetLabsUBAT 2024-05-14T17:27:59.473Z,1715707679.473 [WetLabsUBAT] No Fault, FailCount= 3 2024-05-14T17:27:59.873Z,1715707679.873 [WetLabsUBAT](INFO): Powering up 2024-05-14T17:28:04.845Z,1715707684.845 [SBIT](IMPORTANT): SBIT PASSED 2024-05-14T17:28:04.846Z,1715707684.846 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2024-05-14T17:28:05.131Z,1715707685.131 [MissionManager](IMPORTANT): Started mission Startup 2024-05-14T17:28:05.131Z,1715707685.131 [Startup] Running Loop=1 2024-05-14T17:28:05.131Z,1715707685.131 [Startup](DEBUG): Aggregate::initialize Startup 2024-05-14T17:28:05.131Z,1715707685.131 [Startup:A.GoToSurface] Running Loop=1 2024-05-14T17:28:05.131Z,1715707685.131 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-14T17:28:05.156Z,1715707685.156 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-14T17:28:05.157Z,1715707685.157 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-14T17:28:05.157Z,1715707685.157 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-14T17:28:05.157Z,1715707685.157 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-14T17:28:05.158Z,1715707685.158 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-14T17:28:05.159Z,1715707685.159 [Startup:StartupSatComms] Running Loop=1 2024-05-14T17:28:05.160Z,1715707685.160 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-05-14T17:28:05.160Z,1715707685.160 [Startup:StartupSatComms:A] Running Loop=1 2024-05-14T17:28:05.630Z,1715707685.630 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-05-14T17:28:16.621Z,1715707696.621 [CommandExec](IMPORTANT): got command docking arm 2024-05-14T17:28:16.621Z,1715707696.621 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-14T17:28:29.633Z,1715707709.633 [CommandExec](IMPORTANT): got command docking standby 2024-05-14T17:28:29.633Z,1715707709.633 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-14T17:28:30.072Z,1715707710.072 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup. 2024-05-14T17:28:30.072Z,1715707710.072 [WetLabsUBAT] Communications Fault, FailCount= 4 2024-05-14T17:28:30.072Z,1715707710.072 [WetLabsUBAT](ERROR): Communications Fault 2024-05-14T17:28:30.213Z,1715707710.213 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT 2024-05-14T17:28:30.573Z,1715707710.573 [WetLabsUBAT](INFO): Powering down 2024-05-14T17:28:31.383Z,1715707711.383 [CBIT](INFO): Clearing failed state for component WetLabsUBAT 2024-05-14T17:28:31.384Z,1715707711.384 [WetLabsUBAT] No Fault, FailCount= 4 2024-05-14T17:28:31.786Z,1715707711.786 [WetLabsUBAT](INFO): Powering up 2024-05-14T17:28:34.636Z,1715707714.636 [CommandExec](IMPORTANT): got command docking arm 2024-05-14T17:28:34.637Z,1715707714.637 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-14T17:28:40.944Z,1715707720.944 [CommandExec](IMPORTANT): got command get DockingStepper.loadAtStartup 2024-05-14T17:28:40.944Z,1715707720.944 [CommandExec](IMPORTANT): DockingStepper.loadAtStartup 0 bool 2024-05-14T17:28:51.558Z,1715707731.558 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-14T17:28:51.903Z,1715707731.903 [CommandExec](IMPORTANT): got command configSet DockingStepper.loadAtStartup 1 bool persist 2024-05-14T17:28:51.903Z,1715707731.903 [CommandExec](IMPORTANT): configSet DockingStepper.loadAtStartup requires a restart to take effect. 2024-05-14T17:28:51.928Z,1715707731.928 [CommandExec](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown. 2024-05-14T17:28:55.417Z,1715707735.417 [CommandExec](IMPORTANT): got command restart system 2024-05-14T17:28:57.608Z,1715707737.608 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-14T17:28:57.608Z,1715707737.608 [CommandExec](INFO): Uninitializing the command executive. 2024-05-14T17:28:57.608Z,1715707737.608 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-14T17:28:57.609Z,1715707737.609 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:57.728Z,1715707737.728 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-14T17:28:57.728Z,1715707737.728 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-14T17:28:57.728Z,1715707737.728 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:57.729Z,1715707737.729 [NavChartDb](INFO): Join timeout helper Thread ID is 6787 2024-05-14T17:28:57.893Z,1715707737.893 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-14T17:28:57.895Z,1715707737.895 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:57.909Z,1715707737.909 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2024-05-14T17:28:57.909Z,1715707737.909 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:57.910Z,1715707737.910 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 6788 2024-05-14T17:28:58.232Z,1715707738.232 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-14T17:28:58.328Z,1715707738.328 [WetLabsUBAT](INFO): Powering down 2024-05-14T17:28:58.329Z,1715707738.329 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:58.348Z,1715707738.348 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-05-14T17:28:58.348Z,1715707738.348 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:58.348Z,1715707738.348 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6789 2024-05-14T17:28:58.496Z,1715707738.496 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-14T17:28:58.497Z,1715707738.497 [WetLabsBB2FL](INFO): Powering down 2024-05-14T17:28:58.497Z,1715707738.497 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:58.516Z,1715707738.516 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-05-14T17:28:58.516Z,1715707738.516 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:58.516Z,1715707738.516 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6790 2024-05-14T17:28:58.936Z,1715707738.936 [CTD_Seabird](INFO): Powering down 2024-05-14T17:28:58.948Z,1715707738.948 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-14T17:28:58.948Z,1715707738.948 [CTD_Seabird](INFO): Powering down 2024-05-14T17:28:58.960Z,1715707738.960 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:58.965Z,1715707738.965 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-14T17:28:58.965Z,1715707738.965 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-14T17:28:58.966Z,1715707738.966 [DAT](INFO): Join timeout helper Thread ID is 6791 2024-05-14T17:28:59.077Z,1715707739.077 [DAT](INFO): Powering down 2024-05-14T17:28:59.149Z,1715707739.149 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-14T17:28:59.149Z,1715707739.149 [DAT](INFO): Powering down 2024-05-14T17:28:59.150Z,1715707739.150 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-14