2018-09-19T21:36:42.554Z,1537393002.554 [Supervisor](DEBUG): Initializing supervisor. 2018-09-19T21:36:42.557Z,1537393002.557 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-09-19T21:36:42.558Z,1537393002.558 [SyncHandler](INFO): Protected caller Thread ID is 2563 2018-09-19T21:36:42.559Z,1537393002.559 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-09-19T21:36:42.560Z,1537393002.560 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-09-19T21:36:42.560Z,1537393002.560 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2564 2018-09-19T21:36:42.563Z,1537393002.563 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-09-19T21:36:42.575Z,1537393002.575 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-09-19T21:36:42.576Z,1537393002.576 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-09-19T21:36:42.576Z,1537393002.576 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2565 2018-09-19T21:36:42.577Z,1537393002.577 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-09-19T21:36:42.578Z,1537393002.578 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-09-19T21:36:42.579Z,1537393002.579 [logger ThreadHandler](INFO): Protected caller Thread ID is 2566 2018-09-19T21:36:42.581Z,1537393002.581 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-09-19T21:36:42.581Z,1537393002.581 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-09-19T21:36:42.582Z,1537393002.582 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-09-19T21:36:42.772Z,1537393002.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-09-19T21:36:42.772Z,1537393002.772 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-09-19T21:36:42.915Z,1537393002.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-09-19T21:36:42.916Z,1537393002.916 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-09-19T21:36:43.262Z,1537393003.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-09-19T21:36:43.262Z,1537393003.262 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-09-19T21:36:43.401Z,1537393003.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-09-19T21:36:43.402Z,1537393003.402 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-09-19T21:36:43.483Z,1537393003.483 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-09-19T21:36:43.684Z,1537393003.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-09-19T21:36:43.685Z,1537393003.685 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-09-19T21:36:43.795Z,1537393003.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-09-19T21:36:43.796Z,1537393003.796 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-09-19T21:36:44.158Z,1537393004.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-09-19T21:36:44.158Z,1537393004.158 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-09-19T21:36:44.612Z,1537393004.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-09-19T21:36:44.613Z,1537393004.613 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-09-19T21:36:45.066Z,1537393005.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-09-19T21:36:45.067Z,1537393005.067 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-09-19T21:36:45.565Z,1537393005.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-09-19T21:36:45.566Z,1537393005.566 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-09-19T21:36:45.773Z,1537393005.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-09-19T21:36:45.774Z,1537393005.774 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-09-19T21:36:45.873Z,1537393005.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-09-19T21:36:45.873Z,1537393005.873 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-09-19T21:36:46.363Z,1537393006.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-09-19T21:36:46.364Z,1537393006.364 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-09-19T21:36:46.471Z,1537393006.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-09-19T21:36:46.473Z,1537393006.473 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-09-19T21:36:46.474Z,1537393006.474 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-09-19T21:36:46.703Z,1537393006.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-09-19T21:36:46.703Z,1537393006.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-09-19T21:36:46.803Z,1537393006.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-09-19T21:36:46.897Z,1537393006.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-09-19T21:36:47.000Z,1537393007.000 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-09-19T21:36:47.087Z,1537393007.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-09-19T21:36:47.223Z,1537393007.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-09-19T21:36:47.391Z,1537393007.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-09-19T21:36:47.503Z,1537393007.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-09-19T21:36:47.588Z,1537393007.588 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-09-19T21:36:47.673Z,1537393007.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-09-19T21:36:47.865Z,1537393007.865 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2018-09-19T21:36:47.865Z,1537393007.865 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-09-19T21:36:47.870Z,1537393007.870 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-09-19T21:36:47.995Z,1537393007.995 [SBIT](DEBUG): Construct Startup Built In Test. 2018-09-19T21:36:48.025Z,1537393008.025 [SBIT] Loaded 2018-09-19T21:36:48.025Z,1537393008.025 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-09-19T21:36:48.026Z,1537393008.026 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-09-19T21:36:48.053Z,1537393008.053 [IBIT] Loaded 2018-09-19T21:36:48.054Z,1537393008.054 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-09-19T21:36:48.057Z,1537393008.057 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-09-19T21:36:48.210Z,1537393008.210 [CBIT] Loaded 2018-09-19T21:36:48.210Z,1537393008.210 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-09-19T21:36:48.211Z,1537393008.211 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-09-19T21:36:48.211Z,1537393008.211 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-09-19T21:36:48.277Z,1537393008.277 [VerticalControl](DEBUG): Construct VerticalControl. 2018-09-19T21:36:48.377Z,1537393008.377 [VerticalControl] Loaded 2018-09-19T21:36:48.377Z,1537393008.377 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-09-19T21:36:48.378Z,1537393008.378 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-09-19T21:36:48.438Z,1537393008.438 [HorizontalControl] Loaded 2018-09-19T21:36:48.438Z,1537393008.438 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-09-19T21:36:48.439Z,1537393008.439 [SpeedControl](DEBUG): Construct SpeedControl. 2018-09-19T21:36:48.444Z,1537393008.444 [SpeedControl] Loaded 2018-09-19T21:36:48.445Z,1537393008.445 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-09-19T21:36:48.445Z,1537393008.445 [LoopControl](DEBUG): Construct LoopControl. 2018-09-19T21:36:48.446Z,1537393008.446 [LoopControl] Loaded 2018-09-19T21:36:48.446Z,1537393008.446 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-09-19T21:36:48.447Z,1537393008.447 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-09-19T21:36:48.447Z,1537393008.447 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-09-19T21:36:48.487Z,1537393008.487 [DepthRateCalculator] Loaded 2018-09-19T21:36:48.488Z,1537393008.488 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-09-19T21:36:48.493Z,1537393008.493 [PitchRateCalculator] Loaded 2018-09-19T21:36:48.493Z,1537393008.493 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-09-19T21:36:48.509Z,1537393008.509 [SpeedCalculator] Loaded 2018-09-19T21:36:48.509Z,1537393008.509 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-09-19T21:36:48.530Z,1537393008.530 [TempGradientCalculator] Loaded 2018-09-19T21:36:48.530Z,1537393008.530 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-09-19T21:36:48.546Z,1537393008.546 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-09-19T21:36:48.546Z,1537393008.546 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-09-19T21:36:48.551Z,1537393008.551 [YawRateCalculator] Loaded 2018-09-19T21:36:48.552Z,1537393008.552 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-09-19T21:36:48.581Z,1537393008.581 [ElevatorOffsetCalculator] Loaded 2018-09-19T21:36:48.581Z,1537393008.581 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-09-19T21:36:48.581Z,1537393008.581 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-09-19T21:36:48.582Z,1537393008.582 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-09-19T21:36:48.645Z,1537393008.645 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-09-19T21:36:48.645Z,1537393008.645 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-09-19T21:36:48.882Z,1537393008.882 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-09-19T21:36:48.882Z,1537393008.882 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-09-19T21:36:48.990Z,1537393008.990 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-09-19T21:36:48.990Z,1537393008.990 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-09-19T21:36:49.044Z,1537393009.044 [DeadReckonUsingSpeedCalculator] Loaded 2018-09-19T21:36:49.044Z,1537393009.044 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-09-19T21:36:49.060Z,1537393009.060 [NavChart] Loaded 2018-09-19T21:36:49.061Z,1537393009.061 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-09-19T21:36:49.065Z,1537393009.065 [UniversalFixResidualReporter] Loaded 2018-09-19T21:36:49.065Z,1537393009.065 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-09-19T21:36:49.065Z,1537393009.065 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-09-19T21:36:49.066Z,1537393009.066 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-09-19T21:36:49.079Z,1537393009.079 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-09-19T21:36:49.079Z,1537393009.079 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-09-19T21:36:49.229Z,1537393009.229 [Aanderaa_O2] Loaded 2018-09-19T21:36:49.230Z,1537393009.230 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-09-19T21:36:49.274Z,1537393009.274 [WetLabsSeaOWL_UV_A] Loaded 2018-09-19T21:36:49.275Z,1537393009.275 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2018-09-19T21:36:49.276Z,1537393009.276 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406D54E0 2018-09-19T21:36:49.276Z,1537393009.276 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 2645 2018-09-19T21:36:49.277Z,1537393009.277 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-09-19T21:36:49.277Z,1537393009.277 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-09-19T21:36:49.572Z,1537393009.572 [AHRS_M2] Loaded 2018-09-19T21:36:49.572Z,1537393009.572 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-09-19T21:36:49.642Z,1537393009.642 [DataOverHttps] Loaded 2018-09-19T21:36:49.642Z,1537393009.642 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-09-19T21:36:49.655Z,1537393009.655 [Depth_Keller] Loaded 2018-09-19T21:36:49.656Z,1537393009.656 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-09-19T21:36:49.660Z,1537393009.660 [DropWeight] Loaded 2018-09-19T21:36:49.661Z,1537393009.661 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-09-19T21:36:49.840Z,1537393009.840 [NAL9602] Loaded 2018-09-19T21:36:49.840Z,1537393009.840 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-09-19T21:36:49.845Z,1537393009.845 [Onboard] Loaded 2018-09-19T21:36:49.846Z,1537393009.846 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-09-19T21:36:49.853Z,1537393009.853 [Radio_Surface] Loaded 2018-09-19T21:36:49.853Z,1537393009.853 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-09-19T21:36:49.854Z,1537393009.854 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409364E0 2018-09-19T21:36:49.854Z,1537393009.854 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2646 2018-09-19T21:36:49.883Z,1537393009.883 [RDI_Pathfinder] Loaded 2018-09-19T21:36:49.883Z,1537393009.883 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-09-19T21:36:49.905Z,1537393009.905 [RDI_PathfinderUp] Loaded 2018-09-19T21:36:49.905Z,1537393009.905 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2018-09-19T21:36:49.912Z,1537393009.912 [SCPI] Loaded 2018-09-19T21:36:49.912Z,1537393009.912 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-09-19T21:36:49.935Z,1537393009.935 [BPC1](INFO): BPC1A: got serial number for stick 1: 0140 2018-09-19T21:36:49.936Z,1537393009.936 [BPC1](INFO): BPC1A: got serial number for stick 2: 0163 2018-09-19T21:36:49.937Z,1537393009.937 [BPC1](INFO): BPC1A: got serial number for stick 3: 0157 2018-09-19T21:36:49.938Z,1537393009.938 [BPC1](INFO): BPC1A: got serial number for stick 4: 0159 2018-09-19T21:36:49.939Z,1537393009.939 [BPC1](INFO): BPC1A: got serial number for stick 5: 016E 2018-09-19T21:36:49.939Z,1537393009.939 [BPC1](INFO): BPC1A: got serial number for stick 6: 0160 2018-09-19T21:36:49.940Z,1537393009.940 [BPC1](INFO): BPC1A: got serial number for stick 7: 015C 2018-09-19T21:36:49.941Z,1537393009.941 [BPC1](INFO): BPC1A: got serial number for stick 8: 016D 2018-09-19T21:36:49.942Z,1537393009.942 [BPC1](INFO): BPC1A: got serial number for stick 9: 012D 2018-09-19T21:36:49.942Z,1537393009.942 [BPC1](INFO): BPC1A: got serial number for stick 10: 015E 2018-09-19T21:36:49.943Z,1537393009.943 [BPC1](INFO): BPC1A: got serial number for stick 11: 0164 2018-09-19T21:36:49.944Z,1537393009.944 [BPC1](INFO): BPC1A: got serial number for stick 12: 015B 2018-09-19T21:36:49.945Z,1537393009.945 [BPC1](INFO): BPC1A: got serial number for stick 13: 0172 2018-09-19T21:36:49.945Z,1537393009.945 [BPC1](INFO): BPC1A: got serial number for stick 14: 0166 2018-09-19T21:36:49.946Z,1537393009.946 [BPC1](INFO): BPC1A: got serial number for stick 15: 0170 2018-09-19T21:36:49.947Z,1537393009.947 [BPC1](INFO): BPC1A: got serial number for stick 16: 0148 2018-09-19T21:36:49.947Z,1537393009.947 [BPC1](INFO): BPC1A: got serial number for stick 17: 0130 2018-09-19T21:36:49.948Z,1537393009.948 [BPC1](INFO): BPC1A: got serial number for stick 18: 0169 2018-09-19T21:36:49.949Z,1537393009.949 [BPC1](INFO): BPC1A: got serial number for stick 19: 0158 2018-09-19T21:36:49.950Z,1537393009.950 [BPC1](INFO): BPC1A: got serial number for stick 20: 0162 2018-09-19T21:36:49.950Z,1537393009.950 [BPC1](INFO): BPC1A: got serial number for stick 21: 0150 2018-09-19T21:36:49.951Z,1537393009.951 [BPC1](INFO): BPC1A: got serial number for stick 22: 015F 2018-09-19T21:36:49.952Z,1537393009.952 [BPC1](INFO): BPC1A: got serial number for stick 23: 0171 2018-09-19T21:36:49.953Z,1537393009.953 [BPC1](INFO): BPC1A: got serial number for stick 24: 0143 2018-09-19T21:36:49.953Z,1537393009.953 [BPC1](INFO): BPC1A: got serial number for stick 25: 0144 2018-09-19T21:36:49.954Z,1537393009.954 [BPC1](INFO): BPC1A: got serial number for stick 26: 016A 2018-09-19T21:36:49.955Z,1537393009.955 [BPC1](INFO): BPC1A: got serial number for stick 27: 0155 2018-09-19T21:36:49.956Z,1537393009.956 [BPC1](INFO): BPC1A: got serial number for stick 28: 0151 2018-09-19T21:36:49.956Z,1537393009.956 [BPC1](INFO): BPC1A: got serial number for stick 29: 0154 2018-09-19T21:36:49.957Z,1537393009.957 [BPC1](INFO): BPC1A: got serial number for stick 30: 0161 2018-09-19T21:36:49.958Z,1537393009.958 [BPC1](INFO): BPC1A: got serial number for stick 31: 0138 2018-09-19T21:36:49.959Z,1537393009.959 [BPC1](INFO): BPC1B: got serial number for stick 32: 0165 2018-09-19T21:36:49.960Z,1537393009.960 [BPC1](INFO): BPC1B: got serial number for stick 33: 0135 2018-09-19T21:36:49.961Z,1537393009.961 [BPC1](INFO): BPC1B: got serial number for stick 34: 0149 2018-09-19T21:36:49.962Z,1537393009.962 [BPC1](INFO): BPC1B: got serial number for stick 35: 0146 2018-09-19T21:36:49.962Z,1537393009.962 [BPC1](INFO): BPC1B: got serial number for stick 36: 013D 2018-09-19T21:36:49.963Z,1537393009.963 [BPC1](INFO): BPC1B: got serial number for stick 37: 014D 2018-09-19T21:36:49.964Z,1537393009.964 [BPC1](INFO): BPC1B: got serial number for stick 38: 0156 2018-09-19T21:36:49.965Z,1537393009.965 [BPC1](INFO): BPC1B: got serial number for stick 39: 0133 2018-09-19T21:36:49.965Z,1537393009.965 [BPC1](INFO): BPC1B: got serial number for stick 40: 0131 2018-09-19T21:36:49.966Z,1537393009.966 [BPC1](INFO): BPC1B: got serial number for stick 41: 0145 2018-09-19T21:36:49.967Z,1537393009.967 [BPC1](INFO): BPC1B: got serial number for stick 42: 014A 2018-09-19T21:36:49.968Z,1537393009.968 [BPC1](INFO): BPC1B: got serial number for stick 43: 013C 2018-09-19T21:36:49.968Z,1537393009.968 [BPC1](INFO): BPC1B: got serial number for stick 44: 013B 2018-09-19T21:36:49.969Z,1537393009.969 [BPC1](INFO): BPC1B: got serial number for stick 45: 0132 2018-09-19T21:36:49.970Z,1537393009.970 [BPC1](INFO): BPC1B: got serial number for stick 46: 0134 2018-09-19T21:36:49.970Z,1537393009.970 [BPC1](INFO): BPC1B: got serial number for stick 47: 0153 2018-09-19T21:36:49.971Z,1537393009.971 [BPC1](INFO): BPC1B: got serial number for stick 48: 014B 2018-09-19T21:36:49.972Z,1537393009.972 [BPC1](INFO): BPC1B: got serial number for stick 49: 013A 2018-09-19T21:36:49.973Z,1537393009.973 [BPC1](INFO): BPC1B: got serial number for stick 50: 0136 2018-09-19T21:36:49.973Z,1537393009.973 [BPC1](INFO): BPC1B: got serial number for stick 51: 014F 2018-09-19T21:36:49.974Z,1537393009.974 [BPC1](INFO): BPC1B: got serial number for stick 52: 014E 2018-09-19T21:36:49.975Z,1537393009.975 [BPC1](INFO): BPC1B: got serial number for stick 53: 013F 2018-09-19T21:36:49.976Z,1537393009.976 [BPC1](INFO): BPC1B: got serial number for stick 54: 0152 2018-09-19T21:36:49.976Z,1537393009.976 [BPC1](INFO): BPC1B: got serial number for stick 55: 014C 2018-09-19T21:36:49.977Z,1537393009.977 [BPC1](INFO): BPC1B: got serial number for stick 56: 016B 2018-09-19T21:36:49.978Z,1537393009.978 [BPC1](INFO): BPC1B: got serial number for stick 57: 0141 2018-09-19T21:36:49.979Z,1537393009.979 [BPC1](INFO): BPC1B: got serial number for stick 58: 0139 2018-09-19T21:36:49.979Z,1537393009.979 [BPC1](INFO): BPC1B: got serial number for stick 59: 0137 2018-09-19T21:36:49.980Z,1537393009.980 [BPC1](INFO): BPC1B: got serial number for stick 60: 0142 2018-09-19T21:36:49.981Z,1537393009.981 [BPC1](INFO): BPC1B: got serial number for stick 61: 013E 2018-09-19T21:36:49.981Z,1537393009.981 [BPC1](INFO): BPC1B: got serial number for stick 62: 0147 2018-09-19T21:36:51.885Z,1537393011.885 [BPC1] Loaded 2018-09-19T21:36:51.885Z,1537393011.885 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-09-19T21:36:51.886Z,1537393011.886 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-09-19T21:36:51.886Z,1537393011.886 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-09-19T21:36:51.985Z,1537393011.985 [BuoyancyServo] Loaded 2018-09-19T21:36:51.985Z,1537393011.985 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-09-19T21:36:51.996Z,1537393011.996 [ElevatorServo] Loaded 2018-09-19T21:36:51.997Z,1537393011.997 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-09-19T21:36:52.008Z,1537393012.008 [MassServo] Loaded 2018-09-19T21:36:52.008Z,1537393012.008 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-09-19T21:36:52.019Z,1537393012.019 [RudderServo] Loaded 2018-09-19T21:36:52.020Z,1537393012.020 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-09-19T21:36:52.030Z,1537393012.030 [ThrusterServo] Loaded 2018-09-19T21:36:52.031Z,1537393012.031 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-09-19T21:36:52.031Z,1537393012.031 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-09-19T21:36:52.032Z,1537393012.032 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-09-19T21:36:52.130Z,1537393012.130 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-09-19T21:36:52.131Z,1537393012.131 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-09-19T21:36:52.154Z,1537393012.154 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-09-19T21:36:52.157Z,1537393012.157 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-09-19T21:36:52.158Z,1537393012.158 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-09-19T21:36:52.165Z,1537393012.165 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-09-19T21:36:52.166Z,1537393012.166 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409DF4E0 2018-09-19T21:36:52.166Z,1537393012.166 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2647 2018-09-19T21:36:52.171Z,1537393012.171 [Supervisor](INFO): Main Thread ID is 800 2018-09-19T21:36:52.171Z,1537393012.171 [Supervisor](DEBUG): Running supervisor. 2018-09-19T21:36:52.172Z,1537393012.172 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2648 2018-09-19T21:36:52.174Z,1537393012.174 [controlThread ThreadHandler](INFO): Handler Thread ID is 2649 2018-09-19T21:36:52.175Z,1537393012.175 [controlThread](DEBUG): Initializing ControlThread 2018-09-19T21:36:52.176Z,1537393012.176 [SBIT](INFO): Initialize SBIT Component. 2018-09-19T21:36:52.176Z,1537393012.176 [SBIT](IMPORTANT): git: 2018-09-04-21-gd54d9fc 2018-09-19T21:36:52.176Z,1537393012.176 [SBIT](INFO): git hash: d54d9fc12f891c3a6b7eed365ed7708e78930892 2018-09-19T21:36:52.177Z,1537393012.177 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-09-19T21:36:52.178Z,1537393012.178 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-09-19T21:36:52.179Z,1537393012.179 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-09-19T21:36:52.180Z,1537393012.180 [IBIT](INFO): Initialize IBIT Component. 2018-09-19T21:36:52.181Z,1537393012.181 [CBIT](DEBUG): Initialize CBIT Component. 2018-09-19T21:36:52.181Z,1537393012.181 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-09-19T21:36:52.182Z,1537393012.182 [logger ThreadHandler](INFO): Handler Thread ID is 2650 2018-09-19T21:36:52.199Z,1537393012.199 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 2651 2018-09-19T21:36:52.200Z,1537393012.200 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-09-19T21:36:52.235Z,1537393012.235 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2652 2018-09-19T21:36:52.241Z,1537393012.241 [Radio_Surface](INFO): Powering up 2018-09-19T21:36:52.251Z,1537393012.251 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2653 2018-09-19T21:36:52.254Z,1537393012.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-09-19T21:36:52.254Z,1537393012.254 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-09-19T21:36:52.255Z,1537393012.255 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-09-19T21:36:52.255Z,1537393012.255 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-09-19T21:36:52.255Z,1537393012.255 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-09-19T21:36:52.255Z,1537393012.255 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-09-19T21:36:52.255Z,1537393012.255 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-09-19T21:36:52.255Z,1537393012.255 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-09-19T21:36:52.256Z,1537393012.256 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-09-19T21:36:52.256Z,1537393012.256 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-09-19T21:36:52.256Z,1537393012.256 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-09-19T21:36:52.256Z,1537393012.256 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-09-19T21:36:52.257Z,1537393012.257 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-09-19T21:36:52.257Z,1537393012.257 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-09-19T21:36:52.257Z,1537393012.257 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-09-19T21:36:52.257Z,1537393012.257 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-09-19T21:36:52.286Z,1537393012.286 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-09-19T21:36:52.288Z,1537393012.288 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-09-19T21:36:52.289Z,1537393012.289 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-09-19T21:36:52.289Z,1537393012.289 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-09-19T21:36:52.290Z,1537393012.290 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-09-19T21:36:52.290Z,1537393012.290 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-09-19T21:36:52.290Z,1537393012.290 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-09-19T21:36:52.291Z,1537393012.291 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-09-19T21:36:52.291Z,1537393012.291 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-09-19T21:36:52.292Z,1537393012.292 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-09-19T21:36:52.292Z,1537393012.292 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-09-19T21:36:52.293Z,1537393012.293 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:36:52.293Z,1537393012.293 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:36:52.294Z,1537393012.294 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:36:52.294Z,1537393012.294 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:36:52.295Z,1537393012.295 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:36:52.295Z,1537393012.295 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:36:52.295Z,1537393012.295 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-09-19T21:36:52.296Z,1537393012.296 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-09-19T21:36:52.306Z,1537393012.306 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-09-19T21:36:52.317Z,1537393012.317 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-09-19T21:36:52.353Z,1537393012.353 [MissionManager](DEBUG): 2018-09-19T21:36:52.353Z,1537393012.353 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-09-19T21:36:52.432Z,1537393012.432 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-09-19T21:36:52.433Z,1537393012.433 [Default:A.Wait](DEBUG): Construct Wait. 2018-09-19T21:36:52.435Z,1537393012.435 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-09-19T21:36:52.482Z,1537393012.482 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-09-19T21:36:52.484Z,1537393012.484 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-09-19T21:36:52.514Z,1537393012.514 [Default:E.Execute](DEBUG): Construct Execute. 2018-09-19T21:36:52.518Z,1537393012.518 [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 2018-09-19T21:36:52.531Z,1537393012.531 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-09-19T21:36:52.590Z,1537393012.590 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-09-19T21:36:52.695Z,1537393012.695 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-09-19T21:36:52.738Z,1537393012.738 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-19T21:36:52.937Z,1537393012.937 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-09-19T21:36:53.023Z,1537393013.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:53.039Z,1537393013.039 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:53.151Z,1537393013.151 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:36:53.159Z,1537393013.159 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-09-19T21:36:53.173Z,1537393013.173 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:36:53.179Z,1537393013.179 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-09-19T21:36:53.201Z,1537393013.201 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:36:53.207Z,1537393013.207 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:36:53.213Z,1537393013.213 [RudderServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:36:53.219Z,1537393013.219 [RudderServo](DEBUG): Initializing RudderServo. 2018-09-19T21:36:53.224Z,1537393013.224 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:36:53.231Z,1537393013.231 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-09-19T21:36:53.364Z,1537393013.364 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-09-19T21:36:53.364Z,1537393013.364 [DropWeight] Hardware Fault, FailCount= 1 2018-09-19T21:36:53.364Z,1537393013.364 [DropWeight](ERROR): Hardware Fault 2018-09-19T21:36:53.455Z,1537393013.455 [RDI_PathfinderUp](INFO): Powering down 2018-09-19T21:36:53.501Z,1537393013.501 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-09-19T21:36:53.520Z,1537393013.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:53.521Z,1537393013.521 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:53.547Z,1537393013.547 [CommandLine](FAULT): Scheduling is paused 2018-09-19T21:36:53.548Z,1537393013.548 [CBIT](INFO): Critical error at 20180919T213653 2018-09-19T21:36:53.548Z,1537393013.548 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-09-19T21:36:53.550Z,1537393013.550 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-09-19T21:36:53.551Z,1537393013.551 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-09-19T21:36:53.723Z,1537393013.723 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-09-19T21:36:53.726Z,1537393013.726 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:53.727Z,1537393013.727 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:54.058Z,1537393014.058 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-09-19T21:36:54.059Z,1537393014.059 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-09-19T21:36:54.059Z,1537393014.059 [BuoyancyServo] Communications Fault, FailCount= 1 2018-09-19T21:36:54.059Z,1537393014.059 [BuoyancyServo](ERROR): Communications Fault 2018-09-19T21:36:54.264Z,1537393014.264 [CBIT](INFO): Critical error at 20180919T213653 2018-09-19T21:36:54.267Z,1537393014.267 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-09-19T21:36:54.343Z,1537393014.343 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:54.344Z,1537393014.344 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:54.371Z,1537393014.371 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-09-19T21:36:54.371Z,1537393014.371 [BuoyancyServo](INFO): Powering down 2018-09-19T21:36:54.711Z,1537393014.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:54.712Z,1537393014.712 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:55.106Z,1537393015.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:55.107Z,1537393015.107 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:55.502Z,1537393015.502 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:55.503Z,1537393015.503 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:55.994Z,1537393015.994 [Aanderaa_O2](INFO): Powering down 2018-09-19T21:36:56.130Z,1537393016.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:56.138Z,1537393016.138 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:56.338Z,1537393016.338 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-09-19T21:36:56.378Z,1537393016.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:56.380Z,1537393016.380 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:36:56.432Z,1537393016.432 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-09-19T21:36:56.432Z,1537393016.432 [BuoyancyServo] No Fault, FailCount= 1 2018-09-19T21:36:56.741Z,1537393016.741 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:36:56.859Z,1537393016.859 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-09-19T21:37:06.311Z,1537393026.311 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2018-09-19T21:37:16.718Z,1537393036.718 [SBIT](IMPORTANT): Beginning Startup BIT 2018-09-19T21:37:16.739Z,1537393036.739 [CBIT](IMPORTANT): Beginning ground fault scan 2018-09-19T21:37:20.012Z,1537393040.012 [NAL9602](INFO): Powering up NAL9602 2018-09-19T21:37:27.732Z,1537393047.732 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.018670 CHAN A1 (24V): -0.022210 CHAN A2 (12V): -0.007068 CHAN A3 (5V): -0.002039 CHAN B0 (3.3V): 0.000093 CHAN B1 (3.15aV): 0.000199 CHAN B2 (3.15bV): -0.000048 CHAN B3 (GND): 0.002039 OPEN: 0.005146 Full Scale Calc: 4.765 mA, -1.589 mA 2018-09-19T21:37:31.204Z,1537393051.204 [NAL9602](INFO): NAL9602 initialized 2018-09-19T21:37:53.886Z,1537393073.886 [BPC1](INFO): BPC1A: initialized. 2018-09-19T21:37:53.898Z,1537393073.898 [BPC1](INFO): BPC1B: initialized. 2018-09-19T21:37:55.486Z,1537393075.486 [BPC1](INFO): BPC1A: sending IBPS break. 2018-09-19T21:37:55.487Z,1537393075.487 [BPC1](INFO): BPC1B: sending IBPS break. 2018-09-19T21:38:00.021Z,1537393080.021 [BPC1](INFO): BPC1A: validating data format. 2018-09-19T21:38:00.038Z,1537393080.038 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU. 2018-09-19T21:38:00.039Z,1537393080.039 [BPC1](INFO): BPC1B: validating data format. 2018-09-19T21:38:00.093Z,1537393080.093 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU. 2018-09-19T21:38:00.411Z,1537393080.411 [BPC1](INFO): BPC1A valid message: 1824 bytes. 2018-09-19T21:38:00.413Z,1537393080.413 [BPC1](ERROR): BPC1A: No match for stick with serial number:0165 in the onboard configuration file. 2018-09-19T21:38:00.413Z,1537393080.413 [BPC1](INFO): BPC1B valid message: 1824 bytes. 2018-09-19T21:38:00.414Z,1537393080.414 [BPC1](ERROR): BPC1B: No match for stick with serial number:0140 in the onboard configuration file. 2018-09-19T21:38:00.414Z,1537393080.414 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-09-19T21:38:00.414Z,1537393080.414 [BPC1] Data Fault, FailCount= 1 2018-09-19T21:38:00.414Z,1537393080.414 [BPC1](ERROR): Data Fault 2018-09-19T21:38:00.540Z,1537393080.540 [CBIT](ERROR): Data Fault in component: BPC1 2018-09-19T21:38:10.826Z,1537393090.826 [SBIT](IMPORTANT): SBIT PASSED 2018-09-19T21:38:10.867Z,1537393090.867 [CommandLine](IMPORTANT): got command configSet list 2018-09-19T21:38:10.867Z,1537393090.867 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-09-19T21:38:10.868Z,1537393090.868 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 minute; 2018-09-19T21:38:10.868Z,1537393090.868 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter; 2018-09-19T21:38:10.868Z,1537393090.868 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter; 2018-09-19T21:38:11.226Z,1537393091.226 [MissionManager](IMPORTANT): Started mission Startup 2018-09-19T21:38:11.226Z,1537393091.226 [Startup] Running Loop=1 2018-09-19T21:38:11.227Z,1537393091.227 [Startup](DEBUG): Aggregate::initialize Startup 2018-09-19T21:38:11.227Z,1537393091.227 [Startup:A.GoToSurface] Running Loop=1 2018-09-19T21:38:11.227Z,1537393091.227 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-09-19T21:38:11.227Z,1537393091.227 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-09-19T21:38:11.228Z,1537393091.228 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-09-19T21:38:11.228Z,1537393091.228 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-09-19T21:38:11.229Z,1537393091.229 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-09-19T21:38:11.229Z,1537393091.229 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-09-19T21:38:11.235Z,1537393091.235 [Startup:StartupSatComms] Running Loop=1 2018-09-19T21:38:11.235Z,1537393091.235 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-09-19T21:38:11.235Z,1537393091.235 [Startup:StartupSatComms:A] Running Loop=1 2018-09-19T21:38:11.598Z,1537393091.598 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-09-19T21:39:11.595Z,1537393151.595 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-19T21:38:11.2Z 2018-09-19T21:39:11.595Z,1537393151.595 [Startup:StartupSatComms:A] Stopped 2018-09-19T21:39:11.595Z,1537393151.595 [Startup:StartupSatComms:B] Running Loop=1 2018-09-19T21:39:11.987Z,1537393151.987 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-09-19T21:39:52.408Z,1537393192.408 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:39:52.409Z,1537393192.409 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2018-09-19T21:39:52.409Z,1537393192.409 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:39:52.415Z,1537393192.415 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:39:52.415Z,1537393192.415 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-09-19T21:39:52.415Z,1537393192.415 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:39:52.444Z,1537393192.444 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:39:52.445Z,1537393192.445 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:39:52.804Z,1537393192.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:39:52.804Z,1537393192.804 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2018-09-19T21:39:52.805Z,1537393192.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:39:52.805Z,1537393192.805 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-09-19T21:39:53.221Z,1537393193.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:39:53.222Z,1537393193.222 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:39:53.222Z,1537393193.222 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:39:53.222Z,1537393193.222 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:39:53.223Z,1537393193.223 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:39:53.223Z,1537393193.223 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:39:57.665Z,1537393197.665 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004097 2018-09-19T21:40:01.766Z,1537393201.766 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T212506/Courier0007.lzma 2018-09-19T21:40:02.525Z,1537393202.525 [DataOverHttps](INFO): Moved sent file to Logs/20180919T212506/Courier0007.lzma.bak 2018-09-19T21:40:02.525Z,1537393202.525 [DataOverHttps](INFO): SBD MOMSN=8540492 2018-09-19T21:40:11.503Z,1537393211.503 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20180919T213642/Courier0000.lzma 2018-09-19T21:40:11.929Z,1537393211.929 [Startup:StartupSatComms:B](INFO): Timed out from 2018-09-19T21:39:11.6Z 2018-09-19T21:40:11.929Z,1537393211.929 [Startup:StartupSatComms:B] Stopped 2018-09-19T21:40:11.929Z,1537393211.929 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-09-19T21:40:11.929Z,1537393211.929 [Startup:StartupSatComms] Stopped 2018-09-19T21:40:11.930Z,1537393211.930 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-09-19T21:40:11.931Z,1537393211.931 [Startup](INFO): Completed Startup 2018-09-19T21:40:11.931Z,1537393211.931 [MissionManager](INFO): Startup is completed. 2018-09-19T21:40:11.931Z,1537393211.931 [MissionManager](INFO): Uninitializing Mission Startup 2018-09-19T21:40:11.931Z,1537393211.931 [Startup] Stopped 2018-09-19T21:40:11.931Z,1537393211.931 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-09-19T21:40:11.931Z,1537393211.931 [Startup:A.GoToSurface] Stopped 2018-09-19T21:40:11.932Z,1537393211.932 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-09-19T21:40:12.265Z,1537393212.265 [DataOverHttps](INFO): Moved sent file to Logs/20180919T213642/Courier0000.lzma.bak 2018-09-19T21:40:12.265Z,1537393212.265 [DataOverHttps](INFO): SBD MOMSN=8540494 2018-09-19T21:40:12.342Z,1537393212.342 [MissionManager](IMPORTANT): Started mission Default 2018-09-19T21:40:12.342Z,1537393212.342 [Default] Running Loop=1 2018-09-19T21:40:12.342Z,1537393212.342 [Default](DEBUG): Aggregate::initialize Default 2018-09-19T21:40:12.343Z,1537393212.343 [Default:B.GoToSurface] Running Loop=1 2018-09-19T21:40:12.343Z,1537393212.343 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-09-19T21:40:12.343Z,1537393212.343 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-09-19T21:40:12.343Z,1537393212.343 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-09-19T21:40:12.344Z,1537393212.344 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-09-19T21:40:12.344Z,1537393212.344 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-09-19T21:40:12.344Z,1537393212.344 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-09-19T21:40:12.345Z,1537393212.345 [Default:A.Wait] Running Loop=1 2018-09-19T21:40:12.345Z,1537393212.345 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-09-19T21:40:25.595Z,1537393225.595 [Default:A.Wait](INFO): Done Waiting. 2018-09-19T21:40:25.595Z,1537393225.595 [Default:A.Wait] Stopped 2018-09-19T21:40:25.595Z,1537393225.595 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-09-19T21:40:25.962Z,1537393225.962 [Default:CheckIn] Running Loop=1 2018-09-19T21:40:25.970Z,1537393225.970 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-19T21:40:25.970Z,1537393225.970 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-19T21:40:26.391Z,1537393226.391 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-09-19T21:41:08.371Z,1537393268.371 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd, 14.617, -0.091, 0.000 2018-09-19T21:41:29.304Z,1537393289.304 [CommandLine](IMPORTANT): got command get BPC1.batterySamplingInterval 2018-09-19T21:41:29.305Z,1537393289.305 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval 0.016667 h 2018-09-19T21:41:38.384Z,1537393298.384 [CommandLine](IMPORTANT): got command failComponent 2018-09-19T21:41:38.384Z,1537393298.384 [CommandLine](IMPORTANT): Failed components: 2018-09-19T21:41:38.385Z,1537393298.385 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2018-09-19T21:41:38.385Z,1537393298.385 [CommandLine](IMPORTANT): BPC1: Data Fault 2018-09-19T21:42:16.924Z,1537393336.924 [CommandLine](IMPORTANT): got command failComponent none BPC1 2018-09-19T21:42:16.924Z,1537393336.924 [BPC1] No Fault, FailCount= 1 2018-09-19T21:42:16.925Z,1537393336.925 [CommandLine](IMPORTANT): BPC1 failureMode is No Fault 2018-09-19T21:42:34.569Z,1537393354.569 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-09-19T21:42:53.425Z,1537393373.425 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:42:53.425Z,1537393373.425 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2018-09-19T21:42:53.425Z,1537393373.425 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:42:53.427Z,1537393373.427 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:42:53.428Z,1537393373.428 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-09-19T21:42:53.428Z,1537393373.428 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:42:53.465Z,1537393373.465 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:42:53.465Z,1537393373.465 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:42:53.823Z,1537393373.823 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:42:53.823Z,1537393373.823 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2018-09-19T21:42:53.823Z,1537393373.823 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:42:53.823Z,1537393373.823 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-09-19T21:42:54.211Z,1537393374.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:42:54.212Z,1537393374.212 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:42:54.212Z,1537393374.212 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:42:54.213Z,1537393374.213 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:42:54.213Z,1537393374.213 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:42:54.214Z,1537393374.214 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:44:53.999Z,1537393493.999 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-19T21:45:11.043Z,1537393511.043 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-09-19T21:45:26.051Z,1537393526.051 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-09-19T21:45:26.051Z,1537393526.051 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-09-19T21:45:26.051Z,1537393526.051 [RDI_Pathfinder](ERROR): Communications Fault 2018-09-19T21:45:26.108Z,1537393526.108 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-19T21:40:25.0Z 2018-09-19T21:45:26.108Z,1537393526.108 [Default:CheckIn:Read_GPS] Stopped 2018-09-19T21:45:26.108Z,1537393526.108 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-19T21:45:26.212Z,1537393526.212 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-09-19T21:45:26.331Z,1537393526.331 [RDI_Pathfinder](INFO): Powering down 2018-09-19T21:45:26.360Z,1537393526.360 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-09-19T21:45:28.353Z,1537393528.353 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-09-19T21:45:28.354Z,1537393528.354 [RDI_Pathfinder] No Fault, FailCount= 1 2018-09-19T21:45:38.911Z,1537393538.911 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-09-19T21:45:39.311Z,1537393539.311 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-09-19T21:45:40.502Z,1537393540.502 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-09-19T21:45:44.567Z,1537393544.567 [NAL9602](ERROR): getFix uart error: serial timeout 2018-09-19T21:45:44.739Z,1537393544.739 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-19T21:45:47.198Z,1537393547.198 [MassServo](ERROR): getPosition uart error serial timeout 2018-09-19T21:45:47.199Z,1537393547.199 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-09-19T21:45:47.199Z,1537393547.199 [MassServo] Communications Fault, FailCount= 1 2018-09-19T21:45:47.199Z,1537393547.199 [MassServo](ERROR): Communications Fault 2018-09-19T21:45:47.204Z,1537393547.204 [CBIT](ERROR): Communications Fault in component: MassServo 2018-09-19T21:45:47.385Z,1537393547.385 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-19T21:45:47.385Z,1537393547.385 [MassServo](INFO): Powering down 2018-09-19T21:45:48.148Z,1537393548.148 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:45:48.148Z,1537393548.148 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:45:48.153Z,1537393548.153 [CBIT](INFO): Clearing failed state for component MassServo 2018-09-19T21:45:48.153Z,1537393548.153 [MassServo] No Fault, FailCount= 1 2018-09-19T21:45:50.587Z,1537393550.587 [MassServo](FAULT): Command Overflow Error 2018-09-19T21:45:50.587Z,1537393550.587 [MassServo](FAULT): Mass initialization uart error:no error 2018-09-19T21:45:50.587Z,1537393550.587 [MassServo] Communications Fault, FailCount= 2 2018-09-19T21:45:50.587Z,1537393550.587 [MassServo](ERROR): Communications Fault 2018-09-19T21:45:50.592Z,1537393550.592 [CBIT](ERROR): Communications Fault in component: MassServo 2018-09-19T21:45:50.851Z,1537393550.851 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-09-19T21:45:50.851Z,1537393550.851 [RDI_Pathfinder] Communications Fault, FailCount= 2 2018-09-19T21:45:50.852Z,1537393550.852 [RDI_Pathfinder](ERROR): Communications Fault 2018-09-19T21:45:50.902Z,1537393550.902 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-19T21:45:50.902Z,1537393550.902 [MassServo](INFO): Powering down 2018-09-19T21:45:50.932Z,1537393550.932 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-09-19T21:45:51.237Z,1537393551.237 [RDI_Pathfinder](INFO): Powering down 2018-09-19T21:45:51.718Z,1537393551.718 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:45:51.730Z,1537393551.730 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:45:51.759Z,1537393551.759 [CBIT](INFO): Clearing failed state for component MassServo 2018-09-19T21:45:51.760Z,1537393551.760 [MassServo] No Fault, FailCount= 2 2018-09-19T21:45:52.151Z,1537393552.151 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-09-19T21:45:52.151Z,1537393552.151 [RDI_Pathfinder] No Fault, FailCount= 2 2018-09-19T21:45:54.499Z,1537393554.499 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-09-19T21:45:54.499Z,1537393554.499 [MassServo] Communications Fault, FailCount= 3 2018-09-19T21:45:54.499Z,1537393554.499 [MassServo](ERROR): Communications Fault 2018-09-19T21:45:54.516Z,1537393554.516 [CBIT](ERROR): Communications Fault in component: MassServo 2018-09-19T21:45:54.641Z,1537393554.641 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:45:54.641Z,1537393554.641 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2018-09-19T21:45:54.642Z,1537393554.642 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:45:54.673Z,1537393554.673 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:45:54.673Z,1537393554.673 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-09-19T21:45:54.673Z,1537393554.673 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:45:54.709Z,1537393554.709 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-19T21:45:54.709Z,1537393554.709 [MassServo](INFO): Powering down 2018-09-19T21:45:54.758Z,1537393554.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:45:54.758Z,1537393554.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:45:55.123Z,1537393555.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:45:55.123Z,1537393555.123 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2018-09-19T21:45:55.124Z,1537393555.124 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:45:55.124Z,1537393555.124 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-09-19T21:45:55.345Z,1537393555.345 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-19T21:45:55.385Z,1537393555.385 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:45:55.385Z,1537393555.385 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:45:55.386Z,1537393555.386 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:45:55.410Z,1537393555.410 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:45:55.411Z,1537393555.411 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:45:55.411Z,1537393555.411 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:45:55.456Z,1537393555.456 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:45:55.456Z,1537393555.456 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:45:55.478Z,1537393555.478 [CBIT](INFO): Clearing failed state for component MassServo 2018-09-19T21:45:55.478Z,1537393555.478 [MassServo] No Fault, FailCount= 3 2018-09-19T21:45:56.143Z,1537393556.143 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-19T21:45:57.743Z,1537393557.743 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-19T21:45:58.114Z,1537393558.114 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-09-19T21:45:58.115Z,1537393558.115 [MassServo] Communications Fault, FailCount= 4 2018-09-19T21:45:58.115Z,1537393558.115 [MassServo](ERROR): Communications Fault 2018-09-19T21:45:58.124Z,1537393558.124 [CBIT](ERROR): Communications Fault in component: MassServo 2018-09-19T21:45:58.431Z,1537393558.431 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-19T21:45:58.431Z,1537393558.431 [MassServo](INFO): Powering down 2018-09-19T21:45:58.547Z,1537393558.547 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-19T21:45:58.551Z,1537393558.551 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-19T21:45:59.120Z,1537393559.120 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:45:59.120Z,1537393559.120 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:45:59.139Z,1537393559.139 [CBIT](INFO): Clearing failed state for component MassServo 2018-09-19T21:45:59.139Z,1537393559.139 [MassServo] No Fault, FailCount= 4 2018-09-19T21:45:59.167Z,1537393559.167 [Radio_Surface](INFO): Powering down 2018-09-19T21:46:01.714Z,1537393561.714 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-09-19T21:46:01.715Z,1537393561.715 [MassServo] Communications Fault, FailCount= 5 2018-09-19T21:46:01.715Z,1537393561.715 [MassServo](ERROR): Communications Fault 2018-09-19T21:46:01.720Z,1537393561.720 [CBIT](ERROR): Communications Fault in component: MassServo 2018-09-19T21:46:01.720Z,1537393561.720 [CBIT](CRITICAL): Com