2023-11-28T15:42:47.795Z,1701186167.795 [Supervisor](DEBUG): Initializing supervisor.
2023-11-28T15:42:47.841Z,1701186167.841 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-11-28T15:42:47.841Z,1701186167.841 [SyncHandler](INFO): Protected caller Thread ID is 7544
2023-11-28T15:42:47.842Z,1701186167.842 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-11-28T15:42:47.843Z,1701186167.843 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-11-28T15:42:47.843Z,1701186167.843 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7545
2023-11-28T15:42:47.847Z,1701186167.847 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-11-28T15:42:47.864Z,1701186167.864 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-11-28T15:42:47.865Z,1701186167.865 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-11-28T15:42:47.865Z,1701186167.865 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7546
2023-11-28T15:42:47.869Z,1701186167.869 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-11-28T15:42:47.870Z,1701186167.870 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-11-28T15:42:47.871Z,1701186167.871 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7547
2023-11-28T15:42:47.873Z,1701186167.873 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-11-28T15:42:47.874Z,1701186167.874 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-11-28T15:42:47.874Z,1701186167.874 [logger ThreadHandler](INFO): Protected caller Thread ID is 7548
2023-11-28T15:42:47.877Z,1701186167.877 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-11-28T15:42:47.878Z,1701186167.878 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-11-28T15:42:47.882Z,1701186167.882 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-11-28T15:42:48.075Z,1701186168.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-11-28T15:42:48.076Z,1701186168.076 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-11-28T15:42:48.751Z,1701186168.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-11-28T15:42:48.752Z,1701186168.752 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-11-28T15:42:48.964Z,1701186168.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-11-28T15:42:48.965Z,1701186168.965 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-11-28T15:42:49.046Z,1701186169.046 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-11-28T15:42:49.176Z,1701186169.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-11-28T15:42:49.176Z,1701186169.176 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-11-28T15:42:49.259Z,1701186169.259 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-11-28T15:42:49.368Z,1701186169.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-11-28T15:42:49.368Z,1701186169.368 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-11-28T15:42:49.738Z,1701186169.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-11-28T15:42:49.738Z,1701186169.738 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-11-28T15:42:50.206Z,1701186170.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-11-28T15:42:50.206Z,1701186170.206 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-11-28T15:42:50.416Z,1701186170.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-11-28T15:42:50.417Z,1701186170.417 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-11-28T15:42:50.600Z,1701186170.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-11-28T15:42:50.601Z,1701186170.601 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-11-28T15:42:51.128Z,1701186171.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-11-28T15:42:51.129Z,1701186171.129 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-11-28T15:42:52.104Z,1701186172.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-11-28T15:42:52.104Z,1701186172.104 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-11-28T15:42:52.321Z,1701186172.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-11-28T15:42:52.322Z,1701186172.322 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-11-28T15:42:52.684Z,1701186172.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-11-28T15:42:52.685Z,1701186172.685 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-11-28T15:42:53.006Z,1701186173.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-11-28T15:42:53.008Z,1701186173.008 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-11-28T15:42:53.011Z,1701186173.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-11-28T15:42:53.097Z,1701186173.097 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-11-28T15:42:53.261Z,1701186173.261 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-11-28T15:42:53.364Z,1701186173.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-11-28T15:42:53.453Z,1701186173.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-11-28T15:42:53.550Z,1701186173.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-11-28T15:42:53.664Z,1701186173.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-11-28T15:42:53.929Z,1701186173.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-11-28T15:42:53.930Z,1701186173.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-11-28T15:42:54.026Z,1701186174.026 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-11-28T15:42:54.126Z,1701186174.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-11-28T15:42:54.264Z,1701186174.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-11-28T15:42:54.364Z,1701186174.364 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-11-28T15:42:54.379Z,1701186174.379 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-11-28T15:42:54.502Z,1701186174.502 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-11-28T15:42:54.502Z,1701186174.502 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-11-28T15:42:54.571Z,1701186174.571 [VerticalControl](DEBUG): Construct VerticalControl.
2023-11-28T15:42:54.631Z,1701186174.631 [VerticalControl] Loaded
2023-11-28T15:42:54.631Z,1701186174.631 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-11-28T15:42:54.634Z,1701186174.634 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-11-28T15:42:54.675Z,1701186174.675 [HorizontalControl] Loaded
2023-11-28T15:42:54.675Z,1701186174.675 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-11-28T15:42:54.677Z,1701186174.677 [SpeedControl](DEBUG): Construct SpeedControl.
2023-11-28T15:42:54.680Z,1701186174.680 [SpeedControl] Loaded
2023-11-28T15:42:54.680Z,1701186174.680 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-11-28T15:42:54.683Z,1701186174.683 [LoopControl](DEBUG): Construct LoopControl.
2023-11-28T15:42:54.683Z,1701186174.683 [LoopControl] Loaded
2023-11-28T15:42:54.684Z,1701186174.684 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-11-28T15:42:54.684Z,1701186174.684 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-11-28T15:42:54.685Z,1701186174.685 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-11-28T15:42:54.755Z,1701186174.755 [DepthRateCalculator] Loaded
2023-11-28T15:42:54.755Z,1701186174.755 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-11-28T15:42:54.760Z,1701186174.760 [PitchRateCalculator] Loaded
2023-11-28T15:42:54.760Z,1701186174.760 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-11-28T15:42:54.770Z,1701186174.770 [SpeedCalculator] Loaded
2023-11-28T15:42:54.770Z,1701186174.770 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-11-28T15:42:54.774Z,1701186174.774 [YawRateCalculator] Loaded
2023-11-28T15:42:54.775Z,1701186174.775 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-11-28T15:42:54.793Z,1701186174.793 [ElevatorOffsetCalculator] Loaded
2023-11-28T15:42:54.793Z,1701186174.793 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-11-28T15:42:54.794Z,1701186174.794 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-11-28T15:42:54.794Z,1701186174.794 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-11-28T15:42:54.837Z,1701186174.837 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-11-28T15:42:54.837Z,1701186174.837 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-11-28T15:42:54.920Z,1701186174.920 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-11-28T15:42:54.920Z,1701186174.920 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-11-28T15:42:55.277Z,1701186175.277 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-11-28T15:42:55.277Z,1701186175.277 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-11-28T15:42:55.456Z,1701186175.456 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-11-28T15:42:55.457Z,1701186175.457 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-11-28T15:42:56.162Z,1701186176.162 [AHRS_M2] Loaded
2023-11-28T15:42:56.162Z,1701186176.162 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-11-28T15:42:56.418Z,1701186176.418 [BackseatComponent] Loaded
2023-11-28T15:42:56.418Z,1701186176.418 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-11-28T15:42:56.441Z,1701186176.441 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2023-11-28T15:42:56.442Z,1701186176.442 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7630
2023-11-28T15:42:56.444Z,1701186176.444 [LcmUniversalReporter] Loaded
2023-11-28T15:42:56.445Z,1701186176.445 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-11-28T15:42:57.256Z,1701186177.256 [BPC1] Loaded
2023-11-28T15:42:57.256Z,1701186177.256 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-11-28T15:42:57.433Z,1701186177.433 [DataOverHttps] Loaded
2023-11-28T15:42:57.434Z,1701186177.434 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-11-28T15:42:57.435Z,1701186177.435 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2023-11-28T15:42:57.445Z,1701186177.445 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7631
2023-11-28T15:42:57.498Z,1701186177.498 [Depth_Keller] Loaded
2023-11-28T15:42:57.498Z,1701186177.498 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-11-28T15:42:57.503Z,1701186177.503 [DropWeight] Loaded
2023-11-28T15:42:57.503Z,1701186177.503 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-11-28T15:42:57.597Z,1701186177.597 [NAL9602] Loaded
2023-11-28T15:42:57.598Z,1701186177.598 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-11-28T15:42:57.627Z,1701186177.627 [Onboard] Loaded
2023-11-28T15:42:57.627Z,1701186177.627 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-11-28T15:42:57.628Z,1701186177.628 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0
2023-11-28T15:42:57.629Z,1701186177.629 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7632
2023-11-28T15:42:57.635Z,1701186177.635 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2023-11-28T15:42:57.646Z,1701186177.646 [PowerOnly] Loaded
2023-11-28T15:42:57.646Z,1701186177.646 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-11-28T15:42:57.728Z,1701186177.728 [Power24vConverter] Loaded
2023-11-28T15:42:57.728Z,1701186177.728 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-11-28T15:42:57.741Z,1701186177.741 [Radio_Surface] Loaded
2023-11-28T15:42:57.741Z,1701186177.741 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-11-28T15:42:57.742Z,1701186177.742 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A194E0
2023-11-28T15:42:57.742Z,1701186177.742 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7633
2023-11-28T15:42:57.867Z,1701186177.867 [RDI_Pathfinder] Loaded
2023-11-28T15:42:57.868Z,1701186177.868 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-11-28T15:42:58.238Z,1701186178.238 [DAT] Loaded
2023-11-28T15:42:58.238Z,1701186178.238 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-11-28T15:42:58.239Z,1701186178.239 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0
2023-11-28T15:42:58.240Z,1701186178.240 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7634
2023-11-28T15:42:58.240Z,1701186178.240 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-11-28T15:42:58.241Z,1701186178.241 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-11-28T15:42:58.538Z,1701186178.538 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-11-28T15:42:58.538Z,1701186178.538 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-11-28T15:42:58.552Z,1701186178.552 [NavChart] Loaded
2023-11-28T15:42:58.552Z,1701186178.552 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-11-28T15:42:58.557Z,1701186178.557 [UniversalFixResidualReporter] Loaded
2023-11-28T15:42:58.558Z,1701186178.558 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-11-28T15:42:58.558Z,1701186178.558 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-11-28T15:42:58.559Z,1701186178.559 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-11-28T15:42:58.722Z,1701186178.722 [SBIT](DEBUG): Construct Startup Built In Test.
2023-11-28T15:42:58.731Z,1701186178.731 [SBIT] Loaded
2023-11-28T15:42:58.731Z,1701186178.731 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-11-28T15:42:58.734Z,1701186178.734 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-11-28T15:42:58.747Z,1701186178.747 [IBIT] Loaded
2023-11-28T15:42:58.747Z,1701186178.747 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-11-28T15:42:58.753Z,1701186178.753 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-11-28T15:42:58.855Z,1701186178.855 [CBIT] Loaded
2023-11-28T15:42:58.856Z,1701186178.856 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-11-28T15:42:58.856Z,1701186178.856 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-11-28T15:42:58.857Z,1701186178.857 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-11-28T15:42:59.017Z,1701186179.017 [BuoyancyServo] Loaded
2023-11-28T15:42:59.017Z,1701186179.017 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-11-28T15:42:59.037Z,1701186179.037 [ElevatorServo] Loaded
2023-11-28T15:42:59.037Z,1701186179.037 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-11-28T15:42:59.057Z,1701186179.057 [MassServo] Loaded
2023-11-28T15:42:59.057Z,1701186179.057 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-11-28T15:42:59.077Z,1701186179.077 [RudderServo] Loaded
2023-11-28T15:42:59.077Z,1701186179.077 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-11-28T15:42:59.094Z,1701186179.094 [ThrusterServo] Loaded
2023-11-28T15:42:59.094Z,1701186179.094 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-11-28T15:42:59.095Z,1701186179.095 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-11-28T15:42:59.095Z,1701186179.095 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-11-28T15:42:59.112Z,1701186179.112 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-11-28T15:42:59.112Z,1701186179.112 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-11-28T15:42:59.332Z,1701186179.332 [CTD_Seabird] Loaded
2023-11-28T15:42:59.332Z,1701186179.332 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-11-28T15:42:59.352Z,1701186179.352 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B944E0
2023-11-28T15:42:59.353Z,1701186179.353 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7635
2023-11-28T15:42:59.374Z,1701186179.374 [PAR_Licor] Loaded
2023-11-28T15:42:59.374Z,1701186179.374 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-11-28T15:42:59.414Z,1701186179.414 [WetLabsBB2FL] Loaded
2023-11-28T15:42:59.415Z,1701186179.415 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-11-28T15:42:59.416Z,1701186179.416 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BC44E0
2023-11-28T15:42:59.416Z,1701186179.416 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7636
2023-11-28T15:42:59.435Z,1701186179.435 [WetLabsUBAT] Loaded
2023-11-28T15:42:59.435Z,1701186179.435 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-11-28T15:42:59.436Z,1701186179.436 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BF44E0
2023-11-28T15:42:59.437Z,1701186179.437 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 7637
2023-11-28T15:42:59.437Z,1701186179.437 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-11-28T15:42:59.444Z,1701186179.444 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-11-28T15:42:59.446Z,1701186179.446 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-11-28T15:42:59.458Z,1701186179.458 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-11-28T15:42:59.459Z,1701186179.459 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C244E0
2023-11-28T15:42:59.460Z,1701186179.460 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7638
2023-11-28T15:42:59.465Z,1701186179.465 [Supervisor](INFO): Main Thread ID is 7543
2023-11-28T15:42:59.465Z,1701186179.465 [Supervisor](DEBUG): Running supervisor.
2023-11-28T15:42:59.466Z,1701186179.466 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7639
2023-11-28T15:42:59.466Z,1701186179.466 [CommandExec](INFO): Initializing the command executive.
2023-11-28T15:42:59.468Z,1701186179.468 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7640
2023-11-28T15:42:59.470Z,1701186179.470 [controlThread ThreadHandler](INFO): Handler Thread ID is 7641
2023-11-28T15:42:59.470Z,1701186179.470 [controlThread](DEBUG): Initializing ControlThread
2023-11-28T15:42:59.471Z,1701186179.471 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-11-28T15:42:59.473Z,1701186179.473 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-11-28T15:42:59.474Z,1701186179.474 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-11-28T15:42:59.474Z,1701186179.474 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-11-28T15:42:59.474Z,1701186179.474 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-11-28T15:42:59.475Z,1701186179.475 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-11-28T15:42:59.475Z,1701186179.475 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-11-28T15:42:59.475Z,1701186179.475 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-11-28T15:42:59.476Z,1701186179.476 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-11-28T15:42:59.482Z,1701186179.482 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-11-28T15:42:59.482Z,1701186179.482 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-11-28T15:42:59.483Z,1701186179.483 [SBIT](INFO): Initialize SBIT Component.
2023-11-28T15:42:59.483Z,1701186179.483 [SBIT](IMPORTANT): git: 2023-10-12
2023-11-28T15:42:59.483Z,1701186179.483 [SBIT](INFO): git hash: f38038725fad7dfee583a1d6cb07fccd712c5d54
2023-11-28T15:42:59.484Z,1701186179.484 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-11-28T15:42:59.485Z,1701186179.485 [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-11-28T15:42:59.486Z,1701186179.486 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2023-11-28T15:42:59.486Z,1701186179.486 [IBIT](INFO): Initialize IBIT Component.
2023-11-28T15:42:59.487Z,1701186179.487 [CBIT](DEBUG): Initialize CBIT Component.
2023-11-28T15:42:59.488Z,1701186179.488 [logger ThreadHandler](INFO): Handler Thread ID is 7642
2023-11-28T15:42:59.499Z,1701186179.499 [CBIT](DEBUG): Initialized mux pins.
2023-11-28T15:42:59.499Z,1701186179.499 [CBIT](DEBUG): Initializing the watchdog timer.
2023-11-28T15:42:59.508Z,1701186179.508 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7643
2023-11-28T15:42:59.520Z,1701186179.520 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7644
2023-11-28T15:42:59.521Z,1701186179.521 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-11-28T15:42:59.523Z,1701186179.523 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-11-28T15:42:59.524Z,1701186179.524 [CBIT](DEBUG): Initializing heartbeat.
2023-11-28T15:42:59.532Z,1701186179.532 [Onboard ThreadHandler](INFO): Handler Thread ID is 7645
2023-11-28T15:42:59.563Z,1701186179.563 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7646
2023-11-28T15:42:59.580Z,1701186179.580 [DAT ThreadHandler](INFO): Handler Thread ID is 7647
2023-11-28T15:42:59.580Z,1701186179.580 [DAT](INFO): Powering up
2023-11-28T15:42:59.580Z,1701186179.580 [DAT](DEBUG): Initializing DAT.
2023-11-28T15:42:59.585Z,1701186179.585 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7649
2023-11-28T15:42:59.585Z,1701186179.585 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-11-28T15:42:59.588Z,1701186179.588 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7651
2023-11-28T15:42:59.592Z,1701186179.592 [WetLabsBB2FL](INFO): Powering up
2023-11-28T15:42:59.593Z,1701186179.593 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 7653
2023-11-28T15:42:59.595Z,1701186179.595 [CBIT](DEBUG): Deactivating GF circuits.
2023-11-28T15:42:59.596Z,1701186179.596 [CBIT](DEBUG): Deactivating emergency mode.
2023-11-28T15:42:59.596Z,1701186179.596 [WetLabsUBAT](INFO): Powering up
2023-11-28T15:42:59.597Z,1701186179.597 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7655
2023-11-28T15:42:59.608Z,1701186179.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-11-28T15:42:59.609Z,1701186179.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-11-28T15:42:59.609Z,1701186179.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-11-28T15:42:59.609Z,1701186179.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-11-28T15:42:59.609Z,1701186179.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-11-28T15:42:59.609Z,1701186179.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-11-28T15:42:59.610Z,1701186179.610 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-11-28T15:42:59.610Z,1701186179.610 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-11-28T15:42:59.635Z,1701186179.635 [CBIT](DEBUG): Backplane powered.
2023-11-28T15:42:59.640Z,1701186179.640 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-11-28T15:42:59.672Z,1701186179.672 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-28T15:42:59.701Z,1701186179.701 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-11-28T15:42:59.702Z,1701186179.702 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-11-28T15:42:59.834Z,1701186179.834 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-11-28T15:42:59.845Z,1701186179.845 [Default:A.Wait](DEBUG): Construct Wait.
2023-11-28T15:42:59.865Z,1701186179.865 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-28T15:42:59.892Z,1701186179.892 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-11-28T15:42:59.895Z,1701186179.895 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-11-28T15:42:59.921Z,1701186179.921 [Default:E.Execute](DEBUG): Construct Execute.
2023-11-28T15:42:59.936Z,1701186179.936 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-11-28T15:42:59.947Z,1701186179.947 [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-11-28T15:42:59.959Z,1701186179.959 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-11-28T15:42:59.971Z,1701186179.971 [Radio_Surface](INFO): Powering up
2023-11-28T15:43:00.296Z,1701186180.296 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-11-28T15:43:00.298Z,1701186180.298 [PowerOnly](INFO): Powering up loadControl
2023-11-28T15:43:00.316Z,1701186180.316 [Power24vConverter](INFO): Powering up.
2023-11-28T15:43:00.316Z,1701186180.316 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-11-28T15:43:00.340Z,1701186180.340 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-11-28T15:43:00.365Z,1701186180.365 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-11-28T15:43:00.371Z,1701186180.371 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-11-28T15:43:00.372Z,1701186180.372 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-11-28T15:43:00.379Z,1701186180.379 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-11-28T15:43:00.380Z,1701186180.380 [MassServo](DEBUG): Initializing EZServoServo.
2023-11-28T15:43:00.387Z,1701186180.387 [MassServo](DEBUG): Initializing MassServo.
2023-11-28T15:43:00.388Z,1701186180.388 [RudderServo](DEBUG): Initializing EZServoServo.
2023-11-28T15:43:00.395Z,1701186180.395 [RudderServo](DEBUG): Initializing RudderServo.
2023-11-28T15:43:00.396Z,1701186180.396 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-11-28T15:43:00.403Z,1701186180.403 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-11-28T15:43:00.512Z,1701186180.512 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-11-28T15:43:00.512Z,1701186180.512 [DropWeight] Hardware Fault, FailCount= 1
2023-11-28T15:43:00.512Z,1701186180.512 [DropWeight](ERROR): Hardware Fault
2023-11-28T15:43:00.541Z,1701186180.541 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-11-28T15:43:00.558Z,1701186180.558 [CommandExec](FAULT): Scheduling is paused
2023-11-28T15:43:00.558Z,1701186180.558 [CBIT](INFO): Critical error at 20231128T154300
2023-11-28T15:43:00.558Z,1701186180.558 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-11-28T15:43:00.569Z,1701186180.569 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-11-28T15:43:00.569Z,1701186180.569 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-11-28T15:43:00.865Z,1701186180.865 [CBIT](INFO): Critical error at 20231128T154300
2023-11-28T15:43:02.203Z,1701186182.203 [WetLabsBB2FL](INFO): Powering down
2023-11-28T15:43:08.860Z,1701186188.860 [WetLabsUBAT](INFO): Powering down
2023-11-28T15:43:12.687Z,1701186192.687 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005712
2023-11-28T15:43:13.440Z,1701186193.440 [DAT](INFO): DAT read: 

2023-11-28T15:43:13.441Z,1701186193.441 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-11-28T15:43:15.204Z,1701186195.204 [DAT](INFO): DAT read: MF Frequency Band

2023-11-28T15:43:15.205Z,1701186195.205 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-11-28T15:43:15.206Z,1701186195.206 [DAT](INFO): DAT read: Nov 17 2023 00:52:09 

2023-11-28T15:43:16.464Z,1701186196.464 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-11-28T15:43:16.466Z,1701186196.466 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-11-28T15:43:16.466Z,1701186196.466 [DAT](INFO): commRate: 800
2023-11-28T15:43:18.531Z,1701186198.531 [DAT](INFO): entering command mode
2023-11-28T15:43:18.732Z,1701186198.732 [DAT](INFO): DAT read: 

2023-11-28T15:43:18.732Z,1701186198.732 [DAT](INFO): DAT read: user:1>

2023-11-28T15:43:18.733Z,1701186198.733 [DAT](INFO): setting verbose to 3
2023-11-28T15:43:18.984Z,1701186198.984 [DAT](INFO): DAT read: user:1>

2023-11-28T15:43:18.985Z,1701186198.985 [DAT](INFO): DAT read: Verbose         | 3           

2023-11-28T15:43:18.985Z,1701186198.985 [DAT](INFO): set verbose to 3
2023-11-28T15:43:18.986Z,1701186198.986 [DAT](INFO): setting DatVerbose to 27440
2023-11-28T15:43:19.236Z,1701186199.236 [DAT](INFO): DAT read: user:2>

2023-11-28T15:43:19.237Z,1701186199.237 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-11-28T15:43:19.237Z,1701186199.237 [DAT](INFO): set DatVerbose to 27440
2023-11-28T15:43:19.238Z,1701186199.238 [DAT](INFO): setting transmit power to 8
2023-11-28T15:43:19.488Z,1701186199.488 [DAT](INFO): DAT read: user:3>

2023-11-28T15:43:19.489Z,1701186199.489 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-11-28T15:43:19.489Z,1701186199.489 [DAT](INFO): set transmit power to 8
2023-11-28T15:43:19.490Z,1701186199.490 [DAT](INFO): setting local address to 10
2023-11-28T15:43:19.740Z,1701186199.740 [DAT](INFO): DAT read: user:4>

2023-11-28T15:43:19.741Z,1701186199.741 [DAT](INFO): DAT read: LocalAddr       | 10          

2023-11-28T15:43:19.741Z,1701186199.741 [DAT](INFO): set local address to 10
2023-11-28T15:43:19.742Z,1701186199.742 [DAT](INFO): Setting time to: 15:43:19 And date to:11/28/2023
2023-11-28T15:43:19.992Z,1701186199.992 [DAT](INFO): DAT read: user:5>

2023-11-28T15:43:19.993Z,1701186199.993 [DAT](INFO): DAT read: Tue Nov 28, 2023  15:43:19  

2023-11-28T15:43:19.993Z,1701186199.993 [DAT](INFO): Local DAT time set to Tue Nov 28, 2023  15:43:19  

2023-11-28T15:43:27.914Z,1701186207.914 [NAL9602](INFO): Powering up NAL9602
2023-11-28T15:43:38.805Z,1701186218.805 [NAL9602](INFO): NAL9602 initialized
2023-11-28T15:43:48.934Z,1701186228.934 [SBIT](IMPORTANT): Beginning Startup BIT
2023-11-28T15:43:48.939Z,1701186228.939 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-28T15:43:59.948Z,1701186239.948 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007795
CHAN A1 (24V): 0.004454
CHAN A2 (12V): -0.005499
CHAN A3 (5V): -0.001487
CHAN B0 (3.3V): 0.000890
CHAN B1 (3.15aV): 0.000496
CHAN B2 (3.15bV): 0.000510
CHAN B3 (GND): 0.001145
OPEN: 0.005043
Full Scale: +/- 1 mA
2023-11-28T15:44:07.485Z,1701186247.485 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-11-28T15:44:42.682Z,1701186282.682 [SBIT](IMPORTANT): SBIT PASSED
2023-11-28T15:44:42.682Z,1701186282.682 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-11-28T15:44:42.683Z,1701186282.683 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-11-28T15:44:42.683Z,1701186282.683 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2023-11-28T15:44:42.683Z,1701186282.683 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;

2023-11-28T15:44:42.684Z,1701186282.684 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-11-28T15:44:42.684Z,1701186282.684 [SBIT](IMPORTANT): Express linearApproximation downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;

2023-11-28T15:44:42.684Z,1701186282.684 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=201.867 cubic_centimeter;

2023-11-28T15:44:42.684Z,1701186282.684 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=0 second;

2023-11-28T15:44:42.684Z,1701186282.684 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=0.0015 reciprocal_second;

2023-11-28T15:44:42.684Z,1701186282.684 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=0.01 none;

2023-11-28T15:44:42.684Z,1701186282.684 [SBIT](IMPORTANT): VerticalControl.massDeadband=0.1 millimeter;

2023-11-28T15:44:42.684Z,1701186282.684 [SBIT](IMPORTANT): VerticalControl.massDefault=7.44405 millimeter;

2023-11-28T15:44:43.087Z,1701186283.087 [MissionManager](IMPORTANT): Started mission Startup
2023-11-28T15:44:43.088Z,1701186283.088 [Startup] Running Loop=1
2023-11-28T15:44:43.088Z,1701186283.088 [Startup](DEBUG): Aggregate::initialize Startup
2023-11-28T15:44:43.088Z,1701186283.088 [Startup:A.GoToSurface] Running Loop=1
2023-11-28T15:44:43.088Z,1701186283.088 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-28T15:44:43.089Z,1701186283.089 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-28T15:44:43.089Z,1701186283.089 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-28T15:44:43.089Z,1701186283.089 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-28T15:44:43.090Z,1701186283.090 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-28T15:44:43.090Z,1701186283.090 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-28T15:44:43.092Z,1701186283.092 [Startup:StartupSatComms] Running Loop=1
2023-11-28T15:44:43.092Z,1701186283.092 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-11-28T15:44:43.092Z,1701186283.092 [Startup:StartupSatComms:A] Running Loop=1
2023-11-28T15:44:43.454Z,1701186283.454 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-11-28T15:45:43.251Z,1701186343.251 [Startup:StartupSatComms:A](INFO): Timed out from 2023-11-28T15:44:43.1Z
2023-11-28T15:45:43.251Z,1701186343.251 [Startup:StartupSatComms:A] Stopped
2023-11-28T15:45:43.251Z,1701186343.251 [Startup:StartupSatComms:B] Running Loop=1
2023-11-28T15:45:43.671Z,1701186343.671 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-28T15:45:51.039Z,1701186351.039 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20231128T154247/Courier0000.lzma
2023-11-28T15:45:52.041Z,1701186352.041 [DataOverHttps](INFO): Moved sent file to Logs/20231128T154247/Courier0000.lzma.bak
2023-11-28T15:45:52.041Z,1701186352.041 [DataOverHttps](INFO): SBD MOMSN=19074431
2023-11-28T15:45:59.812Z,1701186359.812 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-11-28T15:45:59.813Z,1701186359.813 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T15:45:59.905Z,1701186359.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T15:46:00.272Z,1701186360.272 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T15:46:00.272Z,1701186360.272 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-11-28T15:46:08.268Z,1701186368.268 [DataOverHttps](INFO): Sending 1081 bytes from file Logs/20231128T154247/Express0001.lzma
2023-11-28T15:46:09.269Z,1701186369.269 [DataOverHttps](INFO): Moved sent file to Logs/20231128T154247/Express0001.lzma.bak
2023-11-28T15:46:09.269Z,1701186369.269 [DataOverHttps](INFO): SBD MOMSN=19074436
2023-11-28T15:46:10.333Z,1701186370.333 [Startup:StartupSatComms:B] Stopped
2023-11-28T15:46:10.333Z,1701186370.333 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-11-28T15:46:10.334Z,1701186370.334 [Startup:StartupSatComms] Stopped
2023-11-28T15:46:10.334Z,1701186370.334 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-11-28T15:46:10.334Z,1701186370.334 [Startup](INFO): Completed Startup
2023-11-28T15:46:10.335Z,1701186370.335 [MissionManager](INFO): Startup is completed.
2023-11-28T15:46:10.335Z,1701186370.335 [MissionManager](INFO): Uninitializing Mission Startup
2023-11-28T15:46:10.335Z,1701186370.335 [Startup] Stopped
2023-11-28T15:46:10.359Z,1701186370.359 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-11-28T15:46:10.359Z,1701186370.359 [Startup:A.GoToSurface] Stopped
2023-11-28T15:46:10.359Z,1701186370.359 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-28T15:46:10.753Z,1701186370.753 [MissionManager](IMPORTANT): Started mission Default
2023-11-28T15:46:10.753Z,1701186370.753 [Default] Running Loop=1
2023-11-28T15:46:10.754Z,1701186370.754 [Default](DEBUG): Aggregate::initialize Default
2023-11-28T15:46:10.754Z,1701186370.754 [Default:B.GoToSurface] Running Loop=1
2023-11-28T15:46:10.754Z,1701186370.754 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-28T15:46:10.754Z,1701186370.754 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-28T15:46:10.754Z,1701186370.754 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-28T15:46:10.755Z,1701186370.755 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-28T15:46:10.783Z,1701186370.783 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-28T15:46:10.784Z,1701186370.784 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-28T15:46:10.784Z,1701186370.784 [Default:A.Wait] Running Loop=1
2023-11-28T15:46:10.784Z,1701186370.784 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-11-28T15:46:24.106Z,1701186384.106 [Default:A.Wait](INFO): Done Waiting.
2023-11-28T15:46:24.106Z,1701186384.106 [Default:A.Wait] Stopped
2023-11-28T15:46:24.106Z,1701186384.106 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-11-28T15:46:24.464Z,1701186384.464 [Default:CheckIn] Running Loop=1
2023-11-28T15:46:24.464Z,1701186384.464 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-28T15:46:24.464Z,1701186384.464 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-28T15:46:24.862Z,1701186384.862 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-11-28T15:48:42.209Z,1701186522.209 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-28T15:49:00.848Z,1701186540.848 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-11-28T15:49:00.848Z,1701186540.848 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T15:49:00.859Z,1701186540.859 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T15:49:01.242Z,1701186541.242 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T15:49:01.242Z,1701186541.242 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-11-28T15:49:29.944Z,1701186569.944 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-3268,V
2023-11-28T15:50:25.643Z,1701186625.643 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-11-28T15:50:25.646Z,1701186625.646 [BPC1](INFO): Received data from all battery sticks.
2023-11-28T15:51:24.639Z,1701186684.639 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-28T15:46:24.5Z
2023-11-28T15:51:24.639Z,1701186684.639 [Default:CheckIn:Read_GPS] Stopped
2023-11-28T15:51:24.639Z,1701186684.639 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-28T15:51:25.042Z,1701186685.042 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-28T15:51:34.960Z,1701186694.960 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20231128T154247/Courier0004.lzma
2023-11-28T15:51:35.961Z,1701186695.961 [DataOverHttps](INFO): Moved sent file to Logs/20231128T154247/Courier0004.lzma.bak
2023-11-28T15:51:35.961Z,1701186695.961 [DataOverHttps](INFO): SBD MOMSN=19074460
2023-11-28T15:51:54.595Z,1701186714.595 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20231128T154247/Express0005.lzma
2023-11-28T15:51:55.597Z,1701186715.597 [DataOverHttps](INFO): Moved sent file to Logs/20231128T154247/Express0005.lzma.bak
2023-11-28T15:51:55.597Z,1701186715.597 [DataOverHttps](INFO): SBD MOMSN=19074462
2023-11-28T15:51:56.626Z,1701186716.626 [Default:CheckIn:Read_Iridium] Stopped
2023-11-28T15:51:56.626Z,1701186716.626 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-28T15:51:56.626Z,1701186716.626 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-28T15:52:01.956Z,1701186721.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-11-28T15:52:01.956Z,1701186721.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T15:52:02.010Z,1701186722.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T15:52:02.306Z,1701186722.306 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T15:52:02.306Z,1701186722.306 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-11-28T15:53:39.627Z,1701186819.627 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-28T15:53:39.627Z,1701186819.627 [NAL9602] Data Fault, FailCount= 1
2023-11-28T15:53:39.627Z,1701186819.627 [NAL9602](ERROR): Data Fault
2023-11-28T15:53:39.646Z,1701186819.646 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-28T15:53:40.025Z,1701186820.025 [NAL9602](INFO): Powering down
2023-11-28T15:53:40.855Z,1701186820.855 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-28T15:53:40.855Z,1701186820.855 [NAL9602] No Fault, FailCount= 1
2023-11-28T15:54:10.325Z,1701186850.325 [NAL9602](INFO): Powering up NAL9602
2023-11-28T15:54:21.232Z,1701186861.232 [NAL9602](INFO): NAL9602 initialized
2023-11-28T15:55:02.860Z,1701186902.860 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-11-28T15:55:02.860Z,1701186902.860 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T15:55:02.871Z,1701186902.871 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T15:55:03.284Z,1701186903.284 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T15:55:03.284Z,1701186903.284 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-11-28T15:56:57.189Z,1701187017.189 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-28T15:56:57.189Z,1701187017.189 [Default:CheckIn:C.Wait] Stopped
2023-11-28T15:56:57.189Z,1701187017.189 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-28T15:56:57.189Z,1701187017.189 [Default:CheckIn:D] Running Loop=1
2023-11-28T15:56:57.623Z,1701187017.623 [Default:CheckIn:D] Stopped
2023-11-28T15:56:57.623Z,1701187017.623 [Default:CheckIn:E] Running Loop=1
2023-11-28T15:56:58.050Z,1701187018.050 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.781154 min 
2023-11-28T15:56:58.050Z,1701187018.050 [Default:CheckIn:E] Stopped
2023-11-28T15:56:58.050Z,1701187018.050 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-28T15:56:58.050Z,1701187018.050 [Default:CheckIn] Stopped
2023-11-28T15:56:58.050Z,1701187018.050 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-28T15:56:58.050Z,1701187018.050 [Default:CheckIn](INFO): Running loop #2
2023-11-28T15:56:58.050Z,1701187018.050 [Default:CheckIn] Running Loop=2
2023-11-28T15:56:58.050Z,1701187018.050 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-28T15:56:58.051Z,1701187018.051 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-28T15:58:00.647Z,1701187080.647 [CBIT](INFO): Clearing failed state for component DropWeight
2023-11-28T15:58:00.647Z,1701187080.647 [DropWeight] No Fault, FailCount= 1
2023-11-28T15:58:03.904Z,1701187083.904 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-11-28T15:58:03.904Z,1701187083.904 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T15:58:03.933Z,1701187083.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T15:58:04.288Z,1701187084.288 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T15:58:04.288Z,1701187084.288 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-11-28T16:00:07.918Z,1701187207.918 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD,        +0.  0.00,1015.98
2023-11-28T16:01:04.841Z,1701187264.841 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-11-28T16:01:04.842Z,1701187264.842 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:01:04.852Z,1701187264.852 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:01:05.262Z,1701187265.262 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:01:05.262Z,1701187265.262 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-11-28T16:01:58.204Z,1701187318.204 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-28T15:56:58.1Z
2023-11-28T16:01:58.204Z,1701187318.204 [Default:CheckIn:Read_GPS] Stopped
2023-11-28T16:01:58.204Z,1701187318.204 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-28T16:02:05.888Z,1701187325.888 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231128T154247/Courier0007.lzma
2023-11-28T16:02:06.885Z,1701187326.885 [DataOverHttps](INFO): Moved sent file to Logs/20231128T154247/Courier0007.lzma.bak
2023-11-28T16:02:06.885Z,1701187326.885 [DataOverHttps](INFO): SBD MOMSN=19074466
2023-11-28T16:02:23.063Z,1701187343.063 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20231128T154247/Express0008.lzma
2023-11-28T16:02:24.065Z,1701187344.065 [DataOverHttps](INFO): Moved sent file to Logs/20231128T154247/Express0008.lzma.bak
2023-11-28T16:02:24.065Z,1701187344.065 [DataOverHttps](INFO): SBD MOMSN=19074468
2023-11-28T16:02:25.237Z,1701187345.237 [Default:CheckIn:Read_Iridium] Stopped
2023-11-28T16:02:25.237Z,1701187345.237 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-28T16:02:25.237Z,1701187345.237 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-28T16:04:05.843Z,1701187445.843 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-11-28T16:04:05.845Z,1701187445.845 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:04:05.908Z,1701187445.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:04:06.268Z,1701187446.268 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:04:06.268Z,1701187446.268 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-11-28T16:04:24.817Z,1701187464.817 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-28T16:04:24.817Z,1701187464.817 [NAL9602] Data Fault, FailCount= 2
2023-11-28T16:04:24.817Z,1701187464.817 [NAL9602](ERROR): Data Fault
2023-11-28T16:04:24.858Z,1701187464.858 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-28T16:04:25.221Z,1701187465.221 [NAL9602](INFO): Powering down
2023-11-28T16:04:26.071Z,1701187466.071 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-28T16:04:26.071Z,1701187466.071 [NAL9602] No Fault, FailCount= 2
2023-11-28T16:04:55.521Z,1701187495.521 [NAL9602](INFO): Powering up NAL9602
2023-11-28T16:05:06.425Z,1701187506.425 [NAL9602](INFO): NAL9602 initialized
2023-11-28T16:07:06.845Z,1701187626.845 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-11-28T16:07:06.845Z,1701187626.845 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:07:06.884Z,1701187626.884 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:07:07.243Z,1701187627.243 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:07:07.243Z,1701187627.243 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-11-28T16:07:25.877Z,1701187645.877 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-28T16:07:25.878Z,1701187645.878 [Default:CheckIn:C.Wait] Stopped
2023-11-28T16:07:25.878Z,1701187645.878 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-28T16:07:25.878Z,1701187645.878 [Default:CheckIn:D] Running Loop=1
2023-11-28T16:07:26.252Z,1701187646.252 [Default:CheckIn:D] Stopped
2023-11-28T16:07:26.253Z,1701187646.253 [Default:CheckIn:E] Running Loop=1
2023-11-28T16:07:26.626Z,1701187646.626 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.258315 min 
2023-11-28T16:07:26.626Z,1701187646.626 [Default:CheckIn:E] Stopped
2023-11-28T16:07:26.626Z,1701187646.626 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-28T16:07:26.626Z,1701187646.626 [Default:CheckIn] Stopped
2023-11-28T16:07:26.626Z,1701187646.626 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-28T16:07:26.626Z,1701187646.626 [Default:CheckIn](INFO): Running loop #3
2023-11-28T16:07:26.626Z,1701187646.626 [Default:CheckIn] Running Loop=3
2023-11-28T16:07:26.626Z,1701187646.626 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-28T16:07:26.627Z,1701187646.627 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-28T16:10:07.892Z,1701187807.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-11-28T16:10:07.892Z,1701187807.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:10:07.926Z,1701187807.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:10:08.308Z,1701187808.308 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:10:08.308Z,1701187808.308 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-11-28T16:12:26.624Z,1701187946.624 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.603119
2023-11-28T16:12:26.828Z,1701187946.828 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-28T16:07:26.6Z
2023-11-28T16:12:26.828Z,1701187946.828 [Default:CheckIn:Read_GPS] Stopped
2023-11-28T16:12:26.828Z,1701187946.828 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-28T16:12:59.755Z,1701187979.755 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:13:08.857Z,1701187988.857 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-11-28T16:13:08.857Z,1701187988.857 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:13:08.868Z,1701187988.868 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:13:09.280Z,1701187989.280 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:13:09.280Z,1701187989.280 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-11-28T16:13:34.895Z,1701188014.895 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:14:10.039Z,1701188050.039 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:14:45.179Z,1701188085.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:15:10.021Z,1701188110.021 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-28T16:15:10.021Z,1701188110.021 [NAL9602] Data Fault, FailCount= 3
2023-11-28T16:15:10.021Z,1701188110.021 [NAL9602](ERROR): Data Fault
2023-11-28T16:15:10.084Z,1701188110.084 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-28T16:15:10.425Z,1701188110.425 [NAL9602](INFO): Powering down
2023-11-28T16:15:11.258Z,1701188111.258 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-28T16:15:11.258Z,1701188111.258 [NAL9602] No Fault, FailCount= 3
2023-11-28T16:15:20.331Z,1701188120.331 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:15:40.729Z,1701188140.729 [NAL9602](INFO): Powering up NAL9602
2023-11-28T16:15:51.637Z,1701188151.637 [NAL9602](INFO): NAL9602 initialized
2023-11-28T16:15:55.483Z,1701188155.483 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:16:09.822Z,1701188169.822 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-11-28T16:16:09.822Z,1701188169.822 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:16:09.833Z,1701188169.833 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:16:10.274Z,1701188170.274 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:16:10.274Z,1701188170.274 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-11-28T16:16:30.624Z,1701188190.624 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:17:05.763Z,1701188225.763 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:17:40.903Z,1701188260.903 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:18:16.071Z,1701188296.071 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:18:51.211Z,1701188331.211 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:19:10.856Z,1701188350.856 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-11-28T16:19:10.856Z,1701188350.856 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:19:10.868Z,1701188350.868 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:19:11.242Z,1701188351.242 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:19:11.242Z,1701188351.242 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-11-28T16:19:26.357Z,1701188366.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:20:01.496Z,1701188401.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:20:36.635Z,1701188436.635 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:21:11.783Z,1701188471.783 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:21:46.936Z,1701188506.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:22:11.878Z,1701188531.878 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-11-28T16:22:11.878Z,1701188531.878 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:22:11.906Z,1701188531.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:22:12.264Z,1701188532.264 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:22:12.264Z,1701188532.264 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-11-28T16:22:22.075Z,1701188542.075 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:22:57.223Z,1701188577.223 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:23:32.364Z,1701188612.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:24:07.512Z,1701188647.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:24:42.651Z,1701188682.651 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-28T16:25:12.828Z,1701188712.828 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2023-11-28T16:25:12.828Z,1701188712.828 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:25:12.880Z,1701188712.880 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:25:13.266Z,1701188713.266 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:25:13.267Z,1701188713.267 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2023-11-28T16:25:15.288Z,1701188715.288 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003742
2023-11-28T16:25:22.631Z,1701188722.631 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231128T154247/Courier0010.lzma
2023-11-28T16:25:23.633Z,1701188723.633 [DataOverHttps](INFO): Moved sent file to Logs/20231128T154247/Courier0010.lzma.bak
2023-11-28T16:25:23.633Z,1701188723.633 [DataOverHttps](INFO): SBD MOMSN=19074472
2023-11-28T16:25:39.519Z,1701188739.519 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20231128T154247/Express0011.lzma
2023-11-28T16:25:40.521Z,1701188740.521 [DataOverHttps](INFO): Moved sent file to Logs/20231128T154247/Express0011.lzma.bak
2023-11-28T16:25:40.521Z,1701188740.521 [DataOverHttps](INFO): SBD MOMSN=19074474
2023-11-28T16:25:41.938Z,1701188741.938 [Default:CheckIn:Read_Iridium] Stopped
2023-11-28T16:25:41.939Z,1701188741.939 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-28T16:25:41.939Z,1701188741.939 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-28T16:25:55.221Z,1701188755.221 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-28T16:25:55.221Z,1701188755.221 [NAL9602] Data Fault, FailCount= 4
2023-11-28T16:25:55.221Z,1701188755.221 [NAL9602](ERROR): Data Fault
2023-11-28T16:25:55.272Z,1701188755.272 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-28T16:25:55.625Z,1701188755.625 [NAL9602](INFO): Powering down
2023-11-28T16:25:56.454Z,1701188756.454 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-28T16:25:56.454Z,1701188756.454 [NAL9602] No Fault, FailCount= 4
2023-11-28T16:26:09.484Z,1701188769.484 [CommandExec](IMPORTANT): got command failComponent
2023-11-28T16:26:09.484Z,1701188769.484 [CommandExec](IMPORTANT): Failed components:
2023-11-28T16:26:09.484Z,1701188769.484 [CommandExec](IMPORTANT): No failed Components.
2023-11-28T16:26:20.832Z,1701188780.832 [CommandExec](IMPORTANT): got command gfscan
2023-11-28T16:26:21.122Z,1701188781.122 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-28T16:26:25.930Z,1701188785.930 [NAL9602](INFO): Powering up NAL9602
2023-11-28T16:26:32.058Z,1701188792.058 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007818
CHAN A1 (24V): 0.005495
CHAN A2 (12V): -0.005712
CHAN A3 (5V): -0.002369
CHAN B0 (3.3V): 0.000785
CHAN B1 (3.15aV): -0.000036
CHAN B2 (3.15bV): -0.000043
CHAN B3 (GND): 0.001301
OPEN: 0.005302
Full Scale: +/- 1 mA
2023-11-28T16:26:36.833Z,1701188796.833 [NAL9602](INFO): NAL9602 initialized
2023-11-28T16:28:13.805Z,1701188893.805 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2023-11-28T16:28:13.805Z,1701188893.805 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-28T16:28:13.816Z,1701188893.816 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-28T16:28:14.241Z,1701188894.241 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-28T16:28:14.241Z,1701188894.241 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2023-11-28T16:28:29.328Z,1701188909.328 [CommandExec](IMPORTANT): got command restart system
2023-11-28T16:28:31.455Z,1701188911.455 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-28T16:28:31.456Z,1701188911.456 [CommandExec](INFO): Uninitializing the command executive.
2023-11-28T16:28:31.456Z,1701188911.456 [CommandExec](INFO): Uninitializing the command scheduler.
2023-11-28T16:28:31.456Z,1701188911.456 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:31.511Z,1701188911.511 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-11-28T16:28:31.512Z,1701188911.512 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-11-28T16:28:31.512Z,1701188911.512 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:31.513Z,1701188911.513 [NavChartDb](INFO): Join timeout helper Thread ID is 7701
2023-11-28T16:28:31.751Z,1701188911.751 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-28T16:28:31.752Z,1701188911.752 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:31.759Z,1701188911.759 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-11-28T16:28:31.759Z,1701188911.759 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:31.760Z,1701188911.760 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7702
2023-11-28T16:28:31.824Z,1701188911.824 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-28T16:28:31.824Z,1701188911.824 [WetLabsUBAT](INFO): Powering down
2023-11-28T16:28:31.825Z,1701188911.825 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:31.839Z,1701188911.839 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-11-28T16:28:31.839Z,1701188911.839 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:31.840Z,1701188911.840 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7703
2023-11-28T16:28:31.975Z,1701188911.975 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23112808293935,35.,   0.0,1521.3,  0
2023-11-28T16:28:32.263Z,1701188912.263 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-28T16:28:32.264Z,1701188912.264 [WetLabsBB2FL](INFO): Powering down
2023-11-28T16:28:32.264Z,1701188912.264 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:32.280Z,1701188912.280 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-11-28T16:28:32.280Z,1701188912.280 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:32.280Z,1701188912.280 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7704
2023-11-28T16:28:33.067Z,1701188913.067 [CTD_Seabird](INFO): Powering down
2023-11-28T16:28:33.080Z,1701188913.080 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-28T16:28:33.081Z,1701188913.081 [CTD_Seabird](INFO): Powering down
2023-11-28T16:28:33.095Z,1701188913.095 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:33.099Z,1701188913.099 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-11-28T16:28:33.099Z,1701188913.099 [DAT ThreadHandler](INFO): Thread cancelled.
2023-11-28T16:28:33.100Z,1701188913.100 [DAT](INFO): Join timeout helper Thread ID is 7705
2023-11-28T16:28:33.331Z,1701188913.331 [DAT](INFO): Powering down
2023-11-28T16:28:33.400Z,1701188913.400 [DAT Th