2019-03-22T23:14:27.586Z,1553296467.586 [Supervisor](DEBUG): Initializing supervisor.
2019-03-22T23:14:27.588Z,1553296467.588 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-22T23:14:27.589Z,1553296467.589 [SyncHandler](INFO): Protected caller Thread ID is 6221
2019-03-22T23:14:27.589Z,1553296467.589 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-22T23:14:27.590Z,1553296467.590 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-22T23:14:27.590Z,1553296467.590 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6222
2019-03-22T23:14:27.593Z,1553296467.593 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-22T23:14:27.605Z,1553296467.605 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-22T23:14:27.606Z,1553296467.606 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-22T23:14:27.606Z,1553296467.606 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6223
2019-03-22T23:14:27.607Z,1553296467.607 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-22T23:14:27.608Z,1553296467.608 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-22T23:14:27.608Z,1553296467.608 [logger ThreadHandler](INFO): Protected caller Thread ID is 6224
2019-03-22T23:14:27.610Z,1553296467.610 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-22T23:14:27.611Z,1553296467.611 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-22T23:14:27.612Z,1553296467.612 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-22T23:14:28.040Z,1553296468.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-22T23:14:28.041Z,1553296468.041 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-22T23:14:28.187Z,1553296468.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-22T23:14:28.188Z,1553296468.188 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-22T23:14:28.270Z,1553296468.270 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-22T23:14:28.370Z,1553296468.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-22T23:14:28.371Z,1553296468.371 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-22T23:14:28.468Z,1553296468.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-22T23:14:28.469Z,1553296468.469 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-22T23:14:28.845Z,1553296468.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-22T23:14:28.846Z,1553296468.846 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-22T23:14:28.950Z,1553296468.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-22T23:14:28.951Z,1553296468.951 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-22T23:14:29.413Z,1553296469.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-22T23:14:29.414Z,1553296469.414 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-22T23:14:29.620Z,1553296469.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-22T23:14:29.621Z,1553296469.621 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-22T23:14:29.931Z,1553296469.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-22T23:14:29.932Z,1553296469.932 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-22T23:14:30.245Z,1553296470.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-22T23:14:30.246Z,1553296470.246 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-22T23:14:30.516Z,1553296470.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-22T23:14:30.517Z,1553296470.517 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-22T23:14:31.018Z,1553296471.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-22T23:14:31.019Z,1553296471.019 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-22T23:14:31.360Z,1553296471.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-22T23:14:31.361Z,1553296471.361 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-22T23:14:31.570Z,1553296471.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-22T23:14:31.572Z,1553296471.572 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-03-22T23:14:31.573Z,1553296471.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-03-22T23:14:31.753Z,1553296471.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-03-22T23:14:31.837Z,1553296471.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-03-22T23:14:31.954Z,1553296471.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-03-22T23:14:32.041Z,1553296472.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-03-22T23:14:32.268Z,1553296472.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-22T23:14:32.269Z,1553296472.269 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-03-22T23:14:32.370Z,1553296472.370 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-03-22T23:14:32.482Z,1553296472.482 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-03-22T23:14:32.578Z,1553296472.578 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-03-22T23:14:32.733Z,1553296472.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-03-22T23:14:32.836Z,1553296472.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-03-22T23:14:32.938Z,1553296472.938 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-03-22T23:14:32.939Z,1553296472.939 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-22T23:14:32.949Z,1553296472.949 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-22T23:14:32.963Z,1553296472.963 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-22T23:14:32.963Z,1553296472.963 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-22T23:14:33.028Z,1553296473.028 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-22T23:14:33.140Z,1553296473.140 [VerticalControl] Loaded
2019-03-22T23:14:33.140Z,1553296473.140 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-22T23:14:33.141Z,1553296473.141 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-22T23:14:33.210Z,1553296473.210 [HorizontalControl] Loaded
2019-03-22T23:14:33.210Z,1553296473.210 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-22T23:14:33.210Z,1553296473.210 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-22T23:14:33.216Z,1553296473.216 [SpeedControl] Loaded
2019-03-22T23:14:33.216Z,1553296473.216 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-22T23:14:33.217Z,1553296473.217 [LoopControl](DEBUG): Construct LoopControl.
2019-03-22T23:14:33.217Z,1553296473.217 [LoopControl] Loaded
2019-03-22T23:14:33.218Z,1553296473.218 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-22T23:14:33.218Z,1553296473.218 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-22T23:14:33.219Z,1553296473.219 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-22T23:14:33.330Z,1553296473.330 [ElevatorServo] Loaded
2019-03-22T23:14:33.331Z,1553296473.331 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-22T23:14:33.341Z,1553296473.341 [MassServo] Loaded
2019-03-22T23:14:33.342Z,1553296473.342 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-22T23:14:33.353Z,1553296473.353 [ThrusterServo] Loaded
2019-03-22T23:14:33.353Z,1553296473.353 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-22T23:14:33.353Z,1553296473.353 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-22T23:14:33.354Z,1553296473.354 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-22T23:14:33.393Z,1553296473.393 [DepthRateCalculator] Loaded
2019-03-22T23:14:33.393Z,1553296473.393 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-22T23:14:33.398Z,1553296473.398 [PitchRateCalculator] Loaded
2019-03-22T23:14:33.399Z,1553296473.399 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-22T23:14:33.413Z,1553296473.413 [SpeedCalculator] Loaded
2019-03-22T23:14:33.414Z,1553296473.414 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-22T23:14:33.434Z,1553296473.434 [TempGradientCalculator] Loaded
2019-03-22T23:14:33.434Z,1553296473.434 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-22T23:14:33.439Z,1553296473.439 [YawRateCalculator] Loaded
2019-03-22T23:14:33.440Z,1553296473.440 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-22T23:14:33.469Z,1553296473.469 [ElevatorOffsetCalculator] Loaded
2019-03-22T23:14:33.469Z,1553296473.469 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-22T23:14:33.470Z,1553296473.470 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-22T23:14:33.470Z,1553296473.470 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-22T23:14:33.572Z,1553296473.572 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-22T23:14:33.572Z,1553296473.572 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-22T23:14:33.631Z,1553296473.631 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-22T23:14:33.631Z,1553296473.631 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-22T23:14:33.651Z,1553296473.651 [NavChart] Loaded
2019-03-22T23:14:33.652Z,1553296473.652 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-22T23:14:33.656Z,1553296473.656 [UniversalFixResidualReporter] Loaded
2019-03-22T23:14:33.656Z,1553296473.656 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-22T23:14:33.656Z,1553296473.656 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-22T23:14:33.657Z,1553296473.657 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-22T23:14:33.962Z,1553296473.962 [DataOverHttps] Loaded
2019-03-22T23:14:33.962Z,1553296473.962 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-22T23:14:33.963Z,1553296473.963 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408004E0
2019-03-22T23:14:33.964Z,1553296473.964 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6303
2019-03-22T23:14:33.977Z,1553296473.977 [Depth_Keller] Loaded
2019-03-22T23:14:33.977Z,1553296473.977 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-22T23:14:34.073Z,1553296474.073 [NAL9602] Loaded
2019-03-22T23:14:34.073Z,1553296474.073 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-22T23:14:34.089Z,1553296474.089 [Onboard] Loaded
2019-03-22T23:14:34.089Z,1553296474.089 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-22T23:14:34.092Z,1553296474.092 [Radio_Surface] Loaded
2019-03-22T23:14:34.092Z,1553296474.092 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-22T23:14:34.093Z,1553296474.093 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408304E0
2019-03-22T23:14:34.094Z,1553296474.094 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6304
2019-03-22T23:14:34.140Z,1553296474.140 [PNI_TCM] Loaded
2019-03-22T23:14:34.140Z,1553296474.140 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-03-22T23:14:34.198Z,1553296474.198 [Rowe_600LCM] Loaded
2019-03-22T23:14:34.198Z,1553296474.198 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-03-22T23:14:34.199Z,1553296474.199 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408604E0
2019-03-22T23:14:34.200Z,1553296474.200 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6305
2019-03-22T23:14:34.224Z,1553296474.224 [BPC1](INFO): BPC1A: got serial number for stick 1: 023F
2019-03-22T23:14:34.225Z,1553296474.225 [BPC1](INFO): BPC1A: got serial number for stick 2: 02A8
2019-03-22T23:14:34.226Z,1553296474.226 [BPC1](INFO): BPC1A: got serial number for stick 3: 01D7
2019-03-22T23:14:34.227Z,1553296474.227 [BPC1](INFO): BPC1A: got serial number for stick 4: 01EF
2019-03-22T23:14:34.227Z,1553296474.227 [BPC1](INFO): BPC1A: got serial number for stick 5: 0299
2019-03-22T23:14:34.228Z,1553296474.228 [BPC1](INFO): BPC1A: got serial number for stick 6: 0292
2019-03-22T23:14:34.229Z,1553296474.229 [BPC1](INFO): BPC1A: got serial number for stick 7: 0296
2019-03-22T23:14:34.230Z,1553296474.230 [BPC1](INFO): BPC1A: got serial number for stick 8: 0291
2019-03-22T23:14:34.230Z,1553296474.230 [BPC1](INFO): BPC1A: marked stick 8 as reserve.
2019-03-22T23:14:34.231Z,1553296474.231 [BPC1](INFO): BPC1A: got serial number for stick 9: 00BB
2019-03-22T23:14:34.232Z,1553296474.232 [BPC1](INFO): BPC1A: got serial number for stick 10: 025B
2019-03-22T23:14:34.233Z,1553296474.233 [BPC1](INFO): BPC1A: got serial number for stick 11: 024E
2019-03-22T23:14:34.234Z,1553296474.234 [BPC1](INFO): BPC1A: got serial number for stick 12: 029C
2019-03-22T23:14:34.234Z,1553296474.234 [BPC1](INFO): BPC1A: got serial number for stick 13: 0242
2019-03-22T23:14:34.235Z,1553296474.235 [BPC1](INFO): BPC1A: got serial number for stick 14: 01FA
2019-03-22T23:14:34.236Z,1553296474.236 [BPC1](INFO): BPC1A: got serial number for stick 15: 0269
2019-03-22T23:14:34.237Z,1553296474.237 [BPC1](INFO): BPC1A: got serial number for stick 16: 02AD
2019-03-22T23:14:34.237Z,1553296474.237 [BPC1](INFO): BPC1A: marked stick 16 as reserve.
2019-03-22T23:14:34.238Z,1553296474.238 [BPC1](INFO): BPC1A: got serial number for stick 17: 0295
2019-03-22T23:14:34.239Z,1553296474.239 [BPC1](INFO): BPC1A: got serial number for stick 18: 0224
2019-03-22T23:14:34.240Z,1553296474.240 [BPC1](INFO): BPC1A: got serial number for stick 19: 00F6
2019-03-22T23:14:34.240Z,1553296474.240 [BPC1](INFO): BPC1A: got serial number for stick 20: 0294
2019-03-22T23:14:34.241Z,1553296474.241 [BPC1](INFO): BPC1A: got serial number for stick 21: 0271
2019-03-22T23:14:34.242Z,1553296474.242 [BPC1](INFO): BPC1A: got serial number for stick 22: 0206
2019-03-22T23:14:34.243Z,1553296474.243 [BPC1](INFO): BPC1A: got serial number for stick 23: 02B0
2019-03-22T23:14:34.243Z,1553296474.243 [BPC1](INFO): BPC1A: got serial number for stick 24: 020C
2019-03-22T23:14:34.244Z,1553296474.244 [BPC1](INFO): BPC1A: marked stick 24 as reserve.
2019-03-22T23:14:34.244Z,1553296474.244 [BPC1](INFO): BPC1A: got serial number for stick 25: 016F
2019-03-22T23:14:34.245Z,1553296474.245 [BPC1](INFO): BPC1A: got serial number for stick 26: 026A
2019-03-22T23:14:34.246Z,1553296474.246 [BPC1](INFO): BPC1A: got serial number for stick 27: 01C6
2019-03-22T23:14:34.247Z,1553296474.247 [BPC1](INFO): BPC1A: got serial number for stick 28: 0246
2019-03-22T23:14:34.247Z,1553296474.247 [BPC1](INFO): BPC1A: got serial number for stick 29: 02D3
2019-03-22T23:14:34.248Z,1553296474.248 [BPC1](INFO): BPC1A: got serial number for stick 30: 01C0
2019-03-22T23:14:34.249Z,1553296474.249 [BPC1](INFO): BPC1A: got serial number for stick 31: 01F3
2019-03-22T23:14:34.250Z,1553296474.250 [BPC1](INFO): BPC1B: got serial number for stick 32: 0163
2019-03-22T23:14:34.251Z,1553296474.251 [BPC1](INFO): BPC1B: got serial number for stick 33: 01AA
2019-03-22T23:14:34.252Z,1553296474.252 [BPC1](INFO): BPC1B: got serial number for stick 34: 0197
2019-03-22T23:14:34.253Z,1553296474.253 [BPC1](INFO): BPC1B: got serial number for stick 35: 0215
2019-03-22T23:14:34.254Z,1553296474.254 [BPC1](INFO): BPC1B: got serial number for stick 36: 020E
2019-03-22T23:14:34.255Z,1553296474.255 [BPC1](INFO): BPC1B: got serial number for stick 37: 0228
2019-03-22T23:14:34.255Z,1553296474.255 [BPC1](INFO): BPC1B: got serial number for stick 38: 02B4
2019-03-22T23:14:34.256Z,1553296474.256 [BPC1](INFO): BPC1B: got serial number for stick 39: 01AC
2019-03-22T23:14:34.257Z,1553296474.257 [BPC1](INFO): BPC1B: marked stick 39 as reserve.
2019-03-22T23:14:34.257Z,1553296474.257 [BPC1](INFO): BPC1B: got serial number for stick 40: 022A
2019-03-22T23:14:34.258Z,1553296474.258 [BPC1](INFO): BPC1B: got serial number for stick 41: 0232
2019-03-22T23:14:34.259Z,1553296474.259 [BPC1](INFO): BPC1B: got serial number for stick 42: 0219
2019-03-22T23:14:34.260Z,1553296474.260 [BPC1](INFO): BPC1B: got serial number for stick 43: 0229
2019-03-22T23:14:34.261Z,1553296474.261 [BPC1](INFO): BPC1B: got serial number for stick 44: 0253
2019-03-22T23:14:34.262Z,1553296474.262 [BPC1](INFO): BPC1B: got serial number for stick 45: 02A1
2019-03-22T23:14:34.262Z,1553296474.262 [BPC1](INFO): BPC1B: got serial number for stick 46: 028A
2019-03-22T23:14:34.263Z,1553296474.263 [BPC1](INFO): BPC1B: got serial number for stick 47: 019A
2019-03-22T23:14:34.263Z,1553296474.263 [BPC1](INFO): BPC1B: marked stick 47 as reserve.
2019-03-22T23:14:34.264Z,1553296474.264 [BPC1](INFO): BPC1B: got serial number for stick 48: 0202
2019-03-22T23:14:34.265Z,1553296474.265 [BPC1](INFO): BPC1B: got serial number for stick 49: 027C
2019-03-22T23:14:34.266Z,1553296474.266 [BPC1](INFO): BPC1B: got serial number for stick 50: 022F
2019-03-22T23:14:34.267Z,1553296474.267 [BPC1](INFO): BPC1B: got serial number for stick 51: 01FF
2019-03-22T23:14:34.268Z,1553296474.268 [BPC1](INFO): BPC1B: got serial number for stick 52: 0203
2019-03-22T23:14:34.268Z,1553296474.268 [BPC1](INFO): BPC1B: got serial number for stick 53: 02A6
2019-03-22T23:14:34.269Z,1553296474.269 [BPC1](INFO): BPC1B: got serial number for stick 54: 01D4
2019-03-22T23:14:34.270Z,1553296474.270 [BPC1](INFO): BPC1B: got serial number for stick 55: 017F
2019-03-22T23:14:34.270Z,1553296474.270 [BPC1](INFO): BPC1B: marked stick 55 as reserve.
2019-03-22T23:14:34.271Z,1553296474.271 [BPC1](INFO): BPC1B: got serial number for stick 56: 021D
2019-03-22T23:14:34.272Z,1553296474.272 [BPC1](INFO): BPC1B: got serial number for stick 57: 01BF
2019-03-22T23:14:34.273Z,1553296474.273 [BPC1](INFO): BPC1B: got serial number for stick 58: 0216
2019-03-22T23:14:34.273Z,1553296474.273 [BPC1](INFO): BPC1B: got serial number for stick 59: 024A
2019-03-22T23:14:34.274Z,1553296474.274 [BPC1](INFO): BPC1B: got serial number for stick 60: 01DA
2019-03-22T23:14:34.275Z,1553296474.275 [BPC1](INFO): BPC1B: got serial number for stick 61: 01DF
2019-03-22T23:14:34.276Z,1553296474.276 [BPC1](INFO): BPC1B: got serial number for stick 62: 0205
2019-03-22T23:14:35.947Z,1553296475.947 [BPC1] Loaded
2019-03-22T23:14:35.947Z,1553296475.947 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-22T23:14:35.949Z,1553296475.949 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-22T23:14:35.949Z,1553296475.949 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-22T23:14:36.124Z,1553296476.124 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-22T23:14:36.124Z,1553296476.124 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-22T23:14:36.343Z,1553296476.343 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-22T23:14:36.344Z,1553296476.344 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-22T23:14:36.369Z,1553296476.369 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-22T23:14:36.370Z,1553296476.370 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-22T23:14:36.787Z,1553296476.787 [CTD_NeilBrown] Loaded
2019-03-22T23:14:36.787Z,1553296476.787 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-22T23:14:36.788Z,1553296476.788 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409ED4E0
2019-03-22T23:14:36.789Z,1553296476.789 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6306
2019-03-22T23:14:36.836Z,1553296476.836 [WetLabsBB2FL] Loaded
2019-03-22T23:14:36.837Z,1553296476.837 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-03-22T23:14:36.838Z,1553296476.838 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A1D4E0
2019-03-22T23:14:36.838Z,1553296476.838 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6307
2019-03-22T23:14:36.839Z,1553296476.839 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-22T23:14:36.839Z,1553296476.839 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-22T23:14:37.099Z,1553296477.099 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-22T23:14:37.100Z,1553296477.100 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-22T23:14:37.225Z,1553296477.225 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-22T23:14:37.237Z,1553296477.237 [SBIT] Loaded
2019-03-22T23:14:37.237Z,1553296477.237 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-22T23:14:37.238Z,1553296477.238 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-22T23:14:37.249Z,1553296477.249 [IBIT] Loaded
2019-03-22T23:14:37.250Z,1553296477.250 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-22T23:14:37.253Z,1553296477.253 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-22T23:14:37.391Z,1553296477.391 [CBIT] Loaded
2019-03-22T23:14:37.391Z,1553296477.391 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-22T23:14:37.391Z,1553296477.391 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-22T23:14:37.395Z,1553296477.395 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-22T23:14:37.396Z,1553296477.396 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-22T23:14:37.402Z,1553296477.402 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-22T23:14:37.403Z,1553296477.403 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0
2019-03-22T23:14:37.404Z,1553296477.404 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6308
2019-03-22T23:14:37.408Z,1553296477.408 [Supervisor](INFO): Main Thread ID is 5682
2019-03-22T23:14:37.408Z,1553296477.408 [Supervisor](DEBUG): Running supervisor.
2019-03-22T23:14:37.409Z,1553296477.409 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6309
2019-03-22T23:14:37.411Z,1553296477.411 [controlThread ThreadHandler](INFO): Handler Thread ID is 6310
2019-03-22T23:14:37.412Z,1553296477.412 [controlThread](DEBUG): Initializing ControlThread
2019-03-22T23:14:37.413Z,1553296477.413 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-22T23:14:37.414Z,1553296477.414 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-22T23:14:37.415Z,1553296477.415 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-22T23:14:37.415Z,1553296477.415 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-22T23:14:37.416Z,1553296477.416 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-22T23:14:37.417Z,1553296477.417 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-22T23:14:37.417Z,1553296477.417 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-22T23:14:37.417Z,1553296477.417 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-22T23:14:37.418Z,1553296477.418 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-22T23:14:37.418Z,1553296477.418 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-22T23:14:37.419Z,1553296477.419 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T23:14:37.420Z,1553296477.420 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-22T23:14:37.420Z,1553296477.420 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-22T23:14:37.426Z,1553296477.426 [SBIT](INFO): Initialize SBIT Component.
2019-03-22T23:14:37.426Z,1553296477.426 [SBIT](IMPORTANT): git: 2019-03-18-18-g6cf6cc5
2019-03-22T23:14:37.426Z,1553296477.426 [SBIT](INFO): git hash: 6cf6cc5c1826bb1f8f9a0dab23a9d4b1019792a5
2019-03-22T23:14:37.427Z,1553296477.427 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-22T23:14:37.428Z,1553296477.428 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-03-22T23:14:37.429Z,1553296477.429 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-22T23:14:37.430Z,1553296477.430 [IBIT](INFO): Initialize IBIT Component.
2019-03-22T23:14:37.430Z,1553296477.430 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-22T23:14:37.431Z,1553296477.431 [logger ThreadHandler](INFO): Handler Thread ID is 6311
2019-03-22T23:14:37.445Z,1553296477.445 [CBIT](DEBUG): Initialized mux pins.
2019-03-22T23:14:37.445Z,1553296477.445 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-22T23:14:37.449Z,1553296477.449 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6312
2019-03-22T23:14:37.450Z,1553296477.450 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-22T23:14:37.457Z,1553296477.457 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6313
2019-03-22T23:14:37.465Z,1553296477.465 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6314
2019-03-22T23:14:37.466Z,1553296477.466 [Rowe_600LCM](INFO): Initializing
2019-03-22T23:14:37.469Z,1553296477.469 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-22T23:14:37.469Z,1553296477.469 [CBIT](DEBUG): Initializing heartbeat.
2019-03-22T23:14:37.481Z,1553296477.481 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6315
2019-03-22T23:14:37.489Z,1553296477.489 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6316
2019-03-22T23:14:37.494Z,1553296477.494 [WetLabsBB2FL](INFO): Powering down
2019-03-22T23:14:37.521Z,1553296477.521 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6317
2019-03-22T23:14:37.524Z,1553296477.524 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-22T23:14:37.524Z,1553296477.524 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-22T23:14:37.525Z,1553296477.525 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-22T23:14:37.525Z,1553296477.525 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-22T23:14:37.525Z,1553296477.525 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-22T23:14:37.525Z,1553296477.525 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-22T23:14:37.525Z,1553296477.525 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-22T23:14:37.526Z,1553296477.526 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-22T23:14:37.526Z,1553296477.526 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-22T23:14:37.526Z,1553296477.526 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-22T23:14:37.526Z,1553296477.526 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-22T23:14:37.526Z,1553296477.526 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-22T23:14:37.527Z,1553296477.527 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-22T23:14:37.527Z,1553296477.527 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-22T23:14:37.527Z,1553296477.527 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-22T23:14:37.527Z,1553296477.527 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-22T23:14:37.541Z,1553296477.541 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-22T23:14:37.541Z,1553296477.541 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-22T23:14:37.577Z,1553296477.577 [CBIT](DEBUG): Backplane powered.
2019-03-22T23:14:37.578Z,1553296477.578 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-22T23:14:37.588Z,1553296477.588 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-22T23:14:37.611Z,1553296477.611 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-03-22T23:14:37.612Z,1553296477.612 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-22T23:14:37.687Z,1553296477.687 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-22T23:14:37.688Z,1553296477.688 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-22T23:14:37.689Z,1553296477.689 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-22T23:14:37.724Z,1553296477.724 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-22T23:14:37.742Z,1553296477.742 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-22T23:14:37.748Z,1553296477.748 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-22T23:14:37.773Z,1553296477.773 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-03-22T23:14:37.777Z,1553296477.777 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,ElevatorServo,MassServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-22T23:14:37.842Z,1553296477.842 [BPC1](INFO): Start
2019-03-22T23:14:37.861Z,1553296477.861 [Radio_Surface](INFO): Powering up
2019-03-22T23:14:37.938Z,1553296477.938 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:14:37.949Z,1553296477.949 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-22T23:14:37.950Z,1553296477.950 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:14:37.965Z,1553296477.965 [MassServo](DEBUG): Initializing MassServo.
2019-03-22T23:14:37.966Z,1553296477.966 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:14:37.977Z,1553296477.977 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-22T23:14:38.155Z,1553296478.155 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T23:14:38.253Z,1553296478.253 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./auv-shared/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2019-03-22T23:14:38.282Z,1553296478.282 [BPC1](INFO): Starting
2019-03-22T23:14:38.626Z,1553296478.626 [BPC1](INFO): Starting
2019-03-22T23:14:39.014Z,1553296479.014 [BPC1](INFO): Starting
2019-03-22T23:14:39.418Z,1553296479.418 [BPC1](INFO): Starting
2019-03-22T23:14:39.833Z,1553296479.833 [BPC1](INFO): Starting
2019-03-22T23:14:40.224Z,1553296480.224 [BPC1](INFO): Starting
2019-03-22T23:14:40.638Z,1553296480.638 [BPC1](INFO): Starting
2019-03-22T23:14:41.053Z,1553296481.053 [BPC1](INFO): Starting
2019-03-22T23:14:41.456Z,1553296481.456 [BPC1](INFO): Starting
2019-03-22T23:14:41.856Z,1553296481.856 [BPC1](INFO): Starting
2019-03-22T23:14:42.269Z,1553296482.269 [BPC1](INFO): Starting
2019-03-22T23:14:42.664Z,1553296482.664 [BPC1](INFO): Starting
2019-03-22T23:14:43.068Z,1553296483.068 [BPC1](INFO): Starting
2019-03-22T23:14:43.513Z,1553296483.513 [BPC1](INFO): Starting
2019-03-22T23:14:43.889Z,1553296483.889 [BPC1](INFO): Starting
2019-03-22T23:14:44.280Z,1553296484.280 [BPC1](INFO): Starting
2019-03-22T23:14:44.684Z,1553296484.684 [BPC1](INFO): Starting
2019-03-22T23:14:45.103Z,1553296485.103 [BPC1](INFO): Starting
2019-03-22T23:14:45.501Z,1553296485.501 [BPC1](INFO): Starting
2019-03-22T23:14:45.896Z,1553296485.896 [BPC1](INFO): Starting
2019-03-22T23:14:46.316Z,1553296486.316 [BPC1](INFO): Starting
2019-03-22T23:14:46.707Z,1553296486.707 [BPC1](INFO): Starting
2019-03-22T23:14:47.124Z,1553296487.124 [BPC1](INFO): Starting
2019-03-22T23:14:47.531Z,1553296487.531 [BPC1](INFO): Starting
2019-03-22T23:14:47.927Z,1553296487.927 [BPC1](INFO): Starting
2019-03-22T23:14:48.324Z,1553296488.324 [BPC1](INFO): Starting
2019-03-22T23:14:48.732Z,1553296488.732 [BPC1](INFO): Starting
2019-03-22T23:14:49.133Z,1553296489.133 [BPC1](INFO): Starting
2019-03-22T23:14:49.537Z,1553296489.537 [BPC1](INFO): Starting
2019-03-22T23:14:49.937Z,1553296489.937 [BPC1](INFO): Starting
2019-03-22T23:14:50.345Z,1553296490.345 [BPC1](INFO): Starting
2019-03-22T23:14:50.744Z,1553296490.744 [BPC1](INFO): Starting
2019-03-22T23:14:51.153Z,1553296491.153 [BPC1](INFO): Starting
2019-03-22T23:14:51.558Z,1553296491.558 [BPC1](INFO): Starting
2019-03-22T23:14:51.956Z,1553296491.956 [BPC1](INFO): Starting
2019-03-22T23:14:52.360Z,1553296492.360 [BPC1](INFO): Starting
2019-03-22T23:14:52.773Z,1553296492.773 [BPC1](INFO): Starting
2019-03-22T23:14:53.169Z,1553296493.169 [BPC1](INFO): Starting
2019-03-22T23:14:53.573Z,1553296493.573 [BPC1](INFO): Starting
2019-03-22T23:14:53.976Z,1553296493.976 [BPC1](INFO): Starting
2019-03-22T23:14:54.380Z,1553296494.380 [BPC1](INFO): Starting
2019-03-22T23:14:54.784Z,1553296494.784 [BPC1](INFO): Starting
2019-03-22T23:14:55.189Z,1553296495.189 [BPC1](INFO): Starting
2019-03-22T23:14:55.593Z,1553296495.593 [BPC1](INFO): Starting
2019-03-22T23:14:55.997Z,1553296495.997 [BPC1](INFO): Starting
2019-03-22T23:14:56.400Z,1553296496.400 [BPC1](INFO): Starting
2019-03-22T23:14:56.805Z,1553296496.805 [BPC1](INFO): Starting
2019-03-22T23:14:57.212Z,1553296497.212 [BPC1](INFO): Starting
2019-03-22T23:14:57.616Z,1553296497.616 [BPC1](INFO): Starting
2019-03-22T23:14:58.016Z,1553296498.016 [BPC1](INFO): Starting
2019-03-22T23:14:58.421Z,1553296498.421 [BPC1](INFO): Starting
2019-03-22T23:14:58.836Z,1553296498.836 [BPC1](INFO): Starting
2019-03-22T23:14:59.229Z,1553296499.229 [BPC1](INFO): Starting
2019-03-22T23:14:59.632Z,1553296499.632 [BPC1](INFO): Starting
2019-03-22T23:15:00.036Z,1553296500.036 [BPC1](INFO): Starting
2019-03-22T23:15:00.441Z,1553296500.441 [BPC1](INFO): Starting
2019-03-22T23:15:00.844Z,1553296500.844 [BPC1](INFO): Starting
2019-03-22T23:15:01.246Z,1553296501.246 [BPC1](INFO): Starting
2019-03-22T23:15:01.653Z,1553296501.653 [BPC1](INFO): Starting
2019-03-22T23:15:02.057Z,1553296502.057 [BPC1](INFO): Starting
2019-03-22T23:15:02.465Z,1553296502.465 [BPC1](INFO): Starting
2019-03-22T23:15:02.866Z,1553296502.866 [BPC1](INFO): Starting
2019-03-22T23:15:03.275Z,1553296503.275 [BPC1](INFO): Starting
2019-03-22T23:15:03.672Z,1553296503.672 [BPC1](INFO): Starting
2019-03-22T23:15:04.076Z,1553296504.076 [BPC1](INFO): Starting
2019-03-22T23:15:04.458Z,1553296504.458 [NAL9602](INFO): Powering up NAL9602
2019-03-22T23:15:04.481Z,1553296504.481 [BPC1](INFO): Starting
2019-03-22T23:15:04.884Z,1553296504.884 [BPC1](INFO): Starting
2019-03-22T23:15:05.288Z,1553296505.288 [BPC1](INFO): Starting
2019-03-22T23:15:05.694Z,1553296505.694 [BPC1](INFO): Starting
2019-03-22T23:15:06.093Z,1553296506.093 [BPC1](INFO): Starting
2019-03-22T23:15:06.111Z,1553296506.111 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-22T23:15:06.115Z,1553296506.115 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-22T23:15:06.505Z,1553296506.505 [BPC1](INFO): Starting
2019-03-22T23:15:06.920Z,1553296506.920 [BPC1](INFO): Starting
2019-03-22T23:15:07.308Z,1553296507.308 [BPC1](INFO): Starting
2019-03-22T23:15:07.713Z,1553296507.713 [BPC1](INFO): Starting
2019-03-22T23:15:08.126Z,1553296508.126 [BPC1](INFO): Starting
2019-03-22T23:15:08.629Z,1553296508.629 [BPC1](INFO): Starting
2019-03-22T23:15:09.018Z,1553296509.018 [BPC1](INFO): Starting
2019-03-22T23:15:09.430Z,1553296509.430 [BPC1](INFO): Starting
2019-03-22T23:15:09.831Z,1553296509.831 [BPC1](INFO): Starting
2019-03-22T23:15:10.250Z,1553296510.250 [BPC1](INFO): Starting
2019-03-22T23:15:10.641Z,1553296510.641 [BPC1](INFO): Starting
2019-03-22T23:15:11.037Z,1553296511.037 [BPC1](INFO): Starting
2019-03-22T23:15:11.454Z,1553296511.454 [BPC1](INFO): Starting
2019-03-22T23:15:11.845Z,1553296511.845 [BPC1](INFO): Starting
2019-03-22T23:15:12.250Z,1553296512.250 [BPC1](INFO): Starting
2019-03-22T23:15:12.670Z,1553296512.670 [BPC1](INFO): Starting
2019-03-22T23:15:13.062Z,1553296513.062 [BPC1](INFO): Starting
2019-03-22T23:15:13.469Z,1553296513.469 [BPC1](INFO): Starting
2019-03-22T23:15:13.901Z,1553296513.901 [BPC1](INFO): Starting
2019-03-22T23:15:14.269Z,1553296514.269 [BPC1](INFO): Starting
2019-03-22T23:15:14.673Z,1553296514.673 [BPC1](INFO): Starting
2019-03-22T23:15:15.118Z,1553296515.118 [BPC1](INFO): Starting
2019-03-22T23:15:15.466Z,1553296515.466 [NAL9602](INFO): NAL9602 initialized
2019-03-22T23:15:15.505Z,1553296515.505 [BPC1](INFO): Starting
2019-03-22T23:15:15.905Z,1553296515.905 [BPC1](INFO): Starting
2019-03-22T23:15:16.289Z,1553296516.289 [NAL9602](DEBUG): Fix Requested
2019-03-22T23:15:16.320Z,1553296516.320 [BPC1](INFO): Starting
2019-03-22T23:15:16.724Z,1553296516.724 [BPC1](INFO): Starting
2019-03-22T23:15:17.138Z,1553296517.138 [BPC1](INFO): Starting
2019-03-22T23:15:17.245Z,1553296517.245 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.021721
CHAN A1 (24V): 0.001565
CHAN A2 (12V): -0.068018
CHAN A3 (5V): -0.003254
CHAN B0 (3.3V): -0.000922
CHAN B1 (3.15aV): -0.000979
CHAN B2 (3.15bV): -0.000794
CHAN B3 (GND): -0.000006
OPEN: -0.001204
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-22T23:15:17.532Z,1553296517.532 [BPC1](INFO): Starting
2019-03-22T23:15:17.920Z,1553296517.920 [BPC1](INFO): Starting
2019-03-22T23:15:18.315Z,1553296518.315 [BPC1](INFO): Starting
2019-03-22T23:15:18.737Z,1553296518.737 [BPC1](INFO): Starting
2019-03-22T23:15:19.145Z,1553296519.145 [BPC1](INFO): Starting
2019-03-22T23:15:19.176Z,1553296519.176 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T23:15:19.548Z,1553296519.548 [BPC1](INFO): Starting
2019-03-22T23:15:19.934Z,1553296519.934 [BPC1](INFO): Starting
2019-03-22T23:15:20.342Z,1553296520.342 [BPC1](INFO): Starting
2019-03-22T23:15:20.755Z,1553296520.755 [BPC1](INFO): Starting
2019-03-22T23:15:21.164Z,1553296521.164 [BPC1](INFO): Starting
2019-03-22T23:15:21.686Z,1553296521.686 [BPC1](INFO): Starting
2019-03-22T23:15:22.082Z,1553296522.082 [BPC1](INFO): Starting
2019-03-22T23:15:22.482Z,1553296522.482 [BPC1](INFO): Starting
2019-03-22T23:15:22.878Z,1553296522.878 [BPC1](INFO): Starting
2019-03-22T23:15:23.292Z,1553296523.292 [BPC1](INFO): Starting
2019-03-22T23:15:23.697Z,1553296523.697 [BPC1](INFO): Starting
2019-03-22T23:15:24.100Z,1553296524.100 [BPC1](INFO): Starting
2019-03-22T23:15:24.509Z,1553296524.509 [BPC1](INFO): Starting
2019-03-22T23:15:24.898Z,1553296524.898 [BPC1](INFO): Starting
2019-03-22T23:15:25.302Z,1553296525.302 [BPC1](INFO): Starting
2019-03-22T23:15:25.706Z,1553296525.706 [BPC1](INFO): Starting
2019-03-22T23:15:26.110Z,1553296526.110 [BPC1](INFO): Starting
2019-03-22T23:15:26.513Z,1553296526.513 [BPC1](INFO): Starting
2019-03-22T23:15:26.918Z,1553296526.918 [BPC1](INFO): Starting
2019-03-22T23:15:27.334Z,1553296527.334 [BPC1](INFO): Starting
2019-03-22T23:15:27.731Z,1553296527.731 [BPC1](INFO): Starting
2019-03-22T23:15:28.134Z,1553296528.134 [BPC1](INFO): Starting
2019-03-22T23:15:28.561Z,1553296528.561 [BPC1](INFO): Starting
2019-03-22T23:15:28.957Z,1553296528.957 [BPC1](INFO): Starting
2019-03-22T23:15:29.353Z,1553296529.353 [BPC1](INFO): Starting
2019-03-22T23:15:29.792Z,1553296529.792 [BPC1](INFO): Starting
2019-03-22T23:15:30.151Z,1553296530.151 [BPC1](INFO): Starting
2019-03-22T23:15:30.554Z,1553296530.554 [BPC1](INFO): Starting
2019-03-22T23:15:30.963Z,1553296530.963 [BPC1](INFO): Starting
2019-03-22T23:15:31.366Z,1553296531.366 [BPC1](INFO): Starting
2019-03-22T23:15:31.766Z,1553296531.766 [BPC1](INFO): Starting
2019-03-22T23:15:32.170Z,1553296532.170 [BPC1](INFO): Starting
2019-03-22T23:15:32.573Z,1553296532.573 [BPC1](INFO): Starting
2019-03-22T23:15:32.979Z,1553296532.979 [BPC1](INFO): Starting
2019-03-22T23:15:33.382Z,1553296533.382 [BPC1](INFO): Starting
2019-03-22T23:15:33.796Z,1553296533.796 [BPC1](INFO): Starting
2019-03-22T23:15:34.201Z,1553296534.201 [BPC1](INFO): Starting
2019-03-22T23:15:34.604Z,1553296534.604 [BPC1](INFO): Starting
2019-03-22T23:15:35.013Z,1553296535.013 [BPC1](INFO): Starting
2019-03-22T23:15:35.402Z,1553296535.402 [BPC1](INFO): Starting
2019-03-22T23:15:35.806Z,1553296535.806 [BPC1](INFO): Starting
2019-03-22T23:15:36.209Z,1553296536.209 [BPC1](INFO): Starting
2019-03-22T23:15:36.614Z,1553296536.614 [BPC1](INFO): Starting
2019-03-22T23:15:37.017Z,1553296537.017 [BPC1](INFO): Starting
2019-03-22T23:15:37.422Z,1553296537.422 [BPC1](INFO): Starting
2019-03-22T23:15:37.826Z,1553296537.826 [BPC1](INFO): Starting
2019-03-22T23:15:38.234Z,1553296538.234 [BPC1](INFO): Starting
2019-03-22T23:15:38.234Z,1553296538.234 [BPC1](INFO): Initialized.
2019-03-22T23:15:39.073Z,1553296539.073 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:15:39.085Z,1553296539.085 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:15:45.575Z,1553296545.575 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T23:15:45.575Z,1553296545.575 [SBIT](FAULT): Control surface position failure.
2019-03-22T23:15:45.915Z,1553296545.915 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:15:46.328Z,1553296546.328 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:15:46.633Z,1553296546.633 [CommandLine](IMPORTANT): got command failComponent
2019-03-22T23:15:46.634Z,1553296546.634 [CommandLine](IMPORTANT): Failed components:
2019-03-22T23:15:46.634Z,1553296546.634 [CommandLine](IMPORTANT): No failed Components.
2019-03-22T23:15:50.866Z,1553296550.866 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:15:51.068Z,1553296551.068 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:15:51.068Z,1553296551.068 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:15:51.069Z,1553296551.069 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:15:51.273Z,1553296551.273 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:15:51.274Z,1553296551.274 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T23:15:51.471Z,1553296551.471 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:15:51.495Z,1553296551.495 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:15:52.213Z,1553296552.213 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:15:52.225Z,1553296552.225 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:15:59.108Z,1553296559.108 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T23:15:59.108Z,1553296559.108 [SBIT](FAULT): Control surface position failure.
2019-03-22T23:15:59.484Z,1553296559.484 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:15:59.484Z,1553296559.484 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:15:59.539Z,1553296559.539 [SBIT](CRITICAL): SBIT FAILED
2019-03-22T23:15:59.647Z,1553296559.647 [CommandLine](IMPORTANT): got command configSet list
2019-03-22T23:15:59.647Z,1553296559.647 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-22T23:15:59.648Z,1553296559.648 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-22T23:15:59.648Z,1553296559.648 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=10 minute;

2019-03-22T23:15:59.648Z,1553296559.648 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool;

2019-03-22T23:15:59.648Z,1553296559.648 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-03-22T23:15:59.648Z,1553296559.648 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-03-22T23:15:59.648Z,1553296559.648 [CommandLine](IMPORTANT): Depth_Keller.offset=-0.13 decibar;

2019-03-22T23:15:59.648Z,1553296559.648 [CommandLine](IMPORTANT): Depth_Keller.scale=45.92 micropascal;

2019-03-22T23:15:59.648Z,1553296559.648 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-03-22T23:15:59.648Z,1553296559.648 [CommandLine](IMPORTANT): PNI_TCM.loadAtStartup=1 bool;

2019-03-22T23:15:59.649Z,1553296559.649 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2019-03-22T23:15:59.649Z,1553296559.649 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2019-03-22T23:15:59.662Z,1553296559.662 [CommandLine](FAULT): Scheduling is paused
2019-03-22T23:15:59.662Z,1553296559.662 [CBIT](INFO): Critical error at 20190322T231559
2019-03-22T23:15:59.663Z,1553296559.663 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-22T23:15:59.954Z,1553296559.954 [MissionManager](IMPORTANT): Started mission Startup
2019-03-22T23:15:59.954Z,1553296559.954 [Startup] Running Loop=1
2019-03-22T23:15:59.954Z,1553296559.954 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-22T23:15:59.954Z,1553296559.954 [Startup:A.GoToSurface] Running Loop=1
2019-03-22T23:15:59.955Z,1553296559.955 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-22T23:15:59.955Z,1553296559.955 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-22T23:15:59.956Z,1553296559.956 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-22T23:15:59.956Z,1553296559.956 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-22T23:15:59.956Z,1553296559.956 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-22T23:15:59.973Z,1553296559.973 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-22T23:15:59.974Z,1553296559.974 [Startup:StartupSatComms] Running Loop=1
2019-03-22T23:15:59.975Z,1553296559.975 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-22T23:15:59.975Z,1553296559.975 [Startup:StartupSatComms:A] Running Loop=1
2019-03-22T23:16:00.355Z,1553296560.355 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-22T23:16:04.326Z,1553296564.326 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:16:04.468Z,1553296564.468 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T23:16:04.468Z,1553296564.468 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T23:16:04.742Z,1553296564.742 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:16:04.894Z,1553296564.894 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:16:04.895Z,1553296564.895 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:16:04.910Z,1553296564.910 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:16:05.148Z,1553296565.148 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:16:05.569Z,1553296565.569 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:16:05.965Z,1553296565.965 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:16:13.217Z,1553296573.217 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:16:13.217Z,1553296573.217 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:16:18.059Z,1553296578.059 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:16:18.205Z,1553296578.205 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:16:18.205Z,1553296578.205 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:16:18.206Z,1553296578.206 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:16:18.363Z,1553296578.363 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:16:18.363Z,1553296578.363 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T23:16:18.502Z,1553296578.502 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:16:18.541Z,1553296578.541 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:16:19.285Z,1553296579.285 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:16:19.297Z,1553296579.297 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:16:26.558Z,1553296586.558 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:16:26.558Z,1553296586.558 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:16:31.406Z,1553296591.406 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:16:31.565Z,1553296591.565 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:16:31.565Z,1553296591.565 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:16:31.566Z,1553296591.566 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:16:31.728Z,1553296591.728 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T23:16:31.728Z,1553296591.728 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T23:16:31.927Z,1553296591.927 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:16:31.974Z,1553296591.974 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:16:32.625Z,1553296592.625 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:16:32.638Z,1553296592.638 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:16:38.197Z,1553296598.197 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T23:16:38.197Z,1553296598.197 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-03-22T23:16:38.197Z,1553296598.197 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T23:16:38.346Z,1553296598.346 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T23:16:38.601Z,1553296598.601 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T23:16:39.886Z,1553296599.886 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:16:40.294Z,1553296600.294 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:16:40.309Z,1553296600.309 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T23:16:40.310Z,1553296600.310 [Rowe_600LCM] No Fault, FailCount= 1
2019-03-22T23:16:40.457Z,1553296600.457 [Rowe_600LCM](INFO): Initializing
2019-03-22T23:16:40.957Z,1553296600.957 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T23:16:41.023Z,1553296601.023 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./auv-shared/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2019-03-22T23:16:44.734Z,1553296604.734 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:16:44.812Z,1553296604.812 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:16:44.812Z,1553296604.812 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:16:44.812Z,1553296604.812 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:16:44.893Z,1553296604.893 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:16:44.893Z,1553296604.893 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T23:16:45.139Z,1553296605.139 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:16:45.147Z,1553296605.147 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:16:45.953Z,1553296605.953 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:16:46.357Z,1553296606.357 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:16:53.228Z,1553296613.228 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:16:53.228Z,1553296613.228 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:16:57.673Z,1553296617.673 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:16:57.736Z,1553296617.736 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T23:16:57.736Z,1553296617.736 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:16:58.076Z,1553296618.076 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:16:58.136Z,1553296618.136 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:16:58.137Z,1553296618.137 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:16:58.143Z,1553296618.143 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:16:58.503Z,1553296618.503 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:16:58.893Z,1553296618.893 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:16:59.285Z,1553296619.285 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:17:00.081Z,1553296620.081 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:17:00.102Z,1553296620.102 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-22T23:15:59.0Z
2019-03-22T23:17:00.103Z,1553296620.103 [Startup:StartupSatComms:A] Stopped
2019-03-22T23:17:00.103Z,1553296620.103 [Startup:StartupSatComms:B] Running Loop=1
2019-03-22T23:17:00.507Z,1553296620.507 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-22T23:17:04.027Z,1553296624.027 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004791
2019-03-22T23:17:04.213Z,1553296624.213 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:17:04.238Z,1553296624.238 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:17:04.239Z,1553296624.239 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:17:04.615Z,1553296624.615 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:17:05.433Z,1553296625.433 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:17:06.629Z,1553296626.629 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:17:11.478Z,1553296631.478 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:17:11.683Z,1553296631.683 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:17:11.683Z,1553296631.683 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:17:11.925Z,1553296631.925 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:17:12.325Z,1553296632.325 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:17:12.721Z,1553296632.721 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:17:15.428Z,1553296635.428 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190322T225730/Courier0007.lzma
2019-03-22T23:17:16.234Z,1553296636.234 [DataOverHttps](INFO): Moved sent file to Logs/20190322T225730/Courier0007.lzma.bak
2019-03-22T23:17:16.235Z,1553296636.235 [DataOverHttps](INFO): SBD MOMSN=10217331
2019-03-22T23:17:16.330Z,1553296636.330 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:17:16.462Z,1553296636.462 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T23:17:16.463Z,1553296636.463 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:17:16.752Z,1553296636.752 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:17:17.557Z,1553296637.557 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:17:24.012Z,1553296644.012 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:17:28.046Z,1553296648.046 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:17:28.219Z,1553296648.219 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:17:28.220Z,1553296648.220 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:17:28.486Z,1553296648.486 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:17:29.277Z,1553296649.277 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:17:34.460Z,1553296654.460 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20190322T231427/Courier0000.lzma
2019-03-22T23:17:35.266Z,1553296655.266 [DataOverHttps](INFO): Moved sent file to Logs/20190322T231427/Courier0000.lzma.bak
2019-03-22T23:17:35.266Z,1553296655.266 [DataOverHttps](INFO): SBD MOMSN=10217333
2019-03-22T23:17:36.526Z,1553296656.526 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:17:37.765Z,1553296657.765 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-22T23:17:37.765Z,1553296657.765 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-22T23:17:37.766Z,1553296657.766 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-22T23:17:37.766Z,1553296657.766 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-22T23:17:37.792Z,1553296657.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-22T23:17:37.792Z,1553296657.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-22T23:17:38.188Z,1553296658.188 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-22T23:17:38.188Z,1553296658.188 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-22T23:17:38.188Z,1553296658.188 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-22T23:17:38.188Z,1553296658.188 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-22T23:17:38.577Z,1553296658.577 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T23:17:40.567Z,1553296660.567 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:17:40.751Z,1553296660.751 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T23:17:40.751Z,1553296660.751 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:17:40.985Z,1553296660.985 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:17:41.797Z,1553296661.797 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:17:48.657Z,1553296668.657 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:17:52.686Z,1553296672.686 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:17:52.866Z,1553296672.866 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:17:52.866Z,1553296672.866 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:17:53.096Z,1553296673.096 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:17:53.913Z,1553296673.913 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:17:54.525Z,1553296674.525 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20190322T225730/Express0008.lzma
2019-03-22T23:17:55.330Z,1553296675.330 [DataOverHttps](INFO): Moved sent file to Logs/20190322T225730/Express0008.lzma.bak
2019-03-22T23:17:55.330Z,1553296675.330 [DataOverHttps](INFO): SBD MOMSN=10217337
2019-03-22T23:18:00.399Z,1553296680.399 [Startup:StartupSatComms:B](INFO): Timed out from 2019-03-22T23:17:00.1Z
2019-03-22T23:18:00.399Z,1553296680.399 [Startup:StartupSatComms:B] Stopped
2019-03-22T23:18:00.399Z,1553296680.399 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-22T23:18:00.399Z,1553296680.399 [Startup:StartupSatComms] Stopped
2019-03-22T23:18:00.400Z,1553296680.400 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-22T23:18:00.400Z,1553296680.400 [Startup](INFO): Completed Startup
2019-03-22T23:18:00.400Z,1553296680.400 [MissionManager](INFO): Startup is completed.
2019-03-22T23:18:00.409Z,1553296680.409 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-22T23:18:00.409Z,1553296680.409 [Startup] Stopped
2019-03-22T23:18:00.409Z,1553296680.409 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-22T23:18:00.409Z,1553296680.409 [Startup:A.GoToSurface] Stopped
2019-03-22T23:18:00.409Z,1553296680.409 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-22T23:18:00.778Z,1553296680.778 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:18:00.800Z,1553296680.800 [MissionManager](IMPORTANT): Started mission Default
2019-03-22T23:18:00.800Z,1553296680.800 [Default] Running Loop=1
2019-03-22T23:18:00.800Z,1553296680.800 [Default](DEBUG): Aggregate::initialize Default
2019-03-22T23:18:00.800Z,1553296680.800 [Default:B.GoToSurface] Running Loop=1
2019-03-22T23:18:00.800Z,1553296680.800 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-22T23:18:00.800Z,1553296680.800 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-22T23:18:00.809Z,1553296680.809 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-22T23:18:00.809Z,1553296680.809 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-22T23:18:00.810Z,1553296680.810 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-22T23:18:00.810Z,1553296680.810 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-22T23:18:00.810Z,1553296680.810 [Default:A.Wait] Running Loop=1
2019-03-22T23:18:00.810Z,1553296680.810 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-22T23:18:05.237Z,1553296685.237 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:18:05.384Z,1553296685.384 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T23:18:05.384Z,1553296685.384 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:18:05.645Z,1553296685.645 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:18:06.445Z,1553296686.445 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:18:13.291Z,1553296693.291 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:18:14.123Z,1553296694.123 [Default:A.Wait](INFO): Done Waiting.
2019-03-22T23:18:14.123Z,1553296694.123 [Default:A.Wait] Stopped
2019-03-22T23:18:14.123Z,1553296694.123 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-22T23:18:14.543Z,1553296694.543 [Default:CheckIn] Running Loop=1
2019-03-22T23:18:14.543Z,1553296694.543 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-22T23:18:14.543Z,1553296694.543 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-22T23:18:14.931Z,1553296694.931 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-22T23:18:15.778Z,1553296695.778 [DataOverHttps](INFO): Sending 928 bytes from file Logs/20190322T231427/Express0001.lzma
2019-03-22T23:18:16.582Z,1553296696.582 [DataOverHttps](INFO): Moved sent file to Logs/20190322T231427/Express0001.lzma.bak
2019-03-22T23:18:16.582Z,1553296696.582 [DataOverHttps](INFO): SBD MOMSN=10217344
2019-03-22T23:18:17.331Z,1553296697.331 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:18:17.454Z,1553296697.454 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:18:17.454Z,1553296697.454 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:18:17.746Z,1553296697.746 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:18:18.561Z,1553296698.561 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:18:25.041Z,1553296705.041 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:18:29.448Z,1553296709.448 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:18:29.585Z,1553296709.585 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T23:18:29.586Z,1553296709.586 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:18:29.867Z,1553296709.867 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:18:30.681Z,1553296710.681 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:18:37.158Z,1553296717.158 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:18:41.137Z,1553296721.137 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T23:18:41.137Z,1553296721.137 [Rowe_600LCM] Communications Fault, FailCount= 2
2019-03-22T23:18:41.137Z,1553296721.137 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T23:18:41.211Z,1553296721.211 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T23:18:41.541Z,1553296721.541 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T23:18:41.604Z,1553296721.604 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:18:41.786Z,1553296721.786 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:18:41.786Z,1553296721.786 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:18:41.997Z,1553296721.997 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:18:42.809Z,1553296722.809 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:18:43.215Z,1553296723.215 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T23:18:43.215Z,1553296723.215 [Rowe_600LCM] No Fault, FailCount= 2
2019-03-22T23:18:43.397Z,1553296723.397 [Rowe_600LCM](INFO): Initializing
2019-03-22T23:18:43.943Z,1553296723.943 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T23:18:44.100Z,1553296724.100 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./auv-shared/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2019-03-22T23:18:49.246Z,1553296729.246 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:18:53.704Z,1553296733.704 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:18:53.771Z,1553296733.771 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T23:18:53.771Z,1553296733.771 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:18:54.100Z,1553296734.100 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:18:54.917Z,1553296734.917 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:19:01.366Z,1553296741.366 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:19:05.818Z,1553296745.818 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:19:05.869Z,1553296745.869 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:19:05.869Z,1553296745.869 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:19:06.220Z,1553296746.220 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:19:07.037Z,1553296747.037 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:19:13.507Z,1553296753.507 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:19:17.930Z,1553296757.930 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:19:18.052Z,1553296758.052 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T23:19:18.052Z,1553296758.052 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:19:18.346Z,1553296758.346 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:19:19.169Z,1553296759.169 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:19:27.223Z,1553296767.223 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:19:30.467Z,1553296770.467 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:19:30.575Z,1553296770.575 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:19:30.575Z,1553296770.575 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:19:30.865Z,1553296770.865 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3AAF,0A,FFAD,0B,FFB1%3B
$B11,0C,0001,0D,003A,0E,0036,0F,0E49,10,18E5,11,0A54,12,0AD9%4F
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%30
$B11,1A,0031,1B,458F,1C,0163%4A
$B12,02,000A,01,02A8,03,0001,08,0B96,09,3A57,0A,0000,0B,0002%47
$B12,0C,0001,0D,0037,0E,0033,0F,0D7B,10,189B,11,FFFF,12,FFFF%31
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%33
$B12,1A,0031,1B,458F,1C,01AA%4C
$B15,02,000A,01,02A8,03,0001,08,0B98,09,3AA9,0A,FF99,0B,FF9B%4D
$B15,0C,0001,0D,003C,0E,003A,0F,0F25,10,196E,11,08BD,12,08FF%4F
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%39
$B15,1A,0031,1B,4B19,1C,020E%3C
$B16,02,000A,01,02A8,03,0001,08,0B96,09,3AB8,0A,0000,0B,0000%39
$B16,0C,0001,0D,003C,0E,003A,0F,0F66,10,19D3,11,FFFF,12,FFFF%49
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3A
$B16,1A,0031,1B,4B19,1C,0228%40
$B17,02,000A,01,02A8,03,0001,08,0B94,09,3ABA,0A,0000,0B,0000%43
$B17,0C,0001,0D,003C,0E,003B,0F,0F69,10,19D6,11,FFFF,12,FFFF%41
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3B
$B17,1A,0031,1B,4B19,1C,02B4%3D
$B18,02,C0E0,01,02A8,03,0001,08,0B92,09,3B81,0A,0000,0B,0003%37
$B18,0C,0001,0D,003E,0E,0039,0F,0F17,10,1880,11,FFFF,12,FFFF%41
$B18,13,BC34,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%3F
$B18,1A,0031,1B,458F,1C,01AC%44
$B17,02,000A,01,02A8,03,0001,08,0B96,09,3AAD,0A,0000,0B,0000%47
$B17,0C,0001,0D,003A,0E,0036,0F,0E19,10,18AA,11,FFFF,12,FFFF%40
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3B
$B17,1A,0031,1B,4B19,1C,0205%4E
$B18,02,000A,01,02A8,03,0001,08,0B8F,09,3B80,0A,0000,0B,0000%37
$B18,0C,0001,0D,003E,0E,0039,0F,0F07,10,1876,11,FFFF,12,FFFF%49
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%39
$B18,1A,0031,1B,458F,1C,017F%37

2019-03-22T23:19:31.689Z,1553296771.689 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:19:38.943Z,1553296778.943 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:19:42.174Z,1553296782.174 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:19:42.276Z,1553296782.276 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T23:19:42.281Z,1553296782.281 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:19:42.597Z,1553296782.597 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3AAE,0A,FFAD,0B,FFB0%39
$B11,0C,0001,0D,003A,0E,0036,0F,0E49,10,18E5,11,0A54,12,0AB7%47
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%30
$B11,1A,0031,1B,458F,1C,0163%4A
$B12,02,000A,01,02A8,03,0001,08,0B96,09,3A57,0A,0000,0B,0001%44
$B12,0C,0001,0D,0037,0E,0033,0F,0D7B,10,189B,11,FFFF,12,FFFF%31
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%33
$B12,1A,0031,1B,458F,1C,01AA%4C
$B15,02,000A,01,02A8,03,0001,08,0B98,09,3AA8,0A,FF98,0B,FF9A%4E
$B15,0C,0001,0D,003C,0E,0039,0F,0F24,10,196E,11,08BC,12,08E8%4C
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%39
$B15,1A,0031,1B,4B19,1C,020E%3C
$B16,02,000A,01,02A8,03,0001,08,0B96,09,3AB9,0A,0000,0B,0000%38
$B16,0C,0001,0D,003C,0E,003A,0F,0F66,10,19D3,11,FFFF,12,FFFF%49
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3A
$B16,1A,0031,1B,4B19,1C,0228%40
$B17,02,000A,01,02A8,03,0001,08,0B94,09,3ABA,0A,0000,0B,0000%43
$B17,0C,0001,0D,003C,0E,003B,0F,0F69,10,19D6,11,FFFF,12,FFFF%41
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3B
$B17,1A,0031,1B,4B19,1C,02B4%3D
$B18,02,C0E0,01,02A8,03,0001,08,0B93,09,3B81,0A,0000,0B,0002%37
$B18,0C,0001,0D,003E,0E,0039,0F,0F17,10,1880,11,FFFF,12,FFFF%41
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%39
$B18,1A,0031,1B,458F,1C,01AC%44
$B17,02,000A,01,02A8,03,0001,08,0B96,09,3AAD,0A,0000,0B,0000%47
$B17,0C,0001,0D,003A,0E,0036,0F,0E19,10,18AA,11,FFFF,12,FFFF%40
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3B
$B17,1A,0031,1B,4B19,1C,0205%4E
$B18,02,000A,01,02A8,03,0001,08,0B8F,09,3B80,0A,0000,0B,0000%37
$B18,0C,0001,0D,003E,0E,0039,0F,0F07,10,1876,11,FFFF,12,FFFF%49
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%39
$B18,1A,0031,1B,458F,1C,017F%37

2019-03-22T23:19:43.801Z,1553296783.801 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:19:46.227Z,1553296786.227 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:19:47.254Z,1553296787.254 [CommandLine](IMPORTANT): got command restart application
2019-03-22T23:19:48.257Z,1553296788.257 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:19:48.258Z,1553296788.258 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:48.401Z,1553296788.401 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-22T23:19:48.401Z,1553296788.401 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:48.402Z,1553296788.402 [CommandLine](INFO): Join timeout helper Thread ID is 6363
2019-03-22T23:19:48.409Z,1553296788.409 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-22T23:19:48.409Z,1553296788.409 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:48.409Z,1553296788.409 [NavChartDb](INFO): Join timeout helper Thread ID is 6364
2019-03-22T23:19:48.777Z,1553296788.777 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:19:48.778Z,1553296788.778 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:48.797Z,1553296788.797 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-03-22T23:19:48.797Z,1553296788.797 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:48.797Z,1553296788.797 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6365
2019-03-22T23:19:49.093Z,1553296789.093 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:19:49.094Z,1553296789.094 [WetLabsBB2FL](INFO): Powering down
2019-03-22T23:19:49.094Z,1553296789.094 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:49.113Z,1553296789.113 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-22T23:19:49.113Z,1553296789.113 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:49.113Z,1553296789.113 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6366
2019-03-22T23:19:49.181Z,1553296789.181 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:19:49.181Z,1553296789.181 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:49.189Z,1553296789.189 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-03-22T23:19:49.189Z,1553296789.189 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:49.189Z,1553296789.189 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6367
2019-03-22T23:19:49.821Z,1553296789.821 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:19:49.821Z,1553296789.821 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T23:19:50.045Z,1553296790.045 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.062Z,1553296790.062 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-22T23:19:50.062Z,1553296790.062 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.063Z,1553296790.063 [Radio_Surface](INFO): Join timeout helper Thread ID is 6369
2019-03-22T23:19:50.245Z,1553296790.245 [Radio_Surface](INFO): Powering down
2019-03-22T23:19:50.246Z,1553296790.246 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:19:50.246Z,1553296790.246 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.262Z,1553296790.262 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-22T23:19:50.262Z,1553296790.262 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.262Z,1553296790.262 [DataOverHttps](INFO): Join timeout helper Thread ID is 6370
2019-03-22T23:19:50.525Z,1553296790.525 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:19:50.525Z,1553296790.525 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.542Z,1553296790.542 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-22T23:19:50.542Z,1553296790.542 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.542Z,1553296790.542 [logger](INFO): Join timeout helper Thread ID is 6371
2019-03-22T23:19:50.577Z,1553296790.577 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:19:50.577Z,1553296790.577 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.582Z,1553296790.582 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-22T23:19:50.582Z,1553296790.582 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.582Z,1553296790.582 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-22T23:19:50.582Z,1553296790.582 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.582Z,1553296790.582 [controlThread](INFO): Join timeout helper Thread ID is 6372
2019-03-22T23:19:50.621Z,1553296790.621 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:19:50.621Z,1553296790.621 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-22T23:19:50.622Z,1553296790.622 [NAL9602](INFO): Powering down
2019-03-22T23:19:50.623Z,1553296790.623 [PNI_TCM](INFO): Powering down
2019-03-22T23:19:50.709Z,1553296790.709 [BPC1](INFO): uninitialize
2019-03-22T23:19:50.710Z,1553296790.710 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-22T23:19:50.711Z,1553296790.711 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-22T23:19:50.712Z,1553296790.712 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-22T23:19:50.712Z,1553296790.712 [MissionManager](INFO): Uninitializing Mission Default
2019-03-22T23:19:50.712Z,1553296790.712 [Default] Stopped
2019-03-22T23:19:50.712Z,1553296790.712 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-22T23:19:50.712Z,1553296790.712 [Default:B.GoToSurface] Stopped
2019-03-22T23:19:50.712Z,1553296790.712 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-22T23:19:50.713Z,1553296790.713 [Default:CheckIn] Stopped
2019-03-22T23:19:50.713Z,1553296790.713 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-22T23:19:50.713Z,1553296790.713 [Default:CheckIn:Read_GPS] Stopped
2019-03-22T23:19:50.716Z,1553296790.716 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-22T23:19:50.716Z,1553296790.716 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-22T23:19:50.716Z,1553296790.716 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-22T23:19:50.716Z,1553296790.716 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-22T23:19:50.717Z,1553296790.717 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-22T23:19:50.717Z,1553296790.717 [ElevatorServo](INFO): Powering down
2019-03-22T23:19:50.718Z,1553296790.718 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-22T23:19:50.718Z,1553296790.718 [MassServo](INFO): Powering down
2019-03-22T23:19:50.719Z,1553296790.719 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-22T23:19:50.719Z,1553296790.719 [ThrusterServo](INFO): Powering down
2019-03-22T23:19:50.720Z,1553296790.720 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-22T23:19:50.720Z,1553296790.720 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-22T23:19:50.721Z,1553296790.721 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-22T23:19:50.721Z,1553296790.721 [CBIT](DEBUG): Powering off loads.
2019-03-22T23:19:50.733Z,1553296790.733 [CBIT](DEBUG): Disabling WDT.
2019-03-22T23:19:50.745Z,1553296790.745 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-22T23:19:50.745Z,1553296790.745 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.805Z,1553296790.805 [BPC1](INFO): uninitialize
2019-03-22T23:19:50.845Z,1553296790.845 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.851Z,1553296790.851 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.855Z,1553296790.855 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.889Z,1553296790.889 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.891Z,1553296790.891 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.924Z,1553296790.924 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:19:50.985Z,1553296790.985 [logger ThreadHandler](INFO): Thread cancelled.