2018-09-18T21:11:17.781Z,1537305077.781 [Supervisor](DEBUG): Initializing supervisor.
2018-09-18T21:11:17.785Z,1537305077.785 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-18T21:11:17.785Z,1537305077.785 [SyncHandler](INFO): Protected caller Thread ID is 501
2018-09-18T21:11:17.786Z,1537305077.786 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-18T21:11:17.787Z,1537305077.787 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-18T21:11:17.787Z,1537305077.787 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 502
2018-09-18T21:11:17.790Z,1537305077.790 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-18T21:11:17.803Z,1537305077.803 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-18T21:11:17.804Z,1537305077.804 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-18T21:11:17.805Z,1537305077.805 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 503
2018-09-18T21:11:17.806Z,1537305077.806 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-18T21:11:17.807Z,1537305077.807 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-18T21:11:17.807Z,1537305077.807 [logger ThreadHandler](INFO): Protected caller Thread ID is 504
2018-09-18T21:11:17.809Z,1537305077.809 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-18T21:11:17.810Z,1537305077.810 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-18T21:11:17.811Z,1537305077.811 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-18T21:11:18.173Z,1537305078.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-18T21:11:18.173Z,1537305078.173 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-18T21:11:18.389Z,1537305078.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-18T21:11:18.389Z,1537305078.389 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-18T21:11:18.706Z,1537305078.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-18T21:11:18.707Z,1537305078.707 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-18T21:11:18.852Z,1537305078.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-18T21:11:18.852Z,1537305078.852 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-18T21:11:18.952Z,1537305078.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-18T21:11:18.953Z,1537305078.953 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-18T21:11:19.446Z,1537305079.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-18T21:11:19.447Z,1537305079.447 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-18T21:11:19.909Z,1537305079.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-18T21:11:19.910Z,1537305079.910 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-18T21:11:20.405Z,1537305080.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-18T21:11:20.406Z,1537305080.406 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-18T21:11:20.513Z,1537305080.513 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-18T21:11:20.514Z,1537305080.514 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-18T21:11:20.613Z,1537305080.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-18T21:11:20.614Z,1537305080.614 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-18T21:11:20.829Z,1537305080.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-18T21:11:20.830Z,1537305080.830 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-18T21:11:21.042Z,1537305081.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-18T21:11:21.043Z,1537305081.043 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-18T21:11:21.410Z,1537305081.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-18T21:11:21.410Z,1537305081.410 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-18T21:11:21.557Z,1537305081.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-18T21:11:21.558Z,1537305081.558 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-18T21:11:21.641Z,1537305081.641 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-09-18T21:11:21.642Z,1537305081.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-09-18T21:11:21.748Z,1537305081.748 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-09-18T21:11:21.873Z,1537305081.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-09-18T21:11:21.969Z,1537305081.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-09-18T21:11:22.056Z,1537305082.056 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-09-18T21:11:22.222Z,1537305082.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-09-18T21:11:22.411Z,1537305082.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-09-18T21:11:22.509Z,1537305082.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-09-18T21:11:23.019Z,1537305083.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-18T21:11:23.020Z,1537305083.020 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-09-18T21:11:23.138Z,1537305083.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-09-18T21:11:23.490Z,1537305083.490 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-09-18T21:11:23.595Z,1537305083.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-09-18T21:11:23.729Z,1537305083.729 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-09-18T21:11:23.729Z,1537305083.729 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-09-18T21:11:23.746Z,1537305083.746 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-18T21:11:23.984Z,1537305083.984 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-18T21:11:23.985Z,1537305083.985 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-18T21:11:24.028Z,1537305084.028 [DepthRateCalculator] Loaded
2018-09-18T21:11:24.028Z,1537305084.028 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-18T21:11:24.035Z,1537305084.035 [PitchRateCalculator] Loaded
2018-09-18T21:11:24.035Z,1537305084.035 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-18T21:11:24.052Z,1537305084.052 [SpeedCalculator] Loaded
2018-09-18T21:11:24.053Z,1537305084.053 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-18T21:11:24.075Z,1537305084.075 [TempGradientCalculator] Loaded
2018-09-18T21:11:24.076Z,1537305084.076 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-18T21:11:24.092Z,1537305084.092 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-09-18T21:11:24.093Z,1537305084.093 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-09-18T21:11:24.099Z,1537305084.099 [YawRateCalculator] Loaded
2018-09-18T21:11:24.099Z,1537305084.099 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-18T21:11:24.142Z,1537305084.142 [ElevatorOffsetCalculator] Loaded
2018-09-18T21:11:24.142Z,1537305084.142 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-18T21:11:24.143Z,1537305084.143 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-18T21:11:24.143Z,1537305084.143 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-18T21:11:24.208Z,1537305084.208 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-18T21:11:24.209Z,1537305084.209 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-18T21:11:24.323Z,1537305084.323 [BuoyancyServo] Loaded
2018-09-18T21:11:24.323Z,1537305084.323 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-18T21:11:24.324Z,1537305084.324 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-18T21:11:24.325Z,1537305084.325 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-18T21:11:24.339Z,1537305084.339 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-18T21:11:24.339Z,1537305084.339 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-18T21:11:24.440Z,1537305084.440 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-18T21:11:24.440Z,1537305084.440 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-18T21:11:24.734Z,1537305084.734 [DataOverHttps] Loaded
2018-09-18T21:11:24.735Z,1537305084.735 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-18T21:11:24.749Z,1537305084.749 [Depth_Keller] Loaded
2018-09-18T21:11:24.750Z,1537305084.750 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-18T21:11:24.755Z,1537305084.755 [DropWeight] Loaded
2018-09-18T21:11:24.755Z,1537305084.755 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-18T21:11:24.857Z,1537305084.857 [NAL9602] Loaded
2018-09-18T21:11:24.858Z,1537305084.858 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-18T21:11:24.875Z,1537305084.875 [Onboard] Loaded
2018-09-18T21:11:24.875Z,1537305084.875 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-18T21:11:24.887Z,1537305084.887 [Radio_Surface] Loaded
2018-09-18T21:11:24.887Z,1537305084.887 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-18T21:11:24.888Z,1537305084.888 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088B4E0
2018-09-18T21:11:24.889Z,1537305084.889 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 586
2018-09-18T21:11:24.939Z,1537305084.939 [PNI_TCM] Loaded
2018-09-18T21:11:24.939Z,1537305084.939 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-09-18T21:11:24.961Z,1537305084.961 [BPC1](CRITICAL): No configuration setting for: BPC1.batteryCommsTimout
2018-09-18T21:11:24.965Z,1537305084.965 [BPC1](INFO): BPC1A: got serial number for stick 1: 0138
2018-09-18T21:11:24.967Z,1537305084.967 [BPC1](INFO): BPC1A: got serial number for stick 2: 0125
2018-09-18T21:11:24.968Z,1537305084.968 [BPC1](INFO): BPC1A: got serial number for stick 3: 00EF
2018-09-18T21:11:24.969Z,1537305084.969 [BPC1](INFO): BPC1A: got serial number for stick 4: 0158
2018-09-18T21:11:24.969Z,1537305084.969 [BPC1](INFO): BPC1A: got serial number for stick 5: 0088
2018-09-18T21:11:24.970Z,1537305084.970 [BPC1](INFO): BPC1A: got serial number for stick 6: 00A3
2018-09-18T21:11:24.971Z,1537305084.971 [BPC1](INFO): BPC1A: got serial number for stick 7: 012D
2018-09-18T21:11:24.972Z,1537305084.972 [BPC1](INFO): BPC1A: got serial number for stick 8: 00E7
2018-09-18T21:11:24.973Z,1537305084.973 [BPC1](INFO): BPC1A: got serial number for stick 9: 0100
2018-09-18T21:11:24.974Z,1537305084.974 [BPC1](INFO): BPC1A: got serial number for stick 10: 00B9
2018-09-18T21:11:24.974Z,1537305084.974 [BPC1](INFO): BPC1A: got serial number for stick 11: 008C
2018-09-18T21:11:24.975Z,1537305084.975 [BPC1](INFO): BPC1A: got serial number for stick 12: 0117
2018-09-18T21:11:24.976Z,1537305084.976 [BPC1](INFO): BPC1A: got serial number for stick 13: 00E4
2018-09-18T21:11:24.977Z,1537305084.977 [BPC1](INFO): BPC1A: got serial number for stick 14: 011D
2018-09-18T21:11:24.978Z,1537305084.978 [BPC1](INFO): BPC1A: got serial number for stick 15: 014E
2018-09-18T21:11:24.978Z,1537305084.978 [BPC1](INFO): BPC1A: got serial number for stick 16: 00EC
2018-09-18T21:11:24.979Z,1537305084.979 [BPC1](INFO): BPC1A: got serial number for stick 17: 008A
2018-09-18T21:11:24.980Z,1537305084.980 [BPC1](INFO): BPC1A: got serial number for stick 18: 0140
2018-09-18T21:11:24.981Z,1537305084.981 [BPC1](INFO): BPC1A: got serial number for stick 19: 00E5
2018-09-18T21:11:24.982Z,1537305084.982 [BPC1](INFO): BPC1A: got serial number for stick 20: 00E0
2018-09-18T21:11:24.983Z,1537305084.983 [BPC1](INFO): BPC1A: got serial number for stick 21: 00CD
2018-09-18T21:11:24.983Z,1537305084.983 [BPC1](INFO): BPC1A: got serial number for stick 22: 009F
2018-09-18T21:11:24.984Z,1537305084.984 [BPC1](INFO): BPC1A: got serial number for stick 23: 009C
2018-09-18T21:11:24.985Z,1537305084.985 [BPC1](INFO): BPC1A: got serial number for stick 24: 00A6
2018-09-18T21:11:24.986Z,1537305084.986 [BPC1](INFO): BPC1A: got serial number for stick 25: 00E8
2018-09-18T21:11:24.986Z,1537305084.986 [BPC1](INFO): BPC1A: got serial number for stick 26: 00D1
2018-09-18T21:11:24.987Z,1537305084.987 [BPC1](INFO): BPC1A: got serial number for stick 27: 00F3
2018-09-18T21:11:24.988Z,1537305084.988 [BPC1](INFO): BPC1A: got serial number for stick 28: 0092
2018-09-18T21:11:24.989Z,1537305084.989 [BPC1](INFO): BPC1A: got serial number for stick 29: 00AD
2018-09-18T21:11:24.990Z,1537305084.990 [BPC1](INFO): BPC1A: got serial number for stick 30: 00E9
2018-09-18T21:11:24.990Z,1537305084.990 [BPC1](INFO): BPC1A: got serial number for stick 31: 0126
2018-09-18T21:11:24.992Z,1537305084.992 [BPC1](INFO): BPC1B: got serial number for stick 32: 0157
2018-09-18T21:11:24.993Z,1537305084.993 [BPC1](INFO): BPC1B: got serial number for stick 33: 00F1
2018-09-18T21:11:24.994Z,1537305084.994 [BPC1](INFO): BPC1B: got serial number for stick 34: 00BF
2018-09-18T21:11:24.994Z,1537305084.994 [BPC1](INFO): BPC1B: got serial number for stick 35: 0089
2018-09-18T21:11:24.995Z,1537305084.995 [BPC1](INFO): BPC1B: got serial number for stick 36: 00C2
2018-09-18T21:11:24.996Z,1537305084.996 [BPC1](INFO): BPC1B: got serial number for stick 37: 00CA
2018-09-18T21:11:24.997Z,1537305084.997 [BPC1](INFO): BPC1B: got serial number for stick 38: 011A
2018-09-18T21:11:24.998Z,1537305084.998 [BPC1](INFO): BPC1B: got serial number for stick 39: 00FD
2018-09-18T21:11:24.999Z,1537305084.999 [BPC1](INFO): BPC1B: got serial number for stick 40: 0095
2018-09-18T21:11:24.999Z,1537305084.999 [BPC1](INFO): BPC1B: got serial number for stick 41: 00BE
2018-09-18T21:11:25.000Z,1537305085.000 [BPC1](INFO): BPC1B: got serial number for stick 42: 0086
2018-09-18T21:11:25.001Z,1537305085.001 [BPC1](INFO): BPC1B: got serial number for stick 43: 00AE
2018-09-18T21:11:25.002Z,1537305085.002 [BPC1](INFO): BPC1B: got serial number for stick 44: 00CB
2018-09-18T21:11:25.003Z,1537305085.003 [BPC1](INFO): BPC1B: got serial number for stick 45: 00F7
2018-09-18T21:11:25.003Z,1537305085.003 [BPC1](INFO): BPC1B: got serial number for stick 46: 00A1
2018-09-18T21:11:25.004Z,1537305085.004 [BPC1](INFO): BPC1B: got serial number for stick 47: 00F0
2018-09-18T21:11:25.005Z,1537305085.005 [BPC1](INFO): BPC1B: got serial number for stick 48: 00C9
2018-09-18T21:11:25.006Z,1537305085.006 [BPC1](INFO): BPC1B: got serial number for stick 49: 0103
2018-09-18T21:11:25.007Z,1537305085.007 [BPC1](INFO): BPC1B: got serial number for stick 50: 0152
2018-09-18T21:11:25.007Z,1537305085.007 [BPC1](INFO): BPC1B: got serial number for stick 51: 014A
2018-09-18T21:11:25.008Z,1537305085.008 [BPC1](INFO): BPC1B: got serial number for stick 52: 0114
2018-09-18T21:11:25.009Z,1537305085.009 [BPC1](INFO): BPC1B: got serial number for stick 53: 015E
2018-09-18T21:11:25.010Z,1537305085.010 [BPC1](INFO): BPC1B: got serial number for stick 54: 008E
2018-09-18T21:11:25.011Z,1537305085.011 [BPC1](INFO): BPC1B: got serial number for stick 55: 00AC
2018-09-18T21:11:25.011Z,1537305085.011 [BPC1](INFO): BPC1B: got serial number for stick 56: 0091
2018-09-18T21:11:25.012Z,1537305085.012 [BPC1](INFO): BPC1B: got serial number for stick 57: 0153
2018-09-18T21:11:25.013Z,1537305085.013 [BPC1](INFO): BPC1B: got serial number for stick 58: 00AF
2018-09-18T21:11:25.014Z,1537305085.014 [BPC1](INFO): BPC1B: got serial number for stick 59: 0151
2018-09-18T21:11:25.015Z,1537305085.015 [BPC1](INFO): BPC1B: got serial number for stick 60: 00DC
2018-09-18T21:11:25.015Z,1537305085.015 [BPC1](INFO): BPC1B: got serial number for stick 61: 00BA
2018-09-18T21:11:25.016Z,1537305085.016 [BPC1](INFO): BPC1B: got serial number for stick 62: 00DA
2018-09-18T21:11:26.775Z,1537305086.775 [BPC1] Loaded
2018-09-18T21:11:26.776Z,1537305086.776 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-18T21:11:26.776Z,1537305086.776 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-18T21:11:26.777Z,1537305086.777 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-18T21:11:26.911Z,1537305086.911 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-18T21:11:26.935Z,1537305086.935 [SBIT] Loaded
2018-09-18T21:11:26.935Z,1537305086.935 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-18T21:11:26.936Z,1537305086.936 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-18T21:11:26.948Z,1537305086.948 [IBIT] Loaded
2018-09-18T21:11:26.949Z,1537305086.949 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-18T21:11:26.952Z,1537305086.952 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-18T21:11:27.109Z,1537305087.109 [CBIT] Loaded
2018-09-18T21:11:27.109Z,1537305087.109 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-18T21:11:27.110Z,1537305087.110 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-18T21:11:27.111Z,1537305087.111 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-18T21:11:27.259Z,1537305087.259 [Aanderaa_O2] Loaded
2018-09-18T21:11:27.260Z,1537305087.260 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-09-18T21:11:27.340Z,1537305087.340 [CTD_NeilBrown] Loaded
2018-09-18T21:11:27.340Z,1537305087.340 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-09-18T21:11:27.342Z,1537305087.342 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4094F4E0
2018-09-18T21:11:27.342Z,1537305087.342 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 587
2018-09-18T21:11:27.395Z,1537305087.395 [CTD_Seabird] Loaded
2018-09-18T21:11:27.396Z,1537305087.396 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-09-18T21:11:27.397Z,1537305087.397 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4097F4E0
2018-09-18T21:11:27.397Z,1537305087.397 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 588
2018-09-18T21:11:27.415Z,1537305087.415 [ESPComponent] Loaded
2018-09-18T21:11:27.416Z,1537305087.416 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-09-18T21:11:27.431Z,1537305087.431 [PAR_Licor] Loaded
2018-09-18T21:11:27.431Z,1537305087.431 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-09-18T21:11:27.483Z,1537305087.483 [WetLabsBB2FL] Loaded
2018-09-18T21:11:27.484Z,1537305087.484 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-09-18T21:11:27.485Z,1537305087.485 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409AF4E0
2018-09-18T21:11:27.485Z,1537305087.485 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 589
2018-09-18T21:11:27.486Z,1537305087.486 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-18T21:11:27.486Z,1537305087.486 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-18T21:11:27.553Z,1537305087.553 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-18T21:11:27.652Z,1537305087.652 [VerticalControl] Loaded
2018-09-18T21:11:27.652Z,1537305087.652 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-18T21:11:27.653Z,1537305087.653 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-18T21:11:27.718Z,1537305087.718 [HorizontalControl] Loaded
2018-09-18T21:11:27.719Z,1537305087.719 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-18T21:11:27.719Z,1537305087.719 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-18T21:11:27.725Z,1537305087.725 [SpeedControl] Loaded
2018-09-18T21:11:27.725Z,1537305087.725 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-18T21:11:27.726Z,1537305087.726 [LoopControl](DEBUG): Construct LoopControl.
2018-09-18T21:11:27.726Z,1537305087.726 [LoopControl] Loaded
2018-09-18T21:11:27.727Z,1537305087.727 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-18T21:11:27.727Z,1537305087.727 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-18T21:11:27.728Z,1537305087.728 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-18T21:11:27.810Z,1537305087.810 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-18T21:11:27.810Z,1537305087.810 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-18T21:11:27.914Z,1537305087.914 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-18T21:11:27.914Z,1537305087.914 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-18T21:11:27.932Z,1537305087.932 [NavChart] Loaded
2018-09-18T21:11:27.932Z,1537305087.932 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-18T21:11:27.937Z,1537305087.937 [UniversalFixResidualReporter] Loaded
2018-09-18T21:11:27.937Z,1537305087.937 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-18T21:11:27.937Z,1537305087.937 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-18T21:11:27.941Z,1537305087.941 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-18T21:11:27.942Z,1537305087.942 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-18T21:11:27.949Z,1537305087.949 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-18T21:11:27.950Z,1537305087.950 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A3B4E0
2018-09-18T21:11:27.951Z,1537305087.951 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 590
2018-09-18T21:11:27.956Z,1537305087.956 [Supervisor](INFO): Main Thread ID is 9268
2018-09-18T21:11:27.956Z,1537305087.956 [Supervisor](DEBUG): Running supervisor.
2018-09-18T21:11:27.957Z,1537305087.957 [CommandLine ThreadHandler](INFO): Handler Thread ID is 591
2018-09-18T21:11:27.959Z,1537305087.959 [controlThread ThreadHandler](INFO): Handler Thread ID is 592
2018-09-18T21:11:27.960Z,1537305087.960 [controlThread](DEBUG): Initializing ControlThread
2018-09-18T21:11:27.961Z,1537305087.961 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-18T21:11:27.961Z,1537305087.961 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-18T21:11:27.961Z,1537305087.961 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-18T21:11:27.962Z,1537305087.962 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-18T21:11:27.962Z,1537305087.962 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-18T21:11:27.963Z,1537305087.963 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-18T21:11:27.963Z,1537305087.963 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-18T21:11:27.971Z,1537305087.971 [SBIT](INFO): Initialize SBIT Component.
2018-09-18T21:11:27.971Z,1537305087.971 [SBIT](IMPORTANT): git: 2018-09-06-6-gbaf5c46
2018-09-18T21:11:27.972Z,1537305087.972 [SBIT](INFO): git hash: baf5c469f70e7902a2cb0333843877cb31121fce
2018-09-18T21:11:27.972Z,1537305087.972 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-18T21:11:27.973Z,1537305087.973 [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-18T21:11:27.974Z,1537305087.974 [SBIT](INFO): Beginning SBIT in 160.000000 seconds.
2018-09-18T21:11:27.975Z,1537305087.975 [IBIT](INFO): Initialize IBIT Component.
2018-09-18T21:11:27.976Z,1537305087.976 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-18T21:11:27.976Z,1537305087.976 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-09-18T21:11:27.977Z,1537305087.977 [logger ThreadHandler](INFO): Handler Thread ID is 593
2018-09-18T21:11:27.997Z,1537305087.997 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 594
2018-09-18T21:11:28.003Z,1537305088.003 [Radio_Surface](INFO): Powering up
2018-09-18T21:11:28.009Z,1537305088.009 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 595
2018-09-18T21:11:28.010Z,1537305088.010 [CTD_NeilBrown](INFO): Powering down
2018-09-18T21:11:28.037Z,1537305088.037 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 596
2018-09-18T21:11:28.038Z,1537305088.038 [CTD_Seabird](INFO): Initializing
2018-09-18T21:11:28.038Z,1537305088.038 [CTD_Seabird](INFO): Checking LCM
2018-09-18T21:11:28.047Z,1537305088.047 [CTD_Seabird](INFO): LCM OK
2018-09-18T21:11:28.047Z,1537305088.047 [CTD_Seabird](INFO): Powering up
2018-09-18T21:11:28.057Z,1537305088.057 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 597
2018-09-18T21:11:28.058Z,1537305088.058 [WetLabsBB2FL](INFO): Powering down
2018-09-18T21:11:28.085Z,1537305088.085 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 598
2018-09-18T21:11:28.093Z,1537305088.093 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-18T21:11:28.095Z,1537305088.095 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-18T21:11:28.096Z,1537305088.096 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-18T21:11:28.096Z,1537305088.096 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-18T21:11:28.097Z,1537305088.097 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:11:28.097Z,1537305088.097 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:11:28.098Z,1537305088.098 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:11:28.098Z,1537305088.098 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-18T21:11:28.099Z,1537305088.099 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-18T21:11:28.100Z,1537305088.100 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-18T21:11:28.107Z,1537305088.107 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-18T21:11:28.107Z,1537305088.107 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-18T21:11:28.108Z,1537305088.108 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-18T21:11:28.108Z,1537305088.108 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-18T21:11:28.108Z,1537305088.108 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-18T21:11:28.108Z,1537305088.108 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-18T21:11:28.109Z,1537305088.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-18T21:11:28.109Z,1537305088.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-18T21:11:28.109Z,1537305088.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-18T21:11:28.109Z,1537305088.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-18T21:11:28.109Z,1537305088.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-18T21:11:28.110Z,1537305088.110 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-18T21:11:28.110Z,1537305088.110 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-18T21:11:28.110Z,1537305088.110 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-18T21:11:28.110Z,1537305088.110 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-18T21:11:28.110Z,1537305088.110 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-18T21:11:28.145Z,1537305088.145 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-18T21:11:28.189Z,1537305088.189 [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-18T21:11:28.190Z,1537305088.190 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-18T21:11:28.270Z,1537305088.270 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-18T21:11:28.271Z,1537305088.271 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-18T21:11:28.289Z,1537305088.289 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-18T21:11:28.312Z,1537305088.312 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-18T21:11:28.315Z,1537305088.315 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-18T21:11:28.337Z,1537305088.337 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-18T21:11:28.357Z,1537305088.357 [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-18T21:11:28.361Z,1537305088.361 [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-18T21:11:28.387Z,1537305088.387 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-18T21:11:28.424Z,1537305088.424 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-09-18T21:11:28.757Z,1537305088.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-18T21:11:28.849Z,1537305088.849 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T21:11:28.977Z,1537305088.977 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-18T21:11:28.985Z,1537305088.985 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-18T21:11:29.021Z,1537305089.021 [CommandLine](FAULT): Scheduling is paused
2018-09-18T21:11:29.022Z,1537305089.022 [CBIT](INFO): Critical error at 20180918T211127
2018-09-18T21:11:29.022Z,1537305089.022 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-09-18T21:11:29.223Z,1537305089.223 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-09-18T21:11:29.223Z,1537305089.223 [DropWeight] Hardware Fault, FailCount= 1
2018-09-18T21:11:29.223Z,1537305089.223 [DropWeight](ERROR): Hardware Fault
2018-09-18T21:11:29.277Z,1537305089.277 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T21:11:29.288Z,1537305089.288 [CBIT](INFO): Critical error at 20180918T211129
2018-09-18T21:11:29.291Z,1537305089.291 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-09-18T21:11:29.292Z,1537305089.292 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-09-18T21:11:29.553Z,1537305089.553 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T21:11:29.897Z,1537305089.897 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-18T21:11:29.897Z,1537305089.897 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-18T21:11:29.897Z,1537305089.897 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-18T21:11:29.897Z,1537305089.897 [BuoyancyServo](ERROR): Communications Fault
2018-09-18T21:11:29.899Z,1537305089.899 [CBIT](INFO): Critical error at 20180918T211129
2018-09-18T21:11:29.901Z,1537305089.901 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-18T21:11:29.993Z,1537305089.993 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T21:11:29.999Z,1537305089.999 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-18T21:11:29.000Z,1537305090.000 [BuoyancyServo](INFO): Powering down
2018-09-18T21:11:30.361Z,1537305090.361 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T21:11:30.757Z,1537305090.757 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T21:11:31.180Z,1537305091.180 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T21:11:31.560Z,1537305091.560 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T21:11:32.069Z,1537305092.069 [Aanderaa_O2](INFO): Powering down
2018-09-18T21:11:32.402Z,1537305092.402 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-18T21:11:32.402Z,1537305092.402 [BuoyancyServo] No Fault, FailCount= 1
2018-09-18T21:11:32.794Z,1537305092.794 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-18T21:11:32.914Z,1537305092.914 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-18T21:11:33.504Z,1537305093.504 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-18T21:11:33.628Z,1537305093.628 [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-18T21:11:33.666Z,1537305093.666 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-09-18T21:11:34.018Z,1537305094.018 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-18T21:11:34.018Z,1537305094.018 [CTD_Seabird](INFO): Powering down
2018-09-18T21:11:55.114Z,1537305115.114 [NAL9602](INFO): Powering up NAL9602
2018-09-18T21:12:06.314Z,1537305126.314 [NAL9602](INFO): NAL9602 initialized
2018-09-18T21:12:29.572Z,1537305149.572 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:12:29.585Z,1537305149.585 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:12:36.740Z,1537305156.740 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:12:37.139Z,1537305157.139 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:12:41.151Z,1537305161.151 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:12:41.232Z,1537305161.232 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:12:41.548Z,1537305161.548 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:12:41.555Z,1537305161.555 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:12:41.946Z,1537305161.946 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:12:42.026Z,1537305162.026 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:12:42.346Z,1537305162.346 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:12:42.354Z,1537305162.354 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:12:42.364Z,1537305162.364 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:12:43.164Z,1537305163.164 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:12:43.947Z,1537305163.947 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:12:47.539Z,1537305167.539 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:12:47.579Z,1537305167.579 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:12:47.939Z,1537305167.939 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:12:47.967Z,1537305167.967 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:12:48.757Z,1537305168.757 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:12:50.373Z,1537305170.373 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:12:54.347Z,1537305174.347 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:12:54.429Z,1537305174.429 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:12:54.742Z,1537305174.742 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:12:54.749Z,1537305174.749 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:12:55.573Z,1537305175.573 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:12:55.952Z,1537305175.952 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:13:00.753Z,1537305180.753 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:13:00.831Z,1537305180.831 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:13:01.154Z,1537305181.154 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:13:01.162Z,1537305181.162 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:13:01.968Z,1537305181.968 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:13:02.351Z,1537305182.351 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:13:06.758Z,1537305186.758 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:13:06.831Z,1537305186.831 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:13:07.162Z,1537305187.162 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:13:07.170Z,1537305187.170 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:13:07.960Z,1537305187.960 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:13:09.554Z,1537305189.554 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:13:09.555Z,1537305189.555 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:13:13.943Z,1537305193.943 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:13:13.985Z,1537305193.985 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:13:14.343Z,1537305194.343 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:13:14.351Z,1537305194.351 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:13:14.352Z,1537305194.352 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:13:14.480Z,1537305194.480 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:13:14.761Z,1537305194.761 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:13:14.791Z,1537305194.791 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:13:15.160Z,1537305195.160 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:13:15.560Z,1537305195.560 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:13:22.367Z,1537305202.367 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:13:22.761Z,1537305202.761 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:13:27.554Z,1537305207.554 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:13:27.638Z,1537305207.638 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:13:27.638Z,1537305207.638 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:13:27.719Z,1537305207.719 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:13:27.949Z,1537305207.949 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:13:27.957Z,1537305207.957 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:13:27.958Z,1537305207.958 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:13:27.966Z,1537305207.966 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:13:28.760Z,1537305208.760 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:13:29.160Z,1537305209.160 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:13:35.939Z,1537305215.939 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:13:36.343Z,1537305216.343 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:13:40.347Z,1537305220.347 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:13:40.423Z,1537305220.423 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:13:40.747Z,1537305220.747 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:13:40.755Z,1537305220.755 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:13:41.154Z,1537305221.154 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:13:41.239Z,1537305221.239 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:13:41.551Z,1537305221.551 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:13:41.560Z,1537305221.560 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:13:41.572Z,1537305221.572 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:13:42.372Z,1537305222.372 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:13:48.765Z,1537305228.764 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:13:49.157Z,1537305229.157 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:13:52.763Z,1537305232.763 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:13:52.843Z,1537305232.843 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:13:53.154Z,1537305233.154 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:13:53.161Z,1537305233.161 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:13:53.553Z,1537305233.553 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:13:53.632Z,1537305233.632 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:13:53.952Z,1537305233.952 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:13:53.959Z,1537305233.959 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:13:53.981Z,1537305233.981 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:13:54.760Z,1537305234.760 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:13:55.547Z,1537305235.547 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:14:00.340Z,1537305240.340 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:14:00.377Z,1537305240.377 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:14:00.744Z,1537305240.744 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:14:00.760Z,1537305240.760 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:14:01.546Z,1537305241.546 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:14:01.560Z,1537305241.560 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:14:06.339Z,1537305246.339 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:14:06.423Z,1537305246.423 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:14:06.753Z,1537305246.753 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:14:06.761Z,1537305246.761 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:14:07.555Z,1537305247.555 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:14:08.762Z,1537305248.762 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:14:08.811Z,1537305248.811 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-18T21:14:08.816Z,1537305248.816 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-18T21:14:13.550Z,1537305253.550 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:14:13.630Z,1537305253.630 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:14:13.955Z,1537305253.955 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:14:13.963Z,1537305253.963 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:14:14.755Z,1537305254.755 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:14:14.768Z,1537305254.768 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:14:19.560Z,1537305259.560 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:14:19.640Z,1537305259.640 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:14:19.663Z,1537305259.663 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000879
CHAN A1 (24V): -0.004595
CHAN A2 (12V): -0.002036
CHAN A3 (5V): -0.001678
CHAN B0 (3.3V): -0.000158
CHAN B1 (3.15aV): -0.000169
CHAN B2 (3.15bV): -0.000323
CHAN B3 (GND): -0.000227
OPEN: -0.000264
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-18T21:14:19.969Z,1537305259.969 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:14:19.979Z,1537305259.979 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:14:20.760Z,1537305260.760 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:14:21.946Z,1537305261.946 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:14:21.963Z,1537305261.963 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-18T21:14:21.963Z,1537305261.963 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-18T21:14:21.963Z,1537305261.963 [SBIT](ERROR): Could not read massPosReader_.
2018-09-18T21:14:27.153Z,1537305267.153 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:14:27.228Z,1537305267.228 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:14:27.549Z,1537305267.549 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:14:27.565Z,1537305267.565 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:14:27.940Z,1537305267.940 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:14:28.353Z,1537305268.353 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:14:28.389Z,1537305268.389 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T21:14:28.389Z,1537305268.389 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-09-18T21:14:28.389Z,1537305268.389 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T21:14:28.409Z,1537305268.409 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T21:14:28.783Z,1537305268.783 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T21:14:28.783Z,1537305268.783 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-09-18T21:14:29.188Z,1537305269.188 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:14:29.188Z,1537305269.188 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:14:29.188Z,1537305269.188 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:14:32.434Z,1537305272.434 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:14:32.513Z,1537305272.513 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:14:32.801Z,1537305272.801 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:14:32.809Z,1537305272.809 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:14:33.147Z,1537305273.147 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004300
2018-09-18T21:14:33.997Z,1537305273.997 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:14:35.571Z,1537305275.571 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:14:39.585Z,1537305279.585 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:14:39.655Z,1537305279.655 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:14:39.978Z,1537305279.978 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:14:39.985Z,1537305279.985 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:14:40.792Z,1537305280.792 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:14:41.185Z,1537305281.185 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:14:45.579Z,1537305285.579 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:14:45.653Z,1537305285.653 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:14:45.982Z,1537305285.982 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:14:45.989Z,1537305285.989 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:14:46.793Z,1537305286.793 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:14:47.600Z,1537305287.600 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:14:48.393Z,1537305288.393 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:14:48.415Z,1537305288.415 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-18T21:14:48.415Z,1537305288.415 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-18T21:14:48.415Z,1537305288.415 [SBIT](ERROR): Could not read massPosReader_.
2018-09-18T21:14:48.415Z,1537305288.415 [SBIT](FAULT): Control surface position failure.
2018-09-18T21:14:52.387Z,1537305292.387 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:14:52.424Z,1537305292.424 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:14:52.424Z,1537305292.424 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:14:52.494Z,1537305292.494 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:14:52.804Z,1537305292.804 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:14:52.814Z,1537305292.814 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:14:52.814Z,1537305292.814 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:14:52.822Z,1537305292.822 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:14:53.611Z,1537305293.611 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:14:53.624Z,1537305293.624 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:15:00.386Z,1537305300.386 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:15:00.778Z,1537305300.778 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:15:02.007Z,1537305302.007 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-18T21:15:02.007Z,1537305302.007 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-18T21:15:02.007Z,1537305302.007 [SBIT](ERROR): Could not read massPosReader_.
2018-09-18T21:15:02.007Z,1537305302.007 [SBIT](FAULT): Control surface position failure.
2018-09-18T21:15:02.413Z,1537305302.413 [SBIT](CRITICAL): SBIT FAILED
2018-09-18T21:15:02.457Z,1537305302.457 [CommandLine](IMPORTANT): got command configSet list
2018-09-18T21:15:02.457Z,1537305302.457 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-18T21:15:02.458Z,1537305302.458 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2018-09-18T21:15:02.458Z,1537305302.458 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2018-09-18T21:15:02.458Z,1537305302.458 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2018-09-18T21:15:02.459Z,1537305302.459 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2018-09-18T21:15:02.459Z,1537305302.459 [CommandLine](IMPORTANT): ElevatorServo.loadAtStartup=0 bool;

2018-09-18T21:15:02.459Z,1537305302.459 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_salinity;

2018-09-18T21:15:02.459Z,1537305302.459 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_temperature;

2018-09-18T21:15:02.459Z,1537305302.459 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_median_sea_water_temperature;

2018-09-18T21:15:02.459Z,1537305302.459 [CommandLine](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2018-09-18T21:15:02.459Z,1537305302.459 [CommandLine](IMPORTANT): Express none Rowe_600LCM.height_above_sea_floor;

2018-09-18T21:15:02.459Z,1537305302.459 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-09-18T21:15:02.460Z,1537305302.460 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-09-18T21:15:02.460Z,1537305302.460 [CommandLine](IMPORTANT): MassServo.loadAtStartup=0 bool;

2018-09-18T21:15:02.460Z,1537305302.460 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-09-18T21:15:02.460Z,1537305302.460 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2018-09-18T21:15:02.460Z,1537305302.460 [CommandLine](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2018-09-18T21:15:02.460Z,1537305302.460 [CommandLine](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2018-09-18T21:15:02.460Z,1537305302.460 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=497.852 cubic_centimeter;

2018-09-18T21:15:02.461Z,1537305302.461 [CommandLine](IMPORTANT): VerticalControl.massDefault=-35 millimeter;

2018-09-18T21:15:02.466Z,1537305302.466 [CBIT](INFO): Critical error at 20180918T211502
2018-09-18T21:15:02.819Z,1537305302.819 [MissionManager](IMPORTANT): Started mission Startup
2018-09-18T21:15:02.819Z,1537305302.819 [Startup] Running Loop=1
2018-09-18T21:15:02.819Z,1537305302.819 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-18T21:15:02.819Z,1537305302.819 [Startup:A.GoToSurface] Running Loop=1
2018-09-18T21:15:02.819Z,1537305302.819 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-18T21:15:02.820Z,1537305302.820 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-18T21:15:02.825Z,1537305302.825 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-18T21:15:02.825Z,1537305302.825 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-18T21:15:02.826Z,1537305302.826 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-18T21:15:02.826Z,1537305302.826 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-18T21:15:02.849Z,1537305302.849 [Startup:StartupSatComms] Running Loop=1
2018-09-18T21:15:02.849Z,1537305302.849 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-18T21:15:02.849Z,1537305302.849 [Startup:StartupSatComms:A] Running Loop=1
2018-09-18T21:15:03.206Z,1537305303.206 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-18T21:15:05.177Z,1537305305.177 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:15:05.257Z,1537305305.257 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:15:05.575Z,1537305305.575 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:15:05.583Z,1537305305.583 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:15:05.584Z,1537305305.584 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:15:05.708Z,1537305305.708 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:15:05.993Z,1537305305.993 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:15:06.019Z,1537305306.019 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:15:06.396Z,1537305306.396 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:15:07.189Z,1537305307.189 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:15:13.990Z,1537305313.990 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:15:14.387Z,1537305314.387 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:15:18.794Z,1537305318.794 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:15:18.881Z,1537305318.881 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:15:18.882Z,1537305318.882 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:15:18.953Z,1537305318.953 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:15:19.191Z,1537305319.191 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:15:19.200Z,1537305319.200 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:15:19.200Z,1537305319.200 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:15:19.208Z,1537305319.208 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:15:20.013Z,1537305320.013 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:15:20.030Z,1537305320.030 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:15:27.181Z,1537305327.181 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:15:27.984Z,1537305327.984 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:15:32.326Z,1537305332.326 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:15:32.405Z,1537305332.405 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:15:32.545Z,1537305332.545 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:15:32.630Z,1537305332.630 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:15:32.630Z,1537305332.630 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:15:32.638Z,1537305332.638 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:15:32.894Z,1537305332.894 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:15:32.911Z,1537305332.911 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:15:33.316Z,1537305333.316 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:15:33.689Z,1537305333.689 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:15:34.475Z,1537305334.475 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:15:38.475Z,1537305338.475 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:15:38.512Z,1537305338.512 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:15:38.875Z,1537305338.875 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:15:38.894Z,1537305338.894 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:15:39.689Z,1537305339.689 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:15:40.875Z,1537305340.875 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:15:45.283Z,1537305345.283 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:15:45.358Z,1537305345.358 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:15:45.682Z,1537305345.682 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:15:45.697Z,1537305345.697 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:15:46.496Z,1537305346.496 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:15:46.497Z,1537305346.497 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:15:51.280Z,1537305351.280 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:15:51.358Z,1537305351.358 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:15:51.680Z,1537305351.680 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:15:51.688Z,1537305351.688 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:15:52.544Z,1537305352.544 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:15:53.294Z,1537305353.294 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:15:58.092Z,1537305358.092 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:15:58.167Z,1537305358.167 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:15:58.493Z,1537305358.493 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:15:58.501Z,1537305358.501 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:15:59.296Z,1537305359.296 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:15:59.688Z,1537305359.688 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:16:02.914Z,1537305362.914 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-18T21:15:02.8Z
2018-09-18T21:16:02.914Z,1537305362.914 [Startup:StartupSatComms:A] Stopped
2018-09-18T21:16:02.914Z,1537305362.914 [Startup:StartupSatComms:B] Running Loop=1
2018-09-18T21:16:03.322Z,1537305363.322 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-18T21:16:07.210Z,1537305367.210 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:16:07.211Z,1537305367.211 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:16:07.295Z,1537305367.295 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:16:07.327Z,1537305367.327 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180918T205225/Courier0007.lzma
2018-09-18T21:16:07.434Z,1537305367.434 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:16:07.450Z,1537305367.450 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:16:08.128Z,1537305368.128 [DataOverHttps](INFO): Moved sent file to Logs/20180918T205225/Courier0007.lzma.bak
2018-09-18T21:16:08.128Z,1537305368.128 [DataOverHttps](INFO): SBD MOMSN=8531881
2018-09-18T21:16:08.208Z,1537305368.208 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:16:12.090Z,1537305372.090 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:16:12.175Z,1537305372.175 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:16:12.293Z,1537305372.293 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:16:12.318Z,1537305372.318 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:16:13.114Z,1537305373.114 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:16:17.028Z,1537305377.028 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:16:17.092Z,1537305377.092 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20180918T211117/Courier0000.lzma
2018-09-18T21:16:17.860Z,1537305377.860 [DataOverHttps](INFO): Moved sent file to Logs/20180918T211117/Courier0000.lzma.bak
2018-09-18T21:16:17.860Z,1537305377.860 [DataOverHttps](INFO): SBD MOMSN=8531883
2018-09-18T21:16:21.850Z,1537305381.850 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:16:21.851Z,1537305381.851 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:16:21.940Z,1537305381.940 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:16:22.070Z,1537305382.070 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:16:22.090Z,1537305382.090 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:16:22.837Z,1537305382.837 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:16:26.726Z,1537305386.726 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:16:26.894Z,1537305386.894 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:16:26.926Z,1537305386.926 [DataOverHttps](INFO): Sending 318 bytes from file Logs/20180918T205225/Express0008.lzma
2018-09-18T21:16:27.035Z,1537305387.035 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:16:27.044Z,1537305387.044 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:16:27.723Z,1537305387.723 [DataOverHttps](INFO): Moved sent file to Logs/20180918T205225/Express0008.lzma.bak
2018-09-18T21:16:27.724Z,1537305387.724 [DataOverHttps](INFO): SBD MOMSN=8531891
2018-09-18T21:16:27.788Z,1537305387.788 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:16:31.694Z,1537305391.694 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:16:36.542Z,1537305396.542 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:16:36.542Z,1537305396.542 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:16:36.624Z,1537305396.624 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:16:36.691Z,1537305396.691 [DataOverHttps](INFO): Sending 1030 bytes from file Logs/20180918T211117/Express0001.lzma
2018-09-18T21:16:36.768Z,1537305396.768 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:16:36.776Z,1537305396.776 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:16:37.732Z,1537305397.732 [DataOverHttps](INFO): Moved sent file to Logs/20180918T211117/Express0001.lzma.bak
2018-09-18T21:16:37.732Z,1537305397.732 [DataOverHttps](INFO): SBD MOMSN=8531902
2018-09-18T21:16:37.824Z,1537305397.824 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:16:38.256Z,1537305398.256 [Startup:StartupSatComms:B] Stopped
2018-09-18T21:16:38.256Z,1537305398.256 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-18T21:16:38.256Z,1537305398.256 [Startup:StartupSatComms] Stopped
2018-09-18T21:16:38.257Z,1537305398.257 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-18T21:16:38.258Z,1537305398.258 [Startup](INFO): Completed Startup
2018-09-18T21:16:38.258Z,1537305398.258 [MissionManager](INFO): Startup is completed.
2018-09-18T21:16:38.258Z,1537305398.258 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-18T21:16:38.258Z,1537305398.258 [Startup] Stopped
2018-09-18T21:16:38.258Z,1537305398.258 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-18T21:16:38.258Z,1537305398.258 [Startup:A.GoToSurface] Stopped
2018-09-18T21:16:38.258Z,1537305398.258 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-18T21:16:38.392Z,1537305398.392 [MissionManager](IMPORTANT): Started mission Default
2018-09-18T21:16:38.393Z,1537305398.393 [Default] Running Loop=1
2018-09-18T21:16:38.393Z,1537305398.393 [Default](DEBUG): Aggregate::initialize Default
2018-09-18T21:16:38.393Z,1537305398.393 [Default:B.GoToSurface] Running Loop=1
2018-09-18T21:16:38.393Z,1537305398.393 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-18T21:16:38.393Z,1537305398.393 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-18T21:16:38.394Z,1537305398.394 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-18T21:16:38.394Z,1537305398.394 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-18T21:16:38.394Z,1537305398.394 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-18T21:16:38.395Z,1537305398.395 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-18T21:16:38.395Z,1537305398.395 [Default:A.Wait] Running Loop=1
2018-09-18T21:16:38.395Z,1537305398.395 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-18T21:16:41.458Z,1537305401.458 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:16:41.531Z,1537305401.531 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:16:41.662Z,1537305401.662 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:16:41.671Z,1537305401.671 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:16:42.436Z,1537305402.436 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:16:43.613Z,1537305403.613 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:16:44.809Z,1537305404.809 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:16:47.621Z,1537305407.621 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:16:47.636Z,1537305407.636 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:16:48.013Z,1537305408.013 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:16:48.031Z,1537305408.031 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:16:48.820Z,1537305408.820 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:16:49.201Z,1537305409.201 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:16:49.282Z,1537305409.282 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:16:49.609Z,1537305409.609 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:16:49.642Z,1537305409.642 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:16:50.404Z,1537305410.404 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:16:50.816Z,1537305410.816 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:16:51.650Z,1537305411.650 [Default:A.Wait](INFO): Done Waiting.
2018-09-18T21:16:51.650Z,1537305411.650 [Default:A.Wait] Stopped
2018-09-18T21:16:51.650Z,1537305411.650 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-18T21:16:52.045Z,1537305412.045 [Default:CheckIn] Running Loop=1
2018-09-18T21:16:52.045Z,1537305412.045 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-18T21:16:52.045Z,1537305412.045 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-18T21:16:52.429Z,1537305412.429 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-18T21:16:54.821Z,1537305414.821 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:16:54.848Z,1537305414.848 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:16:55.212Z,1537305415.212 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:16:55.220Z,1537305415.220 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:16:56.032Z,1537305416.032 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:16:57.622Z,1537305417.622 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:17:02.419Z,1537305422.419 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:17:02.495Z,1537305422.495 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:17:02.824Z,1537305422.824 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:17:02.832Z,1537305422.832 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:17:03.609Z,1537305423.609 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:17:03.620Z,1537305423.620 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:17:08.427Z,1537305428.427 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:17:08.499Z,1537305428.499 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:17:08.817Z,1537305428.817 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:17:08.825Z,1537305428.825 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:17:09.174Z,1537305429.174 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-09-18T21:17:09.625Z,1537305429.625 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:17:10.821Z,1537305430.821 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:17:15.610Z,1537305435.610 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:17:15.692Z,1537305435.692 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:17:16.009Z,1537305436.009 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:17:16.017Z,1537305436.017 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:17:16.835Z,1537305436.835 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:17:17.211Z,1537305437.211 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:17:22.016Z,1537305442.016 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:17:22.146Z,1537305442.146 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:17:22.413Z,1537305442.413 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:17:22.422Z,1537305442.422 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:17:23.228Z,1537305443.228 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:17:24.025Z,1537305444.025 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:17:28.420Z,1537305448.420 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:17:28.490Z,1537305448.490 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:17:28.825Z,1537305448.825 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:17:28.832Z,1537305448.832 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:17:29.259Z,1537305449.259 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T21:17:29.259Z,1537305449.259 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-09-18T21:17:29.259Z,1537305449.259 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T21:17:29.271Z,1537305449.271 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T21:17:29.624Z,1537305449.624 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:17:29.665Z,1537305449.665 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T21:17:29.665Z,1537305449.665 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-09-18T21:17:30.021Z,1537305450.021 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:17:30.022Z,1537305450.022 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:17:30.022Z,1537305450.022 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:17:30.411Z,1537305450.411 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:17:30.809Z,1537305450.809 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:17:34.822Z,1537305454.822 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:17:34.891Z,1537305454.891 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:17:34.892Z,1537305454.892 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:17:34.922Z,1537305454.922 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:17:35.223Z,1537305455.223 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:17:35.230Z,1537305455.230 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:17:35.230Z,1537305455.230 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:17:35.238Z,1537305455.238 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:17:36.028Z,1537305456.028 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:17:36.040Z,1537305456.040 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:17:37.616Z,1537305457.616 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:17:41.923Z,1537305461.923 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:17:42.027Z,1537305462.027 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:17:42.157Z,1537305462.157 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:17:42.165Z,1537305462.165 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:17:42.941Z,1537305462.941 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:17:42.941Z,1537305462.941 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:17:48.103Z,1537305468.103 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:17:48.181Z,1537305468.181 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:17:48.522Z,1537305468.522 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:17:48.531Z,1537305468.531 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:17:49.332Z,1537305469.332 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:17:49.715Z,1537305469.715 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:17:54.522Z,1537305474.522 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:17:54.600Z,1537305474.600 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:17:54.922Z,1537305474.922 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:17:54.951Z,1537305474.951 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:17:55.728Z,1537305475.728 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:17:56.507Z,1537305476.507 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:18:01.327Z,1537305481.327 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:18:01.400Z,1537305481.400 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:18:01.702Z,1537305481.702 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:18:01.722Z,1537305481.722 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:18:02.908Z,1537305482.908 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:18:02.924Z,1537305482.924 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:18:07.711Z,1537305487.711 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:18:07.795Z,1537305487.795 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:18:08.110Z,1537305488.110 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:18:08.126Z,1537305488.126 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:18:08.937Z,1537305488.937 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:18:10.126Z,1537305490.126 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:18:14.904Z,1537305494.904 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:18:14.989Z,1537305494.989 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:18:15.303Z,1537305495.303 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:18:15.336Z,1537305495.336 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:18:16.141Z,1537305496.141 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:18:16.526Z,1537305496.526 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:18:21.311Z,1537305501.311 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:18:21.391Z,1537305501.391 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:18:21.721Z,1537305501.721 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:18:21.758Z,1537305501.758 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:18:22.536Z,1537305502.536 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:18:23.310Z,1537305503.310 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:18:27.725Z,1537305507.725 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:18:27.798Z,1537305507.798 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:18:28.124Z,1537305508.124 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:18:28.131Z,1537305508.131 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:18:28.928Z,1537305508.928 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:18:29.704Z,1537305509.704 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:18:34.121Z,1537305514.121 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:18:34.202Z,1537305514.202 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:18:34.518Z,1537305514.518 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:18:34.545Z,1537305514.545 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:18:35.338Z,1537305515.338 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:18:35.709Z,1537305515.709 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:18:42.024Z,1537305522.024 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:18:42.025Z,1537305522.025 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:18:42.107Z,1537305522.107 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:18:42.253Z,1537305522.253 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:18:42.261Z,1537305522.261 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:18:43.012Z,1537305523.012 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:18:44.586Z,1537305524.586 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:18:46.176Z,1537305526.176 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:18:46.249Z,1537305526.249 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:18:46.577Z,1537305526.577 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:18:46.585Z,1537305526.585 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:18:47.404Z,1537305527.404 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:18:48.572Z,1537305528.572 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:18:48.967Z,1537305528.967 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:18:49.010Z,1537305529.010 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:18:49.389Z,1537305529.389 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:18:49.398Z,1537305529.398 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:18:50.200Z,1537305530.200 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:18:52.971Z,1537305532.971 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:18:53.010Z,1537305533.010 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:18:53.378Z,1537305533.378 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:18:53.386Z,1537305533.386 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:18:54.196Z,1537305534.196 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:18:58.182Z,1537305538.182 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:19:01.795Z,1537305541.795 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:19:02.968Z,1537305542.968 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:19:03.055Z,1537305543.055 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:19:03.394Z,1537305543.394 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:19:03.402Z,1537305543.402 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:19:04.204Z,1537305544.204 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:19:06.576Z,1537305546.576 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:19:06.653Z,1537305546.653 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:19:06.975Z,1537305546.975 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:19:06.983Z,1537305546.983 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:19:07.796Z,1537305547.796 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:19:11.389Z,1537305551.389 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:19:14.977Z,1537305554.977 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:19:16.195Z,1537305556.195 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:19:16.271Z,1537305556.271 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:19:16.568Z,1537305556.568 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:19:16.608Z,1537305556.608 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:19:17.404Z,1537305557.404 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:19:19.799Z,1537305559.799 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:19:19.872Z,1537305559.872 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:19:20.194Z,1537305560.194 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:19:20.202Z,1537305560.202 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:19:20.985Z,1537305560.985 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:19:24.186Z,1537305564.186 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:19:28.190Z,1537305568.190 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:19:28.570Z,1537305568.570 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:19:28.651Z,1537305568.651 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:19:28.967Z,1537305568.967 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:19:28.995Z,1537305568.995 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:19:29.805Z,1537305569.805 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:19:32.571Z,1537305572.571 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:19:32.652Z,1537305572.652 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:19:32.971Z,1537305572.971 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:19:32.978Z,1537305572.978 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:19:33.793Z,1537305573.793 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:19:36.975Z,1537305576.975 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:19:42.125Z,1537305582.125 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:19:42.125Z,1537305582.125 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:19:42.210Z,1537305582.210 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:19:42.360Z,1537305582.360 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:19:42.368Z,1537305582.368 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:19:43.096Z,1537305583.096 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:19:45.092Z,1537305585.092 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:19:45.170Z,1537305585.170 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:19:45.489Z,1537305585.489 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:19:45.497Z,1537305585.497 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:19:46.292Z,1537305586.292 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:19:50.280Z,1537305590.280 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:19:53.494Z,1537305593.494 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:19:55.071Z,1537305595.071 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:19:55.154Z,1537305595.154 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:19:55.479Z,1537305595.479 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:19:55.499Z,1537305595.499 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:19:56.293Z,1537305596.293 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:19:58.275Z,1537305598.275 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:19:58.360Z,1537305598.360 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:19:58.676Z,1537305598.676 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:19:58.684Z,1537305598.684 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:19:59.497Z,1537305599.497 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:20:03.880Z,1537305603.880 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:20:06.672Z,1537305606.672 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:20:08.682Z,1537305608.682 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:20:08.766Z,1537305608.766 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:20:09.098Z,1537305609.098 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:20:09.106Z,1537305609.106 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:20:09.897Z,1537305609.897 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:20:11.896Z,1537305611.896 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:20:12.022Z,1537305612.022 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:20:12.293Z,1537305612.293 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:20:12.310Z,1537305612.310 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:20:13.097Z,1537305613.097 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:20:17.081Z,1537305617.081 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:20:20.271Z,1537305620.271 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:20:21.494Z,1537305621.494 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:20:21.570Z,1537305621.570 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:20:21.871Z,1537305621.871 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:20:21.890Z,1537305621.890 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:20:22.685Z,1537305622.685 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:20:24.671Z,1537305624.671 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:20:24.751Z,1537305624.751 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:20:25.072Z,1537305625.072 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:20:25.091Z,1537305625.091 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:20:25.896Z,1537305625.896 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:20:27.482Z,1537305627.482 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:20:29.082Z,1537305629.082 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:20:30.298Z,1537305630.298 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T21:20:30.298Z,1537305630.298 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-09-18T21:20:30.298Z,1537305630.298 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T21:20:30.309Z,1537305630.309 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T21:20:30.701Z,1537305630.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T21:20:30.701Z,1537305630.701 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-09-18T21:20:31.086Z,1537305631.086 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:20:31.101Z,1537305631.101 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:20:31.110Z,1537305631.110 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:20:31.110Z,1537305631.110 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:20:31.111Z,1537305631.111 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:20:31.470Z,1537305631.470 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:20:31.486Z,1537305631.486 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:20:32.292Z,1537305632.292 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:20:33.097Z,1537305633.097 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:20:33.163Z,1537305633.163 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:20:33.482Z,1537305633.482 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:20:33.505Z,1537305633.505 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:20:34.688Z,1537305634.688 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:20:35.890Z,1537305635.890 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:20:39.095Z,1537305639.095 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:20:42.222Z,1537305642.222 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:20:42.239Z,1537305642.239 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:20:42.377Z,1537305642.377 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:20:42.408Z,1537305642.408 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:20:43.156Z,1537305643.156 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:20:43.932Z,1537305643.932 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:20:44.011Z,1537305644.011 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:20:44.335Z,1537305644.335 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:20:44.373Z,1537305644.373 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:20:45.155Z,1537305645.155 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:20:50.335Z,1537305650.335 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:20:52.337Z,1537305652.337 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:20:55.136Z,1537305655.136 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:20:55.214Z,1537305655.214 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:20:55.538Z,1537305655.538 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:20:55.546Z,1537305655.546 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:20:56.348Z,1537305656.348 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:20:57.151Z,1537305657.151 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:20:57.233Z,1537305657.233 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:20:57.549Z,1537305657.549 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:20:57.568Z,1537305657.568 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:20:58.344Z,1537305658.344 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:21:03.543Z,1537305663.543 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:21:05.545Z,1537305665.545 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:21:08.327Z,1537305668.327 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:21:08.415Z,1537305668.415 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:21:08.746Z,1537305668.746 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:21:08.763Z,1537305668.763 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:21:09.949Z,1537305669.949 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:21:10.327Z,1537305670.327 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:21:10.415Z,1537305670.415 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:21:10.728Z,1537305670.728 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:21:10.736Z,1537305670.736 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:21:11.562Z,1537305671.562 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:21:16.735Z,1537305676.735 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:21:19.137Z,1537305679.137 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:21:20.739Z,1537305680.739 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:21:20.816Z,1537305680.816 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:21:21.142Z,1537305681.142 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:21:21.149Z,1537305681.149 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:21:21.948Z,1537305681.948 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:21:23.546Z,1537305683.546 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:21:23.625Z,1537305683.625 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:21:23.946Z,1537305683.946 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:21:23.962Z,1537305683.962 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:21:24.744Z,1537305684.744 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:21:28.735Z,1537305688.735 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:21:31.534Z,1537305691.534 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:21:33.149Z,1537305693.149 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:21:33.221Z,1537305693.221 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:21:33.550Z,1537305693.550 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:21:33.558Z,1537305693.558 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:21:34.352Z,1537305694.352 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:21:35.556Z,1537305695.556 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:21:36.347Z,1537305696.347 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:21:36.422Z,1537305696.422 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:21:36.727Z,1537305696.727 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:21:36.743Z,1537305696.743 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:21:37.553Z,1537305697.553 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:21:42.258Z,1537305702.258 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:21:42.297Z,1537305702.297 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:21:42.413Z,1537305702.413 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:21:42.430Z,1537305702.430 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:21:43.600Z,1537305703.600 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:21:45.198Z,1537305705.198 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:21:49.978Z,1537305709.978 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:21:50.065Z,1537305710.065 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:21:50.386Z,1537305710.386 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:21:50.411Z,1537305710.411 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:21:50.805Z,1537305710.805 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:21:51.211Z,1537305711.211 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:21:52.427Z,1537305712.427 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-18T21:16:52.0Z
2018-09-18T21:21:52.427Z,1537305712.427 [Default:CheckIn:Read_GPS] Stopped
2018-09-18T21:21:52.428Z,1537305712.428 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-18T21:21:52.804Z,1537305712.804 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-18T21:21:56.698Z,1537305716.698 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:21:56.781Z,1537305716.781 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:21:56.837Z,1537305716.837 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180918T211117/Courier0004.lzma
2018-09-18T21:21:56.891Z,1537305716.891 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:21:56.900Z,1537305716.900 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:21:57.611Z,1537305717.611 [DataOverHttps](INFO): Moved sent file to Logs/20180918T211117/Courier0004.lzma.bak
2018-09-18T21:21:57.611Z,1537305717.611 [DataOverHttps](INFO): SBD MOMSN=8531936
2018-09-18T21:21:57.716Z,1537305717.716 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:22:01.571Z,1537305721.571 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:22:06.353Z,1537305726.353 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:22:06.439Z,1537305726.439 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:22:06.439Z,1537305726.439 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:22:06.517Z,1537305726.517 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180918T211117/Express0005.lzma
2018-09-18T21:22:06.547Z,1537305726.547 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:22:06.556Z,1537305726.556 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:22:07.307Z,1537305727.307 [DataOverHttps](INFO): Moved sent file to Logs/20180918T211117/Express0005.lzma.bak
2018-09-18T21:22:07.308Z,1537305727.308 [DataOverHttps](INFO): SBD MOMSN=8531938
2018-09-18T21:22:07.339Z,1537305727.339 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-18T21:22:07.340Z,1537305727.340 [NAL9602] Data Fault, FailCount= 1
2018-09-18T21:22:07.340Z,1537305727.340 [NAL9602](ERROR): Data Fault
2018-09-18T21:22:07.404Z,1537305727.404 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:22:07.433Z,1537305727.433 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-18T21:22:08.029Z,1537305728.029 [NAL9602](INFO): Powering down
2018-09-18T21:22:08.090Z,1537305728.090 [Default:CheckIn:Read_Iridium] Stopped
2018-09-18T21:22:08.090Z,1537305728.090 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-18T21:22:08.091Z,1537305728.091 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-18T21:22:11.269Z,1537305731.269 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:22:11.351Z,1537305731.351 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:22:11.372Z,1537305731.372 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-18T21:22:11.372Z,1537305731.372 [NAL9602] No Fault, FailCount= 1
2018-09-18T21:22:11.477Z,1537305731.477 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:22:11.494Z,1537305731.494 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:22:12.232Z,1537305732.232 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:22:13.419Z,1537305733.419 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:22:14.618Z,1537305734.618 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:22:17.418Z,1537305737.418 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:22:17.433Z,1537305737.433 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:22:17.819Z,1537305737.819 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:22:17.826Z,1537305737.826 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:22:18.632Z,1537305738.632 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:22:19.020Z,1537305739.020 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:22:19.094Z,1537305739.094 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:22:19.422Z,1537305739.422 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:22:19.429Z,1537305739.429 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:22:20.236Z,1537305740.236 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:22:25.011Z,1537305745.011 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:22:27.019Z,1537305747.019 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:22:29.420Z,1537305749.420 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:22:29.488Z,1537305749.488 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:22:29.819Z,1537305749.819 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:22:29.827Z,1537305749.827 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:22:30.629Z,1537305750.629 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:22:31.418Z,1537305751.418 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:22:31.486Z,1537305751.486 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:22:31.819Z,1537305751.819 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:22:31.826Z,1537305751.826 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:22:32.241Z,1537305752.241 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:22:32.632Z,1537305752.632 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:22:34.211Z,1537305754.211 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:22:36.620Z,1537305756.620 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:22:36.653Z,1537305756.653 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:22:37.020Z,1537305757.020 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:22:37.028Z,1537305757.028 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:22:37.829Z,1537305757.829 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:22:38.190Z,1537305758.190 [NAL9602](INFO): Powering up NAL9602
2018-09-18T21:22:38.620Z,1537305758.620 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:22:38.636Z,1537305758.636 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:22:39.017Z,1537305759.017 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:22:39.033Z,1537305759.033 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:22:39.824Z,1537305759.824 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:22:45.425Z,1537305765.425 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:22:47.014Z,1537305767.014 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:22:49.386Z,1537305769.386 [NAL9602](INFO): NAL9602 initialized
2018-09-18T21:22:50.245Z,1537305770.245 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:22:50.328Z,1537305770.328 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:22:50.626Z,1537305770.626 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:22:50.665Z,1537305770.665 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:22:51.432Z,1537305771.432 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:22:52.221Z,1537305772.221 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:22:52.304Z,1537305772.304 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:22:52.622Z,1537305772.622 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:22:52.630Z,1537305772.630 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:22:53.432Z,1537305773.432 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:22:59.030Z,1537305779.030 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:23:00.622Z,1537305780.622 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:23:03.424Z,1537305783.424 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:23:03.499Z,1537305783.499 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:23:03.823Z,1537305783.823 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:23:03.831Z,1537305783.831 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:23:04.644Z,1537305784.644 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:23:05.812Z,1537305785.812 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:23:05.901Z,1537305785.901 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:23:06.212Z,1537305786.212 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:23:06.236Z,1537305786.236 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:23:07.029Z,1537305787.029 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:23:11.725Z,1537305791.725 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:23:13.825Z,1537305793.825 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:23:16.216Z,1537305796.216 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:23:16.299Z,1537305796.299 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:23:16.616Z,1537305796.616 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:23:16.639Z,1537305796.639 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:23:17.433Z,1537305797.433 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:23:17.838Z,1537305797.838 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:23:17.909Z,1537305797.909 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:23:18.240Z,1537305798.240 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:23:18.247Z,1537305798.247 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:23:19.033Z,1537305799.033 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:23:24.230Z,1537305804.230 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:23:25.827Z,1537305805.827 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:23:28.622Z,1537305808.622 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:23:28.701Z,1537305808.701 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:23:29.025Z,1537305809.025 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:23:29.034Z,1537305809.034 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:23:29.848Z,1537305809.848 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:23:30.634Z,1537305810.634 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:23:30.723Z,1537305810.723 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:23:31.022Z,1537305811.022 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:23:31.038Z,1537305811.038 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:23:31.442Z,1537305811.442 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T21:23:31.442Z,1537305811.442 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-09-18T21:23:31.442Z,1537305811.442 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T21:23:31.454Z,1537305811.454 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T21:23:31.837Z,1537305811.837 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:23:31.857Z,1537305811.857 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T21:23:31.857Z,1537305811.857 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-09-18T21:23:32.232Z,1537305812.232 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:23:32.232Z,1537305812.232 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:23:32.237Z,1537305812.237 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:23:37.423Z,1537305817.423 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:23:39.433Z,1537305819.433 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:23:42.234Z,1537305822.234 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:23:42.318Z,1537305822.318 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:23:42.635Z,1537305822.635 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:23:42.655Z,1537305822.655 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:23:43.437Z,1537305823.437 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:23:44.222Z,1537305824.222 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:23:44.301Z,1537305824.301 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:23:44.634Z,1537305824.634 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:23:44.642Z,1537305824.642 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:23:45.444Z,1537305825.444 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:23:50.630Z,1537305830.630 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:23:53.022Z,1537305833.022 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:23:55.418Z,1537305835.418 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:23:55.501Z,1537305835.501 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:23:55.842Z,1537305835.842 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:23:55.850Z,1537305835.850 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:23:56.648Z,1537305836.648 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:23:57.820Z,1537305837.820 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:23:57.908Z,1537305837.908 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:23:58.220Z,1537305838.220 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:23:58.228Z,1537305838.228 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:23:59.060Z,1537305839.060 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:24:04.223Z,1537305844.223 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:24:06.223Z,1537305846.223 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:24:08.241Z,1537305848.241 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:24:08.323Z,1537305848.323 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:24:08.642Z,1537305848.642 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:24:08.649Z,1537305848.649 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:24:11.744Z,1537305851.744 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:24:11.821Z,1537305851.821 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:24:11.970Z,1537305851.970 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:24:11.979Z,1537305851.979 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:24:12.352Z,1537305852.352 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:24:12.749Z,1537305852.749 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:24:13.506Z,1537305853.506 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:24:17.522Z,1537305857.522 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:24:17.537Z,1537305857.537 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:24:17.926Z,1537305857.926 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:24:17.933Z,1537305857.933 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:24:18.720Z,1537305858.720 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:24:19.504Z,1537305859.504 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:24:23.918Z,1537305863.918 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:24:24.001Z,1537305864.001 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:24:24.312Z,1537305864.312 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:24:24.319Z,1537305864.319 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:24:25.120Z,1537305865.120 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:24:25.910Z,1537305865.910 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:24:26.311Z,1537305866.311 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:24:30.318Z,1537305870.318 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:24:30.398Z,1537305870.398 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:24:30.703Z,1537305870.703 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:24:30.720Z,1537305870.720 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:24:30.720Z,1537305870.720 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:24:30.777Z,1537305870.777 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:24:31.111Z,1537305871.111 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:24:31.119Z,1537305871.119 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:24:31.532Z,1537305871.532 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:24:31.920Z,1537305871.920 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:24:38.721Z,1537305878.721 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:24:39.521Z,1537305879.521 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:24:43.508Z,1537305883.508 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:24:43.592Z,1537305883.592 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:24:43.913Z,1537305883.913 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:24:43.937Z,1537305883.937 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:24:44.314Z,1537305884.314 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:24:44.395Z,1537305884.395 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:24:44.717Z,1537305884.717 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:24:44.725Z,1537305884.725 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:24:44.736Z,1537305884.736 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:24:45.532Z,1537305885.532 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:24:51.906Z,1537305891.906 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:24:52.707Z,1537305892.707 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:24:56.727Z,1537305896.727 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:24:56.808Z,1537305896.808 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:24:57.122Z,1537305897.122 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:24:57.130Z,1537305897.130 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:24:57.521Z,1537305897.521 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:24:57.600Z,1537305897.600 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:24:57.899Z,1537305897.899 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:24:57.919Z,1537305897.919 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:24:58.328Z,1537305898.328 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:24:58.717Z,1537305898.717 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:25:05.117Z,1537305905.117 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:25:06.338Z,1537305906.338 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:25:13.282Z,1537305913.282 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:25:13.369Z,1537305913.369 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:25:13.369Z,1537305913.369 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:25:13.449Z,1537305913.449 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:25:13.596Z,1537305913.596 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:25:13.606Z,1537305913.606 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:25:13.607Z,1537305913.607 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:25:13.614Z,1537305913.614 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:25:14.332Z,1537305914.332 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:25:14.348Z,1537305914.348 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:25:20.712Z,1537305920.712 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:25:21.127Z,1537305921.127 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:25:25.521Z,1537305925.521 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:25:25.602Z,1537305925.602 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:25:25.915Z,1537305925.915 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:25:25.923Z,1537305925.923 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:25:25.924Z,1537305925.924 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:25:26.045Z,1537305926.045 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:25:26.325Z,1537305926.325 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:25:26.334Z,1537305926.334 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:25:26.732Z,1537305926.732 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:25:27.124Z,1537305927.124 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:25:33.922Z,1537305933.922 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:25:34.325Z,1537305934.325 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:25:38.708Z,1537305938.708 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:25:38.799Z,1537305938.799 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:25:39.120Z,1537305939.120 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:25:39.128Z,1537305939.128 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:25:39.129Z,1537305939.129 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:25:39.258Z,1537305939.258 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:25:39.537Z,1537305939.537 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:25:39.545Z,1537305939.545 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:25:39.944Z,1537305939.944 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:25:40.341Z,1537305940.341 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:25:47.521Z,1537305947.521 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:25:47.916Z,1537305947.916 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:25:52.329Z,1537305952.329 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:25:52.408Z,1537305952.408 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:25:52.707Z,1537305952.707 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:25:52.797Z,1537305952.797 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:25:52.797Z,1537305952.797 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:25:52.805Z,1537305952.805 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:25:53.107Z,1537305953.107 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:25:53.115Z,1537305953.115 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:25:53.540Z,1537305953.540 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:25:53.929Z,1537305953.929 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:26:00.726Z,1537305960.726 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:26:00.727Z,1537305960.727 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:26:05.112Z,1537305965.112 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:26:05.198Z,1537305965.198 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:26:05.199Z,1537305965.199 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:26:05.275Z,1537305965.275 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:26:05.515Z,1537305965.515 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:26:05.522Z,1537305965.522 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:26:05.522Z,1537305965.522 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:26:05.538Z,1537305965.538 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:26:06.325Z,1537305966.325 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:26:06.337Z,1537305966.337 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:26:07.522Z,1537305967.522 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:26:11.839Z,1537305971.839 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:26:11.854Z,1537305971.854 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:26:12.029Z,1537305972.029 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:26:12.050Z,1537305972.050 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:26:12.809Z,1537305972.809 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:26:12.824Z,1537305972.824 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:26:17.181Z,1537305977.181 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:26:17.254Z,1537305977.254 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:26:17.586Z,1537305977.586 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:26:17.605Z,1537305977.605 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:26:18.392Z,1537305978.392 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:26:19.978Z,1537305979.978 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:26:19.978Z,1537305979.978 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:26:24.390Z,1537305984.390 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:26:24.408Z,1537305984.408 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:26:24.766Z,1537305984.766 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:26:24.787Z,1537305984.787 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:26:24.787Z,1537305984.787 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:26:24.907Z,1537305984.907 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:26:25.197Z,1537305985.197 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:26:25.225Z,1537305985.225 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:26:25.584Z,1537305985.584 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:26:25.989Z,1537305985.989 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:26:29.631Z,1537305989.631 [CBIT](INFO): Clearing failed state for component DropWeight
2018-09-18T21:26:29.631Z,1537305989.631 [DropWeight] No Fault, FailCount= 1
2018-09-18T21:26:32.387Z,1537305992.387 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T21:26:32.388Z,1537305992.388 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-09-18T21:26:32.388Z,1537305992.388 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T21:26:32.401Z,1537305992.401 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T21:26:32.791Z,1537305992.791 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:26:32.810Z,1537305992.810 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T21:26:32.811Z,1537305992.811 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-09-18T21:26:33.200Z,1537305993.200 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:26:33.209Z,1537305993.209 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T21:26:33.210Z,1537305993.210 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T21:26:33.210Z,1537305993.210 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T21:26:37.580Z,1537305997.580 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:26:37.658Z,1537305997.658 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:26:37.979Z,1537305997.979 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:26:37.987Z,1537305997.987 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:26:37.988Z,1537305997.988 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:26:38.110Z,1537305998.110 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:26:38.405Z,1537305998.405 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:26:38.413Z,1537305998.413 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:26:38.808Z,1537305998.808 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:26:39.604Z,1537305999.604 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:26:46.385Z,1537306006.385 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:26:46.781Z,1537306006.781 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:26:50.801Z,1537306010.801 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:26:50.883Z,1537306010.883 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:26:51.201Z,1537306011.201 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:26:51.210Z,1537306011.210 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:26:51.211Z,1537306011.211 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:26:51.328Z,1537306011.328 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:26:51.609Z,1537306011.609 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:26:51.617Z,1537306011.617 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:26:51.985Z,1537306011.985 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:26:52.388Z,1537306012.388 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:26:58.782Z,1537306018.782 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:26:59.593Z,1537306019.593 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:27:03.188Z,1537306023.188 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:27:03.268Z,1537306023.268 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:27:03.583Z,1537306023.583 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:27:03.591Z,1537306023.591 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:27:03.999Z,1537306023.999 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:27:04.065Z,1537306024.065 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:27:04.388Z,1537306024.388 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:27:04.389Z,1537306024.389 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:27:04.411Z,1537306024.411 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:27:05.192Z,1537306025.192 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:27:08.395Z,1537306028.395 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-09-18T21:27:08.395Z,1537306028.395 [Default:CheckIn:C.Wait] Stopped
2018-09-18T21:27:08.395Z,1537306028.395 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-18T21:27:08.395Z,1537306028.395 [Default:CheckIn:D] Running Loop=1
2018-09-18T21:27:08.810Z,1537306028.810 [Default:CheckIn:D] Stopped
2018-09-18T21:27:08.810Z,1537306028.810 [Default:CheckIn:E] Running Loop=1
2018-09-18T21:27:11.900Z,1537306031.900 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:27:11.917Z,1537306031.917 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.506921 min 
2018-09-18T21:27:11.919Z,1537306031.919 [Default:CheckIn:E] Stopped
2018-09-18T21:27:11.919Z,1537306031.919 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-09-18T21:27:11.919Z,1537306031.919 [Default:CheckIn] Stopped
2018-09-18T21:27:11.919Z,1537306031.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-18T21:27:11.919Z,1537306031.919 [Default:CheckIn](INFO): Running loop #2
2018-09-18T21:27:11.919Z,1537306031.919 [Default:CheckIn] Running Loop=2
2018-09-18T21:27:11.920Z,1537306031.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-18T21:27:11.920Z,1537306031.920 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-18T21:27:12.062Z,1537306032.062 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:27:15.610Z,1537306035.610 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:27:15.691Z,1537306035.691 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:27:16.010Z,1537306036.010 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:27:16.034Z,1537306036.034 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:27:16.425Z,1537306036.425 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:27:16.493Z,1537306036.493 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:27:16.844Z,1537306036.844 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:27:16.845Z,1537306036.845 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:27:16.852Z,1537306036.852 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:27:17.652Z,1537306037.652 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:27:19.211Z,1537306039.211 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:27:23.625Z,1537306043.625 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:27:23.660Z,1537306043.660 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:27:24.021Z,1537306044.021 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:27:24.041Z,1537306044.041 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:27:24.421Z,1537306044.421 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:27:24.832Z,1537306044.832 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:27:29.230Z,1537306049.230 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:27:29.305Z,1537306049.305 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:27:29.635Z,1537306049.635 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:27:29.643Z,1537306049.643 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:27:30.429Z,1537306050.429 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:27:32.422Z,1537306052.422 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:27:37.218Z,1537306057.218 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:27:37.298Z,1537306057.298 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:27:37.632Z,1537306057.632 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:27:37.640Z,1537306057.640 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:27:38.030Z,1537306058.030 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:27:38.441Z,1537306058.441 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:27:42.810Z,1537306062.810 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:27:42.897Z,1537306062.897 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2018-09-18T21:27:43.212Z,1537306063.212 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T21:27:43.220Z,1537306063.220 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:27:44.052Z,1537306064.052 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:27:45.620Z,1537306065.620 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:27:50.433Z,1537306070.433 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:27:50.507Z,1537306070.507 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:27:50.824Z,1537306070.824 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T21:27:50.832Z,1537306070.832 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:27:51.220Z,1537306071.220 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:27:51.628Z,1537306071.628 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:27:55.630Z,1537306075.630 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:27:55.701Z,1537306075.701 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:27:56.057Z,1537306076.057 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:27:56.105Z,1537306076.105 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:27:56.833Z,1537306076.833 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:27:58.846Z,1537306078.846 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:28:02.818Z,1537306082.818 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T21:28:02.932Z,1537306082.932 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T21:28:03.221Z,1537306083.221 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T21:28:03.261Z,1537306083.261 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T21:28:03.622Z,1537306083.622 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T21:28:04.032Z,1537306084.032 [BPC1](INFO): BPC1B: initialized.
2018-09-18T21:28:08.023Z,1537306088.023 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T21:28:08.098Z,1537306088.098 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-18T21:28:08.422Z,1537306088.422 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T21:28:08.430Z,1537306088.430 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T21:28:10.533Z,1537306090.533 [CommandLine](IMPORTANT): got command restart application
2018-09-18T21:28:11.540Z,1537306091.540 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:28:11.540Z,1537306091.540 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:11.541Z,1537306091.541 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-18T21:28:11.541Z,1537306091.541 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:11.542Z,1537306091.542 [CommandLine](INFO): Join timeout helper Thread ID is 629
2018-09-18T21:28:11.572Z,1537306091.572 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-18T21:28:11.573Z,1537306091.573 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:11.573Z,1537306091.573 [NavChartDb](INFO): Join timeout helper Thread ID is 630
2018-09-18T21:28:11.793Z,1537306091.793 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:28:11.793Z,1537306091.793 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:11.805Z,1537306091.805 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-09-18T21:28:11.805Z,1537306091.805 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:11.805Z,1537306091.805 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 631
2018-09-18T21:28:11.833Z,1537306091.833 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:28:11.833Z,1537306091.833 [WetLabsBB2FL](INFO): Powering down
2018-09-18T21:28:11.834Z,1537306091.834 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:11.845Z,1537306091.845 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-09-18T21:28:11.845Z,1537306091.845 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:11.845Z,1537306091.845 [CTD_Seabird](INFO): Join timeout helper Thread ID is 632
2018-09-18T21:28:11.950Z,1537306091.950 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:28:12.089Z,1537306092.089 [BPC1](INFO): BPC1A: initialized.
2018-09-18T21:28:12.089Z,1537306092.089 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T21:28:12.142Z,1537306092.142 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-18T21:28:12.142Z,1537306092.142 [CTD_Seabird](INFO): Powering down
2018-09-18T21:28:12.143Z,1537306092.143 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:12.153Z,1537306092.153 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-09-18T21:28:12.153Z,1537306092.153 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:12.153Z,1537306092.153 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 634
2018-09-18T21:28:12.401Z,1537306092.401 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:28:12.401Z,1537306092.401 [CTD_NeilBrown](INFO): Powering down
2018-09-18T21:28:12.402Z,1537306092.402 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:12.417Z,1537306092.417 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-18T21:28:12.418Z,1537306092.418 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:12.418Z,1537306092.418 [Radio_Surface](INFO): Join timeout helper Thread ID is 635
2018-09-18T21:28:12.801Z,1537306092.801 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:28:12.801Z,1537306092.801 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:12.813Z,1537306092.813 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-18T21:28:12.813Z,1537306092.813 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:12.814Z,1537306092.814 [logger](INFO): Join timeout helper Thread ID is 636
2018-09-18T21:28:12.821Z,1537306092.821 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:28:12.821Z,1537306092.821 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:12.833Z,1537306092.833 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-18T21:28:12.834Z,1537306092.834 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:12.834Z,1537306092.834 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-18T21:28:12.834Z,1537306092.834 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:12.834Z,1537306092.834 [controlThread](INFO): Join timeout helper Thread ID is 637
2018-09-18T21:28:12.969Z,1537306092.969 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T21:28:12.969Z,1537306092.969 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-18T21:28:12.975Z,1537306092.975 [NAL9602](INFO): Powering down
2018-09-18T21:28:12.977Z,1537306092.977 [PNI_TCM](INFO): Powering down
2018-09-18T21:28:13.066Z,1537306093.066 [Aanderaa_O2](INFO): Powering down
2018-09-18T21:28:13.067Z,1537306093.067 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-18T21:28:13.069Z,1537306093.069 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-18T21:28:13.069Z,1537306093.069 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-18T21:28:13.070Z,1537306093.070 [MissionManager](INFO): Uninitializing Mission Default
2018-09-18T21:28:13.070Z,1537306093.070 [Default] Stopped
2018-09-18T21:28:13.070Z,1537306093.070 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-18T21:28:13.070Z,1537306093.070 [Default:B.GoToSurface] Stopped
2018-09-18T21:28:13.070Z,1537306093.070 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-18T21:28:13.070Z,1537306093.070 [Default:CheckIn] Stopped
2018-09-18T21:28:13.071Z,1537306093.071 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-18T21:28:13.071Z,1537306093.071 [Default:CheckIn:Read_GPS] Stopped
2018-09-18T21:28:13.075Z,1537306093.075 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-18T21:28:13.075Z,1537306093.075 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-18T21:28:13.075Z,1537306093.075 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-18T21:28:13.076Z,1537306093.076 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-18T21:28:13.076Z,1537306093.076 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-18T21:28:13.076Z,1537306093.076 [BuoyancyServo](INFO): Powering down
2018-09-18T21:28:13.089Z,1537306093.089 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-18T21:28:13.089Z,1537306093.089 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-18T21:28:13.089Z,1537306093.089 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-18T21:28:13.090Z,1537306093.090 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:13.200Z,1537306093.200 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:13.246Z,1537306093.246 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:13.251Z,1537306093.251 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:13.254Z,1537306093.254 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:13.286Z,1537306093.286 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-18T21:28:13.349Z,1537306093.349 [logger ThreadHandler](INFO): Thread cancelled.