2023-06-23T17:16:28.964Z,1687540588.964 [Supervisor](DEBUG): Initializing supervisor. 2023-06-23T17:16:28.969Z,1687540588.969 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-06-23T17:16:28.969Z,1687540588.969 [SyncHandler](INFO): Protected caller Thread ID is 8233 2023-06-23T17:16:28.970Z,1687540588.970 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-06-23T17:16:28.971Z,1687540588.971 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-06-23T17:16:28.972Z,1687540588.972 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8234 2023-06-23T17:16:28.976Z,1687540588.976 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-06-23T17:16:28.995Z,1687540588.995 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-06-23T17:16:28.996Z,1687540588.996 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-06-23T17:16:28.996Z,1687540588.996 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8235 2023-06-23T17:16:29.000Z,1687540589.000 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-06-23T17:16:29.001Z,1687540589.001 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-06-23T17:16:29.002Z,1687540589.002 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8236 2023-06-23T17:16:29.004Z,1687540589.004 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-06-23T17:16:29.005Z,1687540589.005 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-06-23T17:16:29.005Z,1687540589.005 [logger ThreadHandler](INFO): Protected caller Thread ID is 8237 2023-06-23T17:16:29.009Z,1687540589.009 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-06-23T17:16:29.009Z,1687540589.009 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-06-23T17:16:29.011Z,1687540589.011 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-06-23T17:16:29.112Z,1687540589.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-06-23T17:16:29.113Z,1687540589.113 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-06-23T17:16:29.879Z,1687540589.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-06-23T17:16:29.879Z,1687540589.879 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-06-23T17:16:30.118Z,1687540590.118 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-06-23T17:16:30.119Z,1687540590.119 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-06-23T17:16:30.201Z,1687540590.201 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-06-23T17:16:30.337Z,1687540590.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-06-23T17:16:30.338Z,1687540590.338 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-06-23T17:16:30.421Z,1687540590.421 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-06-23T17:16:30.533Z,1687540590.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-06-23T17:16:30.534Z,1687540590.534 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-06-23T17:16:30.950Z,1687540590.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-06-23T17:16:30.951Z,1687540590.951 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-06-23T17:16:31.477Z,1687540591.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-06-23T17:16:31.477Z,1687540591.477 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-06-23T17:16:31.707Z,1687540591.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-06-23T17:16:31.708Z,1687540591.708 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-06-23T17:16:31.927Z,1687540591.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-06-23T17:16:31.928Z,1687540591.928 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-06-23T17:16:32.824Z,1687540592.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-06-23T17:16:32.824Z,1687540592.824 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-06-23T17:16:33.130Z,1687540593.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-06-23T17:16:33.131Z,1687540593.131 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-06-23T17:16:33.365Z,1687540593.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-06-23T17:16:33.366Z,1687540593.366 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-06-23T17:16:33.760Z,1687540593.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-06-23T17:16:33.761Z,1687540593.761 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-06-23T17:16:34.131Z,1687540594.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-06-23T17:16:34.133Z,1687540594.133 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2023-06-23T17:16:34.134Z,1687540594.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2023-06-23T17:16:34.221Z,1687540594.221 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2023-06-23T17:16:34.396Z,1687540594.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2023-06-23T17:16:34.502Z,1687540594.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2023-06-23T17:16:34.591Z,1687540594.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2023-06-23T17:16:34.690Z,1687540594.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2023-06-23T17:16:34.809Z,1687540594.809 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2023-06-23T17:16:35.082Z,1687540595.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-06-23T17:16:35.082Z,1687540595.082 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2023-06-23T17:16:35.182Z,1687540595.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2023-06-23T17:16:35.286Z,1687540595.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2023-06-23T17:16:35.438Z,1687540595.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2023-06-23T17:16:35.544Z,1687540595.544 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2023-06-23T17:16:35.544Z,1687540595.544 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-06-23T17:16:35.552Z,1687540595.552 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-06-23T17:16:35.652Z,1687540595.652 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-06-23T17:16:35.653Z,1687540595.653 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-06-23T17:16:35.704Z,1687540595.704 [VerticalControl](DEBUG): Construct VerticalControl. 2023-06-23T17:16:35.765Z,1687540595.765 [VerticalControl] Loaded 2023-06-23T17:16:35.765Z,1687540595.765 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-06-23T17:16:35.768Z,1687540595.768 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-06-23T17:16:35.810Z,1687540595.810 [HorizontalControl] Loaded 2023-06-23T17:16:35.810Z,1687540595.810 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-06-23T17:16:35.812Z,1687540595.812 [SpeedControl](DEBUG): Construct SpeedControl. 2023-06-23T17:16:35.816Z,1687540595.816 [SpeedControl] Loaded 2023-06-23T17:16:35.816Z,1687540595.816 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-06-23T17:16:35.819Z,1687540595.819 [LoopControl](DEBUG): Construct LoopControl. 2023-06-23T17:16:35.819Z,1687540595.819 [LoopControl] Loaded 2023-06-23T17:16:35.819Z,1687540595.819 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-06-23T17:16:35.820Z,1687540595.820 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-06-23T17:16:35.820Z,1687540595.820 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-06-23T17:16:35.869Z,1687540595.869 [DepthRateCalculator] Loaded 2023-06-23T17:16:35.870Z,1687540595.870 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-06-23T17:16:35.875Z,1687540595.875 [PitchRateCalculator] Loaded 2023-06-23T17:16:35.875Z,1687540595.875 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-06-23T17:16:35.885Z,1687540595.885 [SpeedCalculator] Loaded 2023-06-23T17:16:35.885Z,1687540595.885 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-06-23T17:16:35.890Z,1687540595.890 [YawRateCalculator] Loaded 2023-06-23T17:16:35.890Z,1687540595.890 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-06-23T17:16:35.909Z,1687540595.909 [ElevatorOffsetCalculator] Loaded 2023-06-23T17:16:35.909Z,1687540595.909 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-06-23T17:16:35.909Z,1687540595.909 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-06-23T17:16:35.910Z,1687540595.910 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-06-23T17:16:35.936Z,1687540595.936 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-06-23T17:16:35.937Z,1687540595.937 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-06-23T17:16:35.992Z,1687540595.992 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-06-23T17:16:35.993Z,1687540595.993 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-06-23T17:16:36.300Z,1687540596.300 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-06-23T17:16:36.301Z,1687540596.301 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-06-23T17:16:36.450Z,1687540596.450 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-06-23T17:16:36.451Z,1687540596.451 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-06-23T17:16:36.897Z,1687540596.897 [AHRS_M2] Loaded 2023-06-23T17:16:36.897Z,1687540596.897 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-06-23T17:16:36.932Z,1687540596.932 [BackseatComponent] Loaded 2023-06-23T17:16:36.932Z,1687540596.932 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-06-23T17:16:36.933Z,1687540596.933 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4098B4E0 2023-06-23T17:16:36.934Z,1687540596.934 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8319 2023-06-23T17:16:36.936Z,1687540596.936 [LcmUniversalReporter] Loaded 2023-06-23T17:16:36.937Z,1687540596.937 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-06-23T17:16:38.265Z,1687540598.265 [BPC1] Loaded 2023-06-23T17:16:38.266Z,1687540598.266 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-06-23T17:16:38.338Z,1687540598.338 [DataOverHttps] Loaded 2023-06-23T17:16:38.338Z,1687540598.338 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-06-23T17:16:38.339Z,1687540598.339 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409BB4E0 2023-06-23T17:16:38.339Z,1687540598.339 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8320 2023-06-23T17:16:38.361Z,1687540598.361 [Depth_Keller] Loaded 2023-06-23T17:16:38.361Z,1687540598.361 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-06-23T17:16:38.366Z,1687540598.366 [DropWeight] Loaded 2023-06-23T17:16:38.366Z,1687540598.366 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-06-23T17:16:38.431Z,1687540598.431 [NAL9602] Loaded 2023-06-23T17:16:38.431Z,1687540598.431 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-06-23T17:16:38.462Z,1687540598.462 [Onboard] Loaded 2023-06-23T17:16:38.462Z,1687540598.462 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-06-23T17:16:38.463Z,1687540598.463 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409EB4E0 2023-06-23T17:16:38.463Z,1687540598.463 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8321 2023-06-23T17:16:38.469Z,1687540598.469 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1 2023-06-23T17:16:38.481Z,1687540598.481 [PowerOnly] Loaded 2023-06-23T17:16:38.481Z,1687540598.481 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-06-23T17:16:38.493Z,1687540598.493 [Power24vConverter] Loaded 2023-06-23T17:16:38.493Z,1687540598.493 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-06-23T17:16:38.507Z,1687540598.507 [Radio_Surface] Loaded 2023-06-23T17:16:38.507Z,1687540598.507 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-06-23T17:16:38.508Z,1687540598.508 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1B4E0 2023-06-23T17:16:38.509Z,1687540598.509 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8322 2023-06-23T17:16:38.554Z,1687540598.554 [RDI_Pathfinder] Loaded 2023-06-23T17:16:38.555Z,1687540598.555 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2023-06-23T17:16:38.649Z,1687540598.649 [DAT] Loaded 2023-06-23T17:16:38.650Z,1687540598.650 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-06-23T17:16:38.651Z,1687540598.651 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A4B4E0 2023-06-23T17:16:38.651Z,1687540598.651 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8323 2023-06-23T17:16:38.652Z,1687540598.652 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-06-23T17:16:38.652Z,1687540598.652 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-06-23T17:16:38.764Z,1687540598.764 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-06-23T17:16:38.764Z,1687540598.764 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-06-23T17:16:38.778Z,1687540598.778 [NavChart] Loaded 2023-06-23T17:16:38.778Z,1687540598.778 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-06-23T17:16:38.784Z,1687540598.784 [UniversalFixResidualReporter] Loaded 2023-06-23T17:16:38.784Z,1687540598.784 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-06-23T17:16:38.785Z,1687540598.785 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-06-23T17:16:38.785Z,1687540598.785 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-06-23T17:16:38.930Z,1687540598.930 [SBIT](DEBUG): Construct Startup Built In Test. 2023-06-23T17:16:38.939Z,1687540598.939 [SBIT] Loaded 2023-06-23T17:16:38.940Z,1687540598.940 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-06-23T17:16:38.943Z,1687540598.943 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-06-23T17:16:38.955Z,1687540598.955 [IBIT] Loaded 2023-06-23T17:16:38.956Z,1687540598.956 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-06-23T17:16:38.961Z,1687540598.961 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-06-23T17:16:39.069Z,1687540599.069 [CBIT] Loaded 2023-06-23T17:16:39.069Z,1687540599.069 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-06-23T17:16:39.070Z,1687540599.070 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-06-23T17:16:39.070Z,1687540599.070 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-06-23T17:16:39.203Z,1687540599.203 [BuoyancyServo] Loaded 2023-06-23T17:16:39.203Z,1687540599.203 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-06-23T17:16:39.224Z,1687540599.224 [ElevatorServo] Loaded 2023-06-23T17:16:39.224Z,1687540599.224 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-06-23T17:16:39.244Z,1687540599.244 [MassServo] Loaded 2023-06-23T17:16:39.245Z,1687540599.245 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-06-23T17:16:39.264Z,1687540599.264 [RudderServo] Loaded 2023-06-23T17:16:39.264Z,1687540599.264 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-06-23T17:16:39.282Z,1687540599.282 [ThrusterServo] Loaded 2023-06-23T17:16:39.282Z,1687540599.282 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2023-06-23T17:16:39.283Z,1687540599.283 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-06-23T17:16:39.283Z,1687540599.283 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-06-23T17:16:39.294Z,1687540599.294 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-06-23T17:16:39.295Z,1687540599.295 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-06-23T17:16:39.470Z,1687540599.470 [CTD_Seabird] Loaded 2023-06-23T17:16:39.470Z,1687540599.470 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-06-23T17:16:39.471Z,1687540599.471 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0 2023-06-23T17:16:39.471Z,1687540599.471 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8324 2023-06-23T17:16:39.492Z,1687540599.492 [PAR_Licor] Loaded 2023-06-23T17:16:39.493Z,1687540599.493 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-06-23T17:16:39.525Z,1687540599.525 [WetLabsBB2FL] Loaded 2023-06-23T17:16:39.525Z,1687540599.525 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-06-23T17:16:39.526Z,1687540599.526 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BC34E0 2023-06-23T17:16:39.527Z,1687540599.527 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8325 2023-06-23T17:16:39.547Z,1687540599.547 [WetLabsUBAT] Loaded 2023-06-23T17:16:39.548Z,1687540599.548 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2023-06-23T17:16:39.549Z,1687540599.549 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BF34E0 2023-06-23T17:16:39.549Z,1687540599.549 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 8326 2023-06-23T17:16:39.550Z,1687540599.550 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-06-23T17:16:39.556Z,1687540599.556 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-06-23T17:16:39.559Z,1687540599.559 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-06-23T17:16:39.570Z,1687540599.570 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-06-23T17:16:39.571Z,1687540599.571 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C234E0 2023-06-23T17:16:39.572Z,1687540599.572 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8327 2023-06-23T17:16:39.577Z,1687540599.577 [Supervisor](INFO): Main Thread ID is 7937 2023-06-23T17:16:39.577Z,1687540599.577 [Supervisor](DEBUG): Running supervisor. 2023-06-23T17:16:39.577Z,1687540599.577 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8328 2023-06-23T17:16:39.578Z,1687540599.578 [CommandExec](INFO): Initializing the command executive. 2023-06-23T17:16:39.579Z,1687540599.579 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8329 2023-06-23T17:16:39.582Z,1687540599.582 [controlThread ThreadHandler](INFO): Handler Thread ID is 8330 2023-06-23T17:16:39.582Z,1687540599.582 [controlThread](DEBUG): Initializing ControlThread 2023-06-23T17:16:39.583Z,1687540599.583 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-06-23T17:16:39.585Z,1687540599.585 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-06-23T17:16:39.585Z,1687540599.585 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-06-23T17:16:39.586Z,1687540599.586 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-06-23T17:16:39.586Z,1687540599.586 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-06-23T17:16:39.587Z,1687540599.587 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-06-23T17:16:39.587Z,1687540599.587 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-06-23T17:16:39.587Z,1687540599.587 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-06-23T17:16:39.588Z,1687540599.588 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-06-23T17:16:39.593Z,1687540599.593 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-06-23T17:16:39.593Z,1687540599.593 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-06-23T17:16:39.594Z,1687540599.594 [SBIT](INFO): Initialize SBIT Component. 2023-06-23T17:16:39.594Z,1687540599.594 [SBIT](IMPORTANT): git: 2023-05-23-14-gd957ee9d8 2023-06-23T17:16:39.594Z,1687540599.594 [SBIT](INFO): git hash: d957ee9d857d054abd03546ddf42b65141472a60 2023-06-23T17:16:39.595Z,1687540599.595 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-06-23T17:16:39.596Z,1687540599.596 [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 2023-06-23T17:16:39.597Z,1687540599.597 [SBIT](INFO): Beginning SBIT in 46.000000 seconds. 2023-06-23T17:16:39.598Z,1687540599.598 [IBIT](INFO): Initialize IBIT Component. 2023-06-23T17:16:39.599Z,1687540599.599 [CBIT](DEBUG): Initialize CBIT Component. 2023-06-23T17:16:39.600Z,1687540599.600 [logger ThreadHandler](INFO): Handler Thread ID is 8331 2023-06-23T17:16:39.610Z,1687540599.610 [CBIT](DEBUG): Initialized mux pins. 2023-06-23T17:16:39.611Z,1687540599.611 [CBIT](DEBUG): Initializing the watchdog timer. 2023-06-23T17:16:39.619Z,1687540599.619 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8332 2023-06-23T17:16:39.631Z,1687540599.631 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8333 2023-06-23T17:16:39.632Z,1687540599.632 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-06-23T17:16:39.635Z,1687540599.635 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-06-23T17:16:39.635Z,1687540599.635 [CBIT](DEBUG): Initializing heartbeat. 2023-06-23T17:16:39.643Z,1687540599.643 [Onboard ThreadHandler](INFO): Handler Thread ID is 8334 2023-06-23T17:16:39.663Z,1687540599.663 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8335 2023-06-23T17:16:39.683Z,1687540599.683 [DAT ThreadHandler](INFO): Handler Thread ID is 8336 2023-06-23T17:16:39.684Z,1687540599.684 [DAT](INFO): Powering up 2023-06-23T17:16:39.684Z,1687540599.684 [DAT](DEBUG): Initializing DAT. 2023-06-23T17:16:39.688Z,1687540599.688 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8338 2023-06-23T17:16:39.688Z,1687540599.688 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-06-23T17:16:39.692Z,1687540599.692 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8340 2023-06-23T17:16:39.695Z,1687540599.695 [WetLabsBB2FL](INFO): Powering up 2023-06-23T17:16:39.696Z,1687540599.696 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 8342 2023-06-23T17:16:39.699Z,1687540599.699 [WetLabsUBAT](INFO): Powering up 2023-06-23T17:16:39.700Z,1687540599.700 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8344 2023-06-23T17:16:39.703Z,1687540599.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-06-23T17:16:39.704Z,1687540599.704 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-06-23T17:16:39.704Z,1687540599.704 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-06-23T17:16:39.704Z,1687540599.704 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-06-23T17:16:39.704Z,1687540599.704 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-06-23T17:16:39.704Z,1687540599.704 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-06-23T17:16:39.705Z,1687540599.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-06-23T17:16:39.705Z,1687540599.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-06-23T17:16:39.706Z,1687540599.706 [CBIT](DEBUG): Deactivating GF circuits. 2023-06-23T17:16:39.707Z,1687540599.707 [CBIT](DEBUG): Deactivating emergency mode. 2023-06-23T17:16:39.746Z,1687540599.746 [CBIT](DEBUG): Backplane powered. 2023-06-23T17:16:39.751Z,1687540599.751 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-06-23T17:16:39.776Z,1687540599.776 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-23T17:16:39.804Z,1687540599.804 [MissionManager](DEBUG): 2023-06-23T17:16:39.805Z,1687540599.805 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-06-23T17:16:39.900Z,1687540599.900 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-06-23T17:16:39.901Z,1687540599.901 [Default:A.Wait](DEBUG): Construct Wait. 2023-06-23T17:16:39.904Z,1687540599.904 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-23T17:16:39.952Z,1687540599.952 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-06-23T17:16:39.970Z,1687540599.970 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-06-23T17:16:39.976Z,1687540599.976 [Default:E.Execute](DEBUG): Construct Execute. 2023-06-23T17:16:39.996Z,1687540599.996 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-06-23T17:16:40.001Z,1687540600.001 [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, 2023-06-23T17:16:40.023Z,1687540600.023 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-23T17:16:40.071Z,1687540600.071 [Radio_Surface](INFO): Powering up 2023-06-23T17:16:40.124Z,1687540600.124 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m 2023-06-23T17:16:40.126Z,1687540600.126 [Power24vConverter](INFO): Powering up. 2023-06-23T17:16:40.131Z,1687540600.131 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2023-06-23T17:16:40.207Z,1687540600.207 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-06-23T17:16:40.252Z,1687540600.252 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-06-23T17:16:40.259Z,1687540600.259 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-06-23T17:16:40.260Z,1687540600.260 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-06-23T17:16:40.267Z,1687540600.267 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-06-23T17:16:40.268Z,1687540600.268 [MassServo](DEBUG): Initializing EZServoServo. 2023-06-23T17:16:40.275Z,1687540600.275 [MassServo](DEBUG): Initializing MassServo. 2023-06-23T17:16:40.275Z,1687540600.275 [RudderServo](DEBUG): Initializing EZServoServo. 2023-06-23T17:16:40.283Z,1687540600.283 [RudderServo](DEBUG): Initializing RudderServo. 2023-06-23T17:16:40.284Z,1687540600.284 [ThrusterServo](DEBUG): Initializing EZServoServo. 2023-06-23T17:16:40.291Z,1687540600.291 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2023-06-23T17:16:42.307Z,1687540602.307 [WetLabsBB2FL](INFO): Powering down 2023-06-23T17:16:48.963Z,1687540608.963 [WetLabsUBAT](INFO): Powering down 2023-06-23T17:16:51.779Z,1687540611.779 [DAT](INFO): DAT read: 2023-06-23T17:16:51.781Z,1687540611.781 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-06-23T17:16:53.543Z,1687540613.543 [DAT](INFO): DAT read: MF Frequency Band 2023-06-23T17:16:53.545Z,1687540613.545 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2023-06-23T17:16:53.545Z,1687540613.545 [DAT](INFO): DAT read: Jun 23 2023 17:16:47 2023-06-23T17:16:54.551Z,1687540614.551 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-06-23T17:16:54.553Z,1687540614.553 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-06-23T17:16:54.553Z,1687540614.553 [DAT](INFO): commRate: 800 2023-06-23T17:16:56.618Z,1687540616.618 [DAT](INFO): entering command mode 2023-06-23T17:16:56.819Z,1687540616.819 [DAT](INFO): DAT read: 2023-06-23T17:16:56.820Z,1687540616.820 [DAT](INFO): DAT read: user:1> 2023-06-23T17:16:56.820Z,1687540616.820 [DAT](INFO): setting verbose to 3 2023-06-23T17:16:57.071Z,1687540617.071 [DAT](INFO): DAT read: user:1> 2023-06-23T17:16:57.072Z,1687540617.072 [DAT](INFO): DAT read: Verbose | 3 2023-06-23T17:16:57.073Z,1687540617.073 [DAT](INFO): set verbose to 3 2023-06-23T17:16:57.073Z,1687540617.073 [DAT](INFO): setting DatVerbose to 27440 2023-06-23T17:16:57.323Z,1687540617.323 [DAT](INFO): DAT read: user:2> 2023-06-23T17:16:57.324Z,1687540617.324 [DAT](INFO): DAT read: DatVerbose | 27440 2023-06-23T17:16:57.324Z,1687540617.324 [DAT](INFO): set DatVerbose to 27440 2023-06-23T17:16:57.325Z,1687540617.325 [DAT](INFO): setting transmit power to 8 2023-06-23T17:16:57.575Z,1687540617.575 [DAT](INFO): DAT read: user:3> 2023-06-23T17:16:57.576Z,1687540617.576 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-06-23T17:16:57.576Z,1687540617.576 [DAT](INFO): set transmit power to 8 2023-06-23T17:16:57.577Z,1687540617.577 [DAT](INFO): setting local address to 10 2023-06-23T17:16:57.829Z,1687540617.829 [DAT](INFO): DAT read: user:4> 2023-06-23T17:16:57.830Z,1687540617.830 [DAT](INFO): DAT read: LocalAddr | 10 2023-06-23T17:16:57.830Z,1687540617.830 [DAT](INFO): set local address to 10 2023-06-23T17:16:57.831Z,1687540617.831 [DAT](INFO): Setting time to: 17:16:57 And date to:6/23/2023 2023-06-23T17:16:58.079Z,1687540618.079 [DAT](INFO): DAT read: user:5> 2023-06-23T17:16:58.080Z,1687540618.080 [DAT](INFO): DAT read: Fri Jun 23, 2023 17:16:57 2023-06-23T17:16:58.080Z,1687540618.080 [DAT](INFO): Local DAT time set to Fri Jun 23, 2023 17:16:57 2023-06-23T17:17:08.692Z,1687540628.692 [NAL9602](INFO): Powering up NAL9602 2023-06-23T17:17:16.495Z,1687540636.495 [CommandExec](IMPORTANT): got command get 2023-06-23T17:17:16.495Z,1687540636.495 [CommandExec](FAULT): Incomplete syntax. Try: help get 2023-06-23T17:17:19.604Z,1687540639.604 [NAL9602](INFO): NAL9602 initialized 2023-06-23T17:17:21.280Z,1687540641.280 [CommandExec](IMPORTANT): got command show variable poweronly 2023-06-23T17:17:21.322Z,1687540641.322 [CommandExec](IMPORTANT): PowerOnly.loadAtStartup (bool) 2023-06-23T17:17:21.322Z,1687540641.322 [CommandExec](IMPORTANT): PowerOnly.simulateHardware (bool) 2023-06-23T17:17:21.326Z,1687540641.326 [CommandExec](IMPORTANT): PowerOnly.sampleTime (second) 2023-06-23T17:17:21.327Z,1687540641.327 [CommandExec](IMPORTANT): PowerOnly.sampleTime1 (second) 2023-06-23T17:17:21.327Z,1687540641.327 [CommandExec](IMPORTANT): PowerOnly.sampleTime2 (second) 2023-06-23T17:17:21.328Z,1687540641.328 [CommandExec](IMPORTANT): PowerOnly.sampleTime3 (second) 2023-06-23T17:17:21.410Z,1687540641.410 [CommandExec](IMPORTANT): PowerOnly.loadControl (none) 2023-06-23T17:17:21.414Z,1687540641.414 [CommandExec](IMPORTANT): PowerOnly.loadControl2 (none) 2023-06-23T17:17:21.415Z,1687540641.415 [CommandExec](IMPORTANT): PowerOnly.loadControl3 (none) 2023-06-23T17:17:21.448Z,1687540641.448 [CommandExec](IMPORTANT): PowerOnly.samplingTime1 (second) 2023-06-23T17:17:21.552Z,1687540641.552 [CommandExec](IMPORTANT): PowerOnly.enableBroadcast (bool) 2023-06-23T17:17:21.552Z,1687540641.552 [CommandExec](IMPORTANT): PowerOnly.samplePowerOnly (bool) 2023-06-23T17:17:21.553Z,1687540641.553 [CommandExec](IMPORTANT): PowerOnly.component_voltage_loadControl (volt) 2023-06-23T17:17:21.553Z,1687540641.553 [CommandExec](IMPORTANT): PowerOnly.component_avgVoltage_loadControl (volt) 2023-06-23T17:17:21.553Z,1687540641.553 [CommandExec](IMPORTANT): PowerOnly.component_current_loadControl (milliampere) 2023-06-23T17:17:21.554Z,1687540641.554 [CommandExec](IMPORTANT): PowerOnly.component_avgCurrent_loadControl (milliampere) 2023-06-23T17:17:21.554Z,1687540641.554 [CommandExec](IMPORTANT): PowerOnly.sampleLoad1 (bool) 2023-06-23T17:17:26.097Z,1687540646.097 [SBIT](IMPORTANT): Beginning Startup BIT 2023-06-23T17:17:26.105Z,1687540646.105 [CBIT](IMPORTANT): Beginning ground fault scan 2023-06-23T17:17:32.612Z,1687540652.612 [CommandExec](IMPORTANT): got command get PowerOnly.samplePowerOnly 2023-06-23T17:17:32.613Z,1687540652.613 [CommandExec](IMPORTANT): PowerOnly.samplePowerOnly 0 bool 2023-06-23T17:17:37.047Z,1687540657.047 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004405 CHAN A1 (24V): 0.007576 CHAN A2 (12V): -0.003131 CHAN A3 (5V): -0.000141 CHAN B0 (3.3V): 0.001699 CHAN B1 (3.15aV): 0.001060 CHAN B2 (3.15bV): 0.000919 CHAN B3 (GND): 0.002439 OPEN: 0.005670 Full Scale: +/- 1 mA 2023-06-23T17:17:42.379Z,1687540662.379 [CommandExec](IMPORTANT): got command get PowerOnly.sampleLoad1 2023-06-23T17:17:42.379Z,1687540662.379 [CommandExec](IMPORTANT): PowerOnly.sampleLoad1 0 bool 2023-06-23T17:17:46.917Z,1687540666.917 [CommandLine](INFO): End of History 2023-06-23T17:17:47.317Z,1687540667.317 [CommandLine](INFO): End of History 2023-06-23T17:17:47.818Z,1687540667.818 [CommandLine](INFO): End of History 2023-06-23T17:17:48.238Z,1687540668.238 [CommandLine](INFO): End of History 2023-06-23T17:17:48.632Z,1687540668.632 [CommandLine](INFO): End of History 2023-06-23T17:17:49.024Z,1687540669.024 [CommandLine](INFO): End of History 2023-06-23T17:17:49.421Z,1687540669.421 [CommandLine](INFO): End of History 2023-06-23T17:17:49.897Z,1687540669.897 [CommandLine](INFO): End of History 2023-06-23T17:17:50.359Z,1687540670.359 [CommandLine](INFO): End of History 2023-06-23T17:17:50.753Z,1687540670.753 [CommandLine](INFO): End of History 2023-06-23T17:17:51.144Z,1687540671.144 [CommandLine](INFO): End of History 2023-06-23T17:17:54.477Z,1687540674.477 [CommandExec](IMPORTANT): got command get PowerOnly.sampleLoad1 2023-06-23T17:17:54.477Z,1687540674.477 [CommandExec](IMPORTANT): PowerOnly.sampleLoad1 0 bool 2023-06-23T17:18:07.284Z,1687540687.284 [CommandExec](IMPORTANT): got command configSet PowerOnly.sampleTime1 1 second 2023-06-23T17:18:19.853Z,1687540699.853 [SBIT](IMPORTANT): SBIT PASSED 2023-06-23T17:18:19.853Z,1687540699.853 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-06-23T17:18:19.854Z,1687540699.854 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2023-06-23T17:18:19.854Z,1687540699.854 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere; 2023-06-23T17:18:19.855Z,1687540699.855 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2023-06-23T17:18:19.855Z,1687540699.855 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=295.498135 cubic_centimeter; 2023-06-23T17:18:19.855Z,1687540699.855 [SBIT](IMPORTANT): VerticalControl.massDefault=6.676639 millimeter; 2023-06-23T17:18:19.855Z,1687540699.855 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool; 2023-06-23T17:18:20.220Z,1687540700.220 [MissionManager](IMPORTANT): Started mission Startup 2023-06-23T17:18:20.220Z,1687540700.220 [Startup] Running Loop=1 2023-06-23T17:18:20.220Z,1687540700.220 [Startup](DEBUG): Aggregate::initialize Startup 2023-06-23T17:18:20.220Z,1687540700.220 [Startup:A.GoToSurface] Running Loop=1 2023-06-23T17:18:20.220Z,1687540700.220 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-23T17:18:20.221Z,1687540700.221 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-23T17:18:20.221Z,1687540700.221 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-23T17:18:20.222Z,1687540700.222 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-23T17:18:20.222Z,1687540700.222 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-23T17:18:20.223Z,1687540700.223 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-23T17:18:20.224Z,1687540700.224 [Startup:StartupSatComms] Running Loop=1 2023-06-23T17:18:20.224Z,1687540700.224 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-06-23T17:18:20.224Z,1687540700.224 [Startup:StartupSatComms:A] Running Loop=1 2023-06-23T17:18:20.613Z,1687540700.613 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-06-23T17:18:28.837Z,1687540708.837 [CommandExec](IMPORTANT): got command get PowerOnly.loadControl 2023-06-23T17:18:28.837Z,1687540708.837 [CommandExec](IMPORTANT): PowerOnly.loadControl /dev/loadB1 2023-06-23T17:19:07.689Z,1687540747.689 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.014140 2023-06-23T17:19:20.458Z,1687540760.458 [Startup:StartupSatComms:A](INFO): Timed out from 2023-06-23T17:18:20.2Z 2023-06-23T17:19:20.458Z,1687540760.458 [Startup:StartupSatComms:A] Stopped 2023-06-23T17:19:20.458Z,1687540760.458 [Startup:StartupSatComms:B] Running Loop=1 2023-06-23T17:19:20.821Z,1687540760.821 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-06-23T17:19:28.198Z,1687540768.198 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230623T171037/Courier0004.lzma 2023-06-23T17:19:29.202Z,1687540769.202 [DataOverHttps](INFO): Moved sent file to Logs/20230623T171037/Courier0004.lzma.bak 2023-06-23T17:19:29.203Z,1687540769.203 [DataOverHttps](INFO): SBD MOMSN=18422313 2023-06-23T17:19:39.866Z,1687540779.866 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-06-23T17:19:39.866Z,1687540779.866 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-23T17:19:39.877Z,1687540779.877 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-23T17:19:40.230Z,1687540780.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-23T17:19:40.230Z,1687540780.230 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-06-23T17:19:45.362Z,1687540785.362 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230623T171446/Courier0000.lzma 2023-06-23T17:19:46.364Z,1687540786.364 [DataOverHttps](INFO): Moved sent file to Logs/20230623T171446/Courier0000.lzma.bak 2023-06-23T17:19:46.364Z,1687540786.364 [DataOverHttps](INFO): SBD MOMSN=18422315 2023-06-23T17:20:02.033Z,1687540802.033 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2023-06-23T17:20:02.411Z,1687540802.411 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230623T171628/Courier0000.lzma 2023-06-23T17:20:03.412Z,1687540803.412 [DataOverHttps](INFO): Moved sent file to Logs/20230623T171628/Courier0000.lzma.bak 2023-06-23T17:20:03.412Z,1687540803.412 [DataOverHttps](INFO): SBD MOMSN=18422318 2023-06-23T17:20:20.636Z,1687540820.636 [Startup:StartupSatComms:B](INFO): Timed out from 2023-06-23T17:19:20.5Z 2023-06-23T17:20:20.637Z,1687540820.637 [Startup:StartupSatComms:B] Stopped 2023-06-23T17:20:20.637Z,1687540820.637 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-06-23T17:20:20.637Z,1687540820.637 [Startup:StartupSatComms] Stopped 2023-06-23T17:20:20.637Z,1687540820.637 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-06-23T17:20:20.638Z,1687540820.638 [Startup](INFO): Completed Startup 2023-06-23T17:20:20.638Z,1687540820.638 [MissionManager](INFO): Startup is completed. 2023-06-23T17:20:20.638Z,1687540820.638 [MissionManager](INFO): Uninitializing Mission Startup 2023-06-23T17:20:20.638Z,1687540820.638 [Startup] Stopped 2023-06-23T17:20:20.682Z,1687540820.682 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-06-23T17:20:20.683Z,1687540820.683 [Startup:A.GoToSurface] Stopped 2023-06-23T17:20:20.683Z,1687540820.683 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-23T17:20:21.028Z,1687540821.028 [MissionManager](IMPORTANT): Started mission Default 2023-06-23T17:20:21.028Z,1687540821.028 [Default] Running Loop=1 2023-06-23T17:20:21.029Z,1687540821.029 [Default](DEBUG): Aggregate::initialize Default 2023-06-23T17:20:21.029Z,1687540821.029 [Default:B.GoToSurface] Running Loop=1 2023-06-23T17:20:21.029Z,1687540821.029 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-23T17:20:21.029Z,1687540821.029 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-23T17:20:21.029Z,1687540821.029 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-23T17:20:21.030Z,1687540821.030 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-23T17:20:21.030Z,1687540821.030 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-23T17:20:21.040Z,1687540821.040 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-23T17:20:21.040Z,1687540821.040 [Default:A.Wait] Running Loop=1 2023-06-23T17:20:21.040Z,1687540821.040 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-06-23T17:20:21.380Z,1687540821.380 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20230622T000312/Express0005.lzma 2023-06-23T17:20:22.380Z,1687540822.380 [DataOverHttps](INFO): Moved sent file to Logs/20230622T000312/Express0005.lzma.bak 2023-06-23T17:20:22.380Z,1687540822.380 [DataOverHttps](INFO): SBD MOMSN=18422321 2023-06-23T17:20:34.385Z,1687540834.385 [Default:A.Wait](INFO): Done Waiting. 2023-06-23T17:20:34.385Z,1687540834.385 [Default:A.Wait] Stopped 2023-06-23T17:20:34.385Z,1687540834.385 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-06-23T17:20:34.781Z,1687540834.781 [Default:CheckIn] Running Loop=1 2023-06-23T17:20:34.781Z,1687540834.781 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-23T17:20:34.781Z,1687540834.781 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-23T17:20:35.170Z,1687540835.170 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-06-23T17:21:01.666Z,1687540861.666 [CommandExec](IMPORTANT): got command restart application 2023-06-23T17:21:02.670Z,1687540862.670 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-06-23T17:21:02.671Z,1687540862.671 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:02.671Z,1687540862.671 [CommandExec](INFO): Uninitializing the command executive. 2023-06-23T17:21:02.671Z,1687540862.671 [CommandExec](INFO): Uninitializing the command scheduler. 2023-06-23T17:21:02.671Z,1687540862.671 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:02.715Z,1687540862.715 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-06-23T17:21:02.715Z,1687540862.715 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-06-23T17:21:02.715Z,1687540862.715 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:02.716Z,1687540862.716 [NavChartDb](INFO): Join timeout helper Thread ID is 8366 2023-06-23T17:21:02.855Z,1687540862.855 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:02.855Z,1687540862.855 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:02.874Z,1687540862.874 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2023-06-23T17:21:02.875Z,1687540862.875 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:02.875Z,1687540862.875 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 8367 2023-06-23T17:21:03.135Z,1687540863.135 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:03.135Z,1687540863.135 [WetLabsUBAT](INFO): Powering down 2023-06-23T17:21:03.136Z,1687540863.136 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:03.142Z,1687540863.142 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-06-23T17:21:03.143Z,1687540863.143 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:03.143Z,1687540863.143 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8368 2023-06-23T17:21:03.471Z,1687540863.471 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:03.471Z,1687540863.471 [WetLabsBB2FL](INFO): Powering down 2023-06-23T17:21:03.472Z,1687540863.472 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:03.491Z,1687540863.491 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-06-23T17:21:03.491Z,1687540863.491 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:03.491Z,1687540863.491 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8369 2023-06-23T17:21:03.759Z,1687540863.759 [CTD_Seabird](INFO): Powering down 2023-06-23T17:21:03.770Z,1687540863.770 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:03.771Z,1687540863.771 [CTD_Seabird](INFO): Powering down 2023-06-23T17:21:03.790Z,1687540863.790 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:03.807Z,1687540863.807 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-06-23T17:21:03.807Z,1687540863.807 [DAT ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:03.807Z,1687540863.807 [DAT](INFO): Join timeout helper Thread ID is 8370 2023-06-23T17:21:03.927Z,1687540863.927 [DAT](INFO): Powering down 2023-06-23T17:21:03.999Z,1687540863.999 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:03.999Z,1687540863.999 [DAT](INFO): Powering down 2023-06-23T17:21:04.000Z,1687540864.000 [DAT ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:04.012Z,1687540864.012 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-06-23T17:21:04.012Z,1687540864.012 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:04.012Z,1687540864.012 [Radio_Surface](INFO): Join timeout helper Thread ID is 8371 2023-06-23T17:21:04.391Z,1687540864.391 [Radio_Surface](INFO): Powering down 2023-06-23T17:21:04.392Z,1687540864.392 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:04.392Z,1687540864.392 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:04.411Z,1687540864.411 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-06-23T17:21:04.411Z,1687540864.411 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:04.412Z,1687540864.412 [Onboard](INFO): Join timeout helper Thread ID is 8372 2023-06-23T17:21:04.855Z,1687540864.855 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:04.855Z,1687540864.855 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:04.875Z,1687540864.875 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-06-23T17:21:04.875Z,1687540864.875 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:04.876Z,1687540864.876 [DataOverHttps](INFO): Join timeout helper Thread ID is 8373 2023-06-23T17:21:05.367Z,1687540865.367 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:05.369Z,1687540865.369 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:05.387Z,1687540865.387 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-06-23T17:21:05.387Z,1687540865.387 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:05.388Z,1687540865.388 [BackseatComponent](INFO): Join timeout helper Thread ID is 8374 2023-06-23T17:21:05.563Z,1687540865.563 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:05.563Z,1687540865.563 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:05.567Z,1687540865.567 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-06-23T17:21:05.567Z,1687540865.567 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:05.568Z,1687540865.568 [logger](INFO): Join timeout helper Thread ID is 8375 2023-06-23T17:21:05.611Z,1687540865.611 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:05.611Z,1687540865.611 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:05.628Z,1687540865.628 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-06-23T17:21:05.628Z,1687540865.628 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:05.629Z,1687540865.629 [CommandLine](INFO): Join timeout helper Thread ID is 8376 2023-06-23T17:21:05.703Z,1687540865.703 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:05.703Z,1687540865.703 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:05.707Z,1687540865.707 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-06-23T17:21:05.707Z,1687540865.707 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:05.708Z,1687540865.708 [CommandExec](INFO): Join timeout helper Thread ID is 8377 2023-06-23T17:21:05.709Z,1687540865.709 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-06-23T17:21:05.709Z,1687540865.709 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:05.709Z,1687540865.709 [controlThread](INFO): Join timeout helper Thread ID is 8378 2023-06-23T17:21:05.851Z,1687540865.851 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-23T17:21:05.851Z,1687540865.851 [controlThread](DEBUG): Uninitializing ControlThread 2023-06-23T17:21:05.851Z,1687540865.851 [AHRS_M2](INFO): Powering down 2023-06-23T17:21:05.924Z,1687540865.924 [NAL9602](INFO): Powering down 2023-06-23T17:21:05.994Z,1687540865.994 [RDI_Pathfinder](INFO): Powering down 2023-06-23T17:21:05.996Z,1687540865.996 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-06-23T17:21:05.997Z,1687540865.997 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-06-23T17:21:05.997Z,1687540865.997 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-06-23T17:21:05.998Z,1687540865.998 [MissionManager](INFO): Uninitializing Mission Default 2023-06-23T17:21:05.998Z,1687540865.998 [Default] Stopped 2023-06-23T17:21:05.998Z,1687540865.998 [Default](DEBUG): Aggregate::uninitialize Default 2023-06-23T17:21:05.998Z,1687540865.998 [Default:B.GoToSurface] Stopped 2023-06-23T17:21:05.998Z,1687540865.998 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-23T17:21:05.998Z,1687540865.998 [Default:CheckIn] Stopped 2023-06-23T17:21:05.998Z,1687540865.998 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-23T17:21:05.998Z,1687540865.998 [Default:CheckIn:Read_GPS] Stopped 2023-06-23T17:21:06.001Z,1687540866.001 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-06-23T17:21:06.002Z,1687540866.002 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-06-23T17:21:06.002Z,1687540866.002 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-06-23T17:21:06.002Z,1687540866.002 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-06-23T17:21:06.003Z,1687540866.003 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-06-23T17:21:06.003Z,1687540866.003 [BuoyancyServo](INFO): Powering down 2023-06-23T17:21:06.015Z,1687540866.015 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-06-23T17:21:06.015Z,1687540866.015 [ElevatorServo](INFO): Powering down 2023-06-23T17:21:06.016Z,1687540866.016 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-06-23T17:21:06.016Z,1687540866.016 [MassServo](INFO): Powering down 2023-06-23T17:21:06.016Z,1687540866.016 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-06-23T17:21:06.017Z,1687540866.017 [RudderServo](INFO): Powering down 2023-06-23T17:21:06.017Z,1687540866.017 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-06-23T17:21:06.017Z,1687540866.017 [ThrusterServo](INFO): Powering down 2023-06-23T17:21:06.018Z,1687540866.018 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-06-23T17:21:06.019Z,1687540866.019 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-06-23T17:21:06.019Z,1687540866.019 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-06-23T17:21:06.019Z,1687540866.019 [CBIT](DEBUG): Powering off loads. 2023-06-23T17:21:06.030Z,1687540866.030 [CBIT](DEBUG): Disabling WDT. 2023-06-23T17:21:06.042Z,1687540866.042 [CBIT](DEBUG): Opening all GF detection circuits. 2023-06-23T17:21:06.043Z,1687540866.043 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.090Z,1687540866.090 [DAT ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.097Z,1687540866.097 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.100Z,1687540866.100 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.106Z,1687540866.106 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.150Z,1687540866.150 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.215Z,1687540866.215 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.216Z,1687540866.216 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.220Z,1687540866.220 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.228Z,1687540866.228 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-23T17:21:06.290Z,1687540866.290 [logger ThreadHandler](INFO): Thread cancelled.