2018-09-18T20:52:25.436Z,1537303945.436 [Supervisor](DEBUG): Initializing supervisor.
2018-09-18T20:52:25.439Z,1537303945.439 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-18T20:52:25.440Z,1537303945.440 [SyncHandler](INFO): Protected caller Thread ID is 358
2018-09-18T20:52:25.441Z,1537303945.441 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-18T20:52:25.441Z,1537303945.441 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-18T20:52:25.442Z,1537303945.442 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 359
2018-09-18T20:52:25.445Z,1537303945.445 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-18T20:52:25.457Z,1537303945.457 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-18T20:52:25.458Z,1537303945.458 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-18T20:52:25.459Z,1537303945.459 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 360
2018-09-18T20:52:25.459Z,1537303945.459 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-18T20:52:25.460Z,1537303945.460 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-18T20:52:25.461Z,1537303945.461 [logger ThreadHandler](INFO): Protected caller Thread ID is 361
2018-09-18T20:52:25.463Z,1537303945.463 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-18T20:52:25.463Z,1537303945.463 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-18T20:52:25.465Z,1537303945.465 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-18T20:52:25.817Z,1537303945.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-18T20:52:25.817Z,1537303945.817 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-18T20:52:26.026Z,1537303946.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-18T20:52:26.026Z,1537303946.026 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-18T20:52:26.338Z,1537303946.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-18T20:52:26.338Z,1537303946.338 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-18T20:52:26.485Z,1537303946.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-18T20:52:26.486Z,1537303946.486 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-18T20:52:26.584Z,1537303946.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-18T20:52:26.585Z,1537303946.585 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-18T20:52:27.077Z,1537303947.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-18T20:52:27.078Z,1537303947.078 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-18T20:52:27.534Z,1537303947.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-18T20:52:27.534Z,1537303947.534 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-18T20:52:28.025Z,1537303948.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-18T20:52:28.025Z,1537303948.025 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-18T20:52:28.131Z,1537303948.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-18T20:52:28.132Z,1537303948.132 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-18T20:52:28.230Z,1537303948.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-18T20:52:28.231Z,1537303948.231 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-18T20:52:28.443Z,1537303948.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-18T20:52:28.444Z,1537303948.444 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-18T20:52:28.652Z,1537303948.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-18T20:52:28.652Z,1537303948.652 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-18T20:52:29.021Z,1537303949.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-18T20:52:29.021Z,1537303949.021 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-18T20:52:29.167Z,1537303949.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-18T20:52:29.168Z,1537303949.168 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-18T20:52:29.250Z,1537303949.250 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-09-18T20:52:29.251Z,1537303949.251 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-09-18T20:52:29.355Z,1537303949.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-09-18T20:52:29.737Z,1537303949.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-09-18T20:52:29.901Z,1537303949.901 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-09-18T20:52:29.996Z,1537303949.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-09-18T20:52:30.158Z,1537303950.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-09-18T20:52:30.341Z,1537303950.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-09-18T20:52:30.417Z,1537303950.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-09-18T20:52:30.653Z,1537303950.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-18T20:52:30.654Z,1537303950.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-09-18T20:52:30.738Z,1537303950.738 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-09-18T20:52:30.829Z,1537303950.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-09-18T20:52:30.932Z,1537303950.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-09-18T20:52:31.063Z,1537303951.063 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-09-18T20:52:31.064Z,1537303951.064 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-09-18T20:52:31.081Z,1537303951.081 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-18T20:52:31.344Z,1537303951.344 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-18T20:52:31.345Z,1537303951.345 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-18T20:52:31.390Z,1537303951.390 [DepthRateCalculator] Loaded
2018-09-18T20:52:31.391Z,1537303951.391 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-18T20:52:31.397Z,1537303951.397 [PitchRateCalculator] Loaded
2018-09-18T20:52:31.397Z,1537303951.397 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-18T20:52:31.414Z,1537303951.414 [SpeedCalculator] Loaded
2018-09-18T20:52:31.414Z,1537303951.414 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-18T20:52:31.437Z,1537303951.437 [TempGradientCalculator] Loaded
2018-09-18T20:52:31.437Z,1537303951.437 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-18T20:52:31.453Z,1537303951.453 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-09-18T20:52:31.453Z,1537303951.453 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-09-18T20:52:31.459Z,1537303951.459 [YawRateCalculator] Loaded
2018-09-18T20:52:31.459Z,1537303951.459 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-18T20:52:31.501Z,1537303951.501 [ElevatorOffsetCalculator] Loaded
2018-09-18T20:52:31.501Z,1537303951.501 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-18T20:52:31.502Z,1537303951.502 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-18T20:52:31.502Z,1537303951.502 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-18T20:52:31.572Z,1537303951.572 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-18T20:52:31.573Z,1537303951.573 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-18T20:52:31.695Z,1537303951.695 [BuoyancyServo] Loaded
2018-09-18T20:52:31.695Z,1537303951.695 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-18T20:52:31.696Z,1537303951.696 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-18T20:52:31.696Z,1537303951.696 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-18T20:52:31.711Z,1537303951.711 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-18T20:52:31.711Z,1537303951.711 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-18T20:52:31.821Z,1537303951.821 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-18T20:52:31.822Z,1537303951.822 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-18T20:52:32.133Z,1537303952.133 [DataOverHttps] Loaded
2018-09-18T20:52:32.134Z,1537303952.134 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-18T20:52:32.148Z,1537303952.148 [Depth_Keller] Loaded
2018-09-18T20:52:32.148Z,1537303952.148 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-18T20:52:32.154Z,1537303952.154 [DropWeight] Loaded
2018-09-18T20:52:32.154Z,1537303952.154 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-18T20:52:32.259Z,1537303952.259 [NAL9602] Loaded
2018-09-18T20:52:32.259Z,1537303952.259 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-18T20:52:32.276Z,1537303952.276 [Onboard] Loaded
2018-09-18T20:52:32.277Z,1537303952.277 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-18T20:52:32.288Z,1537303952.288 [Radio_Surface] Loaded
2018-09-18T20:52:32.288Z,1537303952.288 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-18T20:52:32.289Z,1537303952.289 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088B4E0
2018-09-18T20:52:32.290Z,1537303952.290 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 446
2018-09-18T20:52:32.340Z,1537303952.340 [PNI_TCM] Loaded
2018-09-18T20:52:32.340Z,1537303952.340 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-09-18T20:52:32.363Z,1537303952.363 [BPC1](CRITICAL): No configuration setting for: BPC1.batteryCommsTimout
2018-09-18T20:52:32.367Z,1537303952.367 [BPC1](INFO): BPC1A: got serial number for stick 1: 0138
2018-09-18T20:52:32.368Z,1537303952.368 [BPC1](INFO): BPC1A: got serial number for stick 2: 0125
2018-09-18T20:52:32.369Z,1537303952.369 [BPC1](INFO): BPC1A: got serial number for stick 3: 00EF
2018-09-18T20:52:32.370Z,1537303952.370 [BPC1](INFO): BPC1A: got serial number for stick 4: 0158
2018-09-18T20:52:32.371Z,1537303952.371 [BPC1](INFO): BPC1A: got serial number for stick 5: 0088
2018-09-18T20:52:32.371Z,1537303952.371 [BPC1](INFO): BPC1A: got serial number for stick 6: 00A3
2018-09-18T20:52:32.372Z,1537303952.372 [BPC1](INFO): BPC1A: got serial number for stick 7: 012D
2018-09-18T20:52:32.373Z,1537303952.373 [BPC1](INFO): BPC1A: got serial number for stick 8: 00E7
2018-09-18T20:52:32.374Z,1537303952.374 [BPC1](INFO): BPC1A: got serial number for stick 9: 0100
2018-09-18T20:52:32.375Z,1537303952.375 [BPC1](INFO): BPC1A: got serial number for stick 10: 00B9
2018-09-18T20:52:32.376Z,1537303952.376 [BPC1](INFO): BPC1A: got serial number for stick 11: 008C
2018-09-18T20:52:32.376Z,1537303952.376 [BPC1](INFO): BPC1A: got serial number for stick 12: 0117
2018-09-18T20:52:32.377Z,1537303952.377 [BPC1](INFO): BPC1A: got serial number for stick 13: 00E4
2018-09-18T20:52:32.378Z,1537303952.378 [BPC1](INFO): BPC1A: got serial number for stick 14: 011D
2018-09-18T20:52:32.379Z,1537303952.379 [BPC1](INFO): BPC1A: got serial number for stick 15: 014E
2018-09-18T20:52:32.379Z,1537303952.379 [BPC1](INFO): BPC1A: got serial number for stick 16: 00EC
2018-09-18T20:52:32.380Z,1537303952.380 [BPC1](INFO): BPC1A: got serial number for stick 17: 008A
2018-09-18T20:52:32.381Z,1537303952.381 [BPC1](INFO): BPC1A: got serial number for stick 18: 0140
2018-09-18T20:52:32.382Z,1537303952.382 [BPC1](INFO): BPC1A: got serial number for stick 19: 00E5
2018-09-18T20:52:32.383Z,1537303952.383 [BPC1](INFO): BPC1A: got serial number for stick 20: 00E0
2018-09-18T20:52:32.383Z,1537303952.383 [BPC1](INFO): BPC1A: got serial number for stick 21: 00CD
2018-09-18T20:52:32.384Z,1537303952.384 [BPC1](INFO): BPC1A: got serial number for stick 22: 009F
2018-09-18T20:52:32.385Z,1537303952.385 [BPC1](INFO): BPC1A: got serial number for stick 23: 009C
2018-09-18T20:52:32.386Z,1537303952.386 [BPC1](INFO): BPC1A: got serial number for stick 24: 00A6
2018-09-18T20:52:32.386Z,1537303952.386 [BPC1](INFO): BPC1A: got serial number for stick 25: 00E8
2018-09-18T20:52:32.387Z,1537303952.387 [BPC1](INFO): BPC1A: got serial number for stick 26: 00D1
2018-09-18T20:52:32.388Z,1537303952.388 [BPC1](INFO): BPC1A: got serial number for stick 27: 00F3
2018-09-18T20:52:32.389Z,1537303952.389 [BPC1](INFO): BPC1A: got serial number for stick 28: 0092
2018-09-18T20:52:32.389Z,1537303952.389 [BPC1](INFO): BPC1A: got serial number for stick 29: 00AD
2018-09-18T20:52:32.390Z,1537303952.390 [BPC1](INFO): BPC1A: got serial number for stick 30: 00E9
2018-09-18T20:52:32.391Z,1537303952.391 [BPC1](INFO): BPC1A: got serial number for stick 31: 0126
2018-09-18T20:52:32.393Z,1537303952.393 [BPC1](INFO): BPC1B: got serial number for stick 32: 0157
2018-09-18T20:52:32.393Z,1537303952.393 [BPC1](INFO): BPC1B: got serial number for stick 33: 00F1
2018-09-18T20:52:32.394Z,1537303952.394 [BPC1](INFO): BPC1B: got serial number for stick 34: 00BF
2018-09-18T20:52:32.395Z,1537303952.395 [BPC1](INFO): BPC1B: got serial number for stick 35: 0089
2018-09-18T20:52:32.396Z,1537303952.396 [BPC1](INFO): BPC1B: got serial number for stick 36: 00C2
2018-09-18T20:52:32.397Z,1537303952.397 [BPC1](INFO): BPC1B: got serial number for stick 37: 00CA
2018-09-18T20:52:32.397Z,1537303952.397 [BPC1](INFO): BPC1B: got serial number for stick 38: 011A
2018-09-18T20:52:32.398Z,1537303952.398 [BPC1](INFO): BPC1B: got serial number for stick 39: 00FD
2018-09-18T20:52:32.399Z,1537303952.399 [BPC1](INFO): BPC1B: got serial number for stick 40: 0095
2018-09-18T20:52:32.400Z,1537303952.400 [BPC1](INFO): BPC1B: got serial number for stick 41: 00BE
2018-09-18T20:52:32.401Z,1537303952.401 [BPC1](INFO): BPC1B: got serial number for stick 42: 0086
2018-09-18T20:52:32.401Z,1537303952.401 [BPC1](INFO): BPC1B: got serial number for stick 43: 00AE
2018-09-18T20:52:32.402Z,1537303952.402 [BPC1](INFO): BPC1B: got serial number for stick 44: 00CB
2018-09-18T20:52:32.403Z,1537303952.403 [BPC1](INFO): BPC1B: got serial number for stick 45: 00F7
2018-09-18T20:52:32.404Z,1537303952.404 [BPC1](INFO): BPC1B: got serial number for stick 46: 00A1
2018-09-18T20:52:32.404Z,1537303952.404 [BPC1](INFO): BPC1B: got serial number for stick 47: 00F0
2018-09-18T20:52:32.405Z,1537303952.405 [BPC1](INFO): BPC1B: got serial number for stick 48: 00C9
2018-09-18T20:52:32.406Z,1537303952.406 [BPC1](INFO): BPC1B: got serial number for stick 49: 0103
2018-09-18T20:52:32.407Z,1537303952.407 [BPC1](INFO): BPC1B: got serial number for stick 50: 0152
2018-09-18T20:52:32.408Z,1537303952.408 [BPC1](INFO): BPC1B: got serial number for stick 51: 014A
2018-09-18T20:52:32.408Z,1537303952.408 [BPC1](INFO): BPC1B: got serial number for stick 52: 0114
2018-09-18T20:52:32.409Z,1537303952.409 [BPC1](INFO): BPC1B: got serial number for stick 53: 015E
2018-09-18T20:52:32.410Z,1537303952.410 [BPC1](INFO): BPC1B: got serial number for stick 54: 008E
2018-09-18T20:52:32.411Z,1537303952.411 [BPC1](INFO): BPC1B: got serial number for stick 55: 00AC
2018-09-18T20:52:32.411Z,1537303952.411 [BPC1](INFO): BPC1B: got serial number for stick 56: 0091
2018-09-18T20:52:32.412Z,1537303952.412 [BPC1](INFO): BPC1B: got serial number for stick 57: 0153
2018-09-18T20:52:32.413Z,1537303952.413 [BPC1](INFO): BPC1B: got serial number for stick 58: 00AF
2018-09-18T20:52:32.414Z,1537303952.414 [BPC1](INFO): BPC1B: got serial number for stick 59: 0151
2018-09-18T20:52:32.414Z,1537303952.414 [BPC1](INFO): BPC1B: got serial number for stick 60: 00DC
2018-09-18T20:52:32.415Z,1537303952.415 [BPC1](INFO): BPC1B: got serial number for stick 61: 00BA
2018-09-18T20:52:32.416Z,1537303952.416 [BPC1](INFO): BPC1B: got serial number for stick 62: 00DA
2018-09-18T20:52:34.254Z,1537303954.254 [BPC1] Loaded
2018-09-18T20:52:34.254Z,1537303954.254 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-18T20:52:34.254Z,1537303954.254 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-18T20:52:34.255Z,1537303954.255 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-18T20:52:34.401Z,1537303954.401 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-18T20:52:34.424Z,1537303954.424 [SBIT] Loaded
2018-09-18T20:52:34.424Z,1537303954.424 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-18T20:52:34.425Z,1537303954.425 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-18T20:52:34.437Z,1537303954.437 [IBIT] Loaded
2018-09-18T20:52:34.437Z,1537303954.437 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-18T20:52:34.441Z,1537303954.441 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-18T20:52:34.597Z,1537303954.597 [CBIT] Loaded
2018-09-18T20:52:34.598Z,1537303954.598 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-18T20:52:34.598Z,1537303954.598 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-18T20:52:34.599Z,1537303954.599 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-18T20:52:34.758Z,1537303954.758 [Aanderaa_O2] Loaded
2018-09-18T20:52:34.759Z,1537303954.759 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-09-18T20:52:34.842Z,1537303954.842 [CTD_NeilBrown] Loaded
2018-09-18T20:52:34.842Z,1537303954.842 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-09-18T20:52:34.843Z,1537303954.843 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4094F4E0
2018-09-18T20:52:34.844Z,1537303954.844 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 447
2018-09-18T20:52:34.897Z,1537303954.897 [CTD_Seabird] Loaded
2018-09-18T20:52:34.897Z,1537303954.897 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-09-18T20:52:34.898Z,1537303954.898 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4097F4E0
2018-09-18T20:52:34.899Z,1537303954.899 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 448
2018-09-18T20:52:34.917Z,1537303954.917 [ESPComponent] Loaded
2018-09-18T20:52:34.917Z,1537303954.917 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-09-18T20:52:34.932Z,1537303954.932 [PAR_Licor] Loaded
2018-09-18T20:52:34.932Z,1537303954.932 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-09-18T20:52:34.983Z,1537303954.983 [WetLabsBB2FL] Loaded
2018-09-18T20:52:34.984Z,1537303954.984 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-09-18T20:52:34.985Z,1537303954.985 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409AF4E0
2018-09-18T20:52:34.985Z,1537303954.985 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 449
2018-09-18T20:52:34.986Z,1537303954.986 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-18T20:52:34.986Z,1537303954.986 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-18T20:52:35.173Z,1537303955.173 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-18T20:52:35.404Z,1537303955.404 [VerticalControl] Loaded
2018-09-18T20:52:35.405Z,1537303955.405 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-18T20:52:35.405Z,1537303955.405 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-18T20:52:35.469Z,1537303955.469 [HorizontalControl] Loaded
2018-09-18T20:52:35.469Z,1537303955.469 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-18T20:52:35.470Z,1537303955.470 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-18T20:52:35.475Z,1537303955.475 [SpeedControl] Loaded
2018-09-18T20:52:35.476Z,1537303955.476 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-18T20:52:35.476Z,1537303955.476 [LoopControl](DEBUG): Construct LoopControl.
2018-09-18T20:52:35.477Z,1537303955.477 [LoopControl] Loaded
2018-09-18T20:52:35.477Z,1537303955.477 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-18T20:52:35.478Z,1537303955.478 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-18T20:52:35.478Z,1537303955.478 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-18T20:52:35.504Z,1537303955.504 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-18T20:52:35.504Z,1537303955.504 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-18T20:52:35.602Z,1537303955.602 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-18T20:52:35.602Z,1537303955.602 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-18T20:52:35.619Z,1537303955.619 [NavChart] Loaded
2018-09-18T20:52:35.619Z,1537303955.619 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-18T20:52:35.624Z,1537303955.624 [UniversalFixResidualReporter] Loaded
2018-09-18T20:52:35.624Z,1537303955.624 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-18T20:52:35.624Z,1537303955.624 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-18T20:52:35.628Z,1537303955.628 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-18T20:52:35.629Z,1537303955.629 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-18T20:52:35.636Z,1537303955.636 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-18T20:52:35.637Z,1537303955.637 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A3B4E0
2018-09-18T20:52:35.637Z,1537303955.637 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 450
2018-09-18T20:52:35.642Z,1537303955.642 [Supervisor](INFO): Main Thread ID is 9268
2018-09-18T20:52:35.642Z,1537303955.642 [Supervisor](DEBUG): Running supervisor.
2018-09-18T20:52:35.643Z,1537303955.643 [CommandLine ThreadHandler](INFO): Handler Thread ID is 451
2018-09-18T20:52:35.645Z,1537303955.645 [controlThread ThreadHandler](INFO): Handler Thread ID is 452
2018-09-18T20:52:35.646Z,1537303955.646 [controlThread](DEBUG): Initializing ControlThread
2018-09-18T20:52:35.646Z,1537303955.646 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-18T20:52:35.647Z,1537303955.647 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-18T20:52:35.647Z,1537303955.647 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-18T20:52:35.647Z,1537303955.647 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-18T20:52:35.648Z,1537303955.648 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-18T20:52:35.649Z,1537303955.649 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-18T20:52:35.649Z,1537303955.649 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-18T20:52:35.655Z,1537303955.655 [SBIT](INFO): Initialize SBIT Component.
2018-09-18T20:52:35.655Z,1537303955.655 [SBIT](IMPORTANT): git: 2018-09-06-6-gbaf5c46
2018-09-18T20:52:35.656Z,1537303955.656 [SBIT](INFO): git hash: baf5c469f70e7902a2cb0333843877cb31121fce
2018-09-18T20:52:35.656Z,1537303955.656 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-18T20:52:35.657Z,1537303955.657 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-09-18T20:52:35.659Z,1537303955.659 [SBIT](INFO): Beginning SBIT in 160.000000 seconds.
2018-09-18T20:52:35.659Z,1537303955.659 [IBIT](INFO): Initialize IBIT Component.
2018-09-18T20:52:35.660Z,1537303955.660 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-18T20:52:35.661Z,1537303955.661 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-09-18T20:52:35.661Z,1537303955.661 [logger ThreadHandler](INFO): Handler Thread ID is 453
2018-09-18T20:52:35.681Z,1537303955.681 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 454
2018-09-18T20:52:35.687Z,1537303955.687 [Radio_Surface](INFO): Powering up
2018-09-18T20:52:35.693Z,1537303955.693 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 455
2018-09-18T20:52:35.694Z,1537303955.694 [CTD_NeilBrown](INFO): Powering down
2018-09-18T20:52:35.721Z,1537303955.721 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 456
2018-09-18T20:52:35.722Z,1537303955.722 [CTD_Seabird](INFO): Initializing
2018-09-18T20:52:35.722Z,1537303955.722 [CTD_Seabird](INFO): Checking LCM
2018-09-18T20:52:35.731Z,1537303955.731 [CTD_Seabird](INFO): LCM OK
2018-09-18T20:52:35.731Z,1537303955.731 [CTD_Seabird](INFO): Powering up
2018-09-18T20:52:35.741Z,1537303955.741 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 457
2018-09-18T20:52:35.742Z,1537303955.742 [WetLabsBB2FL](INFO): Powering down
2018-09-18T20:52:35.769Z,1537303955.769 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 458
2018-09-18T20:52:35.772Z,1537303955.772 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-18T20:52:35.772Z,1537303955.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-18T20:52:35.773Z,1537303955.773 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-18T20:52:35.773Z,1537303955.773 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-18T20:52:35.773Z,1537303955.773 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-18T20:52:35.773Z,1537303955.773 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-18T20:52:35.773Z,1537303955.773 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-18T20:52:35.774Z,1537303955.774 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-18T20:52:35.774Z,1537303955.774 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-18T20:52:35.774Z,1537303955.774 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-18T20:52:35.774Z,1537303955.774 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-18T20:52:35.774Z,1537303955.774 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-18T20:52:35.774Z,1537303955.774 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-18T20:52:35.775Z,1537303955.775 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-18T20:52:35.775Z,1537303955.775 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-18T20:52:35.775Z,1537303955.775 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-18T20:52:35.781Z,1537303955.781 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-18T20:52:35.783Z,1537303955.783 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-18T20:52:35.783Z,1537303955.783 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-18T20:52:35.784Z,1537303955.784 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-18T20:52:35.789Z,1537303955.789 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T20:52:35.790Z,1537303955.790 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T20:52:35.790Z,1537303955.790 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T20:52:35.790Z,1537303955.790 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-18T20:52:35.791Z,1537303955.791 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-18T20:52:35.792Z,1537303955.792 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-18T20:52:35.829Z,1537303955.829 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-18T20:52:35.877Z,1537303955.877 [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>


2018-09-18T20:52:35.877Z,1537303955.877 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-18T20:52:35.975Z,1537303955.975 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-18T20:52:35.976Z,1537303955.976 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-18T20:52:35.978Z,1537303955.978 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-18T20:52:36.022Z,1537303956.022 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-18T20:52:36.024Z,1537303956.024 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-18T20:52:36.046Z,1537303956.046 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-18T20:52:36.050Z,1537303956.050 [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>


2018-09-18T20:52:36.055Z,1537303956.055 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-18T20:52:36.097Z,1537303956.097 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-18T20:52:36.124Z,1537303956.124 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-09-18T20:52:36.481Z,1537303956.481 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-18T20:52:36.565Z,1537303956.565 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:52:36.689Z,1537303956.689 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-18T20:52:36.697Z,1537303956.697 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-18T20:52:36.731Z,1537303956.731 [CommandLine](FAULT): Scheduling is paused
2018-09-18T20:52:36.731Z,1537303956.731 [CBIT](INFO): Critical error at 20180918T205235
2018-09-18T20:52:36.732Z,1537303956.732 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-09-18T20:52:36.947Z,1537303956.947 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-09-18T20:52:36.947Z,1537303956.947 [DropWeight] Hardware Fault, FailCount= 1
2018-09-18T20:52:36.947Z,1537303956.947 [DropWeight](ERROR): Hardware Fault
2018-09-18T20:52:37.009Z,1537303957.009 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:52:37.032Z,1537303957.032 [CBIT](INFO): Critical error at 20180918T205236
2018-09-18T20:52:37.035Z,1537303957.035 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-09-18T20:52:37.036Z,1537303957.036 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-09-18T20:52:37.233Z,1537303957.233 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:52:37.565Z,1537303957.565 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-18T20:52:37.565Z,1537303957.565 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-18T20:52:37.565Z,1537303957.565 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-18T20:52:37.565Z,1537303957.565 [BuoyancyServo](ERROR): Communications Fault
2018-09-18T20:52:37.568Z,1537303957.568 [CBIT](INFO): Critical error at 20180918T205237
2018-09-18T20:52:37.570Z,1537303957.570 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-18T20:52:37.653Z,1537303957.653 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:52:37.670Z,1537303957.670 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-18T20:52:37.671Z,1537303957.671 [BuoyancyServo](INFO): Powering down
2018-09-18T20:52:38.031Z,1537303958.031 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:52:38.437Z,1537303958.437 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:52:38.856Z,1537303958.856 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:52:39.232Z,1537303959.232 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:52:39.745Z,1537303959.745 [Aanderaa_O2](INFO): Powering down
2018-09-18T20:52:39.842Z,1537303959.842 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-18T20:52:39.842Z,1537303959.842 [BuoyancyServo] No Fault, FailCount= 1
2018-09-18T20:52:40.062Z,1537303960.062 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-18T20:52:40.183Z,1537303960.183 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-18T20:52:41.248Z,1537303961.248 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-18T20:52:41.375Z,1537303961.375 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-09-18T20:52:41.418Z,1537303961.418 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-09-18T20:52:41.795Z,1537303961.795 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-18T20:52:41.795Z,1537303961.795 [CTD_Seabird](INFO): Powering down
2018-09-18T20:53:02.390Z,1537303982.390 [NAL9602](INFO): Powering up NAL9602
2018-09-18T20:53:13.590Z,1537303993.590 [NAL9602](INFO): NAL9602 initialized
2018-09-18T20:53:37.248Z,1537304017.248 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:53:37.260Z,1537304017.260 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:53:38.817Z,1537304018.817 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:53:42.440Z,1537304022.440 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:53:42.477Z,1537304022.477 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:53:42.830Z,1537304022.830 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:53:42.840Z,1537304022.840 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:53:43.636Z,1537304023.636 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:53:44.426Z,1537304024.426 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:53:45.227Z,1537304025.227 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:53:49.223Z,1537304029.223 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:53:49.301Z,1537304029.301 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:53:49.301Z,1537304029.301 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:53:49.339Z,1537304029.339 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:53:49.623Z,1537304029.623 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:53:49.632Z,1537304029.632 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:53:49.633Z,1537304029.633 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:53:49.641Z,1537304029.641 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:53:50.431Z,1537304030.431 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:53:50.444Z,1537304030.444 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:53:57.634Z,1537304037.634 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:53:58.030Z,1537304038.030 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:54:02.024Z,1537304042.024 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:54:02.101Z,1537304042.101 [BPC1](INFO): BPC1A: no match for SMBUS stick msg of size 1803 bytes.
2018-09-18T20:54:02.102Z,1537304042.102 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
Got:
$B11,02,000A,01,02A8,03,0001,08,0B88,09,392E,0A,FFFA,0B,FFFE%40
$B11,0C,0001,0D,0033,0E,002D,0F,0BEA,10,17BC,11,7724,12,FFFF%44
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,000E,18,1A90,19,3840%4D
$B11,1A,0031,1B,458B,1C,008A%33
$B12,02,000A,01,02A8,03,0001,08,0B81,09,344D,0A,0000,0B,0000%44
$B12,0C,0001,0D,0008,0E,0008,0F,01DF,10,192D,11,FFFF,12,FFFF%3B
$B12,13,FFFF,14,0000,15,0000,16,4AC0,17,000B,18,1A90,19,3840%40
$B12,1A,0031,1B,458C,1C,0140%4D
$B13,02,000A,01,02A8,03,0001,08,0B85,09,3920,0A,FFFC,0B,FFFE%38
$B13,0C,0001,0D,002C,0E,002C,0F,0B93,10,1A63,11,AD9D,12,FFFF%32
$B13,13,FFFF,14,0BB8,15,41A0,16,00C0,17,000E,18,1A90,19,3840%4F
$B13,1A,0031,1B,458C,1C,00E5%39
$B14,02,000A,01,02A8,03,0001,08,0B8A,09,3928,0A,FFFC,0B,FFFE%43
$B14,0C,0001,0D,0032,0E,002D,0F,0BD6,10,17CD,11,B18A,12,FFFF%3C
$B14,13,FFFF,14,0BB8,15,41A0,16,00C0,17,000E,18,1A90,19,3840%48
$B14,1A,0031,1B,458C,1C,00E0%3B
$B15,02,000A,01,02A8,03,0001,08,0B86,09,37CB,0A,0000,0B,0000%36
$B15,0C,0001,0D,0023,0E,0022,0F,08DF,10,19D0,11,FFFF,12,FFFF%36
$B15,13,FFFF,14,0000,15,0000,16,48C0,17,0009,18,1A90,19,3840%45
$B15,1A,0031,1B,458C,1C,00CD%48
$B16,02,000A,01,02A8,03,0001,08,0B84,09,393B,0A,0000,0B,0000%49
$B16,0C,0001,0D,002E,0E,002D,0F,0BF1,10,1A56,11,FFFF,12,FFFF%35
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,000D,18,1A90,19,3840%4B
$B16,1A,0031,1B,458B,1C,009F%32
$B17,02,000A,01,02A8,03,0001,08,0B86,09,3946,0A,0000,0B,0000%39
$B17,0C,0001,0D,0031,0E,002E,0F,0C1D,10,1933,11,FFFF,12,FFFF%38
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,000D,18,1A90,19,3840%4A
$B17,1A,0031,1B,458B,1C,009C%36
$B18,02,000A,01,
=====================================
IBPS  V2.10
OceanServer Technology, Inc (c) 2007
 www.ocean-server.com

 S - Setup Controller
 B - Battery Status
 X - Host HEX
 H - Help

->,1B,458B,1C,00A6%34

2018-09-18T20:54:02.441Z,1537304042.441 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:54:02.518Z,1537304042.518 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:54:02.857Z,1537304042.857 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:54:02.857Z,1537304042.857 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:54:02.875Z,1537304042.875 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:54:03.648Z,1537304043.648 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:54:04.034Z,1537304044.034 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:54:08.437Z,1537304048.437 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:54:08.465Z,1537304048.465 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:54:08.841Z,1537304048.841 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:54:08.853Z,1537304048.853 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:54:09.635Z,1537304049.635 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:54:11.219Z,1537304051.219 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:54:16.025Z,1537304056.025 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:54:16.025Z,1537304056.025 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:54:16.123Z,1537304056.124 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:54:16.430Z,1537304056.430 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:54:16.438Z,1537304056.438 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:54:17.240Z,1537304057.240 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:54:20.424Z,1537304060.424 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:54:20.501Z,1537304060.501 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T20:54:20.823Z,1537304060.823 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:54:20.831Z,1537304060.831 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:54:21.636Z,1537304061.636 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:54:23.215Z,1537304063.215 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:54:24.445Z,1537304064.445 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:54:27.619Z,1537304067.619 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:54:27.635Z,1537304067.635 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:54:28.018Z,1537304068.018 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:54:28.026Z,1537304068.026 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:54:28.841Z,1537304068.841 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:54:28.841Z,1537304068.841 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:54:28.929Z,1537304068.929 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:54:29.225Z,1537304069.225 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:54:29.233Z,1537304069.233 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:54:30.432Z,1537304070.432 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:54:31.626Z,1537304071.626 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:54:35.232Z,1537304075.232 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:54:35.247Z,1537304075.247 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:54:35.623Z,1537304075.623 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:54:35.630Z,1537304075.630 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:54:36.417Z,1537304076.417 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:54:36.428Z,1537304076.428 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:54:41.215Z,1537304081.215 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:54:41.300Z,1537304081.300 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T20:54:41.616Z,1537304081.616 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:54:41.625Z,1537304081.625 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:54:42.448Z,1537304082.448 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:54:42.819Z,1537304082.819 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:54:47.627Z,1537304087.627 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:54:47.706Z,1537304087.706 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:54:48.023Z,1537304088.023 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:54:48.031Z,1537304088.031 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:54:48.840Z,1537304088.840 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:54:50.040Z,1537304090.040 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:54:54.843Z,1537304094.843 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:54:54.922Z,1537304094.922 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:54:55.230Z,1537304095.230 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:54:55.262Z,1537304095.262 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:54:56.049Z,1537304096.049 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:54:56.426Z,1537304096.426 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:55:01.233Z,1537304101.233 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:55:01.323Z,1537304101.323 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:55:01.615Z,1537304101.615 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:55:01.635Z,1537304101.635 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:55:02.429Z,1537304102.429 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:55:03.215Z,1537304103.215 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:55:07.629Z,1537304107.629 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:55:07.755Z,1537304107.755 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T20:55:08.026Z,1537304108.026 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:55:08.033Z,1537304108.033 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:55:08.836Z,1537304108.836 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:55:09.228Z,1537304109.228 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:55:14.026Z,1537304114.026 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:55:14.178Z,1537304114.178 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:55:14.428Z,1537304114.428 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:55:14.473Z,1537304114.473 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:55:15.654Z,1537304115.654 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:55:16.035Z,1537304116.035 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:55:16.099Z,1537304116.099 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-18T20:55:16.104Z,1537304116.104 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-18T20:55:20.834Z,1537304120.834 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:55:20.909Z,1537304120.909 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:55:21.230Z,1537304121.230 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:55:21.238Z,1537304121.238 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:55:22.044Z,1537304122.044 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:55:22.823Z,1537304122.823 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:55:26.893Z,1537304126.893 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000786
CHAN A1 (24V): -0.003998
CHAN A2 (12V): -0.002498
CHAN A3 (5V): -0.001565
CHAN B0 (3.3V): 0.000067
CHAN B1 (3.15aV): -0.000154
CHAN B2 (3.15bV): -0.000731
CHAN B3 (GND): 0.000273
OPEN: -0.000486
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-18T20:55:27.240Z,1537304127.240 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:55:27.316Z,1537304127.316 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:55:27.624Z,1537304127.624 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:55:27.632Z,1537304127.632 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:55:28.440Z,1537304128.440 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:55:29.228Z,1537304129.228 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:55:29.258Z,1537304129.258 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-18T20:55:29.258Z,1537304129.258 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-18T20:55:29.259Z,1537304129.259 [SBIT](ERROR): Could not read massPosReader_.
2018-09-18T20:55:34.016Z,1537304134.016 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:55:34.107Z,1537304134.107 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T20:55:34.415Z,1537304134.415 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:55:34.447Z,1537304134.447 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:55:35.241Z,1537304135.241 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:55:35.241Z,1537304135.241 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:55:36.037Z,1537304136.037 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T20:55:36.037Z,1537304136.037 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-09-18T20:55:36.037Z,1537304136.037 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T20:55:36.058Z,1537304136.058 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T20:55:36.474Z,1537304136.474 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T20:55:36.474Z,1537304136.474 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-09-18T20:55:36.858Z,1537304136.858 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T20:55:36.858Z,1537304136.858 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T20:55:36.859Z,1537304136.859 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T20:55:40.908Z,1537304140.908 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:55:40.988Z,1537304140.988 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:55:41.109Z,1537304141.109 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:55:41.116Z,1537304141.116 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:55:41.823Z,1537304141.823 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003855
2018-09-18T20:55:41.901Z,1537304141.901 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:55:43.059Z,1537304143.059 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:55:43.458Z,1537304143.458 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:55:47.865Z,1537304147.865 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:55:47.943Z,1537304147.943 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:55:47.944Z,1537304147.944 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:55:47.974Z,1537304147.974 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:55:48.256Z,1537304148.256 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:55:48.264Z,1537304148.264 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:55:48.264Z,1537304148.264 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:55:48.273Z,1537304148.273 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:55:49.064Z,1537304149.064 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:55:49.076Z,1537304149.076 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:55:55.884Z,1537304155.884 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-18T20:55:55.885Z,1537304155.885 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-18T20:55:55.885Z,1537304155.885 [SBIT](ERROR): Could not read massPosReader_.
2018-09-18T20:55:55.885Z,1537304155.885 [SBIT](FAULT): Control surface position failure.
2018-09-18T20:55:56.254Z,1537304156.254 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:55:56.254Z,1537304156.254 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:56:00.665Z,1537304160.665 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:56:00.739Z,1537304160.739 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T20:56:01.054Z,1537304161.054 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:56:01.062Z,1537304161.062 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:56:01.062Z,1537304161.062 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:56:01.185Z,1537304161.185 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:56:01.457Z,1537304161.457 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:56:01.470Z,1537304161.470 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:56:01.865Z,1537304161.865 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:56:02.284Z,1537304162.284 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:56:08.261Z,1537304168.261 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:56:09.475Z,1537304169.475 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-18T20:56:09.475Z,1537304169.475 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-18T20:56:09.475Z,1537304169.475 [SBIT](ERROR): Could not read massPosReader_.
2018-09-18T20:56:09.475Z,1537304169.475 [SBIT](FAULT): Control surface position failure.
2018-09-18T20:56:09.855Z,1537304169.855 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:56:09.872Z,1537304169.872 [SBIT](CRITICAL): SBIT FAILED
2018-09-18T20:56:09.901Z,1537304169.901 [CommandLine](IMPORTANT): got command configSet list
2018-09-18T20:56:09.901Z,1537304169.901 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-18T20:56:09.902Z,1537304169.902 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2018-09-18T20:56:09.902Z,1537304169.902 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2018-09-18T20:56:09.902Z,1537304169.902 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2018-09-18T20:56:09.903Z,1537304169.903 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2018-09-18T20:56:09.903Z,1537304169.903 [CommandLine](IMPORTANT): ElevatorServo.loadAtStartup=0 bool;

2018-09-18T20:56:09.903Z,1537304169.903 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_salinity;

2018-09-18T20:56:09.903Z,1537304169.903 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_temperature;

2018-09-18T20:56:09.903Z,1537304169.903 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_median_sea_water_temperature;

2018-09-18T20:56:09.903Z,1537304169.903 [CommandLine](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2018-09-18T20:56:09.903Z,1537304169.903 [CommandLine](IMPORTANT): Express none Rowe_600LCM.height_above_sea_floor;

2018-09-18T20:56:09.903Z,1537304169.903 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-09-18T20:56:09.904Z,1537304169.904 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-09-18T20:56:09.904Z,1537304169.904 [CommandLine](IMPORTANT): MassServo.loadAtStartup=0 bool;

2018-09-18T20:56:09.904Z,1537304169.904 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-09-18T20:56:09.904Z,1537304169.904 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2018-09-18T20:56:09.904Z,1537304169.904 [CommandLine](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2018-09-18T20:56:09.905Z,1537304169.905 [CommandLine](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2018-09-18T20:56:09.905Z,1537304169.905 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=497.852 cubic_centimeter;

2018-09-18T20:56:09.906Z,1537304169.906 [CommandLine](IMPORTANT): VerticalControl.massDefault=-35 millimeter;

2018-09-18T20:56:09.930Z,1537304169.930 [CBIT](INFO): Critical error at 20180918T205609
2018-09-18T20:56:10.281Z,1537304170.281 [MissionManager](IMPORTANT): Started mission Startup
2018-09-18T20:56:10.281Z,1537304170.281 [Startup] Running Loop=1
2018-09-18T20:56:10.281Z,1537304170.281 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-18T20:56:10.281Z,1537304170.281 [Startup:A.GoToSurface] Running Loop=1
2018-09-18T20:56:10.281Z,1537304170.281 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-18T20:56:10.282Z,1537304170.282 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-18T20:56:10.283Z,1537304170.283 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-18T20:56:10.283Z,1537304170.283 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-18T20:56:10.283Z,1537304170.283 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-18T20:56:10.284Z,1537304170.284 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-18T20:56:10.317Z,1537304170.317 [Startup:StartupSatComms] Running Loop=1
2018-09-18T20:56:10.317Z,1537304170.317 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-18T20:56:10.317Z,1537304170.317 [Startup:StartupSatComms:A] Running Loop=1
2018-09-18T20:56:10.679Z,1537304170.679 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-18T20:56:12.675Z,1537304172.675 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:56:12.747Z,1537304172.747 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:56:13.066Z,1537304173.066 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:56:13.073Z,1537304173.073 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:56:13.884Z,1537304173.884 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:56:14.646Z,1537304174.646 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:56:14.731Z,1537304174.731 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:56:15.066Z,1537304175.066 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:56:15.074Z,1537304175.074 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:56:15.447Z,1537304175.447 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:56:15.865Z,1537304175.865 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:56:19.855Z,1537304179.855 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:56:19.872Z,1537304179.872 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T20:56:20.256Z,1537304180.256 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:56:20.264Z,1537304180.264 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:56:21.068Z,1537304181.068 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:56:23.069Z,1537304183.069 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:56:27.055Z,1537304187.055 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:56:27.133Z,1537304187.133 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:56:27.456Z,1537304187.456 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:56:27.463Z,1537304187.463 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:56:28.262Z,1537304188.262 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:56:28.276Z,1537304188.276 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:56:33.062Z,1537304193.062 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:56:33.138Z,1537304193.138 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:56:33.470Z,1537304193.470 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:56:33.478Z,1537304193.478 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:56:34.284Z,1537304194.284 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:56:35.048Z,1537304195.048 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:56:40.982Z,1537304200.982 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:56:41.062Z,1537304201.062 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:56:41.201Z,1537304201.201 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:56:41.210Z,1537304201.210 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:56:41.953Z,1537304201.953 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:56:41.964Z,1537304201.964 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:56:46.743Z,1537304206.743 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:56:46.825Z,1537304206.825 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T20:56:47.141Z,1537304207.141 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:56:47.150Z,1537304207.150 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:56:48.333Z,1537304208.333 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:56:49.120Z,1537304209.120 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:56:53.927Z,1537304213.927 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:56:54.007Z,1537304214.007 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:56:54.323Z,1537304214.323 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:56:54.332Z,1537304214.332 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:56:55.123Z,1537304215.123 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:56:55.136Z,1537304215.136 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:56:59.126Z,1537304219.126 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:56:59.199Z,1537304219.199 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:56:59.529Z,1537304219.529 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:56:59.536Z,1537304219.536 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:57:00.340Z,1537304220.340 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:57:02.343Z,1537304222.343 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:57:06.746Z,1537304226.746 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:57:07.147Z,1537304227.147 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:57:07.225Z,1537304227.225 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:57:07.546Z,1537304227.546 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:57:07.555Z,1537304227.555 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:57:08.352Z,1537304228.352 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:57:10.333Z,1537304230.333 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-18T20:56:10.3Z
2018-09-18T20:57:10.334Z,1537304230.334 [Startup:StartupSatComms:A] Stopped
2018-09-18T20:57:10.334Z,1537304230.334 [Startup:StartupSatComms:B] Running Loop=1
2018-09-18T20:57:10.735Z,1537304230.735 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-18T20:57:11.284Z,1537304231.284 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:57:11.358Z,1537304231.358 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T20:57:11.541Z,1537304231.541 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:57:11.550Z,1537304231.550 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:57:14.709Z,1537304234.709 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180918T204345/Courier0004.lzma
2018-09-18T20:57:15.177Z,1537304235.177 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:57:15.508Z,1537304235.508 [DataOverHttps](INFO): Moved sent file to Logs/20180918T204345/Courier0004.lzma.bak
2018-09-18T20:57:15.508Z,1537304235.508 [DataOverHttps](INFO): SBD MOMSN=8531761
2018-09-18T20:57:15.566Z,1537304235.566 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:57:19.629Z,1537304239.629 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:57:19.703Z,1537304239.703 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:57:19.962Z,1537304239.962 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:57:19.969Z,1537304239.969 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:57:20.768Z,1537304240.768 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:57:24.302Z,1537304244.302 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:57:24.303Z,1537304244.303 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:57:24.369Z,1537304244.369 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20180918T205225/Courier0000.lzma
2018-09-18T20:57:25.136Z,1537304245.136 [DataOverHttps](INFO): Moved sent file to Logs/20180918T205225/Courier0000.lzma.bak
2018-09-18T20:57:25.136Z,1537304245.136 [DataOverHttps](INFO): SBD MOMSN=8531763
2018-09-18T20:57:29.103Z,1537304249.103 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:57:29.186Z,1537304249.186 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:57:29.187Z,1537304249.187 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:57:29.201Z,1537304249.201 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:57:29.350Z,1537304249.350 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:57:29.371Z,1537304249.371 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:57:29.371Z,1537304249.371 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:57:29.390Z,1537304249.390 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:57:30.132Z,1537304250.132 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:57:30.144Z,1537304250.144 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:57:34.280Z,1537304254.280 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20180918T204345/Express0005.lzma
2018-09-18T20:57:35.068Z,1537304255.068 [DataOverHttps](INFO): Moved sent file to Logs/20180918T204345/Express0005.lzma.bak
2018-09-18T20:57:35.068Z,1537304255.068 [DataOverHttps](INFO): SBD MOMSN=8531771
2018-09-18T20:57:39.117Z,1537304259.117 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:57:39.117Z,1537304259.117 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:57:43.931Z,1537304263.931 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:57:44.008Z,1537304264.008 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T20:57:44.016Z,1537304264.016 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:57:44.081Z,1537304264.081 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:57:44.155Z,1537304264.155 [DataOverHttps](INFO): Sending 1040 bytes from file Logs/20180918T205225/Express0001.lzma
2018-09-18T20:57:44.220Z,1537304264.220 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:57:44.230Z,1537304264.230 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:57:44.230Z,1537304264.230 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:57:44.239Z,1537304264.239 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:57:44.912Z,1537304264.912 [DataOverHttps](INFO): Moved sent file to Logs/20180918T205225/Express0001.lzma.bak
2018-09-18T20:57:44.912Z,1537304264.912 [DataOverHttps](INFO): SBD MOMSN=8531776
2018-09-18T20:57:44.988Z,1537304264.988 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:57:45.001Z,1537304265.001 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:57:45.664Z,1537304265.664 [Startup:StartupSatComms:B] Stopped
2018-09-18T20:57:45.665Z,1537304265.665 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-18T20:57:45.665Z,1537304265.665 [Startup:StartupSatComms] Stopped
2018-09-18T20:57:45.665Z,1537304265.665 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-18T20:57:45.666Z,1537304265.666 [Startup](INFO): Completed Startup
2018-09-18T20:57:45.666Z,1537304265.666 [MissionManager](INFO): Startup is completed.
2018-09-18T20:57:45.666Z,1537304265.666 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-18T20:57:45.666Z,1537304265.666 [Startup] Stopped
2018-09-18T20:57:45.666Z,1537304265.666 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-18T20:57:45.667Z,1537304265.667 [Startup:A.GoToSurface] Stopped
2018-09-18T20:57:45.667Z,1537304265.667 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-18T20:57:45.813Z,1537304265.813 [MissionManager](IMPORTANT): Started mission Default
2018-09-18T20:57:45.813Z,1537304265.813 [Default] Running Loop=1
2018-09-18T20:57:45.813Z,1537304265.813 [Default](DEBUG): Aggregate::initialize Default
2018-09-18T20:57:45.813Z,1537304265.813 [Default:B.GoToSurface] Running Loop=1
2018-09-18T20:57:45.813Z,1537304265.813 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-18T20:57:45.814Z,1537304265.814 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-18T20:57:45.814Z,1537304265.814 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-18T20:57:45.815Z,1537304265.815 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-18T20:57:45.815Z,1537304265.815 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-18T20:57:45.816Z,1537304265.816 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-18T20:57:45.816Z,1537304265.816 [Default:A.Wait] Running Loop=1
2018-09-18T20:57:45.816Z,1537304265.816 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-18T20:57:51.742Z,1537304271.742 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:57:52.538Z,1537304272.538 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:57:56.144Z,1537304276.144 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:57:56.216Z,1537304276.216 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:57:56.543Z,1537304276.543 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:57:56.551Z,1537304276.551 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:57:57.356Z,1537304277.356 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:57:57.357Z,1537304277.357 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:57:57.442Z,1537304277.442 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:57:57.745Z,1537304277.745 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:57:57.753Z,1537304277.753 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:57:58.972Z,1537304278.972 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:57:58.989Z,1537304278.989 [Default:A.Wait](INFO): Done Waiting.
2018-09-18T20:57:58.989Z,1537304278.989 [Default:A.Wait] Stopped
2018-09-18T20:57:58.989Z,1537304278.989 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-18T20:57:59.380Z,1537304279.380 [Default:CheckIn] Running Loop=1
2018-09-18T20:57:59.380Z,1537304279.380 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-18T20:57:59.380Z,1537304279.380 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-18T20:57:59.772Z,1537304279.772 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-18T20:58:04.169Z,1537304284.169 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:58:06.141Z,1537304286.141 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:58:08.944Z,1537304288.944 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:58:09.019Z,1537304289.019 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T20:58:09.357Z,1537304289.357 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:58:09.366Z,1537304289.366 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:58:10.172Z,1537304290.172 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:58:10.941Z,1537304290.941 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:58:11.016Z,1537304291.016 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:58:11.349Z,1537304291.349 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:58:11.357Z,1537304291.357 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:58:12.156Z,1537304292.156 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:58:16.515Z,1537304296.515 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-09-18T20:58:17.358Z,1537304297.358 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:58:18.944Z,1537304298.944 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:58:22.161Z,1537304302.161 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:58:22.241Z,1537304302.241 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:58:22.560Z,1537304302.560 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:58:22.569Z,1537304302.569 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:58:23.356Z,1537304303.356 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:58:23.357Z,1537304303.357 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:58:23.443Z,1537304303.443 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:58:23.743Z,1537304303.743 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:58:23.750Z,1537304303.750 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:58:24.556Z,1537304304.556 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:58:30.543Z,1537304310.543 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:58:31.355Z,1537304311.355 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:58:34.945Z,1537304314.945 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:58:35.019Z,1537304315.019 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T20:58:35.346Z,1537304315.346 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:58:35.354Z,1537304315.354 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:58:36.156Z,1537304316.156 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:58:36.157Z,1537304316.157 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:58:36.235Z,1537304316.235 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:58:36.545Z,1537304316.545 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:58:36.553Z,1537304316.553 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:58:36.983Z,1537304316.983 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T20:58:36.984Z,1537304316.984 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-09-18T20:58:36.984Z,1537304316.984 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T20:58:37.028Z,1537304317.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T20:58:37.372Z,1537304317.372 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:58:37.392Z,1537304317.392 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T20:58:37.413Z,1537304317.413 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-09-18T20:58:37.769Z,1537304317.769 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T20:58:37.769Z,1537304317.769 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T20:58:37.770Z,1537304317.770 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T20:58:43.356Z,1537304323.356 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:58:44.945Z,1537304324.945 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:58:49.287Z,1537304329.287 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:58:49.358Z,1537304329.358 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:58:49.485Z,1537304329.485 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:58:49.509Z,1537304329.509 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:58:49.509Z,1537304329.509 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:58:49.604Z,1537304329.604 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:58:49.865Z,1537304329.865 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:58:49.873Z,1537304329.873 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:58:50.268Z,1537304330.268 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:58:51.089Z,1537304331.089 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:58:51.469Z,1537304331.469 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:58:55.462Z,1537304335.462 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:58:55.498Z,1537304335.498 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T20:58:55.847Z,1537304335.847 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:58:55.855Z,1537304335.855 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:58:56.672Z,1537304336.672 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:58:58.251Z,1537304338.251 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:59:03.065Z,1537304343.065 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:59:03.146Z,1537304343.146 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:59:03.461Z,1537304343.461 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:59:03.469Z,1537304343.469 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:59:03.852Z,1537304343.852 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:59:04.264Z,1537304344.264 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:59:08.665Z,1537304348.665 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:59:08.737Z,1537304348.737 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:59:09.070Z,1537304349.070 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:59:09.078Z,1537304349.078 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:59:09.857Z,1537304349.857 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:59:11.849Z,1537304351.849 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:59:16.252Z,1537304356.252 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:59:16.326Z,1537304356.326 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:59:16.661Z,1537304356.661 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:59:16.669Z,1537304356.669 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:59:17.068Z,1537304357.068 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:59:17.476Z,1537304357.476 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:59:19.066Z,1537304359.066 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:59:21.854Z,1537304361.854 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:59:21.937Z,1537304361.937 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T20:59:22.252Z,1537304362.252 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:59:22.260Z,1537304362.260 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:59:22.673Z,1537304362.673 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:59:22.705Z,1537304362.705 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:59:23.076Z,1537304363.076 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:59:23.077Z,1537304363.077 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:59:23.088Z,1537304363.088 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:59:23.864Z,1537304363.864 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:59:30.658Z,1537304370.658 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:59:31.062Z,1537304371.062 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:59:35.462Z,1537304375.462 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:59:35.541Z,1537304375.541 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:59:35.542Z,1537304375.542 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:59:35.613Z,1537304375.613 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:59:35.851Z,1537304375.851 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:59:35.860Z,1537304375.860 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:59:35.860Z,1537304375.860 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:59:35.868Z,1537304375.868 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:59:36.676Z,1537304376.676 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:59:36.693Z,1537304376.693 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:59:43.851Z,1537304383.851 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:59:44.253Z,1537304384.253 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:59:49.392Z,1537304389.392 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:59:49.476Z,1537304389.476 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T20:59:49.477Z,1537304389.477 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:59:49.556Z,1537304389.556 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:59:49.687Z,1537304389.687 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:59:49.695Z,1537304389.695 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:59:49.695Z,1537304389.695 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:59:49.716Z,1537304389.716 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:59:50.440Z,1537304390.440 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:59:50.452Z,1537304390.452 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:59:57.634Z,1537304397.634 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:59:57.635Z,1537304397.635 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:00:02.030Z,1537304402.030 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:00:02.108Z,1537304402.108 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:00:02.429Z,1537304402.429 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:00:02.438Z,1537304402.438 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:00:02.438Z,1537304402.438 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:00:02.564Z,1537304402.564 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:00:02.841Z,1537304402.841 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:00:02.849Z,1537304402.849 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:00:03.237Z,1537304403.237 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:00:03.652Z,1537304403.652 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:00:10.433Z,1537304410.433 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:00:11.223Z,1537304411.223 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:00:15.227Z,1537304415.227 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:00:15.307Z,1537304415.307 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:00:15.307Z,1537304415.307 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:00:15.378Z,1537304415.378 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:00:15.624Z,1537304415.624 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:00:15.632Z,1537304415.632 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:00:15.633Z,1537304415.633 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:00:15.640Z,1537304415.640 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:00:16.448Z,1537304416.448 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:00:16.468Z,1537304416.468 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:00:23.226Z,1537304423.226 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:00:24.036Z,1537304424.036 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:00:27.266Z,1537304427.266 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:00:27.404Z,1537304427.404 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:00:27.642Z,1537304427.642 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:00:27.665Z,1537304427.665 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:00:28.441Z,1537304428.441 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:00:28.826Z,1537304428.826 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:00:29.017Z,1537304429.017 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:00:29.218Z,1537304429.218 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:00:29.235Z,1537304429.235 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:00:30.048Z,1537304430.048 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:00:30.049Z,1537304430.049 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:00:34.429Z,1537304434.429 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:00:34.457Z,1537304434.457 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:00:34.824Z,1537304434.824 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:00:34.832Z,1537304434.832 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:00:35.648Z,1537304435.648 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:00:37.223Z,1537304437.223 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:00:41.627Z,1537304441.627 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:00:41.709Z,1537304441.709 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:00:42.030Z,1537304442.030 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:00:42.038Z,1537304442.038 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:00:42.840Z,1537304442.840 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:00:43.222Z,1537304443.222 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:00:49.543Z,1537304449.543 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:00:49.544Z,1537304449.544 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:00:49.638Z,1537304449.638 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:00:49.755Z,1537304449.755 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:00:49.764Z,1537304449.764 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:00:50.541Z,1537304450.541 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:00:53.320Z,1537304453.320 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:00:53.393Z,1537304453.393 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:00:53.722Z,1537304453.722 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:00:53.729Z,1537304453.729 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:00:54.524Z,1537304454.524 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:00:55.718Z,1537304455.718 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:00:57.761Z,1537304457.761 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:01:00.109Z,1537304460.109 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:01:00.141Z,1537304460.141 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:01:00.507Z,1537304460.507 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:01:00.515Z,1537304460.515 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:01:01.320Z,1537304461.320 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:01:02.515Z,1537304462.515 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:01:02.595Z,1537304462.595 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:01:02.900Z,1537304462.900 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:01:02.916Z,1537304462.916 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:01:03.713Z,1537304463.713 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:01:08.906Z,1537304468.906 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:01:10.502Z,1537304470.502 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:01:13.717Z,1537304473.717 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:01:13.793Z,1537304473.793 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:01:14.122Z,1537304474.122 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:01:14.130Z,1537304474.130 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:01:14.924Z,1537304474.924 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:01:14.925Z,1537304474.925 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:01:15.001Z,1537304475.001 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:01:15.307Z,1537304475.307 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:01:15.314Z,1537304475.314 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:01:16.124Z,1537304476.124 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:01:22.513Z,1537304482.513 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:01:22.922Z,1537304482.922 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:01:27.309Z,1537304487.309 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:01:27.394Z,1537304487.394 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:01:27.703Z,1537304487.703 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:01:27.719Z,1537304487.719 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:01:27.720Z,1537304487.720 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:01:27.833Z,1537304487.833 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:01:28.113Z,1537304488.113 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:01:28.137Z,1537304488.137 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:01:28.525Z,1537304488.525 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:01:28.940Z,1537304488.940 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:01:35.321Z,1537304495.321 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:01:36.514Z,1537304496.514 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:01:38.131Z,1537304498.131 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T21:01:38.131Z,1537304498.131 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-09-18T21:01:38.131Z,1537304498.131 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T21:01:38.152Z,1537304498.152 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T21:01:38.542Z,1537304498.542 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T21:01:38.542Z,1537304498.542 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-09-18T21:01:38.927Z,1537304498.927 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:01:38.927Z,1537304498.927 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:01:38.928Z,1537304498.928 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:01:39.709Z,1537304499.709 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:01:39.783Z,1537304499.783 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:01:40.113Z,1537304500.113 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:01:40.120Z,1537304500.120 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:01:40.929Z,1537304500.929 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:01:40.929Z,1537304500.929 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:01:41.009Z,1537304501.009 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:01:41.329Z,1537304501.329 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:01:41.337Z,1537304501.337 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:01:42.117Z,1537304502.117 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:01:49.644Z,1537304509.644 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:01:49.645Z,1537304509.645 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:01:52.118Z,1537304512.118 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:01:52.192Z,1537304512.192 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:01:52.526Z,1537304512.526 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:01:52.557Z,1537304512.557 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:01:53.324Z,1537304513.324 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:01:54.115Z,1537304514.115 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:01:54.190Z,1537304514.190 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:01:54.513Z,1537304514.513 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:01:54.522Z,1537304514.522 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:01:54.922Z,1537304514.922 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:01:55.337Z,1537304515.337 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:01:59.316Z,1537304519.316 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:01:59.332Z,1537304519.332 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:01:59.715Z,1537304519.715 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:01:59.724Z,1537304519.724 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:02:00.521Z,1537304520.521 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:02:02.904Z,1537304522.904 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:02:06.918Z,1537304526.918 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:02:06.993Z,1537304526.993 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:02:07.322Z,1537304527.322 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:02:07.329Z,1537304527.329 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:02:07.719Z,1537304527.719 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:02:08.132Z,1537304528.132 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:02:09.728Z,1537304529.728 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:02:12.931Z,1537304532.931 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:02:13.010Z,1537304533.010 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:02:13.323Z,1537304533.323 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:02:13.343Z,1537304533.343 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:02:13.343Z,1537304533.343 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:02:13.378Z,1537304533.378 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:02:13.733Z,1537304533.733 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:02:13.740Z,1537304533.740 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:02:14.129Z,1537304534.129 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:02:14.520Z,1537304534.520 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:02:21.317Z,1537304541.317 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:02:21.318Z,1537304541.318 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:02:26.106Z,1537304546.106 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:02:26.186Z,1537304546.186 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:02:26.507Z,1537304546.507 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:02:26.591Z,1537304546.591 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:02:26.591Z,1537304546.591 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:02:26.599Z,1537304546.599 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:02:26.908Z,1537304546.908 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:02:26.916Z,1537304546.916 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:02:27.340Z,1537304547.340 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:02:27.736Z,1537304547.736 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:02:34.523Z,1537304554.523 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:02:34.912Z,1537304554.912 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:02:39.310Z,1537304559.310 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:02:39.388Z,1537304559.388 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:02:39.388Z,1537304559.388 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:02:39.465Z,1537304559.465 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:02:39.714Z,1537304559.714 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:02:39.721Z,1537304559.721 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:02:39.722Z,1537304559.722 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:02:39.729Z,1537304559.729 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:02:40.540Z,1537304560.540 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:02:40.553Z,1537304560.553 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:02:49.641Z,1537304569.641 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:02:49.641Z,1537304569.641 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:02:52.145Z,1537304572.145 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:02:52.223Z,1537304572.223 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:02:52.548Z,1537304572.548 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:02:52.557Z,1537304572.557 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:02:52.558Z,1537304572.558 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:02:52.677Z,1537304572.677 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:02:52.969Z,1537304572.969 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:02:52.977Z,1537304572.977 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:02:53.373Z,1537304573.373 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:02:53.769Z,1537304573.769 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:02:59.765Z,1537304579.765 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-18T20:57:59.4Z
2018-09-18T21:02:59.765Z,1537304579.765 [Default:CheckIn:Read_GPS] Stopped
2018-09-18T21:02:59.765Z,1537304579.765 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-18T21:03:00.167Z,1537304580.167 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-18T21:03:00.704Z,1537304580.704 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:03:04.088Z,1537304584.088 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:03:04.163Z,1537304584.163 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180918T205225/Courier0004.lzma
2018-09-18T21:03:04.948Z,1537304584.948 [DataOverHttps](INFO): Moved sent file to Logs/20180918T205225/Courier0004.lzma.bak
2018-09-18T21:03:04.948Z,1537304584.948 [DataOverHttps](INFO): SBD MOMSN=8531810
2018-09-18T21:03:05.691Z,1537304585.691 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:03:05.775Z,1537304585.775 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:03:05.775Z,1537304585.775 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:03:05.846Z,1537304585.846 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:03:05.985Z,1537304585.985 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:03:05.993Z,1537304585.993 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:03:05.994Z,1537304585.994 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:03:06.001Z,1537304586.001 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:03:09.225Z,1537304589.225 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:03:09.236Z,1537304589.236 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:03:10.347Z,1537304590.347 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:03:13.927Z,1537304593.927 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180918T205225/Express0005.lzma
2018-09-18T21:03:14.371Z,1537304594.371 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:03:14.407Z,1537304594.407 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:03:14.728Z,1537304594.728 [DataOverHttps](INFO): Moved sent file to Logs/20180918T205225/Express0005.lzma.bak
2018-09-18T21:03:14.728Z,1537304594.728 [DataOverHttps](INFO): SBD MOMSN=8531812
2018-09-18T21:03:14.750Z,1537304594.750 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-18T21:03:14.750Z,1537304594.750 [NAL9602] Data Fault, FailCount= 1
2018-09-18T21:03:14.751Z,1537304594.751 [NAL9602](ERROR): Data Fault
2018-09-18T21:03:14.787Z,1537304594.787 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:03:14.794Z,1537304594.794 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:03:14.889Z,1537304594.889 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-18T21:03:15.441Z,1537304595.441 [NAL9602](INFO): Powering down
2018-09-18T21:03:15.528Z,1537304595.528 [Default:CheckIn:Read_Iridium] Stopped
2018-09-18T21:03:15.528Z,1537304595.528 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-18T21:03:15.528Z,1537304595.528 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-18T21:03:15.664Z,1537304595.664 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:03:18.696Z,1537304598.696 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:03:18.757Z,1537304598.757 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-18T21:03:18.757Z,1537304598.757 [NAL9602] No Fault, FailCount= 1
2018-09-18T21:03:21.602Z,1537304601.602 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:03:21.682Z,1537304601.682 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:03:22.002Z,1537304602.002 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:03:22.010Z,1537304602.010 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:03:22.816Z,1537304602.816 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:03:22.817Z,1537304602.817 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:03:27.634Z,1537304607.634 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:03:27.783Z,1537304607.783 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:03:27.995Z,1537304607.995 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:03:28.003Z,1537304608.003 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:03:28.808Z,1537304608.808 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:03:29.600Z,1537304609.600 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:03:33.999Z,1537304613.999 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:03:34.079Z,1537304614.079 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:03:34.399Z,1537304614.399 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:03:34.406Z,1537304614.406 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:03:35.216Z,1537304615.216 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:03:36.404Z,1537304616.404 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:03:41.206Z,1537304621.206 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:03:41.287Z,1537304621.287 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:03:41.602Z,1537304621.602 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:03:41.611Z,1537304621.611 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:03:42.002Z,1537304622.002 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:03:42.416Z,1537304622.416 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:03:45.570Z,1537304625.570 [NAL9602](INFO): Powering up NAL9602
2018-09-18T21:03:47.209Z,1537304627.209 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:03:47.278Z,1537304627.278 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:03:47.604Z,1537304627.604 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:03:47.612Z,1537304627.612 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:03:48.417Z,1537304628.417 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:03:49.606Z,1537304629.606 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:03:54.399Z,1537304634.399 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:03:54.489Z,1537304634.489 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:03:54.799Z,1537304634.799 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:03:54.808Z,1537304634.808 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:03:55.612Z,1537304635.612 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:03:56.018Z,1537304636.018 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:03:56.370Z,1537304636.370 [NAL9602](INFO): NAL9602 initialized
2018-09-18T21:04:00.803Z,1537304640.803 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:04:00.886Z,1537304640.886 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:04:01.205Z,1537304641.205 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:04:01.213Z,1537304641.213 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:04:02.028Z,1537304642.028 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:04:02.818Z,1537304642.818 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:04:06.800Z,1537304646.800 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:04:06.879Z,1537304646.879 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:04:07.203Z,1537304647.203 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:04:07.210Z,1537304647.210 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:04:08.020Z,1537304648.020 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:04:09.203Z,1537304649.203 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:04:14.414Z,1537304654.414 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:04:14.496Z,1537304654.496 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:04:14.496Z,1537304654.496 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:04:14.812Z,1537304654.812 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:04:14.821Z,1537304654.821 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:04:15.616Z,1537304655.616 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:04:19.125Z,1537304659.125 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:04:19.202Z,1537304659.202 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:04:19.312Z,1537304659.312 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:04:19.335Z,1537304659.335 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:04:20.108Z,1537304660.108 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:04:22.897Z,1537304662.897 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:04:27.271Z,1537304667.271 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:04:27.351Z,1537304667.351 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:04:27.351Z,1537304667.351 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:04:27.682Z,1537304667.682 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:04:27.709Z,1537304667.709 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:04:28.497Z,1537304668.497 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:04:32.073Z,1537304672.073 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:04:32.151Z,1537304672.151 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:04:32.485Z,1537304672.485 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:04:32.494Z,1537304672.494 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:04:33.284Z,1537304673.284 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:04:35.281Z,1537304675.281 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:04:39.300Z,1537304679.300 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T21:04:39.300Z,1537304679.300 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-09-18T21:04:39.300Z,1537304679.300 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T21:04:39.333Z,1537304679.333 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T21:04:39.666Z,1537304679.666 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:04:39.745Z,1537304679.745 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:04:39.766Z,1537304679.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T21:04:39.766Z,1537304679.766 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-09-18T21:04:40.096Z,1537304680.096 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:04:40.103Z,1537304680.103 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:04:40.112Z,1537304680.112 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:04:40.112Z,1537304680.112 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:04:40.113Z,1537304680.113 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:04:40.908Z,1537304680.908 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:04:40.909Z,1537304680.909 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:04:42.470Z,1537304682.470 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:04:45.674Z,1537304685.674 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:04:45.758Z,1537304685.758 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:04:46.080Z,1537304686.080 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:04:46.120Z,1537304686.120 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:04:46.885Z,1537304686.885 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:04:46.918Z,1537304686.918 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:04:46.932Z,1537304686.932 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:04:47.272Z,1537304687.272 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:04:47.281Z,1537304687.281 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:04:48.088Z,1537304688.088 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:04:54.075Z,1537304694.075 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:04:55.301Z,1537304695.301 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:04:58.491Z,1537304698.491 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:04:58.560Z,1537304698.560 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:04:58.888Z,1537304698.888 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:04:58.895Z,1537304698.895 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:04:59.684Z,1537304699.684 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:05:00.079Z,1537304700.079 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:05:00.154Z,1537304700.154 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:05:00.477Z,1537304700.477 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:05:00.485Z,1537304700.485 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:05:01.304Z,1537304701.304 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:05:01.305Z,1537304701.305 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:05:04.893Z,1537304704.893 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:05:04.908Z,1537304704.908 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:05:05.273Z,1537304705.273 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:05:05.280Z,1537304705.280 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:05:06.096Z,1537304706.096 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:05:08.875Z,1537304708.875 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:05:12.888Z,1537304712.888 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:05:13.695Z,1537304713.695 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:05:13.846Z,1537304713.846 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:05:14.091Z,1537304714.091 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:05:14.103Z,1537304714.103 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:05:14.909Z,1537304714.909 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:05:20.065Z,1537304720.065 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:05:20.146Z,1537304720.146 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:05:20.285Z,1537304720.285 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:05:20.293Z,1537304720.293 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:05:21.033Z,1537304721.033 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:05:22.222Z,1537304722.222 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:05:26.627Z,1537304726.627 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:05:26.702Z,1537304726.702 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:05:27.008Z,1537304727.008 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:05:27.016Z,1537304727.016 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:05:27.859Z,1537304727.859 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:05:28.230Z,1537304728.230 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:05:33.013Z,1537304733.013 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:05:33.279Z,1537304733.279 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:05:33.416Z,1537304733.416 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:05:33.424Z,1537304733.424 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:05:34.237Z,1537304734.237 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:05:34.627Z,1537304734.627 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:05:39.822Z,1537304739.822 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:05:39.907Z,1537304739.907 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:05:40.222Z,1537304740.222 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:05:40.231Z,1537304740.231 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:05:41.037Z,1537304741.037 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:05:41.417Z,1537304741.417 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:05:46.626Z,1537304746.626 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:05:46.704Z,1537304746.704 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:05:47.008Z,1537304747.008 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:05:47.049Z,1537304747.049 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:05:47.837Z,1537304747.837 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:05:48.230Z,1537304748.230 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:05:53.012Z,1537304753.012 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:05:53.096Z,1537304753.096 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:05:53.407Z,1537304753.407 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:05:53.440Z,1537304753.440 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:05:54.248Z,1537304754.248 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:05:55.407Z,1537304755.407 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:05:59.415Z,1537304759.415 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:05:59.493Z,1537304759.493 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:05:59.818Z,1537304759.818 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:05:59.825Z,1537304759.825 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:06:00.636Z,1537304760.636 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:06:01.416Z,1537304761.416 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:06:06.630Z,1537304766.630 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:06:06.705Z,1537304766.705 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:06:07.021Z,1537304767.021 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:06:07.029Z,1537304767.029 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:06:07.029Z,1537304767.029 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:06:07.837Z,1537304767.837 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:06:11.423Z,1537304771.423 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:06:11.538Z,1537304771.538 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:06:11.824Z,1537304771.824 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:06:11.832Z,1537304771.832 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:06:12.637Z,1537304772.637 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:06:13.857Z,1537304773.857 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:06:15.021Z,1537304775.021 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:06:20.785Z,1537304780.785 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:06:20.887Z,1537304780.887 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:06:20.887Z,1537304780.887 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:06:20.912Z,1537304780.912 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:06:21.049Z,1537304781.049 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:06:21.062Z,1537304781.062 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:06:21.063Z,1537304781.063 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:06:21.071Z,1537304781.071 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:06:21.846Z,1537304781.846 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:06:21.860Z,1537304781.860 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:06:23.434Z,1537304783.434 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:06:27.025Z,1537304787.025 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:06:27.041Z,1537304787.041 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:06:27.427Z,1537304787.427 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:06:27.435Z,1537304787.435 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:06:28.248Z,1537304788.248 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:06:29.027Z,1537304789.027 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:06:33.842Z,1537304793.842 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:06:33.915Z,1537304793.915 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:06:34.237Z,1537304794.237 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:06:34.246Z,1537304794.246 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:06:35.043Z,1537304795.043 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:06:35.423Z,1537304795.423 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:06:40.230Z,1537304800.230 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:06:40.308Z,1537304800.308 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:06:40.631Z,1537304800.631 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:06:40.640Z,1537304800.640 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:06:41.440Z,1537304801.440 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:06:42.241Z,1537304802.241 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:06:47.016Z,1537304807.016 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:06:47.101Z,1537304807.101 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:06:47.480Z,1537304807.480 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:06:47.543Z,1537304807.543 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:06:48.252Z,1537304808.252 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:06:48.634Z,1537304808.634 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:06:53.421Z,1537304813.421 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:06:53.510Z,1537304813.510 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:06:53.831Z,1537304813.831 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:06:53.863Z,1537304813.863 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:06:54.648Z,1537304814.648 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:06:55.424Z,1537304815.424 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:06:59.842Z,1537304819.842 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:06:59.912Z,1537304819.912 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:07:00.240Z,1537304820.240 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:07:00.247Z,1537304820.247 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:07:01.037Z,1537304821.037 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:07:01.837Z,1537304821.837 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:07:06.633Z,1537304826.633 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:07:06.710Z,1537304826.710 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:07:07.027Z,1537304827.027 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:07:07.035Z,1537304827.035 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:07:07.844Z,1537304827.844 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:07:08.236Z,1537304828.236 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:07:13.024Z,1537304833.024 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:07:13.094Z,1537304833.094 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:07:13.426Z,1537304833.426 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:07:13.434Z,1537304833.434 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:07:14.248Z,1537304834.248 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:07:14.638Z,1537304834.638 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:07:19.356Z,1537304839.356 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:07:19.435Z,1537304839.435 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:07:19.561Z,1537304839.561 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:07:19.568Z,1537304839.568 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:07:20.320Z,1537304840.320 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:07:21.911Z,1537304841.911 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:07:26.306Z,1537304846.306 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:07:26.382Z,1537304846.382 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:07:26.703Z,1537304846.703 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:07:26.704Z,1537304846.704 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:07:26.712Z,1537304846.712 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:07:27.528Z,1537304847.528 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:07:31.500Z,1537304851.500 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:07:31.581Z,1537304851.581 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:07:31.895Z,1537304851.895 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:07:31.916Z,1537304851.916 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:07:32.716Z,1537304852.716 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:07:35.110Z,1537304855.110 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:07:37.136Z,1537304857.136 [CBIT](INFO): Clearing failed state for component DropWeight
2018-09-18T21:07:37.136Z,1537304857.136 [DropWeight] No Fault, FailCount= 1
2018-09-18T21:07:39.919Z,1537304859.919 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:07:39.999Z,1537304859.999 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:07:40.302Z,1537304860.302 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:07:40.302Z,1537304860.302 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:07:40.323Z,1537304860.323 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:07:40.353Z,1537304860.353 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T21:07:40.353Z,1537304860.353 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-09-18T21:07:40.353Z,1537304860.353 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T21:07:40.378Z,1537304860.378 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T21:07:40.748Z,1537304860.748 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T21:07:40.748Z,1537304860.748 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-09-18T21:07:41.113Z,1537304861.113 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:07:41.127Z,1537304861.127 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:07:41.127Z,1537304861.127 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:07:41.128Z,1537304861.128 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:07:45.122Z,1537304865.122 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:07:45.205Z,1537304865.205 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:07:45.518Z,1537304865.518 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:07:45.526Z,1537304865.526 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:07:46.313Z,1537304866.313 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:07:48.710Z,1537304868.710 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:07:52.709Z,1537304872.709 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:07:52.776Z,1537304872.776 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:07:53.106Z,1537304873.106 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:07:53.113Z,1537304873.113 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:07:53.914Z,1537304873.914 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:07:53.924Z,1537304873.924 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:07:55.505Z,1537304875.505 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:07:58.696Z,1537304878.696 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:07:58.781Z,1537304878.781 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:07:59.102Z,1537304879.102 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:07:59.122Z,1537304879.122 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:07:59.525Z,1537304879.525 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:07:59.554Z,1537304879.554 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:07:59.928Z,1537304879.928 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:07:59.929Z,1537304879.929 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:07:59.936Z,1537304879.936 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:08:00.729Z,1537304880.729 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:08:07.496Z,1537304887.496 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:08:07.497Z,1537304887.497 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:08:11.914Z,1537304891.914 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:08:11.992Z,1537304891.992 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:08:12.313Z,1537304892.313 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:08:12.321Z,1537304892.321 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:08:12.321Z,1537304892.321 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:08:12.433Z,1537304892.433 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:08:12.729Z,1537304892.729 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:08:12.761Z,1537304892.761 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:08:13.112Z,1537304893.112 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:08:13.909Z,1537304893.909 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:08:14.696Z,1537304894.696 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:08:15.932Z,1537304895.932 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-09-18T21:08:15.932Z,1537304895.932 [Default:CheckIn:C.Wait] Stopped
2018-09-18T21:08:15.932Z,1537304895.932 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-18T21:08:15.932Z,1537304895.932 [Default:CheckIn:D] Running Loop=1
2018-09-18T21:08:16.331Z,1537304896.331 [Default:CheckIn:D] Stopped
2018-09-18T21:08:16.331Z,1537304896.331 [Default:CheckIn:E] Running Loop=1
2018-09-18T21:08:19.426Z,1537304899.426 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:08:19.440Z,1537304899.440 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:08:19.451Z,1537304899.451 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.508600 min 
2018-09-18T21:08:19.453Z,1537304899.453 [Default:CheckIn:E] Stopped
2018-09-18T21:08:19.453Z,1537304899.453 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-09-18T21:08:19.453Z,1537304899.453 [Default:CheckIn] Stopped
2018-09-18T21:08:19.453Z,1537304899.453 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-18T21:08:19.454Z,1537304899.454 [Default:CheckIn](INFO): Running loop #2
2018-09-18T21:08:19.454Z,1537304899.454 [Default:CheckIn] Running Loop=2
2018-09-18T21:08:19.454Z,1537304899.454 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-18T21:08:19.454Z,1537304899.454 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-18T21:08:19.613Z,1537304899.613 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:08:19.634Z,1537304899.634 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:08:20.400Z,1537304900.400 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:08:20.779Z,1537304900.779 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:08:25.977Z,1537304905.977 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:08:26.055Z,1537304906.055 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:08:26.380Z,1537304906.380 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:08:26.388Z,1537304906.388 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:08:27.157Z,1537304907.157 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:08:27.169Z,1537304907.169 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:08:32.373Z,1537304912.373 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:08:32.453Z,1537304912.453 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:08:32.772Z,1537304912.772 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:08:32.781Z,1537304912.781 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:08:33.969Z,1537304913.969 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:08:34.756Z,1537304914.756 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:08:39.565Z,1537304919.565 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:08:39.643Z,1537304919.643 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:08:39.966Z,1537304919.966 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:08:39.975Z,1537304919.975 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:08:40.780Z,1537304920.780 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:08:41.181Z,1537304921.181 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:08:45.960Z,1537304925.960 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:08:46.044Z,1537304926.044 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:08:46.360Z,1537304926.360 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:08:46.368Z,1537304926.368 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:08:47.180Z,1537304927.180 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:08:47.974Z,1537304927.974 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:08:52.369Z,1537304932.369 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:08:52.442Z,1537304932.442 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:08:52.772Z,1537304932.772 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:08:52.779Z,1537304932.779 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:08:53.588Z,1537304933.588 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:08:54.755Z,1537304934.755 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:08:59.582Z,1537304939.582 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:08:59.659Z,1537304939.659 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:08:59.980Z,1537304939.980 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:08:59.988Z,1537304939.988 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:09:00.373Z,1537304940.373 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:09:00.773Z,1537304940.773 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:09:04.764Z,1537304944.764 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:09:04.837Z,1537304944.837 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:09:05.183Z,1537304945.183 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:09:05.191Z,1537304945.191 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:09:05.993Z,1537304945.993 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:09:07.561Z,1537304947.561 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:09:07.562Z,1537304947.562 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:09:11.977Z,1537304951.977 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:09:12.059Z,1537304952.059 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:09:12.059Z,1537304952.059 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:09:12.076Z,1537304952.076 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:09:12.365Z,1537304952.365 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:09:12.380Z,1537304952.380 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:09:12.381Z,1537304952.381 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:09:12.409Z,1537304952.409 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:09:13.184Z,1537304953.184 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:09:13.196Z,1537304953.196 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:09:19.974Z,1537304959.974 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:09:20.389Z,1537304960.389 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:09:24.767Z,1537304964.767 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:09:24.837Z,1537304964.837 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:09:25.174Z,1537304965.174 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:09:25.182Z,1537304965.182 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:09:25.182Z,1537304965.182 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:09:25.302Z,1537304965.302 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:09:25.581Z,1537304965.581 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:09:25.589Z,1537304965.589 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:09:25.988Z,1537304965.988 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:09:26.772Z,1537304966.772 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:09:33.161Z,1537304973.161 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:09:33.974Z,1537304973.974 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:09:38.365Z,1537304978.365 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:09:38.439Z,1537304978.439 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:09:38.439Z,1537304978.439 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:09:38.517Z,1537304978.517 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:09:38.752Z,1537304978.752 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:09:38.789Z,1537304978.789 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:09:38.789Z,1537304978.789 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:09:38.805Z,1537304978.805 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:09:39.569Z,1537304979.569 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:09:39.581Z,1537304979.581 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:09:46.782Z,1537304986.782 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:09:46.782Z,1537304986.782 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:09:50.756Z,1537304990.756 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:09:50.892Z,1537304990.892 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:09:51.157Z,1537304991.157 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:09:51.164Z,1537304991.164 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:09:51.563Z,1537304991.563 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:09:51.655Z,1537304991.655 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:09:51.970Z,1537304991.970 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:09:51.999Z,1537304991.999 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:09:52.013Z,1537304992.013 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:09:52.776Z,1537304992.776 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:09:58.384Z,1537304998.384 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:10:00.361Z,1537305000.361 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:10:02.752Z,1537305002.752 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:10:02.817Z,1537305002.817 [CommandLine](IMPORTANT): got command get BPC1.batterySamplingInterval
2018-09-18T21:10:02.818Z,1537305002.818 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval 1.000000 h
2018-09-18T21:10:02.844Z,1537305002.844 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:10:03.153Z,1537305003.153 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:10:03.160Z,1537305003.160 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:10:03.981Z,1537305003.981 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:10:04.785Z,1537305004.785 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:10:04.854Z,1537305004.854 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:10:05.156Z,1537305005.156 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:10:05.163Z,1537305005.163 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:10:05.573Z,1537305005.573 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:10:05.972Z,1537305005.972 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:10:07.567Z,1537305007.567 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:10:09.566Z,1537305009.566 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:10:09.583Z,1537305009.583 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:10:09.977Z,1537305009.977 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:10:09.985Z,1537305009.985 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:10:10.788Z,1537305010.788 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:10:11.152Z,1537305011.152 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:10:11.184Z,1537305011.184 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:10:11.552Z,1537305011.552 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:10:11.559Z,1537305011.559 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:10:12.381Z,1537305012.381 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:10:19.907Z,1537305019.907 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:10:19.908Z,1537305019.908 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:10:22.783Z,1537305022.783 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:10:22.864Z,1537305022.864 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:10:23.194Z,1537305023.194 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:10:23.202Z,1537305023.202 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:10:24.004Z,1537305024.004 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:10:24.401Z,1537305024.401 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:10:24.492Z,1537305024.492 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:10:24.769Z,1537305024.769 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:10:24.805Z,1537305024.805 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:10:25.588Z,1537305025.588 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:10:31.177Z,1537305031.177 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:10:31.641Z,1537305031.641 [CommandLine](IMPORTANT): got command get platform_battery_charge
2018-09-18T21:10:31.641Z,1537305031.641 [CommandLine](FAULT): Element has no value
2018-09-18T21:10:33.167Z,1537305033.167 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:10:35.982Z,1537305035.982 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:10:36.147Z,1537305036.147 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:10:36.392Z,1537305036.392 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:10:36.408Z,1537305036.408 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:10:37.181Z,1537305037.181 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:10:37.990Z,1537305037.990 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:10:38.074Z,1537305038.074 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:10:38.394Z,1537305038.394 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:10:38.402Z,1537305038.402 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:10:39.181Z,1537305039.181 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:10:41.210Z,1537305041.210 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T21:10:41.210Z,1537305041.210 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-09-18T21:10:41.210Z,1537305041.210 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T21:10:41.222Z,1537305041.222 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T21:10:41.615Z,1537305041.615 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T21:10:41.615Z,1537305041.615 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-09-18T21:10:42.011Z,1537305042.011 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:10:42.011Z,1537305042.011 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:10:42.011Z,1537305042.011 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:10:43.982Z,1537305043.982 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:10:46.378Z,1537305046.378 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:10:48.368Z,1537305048.368 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:10:48.489Z,1537305048.489 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:10:48.769Z,1537305048.769 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:10:48.776Z,1537305048.776 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:10:49.608Z,1537305049.608 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:10:51.173Z,1537305051.173 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:10:51.264Z,1537305051.264 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:10:51.585Z,1537305051.585 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:10:51.624Z,1537305051.624 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:10:52.388Z,1537305052.388 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:10:55.984Z,1537305055.984 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:10:56.726Z,1537305056.726 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_charge
2018-09-18T21:10:56.727Z,1537305056.727 [CommandLine](IMPORTANT): BPC1.platform_battery_charge nan Ah
2018-09-18T21:10:59.594Z,1537305059.594 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:11:00.368Z,1537305060.368 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:11:00.448Z,1537305060.448 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:11:00.769Z,1537305060.769 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:11:00.776Z,1537305060.776 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:11:01.592Z,1537305061.592 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:11:03.180Z,1537305063.180 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:11:03.968Z,1537305063.968 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:11:04.054Z,1537305064.054 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:11:04.377Z,1537305064.377 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:11:04.390Z,1537305064.390 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:11:05.208Z,1537305065.208 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:11:07.578Z,1537305067.578 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:11:07.594Z,1537305067.594 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:11:07.989Z,1537305067.989 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:11:07.998Z,1537305067.998 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:11:08.804Z,1537305068.804 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:11:11.987Z,1537305071.987 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:11:13.806Z,1537305073.806 [CommandLine](IMPORTANT): got command restart application
2018-09-18T21:11:14.815Z,1537305074.815 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:11:14.816Z,1537305074.816 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.012Z,1537305075.012 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-18T21:11:15.013Z,1537305075.013 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.013Z,1537305075.013 [CommandLine](INFO): Join timeout helper Thread ID is 492
2018-09-18T21:11:15.014Z,1537305075.014 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-18T21:11:15.014Z,1537305075.014 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.014Z,1537305075.014 [NavChartDb](INFO): Join timeout helper Thread ID is 493
2018-09-18T21:11:15.101Z,1537305075.101 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:11:15.101Z,1537305075.101 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.113Z,1537305075.113 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-09-18T21:11:15.113Z,1537305075.113 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.113Z,1537305075.113 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 494
2018-09-18T21:11:15.114Z,1537305075.114 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:11:15.114Z,1537305075.114 [WetLabsBB2FL](INFO): Powering down
2018-09-18T21:11:15.115Z,1537305075.115 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.115Z,1537305075.115 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-09-18T21:11:15.115Z,1537305075.115 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.116Z,1537305075.116 [CTD_Seabird](INFO): Join timeout helper Thread ID is 495
2018-09-18T21:11:15.353Z,1537305075.353 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:11:15.441Z,1537305075.441 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-18T21:11:15.441Z,1537305075.441 [CTD_Seabird](INFO): Powering down
2018-09-18T21:11:15.442Z,1537305075.442 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.448Z,1537305075.448 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-09-18T21:11:15.449Z,1537305075.449 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.449Z,1537305075.449 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 497
2018-09-18T21:11:15.729Z,1537305075.729 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:11:15.729Z,1537305075.729 [CTD_NeilBrown](INFO): Powering down
2018-09-18T21:11:15.730Z,1537305075.730 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.733Z,1537305075.733 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-18T21:11:15.734Z,1537305075.734 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:15.734Z,1537305075.734 [Radio_Surface](INFO): Join timeout helper Thread ID is 498
2018-09-18T21:11:15.980Z,1537305075.980 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:11:16.101Z,1537305076.101 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:11:16.101Z,1537305076.101 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.118Z,1537305076.118 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-18T21:11:16.118Z,1537305076.118 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.119Z,1537305076.119 [logger](INFO): Join timeout helper Thread ID is 499
2018-09-18T21:11:16.119Z,1537305076.119 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:11:16.119Z,1537305076.119 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.120Z,1537305076.120 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-18T21:11:16.121Z,1537305076.121 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.121Z,1537305076.121 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-18T21:11:16.121Z,1537305076.121 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.121Z,1537305076.121 [controlThread](INFO): Join timeout helper Thread ID is 500
2018-09-18T21:11:16.333Z,1537305076.333 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:11:16.333Z,1537305076.333 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-18T21:11:16.334Z,1537305076.334 [NAL9602](INFO): Powering down
2018-09-18T21:11:16.335Z,1537305076.335 [PNI_TCM](INFO): Powering down
2018-09-18T21:11:16.422Z,1537305076.422 [Aanderaa_O2](INFO): Powering down
2018-09-18T21:11:16.423Z,1537305076.423 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-18T21:11:16.425Z,1537305076.425 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-18T21:11:16.425Z,1537305076.425 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-18T21:11:16.426Z,1537305076.426 [MissionManager](INFO): Uninitializing Mission Default
2018-09-18T21:11:16.426Z,1537305076.426 [Default] Stopped
2018-09-18T21:11:16.426Z,1537305076.426 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-18T21:11:16.426Z,1537305076.426 [Default:B.GoToSurface] Stopped
2018-09-18T21:11:16.426Z,1537305076.426 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-18T21:11:16.426Z,1537305076.426 [Default:CheckIn] Stopped
2018-09-18T21:11:16.427Z,1537305076.427 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-18T21:11:16.427Z,1537305076.427 [Default:CheckIn:Read_GPS] Stopped
2018-09-18T21:11:16.431Z,1537305076.431 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-18T21:11:16.431Z,1537305076.431 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-18T21:11:16.431Z,1537305076.431 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-18T21:11:16.432Z,1537305076.432 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-18T21:11:16.432Z,1537305076.432 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-18T21:11:16.432Z,1537305076.432 [BuoyancyServo](INFO): Powering down
2018-09-18T21:11:16.445Z,1537305076.445 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-18T21:11:16.445Z,1537305076.445 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-18T21:11:16.445Z,1537305076.445 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-18T21:11:16.446Z,1537305076.446 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.563Z,1537305076.563 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.607Z,1537305076.607 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.612Z,1537305076.612 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.615Z,1537305076.615 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.644Z,1537305076.644 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:11:16.706Z,1537305076.706 [logger ThreadHandler](INFO): Thread cancelled.