2021-07-22T20:44:42.179Z,1626986682.179 [Supervisor](DEBUG): Initializing supervisor.
2021-07-22T20:44:42.184Z,1626986682.184 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-07-22T20:44:42.184Z,1626986682.184 [SyncHandler](INFO): Protected caller Thread ID is 7235
2021-07-22T20:44:42.185Z,1626986682.185 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-07-22T20:44:42.186Z,1626986682.186 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-07-22T20:44:42.186Z,1626986682.186 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7236
2021-07-22T20:44:42.190Z,1626986682.190 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-07-22T20:44:42.211Z,1626986682.211 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-07-22T20:44:42.212Z,1626986682.212 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-07-22T20:44:42.212Z,1626986682.212 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7237
2021-07-22T20:44:42.214Z,1626986682.214 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-07-22T20:44:42.215Z,1626986682.215 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-07-22T20:44:42.215Z,1626986682.215 [logger ThreadHandler](INFO): Protected caller Thread ID is 7238
2021-07-22T20:44:42.219Z,1626986682.219 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-07-22T20:44:42.220Z,1626986682.220 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-07-22T20:44:42.221Z,1626986682.221 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-07-22T20:44:42.642Z,1626986682.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-07-22T20:44:42.642Z,1626986682.642 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-07-22T20:44:42.858Z,1626986682.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-07-22T20:44:42.859Z,1626986682.859 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-07-22T20:44:43.052Z,1626986683.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-07-22T20:44:43.052Z,1626986683.052 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-07-22T20:44:43.161Z,1626986683.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-07-22T20:44:43.161Z,1626986683.161 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-07-22T20:44:43.532Z,1626986683.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-07-22T20:44:43.533Z,1626986683.533 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-07-22T20:44:43.870Z,1626986683.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-07-22T20:44:43.871Z,1626986683.871 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-07-22T20:44:43.968Z,1626986683.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-07-22T20:44:43.968Z,1626986683.968 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-07-22T20:44:44.119Z,1626986684.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-07-22T20:44:44.120Z,1626986684.120 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-07-22T20:44:44.662Z,1626986684.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-07-22T20:44:44.663Z,1626986684.663 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-07-22T20:44:45.024Z,1626986685.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-07-22T20:44:45.024Z,1626986685.024 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-07-22T20:44:45.106Z,1626986685.106 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-07-22T20:44:45.314Z,1626986685.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-07-22T20:44:45.315Z,1626986685.315 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-07-22T20:44:45.443Z,1626986685.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-07-22T20:44:45.443Z,1626986685.443 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-07-22T20:44:46.117Z,1626986686.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-07-22T20:44:46.117Z,1626986686.117 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-07-22T20:44:46.197Z,1626986686.197 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-07-22T20:44:46.464Z,1626986686.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-07-22T20:44:46.466Z,1626986686.466 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2021-07-22T20:44:46.467Z,1626986686.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2021-07-22T20:44:46.937Z,1626986686.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2021-07-22T20:44:47.036Z,1626986687.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2021-07-22T20:44:47.358Z,1626986687.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2021-07-22T20:44:47.462Z,1626986687.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2021-07-22T20:44:47.546Z,1626986687.546 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2021-07-22T20:44:47.633Z,1626986687.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2021-07-22T20:44:47.768Z,1626986687.768 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2021-07-22T20:44:47.868Z,1626986687.868 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2021-07-22T20:44:48.013Z,1626986688.013 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2021-07-22T20:44:48.277Z,1626986688.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-07-22T20:44:48.277Z,1626986688.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2021-07-22T20:44:48.372Z,1626986688.372 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-07-22T20:44:48.384Z,1626986688.384 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-07-22T20:44:48.730Z,1626986688.730 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-07-22T20:44:48.731Z,1626986688.731 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-07-22T20:44:48.816Z,1626986688.816 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-07-22T20:44:48.817Z,1626986688.817 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-07-22T20:44:48.852Z,1626986688.852 [DeadReckonUsingSpeedCalculator] Loaded
2021-07-22T20:44:48.852Z,1626986688.852 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2021-07-22T20:44:48.865Z,1626986688.865 [NavChart] Loaded
2021-07-22T20:44:48.865Z,1626986688.865 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-07-22T20:44:48.871Z,1626986688.871 [UniversalFixResidualReporter] Loaded
2021-07-22T20:44:48.871Z,1626986688.871 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-07-22T20:44:48.872Z,1626986688.872 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-07-22T20:44:48.872Z,1626986688.872 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-07-22T20:44:48.883Z,1626986688.883 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-07-22T20:44:48.884Z,1626986688.884 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-07-22T20:44:49.062Z,1626986689.062 [ESPComponent] Loaded
2021-07-22T20:44:49.062Z,1626986689.062 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2021-07-22T20:44:49.063Z,1626986689.063 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-07-22T20:44:49.064Z,1626986689.064 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-07-22T20:44:49.119Z,1626986689.119 [VerticalControl](DEBUG): Construct VerticalControl.
2021-07-22T20:44:49.181Z,1626986689.181 [VerticalControl] Loaded
2021-07-22T20:44:49.181Z,1626986689.181 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-07-22T20:44:49.184Z,1626986689.184 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-07-22T20:44:49.222Z,1626986689.222 [HorizontalControl] Loaded
2021-07-22T20:44:49.223Z,1626986689.223 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-07-22T20:44:49.225Z,1626986689.225 [SpeedControl](DEBUG): Construct SpeedControl.
2021-07-22T20:44:49.228Z,1626986689.228 [SpeedControl] Loaded
2021-07-22T20:44:49.229Z,1626986689.229 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-07-22T20:44:49.231Z,1626986689.231 [LoopControl](DEBUG): Construct LoopControl.
2021-07-22T20:44:49.232Z,1626986689.232 [LoopControl] Loaded
2021-07-22T20:44:49.232Z,1626986689.232 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-07-22T20:44:49.232Z,1626986689.232 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-07-22T20:44:49.233Z,1626986689.233 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-07-22T20:44:49.277Z,1626986689.277 [DepthRateCalculator] Loaded
2021-07-22T20:44:49.278Z,1626986689.278 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-07-22T20:44:49.282Z,1626986689.282 [PitchRateCalculator] Loaded
2021-07-22T20:44:49.283Z,1626986689.283 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-07-22T20:44:49.292Z,1626986689.292 [SpeedCalculator] Loaded
2021-07-22T20:44:49.292Z,1626986689.292 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-07-22T20:44:49.306Z,1626986689.306 [TempGradientCalculator] Loaded
2021-07-22T20:44:49.306Z,1626986689.306 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-07-22T20:44:49.311Z,1626986689.311 [YawRateCalculator] Loaded
2021-07-22T20:44:49.311Z,1626986689.311 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-07-22T20:44:49.329Z,1626986689.329 [ElevatorOffsetCalculator] Loaded
2021-07-22T20:44:49.329Z,1626986689.329 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-07-22T20:44:49.329Z,1626986689.329 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-07-22T20:44:49.330Z,1626986689.330 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-07-22T20:44:49.431Z,1626986689.431 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-07-22T20:44:49.431Z,1626986689.431 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-07-22T20:44:49.499Z,1626986689.499 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-07-22T20:44:49.499Z,1626986689.499 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-07-22T20:44:49.628Z,1626986689.628 [BuoyancyServo] Loaded
2021-07-22T20:44:49.628Z,1626986689.628 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-07-22T20:44:49.647Z,1626986689.647 [ElevatorServo] Loaded
2021-07-22T20:44:49.648Z,1626986689.648 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-07-22T20:44:49.666Z,1626986689.666 [RudderServo] Loaded
2021-07-22T20:44:49.666Z,1626986689.666 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-07-22T20:44:49.684Z,1626986689.684 [ThrusterServo] Loaded
2021-07-22T20:44:49.684Z,1626986689.684 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-07-22T20:44:49.685Z,1626986689.685 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-07-22T20:44:49.685Z,1626986689.685 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-07-22T20:44:49.798Z,1626986689.798 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-07-22T20:44:49.799Z,1626986689.799 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-07-22T20:44:50.958Z,1626986690.958 [BPC1] Loaded
2021-07-22T20:44:50.959Z,1626986690.959 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-07-22T20:44:51.030Z,1626986691.030 [DataOverHttps] Loaded
2021-07-22T20:44:51.031Z,1626986691.031 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-07-22T20:44:51.032Z,1626986691.032 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409B34E0
2021-07-22T20:44:51.032Z,1626986691.032 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7321
2021-07-22T20:44:51.053Z,1626986691.053 [Depth_Keller] Loaded
2021-07-22T20:44:51.053Z,1626986691.053 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-07-22T20:44:51.113Z,1626986691.113 [NAL9602] Loaded
2021-07-22T20:44:51.114Z,1626986691.114 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-07-22T20:44:51.141Z,1626986691.141 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2021-07-22T20:44:51.141Z,1626986691.141 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2021-07-22T20:44:51.142Z,1626986691.142 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2021-07-22T20:44:51.142Z,1626986691.142 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2021-07-22T20:44:51.143Z,1626986691.143 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2021-07-22T20:44:51.144Z,1626986691.144 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2021-07-22T20:44:51.144Z,1626986691.144 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2021-07-22T20:44:51.145Z,1626986691.145 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2021-07-22T20:44:51.145Z,1626986691.145 [Onboard] Loaded
2021-07-22T20:44:51.145Z,1626986691.145 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-07-22T20:44:51.146Z,1626986691.146 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409E34E0
2021-07-22T20:44:51.146Z,1626986691.146 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7322
2021-07-22T20:44:51.160Z,1626986691.160 [Power24vConverter] Loaded
2021-07-22T20:44:51.160Z,1626986691.160 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-07-22T20:44:51.173Z,1626986691.173 [Radio_Surface] Loaded
2021-07-22T20:44:51.174Z,1626986691.174 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-07-22T20:44:51.175Z,1626986691.175 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A134E0
2021-07-22T20:44:51.175Z,1626986691.175 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7323
2021-07-22T20:44:51.259Z,1626986691.259 [DAT] Loaded
2021-07-22T20:44:51.259Z,1626986691.259 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-07-22T20:44:51.260Z,1626986691.260 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-07-22T20:44:51.260Z,1626986691.260 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-07-22T20:44:51.416Z,1626986691.416 [SBIT](DEBUG): Construct Startup Built In Test.
2021-07-22T20:44:51.425Z,1626986691.425 [SBIT] Loaded
2021-07-22T20:44:51.425Z,1626986691.425 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-07-22T20:44:51.428Z,1626986691.428 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-07-22T20:44:51.442Z,1626986691.442 [IBIT] Loaded
2021-07-22T20:44:51.442Z,1626986691.442 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-07-22T20:44:51.448Z,1626986691.448 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-07-22T20:44:51.550Z,1626986691.550 [CBIT] Loaded
2021-07-22T20:44:51.551Z,1626986691.551 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-07-22T20:44:51.551Z,1626986691.551 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-07-22T20:44:51.552Z,1626986691.552 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-07-22T20:44:51.621Z,1626986691.621 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-07-22T20:44:51.627Z,1626986691.627 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-07-22T20:44:51.630Z,1626986691.630 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-07-22T20:44:51.673Z,1626986691.673 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-07-22T20:44:51.680Z,1626986691.680 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A9E4E0
2021-07-22T20:44:51.680Z,1626986691.680 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7324
2021-07-22T20:44:51.685Z,1626986691.685 [Supervisor](INFO): Main Thread ID is 6160
2021-07-22T20:44:51.685Z,1626986691.685 [Supervisor](DEBUG): Running supervisor.
2021-07-22T20:44:51.686Z,1626986691.686 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7325
2021-07-22T20:44:51.688Z,1626986691.688 [controlThread ThreadHandler](INFO): Handler Thread ID is 7326
2021-07-22T20:44:51.689Z,1626986691.689 [controlThread](DEBUG): Initializing ControlThread
2021-07-22T20:44:51.690Z,1626986691.690 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:44:51.691Z,1626986691.691 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-07-22T20:44:51.691Z,1626986691.691 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-07-22T20:44:51.692Z,1626986691.692 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-07-22T20:44:51.694Z,1626986691.694 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-07-22T20:44:51.695Z,1626986691.695 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-07-22T20:44:51.695Z,1626986691.695 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-07-22T20:44:51.695Z,1626986691.695 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-07-22T20:44:51.696Z,1626986691.696 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-07-22T20:44:51.696Z,1626986691.696 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-07-22T20:44:51.697Z,1626986691.697 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-07-22T20:44:51.698Z,1626986691.698 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-07-22T20:44:51.698Z,1626986691.698 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-07-22T20:44:51.709Z,1626986691.709 [SBIT](INFO): Initialize SBIT Component.
2021-07-22T20:44:51.709Z,1626986691.709 [SBIT](IMPORTANT): git: 2021-06-24-7-gcba2ba184
2021-07-22T20:44:51.709Z,1626986691.709 [SBIT](INFO): git hash: cba2ba184a996ac72cd367cf95917907d16cfa04
2021-07-22T20:44:51.710Z,1626986691.710 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-07-22T20:44:51.711Z,1626986691.711 [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-07-22T20:44:51.712Z,1626986691.712 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2021-07-22T20:44:51.713Z,1626986691.713 [IBIT](INFO): Initialize IBIT Component.
2021-07-22T20:44:51.714Z,1626986691.714 [CBIT](DEBUG): Initialize CBIT Component.
2021-07-22T20:44:51.720Z,1626986691.720 [logger ThreadHandler](INFO): Handler Thread ID is 7327
2021-07-22T20:44:51.740Z,1626986691.740 [CBIT](DEBUG): Initialized mux pins.
2021-07-22T20:44:51.740Z,1626986691.740 [CBIT](DEBUG): Initializing the watchdog timer.
2021-07-22T20:44:51.745Z,1626986691.745 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7328
2021-07-22T20:44:51.746Z,1626986691.746 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-07-22T20:44:51.749Z,1626986691.749 [Onboard ThreadHandler](INFO): Handler Thread ID is 7329
2021-07-22T20:44:51.750Z,1626986691.750 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7330
2021-07-22T20:44:51.751Z,1626986691.751 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7331
2021-07-22T20:44:51.755Z,1626986691.755 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-07-22T20:44:51.755Z,1626986691.755 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-07-22T20:44:51.755Z,1626986691.755 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-07-22T20:44:51.756Z,1626986691.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-07-22T20:44:51.756Z,1626986691.756 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-07-22T20:44:51.756Z,1626986691.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-07-22T20:44:51.757Z,1626986691.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-07-22T20:44:51.757Z,1626986691.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-07-22T20:44:51.757Z,1626986691.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-07-22T20:44:51.757Z,1626986691.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-07-22T20:44:51.757Z,1626986691.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-07-22T20:44:51.757Z,1626986691.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-07-22T20:44:51.758Z,1626986691.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-07-22T20:44:51.758Z,1626986691.758 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-07-22T20:44:51.758Z,1626986691.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-07-22T20:44:51.758Z,1626986691.758 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-07-22T20:44:51.765Z,1626986691.765 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-07-22T20:44:51.769Z,1626986691.769 [CBIT](DEBUG): Initializing heartbeat.
2021-07-22T20:44:51.840Z,1626986691.840 [CBIT](DEBUG): Deactivating GF circuits.
2021-07-22T20:44:51.840Z,1626986691.840 [CBIT](DEBUG): Deactivating emergency mode.
2021-07-22T20:44:51.876Z,1626986691.876 [CBIT](DEBUG): Backplane powered.
2021-07-22T20:44:51.877Z,1626986691.877 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-07-22T20:44:51.890Z,1626986691.890 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-07-22T20:44:51.910Z,1626986691.910 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2021-07-22T20:44:51.911Z,1626986691.911 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-07-22T20:44:52.005Z,1626986692.005 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-07-22T20:44:52.007Z,1626986692.007 [Default:A.Wait](DEBUG): Construct Wait.
2021-07-22T20:44:52.009Z,1626986692.009 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-07-22T20:44:52.054Z,1626986692.054 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-07-22T20:44:52.056Z,1626986692.056 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-07-22T20:44:52.083Z,1626986692.083 [Default:E.Execute](DEBUG): Construct Execute.
2021-07-22T20:44:52.086Z,1626986692.086 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2021-07-22T20:44:52.091Z,1626986692.091 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,BPC1,Depth_Keller,NAL9602,Power24vConverter,DAT,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-07-22T20:44:52.161Z,1626986692.161 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2021-07-22T20:44:52.161Z,1626986692.161 [Power24vConverter](INFO): Powering up.
2021-07-22T20:44:52.162Z,1626986692.162 [DAT](INFO): Powering up
2021-07-22T20:44:52.162Z,1626986692.162 [DAT](DEBUG): Initializing DAT.
2021-07-22T20:44:52.164Z,1626986692.164 [Radio_Surface](INFO): Powering up
2021-07-22T20:44:52.184Z,1626986692.184 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-07-22T20:44:52.197Z,1626986692.197 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-07-22T20:44:52.204Z,1626986692.204 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-07-22T20:44:52.205Z,1626986692.205 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-07-22T20:44:52.212Z,1626986692.212 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-07-22T20:44:52.213Z,1626986692.213 [RudderServo](DEBUG): Initializing EZServoServo.
2021-07-22T20:44:52.220Z,1626986692.220 [RudderServo](DEBUG): Initializing RudderServo.
2021-07-22T20:44:52.221Z,1626986692.221 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-07-22T20:44:52.228Z,1626986692.228 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-07-22T20:45:12.311Z,1626986712.311 [SBIT](IMPORTANT): Beginning Startup BIT
2021-07-22T20:45:12.315Z,1626986712.315 [CBIT](IMPORTANT): Beginning ground fault scan
2021-07-22T20:45:21.185Z,1626986721.185 [NAL9602](INFO): Powering up NAL9602
2021-07-22T20:45:23.226Z,1626986723.226 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008519
CHAN A1 (24V): 0.001177
CHAN A2 (12V): -0.005646
CHAN A3 (5V): -0.003182
CHAN B0 (3.3V): -0.000893
CHAN B1 (3.15aV): -0.001155
CHAN B2 (3.15bV): -0.001456
CHAN B3 (GND): -0.000200
OPEN: -0.001534
Full Scale Calc: 4.765 mA, -1.589 mA
2021-07-22T20:45:25.655Z,1626986725.655 [SBIT](ERROR): Could not read massPosReader_.
2021-07-22T20:45:32.093Z,1626986732.093 [NAL9602](INFO): NAL9602 initialized
2021-07-22T20:45:52.298Z,1626986752.298 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-07-22T20:45:52.298Z,1626986752.298 [DAT] Communications Fault, FailCount= 1
2021-07-22T20:45:52.298Z,1626986752.298 [DAT](ERROR): Communications Fault
2021-07-22T20:45:52.310Z,1626986752.310 [SBIT](ERROR): Could not read massPosReader_.
2021-07-22T20:45:52.311Z,1626986752.311 [SBIT](FAULT): Control surface position failure.
2021-07-22T20:45:52.314Z,1626986752.314 [CBIT](ERROR): Communications Fault in component: DAT
2021-07-22T20:45:52.730Z,1626986752.730 [DAT](INFO): Powering down
2021-07-22T20:45:53.533Z,1626986753.533 [CBIT](INFO): Clearing failed state for component DAT
2021-07-22T20:45:53.533Z,1626986753.533 [DAT] No Fault, FailCount= 1
2021-07-22T20:45:55.948Z,1626986755.948 [DAT](INFO): Powering up
2021-07-22T20:45:55.948Z,1626986755.948 [DAT](DEBUG): Initializing DAT.
2021-07-22T20:46:06.054Z,1626986766.054 [SBIT](ERROR): Could not read massPosReader_.
2021-07-22T20:46:06.054Z,1626986766.054 [SBIT](FAULT): Control surface position failure.
2021-07-22T20:46:06.471Z,1626986766.471 [SBIT](CRITICAL): SBIT FAILED
2021-07-22T20:46:06.471Z,1626986766.471 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-07-22T20:46:06.473Z,1626986766.473 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2021-07-22T20:46:06.473Z,1626986766.473 [SBIT](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2021-07-22T20:46:06.475Z,1626986766.475 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2021-07-22T20:46:06.475Z,1626986766.475 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2021-07-22T20:46:06.475Z,1626986766.475 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2021-07-22T20:46:06.476Z,1626986766.476 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2021-07-22T20:46:06.476Z,1626986766.476 [SBIT](IMPORTANT): MassServo.loadAtStartup=0 bool;

2021-07-22T20:46:06.476Z,1626986766.476 [SBIT](IMPORTANT): MassServo.overloadTimeout=10 second;

2021-07-22T20:46:06.476Z,1626986766.476 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2021-07-22T20:46:06.476Z,1626986766.476 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=235 cubic_centimeter;

2021-07-22T20:46:06.476Z,1626986766.476 [SBIT](IMPORTANT): VerticalControl.massDefault=0 centimeter;

2021-07-22T20:46:06.476Z,1626986766.476 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2021-07-22T20:46:06.493Z,1626986766.493 [CommandLine](FAULT): Scheduling is paused
2021-07-22T20:46:06.493Z,1626986766.493 [CBIT](INFO): Critical error at 20210722T204606
2021-07-22T20:46:06.493Z,1626986766.493 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-07-22T20:46:06.871Z,1626986766.871 [MissionManager](IMPORTANT): Started mission Startup
2021-07-22T20:46:06.872Z,1626986766.872 [Startup] Running Loop=1
2021-07-22T20:46:06.872Z,1626986766.872 [Startup](DEBUG): Aggregate::initialize Startup
2021-07-22T20:46:06.872Z,1626986766.872 [Startup:A.GoToSurface] Running Loop=1
2021-07-22T20:46:06.872Z,1626986766.872 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-22T20:46:06.873Z,1626986766.873 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-22T20:46:06.873Z,1626986766.873 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-22T20:46:06.873Z,1626986766.873 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-22T20:46:06.874Z,1626986766.874 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-22T20:46:06.874Z,1626986766.874 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-22T20:46:06.876Z,1626986766.876 [Startup:StartupSatComms] Running Loop=1
2021-07-22T20:46:06.876Z,1626986766.876 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-07-22T20:46:06.876Z,1626986766.876 [Startup:StartupSatComms:A] Running Loop=1
2021-07-22T20:46:07.276Z,1626986767.276 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-07-22T20:46:51.691Z,1626986811.691 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-07-22T20:46:51.692Z,1626986811.692 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-07-22T20:46:51.692Z,1626986811.692 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:46:51.693Z,1626986811.693 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-07-22T20:46:51.693Z,1626986811.693 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2021-07-22T20:46:51.693Z,1626986811.693 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:46:51.704Z,1626986811.704 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:46:51.705Z,1626986811.705 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:46:52.116Z,1626986812.116 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:46:52.116Z,1626986812.116 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-07-22T20:46:52.117Z,1626986812.117 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:46:52.117Z,1626986812.117 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2021-07-22T20:46:52.552Z,1626986812.552 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:46:56.130Z,1626986816.130 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-07-22T20:46:56.130Z,1626986816.130 [DAT] Communications Fault, FailCount= 2
2021-07-22T20:46:56.130Z,1626986816.130 [DAT](ERROR): Communications Fault
2021-07-22T20:46:56.146Z,1626986816.146 [CBIT](ERROR): Communications Fault in component: DAT
2021-07-22T20:46:56.535Z,1626986816.535 [DAT](INFO): Powering down
2021-07-22T20:46:57.357Z,1626986817.357 [CBIT](INFO): Clearing failed state for component DAT
2021-07-22T20:46:57.357Z,1626986817.357 [DAT] No Fault, FailCount= 2
2021-07-22T20:46:59.836Z,1626986819.836 [DAT](INFO): Powering up
2021-07-22T20:46:59.836Z,1626986819.836 [DAT](DEBUG): Initializing DAT.
2021-07-22T20:47:07.056Z,1626986827.056 [Startup:StartupSatComms:A](INFO): Timed out from 2021-07-22T20:46:06.9Z
2021-07-22T20:47:07.056Z,1626986827.056 [Startup:StartupSatComms:A] Stopped
2021-07-22T20:47:07.056Z,1626986827.056 [Startup:StartupSatComms:B] Running Loop=1
2021-07-22T20:47:07.448Z,1626986827.448 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-07-22T20:47:19.347Z,1626986839.347 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005028
2021-07-22T20:47:26.324Z,1626986846.324 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20210722T203244/Courier0007.lzma
2021-07-22T20:47:27.326Z,1626986847.326 [DataOverHttps](INFO): Moved sent file to Logs/20210722T203244/Courier0007.lzma.bak
2021-07-22T20:47:27.326Z,1626986847.326 [DataOverHttps](INFO): SBD MOMSN=15753875
2021-07-22T20:47:42.719Z,1626986862.719 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210722T204442/Courier0000.lzma
2021-07-22T20:47:43.721Z,1626986863.721 [DataOverHttps](INFO): Moved sent file to Logs/20210722T204442/Courier0000.lzma.bak
2021-07-22T20:47:43.722Z,1626986863.722 [DataOverHttps](INFO): SBD MOMSN=15753879
2021-07-22T20:47:59.977Z,1626986879.977 [DataOverHttps](INFO): Sending 852 bytes from file Logs/20210722T203244/Express0008.lzma
2021-07-22T20:47:59.989Z,1626986879.989 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-07-22T20:47:59.989Z,1626986879.989 [DAT] Communications Fault, FailCount= 3
2021-07-22T20:47:59.989Z,1626986879.989 [DAT](ERROR): Communications Fault
2021-07-22T20:48:00.010Z,1626986880.010 [CBIT](ERROR): Communications Fault in component: DAT
2021-07-22T20:48:00.382Z,1626986880.382 [DAT](INFO): Powering down
2021-07-22T20:48:00.978Z,1626986880.978 [DataOverHttps](INFO): Moved sent file to Logs/20210722T203244/Express0008.lzma.bak
2021-07-22T20:48:00.978Z,1626986880.978 [DataOverHttps](INFO): SBD MOMSN=15753883
2021-07-22T20:48:01.192Z,1626986881.192 [CBIT](INFO): Clearing failed state for component DAT
2021-07-22T20:48:01.193Z,1626986881.193 [DAT] No Fault, FailCount= 3
2021-07-22T20:48:03.602Z,1626986883.602 [DAT](INFO): Powering up
2021-07-22T20:48:03.602Z,1626986883.602 [DAT](DEBUG): Initializing DAT.
2021-07-22T20:48:07.258Z,1626986887.258 [Startup:StartupSatComms:B](INFO): Timed out from 2021-07-22T20:47:07.1Z
2021-07-22T20:48:07.258Z,1626986887.258 [Startup:StartupSatComms:B] Stopped
2021-07-22T20:48:07.258Z,1626986887.258 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-07-22T20:48:07.258Z,1626986887.258 [Startup:StartupSatComms] Stopped
2021-07-22T20:48:07.259Z,1626986887.259 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-07-22T20:48:07.259Z,1626986887.259 [Startup](INFO): Completed Startup
2021-07-22T20:48:07.260Z,1626986887.260 [MissionManager](INFO): Startup is completed.
2021-07-22T20:48:07.260Z,1626986887.260 [MissionManager](INFO): Uninitializing Mission Startup
2021-07-22T20:48:07.260Z,1626986887.260 [Startup] Stopped
2021-07-22T20:48:07.260Z,1626986887.260 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-07-22T20:48:07.260Z,1626986887.260 [Startup:A.GoToSurface] Stopped
2021-07-22T20:48:07.260Z,1626986887.260 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-22T20:48:07.661Z,1626986887.661 [MissionManager](IMPORTANT): Started mission Default
2021-07-22T20:48:07.661Z,1626986887.661 [Default] Running Loop=1
2021-07-22T20:48:07.661Z,1626986887.661 [Default](DEBUG): Aggregate::initialize Default
2021-07-22T20:48:07.661Z,1626986887.661 [Default:B.GoToSurface] Running Loop=1
2021-07-22T20:48:07.661Z,1626986887.661 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-22T20:48:07.662Z,1626986887.662 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-22T20:48:07.662Z,1626986887.662 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-22T20:48:07.662Z,1626986887.662 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-22T20:48:07.663Z,1626986887.663 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-22T20:48:07.663Z,1626986887.663 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-22T20:48:07.664Z,1626986887.664 [Default:A.Wait] Running Loop=1
2021-07-22T20:48:07.664Z,1626986887.664 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-07-22T20:48:17.005Z,1626986897.005 [DataOverHttps](INFO): Sending 989 bytes from file Logs/20210722T204442/Express0001.lzma
2021-07-22T20:48:18.006Z,1626986898.006 [DataOverHttps](INFO): Moved sent file to Logs/20210722T204442/Express0001.lzma.bak
2021-07-22T20:48:18.006Z,1626986898.006 [DataOverHttps](INFO): SBD MOMSN=15753906
2021-07-22T20:48:20.981Z,1626986900.981 [Default:A.Wait](INFO): Done Waiting.
2021-07-22T20:48:20.981Z,1626986900.981 [Default:A.Wait] Stopped
2021-07-22T20:48:20.981Z,1626986900.981 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-07-22T20:48:21.385Z,1626986901.385 [Default:CheckIn] Running Loop=1
2021-07-22T20:48:21.385Z,1626986901.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-22T20:48:21.385Z,1626986901.385 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-22T20:48:21.832Z,1626986901.832 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-07-22T20:48:21.842Z,1626986901.842 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-07-22T20:48:34.483Z,1626986914.483 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2021-07-22T20:48:34.486Z,1626986914.486 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2021-07-22T20:48:34.487Z,1626986914.487 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -122.099998 degree
2021-07-22T20:48:34.491Z,1626986914.491 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2021-07-22T20:48:34.757Z,1626986914.757 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,BPC1,Depth_Keller,NAL9602,Power24vConverter,DAT,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-07-22T20:48:35.174Z,1626986915.174 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:35.175Z,1626986915.175 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-07-22T20:48:35.175Z,1626986915.175 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:35.176Z,1626986915.176 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:35.176Z,1626986915.176 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2021-07-22T20:48:35.176Z,1626986915.176 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:35.187Z,1626986915.187 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:35.187Z,1626986915.187 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:35.563Z,1626986915.563 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:35.563Z,1626986915.563 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-07-22T20:48:35.564Z,1626986915.564 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:35.564Z,1626986915.564 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2021-07-22T20:48:35.965Z,1626986915.965 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:36.343Z,1626986916.343 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:36.343Z,1626986916.343 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-07-22T20:48:36.344Z,1626986916.344 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:36.345Z,1626986916.345 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:36.345Z,1626986916.345 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2021-07-22T20:48:36.345Z,1626986916.345 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:36.421Z,1626986916.421 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:36.422Z,1626986916.422 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:36.765Z,1626986916.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:36.765Z,1626986916.765 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-07-22T20:48:36.765Z,1626986916.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:36.765Z,1626986916.765 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2021-07-22T20:48:37.136Z,1626986917.136 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:37.540Z,1626986917.540 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:37.540Z,1626986917.540 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-07-22T20:48:37.540Z,1626986917.540 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:37.542Z,1626986917.542 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:37.542Z,1626986917.542 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2021-07-22T20:48:37.542Z,1626986917.542 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:37.553Z,1626986917.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:37.553Z,1626986917.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:37.958Z,1626986917.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:37.958Z,1626986917.958 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-07-22T20:48:37.958Z,1626986917.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:37.958Z,1626986917.958 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2021-07-22T20:48:38.348Z,1626986918.348 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:38.780Z,1626986918.780 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:38.780Z,1626986918.780 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-07-22T20:48:38.780Z,1626986918.780 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:38.781Z,1626986918.781 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:38.781Z,1626986918.781 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2021-07-22T20:48:38.781Z,1626986918.781 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:38.792Z,1626986918.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:38.793Z,1626986918.793 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:39.181Z,1626986919.181 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:39.182Z,1626986919.182 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-07-22T20:48:39.182Z,1626986919.182 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:39.182Z,1626986919.182 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2021-07-22T20:48:39.629Z,1626986919.629 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:40.000Z,1626986920.000 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:40.001Z,1626986920.001 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-07-22T20:48:40.001Z,1626986920.001 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:40.002Z,1626986920.002 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:40.002Z,1626986920.002 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2021-07-22T20:48:40.002Z,1626986920.002 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:40.025Z,1626986920.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:40.025Z,1626986920.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:40.404Z,1626986920.404 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:40.404Z,1626986920.404 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-07-22T20:48:40.405Z,1626986920.405 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:40.405Z,1626986920.405 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2021-07-22T20:48:40.791Z,1626986920.791 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:41.181Z,1626986921.181 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:41.181Z,1626986921.181 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-07-22T20:48:41.181Z,1626986921.181 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:41.182Z,1626986921.182 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:41.182Z,1626986921.182 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2021-07-22T20:48:41.182Z,1626986921.182 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:41.193Z,1626986921.193 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:41.194Z,1626986921.194 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:41.590Z,1626986921.590 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:41.590Z,1626986921.590 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-07-22T20:48:41.590Z,1626986921.590 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:41.590Z,1626986921.590 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2021-07-22T20:48:41.987Z,1626986921.987 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:42.391Z,1626986922.391 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:42.391Z,1626986922.391 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-07-22T20:48:42.391Z,1626986922.391 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:42.393Z,1626986922.393 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:42.393Z,1626986922.393 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2021-07-22T20:48:42.393Z,1626986922.393 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:42.403Z,1626986922.403 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:42.404Z,1626986922.404 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:42.804Z,1626986922.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:42.804Z,1626986922.804 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-07-22T20:48:42.805Z,1626986922.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:42.805Z,1626986922.805 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2021-07-22T20:48:43.194Z,1626986923.194 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:43.603Z,1626986923.603 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:43.603Z,1626986923.603 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-07-22T20:48:43.603Z,1626986923.603 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:43.605Z,1626986923.605 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:43.605Z,1626986923.605 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2021-07-22T20:48:43.605Z,1626986923.605 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:43.616Z,1626986923.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:43.616Z,1626986923.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:44.112Z,1626986924.112 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:44.112Z,1626986924.112 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-07-22T20:48:44.113Z,1626986924.113 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:44.113Z,1626986924.113 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2021-07-22T20:48:44.428Z,1626986924.428 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:44.843Z,1626986924.843 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:44.843Z,1626986924.843 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-07-22T20:48:44.843Z,1626986924.843 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:44.845Z,1626986924.845 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:44.845Z,1626986924.845 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2021-07-22T20:48:44.845Z,1626986924.845 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:44.856Z,1626986924.856 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:44.856Z,1626986924.856 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:45.246Z,1626986925.246 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:45.246Z,1626986925.246 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-07-22T20:48:45.246Z,1626986925.246 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:45.246Z,1626986925.246 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2021-07-22T20:48:45.632Z,1626986925.632 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:46.031Z,1626986926.031 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:46.031Z,1626986926.031 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-07-22T20:48:46.031Z,1626986926.031 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:48:46.061Z,1626986926.061 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2021-07-22T20:48:46.061Z,1626986926.061 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2021-07-22T20:48:46.061Z,1626986926.061 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:48:46.101Z,1626986926.101 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:46.101Z,1626986926.101 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:48:46.442Z,1626986926.442 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:48:46.442Z,1626986926.442 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-07-22T20:48:46.442Z,1626986926.442 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:48:46.442Z,1626986926.443 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2021-07-22T20:48:46.604Z,1626986926.604 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1626986880.000000 second
2021-07-22T20:48:46.836Z,1626986926.836 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:48:46.837Z,1626986926.837 [Default:CheckIn:Read_GPS] Stopped
2021-07-22T20:48:46.837Z,1626986926.837 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-22T20:48:47.254Z,1626986927.254 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-07-22T20:48:50.413Z,1626986930.413 [CommandLine](IMPORTANT): got command show stack
2021-07-22T20:48:50.414Z,1626986930.414 [CommandLine](IMPORTANT): Behavior Stack:

2021-07-22T20:48:50.414Z,1626986930.414 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2021-07-22T20:48:50.414Z,1626986930.414 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium
2021-07-22T20:48:52.501Z,1626986932.501 [CommandLine](IMPORTANT): got command failComponent
2021-07-22T20:48:52.501Z,1626986932.501 [CommandLine](IMPORTANT): Failed components:
2021-07-22T20:48:52.501Z,1626986932.501 [CommandLine](IMPORTANT): No failed Components.
2021-07-22T20:49:01.003Z,1626986941.003 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210722T204442/Courier0004.lzma
2021-07-22T20:49:02.006Z,1626986942.006 [DataOverHttps](INFO): Moved sent file to Logs/20210722T204442/Courier0004.lzma.bak
2021-07-22T20:49:02.006Z,1626986942.006 [DataOverHttps](INFO): SBD MOMSN=15753935
2021-07-22T20:49:03.798Z,1626986943.798 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-07-22T20:49:03.798Z,1626986943.798 [DAT] Communications Fault, FailCount= 4
2021-07-22T20:49:03.798Z,1626986943.798 [DAT](ERROR): Communications Fault
2021-07-22T20:49:03.865Z,1626986943.865 [CBIT](ERROR): Communications Fault in component: DAT
2021-07-22T20:49:04.201Z,1626986944.201 [DAT](INFO): Powering down
2021-07-22T20:49:05.049Z,1626986945.049 [CBIT](INFO): Clearing failed state for component DAT
2021-07-22T20:49:05.049Z,1626986945.049 [DAT] No Fault, FailCount= 4
2021-07-22T20:49:07.450Z,1626986947.450 [DAT](INFO): Powering up
2021-07-22T20:49:07.450Z,1626986947.450 [DAT](DEBUG): Initializing DAT.
2021-07-22T20:49:07.849Z,1626986947.849 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-07-22T20:49:18.664Z,1626986958.664 [DataOverHttps](INFO): Sending 370 bytes from file Logs/20210722T204442/Express0005.lzma
2021-07-22T20:49:19.680Z,1626986959.680 [DataOverHttps](INFO): Moved sent file to Logs/20210722T204442/Express0005.lzma.bak
2021-07-22T20:49:19.680Z,1626986959.680 [DataOverHttps](INFO): SBD MOMSN=15753937
2021-07-22T20:49:22.008Z,1626986962.008 [Default:CheckIn:Read_Iridium] Stopped
2021-07-22T20:49:22.008Z,1626986962.008 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-22T20:49:22.008Z,1626986962.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-22T20:49:44.644Z,1626986984.644 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-07-22T20:50:07.627Z,1626987007.627 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-07-22T20:50:07.627Z,1626987007.627 [DAT] Communications Fault, FailCount= 5
2021-07-22T20:50:07.627Z,1626987007.627 [DAT](ERROR): Communications Fault
2021-07-22T20:50:07.655Z,1626987007.655 [CBIT](ERROR): Communications Fault in component: DAT
2021-07-22T20:50:08.026Z,1626987008.026 [DAT](INFO): Powering down
2021-07-22T20:50:08.859Z,1626987008.859 [CBIT](INFO): Clearing failed state for component DAT
2021-07-22T20:50:08.859Z,1626987008.859 [DAT] No Fault, FailCount= 5
2021-07-22T20:50:11.251Z,1626987011.251 [DAT](INFO): Powering up
2021-07-22T20:50:11.252Z,1626987011.252 [DAT](DEBUG): Initializing DAT.
2021-07-22T20:50:35.495Z,1626987035.495 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-22T20:50:47.224Z,1626987047.224 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-07-22T20:50:47.224Z,1626987047.224 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-07-22T20:50:47.224Z,1626987047.224 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:50:47.227Z,1626987047.227 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-07-22T20:50:47.227Z,1626987047.227 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2021-07-22T20:50:47.227Z,1626987047.227 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:50:47.239Z,1626987047.239 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:50:47.239Z,1626987047.239 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:50:47.643Z,1626987047.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:50:47.643Z,1626987047.643 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-07-22T20:50:47.644Z,1626987047.644 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:50:47.644Z,1626987047.644 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2021-07-22T20:50:48.022Z,1626987048.022 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:50:58.559Z,1626987058.559 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-07-22T20:51:06.210Z,1626987066.210 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-22T20:51:11.459Z,1626987071.459 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-07-22T20:51:11.459Z,1626987071.459 [DAT] Communications Fault, FailCount= 6
2021-07-22T20:51:11.459Z,1626987071.459 [DAT](ERROR): Communications Fault
2021-07-22T20:51:11.514Z,1626987071.514 [CBIT](ERROR): Communications Fault in component: DAT
2021-07-22T20:51:11.846Z,1626987071.846 [DAT](INFO): Powering down
2021-07-22T20:51:12.688Z,1626987072.688 [CBIT](INFO): Clearing failed state for component DAT
2021-07-22T20:51:12.688Z,1626987072.688 [DAT] No Fault, FailCount= 6
2021-07-22T20:51:15.083Z,1626987075.083 [DAT](INFO): Powering up
2021-07-22T20:51:15.083Z,1626987075.083 [DAT](DEBUG): Initializing DAT.
2021-07-22T20:52:15.279Z,1626987135.279 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-07-22T20:52:15.279Z,1626987135.279 [DAT] Communications Fault, FailCount= 7
2021-07-22T20:52:15.279Z,1626987135.279 [DAT](ERROR): Communications Fault
2021-07-22T20:52:15.329Z,1626987135.329 [CBIT](ERROR): Communications Fault in component: DAT
2021-07-22T20:52:15.683Z,1626987135.683 [DAT](INFO): Powering down
2021-07-22T20:52:16.509Z,1626987136.509 [CBIT](INFO): Clearing failed state for component DAT
2021-07-22T20:52:16.509Z,1626987136.509 [DAT] No Fault, FailCount= 7
2021-07-22T20:52:18.915Z,1626987138.915 [DAT](INFO): Powering up
2021-07-22T20:52:18.915Z,1626987138.915 [DAT](DEBUG): Initializing DAT.
2021-07-22T20:52:48.412Z,1626987168.412 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-07-22T20:52:48.412Z,1626987168.412 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-07-22T20:52:48.412Z,1626987168.412 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:52:48.415Z,1626987168.415 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-07-22T20:52:48.415Z,1626987168.415 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2021-07-22T20:52:48.415Z,1626987168.415 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:52:48.427Z,1626987168.427 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:52:48.427Z,1626987168.427 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:52:48.827Z,1626987168.828 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:52:48.828Z,1626987168.828 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-07-22T20:52:48.828Z,1626987168.828 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:52:48.828Z,1626987168.828 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2021-07-22T20:52:49.220Z,1626987169.220 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:52:58.926Z,1626987178.926 [BPC1](ERROR): Battery stick #35 (s/n: 01F9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0AD0.
2021-07-22T20:52:58.927Z,1626987178.927 [BPC1](ERROR): Battery stick #39 (s/n: 01F7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0AD0.
2021-07-22T20:52:58.928Z,1626987178.928 [BPC1](ERROR): Battery stick #41 (s/n: 024C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0AD0.
2021-07-22T20:52:58.928Z,1626987178.928 [BPC1](ERROR): Battery stick #43 (s/n: 01F6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0AD0.
2021-07-22T20:52:58.929Z,1626987178.929 [BPC1](ERROR): Battery stick #47 (s/n: 0255) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0AD0.
2021-07-22T20:52:58.930Z,1626987178.930 [BPC1](ERROR): Battery stick #48 (s/n: 021B) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0AD0.
2021-07-22T20:52:58.931Z,1626987178.931 [BPC1](ERROR): Battery stick #51 (s/n: 0256) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0AD0.
2021-07-22T20:52:58.932Z,1626987178.932 [BPC1](ERROR): Battery stick #55 (s/n: 024B) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2021-07-22T20:52:58.932Z,1626987178.932 [BPC1](ERROR): Battery stick #56 (s/n: 01FB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x0AD0.
2021-07-22T20:52:58.933Z,1626987178.933 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 3.
2021-07-22T20:52:58.937Z,1626987178.937 [BPC1](CRITICAL): Battery Capacity Below Threshold. 11.547200 Ah.
2021-07-22T20:52:58.937Z,1626987178.937 [BPC1](CRITICAL): Battery Voltage Below Threshold. 12.409760 V.
2021-07-22T20:52:58.937Z,1626987178.937 [BPC1](INFO): Received data from all battery sticks.
2021-07-22T20:52:58.991Z,1626987178.991 [CBIT](INFO): Critical error at 20210722T205258
2021-07-22T20:53:19.111Z,1626987199.111 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-07-22T20:53:19.111Z,1626987199.111 [DAT] Communications Fault, FailCount= 8
2021-07-22T20:53:19.111Z,1626987199.111 [DAT](ERROR): Communications Fault
2021-07-22T20:53:19.164Z,1626987199.164 [CBIT](ERROR): Communications Fault in component: DAT
2021-07-22T20:53:19.164Z,1626987199.164 [CBIT](FAULT): Communications Fault in component: DAT
2021-07-22T20:53:19.511Z,1626987199.511 [DAT](INFO): Powering down
2021-07-22T20:53:24.106Z,1626987204.106 [CommandLine](INFO): End of History
2021-07-22T20:53:25.597Z,1626987205.597 [CommandLine](INFO): End of History
2021-07-22T20:53:27.065Z,1626987207.065 [CommandLine](INFO): End of History
2021-07-22T20:53:28.578Z,1626987208.578 [CommandLine](INFO): End of History
2021-07-22T20:53:30.066Z,1626987210.066 [CommandLine](INFO): End of History
2021-07-22T20:53:31.551Z,1626987211.551 [CommandLine](INFO): End of History
2021-07-22T20:53:33.116Z,1626987213.116 [CommandLine](INFO): End of History
2021-07-22T20:53:34.625Z,1626987214.625 [CommandLine](INFO): End of History
2021-07-22T20:53:36.101Z,1626987216.101 [CommandLine](INFO): End of History
2021-07-22T20:53:37.579Z,1626987217.579 [CommandLine](INFO): End of History
2021-07-22T20:53:39.632Z,1626987219.632 [CommandLine](INFO): End of History
2021-07-22T20:53:57.429Z,1626987237.429 [CommandLine](IMPORTANT): got command show variable threshold
2021-07-22T20:53:57.470Z,1626987237.470 [CommandLine](IMPORTANT): StratificationFrontDetector.threshold_VTHI (celsius)
2021-07-22T20:53:57.471Z,1626987237.471 [CommandLine](IMPORTANT): StratificationFrontDetector.threshold_VSHI (practical_salinity_unit)
2021-07-22T20:53:57.472Z,1626987237.472 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.cntThreshold (count)
2021-07-22T20:53:57.472Z,1626987237.472 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.tempHoriGradThreshold (celsius)
2021-07-22T20:53:57.554Z,1626987237.554 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold (meter)
2021-07-22T20:53:57.616Z,1626987237.616 [CommandLine](IMPORTANT): CBIT.humidityThreshold (percent)
2021-07-22T20:53:57.616Z,1626987237.616 [CommandLine](IMPORTANT): CBIT.pressureThreshold (pound_per_square_inch)
2021-07-22T20:53:57.617Z,1626987237.617 [CommandLine](IMPORTANT): CBIT.tempThreshold (fahrenheit)
2021-07-22T20:53:57.617Z,1626987237.617 [CommandLine](IMPORTANT): CBIT.battTempThreshold (celsius)
2021-07-22T20:53:57.618Z,1626987237.618 [CommandLine](IMPORTANT): CBIT.gfChanA0_Threshold (milliampere)
2021-07-22T20:53:57.619Z,1626987237.619 [CommandLine](IMPORTANT): CBIT.gfChanA1_Threshold (milliampere)
2021-07-22T20:53:57.619Z,1626987237.619 [CommandLine](IMPORTANT): CBIT.gfChanA2_Threshold (milliampere)
2021-07-22T20:53:57.620Z,1626987237.620 [CommandLine](IMPORTANT): CBIT.gfChanA3_Threshold (milliampere)
2021-07-22T20:53:57.620Z,1626987237.620 [CommandLine](IMPORTANT): CBIT.gfChanB0_Threshold (milliampere)
2021-07-22T20:53:57.620Z,1626987237.620 [CommandLine](IMPORTANT): CBIT.gfChanB1_Threshold (milliampere)
2021-07-22T20:53:57.621Z,1626987237.621 [CommandLine](IMPORTANT): CBIT.gfChanB2_Threshold (milliampere)
2021-07-22T20:53:57.621Z,1626987237.621 [CommandLine](IMPORTANT): CBIT.gfChanB3_Threshold (milliampere)
2021-07-22T20:53:57.623Z,1626987237.623 [CommandLine](IMPORTANT): IBIT.batteryCapacityThreshold (ampere_hour)
2021-07-22T20:53:57.623Z,1626987237.623 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold (volt)
2021-07-22T20:53:57.626Z,1626987237.626 [CommandLine](IMPORTANT): AMEcho.depthThreshold (meter)
2021-07-22T20:53:57.627Z,1626987237.627 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.surfaceThreshold (meter)
2021-07-22T20:53:57.629Z,1626987237.629 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold (count)
2021-07-22T20:53:57.632Z,1626987237.632 [CommandLine](IMPORTANT): DAT.surfaceThreshold (meter)
2021-07-22T20:53:57.633Z,1626987237.633 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2021-07-22T20:53:57.635Z,1626987237.635 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold (meter)
2021-07-22T20:53:57.647Z,1626987237.647 [CommandLine](IMPORTANT): LBLNavigation.angleThreshold (degree)
2021-07-22T20:53:57.647Z,1626987237.647 [CommandLine](IMPORTANT): LBLNavigation.lookbackThreshold (degree)
2021-07-22T20:54:14.443Z,1626987254.443 [CommandLine](IMPORTANT): got command configSet IBIT.batteryCapacityThreshold 5.000000 ampere_hour persist
2021-07-22T20:54:22.550Z,1626987262.550 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-22T20:54:22.550Z,1626987262.550 [Default:CheckIn:C.Wait] Stopped
2021-07-22T20:54:22.550Z,1626987262.550 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-22T20:54:22.550Z,1626987262.550 [Default:CheckIn:D] Running Loop=1
2021-07-22T20:54:22.949Z,1626987262.949 [Default:CheckIn:D] Stopped
2021-07-22T20:54:22.949Z,1626987262.949 [Default:CheckIn:E] Running Loop=1
2021-07-22T20:54:23.364Z,1626987263.364 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.254794 min 
2021-07-22T20:54:23.364Z,1626987263.364 [Default:CheckIn:E] Stopped
2021-07-22T20:54:23.364Z,1626987263.364 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-22T20:54:23.365Z,1626987263.365 [Default:CheckIn] Stopped
2021-07-22T20:54:23.365Z,1626987263.365 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-22T20:54:23.365Z,1626987263.365 [Default:CheckIn](INFO): Running loop #2
2021-07-22T20:54:23.365Z,1626987263.365 [Default:CheckIn] Running Loop=2
2021-07-22T20:54:23.365Z,1626987263.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-22T20:54:23.371Z,1626987263.371 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-22T20:54:26.899Z,1626987266.899 [CommandLine](IMPORTANT): got command configSet IBIT.batteryVoltageThreshold 11.000000 volt persist
2021-07-22T20:54:30.524Z,1626987270.524 [CommandLine](IMPORTANT): got command show stack
2021-07-22T20:54:30.524Z,1626987270.524 [CommandLine](IMPORTANT): Behavior Stack:

2021-07-22T20:54:30.524Z,1626987270.524 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2021-07-22T20:54:30.524Z,1626987270.524 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2021-07-22T20:54:38.102Z,1626987278.102 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadA6
2021-07-22T20:54:46.652Z,1626987286.652 [CommandLine](IMPORTANT): got command restart application
2021-07-22T20:54:47.658Z,1626987287.658 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-22T20:54:47.658Z,1626987287.658 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:47.761Z,1626987287.761 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-07-22T20:54:47.762Z,1626987287.762 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:47.762Z,1626987287.762 [CommandLine](INFO): Join timeout helper Thread ID is 7400
2021-07-22T20:54:47.763Z,1626987287.763 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-07-22T20:54:47.763Z,1626987287.763 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:47.763Z,1626987287.763 [NavChartDb](INFO): Join timeout helper Thread ID is 7401
2021-07-22T20:54:47.930Z,1626987287.930 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-22T20:54:47.930Z,1626987287.930 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:47.942Z,1626987287.942 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-07-22T20:54:47.942Z,1626987287.942 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:47.942Z,1626987287.942 [Radio_Surface](INFO): Join timeout helper Thread ID is 7402
2021-07-22T20:54:48.110Z,1626987288.110 [Radio_Surface](INFO): Powering down
2021-07-22T20:54:48.111Z,1626987288.111 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-22T20:54:48.111Z,1626987288.111 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:48.114Z,1626987288.114 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-07-22T20:54:48.114Z,1626987288.114 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:48.114Z,1626987288.114 [Onboard](INFO): Join timeout helper Thread ID is 7403
2021-07-22T20:54:49.609Z,1626987289.609 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-07-22T20:54:49.609Z,1626987289.609 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-07-22T20:54:49.610Z,1626987289.610 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-22T20:54:49.612Z,1626987289.612 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-07-22T20:54:49.612Z,1626987289.612 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2021-07-22T20:54:49.612Z,1626987289.612 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2021-07-22T20:54:49.624Z,1626987289.624 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-22T20:54:49.625Z,1626987289.625 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2021-07-22T20:54:50.035Z,1626987290.035 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-22T20:54:50.035Z,1626987290.035 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-07-22T20:54:50.035Z,1626987290.035 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2021-07-22T20:54:50.035Z,1626987290.035 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2021-07-22T20:54:50.422Z,1626987290.422 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2021-07-22T20:54:51.818Z,1626987291.818 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-22T20:54:51.818Z,1626987291.818 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:51.838Z,1626987291.838 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-07-22T20:54:51.838Z,1626987291.838 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:51.838Z,1626987291.838 [DataOverHttps](INFO): Join timeout helper Thread ID is 7404
2021-07-22T20:54:52.806Z,1626987292.806 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-22T20:54:52.809Z,1626987292.809 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:52.818Z,1626987292.818 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-07-22T20:54:52.819Z,1626987292.819 [logger ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:52.819Z,1626987292.819 [logger](INFO): Join timeout helper Thread ID is 7405
2021-07-22T20:54:52.822Z,1626987292.822 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-22T20:54:52.822Z,1626987292.822 [logger ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:52.839Z,1626987292.839 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-07-22T20:54:52.839Z,1626987292.839 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:52.839Z,1626987292.839 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-07-22T20:54:52.839Z,1626987292.839 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:52.839Z,1626987292.839 [controlThread](INFO): Join timeout helper Thread ID is 7406
2021-07-22T20:54:52.858Z,1626987292.858 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-07-22T20:54:52.858Z,1626987292.858 [controlThread](DEBUG): Uninitializing ControlThread
2021-07-22T20:54:52.860Z,1626987292.860 [NAL9602](INFO): Powering down
2021-07-22T20:54:52.862Z,1626987292.862 [DAT](INFO): Powering down
2021-07-22T20:54:52.863Z,1626987292.863 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-07-22T20:54:52.864Z,1626987292.864 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-07-22T20:54:52.865Z,1626987292.865 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-07-22T20:54:52.865Z,1626987292.865 [MissionManager](INFO): Uninitializing Mission Default
2021-07-22T20:54:52.865Z,1626987292.865 [Default] Stopped
2021-07-22T20:54:52.866Z,1626987292.866 [Default](DEBUG): Aggregate::uninitialize Default
2021-07-22T20:54:52.866Z,1626987292.866 [Default:B.GoToSurface] Stopped
2021-07-22T20:54:52.866Z,1626987292.866 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-22T20:54:52.866Z,1626987292.866 [Default:CheckIn] Stopped
2021-07-22T20:54:52.866Z,1626987292.866 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-22T20:54:52.866Z,1626987292.866 [Default:CheckIn:Read_GPS] Stopped
2021-07-22T20:54:52.870Z,1626987292.870 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-07-22T20:54:52.870Z,1626987292.870 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-07-22T20:54:52.870Z,1626987292.870 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-07-22T20:54:52.870Z,1626987292.870 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-07-22T20:54:52.871Z,1626987292.871 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-07-22T20:54:52.871Z,1626987292.871 [BuoyancyServo](INFO): Powering down
2021-07-22T20:54:52.886Z,1626987292.886 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-07-22T20:54:52.886Z,1626987292.886 [ElevatorServo](INFO): Powering down
2021-07-22T20:54:52.887Z,1626987292.887 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-07-22T20:54:52.887Z,1626987292.887 [RudderServo](INFO): Powering down
2021-07-22T20:54:52.888Z,1626987292.888 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-07-22T20:54:52.888Z,1626987292.888 [ThrusterServo](INFO): Powering down
2021-07-22T20:54:52.889Z,1626987292.889 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-07-22T20:54:52.890Z,1626987292.890 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-07-22T20:54:52.890Z,1626987292.890 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-07-22T20:54:52.890Z,1626987292.890 [CBIT](DEBUG): Powering off loads.
2021-07-22T20:54:52.901Z,1626987292.901 [CBIT](DEBUG): Disabling WDT.
2021-07-22T20:54:52.913Z,1626987292.913 [CBIT](DEBUG): Opening all GF detection circuits.
2021-07-22T20:54:52.915Z,1626987292.915 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:53.005Z,1626987293.005 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:53.008Z,1626987293.008 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:53.015Z,1626987293.015 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:53.101Z,1626987293.101 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-07-22T20:54:53.166Z,1626987293.166 [logger ThreadHandler](INFO): Thread cancelled.