2021-02-09T21:42:19.320Z,1612906939.320 [Supervisor](DEBUG): Initializing supervisor.
2021-02-09T21:42:19.340Z,1612906939.340 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-02-09T21:42:19.340Z,1612906939.340 [SyncHandler](INFO): Protected caller Thread ID is 4516
2021-02-09T21:42:19.341Z,1612906939.341 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-02-09T21:42:19.352Z,1612906939.352 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-02-09T21:42:19.352Z,1612906939.352 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4517
2021-02-09T21:42:19.355Z,1612906939.355 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-02-09T21:42:19.378Z,1612906939.378 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-02-09T21:42:19.392Z,1612906939.392 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-02-09T21:42:19.404Z,1612906939.404 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4518
2021-02-09T21:42:19.406Z,1612906939.406 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-02-09T21:42:19.416Z,1612906939.416 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-02-09T21:42:19.416Z,1612906939.416 [logger ThreadHandler](INFO): Protected caller Thread ID is 4519
2021-02-09T21:42:19.419Z,1612906939.419 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-02-09T21:42:19.419Z,1612906939.419 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-02-09T21:42:19.421Z,1612906939.421 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-02-09T21:42:20.545Z,1612906940.545 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-02-09T21:42:20.546Z,1612906940.546 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-02-09T21:42:20.964Z,1612906940.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-02-09T21:42:20.965Z,1612906940.965 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-02-09T21:42:21.403Z,1612906941.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-02-09T21:42:21.404Z,1612906941.404 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-02-09T21:42:21.728Z,1612906941.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-02-09T21:42:21.729Z,1612906941.729 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-02-09T21:42:22.553Z,1612906942.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-02-09T21:42:22.554Z,1612906942.554 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-02-09T21:42:23.295Z,1612906943.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-02-09T21:42:23.296Z,1612906943.296 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-02-09T21:42:23.475Z,1612906943.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-02-09T21:42:23.476Z,1612906943.476 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-02-09T21:42:23.784Z,1612906943.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-02-09T21:42:23.785Z,1612906943.785 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-02-09T21:42:24.848Z,1612906944.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-02-09T21:42:24.849Z,1612906944.849 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-02-09T21:42:25.760Z,1612906945.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-02-09T21:42:25.761Z,1612906945.761 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-02-09T21:42:25.932Z,1612906945.932 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-02-09T21:42:26.353Z,1612906946.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-02-09T21:42:26.354Z,1612906946.354 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-02-09T21:42:26.555Z,1612906946.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-02-09T21:42:26.555Z,1612906946.555 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-02-09T21:42:28.390Z,1612906948.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-02-09T21:42:28.391Z,1612906948.391 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-02-09T21:42:28.599Z,1612906948.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2021-02-09T21:42:28.599Z,1612906948.599 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-02-09T21:42:29.068Z,1612906949.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-02-09T21:42:29.070Z,1612906949.070 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2021-02-09T21:42:29.071Z,1612906949.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2021-02-09T21:42:29.454Z,1612906949.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2021-02-09T21:42:29.626Z,1612906949.626 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2021-02-09T21:42:29.841Z,1612906949.841 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2021-02-09T21:42:30.032Z,1612906950.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2021-02-09T21:42:30.177Z,1612906950.177 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2021-02-09T21:42:30.340Z,1612906950.340 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2021-02-09T21:42:30.566Z,1612906950.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2021-02-09T21:42:30.747Z,1612906950.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2021-02-09T21:42:31.051Z,1612906951.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2021-02-09T21:42:31.570Z,1612906951.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-02-09T21:42:31.571Z,1612906951.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2021-02-09T21:42:31.759Z,1612906951.759 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2021-02-09T21:42:31.759Z,1612906951.759 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-02-09T21:42:31.825Z,1612906951.825 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-02-09T21:42:32.601Z,1612906952.601 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-02-09T21:42:32.601Z,1612906952.601 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-02-09T21:42:33.082Z,1612906953.082 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-02-09T21:42:33.082Z,1612906953.082 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-02-09T21:42:33.211Z,1612906953.211 [DeadReckonUsingSpeedCalculator] Loaded
2021-02-09T21:42:33.211Z,1612906953.211 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2021-02-09T21:42:33.280Z,1612906953.280 [NavChart] Loaded
2021-02-09T21:42:33.280Z,1612906953.280 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-02-09T21:42:33.288Z,1612906953.288 [UniversalFixResidualReporter] Loaded
2021-02-09T21:42:33.288Z,1612906953.288 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-02-09T21:42:33.288Z,1612906953.288 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-02-09T21:42:33.289Z,1612906953.289 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-02-09T21:42:33.344Z,1612906953.344 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-02-09T21:42:33.344Z,1612906953.344 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-02-09T21:42:33.786Z,1612906953.786 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-02-09T21:42:33.787Z,1612906953.787 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-02-09T21:42:33.898Z,1612906953.898 [VerticalControl](DEBUG): Construct VerticalControl.
2021-02-09T21:42:34.133Z,1612906954.133 [VerticalControl] Loaded
2021-02-09T21:42:34.133Z,1612906954.133 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-02-09T21:42:34.138Z,1612906954.138 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-02-09T21:42:34.286Z,1612906954.286 [HorizontalControl] Loaded
2021-02-09T21:42:34.286Z,1612906954.286 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-02-09T21:42:34.315Z,1612906954.315 [SpeedControl](DEBUG): Construct SpeedControl.
2021-02-09T21:42:34.320Z,1612906954.320 [SpeedControl] Loaded
2021-02-09T21:42:34.321Z,1612906954.321 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-02-09T21:42:34.337Z,1612906954.337 [LoopControl](DEBUG): Construct LoopControl.
2021-02-09T21:42:34.338Z,1612906954.338 [LoopControl] Loaded
2021-02-09T21:42:34.338Z,1612906954.338 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-02-09T21:42:34.338Z,1612906954.338 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-02-09T21:42:34.339Z,1612906954.339 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-02-09T21:42:34.428Z,1612906954.428 [DepthRateCalculator] Loaded
2021-02-09T21:42:34.428Z,1612906954.428 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-02-09T21:42:34.449Z,1612906954.449 [PitchRateCalculator] Loaded
2021-02-09T21:42:34.450Z,1612906954.450 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-02-09T21:42:34.485Z,1612906954.485 [SpeedCalculator] Loaded
2021-02-09T21:42:34.485Z,1612906954.485 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-02-09T21:42:34.534Z,1612906954.534 [TempGradientCalculator] Loaded
2021-02-09T21:42:34.534Z,1612906954.534 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-02-09T21:42:34.555Z,1612906954.555 [YawRateCalculator] Loaded
2021-02-09T21:42:34.556Z,1612906954.556 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-02-09T21:42:34.620Z,1612906954.620 [ElevatorOffsetCalculator] Loaded
2021-02-09T21:42:34.620Z,1612906954.620 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-02-09T21:42:34.621Z,1612906954.621 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-02-09T21:42:34.622Z,1612906954.622 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-02-09T21:42:34.630Z,1612906954.630 [Module Loader](ERROR): Cannot load library: Modules/Dock.so: wrong ELF class: ELFCLASS64
2021-02-09T21:42:34.631Z,1612906954.631 [Module Loader](ERROR): Could not load the module at Modules/Dock.so
2021-02-09T21:42:34.631Z,1612906954.631 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-02-09T21:42:34.830Z,1612906954.830 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-02-09T21:42:34.830Z,1612906954.830 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-02-09T21:42:35.078Z,1612906955.078 [BuoyancyServo] Loaded
2021-02-09T21:42:35.078Z,1612906955.078 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-02-09T21:42:35.118Z,1612906955.118 [ElevatorServo] Loaded
2021-02-09T21:42:35.118Z,1612906955.118 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-02-09T21:42:35.153Z,1612906955.153 [MassServo] Loaded
2021-02-09T21:42:35.153Z,1612906955.153 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-02-09T21:42:35.180Z,1612906955.180 [RudderServo] Loaded
2021-02-09T21:42:35.180Z,1612906955.180 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-02-09T21:42:35.207Z,1612906955.207 [ThrusterServo] Loaded
2021-02-09T21:42:35.207Z,1612906955.207 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-02-09T21:42:35.212Z,1612906955.212 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-02-09T21:42:35.213Z,1612906955.213 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-02-09T21:42:35.477Z,1612906955.477 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-02-09T21:42:35.477Z,1612906955.477 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-02-09T21:42:36.712Z,1612906956.712 [AHRS_M2] Loaded
2021-02-09T21:42:36.712Z,1612906956.712 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-02-09T21:42:36.944Z,1612906956.944 [DataOverHttps] Loaded
2021-02-09T21:42:36.945Z,1612906956.945 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-02-09T21:42:36.952Z,1612906956.952 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409BE4E0
2021-02-09T21:42:36.952Z,1612906956.952 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4601
2021-02-09T21:42:36.982Z,1612906956.982 [Depth_Keller] Loaded
2021-02-09T21:42:36.982Z,1612906956.982 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-02-09T21:42:37.212Z,1612906957.212 [NAL9602] Loaded
2021-02-09T21:42:37.213Z,1612906957.213 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-02-09T21:42:37.310Z,1612906957.310 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2021-02-09T21:42:37.311Z,1612906957.311 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2021-02-09T21:42:37.311Z,1612906957.311 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2021-02-09T21:42:37.324Z,1612906957.324 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2021-02-09T21:42:37.324Z,1612906957.324 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2021-02-09T21:42:37.325Z,1612906957.325 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2021-02-09T21:42:37.325Z,1612906957.325 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2021-02-09T21:42:37.326Z,1612906957.326 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2021-02-09T21:42:37.326Z,1612906957.326 [Onboard] Loaded
2021-02-09T21:42:37.326Z,1612906957.326 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-02-09T21:42:37.336Z,1612906957.336 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409EE4E0
2021-02-09T21:42:37.337Z,1612906957.337 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4602
2021-02-09T21:42:37.345Z,1612906957.345 [Radio_Surface] Loaded
2021-02-09T21:42:37.345Z,1612906957.345 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-02-09T21:42:37.352Z,1612906957.352 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1E4E0
2021-02-09T21:42:37.352Z,1612906957.352 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4603
2021-02-09T21:42:40.634Z,1612906960.634 [BPC1] Loaded
2021-02-09T21:42:40.634Z,1612906960.634 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-02-09T21:42:40.635Z,1612906960.635 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-02-09T21:42:40.635Z,1612906960.635 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-02-09T21:42:40.943Z,1612906960.943 [SBIT](DEBUG): Construct Startup Built In Test.
2021-02-09T21:42:40.980Z,1612906960.980 [SBIT] Loaded
2021-02-09T21:42:40.980Z,1612906960.980 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-02-09T21:42:40.994Z,1612906960.994 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-02-09T21:42:41.019Z,1612906961.019 [IBIT] Loaded
2021-02-09T21:42:41.019Z,1612906961.019 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-02-09T21:42:41.039Z,1612906961.039 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-02-09T21:42:41.342Z,1612906961.342 [CBIT] Loaded
2021-02-09T21:42:41.342Z,1612906961.342 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-02-09T21:42:41.343Z,1612906961.343 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-02-09T21:42:41.343Z,1612906961.343 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-02-09T21:42:41.456Z,1612906961.456 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-02-09T21:42:41.461Z,1612906961.461 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-02-09T21:42:41.463Z,1612906961.463 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-02-09T21:42:41.483Z,1612906961.483 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-02-09T21:42:41.496Z,1612906961.496 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AAA4E0
2021-02-09T21:42:41.497Z,1612906961.497 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4604
2021-02-09T21:42:41.501Z,1612906961.501 [Supervisor](INFO): Main Thread ID is 4514
2021-02-09T21:42:41.501Z,1612906961.501 [Supervisor](DEBUG): Running supervisor.
2021-02-09T21:42:41.502Z,1612906961.502 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4605
2021-02-09T21:42:41.528Z,1612906961.528 [controlThread ThreadHandler](INFO): Handler Thread ID is 4606
2021-02-09T21:42:41.529Z,1612906961.529 [controlThread](DEBUG): Initializing ControlThread
2021-02-09T21:42:41.530Z,1612906961.530 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-02-09T21:42:41.530Z,1612906961.530 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-02-09T21:42:41.531Z,1612906961.531 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-02-09T21:42:41.531Z,1612906961.531 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-02-09T21:42:41.533Z,1612906961.533 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-02-09T21:42:41.533Z,1612906961.533 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-02-09T21:42:41.534Z,1612906961.534 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-02-09T21:42:41.534Z,1612906961.534 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-02-09T21:42:41.534Z,1612906961.534 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-02-09T21:42:41.535Z,1612906961.535 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-02-09T21:42:41.535Z,1612906961.535 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-02-09T21:42:41.535Z,1612906961.535 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-02-09T21:42:41.536Z,1612906961.536 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-02-09T21:42:41.540Z,1612906961.540 [SBIT](INFO): Initialize SBIT Component.
2021-02-09T21:42:41.541Z,1612906961.541 [SBIT](IMPORTANT): git: 2020_06_10_lcm_load_test-6-ga170864
2021-02-09T21:42:41.541Z,1612906961.541 [SBIT](INFO): git hash: a1708641688566620cff26e57d44215247cf1b46
2021-02-09T21:42:41.542Z,1612906961.542 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-02-09T21:42:41.543Z,1612906961.543 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2021-02-09T21:42:41.544Z,1612906961.544 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2021-02-09T21:42:41.544Z,1612906961.544 [IBIT](INFO): Initialize IBIT Component.
2021-02-09T21:42:41.545Z,1612906961.545 [CBIT](DEBUG): Initialize CBIT Component.
2021-02-09T21:42:41.552Z,1612906961.552 [logger ThreadHandler](INFO): Handler Thread ID is 4607
2021-02-09T21:42:41.560Z,1612906961.560 [CBIT](DEBUG): Initialized mux pins.
2021-02-09T21:42:41.560Z,1612906961.560 [CBIT](DEBUG): Initializing the watchdog timer.
2021-02-09T21:42:41.580Z,1612906961.580 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4608
2021-02-09T21:42:41.581Z,1612906961.581 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-02-09T21:42:41.584Z,1612906961.584 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-02-09T21:42:41.584Z,1612906961.584 [CBIT](DEBUG): Initializing heartbeat.
2021-02-09T21:42:41.596Z,1612906961.596 [Onboard ThreadHandler](INFO): Handler Thread ID is 4609
2021-02-09T21:42:41.620Z,1612906961.620 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4610
2021-02-09T21:42:41.651Z,1612906961.651 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4611
2021-02-09T21:42:41.656Z,1612906961.656 [CBIT](DEBUG): Deactivating GF circuits.
2021-02-09T21:42:41.656Z,1612906961.656 [CBIT](DEBUG): Deactivating emergency mode.
2021-02-09T21:42:41.669Z,1612906961.669 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-02-09T21:42:41.669Z,1612906961.669 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-02-09T21:42:41.669Z,1612906961.669 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-02-09T21:42:41.669Z,1612906961.669 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-02-09T21:42:41.670Z,1612906961.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-02-09T21:42:41.670Z,1612906961.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-02-09T21:42:41.670Z,1612906961.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-02-09T21:42:41.670Z,1612906961.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-02-09T21:42:41.670Z,1612906961.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-02-09T21:42:41.670Z,1612906961.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-02-09T21:42:41.671Z,1612906961.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-02-09T21:42:41.671Z,1612906961.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-02-09T21:42:41.671Z,1612906961.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-02-09T21:42:41.671Z,1612906961.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-02-09T21:42:41.671Z,1612906961.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-02-09T21:42:41.672Z,1612906961.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-02-09T21:42:41.692Z,1612906961.692 [CBIT](DEBUG): Backplane powered.
2021-02-09T21:42:41.692Z,1612906961.692 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-02-09T21:42:41.725Z,1612906961.725 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-09T21:42:41.792Z,1612906961.792 [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-09T21:42:41.792Z,1612906961.792 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-02-09T21:42:41.879Z,1612906961.879 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-02-09T21:42:41.904Z,1612906961.904 [Default:A.Wait](DEBUG): Construct Wait.
2021-02-09T21:42:41.907Z,1612906961.907 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-09T21:42:41.958Z,1612906961.958 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-02-09T21:42:41.960Z,1612906961.960 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-02-09T21:42:41.994Z,1612906961.994 [Default:E.Execute](DEBUG): Construct Execute.
2021-02-09T21:42:41.997Z,1612906961.997 [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-09T21:42:42.001Z,1612906962.001 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-02-09T21:42:42.024Z,1612906962.024 [Radio_Surface](INFO): Powering up
2021-02-09T21:42:42.034Z,1612906962.034 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-09T21:42:42.192Z,1612906962.192 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2021-02-09T21:42:42.258Z,1612906962.258 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-02-09T21:42:42.624Z,1612906962.624 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-02-09T21:42:42.636Z,1612906962.636 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-02-09T21:42:42.658Z,1612906962.658 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-02-09T21:42:42.668Z,1612906962.668 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-02-09T21:42:42.682Z,1612906962.682 [MassServo](DEBUG): Initializing EZServoServo.
2021-02-09T21:42:42.692Z,1612906962.692 [MassServo](DEBUG): Initializing MassServo.
2021-02-09T21:42:42.732Z,1612906962.732 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-09T21:42:42.744Z,1612906962.744 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-09T21:42:42.757Z,1612906962.757 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-02-09T21:42:42.768Z,1612906962.768 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-02-09T21:42:42.958Z,1612906962.958 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-02-09T21:42:43.608Z,1612906963.608 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-02-09T21:42:43.608Z,1612906963.608 [RudderServo](FAULT): Rudder failed to initialize
2021-02-09T21:42:43.608Z,1612906963.608 [RudderServo] Communications Fault, FailCount= 1
2021-02-09T21:42:43.608Z,1612906963.608 [RudderServo](ERROR): Communications Fault
2021-02-09T21:42:43.724Z,1612906963.724 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-02-09T21:42:43.901Z,1612906963.901 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-09T21:42:43.901Z,1612906963.901 [RudderServo](INFO): Powering down
2021-02-09T21:42:44.558Z,1612906964.558 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-09T21:42:44.677Z,1612906964.677 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-09T21:42:44.681Z,1612906964.681 [CBIT](INFO): Clearing failed state for component RudderServo
2021-02-09T21:42:44.681Z,1612906964.681 [RudderServo] No Fault, FailCount= 1
2021-02-09T21:42:47.284Z,1612906967.284 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:47.788Z,1612906967.788 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:48.200Z,1612906968.200 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:48.680Z,1612906968.680 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:49.212Z,1612906969.212 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:49.684Z,1612906969.684 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:49.776Z,1612906969.776 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.026894 sec
2021-02-09T21:42:49.812Z,1612906969.812 [MassServo](INFO): Mass Shifter getPosition after 0.062801 sec
2021-02-09T21:42:49.849Z,1612906969.849 [MassServo](INFO): Mass Shifter done after 0.099352 sec
2021-02-09T21:42:50.248Z,1612906970.248 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:50.365Z,1612906970.365 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.059423 sec
2021-02-09T21:42:50.400Z,1612906970.400 [MassServo](INFO): Mass Shifter getPosition after 0.094983 sec
2021-02-09T21:42:50.401Z,1612906970.401 [MassServo](INFO): Mass Shifter done after 0.095576 sec
2021-02-09T21:42:50.800Z,1612906970.800 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:50.886Z,1612906970.886 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000287 sec
2021-02-09T21:42:50.924Z,1612906970.924 [MassServo](INFO): Mass Shifter getPosition after 0.039084 sec
2021-02-09T21:42:50.925Z,1612906970.925 [MassServo](INFO): Mass Shifter done after 0.039681 sec
2021-02-09T21:42:51.380Z,1612906971.380 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:51.487Z,1612906971.487 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.009429 sec
2021-02-09T21:42:51.524Z,1612906971.524 [MassServo](INFO): Mass Shifter getPosition after 0.046782 sec
2021-02-09T21:42:51.848Z,1612906971.848 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:52.388Z,1612906972.388 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:52.788Z,1612906972.788 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:53.180Z,1612906973.180 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:53.604Z,1612906973.604 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:54.020Z,1612906974.020 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:54.508Z,1612906974.508 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:54.912Z,1612906974.912 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:55.320Z,1612906975.320 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:42:55.321Z,1612906975.321 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-09T21:42:55.321Z,1612906975.321 [AHRS_M2] Communications Fault, FailCount= 1
2021-02-09T21:42:55.321Z,1612906975.321 [AHRS_M2](ERROR): Communications Fault
2021-02-09T21:42:55.416Z,1612906975.416 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-09T21:42:55.438Z,1612906975.438 [AHRS_M2](INFO): Powering down
2021-02-09T21:42:56.292Z,1612906976.292 [CBIT](INFO): Clearing failed state for component AHRS_M2
2021-02-09T21:42:56.292Z,1612906976.292 [AHRS_M2] No Fault, FailCount= 1
2021-02-09T21:42:56.631Z,1612906976.631 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-09T21:42:57.958Z,1612906977.958 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004934
2021-02-09T21:43:00.188Z,1612906980.188 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:00.640Z,1612906980.640 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:01.064Z,1612906981.064 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:01.456Z,1612906981.456 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:01.864Z,1612906981.864 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:02.248Z,1612906982.248 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:02.664Z,1612906982.664 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:03.068Z,1612906983.068 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:03.472Z,1612906983.472 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:03.892Z,1612906983.892 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:04.272Z,1612906984.272 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:04.672Z,1612906984.672 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:05.088Z,1612906985.088 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:05.528Z,1612906985.528 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:05.948Z,1612906985.948 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:05.997Z,1612906985.997 [SBIT](IMPORTANT): Beginning Startup BIT
2021-02-09T21:43:06.013Z,1612906986.013 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-09T21:43:06.356Z,1612906986.356 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:06.784Z,1612906986.784 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:07.220Z,1612906987.220 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:07.226Z,1612906987.226 [NAL9602](INFO): Powering up NAL9602
2021-02-09T21:43:07.688Z,1612906987.688 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:08.300Z,1612906988.300 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:08.417Z,1612906988.417 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000295 sec
2021-02-09T21:43:08.764Z,1612906988.764 [MassServo](INFO): Mass Shifter getPosition after 0.347494 sec
2021-02-09T21:43:08.800Z,1612906988.800 [MassServo](INFO): Mass Shifter done after 0.383361 sec
2021-02-09T21:43:09.160Z,1612906989.160 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:09.225Z,1612906989.225 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000518 sec
2021-02-09T21:43:09.264Z,1612906989.264 [MassServo](INFO): Mass Shifter getPosition after 0.039541 sec
2021-02-09T21:43:09.265Z,1612906989.265 [MassServo](INFO): Mass Shifter done after 0.040144 sec
2021-02-09T21:43:09.736Z,1612906989.736 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:09.736Z,1612906989.736 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-09T21:43:09.736Z,1612906989.736 [AHRS_M2] Communications Fault, FailCount= 2
2021-02-09T21:43:09.736Z,1612906989.736 [AHRS_M2](ERROR): Communications Fault
2021-02-09T21:43:09.837Z,1612906989.837 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000341 sec
2021-02-09T21:43:09.884Z,1612906989.884 [MassServo](INFO): Mass Shifter getPosition after 0.039342 sec
2021-02-09T21:43:09.884Z,1612906989.884 [MassServo](INFO): Mass Shifter done after 0.047499 sec
2021-02-09T21:43:09.917Z,1612906989.917 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-09T21:43:09.922Z,1612906989.922 [AHRS_M2](INFO): Powering down
2021-02-09T21:43:10.165Z,1612906990.165 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000252 sec
2021-02-09T21:43:10.201Z,1612906990.201 [MassServo](INFO): Mass Shifter getPosition after 0.036061 sec
2021-02-09T21:43:10.201Z,1612906990.201 [MassServo](INFO): Mass Shifter done after 0.036679 sec
2021-02-09T21:43:10.344Z,1612906990.344 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000727 sec
2021-02-09T21:43:10.380Z,1612906990.380 [MassServo](INFO): Mass Shifter getPosition after 0.036915 sec
2021-02-09T21:43:10.381Z,1612906990.381 [MassServo](INFO): Mass Shifter done after 0.037512 sec
2021-02-09T21:43:10.775Z,1612906990.775 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000292 sec
2021-02-09T21:43:10.812Z,1612906990.812 [MassServo](INFO): Mass Shifter getPosition after 0.037709 sec
2021-02-09T21:43:10.813Z,1612906990.813 [MassServo](INFO): Mass Shifter done after 0.038316 sec
2021-02-09T21:43:10.881Z,1612906990.881 [CBIT](INFO): Clearing failed state for component AHRS_M2
2021-02-09T21:43:10.881Z,1612906990.881 [AHRS_M2] No Fault, FailCount= 2
2021-02-09T21:43:11.132Z,1612906991.132 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-09T21:43:11.205Z,1612906991.205 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000242 sec
2021-02-09T21:43:11.244Z,1612906991.244 [MassServo](INFO): Mass Shifter getPosition after 0.039416 sec
2021-02-09T21:43:11.245Z,1612906991.245 [MassServo](INFO): Mass Shifter done after 0.040019 sec
2021-02-09T21:43:11.595Z,1612906991.595 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000507 sec
2021-02-09T21:43:11.635Z,1612906991.635 [MassServo](INFO): Mass Shifter getPosition after 0.040148 sec
2021-02-09T21:43:11.635Z,1612906991.635 [MassServo](INFO): Mass Shifter done after 0.040723 sec
2021-02-09T21:43:11.961Z,1612906991.961 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000301 sec
2021-02-09T21:43:12.000Z,1612906992.000 [MassServo](INFO): Mass Shifter getPosition after 0.039598 sec
2021-02-09T21:43:12.001Z,1612906992.001 [MassServo](INFO): Mass Shifter done after 0.040196 sec
2021-02-09T21:43:12.367Z,1612906992.367 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000238 sec
2021-02-09T21:43:12.420Z,1612906992.420 [MassServo](INFO): Mass Shifter getPosition after 0.053067 sec
2021-02-09T21:43:12.421Z,1612906992.421 [MassServo](INFO): Mass Shifter done after 0.053680 sec
2021-02-09T21:43:12.802Z,1612906992.802 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000514 sec
2021-02-09T21:43:12.841Z,1612906992.841 [MassServo](INFO): Mass Shifter getPosition after 0.038867 sec
2021-02-09T21:43:12.841Z,1612906992.841 [MassServo](INFO): Mass Shifter done after 0.039479 sec
2021-02-09T21:43:13.224Z,1612906993.224 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000290 sec
2021-02-09T21:43:13.264Z,1612906993.264 [MassServo](INFO): Mass Shifter getPosition after 0.061004 sec
2021-02-09T21:43:13.265Z,1612906993.265 [MassServo](INFO): Mass Shifter done after 0.061615 sec
2021-02-09T21:43:13.608Z,1612906993.608 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000327 sec
2021-02-09T21:43:13.656Z,1612906993.656 [MassServo](INFO): Mass Shifter getPosition after 0.048677 sec
2021-02-09T21:43:13.657Z,1612906993.657 [MassServo](INFO): Mass Shifter done after 0.049252 sec
2021-02-09T21:43:13.980Z,1612906993.980 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000505 sec
2021-02-09T21:43:14.017Z,1612906994.017 [MassServo](INFO): Mass Shifter getPosition after 0.037930 sec
2021-02-09T21:43:14.018Z,1612906994.018 [MassServo](INFO): Mass Shifter done after 0.038681 sec
2021-02-09T21:43:14.688Z,1612906994.688 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:14.715Z,1612906994.715 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000343 sec
2021-02-09T21:43:14.756Z,1612906994.756 [MassServo](INFO): Mass Shifter getPosition after 0.041589 sec
2021-02-09T21:43:14.757Z,1612906994.757 [MassServo](INFO): Mass Shifter done after 0.042191 sec
2021-02-09T21:43:15.188Z,1612906995.188 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:15.227Z,1612906995.227 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000515 sec
2021-02-09T21:43:15.264Z,1612906995.264 [MassServo](INFO): Mass Shifter getPosition after 0.037577 sec
2021-02-09T21:43:15.265Z,1612906995.265 [MassServo](INFO): Mass Shifter done after 0.038300 sec
2021-02-09T21:43:15.592Z,1612906995.592 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:15.632Z,1612906995.632 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000346 sec
2021-02-09T21:43:15.670Z,1612906995.670 [MassServo](INFO): Mass Shifter getPosition after 0.037933 sec
2021-02-09T21:43:15.671Z,1612906995.671 [MassServo](INFO): Mass Shifter done after 0.038725 sec
2021-02-09T21:43:16.096Z,1612906996.096 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:16.123Z,1612906996.123 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000236 sec
2021-02-09T21:43:16.160Z,1612906996.160 [MassServo](INFO): Mass Shifter getPosition after 0.037897 sec
2021-02-09T21:43:16.161Z,1612906996.161 [MassServo](INFO): Mass Shifter done after 0.038485 sec
2021-02-09T21:43:16.620Z,1612906996.620 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:16.695Z,1612906996.695 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000473 sec
2021-02-09T21:43:16.732Z,1612906996.732 [MassServo](INFO): Mass Shifter getPosition after 0.037848 sec
2021-02-09T21:43:16.733Z,1612906996.733 [MassServo](INFO): Mass Shifter done after 0.038437 sec
2021-02-09T21:43:17.056Z,1612906997.056 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:17.107Z,1612906997.107 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000337 sec
2021-02-09T21:43:17.148Z,1612906997.148 [MassServo](INFO): Mass Shifter getPosition after 0.042016 sec
2021-02-09T21:43:17.149Z,1612906997.149 [MassServo](INFO): Mass Shifter done after 0.042763 sec
2021-02-09T21:43:17.484Z,1612906997.484 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:17.514Z,1612906997.514 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000238 sec
2021-02-09T21:43:17.556Z,1612906997.556 [MassServo](INFO): Mass Shifter getPosition after 0.041803 sec
2021-02-09T21:43:17.557Z,1612906997.557 [MassServo](INFO): Mass Shifter done after 0.042373 sec
2021-02-09T21:43:17.996Z,1612906997.996 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:18.048Z,1612906998.048 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000473 sec
2021-02-09T21:43:18.085Z,1612906998.085 [MassServo](INFO): Mass Shifter getPosition after 0.058004 sec
2021-02-09T21:43:18.086Z,1612906998.086 [MassServo](INFO): Mass Shifter done after 0.058597 sec
2021-02-09T21:43:18.094Z,1612906998.094 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.005572
CHAN A1 (24V): 0.006845
CHAN A2 (12V): -0.005550
CHAN A3 (5V): -0.002571
CHAN B0 (3.3V): -0.001416
CHAN B1 (3.15aV): -0.001215
CHAN B2 (3.15bV): -0.002314
CHAN B3 (GND): -0.000344
OPEN: -0.001206
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-09T21:43:18.440Z,1612906998.440 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:18.445Z,1612906998.445 [NAL9602](INFO): NAL9602 initialized
2021-02-09T21:43:18.483Z,1612906998.483 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000339 sec
2021-02-09T21:43:18.520Z,1612906998.520 [MassServo](INFO): Mass Shifter getPosition after 0.037963 sec
2021-02-09T21:43:18.521Z,1612906998.521 [MassServo](INFO): Mass Shifter done after 0.038538 sec
2021-02-09T21:43:18.856Z,1612906998.856 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:18.922Z,1612906998.922 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000239 sec
2021-02-09T21:43:18.960Z,1612906998.960 [MassServo](INFO): Mass Shifter getPosition after 0.038071 sec
2021-02-09T21:43:18.961Z,1612906998.961 [MassServo](INFO): Mass Shifter done after 0.038651 sec
2021-02-09T21:43:19.284Z,1612906999.284 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:19.315Z,1612906999.315 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000469 sec
2021-02-09T21:43:19.364Z,1612906999.364 [MassServo](INFO): Mass Shifter getPosition after 0.049648 sec
2021-02-09T21:43:19.700Z,1612906999.700 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:20.220Z,1612907000.220 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:20.636Z,1612907000.636 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:21.040Z,1612907001.040 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:21.440Z,1612907001.440 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:21.980Z,1612907001.980 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:22.041Z,1612907002.041 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000293 sec
2021-02-09T21:43:22.384Z,1612907002.384 [MassServo](INFO): Mass Shifter getPosition after 0.343554 sec
2021-02-09T21:43:22.420Z,1612907002.420 [MassServo](INFO): Mass Shifter done after 0.379474 sec
2021-02-09T21:43:22.784Z,1612907002.784 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:22.838Z,1612907002.838 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000506 sec
2021-02-09T21:43:22.877Z,1612907002.877 [MassServo](INFO): Mass Shifter getPosition after 0.040051 sec
2021-02-09T21:43:22.878Z,1612907002.878 [MassServo](INFO): Mass Shifter done after 0.040851 sec
2021-02-09T21:43:23.240Z,1612907003.240 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:23.293Z,1612907003.293 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000291 sec
2021-02-09T21:43:23.328Z,1612907003.328 [MassServo](INFO): Mass Shifter getPosition after 0.035575 sec
2021-02-09T21:43:23.329Z,1612907003.329 [MassServo](INFO): Mass Shifter done after 0.036168 sec
2021-02-09T21:43:23.692Z,1612907003.692 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:23.777Z,1612907003.777 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000244 sec
2021-02-09T21:43:23.812Z,1612907003.812 [MassServo](INFO): Mass Shifter getPosition after 0.035490 sec
2021-02-09T21:43:23.813Z,1612907003.813 [MassServo](INFO): Mass Shifter done after 0.036079 sec
2021-02-09T21:43:24.172Z,1612907004.172 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:24.172Z,1612907004.172 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-09T21:43:24.172Z,1612907004.172 [AHRS_M2] Communications Fault, FailCount= 3
2021-02-09T21:43:24.172Z,1612907004.172 [AHRS_M2](ERROR): Communications Fault
2021-02-09T21:43:24.229Z,1612907004.229 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000480 sec
2021-02-09T21:43:24.268Z,1612907004.268 [MassServo](INFO): Mass Shifter getPosition after 0.039528 sec
2021-02-09T21:43:24.269Z,1612907004.269 [MassServo](INFO): Mass Shifter done after 0.040107 sec
2021-02-09T21:43:24.303Z,1612907004.303 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-09T21:43:24.313Z,1612907004.313 [AHRS_M2](INFO): Powering down
2021-02-09T21:43:24.465Z,1612907004.465 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000296 sec
2021-02-09T21:43:24.500Z,1612907004.500 [MassServo](INFO): Mass Shifter getPosition after 0.035542 sec
2021-02-09T21:43:24.501Z,1612907004.501 [MassServo](INFO): Mass Shifter done after 0.036123 sec
2021-02-09T21:43:24.809Z,1612907004.809 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000243 sec
2021-02-09T21:43:24.844Z,1612907004.844 [MassServo](INFO): Mass Shifter getPosition after 0.035588 sec
2021-02-09T21:43:24.845Z,1612907004.845 [MassServo](INFO): Mass Shifter done after 0.036179 sec
2021-02-09T21:43:25.189Z,1612907005.189 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000481 sec
2021-02-09T21:43:25.228Z,1612907005.228 [MassServo](INFO): Mass Shifter getPosition after 0.039533 sec
2021-02-09T21:43:25.229Z,1612907005.229 [MassServo](INFO): Mass Shifter done after 0.040104 sec
2021-02-09T21:43:25.261Z,1612907005.261 [CBIT](INFO): Clearing failed state for component AHRS_M2
2021-02-09T21:43:25.261Z,1612907005.261 [AHRS_M2] No Fault, FailCount= 3
2021-02-09T21:43:25.520Z,1612907005.520 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-09T21:43:25.658Z,1612907005.658 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000295 sec
2021-02-09T21:43:25.696Z,1612907005.696 [MassServo](INFO): Mass Shifter getPosition after 0.038981 sec
2021-02-09T21:43:25.697Z,1612907005.697 [MassServo](INFO): Mass Shifter done after 0.039558 sec
2021-02-09T21:43:26.093Z,1612907006.093 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000242 sec
2021-02-09T21:43:26.128Z,1612907006.128 [MassServo](INFO): Mass Shifter getPosition after 0.035596 sec
2021-02-09T21:43:26.144Z,1612907006.144 [MassServo](INFO): Mass Shifter done after 0.051366 sec
2021-02-09T21:43:26.409Z,1612907006.409 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000485 sec
2021-02-09T21:43:26.449Z,1612907006.449 [MassServo](INFO): Mass Shifter getPosition after 0.039932 sec
2021-02-09T21:43:26.449Z,1612907006.449 [MassServo](INFO): Mass Shifter done after 0.040503 sec
2021-02-09T21:43:26.882Z,1612907006.882 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000348 sec
2021-02-09T21:43:26.920Z,1612907006.920 [MassServo](INFO): Mass Shifter getPosition after 0.038953 sec
2021-02-09T21:43:26.921Z,1612907006.921 [MassServo](INFO): Mass Shifter done after 0.039526 sec
2021-02-09T21:43:27.167Z,1612907007.167 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000238 sec
2021-02-09T21:43:27.220Z,1612907007.220 [MassServo](INFO): Mass Shifter getPosition after 0.053438 sec
2021-02-09T21:43:27.221Z,1612907007.221 [MassServo](INFO): Mass Shifter done after 0.054018 sec
2021-02-09T21:43:27.563Z,1612907007.563 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000473 sec
2021-02-09T21:43:27.600Z,1612907007.600 [MassServo](INFO): Mass Shifter getPosition after 0.037545 sec
2021-02-09T21:43:27.601Z,1612907007.601 [MassServo](INFO): Mass Shifter done after 0.038112 sec
2021-02-09T21:43:27.987Z,1612907007.987 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000293 sec
2021-02-09T21:43:28.036Z,1612907008.036 [MassServo](INFO): Mass Shifter getPosition after 0.049330 sec
2021-02-09T21:43:28.037Z,1612907008.037 [MassServo](INFO): Mass Shifter done after 0.049906 sec
2021-02-09T21:43:28.367Z,1612907008.367 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000236 sec
2021-02-09T21:43:28.405Z,1612907008.405 [MassServo](INFO): Mass Shifter getPosition after 0.038128 sec
2021-02-09T21:43:28.405Z,1612907008.405 [MassServo](INFO): Mass Shifter done after 0.038928 sec
2021-02-09T21:43:29.076Z,1612907009.076 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:29.158Z,1612907009.158 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000475 sec
2021-02-09T21:43:29.196Z,1612907009.196 [MassServo](INFO): Mass Shifter getPosition after 0.039044 sec
2021-02-09T21:43:29.197Z,1612907009.197 [MassServo](INFO): Mass Shifter done after 0.039622 sec
2021-02-09T21:43:29.536Z,1612907009.536 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:29.559Z,1612907009.559 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000292 sec
2021-02-09T21:43:29.596Z,1612907009.596 [MassServo](INFO): Mass Shifter getPosition after 0.037528 sec
2021-02-09T21:43:29.597Z,1612907009.597 [MassServo](INFO): Mass Shifter done after 0.038106 sec
2021-02-09T21:43:29.932Z,1612907009.932 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:29.963Z,1612907009.963 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000238 sec
2021-02-09T21:43:30.004Z,1612907010.004 [MassServo](INFO): Mass Shifter getPosition after 0.041549 sec
2021-02-09T21:43:30.005Z,1612907010.005 [MassServo](INFO): Mass Shifter done after 0.042123 sec
2021-02-09T21:43:30.376Z,1612907010.376 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:30.435Z,1612907010.435 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000481 sec
2021-02-09T21:43:30.472Z,1612907010.472 [MassServo](INFO): Mass Shifter getPosition after 0.037488 sec
2021-02-09T21:43:30.473Z,1612907010.473 [MassServo](INFO): Mass Shifter done after 0.038067 sec
2021-02-09T21:43:30.820Z,1612907010.820 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:30.875Z,1612907010.875 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000291 sec
2021-02-09T21:43:30.948Z,1612907010.948 [MassServo](INFO): Mass Shifter getPosition after 0.073196 sec
2021-02-09T21:43:30.949Z,1612907010.949 [MassServo](INFO): Mass Shifter done after 0.073768 sec
2021-02-09T21:43:31.308Z,1612907011.308 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:31.400Z,1612907011.400 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000475 sec
2021-02-09T21:43:31.440Z,1612907011.440 [MassServo](INFO): Mass Shifter getPosition after 0.057152 sec
2021-02-09T21:43:31.441Z,1612907011.441 [MassServo](INFO): Mass Shifter done after 0.057742 sec
2021-02-09T21:43:31.800Z,1612907011.800 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:31.831Z,1612907011.831 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000337 sec
2021-02-09T21:43:31.868Z,1612907011.868 [MassServo](INFO): Mass Shifter getPosition after 0.037750 sec
2021-02-09T21:43:31.869Z,1612907011.869 [MassServo](INFO): Mass Shifter done after 0.038340 sec
2021-02-09T21:43:32.204Z,1612907012.204 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:32.251Z,1612907012.251 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000237 sec
2021-02-09T21:43:32.288Z,1612907012.288 [MassServo](INFO): Mass Shifter getPosition after 0.037418 sec
2021-02-09T21:43:32.289Z,1612907012.289 [MassServo](INFO): Mass Shifter done after 0.037991 sec
2021-02-09T21:43:32.612Z,1612907012.612 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:32.651Z,1612907012.651 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000471 sec
2021-02-09T21:43:32.700Z,1612907012.700 [MassServo](INFO): Mass Shifter getPosition after 0.049417 sec
2021-02-09T21:43:32.701Z,1612907012.701 [MassServo](INFO): Mass Shifter done after 0.049994 sec
2021-02-09T21:43:33.056Z,1612907013.056 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:33.095Z,1612907013.095 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000338 sec
2021-02-09T21:43:33.148Z,1612907013.148 [MassServo](INFO): Mass Shifter getPosition after 0.053552 sec
2021-02-09T21:43:33.149Z,1612907013.149 [MassServo](INFO): Mass Shifter done after 0.054263 sec
2021-02-09T21:43:33.496Z,1612907013.496 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:33.519Z,1612907013.519 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000234 sec
2021-02-09T21:43:33.556Z,1612907013.556 [MassServo](INFO): Mass Shifter getPosition after 0.037582 sec
2021-02-09T21:43:33.557Z,1612907013.557 [MassServo](INFO): Mass Shifter done after 0.038308 sec
2021-02-09T21:43:33.892Z,1612907013.892 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:33.971Z,1612907013.971 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000478 sec
2021-02-09T21:43:34.012Z,1612907014.012 [MassServo](INFO): Mass Shifter getPosition after 0.041587 sec
2021-02-09T21:43:34.013Z,1612907014.013 [MassServo](INFO): Mass Shifter done after 0.042165 sec
2021-02-09T21:43:34.340Z,1612907014.340 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:34.372Z,1612907014.372 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000340 sec
2021-02-09T21:43:34.416Z,1612907014.416 [MassServo](INFO): Mass Shifter getPosition after 0.045134 sec
2021-02-09T21:43:34.417Z,1612907014.417 [MassServo](INFO): Mass Shifter done after 0.045873 sec
2021-02-09T21:43:34.760Z,1612907014.760 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:34.784Z,1612907014.784 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000248 sec
2021-02-09T21:43:34.820Z,1612907014.820 [MassServo](INFO): Mass Shifter getPosition after 0.036100 sec
2021-02-09T21:43:34.821Z,1612907014.821 [MassServo](INFO): Mass Shifter done after 0.036794 sec
2021-02-09T21:43:35.156Z,1612907015.156 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:35.230Z,1612907015.230 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000473 sec
2021-02-09T21:43:35.268Z,1612907015.268 [MassServo](INFO): Mass Shifter getPosition after 0.039225 sec
2021-02-09T21:43:35.269Z,1612907015.269 [MassServo](INFO): Mass Shifter done after 0.039809 sec
2021-02-09T21:43:35.604Z,1612907015.604 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:35.639Z,1612907015.639 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000340 sec
2021-02-09T21:43:35.676Z,1612907015.676 [MassServo](INFO): Mass Shifter getPosition after 0.037951 sec
2021-02-09T21:43:35.677Z,1612907015.677 [MassServo](INFO): Mass Shifter done after 0.038662 sec
2021-02-09T21:43:36.012Z,1612907016.012 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:36.039Z,1612907016.039 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000241 sec
2021-02-09T21:43:36.076Z,1612907016.076 [MassServo](INFO): Mass Shifter getPosition after 0.037850 sec
2021-02-09T21:43:36.077Z,1612907016.077 [MassServo](INFO): Mass Shifter done after 0.038570 sec
2021-02-09T21:43:36.416Z,1612907016.416 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:36.448Z,1612907016.448 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000560 sec
2021-02-09T21:43:36.508Z,1612907016.508 [MassServo](INFO): Mass Shifter getPosition after 0.060575 sec
2021-02-09T21:43:36.509Z,1612907016.509 [MassServo](INFO): Mass Shifter done after 0.061145 sec
2021-02-09T21:43:36.619Z,1612907016.619 [CommandLine](IMPORTANT): got command failComponent
2021-02-09T21:43:36.619Z,1612907016.619 [CommandLine](IMPORTANT): Failed components:
2021-02-09T21:43:36.620Z,1612907016.620 [CommandLine](IMPORTANT): No failed Components.
2021-02-09T21:43:36.836Z,1612907016.836 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:36.883Z,1612907016.883 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000341 sec
2021-02-09T21:43:36.921Z,1612907016.921 [MassServo](INFO): Mass Shifter getPosition after 0.038093 sec
2021-02-09T21:43:36.922Z,1612907016.922 [MassServo](INFO): Mass Shifter done after 0.038776 sec
2021-02-09T21:43:37.244Z,1612907017.244 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:37.287Z,1612907017.287 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000238 sec
2021-02-09T21:43:37.328Z,1612907017.328 [MassServo](INFO): Mass Shifter getPosition after 0.041909 sec
2021-02-09T21:43:37.329Z,1612907017.329 [MassServo](INFO): Mass Shifter done after 0.042492 sec
2021-02-09T21:43:37.664Z,1612907017.664 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:37.695Z,1612907017.695 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000471 sec
2021-02-09T21:43:37.732Z,1612907017.732 [MassServo](INFO): Mass Shifter getPosition after 0.037797 sec
2021-02-09T21:43:38.068Z,1612907018.068 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:38.548Z,1612907018.548 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:38.548Z,1612907018.548 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-09T21:43:38.548Z,1612907018.548 [AHRS_M2] Communications Fault, FailCount= 4
2021-02-09T21:43:38.548Z,1612907018.548 [AHRS_M2](ERROR): Communications Fault
2021-02-09T21:43:38.604Z,1612907018.604 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-09T21:43:38.628Z,1612907018.628 [AHRS_M2](INFO): Powering down
2021-02-09T21:43:39.489Z,1612907019.489 [CBIT](INFO): Clearing failed state for component AHRS_M2
2021-02-09T21:43:39.489Z,1612907019.489 [AHRS_M2] No Fault, FailCount= 4
2021-02-09T21:43:39.836Z,1612907019.836 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-09T21:43:43.382Z,1612907023.382 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:43.788Z,1612907023.788 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:44.200Z,1612907024.200 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:44.616Z,1612907024.616 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:45.036Z,1612907025.036 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:45.472Z,1612907025.472 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:45.896Z,1612907025.896 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:46.336Z,1612907026.336 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:46.772Z,1612907026.772 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:47.228Z,1612907027.228 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:47.681Z,1612907027.681 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:48.112Z,1612907028.112 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:48.708Z,1612907028.708 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:48.714Z,1612907028.714 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:43:48.841Z,1612907028.841 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000291 sec
2021-02-09T21:43:49.180Z,1612907029.180 [MassServo](INFO): Mass Shifter getPosition after 0.339427 sec
2021-02-09T21:43:49.217Z,1612907029.217 [MassServo](INFO): Mass Shifter done after 0.376013 sec
2021-02-09T21:43:49.588Z,1612907029.588 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:49.741Z,1612907029.741 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000494 sec
2021-02-09T21:43:49.782Z,1612907029.782 [MassServo](INFO): Mass Shifter getPosition after 0.040795 sec
2021-02-09T21:43:49.783Z,1612907029.783 [MassServo](INFO): Mass Shifter done after 0.042398 sec
2021-02-09T21:43:50.140Z,1612907030.140 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:50.269Z,1612907030.269 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000295 sec
2021-02-09T21:43:50.304Z,1612907030.304 [MassServo](INFO): Mass Shifter getPosition after 0.035553 sec
2021-02-09T21:43:50.305Z,1612907030.305 [MassServo](INFO): Mass Shifter done after 0.036313 sec
2021-02-09T21:43:50.660Z,1612907030.660 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:50.741Z,1612907030.741 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000241 sec
2021-02-09T21:43:50.777Z,1612907030.777 [MassServo](INFO): Mass Shifter getPosition after 0.035814 sec
2021-02-09T21:43:50.777Z,1612907030.777 [MassServo](INFO): Mass Shifter done after 0.036592 sec
2021-02-09T21:43:51.140Z,1612907031.140 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:51.241Z,1612907031.241 [CommandLine](IMPORTANT): got command failComponent
2021-02-09T21:43:51.241Z,1612907031.241 [CommandLine](IMPORTANT): Failed components:
2021-02-09T21:43:51.241Z,1612907031.241 [CommandLine](IMPORTANT): No failed Components.
2021-02-09T21:43:51.255Z,1612907031.255 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000509 sec
2021-02-09T21:43:51.300Z,1612907031.300 [MassServo](INFO): Mass Shifter getPosition after 0.045967 sec
2021-02-09T21:43:51.301Z,1612907031.301 [MassServo](INFO): Mass Shifter done after 0.046791 sec
2021-02-09T21:43:51.700Z,1612907031.700 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:51.861Z,1612907031.861 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000347 sec
2021-02-09T21:43:51.896Z,1612907031.896 [MassServo](INFO): Mass Shifter getPosition after 0.035881 sec
2021-02-09T21:43:51.897Z,1612907031.897 [MassServo](INFO): Mass Shifter done after 0.036619 sec
2021-02-09T21:43:52.348Z,1612907032.348 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:52.403Z,1612907032.403 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000477 sec
2021-02-09T21:43:52.456Z,1612907032.456 [MassServo](INFO): Mass Shifter getPosition after 0.053432 sec
2021-02-09T21:43:52.457Z,1612907032.457 [MassServo](INFO): Mass Shifter done after 0.054017 sec
2021-02-09T21:43:52.793Z,1612907032.793 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2021-02-09T21:43:52.793Z,1612907032.793 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-09T21:43:52.793Z,1612907032.793 [AHRS_M2] Communications Fault, FailCount= 5
2021-02-09T21:43:52.794Z,1612907032.794 [AHRS_M2](ERROR): Communications Fault
2021-02-09T21:43:52.831Z,1612907032.831 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000342 sec
2021-02-09T21:43:52.929Z,1612907032.929 [MassServo](INFO): Mass Shifter getPosition after 0.098711 sec
2021-02-09T21:43:52.930Z,1612907032.930 [MassServo](INFO): Mass Shifter done after 0.100041 sec
2021-02-09T21:43:52.952Z,1612907032.952 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-09T21:43:52.953Z,1612907032.953 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2021-02-09T21:43:52.978Z,1612907032.978 [AHRS_M2](INFO): Powering down
2021-02-09T21:43:53.108Z,1612907033.108 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000242 sec
2021-02-09T21:43:53.149Z,1612907033.149 [MassServo](INFO): Mass Shifter getPosition after 0.041185 sec
2021-02-09T21:43:53.149Z,1612907033.149 [MassServo](INFO): Mass Shifter done after 0.041769 sec
2021-02-09T21:43:53.153Z,1612907033.153 [CommandLine](FAULT): Scheduling is paused
2021-02-09T21:43:53.153Z,1612907033.153 [CBIT](INFO): Critical error at 20210209T214352
2021-02-09T21:43:53.153Z,1612907033.153 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-02-09T21:43:53.380Z,1612907033.380 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000473 sec
2021-02-09T21:43:53.416Z,1612907033.416 [MassServo](INFO): Mass Shifter getPosition after 0.037420 sec
2021-02-09T21:43:53.417Z,1612907033.417 [MassServo](INFO): Mass Shifter done after 0.038004 sec
2021-02-09T21:43:53.782Z,1612907033.782 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000338 sec
2021-02-09T21:43:53.824Z,1612907033.824 [MassServo](INFO): Mass Shifter getPosition after 0.042509 sec
2021-02-09T21:43:53.825Z,1612907033.825 [MassServo](INFO): Mass Shifter done after 0.043094 sec
2021-02-09T21:43:54.205Z,1612907034.205 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000242 sec
2021-02-09T21:43:54.246Z,1612907034.246 [MassServo](INFO): Mass Shifter getPosition after 0.041178 sec
2021-02-09T21:43:54.247Z,1612907034.247 [MassServo](INFO): Mass Shifter done after 0.041981 sec
2021-02-09T21:43:54.590Z,1612907034.590 [MassServo](INFO): Mass Shifter requestVoltageAndCurrent after 0.000472 sec
2021-02-09T21:43:54.632Z,1612907034.632 [MassServo](INFO): Mass Shifter getPosition after 0.042260 sec
2021-02-09T21:43:59.917Z,1612907039.917 [SBIT](IMPORTANT): SBIT PASSED
2021-02-09T21:43:59.917Z,1612907039.917 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-02-09T21:43:59.918Z,1612907039.918 [SBIT](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2021-02-09T21:43:59.918Z,1612907039.918 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2021-02-09T21:43:59.918Z,1612907039.918 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2021-02-09T21:43:59.918Z,1612907039.918 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2021-02-09T21:43:59.918Z,1612907039.918 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=235 cubic_centimeter;

2021-02-09T21:43:59.919Z,1612907039.919 [SBIT](IMPORTANT): VerticalControl.massDefault=-1 millimeter;

2021-02-09T21:43:59.919Z,1612907039.919 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2021-02-09T21:44:00.241Z,1612907040.241 [MissionManager](IMPORTANT): Started mission Startup
2021-02-09T21:44:00.242Z,1612907040.242 [Startup] Running Loop=1
2021-02-09T21:44:00.242Z,1612907040.242 [Startup](DEBUG): Aggregate::initialize Startup
2021-02-09T21:44:00.242Z,1612907040.242 [Startup:A.GoToSurface] Running Loop=1
2021-02-09T21:44:00.242Z,1612907040.242 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-09T21:44:00.243Z,1612907040.243 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-02-09T21:44:00.243Z,1612907040.243 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-02-09T21:44:00.243Z,1612907040.243 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-02-09T21:44:00.244Z,1612907040.244 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-09T21:44:00.244Z,1612907040.244 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-09T21:44:00.246Z,1612907040.246 [Startup:StartupSatComms] Running Loop=1
2021-02-09T21:44:00.246Z,1612907040.246 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-02-09T21:44:00.246Z,1612907040.246 [Startup:StartupSatComms:A] Running Loop=1
2021-02-09T21:44:00.646Z,1612907040.646 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-02-09T21:44:01.301Z,1612907041.301 [CommandLine](IMPORTANT): got command failComponent hardware ElevatorServo
2021-02-09T21:44:01.302Z,1612907041.302 [ElevatorServo] Hardware Fault, FailCount= 1
2021-02-09T21:44:01.302Z,1612907041.302 [ElevatorServo](ERROR): Hardware Fault
2021-02-09T21:44:01.302Z,1612907041.302 [CommandLine](IMPORTANT): ElevatorServo failureMode is Hardware Fault
2021-02-09T21:44:01.446Z,1612907041.446 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:44:01.532Z,1612907041.532 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-02-09T21:44:01.532Z,1612907041.532 [ElevatorServo](INFO): Powering down
2021-02-09T21:44:01.553Z,1612907041.553 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2021-02-09T21:44:02.322Z,1612907042.322 [CBIT](INFO): Clearing failed state for component ElevatorServo
2021-02-09T21:44:02.323Z,1612907042.323 [ElevatorServo] No Fault, FailCount= 1
2021-02-09T21:44:02.739Z,1612907042.739 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-02-09T21:44:02.744Z,1612907042.744 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-02-09T21:44:13.589Z,1612907053.589 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:44:19.072Z,1612907059.072 [CommandLine](IMPORTANT): got command get platform_elevator_angle
2021-02-09T21:44:19.073Z,1612907059.073 [CommandLine](IMPORTANT): platform_elevator_angle 0.000345 rad
2021-02-09T21:44:25.874Z,1612907065.874 [CommandLine](IMPORTANT): got command get platform_elevator_angle
2021-02-09T21:44:25.875Z,1612907065.875 [CommandLine](IMPORTANT): platform_elevator_angle 0.000345 rad
2021-02-09T21:44:26.090Z,1612907066.090 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:44:29.827Z,1612907069.827 [CommandLine](IMPORTANT): got command get platform_elevator_angle degree
2021-02-09T21:44:29.828Z,1612907069.828 [CommandLine](IMPORTANT): platform_elevator_angle 0.019795 arcdeg
2021-02-09T21:44:38.614Z,1612907078.614 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:44:41.851Z,1612907081.851 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-02-09T21:44:41.872Z,1612907081.872 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-02-09T21:44:41.872Z,1612907081.872 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-02-09T21:44:41.873Z,1612907081.873 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-02-09T21:44:41.873Z,1612907081.873 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2021-02-09T21:44:41.873Z,1612907081.873 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-02-09T21:44:41.895Z,1612907081.895 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-02-09T21:44:41.895Z,1612907081.895 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-02-09T21:44:42.271Z,1612907082.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-02-09T21:44:42.295Z,1612907082.295 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-02-09T21:44:42.296Z,1612907082.296 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-02-09T21:44:42.296Z,1612907082.296 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2021-02-09T21:44:42.669Z,1612907082.669 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-02-09T21:44:44.891Z,1612907084.891 [CommandLine](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 10.000000 degree
2021-02-09T21:44:44.894Z,1612907084.894 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2021-02-09T21:44:45.147Z,1612907085.147 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-02-09T21:44:50.744Z,1612907090.744 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:45:00.458Z,1612907100.458 [Startup:StartupSatComms:A](INFO): Timed out from 2021-02-09T21:44:00.2Z
2021-02-09T21:45:00.458Z,1612907100.458 [Startup:StartupSatComms:A] Stopped
2021-02-09T21:45:00.458Z,1612907100.458 [Startup:StartupSatComms:B] Running Loop=1
2021-02-09T21:45:00.866Z,1612907100.866 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-02-09T21:45:03.454Z,1612907103.454 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:45:11.022Z,1612907111.022 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20210125T190303/Courier0013.lzma
2021-02-09T21:45:12.022Z,1612907112.022 [DataOverHttps](INFO): Moved sent file to Logs/20210125T190303/Courier0013.lzma.bak
2021-02-09T21:45:12.022Z,1612907112.022 [DataOverHttps](INFO): SBD MOMSN=15220563
2021-02-09T21:45:15.585Z,1612907115.585 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:45:22.179Z,1612907122.179 [CommandLine](IMPORTANT): got command show variable elevatorServo.uart
2021-02-09T21:45:22.417Z,1612907122.417 [CommandLine](IMPORTANT): ElevatorServo.uart (none)
2021-02-09T21:45:27.580Z,1612907127.580 [CommandLine](IMPORTANT): got command get ElevatorServo.uart
2021-02-09T21:45:27.580Z,1612907127.580 [CommandLine](IMPORTANT): ElevatorServo.uart /dev/ttyA4
2021-02-09T21:45:28.098Z,1612907128.098 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:45:35.828Z,1612907135.828 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20210125T190303/Courier0016.lzma
2021-02-09T21:45:36.830Z,1612907136.830 [DataOverHttps](INFO): Moved sent file to Logs/20210125T190303/Courier0016.lzma.bak
2021-02-09T21:45:36.830Z,1612907136.830 [DataOverHttps](INFO): SBD MOMSN=15220568
2021-02-09T21:45:40.218Z,1612907140.218 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:45:53.172Z,1612907153.172 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:46:00.829Z,1612907160.829 [Startup:StartupSatComms:B](INFO): Timed out from 2021-02-09T21:45:00.5Z
2021-02-09T21:46:00.829Z,1612907160.829 [Startup:StartupSatComms:B] Stopped
2021-02-09T21:46:00.830Z,1612907160.830 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-02-09T21:46:00.830Z,1612907160.830 [Startup:StartupSatComms] Stopped
2021-02-09T21:46:00.830Z,1612907160.830 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-02-09T21:46:00.830Z,1612907160.830 [Startup](INFO): Completed Startup
2021-02-09T21:46:00.831Z,1612907160.831 [MissionManager](INFO): Startup is completed.
2021-02-09T21:46:00.831Z,1612907160.831 [MissionManager](INFO): Uninitializing Mission Startup
2021-02-09T21:46:00.831Z,1612907160.831 [Startup] Stopped
2021-02-09T21:46:00.831Z,1612907160.831 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-02-09T21:46:00.831Z,1612907160.831 [Startup:A.GoToSurface] Stopped
2021-02-09T21:46:00.831Z,1612907160.831 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-09T21:46:01.254Z,1612907161.254 [MissionManager](IMPORTANT): Started mission Default
2021-02-09T21:46:01.254Z,1612907161.254 [Default] Running Loop=1
2021-02-09T21:46:01.254Z,1612907161.254 [Default](DEBUG): Aggregate::initialize Default
2021-02-09T21:46:01.254Z,1612907161.254 [Default:B.GoToSurface] Running Loop=1
2021-02-09T21:46:01.254Z,1612907161.254 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-09T21:46:01.254Z,1612907161.254 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-02-09T21:46:01.255Z,1612907161.255 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-02-09T21:46:01.255Z,1612907161.255 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-02-09T21:46:01.255Z,1612907161.255 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-09T21:46:01.256Z,1612907161.256 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-09T21:46:01.256Z,1612907161.256 [Default:A.Wait] Running Loop=1
2021-02-09T21:46:01.256Z,1612907161.256 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-02-09T21:46:02.729Z,1612907162.729 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20210125T190303/Courier0019.lzma
2021-02-09T21:46:03.731Z,1612907163.731 [DataOverHttps](INFO): Moved sent file to Logs/20210125T190303/Courier0019.lzma.bak
2021-02-09T21:46:03.731Z,1612907163.731 [DataOverHttps](INFO): SBD MOMSN=15220573
2021-02-09T21:46:05.390Z,1612907165.390 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:46:14.610Z,1612907174.610 [Default:A.Wait](INFO): Done Waiting.
2021-02-09T21:46:14.610Z,1612907174.610 [Default:A.Wait] Stopped
2021-02-09T21:46:14.610Z,1612907174.610 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-02-09T21:46:14.970Z,1612907174.970 [Default:CheckIn] Running Loop=1
2021-02-09T21:46:14.970Z,1612907174.970 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-02-09T21:46:14.970Z,1612907174.970 [Default:CheckIn:Read_GPS] Running Loop=1
2021-02-09T21:46:15.374Z,1612907175.374 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-02-09T21:46:17.386Z,1612907177.386 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:46:29.929Z,1612907189.929 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:46:42.462Z,1612907202.462 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2021-02-09T21:46:42.896Z,1612907202.896 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-02-09T21:46:42.896Z,1612907202.896 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-02-09T21:46:42.896Z,1612907202.896 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-02-09T21:46:42.897Z,1612907202.897 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-02-09T21:46:42.897Z,1612907202.897 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2021-02-09T21:46:42.897Z,1612907202.897 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-02-09T21:46:42.929Z,1612907202.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-02-09T21:46:42.930Z,1612907202.930 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-02-09T21:46:43.315Z,1612907203.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-02-09T21:46:43.315Z,1612907203.315 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-02-09T21:46:43.357Z,1612907203.357 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-02-09T21:46:43.357Z,1612907203.357 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2021-02-09T21:46:43.711Z,1612907203.711 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-02-09T21:46:45.820Z,1612907205.820 [CommandLine](IMPORTANT): got command restart application
2021-02-09T21:46:46.824Z,1612907206.824 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T21:46:46.824Z,1612907206.824 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:46.876Z,1612907206.876 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-02-09T21:46:46.876Z,1612907206.876 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:46.877Z,1612907206.877 [CommandLine](INFO): Join timeout helper Thread ID is 4638
2021-02-09T21:46:46.879Z,1612907206.879 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-02-09T21:46:46.879Z,1612907206.879 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:46.880Z,1612907206.880 [NavChartDb](INFO): Join timeout helper Thread ID is 4639
2021-02-09T21:46:47.052Z,1612907207.052 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T21:46:47.052Z,1612907207.052 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:47.060Z,1612907207.060 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-02-09T21:46:47.060Z,1612907207.060 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:47.060Z,1612907207.060 [Radio_Surface](INFO): Join timeout helper Thread ID is 4640
2021-02-09T21:46:47.396Z,1612907207.396 [Radio_Surface](INFO): Powering down
2021-02-09T21:46:47.397Z,1612907207.397 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T21:46:47.397Z,1612907207.397 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:47.400Z,1612907207.400 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-02-09T21:46:47.400Z,1612907207.400 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:47.400Z,1612907207.400 [Onboard](INFO): Join timeout helper Thread ID is 4641
2021-02-09T21:46:51.796Z,1612907211.796 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T21:46:51.796Z,1612907211.796 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:51.804Z,1612907211.804 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-02-09T21:46:51.804Z,1612907211.804 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:51.804Z,1612907211.804 [DataOverHttps](INFO): Join timeout helper Thread ID is 4642
2021-02-09T21:46:52.176Z,1612907212.176 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:5323992 %. Average:532434.812500
2021-02-09T21:46:52.628Z,1612907212.628 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T21:46:52.632Z,1612907212.632 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:52.653Z,1612907212.653 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-02-09T21:46:52.653Z,1612907212.653 [logger ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:52.654Z,1612907212.654 [logger](INFO): Join timeout helper Thread ID is 4643
2021-02-09T21:46:52.700Z,1612907212.700 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T21:46:52.700Z,1612907212.700 [logger ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:52.712Z,1612907212.712 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-02-09T21:46:52.712Z,1612907212.712 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:52.713Z,1612907212.713 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-02-09T21:46:52.713Z,1612907212.713 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:52.713Z,1612907212.713 [controlThread](INFO): Join timeout helper Thread ID is 4644
2021-02-09T21:46:52.956Z,1612907212.956 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-09T21:46:52.956Z,1612907212.956 [controlThread](DEBUG): Uninitializing ControlThread
2021-02-09T21:46:52.956Z,1612907212.956 [AHRS_M2](INFO): Powering down
2021-02-09T21:46:52.957Z,1612907212.957 [NAL9602](INFO): Powering down
2021-02-09T21:46:52.960Z,1612907212.960 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-02-09T21:46:52.961Z,1612907212.961 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-02-09T21:46:52.962Z,1612907212.962 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-02-09T21:46:52.962Z,1612907212.962 [MissionManager](INFO): Uninitializing Mission Default
2021-02-09T21:46:52.962Z,1612907212.962 [Default] Stopped
2021-02-09T21:46:52.962Z,1612907212.962 [Default](DEBUG): Aggregate::uninitialize Default
2021-02-09T21:46:52.962Z,1612907212.962 [Default:B.GoToSurface] Stopped
2021-02-09T21:46:52.962Z,1612907212.962 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-09T21:46:52.963Z,1612907212.963 [Default:CheckIn] Stopped
2021-02-09T21:46:52.963Z,1612907212.963 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-02-09T21:46:52.963Z,1612907212.963 [Default:CheckIn:Read_GPS] Stopped
2021-02-09T21:46:52.966Z,1612907212.966 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-02-09T21:46:52.966Z,1612907212.966 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-02-09T21:46:52.966Z,1612907212.966 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-02-09T21:46:52.966Z,1612907212.966 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-02-09T21:46:52.967Z,1612907212.967 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-02-09T21:46:52.967Z,1612907212.967 [BuoyancyServo](INFO): Powering down
2021-02-09T21:46:52.980Z,1612907212.980 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-02-09T21:46:52.980Z,1612907212.980 [ElevatorServo](INFO): Powering down
2021-02-09T21:46:52.981Z,1612907212.981 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-02-09T21:46:52.981Z,1612907212.981 [MassServo](INFO): Powering down
2021-02-09T21:46:52.981Z,1612907212.981 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-09T21:46:52.982Z,1612907212.982 [RudderServo](INFO): Powering down
2021-02-09T21:46:52.982Z,1612907212.982 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-02-09T21:46:52.982Z,1612907212.982 [ThrusterServo](INFO): Powering down
2021-02-09T21:46:52.983Z,1612907212.983 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-02-09T21:46:52.984Z,1612907212.984 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-02-09T21:46:52.984Z,1612907212.984 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-02-09T21:46:52.984Z,1612907212.984 [CBIT](DEBUG): Powering off loads.
2021-02-09T21:46:52.995Z,1612907212.995 [CBIT](DEBUG): Disabling WDT.
2021-02-09T21:46:53.007Z,1612907213.007 [CBIT](DEBUG): Opening all GF detection circuits.
2021-02-09T21:46:53.008Z,1612907213.008 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:53.114Z,1612907213.114 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:53.117Z,1612907213.117 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:53.122Z,1612907213.122 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:53.163Z,1612907213.163 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-09T21:46:53.229Z,1612907213.229 [logger ThreadHandler](INFO): Thread cancelled.