2018-09-19T21:44:38.524Z,1537393478.524 [Supervisor](DEBUG): Initializing supervisor. 2018-09-19T21:44:38.531Z,1537393478.531 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-09-19T21:44:38.531Z,1537393478.531 [SyncHandler](INFO): Protected caller Thread ID is 2691 2018-09-19T21:44:38.532Z,1537393478.532 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-09-19T21:44:38.533Z,1537393478.533 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-09-19T21:44:38.533Z,1537393478.533 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2692 2018-09-19T21:44:38.537Z,1537393478.537 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-09-19T21:44:38.549Z,1537393478.549 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-09-19T21:44:38.551Z,1537393478.551 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-09-19T21:44:38.551Z,1537393478.551 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2693 2018-09-19T21:44:38.552Z,1537393478.552 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-09-19T21:44:38.553Z,1537393478.553 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-09-19T21:44:38.553Z,1537393478.553 [logger ThreadHandler](INFO): Protected caller Thread ID is 2694 2018-09-19T21:44:38.555Z,1537393478.555 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-09-19T21:44:38.556Z,1537393478.556 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-09-19T21:44:38.557Z,1537393478.557 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-09-19T21:44:38.915Z,1537393478.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-09-19T21:44:38.916Z,1537393478.916 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-09-19T21:44:39.108Z,1537393479.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-09-19T21:44:39.111Z,1537393479.111 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-09-19T21:44:39.711Z,1537393479.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-09-19T21:44:39.712Z,1537393479.712 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-09-19T21:44:39.867Z,1537393479.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-09-19T21:44:39.868Z,1537393479.868 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-09-19T21:44:39.980Z,1537393479.980 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-09-19T21:44:40.357Z,1537393480.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-09-19T21:44:40.358Z,1537393480.358 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-09-19T21:44:40.562Z,1537393480.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-09-19T21:44:40.563Z,1537393480.563 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-09-19T21:44:41.117Z,1537393481.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-09-19T21:44:41.117Z,1537393481.117 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-09-19T21:44:41.850Z,1537393481.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-09-19T21:44:41.850Z,1537393481.850 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-09-19T21:44:42.324Z,1537393482.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-09-19T21:44:42.325Z,1537393482.325 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-09-19T21:44:43.101Z,1537393483.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-09-19T21:44:43.102Z,1537393483.102 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-09-19T21:44:43.475Z,1537393483.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-09-19T21:44:43.475Z,1537393483.475 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-09-19T21:44:43.642Z,1537393483.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-09-19T21:44:43.643Z,1537393483.643 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-09-19T21:44:44.620Z,1537393484.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-09-19T21:44:44.621Z,1537393484.621 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-09-19T21:44:44.743Z,1537393484.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-09-19T21:44:44.745Z,1537393484.745 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-09-19T21:44:44.746Z,1537393484.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-09-19T21:44:45.094Z,1537393485.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-09-19T21:44:45.095Z,1537393485.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-09-19T21:44:45.299Z,1537393485.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-09-19T21:44:45.426Z,1537393485.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-09-19T21:44:45.543Z,1537393485.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-09-19T21:44:45.742Z,1537393485.742 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-09-19T21:44:45.907Z,1537393485.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-09-19T21:44:46.240Z,1537393486.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-09-19T21:44:46.366Z,1537393486.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-09-19T21:44:46.555Z,1537393486.555 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-09-19T21:44:46.640Z,1537393486.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-09-19T21:44:46.952Z,1537393486.952 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-09-19T21:44:46.955Z,1537393486.955 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-09-19T21:44:47.117Z,1537393487.117 [SBIT](DEBUG): Construct Startup Built In Test. 2018-09-19T21:44:47.151Z,1537393487.151 [SBIT] Loaded 2018-09-19T21:44:47.151Z,1537393487.151 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-09-19T21:44:47.152Z,1537393487.152 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-09-19T21:44:47.199Z,1537393487.199 [IBIT] Loaded 2018-09-19T21:44:47.199Z,1537393487.199 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-09-19T21:44:47.203Z,1537393487.203 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-09-19T21:44:47.483Z,1537393487.483 [CBIT] Loaded 2018-09-19T21:44:47.484Z,1537393487.484 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-09-19T21:44:47.484Z,1537393487.484 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-09-19T21:44:47.485Z,1537393487.485 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-09-19T21:44:47.561Z,1537393487.561 [VerticalControl](DEBUG): Construct VerticalControl. 2018-09-19T21:44:47.770Z,1537393487.770 [VerticalControl] Loaded 2018-09-19T21:44:47.770Z,1537393487.770 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-09-19T21:44:47.771Z,1537393487.771 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-09-19T21:44:47.835Z,1537393487.835 [HorizontalControl] Loaded 2018-09-19T21:44:47.836Z,1537393487.836 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-09-19T21:44:47.836Z,1537393487.836 [SpeedControl](DEBUG): Construct SpeedControl. 2018-09-19T21:44:47.842Z,1537393487.842 [SpeedControl] Loaded 2018-09-19T21:44:47.842Z,1537393487.842 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-09-19T21:44:47.843Z,1537393487.843 [LoopControl](DEBUG): Construct LoopControl. 2018-09-19T21:44:47.843Z,1537393487.843 [LoopControl] Loaded 2018-09-19T21:44:47.844Z,1537393487.844 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-09-19T21:44:47.844Z,1537393487.844 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-09-19T21:44:47.845Z,1537393487.845 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-09-19T21:44:47.888Z,1537393487.888 [DepthRateCalculator] Loaded 2018-09-19T21:44:47.888Z,1537393487.888 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-09-19T21:44:47.893Z,1537393487.893 [PitchRateCalculator] Loaded 2018-09-19T21:44:47.894Z,1537393487.894 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-09-19T21:44:47.914Z,1537393487.914 [SpeedCalculator] Loaded 2018-09-19T21:44:47.914Z,1537393487.914 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-09-19T21:44:47.942Z,1537393487.942 [TempGradientCalculator] Loaded 2018-09-19T21:44:47.942Z,1537393487.942 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-09-19T21:44:47.969Z,1537393487.969 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-09-19T21:44:47.969Z,1537393487.969 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-09-19T21:44:48.043Z,1537393488.043 [YawRateCalculator] Loaded 2018-09-19T21:44:48.043Z,1537393488.043 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-09-19T21:44:48.124Z,1537393488.124 [ElevatorOffsetCalculator] Loaded 2018-09-19T21:44:48.124Z,1537393488.124 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-09-19T21:44:48.125Z,1537393488.125 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-09-19T21:44:48.125Z,1537393488.125 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-09-19T21:44:48.215Z,1537393488.215 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-09-19T21:44:48.216Z,1537393488.216 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-09-19T21:44:48.587Z,1537393488.587 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-09-19T21:44:48.588Z,1537393488.588 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-09-19T21:44:48.694Z,1537393488.694 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-09-19T21:44:48.694Z,1537393488.694 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-09-19T21:44:48.758Z,1537393488.758 [DeadReckonUsingSpeedCalculator] Loaded 2018-09-19T21:44:48.758Z,1537393488.758 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-09-19T21:44:48.775Z,1537393488.775 [NavChart] Loaded 2018-09-19T21:44:48.775Z,1537393488.775 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-09-19T21:44:48.779Z,1537393488.779 [UniversalFixResidualReporter] Loaded 2018-09-19T21:44:48.780Z,1537393488.780 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-09-19T21:44:48.780Z,1537393488.780 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-09-19T21:44:48.781Z,1537393488.781 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-09-19T21:44:48.836Z,1537393488.836 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-09-19T21:44:48.837Z,1537393488.837 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-09-19T21:44:49.081Z,1537393489.081 [Aanderaa_O2] Loaded 2018-09-19T21:44:49.082Z,1537393489.082 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-09-19T21:44:49.133Z,1537393489.133 [WetLabsSeaOWL_UV_A] Loaded 2018-09-19T21:44:49.134Z,1537393489.134 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2018-09-19T21:44:49.135Z,1537393489.135 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406D54E0 2018-09-19T21:44:49.135Z,1537393489.135 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 2774 2018-09-19T21:44:49.136Z,1537393489.136 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-09-19T21:44:49.136Z,1537393489.136 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-09-19T21:44:49.713Z,1537393489.713 [AHRS_M2] Loaded 2018-09-19T21:44:49.713Z,1537393489.713 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-09-19T21:44:49.957Z,1537393489.957 [DataOverHttps] Loaded 2018-09-19T21:44:49.958Z,1537393489.958 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-09-19T21:44:50.063Z,1537393490.063 [Depth_Keller] Loaded 2018-09-19T21:44:50.063Z,1537393490.063 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-09-19T21:44:50.105Z,1537393490.105 [DropWeight] Loaded 2018-09-19T21:44:50.105Z,1537393490.105 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-09-19T21:44:50.225Z,1537393490.225 [NAL9602] Loaded 2018-09-19T21:44:50.225Z,1537393490.225 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-09-19T21:44:50.231Z,1537393490.231 [Onboard] Loaded 2018-09-19T21:44:50.231Z,1537393490.231 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-09-19T21:44:50.238Z,1537393490.238 [Radio_Surface] Loaded 2018-09-19T21:44:50.238Z,1537393490.238 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-09-19T21:44:50.239Z,1537393490.239 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409364E0 2018-09-19T21:44:50.240Z,1537393490.240 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2775 2018-09-19T21:44:50.270Z,1537393490.270 [RDI_Pathfinder] Loaded 2018-09-19T21:44:50.271Z,1537393490.271 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-09-19T21:44:50.293Z,1537393490.293 [RDI_PathfinderUp] Loaded 2018-09-19T21:44:50.293Z,1537393490.293 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2018-09-19T21:44:50.303Z,1537393490.303 [SCPI] Loaded 2018-09-19T21:44:50.304Z,1537393490.304 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-09-19T21:44:50.329Z,1537393490.329 [BPC1](INFO): BPC1A: got serial number for stick 1: 0140 2018-09-19T21:44:50.330Z,1537393490.330 [BPC1](INFO): BPC1A: got serial number for stick 2: 0163 2018-09-19T21:44:50.331Z,1537393490.331 [BPC1](INFO): BPC1A: got serial number for stick 3: 0157 2018-09-19T21:44:50.332Z,1537393490.332 [BPC1](INFO): BPC1A: got serial number for stick 4: 0159 2018-09-19T21:44:50.333Z,1537393490.333 [BPC1](INFO): BPC1A: got serial number for stick 5: 016E 2018-09-19T21:44:50.333Z,1537393490.333 [BPC1](INFO): BPC1A: got serial number for stick 6: 0160 2018-09-19T21:44:50.334Z,1537393490.334 [BPC1](INFO): BPC1A: got serial number for stick 7: 015C 2018-09-19T21:44:50.335Z,1537393490.335 [BPC1](INFO): BPC1A: got serial number for stick 8: 016D 2018-09-19T21:44:50.336Z,1537393490.336 [BPC1](INFO): BPC1A: got serial number for stick 9: 012D 2018-09-19T21:44:50.336Z,1537393490.336 [BPC1](INFO): BPC1A: got serial number for stick 10: 015E 2018-09-19T21:44:50.337Z,1537393490.337 [BPC1](INFO): BPC1A: got serial number for stick 11: 0164 2018-09-19T21:44:50.338Z,1537393490.338 [BPC1](INFO): BPC1A: got serial number for stick 12: 015B 2018-09-19T21:44:50.339Z,1537393490.339 [BPC1](INFO): BPC1A: got serial number for stick 13: 0172 2018-09-19T21:44:50.339Z,1537393490.339 [BPC1](INFO): BPC1A: got serial number for stick 14: 0166 2018-09-19T21:44:50.340Z,1537393490.340 [BPC1](INFO): BPC1A: got serial number for stick 15: 0170 2018-09-19T21:44:50.341Z,1537393490.341 [BPC1](INFO): BPC1A: got serial number for stick 16: 0148 2018-09-19T21:44:50.342Z,1537393490.342 [BPC1](INFO): BPC1A: got serial number for stick 17: 0130 2018-09-19T21:44:50.342Z,1537393490.342 [BPC1](INFO): BPC1A: got serial number for stick 18: 0169 2018-09-19T21:44:50.343Z,1537393490.343 [BPC1](INFO): BPC1A: got serial number for stick 19: 0158 2018-09-19T21:44:50.344Z,1537393490.344 [BPC1](INFO): BPC1A: got serial number for stick 20: 0162 2018-09-19T21:44:50.345Z,1537393490.345 [BPC1](INFO): BPC1A: got serial number for stick 21: 0150 2018-09-19T21:44:50.345Z,1537393490.345 [BPC1](INFO): BPC1A: got serial number for stick 22: 015F 2018-09-19T21:44:50.346Z,1537393490.346 [BPC1](INFO): BPC1A: got serial number for stick 23: 0171 2018-09-19T21:44:50.347Z,1537393490.347 [BPC1](INFO): BPC1A: got serial number for stick 24: 0143 2018-09-19T21:44:50.347Z,1537393490.347 [BPC1](INFO): BPC1A: got serial number for stick 25: 0144 2018-09-19T21:44:50.348Z,1537393490.348 [BPC1](INFO): BPC1A: got serial number for stick 26: 016A 2018-09-19T21:44:50.349Z,1537393490.349 [BPC1](INFO): BPC1A: got serial number for stick 27: 0155 2018-09-19T21:44:50.350Z,1537393490.350 [BPC1](INFO): BPC1A: got serial number for stick 28: 0151 2018-09-19T21:44:50.350Z,1537393490.350 [BPC1](INFO): BPC1A: got serial number for stick 29: 0154 2018-09-19T21:44:50.351Z,1537393490.351 [BPC1](INFO): BPC1A: got serial number for stick 30: 0161 2018-09-19T21:44:50.352Z,1537393490.352 [BPC1](INFO): BPC1A: got serial number for stick 31: 0138 2018-09-19T21:44:50.353Z,1537393490.353 [BPC1](INFO): BPC1B: got serial number for stick 32: 0165 2018-09-19T21:44:50.356Z,1537393490.356 [BPC1](INFO): BPC1B: got serial number for stick 33: 0135 2018-09-19T21:44:50.357Z,1537393490.357 [BPC1](INFO): BPC1B: got serial number for stick 34: 0149 2018-09-19T21:44:50.358Z,1537393490.358 [BPC1](INFO): BPC1B: got serial number for stick 35: 0146 2018-09-19T21:44:50.358Z,1537393490.358 [BPC1](INFO): BPC1B: got serial number for stick 36: 013D 2018-09-19T21:44:50.359Z,1537393490.359 [BPC1](INFO): BPC1B: got serial number for stick 37: 014D 2018-09-19T21:44:50.360Z,1537393490.360 [BPC1](INFO): BPC1B: got serial number for stick 38: 0156 2018-09-19T21:44:50.361Z,1537393490.361 [BPC1](INFO): BPC1B: got serial number for stick 39: 0133 2018-09-19T21:44:50.361Z,1537393490.361 [BPC1](INFO): BPC1B: got serial number for stick 40: 0131 2018-09-19T21:44:50.362Z,1537393490.362 [BPC1](INFO): BPC1B: got serial number for stick 41: 0145 2018-09-19T21:44:50.363Z,1537393490.363 [BPC1](INFO): BPC1B: got serial number for stick 42: 014A 2018-09-19T21:44:50.364Z,1537393490.364 [BPC1](INFO): BPC1B: got serial number for stick 43: 013C 2018-09-19T21:44:50.364Z,1537393490.364 [BPC1](INFO): BPC1B: got serial number for stick 44: 013B 2018-09-19T21:44:50.365Z,1537393490.365 [BPC1](INFO): BPC1B: got serial number for stick 45: 0132 2018-09-19T21:44:50.366Z,1537393490.366 [BPC1](INFO): BPC1B: got serial number for stick 46: 0134 2018-09-19T21:44:50.370Z,1537393490.370 [BPC1](INFO): BPC1B: got serial number for stick 47: 0153 2018-09-19T21:44:50.371Z,1537393490.371 [BPC1](INFO): BPC1B: got serial number for stick 48: 014B 2018-09-19T21:44:50.372Z,1537393490.372 [BPC1](INFO): BPC1B: got serial number for stick 49: 013A 2018-09-19T21:44:50.372Z,1537393490.372 [BPC1](INFO): BPC1B: got serial number for stick 50: 0136 2018-09-19T21:44:50.373Z,1537393490.373 [BPC1](INFO): BPC1B: got serial number for stick 51: 014F 2018-09-19T21:44:50.374Z,1537393490.374 [BPC1](INFO): BPC1B: got serial number for stick 52: 014E 2018-09-19T21:44:50.375Z,1537393490.375 [BPC1](INFO): BPC1B: got serial number for stick 53: 013F 2018-09-19T21:44:50.375Z,1537393490.375 [BPC1](INFO): BPC1B: got serial number for stick 54: 0152 2018-09-19T21:44:50.376Z,1537393490.376 [BPC1](INFO): BPC1B: got serial number for stick 55: 014C 2018-09-19T21:44:50.377Z,1537393490.377 [BPC1](INFO): BPC1B: got serial number for stick 56: 016B 2018-09-19T21:44:50.377Z,1537393490.377 [BPC1](INFO): BPC1B: got serial number for stick 57: 0141 2018-09-19T21:44:50.378Z,1537393490.378 [BPC1](INFO): BPC1B: got serial number for stick 58: 0139 2018-09-19T21:44:50.379Z,1537393490.379 [BPC1](INFO): BPC1B: got serial number for stick 59: 0137 2018-09-19T21:44:50.380Z,1537393490.380 [BPC1](INFO): BPC1B: got serial number for stick 60: 0142 2018-09-19T21:44:50.380Z,1537393490.380 [BPC1](INFO): BPC1B: got serial number for stick 61: 013E 2018-09-19T21:44:50.381Z,1537393490.381 [BPC1](INFO): BPC1B: got serial number for stick 62: 0147 2018-09-19T21:44:53.158Z,1537393493.158 [BPC1] Loaded 2018-09-19T21:44:53.158Z,1537393493.158 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-09-19T21:44:53.159Z,1537393493.159 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-09-19T21:44:53.160Z,1537393493.160 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-09-19T21:44:53.486Z,1537393493.486 [BuoyancyServo] Loaded 2018-09-19T21:44:53.486Z,1537393493.486 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-09-19T21:44:53.503Z,1537393493.503 [ElevatorServo] Loaded 2018-09-19T21:44:53.503Z,1537393493.503 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-09-19T21:44:53.515Z,1537393493.515 [MassServo] Loaded 2018-09-19T21:44:53.515Z,1537393493.515 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-09-19T21:44:53.529Z,1537393493.529 [RudderServo] Loaded 2018-09-19T21:44:53.529Z,1537393493.529 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-09-19T21:44:53.540Z,1537393493.540 [ThrusterServo] Loaded 2018-09-19T21:44:53.541Z,1537393493.541 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-09-19T21:44:53.541Z,1537393493.541 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-09-19T21:44:53.542Z,1537393493.542 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-09-19T21:44:53.748Z,1537393493.748 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-09-19T21:44:53.748Z,1537393493.748 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-09-19T21:44:53.773Z,1537393493.773 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-09-19T21:44:53.777Z,1537393493.777 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-09-19T21:44:53.778Z,1537393493.778 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-09-19T21:44:53.785Z,1537393493.785 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-09-19T21:44:53.786Z,1537393493.786 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409DF4E0 2018-09-19T21:44:53.786Z,1537393493.786 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2777 2018-09-19T21:44:53.791Z,1537393493.791 [Supervisor](INFO): Main Thread ID is 2690 2018-09-19T21:44:53.791Z,1537393493.791 [Supervisor](DEBUG): Running supervisor. 2018-09-19T21:44:53.791Z,1537393493.791 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2778 2018-09-19T21:44:53.794Z,1537393493.794 [controlThread ThreadHandler](INFO): Handler Thread ID is 2779 2018-09-19T21:44:53.794Z,1537393493.794 [controlThread](DEBUG): Initializing ControlThread 2018-09-19T21:44:53.795Z,1537393493.795 [SBIT](INFO): Initialize SBIT Component. 2018-09-19T21:44:53.795Z,1537393493.795 [SBIT](IMPORTANT): git: 2018-09-04-21-gd54d9fc 2018-09-19T21:44:53.796Z,1537393493.796 [SBIT](INFO): git hash: d54d9fc12f891c3a6b7eed365ed7708e78930892 2018-09-19T21:44:53.796Z,1537393493.796 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-09-19T21:44:53.797Z,1537393493.797 [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:44:53.799Z,1537393493.799 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2018-09-19T21:44:53.799Z,1537393493.799 [IBIT](INFO): Initialize IBIT Component. 2018-09-19T21:44:53.800Z,1537393493.800 [CBIT](DEBUG): Initialize CBIT Component. 2018-09-19T21:44:53.800Z,1537393493.800 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-09-19T21:44:53.801Z,1537393493.801 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-09-19T21:44:53.802Z,1537393493.802 [logger ThreadHandler](INFO): Handler Thread ID is 2780 2018-09-19T21:44:53.824Z,1537393493.824 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 2781 2018-09-19T21:44:53.826Z,1537393493.826 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-09-19T21:44:53.851Z,1537393493.851 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2782 2018-09-19T21:44:53.860Z,1537393493.860 [Radio_Surface](INFO): Powering up 2018-09-19T21:44:53.867Z,1537393493.867 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2783 2018-09-19T21:44:53.874Z,1537393493.874 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-09-19T21:44:53.875Z,1537393493.875 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-09-19T21:44:53.875Z,1537393493.875 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-09-19T21:44:53.875Z,1537393493.875 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-09-19T21:44:53.875Z,1537393493.875 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-09-19T21:44:53.875Z,1537393493.875 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-09-19T21:44:53.876Z,1537393493.876 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-09-19T21:44:53.876Z,1537393493.876 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-09-19T21:44:53.876Z,1537393493.876 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-09-19T21:44:53.876Z,1537393493.876 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-09-19T21:44:53.877Z,1537393493.877 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-09-19T21:44:53.877Z,1537393493.877 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-09-19T21:44:53.877Z,1537393493.877 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-09-19T21:44:53.877Z,1537393493.877 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-09-19T21:44:53.877Z,1537393493.877 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-09-19T21:44:53.877Z,1537393493.878 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-09-19T21:44:53.907Z,1537393493.907 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-09-19T21:44:53.908Z,1537393493.908 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-09-19T21:44:53.909Z,1537393493.909 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-09-19T21:44:53.910Z,1537393493.910 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-09-19T21:44:53.910Z,1537393493.910 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-09-19T21:44:53.910Z,1537393493.910 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-09-19T21:44:53.911Z,1537393493.911 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-09-19T21:44:53.911Z,1537393493.911 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-09-19T21:44:53.912Z,1537393493.912 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-09-19T21:44:53.912Z,1537393493.912 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-09-19T21:44:53.913Z,1537393493.913 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-09-19T21:44:53.913Z,1537393493.913 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:44:53.914Z,1537393493.914 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:44:53.914Z,1537393493.914 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:44:53.915Z,1537393493.915 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:44:53.915Z,1537393493.915 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:44:53.916Z,1537393493.916 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:44:53.916Z,1537393493.916 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-09-19T21:44:53.916Z,1537393493.916 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-09-19T21:44:53.925Z,1537393493.925 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-09-19T21:44:53.956Z,1537393493.956 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-09-19T21:44:53.977Z,1537393493.977 [MissionManager](DEBUG): 2018-09-19T21:44:53.978Z,1537393493.978 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-09-19T21:44:54.175Z,1537393494.175 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-09-19T21:44:54.176Z,1537393494.176 [Default:A.Wait](DEBUG): Construct Wait. 2018-09-19T21:44:54.178Z,1537393494.178 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-09-19T21:44:54.230Z,1537393494.230 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-09-19T21:44:54.233Z,1537393494.233 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-09-19T21:44:54.255Z,1537393494.255 [Default:E.Execute](DEBUG): Construct Execute. 2018-09-19T21:44:54.271Z,1537393494.271 [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:44:54.276Z,1537393494.276 [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:44:54.365Z,1537393494.365 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-09-19T21:44:54.493Z,1537393494.493 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-09-19T21:44:55.024Z,1537393495.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:44:55.048Z,1537393495.048 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:44:55.172Z,1537393495.172 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:44:55.179Z,1537393495.179 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-09-19T21:44:55.213Z,1537393495.213 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:44:55.213Z,1537393495.213 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-09-19T21:44:55.299Z,1537393495.299 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:44:55.299Z,1537393495.299 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:44:55.373Z,1537393495.373 [RudderServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:44:55.379Z,1537393495.379 [RudderServo](DEBUG): Initializing RudderServo. 2018-09-19T21:44:55.437Z,1537393495.437 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:44:55.441Z,1537393495.441 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-09-19T21:44:55.860Z,1537393495.860 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-09-19T21:44:55.860Z,1537393495.860 [DropWeight] Hardware Fault, FailCount= 1 2018-09-19T21:44:55.860Z,1537393495.860 [DropWeight](ERROR): Hardware Fault 2018-09-19T21:44:55.951Z,1537393495.951 [RDI_PathfinderUp](INFO): Powering down 2018-09-19T21:44:56.080Z,1537393496.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:44:56.081Z,1537393496.081 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:44:56.546Z,1537393496.546 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-09-19T21:44:56.547Z,1537393496.547 [ElevatorServo](FAULT): Elevator failed to initialize 2018-09-19T21:44:56.547Z,1537393496.547 [ElevatorServo] Communications Fault, FailCount= 1 2018-09-19T21:44:56.547Z,1537393496.547 [ElevatorServo](ERROR): Communications Fault 2018-09-19T21:44:56.704Z,1537393496.704 [CommandLine](FAULT): Scheduling is paused 2018-09-19T21:44:56.705Z,1537393496.705 [CBIT](INFO): Critical error at 20180919T214455 2018-09-19T21:44:56.705Z,1537393496.705 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-09-19T21:44:56.707Z,1537393496.707 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-09-19T21:44:56.708Z,1537393496.708 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-09-19T21:44:56.708Z,1537393496.708 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2018-09-19T21:45:10.480Z,1537393510.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:45:10.481Z,1537393510.481 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:45:10.504Z,1537393510.504 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-09-19T21:45:10.505Z,1537393510.505 [ElevatorServo](INFO): Powering down 2018-09-19T21:45:10.663Z,1537393510.663 [CBIT](INFO): Critical error at 20180919T214456 2018-09-19T21:45:10.842Z,1537393510.842 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:45:10.844Z,1537393510.844 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T21:45:11.179Z,1537393511.179 [MassServo](FAULT): Mass initialization uart error:serial timeout 2018-09-19T21:45:11.179Z,1537393511.179 [MassServo] Communications Fault, FailCount= 1 2018-09-19T21:45:11.179Z,1537393511.179 [MassServo](ERROR): Communications Fault 2018-09-19T21:45:11.235Z,1537393511.235 [CBIT](ERROR): Communications Fault in component: MassServo 2018-09-19T21:45:11.363Z,1537393511.363 [Aanderaa_O2](INFO): Powering down 2018-09-19T21:45:11.729Z,1537393511.729 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:45:11.844Z,1537393511.844 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-09-19T21:45:11.845Z,1537393511.845 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-19T21:45:11.845Z,1537393511.845 [MassServo](INFO): Powering down 2018-09-19T21:45:11.946Z,1537393511.946 [CBIT](INFO): Clearing failed state for component ElevatorServo 2018-09-19T21:45:11.947Z,1537393511.947 [ElevatorServo] No Fault, FailCount= 1 2018-09-19T21:45:11.992Z,1537393511.992 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004234 2018-09-19T21:45:12.720Z,1537393512.720 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:45:12.839Z,1537393512.839 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:45:13.132Z,1537393513.132 [CBIT](INFO): Clearing failed state for component MassServo 2018-09-19T21:45:13.133Z,1537393513.133 [MassServo] No Fault, FailCount= 1 2018-09-19T21:45:20.368Z,1537393520.368 [NAL9602](INFO): Powering up NAL9602 2018-09-19T21:45:22.499Z,1537393522.499 [SBIT](IMPORTANT): Beginning Startup BIT 2018-09-19T21:45:22.503Z,1537393522.503 [CBIT](IMPORTANT): Beginning ground fault scan 2018-09-19T21:45:28.169Z,1537393528.169 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-19T21:45:28.219Z,1537393528.219 [Radio_Surface](INFO): Powering down 2018-09-19T21:45:33.501Z,1537393533.501 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.018690 CHAN A1 (24V): -0.021892 CHAN A2 (12V): -0.007195 CHAN A3 (5V): -0.002052 CHAN B0 (3.3V): 0.000232 CHAN B1 (3.15aV): -0.000222 CHAN B2 (3.15bV): 0.000137 CHAN B3 (GND): 0.002398 OPEN: 0.004909 Full Scale Calc: 4.765 mA, -1.589 mA 2018-09-19T21:45:34.299Z,1537393534.299 [Radio_Surface](INFO): Powering up 2018-09-19T21:45:36.196Z,1537393536.196 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2018-09-19T21:45:36.196Z,1537393536.196 [RDI_Pathfinder] Communications Fault, FailCount= 1 2018-09-19T21:45:36.196Z,1537393536.196 [RDI_Pathfinder](ERROR): Communications Fault 2018-09-19T21:45:36.237Z,1537393536.237 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2018-09-19T21:45:36.585Z,1537393536.585 [RDI_Pathfinder](INFO): Powering down 2018-09-19T21:45:37.508Z,1537393537.508 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-09-19T21:45:37.508Z,1537393537.508 [RDI_Pathfinder] No Fault, FailCount= 1 2018-09-19T21:45:37.762Z,1537393537.762 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2018-09-19T21:45:37.763Z,1537393537.763 [AHRS_M2] Data Fault, FailCount= 1 2018-09-19T21:45:37.763Z,1537393537.763 [AHRS_M2](ERROR): Data Fault 2018-09-19T21:45:37.803Z,1537393537.803 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.383119 seconds old. 2018-09-19T21:45:37.805Z,1537393537.805 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.383542 seconds old. 2018-09-19T21:45:38.320Z,1537393538.320 [CBIT](ERROR): Data Fault in component: AHRS_M2 2018-09-19T21:45:38.328Z,1537393538.328 [AHRS_M2](INFO): Powering down 2018-09-19T21:45:38.375Z,1537393538.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.955135 seconds old. 2018-09-19T21:45:38.377Z,1537393538.377 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.955557 seconds old. 2018-09-19T21:45:38.765Z,1537393538.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.344608 seconds old. 2018-09-19T21:45:38.774Z,1537393538.774 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.345023 seconds old. 2018-09-19T21:45:39.161Z,1537393539.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.740744 seconds old. 2018-09-19T21:45:39.162Z,1537393539.162 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.741163 seconds old. 2018-09-19T21:45:39.560Z,1537393539.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.139547 seconds old. 2018-09-19T21:45:39.561Z,1537393539.561 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.139939 seconds old. 2018-09-19T21:45:39.960Z,1537393539.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.540059 seconds old. 2018-09-19T21:45:39.962Z,1537393539.962 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.540457 seconds old. 2018-09-19T21:45:40.329Z,1537393540.329 [AHRS_M2](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Software Overcurrent. Current Limiter Activated. 2018-09-19T21:45:40.330Z,1537393540.330 [AHRS_M2] Hardware Fault, FailCount= 1 2018-09-19T21:45:40.330Z,1537393540.330 [AHRS_M2](ERROR): Hardware Fault 2018-09-19T21:45:40.375Z,1537393540.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.954854 seconds old. 2018-09-19T21:45:40.376Z,1537393540.376 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.955261 seconds old. 2018-09-19T21:45:40.760Z,1537393540.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.339529 seconds old. 2018-09-19T21:45:40.761Z,1537393540.761 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.339939 seconds old. 2018-09-19T21:45:41.144Z,1537393541.144 [NAL9602](ERROR): NAL9602 initialization error. 2018-09-19T21:45:41.144Z,1537393541.144 [NAL9602] Communications Fault, FailCount= 1 2018-09-19T21:45:41.144Z,1537393541.144 [NAL9602](ERROR): Communications Fault 2018-09-19T21:45:41.172Z,1537393541.172 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.751731 seconds old. 2018-09-19T21:45:41.173Z,1537393541.173 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.752142 seconds old. 2018-09-19T21:45:41.304Z,1537393541.304 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-09-19T21:45:41.529Z,1537393541.529 [AHRS_M2](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. 2018-09-19T21:45:41.544Z,1537393541.544 [NAL9602](INFO): Powering down 2018-09-19T21:45:41.631Z,1537393541.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.210915 seconds old. 2018-09-19T21:45:41.632Z,1537393541.632 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.211325 seconds old. 2018-09-19T21:45:41.960Z,1537393541.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.539578 seconds old. 2018-09-19T21:45:41.961Z,1537393541.961 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.539940 seconds old. 2018-09-19T21:45:42.111Z,1537393542.111 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-09-19T21:45:42.111Z,1537393542.111 [AHRS_M2] No Fault, FailCount= 1 2018-09-19T21:45:42.329Z,1537393542.329 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-09-19T21:45:42.368Z,1537393542.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.948073 seconds old. 2018-09-19T21:45:42.370Z,1537393542.370 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.948491 seconds old. 2018-09-19T21:45:42.485Z,1537393542.485 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-19T21:45:42.485Z,1537393542.485 [NAL9602] No Fault, FailCount= 1 2018-09-19T21:45:42.756Z,1537393542.756 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.335485 seconds old. 2018-09-19T21:45:42.757Z,1537393542.757 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.335903 seconds old. 2018-09-19T21:45:43.176Z,1537393543.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 5.756249 seconds old. 2018-09-19T21:45:43.178Z,1537393543.178 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.756665 seconds old. 2018-09-19T21:45:43.560Z,1537393543.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.139542 seconds old. 2018-09-19T21:45:43.561Z,1537393543.561 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.139956 seconds old. 2018-09-19T21:45:43.960Z,1537393543.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.539529 seconds old. 2018-09-19T21:45:43.961Z,1537393543.961 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.539938 seconds old. 2018-09-19T21:45:44.383Z,1537393544.383 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 6.962606 seconds old. 2018-09-19T21:45:44.384Z,1537393544.384 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.963045 seconds old. 2018-09-19T21:45:44.401Z,1537393544.401 [Radio_Surface](INFO): Powering down 2018-09-19T21:45:44.889Z,1537393544.889 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.469047 seconds old. 2018-09-19T21:45:44.911Z,1537393544.911 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.489637 seconds old. 2018-09-19T21:45:45.131Z,1537393545.131 [Depth_Keller](ERROR): Pressure reading out of range: 957.962097 decibar 2018-09-19T21:45:45.176Z,1537393545.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 7.756232 seconds old. 2018-09-19T21:45:45.179Z,1537393545.179 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.758192 seconds old. 2018-09-19T21:45:46.592Z,1537393546.592 [MassServo](FAULT): Invalid EZ Servo response:"/0@26956" 2018-09-19T21:45:46.592Z,1537393546.592 [MassServo] Communications Fault, FailCount= 1 2018-09-19T21:45:46.592Z,1537393546.592 [MassServo](ERROR): Communications Fault 2018-09-19T21:45:46.599Z,1537393546.599 [CBIT](ERROR): Communications Fault in component: MassServo 2018-09-19T21:45:46.784Z,1537393546.784 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-19T21:45:46.784Z,1537393546.784 [MassServo](INFO): Powering down 2018-09-19T21:45:47.499Z,1537393547.499 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:45:47.499Z,1537393547.499 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:45:47.521Z,1537393547.521 [CBIT](INFO): Clearing failed state for component MassServo 2018-09-19T21:45:47.521Z,1537393547.521 [MassServo] No Fault, FailCount= 1 2018-09-19T21:45:50.471Z,1537393550.471 [Radio_Surface](INFO): Powering up 2018-09-19T21:45:51.051Z,1537393551.051 [RDI_Pathfinder](ERROR): Failed to parse::SA, +0.00, +0.00, 0.00 2018-09-19T21:45:51.402Z,1537393551.402 [MassServo](ERROR): isCommunicating uart error serial timeout 2018-09-19T21:45:51.403Z,1537393551.403 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-09-19T21:45:51.403Z,1537393551.403 [MassServo] Communications Fault, FailCount= 2 2018-09-19T21:45:51.403Z,1537393551.403 [MassServo](ERROR): Communications Fault 2018-09-19T21:45:51.403Z,1537393551.403 [MassServo](FAULT): Mass Shifter error waiting for homing. Uart error: serial timeout 2018-09-19T21:45:51.403Z,1537393551.403 [MassServo] Hardware Fault, FailCount= 2 2018-09-19T21:45:51.403Z,1537393551.403 [MassServo](ERROR): Hardware Fault 2018-09-19T21:45:52.046Z,1537393552.046 [MassServo](ERROR): getPosition uart error serial timeout 2018-09-19T21:45:52.047Z,1537393552.047 [MassServo](FAULT): Invalid EZ Servo response:"" 2018-09-19T21:45:52.047Z,1537393552.047 [MassServo] Communications Fault, FailCount= 2 2018-09-19T21:45:52.047Z,1537393552.047 [MassServo](ERROR): Communications Fault 2018-09-19T21:45:52.053Z,1537393552.053 [CBIT](ERROR): Communications Fault in component: MassServo 2018-09-19T21:45:52.260Z,1537393552.260 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-19T21:45:52.260Z,1537393552.260 [MassServo](INFO): Powering down 2018-09-19T21:45:53.030Z,1537393553.030 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:45:53.030Z,1537393553.030 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:45:53.061Z,1537393553.061 [CBIT](INFO): Clearing failed state for component MassServo 2018-09-19T21:45:53.061Z,1537393553.061 [MassServo] No Fault, FailCount= 2 2018-09-19T21:45:54.083Z,1537393554.083 [Depth_Keller](ERROR): Pressure reading out of range: 957.962097 decibar 2018-09-19T21:45:55.287Z,1537393555.287 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2018-09-19T21:45:55.287Z,1537393555.287 [MassServo] Communications Fault, FailCount= 3 2018-09-19T21:45:55.287Z,1537393555.287 [MassServo](ERROR): Communications Fault 2018-09-19T21:45:55.292Z,1537393555.292 [CBIT](ERROR): Communications Fault in component: MassServo 2018-09-19T21:45:55.485Z,1537393555.485 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-19T21:45:55.485Z,1537393555.485 [MassServo](INFO): Powering down 2018-09-19T21:45:55.754Z,1537393555.754 [BPC1](INFO): BPC1A: initialized. 2018-09-19T21:45:56.194Z,1537393556.194 [BPC1](INFO): BPC1B: initialized. 2018-09-19T21:45:56.311Z,1537393556.311 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T21:45:56.311Z,1537393556.311 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T21:45:56.323Z,1537393556.323 [CBIT](INFO): Clearing failed state for component MassServo 20