2023-05-11T16:45:37.979Z,1683823537.979 [Supervisor](DEBUG): Initializing supervisor.
2023-05-11T16:45:37.984Z,1683823537.984 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-11T16:45:37.984Z,1683823537.984 [SyncHandler](INFO): Protected caller Thread ID is 9230
2023-05-11T16:45:37.985Z,1683823537.985 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-11T16:45:37.986Z,1683823537.986 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-11T16:45:37.986Z,1683823537.986 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9231
2023-05-11T16:45:37.991Z,1683823537.991 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-11T16:45:38.008Z,1683823538.008 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-11T16:45:38.009Z,1683823538.009 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-11T16:45:38.009Z,1683823538.009 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9232
2023-05-11T16:45:38.014Z,1683823538.014 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-11T16:45:38.015Z,1683823538.015 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-11T16:45:38.015Z,1683823538.015 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9233
2023-05-11T16:45:38.017Z,1683823538.017 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-11T16:45:38.018Z,1683823538.018 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-11T16:45:38.018Z,1683823538.018 [logger ThreadHandler](INFO): Protected caller Thread ID is 9234
2023-05-11T16:45:38.022Z,1683823538.022 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-11T16:45:38.022Z,1683823538.022 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-11T16:45:38.024Z,1683823538.024 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-11T16:45:38.468Z,1683823538.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-11T16:45:38.469Z,1683823538.469 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-11T16:45:38.673Z,1683823538.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-11T16:45:38.674Z,1683823538.674 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-11T16:45:38.752Z,1683823538.752 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-11T16:45:38.961Z,1683823538.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-11T16:45:38.962Z,1683823538.962 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-11T16:45:39.038Z,1683823539.038 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-11T16:45:39.132Z,1683823539.132 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-11T16:45:39.133Z,1683823539.133 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-11T16:45:39.455Z,1683823539.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-11T16:45:39.456Z,1683823539.456 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-11T16:45:39.584Z,1683823539.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-11T16:45:39.584Z,1683823539.584 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-11T16:45:40.063Z,1683823540.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-11T16:45:40.064Z,1683823540.064 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-11T16:45:40.239Z,1683823540.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-11T16:45:40.240Z,1683823540.240 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-11T16:45:40.632Z,1683823540.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-11T16:45:40.633Z,1683823540.633 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-11T16:45:41.459Z,1683823541.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-11T16:45:41.459Z,1683823541.459 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-11T16:45:41.568Z,1683823541.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-11T16:45:41.569Z,1683823541.569 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-11T16:45:42.319Z,1683823542.319 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-11T16:45:42.319Z,1683823542.319 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-11T16:45:42.636Z,1683823542.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-11T16:45:42.636Z,1683823542.636 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-11T16:45:42.849Z,1683823542.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-11T16:45:42.851Z,1683823542.851 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-05-11T16:45:42.852Z,1683823542.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-05-11T16:45:43.031Z,1683823543.031 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-05-11T16:45:43.113Z,1683823543.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-05-11T16:45:43.242Z,1683823543.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-05-11T16:45:43.326Z,1683823543.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-05-11T16:45:43.586Z,1683823543.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-11T16:45:43.586Z,1683823543.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-05-11T16:45:43.688Z,1683823543.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-05-11T16:45:43.792Z,1683823543.792 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-05-11T16:45:43.903Z,1683823543.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-05-11T16:45:44.046Z,1683823544.046 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-05-11T16:45:44.143Z,1683823544.143 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-05-11T16:45:44.236Z,1683823544.236 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2023-05-11T16:45:44.236Z,1683823544.236 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-11T16:45:44.248Z,1683823544.248 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-11T16:45:44.258Z,1683823544.258 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-11T16:45:44.259Z,1683823544.259 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-11T16:45:44.357Z,1683823544.357 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-11T16:45:44.357Z,1683823544.357 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-11T16:45:44.406Z,1683823544.406 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-11T16:45:44.465Z,1683823544.465 [VerticalControl] Loaded
2023-05-11T16:45:44.465Z,1683823544.465 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-11T16:45:44.468Z,1683823544.468 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-11T16:45:44.509Z,1683823544.509 [HorizontalControl] Loaded
2023-05-11T16:45:44.509Z,1683823544.509 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-11T16:45:44.511Z,1683823544.511 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-11T16:45:44.514Z,1683823544.514 [SpeedControl] Loaded
2023-05-11T16:45:44.515Z,1683823544.515 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-11T16:45:44.517Z,1683823544.517 [LoopControl](DEBUG): Construct LoopControl.
2023-05-11T16:45:44.518Z,1683823544.518 [LoopControl] Loaded
2023-05-11T16:45:44.518Z,1683823544.518 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-11T16:45:44.518Z,1683823544.518 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-11T16:45:44.519Z,1683823544.519 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-11T16:45:44.673Z,1683823544.673 [BuoyancyServo] Loaded
2023-05-11T16:45:44.673Z,1683823544.673 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-11T16:45:44.693Z,1683823544.693 [ElevatorServo] Loaded
2023-05-11T16:45:44.693Z,1683823544.693 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-11T16:45:44.712Z,1683823544.712 [MassServo] Loaded
2023-05-11T16:45:44.712Z,1683823544.712 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-11T16:45:44.731Z,1683823544.731 [RudderServo] Loaded
2023-05-11T16:45:44.731Z,1683823544.731 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-11T16:45:44.745Z,1683823544.745 [ThrusterHE] Loaded
2023-05-11T16:45:44.746Z,1683823544.746 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-11T16:45:44.746Z,1683823544.746 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-11T16:45:44.747Z,1683823544.747 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-11T16:45:44.796Z,1683823544.796 [DepthRateCalculator] Loaded
2023-05-11T16:45:44.797Z,1683823544.797 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-11T16:45:44.801Z,1683823544.801 [PitchRateCalculator] Loaded
2023-05-11T16:45:44.802Z,1683823544.802 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-11T16:45:44.811Z,1683823544.811 [SpeedCalculator] Loaded
2023-05-11T16:45:44.811Z,1683823544.811 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-11T16:45:44.816Z,1683823544.816 [YawRateCalculator] Loaded
2023-05-11T16:45:44.816Z,1683823544.816 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-11T16:45:44.834Z,1683823544.834 [ElevatorOffsetCalculator] Loaded
2023-05-11T16:45:44.834Z,1683823544.834 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-11T16:45:44.835Z,1683823544.835 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-11T16:45:44.835Z,1683823544.835 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-11T16:45:44.947Z,1683823544.947 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-11T16:45:44.947Z,1683823544.947 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-11T16:45:44.960Z,1683823544.960 [NavChart] Loaded
2023-05-11T16:45:44.961Z,1683823544.961 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-11T16:45:44.966Z,1683823544.966 [UniversalFixResidualReporter] Loaded
2023-05-11T16:45:44.967Z,1683823544.967 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-11T16:45:44.967Z,1683823544.967 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-11T16:45:44.968Z,1683823544.968 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-11T16:45:45.407Z,1683823545.407 [AHRS_M2] Loaded
2023-05-11T16:45:45.407Z,1683823545.407 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-11T16:45:45.442Z,1683823545.442 [BackseatComponent] Loaded
2023-05-11T16:45:45.443Z,1683823545.443 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-11T16:45:45.444Z,1683823545.444 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2023-05-11T16:45:45.444Z,1683823545.444 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9316
2023-05-11T16:45:45.447Z,1683823545.447 [LcmUniversalReporter] Loaded
2023-05-11T16:45:45.447Z,1683823545.447 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-11T16:45:46.679Z,1683823546.679 [BPC1] Loaded
2023-05-11T16:45:46.680Z,1683823546.680 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-11T16:45:46.765Z,1683823546.765 [DATMMP] Loaded
2023-05-11T16:45:46.766Z,1683823546.766 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-11T16:45:46.767Z,1683823546.767 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409274E0
2023-05-11T16:45:46.767Z,1683823546.767 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 9317
2023-05-11T16:45:46.839Z,1683823546.839 [DataOverHttps] Loaded
2023-05-11T16:45:46.839Z,1683823546.839 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-11T16:45:46.840Z,1683823546.840 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409574E0
2023-05-11T16:45:46.840Z,1683823546.840 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9318
2023-05-11T16:45:46.861Z,1683823546.861 [Depth_Keller] Loaded
2023-05-11T16:45:46.861Z,1683823546.861 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-11T16:45:46.923Z,1683823546.923 [NAL9602] Loaded
2023-05-11T16:45:46.923Z,1683823546.923 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-11T16:45:46.952Z,1683823546.952 [Onboard] Loaded
2023-05-11T16:45:46.953Z,1683823546.953 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-11T16:45:46.954Z,1683823546.954 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409874E0
2023-05-11T16:45:46.954Z,1683823546.954 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9319
2023-05-11T16:45:46.966Z,1683823546.966 [Power24vConverter] Loaded
2023-05-11T16:45:46.966Z,1683823546.966 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-11T16:45:46.979Z,1683823546.979 [Radio_Surface] Loaded
2023-05-11T16:45:46.980Z,1683823546.980 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-11T16:45:46.981Z,1683823546.981 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409B74E0
2023-05-11T16:45:46.981Z,1683823546.981 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9320
2023-05-11T16:45:46.982Z,1683823546.982 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-11T16:45:46.983Z,1683823546.983 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-11T16:45:47.053Z,1683823547.053 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-11T16:45:47.054Z,1683823547.054 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-11T16:45:47.202Z,1683823547.202 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-11T16:45:47.203Z,1683823547.203 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-11T16:45:47.229Z,1683823547.229 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-11T16:45:47.230Z,1683823547.230 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-11T16:45:47.398Z,1683823547.398 [CTD_Seabird] Loaded
2023-05-11T16:45:47.398Z,1683823547.398 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-11T16:45:47.399Z,1683823547.399 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AEE4E0
2023-05-11T16:45:47.399Z,1683823547.399 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9321
2023-05-11T16:45:47.432Z,1683823547.432 [ESPComponent] Loaded
2023-05-11T16:45:47.432Z,1683823547.432 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-05-11T16:45:47.452Z,1683823547.452 [PAR_Licor] Loaded
2023-05-11T16:45:47.452Z,1683823547.452 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-11T16:45:47.483Z,1683823547.483 [WetLabsBB2FL] Loaded
2023-05-11T16:45:47.483Z,1683823547.483 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-11T16:45:47.484Z,1683823547.484 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B1E4E0
2023-05-11T16:45:47.484Z,1683823547.484 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9322
2023-05-11T16:45:47.485Z,1683823547.485 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-11T16:45:47.486Z,1683823547.486 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-11T16:45:47.792Z,1683823547.792 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-11T16:45:47.792Z,1683823547.792 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-11T16:45:47.930Z,1683823547.930 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-11T16:45:47.940Z,1683823547.940 [SBIT] Loaded
2023-05-11T16:45:47.940Z,1683823547.940 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-11T16:45:47.943Z,1683823547.943 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-11T16:45:47.956Z,1683823547.956 [IBIT] Loaded
2023-05-11T16:45:47.956Z,1683823547.956 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-11T16:45:47.962Z,1683823547.962 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-11T16:45:48.067Z,1683823548.067 [CBIT] Loaded
2023-05-11T16:45:48.067Z,1683823548.067 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-11T16:45:48.068Z,1683823548.068 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-11T16:45:48.074Z,1683823548.074 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-11T16:45:48.077Z,1683823548.077 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-11T16:45:48.088Z,1683823548.088 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-11T16:45:48.089Z,1683823548.089 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C014E0
2023-05-11T16:45:48.089Z,1683823548.089 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9323
2023-05-11T16:45:48.094Z,1683823548.094 [Supervisor](INFO): Main Thread ID is 9039
2023-05-11T16:45:48.094Z,1683823548.094 [Supervisor](DEBUG): Running supervisor.
2023-05-11T16:45:48.094Z,1683823548.094 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9324
2023-05-11T16:45:48.095Z,1683823548.095 [CommandExec](INFO): Initializing the command executive.
2023-05-11T16:45:48.096Z,1683823548.096 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9325
2023-05-11T16:45:48.099Z,1683823548.099 [controlThread ThreadHandler](INFO): Handler Thread ID is 9326
2023-05-11T16:45:48.099Z,1683823548.099 [controlThread](DEBUG): Initializing ControlThread
2023-05-11T16:45:48.100Z,1683823548.100 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-11T16:45:48.102Z,1683823548.102 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-11T16:45:48.102Z,1683823548.102 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-11T16:45:48.103Z,1683823548.103 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-11T16:45:48.104Z,1683823548.104 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-11T16:45:48.104Z,1683823548.104 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-11T16:45:48.105Z,1683823548.105 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-11T16:45:48.105Z,1683823548.105 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-11T16:45:48.106Z,1683823548.106 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-11T16:45:48.107Z,1683823548.107 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-11T16:45:48.107Z,1683823548.107 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-11T16:45:48.114Z,1683823548.114 [SBIT](INFO): Initialize SBIT Component.
2023-05-11T16:45:48.114Z,1683823548.114 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973
2023-05-11T16:45:48.114Z,1683823548.114 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c
2023-05-11T16:45:48.115Z,1683823548.115 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-11T16:45:48.116Z,1683823548.116 [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-05-11T16:45:48.117Z,1683823548.117 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2023-05-11T16:45:48.118Z,1683823548.118 [IBIT](INFO): Initialize IBIT Component.
2023-05-11T16:45:48.118Z,1683823548.118 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-11T16:45:48.119Z,1683823548.119 [logger ThreadHandler](INFO): Handler Thread ID is 9327
2023-05-11T16:45:48.129Z,1683823548.129 [CBIT](DEBUG): Initialized mux pins.
2023-05-11T16:45:48.130Z,1683823548.130 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-11T16:45:48.138Z,1683823548.138 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9328
2023-05-11T16:45:48.150Z,1683823548.150 [DATMMP ThreadHandler](INFO): Handler Thread ID is 9329
2023-05-11T16:45:48.150Z,1683823548.150 [DATMMP](INFO): Start
2023-05-11T16:45:48.151Z,1683823548.151 [DATMMP](INFO): Powering up
2023-05-11T16:45:48.151Z,1683823548.151 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-11T16:45:48.153Z,1683823548.153 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-11T16:45:48.154Z,1683823548.154 [CBIT](DEBUG): Initializing heartbeat.
2023-05-11T16:45:48.156Z,1683823548.156 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9331
2023-05-11T16:45:48.157Z,1683823548.157 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-11T16:45:48.166Z,1683823548.166 [Onboard ThreadHandler](INFO): Handler Thread ID is 9332
2023-05-11T16:45:48.196Z,1683823548.196 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9333
2023-05-11T16:45:48.206Z,1683823548.206 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9334
2023-05-11T16:45:48.206Z,1683823548.206 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-11T16:45:48.211Z,1683823548.211 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9336
2023-05-11T16:45:48.214Z,1683823548.214 [WetLabsBB2FL](INFO): Powering up
2023-05-11T16:45:48.215Z,1683823548.215 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9338
2023-05-11T16:45:48.218Z,1683823548.218 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-11T16:45:48.218Z,1683823548.218 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-11T16:45:48.218Z,1683823548.218 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-11T16:45:48.218Z,1683823548.218 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-11T16:45:48.218Z,1683823548.218 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-11T16:45:48.219Z,1683823548.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-11T16:45:48.219Z,1683823548.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-11T16:45:48.219Z,1683823548.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-11T16:45:48.225Z,1683823548.225 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-11T16:45:48.226Z,1683823548.226 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-11T16:45:48.265Z,1683823548.265 [CBIT](DEBUG): Backplane powered.
2023-05-11T16:45:48.266Z,1683823548.266 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-11T16:45:48.279Z,1683823548.279 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-11T16:45:48.310Z,1683823548.310 [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-05-11T16:45:48.311Z,1683823548.311 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-11T16:45:48.385Z,1683823548.385 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-11T16:45:48.386Z,1683823548.386 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-11T16:45:48.388Z,1683823548.388 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-11T16:45:48.401Z,1683823548.401 [DATMMP](INFO): Starting
2023-05-11T16:45:48.445Z,1683823548.445 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-11T16:45:48.448Z,1683823548.448 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-11T16:45:48.466Z,1683823548.466 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-11T16:45:48.485Z,1683823548.485 [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-05-11T16:45:48.490Z,1683823548.490 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,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-05-11T16:45:48.498Z,1683823548.498 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-11T16:45:48.568Z,1683823548.568 [Power24vConverter](INFO): Powering up.
2023-05-11T16:45:48.598Z,1683823548.598 [Radio_Surface](INFO): Powering up
2023-05-11T16:45:48.617Z,1683823548.617 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-11T16:45:48.638Z,1683823548.638 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-11T16:45:48.639Z,1683823548.639 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-11T16:45:48.646Z,1683823548.646 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-11T16:45:48.647Z,1683823548.647 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-11T16:45:48.654Z,1683823548.654 [DATMMP](INFO): Starting
2023-05-11T16:45:48.654Z,1683823548.654 [MassServo](DEBUG): Initializing MassServo.
2023-05-11T16:45:48.655Z,1683823548.655 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-11T16:45:48.662Z,1683823548.662 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-11T16:45:48.663Z,1683823548.663 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-11T16:45:48.670Z,1683823548.670 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-11T16:45:48.906Z,1683823548.906 [DATMMP](INFO): Starting
2023-05-11T16:45:49.158Z,1683823549.158 [DATMMP](INFO): Starting
2023-05-11T16:45:49.409Z,1683823549.409 [DATMMP](INFO): Starting
2023-05-11T16:45:49.662Z,1683823549.662 [DATMMP](INFO): Starting
2023-05-11T16:45:49.914Z,1683823549.914 [DATMMP](INFO): Starting
2023-05-11T16:45:50.166Z,1683823550.166 [DATMMP](INFO): Starting
2023-05-11T16:45:50.322Z,1683823550.322 [WetLabsBB2FL](INFO): Powering down
2023-05-11T16:45:50.418Z,1683823550.418 [DATMMP](INFO): Starting
2023-05-11T16:45:50.670Z,1683823550.670 [DATMMP](INFO): Starting
2023-05-11T16:45:50.921Z,1683823550.921 [DATMMP](INFO): Starting
2023-05-11T16:45:51.174Z,1683823551.174 [DATMMP](INFO): Starting
2023-05-11T16:45:51.426Z,1683823551.426 [DATMMP](INFO): Starting
2023-05-11T16:45:51.678Z,1683823551.678 [DATMMP](INFO): Starting
2023-05-11T16:45:51.929Z,1683823551.929 [DATMMP](INFO): Starting
2023-05-11T16:45:52.181Z,1683823552.181 [DATMMP](INFO): Starting
2023-05-11T16:45:52.434Z,1683823552.434 [DATMMP](INFO): Starting
2023-05-11T16:45:52.686Z,1683823552.686 [DATMMP](INFO): Starting
2023-05-11T16:45:52.938Z,1683823552.938 [DATMMP](INFO): Starting
2023-05-11T16:45:53.190Z,1683823553.190 [DATMMP](INFO): Starting
2023-05-11T16:45:53.442Z,1683823553.442 [DATMMP](INFO): Starting
2023-05-11T16:45:53.694Z,1683823553.694 [DATMMP](INFO): Starting
2023-05-11T16:45:53.946Z,1683823553.946 [DATMMP](INFO): Starting
2023-05-11T16:45:54.198Z,1683823554.198 [DATMMP](INFO): Starting
2023-05-11T16:45:54.439Z,1683823554.439 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-11T16:45:54.450Z,1683823554.450 [DATMMP](INFO): Starting
2023-05-11T16:45:54.702Z,1683823554.702 [DATMMP](INFO): Starting
2023-05-11T16:45:54.953Z,1683823554.953 [DATMMP](INFO): Starting
2023-05-11T16:45:55.206Z,1683823555.206 [DATMMP](INFO): Starting
2023-05-11T16:45:55.457Z,1683823555.457 [DATMMP](INFO): Starting
2023-05-11T16:45:55.713Z,1683823555.713 [DATMMP](INFO): Starting
2023-05-11T16:45:55.966Z,1683823555.966 [DATMMP](INFO): Starting
2023-05-11T16:45:56.217Z,1683823556.217 [DATMMP](INFO): Starting
2023-05-11T16:45:56.470Z,1683823556.470 [DATMMP](INFO): Starting
2023-05-11T16:45:56.722Z,1683823556.722 [DATMMP](INFO): Starting
2023-05-11T16:45:56.973Z,1683823556.973 [DATMMP](INFO): Starting
2023-05-11T16:45:57.226Z,1683823557.226 [DATMMP](INFO): Starting
2023-05-11T16:45:57.478Z,1683823557.478 [DATMMP](INFO): Starting
2023-05-11T16:45:57.730Z,1683823557.730 [DATMMP](INFO): Starting
2023-05-11T16:45:57.982Z,1683823557.982 [DATMMP](INFO): Starting
2023-05-11T16:45:58.234Z,1683823558.234 [DATMMP](INFO): Starting
2023-05-11T16:45:58.486Z,1683823558.486 [DATMMP](INFO): Starting
2023-05-11T16:45:58.738Z,1683823558.738 [DATMMP](INFO): Starting
2023-05-11T16:45:58.990Z,1683823558.990 [DATMMP](INFO): Starting
2023-05-11T16:45:59.242Z,1683823559.242 [DATMMP](INFO): Starting
2023-05-11T16:45:59.494Z,1683823559.494 [DATMMP](INFO): Starting
2023-05-11T16:45:59.745Z,1683823559.745 [DATMMP](INFO): Starting
2023-05-11T16:45:59.998Z,1683823559.998 [DATMMP](INFO): Starting
2023-05-11T16:46:00.249Z,1683823560.249 [DATMMP](INFO): Starting
2023-05-11T16:46:00.250Z,1683823560.250 [DATMMP](INFO): DAT read: 

2023-05-11T16:46:00.251Z,1683823560.251 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-11T16:46:00.502Z,1683823560.502 [DATMMP](INFO): Starting
2023-05-11T16:46:00.754Z,1683823560.754 [DATMMP](INFO): Starting
2023-05-11T16:46:01.006Z,1683823561.006 [DATMMP](INFO): Starting
2023-05-11T16:46:01.257Z,1683823561.257 [DATMMP](INFO): Starting
2023-05-11T16:46:01.509Z,1683823561.509 [DATMMP](INFO): Starting
2023-05-11T16:46:01.762Z,1683823561.762 [DATMMP](INFO): Starting
2023-05-11T16:46:02.014Z,1683823562.014 [DATMMP](INFO): Starting
2023-05-11T16:46:02.014Z,1683823562.014 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-11T16:46:02.015Z,1683823562.015 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-11T16:46:02.016Z,1683823562.016 [DATMMP](INFO): DAT read: Apr 11 2000 16:45:56 

2023-05-11T16:46:02.266Z,1683823562.266 [DATMMP](INFO): Starting
2023-05-11T16:46:02.518Z,1683823562.518 [DATMMP](INFO): Starting
2023-05-11T16:46:02.770Z,1683823562.770 [DATMMP](INFO): Starting
2023-05-11T16:46:03.022Z,1683823563.022 [DATMMP](INFO): Starting
2023-05-11T16:46:03.274Z,1683823563.274 [DATMMP](INFO): Starting
2023-05-11T16:46:03.274Z,1683823563.274 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-11T16:46:03.276Z,1683823563.276 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-11T16:46:03.276Z,1683823563.276 [DATMMP](INFO): commRate: 800
2023-05-11T16:46:03.526Z,1683823563.526 [DATMMP](INFO): Starting
2023-05-11T16:46:03.778Z,1683823563.778 [DATMMP](INFO): Starting
2023-05-11T16:46:04.030Z,1683823564.030 [DATMMP](INFO): Starting
2023-05-11T16:46:04.282Z,1683823564.282 [DATMMP](INFO): Starting
2023-05-11T16:46:04.534Z,1683823564.534 [DATMMP](INFO): Starting
2023-05-11T16:46:04.786Z,1683823564.786 [DATMMP](INFO): Starting
2023-05-11T16:46:05.038Z,1683823565.038 [DATMMP](INFO): Starting
2023-05-11T16:46:05.290Z,1683823565.290 [DATMMP](INFO): Starting
2023-05-11T16:46:05.342Z,1683823565.342 [DATMMP](INFO): entering command mode
2023-05-11T16:46:05.542Z,1683823565.542 [DATMMP](INFO): Starting
2023-05-11T16:46:05.542Z,1683823565.542 [DATMMP](INFO): DAT read: 

2023-05-11T16:46:05.543Z,1683823565.543 [DATMMP](INFO): DAT read: user:1

2023-05-11T16:46:05.543Z,1683823565.543 [DATMMP](DEBUG): read user prompt 1: user:1

2023-05-11T16:46:05.543Z,1683823565.543 [DATMMP](INFO): entering MMP mode
2023-05-11T16:46:05.794Z,1683823565.794 [DATMMP](INFO): Starting
2023-05-11T16:46:05.794Z,1683823565.794 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:46:05.796Z,1683823565.796 [DATMMP](INFO): status rx: 	 x1
2023-05-11T16:46:05.796Z,1683823565.796 [DATMMP](INFO): MMP status: 1: started
2023-05-11T16:46:05.796Z,1683823565.796 [DATMMP](INFO): Received message type: status
2023-05-11T16:46:05.796Z,1683823565.796 [DATMMP](INFO): Handled
2023-05-11T16:46:06.050Z,1683823566.050 [DATMMP](INFO): Starting
2023-05-11T16:46:06.051Z,1683823566.051 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-11T16:46:06.052Z,1683823566.052 [DATMMP](INFO): Setting local address: 12
2023-05-11T16:46:06.055Z,1683823566.055 [DATMMP](INFO): Setting time to: 16:46:6 And date to:5/11/2023
2023-05-11T16:46:06.298Z,1683823566.298 [DATMMP](INFO): Starting
2023-05-11T16:46:06.298Z,1683823566.298 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:46:06.299Z,1683823566.299 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-11T16:46:06.299Z,1683823566.299 [DATMMP](INFO): Received message type: mdmpower
2023-05-11T16:46:06.299Z,1683823566.299 [DATMMP](INFO): Handled
2023-05-11T16:46:06.550Z,1683823566.550 [DATMMP](INFO): Starting
2023-05-11T16:46:06.550Z,1683823566.550 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-11T16:46:06.551Z,1683823566.551 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-11T16:46:06.551Z,1683823566.551 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-11T16:46:06.551Z,1683823566.551 [DATMMP](INFO): Match for : sreg_txpower
2023-05-11T16:46:06.551Z,1683823566.551 [DATMMP](INFO): ACK
2023-05-11T16:46:06.802Z,1683823566.802 [DATMMP](INFO): Starting
2023-05-11T16:46:06.802Z,1683823566.802 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:46:06.803Z,1683823566.803 [DATMMP](INFO): mdmlocal rx: 	 xC	 x0	 x0	 x0
2023-05-11T16:46:06.803Z,1683823566.803 [DATMMP](INFO): Received message type: mdmlocal
2023-05-11T16:46:06.803Z,1683823566.803 [DATMMP](INFO): Handled
2023-05-11T16:46:07.054Z,1683823567.054 [DATMMP](INFO): Starting
2023-05-11T16:46:07.054Z,1683823567.054 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-11T16:46:07.055Z,1683823567.055 [DATMMP](INFO): sreg_locaddr rx: 	 xC
2023-05-11T16:46:07.055Z,1683823567.055 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-11T16:46:07.055Z,1683823567.055 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-11T16:46:07.055Z,1683823567.055 [DATMMP](INFO): ACK
2023-05-11T16:46:07.306Z,1683823567.306 [DATMMP](INFO): Starting
2023-05-11T16:46:07.306Z,1683823567.306 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-11T16:46:07.307Z,1683823567.307 [DATMMP](INFO): timedate rx: 	 x0	 x10	 x2E	 x6	 x4	 xB	 x7B	 x0
2023-05-11T16:46:07.308Z,1683823567.307 [DATMMP](INFO): Received message type: timedate
2023-05-11T16:46:07.308Z,1683823567.308 [DATMMP](INFO): Match for : timedate
2023-05-11T16:46:07.308Z,1683823567.308 [DATMMP](INFO): ACK
2023-05-11T16:46:07.558Z,1683823567.558 [DATMMP](INFO): Starting
2023-05-11T16:46:07.558Z,1683823567.558 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:46:07.559Z,1683823567.559 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-11T16:46:07.559Z,1683823567.559 [DATMMP](INFO): Received message type: sys_verb
2023-05-11T16:46:07.559Z,1683823567.559 [DATMMP](INFO): Handled
2023-05-11T16:46:07.809Z,1683823567.809 [DATMMP](INFO): Starting
2023-05-11T16:46:07.810Z,1683823567.810 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-11T16:46:07.810Z,1683823567.810 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-11T16:46:07.811Z,1683823567.811 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-11T16:46:07.811Z,1683823567.811 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-11T16:46:07.811Z,1683823567.811 [DATMMP](INFO): ACK
2023-05-11T16:46:08.062Z,1683823568.062 [DATMMP](INFO): Starting
2023-05-11T16:46:08.062Z,1683823568.062 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-11T16:46:08.063Z,1683823568.063 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-11T16:46:08.063Z,1683823568.063 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-11T16:46:08.063Z,1683823568.063 [DATMMP](INFO): Received message type: version
2023-05-11T16:46:08.063Z,1683823568.063 [DATMMP](INFO): Match for : version
2023-05-11T16:46:08.063Z,1683823568.063 [DATMMP](INFO): ACK
2023-05-11T16:46:08.064Z,1683823568.064 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-11T16:46:11.666Z,1683823571.666 [MassServo](ERROR): getPosition uart error serial timeout
2023-05-11T16:46:11.666Z,1683823571.666 [MassServo](FAULT): Invalid EZ Servo response:""
2023-05-11T16:46:11.666Z,1683823571.666 [MassServo] Communications Fault, FailCount= 1
2023-05-11T16:46:11.666Z,1683823571.666 [MassServo](ERROR): Communications Fault
2023-05-11T16:46:11.695Z,1683823571.695 [CBIT](ERROR): Communications Fault in component: MassServo
2023-05-11T16:46:11.972Z,1683823571.972 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-11T16:46:11.972Z,1683823571.972 [MassServo](INFO): Powering down
2023-05-11T16:46:12.780Z,1683823572.780 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-11T16:46:12.898Z,1683823572.898 [MassServo](DEBUG): Initializing MassServo.
2023-05-11T16:46:12.903Z,1683823572.903 [CBIT](INFO): Clearing failed state for component MassServo
2023-05-11T16:46:12.903Z,1683823572.903 [MassServo] No Fault, FailCount= 1
2023-05-11T16:46:17.195Z,1683823577.195 [NAL9602](INFO): Powering up NAL9602
2023-05-11T16:46:22.682Z,1683823582.682 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:46:22.683Z,1683823582.683 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-11T16:46:22.683Z,1683823582.683 [DATMMP](INFO): Received message type: timesync
2023-05-11T16:46:22.683Z,1683823582.683 [DATMMP](INFO): Handled
2023-05-11T16:46:28.103Z,1683823588.103 [NAL9602](INFO): NAL9602 initialized
2023-05-11T16:46:32.510Z,1683823592.510 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:46:32.511Z,1683823592.511 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-11T16:46:32.511Z,1683823592.511 [DATMMP](INFO): Received message type: timesync
2023-05-11T16:46:32.511Z,1683823592.511 [DATMMP](INFO): Handled
2023-05-11T16:46:38.462Z,1683823598.462 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-11T16:46:38.465Z,1683823598.465 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-11T16:46:38.466Z,1683823598.466 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-11T16:46:38.469Z,1683823598.469 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-11T16:46:38.470Z,1683823598.470 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-11T16:46:38.473Z,1683823598.473 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-11T16:46:38.473Z,1683823598.473 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-11T16:46:38.474Z,1683823598.474 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range
2023-05-11T16:46:38.654Z,1683823598.654 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,Maintain_Depth_Keller.depth,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-05-11T16:46:51.654Z,1683823611.654 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683823598 second
2023-05-11T16:47:09.778Z,1683823629.778 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-11T16:47:09.782Z,1683823629.782 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-11T16:47:20.680Z,1683823640.680 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008919
CHAN A1 (24V): -0.008128
CHAN A2 (12V): -0.005361
CHAN A3 (5V): -0.002349
CHAN B0 (3.3V): -0.000763
CHAN B1 (3.15aV): -0.001078
CHAN B2 (3.15bV): -0.000666
CHAN B3 (GND): -0.000079
OPEN: 0.004190
Full Scale: +/- 1 mA
2023-05-11T16:48:03.935Z,1683823683.935 [SBIT](IMPORTANT): SBIT PASSED
2023-05-11T16:48:03.935Z,1683823683.935 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-11T16:48:03.936Z,1683823683.936 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-05-11T16:48:03.936Z,1683823683.936 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2023-05-11T16:48:03.936Z,1683823683.936 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-11T16:48:03.937Z,1683823683.937 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2023-05-11T16:48:03.937Z,1683823683.937 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-05-11T16:48:03.937Z,1683823683.937 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-05-11T16:48:03.937Z,1683823683.937 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-11T16:48:03.937Z,1683823683.937 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-11T16:48:03.937Z,1683823683.937 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2023-05-11T16:48:03.937Z,1683823683.937 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter;

2023-05-11T16:48:03.938Z,1683823683.938 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-05-11T16:48:03.938Z,1683823683.938 [SBIT](IMPORTANT): VerticalControl.massDefault=15.484465 millimeter;

2023-05-11T16:48:04.315Z,1683823684.315 [MissionManager](IMPORTANT): Started mission Startup
2023-05-11T16:48:04.316Z,1683823684.316 [Startup] Running Loop=1
2023-05-11T16:48:04.316Z,1683823684.316 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-11T16:48:04.316Z,1683823684.316 [Startup:A.GoToSurface] Running Loop=1
2023-05-11T16:48:04.316Z,1683823684.316 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-11T16:48:04.316Z,1683823684.316 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-11T16:48:04.317Z,1683823684.317 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-11T16:48:04.317Z,1683823684.317 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-11T16:48:04.318Z,1683823684.318 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-11T16:48:04.318Z,1683823684.318 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-11T16:48:04.319Z,1683823684.319 [Startup:StartupSatComms] Running Loop=1
2023-05-11T16:48:04.320Z,1683823684.320 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-11T16:48:04.320Z,1683823684.320 [Startup:StartupSatComms:A] Running Loop=1
2023-05-11T16:48:04.736Z,1683823684.736 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-11T16:48:04.736Z,1683823684.736 [Startup:StartupSatComms:A] Stopped
2023-05-11T16:48:04.736Z,1683823684.736 [Startup:StartupSatComms:B] Running Loop=1
2023-05-11T16:48:05.135Z,1683823685.135 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-11T16:48:16.164Z,1683823696.164 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004380
2023-05-11T16:48:16.841Z,1683823696.841 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:48:23.569Z,1683823703.569 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T163033/Courier0003.lzma
2023-05-11T16:48:24.573Z,1683823704.573 [DataOverHttps](INFO): Moved sent file to Logs/20230511T163033/Courier0003.lzma.bak
2023-05-11T16:48:24.573Z,1683823704.573 [DataOverHttps](INFO): SBD MOMSN=18290458
2023-05-11T16:48:29.864Z,1683823709.864 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:48:29.882Z,1683823709.882 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:48:41.861Z,1683823721.861 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230511T164537/Courier0000.lzma
2023-05-11T16:48:42.863Z,1683823722.863 [DataOverHttps](INFO): Moved sent file to Logs/20230511T164537/Courier0000.lzma.bak
2023-05-11T16:48:42.863Z,1683823722.863 [DataOverHttps](INFO): SBD MOMSN=18290460
2023-05-11T16:48:43.547Z,1683823723.547 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:48:43.572Z,1683823723.572 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:48:56.862Z,1683823736.862 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:48:56.867Z,1683823736.867 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:49:00.153Z,1683823740.153 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20230511T163033/Express0004.lzma
2023-05-11T16:49:01.155Z,1683823741.155 [DataOverHttps](INFO): Moved sent file to Logs/20230511T163033/Express0004.lzma.bak
2023-05-11T16:49:01.155Z,1683823741.155 [DataOverHttps](INFO): SBD MOMSN=18290463
2023-05-11T16:49:04.954Z,1683823744.954 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-11T16:48:04.7Z
2023-05-11T16:49:04.954Z,1683823744.954 [Startup:StartupSatComms:B] Stopped
2023-05-11T16:49:04.954Z,1683823744.954 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-11T16:49:04.954Z,1683823744.954 [Startup:StartupSatComms] Stopped
2023-05-11T16:49:04.954Z,1683823744.954 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-11T16:49:04.955Z,1683823744.955 [Startup](INFO): Completed Startup
2023-05-11T16:49:04.955Z,1683823744.955 [MissionManager](INFO): Startup is completed.
2023-05-11T16:49:04.955Z,1683823744.955 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-11T16:49:04.955Z,1683823744.955 [Startup] Stopped
2023-05-11T16:49:04.955Z,1683823744.955 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-11T16:49:04.955Z,1683823744.955 [Startup:A.GoToSurface] Stopped
2023-05-11T16:49:04.956Z,1683823744.956 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-11T16:49:05.367Z,1683823745.367 [MissionManager](IMPORTANT): Started mission Default
2023-05-11T16:49:05.367Z,1683823745.367 [Default] Running Loop=1
2023-05-11T16:49:05.367Z,1683823745.367 [Default](DEBUG): Aggregate::initialize Default
2023-05-11T16:49:05.367Z,1683823745.367 [Default:B.GoToSurface] Running Loop=1
2023-05-11T16:49:05.367Z,1683823745.367 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-11T16:49:05.368Z,1683823745.368 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-11T16:49:05.368Z,1683823745.368 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-11T16:49:05.368Z,1683823745.368 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-11T16:49:05.368Z,1683823745.368 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-11T16:49:05.369Z,1683823745.369 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-11T16:49:05.369Z,1683823745.369 [Default:A.Wait] Running Loop=1
2023-05-11T16:49:05.369Z,1683823745.369 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-11T16:49:10.255Z,1683823750.255 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:49:10.262Z,1683823750.262 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:49:18.587Z,1683823758.587 [DataOverHttps](INFO): Sending 1262 bytes from file Logs/20230511T164537/Express0001.lzma
2023-05-11T16:49:18.715Z,1683823758.715 [Default:A.Wait](INFO): Done Waiting.
2023-05-11T16:49:18.716Z,1683823758.716 [Default:A.Wait] Stopped
2023-05-11T16:49:18.716Z,1683823758.716 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T16:49:19.125Z,1683823759.125 [Default:CheckIn] Running Loop=1
2023-05-11T16:49:19.125Z,1683823759.125 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T16:49:19.125Z,1683823759.125 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T16:49:19.523Z,1683823759.523 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-11T16:49:19.584Z,1683823759.584 [DataOverHttps](INFO): Moved sent file to Logs/20230511T164537/Express0001.lzma.bak
2023-05-11T16:49:19.584Z,1683823759.584 [DataOverHttps](INFO): SBD MOMSN=18290469
2023-05-11T16:49:22.320Z,1683823762.320 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-05-11T16:49:23.529Z,1683823763.529 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:49:23.535Z,1683823763.535 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:49:36.862Z,1683823776.862 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:49:36.868Z,1683823776.868 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:49:50.194Z,1683823790.194 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:49:50.200Z,1683823790.200 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:50:03.539Z,1683823803.539 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:50:03.545Z,1683823803.545 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:50:16.872Z,1683823816.872 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:50:16.879Z,1683823816.879 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:50:30.203Z,1683823830.203 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:50:30.209Z,1683823830.209 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:51:32.007Z,1683823892.007 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T16:53:09.791Z,1683823989.791 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2023-05-11T16:53:09.794Z,1683823989.794 [BPC1](INFO): Received data from all battery sticks.
2023-05-11T16:54:19.307Z,1683824059.307 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T16:49:19.1Z
2023-05-11T16:54:19.308Z,1683824059.308 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T16:54:19.308Z,1683824059.308 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T16:54:19.712Z,1683824059.712 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-11T16:54:26.148Z,1683824066.148 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230511T164537/Courier0004.lzma
2023-05-11T16:54:27.147Z,1683824067.147 [DataOverHttps](INFO): Moved sent file to Logs/20230511T164537/Courier0004.lzma.bak
2023-05-11T16:54:27.147Z,1683824067.147 [DataOverHttps](INFO): SBD MOMSN=18290499
2023-05-11T16:54:44.545Z,1683824084.545 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20230511T164537/Express0005.lzma
2023-05-11T16:54:45.547Z,1683824085.547 [DataOverHttps](INFO): Moved sent file to Logs/20230511T164537/Express0005.lzma.bak
2023-05-11T16:54:45.548Z,1683824085.548 [DataOverHttps](INFO): SBD MOMSN=18290501
2023-05-11T16:54:48.059Z,1683824088.059 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T16:54:48.059Z,1683824088.059 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T16:54:48.059Z,1683824088.059 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T16:54:52.066Z,1683824092.066 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T16:55:11.891Z,1683824111.891 [CommandExec](IMPORTANT): got command restart application
2023-05-11T16:55:12.893Z,1683824112.893 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-05-11T16:55:12.894Z,1683824112.894 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:12.894Z,1683824112.894 [CommandExec](INFO): Uninitializing the command executive.
2023-05-11T16:55:12.894Z,1683824112.894 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-11T16:55:12.894Z,1683824112.894 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:12.945Z,1683824112.945 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-11T16:55:12.946Z,1683824112.946 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-11T16:55:12.946Z,1683824112.946 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:12.946Z,1683824112.946 [NavChartDb](INFO): Join timeout helper Thread ID is 9367
2023-05-11T16:55:13.210Z,1683824113.210 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:13.210Z,1683824113.210 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:13.229Z,1683824113.229 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-11T16:55:13.230Z,1683824113.230 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:13.230Z,1683824113.230 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9368
2023-05-11T16:55:13.614Z,1683824113.614 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:13.614Z,1683824113.614 [WetLabsBB2FL](INFO): Powering down
2023-05-11T16:55:13.615Z,1683824113.615 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:13.633Z,1683824113.633 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-11T16:55:13.634Z,1683824113.634 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:13.634Z,1683824113.634 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9369
2023-05-11T16:55:13.650Z,1683824113.650 [CTD_Seabird](INFO): Powering down
2023-05-11T16:55:13.671Z,1683824113.671 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:13.671Z,1683824113.671 [CTD_Seabird](INFO): Powering down
2023-05-11T16:55:13.687Z,1683824113.687 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:13.694Z,1683824113.694 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-11T16:55:13.694Z,1683824113.694 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:13.694Z,1683824113.694 [Radio_Surface](INFO): Join timeout helper Thread ID is 9370
2023-05-11T16:55:14.022Z,1683824114.022 [Radio_Surface](INFO): Powering down
2023-05-11T16:55:14.022Z,1683824114.022 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:14.023Z,1683824114.023 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:14.043Z,1683824114.043 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-11T16:55:14.043Z,1683824114.043 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:14.043Z,1683824114.043 [Onboard](INFO): Join timeout helper Thread ID is 9371
2023-05-11T16:55:14.610Z,1683824114.610 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-11T16:55:18.658Z,1683824118.658 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:18.658Z,1683824118.658 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:18.659Z,1683824118.659 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-11T16:55:18.659Z,1683824118.659 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:18.660Z,1683824118.660 [DataOverHttps](INFO): Join timeout helper Thread ID is 9372
2023-05-11T16:55:19.630Z,1683824119.630 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:19.630Z,1683824119.630 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:19.634Z,1683824119.634 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-11T16:55:19.634Z,1683824119.634 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:19.635Z,1683824119.635 [DATMMP](INFO): Join timeout helper Thread ID is 9373
2023-05-11T16:55:19.837Z,1683824119.837 [DATMMP](INFO): Stop
2023-05-11T16:55:19.837Z,1683824119.837 [DATMMP](INFO): uninitialize
2023-05-11T16:55:19.838Z,1683824119.838 [DATMMP](INFO): Powering down
2023-05-11T16:55:19.910Z,1683824119.910 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:19.910Z,1683824119.910 [DATMMP](INFO): uninitialize
2023-05-11T16:55:19.910Z,1683824119.910 [DATMMP](INFO): Powering down
2023-05-11T16:55:19.911Z,1683824119.911 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:19.923Z,1683824119.923 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-11T16:55:19.923Z,1683824119.923 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:19.924Z,1683824119.924 [BackseatComponent](INFO): Join timeout helper Thread ID is 9374
2023-05-11T16:55:20.014Z,1683824120.014 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:20.014Z,1683824120.014 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.015Z,1683824120.015 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-11T16:55:20.015Z,1683824120.015 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.016Z,1683824120.016 [logger](INFO): Join timeout helper Thread ID is 9375
2023-05-11T16:55:20.206Z,1683824120.206 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:20.206Z,1683824120.206 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.226Z,1683824120.226 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-11T16:55:20.226Z,1683824120.226 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.227Z,1683824120.227 [CommandLine](INFO): Join timeout helper Thread ID is 9376
2023-05-11T16:55:20.246Z,1683824120.246 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:20.246Z,1683824120.246 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.247Z,1683824120.247 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-11T16:55:20.247Z,1683824120.247 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.248Z,1683824120.248 [CommandExec](INFO): Join timeout helper Thread ID is 9377
2023-05-11T16:55:20.249Z,1683824120.249 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-11T16:55:20.249Z,1683824120.249 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.250Z,1683824120.250 [controlThread](INFO): Join timeout helper Thread ID is 9378
2023-05-11T16:55:20.382Z,1683824120.382 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T16:55:20.382Z,1683824120.382 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-11T16:55:20.382Z,1683824120.382 [AHRS_M2](INFO): Powering down
2023-05-11T16:55:20.454Z,1683824120.454 [NAL9602](INFO): Powering down
2023-05-11T16:55:20.457Z,1683824120.457 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-11T16:55:20.458Z,1683824120.458 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-11T16:55:20.458Z,1683824120.458 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-11T16:55:20.459Z,1683824120.459 [MissionManager](INFO): Uninitializing Mission Default
2023-05-11T16:55:20.459Z,1683824120.459 [Default] Stopped
2023-05-11T16:55:20.459Z,1683824120.459 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-11T16:55:20.459Z,1683824120.459 [Default:B.GoToSurface] Stopped
2023-05-11T16:55:20.459Z,1683824120.459 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-11T16:55:20.459Z,1683824120.459 [Default:CheckIn] Stopped
2023-05-11T16:55:20.459Z,1683824120.459 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T16:55:20.459Z,1683824120.459 [Default:CheckIn:C.Wait] Stopped
2023-05-11T16:55:20.459Z,1683824120.459 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T16:55:20.462Z,1683824120.462 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-11T16:55:20.462Z,1683824120.462 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-11T16:55:20.463Z,1683824120.463 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-11T16:55:20.463Z,1683824120.463 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-11T16:55:20.463Z,1683824120.463 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-11T16:55:20.463Z,1683824120.463 [BuoyancyServo](INFO): Powering down
2023-05-11T16:55:20.477Z,1683824120.477 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-11T16:55:20.478Z,1683824120.478 [ElevatorServo](INFO): Powering down
2023-05-11T16:55:20.478Z,1683824120.478 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-11T16:55:20.479Z,1683824120.479 [MassServo](INFO): Powering down
2023-05-11T16:55:20.479Z,1683824120.479 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-11T16:55:20.479Z,1683824120.479 [RudderServo](INFO): Powering down
2023-05-11T16:55:20.480Z,1683824120.480 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-05-11T16:55:20.480Z,1683824120.480 [ThrusterHE](INFO): Powering down
2023-05-11T16:55:20.481Z,1683824120.481 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-11T16:55:20.482Z,1683824120.482 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-11T16:55:20.482Z,1683824120.482 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-11T16:55:20.482Z,1683824120.482 [CBIT](DEBUG): Powering off loads.
2023-05-11T16:55:20.493Z,1683824120.493 [CBIT](DEBUG): Disabling WDT.
2023-05-11T16:55:20.505Z,1683824120.505 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-11T16:55:20.506Z,1683824120.506 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.560Z,1683824120.560 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.563Z,1683824120.563 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.569Z,1683824120.569 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.575Z,1683824120.575 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.616Z,1683824120.616 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.660Z,1683824120.660 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.665Z,1683824120.665 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.698Z,1683824120.698 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-11T16:55:20.759Z,1683824120.759 [logger ThreadHandler](INFO): Thread cancelled.