2021-07-13T16:12:29.195Z,1626192749.195 [Supervisor](DEBUG): Initializing supervisor.
2021-07-13T16:12:29.200Z,1626192749.200 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-07-13T16:12:29.200Z,1626192749.200 [SyncHandler](INFO): Protected caller Thread ID is 997
2021-07-13T16:12:29.201Z,1626192749.201 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-07-13T16:12:29.202Z,1626192749.202 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-07-13T16:12:29.202Z,1626192749.202 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 998
2021-07-13T16:12:29.206Z,1626192749.206 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-07-13T16:12:29.226Z,1626192749.226 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-07-13T16:12:29.227Z,1626192749.227 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-07-13T16:12:29.228Z,1626192749.228 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 999
2021-07-13T16:12:29.230Z,1626192749.230 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-07-13T16:12:29.231Z,1626192749.231 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-07-13T16:12:29.231Z,1626192749.231 [logger ThreadHandler](INFO): Protected caller Thread ID is 1000
2021-07-13T16:12:29.235Z,1626192749.235 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-07-13T16:12:29.235Z,1626192749.235 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-07-13T16:12:29.237Z,1626192749.237 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-07-13T16:12:29.656Z,1626192749.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-07-13T16:12:29.657Z,1626192749.657 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-07-13T16:12:29.804Z,1626192749.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-07-13T16:12:29.804Z,1626192749.804 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-07-13T16:12:29.886Z,1626192749.886 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-07-13T16:12:30.107Z,1626192750.107 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-07-13T16:12:30.107Z,1626192750.107 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-07-13T16:12:30.186Z,1626192750.186 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-07-13T16:12:30.282Z,1626192750.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-07-13T16:12:30.283Z,1626192750.283 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-07-13T16:12:30.626Z,1626192750.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-07-13T16:12:30.627Z,1626192750.627 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-07-13T16:12:30.751Z,1626192750.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-07-13T16:12:30.752Z,1626192750.752 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-07-13T16:12:31.283Z,1626192751.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-07-13T16:12:31.283Z,1626192751.283 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-07-13T16:12:31.476Z,1626192751.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-07-13T16:12:31.476Z,1626192751.476 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-07-13T16:12:31.857Z,1626192751.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-07-13T16:12:31.858Z,1626192751.858 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-07-13T16:12:32.101Z,1626192752.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-07-13T16:12:32.101Z,1626192752.101 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-07-13T16:12:32.211Z,1626192752.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-07-13T16:12:32.212Z,1626192752.212 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-07-13T16:12:32.944Z,1626192752.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-07-13T16:12:32.945Z,1626192752.945 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-07-13T16:12:33.569Z,1626192753.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-07-13T16:12:33.570Z,1626192753.570 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-07-13T16:12:33.787Z,1626192753.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-07-13T16:12:33.789Z,1626192753.789 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2021-07-13T16:12:33.790Z,1626192753.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2021-07-13T16:12:33.987Z,1626192753.987 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2021-07-13T16:12:34.069Z,1626192754.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2021-07-13T16:12:34.207Z,1626192754.207 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2021-07-13T16:12:34.293Z,1626192754.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2021-07-13T16:12:34.554Z,1626192754.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-07-13T16:12:34.555Z,1626192754.555 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2021-07-13T16:12:34.659Z,1626192754.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2021-07-13T16:12:34.771Z,1626192754.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2021-07-13T16:12:34.866Z,1626192754.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2021-07-13T16:12:35.013Z,1626192755.013 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2021-07-13T16:12:35.112Z,1626192755.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2021-07-13T16:12:35.206Z,1626192755.206 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2021-07-13T16:12:35.207Z,1626192755.207 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-07-13T16:12:35.220Z,1626192755.220 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-07-13T16:12:35.231Z,1626192755.231 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-07-13T16:12:35.232Z,1626192755.232 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-07-13T16:12:35.331Z,1626192755.331 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-07-13T16:12:35.331Z,1626192755.331 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-07-13T16:12:35.386Z,1626192755.386 [VerticalControl](DEBUG): Construct VerticalControl.
2021-07-13T16:12:35.448Z,1626192755.448 [VerticalControl] Loaded
2021-07-13T16:12:35.449Z,1626192755.449 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-07-13T16:12:35.451Z,1626192755.451 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-07-13T16:12:35.490Z,1626192755.490 [HorizontalControl] Loaded
2021-07-13T16:12:35.490Z,1626192755.490 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-07-13T16:12:35.493Z,1626192755.493 [SpeedControl](DEBUG): Construct SpeedControl.
2021-07-13T16:12:35.496Z,1626192755.496 [SpeedControl] Loaded
2021-07-13T16:12:35.496Z,1626192755.496 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-07-13T16:12:35.499Z,1626192755.499 [LoopControl](DEBUG): Construct LoopControl.
2021-07-13T16:12:35.499Z,1626192755.499 [LoopControl] Loaded
2021-07-13T16:12:35.500Z,1626192755.500 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-07-13T16:12:35.500Z,1626192755.500 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-07-13T16:12:35.501Z,1626192755.501 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-07-13T16:12:35.656Z,1626192755.656 [BuoyancyServo] Loaded
2021-07-13T16:12:35.657Z,1626192755.657 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-07-13T16:12:35.676Z,1626192755.676 [ElevatorServo] Loaded
2021-07-13T16:12:35.676Z,1626192755.676 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-07-13T16:12:35.694Z,1626192755.694 [MassServo] Loaded
2021-07-13T16:12:35.695Z,1626192755.695 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-07-13T16:12:35.713Z,1626192755.713 [RudderServo] Loaded
2021-07-13T16:12:35.713Z,1626192755.713 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-07-13T16:12:35.728Z,1626192755.728 [ThrusterHE] Loaded
2021-07-13T16:12:35.728Z,1626192755.728 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2021-07-13T16:12:35.729Z,1626192755.729 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-07-13T16:12:35.729Z,1626192755.729 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-07-13T16:12:35.772Z,1626192755.772 [DepthRateCalculator] Loaded
2021-07-13T16:12:35.773Z,1626192755.773 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-07-13T16:12:35.777Z,1626192755.777 [PitchRateCalculator] Loaded
2021-07-13T16:12:35.778Z,1626192755.778 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-07-13T16:12:35.788Z,1626192755.788 [SpeedCalculator] Loaded
2021-07-13T16:12:35.788Z,1626192755.788 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-07-13T16:12:35.802Z,1626192755.802 [TempGradientCalculator] Loaded
2021-07-13T16:12:35.802Z,1626192755.802 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-07-13T16:12:35.806Z,1626192755.806 [YawRateCalculator] Loaded
2021-07-13T16:12:35.807Z,1626192755.807 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-07-13T16:12:35.824Z,1626192755.824 [ElevatorOffsetCalculator] Loaded
2021-07-13T16:12:35.825Z,1626192755.825 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-07-13T16:12:35.825Z,1626192755.825 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-07-13T16:12:35.826Z,1626192755.826 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-07-13T16:12:35.907Z,1626192755.907 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-07-13T16:12:35.907Z,1626192755.907 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-07-13T16:12:35.921Z,1626192755.921 [NavChart] Loaded
2021-07-13T16:12:35.922Z,1626192755.922 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-07-13T16:12:35.927Z,1626192755.927 [UniversalFixResidualReporter] Loaded
2021-07-13T16:12:35.928Z,1626192755.928 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-07-13T16:12:35.928Z,1626192755.928 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-07-13T16:12:35.929Z,1626192755.929 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-07-13T16:12:36.300Z,1626192756.300 [AHRS_M2] Loaded
2021-07-13T16:12:36.300Z,1626192756.300 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-07-13T16:12:36.330Z,1626192756.330 [BackseatComponent] Loaded
2021-07-13T16:12:36.331Z,1626192756.331 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-07-13T16:12:36.332Z,1626192756.332 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4086C4E0
2021-07-13T16:12:36.332Z,1626192756.332 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1082
2021-07-13T16:12:36.335Z,1626192756.335 [LcmUniversalReporter] Loaded
2021-07-13T16:12:36.335Z,1626192756.335 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-07-13T16:12:37.143Z,1626192757.143 [BPC1] Loaded
2021-07-13T16:12:37.144Z,1626192757.144 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-07-13T16:12:37.217Z,1626192757.217 [DataOverHttps] Loaded
2021-07-13T16:12:37.218Z,1626192757.218 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-07-13T16:12:37.219Z,1626192757.219 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4089C4E0
2021-07-13T16:12:37.219Z,1626192757.219 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1083
2021-07-13T16:12:37.240Z,1626192757.240 [Depth_Keller] Loaded
2021-07-13T16:12:37.240Z,1626192757.240 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-07-13T16:12:37.245Z,1626192757.245 [DropWeight] Loaded
2021-07-13T16:12:37.245Z,1626192757.245 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-07-13T16:12:37.304Z,1626192757.304 [NAL9602] Loaded
2021-07-13T16:12:37.305Z,1626192757.305 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-07-13T16:12:37.334Z,1626192757.334 [Onboard] Loaded
2021-07-13T16:12:37.334Z,1626192757.334 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-07-13T16:12:37.335Z,1626192757.335 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408CC4E0
2021-07-13T16:12:37.335Z,1626192757.335 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1084
2021-07-13T16:12:37.348Z,1626192757.348 [Power24vConverter] Loaded
2021-07-13T16:12:37.349Z,1626192757.349 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-07-13T16:12:37.361Z,1626192757.361 [Radio_Surface] Loaded
2021-07-13T16:12:37.362Z,1626192757.362 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-07-13T16:12:37.363Z,1626192757.363 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408FC4E0
2021-07-13T16:12:37.363Z,1626192757.363 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1085
2021-07-13T16:12:37.364Z,1626192757.364 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-07-13T16:12:37.365Z,1626192757.365 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-07-13T16:12:37.423Z,1626192757.423 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-07-13T16:12:37.424Z,1626192757.424 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-07-13T16:12:37.533Z,1626192757.533 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-07-13T16:12:37.534Z,1626192757.534 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-07-13T16:12:37.562Z,1626192757.562 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-07-13T16:12:37.562Z,1626192757.562 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-07-13T16:12:37.740Z,1626192757.740 [CTD_Seabird] Loaded
2021-07-13T16:12:37.741Z,1626192757.741 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-07-13T16:12:37.742Z,1626192757.742 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A1B4E0
2021-07-13T16:12:37.742Z,1626192757.742 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1086
2021-07-13T16:12:37.762Z,1626192757.762 [PAR_Licor] Loaded
2021-07-13T16:12:37.762Z,1626192757.762 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-07-13T16:12:37.795Z,1626192757.795 [WetLabsBB2FL] Loaded
2021-07-13T16:12:37.795Z,1626192757.795 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-07-13T16:12:37.796Z,1626192757.796 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A4B4E0
2021-07-13T16:12:37.796Z,1626192757.796 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1087
2021-07-13T16:12:37.797Z,1626192757.797 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-07-13T16:12:37.798Z,1626192757.798 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-07-13T16:12:38.366Z,1626192758.366 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-07-13T16:12:38.366Z,1626192758.366 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-07-13T16:12:38.594Z,1626192758.594 [SBIT](DEBUG): Construct Startup Built In Test.
2021-07-13T16:12:38.603Z,1626192758.603 [SBIT] Loaded
2021-07-13T16:12:38.604Z,1626192758.604 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-07-13T16:12:38.606Z,1626192758.606 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-07-13T16:12:38.620Z,1626192758.620 [IBIT] Loaded
2021-07-13T16:12:38.620Z,1626192758.620 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-07-13T16:12:38.626Z,1626192758.626 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-07-13T16:12:38.807Z,1626192758.807 [CBIT] Loaded
2021-07-13T16:12:38.807Z,1626192758.807 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-07-13T16:12:38.808Z,1626192758.808 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-07-13T16:12:38.829Z,1626192758.829 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-07-13T16:12:38.832Z,1626192758.832 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-07-13T16:12:38.909Z,1626192758.909 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-07-13T16:12:38.910Z,1626192758.910 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B314E0
2021-07-13T16:12:38.911Z,1626192758.911 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1088
2021-07-13T16:12:38.915Z,1626192758.915 [Supervisor](INFO): Main Thread ID is 830
2021-07-13T16:12:38.915Z,1626192758.915 [Supervisor](DEBUG): Running supervisor.
2021-07-13T16:12:38.916Z,1626192758.916 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1089
2021-07-13T16:12:38.925Z,1626192758.925 [controlThread ThreadHandler](INFO): Handler Thread ID is 1090
2021-07-13T16:12:38.926Z,1626192758.926 [controlThread](DEBUG): Initializing ControlThread
2021-07-13T16:12:38.926Z,1626192758.926 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-07-13T16:12:38.928Z,1626192758.928 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-07-13T16:12:38.929Z,1626192758.929 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-07-13T16:12:38.929Z,1626192758.929 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-07-13T16:12:38.930Z,1626192758.930 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-07-13T16:12:38.931Z,1626192758.931 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-07-13T16:12:38.931Z,1626192758.931 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-07-13T16:12:38.931Z,1626192758.931 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-07-13T16:12:38.932Z,1626192758.932 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-07-13T16:12:38.932Z,1626192758.932 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-07-13T16:12:38.933Z,1626192758.933 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-07-13T16:12:38.934Z,1626192758.934 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-07-13T16:12:38.941Z,1626192758.941 [SBIT](INFO): Initialize SBIT Component.
2021-07-13T16:12:38.942Z,1626192758.942 [SBIT](IMPORTANT): git: 2021-07-01
2021-07-13T16:12:38.942Z,1626192758.942 [SBIT](INFO): git hash: b26188ebb415e504881e373e6767e998ee6759a7
2021-07-13T16:12:38.942Z,1626192758.942 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-07-13T16:12:38.943Z,1626192758.943 [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
2021-07-13T16:12:38.945Z,1626192758.945 [SBIT](INFO): Beginning SBIT in 72.000000 seconds.
2021-07-13T16:12:38.945Z,1626192758.945 [IBIT](INFO): Initialize IBIT Component.
2021-07-13T16:12:38.947Z,1626192758.947 [CBIT](DEBUG): Initialize CBIT Component.
2021-07-13T16:12:38.948Z,1626192758.948 [logger ThreadHandler](INFO): Handler Thread ID is 1091
2021-07-13T16:12:38.961Z,1626192758.961 [CBIT](DEBUG): Initialized mux pins.
2021-07-13T16:12:38.961Z,1626192758.961 [CBIT](DEBUG): Initializing the watchdog timer.
2021-07-13T16:12:38.981Z,1626192758.981 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1093
2021-07-13T16:12:38.982Z,1626192758.982 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-07-13T16:12:38.985Z,1626192758.985 [Onboard ThreadHandler](INFO): Handler Thread ID is 1094
2021-07-13T16:12:39.004Z,1626192759.003 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1092
2021-07-13T16:12:39.005Z,1626192759.005 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-07-13T16:12:39.006Z,1626192759.006 [CBIT](DEBUG): Initializing heartbeat.
2021-07-13T16:12:39.008Z,1626192759.008 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1095
2021-07-13T16:12:39.025Z,1626192759.025 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1096
2021-07-13T16:12:39.026Z,1626192759.026 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-07-13T16:12:39.030Z,1626192759.030 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1098
2021-07-13T16:12:39.033Z,1626192759.033 [WetLabsBB2FL](INFO): Powering up
2021-07-13T16:12:39.034Z,1626192759.034 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1100
2021-07-13T16:12:39.038Z,1626192759.038 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-07-13T16:12:39.038Z,1626192759.038 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-07-13T16:12:39.038Z,1626192759.038 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-07-13T16:12:39.038Z,1626192759.038 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-07-13T16:12:39.039Z,1626192759.039 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-07-13T16:12:39.039Z,1626192759.039 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-07-13T16:12:39.039Z,1626192759.039 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-07-13T16:12:39.039Z,1626192759.039 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-07-13T16:12:39.039Z,1626192759.039 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-07-13T16:12:39.040Z,1626192759.040 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-07-13T16:12:39.040Z,1626192759.040 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-07-13T16:12:39.040Z,1626192759.040 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-07-13T16:12:39.041Z,1626192759.041 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-07-13T16:12:39.041Z,1626192759.041 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-07-13T16:12:39.041Z,1626192759.041 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-07-13T16:12:39.042Z,1626192759.042 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-07-13T16:12:39.077Z,1626192759.077 [CBIT](DEBUG): Deactivating GF circuits.
2021-07-13T16:12:39.077Z,1626192759.077 [CBIT](DEBUG): Deactivating emergency mode.
2021-07-13T16:12:39.113Z,1626192759.113 [CBIT](DEBUG): Backplane powered.
2021-07-13T16:12:39.114Z,1626192759.114 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-07-13T16:12:39.132Z,1626192759.132 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-07-13T16:12:39.184Z,1626192759.184 [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>


2021-07-13T16:12:39.205Z,1626192759.205 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-07-13T16:12:39.308Z,1626192759.308 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-07-13T16:12:39.326Z,1626192759.326 [Default:A.Wait](DEBUG): Construct Wait.
2021-07-13T16:12:39.328Z,1626192759.328 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-07-13T16:12:39.398Z,1626192759.398 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-07-13T16:12:39.400Z,1626192759.400 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-07-13T16:12:39.419Z,1626192759.419 [Default:E.Execute](DEBUG): Construct Execute.
2021-07-13T16:12:39.422Z,1626192759.422 [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="P2H">
                <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>


2021-07-13T16:12:39.425Z,1626192759.425 [Radio_Surface](INFO): Powering up
2021-07-13T16:12:39.433Z,1626192759.433 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-07-13T16:12:39.453Z,1626192759.453 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-07-13T16:12:39.536Z,1626192759.536 [Power24vConverter](INFO): Powering up.
2021-07-13T16:12:39.582Z,1626192759.582 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-07-13T16:12:39.589Z,1626192759.589 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-07-13T16:12:39.590Z,1626192759.590 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-07-13T16:12:39.597Z,1626192759.597 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-07-13T16:12:39.598Z,1626192759.598 [MassServo](DEBUG): Initializing EZServoServo.
2021-07-13T16:12:39.605Z,1626192759.605 [MassServo](DEBUG): Initializing MassServo.
2021-07-13T16:12:39.606Z,1626192759.606 [RudderServo](DEBUG): Initializing EZServoServo.
2021-07-13T16:12:39.613Z,1626192759.613 [RudderServo](DEBUG): Initializing RudderServo.
2021-07-13T16:12:39.614Z,1626192759.614 [ThrusterHE](DEBUG): Initializing EZServoServo.
2021-07-13T16:12:39.621Z,1626192759.621 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2021-07-13T16:12:41.141Z,1626192761.141 [WetLabsBB2FL](INFO): Powering down
2021-07-13T16:13:07.323Z,1626192787.323 [NAL9602](INFO): Powering up NAL9602
2021-07-13T16:13:18.235Z,1626192798.235 [NAL9602](INFO): NAL9602 initialized
2021-07-13T16:13:51.381Z,1626192831.381 [SBIT](IMPORTANT): Beginning Startup BIT
2021-07-13T16:13:51.385Z,1626192831.385 [CBIT](IMPORTANT): Beginning ground fault scan
2021-07-13T16:13:53.039Z,1626192833.039 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:14:02.305Z,1626192842.305 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007082
CHAN A1 (24V): 0.000565
CHAN A2 (12V): -0.003939
CHAN A3 (5V): -0.002287
CHAN B0 (3.3V): -0.001488
CHAN B1 (3.15aV): -0.001302
CHAN B2 (3.15bV): -0.001862
CHAN B3 (GND): 0.000168
OPEN: 0.004818
Full Scale Calc: 4.765 mA, -1.589 mA
2021-07-13T16:14:06.305Z,1626192846.305 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:14:20.105Z,1626192860.105 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:14:33.788Z,1626192873.788 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:14:45.555Z,1626192885.555 [SBIT](IMPORTANT): SBIT PASSED
2021-07-13T16:14:45.555Z,1626192885.555 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-07-13T16:14:45.556Z,1626192885.556 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=8 count;

2021-07-13T16:14:45.556Z,1626192885.556 [SBIT](IMPORTANT): BPC1.batterySamplingInterval=1 minute;

2021-07-13T16:14:45.557Z,1626192885.557 [SBIT](IMPORTANT): BPC1.batteryStickCommsTimeout=10 second;

2021-07-13T16:14:45.557Z,1626192885.557 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-07-13T16:14:45.557Z,1626192885.557 [SBIT](IMPORTANT): CBIT.gf24Offset=-27 microampere;

2021-07-13T16:14:45.557Z,1626192885.557 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-07-13T16:14:45.557Z,1626192885.557 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2021-07-13T16:14:45.557Z,1626192885.557 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 2.000000 degree;

2021-07-13T16:14:45.558Z,1626192885.558 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=200 cubic_centimeter;

2021-07-13T16:14:45.558Z,1626192885.558 [SBIT](IMPORTANT): VerticalControl.massDefault=13 millimeter;

2021-07-13T16:14:45.931Z,1626192885.931 [MissionManager](IMPORTANT): Started mission Startup
2021-07-13T16:14:45.932Z,1626192885.932 [Startup] Running Loop=1
2021-07-13T16:14:45.932Z,1626192885.932 [Startup](DEBUG): Aggregate::initialize Startup
2021-07-13T16:14:45.932Z,1626192885.932 [Startup:A.GoToSurface] Running Loop=1
2021-07-13T16:14:45.932Z,1626192885.932 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-13T16:14:45.933Z,1626192885.933 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-13T16:14:45.934Z,1626192885.934 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-13T16:14:45.934Z,1626192885.934 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-13T16:14:45.934Z,1626192885.934 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-13T16:14:45.935Z,1626192885.935 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-13T16:14:45.936Z,1626192885.936 [Startup:StartupSatComms] Running Loop=1
2021-07-13T16:14:45.936Z,1626192885.936 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-07-13T16:14:45.937Z,1626192885.937 [Startup:StartupSatComms:A] Running Loop=1
2021-07-13T16:14:46.316Z,1626192886.316 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-07-13T16:14:47.120Z,1626192887.120 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:15:00.447Z,1626192900.447 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:15:06.771Z,1626192906.771 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004615
2021-07-13T16:15:14.191Z,1626192914.191 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:15:27.516Z,1626192927.516 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:15:39.310Z,1626192939.310 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-07-13T16:15:39.310Z,1626192939.310 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:15:39.381Z,1626192939.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:15:39.729Z,1626192939.729 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:15:39.729Z,1626192939.729 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-07-13T16:15:46.204Z,1626192946.204 [Startup:StartupSatComms:A](INFO): Timed out from 2021-07-13T16:14:45.9Z
2021-07-13T16:15:46.204Z,1626192946.204 [Startup:StartupSatComms:A] Stopped
2021-07-13T16:15:46.204Z,1626192946.204 [Startup:StartupSatComms:B] Running Loop=1
2021-07-13T16:15:46.603Z,1626192946.603 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-07-13T16:15:53.076Z,1626192953.076 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210713T160225/Courier0007.lzma
2021-07-13T16:15:54.079Z,1626192954.079 [DataOverHttps](INFO): Moved sent file to Logs/20210713T160225/Courier0007.lzma.bak
2021-07-13T16:15:54.079Z,1626192954.079 [DataOverHttps](INFO): SBD MOMSN=15746382
2021-07-13T16:16:09.777Z,1626192969.777 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210713T161229/Courier0000.lzma
2021-07-13T16:16:10.778Z,1626192970.778 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Courier0000.lzma.bak
2021-07-13T16:16:10.779Z,1626192970.779 [DataOverHttps](INFO): SBD MOMSN=15746384
2021-07-13T16:16:26.220Z,1626192986.220 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20210713T160225/Express0008.lzma
2021-07-13T16:16:27.222Z,1626192987.222 [DataOverHttps](INFO): Moved sent file to Logs/20210713T160225/Express0008.lzma.bak
2021-07-13T16:16:27.223Z,1626192987.223 [DataOverHttps](INFO): SBD MOMSN=15746387
2021-07-13T16:16:42.794Z,1626193002.794 [DataOverHttps](INFO): Sending 952 bytes from file Logs/20210713T161229/Express0001.lzma
2021-07-13T16:16:43.794Z,1626193003.794 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Express0001.lzma.bak
2021-07-13T16:16:43.794Z,1626193003.794 [DataOverHttps](INFO): SBD MOMSN=15746390
2021-07-13T16:16:45.203Z,1626193005.203 [Startup:StartupSatComms:B] Stopped
2021-07-13T16:16:45.204Z,1626193005.204 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-07-13T16:16:45.204Z,1626193005.204 [Startup:StartupSatComms] Stopped
2021-07-13T16:16:45.204Z,1626193005.204 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-07-13T16:16:45.205Z,1626193005.205 [Startup](INFO): Completed Startup
2021-07-13T16:16:45.205Z,1626193005.205 [MissionManager](INFO): Startup is completed.
2021-07-13T16:16:45.205Z,1626193005.205 [MissionManager](INFO): Uninitializing Mission Startup
2021-07-13T16:16:45.205Z,1626193005.205 [Startup] Stopped
2021-07-13T16:16:45.206Z,1626193005.206 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-07-13T16:16:45.206Z,1626193005.206 [Startup:A.GoToSurface] Stopped
2021-07-13T16:16:45.206Z,1626193005.206 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-13T16:16:45.615Z,1626193005.615 [MissionManager](IMPORTANT): Started mission Default
2021-07-13T16:16:45.615Z,1626193005.615 [Default] Running Loop=1
2021-07-13T16:16:45.615Z,1626193005.615 [Default](DEBUG): Aggregate::initialize Default
2021-07-13T16:16:45.615Z,1626193005.615 [Default:B.GoToSurface] Running Loop=1
2021-07-13T16:16:45.615Z,1626193005.615 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-13T16:16:45.616Z,1626193005.616 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-13T16:16:45.616Z,1626193005.616 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-13T16:16:45.616Z,1626193005.616 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-13T16:16:45.617Z,1626193005.617 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-13T16:16:45.617Z,1626193005.617 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-13T16:16:45.618Z,1626193005.618 [Default:A.Wait] Running Loop=1
2021-07-13T16:16:45.618Z,1626193005.618 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-07-13T16:16:52.439Z,1626193012.439 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute
2021-07-13T16:16:52.451Z,1626193012.451 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2021-07-13T16:16:52.514Z,1626193012.514 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-07-13T16:16:58.920Z,1626193018.920 [Default:A.Wait](INFO): Done Waiting.
2021-07-13T16:16:58.920Z,1626193018.920 [Default:A.Wait] Stopped
2021-07-13T16:16:58.920Z,1626193018.920 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-07-13T16:16:59.325Z,1626193019.325 [Default:CheckIn] Running Loop=1
2021-07-13T16:16:59.325Z,1626193019.325 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-13T16:16:59.325Z,1626193019.325 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-13T16:16:59.731Z,1626193019.731 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-07-13T16:17:00.144Z,1626193020.144 [CommandLine](IMPORTANT): got command maintain clear
2021-07-13T16:17:00.175Z,1626193020.175 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-07-13T16:18:03.080Z,1626193083.080 [CommandLine](IMPORTANT): got command ibit
2021-07-13T16:18:03.161Z,1626193083.161 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-07-13T16:18:03.161Z,1626193083.161 [IBIT](IMPORTANT): Beginning control surface checks.
2021-07-13T16:18:03.165Z,1626193083.165 [CBIT](IMPORTANT): Beginning ground fault scan
2021-07-13T16:18:14.161Z,1626193094.161 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006515
CHAN A1 (24V): -0.000200
CHAN A2 (12V): -0.003852
CHAN A3 (5V): -0.002546
CHAN B0 (3.3V): -0.000901
CHAN B1 (3.15aV): -0.001291
CHAN B2 (3.15bV): -0.001417
CHAN B3 (GND): -0.000368
OPEN: 0.004629
Full Scale Calc: 4.765 mA, -1.589 mA
2021-07-13T16:18:21.326Z,1626193101.326 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-13T16:18:40.347Z,1626193120.347 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-07-13T16:18:40.347Z,1626193120.347 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:18:40.422Z,1626193120.422 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:18:40.799Z,1626193120.799 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:18:40.799Z,1626193120.799 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-07-13T16:19:54.293Z,1626193194.293 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2021-07-13T16:19:54.685Z,1626193194.685 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2021-07-13T16:19:55.097Z,1626193195.097 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2021-07-13T16:19:55.098Z,1626193195.098 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2021-07-13T16:19:55.098Z,1626193195.098 [IBIT](IMPORTANT): Pressure:9.781490 PSI
2021-07-13T16:19:55.098Z,1626193195.098 [IBIT](IMPORTANT): Humidity:12.361128 %
2021-07-13T16:19:55.477Z,1626193195.477 [IBIT](IMPORTANT): Vehicle Pitch:-1.174591 degrees
2021-07-13T16:19:55.477Z,1626193195.477 [IBIT](IMPORTANT): Vehicle  Roll:3.214893 degrees
2021-07-13T16:19:55.478Z,1626193195.478 [IBIT](IMPORTANT): Vehicle  Heading:259.369080 degrees
2021-07-13T16:19:55.880Z,1626193195.880 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2021-07-13T16:19:55.880Z,1626193195.880 [IBIT](IMPORTANT): buoyancyNeutral: 200.000000 cc
2021-07-13T16:19:55.881Z,1626193195.881 [IBIT](IMPORTANT): massDefault: 1.300000 cm
2021-07-13T16:19:55.881Z,1626193195.881 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2021-07-13T16:19:55.882Z,1626193195.882 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2021-07-13T16:19:55.882Z,1626193195.882 [IBIT](IMPORTANT): IBIT FAILED
2021-07-13T16:20:35.057Z,1626193235.057 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2021-07-13T16:20:35.060Z,1626193235.060 [BPC1](INFO): Received data from all battery sticks.
2021-07-13T16:21:41.319Z,1626193301.319 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-07-13T16:21:41.319Z,1626193301.319 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:21:41.330Z,1626193301.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:21:41.742Z,1626193301.742 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:21:41.742Z,1626193301.742 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-07-13T16:21:59.516Z,1626193319.516 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-07-13T16:16:59.3Z
2021-07-13T16:21:59.516Z,1626193319.516 [Default:CheckIn:Read_GPS] Stopped
2021-07-13T16:21:59.516Z,1626193319.516 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-13T16:21:59.906Z,1626193319.906 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-07-13T16:22:06.516Z,1626193326.516 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20210713T161229/Courier0004.lzma
2021-07-13T16:22:07.518Z,1626193327.518 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Courier0004.lzma.bak
2021-07-13T16:22:07.519Z,1626193327.519 [DataOverHttps](INFO): SBD MOMSN=15746411
2021-07-13T16:22:22.806Z,1626193342.806 [DataOverHttps](INFO): Sending 859 bytes from file Logs/20210713T161229/Express0005.lzma
2021-07-13T16:22:23.806Z,1626193343.806 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Express0005.lzma.bak
2021-07-13T16:22:23.807Z,1626193343.807 [DataOverHttps](INFO): SBD MOMSN=15746413
2021-07-13T16:22:24.955Z,1626193344.955 [Default:CheckIn:Read_Iridium] Stopped
2021-07-13T16:22:24.955Z,1626193344.955 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-13T16:22:24.955Z,1626193344.955 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-13T16:22:40.305Z,1626193360.305 [Power24vConverter](INFO): Powering down.
2021-07-13T16:22:49.954Z,1626193369.954 [CommandLine](IMPORTANT): got command ibit
2021-07-13T16:22:50.039Z,1626193370.039 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-07-13T16:22:50.039Z,1626193370.039 [IBIT](IMPORTANT): Beginning control surface checks.
2021-07-13T16:22:50.046Z,1626193370.046 [CBIT](IMPORTANT): Beginning ground fault scan
2021-07-13T16:23:00.920Z,1626193380.920 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005824
CHAN A1 (24V): -0.000073
CHAN A2 (12V): -0.005082
CHAN A3 (5V): -0.003452
CHAN B0 (3.3V): -0.001516
CHAN B1 (3.15aV): -0.001547
CHAN B2 (3.15bV): -0.001623
CHAN B3 (GND): -0.000145
OPEN: 0.004423
Full Scale Calc: 4.765 mA, -1.589 mA
2021-07-13T16:23:20.286Z,1626193400.286 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-07-13T16:23:20.287Z,1626193400.287 [NAL9602] Data Fault, FailCount= 1
2021-07-13T16:23:20.287Z,1626193400.287 [NAL9602](ERROR): Data Fault
2021-07-13T16:23:20.304Z,1626193400.304 [CBIT](ERROR): Data Fault in component: NAL9602
2021-07-13T16:23:20.694Z,1626193400.694 [NAL9602](INFO): Powering down
2021-07-13T16:23:21.519Z,1626193401.519 [CBIT](INFO): Clearing failed state for component NAL9602
2021-07-13T16:23:21.519Z,1626193401.519 [NAL9602] No Fault, FailCount= 1
2021-07-13T16:23:50.991Z,1626193430.991 [NAL9602](INFO): Powering up NAL9602
2021-07-13T16:24:01.902Z,1626193441.902 [NAL9602](INFO): NAL9602 initialized
2021-07-13T16:24:41.124Z,1626193481.124 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2021-07-13T16:24:41.512Z,1626193481.512 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.532000
2021-07-13T16:24:41.512Z,1626193481.512 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2021-07-13T16:24:41.513Z,1626193481.513 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2021-07-13T16:24:41.929Z,1626193481.929 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2021-07-13T16:24:41.930Z,1626193481.930 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2021-07-13T16:24:41.930Z,1626193481.930 [IBIT](IMPORTANT): Pressure:9.781490 PSI
2021-07-13T16:24:41.930Z,1626193481.930 [IBIT](IMPORTANT): Humidity:12.361128 %
2021-07-13T16:24:42.319Z,1626193482.319 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-07-13T16:24:42.319Z,1626193482.319 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:24:42.327Z,1626193482.327 [IBIT](IMPORTANT): Vehicle Pitch:-1.185282 degrees
2021-07-13T16:24:42.327Z,1626193482.327 [IBIT](IMPORTANT): Vehicle  Roll:3.216603 degrees
2021-07-13T16:24:42.327Z,1626193482.327 [IBIT](IMPORTANT): Vehicle  Heading:259.361877 degrees
2021-07-13T16:24:42.330Z,1626193482.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:24:42.744Z,1626193482.744 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2021-07-13T16:24:42.745Z,1626193482.745 [IBIT](IMPORTANT): buoyancyNeutral: 200.000000 cc
2021-07-13T16:24:42.745Z,1626193482.745 [IBIT](IMPORTANT): massDefault: 1.300000 cm
2021-07-13T16:24:42.745Z,1626193482.745 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2021-07-13T16:24:42.746Z,1626193482.746 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2021-07-13T16:24:42.746Z,1626193482.746 [IBIT](IMPORTANT): IBIT FAILED
2021-07-13T16:24:42.749Z,1626193482.749 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:24:42.749Z,1626193482.749 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-07-13T16:25:45.079Z,1626193545.079 [CommandLine](IMPORTANT): got command burn on
2021-07-13T16:25:45.079Z,1626193545.079 [CommandLine](IMPORTANT): Activating dropweight wire
2021-07-13T16:27:25.526Z,1626193645.526 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-13T16:27:25.527Z,1626193645.527 [Default:CheckIn:C.Wait] Stopped
2021-07-13T16:27:25.527Z,1626193645.527 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-13T16:27:25.527Z,1626193645.527 [Default:CheckIn:D] Running Loop=1
2021-07-13T16:27:25.940Z,1626193645.940 [Default:CheckIn:D] Stopped
2021-07-13T16:27:25.940Z,1626193645.940 [Default:CheckIn:E] Running Loop=1
2021-07-13T16:27:26.332Z,1626193646.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672075 min 
2021-07-13T16:27:26.349Z,1626193646.349 [Default:CheckIn:E] Stopped
2021-07-13T16:27:26.349Z,1626193646.349 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-13T16:27:26.349Z,1626193646.349 [Default:CheckIn] Stopped
2021-07-13T16:27:26.349Z,1626193646.349 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-13T16:27:26.349Z,1626193646.349 [Default:CheckIn](INFO): Running loop #2
2021-07-13T16:27:26.349Z,1626193646.349 [Default:CheckIn] Running Loop=2
2021-07-13T16:27:26.349Z,1626193646.349 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-13T16:27:26.350Z,1626193646.350 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-13T16:27:43.315Z,1626193663.315 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-07-13T16:27:43.315Z,1626193663.315 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:27:43.326Z,1626193663.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:27:43.732Z,1626193663.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:27:43.737Z,1626193663.737 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-07-13T16:30:44.297Z,1626193844.297 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-07-13T16:30:44.297Z,1626193844.297 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:30:44.308Z,1626193844.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:30:44.720Z,1626193844.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:30:44.720Z,1626193844.720 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-07-13T16:31:11.057Z,1626193871.057 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.387260
2021-07-13T16:31:21.237Z,1626193881.237 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002773
2021-07-13T16:32:26.506Z,1626193946.506 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-07-13T16:27:26.3Z
2021-07-13T16:32:26.506Z,1626193946.506 [Default:CheckIn:Read_GPS] Stopped
2021-07-13T16:32:26.506Z,1626193946.506 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-13T16:32:31.044Z,1626193951.044 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210713T161229/Courier0007.lzma
2021-07-13T16:32:32.046Z,1626193952.046 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Courier0007.lzma.bak
2021-07-13T16:32:32.047Z,1626193952.047 [DataOverHttps](INFO): SBD MOMSN=15746439
2021-07-13T16:32:53.066Z,1626193973.066 [DataOverHttps](INFO): Sending 811 bytes from file Logs/20210713T161229/Express0008.lzma
2021-07-13T16:32:54.066Z,1626193974.066 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Express0008.lzma.bak
2021-07-13T16:32:54.067Z,1626193974.067 [DataOverHttps](INFO): SBD MOMSN=15746441
2021-07-13T16:32:55.438Z,1626193975.438 [Default:CheckIn:Read_Iridium] Stopped
2021-07-13T16:32:55.438Z,1626193975.438 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-13T16:32:55.438Z,1626193975.438 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-13T16:33:04.767Z,1626193984.767 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:33:18.095Z,1626193998.095 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:33:31.360Z,1626194011.360 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:33:44.689Z,1626194024.689 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:33:45.192Z,1626194025.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-07-13T16:33:45.193Z,1626194025.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:33:45.204Z,1626194025.204 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:33:45.549Z,1626194025.549 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:33:45.549Z,1626194025.549 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-07-13T16:33:58.493Z,1626194038.493 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:34:04.091Z,1626194044.091 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-07-13T16:34:04.091Z,1626194044.091 [NAL9602] Data Fault, FailCount= 2
2021-07-13T16:34:04.091Z,1626194044.091 [NAL9602](ERROR): Data Fault
2021-07-13T16:34:04.109Z,1626194044.109 [CBIT](ERROR): Data Fault in component: NAL9602
2021-07-13T16:34:04.490Z,1626194044.490 [NAL9602](INFO): Powering down
2021-07-13T16:34:05.319Z,1626194045.319 [CBIT](INFO): Clearing failed state for component NAL9602
2021-07-13T16:34:05.319Z,1626194045.319 [NAL9602] No Fault, FailCount= 2
2021-07-13T16:34:12.208Z,1626194052.208 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:34:25.519Z,1626194065.519 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:34:34.803Z,1626194074.803 [NAL9602](INFO): Powering up NAL9602
2021-07-13T16:34:38.449Z,1626194078.449 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:34:45.734Z,1626194085.734 [NAL9602](INFO): NAL9602 initialized
2021-07-13T16:34:52.227Z,1626194092.227 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:35:05.564Z,1626194105.564 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:35:18.891Z,1626194118.891 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-13T16:36:46.175Z,1626194206.175 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-07-13T16:36:46.175Z,1626194206.175 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:36:46.190Z,1626194206.190 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:36:46.613Z,1626194206.613 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:36:46.613Z,1626194206.613 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-07-13T16:37:36.209Z,1626194256.209 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247204
2021-07-13T16:37:56.079Z,1626194276.079 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-13T16:37:56.080Z,1626194276.080 [Default:CheckIn:C.Wait] Stopped
2021-07-13T16:37:56.080Z,1626194276.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-13T16:37:56.080Z,1626194276.080 [Default:CheckIn:D] Running Loop=1
2021-07-13T16:37:56.488Z,1626194276.488 [Default:CheckIn:D] Stopped
2021-07-13T16:37:56.488Z,1626194276.488 [Default:CheckIn:E] Running Loop=1
2021-07-13T16:37:56.905Z,1626194276.905 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.181211 min 
2021-07-13T16:37:56.905Z,1626194276.905 [Default:CheckIn:E] Stopped
2021-07-13T16:37:56.905Z,1626194276.905 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-13T16:37:56.905Z,1626194276.905 [Default:CheckIn] Stopped
2021-07-13T16:37:56.906Z,1626194276.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-13T16:37:56.906Z,1626194276.906 [Default:CheckIn](INFO): Running loop #3
2021-07-13T16:37:56.906Z,1626194276.906 [Default:CheckIn] Running Loop=3
2021-07-13T16:37:56.906Z,1626194276.906 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-13T16:37:56.906Z,1626194276.906 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-13T16:37:58.896Z,1626194278.896 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2021-07-13T16:37:58.899Z,1626194278.899 [BPC1](INFO): Received data from all battery sticks.
2021-07-13T16:38:08.337Z,1626194288.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:38:43.485Z,1626194323.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:39:18.641Z,1626194358.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:39:47.161Z,1626194387.161 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-07-13T16:39:47.161Z,1626194387.161 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:39:47.204Z,1626194387.204 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:39:47.575Z,1626194387.575 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:39:47.575Z,1626194387.575 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-07-13T16:39:53.781Z,1626194393.781 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:40:28.929Z,1626194428.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:41:04.077Z,1626194464.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:41:39.225Z,1626194499.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:42:14.381Z,1626194534.381 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:42:48.147Z,1626194568.147 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-07-13T16:42:48.147Z,1626194568.147 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:42:48.170Z,1626194568.170 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:42:48.568Z,1626194568.568 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:42:48.569Z,1626194568.569 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-07-13T16:42:49.521Z,1626194569.521 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:42:57.042Z,1626194577.042 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-07-13T16:37:56.9Z
2021-07-13T16:42:57.043Z,1626194577.043 [Default:CheckIn:Read_GPS] Stopped
2021-07-13T16:42:57.043Z,1626194577.043 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-13T16:43:16.876Z,1626194596.876 [NAL9602](INFO): SBD MO Status=2, MOMSN=4942, MT Status=2, MTMSN=0
2021-07-13T16:43:16.876Z,1626194596.876 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-13T16:43:24.661Z,1626194604.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:43:59.801Z,1626194639.801 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:44:09.032Z,1626194649.032 [NAL9602](INFO): SBD MO Status=2, MOMSN=4942, MT Status=2, MTMSN=0
2021-07-13T16:44:09.032Z,1626194649.032 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-13T16:44:34.941Z,1626194674.941 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-13T16:44:35.344Z,1626194675.344 [NAL9602](INFO): SBD MO Status=2, MOMSN=4942, MT Status=2, MTMSN=0
2021-07-13T16:44:35.344Z,1626194675.344 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-13T16:44:47.050Z,1626194687.050 [DataOverHttps](IMPORTANT): SBD MTMSN=20210713T164446
2021-07-13T16:44:47.053Z,1626194687.053 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003156
2021-07-13T16:44:54.184Z,1626194694.184 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20210713T161229/Courier0010.lzma
2021-07-13T16:44:54.187Z,1626194694.187 [DataOverHttps](INFO): Received command:burn off
2021-07-13T16:44:54.239Z,1626194694.239 [CommandLine](IMPORTANT): got command burn off
2021-07-13T16:44:54.239Z,1626194694.239 [CommandLine](IMPORTANT): Deactivating dropweight wire
2021-07-13T16:44:55.411Z,1626194695.411 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Courier0010.lzma.bak
2021-07-13T16:44:55.411Z,1626194695.411 [DataOverHttps](INFO): SBD MOMSN=15746470
2021-07-13T16:45:01.288Z,1626194701.288 [NAL9602](INFO): SBD MO Status=2, MOMSN=4942, MT Status=2, MTMSN=0
2021-07-13T16:45:01.288Z,1626194701.288 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-13T16:45:02.092Z,1626194702.092 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-07-13T16:45:02.092Z,1626194702.092 [NAL9602] Data Fault, FailCount= 3
2021-07-13T16:45:02.092Z,1626194702.092 [NAL9602](ERROR): Data Fault
2021-07-13T16:45:02.110Z,1626194702.110 [CBIT](ERROR): Data Fault in component: NAL9602
2021-07-13T16:45:02.504Z,1626194702.504 [NAL9602](INFO): Powering down
2021-07-13T16:45:03.324Z,1626194703.324 [CBIT](INFO): Clearing failed state for component NAL9602
2021-07-13T16:45:03.324Z,1626194703.324 [NAL9602] No Fault, FailCount= 3
2021-07-13T16:45:10.520Z,1626194710.520 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210713T161229/Courier0013.lzma
2021-07-13T16:45:11.522Z,1626194711.522 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Courier0013.lzma.bak
2021-07-13T16:45:11.523Z,1626194711.523 [DataOverHttps](INFO): SBD MOMSN=15746473
2021-07-13T16:45:27.020Z,1626194727.020 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20210713T161229/Express0011.lzma
2021-07-13T16:45:28.023Z,1626194728.023 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Express0011.lzma.bak
2021-07-13T16:45:28.023Z,1626194728.023 [DataOverHttps](INFO): SBD MOMSN=15746475
2021-07-13T16:45:32.834Z,1626194732.834 [NAL9602](INFO): Powering up NAL9602
2021-07-13T16:45:43.730Z,1626194743.730 [NAL9602](INFO): NAL9602 initialized
2021-07-13T16:45:45.397Z,1626194745.397 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20210713T161229/Express0014.lzma
2021-07-13T16:45:46.398Z,1626194746.398 [DataOverHttps](INFO): Moved sent file to Logs/20210713T161229/Express0014.lzma.bak
2021-07-13T16:45:46.399Z,1626194746.399 [DataOverHttps](INFO): SBD MOMSN=15746479
2021-07-13T16:45:47.795Z,1626194747.795 [Default:CheckIn:Read_Iridium] Stopped
2021-07-13T16:45:47.795Z,1626194747.795 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-13T16:45:47.795Z,1626194747.795 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-13T16:45:48.990Z,1626194748.990 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-07-13T16:45:48.990Z,1626194748.990 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-13T16:45:49.030Z,1626194749.030 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-13T16:45:49.407Z,1626194749.407 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-13T16:45:49.407Z,1626194749.407 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-07-13T16:46:10.750Z,1626194770.750 [CommandLine](IMPORTANT): got command burn off
2021-07-13T16:46:10.750Z,1626194770.750 [CommandLine](IMPORTANT): Deactivating dropweight wire
2021-07-13T16:46:13.087Z,1626194773.087 [CommandLine](IMPORTANT): got command ibit
2021-07-13T16:46:13.242Z,1626194773.242 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-07-13T16:46:13.242Z,1626194773.242 [IBIT](IMPORTANT): Beginning control surface checks.
2021-07-13T16:46:13.245Z,1626194773.245 [CBIT](IMPORTANT): Beginning ground fault scan
2021-07-13T16:46:19.062Z,1626194779.062 [CommandLine](IMPORTANT): got command exit
2021-07-13T16:46:20.069Z,1626194780.069 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2021-07-13T16:46:20.069Z,1626194780.069 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:20.069Z,1626194780.069 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:20.165Z,1626194780.165 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-07-13T16:46:20.165Z,1626194780.165 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:20.165Z,1626194780.165 [CommandLine](INFO): Join timeout helper Thread ID is 1184
2021-07-13T16:46:20.166Z,1626194780.166 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-07-13T16:46:20.166Z,1626194780.166 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:20.167Z,1626194780.167 [NavChartDb](INFO): Join timeout helper Thread ID is 1185
2021-07-13T16:46:20.201Z,1626194780.201 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:20.201Z,1626194780.201 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:20.213Z,1626194780.213 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-07-13T16:46:20.213Z,1626194780.213 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:20.213Z,1626194780.213 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1186
2021-07-13T16:46:20.486Z,1626194780.486 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:20.486Z,1626194780.486 [WetLabsBB2FL](INFO): Powering down
2021-07-13T16:46:20.487Z,1626194780.487 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:20.493Z,1626194780.493 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-07-13T16:46:20.493Z,1626194780.493 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:20.493Z,1626194780.493 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1187
2021-07-13T16:46:20.637Z,1626194780.637 [CTD_Seabird](INFO): Powering down
2021-07-13T16:46:20.649Z,1626194780.649 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:20.649Z,1626194780.649 [CTD_Seabird](INFO): Powering down
2021-07-13T16:46:20.661Z,1626194780.661 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:20.662Z,1626194780.662 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-07-13T16:46:20.662Z,1626194780.662 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:20.662Z,1626194780.662 [Radio_Surface](INFO): Join timeout helper Thread ID is 1188
2021-07-13T16:46:20.985Z,1626194780.985 [Radio_Surface](INFO): Powering down
2021-07-13T16:46:20.986Z,1626194780.986 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:20.986Z,1626194780.986 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:21.002Z,1626194781.002 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-07-13T16:46:21.002Z,1626194781.002 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:21.002Z,1626194781.002 [Onboard](INFO): Join timeout helper Thread ID is 1189
2021-07-13T16:46:21.665Z,1626194781.665 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-07-13T16:46:24.163Z,1626194784.163 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007108
CHAN A1 (24V): 0.000245
CHAN A2 (12V): -0.005075
CHAN A3 (5V): -0.002823
CHAN B0 (3.3V): -0.001844
CHAN B1 (3.15aV): -0.000893
CHAN B2 (3.15bV): -0.000972
CHAN B3 (GND): -0.000188
OPEN: 0.004338
Full Scale Calc: 4.765 mA, -1.589 mA
2021-07-13T16:46:25.721Z,1626194785.721 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:25.721Z,1626194785.721 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:25.729Z,1626194785.729 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-07-13T16:46:25.729Z,1626194785.729 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:25.730Z,1626194785.730 [DataOverHttps](INFO): Join timeout helper Thread ID is 1190
2021-07-13T16:46:26.685Z,1626194786.685 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:26.685Z,1626194786.685 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:26.705Z,1626194786.705 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-07-13T16:46:26.705Z,1626194786.705 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:26.706Z,1626194786.706 [BackseatComponent](INFO): Join timeout helper Thread ID is 1191
2021-07-13T16:46:26.877Z,1626194786.877 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:26.877Z,1626194786.877 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:26.885Z,1626194786.885 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-07-13T16:46:26.885Z,1626194786.885 [logger ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:26.886Z,1626194786.886 [logger](INFO): Join timeout helper Thread ID is 1192
2021-07-13T16:46:26.901Z,1626194786.901 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:26.901Z,1626194786.901 [logger ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:26.905Z,1626194786.905 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-07-13T16:46:26.905Z,1626194786.905 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:26.906Z,1626194786.906 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-07-13T16:46:26.906Z,1626194786.906 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:26.906Z,1626194786.906 [controlThread](INFO): Join timeout helper Thread ID is 1193
2021-07-13T16:46:26.945Z,1626194786.945 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-13T16:46:26.945Z,1626194786.945 [controlThread](DEBUG): Uninitializing ControlThread
2021-07-13T16:46:26.946Z,1626194786.946 [AHRS_M2](INFO): Powering down
2021-07-13T16:46:27.018Z,1626194787.018 [NAL9602](INFO): Powering down
2021-07-13T16:46:27.019Z,1626194787.019 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-07-13T16:46:27.020Z,1626194787.020 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-07-13T16:46:27.021Z,1626194787.021 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-07-13T16:46:27.022Z,1626194787.022 [MissionManager](INFO): Uninitializing Mission Default
2021-07-13T16:46:27.022Z,1626194787.022 [Default] Stopped
2021-07-13T16:46:27.022Z,1626194787.022 [Default](DEBUG): Aggregate::uninitialize Default
2021-07-13T16:46:27.022Z,1626194787.022 [Default:B.GoToSurface] Stopped
2021-07-13T16:46:27.022Z,1626194787.022 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-13T16:46:27.022Z,1626194787.022 [Default:CheckIn] Stopped
2021-07-13T16:46:27.022Z,1626194787.022 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-13T16:46:27.022Z,1626194787.022 [Default:CheckIn:C.Wait] Stopped
2021-07-13T16:46:27.022Z,1626194787.022 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-13T16:46:27.026Z,1626194787.026 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-07-13T16:46:27.027Z,1626194787.027 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-07-13T16:46:27.027Z,1626194787.027 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-07-13T16:46:27.027Z,1626194787.027 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-07-13T16:46:27.028Z,1626194787.028 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-07-13T16:46:27.028Z,1626194787.028 [BuoyancyServo](INFO): Powering down
2021-07-13T16:46:27.041Z,1626194787.041 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-07-13T16:46:27.041Z,1626194787.041 [ElevatorServo](INFO): Powering down
2021-07-13T16:46:27.042Z,1626194787.042 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-07-13T16:46:27.042Z,1626194787.042 [MassServo](INFO): Powering down
2021-07-13T16:46:27.043Z,1626194787.043 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-07-13T16:46:27.043Z,1626194787.043 [RudderServo](INFO): Powering down
2021-07-13T16:46:27.044Z,1626194787.044 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2021-07-13T16:46:27.044Z,1626194787.044 [ThrusterHE](INFO): Powering down
2021-07-13T16:46:27.045Z,1626194787.045 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-07-13T16:46:27.046Z,1626194787.046 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-07-13T16:46:27.046Z,1626194787.046 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-07-13T16:46:27.046Z,1626194787.046 [CBIT](DEBUG): Powering off loads.
2021-07-13T16:46:27.056Z,1626194787.056 [CBIT](DEBUG): Disabling WDT.
2021-07-13T16:46:27.069Z,1626194787.069 [CBIT](DEBUG): Opening all GF detection circuits.
2021-07-13T16:46:27.070Z,1626194787.070 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:27.123Z,1626194787.123 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:27.126Z,1626194787.126 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:27.132Z,1626194787.132 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:27.177Z,1626194787.177 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:27.209Z,1626194787.209 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:27.213Z,1626194787.213 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:27.244Z,1626194787.244 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-07-13T16:46:27.310Z,1626194787.310 [logger ThreadHandler](INFO): Thread cancelled.