2022-12-05T22:33:21.200Z,1670279601.200 [Supervisor](DEBUG): Initializing supervisor.
2022-12-05T22:33:21.205Z,1670279601.205 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-12-05T22:33:21.205Z,1670279601.205 [SyncHandler](INFO): Protected caller Thread ID is 4843
2022-12-05T22:33:21.206Z,1670279601.206 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-12-05T22:33:21.207Z,1670279601.207 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-12-05T22:33:21.207Z,1670279601.207 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4844
2022-12-05T22:33:21.211Z,1670279601.211 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-12-05T22:33:21.228Z,1670279601.228 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-12-05T22:33:21.229Z,1670279601.229 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-12-05T22:33:21.229Z,1670279601.229 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4845
2022-12-05T22:33:21.234Z,1670279601.234 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-12-05T22:33:21.235Z,1670279601.235 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-12-05T22:33:21.235Z,1670279601.235 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4846
2022-12-05T22:33:21.237Z,1670279601.237 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-12-05T22:33:21.238Z,1670279601.238 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-12-05T22:33:21.238Z,1670279601.238 [logger ThreadHandler](INFO): Protected caller Thread ID is 4847
2022-12-05T22:33:21.242Z,1670279601.242 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-12-05T22:33:21.242Z,1670279601.242 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-12-05T22:33:21.244Z,1670279601.244 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-12-05T22:33:21.678Z,1670279601.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-12-05T22:33:21.678Z,1670279601.678 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-12-05T22:33:21.878Z,1670279601.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-12-05T22:33:21.879Z,1670279601.879 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-12-05T22:33:21.956Z,1670279601.956 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-12-05T22:33:22.167Z,1670279602.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-12-05T22:33:22.167Z,1670279602.167 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-12-05T22:33:22.243Z,1670279602.243 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-12-05T22:33:22.335Z,1670279602.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-12-05T22:33:22.336Z,1670279602.336 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-12-05T22:33:22.675Z,1670279602.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-12-05T22:33:22.676Z,1670279602.676 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-12-05T22:33:22.802Z,1670279602.802 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-12-05T22:33:22.803Z,1670279602.803 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-12-05T22:33:23.272Z,1670279603.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-12-05T22:33:23.273Z,1670279603.273 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-12-05T22:33:23.448Z,1670279603.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-12-05T22:33:23.449Z,1670279603.449 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-12-05T22:33:23.832Z,1670279603.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-12-05T22:33:23.832Z,1670279603.832 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-12-05T22:33:24.101Z,1670279604.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-12-05T22:33:24.102Z,1670279604.102 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-12-05T22:33:24.208Z,1670279604.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-12-05T22:33:24.209Z,1670279604.209 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-12-05T22:33:24.907Z,1670279604.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-12-05T22:33:24.907Z,1670279604.907 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-12-05T22:33:25.224Z,1670279605.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-12-05T22:33:25.224Z,1670279605.224 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-12-05T22:33:25.435Z,1670279605.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-12-05T22:33:25.437Z,1670279605.437 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2022-12-05T22:33:25.438Z,1670279605.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2022-12-05T22:33:25.615Z,1670279605.615 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2022-12-05T22:33:25.695Z,1670279605.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2022-12-05T22:33:25.824Z,1670279605.824 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2022-12-05T22:33:25.906Z,1670279605.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2022-12-05T22:33:26.163Z,1670279606.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-12-05T22:33:26.163Z,1670279606.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2022-12-05T22:33:26.354Z,1670279606.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2022-12-05T22:33:26.550Z,1670279606.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2022-12-05T22:33:26.780Z,1670279606.780 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2022-12-05T22:33:26.978Z,1670279606.978 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2022-12-05T22:33:27.073Z,1670279607.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2022-12-05T22:33:27.163Z,1670279607.163 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-12-05T22:33:27.173Z,1670279607.173 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-12-05T22:33:27.184Z,1670279607.184 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-12-05T22:33:27.185Z,1670279607.185 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-12-05T22:33:27.281Z,1670279607.281 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-12-05T22:33:27.281Z,1670279607.281 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-12-05T22:33:27.330Z,1670279607.330 [VerticalControl](DEBUG): Construct VerticalControl.
2022-12-05T22:33:27.389Z,1670279607.389 [VerticalControl] Loaded
2022-12-05T22:33:27.389Z,1670279607.389 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-12-05T22:33:27.392Z,1670279607.392 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-12-05T22:33:27.432Z,1670279607.432 [HorizontalControl] Loaded
2022-12-05T22:33:27.433Z,1670279607.433 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-12-05T22:33:27.435Z,1670279607.435 [SpeedControl](DEBUG): Construct SpeedControl.
2022-12-05T22:33:27.438Z,1670279607.438 [SpeedControl] Loaded
2022-12-05T22:33:27.438Z,1670279607.438 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-12-05T22:33:27.441Z,1670279607.441 [LoopControl](DEBUG): Construct LoopControl.
2022-12-05T22:33:27.441Z,1670279607.441 [LoopControl] Loaded
2022-12-05T22:33:27.442Z,1670279607.442 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-12-05T22:33:27.442Z,1670279607.442 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-12-05T22:33:27.442Z,1670279607.442 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-12-05T22:33:27.640Z,1670279607.640 [BuoyancyServo] Loaded
2022-12-05T22:33:27.640Z,1670279607.640 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-12-05T22:33:27.661Z,1670279607.661 [ElevatorServo] Loaded
2022-12-05T22:33:27.661Z,1670279607.661 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-12-05T22:33:27.682Z,1670279607.682 [MassServo] Loaded
2022-12-05T22:33:27.682Z,1670279607.682 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-12-05T22:33:27.700Z,1670279607.700 [RudderServo] Loaded
2022-12-05T22:33:27.700Z,1670279607.700 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-12-05T22:33:27.715Z,1670279607.715 [ThrusterHE] Loaded
2022-12-05T22:33:27.715Z,1670279607.715 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-12-05T22:33:27.715Z,1670279607.715 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-12-05T22:33:27.716Z,1670279607.716 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-12-05T22:33:27.765Z,1670279607.765 [DepthRateCalculator] Loaded
2022-12-05T22:33:27.765Z,1670279607.765 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-12-05T22:33:27.770Z,1670279607.770 [PitchRateCalculator] Loaded
2022-12-05T22:33:27.770Z,1670279607.770 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-12-05T22:33:27.780Z,1670279607.780 [SpeedCalculator] Loaded
2022-12-05T22:33:27.780Z,1670279607.780 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-12-05T22:33:27.784Z,1670279607.784 [YawRateCalculator] Loaded
2022-12-05T22:33:27.784Z,1670279607.784 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-12-05T22:33:27.802Z,1670279607.802 [ElevatorOffsetCalculator] Loaded
2022-12-05T22:33:27.802Z,1670279607.802 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-12-05T22:33:27.803Z,1670279607.803 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-12-05T22:33:27.803Z,1670279607.803 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-12-05T22:33:27.914Z,1670279607.914 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-12-05T22:33:27.914Z,1670279607.914 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-12-05T22:33:27.928Z,1670279607.928 [NavChart] Loaded
2022-12-05T22:33:27.928Z,1670279607.928 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-12-05T22:33:27.933Z,1670279607.933 [UniversalFixResidualReporter] Loaded
2022-12-05T22:33:27.934Z,1670279607.934 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-12-05T22:33:27.934Z,1670279607.934 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-12-05T22:33:27.935Z,1670279607.935 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-12-05T22:33:28.323Z,1670279608.323 [AHRS_M2] Loaded
2022-12-05T22:33:28.323Z,1670279608.323 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-12-05T22:33:28.358Z,1670279608.358 [BackseatComponent] Loaded
2022-12-05T22:33:28.358Z,1670279608.358 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-12-05T22:33:28.359Z,1670279608.359 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408D14E0
2022-12-05T22:33:28.360Z,1670279608.360 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4930
2022-12-05T22:33:28.362Z,1670279608.362 [LcmUniversalReporter] Loaded
2022-12-05T22:33:28.363Z,1670279608.363 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-12-05T22:33:29.185Z,1670279609.185 [BPC1] Loaded
2022-12-05T22:33:29.186Z,1670279609.186 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-12-05T22:33:29.258Z,1670279609.258 [DataOverHttps] Loaded
2022-12-05T22:33:29.258Z,1670279609.258 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-12-05T22:33:29.259Z,1670279609.259 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409014E0
2022-12-05T22:33:29.259Z,1670279609.259 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4931
2022-12-05T22:33:29.280Z,1670279609.280 [Depth_Keller] Loaded
2022-12-05T22:33:29.280Z,1670279609.280 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-12-05T22:33:29.285Z,1670279609.285 [DropWeight] Loaded
2022-12-05T22:33:29.285Z,1670279609.285 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-12-05T22:33:29.346Z,1670279609.346 [NAL9602] Loaded
2022-12-05T22:33:29.346Z,1670279609.346 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-12-05T22:33:29.376Z,1670279609.376 [Onboard] Loaded
2022-12-05T22:33:29.376Z,1670279609.376 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-12-05T22:33:29.377Z,1670279609.377 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2022-12-05T22:33:29.377Z,1670279609.377 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4932
2022-12-05T22:33:29.389Z,1670279609.389 [Power24vConverter] Loaded
2022-12-05T22:33:29.390Z,1670279609.390 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-12-05T22:33:29.403Z,1670279609.403 [Radio_Surface] Loaded
2022-12-05T22:33:29.403Z,1670279609.403 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-12-05T22:33:29.404Z,1670279609.404 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2022-12-05T22:33:29.404Z,1670279609.404 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4933
2022-12-05T22:33:29.496Z,1670279609.496 [DAT] Loaded
2022-12-05T22:33:29.497Z,1670279609.497 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-12-05T22:33:29.498Z,1670279609.498 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2022-12-05T22:33:29.498Z,1670279609.498 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4934
2022-12-05T22:33:29.498Z,1670279609.498 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-12-05T22:33:29.499Z,1670279609.499 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-12-05T22:33:29.553Z,1670279609.553 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-12-05T22:33:29.554Z,1670279609.554 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-12-05T22:33:29.699Z,1670279609.699 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-12-05T22:33:29.700Z,1670279609.700 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-12-05T22:33:29.726Z,1670279609.726 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-12-05T22:33:29.727Z,1670279609.727 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-12-05T22:33:29.893Z,1670279609.893 [CTD_Seabird] Loaded
2022-12-05T22:33:29.893Z,1670279609.893 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-12-05T22:33:29.894Z,1670279609.894 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABC4E0
2022-12-05T22:33:29.894Z,1670279609.894 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4935
2022-12-05T22:33:29.914Z,1670279609.914 [PAR_Licor] Loaded
2022-12-05T22:33:29.914Z,1670279609.914 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-12-05T22:33:29.945Z,1670279609.945 [WetLabsBB2FL] Loaded
2022-12-05T22:33:29.945Z,1670279609.945 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-12-05T22:33:29.946Z,1670279609.946 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEC4E0
2022-12-05T22:33:29.947Z,1670279609.947 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4936
2022-12-05T22:33:29.947Z,1670279609.947 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-12-05T22:33:29.948Z,1670279609.948 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-12-05T22:33:30.246Z,1670279610.246 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-12-05T22:33:30.247Z,1670279610.247 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-12-05T22:33:30.386Z,1670279610.386 [SBIT](DEBUG): Construct Startup Built In Test.
2022-12-05T22:33:30.394Z,1670279610.394 [SBIT] Loaded
2022-12-05T22:33:30.395Z,1670279610.395 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-12-05T22:33:30.397Z,1670279610.397 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-12-05T22:33:30.411Z,1670279610.411 [IBIT] Loaded
2022-12-05T22:33:30.411Z,1670279610.411 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-12-05T22:33:30.417Z,1670279610.417 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-12-05T22:33:30.518Z,1670279610.519 [CBIT] Loaded
2022-12-05T22:33:30.519Z,1670279610.519 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-12-05T22:33:30.519Z,1670279610.519 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-12-05T22:33:30.525Z,1670279610.525 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-12-05T22:33:30.528Z,1670279610.528 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-12-05T22:33:30.539Z,1670279610.539 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-12-05T22:33:30.540Z,1670279610.540 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCF4E0
2022-12-05T22:33:30.540Z,1670279610.540 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4937
2022-12-05T22:33:30.545Z,1670279610.545 [Supervisor](INFO): Main Thread ID is 4842
2022-12-05T22:33:30.545Z,1670279610.545 [Supervisor](DEBUG): Running supervisor.
2022-12-05T22:33:30.545Z,1670279610.545 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4938
2022-12-05T22:33:30.546Z,1670279610.546 [CommandExec](INFO): Initializing the command executive.
2022-12-05T22:33:30.547Z,1670279610.547 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4939
2022-12-05T22:33:30.550Z,1670279610.550 [controlThread ThreadHandler](INFO): Handler Thread ID is 4940
2022-12-05T22:33:30.550Z,1670279610.550 [controlThread](DEBUG): Initializing ControlThread
2022-12-05T22:33:30.551Z,1670279610.551 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-12-05T22:33:30.553Z,1670279610.553 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-12-05T22:33:30.553Z,1670279610.553 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-12-05T22:33:30.554Z,1670279610.554 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-12-05T22:33:30.555Z,1670279610.555 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-12-05T22:33:30.555Z,1670279610.555 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-12-05T22:33:30.556Z,1670279610.556 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-12-05T22:33:30.556Z,1670279610.556 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-12-05T22:33:30.556Z,1670279610.556 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-12-05T22:33:30.557Z,1670279610.557 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-12-05T22:33:30.558Z,1670279610.558 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-12-05T22:33:30.563Z,1670279610.563 [SBIT](INFO): Initialize SBIT Component.
2022-12-05T22:33:30.564Z,1670279610.564 [SBIT](IMPORTANT): git: 2022-11-28
2022-12-05T22:33:30.564Z,1670279610.564 [SBIT](INFO): git hash: ae900ceef0992f02fc7e77b26b775dfa5c801999
2022-12-05T22:33:30.564Z,1670279610.564 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-12-05T22:33:30.565Z,1670279610.565 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-12-05T22:33:30.567Z,1670279610.567 [SBIT](INFO): Beginning SBIT in 61.000000 seconds.
2022-12-05T22:33:30.567Z,1670279610.567 [IBIT](INFO): Initialize IBIT Component.
2022-12-05T22:33:30.568Z,1670279610.568 [CBIT](DEBUG): Initialize CBIT Component.
2022-12-05T22:33:30.569Z,1670279610.569 [logger ThreadHandler](INFO): Handler Thread ID is 4941
2022-12-05T22:33:30.579Z,1670279610.579 [CBIT](DEBUG): Initialized mux pins.
2022-12-05T22:33:30.579Z,1670279610.579 [CBIT](DEBUG): Initializing the watchdog timer.
2022-12-05T22:33:30.588Z,1670279610.588 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4942
2022-12-05T22:33:30.599Z,1670279610.599 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4943
2022-12-05T22:33:30.600Z,1670279610.600 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-12-05T22:33:30.603Z,1670279610.603 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-12-05T22:33:30.604Z,1670279610.604 [CBIT](DEBUG): Initializing heartbeat.
2022-12-05T22:33:30.612Z,1670279610.612 [Onboard ThreadHandler](INFO): Handler Thread ID is 4944
2022-12-05T22:33:30.629Z,1670279610.629 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4945
2022-12-05T22:33:30.652Z,1670279610.652 [DAT ThreadHandler](INFO): Handler Thread ID is 4946
2022-12-05T22:33:30.652Z,1670279610.652 [DAT](INFO): Powering up
2022-12-05T22:33:30.652Z,1670279610.652 [DAT](DEBUG): Initializing DAT.
2022-12-05T22:33:30.656Z,1670279610.656 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4948
2022-12-05T22:33:30.657Z,1670279610.657 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-12-05T22:33:30.660Z,1670279610.660 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4950
2022-12-05T22:33:30.664Z,1670279610.664 [WetLabsBB2FL](INFO): Powering up
2022-12-05T22:33:30.665Z,1670279610.665 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4952
2022-12-05T22:33:30.667Z,1670279610.667 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-12-05T22:33:30.668Z,1670279610.668 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-12-05T22:33:30.668Z,1670279610.668 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-12-05T22:33:30.668Z,1670279610.668 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-12-05T22:33:30.668Z,1670279610.668 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-12-05T22:33:30.668Z,1670279610.668 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-12-05T22:33:30.669Z,1670279610.669 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-12-05T22:33:30.669Z,1670279610.669 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-12-05T22:33:30.675Z,1670279610.675 [CBIT](DEBUG): Deactivating GF circuits.
2022-12-05T22:33:30.675Z,1670279610.675 [CBIT](DEBUG): Deactivating emergency mode.
2022-12-05T22:33:30.711Z,1670279610.711 [CBIT](DEBUG): Backplane powered.
2022-12-05T22:33:30.712Z,1670279610.712 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-12-05T22:33:30.725Z,1670279610.725 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-12-05T22:33:30.738Z,1670279610.738 [MissionManager](DEBUG):
2022-12-05T22:33:30.739Z,1670279610.739 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-12-05T22:33:30.841Z,1670279610.841 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-12-05T22:33:30.842Z,1670279610.842 [Default:A.Wait](DEBUG): Construct Wait.
2022-12-05T22:33:30.845Z,1670279610.845 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-12-05T22:33:30.876Z,1670279610.876 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-12-05T22:33:30.878Z,1670279610.878 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-12-05T22:33:30.917Z,1670279610.917 [Default:E.Execute](DEBUG): Construct Execute.
2022-12-05T22:33:30.927Z,1670279610.927 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2022-12-05T22:33:30.943Z,1670279610.943 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T22:33:30.957Z,1670279610.957 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-12-05T22:33:31.026Z,1670279611.026 [Power24vConverter](INFO): Powering up.
2022-12-05T22:33:31.035Z,1670279611.035 [Radio_Surface](INFO): Powering up
2022-12-05T22:33:31.077Z,1670279611.077 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-12-05T22:33:31.087Z,1670279611.087 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-12-05T22:33:31.088Z,1670279611.088 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-12-05T22:33:31.095Z,1670279611.095 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-12-05T22:33:31.096Z,1670279611.096 [MassServo](DEBUG): Initializing EZServoServo.
2022-12-05T22:33:31.103Z,1670279611.103 [MassServo](DEBUG): Initializing MassServo.
2022-12-05T22:33:31.104Z,1670279611.104 [RudderServo](DEBUG): Initializing EZServoServo.
2022-12-05T22:33:31.111Z,1670279611.111 [RudderServo](DEBUG): Initializing RudderServo.
2022-12-05T22:33:31.112Z,1670279611.112 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-12-05T22:33:31.119Z,1670279611.119 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-12-05T22:33:32.771Z,1670279612.771 [WetLabsBB2FL](INFO): Powering down
2022-12-05T22:33:36.824Z,1670279616.824 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-12-05T22:33:42.748Z,1670279622.748 [DAT](INFO): DAT read:
2022-12-05T22:33:42.749Z,1670279622.749 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2022-12-05T22:33:44.382Z,1670279624.382 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005251
2022-12-05T22:33:44.512Z,1670279624.512 [DAT](INFO): DAT read: MF Frequency Band
2022-12-05T22:33:44.513Z,1670279624.513 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2022-12-05T22:33:44.514Z,1670279624.514 [DAT](INFO): DAT read: Dec 5 2022 22:33:38
2022-12-05T22:33:45.520Z,1670279625.520 [DAT](INFO): DAT read: Features enabled [Bearing]
2022-12-05T22:33:45.521Z,1670279625.521 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2022-12-05T22:33:45.522Z,1670279625.522 [DAT](INFO): commRate: 800
2022-12-05T22:33:47.587Z,1670279627.587 [DAT](INFO): entering command mode
2022-12-05T22:33:47.788Z,1670279627.788 [DAT](INFO): DAT read:
2022-12-05T22:33:47.788Z,1670279627.788 [DAT](INFO): DAT read: user:1>
2022-12-05T22:33:47.789Z,1670279627.789 [DAT](INFO): setting verbose to 3
2022-12-05T22:33:48.040Z,1670279628.040 [DAT](INFO): DAT read: user:1>
2022-12-05T22:33:48.041Z,1670279628.041 [DAT](INFO): DAT read: Verbose | 3
2022-12-05T22:33:48.041Z,1670279628.041 [DAT](INFO): set verbose to 3
2022-12-05T22:33:48.042Z,1670279628.042 [DAT](INFO): setting DatVerbose to 27440
2022-12-05T22:33:48.292Z,1670279628.292 [DAT](INFO): DAT read: user:2>
2022-12-05T22:33:48.293Z,1670279628.293 [DAT](INFO): DAT read: DatVerbose | 27440
2022-12-05T22:33:48.293Z,1670279628.293 [DAT](INFO): set DatVerbose to 27440
2022-12-05T22:33:48.293Z,1670279628.293 [DAT](INFO): setting transmit power to 8
2022-12-05T22:33:48.544Z,1670279628.544 [DAT](INFO): DAT read: user:3>
2022-12-05T22:33:48.545Z,1670279628.545 [DAT](INFO): DAT read: TxPower | 8 (Max)
2022-12-05T22:33:48.545Z,1670279628.545 [DAT](INFO): set transmit power to 8
2022-12-05T22:33:48.545Z,1670279628.545 [DAT](INFO): setting local address to 5
2022-12-05T22:33:48.796Z,1670279628.796 [DAT](INFO): DAT read: user:4>
2022-12-05T22:33:48.797Z,1670279628.797 [DAT](INFO): DAT read: LocalAddr | 5
2022-12-05T22:33:48.797Z,1670279628.797 [DAT](INFO): set local address to 5
2022-12-05T22:33:48.798Z,1670279628.798 [DAT](INFO): Setting time to: 22:33:48 And date to:12/5/2022
2022-12-05T22:33:49.048Z,1670279629.048 [DAT](INFO): DAT read: user:5>
2022-12-05T22:33:49.049Z,1670279629.049 [DAT](INFO): DAT read: Mon Dec 5, 2022 22:33:48
2022-12-05T22:33:49.049Z,1670279629.049 [DAT](INFO): Local DAT time set to Mon Dec 5, 2022 22:33:48
2022-12-05T22:33:59.641Z,1670279639.641 [NAL9602](INFO): Powering up NAL9602
2022-12-05T22:34:10.549Z,1670279650.549 [NAL9602](INFO): NAL9602 initialized
2022-12-05T22:34:32.002Z,1670279672.002 [SBIT](IMPORTANT): Beginning Startup BIT
2022-12-05T22:34:32.006Z,1670279672.006 [CBIT](IMPORTANT): Beginning ground fault scan
2022-12-05T22:34:42.910Z,1670279682.910 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.013807
CHAN A1 (24V): -0.007875
CHAN A2 (12V): -0.004951
CHAN A3 (5V): -0.002551
CHAN B0 (3.3V): -0.001054
CHAN B1 (3.15aV): -0.000802
CHAN B2 (3.15bV): -0.001484
CHAN B3 (GND): 0.000180
OPEN: 0.004550
Full Scale: +/- 1 mA
2022-12-05T22:35:26.120Z,1670279726.120 [SBIT](IMPORTANT): SBIT PASSED
2022-12-05T22:35:26.120Z,1670279726.120 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-12-05T22:35:26.121Z,1670279726.121 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2022-12-05T22:35:26.121Z,1670279726.121 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;
2022-12-05T22:35:26.121Z,1670279726.121 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;
2022-12-05T22:35:26.121Z,1670279726.121 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;
2022-12-05T22:35:26.121Z,1670279726.121 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2022-12-05T22:35:26.122Z,1670279726.122 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;
2022-12-05T22:35:26.122Z,1670279726.122 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;
2022-12-05T22:35:26.122Z,1670279726.122 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=30 ampere_hour;
2022-12-05T22:35:26.122Z,1670279726.122 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2022-12-05T22:35:26.122Z,1670279726.122 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=324.282117 cubic_centimeter;
2022-12-05T22:35:26.122Z,1670279726.122 [SBIT](IMPORTANT): VerticalControl.massDefault=10.355450 millimeter;
2022-12-05T22:35:26.540Z,1670279726.540 [MissionManager](IMPORTANT): Started mission Startup
2022-12-05T22:35:26.540Z,1670279726.540 [Startup] Running Loop=1
2022-12-05T22:35:26.540Z,1670279726.540 [Startup](DEBUG): Aggregate::initialize Startup
2022-12-05T22:35:26.541Z,1670279726.541 [Startup:A.GoToSurface] Running Loop=1
2022-12-05T22:35:26.541Z,1670279726.541 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-12-05T22:35:26.541Z,1670279726.541 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-12-05T22:35:26.542Z,1670279726.542 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-12-05T22:35:26.542Z,1670279726.542 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-12-05T22:35:26.542Z,1670279726.542 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-12-05T22:35:26.543Z,1670279726.543 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-12-05T22:35:26.544Z,1670279726.544 [Startup:StartupSatComms] Running Loop=1
2022-12-05T22:35:26.544Z,1670279726.544 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-12-05T22:35:26.544Z,1670279726.544 [Startup:StartupSatComms:A] Running Loop=1
2022-12-05T22:35:26.934Z,1670279726.934 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-12-05T22:35:38.924Z,1670279738.924 [CommandExec](IMPORTANT): got command failComponent
2022-12-05T22:35:38.924Z,1670279738.924 [CommandExec](IMPORTANT): Failed components:
2022-12-05T22:35:38.924Z,1670279738.924 [CommandExec](IMPORTANT): No failed Components.
2022-12-05T22:35:49.852Z,1670279749.852 [CommandExec](IMPORTANT): got command
2022-12-05T22:35:49.852Z,1670279749.852 [CommandExec](FAULT): Incomplete syntax. Try: help
2022-12-05T22:36:09.839Z,1670279769.839 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2022-12-05T22:36:09.840Z,1670279769.840 [CommandExec](IMPORTANT): Onboard.Pressure 62821.242188 Pa
2022-12-05T22:36:14.759Z,1670279774.759 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2022-12-05T22:36:14.759Z,1670279774.759 [CommandExec](IMPORTANT): Onboard.Pressure 9.083212 psi
2022-12-05T22:36:21.607Z,1670279781.607 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2022-12-05T22:36:21.607Z,1670279781.607 [CommandExec](IMPORTANT): Onboard.Humidity 7.923330 %
2022-12-05T22:36:26.713Z,1670279786.713 [Startup:StartupSatComms:A](INFO): Timed out from 2022-12-05T22:35:26.5Z
2022-12-05T22:36:26.713Z,1670279786.713 [Startup:StartupSatComms:A] Stopped
2022-12-05T22:36:26.713Z,1670279786.713 [Startup:StartupSatComms:B] Running Loop=1
2022-12-05T22:36:27.120Z,1670279787.120 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-12-05T22:36:30.797Z,1670279790.797 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-12-05T22:36:30.798Z,1670279790.798 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-05T22:36:30.832Z,1670279790.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-05T22:36:31.198Z,1670279791.198 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-05T22:36:31.198Z,1670279791.198 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-12-05T22:36:34.305Z,1670279794.305 [CommandExec](IMPORTANT): got command show variable par
2022-12-05T22:36:34.384Z,1670279794.384 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20221202T213219/Courier0034.lzma
2022-12-05T22:36:34.394Z,1670279794.394 [CommandExec](IMPORTANT): PAR_Licor.loadControl (none)
2022-12-05T22:36:34.394Z,1670279794.394 [CommandExec](IMPORTANT): PAR_Licor.ad (none)
2022-12-05T22:36:34.395Z,1670279794.395 [CommandExec](IMPORTANT): PAR_Licor.adRes (bit)
2022-12-05T22:36:34.395Z,1670279794.395 [CommandExec](IMPORTANT): PAR_Licor.adTimeout (millisecond)
2022-12-05T22:36:34.396Z,1670279794.396 [CommandExec](IMPORTANT): PAR_Licor.adVref (volt)
2022-12-05T22:36:34.449Z,1670279794.449 [CommandExec](IMPORTANT): PAR_Licor.loadAtStartup (bool)
2022-12-05T22:36:34.449Z,1670279794.449 [CommandExec](IMPORTANT): PAR_Licor.simulateHardware (bool)
2022-12-05T22:36:34.450Z,1670279794.450 [CommandExec](IMPORTANT): PAR_Licor.adcCal (microampere_per_count)
2022-12-05T22:36:34.450Z,1670279794.450 [CommandExec](IMPORTANT): PAR_Licor.darkCount (count)
2022-12-05T22:36:34.450Z,1670279794.450 [CommandExec](IMPORTANT): PAR_Licor.maxBound (micromole_per_second_per_square_meter)
2022-12-05T22:36:34.451Z,1670279794.451 [CommandExec](IMPORTANT): PAR_Licor.maxValidPitch (degree)
2022-12-05T22:36:34.455Z,1670279794.455 [CommandExec](IMPORTANT): PAR_Licor.minBound (micromole_per_second_per_square_meter)
2022-12-05T22:36:34.456Z,1670279794.456 [CommandExec](IMPORTANT): PAR_Licor.minValidPitch (degree)
2022-12-05T22:36:34.456Z,1670279794.456 [CommandExec](IMPORTANT): PAR_Licor.multiplier (micromole_per_second_per_square_meter_per_microampere)
2022-12-05T22:36:34.456Z,1670279794.456 [CommandExec](IMPORTANT): PAR_Licor.parCal (none)
2022-12-05T22:36:34.457Z,1670279794.457 [CommandExec](IMPORTANT): PAR_Licor.serial (none)
2022-12-05T22:36:34.611Z,1670279794.611 [CommandExec](IMPORTANT): PAR_Licor.enableBroadcast (bool)
2022-12-05T22:36:34.611Z,1670279794.611 [CommandExec](IMPORTANT): PAR_Licor.component_voltage (volt)
2022-12-05T22:36:34.612Z,1670279794.612 [CommandExec](IMPORTANT): PAR_Licor.component_avgVoltage (volt)
2022-12-05T22:36:34.612Z,1670279794.612 [CommandExec](IMPORTANT): PAR_Licor.component_current (milliampere)
2022-12-05T22:36:34.612Z,1670279794.612 [CommandExec](IMPORTANT): PAR_Licor.component_avgCurrent (milliampere)
2022-12-05T22:36:34.613Z,1670279794.613 [CommandExec](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter)
2022-12-05T22:36:34.613Z,1670279794.613 [CommandExec](IMPORTANT): PAR_Licor.adcCount (count)
2022-12-05T22:36:35.385Z,1670279795.385 [DataOverHttps](INFO): Moved sent file to Logs/20221202T213219/Courier0034.lzma.bak
2022-12-05T22:36:35.385Z,1670279795.385 [DataOverHttps](INFO): SBD MOMSN=17384799
2022-12-05T22:36:42.855Z,1670279802.855 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-12-05T22:36:52.996Z,1670279812.996 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20221205T194151/Courier0000.lzma
2022-12-05T22:36:53.997Z,1670279813.997 [DataOverHttps](INFO): Moved sent file to Logs/20221205T194151/Courier0000.lzma.bak
2022-12-05T22:36:53.997Z,1670279813.997 [DataOverHttps](INFO): SBD MOMSN=17384802
2022-12-05T22:36:56.674Z,1670279816.674 [CommandExec](IMPORTANT): got command report touch PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water
2022-12-05T22:36:57.020Z,1670279817.020 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.195001 umol/s/m2
2022-12-05T22:36:57.464Z,1670279817.464 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.171766 umol/s/m2
2022-12-05T22:36:57.872Z,1670279817.872 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.179027 umol/s/m2
2022-12-05T22:36:58.279Z,1670279818.279 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.155792 umol/s/m2
2022-12-05T22:36:58.665Z,1670279818.665 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.160149 umol/s/m2
2022-12-05T22:36:59.078Z,1670279819.078 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.147079 umol/s/m2
2022-12-05T22:36:59.493Z,1670279819.493 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.155792 umol/s/m2
2022-12-05T22:36:59.892Z,1670279819.892 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.168862 umol/s/m2
2022-12-05T22:37:00.290Z,1670279820.290 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.171766 umol/s/m2
2022-12-05T22:37:00.699Z,1670279820.699 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.174670 umol/s/m2
2022-12-05T22:37:01.094Z,1670279821.094 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.161601 umol/s/m2
2022-12-05T22:37:01.463Z,1670279821.463 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.176123 umol/s/m2
2022-12-05T22:37:01.866Z,1670279821.866 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.164506 umol/s/m2
2022-12-05T22:37:02.327Z,1670279822.327 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.157245 umol/s/m2
2022-12-05T22:37:02.706Z,1670279822.706 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.177575 umol/s/m2
2022-12-05T22:37:03.105Z,1670279823.105 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.148532 umol/s/m2
2022-12-05T22:37:03.498Z,1670279823.498 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.151436 umol/s/m2
2022-12-05T22:37:03.889Z,1670279823.889 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.158697 umol/s/m2
2022-12-05T22:37:04.307Z,1670279824.307 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.125298 umol/s/m2
2022-12-05T22:37:04.712Z,1670279824.712 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.045429 umol/s/m2
2022-12-05T22:37:05.134Z,1670279825.134 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.104968 umol/s/m2
2022-12-05T22:37:05.542Z,1670279825.542 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 8.903119 umol/s/m2
2022-12-05T22:37:05.960Z,1670279825.960 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 8.898763 umol/s/m2
2022-12-05T22:37:06.363Z,1670279826.363 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 8.946683 umol/s/m2
2022-12-05T22:37:06.830Z,1670279826.830 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 8.553151 umol/s/m2
2022-12-05T22:37:07.175Z,1670279827.175 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 3.090171 umol/s/m2
2022-12-05T22:37:07.564Z,1670279827.564 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.062442 umol/s/m2
2022-12-05T22:37:07.973Z,1670279827.973 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.039208 umol/s/m2
2022-12-05T22:37:08.351Z,1670279828.351 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.078416 umol/s/m2
2022-12-05T22:37:08.760Z,1670279828.760 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.056634 umol/s/m2
2022-12-05T22:37:09.174Z,1670279829.174 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.066799 umol/s/m2
2022-12-05T22:37:09.552Z,1670279829.552 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.027591 umol/s/m2
2022-12-05T22:37:09.963Z,1670279829.963 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.023234 umol/s/m2
2022-12-05T22:37:10.267Z,1670279830.267 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20221205T223321/Courier0000.lzma
2022-12-05T22:37:10.379Z,1670279830.379 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.040660 umol/s/m2
2022-12-05T22:37:10.815Z,1670279830.815 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.045017 umol/s/m2
2022-12-05T22:37:11.176Z,1670279831.176 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.049373 umol/s/m2
2022-12-05T22:37:11.269Z,1670279831.269 [DataOverHttps](INFO): Moved sent file to Logs/20221205T223321/Courier0000.lzma.bak
2022-12-05T22:37:11.269Z,1670279831.269 [DataOverHttps](INFO): SBD MOMSN=17384806
2022-12-05T22:37:11.584Z,1670279831.584 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.065347 umol/s/m2
2022-12-05T22:37:11.966Z,1670279831.966 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.050825 umol/s/m2
2022-12-05T22:37:12.406Z,1670279832.406 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.046469 umol/s/m2
2022-12-05T22:37:12.792Z,1670279832.792 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.071155 umol/s/m2
2022-12-05T22:37:13.199Z,1670279833.199 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.049373 umol/s/m2
2022-12-05T22:37:13.620Z,1670279833.620 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.018878 umol/s/m2
2022-12-05T22:37:13.986Z,1670279833.986 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.056634 umol/s/m2
2022-12-05T22:37:14.440Z,1670279834.440 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.053729 umol/s/m2
2022-12-05T22:37:14.823Z,1670279834.823 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.018878 umol/s/m2
2022-12-05T22:37:15.221Z,1670279835.221 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.031947 umol/s/m2
2022-12-05T22:37:15.638Z,1670279835.638 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.039208 umol/s/m2
2022-12-05T22:37:16.028Z,1670279836.028 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.053729 umol/s/m2
2022-12-05T22:37:16.432Z,1670279836.432 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.047921 umol/s/m2
2022-12-05T22:37:16.847Z,1670279836.847 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.046469 umol/s/m2
2022-12-05T22:37:17.246Z,1670279837.246 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.056634 umol/s/m2
2022-12-05T22:37:17.659Z,1670279837.659 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.104555 umol/s/m2
2022-12-05T22:37:18.051Z,1670279838.051 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 8.978631 umol/s/m2
2022-12-05T22:37:18.460Z,1670279838.460 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.064307 umol/s/m2
2022-12-05T22:37:18.961Z,1670279838.961 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.103515 umol/s/m2
2022-12-05T22:37:19.284Z,1670279839.284 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.128201 umol/s/m2
2022-12-05T22:37:19.652Z,1670279839.652 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.120941 umol/s/m2
2022-12-05T22:37:20.109Z,1670279840.109 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.131105 umol/s/m2
2022-12-05T22:37:20.495Z,1670279840.495 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.151436 umol/s/m2
2022-12-05T22:37:20.924Z,1670279840.924 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.155792 umol/s/m2
2022-12-05T22:37:21.366Z,1670279841.366 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.165957 umol/s/m2
2022-12-05T22:37:21.691Z,1670279841.691 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.170314 umol/s/m2
2022-12-05T22:37:22.095Z,1670279842.095 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.171766 umol/s/m2
2022-12-05T22:37:22.470Z,1670279842.470 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.181931 umol/s/m2
2022-12-05T22:37:22.874Z,1670279842.874 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.174670 umol/s/m2
2022-12-05T22:37:23.286Z,1670279843.286 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.155792 umol/s/m2
2022-12-05T22:37:23.698Z,1670279843.698 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.176123 umol/s/m2
2022-12-05T22:37:24.087Z,1670279844.087 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.183383 umol/s/m2
2022-12-05T22:37:24.491Z,1670279844.491 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.173219 umol/s/m2
2022-12-05T22:37:24.900Z,1670279844.900 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.184835 umol/s/m2
2022-12-05T22:37:25.326Z,1670279845.326 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.171766 umol/s/m2
2022-12-05T22:37:25.731Z,1670279845.731 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.174670 umol/s/m2
2022-12-05T22:37:26.158Z,1670279846.158 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 9.180479 umol/s/m2
2022-12-05T22:37:26.564Z,1670279846.564 [CommandExec](IMPORTANT): got command report clear
2022-12-05T22:37:27.037Z,1670279847.037 [Startup:StartupSatComms:B](INFO): Timed out from 2022-12-05T22:36:26.7Z
2022-12-05T22:37:27.037Z,1670279847.037 [Startup:StartupSatComms:B] Stopped
2022-12-05T22:37:27.037Z,1670279847.037 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-12-05T22:37:27.037Z,1670279847.037 [Startup:StartupSatComms] Stopped
2022-12-05T22:37:27.037Z,1670279847.037 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-12-05T22:37:27.038Z,1670279847.038 [Startup](INFO): Completed Startup
2022-12-05T22:37:27.038Z,1670279847.038 [MissionManager](INFO): Startup is completed.
2022-12-05T22:37:27.038Z,1670279847.038 [MissionManager](INFO): Uninitializing Mission Startup
2022-12-05T22:37:27.038Z,1670279847.038 [Startup] Stopped
2022-12-05T22:37:27.038Z,1670279847.038 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-12-05T22:37:27.039Z,1670279847.039 [Startup:A.GoToSurface] Stopped
2022-12-05T22:37:27.039Z,1670279847.039 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-12-05T22:37:27.376Z,1670279847.376 [MissionManager](IMPORTANT): Started mission Default
2022-12-05T22:37:27.376Z,1670279847.376 [Default] Running Loop=1
2022-12-05T22:37:27.376Z,1670279847.376 [Default](DEBUG): Aggregate::initialize Default
2022-12-05T22:37:27.377Z,1670279847.377 [Default:B.GoToSurface] Running Loop=1
2022-12-05T22:37:27.377Z,1670279847.377 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-12-05T22:37:27.377Z,1670279847.377 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-12-05T22:37:27.377Z,1670279847.377 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-12-05T22:37:27.377Z,1670279847.377 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-12-05T22:37:27.378Z,1670279847.378 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-12-05T22:37:27.378Z,1670279847.378 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-12-05T22:37:27.378Z,1670279847.378 [Default:A.Wait] Running Loop=1
2022-12-05T22:37:27.378Z,1670279847.378 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-12-05T22:37:29.472Z,1670279849.472 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20221202T213219/Express0035.lzma
2022-12-05T22:37:30.204Z,1670279850.204 [CommandExec](IMPORTANT): got command report clear
2022-12-05T22:37:30.473Z,1670279850.473 [DataOverHttps](INFO): Moved sent file to Logs/20221202T213219/Express0035.lzma.bak
2022-12-05T22:37:30.473Z,1670279850.473 [DataOverHttps](INFO): SBD MOMSN=17384809
2022-12-05T22:37:40.641Z,1670279860.641 [Default:A.Wait](INFO): Done Waiting.
2022-12-05T22:37:40.641Z,1670279860.641 [Default:A.Wait] Stopped
2022-12-05T22:37:40.641Z,1670279860.641 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-12-05T22:37:41.041Z,1670279861.041 [Default:CheckIn] Running Loop=1
2022-12-05T22:37:41.042Z,1670279861.042 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-05T22:37:41.042Z,1670279861.042 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-05T22:37:41.463Z,1670279861.463 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-12-05T22:37:49.144Z,1670279869.144 [CommandExec](IMPORTANT): got command show variable prop
2022-12-05T22:37:49.149Z,1670279869.149 [CommandExec](IMPORTANT): platform_propeller_rotation_rate (radian_per_second)
2022-12-05T22:37:49.199Z,1670279869.199 [CommandExec](IMPORTANT): Simulator.designPropEff (none)
2022-12-05T22:37:49.223Z,1670279869.223 [CommandExec](IMPORTANT): SpeedControl.propPitch (meter_per_radian)
2022-12-05T22:37:49.232Z,1670279869.232 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction (radian_per_second)
2022-12-05T22:37:49.234Z,1670279869.234 [CommandExec](IMPORTANT): ThrusterHE.platform_propeller_rotation_rate (radian_per_second)
2022-12-05T22:37:56.384Z,1670279876.384 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-12-05T22:38:13.162Z,1670279893.162 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute
2022-12-05T22:38:13.166Z,1670279893.166 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2022-12-05T22:38:13.413Z,1670279893.413 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T22:39:02.901Z,1670279942.901 [CommandExec](IMPORTANT): got command maintain clear
2022-12-05T22:39:03.157Z,1670279943.157 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T22:39:13.953Z,1670279953.953 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-05T22:39:29.730Z,1670279969.730 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200 cubic_centimeter
2022-12-05T22:39:29.762Z,1670279969.762 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2022-12-05T22:39:29.788Z,1670279969.788 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T22:39:31.739Z,1670279971.739 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-12-05T22:39:31.739Z,1670279971.739 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-05T22:39:31.798Z,1670279971.798 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-05T22:39:32.158Z,1670279972.158 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-05T22:39:32.158Z,1670279972.158 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-12-05T22:40:58.377Z,1670280058.377 [CommandExec](IMPORTANT): got command maintain clear
2022-12-05T22:40:58.626Z,1670280058.626 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T22:41:08.287Z,1670280068.287 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-05T22:41:21.614Z,1670280081.614 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-05T22:41:25.257Z,1670280085.257 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5.
2022-12-05T22:41:25.261Z,1670280085.261 [BPC1](INFO): Received data from all battery sticks.
2022-12-05T22:41:59.542Z,1670280119.542 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2022-12-05T22:41:59.546Z,1670280119.546 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2022-12-05T22:41:59.668Z,1670280119.668 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T22:42:13.130Z,1670280133.130 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2022-12-05T22:42:28.798Z,1670280148.798 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0 degree
2022-12-05T22:42:32.735Z,1670280152.735 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-12-05T22:42:32.735Z,1670280152.735 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-05T22:42:32.780Z,1670280152.780 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-05T22:42:33.179Z,1670280153.179 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-05T22:42:33.179Z,1670280153.179 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-12-05T22:42:41.242Z,1670280161.242 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-12-05T22:37:41.0Z
2022-12-05T22:42:41.242Z,1670280161.242 [Default:CheckIn:Read_GPS] Stopped
2022-12-05T22:42:41.242Z,1670280161.242 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-05T22:42:41.650Z,1670280161.650 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-12-05T22:42:48.584Z,1670280168.584 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20221205T223321/Courier0004.lzma
2022-12-05T22:42:49.585Z,1670280169.585 [DataOverHttps](INFO): Moved sent file to Logs/20221205T223321/Courier0004.lzma.bak
2022-12-05T22:42:49.586Z,1670280169.586 [DataOverHttps](INFO): SBD MOMSN=17384824
2022-12-05T22:42:55.606Z,1670280175.606 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree
2022-12-05T22:42:55.629Z,1670280175.629 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2022-12-05T22:42:55.808Z,1670280175.808 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T22:43:06.710Z,1670280186.710 [DataOverHttps](INFO): Sending 1344 bytes from file Logs/20221205T194151/Express0001.lzma
2022-12-05T22:43:07.709Z,1670280187.709 [DataOverHttps](INFO): Moved sent file to Logs/20221205T194151/Express0001.lzma.bak
2022-12-05T22:43:07.709Z,1670280187.709 [DataOverHttps](INFO): SBD MOMSN=17384826
2022-12-05T22:43:14.447Z,1670280194.447 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree
2022-12-05T22:43:27.213Z,1670280207.213 [DataOverHttps](INFO): Sending 1166 bytes from file Logs/20221205T223321/Express0001.lzma
2022-12-05T22:43:28.213Z,1670280208.213 [DataOverHttps](INFO): Moved sent file to Logs/20221205T223321/Express0001.lzma.bak
2022-12-05T22:43:28.213Z,1670280208.213 [DataOverHttps](INFO): SBD MOMSN=17384870
2022-12-05T22:43:31.789Z,1670280211.789 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0 degree
2022-12-05T22:43:46.332Z,1670280226.332 [DataOverHttps](INFO): Sending 886 bytes from file Logs/20221205T223321/Express0005.lzma
2022-12-05T22:43:47.333Z,1670280227.333 [DataOverHttps](INFO): Moved sent file to Logs/20221205T223321/Express0005.lzma.bak
2022-12-05T22:43:47.333Z,1670280227.333 [DataOverHttps](INFO): SBD MOMSN=17384903
2022-12-05T22:43:50.316Z,1670280230.316 [Default:CheckIn:Read_Iridium] Stopped
2022-12-05T22:43:50.316Z,1670280230.316 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-05T22:43:50.316Z,1670280230.316 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-05T22:43:55.693Z,1670280235.693 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2022-12-05T22:43:55.693Z,1670280235.693 [CommandExec](IMPORTANT): platform_buoyancy_position 947.056396 cc
2022-12-05T22:44:12.513Z,1670280252.513 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-12-05T22:44:12.513Z,1670280252.513 [NAL9602] Data Fault, FailCount= 1
2022-12-05T22:44:12.513Z,1670280252.513 [NAL9602](ERROR): Data Fault
2022-12-05T22:44:12.583Z,1670280252.583 [CBIT](ERROR): Data Fault in component: NAL9602
2022-12-05T22:44:12.920Z,1670280252.920 [NAL9602](INFO): Powering down
2022-12-05T22:44:13.190Z,1670280253.190 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:44:13.191Z,1670280253.191 [CommandExec](IMPORTANT): platform_mass_position 0.010306 m
2022-12-05T22:44:13.750Z,1670280253.750 [CBIT](INFO): Clearing failed state for component NAL9602
2022-12-05T22:44:13.750Z,1670280253.750 [NAL9602] No Fault, FailCount= 1
2022-12-05T22:44:43.221Z,1670280283.221 [NAL9602](INFO): Powering up NAL9602
2022-12-05T22:44:54.129Z,1670280294.129 [NAL9602](INFO): NAL9602 initialized
2022-12-05T22:44:55.561Z,1670280295.561 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2022-12-05T22:44:55.562Z,1670280295.562 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitFwd 28.000000 mm
2022-12-05T22:45:05.910Z,1670280305.910 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitAft
2022-12-05T22:45:05.910Z,1670280305.910 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitAft -31.000000 mm
2022-12-05T22:45:32.163Z,1670280332.163 [CommandExec](IMPORTANT): got command get platform_orientation
2022-12-05T22:45:32.163Z,1670280332.163 [CommandExec](IMPORTANT): platform_orientation 5.387853 rad
2022-12-05T22:45:33.748Z,1670280333.748 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-12-05T22:45:33.748Z,1670280333.748 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-05T22:45:33.759Z,1670280333.759 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-05T22:45:34.143Z,1670280334.143 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-05T22:45:34.143Z,1670280334.143 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-12-05T22:45:38.348Z,1670280338.348 [CommandExec](IMPORTANT): got command get platform_orientation degree
2022-12-05T22:45:38.349Z,1670280338.349 [CommandExec](IMPORTANT): platform_orientation 308.696601 arcdeg
2022-12-05T22:46:17.932Z,1670280377.932 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 20 millimeter
2022-12-05T22:46:17.935Z,1670280377.935 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2022-12-05T22:46:18.204Z,1670280378.204 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,Maintain_VerticalControl.elevatorAngleAction,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T22:46:56.316Z,1670280416.316 [CommandExec](IMPORTANT): got command get platform_orientation degree
2022-12-05T22:46:56.317Z,1670280416.317 [CommandExec](IMPORTANT): platform_orientation 308.706956 arcdeg
2022-12-05T22:47:11.354Z,1670280431.354 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:47:11.355Z,1670280431.355 [CommandExec](IMPORTANT): platform_mass_position 0.020000 m
2022-12-05T22:47:24.744Z,1670280444.744 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 25 millimeter
2022-12-05T22:47:41.569Z,1670280461.569 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:47:41.570Z,1670280461.570 [CommandExec](IMPORTANT): platform_mass_position 0.025005 m
2022-12-05T22:47:52.453Z,1670280472.453 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -25 millimeter
2022-12-05T22:48:15.648Z,1670280495.648 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:48:15.649Z,1670280495.649 [CommandExec](IMPORTANT): platform_mass_position 0.008583 m
2022-12-05T22:48:20.078Z,1670280500.078 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:48:20.079Z,1670280500.079 [CommandExec](IMPORTANT): platform_mass_position 0.005105 m
2022-12-05T22:48:26.792Z,1670280506.792 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:48:26.793Z,1670280506.793 [CommandExec](IMPORTANT): platform_mass_position -0.000277 m
2022-12-05T22:48:34.716Z,1670280514.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-12-05T22:48:34.716Z,1670280514.716 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-05T22:48:34.771Z,1670280514.771 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-05T22:48:35.160Z,1670280515.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-05T22:48:35.160Z,1670280515.160 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-12-05T22:48:38.638Z,1670280518.638 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:48:38.639Z,1670280518.639 [CommandExec](IMPORTANT): platform_mass_position -0.009464 m
2022-12-05T22:48:43.348Z,1670280523.348 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:48:43.348Z,1670280523.348 [CommandExec](IMPORTANT): platform_mass_position -0.013262 m
2022-12-05T22:48:49.352Z,1670280529.352 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:48:49.353Z,1670280529.353 [CommandExec](IMPORTANT): platform_mass_position -0.017716 m
2022-12-05T22:48:50.878Z,1670280530.878 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-05T22:48:50.878Z,1670280530.878 [Default:CheckIn:C.Wait] Stopped
2022-12-05T22:48:50.878Z,1670280530.878 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-05T22:48:50.878Z,1670280530.878 [Default:CheckIn:D] Running Loop=1
2022-12-05T22:48:51.285Z,1670280531.285 [Default:CheckIn:D] Stopped
2022-12-05T22:48:51.285Z,1670280531.285 [Default:CheckIn:E] Running Loop=1
2022-12-05T22:48:51.693Z,1670280531.693 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.398477 min
2022-12-05T22:48:51.693Z,1670280531.693 [Default:CheckIn:E] Stopped
2022-12-05T22:48:51.693Z,1670280531.693 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-05T22:48:51.693Z,1670280531.693 [Default:CheckIn] Stopped
2022-12-05T22:48:51.693Z,1670280531.693 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-05T22:48:51.693Z,1670280531.693 [Default:CheckIn](INFO): Running loop #2
2022-12-05T22:48:51.693Z,1670280531.693 [Default:CheckIn] Running Loop=2
2022-12-05T22:48:51.693Z,1670280531.693 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-05T22:48:51.694Z,1670280531.694 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-05T22:48:55.653Z,1670280535.653 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:48:55.654Z,1670280535.654 [CommandExec](IMPORTANT): platform_mass_position -0.022773 m
2022-12-05T22:48:59.392Z,1670280539.392 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:48:59.393Z,1670280539.393 [CommandExec](IMPORTANT): platform_mass_position -0.024984 m
2022-12-05T22:49:02.817Z,1670280542.817 [CommandExec](IMPORTANT): got command get platform_mass_position
2022-12-05T22:49:02.818Z,1670280542.818 [CommandExec](IMPORTANT): platform_mass_position -0.024984 m
2022-12-05T22:49:08.333Z,1670280548.333 [CommandExec](IMPORTANT): got command maintain clear
2022-12-05T22:49:08.707Z,1670280548.707 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-05T22:50:58.735Z,1670280658.735 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-05T22:51:00.744Z,1670280660.744 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247231
2022-12-05T22:51:33.887Z,1670280693.887 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-05T22:51:35.707Z,1670280695.707 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-12-05T22:51:35.707Z,1670280695.707 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-05T22:51:35.718Z,1670280695.718 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-05T22:51:36.132Z,1670280696.132 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-05T22:51:36.133Z,1670280696.133 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-12-05T22:52:09.027Z,1670280729.027 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-05T22:52:44.184Z,1670280764.184 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-12-05T22:52:56.440Z,1670280776.440 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003535
2022-12-05T22:53:51.890Z,1670280831.890 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-12-05T22:48:51.7Z
2022-12-05T22:53:51.890Z,1670280831.890 [Default:CheckIn:Read_GPS] Stopped
2022-12-05T22:53:51.890Z,1670280831.890 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-05T22:53:59.171Z,1670280839.171 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221205T223321/Courier0007.lzma
2022-12-05T22:54:00.175Z,1670280840.175 [DataOverHttps](INFO): Moved sent file to Logs/20221205T223321/Courier0007.lzma.bak
2022-12-05T22:54:00.176Z,1670280840.176 [DataOverHttps](INFO): SBD MOMSN=17384947
2022-12-05T22:54:18.400Z,1670280858.400 [DataOverHttps](INFO): Sending 677 bytes from file Logs/20221205T223321/Express0008.lzma
2022-12-05T22:54:19.401Z,1670280859.401 [DataOverHttps](INFO): Moved sent file to Logs/20221205T223321/Express0008.lzma.bak
2022-12-05T22:54:19.401Z,1670280859.401 [DataOverHttps](INFO): SBD MOMSN=17384950
2022-12-05T22:54:21.792Z,1670280861.792 [Default:CheckIn:Read_Iridium] Stopped
2022-12-05T22:54:21.792Z,1670280861.792 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-05T22:54:21.792Z,1670280861.792 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-05T22:54:36.781Z,1670280876.781 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-12-05T22:54:36.781Z,1670280876.781 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-05T22:54:36.856Z,1670280876.856 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-05T22:54:37.217Z,1670280877.217 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-05T22:54:37.217Z,1670280877.217 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-12-05T22:54:55.321Z,1670280895.321 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-12-05T22:54:55.321Z,1670280895.321 [NAL9602] Data Fault, FailCount= 2
2022-12-05T22:54:55.321Z,1670280895.321 [NAL9602](ERROR): Data Fault
2022-12-05T22:54:55.338Z,1670280895.338 [CBIT](ERROR): Data Fault in component: NAL9602
2022-12-05T22:54:55.725Z,1670280895.725 [NAL9602](INFO): Powering down
2022-12-05T22:54:56.561Z,1670280896.561 [CBIT](INFO): Clearing failed state for component NAL9602
2022-12-05T22:54:56.561Z,1670280896.561 [NAL9602] No Fault, FailCount= 2
2022-12-05T22:55:26.077Z,1670280926.077 [NAL9602](INFO): Powering up NAL9602
2022-12-05T22:55:36.981Z,1670280936.981 [NAL9602](INFO): NAL9602 initialized
2022-12-05T22:57:37.788Z,1670281057.788 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-12-05T22:57:37.788Z,1670281057.788 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-05T22:57:37.798Z,1670281057.798 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-05T22:57:38.206Z,1670281058.206 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-05T22:57:38.206Z,1670281058.206 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-12-05T22:59:15.732Z,1670281155.732 [CommandExec](IMPORTANT): got command quit
2022-12-05T22:59:16.735Z,1670281156.735 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-12-05T22:59:16.735Z,1670281156.735 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:16.736Z,1670281156.736 [CommandExec](INFO): Uninitializing the command executive.
2022-12-05T22:59:16.736Z,1670281156.736 [CommandExec](INFO): Uninitializing the command scheduler.
2022-12-05T22:59:16.736Z,1670281156.736 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:16.907Z,1670281156.907 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2022-12-05T22:59:16.907Z,1670281156.907 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-12-05T22:59:16.907Z,1670281156.907 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:16.908Z,1670281156.908 [NavChartDb](INFO): Join timeout helper Thread ID is 5024
2022-12-05T22:59:17.128Z,1670281157.128 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:17.128Z,1670281157.128 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:17.147Z,1670281157.147 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-12-05T22:59:17.147Z,1670281157.147 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:17.148Z,1670281157.148 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5025
2022-12-05T22:59:17.631Z,1670281157.631 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:17.632Z,1670281157.632 [WetLabsBB2FL](INFO): Powering down
2022-12-05T22:59:17.632Z,1670281157.632 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:17.633Z,1670281157.633 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-12-05T22:59:17.633Z,1670281157.633 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:17.633Z,1670281157.633 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5026
2022-12-05T22:59:18.139Z,1670281158.139 [CTD_Seabird](INFO): Powering down
2022-12-05T22:59:18.151Z,1670281158.151 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:18.151Z,1670281158.151 [CTD_Seabird](INFO): Powering down
2022-12-05T22:59:18.167Z,1670281158.167 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:18.187Z,1670281158.187 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-12-05T22:59:18.187Z,1670281158.187 [DAT ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:18.188Z,1670281158.188 [DAT](INFO): Join timeout helper Thread ID is 5027
2022-12-05T22:59:18.263Z,1670281158.263 [DAT](INFO): Powering down
2022-12-05T22:59:18.336Z,1670281158.336 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:18.336Z,1670281158.336 [DAT](INFO): Powering down
2022-12-05T22:59:18.337Z,1670281158.337 [DAT ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:18.343Z,1670281158.343 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-12-05T22:59:18.343Z,1670281158.343 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:18.344Z,1670281158.344 [Radio_Surface](INFO): Join timeout helper Thread ID is 5028
2022-12-05T22:59:18.563Z,1670281158.563 [Radio_Surface](INFO): Powering down
2022-12-05T22:59:18.564Z,1670281158.564 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:18.565Z,1670281158.565 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:18.572Z,1670281158.572 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-12-05T22:59:18.572Z,1670281158.572 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:18.572Z,1670281158.572 [Onboard](INFO): Join timeout helper Thread ID is 5029
2022-12-05T22:59:21.923Z,1670281161.923 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:21.923Z,1670281161.923 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:21.940Z,1670281161.940 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-12-05T22:59:21.940Z,1670281161.940 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:21.940Z,1670281161.940 [DataOverHttps](INFO): Join timeout helper Thread ID is 5030
2022-12-05T22:59:22.263Z,1670281162.263 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:22.266Z,1670281162.266 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:22.284Z,1670281162.284 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-12-05T22:59:22.284Z,1670281162.284 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:22.285Z,1670281162.285 [BackseatComponent](INFO): Join timeout helper Thread ID is 5031
2022-12-05T22:59:22.470Z,1670281162.470 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-05T22:59:22.470Z,1670281162.470 [Default:CheckIn:C.Wait] Stopped
2022-12-05T22:59:22.470Z,1670281162.470 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-05T22:59:22.470Z,1670281162.470 [Default:CheckIn:D] Running Loop=1
2022-12-05T22:59:22.475Z,1670281162.475 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:22.476Z,1670281162.476 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:22.484Z,1670281162.484 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-12-05T22:59:22.484Z,1670281162.484 [logger ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:22.484Z,1670281162.484 [logger](INFO): Join timeout helper Thread ID is 5032
2022-12-05T22:59:22.485Z,1670281162.485 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:22.485Z,1670281162.485 [logger ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:22.504Z,1670281162.504 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-12-05T22:59:22.504Z,1670281162.504 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:22.504Z,1670281162.504 [CommandLine](INFO): Join timeout helper Thread ID is 5033
2022-12-05T22:59:22.563Z,1670281162.563 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:22.564Z,1670281162.564 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:22.584Z,1670281162.584 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-12-05T22:59:22.584Z,1670281162.584 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:22.584Z,1670281162.584 [CommandExec](INFO): Join timeout helper Thread ID is 5034
2022-12-05T22:59:22.585Z,1670281162.585 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-12-05T22:59:22.586Z,1670281162.586 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:22.586Z,1670281162.586 [controlThread](INFO): Join timeout helper Thread ID is 5035
2022-12-05T22:59:22.831Z,1670281162.831 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-12-05T22:59:22.831Z,1670281162.831 [controlThread](DEBUG): Uninitializing ControlThread
2022-12-05T22:59:22.832Z,1670281162.832 [AHRS_M2](INFO): Powering down
2022-12-05T22:59:22.904Z,1670281162.904 [NAL9602](INFO): Powering down
2022-12-05T22:59:22.906Z,1670281162.906 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-12-05T22:59:22.906Z,1670281162.906 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-12-05T22:59:22.907Z,1670281162.907 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-12-05T22:59:22.908Z,1670281162.908 [MissionManager](INFO): Uninitializing Mission Default
2022-12-05T22:59:22.908Z,1670281162.908 [Default] Stopped
2022-12-05T22:59:22.908Z,1670281162.908 [Default](DEBUG): Aggregate::uninitialize Default
2022-12-05T22:59:22.908Z,1670281162.908 [Default:B.GoToSurface] Stopped
2022-12-05T22:59:22.908Z,1670281162.908 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-12-05T22:59:22.908Z,1670281162.908 [Default:CheckIn] Stopped
2022-12-05T22:59:22.908Z,1670281162.908 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-05T22:59:22.908Z,1670281162.908 [Default:CheckIn:D] Stopped
2022-12-05T22:59:22.911Z,1670281162.911 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-12-05T22:59:22.911Z,1670281162.911 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-12-05T22:59:22.912Z,1670281162.912 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-12-05T22:59:22.912Z,1670281162.912 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-12-05T22:59:22.912Z,1670281162.912 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-12-05T22:59:22.912Z,1670281162.912 [BuoyancyServo](INFO): Powering down
2022-12-05T22:59:22.927Z,1670281162.927 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-12-05T22:59:22.927Z,1670281162.927 [ElevatorServo](INFO): Powering down
2022-12-05T22:59:22.928Z,1670281162.928 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-12-05T22:59:22.928Z,1670281162.928 [MassServo](INFO): Powering down
2022-12-05T22:59:22.929Z,1670281162.929 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-12-05T22:59:22.929Z,1670281162.929 [RudderServo](INFO): Powering down
2022-12-05T22:59:22.930Z,1670281162.930 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-12-05T22:59:22.930Z,1670281162.930 [ThrusterHE](INFO): Powering down
2022-12-05T22:59:22.931Z,1670281162.931 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-12-05T22:59:22.931Z,1670281162.931 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-12-05T22:59:22.932Z,1670281162.932 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-12-05T22:59:22.932Z,1670281162.932 [CBIT](DEBUG): Powering off loads.
2022-12-05T22:59:22.943Z,1670281162.943 [CBIT](DEBUG): Disabling WDT.
2022-12-05T22:59:22.955Z,1670281162.955 [CBIT](DEBUG): Opening all GF detection circuits.
2022-12-05T22:59:22.956Z,1670281162.956 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:23.002Z,1670281163.002 [DAT ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:23.007Z,1670281163.007 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:23.009Z,1670281163.009 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:23.014Z,1670281163.014 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:23.057Z,1670281163.057 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:23.093Z,1670281163.093 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:23.096Z,1670281163.096 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:23.127Z,1670281163.127 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-12-05T22:59:23.187Z,1670281163.187 [logger ThreadHandler](INFO): Thread cancelled.