2021-02-09T16:19:10.125Z,1612887550.125 [Supervisor](DEBUG): Initializing supervisor.
2021-02-09T16:19:10.129Z,1612887550.129 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-02-09T16:19:10.130Z,1612887550.130 [SyncHandler](INFO): Protected caller Thread ID is 5660
2021-02-09T16:19:10.130Z,1612887550.130 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-02-09T16:19:10.131Z,1612887550.131 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-02-09T16:19:10.131Z,1612887550.131 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5661
2021-02-09T16:19:10.135Z,1612887550.135 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-02-09T16:19:10.154Z,1612887550.154 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-02-09T16:19:10.155Z,1612887550.155 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-02-09T16:19:10.155Z,1612887550.155 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5662
2021-02-09T16:19:10.157Z,1612887550.157 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-02-09T16:19:10.158Z,1612887550.158 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-02-09T16:19:10.158Z,1612887550.158 [logger ThreadHandler](INFO): Protected caller Thread ID is 5663
2021-02-09T16:19:10.162Z,1612887550.162 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-02-09T16:19:10.162Z,1612887550.162 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-02-09T16:19:10.164Z,1612887550.164 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-02-09T16:19:10.324Z,1612887550.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-02-09T16:19:10.325Z,1612887550.325 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-02-09T16:19:10.879Z,1612887550.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-02-09T16:19:10.879Z,1612887550.879 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-02-09T16:19:11.082Z,1612887551.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-02-09T16:19:11.082Z,1612887551.082 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-02-09T16:19:11.165Z,1612887551.165 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-02-09T16:19:11.288Z,1612887551.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-02-09T16:19:11.289Z,1612887551.289 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-02-09T16:19:11.373Z,1612887551.373 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-02-09T16:19:11.474Z,1612887551.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-02-09T16:19:11.475Z,1612887551.475 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-02-09T16:19:11.772Z,1612887551.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-02-09T16:19:11.772Z,1612887551.772 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-02-09T16:19:12.290Z,1612887552.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-02-09T16:19:12.291Z,1612887552.291 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-02-09T16:19:12.435Z,1612887552.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-02-09T16:19:12.435Z,1612887552.435 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-02-09T16:19:12.622Z,1612887552.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-02-09T16:19:12.623Z,1612887552.623 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-02-09T16:19:13.034Z,1612887553.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-02-09T16:19:13.035Z,1612887553.035 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-02-09T16:19:13.341Z,1612887553.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-02-09T16:19:13.342Z,1612887553.342 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-02-09T16:19:13.536Z,1612887553.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-02-09T16:19:13.537Z,1612887553.537 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-02-09T16:19:13.874Z,1612887553.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-02-09T16:19:13.874Z,1612887553.874 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-02-09T16:19:14.188Z,1612887554.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-02-09T16:19:14.190Z,1612887554.190 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2021-02-09T16:19:14.191Z,1612887554.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2021-02-09T16:19:14.275Z,1612887554.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2021-02-09T16:19:14.435Z,1612887554.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2021-02-09T16:19:14.542Z,1612887554.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2021-02-09T16:19:14.627Z,1612887554.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2021-02-09T16:19:14.722Z,1612887554.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2021-02-09T16:19:14.900Z,1612887554.900 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2021-02-09T16:19:15.190Z,1612887555.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-02-09T16:19:15.191Z,1612887555.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2021-02-09T16:19:15.380Z,1612887555.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2021-02-09T16:19:15.638Z,1612887555.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2021-02-09T16:19:15.926Z,1612887555.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2021-02-09T16:19:16.092Z,1612887556.092 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-02-09T16:19:16.094Z,1612887556.094 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-02-09T16:19:16.175Z,1612887556.175 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-02-09T16:19:16.175Z,1612887556.175 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-02-09T16:19:16.304Z,1612887556.304 [VerticalControl](DEBUG): Construct VerticalControl.
2021-02-09T16:19:16.362Z,1612887556.362 [VerticalControl] Loaded
2021-02-09T16:19:16.362Z,1612887556.362 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-02-09T16:19:16.365Z,1612887556.365 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-02-09T16:19:16.401Z,1612887556.401 [HorizontalControl] Loaded
2021-02-09T16:19:16.401Z,1612887556.401 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-02-09T16:19:16.404Z,1612887556.404 [SpeedControl](DEBUG): Construct SpeedControl.
2021-02-09T16:19:16.407Z,1612887556.407 [SpeedControl] Loaded
2021-02-09T16:19:16.407Z,1612887556.407 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-02-09T16:19:16.409Z,1612887556.409 [LoopControl](DEBUG): Construct LoopControl.
2021-02-09T16:19:16.410Z,1612887556.410 [LoopControl] Loaded
2021-02-09T16:19:16.410Z,1612887556.410 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-02-09T16:19:16.410Z,1612887556.410 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-02-09T16:19:16.411Z,1612887556.411 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-02-09T16:19:16.449Z,1612887556.449 [DepthRateCalculator] Loaded
2021-02-09T16:19:16.449Z,1612887556.449 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-02-09T16:19:16.454Z,1612887556.454 [PitchRateCalculator] Loaded
2021-02-09T16:19:16.454Z,1612887556.454 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-02-09T16:19:16.464Z,1612887556.464 [SpeedCalculator] Loaded
2021-02-09T16:19:16.464Z,1612887556.464 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-02-09T16:19:16.476Z,1612887556.476 [TempGradientCalculator] Loaded
2021-02-09T16:19:16.477Z,1612887556.477 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-02-09T16:19:16.481Z,1612887556.481 [YawRateCalculator] Loaded
2021-02-09T16:19:16.481Z,1612887556.481 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-02-09T16:19:16.497Z,1612887556.497 [ElevatorOffsetCalculator] Loaded
2021-02-09T16:19:16.497Z,1612887556.497 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-02-09T16:19:16.498Z,1612887556.498 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-02-09T16:19:16.498Z,1612887556.498 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-02-09T16:19:16.524Z,1612887556.524 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-02-09T16:19:16.525Z,1612887556.525 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-02-09T16:19:16.576Z,1612887556.576 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-02-09T16:19:16.577Z,1612887556.577 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-02-09T16:19:16.866Z,1612887556.866 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-02-09T16:19:16.866Z,1612887556.866 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-02-09T16:19:16.961Z,1612887556.961 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-02-09T16:19:16.961Z,1612887556.961 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-02-09T16:19:17.273Z,1612887557.273 [AHRS_M2] Loaded
2021-02-09T16:19:17.273Z,1612887557.273 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-02-09T16:19:17.302Z,1612887557.302 [BackseatComponent] Loaded
2021-02-09T16:19:17.302Z,1612887557.302 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-02-09T16:19:17.303Z,1612887557.303 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2021-02-09T16:19:17.303Z,1612887557.303 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5746
2021-02-09T16:19:17.306Z,1612887557.306 [LcmUniversalReporter] Loaded
2021-02-09T16:19:17.306Z,1612887557.306 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-02-09T16:19:18.058Z,1612887558.058 [BPC1] Loaded
2021-02-09T16:19:18.058Z,1612887558.058 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-02-09T16:19:18.130Z,1612887558.130 [DataOverHttps] Loaded
2021-02-09T16:19:18.131Z,1612887558.131 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-02-09T16:19:18.132Z,1612887558.132 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2021-02-09T16:19:18.132Z,1612887558.132 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5747
2021-02-09T16:19:18.151Z,1612887558.151 [Depth_Keller] Loaded
2021-02-09T16:19:18.151Z,1612887558.151 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-02-09T16:19:18.155Z,1612887558.155 [DropWeight] Loaded
2021-02-09T16:19:18.156Z,1612887558.156 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-02-09T16:19:18.211Z,1612887558.211 [NAL9602] Loaded
2021-02-09T16:19:18.212Z,1612887558.212 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-02-09T16:19:18.239Z,1612887558.239 [Onboard] Loaded
2021-02-09T16:19:18.239Z,1612887558.239 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-02-09T16:19:18.240Z,1612887558.240 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2021-02-09T16:19:18.241Z,1612887558.241 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5748
2021-02-09T16:19:18.253Z,1612887558.253 [Radio_Surface] Loaded
2021-02-09T16:19:18.254Z,1612887558.254 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-02-09T16:19:18.255Z,1612887558.255 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4098D4E0
2021-02-09T16:19:18.255Z,1612887558.255 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5749
2021-02-09T16:19:18.284Z,1612887558.284 [RDI_Pathfinder] Loaded
2021-02-09T16:19:18.284Z,1612887558.284 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2021-02-09T16:19:18.361Z,1612887558.361 [DAT] Loaded
2021-02-09T16:19:18.361Z,1612887558.361 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-02-09T16:19:18.362Z,1612887558.362 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-02-09T16:19:18.362Z,1612887558.362 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-02-09T16:19:18.436Z,1612887558.436 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-02-09T16:19:18.436Z,1612887558.436 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-02-09T16:19:18.449Z,1612887558.449 [NavChart] Loaded
2021-02-09T16:19:18.449Z,1612887558.449 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-02-09T16:19:18.454Z,1612887558.454 [UniversalFixResidualReporter] Loaded
2021-02-09T16:19:18.455Z,1612887558.455 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-02-09T16:19:18.455Z,1612887558.455 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-02-09T16:19:18.456Z,1612887558.456 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-02-09T16:19:18.588Z,1612887558.588 [SBIT](DEBUG): Construct Startup Built In Test.
2021-02-09T16:19:18.597Z,1612887558.597 [SBIT] Loaded
2021-02-09T16:19:18.597Z,1612887558.597 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-02-09T16:19:18.599Z,1612887558.599 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-02-09T16:19:18.612Z,1612887558.612 [IBIT] Loaded
2021-02-09T16:19:18.612Z,1612887558.612 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-02-09T16:19:18.618Z,1612887558.618 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-02-09T16:19:18.711Z,1612887558.711 [CBIT] Loaded
2021-02-09T16:19:18.712Z,1612887558.712 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-02-09T16:19:18.712Z,1612887558.712 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-02-09T16:19:18.713Z,1612887558.713 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-02-09T16:19:18.821Z,1612887558.821 [BuoyancyServo] Loaded
2021-02-09T16:19:18.821Z,1612887558.821 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-02-09T16:19:18.840Z,1612887558.840 [ElevatorServo] Loaded
2021-02-09T16:19:18.840Z,1612887558.840 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-02-09T16:19:18.858Z,1612887558.858 [MassServo] Loaded
2021-02-09T16:19:18.859Z,1612887558.859 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-02-09T16:19:18.875Z,1612887558.875 [RudderServo] Loaded
2021-02-09T16:19:18.876Z,1612887558.876 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-02-09T16:19:18.892Z,1612887558.892 [ThrusterServo] Loaded
2021-02-09T16:19:18.892Z,1612887558.892 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-02-09T16:19:18.893Z,1612887558.893 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-02-09T16:19:18.893Z,1612887558.893 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-02-09T16:19:18.904Z,1612887558.904 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-02-09T16:19:18.904Z,1612887558.904 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-02-09T16:19:19.067Z,1612887559.067 [CTD_Seabird] Loaded
2021-02-09T16:19:19.067Z,1612887559.067 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-02-09T16:19:19.068Z,1612887559.068 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABE4E0
2021-02-09T16:19:19.068Z,1612887559.068 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5750
2021-02-09T16:19:19.087Z,1612887559.087 [PAR_Licor] Loaded
2021-02-09T16:19:19.088Z,1612887559.088 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-02-09T16:19:19.119Z,1612887559.119 [WetLabsBB2FL] Loaded
2021-02-09T16:19:19.119Z,1612887559.119 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-02-09T16:19:19.120Z,1612887559.120 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEE4E0
2021-02-09T16:19:19.120Z,1612887559.120 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5751
2021-02-09T16:19:19.121Z,1612887559.121 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-02-09T16:19:19.127Z,1612887559.127 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-02-09T16:19:19.130Z,1612887559.130 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-02-09T16:19:19.140Z,1612887559.140 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-02-09T16:19:19.141Z,1612887559.141 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B1E4E0
2021-02-09T16:19:19.141Z,1612887559.141 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5752
2021-02-09T16:19:19.145Z,1612887559.145 [Supervisor](INFO): Main Thread ID is 5659
2021-02-09T16:19:19.145Z,1612887559.145 [Supervisor](DEBUG): Running supervisor.
2021-02-09T16:19:19.146Z,1612887559.146 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5753
2021-02-09T16:19:19.150Z,1612887559.150 [controlThread ThreadHandler](INFO): Handler Thread ID is 5754
2021-02-09T16:19:19.150Z,1612887559.150 [controlThread](DEBUG): Initializing ControlThread
2021-02-09T16:19:19.151Z,1612887559.151 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-02-09T16:19:19.153Z,1612887559.153 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-02-09T16:19:19.154Z,1612887559.154 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-02-09T16:19:19.154Z,1612887559.154 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-02-09T16:19:19.155Z,1612887559.155 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-02-09T16:19:19.155Z,1612887559.155 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-02-09T16:19:19.155Z,1612887559.155 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-02-09T16:19:19.156Z,1612887559.156 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-02-09T16:19:19.156Z,1612887559.156 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-02-09T16:19:19.157Z,1612887559.157 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-02-09T16:19:19.164Z,1612887559.164 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-02-09T16:19:19.164Z,1612887559.164 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-02-09T16:19:19.165Z,1612887559.165 [SBIT](INFO): Initialize SBIT Component.
2021-02-09T16:19:19.165Z,1612887559.165 [SBIT](IMPORTANT): git: 2021-02-08
2021-02-09T16:19:19.165Z,1612887559.165 [SBIT](INFO): git hash: 7b13ec93af056796ce784f1b1cde49b67c75fd8d
2021-02-09T16:19:19.166Z,1612887559.166 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-02-09T16:19:19.167Z,1612887559.167 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2021-02-09T16:19:19.168Z,1612887559.168 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2021-02-09T16:19:19.168Z,1612887559.168 [IBIT](INFO): Initialize IBIT Component.
2021-02-09T16:19:19.169Z,1612887559.169 [CBIT](DEBUG): Initialize CBIT Component.
2021-02-09T16:19:19.170Z,1612887559.170 [logger ThreadHandler](INFO): Handler Thread ID is 5755
2021-02-09T16:19:19.181Z,1612887559.181 [CBIT](DEBUG): Initialized mux pins.
2021-02-09T16:19:19.181Z,1612887559.181 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2021-02-09T16:19:19.182Z,1612887559.182 [CBIT](DEBUG): Initializing the watchdog timer.
2021-02-09T16:19:19.190Z,1612887559.190 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5756
2021-02-09T16:19:19.202Z,1612887559.202 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5757
2021-02-09T16:19:19.203Z,1612887559.203 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-02-09T16:19:19.205Z,1612887559.205 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-02-09T16:19:19.205Z,1612887559.205 [CBIT](DEBUG): Initializing heartbeat.
2021-02-09T16:19:19.214Z,1612887559.214 [Onboard ThreadHandler](INFO): Handler Thread ID is 5758
2021-02-09T16:19:19.231Z,1612887559.231 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5759
2021-02-09T16:19:19.254Z,1612887559.254 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5760
2021-02-09T16:19:19.255Z,1612887559.255 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-02-09T16:19:19.259Z,1612887559.259 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5762
2021-02-09T16:19:19.262Z,1612887559.262 [WetLabsBB2FL](INFO): Powering up
2021-02-09T16:19:19.263Z,1612887559.263 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5764
2021-02-09T16:19:19.266Z,1612887559.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-02-09T16:19:19.266Z,1612887559.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-02-09T16:19:19.266Z,1612887559.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-02-09T16:19:19.267Z,1612887559.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-02-09T16:19:19.267Z,1612887559.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-02-09T16:19:19.267Z,1612887559.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-02-09T16:19:19.267Z,1612887559.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-02-09T16:19:19.267Z,1612887559.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-02-09T16:19:19.267Z,1612887559.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-02-09T16:19:19.267Z,1612887559.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-02-09T16:19:19.268Z,1612887559.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-02-09T16:19:19.268Z,1612887559.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-02-09T16:19:19.268Z,1612887559.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-02-09T16:19:19.268Z,1612887559.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-02-09T16:19:19.268Z,1612887559.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-02-09T16:19:19.268Z,1612887559.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-02-09T16:19:19.277Z,1612887559.277 [CBIT](DEBUG): Deactivating GF circuits.
2021-02-09T16:19:19.277Z,1612887559.277 [CBIT](DEBUG): Deactivating emergency mode.
2021-02-09T16:19:19.313Z,1612887559.313 [CBIT](DEBUG): Backplane powered.
2021-02-09T16:19:19.318Z,1612887559.318 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-02-09T16:19:19.343Z,1612887559.343 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-09T16:19:19.379Z,1612887559.379 [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-02-09T16:19:19.380Z,1612887559.380 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-02-09T16:19:19.450Z,1612887559.450 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-02-09T16:19:19.451Z,1612887559.451 [Default:A.Wait](DEBUG): Construct Wait.
2021-02-09T16:19:19.453Z,1612887559.453 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-09T16:19:19.520Z,1612887559.520 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-02-09T16:19:19.523Z,1612887559.523 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-02-09T16:19:19.544Z,1612887559.544 [Default:E.Execute](DEBUG): Construct Execute.
2021-02-09T16:19:19.556Z,1612887559.556 [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-02-09T16:19:19.560Z,1612887559.560 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-02-09T16:19:19.573Z,1612887559.573 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-09T16:19:19.638Z,1612887559.638 [Radio_Surface](INFO): Powering up
2021-02-09T16:19:19.660Z,1612887559.660 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-02-09T16:19:19.661Z,1612887559.661 [DAT](INFO): Powering up
2021-02-09T16:19:19.661Z,1612887559.661 [DAT](DEBUG): Initializing DAT.
2021-02-09T16:19:19.751Z,1612887559.751 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-02-09T16:19:19.787Z,1612887559.787 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-02-09T16:19:19.794Z,1612887559.794 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-02-09T16:19:19.795Z,1612887559.795 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-02-09T16:19:19.806Z,1612887559.806 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-02-09T16:19:19.807Z,1612887559.807 [MassServo](DEBUG): Initializing EZServoServo.
2021-02-09T16:19:19.818Z,1612887559.818 [MassServo](DEBUG): Initializing MassServo.
2021-02-09T16:19:19.819Z,1612887559.819 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-09T16:19:19.830Z,1612887559.830 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-09T16:19:19.831Z,1612887559.831 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-02-09T16:19:19.842Z,1612887559.842 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-02-09T16:19:20.730Z,1612887560.730 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-02-09T16:19:20.730Z,1612887560.730 [RudderServo](FAULT): Rudder failed to initialize
2021-02-09T16:19:20.730Z,1612887560.730 [RudderServo] Communications Fault, FailCount= 1
2021-02-09T16:19:20.730Z,1612887560.730 [RudderServo](ERROR): Communications Fault
2021-02-09T16:19:20.834Z,1612887560.834 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-02-09T16:19:21.006Z,1612887561.006 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-09T16:19:21.007Z,1612887561.007 [RudderServo](INFO): Powering down
2021-02-09T16:19:21.682Z,1612887561.682 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-09T16:19:21.802Z,1612887561.802 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-09T16:19:21.806Z,1612887561.806 [CBIT](INFO): Clearing failed state for component RudderServo
2021-02-09T16:19:21.806Z,1612887561.806 [RudderServo] No Fault, FailCount= 1
2021-02-09T16:19:21.873Z,1612887561.873 [WetLabsBB2FL](INFO): Powering down
2021-02-09T16:19:33.276Z,1612887573.276 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005145
2021-02-09T16:19:48.387Z,1612887588.387 [NAL9602](INFO): Powering up NAL9602
2021-02-09T16:19:51.590Z,1612887591.590 [CommandLine](IMPORTANT): got command show variable stopDepth
2021-02-09T16:19:51.734Z,1612887591.734 [CommandLine](IMPORTANT): CBIT.stopDepth (meter)
2021-02-09T16:19:51.770Z,1612887591.770 [CommandLine](IMPORTANT): VerticalControl.stopDepthExcursion (meter)
2021-02-09T16:19:54.718Z,1612887594.718 [CommandLine](IMPORTANT): got command get CBIT.stopDepth
2021-02-09T16:19:54.718Z,1612887594.718 [CommandLine](IMPORTANT): CBIT.stopDepth 215.000000 m
2021-02-09T16:19:58.671Z,1612887598.671 [CommandLine](IMPORTANT): got command get CBIT.abortDepth
2021-02-09T16:19:58.671Z,1612887598.671 [CommandLine](IMPORTANT): CBIT.abortDepth 255.000000 m
2021-02-09T16:20:07.702Z,1612887607.702 [CommandLine](IMPORTANT): got command show variable neutral
2021-02-09T16:20:07.801Z,1612887607.801 [CommandLine](IMPORTANT): Config/Simulator.buoyancyNeutralOffset (cubic_centimeter)
2021-02-09T16:20:07.881Z,1612887607.881 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral (cubic_centimeter)
2021-02-09T16:20:08.612Z,1612887608.612 [SBIT](IMPORTANT): Beginning Startup BIT
2021-02-09T16:20:08.620Z,1612887608.620 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-09T16:20:08.991Z,1612887608.991 [NAL9602](ERROR): NAL9602 initialization error.
2021-02-09T16:20:08.991Z,1612887608.991 [NAL9602] Communications Fault, FailCount= 1
2021-02-09T16:20:08.991Z,1612887608.991 [NAL9602](ERROR): Communications Fault
2021-02-09T16:20:09.130Z,1612887609.130 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-02-09T16:20:09.395Z,1612887609.395 [NAL9602](INFO): Powering down
2021-02-09T16:20:10.415Z,1612887610.415 [CBIT](INFO): Clearing failed state for component NAL9602
2021-02-09T16:20:10.416Z,1612887610.416 [NAL9602] No Fault, FailCount= 1
2021-02-09T16:20:15.559Z,1612887615.559 [CommandLine](IMPORTANT): got command get VerticalControl.buoyancyNeutral
2021-02-09T16:20:15.560Z,1612887615.560 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral 225.000000 cc
2021-02-09T16:20:19.681Z,1612887619.681 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.017207
CHAN A1 (24V): 0.036172
CHAN A2 (12V): -0.007577
CHAN A3 (5V): -0.002725
CHAN B0 (3.3V): 0.000520
CHAN B1 (3.15aV): -0.000211
CHAN B2 (3.15bV): -0.000110
CHAN B3 (GND): 0.001133
OPEN: 0.005571
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-09T16:20:19.904Z,1612887619.904 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-02-09T16:20:19.904Z,1612887619.904 [DAT] Communications Fault, FailCount= 1
2021-02-09T16:20:19.904Z,1612887619.904 [DAT](ERROR): Communications Fault
2021-02-09T16:20:20.050Z,1612887620.050 [CBIT](ERROR): Communications Fault in component: DAT
2021-02-09T16:20:20.355Z,1612887620.355 [DAT](INFO): Powering down
2021-02-09T16:20:21.254Z,1612887621.254 [CBIT](INFO): Clearing failed state for component DAT
2021-02-09T16:20:21.254Z,1612887621.254 [DAT] No Fault, FailCount= 1
2021-02-09T16:20:23.546Z,1612887623.546 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-02-09T16:20:23.546Z,1612887623.546 [DAT](INFO): Powering up
2021-02-09T16:20:23.546Z,1612887623.546 [DAT](DEBUG): Initializing DAT.
2021-02-09T16:20:23.596Z,1612887623.596 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-02-09T16:20:30.061Z,1612887630.061 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:20:35.677Z,1612887635.677 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:20:37.681Z,1612887637.681 [DAT](INFO): commRate: 800
2021-02-09T16:20:39.700Z,1612887639.700 [NAL9602](INFO): Powering up NAL9602
2021-02-09T16:20:39.751Z,1612887639.751 [DAT](INFO): entering command mode
2021-02-09T16:20:40.116Z,1612887640.116 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:40.518Z,1612887640.518 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:40.936Z,1612887640.936 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:41.374Z,1612887641.374 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:41.799Z,1612887641.799 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:42.140Z,1612887642.140 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:42.553Z,1612887642.553 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:42.936Z,1612887642.936 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:43.119Z,1612887643.119 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault
2021-02-09T16:20:43.119Z,1612887643.119 [CommandLine](IMPORTANT): VerticalControl.massDefault 0.750000 cm
2021-02-09T16:20:43.347Z,1612887643.347 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:43.750Z,1612887643.750 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:44.151Z,1612887644.151 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:44.563Z,1612887644.563 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:44.952Z,1612887644.952 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:45.377Z,1612887645.377 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:45.760Z,1612887645.760 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:46.199Z,1612887646.199 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:46.637Z,1612887646.637 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:20:46.641Z,1612887646.641 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:46.988Z,1612887646.988 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:47.408Z,1612887647.408 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:47.783Z,1612887647.783 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:48.184Z,1612887648.184 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:48.605Z,1612887648.605 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:48.993Z,1612887648.993 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:49.400Z,1612887649.400 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:49.811Z,1612887649.811 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:50.222Z,1612887650.222 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:50.611Z,1612887650.611 [NAL9602](INFO): NAL9602 initialized
2021-02-09T16:20:50.612Z,1612887650.612 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:51.027Z,1612887651.027 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:51.441Z,1612887651.441 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:51.839Z,1612887651.839 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:52.251Z,1612887652.251 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:52.636Z,1612887652.636 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:53.033Z,1612887653.033 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:53.481Z,1612887653.481 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:53.858Z,1612887653.858 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:54.293Z,1612887654.293 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:54.648Z,1612887654.648 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:54.887Z,1612887654.887 [CommandLine](IMPORTANT): got command show variable limitFwd
2021-02-09T16:20:55.012Z,1612887655.012 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd (millimeter)
2021-02-09T16:20:55.299Z,1612887655.299 [DAT](DEBUG): checking for command mode acknowledgment
2021-02-09T16:20:55.299Z,1612887655.299 [DAT](FAULT): failed to enter command mode
2021-02-09T16:20:55.462Z,1612887655.462 [DAT](INFO): entering command mode
2021-02-09T16:20:55.880Z,1612887655.880 [DAT](INFO): setting verbose to 3
2021-02-09T16:20:56.309Z,1612887656.309 [DAT](INFO): set verbose to 3
2021-02-09T16:20:56.313Z,1612887656.313 [DAT](INFO): setting DatVerbose to 27440
2021-02-09T16:20:56.675Z,1612887656.675 [DAT](INFO): set DatVerbose to 27440
2021-02-09T16:20:56.675Z,1612887656.675 [DAT](INFO): setting transmit power to 8
2021-02-09T16:20:57.073Z,1612887657.073 [DAT](INFO): set transmit power to 8
2021-02-09T16:20:57.074Z,1612887657.074 [DAT](INFO): setting local address to 9
2021-02-09T16:20:57.572Z,1612887657.572 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:20:57.597Z,1612887657.597 [DAT](INFO): set local address to 9
2021-02-09T16:21:02.417Z,1612887662.417 [SBIT](IMPORTANT): SBIT PASSED
2021-02-09T16:21:02.417Z,1612887662.417 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2021-02-09T16:21:02.746Z,1612887662.746 [MissionManager](IMPORTANT): Started mission Startup
2021-02-09T16:21:02.747Z,1612887662.747 [Startup] Running Loop=1
2021-02-09T16:21:02.747Z,1612887662.747 [Startup](DEBUG): Aggregate::initialize Startup
2021-02-09T16:21:02.747Z,1612887662.747 [Startup:A.GoToSurface] Running Loop=1
2021-02-09T16:21:02.747Z,1612887662.747 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-09T16:21:02.748Z,1612887662.748 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-02-09T16:21:02.748Z,1612887662.748 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-02-09T16:21:02.748Z,1612887662.748 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-02-09T16:21:02.749Z,1612887662.749 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-09T16:21:02.757Z,1612887662.757 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-09T16:21:02.759Z,1612887662.759 [Startup:StartupSatComms] Running Loop=1
2021-02-09T16:21:02.759Z,1612887662.759 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-02-09T16:21:02.759Z,1612887662.759 [Startup:StartupSatComms:A] Running Loop=1
2021-02-09T16:21:03.210Z,1612887663.210 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-02-09T16:21:03.796Z,1612887663.796 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2021-02-09T16:21:03.797Z,1612887663.797 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 40.000000 mm
2021-02-09T16:21:08.536Z,1612887668.536 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:21:10.582Z,1612887670.582 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft
2021-02-09T16:21:10.582Z,1612887670.582 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -20.000000 mm
2021-02-09T16:21:19.292Z,1612887679.292 [CommandLine](IMPORTANT): got command configSet DAT.loadAtStartup 0.000000 bool persist
2021-02-09T16:21:19.292Z,1612887679.292 [CommandLine](IMPORTANT): configSet DAT.loadAtStartup requires a restart to take effect.
2021-02-09T16:21:19.293Z,1612887679.293 [CommandLine](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown.
2021-02-09T16:21:19.423Z,1612887679.423 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:21:30.671Z,1612887690.671 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:21:41.575Z,1612887701.575 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:21:52.487Z,1612887712.487 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:22:03.051Z,1612887723.051 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:22:03.082Z,1612887723.082 [Startup:StartupSatComms:A](INFO): Timed out from 2021-02-09T16:21:02.8Z
2021-02-09T16:22:03.082Z,1612887723.082 [Startup:StartupSatComms:A] Stopped
2021-02-09T16:22:03.082Z,1612887723.082 [Startup:StartupSatComms:B] Running Loop=1
2021-02-09T16:22:03.395Z,1612887723.395 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-02-09T16:22:14.438Z,1612887734.438 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:22:19.501Z,1612887739.501 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-02-09T16:22:19.501Z,1612887739.501 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-02-09T16:22:19.516Z,1612887739.516 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-02-09T16:22:19.948Z,1612887739.948 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-02-09T16:22:19.949Z,1612887739.949 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-02-09T16:22:24.855Z,1612887744.855 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:22:35.778Z,1612887755.778 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:22:46.215Z,1612887766.215 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:22:57.533Z,1612887777.533 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:22:58.933Z,1612887778.933 [CommandLine](IMPORTANT): got command strobe off
2021-02-09T16:22:58.934Z,1612887778.934 [CommandLine](IMPORTANT): Deactivating strobe
2021-02-09T16:23:01.469Z,1612887781.469 [CommandLine](IMPORTANT): got command gfscan
2021-02-09T16:23:01.546Z,1612887781.546 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-09T16:23:03.134Z,1612887783.134 [Startup:StartupSatComms:B](INFO): Timed out from 2021-02-09T16:22:03.1Z
2021-02-09T16:23:03.134Z,1612887783.134 [Startup:StartupSatComms:B] Stopped
2021-02-09T16:23:03.134Z,1612887783.134 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-02-09T16:23:03.134Z,1612887783.134 [Startup:StartupSatComms] Stopped
2021-02-09T16:23:03.134Z,1612887783.134 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-02-09T16:23:03.135Z,1612887783.135 [Startup](INFO): Completed Startup
2021-02-09T16:23:03.135Z,1612887783.135 [MissionManager](INFO): Startup is completed.
2021-02-09T16:23:03.135Z,1612887783.135 [MissionManager](INFO): Uninitializing Mission Startup
2021-02-09T16:23:03.135Z,1612887783.135 [Startup] Stopped
2021-02-09T16:23:03.136Z,1612887783.136 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-02-09T16:23:03.136Z,1612887783.136 [Startup:A.GoToSurface] Stopped
2021-02-09T16:23:03.136Z,1612887783.136 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-09T16:23:03.560Z,1612887783.560 [MissionManager](IMPORTANT): Started mission Default
2021-02-09T16:23:03.561Z,1612887783.561 [Default] Running Loop=1
2021-02-09T16:23:03.561Z,1612887783.561 [Default](DEBUG): Aggregate::initialize Default
2021-02-09T16:23:03.561Z,1612887783.561 [Default:B.GoToSurface] Running Loop=1
2021-02-09T16:23:03.561Z,1612887783.561 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-09T16:23:03.561Z,1612887783.561 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-02-09T16:23:03.562Z,1612887783.562 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-02-09T16:23:03.562Z,1612887783.562 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-02-09T16:23:03.562Z,1612887783.562 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-09T16:23:03.563Z,1612887783.563 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-09T16:23:03.563Z,1612887783.563 [Default:A.Wait] Running Loop=1
2021-02-09T16:23:03.563Z,1612887783.563 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-02-09T16:23:12.491Z,1612887792.491 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.014487
CHAN A1 (24V): 0.036088
CHAN A2 (12V): -0.007088
CHAN A3 (5V): -0.002651
CHAN B0 (3.3V): 0.000002
CHAN B1 (3.15aV): -0.000940
CHAN B2 (3.15bV): -0.000125
CHAN B3 (GND): 0.002624
OPEN: 0.005561
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-09T16:23:16.876Z,1612887796.876 [Default:A.Wait](INFO): Done Waiting.
2021-02-09T16:23:16.877Z,1612887796.877 [Default:A.Wait] Stopped
2021-02-09T16:23:16.877Z,1612887796.877 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-02-09T16:23:17.288Z,1612887797.288 [Default:CheckIn] Running Loop=1
2021-02-09T16:23:17.288Z,1612887797.288 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-09T16:23:17.289Z,1612887797.289 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-09T16:23:17.676Z,1612887797.676 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-02-09T16:23:22.125Z,1612887802.125 [CommandLine](IMPORTANT): got command gfscan
2021-02-09T16:23:22.151Z,1612887802.151 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-09T16:23:24.587Z,1612887804.587 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:23:25.395Z,1612887805.395 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-02-09T16:23:33.056Z,1612887813.056 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.012576
CHAN A1 (24V): 0.035722
CHAN A2 (12V): -0.007791
CHAN A3 (5V): -0.002604
CHAN B0 (3.3V): 0.000558
CHAN B1 (3.15aV): -0.000393
CHAN B2 (3.15bV): 0.000211
CHAN B3 (GND): 0.001865
OPEN: 0.005851
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-09T16:23:38.219Z,1612887818.219 [CommandLine](IMPORTANT): got command show variable offset
2021-02-09T16:23:38.296Z,1612887818.296 [CommandLine](IMPORTANT): Depth_Keller.offset (decibar)
2021-02-09T16:23:38.297Z,1612887818.297 [CommandLine](IMPORTANT): DVL_micro.pitchOffset (degree)
2021-02-09T16:23:38.305Z,1612887818.305 [CommandLine](IMPORTANT): DVL_micro.rollOffset (degree)
2021-02-09T16:23:38.318Z,1612887818.318 [CommandLine](IMPORTANT): PNI_TCM.pitchOffset (degree)
2021-02-09T16:23:38.319Z,1612887818.319 [CommandLine](IMPORTANT): PNI_TCM.rollOffset (degree)
2021-02-09T16:23:38.320Z,1612887818.320 [CommandLine](IMPORTANT): Rowe_600.headingOffset (degree)
2021-02-09T16:23:38.321Z,1612887818.321 [CommandLine](IMPORTANT): Rowe_600.pitchOffset (degree)
2021-02-09T16:23:38.321Z,1612887818.321 [CommandLine](IMPORTANT): Rowe_600.rollOffset (degree)
2021-02-09T16:23:38.331Z,1612887818.331 [CommandLine](IMPORTANT): Docked.dockingDepthOffset (meter)
2021-02-09T16:23:38.363Z,1612887818.363 [CommandLine](IMPORTANT): BuoyancyServo.offsetVolume (cubic_centimeter)
2021-02-09T16:23:38.364Z,1612887818.364 [CommandLine](IMPORTANT): ElevatorServo.offsetAngle (degree)
2021-02-09T16:23:38.383Z,1612887818.383 [CommandLine](IMPORTANT): RudderServo.offsetAngle (degree)
2021-02-09T16:23:38.456Z,1612887818.456 [CommandLine](IMPORTANT): Config/Simulator.buoyancyNeutralOffset (cubic_centimeter)
2021-02-09T16:23:38.456Z,1612887818.456 [CommandLine](IMPORTANT): Config/Simulator.massPositionOffset (millimeter)
2021-02-09T16:23:38.479Z,1612887818.479 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup (bool)
2021-02-09T16:23:38.480Z,1612887818.480 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.targetConfidenceLevel (percent)
2021-02-09T16:23:38.480Z,1612887818.480 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.targetErrorBound (degree)
2021-02-09T16:23:38.480Z,1612887818.480 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.verbosity (count)
2021-02-09T16:23:38.524Z,1612887818.524 [CommandLine](IMPORTANT): CBIT.runElevOffsetCalc (bool)
2021-02-09T16:23:38.529Z,1612887818.529 [CommandLine](IMPORTANT): CBIT.gfBattOffset (microampere)
2021-02-09T16:23:38.530Z,1612887818.530 [CommandLine](IMPORTANT): CBIT.gf24Offset (microampere)
2021-02-09T16:23:38.530Z,1612887818.530 [CommandLine](IMPORTANT): CBIT.gf12Offset (microampere)
2021-02-09T16:23:38.530Z,1612887818.530 [CommandLine](IMPORTANT): CBIT.gf5Offset (microampere)
2021-02-09T16:23:38.531Z,1612887818.531 [CommandLine](IMPORTANT): CBIT.gf3_3Offset (microampere)
2021-02-09T16:23:38.531Z,1612887818.531 [CommandLine](IMPORTANT): CBIT.gf3_15Offset (microampere)
2021-02-09T16:23:38.532Z,1612887818.532 [CommandLine](IMPORTANT): CBIT.gfCommOffset (microampere)
2021-02-09T16:23:38.553Z,1612887818.553 [CommandLine](IMPORTANT): CTD_NeilBrown.offset (decibar)
2021-02-09T16:23:38.554Z,1612887818.554 [CommandLine](IMPORTANT): CTD_Seabird.offset (decibar)
2021-02-09T16:23:38.555Z,1612887818.555 [CommandLine](IMPORTANT): CTD_Seabird.oxygenCalCoeffFOffset (none)
2021-02-09T16:23:38.651Z,1612887818.651 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.enableBroadcast (bool)
2021-02-09T16:23:38.651Z,1612887818.651 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_average (radian)
2021-02-09T16:23:38.651Z,1612887818.651 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_variance (radian)
2021-02-09T16:23:38.652Z,1612887818.652 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_error_bound (radian)
2021-02-09T16:23:38.652Z,1612887818.652 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_speed_identifier (meter_per_second)
2021-02-09T16:23:38.653Z,1612887818.653 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_pitch_identifier (radian)
2021-02-09T16:23:38.653Z,1612887818.653 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_mass_position_identifier (meter)
2021-02-09T16:23:38.774Z,1612887818.774 [CommandLine](IMPORTANT): CBIT.empiricalFaultElevOffset (radian)
2021-02-09T16:23:45.445Z,1612887825.445 [CommandLine](IMPORTANT): got command show variable gf24
2021-02-09T16:23:45.491Z,1612887825.491 [CommandLine](IMPORTANT): CBIT.gf24Offset (microampere)
2021-02-09T16:23:51.171Z,1612887831.171 [CommandLine](IMPORTANT): got command get CBIT.gf24Offset
2021-02-09T16:23:51.173Z,1612887831.173 [CommandLine](IMPORTANT): CBIT.gf24Offset 107.000000 uA
2021-02-09T16:23:52.476Z,1612887832.476 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-02-09T16:24:32.471Z,1612887872.471 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-02-09T16:24:34.510Z,1612887874.510 [CommandLine](IMPORTANT): got command gfscan
2021-02-09T16:24:34.524Z,1612887874.524 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-09T16:24:45.403Z,1612887885.403 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.009783
CHAN A1 (24V): 0.036247
CHAN A2 (12V): -0.007688
CHAN A3 (5V): -0.002705
CHAN B0 (3.3V): 0.000002
CHAN B1 (3.15aV): -0.000111
CHAN B2 (3.15bV): -0.000552
CHAN B3 (GND): 0.001924
OPEN: 0.005389
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-09T16:24:46.264Z,1612887886.264 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:24:59.590Z,1612887899.590 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-02-09T16:25:20.523Z,1612887920.523 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-02-09T16:25:20.523Z,1612887920.523 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-02-09T16:25:20.534Z,1612887920.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-02-09T16:25:20.940Z,1612887920.940 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-02-09T16:25:20.940Z,1612887920.940 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-02-09T16:25:53.623Z,1612887953.623 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-02-09T16:26:10.011Z,1612887970.011 [DataOverHttps](IMPORTANT): SBD MTMSN=20210209T162609
2021-02-09T16:26:17.482Z,1612887977.482 [DataOverHttps](INFO): Received command:configSet DAT.loadAtStartup 0 bool persist;configSet CBIT.gf24Offset 143 uA persist
2021-02-09T16:26:17.763Z,1612887977.763 [CommandLine](IMPORTANT): got command configSet DAT.loadAtStartup 0.000000 bool persist
2021-02-09T16:26:17.763Z,1612887977.763 [CommandLine](IMPORTANT): configSet DAT.loadAtStartup requires a restart to take effect.
2021-02-09T16:26:17.764Z,1612887977.764 [CommandLine](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown.
2021-02-09T16:26:17.764Z,1612887977.764 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 143.000000 microampere persist
2021-02-09T16:26:17.765Z,1612887977.765 [CommandLine](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown.
2021-02-09T16:26:21.149Z,1612887981.149 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-02-09T16:27:02.751Z,1612888022.751 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-02-09T16:27:11.605Z,1612888031.605 [CommandLine](IMPORTANT): got command restart application
2021-02-09T16:27:12.609Z,1612888032.609 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2021-02-09T16:27:12.609Z,1612888032.609 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:12.610Z,1612888032.610 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:12.689Z,1612888032.689 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-02-09T16:27:12.689Z,1612888032.689 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:12.690Z,1612888032.690 [CommandLine](INFO): Join timeout helper Thread ID is 5808
2021-02-09T16:27:12.690Z,1612888032.690 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-02-09T16:27:12.690Z,1612888032.690 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:12.691Z,1612888032.691 [NavChartDb](INFO): Join timeout helper Thread ID is 5809
2021-02-09T16:27:12.713Z,1612888032.713 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:12.713Z,1612888032.713 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:12.729Z,1612888032.729 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-02-09T16:27:12.729Z,1612888032.729 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:12.729Z,1612888032.729 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5810
2021-02-09T16:27:13.185Z,1612888033.185 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:13.186Z,1612888033.186 [WetLabsBB2FL](INFO): Powering down
2021-02-09T16:27:13.186Z,1612888033.186 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:13.193Z,1612888033.193 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-02-09T16:27:13.193Z,1612888033.193 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:13.194Z,1612888033.194 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5811
2021-02-09T16:27:13.213Z,1612888033.213 [CTD_Seabird](INFO): Powering down
2021-02-09T16:27:13.225Z,1612888033.225 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:13.225Z,1612888033.225 [CTD_Seabird](INFO): Powering down
2021-02-09T16:27:13.237Z,1612888033.237 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:13.241Z,1612888033.241 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-02-09T16:27:13.242Z,1612888033.242 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:13.242Z,1612888033.242 [Radio_Surface](INFO): Join timeout helper Thread ID is 5812
2021-02-09T16:27:13.509Z,1612888033.509 [Radio_Surface](INFO): Powering down
2021-02-09T16:27:13.510Z,1612888033.510 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:13.510Z,1612888033.510 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:13.530Z,1612888033.530 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-02-09T16:27:13.530Z,1612888033.530 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:13.530Z,1612888033.530 [Onboard](INFO): Join timeout helper Thread ID is 5813
2021-02-09T16:27:14.149Z,1612888034.149 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-02-09T16:27:14.577Z,1612888034.577 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:14.577Z,1612888034.577 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:14.598Z,1612888034.598 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-02-09T16:27:14.598Z,1612888034.598 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:14.598Z,1612888034.598 [DataOverHttps](INFO): Join timeout helper Thread ID is 5814
2021-02-09T16:27:15.153Z,1612888035.153 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:15.153Z,1612888035.153 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.158Z,1612888035.158 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-02-09T16:27:15.158Z,1612888035.158 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.158Z,1612888035.158 [BackseatComponent](INFO): Join timeout helper Thread ID is 5815
2021-02-09T16:27:15.281Z,1612888035.281 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:15.281Z,1612888035.281 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.303Z,1612888035.303 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-02-09T16:27:15.303Z,1612888035.303 [logger ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.304Z,1612888035.304 [logger](INFO): Join timeout helper Thread ID is 5816
2021-02-09T16:27:15.320Z,1612888035.320 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:15.320Z,1612888035.320 [logger ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.327Z,1612888035.327 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-02-09T16:27:15.327Z,1612888035.327 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.327Z,1612888035.327 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-02-09T16:27:15.328Z,1612888035.328 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.328Z,1612888035.328 [controlThread](INFO): Join timeout helper Thread ID is 5817
2021-02-09T16:27:15.585Z,1612888035.585 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T16:27:15.586Z,1612888035.586 [controlThread](DEBUG): Uninitializing ControlThread
2021-02-09T16:27:15.586Z,1612888035.586 [AHRS_M2](INFO): Powering down
2021-02-09T16:27:15.658Z,1612888035.658 [NAL9602](INFO): Powering down
2021-02-09T16:27:15.729Z,1612888035.729 [RDI_Pathfinder](INFO): Powering down
2021-02-09T16:27:15.730Z,1612888035.730 [DAT](INFO): Powering down
2021-02-09T16:27:15.850Z,1612888035.850 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-02-09T16:27:15.851Z,1612888035.851 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-02-09T16:27:15.851Z,1612888035.851 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-02-09T16:27:15.852Z,1612888035.852 [MissionManager](INFO): Uninitializing Mission Default
2021-02-09T16:27:15.852Z,1612888035.852 [Default] Stopped
2021-02-09T16:27:15.852Z,1612888035.852 [Default](DEBUG): Aggregate::uninitialize Default
2021-02-09T16:27:15.852Z,1612888035.852 [Default:B.GoToSurface] Stopped
2021-02-09T16:27:15.852Z,1612888035.852 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-09T16:27:15.852Z,1612888035.852 [Default:CheckIn] Stopped
2021-02-09T16:27:15.852Z,1612888035.852 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-09T16:27:15.852Z,1612888035.852 [Default:CheckIn:Read_GPS] Stopped
2021-02-09T16:27:15.855Z,1612888035.855 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-02-09T16:27:15.855Z,1612888035.855 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-02-09T16:27:15.856Z,1612888035.856 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-02-09T16:27:15.856Z,1612888035.856 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-02-09T16:27:15.856Z,1612888035.856 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-02-09T16:27:15.856Z,1612888035.856 [BuoyancyServo](INFO): Powering down
2021-02-09T16:27:15.869Z,1612888035.869 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-02-09T16:27:15.869Z,1612888035.869 [ElevatorServo](INFO): Powering down
2021-02-09T16:27:15.870Z,1612888035.870 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-02-09T16:27:15.870Z,1612888035.870 [MassServo](INFO): Powering down
2021-02-09T16:27:15.871Z,1612888035.871 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-09T16:27:15.871Z,1612888035.871 [RudderServo](INFO): Powering down
2021-02-09T16:27:15.872Z,1612888035.872 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-02-09T16:27:15.872Z,1612888035.872 [ThrusterServo](INFO): Powering down
2021-02-09T16:27:15.873Z,1612888035.873 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-02-09T16:27:15.873Z,1612888035.873 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-02-09T16:27:15.873Z,1612888035.873 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-02-09T16:27:15.873Z,1612888035.873 [CBIT](DEBUG): Powering off loads.
2021-02-09T16:27:15.885Z,1612888035.885 [CBIT](DEBUG): Disabling WDT.
2021-02-09T16:27:15.897Z,1612888035.897 [CBIT](DEBUG): Opening all GF detection circuits.
2021-02-09T16:27:15.898Z,1612888035.898 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.952Z,1612888035.952 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.955Z,1612888035.955 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:15.960Z,1612888035.960 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:16.001Z,1612888036.001 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:16.054Z,1612888036.054 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:16.058Z,1612888036.058 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:16.066Z,1612888036.066 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-09T16:27:16.124Z,1612888036.124 [logger ThreadHandler](INFO): Thread cancelled.