2023-08-16T12:14:19.819Z,1692188059.819 [Supervisor](DEBUG): Initializing supervisor.
2023-08-16T12:14:19.823Z,1692188059.823 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-16T12:14:19.824Z,1692188059.824 [SyncHandler](INFO): Protected caller Thread ID is 837
2023-08-16T12:14:19.824Z,1692188059.824 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-16T12:14:19.825Z,1692188059.825 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-16T12:14:19.826Z,1692188059.826 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2023-08-16T12:14:19.830Z,1692188059.830 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-16T12:14:19.847Z,1692188059.847 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-16T12:14:19.848Z,1692188059.848 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-16T12:14:19.848Z,1692188059.848 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2023-08-16T12:14:19.852Z,1692188059.852 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-16T12:14:19.853Z,1692188059.853 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-16T12:14:19.854Z,1692188059.854 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2023-08-16T12:14:19.856Z,1692188059.856 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-16T12:14:19.857Z,1692188059.857 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-16T12:14:19.857Z,1692188059.857 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2023-08-16T12:14:19.861Z,1692188059.861 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-16T12:14:19.861Z,1692188059.861 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-16T12:14:19.865Z,1692188059.865 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-16T12:14:20.353Z,1692188060.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-16T12:14:20.354Z,1692188060.354 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-16T12:14:20.567Z,1692188060.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-16T12:14:20.569Z,1692188060.569 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-16T12:14:20.651Z,1692188060.651 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-16T12:14:20.875Z,1692188060.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-16T12:14:20.877Z,1692188060.877 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-16T12:14:20.957Z,1692188060.957 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-16T12:14:21.054Z,1692188061.054 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-16T12:14:21.055Z,1692188061.055 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-16T12:14:21.420Z,1692188061.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-16T12:14:21.421Z,1692188061.421 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-16T12:14:21.555Z,1692188061.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-16T12:14:21.556Z,1692188061.556 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-16T12:14:22.057Z,1692188062.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-16T12:14:22.058Z,1692188062.058 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-16T12:14:22.260Z,1692188062.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-16T12:14:22.261Z,1692188062.261 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-16T12:14:22.688Z,1692188062.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-16T12:14:22.690Z,1692188062.690 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-16T12:14:22.988Z,1692188062.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-16T12:14:22.990Z,1692188062.990 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-16T12:14:23.299Z,1692188063.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-16T12:14:23.300Z,1692188063.300 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-16T12:14:24.706Z,1692188064.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-16T12:14:24.706Z,1692188064.706 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-16T12:14:25.047Z,1692188065.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-16T12:14:25.049Z,1692188065.049 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-16T12:14:25.281Z,1692188065.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-16T12:14:25.283Z,1692188065.283 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-08-16T12:14:25.287Z,1692188065.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-08-16T12:14:25.414Z,1692188065.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-08-16T12:14:25.498Z,1692188065.498 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-08-16T12:14:25.642Z,1692188065.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-08-16T12:14:25.730Z,1692188065.730 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-08-16T12:14:26.002Z,1692188066.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-16T12:14:26.003Z,1692188066.003 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-08-16T12:14:26.109Z,1692188066.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-08-16T12:14:26.218Z,1692188066.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-08-16T12:14:26.341Z,1692188066.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-08-16T12:14:26.494Z,1692188066.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-08-16T12:14:26.594Z,1692188066.594 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-08-16T12:14:26.693Z,1692188066.693 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-08-16T12:14:26.714Z,1692188066.714 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-16T12:14:26.731Z,1692188066.731 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-16T12:14:26.731Z,1692188066.731 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-16T12:14:26.861Z,1692188066.861 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-16T12:14:26.861Z,1692188066.861 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-16T12:14:26.932Z,1692188066.932 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-16T12:14:26.992Z,1692188066.992 [VerticalControl] Loaded
2023-08-16T12:14:26.993Z,1692188066.993 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-16T12:14:26.996Z,1692188066.996 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-16T12:14:27.038Z,1692188067.038 [HorizontalControl] Loaded
2023-08-16T12:14:27.038Z,1692188067.038 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-16T12:14:27.040Z,1692188067.040 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-16T12:14:27.043Z,1692188067.043 [SpeedControl] Loaded
2023-08-16T12:14:27.043Z,1692188067.043 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-16T12:14:27.046Z,1692188067.046 [LoopControl](DEBUG): Construct LoopControl.
2023-08-16T12:14:27.047Z,1692188067.047 [LoopControl] Loaded
2023-08-16T12:14:27.047Z,1692188067.047 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-16T12:14:27.047Z,1692188067.047 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-16T12:14:27.048Z,1692188067.048 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-16T12:14:27.236Z,1692188067.236 [BuoyancyServo] Loaded
2023-08-16T12:14:27.236Z,1692188067.236 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-16T12:14:27.256Z,1692188067.256 [ElevatorServo] Loaded
2023-08-16T12:14:27.256Z,1692188067.256 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-16T12:14:27.275Z,1692188067.275 [MassServo] Loaded
2023-08-16T12:14:27.276Z,1692188067.276 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-16T12:14:27.295Z,1692188067.295 [RudderServo] Loaded
2023-08-16T12:14:27.295Z,1692188067.295 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-16T12:14:27.310Z,1692188067.310 [ThrusterHE] Loaded
2023-08-16T12:14:27.310Z,1692188067.310 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-08-16T12:14:27.310Z,1692188067.310 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-16T12:14:27.311Z,1692188067.311 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-16T12:14:27.382Z,1692188067.382 [DepthRateCalculator] Loaded
2023-08-16T12:14:27.382Z,1692188067.382 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-16T12:14:27.387Z,1692188067.387 [PitchRateCalculator] Loaded
2023-08-16T12:14:27.387Z,1692188067.387 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-16T12:14:27.397Z,1692188067.397 [SpeedCalculator] Loaded
2023-08-16T12:14:27.397Z,1692188067.397 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-16T12:14:27.401Z,1692188067.401 [YawRateCalculator] Loaded
2023-08-16T12:14:27.402Z,1692188067.402 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-16T12:14:27.420Z,1692188067.420 [ElevatorOffsetCalculator] Loaded
2023-08-16T12:14:27.421Z,1692188067.421 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-16T12:14:27.421Z,1692188067.421 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-16T12:14:27.422Z,1692188067.422 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-16T12:14:27.578Z,1692188067.578 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-08-16T12:14:27.579Z,1692188067.579 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-08-16T12:14:27.592Z,1692188067.592 [NavChart] Loaded
2023-08-16T12:14:27.593Z,1692188067.593 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-16T12:14:27.598Z,1692188067.598 [UniversalFixResidualReporter] Loaded
2023-08-16T12:14:27.599Z,1692188067.599 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-16T12:14:27.599Z,1692188067.599 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-16T12:14:27.600Z,1692188067.600 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-16T12:14:28.356Z,1692188068.356 [AHRS_M2] Loaded
2023-08-16T12:14:28.357Z,1692188068.357 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-16T12:14:28.607Z,1692188068.607 [BackseatComponent] Loaded
2023-08-16T12:14:28.607Z,1692188068.607 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-08-16T12:14:28.608Z,1692188068.608 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2023-08-16T12:14:28.608Z,1692188068.608 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2023-08-16T12:14:28.611Z,1692188068.611 [LcmUniversalReporter] Loaded
2023-08-16T12:14:28.612Z,1692188068.612 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-08-16T12:14:28.618Z,1692188068.618 [BioacousticsDataBridge] Loaded
2023-08-16T12:14:28.618Z,1692188068.618 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2023-08-16T12:14:29.456Z,1692188069.456 [BPC1] Loaded
2023-08-16T12:14:29.456Z,1692188069.456 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-16T12:14:29.608Z,1692188069.608 [DataOverHttps] Loaded
2023-08-16T12:14:29.609Z,1692188069.609 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-16T12:14:29.610Z,1692188069.610 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2023-08-16T12:14:29.610Z,1692188069.610 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2023-08-16T12:14:29.631Z,1692188069.631 [Depth_Keller] Loaded
2023-08-16T12:14:29.632Z,1692188069.632 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-16T12:14:29.637Z,1692188069.637 [DropWeight] Loaded
2023-08-16T12:14:29.637Z,1692188069.637 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-16T12:14:29.700Z,1692188069.700 [NAL9602] Loaded
2023-08-16T12:14:29.700Z,1692188069.700 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-16T12:14:29.730Z,1692188069.730 [Onboard] Loaded
2023-08-16T12:14:29.731Z,1692188069.731 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-16T12:14:29.732Z,1692188069.732 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2023-08-16T12:14:29.732Z,1692188069.732 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2023-08-16T12:14:29.744Z,1692188069.744 [Power24vConverter] Loaded
2023-08-16T12:14:29.745Z,1692188069.745 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-16T12:14:29.758Z,1692188069.758 [Radio_Surface] Loaded
2023-08-16T12:14:29.759Z,1692188069.759 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-16T12:14:29.760Z,1692188069.760 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2023-08-16T12:14:29.760Z,1692188069.760 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2023-08-16T12:14:29.774Z,1692188069.774 [Sonardyne_Nano] Loaded
2023-08-16T12:14:29.774Z,1692188069.774 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-08-16T12:14:29.775Z,1692188069.775 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-16T12:14:29.775Z,1692188069.775 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-16T12:14:30.431Z,1692188070.431 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-16T12:14:30.431Z,1692188070.431 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-16T12:14:30.820Z,1692188070.820 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-16T12:14:30.821Z,1692188070.821 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-16T12:14:30.864Z,1692188070.864 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-16T12:14:30.865Z,1692188070.865 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-16T12:14:31.089Z,1692188071.089 [CTD_Seabird] Loaded
2023-08-16T12:14:31.090Z,1692188071.090 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-16T12:14:31.091Z,1692188071.091 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AAE4E0
2023-08-16T12:14:31.091Z,1692188071.091 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2023-08-16T12:14:31.111Z,1692188071.111 [PAR_Licor] Loaded
2023-08-16T12:14:31.112Z,1692188071.112 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-16T12:14:31.153Z,1692188071.153 [WetLabsBB2FL] Loaded
2023-08-16T12:14:31.153Z,1692188071.153 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-16T12:14:31.154Z,1692188071.154 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ADE4E0
2023-08-16T12:14:31.154Z,1692188071.154 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2023-08-16T12:14:31.155Z,1692188071.155 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-16T12:14:31.156Z,1692188071.156 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-16T12:14:31.528Z,1692188071.528 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-16T12:14:31.530Z,1692188071.530 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-16T12:14:31.701Z,1692188071.701 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-16T12:14:31.710Z,1692188071.710 [SBIT] Loaded
2023-08-16T12:14:31.710Z,1692188071.710 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-16T12:14:31.713Z,1692188071.713 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-16T12:14:31.727Z,1692188071.727 [IBIT] Loaded
2023-08-16T12:14:31.727Z,1692188071.727 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-16T12:14:31.733Z,1692188071.733 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-16T12:14:31.840Z,1692188071.840 [CBIT] Loaded
2023-08-16T12:14:31.840Z,1692188071.840 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-16T12:14:31.840Z,1692188071.840 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-16T12:14:31.847Z,1692188071.847 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-16T12:14:31.849Z,1692188071.849 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-16T12:14:31.861Z,1692188071.861 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-16T12:14:31.862Z,1692188071.862 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCA4E0
2023-08-16T12:14:31.862Z,1692188071.862 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2023-08-16T12:14:31.866Z,1692188071.866 [Supervisor](INFO): Main Thread ID is 830
2023-08-16T12:14:31.867Z,1692188071.867 [Supervisor](DEBUG): Running supervisor.
2023-08-16T12:14:31.867Z,1692188071.867 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2023-08-16T12:14:31.868Z,1692188071.868 [CommandExec](INFO): Initializing the command executive.
2023-08-16T12:14:31.869Z,1692188071.869 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2023-08-16T12:14:31.871Z,1692188071.871 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2023-08-16T12:14:31.872Z,1692188071.872 [controlThread](DEBUG): Initializing ControlThread
2023-08-16T12:14:31.873Z,1692188071.873 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-16T12:14:31.874Z,1692188071.874 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-16T12:14:31.875Z,1692188071.875 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-16T12:14:31.876Z,1692188071.876 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-16T12:14:31.877Z,1692188071.877 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-16T12:14:31.877Z,1692188071.877 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-16T12:14:31.878Z,1692188071.878 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-16T12:14:31.878Z,1692188071.878 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-16T12:14:31.879Z,1692188071.879 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-16T12:14:31.880Z,1692188071.880 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-16T12:14:31.880Z,1692188071.880 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-16T12:14:31.888Z,1692188071.888 [SBIT](INFO): Initialize SBIT Component.
2023-08-16T12:14:31.889Z,1692188071.889 [SBIT](IMPORTANT): git: 2023-08-14A
2023-08-16T12:14:31.889Z,1692188071.889 [SBIT](INFO): git hash: 4e38850369b7c4f6a1b70506b817fd3bcb27e673
2023-08-16T12:14:31.889Z,1692188071.889 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-16T12:14:31.890Z,1692188071.890 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-08-16T12:14:31.891Z,1692188071.891 [SBIT](INFO): Beginning SBIT in 116.000000 seconds.
2023-08-16T12:14:31.892Z,1692188071.892 [IBIT](INFO): Initialize IBIT Component.
2023-08-16T12:14:31.893Z,1692188071.893 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-16T12:14:31.894Z,1692188071.894 [logger ThreadHandler](INFO): Handler Thread ID is 934
2023-08-16T12:14:31.904Z,1692188071.904 [CBIT](DEBUG): Initialized mux pins.
2023-08-16T12:14:31.904Z,1692188071.904 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-16T12:14:31.912Z,1692188071.912 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2023-08-16T12:14:31.924Z,1692188071.924 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936
2023-08-16T12:14:31.925Z,1692188071.925 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-16T12:14:31.928Z,1692188071.928 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-16T12:14:31.928Z,1692188071.928 [CBIT](DEBUG): Initializing heartbeat.
2023-08-16T12:14:31.936Z,1692188071.936 [Onboard ThreadHandler](INFO): Handler Thread ID is 937
2023-08-16T12:14:31.954Z,1692188071.954 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938
2023-08-16T12:14:31.973Z,1692188071.973 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2023-08-16T12:14:31.973Z,1692188071.973 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-16T12:14:31.977Z,1692188071.977 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2023-08-16T12:14:31.980Z,1692188071.980 [WetLabsBB2FL](INFO): Powering up
2023-08-16T12:14:31.981Z,1692188071.981 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2023-08-16T12:14:31.990Z,1692188071.990 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-08-16T12:14:31.991Z,1692188071.991 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-08-16T12:14:31.991Z,1692188071.991 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-08-16T12:14:31.991Z,1692188071.991 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-08-16T12:14:31.991Z,1692188071.991 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-08-16T12:14:31.991Z,1692188071.991 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-08-16T12:14:31.991Z,1692188071.991 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-08-16T12:14:31.992Z,1692188071.992 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-08-16T12:14:31.992Z,1692188071.992 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-08-16T12:14:31.992Z,1692188071.992 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-08-16T12:14:31.993Z,1692188071.993 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-08-16T12:14:31.993Z,1692188071.993 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-08-16T12:14:31.993Z,1692188071.993 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI03M.000
2023-08-16T12:14:31.993Z,1692188071.993 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI03M.000
2023-08-16T12:14:31.993Z,1692188071.993 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI06M.000
2023-08-16T12:14:31.993Z,1692188071.993 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI06M.000
2023-08-16T12:14:31.994Z,1692188071.994 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-08-16T12:14:31.994Z,1692188071.994 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-08-16T12:14:31.994Z,1692188071.994 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-08-16T12:14:31.994Z,1692188071.994 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-08-16T12:14:31.994Z,1692188071.994 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-08-16T12:14:31.994Z,1692188071.994 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-08-16T12:14:31.995Z,1692188071.995 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-08-16T12:14:31.995Z,1692188071.995 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-08-16T12:14:31.995Z,1692188071.995 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-08-16T12:14:31.995Z,1692188071.995 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-08-16T12:14:31.000Z,1692188072.000 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-16T12:14:32.000Z,1692188072.000 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-16T12:14:32.040Z,1692188072.040 [CBIT](DEBUG): Backplane powered.
2023-08-16T12:14:32.041Z,1692188072.041 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-16T12:14:32.074Z,1692188072.074 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-16T12:14:32.101Z,1692188072.101 [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-08-16T12:14:32.102Z,1692188072.102 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-16T12:14:32.169Z,1692188072.169 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-16T12:14:32.170Z,1692188072.170 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-16T12:14:32.173Z,1692188072.173 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-16T12:14:32.223Z,1692188072.223 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-16T12:14:32.226Z,1692188072.226 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-16T12:14:32.247Z,1692188072.247 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-16T12:14:32.267Z,1692188072.267 [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-08-16T12:14:32.272Z,1692188072.272 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-08-16T12:14:32.285Z,1692188072.285 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-16T12:14:32.360Z,1692188072.360 [Radio_Surface](INFO): Powering up
2023-08-16T12:14:32.470Z,1692188072.470 [Power24vConverter](INFO): Powering up.
2023-08-16T12:14:32.471Z,1692188072.471 [Sonardyne_Nano](INFO): Initializing.
2023-08-16T12:14:32.562Z,1692188072.562 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-16T12:14:32.568Z,1692188072.568 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-16T12:14:32.569Z,1692188072.569 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-16T12:14:32.576Z,1692188072.576 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-16T12:14:32.577Z,1692188072.577 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-16T12:14:32.584Z,1692188072.584 [MassServo](DEBUG): Initializing MassServo.
2023-08-16T12:14:32.585Z,1692188072.585 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-16T12:14:32.592Z,1692188072.592 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-16T12:14:32.593Z,1692188072.593 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-16T12:14:32.600Z,1692188072.600 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-16T12:14:34.088Z,1692188074.088 [WetLabsBB2FL](INFO): Powering down
2023-08-16T12:14:34.706Z,1692188074.706 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-08-16T12:14:35.957Z,1692188075.957 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2201
2023-08-16T12:14:38.209Z,1692188078.209 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-08-16T12:14:39.016Z,1692188079.016 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-08-16T12:14:59.758Z,1692188099.758 [NAL9602](INFO): Powering up NAL9602
2023-08-16T12:15:10.670Z,1692188110.670 [NAL9602](INFO): NAL9602 initialized
2023-08-16T12:15:55.567Z,1692188155.567 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T12:16:00.411Z,1692188160.411 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-08-16T12:16:14.098Z,1692188174.098 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-16T12:16:15.320Z,1692188175.320 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,121604.00,A,4313.59269,N,08620.36287,W,0.175,151.82,160823,,,A*7E
2023-08-16T12:16:15.323Z,1692188175.323 [NAL9602](INFO): GPS fix at 20230816T121604: (43.226545, -86.339381)
2023-08-16T12:16:25.064Z,1692188185.064 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T12:16:28.670Z,1692188188.670 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-16T12:16:28.674Z,1692188188.674 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-16T12:16:39.637Z,1692188199.637 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008950
CHAN A1 (24V): -0.007021
CHAN A2 (12V): -0.005035
CHAN A3 (5V): -0.003432
CHAN B0 (3.3V): -0.000876
CHAN B1 (3.15aV): -0.000870
CHAN B2 (3.15bV): -0.000841
CHAN B3 (GND): 0.000096
OPEN: -0.004735
Full Scale: +/- 1 mA
2023-08-16T12:16:47.225Z,1692188207.225 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T12:17:09.490Z,1692188229.490 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-08-16T12:17:09.910Z,1692188229.910 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T12:17:22.800Z,1692188242.800 [SBIT](IMPORTANT): SBIT PASSED
2023-08-16T12:17:22.800Z,1692188242.800 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-08-16T12:17:22.801Z,1692188242.801 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-08-16T12:17:22.801Z,1692188242.801 [SBIT](IMPORTANT): CBIT.gfScanTimeout=90 minute;

2023-08-16T12:17:22.801Z,1692188242.801 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-08-16T12:17:22.801Z,1692188242.801 [SBIT](IMPORTANT): Express linearApproximation BackseatComponent.handled_message 1.000000 bool;

2023-08-16T12:17:22.801Z,1692188242.801 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_mean_sea_water_temperature 5.000000 celsius;

2023-08-16T12:17:22.802Z,1692188242.802 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-08-16T12:17:22.802Z,1692188242.802 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-08-16T12:17:22.802Z,1692188242.802 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 50.000000 meter;

2023-08-16T12:17:22.802Z,1692188242.802 [SBIT](IMPORTANT): Express none platform_roll_angle;

2023-08-16T12:17:22.802Z,1692188242.802 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=15 ampere_hour;

2023-08-16T12:17:22.802Z,1692188242.802 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11.5 volt;

2023-08-16T12:17:22.802Z,1692188242.802 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2023-08-16T12:17:22.802Z,1692188242.802 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=1 minute;

2023-08-16T12:17:22.803Z,1692188242.803 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-08-16T12:17:22.803Z,1692188242.803 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=427.503748 cubic_centimeter;

2023-08-16T12:17:22.803Z,1692188242.803 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-08-16T12:17:22.803Z,1692188242.803 [SBIT](IMPORTANT): VerticalControl.massDefault=24.156915 millimeter;

2023-08-16T12:17:23.249Z,1692188243.249 [MissionManager](IMPORTANT): Started mission Startup
2023-08-16T12:17:23.250Z,1692188243.250 [Startup] Running Loop=1
2023-08-16T12:17:23.250Z,1692188243.250 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-16T12:17:23.250Z,1692188243.250 [Startup:A.GoToSurface] Running Loop=1
2023-08-16T12:17:23.250Z,1692188243.250 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-16T12:17:23.251Z,1692188243.251 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-16T12:17:23.251Z,1692188243.251 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-16T12:17:23.251Z,1692188243.251 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-16T12:17:23.252Z,1692188243.252 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-16T12:17:23.252Z,1692188243.252 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-16T12:17:23.254Z,1692188243.254 [Startup:StartupSatComms] Running Loop=1
2023-08-16T12:17:23.254Z,1692188243.254 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-16T12:17:23.254Z,1692188243.254 [Startup:StartupSatComms:A] Running Loop=1
2023-08-16T12:17:23.602Z,1692188243.602 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-16T12:17:25.216Z,1692188245.216 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,121714.00,A,4313.59377,N,08620.36138,W,1.302,327.20,160823,,,A*7F
2023-08-16T12:17:25.218Z,1692188245.218 [NAL9602](INFO): GPS fix at 20230816T121714: (43.226563, -86.339356)
2023-08-16T12:17:25.229Z,1692188245.229 [Startup:StartupSatComms:A] Stopped
2023-08-16T12:17:25.229Z,1692188245.229 [Startup:StartupSatComms:B] Running Loop=1
2023-08-16T12:17:25.637Z,1692188245.637 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-16T12:17:55.913Z,1692188275.913 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2023-08-16T12:17:55.996Z,1692188275.996 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-08-16T12:18:05.722Z,1692188285.722 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004338
2023-08-16T12:18:22.826Z,1692188302.826 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T12:18:25.645Z,1692188305.645 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-16T12:17:25.2Z
2023-08-16T12:18:25.645Z,1692188305.645 [Startup:StartupSatComms:B] Stopped
2023-08-16T12:18:25.645Z,1692188305.645 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-16T12:18:25.645Z,1692188305.645 [Startup:StartupSatComms] Stopped
2023-08-16T12:18:25.645Z,1692188305.645 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-16T12:18:25.646Z,1692188305.646 [Startup](INFO): Completed Startup
2023-08-16T12:18:25.646Z,1692188305.646 [MissionManager](INFO): Startup is completed.
2023-08-16T12:18:25.646Z,1692188305.646 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-16T12:18:25.646Z,1692188305.646 [Startup] Stopped
2023-08-16T12:18:25.646Z,1692188305.646 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-16T12:18:25.646Z,1692188305.646 [Startup:A.GoToSurface] Stopped
2023-08-16T12:18:25.646Z,1692188305.646 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-16T12:18:26.033Z,1692188306.033 [MissionManager](IMPORTANT): Started mission Default
2023-08-16T12:18:26.033Z,1692188306.033 [Default] Running Loop=1
2023-08-16T12:18:26.033Z,1692188306.033 [Default](DEBUG): Aggregate::initialize Default
2023-08-16T12:18:26.033Z,1692188306.033 [Default:B.GoToSurface] Running Loop=1
2023-08-16T12:18:26.034Z,1692188306.034 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-16T12:18:26.034Z,1692188306.034 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-16T12:18:26.034Z,1692188306.034 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-16T12:18:26.034Z,1692188306.034 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-16T12:18:26.035Z,1692188306.035 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-16T12:18:26.035Z,1692188306.035 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-16T12:18:26.035Z,1692188306.035 [Default:A.Wait] Running Loop=1
2023-08-16T12:18:26.035Z,1692188306.035 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-16T12:18:37.115Z,1692188317.115 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T12:18:39.409Z,1692188319.409 [Default:A.Wait](INFO): Done Waiting.
2023-08-16T12:18:39.409Z,1692188319.409 [Default:A.Wait] Stopped
2023-08-16T12:18:39.409Z,1692188319.409 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-16T12:18:39.805Z,1692188319.805 [Default:CheckIn] Running Loop=1
2023-08-16T12:18:39.805Z,1692188319.805 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-16T12:18:39.805Z,1692188319.805 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-16T12:18:40.222Z,1692188320.222 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-16T12:19:00.038Z,1692188340.038 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-08-16T12:19:33.576Z,1692188373.576 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T12:19:44.017Z,1692188384.017 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-16T12:19:45.236Z,1692188385.236 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,121935.00,A,4313.57514,N,08620.34341,W,0.680,146.53,160823,,,A*7E
2023-08-16T12:19:45.239Z,1692188385.239 [NAL9602](INFO): GPS fix at 20230816T121935: (43.226252, -86.339057)
2023-08-16T12:19:45.250Z,1692188385.250 [Default:CheckIn:Read_GPS] Stopped
2023-08-16T12:19:45.250Z,1692188385.250 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-16T12:19:45.798Z,1692188385.798 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-16T12:20:13.006Z,1692188413.006 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-08-16T12:20:18.161Z,1692188418.161 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T12:20:27.105Z,1692188427.105 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-08-16T12:20:31.543Z,1692188431.543 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T12:21:12.884Z,1692188472.884 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T12:21:22.963Z,1692188482.963 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-08-16T12:21:38.305Z,1692188498.305 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T12:22:23.914Z,1692188543.914 [BPC1](ERROR): BPC1B: No match for serial number 0000 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-08-16T12:22:54.855Z,1692188574.855 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-16T12:22:54.887Z,1692188574.887 [BPC1](INFO): Received data from all battery sticks.
2023-08-16T12:24:33.313Z,1692188673.313 [Power24vConverter](INFO): Powering down.
2023-08-16T13:08:42.845Z,1692191322.845 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-16T13:09:11.968Z,1692191351.968 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.246185
2023-08-16T13:09:13.077Z,1692191353.077 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2023-08-16T13:09:13.160Z,1692191353.160 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-08-16T13:09:43.092Z,1692191383.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-16T13:10:04.489Z,1692191404.489 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002967
2023-08-16T13:10:37.930Z,1692191437.930 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-16T13:22:15.033Z,1692192135.033 [CommandExec](IMPORTANT): got command failComponent
2023-08-16T13:22:15.033Z,1692192135.033 [CommandExec](IMPORTANT): Failed components:
2023-08-16T13:22:15.033Z,1692192135.033 [CommandExec](IMPORTANT): No failed Components.
2023-08-16T13:28:23.927Z,1692192503.927 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-08-16T13:30:58.828Z,1692192658.828 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-16T13:30:58.830Z,1692192658.830 [BPC1](INFO): Received data from all battery sticks.
2023-08-16T13:46:39.771Z,1692193599.771 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-16T13:46:42.609Z,1692193602.609 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-08-16T13:46:50.762Z,1692193610.762 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005908
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.004164
CHAN A3 (5V): -0.002690
CHAN B0 (3.3V): -0.000511
CHAN B1 (3.15aV): -0.001044
CHAN B2 (3.15bV): -0.000897
CHAN B3 (GND): -0.000956
OPEN: 0.003498
Full Scale: +/- 1 mA
2023-08-16T13:58:02.985Z,1692194282.985 [CommandExec](IMPORTANT): got command restart system
2023-08-16T13:58:05.136Z,1692194285.136 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-08-16T13:58:05.136Z,1692194285.136 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:05.137Z,1692194285.137 [CommandExec](INFO): Uninitializing the command executive.
2023-08-16T13:58:05.137Z,1692194285.137 [CommandExec](INFO): Uninitializing the command scheduler.
2023-08-16T13:58:05.137Z,1692194285.137 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:05.316Z,1692194285.316 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-08-16T13:58:05.316Z,1692194285.316 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-08-16T13:58:05.316Z,1692194285.316 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:05.317Z,1692194285.317 [NavChartDb](INFO): Join timeout helper Thread ID is 1366
2023-08-16T13:58:05.480Z,1692194285.480 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:05.480Z,1692194285.480 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:05.484Z,1692194285.484 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-08-16T13:58:05.484Z,1692194285.484 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:05.484Z,1692194285.484 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1367
2023-08-16T13:58:05.816Z,1692194285.816 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:05.816Z,1692194285.816 [WetLabsBB2FL](INFO): Powering down
2023-08-16T13:58:05.817Z,1692194285.817 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:05.828Z,1692194285.828 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-08-16T13:58:05.828Z,1692194285.828 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:05.828Z,1692194285.828 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1368
2023-08-16T13:58:06.472Z,1692194286.472 [CTD_Seabird](INFO): Powering down
2023-08-16T13:58:06.484Z,1692194286.484 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:06.484Z,1692194286.484 [CTD_Seabird](INFO): Powering down
2023-08-16T13:58:06.496Z,1692194286.496 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:06.508Z,1692194286.508 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-08-16T13:58:06.508Z,1692194286.508 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:06.508Z,1692194286.508 [Radio_Surface](INFO): Join timeout helper Thread ID is 1369
2023-08-16T13:58:06.568Z,1692194286.568 [Radio_Surface](INFO): Powering down
2023-08-16T13:58:06.569Z,1692194286.569 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:06.569Z,1692194286.569 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:06.570Z,1692194286.570 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-08-16T13:58:06.570Z,1692194286.570 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:06.570Z,1692194286.570 [Onboard](INFO): Join timeout helper Thread ID is 1370
2023-08-16T13:58:07.684Z,1692194287.684 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:07.685Z,1692194287.685 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:07.686Z,1692194287.686 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-08-16T13:58:07.686Z,1692194287.686 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:07.686Z,1692194287.686 [DataOverHttps](INFO): Join timeout helper Thread ID is 1371
2023-08-16T13:58:07.768Z,1692194287.768 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:07.771Z,1692194287.771 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:07.781Z,1692194287.781 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-08-16T13:58:07.781Z,1692194287.781 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:07.781Z,1692194287.781 [BackseatComponent](INFO): Join timeout helper Thread ID is 1372
2023-08-16T13:58:07.936Z,1692194287.936 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:07.936Z,1692194287.936 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:07.953Z,1692194287.953 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-08-16T13:58:07.953Z,1692194287.953 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:07.954Z,1692194287.954 [logger](INFO): Join timeout helper Thread ID is 1373
2023-08-16T13:58:07.954Z,1692194287.954 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:07.954Z,1692194287.954 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:07.955Z,1692194287.955 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-08-16T13:58:07.956Z,1692194287.956 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:07.956Z,1692194287.956 [CommandLine](INFO): Join timeout helper Thread ID is 1374
2023-08-16T13:58:08.004Z,1692194288.004 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:08.004Z,1692194288.004 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.016Z,1692194288.016 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-08-16T13:58:08.017Z,1692194288.017 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.017Z,1692194288.017 [CommandExec](INFO): Join timeout helper Thread ID is 1375
2023-08-16T13:58:08.018Z,1692194288.018 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-08-16T13:58:08.018Z,1692194288.018 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.019Z,1692194288.019 [controlThread](INFO): Join timeout helper Thread ID is 1376
2023-08-16T13:58:08.052Z,1692194288.052 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-16T13:58:08.052Z,1692194288.052 [controlThread](DEBUG): Uninitializing ControlThread
2023-08-16T13:58:08.053Z,1692194288.053 [AHRS_M2](INFO): Powering down
2023-08-16T13:58:08.125Z,1692194288.125 [NAL9602](INFO): Powering down
2023-08-16T13:58:08.126Z,1692194288.126 [Sonardyne_Nano](INFO): Powering down
2023-08-16T13:58:08.245Z,1692194288.245 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-08-16T13:58:08.245Z,1692194288.245 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-08-16T13:58:08.246Z,1692194288.246 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-08-16T13:58:08.246Z,1692194288.246 [MissionManager](INFO): Uninitializing Mission Default
2023-08-16T13:58:08.247Z,1692194288.247 [Default] Stopped
2023-08-16T13:58:08.247Z,1692194288.247 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-16T13:58:08.247Z,1692194288.247 [Default:B.GoToSurface] Stopped
2023-08-16T13:58:08.247Z,1692194288.247 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-16T13:58:08.247Z,1692194288.247 [Default:CheckIn] Stopped
2023-08-16T13:58:08.247Z,1692194288.247 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-16T13:58:08.247Z,1692194288.247 [Default:CheckIn:Read_Iridium] Stopped
2023-08-16T13:58:08.250Z,1692194288.250 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-08-16T13:58:08.250Z,1692194288.250 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-08-16T13:58:08.251Z,1692194288.251 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-08-16T13:58:08.251Z,1692194288.251 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-08-16T13:58:08.251Z,1692194288.251 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-08-16T13:58:08.251Z,1692194288.251 [BuoyancyServo](INFO): Powering down
2023-08-16T13:58:08.264Z,1692194288.264 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-08-16T13:58:08.264Z,1692194288.264 [ElevatorServo](INFO): Powering down
2023-08-16T13:58:08.265Z,1692194288.265 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-08-16T13:58:08.265Z,1692194288.265 [MassServo](INFO): Powering down
2023-08-16T13:58:08.266Z,1692194288.266 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-08-16T13:58:08.266Z,1692194288.266 [RudderServo](INFO): Powering down
2023-08-16T13:58:08.267Z,1692194288.267 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-08-16T13:58:08.267Z,1692194288.267 [ThrusterHE](INFO): Powering down
2023-08-16T13:58:08.268Z,1692194288.268 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-08-16T13:58:08.269Z,1692194288.269 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-08-16T13:58:08.269Z,1692194288.269 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-08-16T13:58:08.269Z,1692194288.269 [CBIT](DEBUG): Powering off loads.
2023-08-16T13:58:08.280Z,1692194288.280 [CBIT](DEBUG): Disabling WDT.
2023-08-16T13:58:08.292Z,1692194288.292 [CBIT](DEBUG): Opening all GF detection circuits.
2023-08-16T13:58:08.293Z,1692194288.293 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.346Z,1692194288.346 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.349Z,1692194288.349 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.355Z,1692194288.355 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.402Z,1692194288.402 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.449Z,1692194288.449 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.453Z,1692194288.453 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.497Z,1692194288.497 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-16T13:58:08.549Z,1692194288.549 [logger ThreadHandler](INFO): Thread cancelled.