2023-04-11T16:36:46.315Z,1681231006.315 [Supervisor](DEBUG): Initializing supervisor.
2023-04-11T16:36:46.319Z,1681231006.319 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-04-11T16:36:46.320Z,1681231006.320 [SyncHandler](INFO): Protected caller Thread ID is 831
2023-04-11T16:36:46.320Z,1681231006.320 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-04-11T16:36:46.322Z,1681231006.322 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-04-11T16:36:46.322Z,1681231006.322 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 832
2023-04-11T16:36:46.326Z,1681231006.326 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-04-11T16:36:46.343Z,1681231006.343 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-04-11T16:36:46.344Z,1681231006.344 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-04-11T16:36:46.344Z,1681231006.344 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 833
2023-04-11T16:36:46.348Z,1681231006.348 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-04-11T16:36:46.350Z,1681231006.350 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-04-11T16:36:46.350Z,1681231006.350 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 834
2023-04-11T16:36:46.352Z,1681231006.352 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-04-11T16:36:46.353Z,1681231006.353 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-04-11T16:36:46.353Z,1681231006.353 [logger ThreadHandler](INFO): Protected caller Thread ID is 835
2023-04-11T16:36:46.357Z,1681231006.357 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-04-11T16:36:46.357Z,1681231006.357 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-04-11T16:36:46.361Z,1681231006.361 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-04-11T16:36:46.684Z,1681231006.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-04-11T16:36:46.685Z,1681231006.685 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-04-11T16:36:47.325Z,1681231007.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-04-11T16:36:47.326Z,1681231007.326 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-04-11T16:36:47.433Z,1681231007.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-04-11T16:36:47.435Z,1681231007.435 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-04-11T16:36:47.903Z,1681231007.903 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-04-11T16:36:47.904Z,1681231007.904 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-04-11T16:36:48.001Z,1681231008.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-04-11T16:36:48.003Z,1681231008.003 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-04-11T16:36:48.082Z,1681231008.082 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-04-11T16:36:48.466Z,1681231008.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-04-11T16:36:48.467Z,1681231008.467 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-04-11T16:36:48.679Z,1681231008.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-04-11T16:36:48.681Z,1681231008.681 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-04-11T16:36:48.815Z,1681231008.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-04-11T16:36:48.817Z,1681231008.817 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-04-11T16:36:49.041Z,1681231009.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-04-11T16:36:49.041Z,1681231009.041 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-04-11T16:36:49.549Z,1681231009.549 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-04-11T16:36:49.550Z,1681231009.550 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-04-11T16:36:49.906Z,1681231009.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-04-11T16:36:49.907Z,1681231009.907 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-04-11T16:36:50.250Z,1681231010.250 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-04-11T16:36:50.578Z,1681231010.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-04-11T16:36:50.579Z,1681231010.579 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-04-11T16:36:50.789Z,1681231010.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-04-11T16:36:50.790Z,1681231010.790 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-04-11T16:36:51.074Z,1681231011.074 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-04-11T16:36:51.076Z,1681231011.076 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2023-04-11T16:36:51.081Z,1681231011.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2023-04-11T16:36:51.187Z,1681231011.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2023-04-11T16:36:51.329Z,1681231011.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2023-04-11T16:36:51.413Z,1681231011.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2023-04-11T16:36:51.495Z,1681231011.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2023-04-11T16:36:51.601Z,1681231011.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2023-04-11T16:36:51.769Z,1681231011.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2023-04-11T16:36:52.028Z,1681231012.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-04-11T16:36:52.029Z,1681231012.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2023-04-11T16:36:52.157Z,1681231012.157 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2023-04-11T16:36:52.249Z,1681231012.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2023-04-11T16:36:52.352Z,1681231012.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2023-04-11T16:36:52.450Z,1681231012.450 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-04-11T16:36:52.470Z,1681231012.470 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-04-11T16:36:52.593Z,1681231012.593 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-04-11T16:36:52.594Z,1681231012.594 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-04-11T16:36:52.609Z,1681231012.609 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-04-11T16:36:52.610Z,1681231012.610 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-04-11T16:36:52.679Z,1681231012.679 [DepthRateCalculator] Loaded
2023-04-11T16:36:52.680Z,1681231012.680 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-04-11T16:36:52.685Z,1681231012.685 [PitchRateCalculator] Loaded
2023-04-11T16:36:52.685Z,1681231012.685 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-04-11T16:36:52.696Z,1681231012.696 [SpeedCalculator] Loaded
2023-04-11T16:36:52.696Z,1681231012.696 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-04-11T16:36:52.711Z,1681231012.711 [VerticalHomogeneityIndexCalculator] Loaded
2023-04-11T16:36:52.711Z,1681231012.711 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2023-04-11T16:36:52.716Z,1681231012.716 [YawRateCalculator] Loaded
2023-04-11T16:36:52.716Z,1681231012.716 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-04-11T16:36:52.738Z,1681231012.738 [ElevatorOffsetCalculator] Loaded
2023-04-11T16:36:52.738Z,1681231012.738 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-04-11T16:36:52.738Z,1681231012.738 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-04-11T16:36:52.739Z,1681231012.739 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-04-11T16:36:52.805Z,1681231012.805 [VerticalControl](DEBUG): Construct VerticalControl.
2023-04-11T16:36:52.860Z,1681231012.860 [VerticalControl] Loaded
2023-04-11T16:36:52.860Z,1681231012.860 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-04-11T16:36:52.863Z,1681231012.863 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-04-11T16:36:52.903Z,1681231012.903 [HorizontalControl] Loaded
2023-04-11T16:36:52.903Z,1681231012.903 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-04-11T16:36:52.906Z,1681231012.906 [SpeedControl](DEBUG): Construct SpeedControl.
2023-04-11T16:36:52.909Z,1681231012.909 [SpeedControl] Loaded
2023-04-11T16:36:52.909Z,1681231012.909 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-04-11T16:36:52.912Z,1681231012.912 [LoopControl](DEBUG): Construct LoopControl.
2023-04-11T16:36:52.912Z,1681231012.912 [LoopControl] Loaded
2023-04-11T16:36:52.912Z,1681231012.912 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-04-11T16:36:52.913Z,1681231012.913 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-04-11T16:36:52.913Z,1681231012.913 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-04-11T16:36:53.050Z,1681231013.050 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-04-11T16:36:53.050Z,1681231013.050 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-04-11T16:36:53.063Z,1681231013.063 [NavChart] Loaded
2023-04-11T16:36:53.064Z,1681231013.064 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-04-11T16:36:53.069Z,1681231013.069 [UniversalFixResidualReporter] Loaded
2023-04-11T16:36:53.070Z,1681231013.070 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-04-11T16:36:53.070Z,1681231013.070 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-04-11T16:36:53.071Z,1681231013.071 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-04-11T16:36:53.248Z,1681231013.248 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-04-11T16:36:53.249Z,1681231013.249 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-04-11T16:36:54.004Z,1681231014.004 [AHRS_M2] Loaded
2023-04-11T16:36:54.004Z,1681231014.004 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-04-11T16:36:54.254Z,1681231014.254 [BackseatComponent] Loaded
2023-04-11T16:36:54.254Z,1681231014.254 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-04-11T16:36:54.255Z,1681231014.255 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2023-04-11T16:36:54.256Z,1681231014.256 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 917
2023-04-11T16:36:54.259Z,1681231014.259 [LcmUniversalReporter] Loaded
2023-04-11T16:36:54.259Z,1681231014.259 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-04-11T16:36:55.070Z,1681231015.070 [BPC1] Loaded
2023-04-11T16:36:55.070Z,1681231015.070 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-04-11T16:36:55.213Z,1681231015.213 [DataOverHttps] Loaded
2023-04-11T16:36:55.213Z,1681231015.213 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-04-11T16:36:55.214Z,1681231015.214 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409264E0
2023-04-11T16:36:55.214Z,1681231015.214 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 918
2023-04-11T16:36:55.235Z,1681231015.235 [Depth_Keller] Loaded
2023-04-11T16:36:55.235Z,1681231015.235 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-04-11T16:36:55.240Z,1681231015.240 [DropWeight] Loaded
2023-04-11T16:36:55.240Z,1681231015.240 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-04-11T16:36:55.278Z,1681231015.278 [DVL_micro] Loaded
2023-04-11T16:36:55.279Z,1681231015.279 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-04-11T16:36:55.342Z,1681231015.342 [NAL9602] Loaded
2023-04-11T16:36:55.342Z,1681231015.342 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-04-11T16:36:55.371Z,1681231015.371 [Onboard] Loaded
2023-04-11T16:36:55.372Z,1681231015.372 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-04-11T16:36:55.373Z,1681231015.373 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409564E0
2023-04-11T16:36:55.373Z,1681231015.373 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 919
2023-04-11T16:36:55.385Z,1681231015.385 [Power24vConverter] Loaded
2023-04-11T16:36:55.386Z,1681231015.386 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-04-11T16:36:55.399Z,1681231015.399 [Radio_Surface] Loaded
2023-04-11T16:36:55.399Z,1681231015.399 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-04-11T16:36:55.400Z,1681231015.400 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409864E0
2023-04-11T16:36:55.400Z,1681231015.400 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 920
2023-04-11T16:36:55.491Z,1681231015.491 [DAT] Loaded
2023-04-11T16:36:55.492Z,1681231015.492 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-04-11T16:36:55.493Z,1681231015.493 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409B64E0
2023-04-11T16:36:55.493Z,1681231015.493 [DAT ThreadHandler](INFO): Protected caller Thread ID is 921
2023-04-11T16:36:55.494Z,1681231015.494 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-04-11T16:36:55.494Z,1681231015.494 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-04-11T16:36:55.864Z,1681231015.864 [BuoyancyServo] Loaded
2023-04-11T16:36:55.865Z,1681231015.865 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-04-11T16:36:55.957Z,1681231015.957 [ElevatorServo] Loaded
2023-04-11T16:36:55.958Z,1681231015.958 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-04-11T16:36:56.012Z,1681231016.012 [MassServo] Loaded
2023-04-11T16:36:56.012Z,1681231016.012 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-04-11T16:36:56.060Z,1681231016.060 [RudderServo] Loaded
2023-04-11T16:36:56.060Z,1681231016.060 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-04-11T16:36:56.096Z,1681231016.096 [ThrusterHE] Loaded
2023-04-11T16:36:56.096Z,1681231016.096 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-04-11T16:36:56.097Z,1681231016.097 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-04-11T16:36:56.097Z,1681231016.097 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-04-11T16:36:56.211Z,1681231016.211 [StratificationFrontDetector](IMPORTANT): thresholdVTHI set to: 0.050012 degC
2023-04-11T16:36:56.211Z,1681231016.211 [StratificationFrontDetector](INFO): (re)initializing
2023-04-11T16:36:56.211Z,1681231016.211 [StratificationFrontDetector] Loaded
2023-04-11T16:36:56.212Z,1681231016.212 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2023-04-11T16:36:56.212Z,1681231016.212 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-04-11T16:36:56.213Z,1681231016.213 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-04-11T16:36:56.270Z,1681231016.270 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-04-11T16:36:56.270Z,1681231016.270 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-04-11T16:36:56.623Z,1681231016.623 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-04-11T16:36:56.624Z,1681231016.624 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-04-11T16:36:56.838Z,1681231016.838 [CTD_Seabird] Loaded
2023-04-11T16:36:56.838Z,1681231016.838 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-04-11T16:36:56.839Z,1681231016.839 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B404E0
2023-04-11T16:36:56.840Z,1681231016.840 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 922
2023-04-11T16:36:56.873Z,1681231016.873 [ESPComponent] Loaded
2023-04-11T16:36:56.873Z,1681231016.873 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-04-11T16:36:56.894Z,1681231016.894 [PAR_Licor] Loaded
2023-04-11T16:36:56.895Z,1681231016.895 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-04-11T16:36:56.925Z,1681231016.925 [WetLabsBB2FL] Loaded
2023-04-11T16:36:56.926Z,1681231016.926 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-04-11T16:36:56.926Z,1681231016.926 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B704E0
2023-04-11T16:36:56.927Z,1681231016.927 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 923
2023-04-11T16:36:56.928Z,1681231016.928 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-04-11T16:36:56.929Z,1681231016.929 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-04-11T16:36:57.094Z,1681231017.094 [SBIT](DEBUG): Construct Startup Built In Test.
2023-04-11T16:36:57.104Z,1681231017.104 [SBIT] Loaded
2023-04-11T16:36:57.104Z,1681231017.104 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-04-11T16:36:57.107Z,1681231017.107 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-04-11T16:36:57.119Z,1681231017.119 [IBIT] Loaded
2023-04-11T16:36:57.120Z,1681231017.120 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-04-11T16:36:57.126Z,1681231017.126 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-04-11T16:36:57.230Z,1681231017.230 [CBIT] Loaded
2023-04-11T16:36:57.230Z,1681231017.230 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-04-11T16:36:57.230Z,1681231017.230 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-04-11T16:36:57.236Z,1681231017.236 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-04-11T16:36:57.239Z,1681231017.239 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-04-11T16:36:57.250Z,1681231017.250 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-04-11T16:36:57.251Z,1681231017.251 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BDE4E0
2023-04-11T16:36:57.252Z,1681231017.252 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 924
2023-04-11T16:36:57.256Z,1681231017.256 [Supervisor](INFO): Main Thread ID is 827
2023-04-11T16:36:57.256Z,1681231017.256 [Supervisor](DEBUG): Running supervisor.
2023-04-11T16:36:57.257Z,1681231017.257 [CommandExec ThreadHandler](INFO): Handler Thread ID is 925
2023-04-11T16:36:57.257Z,1681231017.257 [CommandExec](INFO): Initializing the command executive.
2023-04-11T16:36:57.259Z,1681231017.259 [CommandLine ThreadHandler](INFO): Handler Thread ID is 926
2023-04-11T16:36:57.261Z,1681231017.261 [controlThread ThreadHandler](INFO): Handler Thread ID is 927
2023-04-11T16:36:57.261Z,1681231017.261 [controlThread](DEBUG): Initializing ControlThread
2023-04-11T16:36:57.262Z,1681231017.262 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-04-11T16:36:57.263Z,1681231017.263 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-04-11T16:36:57.263Z,1681231017.263 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-04-11T16:36:57.263Z,1681231017.263 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2023-04-11T16:36:57.264Z,1681231017.264 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-04-11T16:36:57.264Z,1681231017.264 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-04-11T16:36:57.265Z,1681231017.265 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-04-11T16:36:57.267Z,1681231017.267 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-04-11T16:36:57.267Z,1681231017.267 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-04-11T16:36:57.268Z,1681231017.268 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-04-11T16:36:57.268Z,1681231017.268 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-04-11T16:36:57.269Z,1681231017.269 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-04-11T16:36:57.281Z,1681231017.281 [SBIT](INFO): Initialize SBIT Component.
2023-04-11T16:36:57.282Z,1681231017.282 [SBIT](IMPORTANT): git: 2023-04-11
2023-04-11T16:36:57.282Z,1681231017.282 [SBIT](INFO): git hash: 6d7eb32f2d4cf73a2f275df29fd39a45f67762a0
2023-04-11T16:36:57.282Z,1681231017.282 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-04-11T16:36:57.283Z,1681231017.283 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-04-11T16:36:57.284Z,1681231017.284 [SBIT](INFO): Beginning SBIT in 96.000000 seconds.
2023-04-11T16:36:57.285Z,1681231017.285 [IBIT](INFO): Initialize IBIT Component.
2023-04-11T16:36:57.286Z,1681231017.286 [CBIT](DEBUG): Initialize CBIT Component.
2023-04-11T16:36:57.287Z,1681231017.287 [logger ThreadHandler](INFO): Handler Thread ID is 928
2023-04-11T16:36:57.297Z,1681231017.297 [CBIT](DEBUG): Initialized mux pins.
2023-04-11T16:36:57.297Z,1681231017.297 [CBIT](DEBUG): Initializing the watchdog timer.
2023-04-11T16:36:57.305Z,1681231017.305 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 929
2023-04-11T16:36:57.318Z,1681231017.318 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 930
2023-04-11T16:36:57.318Z,1681231017.318 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-04-11T16:36:57.321Z,1681231017.321 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-04-11T16:36:57.322Z,1681231017.322 [CBIT](DEBUG): Initializing heartbeat.
2023-04-11T16:36:57.329Z,1681231017.329 [Onboard ThreadHandler](INFO): Handler Thread ID is 931
2023-04-11T16:36:57.360Z,1681231017.360 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 932
2023-04-11T16:36:57.369Z,1681231017.369 [DAT ThreadHandler](INFO): Handler Thread ID is 933
2023-04-11T16:36:57.370Z,1681231017.370 [DAT](INFO): Powering up
2023-04-11T16:36:57.370Z,1681231017.370 [DAT](DEBUG): Initializing DAT.
2023-04-11T16:36:57.374Z,1681231017.374 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 935
2023-04-11T16:36:57.375Z,1681231017.375 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-04-11T16:36:57.378Z,1681231017.378 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 937
2023-04-11T16:36:57.382Z,1681231017.382 [WetLabsBB2FL](INFO): Powering up
2023-04-11T16:36:57.383Z,1681231017.383 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 939
2023-04-11T16:36:57.391Z,1681231017.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-04-11T16:36:57.391Z,1681231017.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-04-11T16:36:57.391Z,1681231017.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-04-11T16:36:57.391Z,1681231017.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-04-11T16:36:57.392Z,1681231017.392 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-04-11T16:36:57.392Z,1681231017.392 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-04-11T16:36:57.392Z,1681231017.392 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-04-11T16:36:57.392Z,1681231017.392 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-04-11T16:36:57.393Z,1681231017.393 [CBIT](DEBUG): Deactivating GF circuits.
2023-04-11T16:36:57.393Z,1681231017.393 [CBIT](DEBUG): Deactivating emergency mode.
2023-04-11T16:36:57.433Z,1681231017.433 [CBIT](DEBUG): Backplane powered.
2023-04-11T16:36:57.434Z,1681231017.434 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-04-11T16:36:57.470Z,1681231017.470 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-11T16:36:57.498Z,1681231017.498 [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-04-11T16:36:57.498Z,1681231017.498 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-04-11T16:36:57.567Z,1681231017.567 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-04-11T16:36:57.569Z,1681231017.569 [Default:A.Wait](DEBUG): Construct Wait.
2023-04-11T16:36:57.606Z,1681231017.606 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-11T16:36:57.633Z,1681231017.633 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-04-11T16:36:57.636Z,1681231017.636 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-04-11T16:36:57.654Z,1681231017.654 [Default:E.Execute](DEBUG): Construct Execute.
2023-04-11T16:36:57.673Z,1681231017.673 [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-04-11T16:36:57.678Z,1681231017.678 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-11T16:36:57.689Z,1681231017.689 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-04-11T16:36:57.761Z,1681231017.761 [Radio_Surface](INFO): Powering up
2023-04-11T16:36:57.854Z,1681231017.854 [Power24vConverter](INFO): Powering up.
2023-04-11T16:36:57.939Z,1681231017.939 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-04-11T16:36:57.945Z,1681231017.945 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-04-11T16:36:57.946Z,1681231017.946 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-04-11T16:36:57.954Z,1681231017.954 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-04-11T16:36:57.955Z,1681231017.955 [MassServo](DEBUG): Initializing EZServoServo.
2023-04-11T16:36:57.961Z,1681231017.961 [MassServo](DEBUG): Initializing MassServo.
2023-04-11T16:36:57.962Z,1681231017.962 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-11T16:36:57.969Z,1681231017.969 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-11T16:36:57.970Z,1681231017.970 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-04-11T16:36:57.977Z,1681231017.977 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-04-11T16:36:59.993Z,1681231019.993 [WetLabsBB2FL](INFO): Powering down
2023-04-11T16:37:03.470Z,1681231023.470 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-04-11T16:37:04.406Z,1681231024.406 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-04-11T16:37:09.218Z,1681231029.218 [DAT](INFO): DAT read: 

2023-04-11T16:37:09.219Z,1681231029.219 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-04-11T16:37:10.730Z,1681231030.730 [DAT](INFO): DAT read: MF Frequency Band

2023-04-11T16:37:10.731Z,1681231030.731 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-04-11T16:37:10.732Z,1681231030.732 [DAT](INFO): DAT read: Apr 11 2023 16:37:05 

2023-04-11T16:37:11.990Z,1681231031.990 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-04-11T16:37:11.992Z,1681231031.992 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-04-11T16:37:11.992Z,1681231031.992 [DAT](INFO): commRate: 800
2023-04-11T16:37:14.057Z,1681231034.057 [DAT](INFO): entering command mode
2023-04-11T16:37:14.257Z,1681231034.257 [DAT](INFO): DAT read: 

2023-04-11T16:37:14.258Z,1681231034.258 [DAT](INFO): DAT read: user:1>

2023-04-11T16:37:14.258Z,1681231034.258 [DAT](INFO): setting verbose to 3
2023-04-11T16:37:14.510Z,1681231034.510 [DAT](INFO): DAT read: user:1>

2023-04-11T16:37:14.511Z,1681231034.511 [DAT](INFO): DAT read: Verbose         | 3           

2023-04-11T16:37:14.511Z,1681231034.511 [DAT](INFO): set verbose to 3
2023-04-11T16:37:14.511Z,1681231034.511 [DAT](INFO): setting DatVerbose to 27440
2023-04-11T16:37:14.762Z,1681231034.762 [DAT](INFO): DAT read: user:2>

2023-04-11T16:37:14.763Z,1681231034.763 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-04-11T16:37:14.763Z,1681231034.763 [DAT](INFO): set DatVerbose to 27440
2023-04-11T16:37:14.763Z,1681231034.763 [DAT](INFO): setting transmit power to 8
2023-04-11T16:37:15.014Z,1681231035.014 [DAT](INFO): DAT read: user:3>

2023-04-11T16:37:15.015Z,1681231035.015 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-04-11T16:37:15.015Z,1681231035.015 [DAT](INFO): set transmit power to 8
2023-04-11T16:37:15.015Z,1681231035.015 [DAT](INFO): setting local address to 8
2023-04-11T16:37:15.266Z,1681231035.266 [DAT](INFO): DAT read: user:4>

2023-04-11T16:37:15.267Z,1681231035.267 [DAT](INFO): DAT read: LocalAddr       | 8           

2023-04-11T16:37:15.267Z,1681231035.267 [DAT](INFO): set local address to 8
2023-04-11T16:37:15.268Z,1681231035.268 [DAT](INFO): Setting time to: 16:37:15 And date to:4/11/2023
2023-04-11T16:37:15.518Z,1681231035.518 [DAT](INFO): DAT read: user:5>

2023-04-11T16:37:15.519Z,1681231035.519 [DAT](INFO): DAT read: Tue Apr 11, 2023  16:37:15  

2023-04-11T16:37:15.519Z,1681231035.519 [DAT](INFO): Local DAT time set to Tue Apr 11, 2023  16:37:15  

2023-04-11T16:37:25.579Z,1681231045.579 [NAL9602](INFO): Powering up NAL9602
2023-04-11T16:37:36.479Z,1681231056.479 [NAL9602](INFO): NAL9602 initialized
2023-04-11T16:38:33.860Z,1681231113.860 [SBIT](IMPORTANT): Beginning Startup BIT
2023-04-11T16:38:33.885Z,1681231113.885 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-11T16:38:44.817Z,1681231124.817 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006663
CHAN A1 (24V): 0.014352
CHAN A2 (12V): 0.002848
CHAN A3 (5V): -0.001747
CHAN B0 (3.3V): -0.001306
CHAN B1 (3.15aV): -0.001376
CHAN B2 (3.15bV): -0.001044
CHAN B3 (GND): -0.003654
OPEN: -0.003669
Full Scale: +/- 1 mA
2023-04-11T16:39:28.004Z,1681231168.004 [SBIT](IMPORTANT): SBIT PASSED
2023-04-11T16:39:28.004Z,1681231168.004 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-04-11T16:39:28.005Z,1681231168.005 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count;

2023-04-11T16:39:28.005Z,1681231168.005 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2023-04-11T16:39:28.005Z,1681231168.005 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar;

2023-04-11T16:39:28.005Z,1681231168.005 [SBIT](IMPORTANT): Express none CBIT.ampHoursUsed;

2023-04-11T16:39:28.005Z,1681231168.005 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius;

2023-04-11T16:39:28.005Z,1681231168.005 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-04-11T16:39:28.006Z,1681231168.006 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2023-04-11T16:39:28.006Z,1681231168.006 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;

2023-04-11T16:39:28.006Z,1681231168.006 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;

2023-04-11T16:39:28.006Z,1681231168.006 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;

2023-04-11T16:39:28.006Z,1681231168.006 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=304.139044 cubic_centimeter;

2023-04-11T16:39:28.006Z,1681231168.006 [SBIT](IMPORTANT): VerticalControl.massDefault=19.116968 millimeter;

2023-04-11T16:39:28.007Z,1681231168.007 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter;

2023-04-11T16:39:28.007Z,1681231168.007 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=32 millimeter;

2023-04-11T16:39:28.007Z,1681231168.007 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=1.5 meter;

2023-04-11T16:39:28.007Z,1681231168.007 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-04-11T16:39:28.007Z,1681231168.007 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-04-11T16:39:28.007Z,1681231168.007 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;

2023-04-11T16:39:28.421Z,1681231168.421 [MissionManager](IMPORTANT): Started mission Startup
2023-04-11T16:39:28.422Z,1681231168.422 [Startup] Running Loop=1
2023-04-11T16:39:28.422Z,1681231168.422 [Startup](DEBUG): Aggregate::initialize Startup
2023-04-11T16:39:28.422Z,1681231168.422 [Startup:A.GoToSurface] Running Loop=1
2023-04-11T16:39:28.422Z,1681231168.422 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-11T16:39:28.423Z,1681231168.423 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-11T16:39:28.423Z,1681231168.423 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-11T16:39:28.423Z,1681231168.423 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-11T16:39:28.424Z,1681231168.424 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-11T16:39:28.424Z,1681231168.424 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-11T16:39:28.430Z,1681231168.430 [Startup:StartupSatComms] Running Loop=1
2023-04-11T16:39:28.430Z,1681231168.430 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-04-11T16:39:28.430Z,1681231168.430 [Startup:StartupSatComms:A] Running Loop=1
2023-04-11T16:39:28.806Z,1681231168.806 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-04-11T16:39:57.537Z,1681231197.537 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-04-11T16:39:57.537Z,1681231197.537 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T16:39:57.550Z,1681231197.550 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T16:39:57.949Z,1681231197.949 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T16:39:57.949Z,1681231197.949 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-04-11T16:40:04.423Z,1681231204.423 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004484
2023-04-11T16:40:15.530Z,1681231215.530 [DAT](INFO): DAT read: user:6>Lowpower

2023-04-11T16:40:15.530Z,1681231215.530 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:6>Lowpower

2023-04-11T16:40:28.632Z,1681231228.632 [Startup:StartupSatComms:A](INFO): Timed out from 2023-04-11T16:39:28.4Z
2023-04-11T16:40:28.632Z,1681231228.632 [Startup:StartupSatComms:A] Stopped
2023-04-11T16:40:28.632Z,1681231228.632 [Startup:StartupSatComms:B] Running Loop=1
2023-04-11T16:40:29.044Z,1681231229.044 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-11T16:40:36.088Z,1681231236.088 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230411T162400/Courier0007.lzma
2023-04-11T16:40:37.093Z,1681231237.093 [DataOverHttps](INFO): Moved sent file to Logs/20230411T162400/Courier0007.lzma.bak
2023-04-11T16:40:37.093Z,1681231237.093 [DataOverHttps](INFO): SBD MOMSN=18203023
2023-04-11T16:40:52.828Z,1681231252.828 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230411T163646/Courier0000.lzma
2023-04-11T16:40:53.830Z,1681231253.830 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Courier0000.lzma.bak
2023-04-11T16:40:53.830Z,1681231253.830 [DataOverHttps](INFO): SBD MOMSN=18203025
2023-04-11T16:41:09.424Z,1681231269.424 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230411T162400/Express0008.lzma
2023-04-11T16:41:10.426Z,1681231270.426 [DataOverHttps](INFO): Moved sent file to Logs/20230411T162400/Express0008.lzma.bak
2023-04-11T16:41:10.426Z,1681231270.426 [DataOverHttps](INFO): SBD MOMSN=18203028
2023-04-11T16:41:26.170Z,1681231286.170 [DataOverHttps](INFO): Sending 1086 bytes from file Logs/20230411T163646/Express0001.lzma
2023-04-11T16:41:27.171Z,1681231287.171 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0001.lzma.bak
2023-04-11T16:41:27.171Z,1681231287.171 [DataOverHttps](INFO): SBD MOMSN=18203030
2023-04-11T16:41:28.542Z,1681231288.542 [Startup:StartupSatComms:B] Stopped
2023-04-11T16:41:28.542Z,1681231288.542 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-04-11T16:41:28.542Z,1681231288.542 [Startup:StartupSatComms] Stopped
2023-04-11T16:41:28.543Z,1681231288.543 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-04-11T16:41:28.543Z,1681231288.543 [Startup](INFO): Completed Startup
2023-04-11T16:41:28.543Z,1681231288.543 [MissionManager](INFO): Startup is completed.
2023-04-11T16:41:28.544Z,1681231288.544 [MissionManager](INFO): Uninitializing Mission Startup
2023-04-11T16:41:28.544Z,1681231288.544 [Startup] Stopped
2023-04-11T16:41:28.544Z,1681231288.544 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-04-11T16:41:28.544Z,1681231288.544 [Startup:A.GoToSurface] Stopped
2023-04-11T16:41:28.544Z,1681231288.544 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-11T16:41:28.950Z,1681231288.950 [MissionManager](IMPORTANT): Started mission Default
2023-04-11T16:41:28.950Z,1681231288.950 [Default] Running Loop=1
2023-04-11T16:41:28.950Z,1681231288.950 [Default](DEBUG): Aggregate::initialize Default
2023-04-11T16:41:28.950Z,1681231288.950 [Default:B.GoToSurface] Running Loop=1
2023-04-11T16:41:28.950Z,1681231288.950 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-11T16:41:28.951Z,1681231288.951 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-11T16:41:28.951Z,1681231288.951 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-11T16:41:28.951Z,1681231288.951 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-11T16:41:28.952Z,1681231288.952 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-11T16:41:28.952Z,1681231288.952 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-11T16:41:28.952Z,1681231288.952 [Default:A.Wait] Running Loop=1
2023-04-11T16:41:28.952Z,1681231288.952 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-04-11T16:41:42.311Z,1681231302.311 [Default:A.Wait](INFO): Done Waiting.
2023-04-11T16:41:42.311Z,1681231302.311 [Default:A.Wait] Stopped
2023-04-11T16:41:42.311Z,1681231302.311 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-04-11T16:41:42.703Z,1681231302.703 [Default:CheckIn] Running Loop=1
2023-04-11T16:41:42.703Z,1681231302.703 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-11T16:41:42.703Z,1681231302.703 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-11T16:41:43.084Z,1681231303.084 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-04-11T16:42:40.437Z,1681231360.437 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-11T16:42:58.624Z,1681231378.624 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-04-11T16:42:58.624Z,1681231378.624 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T16:42:58.635Z,1681231378.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T16:42:59.047Z,1681231379.047 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T16:42:59.048Z,1681231379.048 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-04-11T16:44:23.088Z,1681231463.088 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-11T16:44:23.088Z,1681231463.088 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+99999.99,+9999.99
2023-04-11T16:44:31.145Z,1681231471.145 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:44:46.093Z,1681231486.093 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:45:00.641Z,1681231500.641 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:45:15.586Z,1681231515.586 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:45:30.534Z,1681231530.534 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:45:45.894Z,1681231545.894 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:45:59.636Z,1681231559.636 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-04-11T16:45:59.636Z,1681231559.636 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T16:45:59.647Z,1681231559.647 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T16:46:00.057Z,1681231560.057 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T16:46:00.057Z,1681231560.057 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-04-11T16:46:00.833Z,1681231560.833 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:46:15.781Z,1681231575.781 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:46:30.731Z,1681231590.731 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:46:42.891Z,1681231602.891 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-11T16:41:42.7Z
2023-04-11T16:46:42.891Z,1681231602.891 [Default:CheckIn:Read_GPS] Stopped
2023-04-11T16:46:42.891Z,1681231602.891 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-11T16:46:43.299Z,1681231603.299 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-11T16:46:45.273Z,1681231605.273 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:46:50.116Z,1681231610.116 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230411T163646/Courier0004.lzma
2023-04-11T16:46:51.118Z,1681231611.118 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Courier0004.lzma.bak
2023-04-11T16:46:51.118Z,1681231611.118 [DataOverHttps](INFO): SBD MOMSN=18203060
2023-04-11T16:47:06.768Z,1681231626.768 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20230411T163646/Express0005.lzma
2023-04-11T16:47:07.770Z,1681231627.770 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0005.lzma.bak
2023-04-11T16:47:07.770Z,1681231627.770 [DataOverHttps](INFO): SBD MOMSN=18203062
2023-04-11T16:47:09.146Z,1681231629.146 [Default:CheckIn:Read_Iridium] Stopped
2023-04-11T16:47:09.146Z,1681231629.146 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-11T16:47:09.146Z,1681231629.146 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-11T16:47:14.372Z,1681231634.372 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-11T16:47:14.373Z,1681231634.373 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+230,1489.0,000
2023-04-11T16:47:39.432Z,1681231659.432 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-11T16:47:39.432Z,1681231659.432 [NAL9602] Data Fault, FailCount= 1
2023-04-11T16:47:39.432Z,1681231659.432 [NAL9602](ERROR): Data Fault
2023-04-11T16:47:39.450Z,1681231659.450 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-11T16:47:39.826Z,1681231659.826 [NAL9602](INFO): Powering down
2023-04-11T16:47:40.653Z,1681231660.653 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-11T16:47:40.653Z,1681231660.653 [NAL9602] No Fault, FailCount= 1
2023-04-11T16:48:10.120Z,1681231690.120 [NAL9602](INFO): Powering up NAL9602
2023-04-11T16:48:21.031Z,1681231701.031 [NAL9602](INFO): NAL9602 initialized
2023-04-11T16:49:00.699Z,1681231740.699 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-04-11T16:49:00.699Z,1681231740.699 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T16:49:00.735Z,1681231740.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T16:49:01.115Z,1681231741.115 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T16:49:01.115Z,1681231741.115 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-04-11T16:52:01.701Z,1681231921.701 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-04-11T16:52:01.701Z,1681231921.701 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T16:52:01.711Z,1681231921.711 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T16:52:02.159Z,1681231922.159 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T16:52:02.160Z,1681231922.160 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-04-11T16:52:03.688Z,1681231923.688 [DVL_micro](ERROR): only read 2 of 4 data items
2023-04-11T16:52:03.689Z,1681231923.689 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,++9999.99
2023-04-11T16:52:08.534Z,1681231928.534 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-11T16:52:08.534Z,1681231928.534 [DVL_micro](ERROR): Failed to parse:
:BI,+01250,-0099,+00000,I
2023-04-11T16:52:09.752Z,1681231929.752 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-11T16:52:09.752Z,1681231929.752 [Default:CheckIn:C.Wait] Stopped
2023-04-11T16:52:09.752Z,1681231929.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-11T16:52:09.752Z,1681231929.752 [Default:CheckIn:D] Running Loop=1
2023-04-11T16:52:10.154Z,1681231930.154 [Default:CheckIn:D] Stopped
2023-04-11T16:52:10.155Z,1681231930.155 [Default:CheckIn:E] Running Loop=1
2023-04-11T16:52:10.580Z,1681231930.580 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.686738 min 
2023-04-11T16:52:10.580Z,1681231930.580 [Default:CheckIn:E] Stopped
2023-04-11T16:52:10.580Z,1681231930.580 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-11T16:52:10.580Z,1681231930.580 [Default:CheckIn] Stopped
2023-04-11T16:52:10.580Z,1681231930.580 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-11T16:52:10.580Z,1681231930.580 [Default:CheckIn](INFO): Running loop #2
2023-04-11T16:52:10.580Z,1681231930.580 [Default:CheckIn] Running Loop=2
2023-04-11T16:52:10.581Z,1681231930.581 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-11T16:52:10.581Z,1681231930.581 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-11T16:54:26.690Z,1681232066.690 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:54:31.941Z,1681232071.941 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:54:46.489Z,1681232086.489 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:54:46.494Z,1681232086.494 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-04-11T16:55:01.029Z,1681232101.029 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:55:02.664Z,1681232102.664 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-04-11T16:55:02.664Z,1681232102.664 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T16:55:02.702Z,1681232102.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T16:55:03.077Z,1681232103.077 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T16:55:03.077Z,1681232103.077 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-04-11T16:55:15.977Z,1681232115.977 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:55:30.926Z,1681232130.926 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:55:46.277Z,1681232146.277 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:56:01.226Z,1681232161.226 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:56:16.173Z,1681232176.173 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:56:31.121Z,1681232191.121 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:56:45.667Z,1681232205.667 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T16:57:10.744Z,1681232230.744 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-11T16:52:10.6Z
2023-04-11T16:57:10.744Z,1681232230.744 [Default:CheckIn:Read_GPS] Stopped
2023-04-11T16:57:10.744Z,1681232230.744 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-11T16:57:17.168Z,1681232237.168 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230411T163646/Courier0007.lzma
2023-04-11T16:57:18.170Z,1681232238.170 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Courier0007.lzma.bak
2023-04-11T16:57:18.171Z,1681232238.171 [DataOverHttps](INFO): SBD MOMSN=18203075
2023-04-11T16:57:33.680Z,1681232253.680 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20230411T163646/Express0008.lzma
2023-04-11T16:57:34.682Z,1681232254.682 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0008.lzma.bak
2023-04-11T16:57:34.682Z,1681232254.682 [DataOverHttps](INFO): SBD MOMSN=18203077
2023-04-11T16:57:35.787Z,1681232255.787 [Default:CheckIn:Read_Iridium] Stopped
2023-04-11T16:57:35.787Z,1681232255.787 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-11T16:57:35.787Z,1681232255.787 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-11T16:58:03.686Z,1681232283.686 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-04-11T16:58:03.686Z,1681232283.686 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T16:58:03.697Z,1681232283.697 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T16:58:04.085Z,1681232284.085 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T16:58:04.085Z,1681232284.085 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-04-11T16:58:24.671Z,1681232304.671 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-11T16:58:24.671Z,1681232304.671 [NAL9602] Data Fault, FailCount= 2
2023-04-11T16:58:24.671Z,1681232304.671 [NAL9602](ERROR): Data Fault
2023-04-11T16:58:24.758Z,1681232304.758 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-11T16:58:25.070Z,1681232305.070 [NAL9602](INFO): Powering down
2023-04-11T16:58:25.914Z,1681232305.914 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-11T16:58:25.914Z,1681232305.914 [NAL9602] No Fault, FailCount= 2
2023-04-11T16:58:55.380Z,1681232335.380 [NAL9602](INFO): Powering up NAL9602
2023-04-11T16:59:06.293Z,1681232346.293 [NAL9602](INFO): NAL9602 initialized
2023-04-11T16:59:53.987Z,1681232393.987 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-11T16:59:53.987Z,1681232393.987 [DVL_micro](ERROR): Failed to parse:
:BI,+01405,-1141,+00000,I
2023-04-11T17:01:04.732Z,1681232464.732 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-04-11T17:01:04.732Z,1681232464.732 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:01:04.747Z,1681232464.747 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:01:05.109Z,1681232465.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:01:05.109Z,1681232465.109 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-04-11T17:02:36.486Z,1681232556.486 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-11T17:02:36.487Z,1681232556.487 [Default:CheckIn:C.Wait] Stopped
2023-04-11T17:02:36.487Z,1681232556.487 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-11T17:02:36.487Z,1681232556.487 [Default:CheckIn:D] Running Loop=1
2023-04-11T17:02:36.893Z,1681232556.893 [Default:CheckIn:D] Stopped
2023-04-11T17:02:36.893Z,1681232556.893 [Default:CheckIn:E] Running Loop=1
2023-04-11T17:02:37.310Z,1681232557.310 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.132379 min 
2023-04-11T17:02:37.310Z,1681232557.310 [Default:CheckIn:E] Stopped
2023-04-11T17:02:37.310Z,1681232557.310 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-11T17:02:37.310Z,1681232557.310 [Default:CheckIn] Stopped
2023-04-11T17:02:37.310Z,1681232557.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-11T17:02:37.310Z,1681232557.310 [Default:CheckIn](INFO): Running loop #3
2023-04-11T17:02:37.310Z,1681232557.310 [Default:CheckIn] Running Loop=3
2023-04-11T17:02:37.310Z,1681232557.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-11T17:02:37.310Z,1681232557.310 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-11T17:04:05.811Z,1681232645.811 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-04-11T17:04:05.811Z,1681232645.811 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:04:05.821Z,1681232645.821 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:04:06.230Z,1681232646.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:04:06.230Z,1681232646.230 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-04-11T17:04:26.377Z,1681232666.377 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:04:41.729Z,1681232681.729 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:04:41.734Z,1681232681.734 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-04-11T17:04:56.681Z,1681232696.681 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:05:11.625Z,1681232711.625 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:05:26.169Z,1681232726.169 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:05:41.117Z,1681232741.117 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:05:55.661Z,1681232755.661 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:06:10.609Z,1681232770.609 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:06:25.156Z,1681232785.156 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:06:39.697Z,1681232799.697 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-04-11T17:07:06.825Z,1681232826.825 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-04-11T17:07:06.825Z,1681232826.825 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:07:06.840Z,1681232826.840 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:07:07.226Z,1681232827.226 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:07:07.226Z,1681232827.226 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-04-11T17:07:37.546Z,1681232857.546 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-11T17:02:37.3Z
2023-04-11T17:07:37.546Z,1681232857.546 [Default:CheckIn:Read_GPS] Stopped
2023-04-11T17:07:37.546Z,1681232857.546 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-11T17:07:44.612Z,1681232864.612 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230411T163646/Courier0010.lzma
2023-04-11T17:07:45.614Z,1681232865.614 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Courier0010.lzma.bak
2023-04-11T17:07:45.614Z,1681232865.614 [DataOverHttps](INFO): SBD MOMSN=18203097
2023-04-11T17:07:58.134Z,1681232878.134 [BPC1](INFO): Calculating totals. Valid battery stick count: 27. Valid reserve battery stick count: 3.
2023-04-11T17:07:58.137Z,1681232878.137 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2023-04-11T17:08:03.964Z,1681232883.964 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230411T163646/Express0011.lzma
2023-04-11T17:08:04.966Z,1681232884.966 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0011.lzma.bak
2023-04-11T17:08:04.966Z,1681232884.966 [DataOverHttps](INFO): SBD MOMSN=18203099
2023-04-11T17:08:06.241Z,1681232886.241 [Default:CheckIn:Read_Iridium] Stopped
2023-04-11T17:08:06.241Z,1681232886.241 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-11T17:08:06.241Z,1681232886.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-11T17:08:14.311Z,1681232894.311 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-11T17:08:14.311Z,1681232894.311 [DVL_micro](ERROR): Failed to parse:
:BI,00375,+00836,+00000,I
2023-04-11T17:09:08.439Z,1681232948.439 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-11T17:09:08.439Z,1681232948.439 [NAL9602] Data Fault, FailCount= 3
2023-04-11T17:09:08.439Z,1681232948.439 [NAL9602](ERROR): Data Fault
2023-04-11T17:09:08.488Z,1681232948.488 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-11T17:09:08.842Z,1681232948.842 [NAL9602](INFO): Powering down
2023-04-11T17:09:09.684Z,1681232949.684 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-11T17:09:09.684Z,1681232949.684 [NAL9602] No Fault, FailCount= 3
2023-04-11T17:09:39.142Z,1681232979.142 [NAL9602](INFO): Powering up NAL9602
2023-04-11T17:09:50.056Z,1681232990.056 [NAL9602](INFO): NAL9602 initialized
2023-04-11T17:10:07.830Z,1681233007.830 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-04-11T17:10:07.830Z,1681233007.830 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:10:07.858Z,1681233007.858 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:10:08.247Z,1681233008.247 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:10:08.247Z,1681233008.247 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-04-11T17:13:06.851Z,1681233186.851 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-11T17:13:06.851Z,1681233186.851 [Default:CheckIn:C.Wait] Stopped
2023-04-11T17:13:06.852Z,1681233186.852 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-11T17:13:06.852Z,1681233186.852 [Default:CheckIn:D] Running Loop=1
2023-04-11T17:13:07.257Z,1681233187.257 [Default:CheckIn:D] Stopped
2023-04-11T17:13:07.257Z,1681233187.257 [Default:CheckIn:E] Running Loop=1
2023-04-11T17:13:07.683Z,1681233187.683 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.638454 min 
2023-04-11T17:13:07.684Z,1681233187.684 [Default:CheckIn:E] Stopped
2023-04-11T17:13:07.684Z,1681233187.684 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-11T17:13:07.684Z,1681233187.684 [Default:CheckIn] Stopped
2023-04-11T17:13:07.684Z,1681233187.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-11T17:13:07.684Z,1681233187.684 [Default:CheckIn](INFO): Running loop #4
2023-04-11T17:13:07.684Z,1681233187.684 [Default:CheckIn] Running Loop=4
2023-04-11T17:13:07.685Z,1681233187.685 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-11T17:13:07.685Z,1681233187.685 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-11T17:13:08.874Z,1681233188.874 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-04-11T17:13:08.875Z,1681233188.875 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:13:08.890Z,1681233188.890 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:13:09.295Z,1681233189.295 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:13:09.295Z,1681233189.295 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-04-11T17:16:09.913Z,1681233369.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-04-11T17:16:09.913Z,1681233369.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:16:09.929Z,1681233369.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:16:10.312Z,1681233370.312 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:16:10.312Z,1681233370.312 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-04-11T17:16:14.745Z,1681233374.745 [DVL_micro](ERROR): Failed to parse:00000000000,35.0,+23.9,0000.0,1489.0,000
2023-04-11T17:18:07.897Z,1681233487.897 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-11T17:13:07.7Z
2023-04-11T17:18:07.897Z,1681233487.897 [Default:CheckIn:Read_GPS] Stopped
2023-04-11T17:18:07.897Z,1681233487.897 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-11T17:18:14.188Z,1681233494.188 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230411T163646/Courier0013.lzma
2023-04-11T17:18:15.190Z,1681233495.190 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Courier0013.lzma.bak
2023-04-11T17:18:15.190Z,1681233495.190 [DataOverHttps](INFO): SBD MOMSN=18203106
2023-04-11T17:18:33.524Z,1681233513.524 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230411T163646/Express0014.lzma
2023-04-11T17:18:34.526Z,1681233514.526 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0014.lzma.bak
2023-04-11T17:18:34.526Z,1681233514.526 [DataOverHttps](INFO): SBD MOMSN=18203108
2023-04-11T17:18:35.833Z,1681233515.833 [Default:CheckIn:Read_Iridium] Stopped
2023-04-11T17:18:35.833Z,1681233515.833 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-11T17:18:35.833Z,1681233515.833 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-11T17:19:10.949Z,1681233550.949 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-11T17:19:10.949Z,1681233550.949 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+0.0,1489.0,000
2023-04-11T17:19:10.962Z,1681233550.962 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2023-04-11T17:19:10.962Z,1681233550.962 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:19:11.007Z,1681233551.007 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:19:11.370Z,1681233551.370 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:19:11.371Z,1681233551.371 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2023-04-11T17:19:51.794Z,1681233591.794 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-11T17:19:51.795Z,1681233591.795 [NAL9602] Data Fault, FailCount= 4
2023-04-11T17:19:51.795Z,1681233591.795 [NAL9602](ERROR): Data Fault
2023-04-11T17:19:51.891Z,1681233591.891 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-11T17:19:52.218Z,1681233592.218 [NAL9602](INFO): Powering down
2023-04-11T17:19:53.084Z,1681233593.084 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-11T17:19:53.084Z,1681233593.084 [NAL9602] No Fault, FailCount= 4
2023-04-11T17:20:22.506Z,1681233622.506 [NAL9602](INFO): Powering up NAL9602
2023-04-11T17:20:33.430Z,1681233633.430 [NAL9602](INFO): NAL9602 initialized
2023-04-11T17:22:12.067Z,1681233732.067 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2023-04-11T17:22:12.067Z,1681233732.067 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:22:12.085Z,1681233732.085 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:22:12.467Z,1681233732.467 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:22:12.467Z,1681233732.467 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2023-04-11T17:22:51.610Z,1681233771.610 [DataOverHttps](IMPORTANT): SBD MTMSN=20230411T172250
2023-04-11T17:22:58.909Z,1681233778.909 [DataOverHttps](INFO): Received command: configSet Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_salinity_homogeneity_index 0.05 practical_salinity_unit persist
2023-04-11T17:23:01.492Z,1681233781.492 [CommandExec](IMPORTANT): got command configSet Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_salinity_homogeneity_index 0.05 practical_salinity_unit persist
2023-04-11T17:23:36.467Z,1681233816.467 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-11T17:23:36.467Z,1681233816.467 [Default:CheckIn:C.Wait] Stopped
2023-04-11T17:23:36.467Z,1681233816.467 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-11T17:23:36.467Z,1681233816.467 [Default:CheckIn:D] Running Loop=1
2023-04-11T17:23:36.874Z,1681233816.874 [Default:CheckIn:D] Stopped
2023-04-11T17:23:36.875Z,1681233816.875 [Default:CheckIn:E] Running Loop=1
2023-04-11T17:23:37.279Z,1681233817.279 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.132072 min 
2023-04-11T17:23:37.279Z,1681233817.279 [Default:CheckIn:E] Stopped
2023-04-11T17:23:37.279Z,1681233817.279 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-11T17:23:37.279Z,1681233817.279 [Default:CheckIn] Stopped
2023-04-11T17:23:37.279Z,1681233817.279 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-11T17:23:37.280Z,1681233817.280 [Default:CheckIn](INFO): Running loop #5
2023-04-11T17:23:37.280Z,1681233817.280 [Default:CheckIn] Running Loop=5
2023-04-11T17:23:37.280Z,1681233817.280 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-11T17:23:37.280Z,1681233817.280 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-11T17:24:20.084Z,1681233860.084 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-11T17:24:20.085Z,1681233860.085 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.999
2023-04-11T17:24:27.758Z,1681233867.758 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-11T17:24:27.758Z,1681233867.758 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+23.9,0000.0,148

2023-04-11T17:25:13.042Z,1681233913.042 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2023-04-11T17:25:13.043Z,1681233913.043 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:25:13.065Z,1681233913.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:25:13.454Z,1681233913.454 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:25:13.454Z,1681233913.454 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2023-04-11T17:25:56.065Z,1681233956.065 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute
2023-04-11T17:25:56.073Z,1681233956.073 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2023-04-11T17:25:56.264Z,1681233956.264 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-11T17:26:01.553Z,1681233961.553 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:26:01.940Z,1681233961.940 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:26:02.364Z,1681233962.364 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:26:02.768Z,1681233962.768 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:26:03.223Z,1681233963.223 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:26:03.548Z,1681233963.548 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:26:03.921Z,1681233963.921 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-11T17:26:03.921Z,1681233963.921 [ThrusterHE](INFO): Powering down
2023-04-11T17:26:04.738Z,1681233964.738 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-04-11T17:26:04.857Z,1681233964.857 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-04-11T17:26:10.420Z,1681233970.420 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:26:10.803Z,1681233970.803 [ThrusterHE](FAULT): Lock Detect. Motor stopped spinning or could not start spinning.
2023-04-11T17:26:10.803Z,1681233970.803 [ThrusterHE] Hardware Fault, FailCount= 1
2023-04-11T17:26:10.803Z,1681233970.803 [ThrusterHE](ERROR): Hardware Fault
2023-04-11T17:26:10.840Z,1681233970.840 [CBIT](ERROR): Hardware Fault in component: ThrusterHE
2023-04-11T17:26:11.216Z,1681233971.216 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-11T17:26:11.216Z,1681233971.216 [ThrusterHE](INFO): Powering down
2023-04-11T17:26:12.014Z,1681233972.014 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-04-11T17:26:12.133Z,1681233972.133 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-04-11T17:26:12.137Z,1681233972.137 [CBIT](INFO): Clearing failed state for component ThrusterHE
2023-04-11T17:26:12.138Z,1681233972.138 [ThrusterHE] No Fault, FailCount= 1
2023-04-11T17:26:14.850Z,1681233974.850 [CommandExec](IMPORTANT): got command maintain clear
2023-04-11T17:26:14.886Z,1681233974.886 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-11T17:26:17.672Z,1681233977.672 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-04-11T17:28:14.126Z,1681234094.126 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2023-04-11T17:28:14.126Z,1681234094.126 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:28:14.145Z,1681234094.145 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:28:14.491Z,1681234094.491 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:28:14.491Z,1681234094.491 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2023-04-11T17:28:37.504Z,1681234117.504 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-11T17:23:37.3Z
2023-04-11T17:28:37.504Z,1681234117.504 [Default:CheckIn:Read_GPS] Stopped
2023-04-11T17:28:37.504Z,1681234117.504 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-11T17:28:38.830Z,1681234118.830 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute
2023-04-11T17:28:38.831Z,1681234118.831 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2023-04-11T17:28:39.177Z,1681234119.177 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-11T17:28:44.428Z,1681234124.428 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:44.840Z,1681234124.840 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230411T163646/Courier0016.lzma
2023-04-11T17:28:44.844Z,1681234124.844 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:45.240Z,1681234125.240 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:45.659Z,1681234125.659 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:45.842Z,1681234125.842 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Courier0016.lzma.bak
2023-04-11T17:28:45.842Z,1681234125.842 [DataOverHttps](INFO): SBD MOMSN=18203122
2023-04-11T17:28:47.684Z,1681234127.684 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:48.073Z,1681234128.073 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-11T17:28:48.073Z,1681234128.073 [ThrusterHE](INFO): Powering down
2023-04-11T17:28:48.478Z,1681234128.478 [ThrusterHE](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Current Limiter Activated.
2023-04-11T17:28:48.478Z,1681234128.478 [ThrusterHE] Hardware Fault, FailCount= 2
2023-04-11T17:28:48.478Z,1681234128.478 [ThrusterHE](ERROR): Hardware Fault
2023-04-11T17:28:48.482Z,1681234128.482 [CBIT](ERROR): Hardware Fault in component: ThrusterHE
2023-04-11T17:28:48.894Z,1681234128.894 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-04-11T17:28:49.015Z,1681234129.015 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-04-11T17:28:49.019Z,1681234129.019 [CBIT](INFO): Clearing failed state for component ThrusterHE
2023-04-11T17:28:49.019Z,1681234129.019 [ThrusterHE] No Fault, FailCount= 2
2023-04-11T17:28:56.968Z,1681234136.968 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:57.396Z,1681234137.396 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:57.800Z,1681234137.800 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:58.213Z,1681234138.213 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:58.624Z,1681234138.624 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:59.016Z,1681234139.016 [ThrusterHE](IMPORTANT): Status lock Detected.
2023-04-11T17:28:59.394Z,1681234139.394 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-11T17:28:59.394Z,1681234139.394 [ThrusterHE](INFO): Powering down
2023-04-11T17:29:00.214Z,1681234140.214 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-04-11T17:29:00.333Z,1681234140.333 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-04-11T17:29:01.430Z,1681234141.430 [CommandExec](IMPORTANT): got command maintain clear
2023-04-11T17:29:01.789Z,1681234141.789 [DataOverHttps](INFO): Sending 509 bytes from file Logs/20230411T163646/Express0017.lzma
2023-04-11T17:29:01.847Z,1681234141.847 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-11T17:29:02.791Z,1681234142.791 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0017.lzma.bak
2023-04-11T17:29:02.791Z,1681234142.791 [DataOverHttps](INFO): SBD MOMSN=18203124
2023-04-11T17:29:15.781Z,1681234155.781 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 600 revolution_per_minute
2023-04-11T17:29:15.782Z,1681234155.782 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2023-04-11T17:29:15.961Z,1681234155.961 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-11T17:29:19.248Z,1681234159.248 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230411T163646/Express0020.lzma
2023-04-11T17:29:20.250Z,1681234160.250 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0020.lzma.bak
2023-04-11T17:29:20.250Z,1681234160.250 [DataOverHttps](INFO): SBD MOMSN=18203140
2023-04-11T17:29:36.116Z,1681234176.116 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20230411T163646/Express0021.lzma
2023-04-11T17:29:36.294Z,1681234176.294 [CommandExec](IMPORTANT): got command maintain clear
2023-04-11T17:29:36.609Z,1681234176.609 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-11T17:29:37.118Z,1681234177.118 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0021.lzma.bak
2023-04-11T17:29:37.118Z,1681234177.118 [DataOverHttps](INFO): SBD MOMSN=18203142
2023-04-11T17:29:38.151Z,1681234178.151 [Default:CheckIn:Read_Iridium] Stopped
2023-04-11T17:29:38.151Z,1681234178.151 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-11T17:29:38.151Z,1681234178.151 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-11T17:30:35.114Z,1681234235.114 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-11T17:30:35.114Z,1681234235.114 [NAL9602] Data Fault, FailCount= 5
2023-04-11T17:30:35.115Z,1681234235.115 [NAL9602](ERROR): Data Fault
2023-04-11T17:30:35.149Z,1681234235.149 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-11T17:30:35.149Z,1681234235.149 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-04-11T17:30:35.518Z,1681234235.518 [NAL9602](INFO): Powering down
2023-04-11T17:30:35.555Z,1681234235.555 [CommandExec](FAULT): Scheduling is paused
2023-04-11T17:30:35.555Z,1681234235.555 [CBIT](INFO): Critical error at 20230411T173035
2023-04-11T17:30:35.555Z,1681234235.555 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-04-11T17:31:15.172Z,1681234275.172 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2023-04-11T17:31:15.172Z,1681234275.172 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:31:15.227Z,1681234275.227 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:31:15.536Z,1681234275.536 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:31:15.536Z,1681234275.536 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2023-04-11T17:32:35.122Z,1681234355.122 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-11T17:32:35.122Z,1681234355.122 [NAL9602] No Fault, FailCount= 5
2023-04-11T17:32:35.504Z,1681234355.504 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-11T17:32:35.504Z,1681234355.504 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+23.8,089.0,000
2023-04-11T17:32:35.505Z,1681234355.505 [NAL9602](INFO): Powering up NAL9602
2023-04-11T17:32:43.187Z,1681234363.187 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-11T17:32:43.187Z,1681234363.187 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+23.9,0000.0,14
2023-04-11T17:32:46.414Z,1681234366.414 [NAL9602](INFO): NAL9602 initialized
2023-04-11T17:34:16.163Z,1681234456.163 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2023-04-11T17:34:16.163Z,1681234456.163 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:34:16.193Z,1681234456.193 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:34:16.569Z,1681234456.569 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:34:16.569Z,1681234456.569 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2023-04-11T17:34:38.789Z,1681234478.789 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-11T17:34:38.789Z,1681234478.789 [Default:CheckIn:C.Wait] Stopped
2023-04-11T17:34:38.789Z,1681234478.789 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-11T17:34:38.790Z,1681234478.790 [Default:CheckIn:D] Running Loop=1
2023-04-11T17:34:39.207Z,1681234479.207 [Default:CheckIn:D] Stopped
2023-04-11T17:34:39.207Z,1681234479.207 [Default:CheckIn:E] Running Loop=1
2023-04-11T17:34:39.617Z,1681234479.617 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.170939 min 
2023-04-11T17:34:39.617Z,1681234479.617 [Default:CheckIn:E] Stopped
2023-04-11T17:34:39.618Z,1681234479.618 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-11T17:34:39.618Z,1681234479.618 [Default:CheckIn] Stopped
2023-04-11T17:34:39.618Z,1681234479.618 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-11T17:34:39.618Z,1681234479.618 [Default:CheckIn](INFO): Running loop #6
2023-04-11T17:34:39.618Z,1681234479.618 [Default:CheckIn] Running Loop=6
2023-04-11T17:34:39.618Z,1681234479.618 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-11T17:34:39.618Z,1681234479.618 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-11T17:35:05.334Z,1681234505.334 [DataOverHttps](IMPORTANT): SBD MTMSN=20230411T173504
2023-04-11T17:35:12.648Z,1681234512.648 [DataOverHttps](INFO): Received command: sched asap "restart system"
2023-04-11T17:35:12.734Z,1681234512.734 [CommandExec](IMPORTANT): got command schedule asap "restart system"
2023-04-11T17:35:12.734Z,1681234512.734 [CommandExec](FAULT): Incomplete syntax. Try: help schedule
2023-04-11T17:36:41.718Z,1681234601.718 [CommandExec](IMPORTANT): got command failComponent
2023-04-11T17:36:41.718Z,1681234601.718 [CommandExec](IMPORTANT): Failed components:
2023-04-11T17:36:41.718Z,1681234601.718 [CommandExec](IMPORTANT): No failed Components.
2023-04-11T17:37:17.263Z,1681234637.263 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2023-04-11T17:37:17.263Z,1681234637.263 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:37:17.274Z,1681234637.274 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:37:17.677Z,1681234637.677 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:37:17.677Z,1681234637.677 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2023-04-11T17:38:39.187Z,1681234719.187 [CommandExec](IMPORTANT): got command help schedule
2023-04-11T17:39:15.302Z,1681234755.302 [CommandExec](IMPORTANT): got command schedule asap "restart system"
2023-04-11T17:39:15.302Z,1681234755.302 [CommandExec](FAULT): Incomplete syntax. Try: help schedule
2023-04-11T17:39:26.442Z,1681234766.442 [CommandExec](IMPORTANT): got command schedule asap "failc"
2023-04-11T17:39:26.443Z,1681234766.443 [CommandExec](FAULT): Incomplete syntax. Try: help schedule
2023-04-11T17:39:39.892Z,1681234779.892 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-11T17:34:39.6Z
2023-04-11T17:39:39.892Z,1681234779.892 [Default:CheckIn:Read_GPS] Stopped
2023-04-11T17:39:39.892Z,1681234779.892 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-11T17:39:47.008Z,1681234787.008 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20230411T163646/Courier0022.lzma
2023-04-11T17:39:48.021Z,1681234788.021 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Courier0022.lzma.bak
2023-04-11T17:39:48.021Z,1681234788.021 [DataOverHttps](INFO): SBD MOMSN=18203147
2023-04-11T17:40:12.711Z,1681234812.711 [DataOverHttps](INFO): Sending 551 bytes from file Logs/20230411T163646/Express0023.lzma
2023-04-11T17:40:13.710Z,1681234813.710 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0023.lzma.bak
2023-04-11T17:40:13.710Z,1681234813.710 [DataOverHttps](INFO): SBD MOMSN=18203150
2023-04-11T17:40:18.366Z,1681234818.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2023-04-11T17:40:18.367Z,1681234818.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-11T17:40:18.460Z,1681234818.460 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-11T17:40:18.796Z,1681234818.796 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-11T17:40:18.796Z,1681234818.796 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2023-04-11T17:40:19.575Z,1681234819.575 [CommandExec](IMPORTANT): got command schedule asap "failc" 1 2 3.000000
2023-04-11T17:40:19.576Z,1681234819.576 [CommandExec](IMPORTANT): Scheduling command #2 of 3 with id=1
2023-04-11T17:40:19.585Z,1681234819.585 [CommandExec](IMPORTANT): Scheduled #1 (#2 of 3 with id='1'): "failc", ASAP
2023-04-11T17:40:28.454Z,1681234828.454 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-11T17:40:28.454Z,1681234828.454 [DVL_micro](ERROR): Failed to parse:
:BI,+01037,+00061,+00862I
2023-04-11T17:40:32.352Z,1681234832.352 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230411T163646/Express0026.lzma
2023-04-11T17:40:33.354Z,1681234833.354 [DataOverHttps](INFO): Moved sent file to Logs/20230411T163646/Express0026.lzma.bak
2023-04-11T17:40:33.354Z,1681234833.354 [DataOverHttps](INFO): SBD MOMSN=18203181
2023-04-11T17:40:34.514Z,1681234834.514 [Default:CheckIn:Read_Iridium] Stopped
2023-04-11T17:40:34.514Z,1681234834.514 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-11T17:40:34.514Z,1681234834.514 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-11T17:40:36.123Z,1681234836.123 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 2936
2023-04-11T17:40:36.136Z,1681234836.136 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999..99
2023-04-11T17:41:16.934Z,1681234876.934 [DataOverHttps](IMPORTANT): SBD MTMSN=20230411T174115
2023-04-11T17:41:24.204Z,1681234884.204 [DataOverHttps](INFO): Received command: restart system
2023-04-11T17:41:24.230Z,1681234884.230 [CommandExec](IMPORTANT): got command restart system
2023-04-11T17:41:26.285Z,1681234886.285 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:41:26.285Z,1681234886.285 [CommandExec](INFO): Uninitializing the command executive.
2023-04-11T17:41:26.285Z,1681234886.285 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-11T17:41:26.285Z,1681234886.285 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:26.345Z,1681234886.345 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-04-11T17:41:26.345Z,1681234886.345 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-11T17:41:26.345Z,1681234886.345 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:26.346Z,1681234886.346 [NavChartDb](INFO): Join timeout helper Thread ID is 1352
2023-04-11T17:41:26.393Z,1681234886.393 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:41:26.394Z,1681234886.394 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:26.405Z,1681234886.405 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-11T17:41:26.405Z,1681234886.405 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:26.405Z,1681234886.405 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1353
2023-04-11T17:41:26.557Z,1681234886.557 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:41:26.557Z,1681234886.557 [WetLabsBB2FL](INFO): Powering down
2023-04-11T17:41:26.558Z,1681234886.558 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:26.565Z,1681234886.565 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-11T17:41:26.565Z,1681234886.565 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:26.565Z,1681234886.565 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1354
2023-04-11T17:41:26.949Z,1681234886.949 [CTD_Seabird](INFO): Powering down
2023-04-11T17:41:26.961Z,1681234886.961 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:41:26.961Z,1681234886.961 [CTD_Seabird](INFO): Powering down
2023-04-11T17:41:26.972Z,1681234886.972 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:26.980Z,1681234886.980 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-04-11T17:41:26.980Z,1681234886.980 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:26.981Z,1681234886.981 [DAT](INFO): Join timeout helper Thread ID is 1355
2023-04-11T17:41:27.065Z,1681234887.065 [DAT](INFO): Powering down
2023-04-11T17:41:27.145Z,1681234887.145 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:41:27.145Z,1681234887.145 [DAT](INFO): Powering down
2023-04-11T17:41:27.146Z,1681234887.146 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:27.167Z,1681234887.167 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-11T17:41:27.167Z,1681234887.167 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:27.168Z,1681234887.168 [Radio_Surface](INFO): Join timeout helper Thread ID is 1356
2023-04-11T17:41:27.201Z,1681234887.201 [Radio_Surface](INFO): Powering down
2023-04-11T17:41:27.202Z,1681234887.202 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:41:27.202Z,1681234887.202 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:27.216Z,1681234887.216 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-04-11T17:41:27.221Z,1681234887.221 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-11T17:41:27.222Z,1681234887.222 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:27.222Z,1681234887.222 [Onboard](INFO): Join timeout helper Thread ID is 1357
2023-04-11T17:41:30.801Z,1681234890.801 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:41:30.801Z,1681234890.801 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:30.809Z,1681234890.809 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-11T17:41:30.809Z,1681234890.809 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-11T17:41:30.810Z,1681234890.810 [DataOverHttps](INFO): Join timeout helper Thread ID is 1358
2023-04-11T17:41:31.229Z,1681234891.229 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-11T17:41:31.229Z,1681234891.229 [DataOverHttps ThreadHa