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.