2024-05-02T19:28:36.221Z,1714678116.221 [Supervisor](DEBUG): Initializing supervisor.
2024-05-02T19:28:36.226Z,1714678116.226 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-05-02T19:28:36.226Z,1714678116.226 [SyncHandler](INFO): Protected caller Thread ID is 6597
2024-05-02T19:28:36.227Z,1714678116.227 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-05-02T19:28:36.228Z,1714678116.228 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-05-02T19:28:36.228Z,1714678116.228 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6598
2024-05-02T19:28:36.232Z,1714678116.232 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-05-02T19:28:36.250Z,1714678116.250 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-05-02T19:28:36.251Z,1714678116.251 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-05-02T19:28:36.252Z,1714678116.252 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6599
2024-05-02T19:28:36.256Z,1714678116.256 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-05-02T19:28:36.257Z,1714678116.257 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-05-02T19:28:36.258Z,1714678116.258 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6600
2024-05-02T19:28:36.260Z,1714678116.260 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-05-02T19:28:36.261Z,1714678116.261 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-05-02T19:28:36.261Z,1714678116.261 [logger ThreadHandler](INFO): Protected caller Thread ID is 6601
2024-05-02T19:28:36.265Z,1714678116.265 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-05-02T19:28:36.265Z,1714678116.265 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-05-02T19:28:36.267Z,1714678116.267 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-05-02T19:28:36.438Z,1714678116.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-05-02T19:28:36.439Z,1714678116.439 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-05-02T19:28:37.217Z,1714678117.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-05-02T19:28:37.217Z,1714678117.217 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-05-02T19:28:37.464Z,1714678117.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-05-02T19:28:37.465Z,1714678117.465 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-05-02T19:28:37.555Z,1714678117.555 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-05-02T19:28:37.699Z,1714678117.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-05-02T19:28:37.699Z,1714678117.699 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-05-02T19:28:37.793Z,1714678117.793 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-05-02T19:28:37.913Z,1714678117.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-05-02T19:28:37.914Z,1714678117.914 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-05-02T19:28:38.328Z,1714678118.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-05-02T19:28:38.329Z,1714678118.329 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-05-02T19:28:38.889Z,1714678118.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-05-02T19:28:38.890Z,1714678118.890 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-05-02T19:28:39.125Z,1714678119.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-05-02T19:28:39.125Z,1714678119.125 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-05-02T19:28:39.332Z,1714678119.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-05-02T19:28:39.333Z,1714678119.333 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-05-02T19:28:39.890Z,1714678119.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-05-02T19:28:39.891Z,1714678119.891 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-05-02T19:28:40.197Z,1714678120.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-05-02T19:28:40.198Z,1714678120.198 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-05-02T19:28:40.439Z,1714678120.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-05-02T19:28:40.440Z,1714678120.440 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-05-02T19:28:40.835Z,1714678120.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-05-02T19:28:40.835Z,1714678120.835 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-05-02T19:28:41.387Z,1714678121.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-05-02T19:28:41.390Z,1714678121.390 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2024-05-02T19:28:41.391Z,1714678121.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2024-05-02T19:28:41.578Z,1714678121.578 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2024-05-02T19:28:41.927Z,1714678121.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2024-05-02T19:28:42.042Z,1714678122.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2024-05-02T19:28:42.140Z,1714678122.140 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2024-05-02T19:28:42.247Z,1714678122.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2024-05-02T19:28:42.372Z,1714678122.372 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2024-05-02T19:28:42.646Z,1714678122.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-05-02T19:28:42.646Z,1714678122.646 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2024-05-02T19:28:42.754Z,1714678122.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2024-05-02T19:28:42.859Z,1714678122.859 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2024-05-02T19:28:43.014Z,1714678123.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2024-05-02T19:28:43.125Z,1714678123.125 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-05-02T19:28:43.139Z,1714678123.139 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-05-02T19:28:43.254Z,1714678123.254 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-05-02T19:28:43.254Z,1714678123.254 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-05-02T19:28:43.306Z,1714678123.306 [VerticalControl](DEBUG): Construct VerticalControl.
2024-05-02T19:28:43.366Z,1714678123.366 [VerticalControl] Loaded
2024-05-02T19:28:43.366Z,1714678123.366 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-05-02T19:28:43.369Z,1714678123.369 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-05-02T19:28:43.417Z,1714678123.417 [HorizontalControl] Loaded
2024-05-02T19:28:43.417Z,1714678123.417 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-05-02T19:28:43.420Z,1714678123.420 [SpeedControl](DEBUG): Construct SpeedControl.
2024-05-02T19:28:43.423Z,1714678123.423 [SpeedControl] Loaded
2024-05-02T19:28:43.423Z,1714678123.423 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-05-02T19:28:43.426Z,1714678123.426 [LoopControl](DEBUG): Construct LoopControl.
2024-05-02T19:28:43.426Z,1714678123.426 [LoopControl] Loaded
2024-05-02T19:28:43.426Z,1714678123.426 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-05-02T19:28:43.427Z,1714678123.427 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-05-02T19:28:43.427Z,1714678123.427 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-05-02T19:28:43.480Z,1714678123.480 [DepthRateCalculator] Loaded
2024-05-02T19:28:43.480Z,1714678123.480 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-05-02T19:28:43.485Z,1714678123.485 [PitchRateCalculator] Loaded
2024-05-02T19:28:43.485Z,1714678123.485 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-05-02T19:28:43.495Z,1714678123.495 [SpeedCalculator] Loaded
2024-05-02T19:28:43.495Z,1714678123.496 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-05-02T19:28:43.500Z,1714678123.500 [YawRateCalculator] Loaded
2024-05-02T19:28:43.500Z,1714678123.500 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-05-02T19:28:43.518Z,1714678123.518 [ElevatorOffsetCalculator] Loaded
2024-05-02T19:28:43.519Z,1714678123.519 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-05-02T19:28:43.519Z,1714678123.519 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-05-02T19:28:43.520Z,1714678123.520 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-05-02T19:28:43.546Z,1714678123.546 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-05-02T19:28:43.547Z,1714678123.547 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-05-02T19:28:43.603Z,1714678123.603 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-05-02T19:28:43.603Z,1714678123.603 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-05-02T19:28:43.938Z,1714678123.938 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-05-02T19:28:43.939Z,1714678123.939 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-05-02T19:28:44.107Z,1714678124.107 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-05-02T19:28:44.108Z,1714678124.108 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-05-02T19:28:44.542Z,1714678124.542 [AHRS_M2] Loaded
2024-05-02T19:28:44.543Z,1714678124.543 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-05-02T19:28:44.578Z,1714678124.578 [BackseatComponent] Loaded
2024-05-02T19:28:44.578Z,1714678124.578 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-05-02T19:28:44.580Z,1714678124.580 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C34E0
2024-05-02T19:28:44.580Z,1714678124.580 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6683
2024-05-02T19:28:44.583Z,1714678124.583 [LcmUniversalReporter] Loaded
2024-05-02T19:28:44.583Z,1714678124.583 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-05-02T19:28:45.393Z,1714678125.393 [BPC1] Loaded
2024-05-02T19:28:45.394Z,1714678125.394 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-05-02T19:28:45.472Z,1714678125.472 [DataOverHttps] Loaded
2024-05-02T19:28:45.472Z,1714678125.472 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-05-02T19:28:45.473Z,1714678125.473 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F34E0
2024-05-02T19:28:45.473Z,1714678125.473 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6684
2024-05-02T19:28:45.494Z,1714678125.494 [Depth_Keller] Loaded
2024-05-02T19:28:45.494Z,1714678125.494 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-05-02T19:28:45.557Z,1714678125.557 [NAL9602] Loaded
2024-05-02T19:28:45.557Z,1714678125.557 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-05-02T19:28:45.586Z,1714678125.586 [Onboard] Loaded
2024-05-02T19:28:45.587Z,1714678125.587 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-05-02T19:28:45.588Z,1714678125.588 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A234E0
2024-05-02T19:28:45.588Z,1714678125.588 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6685
2024-05-02T19:28:45.601Z,1714678125.601 [Power24vConverter] Loaded
2024-05-02T19:28:45.601Z,1714678125.601 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-05-02T19:28:45.614Z,1714678125.614 [Radio_Surface] Loaded
2024-05-02T19:28:45.614Z,1714678125.614 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-05-02T19:28:45.615Z,1714678125.615 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0
2024-05-02T19:28:45.616Z,1714678125.616 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6686
2024-05-02T19:28:45.661Z,1714678125.661 [RDI_Pathfinder] Loaded
2024-05-02T19:28:45.661Z,1714678125.661 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2024-05-02T19:28:45.754Z,1714678125.754 [DAT] Loaded
2024-05-02T19:28:45.755Z,1714678125.755 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-05-02T19:28:45.755Z,1714678125.755 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0
2024-05-02T19:28:45.756Z,1714678125.756 [DAT ThreadHandler](INFO): Protected caller Thread ID is 6687
2024-05-02T19:28:45.757Z,1714678125.757 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-05-02T19:28:45.757Z,1714678125.757 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-05-02T19:28:45.870Z,1714678125.870 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-05-02T19:28:45.870Z,1714678125.870 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-05-02T19:28:45.884Z,1714678125.884 [NavChart] Loaded
2024-05-02T19:28:45.884Z,1714678125.884 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-05-02T19:28:45.890Z,1714678125.890 [UniversalFixResidualReporter] Loaded
2024-05-02T19:28:45.890Z,1714678125.890 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-05-02T19:28:45.891Z,1714678125.891 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-05-02T19:28:45.891Z,1714678125.891 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-05-02T19:28:46.030Z,1714678126.030 [SBIT](DEBUG): Construct Startup Built In Test.
2024-05-02T19:28:46.039Z,1714678126.039 [SBIT] Loaded
2024-05-02T19:28:46.039Z,1714678126.039 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-05-02T19:28:46.042Z,1714678126.042 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-05-02T19:28:46.055Z,1714678126.055 [IBIT] Loaded
2024-05-02T19:28:46.055Z,1714678126.055 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-05-02T19:28:46.062Z,1714678126.062 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-05-02T19:28:46.209Z,1714678126.209 [CBIT] Loaded
2024-05-02T19:28:46.209Z,1714678126.209 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-05-02T19:28:46.209Z,1714678126.209 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-05-02T19:28:46.210Z,1714678126.210 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-05-02T19:28:46.375Z,1714678126.375 [BuoyancyServo] Loaded
2024-05-02T19:28:46.375Z,1714678126.375 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-05-02T19:28:46.397Z,1714678126.397 [ElevatorServo] Loaded
2024-05-02T19:28:46.397Z,1714678126.397 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-05-02T19:28:46.425Z,1714678126.425 [DockingStepper] Loaded
2024-05-02T19:28:46.425Z,1714678126.425 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2024-05-02T19:28:46.448Z,1714678126.448 [MassServo] Loaded
2024-05-02T19:28:46.449Z,1714678126.449 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-05-02T19:28:46.467Z,1714678126.467 [RudderServo] Loaded
2024-05-02T19:28:46.468Z,1714678126.468 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-05-02T19:28:46.485Z,1714678126.485 [ThrusterServo] Loaded
2024-05-02T19:28:46.485Z,1714678126.485 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2024-05-02T19:28:46.486Z,1714678126.486 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-05-02T19:28:46.486Z,1714678126.486 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-05-02T19:28:46.539Z,1714678126.539 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-05-02T19:28:46.552Z,1714678126.552 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-05-02T19:28:47.053Z,1714678127.053 [CTD_Seabird] Loaded
2024-05-02T19:28:47.053Z,1714678127.053 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-05-02T19:28:47.054Z,1714678127.054 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2024-05-02T19:28:47.054Z,1714678127.054 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6689
2024-05-02T19:28:47.087Z,1714678127.087 [ESPComponent] Loaded
2024-05-02T19:28:47.088Z,1714678127.088 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-05-02T19:28:47.108Z,1714678127.108 [PAR_Licor] Loaded
2024-05-02T19:28:47.108Z,1714678127.108 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-05-02T19:28:47.152Z,1714678127.152 [WetLabsBB2FL] Loaded
2024-05-02T19:28:47.152Z,1714678127.152 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-05-02T19:28:47.153Z,1714678127.153 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-05-02T19:28:47.154Z,1714678127.154 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6690
2024-05-02T19:28:47.173Z,1714678127.173 [WetLabsUBAT] Loaded
2024-05-02T19:28:47.174Z,1714678127.174 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2024-05-02T19:28:47.175Z,1714678127.175 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0
2024-05-02T19:28:47.175Z,1714678127.175 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 6691
2024-05-02T19:28:47.176Z,1714678127.176 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-05-02T19:28:47.182Z,1714678127.182 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-05-02T19:28:47.185Z,1714678127.185 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-05-02T19:28:47.197Z,1714678127.197 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-05-02T19:28:47.198Z,1714678127.198 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C674E0
2024-05-02T19:28:47.198Z,1714678127.198 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6692
2024-05-02T19:28:47.203Z,1714678127.203 [Supervisor](INFO): Main Thread ID is 6596
2024-05-02T19:28:47.203Z,1714678127.203 [Supervisor](DEBUG): Running supervisor.
2024-05-02T19:28:47.203Z,1714678127.203 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6693
2024-05-02T19:28:47.204Z,1714678127.204 [CommandExec](INFO): Initializing the command executive.
2024-05-02T19:28:47.206Z,1714678127.206 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6694
2024-05-02T19:28:47.208Z,1714678127.208 [controlThread ThreadHandler](INFO): Handler Thread ID is 6695
2024-05-02T19:28:47.208Z,1714678127.208 [controlThread](DEBUG): Initializing ControlThread
2024-05-02T19:28:47.209Z,1714678127.209 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-05-02T19:28:47.211Z,1714678127.211 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-05-02T19:28:47.211Z,1714678127.211 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-05-02T19:28:47.212Z,1714678127.212 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-05-02T19:28:47.212Z,1714678127.212 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-05-02T19:28:47.212Z,1714678127.212 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-05-02T19:28:47.213Z,1714678127.213 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-05-02T19:28:47.213Z,1714678127.213 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-05-02T19:28:47.213Z,1714678127.213 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-05-02T19:28:47.217Z,1714678127.217 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-05-02T19:28:47.218Z,1714678127.218 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-05-02T19:28:47.218Z,1714678127.218 [SBIT](INFO): Initialize SBIT Component.
2024-05-02T19:28:47.218Z,1714678127.218 [SBIT](IMPORTANT): git: 2024-04-25
2024-05-02T19:28:47.219Z,1714678127.219 [SBIT](INFO): git hash: 9c8b4752de92409818c57bb73ab755afa5204686
2024-05-02T19:28:47.219Z,1714678127.219 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-05-02T19:28:47.220Z,1714678127.220 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-05-02T19:28:47.221Z,1714678127.221 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2024-05-02T19:28:47.222Z,1714678127.222 [IBIT](INFO): Initialize IBIT Component.
2024-05-02T19:28:47.222Z,1714678127.222 [CBIT](DEBUG): Initialize CBIT Component.
2024-05-02T19:28:47.223Z,1714678127.224 [logger ThreadHandler](INFO): Handler Thread ID is 6696
2024-05-02T19:28:47.236Z,1714678127.236 [CBIT](DEBUG): Initialized mux pins.
2024-05-02T19:28:47.236Z,1714678127.236 [CBIT](DEBUG): Initializing the watchdog timer.
2024-05-02T19:28:47.241Z,1714678127.241 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6697
2024-05-02T19:28:47.249Z,1714678127.249 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6698
2024-05-02T19:28:47.250Z,1714678127.250 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-05-02T19:28:47.257Z,1714678127.257 [Onboard ThreadHandler](INFO): Handler Thread ID is 6699
2024-05-02T19:28:47.261Z,1714678127.261 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-05-02T19:28:47.261Z,1714678127.261 [CBIT](DEBUG): Initializing heartbeat.
2024-05-02T19:28:47.277Z,1714678127.277 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6700
2024-05-02T19:28:47.296Z,1714678127.296 [DAT ThreadHandler](INFO): Handler Thread ID is 6701
2024-05-02T19:28:47.297Z,1714678127.297 [DAT](INFO): Powering up
2024-05-02T19:28:47.297Z,1714678127.297 [DAT](DEBUG): Initializing DAT.
2024-05-02T19:28:47.305Z,1714678127.305 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6703
2024-05-02T19:28:47.305Z,1714678127.305 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-05-02T19:28:47.310Z,1714678127.310 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6705
2024-05-02T19:28:47.313Z,1714678127.313 [WetLabsBB2FL](INFO): Powering up
2024-05-02T19:28:47.314Z,1714678127.314 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 6707
2024-05-02T19:28:47.317Z,1714678127.317 [WetLabsUBAT](INFO): Powering up
2024-05-02T19:28:47.318Z,1714678127.318 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6709
2024-05-02T19:28:47.321Z,1714678127.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-05-02T19:28:47.321Z,1714678127.321 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-05-02T19:28:47.321Z,1714678127.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-05-02T19:28:47.322Z,1714678127.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-05-02T19:28:47.322Z,1714678127.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-05-02T19:28:47.322Z,1714678127.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-05-02T19:28:47.322Z,1714678127.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-05-02T19:28:47.322Z,1714678127.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-05-02T19:28:47.332Z,1714678127.332 [CBIT](DEBUG): Deactivating GF circuits.
2024-05-02T19:28:47.332Z,1714678127.332 [CBIT](DEBUG): Deactivating emergency mode.
2024-05-02T19:28:47.372Z,1714678127.372 [CBIT](DEBUG): Backplane powered.
2024-05-02T19:28:47.377Z,1714678127.377 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-05-02T19:28:47.378Z,1714678127.378 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-05-02T19:28:47.378Z,1714678127.378 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-05-02T19:28:47.379Z,1714678127.379 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-05-02T19:28:47.460Z,1714678127.460 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-05-02T19:28:47.465Z,1714678127.465 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-02T19:28:47.513Z,1714678127.513 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-05-02T19:28:47.513Z,1714678127.513 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-05-02T19:28:47.513Z,1714678127.513 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-05-02T19:28:47.514Z,1714678127.514 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-05-02T19:28:47.680Z,1714678127.680 [Radio_Surface](INFO): Powering up
2024-05-02T19:28:47.838Z,1714678127.838 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-05-02T19:28:47.843Z,1714678127.843 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-05-02T19:28:47.869Z,1714678127.869 [Default:A.Wait](DEBUG): Construct Wait.
2024-05-02T19:28:47.871Z,1714678127.871 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-02T19:28:47.920Z,1714678127.920 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-05-02T19:28:47.922Z,1714678127.922 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-05-02T19:28:47.944Z,1714678127.944 [Default:E.Execute](DEBUG): Construct Execute.
2024-05-02T19:28:47.947Z,1714678127.947 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-05-02T19:28:47.964Z,1714678127.964 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-05-02T19:28:47.972Z,1714678127.972 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-05-02T19:28:48.050Z,1714678128.050 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2024-05-02T19:28:48.051Z,1714678128.051 [Power24vConverter](INFO): Powering up.
2024-05-02T19:28:48.053Z,1714678128.053 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2024-05-02T19:28:48.066Z,1714678128.066 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-05-02T19:28:48.105Z,1714678128.105 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-05-02T19:28:48.112Z,1714678128.112 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-05-02T19:28:48.113Z,1714678128.113 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-05-02T19:28:48.121Z,1714678128.121 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-05-02T19:28:48.121Z,1714678128.121 [DockingStepper](INFO): Start
2024-05-02T19:28:48.122Z,1714678128.122 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-02T19:28:48.128Z,1714678128.128 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-02T19:28:48.129Z,1714678128.129 [MassServo](DEBUG): Initializing EZServoServo.
2024-05-02T19:28:48.136Z,1714678128.136 [MassServo](DEBUG): Initializing MassServo.
2024-05-02T19:28:48.137Z,1714678128.137 [RudderServo](DEBUG): Initializing EZServoServo.
2024-05-02T19:28:48.144Z,1714678128.144 [RudderServo](DEBUG): Initializing RudderServo.
2024-05-02T19:28:48.145Z,1714678128.145 [ThrusterServo](DEBUG): Initializing EZServoServo.
2024-05-02T19:28:48.152Z,1714678128.152 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2024-05-02T19:28:48.444Z,1714678128.444 [DockingStepper](INFO): Starting
2024-05-02T19:28:48.800Z,1714678128.800 [DockingStepper](INFO): Starting
2024-05-02T19:28:49.328Z,1714678129.328 [DockingStepper](INFO): Starting
2024-05-02T19:28:49.377Z,1714678129.377 [DockingStepper](INFO): Init string: /1z14929aE13561N3m50
2024-05-02T19:28:49.617Z,1714678129.617 [DockingStepper](INFO): Starting
2024-05-02T19:28:49.924Z,1714678129.924 [WetLabsBB2FL](INFO): Powering down
2024-05-02T19:28:50.052Z,1714678130.052 [DockingStepper](INFO): Starting
2024-05-02T19:28:50.440Z,1714678130.440 [DockingStepper](INFO): Starting
2024-05-02T19:28:50.868Z,1714678130.868 [DockingStepper](INFO): Starting
2024-05-02T19:28:50.893Z,1714678130.893 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-02T19:28:51.352Z,1714678131.352 [DockingStepper](INFO): Starting
2024-05-02T19:28:52.072Z,1714678132.072 [DockingStepper](INFO): Pause
2024-05-02T19:28:54.337Z,1714678134.337 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-05-02T19:28:56.577Z,1714678136.577 [WetLabsUBAT](INFO): Powering down
2024-05-02T19:29:00.401Z,1714678140.401 [DAT](INFO): DAT read:
2024-05-02T19:29:00.402Z,1714678140.402 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-05-02T19:29:01.755Z,1714678141.755 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005519
2024-05-02T19:29:01.913Z,1714678141.913 [DAT](INFO): DAT read: MF Frequency Band
2024-05-02T19:29:01.914Z,1714678141.914 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2024-05-02T19:29:01.915Z,1714678141.915 [DAT](INFO): DAT read: May 2 2024 19:16:38
2024-05-02T19:29:03.173Z,1714678143.173 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-05-02T19:29:03.174Z,1714678143.174 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-05-02T19:29:03.175Z,1714678143.175 [DAT](INFO): commRate: 800
2024-05-02T19:29:05.240Z,1714678145.240 [DAT](INFO): entering command mode
2024-05-02T19:29:05.441Z,1714678145.441 [DAT](INFO): DAT read:
2024-05-02T19:29:05.441Z,1714678145.441 [DAT](INFO): DAT read: user:1>
2024-05-02T19:29:05.442Z,1714678145.442 [DAT](INFO): setting verbose to 3
2024-05-02T19:29:05.693Z,1714678145.693 [DAT](INFO): DAT read: user:1>
2024-05-02T19:29:05.694Z,1714678145.694 [DAT](INFO): DAT read: Verbose | 3
2024-05-02T19:29:05.694Z,1714678145.694 [DAT](INFO): set verbose to 3
2024-05-02T19:29:05.694Z,1714678145.694 [DAT](INFO): setting DatVerbose to 27440
2024-05-02T19:29:05.948Z,1714678145.948 [DAT](INFO): DAT read: user:2>
2024-05-02T19:29:05.949Z,1714678145.949 [DAT](INFO): DAT read: DatVerbose | 27440
2024-05-02T19:29:05.949Z,1714678145.949 [DAT](INFO): set DatVerbose to 27440
2024-05-02T19:29:05.950Z,1714678145.950 [DAT](INFO): setting transmit power to 8
2024-05-02T19:29:06.199Z,1714678146.199 [DAT](INFO): DAT read: user:3>
2024-05-02T19:29:06.200Z,1714678146.200 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-05-02T19:29:06.200Z,1714678146.200 [DAT](INFO): set transmit power to 8
2024-05-02T19:29:06.200Z,1714678146.200 [DAT](INFO): setting local address to 10
2024-05-02T19:29:06.449Z,1714678146.449 [DAT](INFO): DAT read: user:4>
2024-05-02T19:29:06.450Z,1714678146.450 [DAT](INFO): DAT read: LocalAddr | 10
2024-05-02T19:29:06.450Z,1714678146.450 [DAT](INFO): set local address to 10
2024-05-02T19:29:06.451Z,1714678146.451 [DAT](INFO): Setting time to: 19:29:6 And date to:5/2/2024
2024-05-02T19:29:06.701Z,1714678146.701 [DAT](INFO): DAT read: user:5>
2024-05-02T19:29:06.702Z,1714678146.702 [DAT](INFO): DAT read: Thu May 2, 2024 19:29:06
2024-05-02T19:29:06.702Z,1714678146.702 [DAT](INFO): Local DAT time set to Thu May 2, 2024 19:29:06
2024-05-02T19:29:15.853Z,1714678155.853 [NAL9602](INFO): Powering up NAL9602
2024-05-02T19:29:26.762Z,1714678166.762 [NAL9602](INFO): NAL9602 initialized
2024-05-02T19:29:55.038Z,1714678195.038 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-02T19:30:10.794Z,1714678210.794 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-02T19:30:25.742Z,1714678225.742 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-02T19:30:27.004Z,1714678227.004 [SBIT](IMPORTANT): Beginning Startup BIT
2024-05-02T19:30:27.009Z,1714678227.009 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-02T19:30:32.933Z,1714678232.933 [CommandExec](IMPORTANT): got command show variable dock
2024-05-02T19:30:32.940Z,1714678232.940 [CommandExec](IMPORTANT): Dock.docking_state_cmd (enum)
2024-05-02T19:30:32.940Z,1714678232.940 [CommandExec](IMPORTANT): Dock.docking_state (enum)
2024-05-02T19:30:32.957Z,1714678232.957 [CommandExec](IMPORTANT): Dock.lat (angular_degree)
2024-05-02T19:30:32.957Z,1714678232.957 [CommandExec](IMPORTANT): Dock.lon (angular_degree)
2024-05-02T19:30:32.958Z,1714678232.958 [CommandExec](IMPORTANT): Dock.depth (meter)
2024-05-02T19:30:32.958Z,1714678232.958 [CommandExec](IMPORTANT): Dock.transponderCode (count)
2024-05-02T19:30:32.958Z,1714678232.958 [CommandExec](IMPORTANT): Docked.dockingDepthOffset (meter)
2024-05-02T19:30:32.959Z,1714678232.959 [CommandExec](IMPORTANT): Docked.dockRange (meter)
2024-05-02T19:30:32.959Z,1714678232.959 [CommandExec](IMPORTANT): Docked.dockPitch (degree)
2024-05-02T19:30:32.959Z,1714678232.959 [CommandExec](IMPORTANT): Docked.detachTimeout (second)
2024-05-02T19:30:32.960Z,1714678232.960 [CommandExec](IMPORTANT): Docked.dockTimeout (second)
2024-05-02T19:30:32.960Z,1714678232.960 [CommandExec](IMPORTANT): Docked.dataTimeout (second)
2024-05-02T19:30:32.961Z,1714678232.961 [CommandExec](IMPORTANT): Docked.rangeTimeout (second)
2024-05-02T19:30:32.961Z,1714678232.961 [CommandExec](IMPORTANT): Docked.verbose (bool)
2024-05-02T19:30:32.962Z,1714678232.962 [CommandExec](IMPORTANT): SetNav.dockRange (meter)
2024-05-02T19:30:32.963Z,1714678232.963 [CommandExec](IMPORTANT): Undock.undockDepth (meter)
2024-05-02T19:30:32.963Z,1714678232.963 [CommandExec](IMPORTANT): Undock.undockRange (meter)
2024-05-02T19:30:32.963Z,1714678232.963 [CommandExec](IMPORTANT): Undock.undockTimeout (second)
2024-05-02T19:30:32.964Z,1714678232.964 [CommandExec](IMPORTANT): Undock.reverseThrustTimeout (second)
2024-05-02T19:30:32.964Z,1714678232.964 [CommandExec](IMPORTANT): Undock.verbose (bool)
2024-05-02T19:30:32.967Z,1714678232.967 [CommandExec](IMPORTANT): DockingServo.loadAtStartup (bool)
2024-05-02T19:30:32.967Z,1714678232.967 [CommandExec](IMPORTANT): DockingServo.simulateHardware (bool)
2024-05-02T19:30:32.967Z,1714678232.967 [CommandExec](IMPORTANT): DockingServo.countsPerDeg (count_per_angular_degree)
2024-05-02T19:30:32.968Z,1714678232.968 [CommandExec](IMPORTANT): DockingServo.currLimit (percent)
2024-05-02T19:30:32.968Z,1714678232.968 [CommandExec](IMPORTANT): DockingServo.deviationAngle (angular_degree)
2024-05-02T19:30:32.968Z,1714678232.968 [CommandExec](IMPORTANT): DockingServo.limitHi (count)
2024-05-02T19:30:32.969Z,1714678232.969 [CommandExec](IMPORTANT): DockingServo.limitLo (count)
2024-05-02T19:30:32.969Z,1714678232.969 [CommandExec](IMPORTANT): DockingServo.mtrCenter (count)
2024-05-02T19:30:32.969Z,1714678232.969 [CommandExec](IMPORTANT): DockingServo.offsetAngle (degree)
2024-05-02T19:30:32.970Z,1714678232.970 [CommandExec](IMPORTANT): DockingServo.pidW (count)
2024-05-02T19:30:32.970Z,1714678232.970 [CommandExec](IMPORTANT): DockingServo.pidX (count)
2024-05-02T19:30:32.970Z,1714678232.970 [CommandExec](IMPORTANT): DockingServo.pidY (count)
2024-05-02T19:30:32.971Z,1714678232.971 [CommandExec](IMPORTANT): DockingServo.powerOnTimeout (second)
2024-05-02T19:30:32.971Z,1714678232.971 [CommandExec](IMPORTANT): DockingServo.openAngle (degree)
2024-05-02T19:30:32.971Z,1714678232.971 [CommandExec](IMPORTANT): DockingServo.closedAngle (degree)
2024-05-02T19:30:32.972Z,1714678232.972 [CommandExec](IMPORTANT): DockingStepper.loadAtStartup (bool)
2024-05-02T19:30:32.988Z,1714678232.988 [CommandExec](IMPORTANT): DockingStepper.simulateHardware (bool)
2024-05-02T19:30:32.989Z,1714678232.989 [CommandExec](IMPORTANT): DockingStepper.openValue (count)
2024-05-02T19:30:32.989Z,1714678232.989 [CommandExec](IMPORTANT): DockingStepper.closedValue (count)
2024-05-02T19:30:32.989Z,1714678232.989 [CommandExec](IMPORTANT): DockingStepper.slideValue (count)
2024-05-02T19:30:32.990Z,1714678232.990 [CommandExec](IMPORTANT): DockingStepper.deviationValue (count)
2024-05-02T19:30:32.990Z,1714678232.990 [CommandExec](IMPORTANT): DockingStepper.currLimit (percent)
2024-05-02T19:30:32.990Z,1714678232.990 [CommandExec](IMPORTANT): DockingStepper.velocity (none)
2024-05-02T19:30:32.991Z,1714678232.991 [CommandExec](IMPORTANT): DockingStepper.powerOnTimeout (second)
2024-05-02T19:30:33.056Z,1714678233.056 [CommandExec](IMPORTANT): DockingServo.loadControl (none)
2024-05-02T19:30:33.057Z,1714678233.057 [CommandExec](IMPORTANT): DockingServo.uart (none)
2024-05-02T19:30:33.057Z,1714678233.057 [CommandExec](IMPORTANT): DockingServo.baud (bit_per_second)
2024-05-02T19:30:33.057Z,1714678233.057 [CommandExec](IMPORTANT): DockingStepper.loadControl (none)
2024-05-02T19:30:33.058Z,1714678233.058 [CommandExec](IMPORTANT): DockingStepper.uart (none)
2024-05-02T19:30:33.058Z,1714678233.058 [CommandExec](IMPORTANT): DockingStepper.baud (bit_per_second)
2024-05-02T19:30:33.229Z,1714678233.229 [CommandExec](IMPORTANT): DockingStepper.enableBroadcast (bool)
2024-05-02T19:30:33.230Z,1714678233.230 [CommandExec](IMPORTANT): DockingStepper.component_voltage (volt)
2024-05-02T19:30:33.230Z,1714678233.230 [CommandExec](IMPORTANT): DockingStepper.component_avgVoltage (volt)
2024-05-02T19:30:33.230Z,1714678233.230 [CommandExec](IMPORTANT): DockingStepper.component_current (milliampere)
2024-05-02T19:30:33.231Z,1714678233.231 [CommandExec](IMPORTANT): DockingStepper.component_avgCurrent (milliampere)
2024-05-02T19:30:33.231Z,1714678233.231 [CommandExec](IMPORTANT): DockingStepper.armValue (count)
2024-05-02T19:30:33.232Z,1714678233.232 [CommandExec](IMPORTANT): Dock.dock_cable_present (bool)
2024-05-02T19:30:33.236Z,1714678233.236 [CommandExec](IMPORTANT): DockingStepper.cableValue (count)
2024-05-02T19:30:38.090Z,1714678238.090 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010268
CHAN A1 (24V): 0.000734
CHAN A2 (12V): -0.007158
CHAN A3 (5V): -0.002471
CHAN B0 (3.3V): -0.000633
CHAN B1 (3.15aV): -0.000417
CHAN B2 (3.15bV): 0.000389
CHAN B3 (GND): 0.001751
OPEN: 0.005495
Full Scale: +/- 1 mA
2024-05-02T19:30:41.138Z,1714678241.138 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-02T19:30:41.418Z,1714678241.418 [CommandExec](IMPORTANT): got command show variable help
2024-05-02T19:30:48.833Z,1714678248.833 [CommandExec](IMPORTANT): got command help
2024-05-02T19:30:56.619Z,1714678256.619 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-02T19:31:12.374Z,1714678272.374 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-02T19:31:14.061Z,1714678274.061 [CommandExec](IMPORTANT): got command docking arm
2024-05-02T19:31:14.061Z,1714678274.061 [CommandExec](IMPORTANT): Switching docking moudle to ARM.
2024-05-02T19:31:14.945Z,1714678274.945 [DockingStepper](INFO): Resume
2024-05-02T19:31:15.267Z,1714678275.267 [DockingStepper](INFO): Resuming
2024-05-02T19:31:15.689Z,1714678275.689 [DockingStepper](INFO): Start
2024-05-02T19:31:15.689Z,1714678275.689 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-02T19:31:15.805Z,1714678275.805 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-02T19:31:16.046Z,1714678276.046 [DockingStepper](INFO): Starting
2024-05-02T19:31:16.434Z,1714678276.434 [DockingStepper](INFO): Starting
2024-05-02T19:31:16.834Z,1714678276.834 [DockingStepper](INFO): Starting
2024-05-02T19:31:16.879Z,1714678276.879 [DockingStepper](INFO): Init string: /1z14928aE13561N3m50
2024-05-02T19:31:17.246Z,1714678277.246 [DockingStepper](INFO): Starting
2024-05-02T19:31:17.673Z,1714678277.673 [DockingStepper](INFO): Starting
2024-05-02T19:31:18.050Z,1714678278.050 [DockingStepper](INFO): Starting
2024-05-02T19:31:18.457Z,1714678278.457 [DockingStepper](INFO): Starting
2024-05-02T19:31:18.481Z,1714678278.481 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-02T19:31:18.860Z,1714678278.860 [DockingStepper](INFO): Starting
2024-05-02T19:31:19.276Z,1714678279.276 [DockingStepper](INFO): Changing to mode: 2
2024-05-02T19:31:19.276Z,1714678279.276 [DockingStepper](INFO): Armed mode.
2024-05-02T19:31:19.329Z,1714678279.329 [DockingStepper](INFO): Commanding negative:54272
2024-05-02T19:31:20.772Z,1714678280.772 [SBIT](IMPORTANT): SBIT PASSED
2024-05-02T19:31:20.772Z,1714678280.772 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-05-02T19:31:20.773Z,1714678280.773 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;
2024-05-02T19:31:20.773Z,1714678280.773 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=1 bool;
2024-05-02T19:31:20.773Z,1714678280.773 [SBIT](IMPORTANT): DockingStepper.simulateHardware=0 bool;
2024-05-02T19:31:20.773Z,1714678280.773 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2024-05-02T19:31:20.773Z,1714678280.773 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2024-05-02T19:31:20.773Z,1714678280.773 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute;
2024-05-02T19:31:20.773Z,1714678280.773 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2024-05-02T19:31:20.774Z,1714678280.774 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;
2024-05-02T19:31:20.774Z,1714678280.774 [SBIT](IMPORTANT): Express linearApproximation downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;
2024-05-02T19:31:20.774Z,1714678280.774 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;
2024-05-02T19:31:20.774Z,1714678280.774 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=700 cubic_centimeter;
2024-05-02T19:31:20.774Z,1714678280.774 [SBIT](IMPORTANT): VerticalControl.kpDepth=0.09 radian_per_meter;
2024-05-02T19:31:20.774Z,1714678280.774 [SBIT](IMPORTANT): VerticalControl.kpPitchElevator=0.5 none;
2024-05-02T19:31:20.774Z,1714678280.774 [SBIT](IMPORTANT): VerticalControl.massDefault=-19.999430 millimeter;
2024-05-02T19:31:20.774Z,1714678280.774 [SBIT](IMPORTANT): VerticalControl.stopDepthExcursion=20 meter;
2024-05-02T19:31:20.951Z,1714678280.951 [MissionManager](IMPORTANT): Started mission Startup
2024-05-02T19:31:20.951Z,1714678280.951 [Startup] Running Loop=1
2024-05-02T19:31:20.951Z,1714678280.951 [Startup](DEBUG): Aggregate::initialize Startup
2024-05-02T19:31:20.951Z,1714678280.951 [Startup:A.GoToSurface] Running Loop=1
2024-05-02T19:31:20.951Z,1714678280.951 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-02T19:31:20.960Z,1714678280.960 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-02T19:31:20.961Z,1714678280.961 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-02T19:31:20.961Z,1714678280.961 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-02T19:31:20.961Z,1714678280.961 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-02T19:31:20.962Z,1714678280.962 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-02T19:31:20.963Z,1714678280.963 [Startup:StartupSatComms] Running Loop=1
2024-05-02T19:31:20.964Z,1714678280.964 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-05-02T19:31:20.964Z,1714678280.964 [Startup:StartupSatComms:A] Running Loop=1
2024-05-02T19:31:21.343Z,1714678281.343 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-05-02T19:31:47.699Z,1714678307.699 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-05-02T19:31:47.700Z,1714678307.700 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-02T19:31:47.850Z,1714678307.850 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-02T19:31:48.165Z,1714678308.165 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-02T19:31:48.165Z,1714678308.165 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-05-02T19:31:57.633Z,1714678317.633 [CommandExec](IMPORTANT): got command docking standby
2024-05-02T19:31:57.633Z,1714678317.633 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY.
2024-05-02T19:31:57.746Z,1714678317.746 [DockingStepper](INFO): Changing to mode: 1
2024-05-02T19:31:57.746Z,1714678317.746 [DockingStepper](INFO): Standby mode.
2024-05-02T19:31:57.789Z,1714678317.789 [DockingStepper](INFO): Commanding positive:54211
2024-05-02T19:31:57.872Z,1714678317.872 [DockingStepper](FAULT): uart error: serial timeout moving from: 6095 to: 14900 response:
2024-05-02T19:31:57.873Z,1714678317.873 [DockingStepper] Communications Fault, FailCount= 1
2024-05-02T19:31:57.873Z,1714678317.873 [DockingStepper](ERROR): Communications Fault
2024-05-02T19:31:57.877Z,1714678317.877 [CBIT](ERROR): Communications Fault in component: DockingStepper
2024-05-02T19:31:58.127Z,1714678318.127 [DockingStepper](INFO): Stop
2024-05-02T19:31:58.127Z,1714678318.127 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2024-05-02T19:31:58.127Z,1714678318.127 [DockingStepper](INFO): Powering down
2024-05-02T19:31:58.527Z,1714678318.527 [DockingStepper](INFO): Stopping
2024-05-02T19:31:58.922Z,1714678318.922 [DockingStepper](INFO): Stopped
2024-05-02T19:31:58.943Z,1714678318.943 [CBIT](INFO): Clearing failed state for component DockingStepper
2024-05-02T19:31:58.943Z,1714678318.943 [DockingStepper] No Fault, FailCount= 1
2024-05-02T19:31:59.327Z,1714678319.327 [DockingStepper](INFO): Start
2024-05-02T19:31:59.327Z,1714678319.327 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-02T19:31:59.441Z,1714678319.441 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-02T19:31:59.754Z,1714678319.754 [DockingStepper](INFO): Starting
2024-05-02T19:32:00.142Z,1714678320.142 [DockingStepper](INFO): Starting
2024-05-02T19:32:00.536Z,1714678320.536 [DockingStepper](INFO): Starting
2024-05-02T19:32:00.588Z,1714678320.588 [DockingStepper](INFO): Init string: /1z6111aE13561N3m50
2024-05-02T19:32:00.976Z,1714678320.976 [DockingStepper](INFO): Starting
2024-05-02T19:32:01.363Z,1714678321.363 [DockingStepper](INFO): Starting
2024-05-02T19:32:01.781Z,1714678321.781 [DockingStepper](INFO): Starting
2024-05-02T19:32:02.173Z,1714678322.173 [DockingStepper](INFO): Starting
2024-05-02T19:32:02.200Z,1714678322.200 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-02T19:32:02.571Z,1714678322.571 [DockingStepper](INFO): Starting
2024-05-02T19:32:02.968Z,1714678322.968 [DockingStepper](INFO): Changing to mode: 1
2024-05-02T19:32:02.969Z,1714678322.969 [DockingStepper](INFO): Standby mode.
2024-05-02T19:32:03.016Z,1714678323.016 [DockingStepper](INFO): Commanding positive:54112
2024-05-02T19:32:21.334Z,1714678341.334 [Startup:StartupSatComms:A](INFO): Timed out from 2024-05-02T19:31:20.0Z
2024-05-02T19:32:21.334Z,1714678341.334 [Startup:StartupSatComms:A] Stopped
2024-05-02T19:32:21.335Z,1714678341.335 [Startup:StartupSatComms:B] Running Loop=1
2024-05-02T19:32:21.719Z,1714678341.719 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-02T19:32:21.789Z,1714678341.789 [CommandExec](IMPORTANT): got command docking arm
2024-05-02T19:32:21.789Z,1714678341.789 [CommandExec](IMPORTANT): Switching docking moudle to ARM.
2024-05-02T19:32:22.184Z,1714678342.184 [DockingStepper](INFO): Changing to mode: 2
2024-05-02T19:32:22.184Z,1714678342.184 [DockingStepper](INFO): Armed mode.
2024-05-02T19:32:22.232Z,1714678342.232 [DockingStepper](INFO): Commanding negative:53995
2024-05-02T19:32:27.412Z,1714678347.412 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240502T165926/Courier0037.lzma
2024-05-02T19:32:28.415Z,1714678348.415 [DataOverHttps](INFO): Moved sent file to Logs/20240502T165926/Courier0037.lzma.bak
2024-05-02T19:32:28.416Z,1714678348.416 [DataOverHttps](INFO): SBD MOMSN=19816996
2024-05-02T19:32:44.929Z,1714678364.929 [CommandExec](IMPORTANT): got command docking standby
2024-05-02T19:32:44.930Z,1714678364.930 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY.
2024-05-02T19:32:45.040Z,1714678365.040 [DockingStepper](INFO): Changing to mode: 1
2024-05-02T19:32:45.040Z,1714678365.040 [DockingStepper](INFO): Standby mode.
2024-05-02T19:32:45.092Z,1714678365.092 [DockingStepper](INFO): Commanding positive:54001
2024-05-02T19:32:51.508Z,1714678371.508 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240502T192836/Courier0000.lzma
2024-05-02T19:32:52.510Z,1714678372.510 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192836/Courier0000.lzma.bak
2024-05-02T19:32:52.510Z,1714678372.510 [DataOverHttps](INFO): SBD MOMSN=19817004
2024-05-02T19:33:09.052Z,1714678389.052 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20240502T165926/Express0038.lzma
2024-05-02T19:33:10.054Z,1714678390.054 [DataOverHttps](INFO): Moved sent file to Logs/20240502T165926/Express0038.lzma.bak
2024-05-02T19:33:10.055Z,1714678390.055 [DataOverHttps](INFO): SBD MOMSN=19817010
2024-05-02T19:33:21.489Z,1714678401.489 [Startup:StartupSatComms:B](INFO): Timed out from 2024-05-02T19:32:21.3Z
2024-05-02T19:33:21.489Z,1714678401.489 [Startup:StartupSatComms:B] Stopped
2024-05-02T19:33:21.490Z,1714678401.490 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-05-02T19:33:21.490Z,1714678401.490 [Startup:StartupSatComms] Stopped
2024-05-02T19:33:21.490Z,1714678401.490 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-05-02T19:33:21.491Z,1714678401.491 [Startup](INFO): Completed Startup
2024-05-02T19:33:21.491Z,1714678401.491 [MissionManager](INFO): Startup is completed.
2024-05-02T19:33:21.491Z,1714678401.491 [MissionManager](INFO): Uninitializing Mission Startup
2024-05-02T19:33:21.491Z,1714678401.491 [Startup] Stopped
2024-05-02T19:33:21.491Z,1714678401.491 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-05-02T19:33:21.491Z,1714678401.491 [Startup:A.GoToSurface] Stopped
2024-05-02T19:33:21.491Z,1714678401.491 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-02T19:33:21.912Z,1714678401.912 [MissionManager](IMPORTANT): Started mission Default
2024-05-02T19:33:21.912Z,1714678401.912 [Default] Running Loop=1
2024-05-02T19:33:21.912Z,1714678401.912 [Default](DEBUG): Aggregate::initialize Default
2024-05-02T19:33:21.912Z,1714678401.912 [Default:B.GoToSurface] Running Loop=1
2024-05-02T19:33:21.912Z,1714678401.912 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-02T19:33:21.913Z,1714678401.913 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-02T19:33:21.913Z,1714678401.913 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-02T19:33:21.913Z,1714678401.913 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-02T19:33:21.914Z,1714678401.914 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-02T19:33:21.914Z,1714678401.914 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-02T19:33:21.914Z,1714678401.914 [Default:A.Wait] Running Loop=1
2024-05-02T19:33:21.914Z,1714678401.914 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-05-02T19:33:28.567Z,1714678408.567 [DataOverHttps](INFO): Sending 1864 bytes from file Logs/20240502T192836/Express0001.lzma
2024-05-02T19:33:29.577Z,1714678409.577 [DataOverHttps](INFO): Moved sent file to Logs/20240502T192836/Express0001.lzma.bak
2024-05-02T19:33:29.577Z,1714678409.577 [DataOverHttps](INFO): SBD MOMSN=19817018
2024-05-02T19:33:35.242Z,1714678415.242 [Default:A.Wait](INFO): Done Waiting.
2024-05-02T19:33:35.242Z,1714678415.242 [Default:A.Wait] Stopped
2024-05-02T19:33:35.242Z,1714678415.242 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-05-02T19:33:35.701Z,1714678415.701 [Default:CheckIn] Running Loop=1
2024-05-02T19:33:35.701Z,1714678415.701 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-02T19:33:35.702Z,1714678415.702 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-02T19:33:36.054Z,1714678416.054 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-05-02T19:33:42.074Z,1714678422.074 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:33:48.539Z,1714678428.539 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:34:01.526Z,1714678441.526 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:34:14.178Z,1714678454.178 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:34:19.085Z,1714678459.085 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2024-05-02T19:34:19.085Z,1714678459.085 [CommandExec](IMPORTANT): Onboard.Pressure 66754.156250 Pa
2024-05-02T19:34:23.847Z,1714678463.847 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2024-05-02T19:34:23.860Z,1714678463.860 [CommandExec](IMPORTANT): Onboard.Pressure 9.729235 psi
2024-05-02T19:34:27.358Z,1714678467.358 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:34:30.194Z,1714678470.194 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-02T19:34:40.003Z,1714678480.003 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:34:48.269Z,1714678488.269 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2024-05-02T19:34:48.272Z,1714678488.272 [CommandExec](IMPORTANT): Onboard.Humidity 19.161274 %
2024-05-02T19:34:48.509Z,1714678488.509 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-05-02T19:34:48.509Z,1714678488.509 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-02T19:34:48.717Z,1714678488.717 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-02T19:34:49.345Z,1714678489.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-02T19:34:49.345Z,1714678489.345 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-05-02T19:34:53.458Z,1714678493.458 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:35:06.110Z,1714678506.110 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:35:18.407Z,1714678518.407 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:35:31.335Z,1714678531.335 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-02T19:35:32.596Z,1714678532.596 [CommandExec](IMPORTANT): got command quit
2024-05-02T19:35:33.619Z,1714678533.619 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-05-02T19:35:33.620Z,1714678533.620 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-05-02T19:35:33.621Z,1714678533.621 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-05-02T19:35:33.622Z,1714678533.622 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:33.623Z,1714678533.623 [NavChartDb](INFO): Join timeout helper Thread ID is 6766
2024-05-02T19:35:33.625Z,1714678533.625 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:33.636Z,1714678533.636 [CommandExec](INFO): Uninitializing the command executive.
2024-05-02T19:35:33.636Z,1714678533.636 [CommandExec](INFO): Uninitializing the command scheduler.
2024-05-02T19:35:33.637Z,1714678533.637 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:33.900Z,1714678533.900 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:33.901Z,1714678533.901 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:33.902Z,1714678533.902 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2024-05-02T19:35:33.902Z,1714678533.902 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:33.902Z,1714678533.902 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 6767
2024-05-02T19:35:34.240Z,1714678534.240 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:34.240Z,1714678534.240 [WetLabsUBAT](INFO): Powering down
2024-05-02T19:35:34.241Z,1714678534.241 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:34.264Z,1714678534.264 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-05-02T19:35:34.264Z,1714678534.264 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:34.265Z,1714678534.265 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6768
2024-05-02T19:35:34.276Z,1714678534.276 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:34.277Z,1714678534.277 [WetLabsBB2FL](INFO): Powering down
2024-05-02T19:35:34.277Z,1714678534.277 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:34.288Z,1714678534.288 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-05-02T19:35:34.288Z,1714678534.288 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:34.289Z,1714678534.289 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6769
2024-05-02T19:35:35.044Z,1714678535.044 [CTD_Seabird](INFO): Powering down
2024-05-02T19:35:35.056Z,1714678535.056 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:35.056Z,1714678535.056 [CTD_Seabird](INFO): Powering down
2024-05-02T19:35:35.068Z,1714678535.068 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:35.080Z,1714678535.080 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-05-02T19:35:35.080Z,1714678535.080 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:35.080Z,1714678535.080 [DAT](INFO): Join timeout helper Thread ID is 6770
2024-05-02T19:35:35.128Z,1714678535.128 [DAT](INFO): Powering down
2024-05-02T19:35:35.201Z,1714678535.201 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:35.201Z,1714678535.201 [DAT](INFO): Powering down
2024-05-02T19:35:35.202Z,1714678535.202 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:35.213Z,1714678535.213 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-05-02T19:35:35.213Z,1714678535.213 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:35.214Z,1714678535.214 [Radio_Surface](INFO): Join timeout helper Thread ID is 6771
2024-05-02T19:35:35.444Z,1714678535.444 [Radio_Surface](INFO): Powering down
2024-05-02T19:35:35.445Z,1714678535.445 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:35.445Z,1714678535.445 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:35.461Z,1714678535.461 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-05-02T19:35:35.461Z,1714678535.461 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:35.461Z,1714678535.461 [Onboard](INFO): Join timeout helper Thread ID is 6772
2024-05-02T19:35:35.924Z,1714678535.924 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-05-02T19:35:37.593Z,1714678537.593 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:37.594Z,1714678537.594 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:37.606Z,1714678537.606 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-05-02T19:35:37.606Z,1714678537.606 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:37.607Z,1714678537.607 [DataOverHttps](INFO): Join timeout helper Thread ID is 6773
2024-05-02T19:35:37.932Z,1714678537.932 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:37.932Z,1714678537.932 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:37.941Z,1714678537.941 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-05-02T19:35:37.941Z,1714678537.941 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:37.941Z,1714678537.941 [BackseatComponent](INFO): Join timeout helper Thread ID is 6774
2024-05-02T19:35:38.008Z,1714678538.008 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:38.009Z,1714678538.009 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.025Z,1714678538.025 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-05-02T19:35:38.025Z,1714678538.025 [logger ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.025Z,1714678538.025 [logger](INFO): Join timeout helper Thread ID is 6775
2024-05-02T19:35:38.061Z,1714678538.061 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:38.061Z,1714678538.061 [logger ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.077Z,1714678538.077 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-05-02T19:35:38.077Z,1714678538.077 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.077Z,1714678538.077 [CommandLine](INFO): Join timeout helper Thread ID is 6776
2024-05-02T19:35:38.096Z,1714678538.096 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:38.097Z,1714678538.097 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.117Z,1714678538.117 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-05-02T19:35:38.117Z,1714678538.117 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.118Z,1714678538.118 [CommandExec](INFO): Join timeout helper Thread ID is 6777
2024-05-02T19:35:38.125Z,1714678538.125 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-05-02T19:35:38.125Z,1714678538.125 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.126Z,1714678538.126 [controlThread](INFO): Join timeout helper Thread ID is 6778
2024-05-02T19:35:38.470Z,1714678538.470 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-02T19:35:38.470Z,1714678538.470 [controlThread](DEBUG): Uninitializing ControlThread
2024-05-02T19:35:38.470Z,1714678538.470 [AHRS_M2](INFO): Powering down
2024-05-02T19:35:38.541Z,1714678538.541 [NAL9602](INFO): Powering down
2024-05-02T19:35:38.612Z,1714678538.612 [RDI_Pathfinder](INFO): Powering down
2024-05-02T19:35:38.614Z,1714678538.614 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-05-02T19:35:38.614Z,1714678538.614 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-05-02T19:35:38.615Z,1714678538.615 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-05-02T19:35:38.615Z,1714678538.615 [MissionManager](INFO): Uninitializing Mission Default
2024-05-02T19:35:38.615Z,1714678538.615 [Default] Stopped
2024-05-02T19:35:38.616Z,1714678538.616 [Default](DEBUG): Aggregate::uninitialize Default
2024-05-02T19:35:38.616Z,1714678538.616 [Default:B.GoToSurface] Stopped
2024-05-02T19:35:38.616Z,1714678538.616 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-02T19:35:38.616Z,1714678538.616 [Default:CheckIn] Stopped
2024-05-02T19:35:38.616Z,1714678538.616 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-02T19:35:38.616Z,1714678538.616 [Default:CheckIn:Read_GPS] Stopped
2024-05-02T19:35:38.619Z,1714678538.619 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-05-02T19:35:38.619Z,1714678538.619 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-05-02T19:35:38.619Z,1714678538.619 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-05-02T19:35:38.620Z,1714678538.620 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-05-02T19:35:38.620Z,1714678538.620 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-05-02T19:35:38.620Z,1714678538.620 [BuoyancyServo](INFO): Powering down
2024-05-02T19:35:38.632Z,1714678538.632 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-05-02T19:35:38.632Z,1714678538.632 [ElevatorServo](INFO): Powering down
2024-05-02T19:35:38.633Z,1714678538.633 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2024-05-02T19:35:38.633Z,1714678538.633 [DockingStepper](INFO): Powering down
2024-05-02T19:35:38.634Z,1714678538.634 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-05-02T19:35:38.634Z,1714678538.634 [MassServo](INFO): Powering down
2024-05-02T19:35:38.635Z,1714678538.635 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-05-02T19:35:38.635Z,1714678538.635 [RudderServo](INFO): Powering down
2024-05-02T19:35:38.636Z,1714678538.636 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2024-05-02T19:35:38.636Z,1714678538.636 [ThrusterServo](INFO): Powering down
2024-05-02T19:35:38.637Z,1714678538.637 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-05-02T19:35:38.637Z,1714678538.637 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-05-02T19:35:38.638Z,1714678538.638 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-05-02T19:35:38.638Z,1714678538.638 [CBIT](DEBUG): Powering off loads.
2024-05-02T19:35:38.652Z,1714678538.652 [CBIT](DEBUG): Disabling WDT.
2024-05-02T19:35:38.664Z,1714678538.664 [CBIT](DEBUG): Opening all GF detection circuits.
2024-05-02T19:35:38.665Z,1714678538.665 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.715Z,1714678538.715 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.722Z,1714678538.722 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.724Z,1714678538.724 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.729Z,1714678538.729 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.772Z,1714678538.772 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.950Z,1714678538.950 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.952Z,1714678538.952 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.957Z,1714678538.957 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:38.965Z,1714678538.965 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-02T19:35:39.030Z,1714678539.030 [logger ThreadHandler](INFO): Thread cancelled.