2021-09-24T21:10:32.473Z,1632517832.473 [Supervisor](DEBUG): Initializing supervisor. 2021-09-24T21:10:32.505Z,1632517832.505 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-09-24T21:10:32.506Z,1632517832.506 [SyncHandler](INFO): Protected caller Thread ID is 2193 2021-09-24T21:10:32.506Z,1632517832.506 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-09-24T21:10:32.521Z,1632517832.521 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-09-24T21:10:32.521Z,1632517832.521 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2194 2021-09-24T21:10:32.525Z,1632517832.525 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-09-24T21:10:32.574Z,1632517832.574 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-09-24T21:10:32.581Z,1632517832.581 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-09-24T21:10:32.581Z,1632517832.581 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2195 2021-09-24T21:10:32.583Z,1632517832.583 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-09-24T21:10:32.593Z,1632517832.593 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-09-24T21:10:32.593Z,1632517832.593 [logger ThreadHandler](INFO): Protected caller Thread ID is 2196 2021-09-24T21:10:32.597Z,1632517832.597 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-09-24T21:10:32.597Z,1632517832.597 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-09-24T21:10:32.599Z,1632517832.599 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-09-24T21:10:33.829Z,1632517833.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-09-24T21:10:33.830Z,1632517833.830 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-09-24T21:10:34.372Z,1632517834.372 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-09-24T21:10:34.372Z,1632517834.372 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-09-24T21:10:34.879Z,1632517834.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-09-24T21:10:34.879Z,1632517834.879 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-09-24T21:10:35.145Z,1632517835.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-09-24T21:10:35.146Z,1632517835.146 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-09-24T21:10:36.412Z,1632517836.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-09-24T21:10:36.412Z,1632517836.412 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-09-24T21:10:37.308Z,1632517837.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-09-24T21:10:37.309Z,1632517837.309 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-09-24T21:10:37.505Z,1632517837.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-09-24T21:10:37.506Z,1632517837.506 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-09-24T21:10:37.872Z,1632517837.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-09-24T21:10:37.873Z,1632517837.873 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-09-24T21:10:39.453Z,1632517839.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-09-24T21:10:39.454Z,1632517839.454 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-09-24T21:10:40.445Z,1632517840.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-09-24T21:10:40.445Z,1632517840.445 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-09-24T21:10:40.629Z,1632517840.629 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-09-24T21:10:41.151Z,1632517841.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-09-24T21:10:41.152Z,1632517841.152 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-09-24T21:10:41.433Z,1632517841.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-09-24T21:10:41.434Z,1632517841.434 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-09-24T21:10:43.371Z,1632517843.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-09-24T21:10:43.372Z,1632517843.372 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-09-24T21:10:43.498Z,1632517843.498 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-09-24T21:10:44.061Z,1632517844.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-09-24T21:10:44.063Z,1632517844.063 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2021-09-24T21:10:44.065Z,1632517844.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2021-09-24T21:10:44.424Z,1632517844.424 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2021-09-24T21:10:44.570Z,1632517844.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2021-09-24T21:10:44.775Z,1632517844.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2021-09-24T21:10:44.977Z,1632517844.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2021-09-24T21:10:45.106Z,1632517845.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2021-09-24T21:10:45.287Z,1632517845.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2021-09-24T21:10:45.500Z,1632517845.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2021-09-24T21:10:45.682Z,1632517845.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2021-09-24T21:10:45.966Z,1632517845.966 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2021-09-24T21:10:46.527Z,1632517846.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-09-24T21:10:46.527Z,1632517846.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2021-09-24T21:10:47.219Z,1632517847.219 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2021-09-24T21:10:47.236Z,1632517847.236 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-09-24T21:10:47.980Z,1632517847.980 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-09-24T21:10:47.981Z,1632517847.981 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-09-24T21:10:48.154Z,1632517848.154 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-09-24T21:10:48.154Z,1632517848.154 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-09-24T21:10:48.227Z,1632517848.227 [DeadReckonUsingSpeedCalculator] Loaded 2021-09-24T21:10:48.227Z,1632517848.227 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2021-09-24T21:10:48.252Z,1632517848.252 [NavChart] Loaded 2021-09-24T21:10:48.252Z,1632517848.252 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-09-24T21:10:48.274Z,1632517848.274 [UniversalFixResidualReporter] Loaded 2021-09-24T21:10:48.274Z,1632517848.274 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-09-24T21:10:48.275Z,1632517848.275 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-09-24T21:10:48.275Z,1632517848.275 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-09-24T21:10:48.302Z,1632517848.302 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-09-24T21:10:48.303Z,1632517848.303 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-09-24T21:10:48.687Z,1632517848.687 [CTD_Seabird] Loaded 2021-09-24T21:10:48.687Z,1632517848.687 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-09-24T21:10:48.697Z,1632517848.697 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406214E0 2021-09-24T21:10:48.697Z,1632517848.697 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2278 2021-09-24T21:10:48.729Z,1632517848.729 [PAR_Licor] Loaded 2021-09-24T21:10:48.729Z,1632517848.729 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-09-24T21:10:48.807Z,1632517848.807 [WetLabsSeaOWL_UV_A] Loaded 2021-09-24T21:10:48.807Z,1632517848.807 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2021-09-24T21:10:48.817Z,1632517848.817 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406514E0 2021-09-24T21:10:48.818Z,1632517848.818 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 2279 2021-09-24T21:10:48.818Z,1632517848.818 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-09-24T21:10:48.819Z,1632517848.819 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-09-24T21:10:48.927Z,1632517848.927 [VerticalControl](DEBUG): Construct VerticalControl. 2021-09-24T21:10:49.078Z,1632517849.078 [VerticalControl] Loaded 2021-09-24T21:10:49.079Z,1632517849.079 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-09-24T21:10:49.081Z,1632517849.081 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-09-24T21:10:49.185Z,1632517849.185 [HorizontalControl] Loaded 2021-09-24T21:10:49.186Z,1632517849.186 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-09-24T21:10:49.188Z,1632517849.188 [SpeedControl](DEBUG): Construct SpeedControl. 2021-09-24T21:10:49.191Z,1632517849.191 [SpeedControl] Loaded 2021-09-24T21:10:49.191Z,1632517849.191 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-09-24T21:10:49.194Z,1632517849.194 [LoopControl](DEBUG): Construct LoopControl. 2021-09-24T21:10:49.195Z,1632517849.195 [LoopControl] Loaded 2021-09-24T21:10:49.195Z,1632517849.195 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-09-24T21:10:49.195Z,1632517849.195 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-09-24T21:10:49.196Z,1632517849.196 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-09-24T21:10:49.316Z,1632517849.316 [DepthRateCalculator] Loaded 2021-09-24T21:10:49.316Z,1632517849.316 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-09-24T21:10:49.321Z,1632517849.321 [PitchRateCalculator] Loaded 2021-09-24T21:10:49.321Z,1632517849.321 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-09-24T21:10:49.339Z,1632517849.339 [SpeedCalculator] Loaded 2021-09-24T21:10:49.339Z,1632517849.339 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-09-24T21:10:49.364Z,1632517849.364 [TempGradientCalculator] Loaded 2021-09-24T21:10:49.365Z,1632517849.365 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-09-24T21:10:49.389Z,1632517849.389 [YawRateCalculator] Loaded 2021-09-24T21:10:49.389Z,1632517849.389 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-09-24T21:10:49.418Z,1632517849.418 [ElevatorOffsetCalculator] Loaded 2021-09-24T21:10:49.419Z,1632517849.419 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-09-24T21:10:49.419Z,1632517849.419 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-09-24T21:10:49.420Z,1632517849.420 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-09-24T21:10:49.646Z,1632517849.646 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-09-24T21:10:49.647Z,1632517849.647 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-09-24T21:10:49.773Z,1632517849.773 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-09-24T21:10:49.774Z,1632517849.774 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-09-24T21:10:50.063Z,1632517850.063 [BuoyancyServo] Loaded 2021-09-24T21:10:50.063Z,1632517850.063 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-09-24T21:10:50.094Z,1632517850.094 [ElevatorServo] Loaded 2021-09-24T21:10:50.094Z,1632517850.094 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-09-24T21:10:50.146Z,1632517850.146 [MassServo] Loaded 2021-09-24T21:10:50.146Z,1632517850.146 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-09-24T21:10:50.176Z,1632517850.176 [RudderServo] Loaded 2021-09-24T21:10:50.176Z,1632517850.176 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-09-24T21:10:50.214Z,1632517850.214 [ThrusterServo] Loaded 2021-09-24T21:10:50.215Z,1632517850.215 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-09-24T21:10:50.215Z,1632517850.215 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-09-24T21:10:50.216Z,1632517850.216 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-09-24T21:10:50.636Z,1632517850.636 [ExternalSim] Loaded 2021-09-24T21:10:50.636Z,1632517850.636 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread. 2021-09-24T21:10:50.666Z,1632517850.666 [InternalEnvSim] Loaded 2021-09-24T21:10:50.666Z,1632517850.666 [ComponentRegistry](DEBUG): SyncComponent "InternalEnvSim" handled in the control thread. 2021-09-24T21:10:50.732Z,1632517850.732 [InternalSim] Loaded 2021-09-24T21:10:50.732Z,1632517850.732 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2021-09-24T21:10:50.733Z,1632517850.733 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-09-24T21:10:50.733Z,1632517850.733 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-09-24T21:10:51.548Z,1632517851.548 [AHRS_M2] Loaded 2021-09-24T21:10:51.548Z,1632517851.548 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-09-24T21:10:53.427Z,1632517853.427 [BPC1] Loaded 2021-09-24T21:10:53.427Z,1632517853.427 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-09-24T21:10:53.604Z,1632517853.604 [DataOverHttps] Loaded 2021-09-24T21:10:53.604Z,1632517853.604 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-09-24T21:10:53.629Z,1632517853.629 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A214E0 2021-09-24T21:10:53.629Z,1632517853.629 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2280 2021-09-24T21:10:53.657Z,1632517853.657 [Depth_Keller] Loaded 2021-09-24T21:10:53.658Z,1632517853.658 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-09-24T21:10:53.797Z,1632517853.797 [NAL9602] Loaded 2021-09-24T21:10:53.797Z,1632517853.797 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-09-24T21:10:53.858Z,1632517853.858 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2021-09-24T21:10:53.859Z,1632517853.859 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2021-09-24T21:10:53.859Z,1632517853.859 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2021-09-24T21:10:53.860Z,1632517853.860 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2021-09-24T21:10:53.860Z,1632517853.860 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2021-09-24T21:10:53.869Z,1632517853.869 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2021-09-24T21:10:53.869Z,1632517853.869 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2021-09-24T21:10:53.870Z,1632517853.870 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2021-09-24T21:10:53.870Z,1632517853.870 [Onboard] Loaded 2021-09-24T21:10:53.870Z,1632517853.870 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-09-24T21:10:53.885Z,1632517853.885 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A514E0 2021-09-24T21:10:53.885Z,1632517853.885 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2281 2021-09-24T21:10:53.918Z,1632517853.918 [Power24vConverter] Loaded 2021-09-24T21:10:53.918Z,1632517853.918 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2021-09-24T21:10:53.943Z,1632517853.943 [Radio_Surface] Loaded 2021-09-24T21:10:53.943Z,1632517853.943 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-09-24T21:10:53.953Z,1632517853.953 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A814E0 2021-09-24T21:10:53.954Z,1632517853.954 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2282 2021-09-24T21:10:53.954Z,1632517853.954 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-09-24T21:10:53.955Z,1632517853.955 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-09-24T21:10:54.269Z,1632517854.269 [SBIT](DEBUG): Construct Startup Built In Test. 2021-09-24T21:10:54.290Z,1632517854.290 [SBIT] Loaded 2021-09-24T21:10:54.291Z,1632517854.291 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-09-24T21:10:54.294Z,1632517854.294 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-09-24T21:10:54.331Z,1632517854.331 [IBIT] Loaded 2021-09-24T21:10:54.331Z,1632517854.331 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-09-24T21:10:54.336Z,1632517854.336 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-09-24T21:10:54.584Z,1632517854.584 [CBIT] Loaded 2021-09-24T21:10:54.585Z,1632517854.585 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-09-24T21:10:54.585Z,1632517854.585 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-09-24T21:10:54.586Z,1632517854.586 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-09-24T21:10:54.645Z,1632517854.645 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-09-24T21:10:54.663Z,1632517854.663 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-09-24T21:10:54.666Z,1632517854.666 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-09-24T21:10:54.685Z,1632517854.685 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-09-24T21:10:54.701Z,1632517854.701 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B0C4E0 2021-09-24T21:10:54.701Z,1632517854.701 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2283 2021-09-24T21:10:54.706Z,1632517854.706 [Supervisor](INFO): Main Thread ID is 2192 2021-09-24T21:10:54.706Z,1632517854.706 [Supervisor](DEBUG): Running supervisor. 2021-09-24T21:10:54.706Z,1632517854.706 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2284 2021-09-24T21:10:54.715Z,1632517854.715 [controlThread ThreadHandler](INFO): Handler Thread ID is 2285 2021-09-24T21:10:54.715Z,1632517854.715 [controlThread](DEBUG): Initializing ControlThread 2021-09-24T21:10:54.716Z,1632517854.716 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:10:54.717Z,1632517854.717 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-09-24T21:10:54.717Z,1632517854.717 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-09-24T21:10:54.721Z,1632517854.721 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-09-24T21:10:54.723Z,1632517854.723 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-09-24T21:10:54.723Z,1632517854.723 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-09-24T21:10:54.724Z,1632517854.724 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-09-24T21:10:54.724Z,1632517854.724 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-09-24T21:10:54.724Z,1632517854.724 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-09-24T21:10:54.725Z,1632517854.725 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-09-24T21:10:54.725Z,1632517854.725 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-09-24T21:10:54.726Z,1632517854.726 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-09-24T21:10:54.726Z,1632517854.726 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-09-24T21:10:54.728Z,1632517854.728 [ExternalSim](INFO): ExternalSim initializing... 2021-09-24T21:10:54.758Z,1632517854.758 [logger ThreadHandler](INFO): Handler Thread ID is 2286 2021-09-24T21:10:54.787Z,1632517854.787 [ExternalSim](ERROR): Error making socket connection to simulation server at tellum.shore.mbari.org: Can not connect to invalid network address: tellum.shore.mbari.org 2021-09-24T21:10:54.793Z,1632517854.793 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2287 2021-09-24T21:10:54.794Z,1632517854.794 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-09-24T21:10:54.798Z,1632517854.798 [ExternalSim](DEBUG): oceanModelVarCount=0 2021-09-24T21:10:54.801Z,1632517854.801 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 2289 2021-09-24T21:10:54.818Z,1632517854.818 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2290 2021-09-24T21:10:54.819Z,1632517854.819 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-09-24T21:10:54.845Z,1632517854.845 [Onboard ThreadHandler](INFO): Handler Thread ID is 2291 2021-09-24T21:10:54.869Z,1632517854.869 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2292 2021-09-24T21:10:54.885Z,1632517854.885 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2293 2021-09-24T21:10:54.888Z,1632517854.888 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-09-24T21:10:54.888Z,1632517854.888 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-09-24T21:10:54.888Z,1632517854.888 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-09-24T21:10:54.889Z,1632517854.889 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-09-24T21:10:54.889Z,1632517854.889 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-09-24T21:10:54.889Z,1632517854.889 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-09-24T21:10:54.889Z,1632517854.889 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-09-24T21:10:54.889Z,1632517854.889 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-09-24T21:10:54.890Z,1632517854.890 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-09-24T21:10:54.890Z,1632517854.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-09-24T21:10:54.890Z,1632517854.890 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-09-24T21:10:54.890Z,1632517854.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-09-24T21:10:54.890Z,1632517854.890 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-09-24T21:10:54.890Z,1632517854.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-09-24T21:10:54.891Z,1632517854.891 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-09-24T21:10:54.891Z,1632517854.891 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-09-24T21:10:54.953Z,1632517854.953 [ExternalSim](DEBUG): beaconLat = 36.80340 2021-09-24T21:10:54.953Z,1632517854.953 [ExternalSim](DEBUG): beaconLon = -121.82230 2021-09-24T21:10:54.953Z,1632517854.953 [ExternalSim](DEBUG): beaconDepth = 25.00 2021-09-24T21:10:54.957Z,1632517854.957 [ExternalSim](DEBUG): Simulator initialized 2021-09-24T21:10:54.959Z,1632517854.959 [InternalEnvSim](DEBUG): InternaEnvlSim initializing... 2021-09-24T21:10:55.217Z,1632517855.217 [InternalEnvSim](INFO): Longitude of central meridian = -123.000000 2021-09-24T21:10:55.218Z,1632517855.218 [InternalEnvSim](INFO): False easting = 480000.000000 2021-09-24T21:10:55.218Z,1632517855.218 [InternalEnvSim](INFO): False northing = 3840000.000000 2021-09-24T21:10:55.218Z,1632517855.218 [InternalEnvSim](INFO): gridMapping=2 2021-09-24T21:10:55.219Z,1632517855.219 [InternalEnvSim](INFO): Found mass_concentration_of_petroleum_hydrocarbons_in_sea_water 2021-09-24T21:10:55.219Z,1632517855.219 [InternalSim](DEBUG): InternalSim initializing... 2021-09-24T21:10:55.224Z,1632517855.224 [InternalSim](DEBUG): oceanModelVarCount=0 2021-09-24T21:10:55.273Z,1632517855.273 [Radio_Surface](INFO): Powering up 2021-09-24T21:10:55.379Z,1632517855.379 [SBIT](INFO): Initialize SBIT Component. 2021-09-24T21:10:55.379Z,1632517855.379 [SBIT](IMPORTANT): git: 2021-09-08a-14-gd93b36e5a 2021-09-24T21:10:55.379Z,1632517855.379 [SBIT](INFO): git hash: d93b36e5abf42d480fde98b8be8c35b5a9d8a259 2021-09-24T21:10:55.380Z,1632517855.380 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-09-24T21:10:55.385Z,1632517855.385 [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-09-24T21:10:55.386Z,1632517855.386 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2021-09-24T21:10:55.386Z,1632517855.386 [IBIT](INFO): Initialize IBIT Component. 2021-09-24T21:10:55.387Z,1632517855.387 [CBIT](DEBUG): Initialize CBIT Component. 2021-09-24T21:10:55.401Z,1632517855.401 [CBIT](DEBUG): Initialized mux pins. 2021-09-24T21:10:55.401Z,1632517855.401 [CBIT](DEBUG): Initializing the watchdog timer. 2021-09-24T21:10:55.425Z,1632517855.425 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-09-24T21:10:55.426Z,1632517855.426 [CBIT](DEBUG): Initializing heartbeat. 2021-09-24T21:10:55.497Z,1632517855.497 [CBIT](DEBUG): Deactivating GF circuits. 2021-09-24T21:10:55.497Z,1632517855.497 [CBIT](DEBUG): Deactivating emergency mode. 2021-09-24T21:10:55.533Z,1632517855.533 [CBIT](DEBUG): Backplane powered. 2021-09-24T21:10:55.534Z,1632517855.534 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-09-24T21:10:55.564Z,1632517855.564 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-09-24T21:10:55.576Z,1632517855.576 [MissionManager](DEBUG): 2021-09-24T21:10:55.585Z,1632517855.585 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-09-24T21:10:55.669Z,1632517855.669 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-09-24T21:10:55.670Z,1632517855.670 [Default:A.Wait](DEBUG): Construct Wait. 2021-09-24T21:10:55.672Z,1632517855.672 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-09-24T21:10:55.732Z,1632517855.732 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-09-24T21:10:55.734Z,1632517855.734 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-09-24T21:10:55.764Z,1632517855.764 [Default:E.Execute](DEBUG): Construct Execute. 2021-09-24T21:10:55.767Z,1632517855.767 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2021-09-24T21:10:55.777Z,1632517855.777 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,InternalEnvSim,InternalSim,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,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-09-24T21:10:55.851Z,1632517855.851 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-09-24T21:10:55.930Z,1632517855.930 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar 2021-09-24T21:10:55.930Z,1632517855.930 [Power24vConverter](INFO): Powering up. 2021-09-24T21:10:55.971Z,1632517855.971 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-09-24T21:10:55.977Z,1632517855.977 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-09-24T21:10:55.978Z,1632517855.978 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-09-24T21:10:55.989Z,1632517855.989 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-09-24T21:10:55.990Z,1632517855.990 [MassServo](DEBUG): Initializing EZServoServo. 2021-09-24T21:10:56.001Z,1632517856.001 [MassServo](DEBUG): Initializing MassServo. 2021-09-24T21:10:56.002Z,1632517856.002 [RudderServo](DEBUG): Initializing EZServoServo. 2021-09-24T21:10:56.021Z,1632517856.021 [RudderServo](DEBUG): Initializing RudderServo. 2021-09-24T21:10:56.022Z,1632517856.022 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-09-24T21:10:56.033Z,1632517856.033 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-09-24T21:10:56.973Z,1632517856.973 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2021-09-24T21:10:56.973Z,1632517856.973 [RudderServo](FAULT): Rudder failed to initialize 2021-09-24T21:10:56.973Z,1632517856.973 [RudderServo] Communications Fault, FailCount= 1 2021-09-24T21:10:56.973Z,1632517856.973 [RudderServo](ERROR): Communications Fault 2021-09-24T21:10:57.073Z,1632517857.073 [CBIT](ERROR): Communications Fault in component: RudderServo 2021-09-24T21:10:57.270Z,1632517857.270 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-09-24T21:10:57.270Z,1632517857.270 [RudderServo](INFO): Powering down 2021-09-24T21:10:57.970Z,1632517857.970 [RudderServo](DEBUG): Initializing EZServoServo. 2021-09-24T21:10:58.090Z,1632517858.090 [RudderServo](DEBUG): Initializing RudderServo. 2021-09-24T21:10:58.094Z,1632517858.094 [CBIT](INFO): Clearing failed state for component RudderServo 2021-09-24T21:10:58.094Z,1632517858.094 [RudderServo] No Fault, FailCount= 1 2021-09-24T21:11:01.827Z,1632517861.827 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-09-24T21:11:12.536Z,1632517872.536 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004852 2021-09-24T21:11:16.189Z,1632517876.189 [SBIT](IMPORTANT): Beginning Startup BIT 2021-09-24T21:11:16.193Z,1632517876.193 [CBIT](IMPORTANT): Beginning ground fault scan 2021-09-24T21:11:24.210Z,1632517884.210 [NAL9602](INFO): Powering up NAL9602 2021-09-24T21:11:27.089Z,1632517887.089 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.007829 CHAN A1 (24V): 0.000659 CHAN A2 (12V): -0.005019 CHAN A3 (5V): -0.003084 CHAN B0 (3.3V): -0.000969 CHAN B1 (3.15aV): -0.001275 CHAN B2 (3.15bV): -0.001461 CHAN B3 (GND): 0.002809 OPEN: 0.003517 Full Scale Calc: 4.765 mA, -1.589 mA 2021-09-24T21:11:35.120Z,1632517895.120 [NAL9602](INFO): NAL9602 initialized 2021-09-24T21:12:10.311Z,1632517930.311 [SBIT](IMPORTANT): SBIT PASSED 2021-09-24T21:12:10.311Z,1632517930.311 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2021-09-24T21:12:10.312Z,1632517930.312 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2021-09-24T21:12:10.312Z,1632517930.312 [SBIT](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2021-09-24T21:12:10.325Z,1632517930.325 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2021-09-24T21:12:10.325Z,1632517930.325 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2021-09-24T21:12:10.325Z,1632517930.325 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour; 2021-09-24T21:12:10.325Z,1632517930.325 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt; 2021-09-24T21:12:10.325Z,1632517930.325 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool; 2021-09-24T21:12:10.325Z,1632517930.325 [SBIT](IMPORTANT): PAR_Licor.adcCal=8.76539e-06 microampere_per_count; 2021-09-24T21:12:10.326Z,1632517930.326 [SBIT](IMPORTANT): PAR_Licor.darkCount=4 count; 2021-09-24T21:12:10.326Z,1632517930.326 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2021-09-24T21:12:10.326Z,1632517930.326 [SBIT](IMPORTANT): PAR_Licor.multiplier=269.34 micromole_per_second_per_square_meter_per_microampere; 2021-09-24T21:12:10.326Z,1632517930.326 [SBIT](IMPORTANT): PAR_Licor.parCal=1.221 none; 2021-09-24T21:12:10.326Z,1632517930.326 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=235 cubic_centimeter; 2021-09-24T21:12:10.326Z,1632517930.326 [SBIT](IMPORTANT): VerticalControl.massDefault=0 centimeter; 2021-09-24T21:12:10.326Z,1632517930.326 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-26 millimeter; 2021-09-24T21:12:10.326Z,1632517930.326 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=30 millimeter; 2021-09-24T21:12:10.707Z,1632517930.707 [MissionManager](IMPORTANT): Started mission Startup 2021-09-24T21:12:10.707Z,1632517930.707 [Startup] Running Loop=1 2021-09-24T21:12:10.707Z,1632517930.707 [Startup](DEBUG): Aggregate::initialize Startup 2021-09-24T21:12:10.707Z,1632517930.707 [Startup:A.GoToSurface] Running Loop=1 2021-09-24T21:12:10.707Z,1632517930.707 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-09-24T21:12:10.708Z,1632517930.708 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-09-24T21:12:10.708Z,1632517930.708 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-09-24T21:12:10.729Z,1632517930.729 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-09-24T21:12:10.729Z,1632517930.729 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-09-24T21:12:10.730Z,1632517930.730 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-09-24T21:12:10.731Z,1632517930.731 [Startup:StartupSatComms] Running Loop=1 2021-09-24T21:12:10.731Z,1632517930.731 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-09-24T21:12:10.731Z,1632517930.731 [Startup:StartupSatComms:A] Running Loop=1 2021-09-24T21:12:11.106Z,1632517931.106 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-09-24T21:12:22.204Z,1632517942.204 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree 2021-09-24T21:12:22.216Z,1632517942.216 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2021-09-24T21:12:22.216Z,1632517942.216 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree 2021-09-24T21:12:22.228Z,1632517942.228 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2021-09-24T21:12:22.419Z,1632517942.419 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,InternalEnvSim,InternalSim,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,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,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2021-09-24T21:12:22.772Z,1632517942.772 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:22.772Z,1632517942.772 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-09-24T21:12:22.772Z,1632517942.772 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:22.782Z,1632517942.782 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:22.782Z,1632517942.782 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2021-09-24T21:12:22.782Z,1632517942.782 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:22.783Z,1632517942.783 [Startup:StartupSatComms:A] Stopped 2021-09-24T21:12:22.783Z,1632517942.783 [Startup:StartupSatComms:B] Running Loop=1 2021-09-24T21:12:22.889Z,1632517942.889 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:22.889Z,1632517942.889 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:23.184Z,1632517943.184 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-09-24T21:12:23.230Z,1632517943.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:23.230Z,1632517943.230 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-09-24T21:12:23.230Z,1632517943.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:23.230Z,1632517943.230 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2021-09-24T21:12:23.759Z,1632517943.759 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:24.135Z,1632517944.135 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:24.135Z,1632517944.135 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-09-24T21:12:24.135Z,1632517944.135 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:24.136Z,1632517944.136 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:24.181Z,1632517944.181 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2021-09-24T21:12:24.181Z,1632517944.181 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:24.358Z,1632517944.358 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:24.358Z,1632517944.358 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:24.616Z,1632517944.616 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:24.616Z,1632517944.616 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-09-24T21:12:24.651Z,1632517944.651 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:24.651Z,1632517944.651 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2021-09-24T21:12:24.963Z,1632517944.963 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:25.383Z,1632517945.383 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:25.383Z,1632517945.383 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-09-24T21:12:25.383Z,1632517945.383 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:25.384Z,1632517945.384 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:25.384Z,1632517945.384 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2021-09-24T21:12:25.384Z,1632517945.384 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:25.475Z,1632517945.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:25.475Z,1632517945.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:25.812Z,1632517945.812 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:25.812Z,1632517945.812 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2021-09-24T21:12:25.845Z,1632517945.845 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:25.845Z,1632517945.845 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2021-09-24T21:12:26.144Z,1632517946.144 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:26.883Z,1632517946.883 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:26.883Z,1632517946.883 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2021-09-24T21:12:26.883Z,1632517946.883 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:26.884Z,1632517946.884 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:26.901Z,1632517946.901 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2021-09-24T21:12:26.901Z,1632517946.901 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:27.010Z,1632517947.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:27.011Z,1632517947.011 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:27.337Z,1632517947.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:27.337Z,1632517947.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2021-09-24T21:12:27.338Z,1632517947.338 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:27.338Z,1632517947.338 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2021-09-24T21:12:27.529Z,1632517947.529 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:27.882Z,1632517947.882 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:27.882Z,1632517947.882 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2021-09-24T21:12:27.882Z,1632517947.882 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:27.883Z,1632517947.883 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:27.883Z,1632517947.883 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2021-09-24T21:12:27.883Z,1632517947.883 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:27.974Z,1632517947.974 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:27.975Z,1632517947.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:28.338Z,1632517948.338 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:28.338Z,1632517948.338 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2021-09-24T21:12:28.338Z,1632517948.338 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:28.338Z,1632517948.338 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2021-09-24T21:12:28.686Z,1632517948.686 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:29.066Z,1632517949.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:29.066Z,1632517949.066 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2021-09-24T21:12:29.066Z,1632517949.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:29.067Z,1632517949.067 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:29.067Z,1632517949.067 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2021-09-24T21:12:29.067Z,1632517949.067 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:29.134Z,1632517949.134 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:29.134Z,1632517949.134 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:29.542Z,1632517949.542 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:29.542Z,1632517949.542 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2021-09-24T21:12:29.543Z,1632517949.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:29.543Z,1632517949.543 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2021-09-24T21:12:29.906Z,1632517949.906 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:30.317Z,1632517950.317 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:30.317Z,1632517950.317 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2021-09-24T21:12:30.317Z,1632517950.317 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:30.319Z,1632517950.319 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:30.319Z,1632517950.319 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2021-09-24T21:12:30.319Z,1632517950.319 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:30.345Z,1632517950.345 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:30.346Z,1632517950.346 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:30.768Z,1632517950.768 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:30.768Z,1632517950.768 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2021-09-24T21:12:30.781Z,1632517950.781 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:30.781Z,1632517950.781 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2021-09-24T21:12:31.118Z,1632517951.118 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:31.501Z,1632517951.501 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:31.502Z,1632517951.502 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2021-09-24T21:12:31.502Z,1632517951.502 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:31.503Z,1632517951.503 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:31.503Z,1632517951.503 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2021-09-24T21:12:31.503Z,1632517951.503 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:31.550Z,1632517951.550 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:31.550Z,1632517951.550 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:31.959Z,1632517951.959 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:31.960Z,1632517951.960 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2021-09-24T21:12:31.960Z,1632517951.960 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:31.960Z,1632517951.960 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2021-09-24T21:12:32.315Z,1632517952.315 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:32.706Z,1632517952.706 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:32.706Z,1632517952.706 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2021-09-24T21:12:32.706Z,1632517952.706 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:32.707Z,1632517952.707 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:32.707Z,1632517952.707 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2021-09-24T21:12:32.707Z,1632517952.707 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:32.754Z,1632517952.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:32.754Z,1632517952.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:33.152Z,1632517953.152 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:33.152Z,1632517953.152 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2021-09-24T21:12:33.152Z,1632517953.152 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:33.152Z,1632517953.152 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2021-09-24T21:12:33.526Z,1632517953.526 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:33.946Z,1632517953.946 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:33.947Z,1632517953.947 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2021-09-24T21:12:33.947Z,1632517953.947 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:33.948Z,1632517953.948 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:33.948Z,1632517953.948 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2021-09-24T21:12:33.948Z,1632517953.948 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:33.999Z,1632517953.999 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:33.999Z,1632517953.999 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:34.410Z,1632517954.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:34.410Z,1632517954.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2021-09-24T21:12:34.411Z,1632517954.411 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:34.411Z,1632517954.411 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2021-09-24T21:12:34.790Z,1632517954.790 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:35.158Z,1632517955.158 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:35.158Z,1632517955.158 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2021-09-24T21:12:35.158Z,1632517955.158 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-09-24T21:12:35.159Z,1632517955.159 [DeadReckonUsingSpeedCalculator](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2021-09-24T21:12:35.159Z,1632517955.159 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2021-09-24T21:12:35.159Z,1632517955.159 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-09-24T21:12:35.206Z,1632517955.206 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:35.206Z,1632517955.206 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-09-24T21:12:35.633Z,1632517955.633 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-09-24T21:12:35.633Z,1632517955.633 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2021-09-24T21:12:35.634Z,1632517955.634 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-09-24T21:12:35.634Z,1632517955.634 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2021-09-24T21:12:35.983Z,1632517955.983 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-09-24T21:12:36.138Z,1632517956.138 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1632517888.000000 second 2021-09-24T21:12:36.713Z,1632517956.713 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:37.275Z,1632517957.275 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:37.644Z,1632517957.644 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:38.048Z,1632517958.048 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:38.452Z,1632517958.452 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:38.856Z,1632517958.856 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:39.336Z,1632517959.336 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:39.716Z,1632517959.716 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:40.120Z,1632517960.120 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:40.528Z,1632517960.528 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:40.928Z,1632517960.928 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:41.332Z,1632517961.332 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:41.736Z,1632517961.736 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:42.140Z,1632517962.140 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:42.544Z,1632517962.544 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:42.948Z,1632517962.948 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:43.352Z,1632517963.352 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:43.756Z,1632517963.756 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:44.025Z,1632517964.025 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20210924T210403/Courier0000.lzma 2021-09-24T21:12:44.160Z,1632517964.160 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:44.564Z,1632517964.564 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:44.968Z,1632517964.968 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:45.031Z,1632517965.031 [DataOverHttps](INFO): Moved sent file to Logs/20210924T210403/Courier0000.lzma.bak 2021-09-24T21:12:45.031Z,1632517965.031 [DataOverHttps](INFO): SBD MOMSN=15931809 2021-09-24T21:12:45.374Z,1632517965.374 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:45.776Z,1632517965.776 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:46.180Z,1632517966.180 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:46.584Z,1632517966.584 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:46.988Z,1632517966.988 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:47.392Z,1632517967.392 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:47.796Z,1632517967.796 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:48.200Z,1632517968.200 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:48.606Z,1632517968.606 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:49.008Z,1632517969.008 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:49.412Z,1632517969.412 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:49.816Z,1632517969.816 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:50.099Z,1632517970.099 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.500000 meter 2021-09-24T21:12:50.102Z,1632517970.102 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2021-09-24T21:12:50.221Z,1632517970.221 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:12:50.383Z,1632517970.383 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,InternalEnvSim,InternalSim,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,Maintain_Depth_Keller.depth,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-09-24T21:12:50.624Z,1632517970.624 [InternalEnvSim](INFO): Depth 0.000000 outside of range 0.307125 to 26.412750 2021-09-24T21:13:08.220Z,1632517988.220 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210923T203546/Express0428.lzma 2021-09-24T21:13:09.223Z,1632517989.223 [DataOverHttps](INFO): Moved sent file to Logs/20210923T203546/Express0428.lzma.bak 2021-09-24T21:13:09.223Z,1632517989.223 [DataOverHttps](INFO): SBD MOMSN=15931814 2021-09-24T21:13:22.803Z,1632518002.803 [Startup:StartupSatComms:B](INFO): Timed out from 2021-09-24T21:12:22.8Z 2021-09-24T21:13:22.803Z,1632518002.803 [Startup:StartupSatComms:B] Stopped 2021-09-24T21:13:22.804Z,1632518002.804 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2021-09-24T21:13:22.804Z,1632518002.804 [Startup:StartupSatComms] Stopped 2021-09-24T21:13:22.804Z,1632518002.804 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2021-09-24T21:13:22.805Z,1632518002.805 [Startup](INFO): Completed Startup 2021-09-24T21:13:22.805Z,1632518002.805 [MissionManager](INFO): Startup is completed. 2021-09-24T21:13:22.805Z,1632518002.805 [MissionManager](INFO): Uninitializing Mission Startup 2021-09-24T21:13:22.805Z,1632518002.805 [Startup] Stopped 2021-09-24T21:13:22.805Z,1632518002.805 [Startup](DEBUG): Aggregate::uninitialize Startup 2021-09-24T21:13:22.805Z,1632518002.805 [Startup:A.GoToSurface] Stopped 2021-09-24T21:13:22.805Z,1632518002.805 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-09-24T21:13:23.209Z,1632518003.209 [MissionManager](IMPORTANT): Started mission Default 2021-09-24T21:13:23.209Z,1632518003.209 [Default] Running Loop=1 2021-09-24T21:13:23.209Z,1632518003.209 [Default](DEBUG): Aggregate::initialize Default 2021-09-24T21:13:23.210Z,1632518003.210 [Default:B.GoToSurface] Running Loop=1 2021-09-24T21:13:23.210Z,1632518003.210 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-09-24T21:13:23.210Z,1632518003.210 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-09-24T21:13:23.210Z,1632518003.210 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-09-24T21:13:23.211Z,1632518003.211 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-09-24T21:13:23.211Z,1632518003.211 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-09-24T21:13:23.211Z,1632518003.211 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-09-24T21:13:23.211Z,1632518003.211 [Default:A.Wait] Running Loop=1 2021-09-24T21:13:23.211Z,1632518003.211 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-09-24T21:13:28.057Z,1632518008.057 [DataOverHttps](INFO): Sending 659 bytes from file Logs/20210924T210403/Express0001.lzma 2021-09-24T21:13:29.058Z,1632518009.058 [DataOverHttps](INFO): Moved sent file to Logs/20210924T210403/Express0001.lzma.bak 2021-09-24T21:13:29.059Z,1632518009.059 [DataOverHttps](INFO): SBD MOMSN=15931816 2021-09-24T21:13:36.556