2019-03-22T22:41:42.846Z,1553294502.846 [Supervisor](DEBUG): Initializing supervisor.
2019-03-22T22:41:42.849Z,1553294502.849 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-22T22:41:42.850Z,1553294502.850 [SyncHandler](INFO): Protected caller Thread ID is 5797
2019-03-22T22:41:42.850Z,1553294502.850 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-22T22:41:42.851Z,1553294502.851 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-22T22:41:42.852Z,1553294502.852 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5798
2019-03-22T22:41:42.855Z,1553294502.855 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-22T22:41:42.867Z,1553294502.867 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-22T22:41:42.867Z,1553294502.867 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-22T22:41:42.868Z,1553294502.868 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5799
2019-03-22T22:41:42.869Z,1553294502.869 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-22T22:41:42.870Z,1553294502.870 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-22T22:41:42.870Z,1553294502.870 [logger ThreadHandler](INFO): Protected caller Thread ID is 5800
2019-03-22T22:41:42.872Z,1553294502.872 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-22T22:41:42.872Z,1553294502.872 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-22T22:41:42.874Z,1553294502.874 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-22T22:41:43.289Z,1553294503.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-22T22:41:43.290Z,1553294503.290 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-22T22:41:43.433Z,1553294503.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-22T22:41:43.434Z,1553294503.434 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-22T22:41:43.515Z,1553294503.515 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-22T22:41:43.614Z,1553294503.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-22T22:41:43.614Z,1553294503.614 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-22T22:41:43.710Z,1553294503.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-22T22:41:43.710Z,1553294503.710 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-22T22:41:44.258Z,1553294504.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-22T22:41:44.259Z,1553294504.259 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-22T22:41:44.362Z,1553294504.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-22T22:41:44.363Z,1553294504.363 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-22T22:41:44.814Z,1553294504.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-22T22:41:44.815Z,1553294504.815 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-22T22:41:45.014Z,1553294505.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-22T22:41:45.015Z,1553294505.015 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-22T22:41:45.316Z,1553294505.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-22T22:41:45.317Z,1553294505.317 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-22T22:41:45.528Z,1553294505.528 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-22T22:41:45.529Z,1553294505.529 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-22T22:41:45.671Z,1553294505.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-22T22:41:45.671Z,1553294505.671 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-22T22:41:46.158Z,1553294506.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-22T22:41:46.158Z,1553294506.158 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-22T22:41:46.490Z,1553294506.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-22T22:41:46.490Z,1553294506.490 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-22T22:41:46.695Z,1553294506.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-22T22:41:46.697Z,1553294506.697 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-03-22T22:41:46.698Z,1553294506.698 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-03-22T22:41:46.872Z,1553294506.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-03-22T22:41:46.955Z,1553294506.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-03-22T22:41:47.070Z,1553294507.070 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-03-22T22:41:47.156Z,1553294507.156 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-03-22T22:41:47.382Z,1553294507.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-22T22:41:47.383Z,1553294507.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-03-22T22:41:47.482Z,1553294507.482 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-03-22T22:41:47.592Z,1553294507.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-03-22T22:41:47.687Z,1553294507.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-03-22T22:41:47.839Z,1553294507.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-03-22T22:41:47.940Z,1553294507.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-03-22T22:41:48.041Z,1553294508.041 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-03-22T22:41:48.041Z,1553294508.041 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-22T22:41:48.052Z,1553294508.052 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-22T22:41:48.065Z,1553294508.065 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-22T22:41:48.065Z,1553294508.065 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-22T22:41:48.129Z,1553294508.129 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-22T22:41:48.238Z,1553294508.238 [VerticalControl] Loaded
2019-03-22T22:41:48.238Z,1553294508.238 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-22T22:41:48.239Z,1553294508.239 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-22T22:41:48.306Z,1553294508.306 [HorizontalControl] Loaded
2019-03-22T22:41:48.306Z,1553294508.306 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-22T22:41:48.306Z,1553294508.306 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-22T22:41:48.312Z,1553294508.312 [SpeedControl] Loaded
2019-03-22T22:41:48.312Z,1553294508.312 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-22T22:41:48.313Z,1553294508.313 [LoopControl](DEBUG): Construct LoopControl.
2019-03-22T22:41:48.313Z,1553294508.313 [LoopControl] Loaded
2019-03-22T22:41:48.314Z,1553294508.314 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-22T22:41:48.314Z,1553294508.314 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-22T22:41:48.314Z,1553294508.314 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-22T22:41:48.426Z,1553294508.426 [ElevatorServo] Loaded
2019-03-22T22:41:48.426Z,1553294508.426 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-22T22:41:48.437Z,1553294508.437 [MassServo] Loaded
2019-03-22T22:41:48.437Z,1553294508.437 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-22T22:41:48.448Z,1553294508.448 [ThrusterServo] Loaded
2019-03-22T22:41:48.449Z,1553294508.449 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-22T22:41:48.449Z,1553294508.449 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-22T22:41:48.449Z,1553294508.449 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-22T22:41:48.488Z,1553294508.488 [DepthRateCalculator] Loaded
2019-03-22T22:41:48.488Z,1553294508.488 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-22T22:41:48.494Z,1553294508.494 [PitchRateCalculator] Loaded
2019-03-22T22:41:48.494Z,1553294508.494 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-22T22:41:48.508Z,1553294508.508 [SpeedCalculator] Loaded
2019-03-22T22:41:48.509Z,1553294508.509 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-22T22:41:48.529Z,1553294508.529 [TempGradientCalculator] Loaded
2019-03-22T22:41:48.529Z,1553294508.529 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-22T22:41:48.534Z,1553294508.534 [YawRateCalculator] Loaded
2019-03-22T22:41:48.534Z,1553294508.534 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-22T22:41:48.562Z,1553294508.562 [ElevatorOffsetCalculator] Loaded
2019-03-22T22:41:48.563Z,1553294508.563 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-22T22:41:48.563Z,1553294508.563 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-22T22:41:48.564Z,1553294508.564 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-22T22:41:48.664Z,1553294508.664 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-22T22:41:48.664Z,1553294508.664 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-22T22:41:48.721Z,1553294508.721 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-22T22:41:48.721Z,1553294508.721 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-22T22:41:48.741Z,1553294508.741 [NavChart] Loaded
2019-03-22T22:41:48.742Z,1553294508.742 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-22T22:41:48.746Z,1553294508.746 [UniversalFixResidualReporter] Loaded
2019-03-22T22:41:48.746Z,1553294508.746 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-22T22:41:48.746Z,1553294508.746 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-22T22:41:48.747Z,1553294508.747 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-22T22:41:49.048Z,1553294509.048 [DataOverHttps] Loaded
2019-03-22T22:41:49.049Z,1553294509.049 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-22T22:41:49.050Z,1553294509.050 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408004E0
2019-03-22T22:41:49.050Z,1553294509.050 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5879
2019-03-22T22:41:49.063Z,1553294509.063 [Depth_Keller] Loaded
2019-03-22T22:41:49.063Z,1553294509.063 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-22T22:41:49.157Z,1553294509.157 [NAL9602] Loaded
2019-03-22T22:41:49.157Z,1553294509.157 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-22T22:41:49.172Z,1553294509.172 [Onboard] Loaded
2019-03-22T22:41:49.172Z,1553294509.172 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-22T22:41:49.176Z,1553294509.176 [Radio_Surface] Loaded
2019-03-22T22:41:49.176Z,1553294509.176 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-22T22:41:49.177Z,1553294509.177 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408304E0
2019-03-22T22:41:49.177Z,1553294509.177 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5880
2019-03-22T22:41:49.223Z,1553294509.223 [PNI_TCM] Loaded
2019-03-22T22:41:49.223Z,1553294509.223 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-03-22T22:41:49.280Z,1553294509.280 [Rowe_600LCM] Loaded
2019-03-22T22:41:49.280Z,1553294509.280 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-03-22T22:41:49.281Z,1553294509.281 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408604E0
2019-03-22T22:41:49.282Z,1553294509.282 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 5881
2019-03-22T22:41:49.305Z,1553294509.305 [BPC1](INFO): BPC1A: got serial number for stick 1: 023F
2019-03-22T22:41:49.306Z,1553294509.306 [BPC1](INFO): BPC1A: got serial number for stick 2: 02A8
2019-03-22T22:41:49.307Z,1553294509.307 [BPC1](INFO): BPC1A: got serial number for stick 3: 01D7
2019-03-22T22:41:49.308Z,1553294509.308 [BPC1](INFO): BPC1A: got serial number for stick 4: 01EF
2019-03-22T22:41:49.309Z,1553294509.309 [BPC1](INFO): BPC1A: got serial number for stick 5: 0299
2019-03-22T22:41:49.310Z,1553294509.310 [BPC1](INFO): BPC1A: got serial number for stick 6: 0292
2019-03-22T22:41:49.310Z,1553294509.310 [BPC1](INFO): BPC1A: got serial number for stick 7: 0296
2019-03-22T22:41:49.311Z,1553294509.311 [BPC1](INFO): BPC1A: got serial number for stick 8: 0291
2019-03-22T22:41:49.311Z,1553294509.311 [BPC1](INFO): BPC1A: marked stick 8 as reserve.
2019-03-22T22:41:49.312Z,1553294509.312 [BPC1](INFO): BPC1A: got serial number for stick 9: 00BB
2019-03-22T22:41:49.313Z,1553294509.313 [BPC1](INFO): BPC1A: got serial number for stick 10: 025B
2019-03-22T22:41:49.314Z,1553294509.314 [BPC1](INFO): BPC1A: got serial number for stick 11: 024E
2019-03-22T22:41:49.315Z,1553294509.315 [BPC1](INFO): BPC1A: got serial number for stick 12: 029C
2019-03-22T22:41:49.315Z,1553294509.315 [BPC1](INFO): BPC1A: got serial number for stick 13: 0242
2019-03-22T22:41:49.316Z,1553294509.316 [BPC1](INFO): BPC1A: got serial number for stick 14: 01FA
2019-03-22T22:41:49.317Z,1553294509.317 [BPC1](INFO): BPC1A: got serial number for stick 15: 0269
2019-03-22T22:41:49.317Z,1553294509.317 [BPC1](INFO): BPC1A: got serial number for stick 16: 02AD
2019-03-22T22:41:49.318Z,1553294509.318 [BPC1](INFO): BPC1A: marked stick 16 as reserve.
2019-03-22T22:41:49.319Z,1553294509.319 [BPC1](INFO): BPC1A: got serial number for stick 17: 0295
2019-03-22T22:41:49.320Z,1553294509.320 [BPC1](INFO): BPC1A: got serial number for stick 18: 0224
2019-03-22T22:41:49.320Z,1553294509.320 [BPC1](INFO): BPC1A: got serial number for stick 19: 00F6
2019-03-22T22:41:49.321Z,1553294509.321 [BPC1](INFO): BPC1A: got serial number for stick 20: 0294
2019-03-22T22:41:49.322Z,1553294509.322 [BPC1](INFO): BPC1A: got serial number for stick 21: 0271
2019-03-22T22:41:49.323Z,1553294509.323 [BPC1](INFO): BPC1A: got serial number for stick 22: 0206
2019-03-22T22:41:49.323Z,1553294509.323 [BPC1](INFO): BPC1A: got serial number for stick 23: 02B0
2019-03-22T22:41:49.324Z,1553294509.324 [BPC1](INFO): BPC1A: got serial number for stick 24: 020C
2019-03-22T22:41:49.324Z,1553294509.324 [BPC1](INFO): BPC1A: marked stick 24 as reserve.
2019-03-22T22:41:49.325Z,1553294509.325 [BPC1](INFO): BPC1A: got serial number for stick 25: 016F
2019-03-22T22:41:49.326Z,1553294509.326 [BPC1](INFO): BPC1A: got serial number for stick 26: 026A
2019-03-22T22:41:49.326Z,1553294509.326 [BPC1](INFO): BPC1A: got serial number for stick 27: 01C6
2019-03-22T22:41:49.327Z,1553294509.327 [BPC1](INFO): BPC1A: got serial number for stick 28: 0246
2019-03-22T22:41:49.328Z,1553294509.328 [BPC1](INFO): BPC1A: got serial number for stick 29: 02D3
2019-03-22T22:41:49.329Z,1553294509.329 [BPC1](INFO): BPC1A: got serial number for stick 30: 01C0
2019-03-22T22:41:49.329Z,1553294509.329 [BPC1](INFO): BPC1A: got serial number for stick 31: 01F3
2019-03-22T22:41:49.331Z,1553294509.331 [BPC1](INFO): BPC1B: got serial number for stick 32: 0163
2019-03-22T22:41:49.332Z,1553294509.332 [BPC1](INFO): BPC1B: got serial number for stick 33: 01AA
2019-03-22T22:41:49.332Z,1553294509.332 [BPC1](INFO): BPC1B: got serial number for stick 34: 0197
2019-03-22T22:41:49.333Z,1553294509.333 [BPC1](INFO): BPC1B: got serial number for stick 35: 0215
2019-03-22T22:41:49.334Z,1553294509.334 [BPC1](INFO): BPC1B: got serial number for stick 36: 020E
2019-03-22T22:41:49.335Z,1553294509.335 [BPC1](INFO): BPC1B: got serial number for stick 37: 0228
2019-03-22T22:41:49.336Z,1553294509.336 [BPC1](INFO): BPC1B: got serial number for stick 38: 02B4
2019-03-22T22:41:49.336Z,1553294509.336 [BPC1](INFO): BPC1B: got serial number for stick 39: 01AC
2019-03-22T22:41:49.337Z,1553294509.337 [BPC1](INFO): BPC1B: marked stick 39 as reserve.
2019-03-22T22:41:49.338Z,1553294509.338 [BPC1](INFO): BPC1B: got serial number for stick 40: 022A
2019-03-22T22:41:49.339Z,1553294509.339 [BPC1](INFO): BPC1B: got serial number for stick 41: 0232
2019-03-22T22:41:49.339Z,1553294509.339 [BPC1](INFO): BPC1B: got serial number for stick 42: 0219
2019-03-22T22:41:49.340Z,1553294509.340 [BPC1](INFO): BPC1B: got serial number for stick 43: 0229
2019-03-22T22:41:49.341Z,1553294509.341 [BPC1](INFO): BPC1B: got serial number for stick 44: 0253
2019-03-22T22:41:49.342Z,1553294509.342 [BPC1](INFO): BPC1B: got serial number for stick 45: 02A1
2019-03-22T22:41:49.342Z,1553294509.342 [BPC1](INFO): BPC1B: got serial number for stick 46: 028A
2019-03-22T22:41:49.343Z,1553294509.343 [BPC1](INFO): BPC1B: got serial number for stick 47: 019A
2019-03-22T22:41:49.343Z,1553294509.343 [BPC1](INFO): BPC1B: marked stick 47 as reserve.
2019-03-22T22:41:49.344Z,1553294509.344 [BPC1](INFO): BPC1B: got serial number for stick 48: 0202
2019-03-22T22:41:49.345Z,1553294509.345 [BPC1](INFO): BPC1B: got serial number for stick 49: 027C
2019-03-22T22:41:49.346Z,1553294509.346 [BPC1](INFO): BPC1B: got serial number for stick 50: 022F
2019-03-22T22:41:49.347Z,1553294509.347 [BPC1](INFO): BPC1B: got serial number for stick 51: 01FF
2019-03-22T22:41:49.348Z,1553294509.348 [BPC1](INFO): BPC1B: got serial number for stick 52: 0203
2019-03-22T22:41:49.348Z,1553294509.348 [BPC1](INFO): BPC1B: got serial number for stick 53: 02A6
2019-03-22T22:41:49.349Z,1553294509.349 [BPC1](INFO): BPC1B: got serial number for stick 54: 01D4
2019-03-22T22:41:49.350Z,1553294509.350 [BPC1](INFO): BPC1B: got serial number for stick 55: 017F
2019-03-22T22:41:49.350Z,1553294509.350 [BPC1](INFO): BPC1B: marked stick 55 as reserve.
2019-03-22T22:41:49.351Z,1553294509.351 [BPC1](INFO): BPC1B: got serial number for stick 56: 021D
2019-03-22T22:41:49.352Z,1553294509.352 [BPC1](INFO): BPC1B: got serial number for stick 57: 01BF
2019-03-22T22:41:49.352Z,1553294509.352 [BPC1](INFO): BPC1B: got serial number for stick 58: 0216
2019-03-22T22:41:49.353Z,1553294509.353 [BPC1](INFO): BPC1B: got serial number for stick 59: 024A
2019-03-22T22:41:49.354Z,1553294509.354 [BPC1](INFO): BPC1B: got serial number for stick 60: 01DA
2019-03-22T22:41:49.355Z,1553294509.355 [BPC1](INFO): BPC1B: got serial number for stick 61: 01DF
2019-03-22T22:41:49.355Z,1553294509.355 [BPC1](INFO): BPC1B: got serial number for stick 62: 0205
2019-03-22T22:41:51.231Z,1553294511.231 [BPC1] Loaded
2019-03-22T22:41:51.231Z,1553294511.231 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-22T22:41:51.231Z,1553294511.231 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-22T22:41:51.232Z,1553294511.232 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-22T22:41:51.293Z,1553294511.293 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-22T22:41:51.294Z,1553294511.294 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-22T22:41:51.385Z,1553294511.385 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-22T22:41:51.385Z,1553294511.385 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-22T22:41:51.411Z,1553294511.411 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-22T22:41:51.411Z,1553294511.411 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-22T22:41:51.651Z,1553294511.651 [CTD_NeilBrown] Loaded
2019-03-22T22:41:51.651Z,1553294511.651 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-22T22:41:51.652Z,1553294511.652 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409ED4E0
2019-03-22T22:41:51.653Z,1553294511.653 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5882
2019-03-22T22:41:51.699Z,1553294511.699 [WetLabsBB2FL] Loaded
2019-03-22T22:41:51.699Z,1553294511.699 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-03-22T22:41:51.700Z,1553294511.700 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A1D4E0
2019-03-22T22:41:51.701Z,1553294511.701 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5883
2019-03-22T22:41:51.702Z,1553294511.702 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-22T22:41:51.702Z,1553294511.702 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-22T22:41:51.962Z,1553294511.962 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-22T22:41:51.963Z,1553294511.963 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-22T22:41:52.128Z,1553294512.128 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-22T22:41:52.139Z,1553294512.139 [SBIT] Loaded
2019-03-22T22:41:52.139Z,1553294512.139 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-22T22:41:52.140Z,1553294512.140 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-22T22:41:52.151Z,1553294512.151 [IBIT] Loaded
2019-03-22T22:41:52.152Z,1553294512.152 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-22T22:41:52.155Z,1553294512.155 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-22T22:41:52.290Z,1553294512.290 [CBIT] Loaded
2019-03-22T22:41:52.290Z,1553294512.290 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-22T22:41:52.291Z,1553294512.291 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-22T22:41:52.294Z,1553294512.294 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-22T22:41:52.295Z,1553294512.295 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-22T22:41:52.302Z,1553294512.302 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-22T22:41:52.303Z,1553294512.303 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0
2019-03-22T22:41:52.303Z,1553294512.303 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5884
2019-03-22T22:41:52.308Z,1553294512.308 [Supervisor](INFO): Main Thread ID is 5682
2019-03-22T22:41:52.308Z,1553294512.308 [Supervisor](DEBUG): Running supervisor.
2019-03-22T22:41:52.309Z,1553294512.309 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5885
2019-03-22T22:41:52.311Z,1553294512.311 [controlThread ThreadHandler](INFO): Handler Thread ID is 5886
2019-03-22T22:41:52.311Z,1553294512.311 [controlThread](DEBUG): Initializing ControlThread
2019-03-22T22:41:52.312Z,1553294512.312 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-22T22:41:52.314Z,1553294512.314 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-22T22:41:52.315Z,1553294512.315 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-22T22:41:52.315Z,1553294512.315 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-22T22:41:52.316Z,1553294512.316 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-22T22:41:52.316Z,1553294512.316 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-22T22:41:52.317Z,1553294512.317 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-22T22:41:52.317Z,1553294512.317 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-22T22:41:52.317Z,1553294512.317 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-22T22:41:52.318Z,1553294512.318 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-22T22:41:52.319Z,1553294512.319 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T22:41:52.319Z,1553294512.319 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-22T22:41:52.319Z,1553294512.319 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-22T22:41:52.322Z,1553294512.322 [SBIT](INFO): Initialize SBIT Component.
2019-03-22T22:41:52.322Z,1553294512.322 [SBIT](IMPORTANT): git: 2019-03-18-18-g6cf6cc5
2019-03-22T22:41:52.322Z,1553294512.322 [SBIT](INFO): git hash: 6cf6cc5c1826bb1f8f9a0dab23a9d4b1019792a5
2019-03-22T22:41:52.323Z,1553294512.323 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-22T22:41:52.324Z,1553294512.324 [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-22T22:41:52.325Z,1553294512.325 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-22T22:41:52.326Z,1553294512.326 [IBIT](INFO): Initialize IBIT Component.
2019-03-22T22:41:52.326Z,1553294512.326 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-22T22:41:52.327Z,1553294512.327 [logger ThreadHandler](INFO): Handler Thread ID is 5887
2019-03-22T22:41:52.341Z,1553294512.341 [CBIT](DEBUG): Initialized mux pins.
2019-03-22T22:41:52.341Z,1553294512.341 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-22T22:41:52.345Z,1553294512.345 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5888
2019-03-22T22:41:52.346Z,1553294512.346 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-22T22:41:52.353Z,1553294512.353 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5889
2019-03-22T22:41:52.361Z,1553294512.361 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 5890
2019-03-22T22:41:52.362Z,1553294512.362 [Rowe_600LCM](INFO): Initializing
2019-03-22T22:41:52.365Z,1553294512.365 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-22T22:41:52.365Z,1553294512.365 [CBIT](DEBUG): Initializing heartbeat.
2019-03-22T22:41:52.429Z,1553294512.429 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5891
2019-03-22T22:41:52.437Z,1553294512.437 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-22T22:41:52.437Z,1553294512.437 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-22T22:41:52.516Z,1553294512.516 [CBIT](DEBUG): Backplane powered.
2019-03-22T22:41:52.517Z,1553294512.517 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-22T22:41:52.521Z,1553294512.521 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5892
2019-03-22T22:41:52.522Z,1553294512.522 [WetLabsBB2FL](INFO): Powering down
2019-03-22T22:41:52.525Z,1553294512.525 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5893
2019-03-22T22:41:52.528Z,1553294512.528 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-22T22:41:52.529Z,1553294512.529 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-22T22:41:52.529Z,1553294512.529 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-22T22:41:52.529Z,1553294512.529 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-22T22:41:52.529Z,1553294512.529 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-22T22:41:52.530Z,1553294512.530 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-22T22:41:52.530Z,1553294512.530 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-22T22:41:52.530Z,1553294512.530 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-22T22:41:52.530Z,1553294512.530 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-22T22:41:52.530Z,1553294512.530 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-22T22:41:52.531Z,1553294512.531 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-22T22:41:52.531Z,1553294512.531 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-22T22:41:52.531Z,1553294512.531 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-22T22:41:52.531Z,1553294512.531 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-22T22:41:52.531Z,1553294512.531 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-22T22:41:52.532Z,1553294512.532 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-22T22:41:52.583Z,1553294512.583 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-22T22:41:52.714Z,1553294512.714 [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-22T22:41:52.729Z,1553294512.729 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-22T22:41:52.757Z,1553294512.757 [Radio_Surface](INFO): Powering up
2019-03-22T22:41:52.816Z,1553294512.816 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-22T22:41:52.854Z,1553294512.854 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-22T22:41:52.855Z,1553294512.855 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-22T22:41:52.878Z,1553294512.878 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-22T22:41:52.913Z,1553294512.913 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-22T22:41:52.932Z,1553294512.932 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-22T22:41:52.945Z,1553294512.945 [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-22T22:41:52.976Z,1553294512.976 [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-22T22:41:53.046Z,1553294513.046 [BPC1](INFO): Start
2019-03-22T22:41:53.110Z,1553294513.110 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-22T22:41:53.119Z,1553294513.119 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-22T22:41:53.120Z,1553294513.120 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-22T22:41:53.133Z,1553294513.133 [MassServo](DEBUG): Initializing MassServo.
2019-03-22T22:41:53.134Z,1553294513.134 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-22T22:41:53.145Z,1553294513.145 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-22T22:41:53.502Z,1553294513.502 [BPC1](INFO): Starting
2019-03-22T22:41:53.817Z,1553294513.817 [BPC1](INFO): Starting
2019-03-22T22:41:53.971Z,1553294513.971 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T22:41:54.210Z,1553294514.210 [BPC1](INFO): Starting
2019-03-22T22:41:54.239Z,1553294514.239 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2019-03-22T22:41:54.612Z,1553294514.612 [BPC1](INFO): Starting
2019-03-22T22:41:55.031Z,1553294515.031 [BPC1](INFO): Starting
2019-03-22T22:41:55.420Z,1553294515.420 [BPC1](INFO): Starting
2019-03-22T22:41:55.825Z,1553294515.825 [BPC1](INFO): Starting
2019-03-22T22:41:56.228Z,1553294516.228 [BPC1](INFO): Starting
2019-03-22T22:41:56.633Z,1553294516.633 [BPC1](INFO): Starting
2019-03-22T22:41:57.036Z,1553294517.036 [BPC1](INFO): Starting
2019-03-22T22:41:57.445Z,1553294517.445 [BPC1](INFO): Starting
2019-03-22T22:41:57.844Z,1553294517.844 [BPC1](INFO): Starting
2019-03-22T22:41:58.249Z,1553294518.249 [BPC1](INFO): Starting
2019-03-22T22:41:58.652Z,1553294518.652 [BPC1](INFO): Starting
2019-03-22T22:41:59.056Z,1553294519.056 [BPC1](INFO): Starting
2019-03-22T22:41:59.460Z,1553294519.460 [BPC1](INFO): Starting
2019-03-22T22:41:59.865Z,1553294519.865 [BPC1](INFO): Starting
2019-03-22T22:42:00.269Z,1553294520.269 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:42:00.269Z,1553294520.269 [PNI_TCM] Data Fault, FailCount= 1
2019-03-22T22:42:00.269Z,1553294520.269 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:42:00.270Z,1553294520.270 [BPC1](INFO): Starting
2019-03-22T22:42:00.329Z,1553294520.329 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:42:00.672Z,1553294520.672 [PNI_TCM](INFO): Powering down
2019-03-22T22:42:00.809Z,1553294520.809 [BPC1](INFO): Starting
2019-03-22T22:42:01.076Z,1553294521.076 [BPC1](INFO): Starting
2019-03-22T22:42:01.480Z,1553294521.480 [BPC1](INFO): Starting
2019-03-22T22:42:01.884Z,1553294521.884 [BPC1](INFO): Starting
2019-03-22T22:42:01.977Z,1553294521.977 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:42:01.977Z,1553294521.977 [PNI_TCM] No Fault, FailCount= 1
2019-03-22T22:42:02.297Z,1553294522.297 [BPC1](INFO): Starting
2019-03-22T22:42:02.692Z,1553294522.692 [BPC1](INFO): Starting
2019-03-22T22:42:03.109Z,1553294523.109 [BPC1](INFO): Starting
2019-03-22T22:42:03.500Z,1553294523.500 [BPC1](INFO): Starting
2019-03-22T22:42:03.904Z,1553294523.904 [BPC1](INFO): Starting
2019-03-22T22:42:04.321Z,1553294524.321 [BPC1](INFO): Starting
2019-03-22T22:42:04.712Z,1553294524.712 [BPC1](INFO): Starting
2019-03-22T22:42:05.117Z,1553294525.117 [BPC1](INFO): Starting
2019-03-22T22:42:05.520Z,1553294525.520 [BPC1](INFO): Starting
2019-03-22T22:42:05.934Z,1553294525.934 [BPC1](INFO): Starting
2019-03-22T22:42:06.334Z,1553294526.334 [BPC1](INFO): Starting
2019-03-22T22:42:06.733Z,1553294526.733 [BPC1](INFO): Starting
2019-03-22T22:42:07.138Z,1553294527.138 [BPC1](INFO): Starting
2019-03-22T22:42:07.540Z,1553294527.540 [BPC1](INFO): Starting
2019-03-22T22:42:07.945Z,1553294527.945 [BPC1](INFO): Starting
2019-03-22T22:42:08.348Z,1553294528.348 [BPC1](INFO): Starting
2019-03-22T22:42:08.753Z,1553294528.753 [BPC1](INFO): Starting
2019-03-22T22:42:09.157Z,1553294529.157 [BPC1](INFO): Starting
2019-03-22T22:42:09.560Z,1553294529.560 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:42:09.560Z,1553294529.560 [PNI_TCM] Data Fault, FailCount= 2
2019-03-22T22:42:09.561Z,1553294529.561 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:42:09.561Z,1553294529.561 [BPC1](INFO): Starting
2019-03-22T22:42:09.576Z,1553294529.576 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:42:09.964Z,1553294529.964 [PNI_TCM](INFO): Powering down
2019-03-22T22:42:10.053Z,1553294530.053 [BPC1](INFO): Starting
2019-03-22T22:42:10.368Z,1553294530.368 [BPC1](INFO): Starting
2019-03-22T22:42:10.773Z,1553294530.773 [BPC1](INFO): Starting
2019-03-22T22:42:11.178Z,1553294531.178 [BPC1](INFO): Starting
2019-03-22T22:42:11.201Z,1553294531.201 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:42:11.201Z,1553294531.201 [PNI_TCM] No Fault, FailCount= 2
2019-03-22T22:42:11.609Z,1553294531.609 [BPC1](INFO): Starting
2019-03-22T22:42:11.984Z,1553294531.984 [BPC1](INFO): Starting
2019-03-22T22:42:12.401Z,1553294532.401 [BPC1](INFO): Starting
2019-03-22T22:42:12.793Z,1553294532.793 [BPC1](INFO): Starting
2019-03-22T22:42:13.198Z,1553294533.198 [BPC1](INFO): Starting
2019-03-22T22:42:13.613Z,1553294533.613 [BPC1](INFO): Starting
2019-03-22T22:42:14.004Z,1553294534.004 [BPC1](INFO): Starting
2019-03-22T22:42:14.408Z,1553294534.408 [BPC1](INFO): Starting
2019-03-22T22:42:14.812Z,1553294534.812 [BPC1](INFO): Starting
2019-03-22T22:42:15.217Z,1553294535.217 [BPC1](INFO): Starting
2019-03-22T22:42:15.620Z,1553294535.620 [BPC1](INFO): Starting
2019-03-22T22:42:16.024Z,1553294536.024 [BPC1](INFO): Starting
2019-03-22T22:42:16.428Z,1553294536.428 [BPC1](INFO): Starting
2019-03-22T22:42:16.834Z,1553294536.834 [BPC1](INFO): Starting
2019-03-22T22:42:17.236Z,1553294537.236 [BPC1](INFO): Starting
2019-03-22T22:42:17.641Z,1553294537.641 [BPC1](INFO): Starting
2019-03-22T22:42:18.045Z,1553294538.045 [BPC1](INFO): Starting
2019-03-22T22:42:18.449Z,1553294538.449 [BPC1](INFO): Starting
2019-03-22T22:42:18.852Z,1553294538.852 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:42:18.852Z,1553294538.852 [PNI_TCM] Data Fault, FailCount= 3
2019-03-22T22:42:18.852Z,1553294538.852 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:42:18.853Z,1553294538.853 [BPC1](INFO): Starting
2019-03-22T22:42:18.880Z,1553294538.880 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:42:19.242Z,1553294539.242 [NAL9602](INFO): Powering up NAL9602
2019-03-22T22:42:19.258Z,1553294539.258 [PNI_TCM](INFO): Powering down
2019-03-22T22:42:19.345Z,1553294539.345 [BPC1](INFO): Starting
2019-03-22T22:42:19.660Z,1553294539.660 [BPC1](INFO): Starting
2019-03-22T22:42:20.064Z,1553294540.064 [BPC1](INFO): Starting
2019-03-22T22:42:20.471Z,1553294540.471 [BPC1](INFO): Starting
2019-03-22T22:42:20.484Z,1553294540.484 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:42:20.484Z,1553294540.484 [PNI_TCM] No Fault, FailCount= 3
2019-03-22T22:42:20.885Z,1553294540.885 [BPC1](INFO): Starting
2019-03-22T22:42:20.898Z,1553294540.898 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-22T22:42:20.902Z,1553294540.902 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-22T22:42:21.276Z,1553294541.276 [BPC1](INFO): Starting
2019-03-22T22:42:21.693Z,1553294541.693 [BPC1](INFO): Starting
2019-03-22T22:42:22.084Z,1553294542.084 [BPC1](INFO): Starting
2019-03-22T22:42:22.489Z,1553294542.489 [BPC1](INFO): Starting
2019-03-22T22:42:22.905Z,1553294542.905 [BPC1](INFO): Starting
2019-03-22T22:42:23.296Z,1553294543.296 [BPC1](INFO): Starting
2019-03-22T22:42:23.885Z,1553294543.885 [BPC1](INFO): Starting
2019-03-22T22:42:24.274Z,1553294544.274 [BPC1](INFO): Starting
2019-03-22T22:42:24.678Z,1553294544.678 [BPC1](INFO): Starting
2019-03-22T22:42:25.081Z,1553294545.081 [BPC1](INFO): Starting
2019-03-22T22:42:25.486Z,1553294545.486 [BPC1](INFO): Starting
2019-03-22T22:42:25.890Z,1553294545.890 [BPC1](INFO): Starting
2019-03-22T22:42:26.296Z,1553294546.296 [BPC1](INFO): Starting
2019-03-22T22:42:26.701Z,1553294546.701 [BPC1](INFO): Starting
2019-03-22T22:42:27.102Z,1553294547.102 [BPC1](INFO): Starting
2019-03-22T22:42:27.506Z,1553294547.506 [BPC1](INFO): Starting
2019-03-22T22:42:27.909Z,1553294547.909 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:42:27.909Z,1553294547.909 [PNI_TCM] Data Fault, FailCount= 4
2019-03-22T22:42:27.909Z,1553294547.909 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:42:27.910Z,1553294547.910 [BPC1](INFO): Starting
2019-03-22T22:42:27.997Z,1553294547.997 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:42:28.312Z,1553294548.312 [PNI_TCM](INFO): Powering down
2019-03-22T22:42:28.401Z,1553294548.401 [BPC1](INFO): Starting
2019-03-22T22:42:28.717Z,1553294548.717 [BPC1](INFO): Starting
2019-03-22T22:42:29.121Z,1553294549.121 [BPC1](INFO): Starting
2019-03-22T22:42:29.526Z,1553294549.526 [BPC1](INFO): Starting
2019-03-22T22:42:29.578Z,1553294549.578 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:42:29.578Z,1553294549.578 [PNI_TCM] No Fault, FailCount= 4
2019-03-22T22:42:29.945Z,1553294549.945 [BPC1](INFO): Starting
2019-03-22T22:42:30.335Z,1553294550.335 [BPC1](INFO): Starting
2019-03-22T22:42:30.749Z,1553294550.749 [BPC1](INFO): Starting
2019-03-22T22:42:31.142Z,1553294551.142 [BPC1](INFO): Starting
2019-03-22T22:42:31.546Z,1553294551.546 [BPC1](INFO): Starting
2019-03-22T22:42:31.973Z,1553294551.973 [BPC1](INFO): Starting
2019-03-22T22:42:32.049Z,1553294552.049 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.022310
CHAN A1 (24V): 0.001260
CHAN A2 (12V): -0.068177
CHAN A3 (5V): -0.002649
CHAN B0 (3.3V): -0.001051
CHAN B1 (3.15aV): -0.001223
CHAN B2 (3.15bV): -0.000882
CHAN B3 (GND): -0.000541
OPEN: -0.001121
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-22T22:42:32.355Z,1553294552.355 [BPC1](INFO): Starting
2019-03-22T22:42:32.758Z,1553294552.758 [BPC1](INFO): Starting
2019-03-22T22:42:33.190Z,1553294553.190 [BPC1](INFO): Starting
2019-03-22T22:42:33.566Z,1553294553.566 [BPC1](INFO): Starting
2019-03-22T22:42:33.970Z,1553294553.970 [BPC1](INFO): Starting
2019-03-22T22:42:34.026Z,1553294554.026 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T22:42:34.375Z,1553294554.375 [BPC1](INFO): Starting
2019-03-22T22:42:34.778Z,1553294554.778 [BPC1](INFO): Starting
2019-03-22T22:42:35.182Z,1553294555.182 [BPC1](INFO): Starting
2019-03-22T22:42:35.586Z,1553294555.586 [BPC1](INFO): Starting
2019-03-22T22:42:35.990Z,1553294555.990 [BPC1](INFO): Starting
2019-03-22T22:42:36.414Z,1553294556.414 [BPC1](INFO): Starting
2019-03-22T22:42:36.865Z,1553294556.865 [BPC1](INFO): Starting
2019-03-22T22:42:37.269Z,1553294557.269 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:42:37.269Z,1553294557.269 [PNI_TCM] Data Fault, FailCount= 5
2019-03-22T22:42:37.269Z,1553294557.269 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:42:37.270Z,1553294557.270 [BPC1](INFO): Starting
2019-03-22T22:42:37.358Z,1553294557.358 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:42:37.359Z,1553294557.359 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-03-22T22:42:37.672Z,1553294557.672 [PNI_TCM](INFO): Powering down
2019-03-22T22:42:37.761Z,1553294557.761 [BPC1](INFO): Starting
2019-03-22T22:42:37.831Z,1553294557.831 [CommandLine](FAULT): Scheduling is paused
2019-03-22T22:42:37.832Z,1553294557.832 [CBIT](INFO): Critical error at 20190322T224237
2019-03-22T22:42:37.832Z,1553294557.832 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-22T22:42:38.077Z,1553294558.077 [BPC1](INFO): Starting
2019-03-22T22:42:38.481Z,1553294558.481 [BPC1](INFO): Starting
2019-03-22T22:42:38.888Z,1553294558.888 [BPC1](INFO): Starting
2019-03-22T22:42:39.288Z,1553294559.288 [BPC1](INFO): Starting
2019-03-22T22:42:39.693Z,1553294559.693 [BPC1](INFO): Starting
2019-03-22T22:42:40.082Z,1553294560.082 [NAL9602](ERROR): NAL9602 initialization error.
2019-03-22T22:42:40.082Z,1553294560.082 [NAL9602] Communications Fault, FailCount= 1
2019-03-22T22:42:40.082Z,1553294560.082 [NAL9602](ERROR): Communications Fault
2019-03-22T22:42:40.096Z,1553294560.096 [BPC1](INFO): Starting
2019-03-22T22:42:40.186Z,1553294560.186 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-03-22T22:42:40.486Z,1553294560.486 [NAL9602](INFO): Powering down
2019-03-22T22:42:40.502Z,1553294560.502 [BPC1](INFO): Starting
2019-03-22T22:42:40.904Z,1553294560.904 [BPC1](INFO): Starting
2019-03-22T22:42:41.308Z,1553294561.308 [BPC1](INFO): Starting
2019-03-22T22:42:41.426Z,1553294561.426 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-22T22:42:41.426Z,1553294561.426 [NAL9602] No Fault, FailCount= 1
2019-03-22T22:42:41.711Z,1553294561.711 [BPC1](INFO): Starting
2019-03-22T22:42:42.125Z,1553294562.125 [BPC1](INFO): Starting
2019-03-22T22:42:42.522Z,1553294562.522 [BPC1](INFO): Starting
2019-03-22T22:42:42.924Z,1553294562.924 [BPC1](INFO): Starting
2019-03-22T22:42:43.328Z,1553294563.328 [BPC1](INFO): Starting
2019-03-22T22:42:43.732Z,1553294563.732 [BPC1](INFO): Starting
2019-03-22T22:42:44.135Z,1553294564.135 [BPC1](INFO): Starting
2019-03-22T22:42:44.540Z,1553294564.540 [BPC1](INFO): Starting
2019-03-22T22:42:44.944Z,1553294564.944 [BPC1](INFO): Starting
2019-03-22T22:42:45.352Z,1553294565.352 [BPC1](INFO): Starting
2019-03-22T22:42:45.755Z,1553294565.755 [BPC1](INFO): Starting
2019-03-22T22:42:46.156Z,1553294566.156 [BPC1](INFO): Starting
2019-03-22T22:42:46.559Z,1553294566.559 [BPC1](INFO): Starting
2019-03-22T22:42:46.964Z,1553294566.964 [BPC1](INFO): Starting
2019-03-22T22:42:47.371Z,1553294567.371 [BPC1](INFO): Starting
2019-03-22T22:42:47.776Z,1553294567.776 [BPC1](INFO): Starting
2019-03-22T22:42:48.176Z,1553294568.176 [BPC1](INFO): Starting
2019-03-22T22:42:48.580Z,1553294568.580 [BPC1](INFO): Starting
2019-03-22T22:42:48.984Z,1553294568.984 [BPC1](INFO): Starting
2019-03-22T22:42:49.397Z,1553294569.397 [BPC1](INFO): Starting
2019-03-22T22:42:49.792Z,1553294569.792 [BPC1](INFO): Starting
2019-03-22T22:42:50.192Z,1553294570.192 [BPC1](INFO): Starting
2019-03-22T22:42:50.600Z,1553294570.600 [BPC1](INFO): Starting
2019-03-22T22:42:51.008Z,1553294571.008 [BPC1](INFO): Starting
2019-03-22T22:42:51.411Z,1553294571.411 [BPC1](INFO): Starting
2019-03-22T22:42:51.812Z,1553294571.812 [BPC1](INFO): Starting
2019-03-22T22:42:52.217Z,1553294572.217 [BPC1](INFO): Starting
2019-03-22T22:42:52.628Z,1553294572.628 [BPC1](INFO): Starting
2019-03-22T22:42:53.028Z,1553294573.028 [BPC1](INFO): Starting
2019-03-22T22:42:53.432Z,1553294573.432 [BPC1](INFO): Starting
2019-03-22T22:42:53.432Z,1553294573.432 [BPC1](INFO): Initialized.
2019-03-22T22:42:54.253Z,1553294574.253 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:42:54.265Z,1553294574.265 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:42:55.860Z,1553294575.860 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:42:55.860Z,1553294575.860 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:42:59.091Z,1553294579.091 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:42:59.105Z,1553294579.105 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:42:59.106Z,1553294579.106 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:42:59.491Z,1553294579.491 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B97,09,3ACC,0A,FFFA,0B,FFF9%3D
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,8F02,12,7A94%4E
$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,3A71,0A,FFDB,0B,FFCA%45
$B12,0C,0001,0D,0039,0E,0035,0F,0DE7,10,18A9,11,168B,12,0F72%33
$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,3ACF,0A,0000,0B,0000%4B
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,3AB9,0A,0000,0B,0000%3B
$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,3B82,0A,0000,0B,0000%36
$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

2019-03-22T22:43:00.300Z,1553294580.300 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:43:00.325Z,1553294580.325 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:43:00.325Z,1553294580.325 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:43:00.337Z,1553294580.337 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:43:00.707Z,1553294580.707 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:43:00.717Z,1553294580.717 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T22:43:00.717Z,1553294580.717 [SBIT](FAULT): Control surface position failure.
2019-03-22T22:43:01.525Z,1553294581.525 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:43:08.032Z,1553294588.032 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:43:08.836Z,1553294588.836 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:43:10.842Z,1553294590.842 [NAL9602](INFO): Powering up NAL9602
2019-03-22T22:43:12.065Z,1553294592.065 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:43:12.110Z,1553294592.110 [BPC1](INFO): BPC1B: got 4 stick msg (912 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:43:12.110Z,1553294592.110 [BPC1](INFO): BPC1B: got valid message (912 bytes).
2019-03-22T22:43:12.475Z,1553294592.475 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B97,09,3ACC,0A,FFF9,0B,FFF9%45
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,7A94%49
$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,3A6F,0A,FFD2,0B,FFCF%44
$B12,0C,0001,0D,0039,0E,0035,0F,0DE7,10,18A9,11,1222,12,1106%38
$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,0B95,09,3A39,0A,0000,0B,0000%49
$B15,0C,0001,0D,003C,0E,0034,0F,0D95,10,18D0,11,FFFF,12,FFFF%33
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%39
$B15,1A,0031,1B,4B19,1C,0253%4F
$B16,02,000A,01,02A8,03,0001,08,0B96,09,3AB4,0A,0000,0B,0000%35
$B16,0C,0001,0D,003C,0E,0037,0F,0E7F,10,18F3,11,FFFF,12,FFFF%4E
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3A
$B16,1A,0031,1B,4B19,1C,02A1%3A
$B17,02,000A,01,02A8,03,0001,08,0B94,09,3AB9,0A,0000,0B,0000%3B
$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,3B82,0A,0000,0B,0000%36
$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

2019-03-22T22:43:13.293Z,1553294593.293 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:43:13.681Z,1553294593.681 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:43:13.741Z,1553294593.741 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:43:13.741Z,1553294593.741 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:43:14.091Z,1553294594.091 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:43:14.511Z,1553294594.511 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T22:43:14.511Z,1553294594.511 [SBIT](FAULT): Control surface position failure.
2019-03-22T22:43:14.909Z,1553294594.909 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:43:14.926Z,1553294594.926 [SBIT](CRITICAL): SBIT FAILED
2019-03-22T22:43:14.941Z,1553294594.941 [CBIT](INFO): Critical error at 20190322T224314
2019-03-22T22:43:14.997Z,1553294594.997 [CommandLine](IMPORTANT): got command configSet list
2019-03-22T22:43:14.997Z,1553294594.997 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-22T22:43:14.998Z,1553294594.998 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-22T22:43:14.998Z,1553294594.998 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=10 minute;

2019-03-22T22:43:14.998Z,1553294594.998 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool;

2019-03-22T22:43:14.999Z,1553294594.999 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-03-22T22:43:14.999Z,1553294594.999 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-03-22T22:43:14.999Z,1553294594.999 [CommandLine](IMPORTANT): Depth_Keller.offset=-0.13 decibar;

2019-03-22T22:43:14.999Z,1553294594.999 [CommandLine](IMPORTANT): Depth_Keller.scale=45.92 micropascal;

2019-03-22T22:43:14.999Z,1553294594.999 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-03-22T22:43:14.999Z,1553294594.999 [CommandLine](IMPORTANT): PNI_TCM.loadAtStartup=1 bool;

2019-03-22T22:43:14.999Z,1553294594.999 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2019-03-22T22:43:14.999Z,1553294594.999 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2019-03-22T22:43:15.314Z,1553294595.314 [MissionManager](IMPORTANT): Started mission Startup
2019-03-22T22:43:15.314Z,1553294595.314 [Startup] Running Loop=1
2019-03-22T22:43:15.315Z,1553294595.315 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-22T22:43:15.315Z,1553294595.315 [Startup:A.GoToSurface] Running Loop=1
2019-03-22T22:43:15.315Z,1553294595.315 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-22T22:43:15.315Z,1553294595.315 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-22T22:43:15.316Z,1553294595.316 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-22T22:43:15.316Z,1553294595.316 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-22T22:43:15.317Z,1553294595.317 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-22T22:43:15.317Z,1553294595.317 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-22T22:43:15.318Z,1553294595.318 [Startup:StartupSatComms] Running Loop=1
2019-03-22T22:43:15.318Z,1553294595.318 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-22T22:43:15.319Z,1553294595.319 [Startup:StartupSatComms:A] Running Loop=1
2019-03-22T22:43:15.700Z,1553294595.700 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:43:15.714Z,1553294595.714 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-22T22:43:18.932Z,1553294598.932 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:43:18.945Z,1553294598.945 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:43:18.946Z,1553294598.946 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:43:19.342Z,1553294599.342 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:43:20.161Z,1553294600.161 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:43:21.750Z,1553294601.750 [NAL9602](INFO): NAL9602 initialized
2019-03-22T22:43:22.168Z,1553294602.168 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:43:22.569Z,1553294602.569 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:43:27.016Z,1553294607.016 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:43:27.085Z,1553294607.085 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:43:27.085Z,1553294607.085 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:43:27.427Z,1553294607.427 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:43:28.237Z,1553294608.237 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:43:30.663Z,1553294610.663 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:43:33.882Z,1553294613.882 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:43:33.924Z,1553294613.924 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:43:33.924Z,1553294613.924 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:43:34.294Z,1553294614.294 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:43:35.121Z,1553294615.121 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:43:35.510Z,1553294615.510 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:43:40.355Z,1553294620.355 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:43:40.423Z,1553294620.423 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:43:40.424Z,1553294620.424 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:43:40.765Z,1553294620.765 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:43:41.973Z,1553294621.973 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:43:45.615Z,1553294625.615 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:43:49.241Z,1553294629.241 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:43:49.242Z,1553294629.242 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:43:49.286Z,1553294629.286 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:43:49.286Z,1553294629.286 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:43:49.650Z,1553294629.650 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:43:51.281Z,1553294631.281 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:43:52.480Z,1553294632.480 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:43:52.480Z,1553294632.480 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-22T22:43:52.480Z,1553294632.480 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-22T22:43:52.482Z,1553294632.482 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:43:52.482Z,1553294632.482 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-22T22:43:52.482Z,1553294632.482 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-22T22:43:52.492Z,1553294632.492 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-22T22:43:52.492Z,1553294632.492 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-22T22:43:52.892Z,1553294632.892 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-22T22:43:52.892Z,1553294632.892 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-22T22:43:52.893Z,1553294632.893 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-22T22:43:52.893Z,1553294632.893 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-22T22:43:53.278Z,1553294633.278 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T22:43:53.657Z,1553294633.657 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T22:43:53.657Z,1553294633.657 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-03-22T22:43:53.657Z,1553294633.657 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T22:43:53.694Z,1553294633.694 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T22:43:54.061Z,1553294634.061 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T22:43:54.112Z,1553294634.112 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:43:54.238Z,1553294634.238 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:43:54.238Z,1553294634.238 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:43:54.489Z,1553294634.489 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:43:55.309Z,1553294635.309 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:43:55.722Z,1553294635.722 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T22:43:55.722Z,1553294635.722 [Rowe_600LCM] No Fault, FailCount= 1
2019-03-22T22:43:55.857Z,1553294635.857 [Rowe_600LCM](INFO): Initializing
2019-03-22T22:43:56.340Z,1553294636.340 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T22:43:56.400Z,1553294636.400 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2019-03-22T22:44:01.771Z,1553294641.771 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:44:02.579Z,1553294642.579 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:44:04.993Z,1553294644.993 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:44:05.034Z,1553294645.034 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:44:05.035Z,1553294645.035 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:44:05.403Z,1553294645.403 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:44:06.217Z,1553294646.217 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:44:07.427Z,1553294647.427 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:44:07.493Z,1553294647.493 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:44:07.493Z,1553294647.493 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:44:07.839Z,1553294647.839 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:44:08.641Z,1553294648.641 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:44:15.502Z,1553294655.502 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-22T22:43:15.3Z
2019-03-22T22:44:15.502Z,1553294655.502 [Startup:StartupSatComms:A] Stopped
2019-03-22T22:44:15.502Z,1553294655.502 [Startup:StartupSatComms:B] Running Loop=1
2019-03-22T22:44:15.906Z,1553294655.906 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-22T22:44:16.348Z,1553294656.348 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:44:16.753Z,1553294656.753 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:44:19.886Z,1553294659.886 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004567
2019-03-22T22:44:20.389Z,1553294660.389 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:44:20.453Z,1553294660.453 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:44:20.454Z,1553294660.454 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:44:20.798Z,1553294660.798 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:44:21.199Z,1553294661.199 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:44:21.260Z,1553294661.260 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:44:21.261Z,1553294661.261 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:44:21.617Z,1553294661.617 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:44:21.629Z,1553294661.629 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:44:22.434Z,1553294662.434 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:44:25.049Z,1553294665.049 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20190322T213238/Courier0010.lzma
2019-03-22T22:44:25.854Z,1553294665.854 [DataOverHttps](INFO): Moved sent file to Logs/20190322T213238/Courier0010.lzma.bak
2019-03-22T22:44:25.855Z,1553294665.855 [DataOverHttps](INFO): SBD MOMSN=10216993
2019-03-22T22:44:29.681Z,1553294669.681 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:44:32.156Z,1553294672.156 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:44:34.538Z,1553294674.538 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:44:34.654Z,1553294674.654 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:44:34.655Z,1553294674.655 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:44:34.943Z,1553294674.943 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:44:35.753Z,1553294675.753 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:44:35.753Z,1553294675.753 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:44:35.840Z,1553294675.840 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:44:35.840Z,1553294675.840 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:44:36.150Z,1553294676.150 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:44:36.969Z,1553294676.969 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:44:37.900Z,1553294677.900 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190322T213238/Courier0013.lzma
2019-03-22T22:44:38.706Z,1553294678.706 [DataOverHttps](INFO): Moved sent file to Logs/20190322T213238/Courier0013.lzma.bak
2019-03-22T22:44:38.706Z,1553294678.706 [DataOverHttps](INFO): SBD MOMSN=10216998
2019-03-22T22:44:43.013Z,1553294683.013 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:44:47.054Z,1553294687.054 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:44:47.861Z,1553294687.861 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:44:47.979Z,1553294687.979 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:44:47.979Z,1553294687.979 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:44:48.280Z,1553294688.280 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:44:49.101Z,1553294689.101 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:44:50.580Z,1553294690.580 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20190322T224142/Courier0000.lzma
2019-03-22T22:44:50.694Z,1553294690.694 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:44:50.738Z,1553294690.738 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:44:50.739Z,1553294690.739 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:44:51.102Z,1553294691.102 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:44:51.386Z,1553294691.386 [DataOverHttps](INFO): Moved sent file to Logs/20190322T224142/Courier0000.lzma.bak
2019-03-22T22:44:51.386Z,1553294691.386 [DataOverHttps](INFO): SBD MOMSN=10217000
2019-03-22T22:44:51.913Z,1553294691.913 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:44:56.345Z,1553294696.345 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:45:01.194Z,1553294701.194 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:45:01.317Z,1553294701.317 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:45:01.317Z,1553294701.317 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:45:01.611Z,1553294701.611 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:45:02.017Z,1553294702.017 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:45:02.417Z,1553294702.417 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:45:03.025Z,1553294703.025 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20190322T213238/Express0011.lzma
2019-03-22T22:45:03.830Z,1553294703.830 [DataOverHttps](INFO): Moved sent file to Logs/20190322T213238/Express0011.lzma.bak
2019-03-22T22:45:03.830Z,1553294703.830 [DataOverHttps](INFO): SBD MOMSN=10217005
2019-03-22T22:45:06.041Z,1553294706.041 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:45:06.133Z,1553294706.133 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:45:06.133Z,1553294706.133 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:45:06.450Z,1553294706.450 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:45:08.073Z,1553294708.073 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:45:10.091Z,1553294710.091 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:45:14.940Z,1553294714.940 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:45:15.018Z,1553294715.018 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:45:15.018Z,1553294715.018 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:45:15.354Z,1553294715.354 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:45:15.368Z,1553294715.368 [DataOverHttps](INFO): Sending 65 bytes from file Logs/20190322T213238/Express0014.lzma
2019-03-22T22:45:15.775Z,1553294715.775 [Startup:StartupSatComms:B](INFO): Timed out from 2019-03-22T22:44:15.5Z
2019-03-22T22:45:15.775Z,1553294715.775 [Startup:StartupSatComms:B] Stopped
2019-03-22T22:45:15.775Z,1553294715.775 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-22T22:45:15.775Z,1553294715.775 [Startup:StartupSatComms] Stopped
2019-03-22T22:45:15.776Z,1553294715.776 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-22T22:45:15.776Z,1553294715.776 [Startup](INFO): Completed Startup
2019-03-22T22:45:15.777Z,1553294715.777 [MissionManager](INFO): Startup is completed.
2019-03-22T22:45:15.777Z,1553294715.777 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-22T22:45:15.777Z,1553294715.777 [Startup] Stopped
2019-03-22T22:45:15.778Z,1553294715.778 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-22T22:45:15.778Z,1553294715.778 [Startup:A.GoToSurface] Stopped
2019-03-22T22:45:15.778Z,1553294715.778 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-22T22:45:16.173Z,1553294716.173 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:45:16.175Z,1553294716.175 [DataOverHttps](INFO): Moved sent file to Logs/20190322T213238/Express0014.lzma.bak
2019-03-22T22:45:16.175Z,1553294716.175 [DataOverHttps](INFO): SBD MOMSN=10217019
2019-03-22T22:45:16.186Z,1553294716.186 [MissionManager](IMPORTANT): Started mission Default
2019-03-22T22:45:16.186Z,1553294716.186 [Default] Running Loop=1
2019-03-22T22:45:16.186Z,1553294716.186 [Default](DEBUG): Aggregate::initialize Default
2019-03-22T22:45:16.186Z,1553294716.186 [Default:B.GoToSurface] Running Loop=1
2019-03-22T22:45:16.186Z,1553294716.186 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-22T22:45:16.186Z,1553294716.186 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-22T22:45:16.187Z,1553294716.187 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-22T22:45:16.187Z,1553294716.187 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-22T22:45:16.187Z,1553294716.187 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-22T22:45:16.188Z,1553294716.188 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-22T22:45:16.188Z,1553294716.188 [Default:A.Wait] Running Loop=1
2019-03-22T22:45:16.188Z,1553294716.188 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-22T22:45:18.564Z,1553294718.564 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:45:22.209Z,1553294722.209 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:45:22.254Z,1553294722.254 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:45:22.254Z,1553294722.254 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:45:22.606Z,1553294722.606 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:45:23.416Z,1553294723.416 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:45:23.429Z,1553294723.429 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:45:28.264Z,1553294728.264 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:45:28.323Z,1553294728.323 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:45:28.324Z,1553294728.324 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:45:28.668Z,1553294728.668 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:45:29.501Z,1553294729.501 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:45:29.514Z,1553294729.514 [Default:A.Wait](INFO): Done Waiting.
2019-03-22T22:45:29.514Z,1553294729.514 [Default:A.Wait] Stopped
2019-03-22T22:45:29.514Z,1553294729.514 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-22T22:45:29.894Z,1553294729.894 [Default:CheckIn] Running Loop=1
2019-03-22T22:45:29.894Z,1553294729.894 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-22T22:45:29.894Z,1553294729.894 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-22T22:45:30.292Z,1553294730.292 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-22T22:45:33.513Z,1553294733.513 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:45:36.770Z,1553294736.770 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:45:37.163Z,1553294737.163 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:45:37.219Z,1553294737.219 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:45:37.219Z,1553294737.219 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:45:37.449Z,1553294737.449 [CommandLine](IMPORTANT): got command get PNI_TCM.loadControl
2019-03-22T22:45:37.450Z,1553294737.450 [CommandLine](IMPORTANT): PNI_TCM.loadControl /dev/loadB7
2019-03-22T22:45:37.565Z,1553294737.565 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:45:38.373Z,1553294738.373 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:45:39.974Z,1553294739.974 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:45:41.606Z,1553294741.606 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:45:41.677Z,1553294741.677 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:45:41.678Z,1553294741.678 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:45:42.018Z,1553294742.018 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:45:42.821Z,1553294742.821 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:45:44.425Z,1553294744.425 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:45:44.441Z,1553294744.441 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:45:44.442Z,1553294744.442 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:45:44.835Z,1553294744.835 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:45:45.645Z,1553294745.645 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:45:50.084Z,1553294750.084 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:45:53.314Z,1553294753.314 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:45:53.318Z,1553294753.318 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:45:53.318Z,1553294753.318 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-22T22:45:53.318Z,1553294753.318 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-22T22:45:53.320Z,1553294753.320 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:45:53.320Z,1553294753.320 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-22T22:45:53.320Z,1553294753.320 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-22T22:45:53.330Z,1553294753.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-22T22:45:53.330Z,1553294753.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-22T22:45:53.745Z,1553294753.745 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-22T22:45:53.745Z,1553294753.745 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-22T22:45:53.745Z,1553294753.745 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-22T22:45:53.745Z,1553294753.745 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-22T22:45:54.128Z,1553294754.128 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T22:45:54.933Z,1553294754.933 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:45:55.048Z,1553294755.048 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:45:55.048Z,1553294755.048 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:45:55.336Z,1553294755.336 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:45:56.149Z,1553294756.149 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:45:56.517Z,1553294756.517 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T22:45:56.517Z,1553294756.517 [Rowe_600LCM] Communications Fault, FailCount= 2
2019-03-22T22:45:56.517Z,1553294756.517 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T22:45:56.590Z,1553294756.590 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T22:45:56.931Z,1553294756.931 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T22:45:58.171Z,1553294758.171 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:45:58.246Z,1553294758.246 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:45:58.246Z,1553294758.246 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:45:58.568Z,1553294758.568 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:45:58.586Z,1553294758.586 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T22:45:58.586Z,1553294758.586 [Rowe_600LCM] No Fault, FailCount= 2
2019-03-22T22:45:58.785Z,1553294758.785 [Rowe_600LCM](INFO): Initializing
2019-03-22T22:45:59.271Z,1553294759.271 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T22:45:59.338Z,1553294759.338 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2019-03-22T22:45:59.385Z,1553294759.385 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:46:03.818Z,1553294763.818 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:46:06.645Z,1553294766.645 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:46:08.675Z,1553294768.675 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:46:08.753Z,1553294768.753 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:46:08.753Z,1553294768.753 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:46:09.076Z,1553294769.076 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:46:09.889Z,1553294769.889 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:46:11.492Z,1553294771.492 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:46:11.570Z,1553294771.570 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:46:11.570Z,1553294771.570 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:46:11.904Z,1553294771.904 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:46:12.729Z,1553294772.729 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:46:17.150Z,1553294777.150 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:46:19.977Z,1553294779.977 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:46:21.593Z,1553294781.593 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:46:21.659Z,1553294781.659 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:46:21.659Z,1553294781.659 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:46:22.006Z,1553294782.006 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:46:22.817Z,1553294782.817 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:46:24.824Z,1553294784.824 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:46:24.893Z,1553294784.893 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:46:24.893Z,1553294784.893 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:46:25.232Z,1553294785.232 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:46:26.049Z,1553294786.049 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:46:30.484Z,1553294790.484 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:46:33.310Z,1553294793.310 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:46:34.939Z,1553294794.939 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:46:34.999Z,1553294794.999 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:46:34.999Z,1553294794.999 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:46:35.336Z,1553294795.336 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:46:36.149Z,1553294796.149 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:46:38.560Z,1553294798.560 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:46:38.629Z,1553294798.629 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:46:38.629Z,1553294798.629 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:46:38.978Z,1553294798.978 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:46:39.797Z,1553294799.797 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:46:43.408Z,1553294803.408 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:46:47.042Z,1553294807.042 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:46:48.256Z,1553294808.256 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:46:48.323Z,1553294808.323 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:46:48.324Z,1553294808.324 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:46:48.667Z,1553294808.667 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:46:49.493Z,1553294809.493 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:46:51.901Z,1553294811.901 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:46:51.963Z,1553294811.963 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:46:51.963Z,1553294811.963 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:46:52.299Z,1553294812.299 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:46:53.117Z,1553294813.117 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:46:57.144Z,1553294817.144 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:47:00.387Z,1553294820.387 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:47:01.997Z,1553294821.997 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:47:02.059Z,1553294822.059 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:47:02.059Z,1553294822.059 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:47:02.402Z,1553294822.402 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:47:03.217Z,1553294823.217 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:47:05.232Z,1553294825.232 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:47:05.343Z,1553294825.343 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:47:05.343Z,1553294825.343 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:47:05.647Z,1553294825.647 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:47:06.457Z,1553294826.457 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:47:10.487Z,1553294830.487 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:47:14.113Z,1553294834.113 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:47:15.330Z,1553294835.330 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:47:15.388Z,1553294835.388 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:47:15.389Z,1553294835.389 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:47:15.749Z,1553294835.749 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:47:16.565Z,1553294836.565 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:47:18.960Z,1553294838.960 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:47:19.027Z,1553294839.027 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:47:19.027Z,1553294839.027 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:47:19.365Z,1553294839.365 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:47:20.185Z,1553294840.185 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:47:23.809Z,1553294843.809 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:47:27.451Z,1553294847.451 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:47:28.659Z,1553294848.659 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:47:28.720Z,1553294848.720 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:47:28.720Z,1553294848.720 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:47:29.068Z,1553294849.068 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-03-22T22:47:29.881Z,1553294849.881 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:47:32.304Z,1553294852.304 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:47:32.364Z,1553294852.364 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:47:32.364Z,1553294852.364 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:47:32.712Z,1553294852.712 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:47:33.513Z,1553294853.513 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:47:37.555Z,1553294857.555 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:47:37.575Z,1553294857.575 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:47:37.575Z,1553294857.575 [PNI_TCM] No Fault, FailCount= 5
2019-03-22T22:47:40.775Z,1553294860.775 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:47:41.996Z,1553294861.996 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:47:42.123Z,1553294862.123 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:47:42.123Z,1553294862.123 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:47:42.414Z,1553294862.414 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:47:43.229Z,1553294863.229 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:47:45.221Z,1553294865.221 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:47:45.221Z,1553294865.221 [PNI_TCM] Data Fault, FailCount= 1
2019-03-22T22:47:45.221Z,1553294865.221 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:47:45.237Z,1553294865.237 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:47:45.621Z,1553294865.621 [PNI_TCM](INFO): Powering down
2019-03-22T22:47:45.719Z,1553294865.719 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:47:45.777Z,1553294865.777 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:47:45.777Z,1553294865.777 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:47:46.036Z,1553294866.036 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:47:46.849Z,1553294866.849 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:47:46.874Z,1553294866.874 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:47:46.874Z,1553294866.874 [PNI_TCM] No Fault, FailCount= 1
2019-03-22T22:47:50.877Z,1553294870.877 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:47:54.109Z,1553294874.109 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:47:54.509Z,1553294874.509 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:47:54.509Z,1553294874.509 [PNI_TCM] Data Fault, FailCount= 2
2019-03-22T22:47:54.509Z,1553294874.509 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:47:54.520Z,1553294874.520 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:47:54.520Z,1553294874.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-22T22:47:54.520Z,1553294874.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-22T22:47:54.522Z,1553294874.522 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:47:54.522Z,1553294874.522 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-22T22:47:54.522Z,1553294874.522 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-22T22:47:54.532Z,1553294874.532 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-22T22:47:54.532Z,1553294874.532 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-22T22:47:54.532Z,1553294874.532 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:47:54.929Z,1553294874.929 [PNI_TCM](INFO): Powering down
2019-03-22T22:47:55.032Z,1553294875.032 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-22T22:47:55.032Z,1553294875.032 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-22T22:47:55.033Z,1553294875.033 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-22T22:47:55.033Z,1553294875.033 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-22T22:47:55.320Z,1553294875.320 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T22:47:55.726Z,1553294875.726 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:47:55.786Z,1553294875.786 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:47:55.786Z,1553294875.786 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:47:56.137Z,1553294876.137 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:47:56.152Z,1553294876.152 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:47:56.152Z,1553294876.152 [PNI_TCM] No Fault, FailCount= 2
2019-03-22T22:47:56.949Z,1553294876.949 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:47:58.967Z,1553294878.967 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:47:59.038Z,1553294879.038 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:47:59.038Z,1553294879.038 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:47:59.333Z,1553294879.333 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T22:47:59.333Z,1553294879.333 [Rowe_600LCM] Communications Fault, FailCount= 3
2019-03-22T22:47:59.333Z,1553294879.333 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T22:47:59.377Z,1553294879.377 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:47:59.391Z,1553294879.391 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T22:47:59.749Z,1553294879.749 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T22:48:00.181Z,1553294880.181 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:48:01.396Z,1553294881.396 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T22:48:01.396Z,1553294881.396 [Rowe_600LCM] No Fault, FailCount= 3
2019-03-22T22:48:01.505Z,1553294881.505 [Rowe_600LCM](INFO): Initializing
2019-03-22T22:48:01.782Z,1553294881.782 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:48:01.993Z,1553294881.993 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T22:48:02.048Z,1553294882.048 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2019-03-22T22:48:03.805Z,1553294883.805 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:48:03.805Z,1553294883.805 [PNI_TCM] Data Fault, FailCount= 3
2019-03-22T22:48:03.805Z,1553294883.805 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:48:03.847Z,1553294883.847 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:48:04.208Z,1553294884.208 [PNI_TCM](INFO): Powering down
2019-03-22T22:48:05.421Z,1553294885.421 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:48:05.436Z,1553294885.436 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:48:05.436Z,1553294885.436 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:48:05.450Z,1553294885.450 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:48:05.451Z,1553294885.451 [PNI_TCM] No Fault, FailCount= 3
2019-03-22T22:48:05.833Z,1553294885.833 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:48:06.661Z,1553294886.661 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:48:13.096Z,1553294893.096 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:48:13.097Z,1553294893.097 [PNI_TCM] Data Fault, FailCount= 4
2019-03-22T22:48:13.097Z,1553294893.097 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:48:13.098Z,1553294893.098 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:48:13.112Z,1553294893.112 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:48:13.486Z,1553294893.486 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-03-22T22:48:14.001Z,1553294894.001 [NAL9602](FAULT): received: 
2019-03-22T22:48:14.001Z,1553294894.001 [NAL9602] Data Fault, FailCount= 2
2019-03-22T22:48:14.001Z,1553294894.001 [NAL9602](ERROR): Data Fault
2019-03-22T22:48:14.019Z,1553294894.019 [PNI_TCM](INFO): Powering down
2019-03-22T22:48:14.125Z,1553294894.125 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-22T22:48:14.186Z,1553294894.186 [NAL9602](INFO): Powering down
2019-03-22T22:48:14.973Z,1553294894.973 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-22T22:48:14.973Z,1553294894.973 [NAL9602] No Fault, FailCount= 2
2019-03-22T22:48:15.398Z,1553294895.398 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:48:15.398Z,1553294895.398 [PNI_TCM] No Fault, FailCount= 4
2019-03-22T22:48:17.381Z,1553294897.381 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:48:17.479Z,1553294897.479 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T22:48:17.479Z,1553294897.479 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:48:17.810Z,1553294897.810 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:48:18.606Z,1553294898.606 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:48:23.040Z,1553294903.040 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:48:23.040Z,1553294903.040 [PNI_TCM] Data Fault, FailCount= 5
2019-03-22T22:48:23.040Z,1553294903.040 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:48:23.054Z,1553294903.054 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:48:23.054Z,1553294903.054 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-03-22T22:48:23.441Z,1553294903.441 [PNI_TCM](INFO): Powering down
2019-03-22T22:48:23.551Z,1553294903.551 [CBIT](INFO): Critical error at 20190322T224823
2019-03-22T22:48:25.065Z,1553294905.065 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:48:29.505Z,1553294909.505 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:48:29.563Z,1553294909.563 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:48:29.563Z,1553294909.563 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:48:29.912Z,1553294909.912 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:48:30.729Z,1553294910.729 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:48:37.194Z,1553294917.194 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:48:41.625Z,1553294921.625 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:48:41.677Z,1553294921.677 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T22:48:41.677Z,1553294921.677 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:48:42.031Z,1553294922.031 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:48:42.849Z,1553294922.849 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:48:44.438Z,1553294924.438 [NAL9602](INFO): Powering up NAL9602
2019-03-22T22:48:49.305Z,1553294929.305 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:48:53.745Z,1553294933.745 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:48:53.802Z,1553294933.802 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:48:53.802Z,1553294933.802 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:48:54.151Z,1553294934.151 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:48:54.985Z,1553294934.985 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:48:55.342Z,1553294935.342 [NAL9602](INFO): NAL9602 initialized
2019-03-22T22:48:56.169Z,1553294936.169 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:48:58.570Z,1553294938.570 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-22T22:48:59.401Z,1553294939.401 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:01.817Z,1553294941.817 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:01.833Z,1553294941.833 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:49:05.057Z,1553294945.057 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:06.271Z,1553294946.271 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:49:06.324Z,1553294946.324 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T22:49:06.325Z,1553294946.325 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:49:06.679Z,1553294946.679 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:49:07.493Z,1553294947.493 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:49:07.885Z,1553294947.885 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:11.113Z,1553294951.113 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:13.945Z,1553294953.945 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:13.964Z,1553294953.964 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:49:16.765Z,1553294956.765 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:18.389Z,1553294958.389 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:49:18.452Z,1553294958.452 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:49:18.452Z,1553294958.452 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:49:18.785Z,1553294958.785 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:18.805Z,1553294958.805 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:49:19.629Z,1553294959.629 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:49:22.017Z,1553294962.017 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:24.853Z,1553294964.853 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:26.061Z,1553294966.061 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:49:28.077Z,1553294968.077 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:30.513Z,1553294970.513 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:49:30.567Z,1553294970.567 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T22:49:30.567Z,1553294970.567 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:49:30.905Z,1553294970.905 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:30.931Z,1553294970.931 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:49:31.745Z,1553294971.745 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:49:33.741Z,1553294973.741 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:35.753Z,1553294975.753 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:37.777Z,1553294977.777 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:38.603Z,1553294978.603 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:49:41.013Z,1553294981.013 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:42.630Z,1553294982.630 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:49:42.690Z,1553294982.690 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:49:42.690Z,1553294982.690 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:49:43.036Z,1553294983.036 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:49:43.837Z,1553294983.837 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:43.865Z,1553294983.865 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:49:47.065Z,1553294987.065 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:49.897Z,1553294989.897 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:50.715Z,1553294990.715 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:49:53.133Z,1553294993.133 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:55.153Z,1553294995.153 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:49:55.215Z,1553294995.215 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T22:49:55.215Z,1553294995.215 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:49:55.563Z,1553294995.563 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:49:55.567Z,1553294995.567 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:49:55.567Z,1553294995.567 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-03-22T22:49:55.568Z,1553294995.568 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-22T22:49:55.569Z,1553294995.569 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:49:55.569Z,1553294995.569 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-03-22T22:49:55.569Z,1553294995.569 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-22T22:49:55.599Z,1553294995.599 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-22T22:49:55.600Z,1553294995.600 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-22T22:49:55.953Z,1553294995.953 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:49:56.009Z,1553294996.009 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-22T22:49:56.009Z,1553294996.009 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-03-22T22:49:56.009Z,1553294996.009 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-22T22:49:56.009Z,1553294996.009 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-03-22T22:49:56.389Z,1553294996.389 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:49:56.400Z,1553294996.400 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T22:49:58.789Z,1553294998.789 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:01.993Z,1553295001.993 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T22:50:01.993Z,1553295001.993 [Rowe_600LCM] Communications Fault, FailCount= 4
2019-03-22T22:50:01.993Z,1553295001.993 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T22:50:02.013Z,1553295002.013 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:02.083Z,1553295002.083 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T22:50:02.407Z,1553295002.407 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T22:50:02.844Z,1553295002.844 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:50:04.058Z,1553295004.058 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T22:50:04.058Z,1553295004.058 [Rowe_600LCM] No Fault, FailCount= 4
2019-03-22T22:50:04.225Z,1553295004.225 [Rowe_600LCM](INFO): Initializing
2019-03-22T22:50:04.710Z,1553295004.710 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T22:50:04.772Z,1553295004.772 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2019-03-22T22:50:04.845Z,1553295004.845 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:06.870Z,1553295006.870 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:50:06.921Z,1553295006.921 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:50:06.922Z,1553295006.922 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:50:07.280Z,1553295007.280 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:50:08.077Z,1553295008.077 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:08.105Z,1553295008.105 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:50:10.909Z,1553295010.909 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:13.737Z,1553295013.737 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:15.353Z,1553295015.353 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:50:15.757Z,1553295015.757 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:17.773Z,1553295017.773 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:19.394Z,1553295019.394 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:50:19.454Z,1553295019.454 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2019-03-22T22:50:19.454Z,1553295019.454 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T22:50:19.804Z,1553295019.804 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:50:20.617Z,1553295020.617 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:50:21.009Z,1553295021.009 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:23.837Z,1553295023.837 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:27.057Z,1553295027.057 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:28.279Z,1553295028.279 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:50:29.889Z,1553295029.889 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:29.914Z,1553295029.914 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-22T22:45:29.9Z
2019-03-22T22:50:29.914Z,1553295029.914 [Default:CheckIn:Read_GPS] Stopped
2019-03-22T22:50:29.914Z,1553295029.914 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-22T22:50:30.311Z,1553295030.311 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:50:30.322Z,1553295030.322 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-22T22:50:31.922Z,1553295031.922 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:50:31.967Z,1553295031.967 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:50:31.967Z,1553295031.967 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:50:32.329Z,1553295032.329 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B97,09,3ACC,0A,FFF9,0B,0002%38
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,FFFF%32
$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,3A69,0A,FFD1,0B,FFCF%38
$B12,0C,0001,0D,0038,0E,0034,0F,0D94,10,189B,11,1155,12,10A0%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,0B97,09,3ACE,0A,0000,0B,0000%47
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,0B95,09,3AB9,0A,0000,0B,0000%3B
$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,0004%30
$B18,0C,0001,0D,003E,0E,0039,0F,0F17,10,1880,11,FFFF,12,FFFF%41
$B18,13,8D27,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%40
$B18,1A,0031,1B,458F,1C,01AC%44
$B17,02,000A,01,02A8,03,0001,08,0B96,09,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:50:33.121Z,1553295033.121 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:33.149Z,1553295033.149 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:50:35.146Z,1553295035.146 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:50:35.251Z,1553295035.251 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:50:35.251Z,1553295035.251 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:50:35.556Z,1553295035.556 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:50:35.820Z,1553295035.820 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20190322T224142/Courier0004.lzma
2019-03-22T22:50:35.949Z,1553295035.949 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:36.389Z,1553295036.389 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:50:36.626Z,1553295036.626 [DataOverHttps](INFO): Moved sent file to Logs/20190322T224142/Courier0004.lzma.bak
2019-03-22T22:50:36.626Z,1553295036.626 [DataOverHttps](INFO): SBD MOMSN=10217022
2019-03-22T22:50:38.777Z,1553295038.777 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:41.206Z,1553295041.206 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:50:42.013Z,1553295042.013 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:44.041Z,1553295044.041 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:50:44.845Z,1553295044.845 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:44.866Z,1553295044.866 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:50:44.911Z,1553295044.911 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:50:44.911Z,1553295044.911 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:50:45.262Z,1553295045.262 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3ACC,0A,FFF9,0B,FFFD%37
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,FFFF%32
$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,3A67,0A,FFD0,0B,FFCF%37
$B12,0C,0001,0D,0038,0E,0034,0F,0D94,10,189B,11,10F9,12,10A0%4F
$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,0B97,09,3ACE,0A,0000,0B,0000%47
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,1717,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,0001%35
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:50:46.065Z,1553295046.065 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:50:48.069Z,1553295048.069 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:48.633Z,1553295048.633 [DataOverHttps](INFO): Sending 617 bytes from file Logs/20190322T224033/Express0001.lzma
2019-03-22T22:50:48.904Z,1553295048.904 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:50:48.966Z,1553295048.966 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:50:48.967Z,1553295048.967 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:50:49.292Z,1553295049.292 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:50:49.438Z,1553295049.438 [DataOverHttps](INFO): Moved sent file to Logs/20190322T224033/Express0001.lzma.bak
2019-03-22T22:50:49.438Z,1553295049.438 [DataOverHttps](INFO): SBD MOMSN=10217025
2019-03-22T22:50:50.113Z,1553295050.113 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:50:50.905Z,1553295050.905 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:53.325Z,1553295053.325 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:50:53.725Z,1553295053.725 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:55.753Z,1553295055.753 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:56.575Z,1553295056.575 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:50:56.625Z,1553295056.625 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:50:56.626Z,1553295056.626 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:50:56.964Z,1553295056.964 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3ACC,0A,FFF9,0B,FFFB%31
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,AB9C%4B
$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,3A67,0A,FFCF,0B,FFCE%45
$B12,0C,0001,0D,0038,0E,0034,0F,0D94,10,189B,11,10A0,12,104B%46
$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,3ACE,0A,0000,0B,0000%48
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,3AB9,0A,0000,0B,0000%3B
$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,3B82,0A,0000,0B,0001%36
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:50:57.765Z,1553295057.765 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:50:57.786Z,1553295057.786 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:50:58.201Z,1553295058.201 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:50:59.785Z,1553295059.785 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:00.830Z,1553295060.830 [DataOverHttps](INFO): Sending 1064 bytes from file Logs/20190322T224142/Express0001.lzma
2019-03-22T22:51:01.634Z,1553295061.634 [DataOverHttps](INFO): Moved sent file to Logs/20190322T224142/Express0001.lzma.bak
2019-03-22T22:51:01.634Z,1553295061.634 [DataOverHttps](INFO): SBD MOMSN=10217039
2019-03-22T22:51:02.618Z,1553295062.618 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:51:02.676Z,1553295062.676 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:51:02.676Z,1553295062.676 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:51:03.025Z,1553295063.025 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:03.046Z,1553295063.046 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:51:03.853Z,1553295063.853 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:51:05.845Z,1553295065.845 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:05.861Z,1553295065.861 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:51:09.085Z,1553295069.085 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:09.503Z,1553295069.503 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:51:09.550Z,1553295069.550 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:51:09.550Z,1553295069.550 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:51:09.906Z,1553295069.906 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3ACD,0A,FFF9,0B,FFFA%35
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,8F02%4E
$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,3A68,0A,FFD0,0B,FFCF%38
$B12,0C,0001,0D,0038,0E,0034,0F,0D94,10,189B,11,10F9,12,10A0%4F
$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,3ACE,0A,0000,0B,0000%48
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,3ABA,0A,0000,0B,0000%40
$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,3AB9,0A,0000,0B,0000%3B
$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,0000%34
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:51:10.709Z,1553295070.709 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:51:11.506Z,1553295071.506 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:51:11.905Z,1553295071.905 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:13.585Z,1553295073.585 [DataOverHttps](INFO): Sending 462 bytes from file Logs/20190322T224142/Express0005.lzma
2019-03-22T22:51:14.390Z,1553295074.390 [DataOverHttps](INFO): Moved sent file to Logs/20190322T224142/Express0005.lzma.bak
2019-03-22T22:51:14.390Z,1553295074.390 [DataOverHttps](INFO): SBD MOMSN=10217072
2019-03-22T22:51:14.741Z,1553295074.741 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:15.161Z,1553295075.161 [Default:CheckIn:Read_Iridium] Stopped
2019-03-22T22:51:15.161Z,1553295075.161 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-22T22:51:15.161Z,1553295075.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-22T22:51:16.354Z,1553295076.354 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:51:16.471Z,1553295076.471 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:51:16.471Z,1553295076.471 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:51:16.757Z,1553295076.757 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:16.777Z,1553295076.777 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:51:17.593Z,1553295077.593 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:51:17.970Z,1553295077.970 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:51:18.793Z,1553295078.793 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:21.603Z,1553295081.603 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:51:21.655Z,1553295081.655 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:51:21.655Z,1553295081.655 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:51:22.005Z,1553295082.005 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:22.023Z,1553295082.023 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3ACC,0A,FFF9,0B,FFF9%4A
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,7A94%49
$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,3A67,0A,FFCF,0B,FFCF%46
$B12,0C,0001,0D,0038,0E,0034,0F,0D94,10,189B,11,10A0,12,10A0%41
$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,3ACE,0A,0000,0B,0000%48
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,0B92,09,3B81,0A,0000,0B,0000%34
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:51:23.237Z,1553295083.237 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:51:24.841Z,1553295084.841 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:25.259Z,1553295085.259 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:51:28.069Z,1553295088.069 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:29.689Z,1553295089.689 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:51:29.747Z,1553295089.747 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:51:29.748Z,1553295089.748 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:51:30.101Z,1553295090.101 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:51:30.513Z,1553295090.513 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:51:30.893Z,1553295090.893 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:30.925Z,1553295090.925 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:51:33.731Z,1553295093.731 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:51:33.777Z,1553295093.777 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:51:33.777Z,1553295093.777 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:51:34.125Z,1553295094.125 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:34.145Z,1553295094.145 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3ACB,0A,FFF9,0B,FFF9%4B
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,7A94%49
$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,3A67,0A,FFD0,0B,FFCF%37
$B12,0C,0001,0D,0038,0E,0034,0F,0D94,10,189B,11,10F9,12,10A0%4F
$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,3ACE,0A,0000,0B,0000%48
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,3AB9,0A,0000,0B,0000%3B
$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,0000%35
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:51:35.361Z,1553295095.361 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:51:36.961Z,1553295096.961 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:38.169Z,1553295098.169 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:51:39.789Z,1553295099.789 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:43.021Z,1553295103.021 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:43.035Z,1553295103.035 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:51:43.107Z,1553295103.107 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:51:43.107Z,1553295103.107 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:51:43.107Z,1553295103.107 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:51:43.446Z,1553295103.446 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:51:44.245Z,1553295104.245 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:51:45.841Z,1553295105.841 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:46.272Z,1553295106.272 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:51:46.314Z,1553295106.314 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:51:46.315Z,1553295106.315 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:51:46.665Z,1553295106.665 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3ACB,0A,FFF9,0B,FFF9%4B
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,7A94%49
$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,3A66,0A,FFCA,0B,FFCC%45
$B12,0C,0001,0D,0038,0E,0034,0F,0D93,10,189B,11,0F5E,12,0FAA%36
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%33
$B12,1A,1717,1B,458F,1C,01AA%4E
$B15,02,000A,01,02A8,03,0001,08,0B98,09,3ACE,0A,0000,0B,0000%48
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,0B97,09,3AB9,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,0B95,09,3AB9,0A,0000,0B,0000%3A
$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,1717%35
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:51:47.473Z,1553295107.473 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:51:49.081Z,1553295109.081 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:51.501Z,1553295111.501 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:51:51.909Z,1553295111.909 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:54.729Z,1553295114.729 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:55.559Z,1553295115.559 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:51:56.349Z,1553295116.349 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:51:56.416Z,1553295116.416 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:51:56.416Z,1553295116.416 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:51:56.420Z,1553295116.420 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:51:56.420Z,1553295116.420 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-03-22T22:51:56.420Z,1553295116.420 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-22T22:51:56.422Z,1553295116.422 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:51:56.422Z,1553295116.422 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-03-22T22:51:56.422Z,1553295116.422 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-22T22:51:56.432Z,1553295116.432 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-22T22:51:56.432Z,1553295116.432 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-22T22:51:56.749Z,1553295116.749 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:56.770Z,1553295116.770 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:51:56.817Z,1553295116.817 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-22T22:51:56.817Z,1553295116.817 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-03-22T22:51:56.817Z,1553295116.817 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-22T22:51:56.817Z,1553295116.817 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-03-22T22:51:57.199Z,1553295117.199 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T22:51:57.605Z,1553295117.605 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:51:58.777Z,1553295118.777 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:51:58.791Z,1553295118.791 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:51:58.835Z,1553295118.835 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:51:58.835Z,1553295118.835 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:51:59.199Z,1553295119.199 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3ACC,0A,FFF9,0B,FFF9%4A
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,7A94%49
$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,3A66,0A,FFCF,0B,FFCD%45
$B12,0C,0001,0D,0038,0E,0034,0F,0D93,10,189B,11,109F,12,0FF8%30
$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,3ACD,0A,0000,0B,0000%49
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,0B95,09,3AB9,0A,0000,0B,0000%3A
$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,0000%35
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:52:00.001Z,1553295120.001 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:52:02.005Z,1553295122.005 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:04.813Z,1553295124.813 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T22:52:04.813Z,1553295124.813 [Rowe_600LCM] Communications Fault, FailCount= 5
2019-03-22T22:52:04.813Z,1553295124.813 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T22:52:04.837Z,1553295124.837 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:04.865Z,1553295124.865 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T22:52:04.865Z,1553295124.865 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2019-03-22T22:52:05.217Z,1553295125.217 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T22:52:05.656Z,1553295125.656 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:52:07.258Z,1553295127.258 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:52:08.069Z,1553295128.069 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:10.494Z,1553295130.494 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:52:10.564Z,1553295130.564 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:52:10.564Z,1553295130.564 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:52:10.565Z,1553295130.565 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:52:10.607Z,1553295130.607 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:52:10.607Z,1553295130.607 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:52:10.889Z,1553295130.889 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:10.911Z,1553295130.911 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:52:10.914Z,1553295130.914 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B99,09,3ACC,0A,FFF9,0B,FFF9%4B
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,7A94%49
$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,3A66,0A,FFD0,0B,FFCE%35
$B12,0C,0001,0D,0038,0E,0034,0F,0D93,10,189B,11,10F8,12,104A%4D
$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,3ACE,0A,0000,0B,0000%48
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,0B97,09,3AB9,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,0B95,09,3ABA,0A,0000,0B,0000%42
$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,3B82,0A,0000,0B,0000%36
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:52:11.729Z,1553295131.729 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:52:11.741Z,1553295131.741 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:52:14.121Z,1553295134.121 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:16.949Z,1553295136.949 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:18.980Z,1553295138.980 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:52:19.376Z,1553295139.376 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:52:19.785Z,1553295139.785 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:22.638Z,1553295142.638 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:52:22.682Z,1553295142.682 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:52:22.683Z,1553295142.683 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:52:23.017Z,1553295143.017 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:23.039Z,1553295143.039 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B99,09,3ACB,0A,FFF9,0B,FFF9%4A
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,7A94%49
$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,3A66,0A,FFD0,0B,FFCF%36
$B12,0C,0001,0D,0038,0E,0034,0F,0D93,10,189B,11,10F8,12,109F%47
$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,3ACE,0A,0000,0B,0000%48
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,0B97,09,3AB8,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,0B95,09,3ABA,0A,0000,0B,0000%42
$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,0000%35
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:52:23.832Z,1553295143.832 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:52:23.899Z,1553295143.899 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:52:23.899Z,1553295143.899 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:52:23.913Z,1553295143.913 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:52:24.232Z,1553295144.232 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:52:25.049Z,1553295145.049 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:52:25.845Z,1553295145.845 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:29.077Z,1553295149.077 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:31.497Z,1553295151.497 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:52:31.897Z,1553295151.897 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:32.320Z,1553295152.320 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:52:34.729Z,1553295154.729 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:34.743Z,1553295154.743 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:52:34.796Z,1553295154.796 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:52:34.796Z,1553295154.796 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:52:35.150Z,1553295155.150 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B99,09,3ACB,0A,FFF9,0B,FFF9%4A
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,7A94%49
$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,3A65,0A,FFCF,0B,FFCF%44
$B12,0C,0001,0D,0038,0E,0034,0F,0D93,10,189B,11,109F,12,109F%46
$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,3ACE,0A,0000,0B,0000%48
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,0B97,09,3AB8,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,0B95,09,3AB9,0A,0000,0B,0000%3A
$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,0000%35
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:52:36.361Z,1553295156.361 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:52:36.753Z,1553295156.753 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:37.168Z,1553295157.168 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:52:37.237Z,1553295157.237 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:52:37.238Z,1553295157.238 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:52:37.574Z,1553295157.574 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:52:38.377Z,1553295158.377 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:52:38.769Z,1553295158.769 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:40.785Z,1553295160.785 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:44.025Z,1553295164.025 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:44.039Z,1553295164.039 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:52:45.639Z,1553295165.639 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:52:46.849Z,1553295166.849 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:47.271Z,1553295167.271 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:52:47.323Z,1553295167.323 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:52:47.323Z,1553295167.323 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:52:47.670Z,1553295167.670 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B99,09,3ACB,0A,FFF9,0B,FFF9%4A
$B11,0C,0001,0D,003A,0E,0036,0F,0E4D,10,18E2,11,7A94,12,7A94%49
$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,3A65,0A,FFCB,0B,FFCE%43
$B12,0C,0001,0D,0038,0E,0034,0F,0D93,10,189B,11,0F5E,12,104A%34
$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,3ACF,0A,0000,0B,0000%4B
$B15,0C,0001,0D,003C,0E,003A,0F,0F2D,10,196E,11,FFFF,12,FFFF%38
$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,0B97,09,3AB8,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,0B95,09,3AB9,0A,0000,0B,0000%3A
$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,3B80,0A,0000,0B,0000%34
$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,3AAE,0A,0000,0B,0000%46
$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,3B7F,0A,0000,0B,0000%4E
$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-22T22:52:48.885Z,1553295168.885 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:52:50.085Z,1553295170.085 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:50.499Z,1553295170.499 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:52:50.566Z,1553295170.566 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:52:50.566Z,1553295170.566 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:52:50.906Z,1553295170.906 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:52:51.717Z,1553295171.717 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:52:51.717Z,1553295171.717 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:52:52.905Z,1553295172.905 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:54.529Z,1553295174.529 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:52:54.541Z,1553295174.541 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:52:54.542Z,1553295174.542 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:52:54.940Z,1553295174.940 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:52:55.741Z,1553295175.741 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:55.773Z,1553295175.773 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:52:57.753Z,1553295177.753 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:52:58.970Z,1553295178.970 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:52:59.781Z,1553295179.781 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:53:03.005Z,1553295183.005 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:53:03.828Z,1553295183.828 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:53:03.950Z,1553295183.950 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:53:03.950Z,1553295183.950 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:53:04.228Z,1553295184.228 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:53:05.045Z,1553295185.045 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:53:05.833Z,1553295185.833 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:53:06.259Z,1553295186.259 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:53:09.073Z,1553295189.073 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:53:09.891Z,1553295189.891 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:53:09.944Z,1553295189.944 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:53:09.944Z,1553295189.944 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:53:10.285Z,1553295190.285 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:53:11.105Z,1553295191.105 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:53:11.901Z,1553295191.901 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:53:12.322Z,1553295192.322 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:53:14.725Z,1553295194.725 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:53:16.749Z,1553295196.749 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:53:17.164Z,1553295197.164 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:53:17.231Z,1553295197.231 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:53:17.231Z,1553295197.231 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:53:17.570Z,1553295197.570 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:53:18.377Z,1553295198.377 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:53:18.761Z,1553295198.761 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:53:20.785Z,1553295200.785 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:53:21.203Z,1553295201.203 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:53:23.229Z,1553295203.229 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:53:23.229Z,1553295203.229 [PNI_TCM] No Fault, FailCount= 5
2019-03-22T22:53:24.007Z,1553295204.007 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-03-22T22:53:24.007Z,1553295204.007 [NAL9602] Data Fault, FailCount= 3
2019-03-22T22:53:24.007Z,1553295204.007 [NAL9602](ERROR): Data Fault
2019-03-22T22:53:24.036Z,1553295204.036 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-22T22:53:24.406Z,1553295204.406 [NAL9602](INFO): Powering down
2019-03-22T22:53:24.827Z,1553295204.827 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:53:24.867Z,1553295204.867 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:53:24.868Z,1553295204.868 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:53:25.237Z,1553295205.237 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:53:25.251Z,1553295205.251 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-22T22:53:25.251Z,1553295205.251 [NAL9602] No Fault, FailCount= 3
2019-03-22T22:53:26.040Z,1553295206.040 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:53:26.053Z,1553295206.053 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:53:30.888Z,1553295210.888 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:53:30.888Z,1553295210.888 [PNI_TCM] Data Fault, FailCount= 1
2019-03-22T22:53:30.888Z,1553295210.888 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:53:30.889Z,1553295210.889 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:53:30.968Z,1553295210.968 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:53:30.968Z,1553295210.968 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:53:30.982Z,1553295210.982 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:53:31.289Z,1553295211.289 [PNI_TCM](INFO): Powering down
2019-03-22T22:53:31.384Z,1553295211.384 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:53:32.113Z,1553295212.113 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:53:32.522Z,1553295212.522 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:53:32.522Z,1553295212.522 [PNI_TCM] No Fault, FailCount= 1
2019-03-22T22:53:36.146Z,1553295216.146 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:53:39.773Z,1553295219.773 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:53:39.773Z,1553295219.773 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:53:39.832Z,1553295219.832 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:53:39.832Z,1553295219.832 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:53:40.180Z,1553295220.180 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:53:40.180Z,1553295220.180 [PNI_TCM] Data Fault, FailCount= 2
2019-03-22T22:53:40.180Z,1553295220.180 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:53:40.186Z,1553295220.186 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:53:40.238Z,1553295220.238 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:53:40.580Z,1553295220.580 [PNI_TCM](INFO): Powering down
2019-03-22T22:53:41.001Z,1553295221.001 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:53:41.810Z,1553295221.810 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:53:41.810Z,1553295221.810 [PNI_TCM] No Fault, FailCount= 2
2019-03-22T22:53:44.236Z,1553295224.236 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:53:44.302Z,1553295224.302 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:53:44.302Z,1553295224.302 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:53:44.627Z,1553295224.627 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:53:45.445Z,1553295225.445 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:53:49.476Z,1553295229.476 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:53:49.476Z,1553295229.476 [PNI_TCM] Data Fault, FailCount= 3
2019-03-22T22:53:49.476Z,1553295229.476 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:53:49.490Z,1553295229.490 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:53:49.869Z,1553295229.869 [PNI_TCM](INFO): Powering down
2019-03-22T22:53:51.102Z,1553295231.102 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:53:51.102Z,1553295231.102 [PNI_TCM] No Fault, FailCount= 3
2019-03-22T22:53:51.893Z,1553295231.893 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:53:52.707Z,1553295232.707 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:53:54.710Z,1553295234.710 [NAL9602](INFO): Powering up NAL9602
2019-03-22T22:53:55.125Z,1553295235.125 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:53:55.166Z,1553295235.166 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:53:55.167Z,1553295235.167 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:53:55.535Z,1553295235.535 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:53:56.357Z,1553295236.357 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:53:57.150Z,1553295237.150 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:53:57.220Z,1553295237.220 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:53:57.220Z,1553295237.220 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:53:57.224Z,1553295237.224 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:53:57.224Z,1553295237.224 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-03-22T22:53:57.224Z,1553295237.224 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-22T22:53:57.226Z,1553295237.226 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:53:57.226Z,1553295237.226 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-03-22T22:53:57.226Z,1553295237.226 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-22T22:53:57.236Z,1553295237.236 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-22T22:53:57.236Z,1553295237.236 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-22T22:53:57.555Z,1553295237.555 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:53:57.572Z,1553295237.572 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-22T22:53:57.573Z,1553295237.573 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-03-22T22:53:57.573Z,1553295237.573 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-22T22:53:57.573Z,1553295237.573 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-03-22T22:53:57.968Z,1553295237.968 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T22:53:58.373Z,1553295238.373 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:53:58.761Z,1553295238.761 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:53:58.761Z,1553295238.761 [PNI_TCM] Data Fault, FailCount= 4
2019-03-22T22:53:58.761Z,1553295238.761 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:53:58.782Z,1553295238.782 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:53:59.161Z,1553295239.161 [PNI_TCM](INFO): Powering down
2019-03-22T22:54:00.407Z,1553295240.407 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-03-22T22:54:00.407Z,1553295240.407 [PNI_TCM] No Fault, FailCount= 4
2019-03-22T22:54:05.619Z,1553295245.619 [NAL9602](INFO): NAL9602 initialized
2019-03-22T22:54:05.634Z,1553295245.634 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:54:06.441Z,1553295246.441 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:54:06.860Z,1553295246.860 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:54:08.053Z,1553295248.053 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-03-22T22:54:08.053Z,1553295248.053 [PNI_TCM] Data Fault, FailCount= 5
2019-03-22T22:54:08.053Z,1553295248.053 [PNI_TCM](ERROR): Data Fault
2019-03-22T22:54:08.074Z,1553295248.074 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-03-22T22:54:08.074Z,1553295248.074 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-03-22T22:54:08.462Z,1553295248.462 [PNI_TCM](INFO): Powering down
2019-03-22T22:54:08.567Z,1553295248.567 [CBIT](INFO): Critical error at 20190322T225408
2019-03-22T22:54:09.269Z,1553295249.269 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:54:10.481Z,1553295250.481 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:54:10.553Z,1553295250.553 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:54:10.554Z,1553295250.554 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:54:10.554Z,1553295250.554 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:54:10.612Z,1553295250.612 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:54:10.612Z,1553295250.612 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:54:10.887Z,1553295250.887 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:54:10.893Z,1553295250.893 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:54:11.705Z,1553295251.705 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:54:11.717Z,1553295251.717 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:54:19.369Z,1553295259.369 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:54:22.198Z,1553295262.198 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:54:24.217Z,1553295264.217 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:54:24.285Z,1553295264.285 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:54:24.285Z,1553295264.285 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:54:24.628Z,1553295264.628 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:54:25.441Z,1553295265.441 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:54:25.833Z,1553295265.833 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:54:25.874Z,1553295265.874 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:54:25.874Z,1553295265.874 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:54:26.239Z,1553295266.239 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:54:27.061Z,1553295267.061 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:54:33.102Z,1553295273.102 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:54:37.549Z,1553295277.549 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:54:37.949Z,1553295277.949 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:54:38.034Z,1553295278.034 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:54:38.034Z,1553295278.034 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:54:38.361Z,1553295278.361 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:54:39.177Z,1553295279.177 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:54:40.053Z,1553295280.053 [NAL9602](ERROR): getFix uart error: serial timeout
2019-03-22T22:54:40.930Z,1553295280.930 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:54:40.974Z,1553295280.974 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:54:40.974Z,1553295280.974 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:54:41.338Z,1553295281.338 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:54:42.157Z,1553295282.157 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:54:46.992Z,1553295286.992 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:54:51.841Z,1553295291.841 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:54:51.901Z,1553295291.901 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:54:51.901Z,1553295291.901 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:54:52.251Z,1553295292.251 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:54:52.657Z,1553295292.657 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:54:53.065Z,1553295293.065 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:54:56.290Z,1553295296.290 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:54:56.331Z,1553295296.331 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:54:56.332Z,1553295296.332 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:54:56.690Z,1553295296.690 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:54:57.509Z,1553295297.509 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:55:00.325Z,1553295300.325 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:55:05.173Z,1553295305.173 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:55:05.231Z,1553295305.231 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:55:05.231Z,1553295305.231 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:55:05.576Z,1553295305.576 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:55:06.401Z,1553295306.401 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:55:08.000Z,1553295308.000 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:55:10.410Z,1553295310.410 [NAL9602](ERROR): parseGSV uart error: serial timeout
2019-03-22T22:55:11.229Z,1553295311.229 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:55:11.637Z,1553295311.637 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:55:11.689Z,1553295311.689 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with LONG_IBPS_MENU.
2019-03-22T22:55:11.690Z,1553295311.690 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T22:55:12.038Z,1553295312.038 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:55:12.861Z,1553295312.861 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:55:13.656Z,1553295313.656 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:55:14.869Z,1553295314.869 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:55:18.505Z,1553295318.505 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:55:18.572Z,1553295318.572 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:55:18.573Z,1553295318.573 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:55:18.911Z,1553295318.911 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:55:19.321Z,1553295319.321 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:55:19.337Z,1553295319.337 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:55:19.337Z,1553295319.337 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:55:19.733Z,1553295319.733 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:55:19.740Z,1553295319.740 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:55:20.537Z,1553295320.537 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:55:27.389Z,1553295327.389 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:55:28.197Z,1553295328.197 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:55:31.837Z,1553295331.837 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:55:31.905Z,1553295331.905 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:55:31.906Z,1553295331.906 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:55:32.243Z,1553295332.243 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:55:33.061Z,1553295333.061 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:55:33.061Z,1553295333.061 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:55:33.133Z,1553295333.133 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:55:33.134Z,1553295333.134 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:55:33.457Z,1553295333.457 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:55:34.273Z,1553295334.273 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:55:40.321Z,1553295340.321 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:55:42.021Z,1553295342.021 [NAL9602](ERROR): getFix uart error: serial timeout
2019-03-22T22:55:42.047Z,1553295342.047 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:55:44.941Z,1553295344.941 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:55:45.012Z,1553295345.012 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:55:45.012Z,1553295345.012 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:55:45.347Z,1553295345.347 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:55:46.161Z,1553295346.161 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:55:46.554Z,1553295346.554 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:55:46.612Z,1553295346.612 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:55:46.613Z,1553295346.613 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:55:46.966Z,1553295346.966 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:55:47.781Z,1553295347.781 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:55:53.424Z,1553295353.424 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:55:55.040Z,1553295355.040 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:55:58.269Z,1553295358.269 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:55:58.337Z,1553295358.337 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:55:58.337Z,1553295358.337 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:55:58.341Z,1553295358.341 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:55:58.342Z,1553295358.342 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-03-22T22:55:58.342Z,1553295358.342 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-22T22:55:58.343Z,1553295358.343 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-22T22:55:58.343Z,1553295358.343 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-03-22T22:55:58.343Z,1553295358.343 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-22T22:55:58.353Z,1553295358.353 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-22T22:55:58.354Z,1553295358.354 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-22T22:55:58.688Z,1553295358.688 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:55:58.701Z,1553295358.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-22T22:55:58.701Z,1553295358.701 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-03-22T22:55:58.701Z,1553295358.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-22T22:55:58.701Z,1553295358.701 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-03-22T22:55:59.096Z,1553295359.096 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T22:55:59.493Z,1553295359.493 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:55:59.884Z,1553295359.884 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:56:00.004Z,1553295360.004 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:56:00.005Z,1553295360.005 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:56:00.297Z,1553295360.297 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:56:01.109Z,1553295361.109 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:56:07.160Z,1553295367.160 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:56:08.369Z,1553295368.369 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:56:12.008Z,1553295372.008 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:56:12.077Z,1553295372.077 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:56:12.077Z,1553295372.077 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:56:12.394Z,1553295372.394 [NAL9602](ERROR): parseGSV uart error: serial timeout
2019-03-22T22:56:12.415Z,1553295372.415 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:56:13.217Z,1553295373.217 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:56:13.245Z,1553295373.245 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:56:13.245Z,1553295373.245 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:56:13.316Z,1553295373.316 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:56:13.317Z,1553295373.317 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:56:13.631Z,1553295373.631 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:56:14.449Z,1553295374.449 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:56:15.650Z,1553295375.650 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-22T22:56:15.650Z,1553295375.650 [Default:CheckIn:C.Wait] Stopped
2019-03-22T22:56:15.650Z,1553295375.650 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-22T22:56:15.650Z,1553295375.650 [Default:CheckIn:D] Running Loop=1
2019-03-22T22:56:16.054Z,1553295376.054 [Default:CheckIn:D] Stopped
2019-03-22T22:56:16.054Z,1553295376.054 [Default:CheckIn:E] Running Loop=1
2019-03-22T22:56:16.459Z,1553295376.459 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.997796 min 
2019-03-22T22:56:16.459Z,1553295376.459 [Default:CheckIn:E] Stopped
2019-03-22T22:56:16.459Z,1553295376.459 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-22T22:56:16.459Z,1553295376.459 [Default:CheckIn] Stopped
2019-03-22T22:56:16.459Z,1553295376.459 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-22T22:56:16.459Z,1553295376.459 [Default:CheckIn](INFO): Running loop #2
2019-03-22T22:56:16.460Z,1553295376.460 [Default:CheckIn] Running Loop=2
2019-03-22T22:56:16.460Z,1553295376.460 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-22T22:56:16.460Z,1553295376.460 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-22T22:56:20.892Z,1553295380.892 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:56:22.109Z,1553295382.109 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:56:25.341Z,1553295385.341 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:56:25.409Z,1553295385.409 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:56:25.409Z,1553295385.409 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:56:25.746Z,1553295385.746 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:56:26.565Z,1553295386.565 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:56:26.957Z,1553295386.957 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:56:27.027Z,1553295387.027 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:56:27.028Z,1553295387.028 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:56:27.363Z,1553295387.363 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:56:28.185Z,1553295388.185 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:56:34.632Z,1553295394.632 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:56:35.437Z,1553295395.437 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:56:39.480Z,1553295399.480 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:56:39.551Z,1553295399.551 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:56:39.551Z,1553295399.551 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:56:39.887Z,1553295399.887 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:56:39.888Z,1553295399.888 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:56:39.978Z,1553295399.978 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:56:39.979Z,1553295399.979 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:56:40.308Z,1553295400.308 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:56:40.701Z,1553295400.701 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:56:41.513Z,1553295401.513 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:56:44.005Z,1553295404.005 [NAL9602](ERROR): getFix uart error: serial timeout
2019-03-22T22:56:48.117Z,1553295408.117 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:56:49.330Z,1553295409.330 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:56:52.969Z,1553295412.969 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:56:53.027Z,1553295413.027 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:56:53.028Z,1553295413.028 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:56:53.383Z,1553295413.383 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:56:53.781Z,1553295413.781 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:56:53.851Z,1553295413.851 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:56:53.851Z,1553295413.851 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:56:54.189Z,1553295414.189 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:56:54.196Z,1553295414.196 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:56:54.997Z,1553295414.997 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:57:01.449Z,1553295421.449 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:57:02.279Z,1553295422.279 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:57:06.297Z,1553295426.297 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:57:06.358Z,1553295426.358 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:57:06.359Z,1553295426.359 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:57:06.707Z,1553295426.707 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:57:06.707Z,1553295426.707 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:57:06.766Z,1553295426.766 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:57:06.766Z,1553295426.766 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:57:07.119Z,1553295427.119 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:57:07.517Z,1553295427.517 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:57:07.957Z,1553295427.957 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:57:14.370Z,1553295434.370 [NAL9602](ERROR): parseGSV uart error: serial timeout
2019-03-22T22:57:14.384Z,1553295434.384 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:57:15.181Z,1553295435.181 [NAL9602](DEBUG): Fix Requested
2019-03-22T22:57:15.589Z,1553295435.589 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T22:57:19.224Z,1553295439.224 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T22:57:19.298Z,1553295439.298 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T22:57:19.298Z,1553295439.298 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T22:57:19.631Z,1553295439.631 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T22:57:20.045Z,1553295440.045 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T22:57:20.124Z,1553295440.124 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2019-03-22T22:57:20.124Z,1553295440.124 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T22:57:20.449Z,1553295440.449 [BPC1](INFO): BPC1A: initialized.
2019-03-22T22:57:20.456Z,1553295440.456 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T22:57:21.253Z,1553295441.253 [BPC1](INFO): BPC1B: initialized.
2019-03-22T22:57:23.111Z,1553295443.111 [CommandLine](IMPORTANT): got command configSet PNI_TCM.loadAtStartup 1.000000 bool persist
2019-03-22T22:57:23.111Z,1553295443.111 [CommandLine](IMPORTANT): configSet PNI_TCM.loadAtStartup requires a restart to take effect.
2019-03-22T22:57:26.446Z,1553295446.446 [CommandLine](IMPORTANT): got command restart application
2019-03-22T22:57:27.453Z,1553295447.453 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T22:57:27.453Z,1553295447.453 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:27.517Z,1553295447.517 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-22T22:57:27.517Z,1553295447.517 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:27.518Z,1553295447.518 [CommandLine](INFO): Join timeout helper Thread ID is 6000
2019-03-22T22:57:27.518Z,1553295447.518 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-22T22:57:27.518Z,1553295447.518 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:27.519Z,1553295447.519 [NavChartDb](INFO): Join timeout helper Thread ID is 6001
2019-03-22T22:57:27.569Z,1553295447.569 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T22:57:27.569Z,1553295447.569 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:27.577Z,1553295447.577 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-03-22T22:57:27.577Z,1553295447.577 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:27.577Z,1553295447.577 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6002
2019-03-22T22:57:27.709Z,1553295447.709 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T22:57:27.709Z,1553295447.709 [WetLabsBB2FL](INFO): Powering down
2019-03-22T22:57:27.710Z,1553295447.710 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:27.721Z,1553295447.721 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-22T22:57:27.721Z,1553295447.721 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:27.721Z,1553295447.721 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6003
2019-03-22T22:57:27.881Z,1553295447.881 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T22:57:27.881Z,1553295447.881 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:27.897Z,1553295447.897 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-03-22T22:57:27.897Z,1553295447.897 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:27.897Z,1553295447.897 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6004
2019-03-22T22:57:28.112Z,1553295448.112 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T22:57:28.209Z,1553295448.209 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T22:57:28.209Z,1553295448.209 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T22:57:28.298Z,1553295448.298 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.302Z,1553295448.302 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-22T22:57:28.302Z,1553295448.302 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.303Z,1553295448.303 [Radio_Surface](INFO): Join timeout helper Thread ID is 6006
2019-03-22T22:57:28.425Z,1553295448.425 [Radio_Surface](INFO): Powering down
2019-03-22T22:57:28.426Z,1553295448.426 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T22:57:28.426Z,1553295448.426 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.430Z,1553295448.430 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-22T22:57:28.430Z,1553295448.430 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.430Z,1553295448.430 [DataOverHttps](INFO): Join timeout helper Thread ID is 6008
2019-03-22T22:57:28.749Z,1553295448.749 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T22:57:28.749Z,1553295448.749 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.754Z,1553295448.754 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-22T22:57:28.754Z,1553295448.754 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.754Z,1553295448.754 [logger](INFO): Join timeout helper Thread ID is 6009
2019-03-22T22:57:28.754Z,1553295448.754 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T22:57:28.755Z,1553295448.755 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.756Z,1553295448.756 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-22T22:57:28.756Z,1553295448.756 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.756Z,1553295448.756 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-22T22:57:28.756Z,1553295448.756 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.757Z,1553295448.757 [controlThread](INFO): Join timeout helper Thread ID is 6010
2019-03-22T22:57:28.893Z,1553295448.893 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T22:57:28.893Z,1553295448.893 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-22T22:57:28.894Z,1553295448.894 [NAL9602](INFO): Powering down
2019-03-22T22:57:28.895Z,1553295448.895 [PNI_TCM](INFO): Powering down
2019-03-22T22:57:28.896Z,1553295448.896 [BPC1](INFO): uninitialize
2019-03-22T22:57:28.897Z,1553295448.897 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-22T22:57:28.898Z,1553295448.898 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-22T22:57:28.898Z,1553295448.898 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-22T22:57:28.898Z,1553295448.898 [MissionManager](INFO): Uninitializing Mission Default
2019-03-22T22:57:28.899Z,1553295448.899 [Default] Stopped
2019-03-22T22:57:28.899Z,1553295448.899 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-22T22:57:28.899Z,1553295448.899 [Default:B.GoToSurface] Stopped
2019-03-22T22:57:28.899Z,1553295448.899 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-22T22:57:28.899Z,1553295448.899 [Default:CheckIn] Stopped
2019-03-22T22:57:28.899Z,1553295448.899 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-22T22:57:28.899Z,1553295448.899 [Default:CheckIn:Read_GPS] Stopped
2019-03-22T22:57:28.902Z,1553295448.902 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-22T22:57:28.902Z,1553295448.902 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-22T22:57:28.903Z,1553295448.903 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-22T22:57:28.903Z,1553295448.903 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-22T22:57:28.903Z,1553295448.903 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-22T22:57:28.903Z,1553295448.903 [ElevatorServo](INFO): Powering down
2019-03-22T22:57:28.904Z,1553295448.904 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-22T22:57:28.904Z,1553295448.904 [MassServo](INFO): Powering down
2019-03-22T22:57:28.906Z,1553295448.906 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-22T22:57:28.906Z,1553295448.906 [ThrusterServo](INFO): Powering down
2019-03-22T22:57:28.907Z,1553295448.907 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-22T22:57:28.907Z,1553295448.907 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-22T22:57:28.907Z,1553295448.907 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-22T22:57:28.907Z,1553295448.907 [CBIT](DEBUG): Powering off loads.
2019-03-22T22:57:28.921Z,1553295448.921 [CBIT](DEBUG): Disabling WDT.
2019-03-22T22:57:28.933Z,1553295448.933 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-22T22:57:28.933Z,1553295448.933 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:28.992Z,1553295448.992 [BPC1](INFO): uninitialize
2019-03-22T22:57:29.031Z,1553295449.031 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:29.036Z,1553295449.036 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:29.041Z,1553295449.041 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:29.074Z,1553295449.074 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:29.077Z,1553295449.077 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:29.110Z,1553295449.110 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T22:57:29.168Z,1553295449.168 [logger ThreadHandler](INFO): Thread cancelled.