2018-09-19T19:56:24.572Z,1537386984.572 [Supervisor](DEBUG): Initializing supervisor.
2018-09-19T19:56:24.575Z,1537386984.575 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-19T19:56:24.575Z,1537386984.575 [SyncHandler](INFO): Protected caller Thread ID is 1375
2018-09-19T19:56:24.576Z,1537386984.576 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-19T19:56:24.577Z,1537386984.577 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-19T19:56:24.578Z,1537386984.578 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1376
2018-09-19T19:56:24.581Z,1537386984.581 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-19T19:56:24.593Z,1537386984.593 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-19T19:56:24.594Z,1537386984.594 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-19T19:56:24.594Z,1537386984.594 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1377
2018-09-19T19:56:24.595Z,1537386984.595 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-19T19:56:24.596Z,1537386984.596 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-19T19:56:24.596Z,1537386984.596 [logger ThreadHandler](INFO): Protected caller Thread ID is 1378
2018-09-19T19:56:24.598Z,1537386984.598 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-19T19:56:24.599Z,1537386984.599 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-19T19:56:24.600Z,1537386984.600 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-19T19:56:24.789Z,1537386984.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-19T19:56:24.790Z,1537386984.790 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-19T19:56:24.933Z,1537386984.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-19T19:56:24.934Z,1537386984.934 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-19T19:56:25.281Z,1537386985.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-19T19:56:25.282Z,1537386985.282 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-19T19:56:25.420Z,1537386985.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-19T19:56:25.421Z,1537386985.421 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-19T19:56:25.501Z,1537386985.501 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-19T19:56:25.703Z,1537386985.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-19T19:56:25.704Z,1537386985.704 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-19T19:56:25.802Z,1537386985.802 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-19T19:56:25.803Z,1537386985.803 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-19T19:56:26.145Z,1537386986.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-19T19:56:26.146Z,1537386986.146 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-19T19:56:26.616Z,1537386986.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-19T19:56:26.617Z,1537386986.617 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-19T19:56:27.181Z,1537386987.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-19T19:56:27.181Z,1537386987.181 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-19T19:56:27.686Z,1537386987.686 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-19T19:56:27.687Z,1537386987.687 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-19T19:56:27.905Z,1537386987.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-19T19:56:27.906Z,1537386987.906 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-19T19:56:28.004Z,1537386988.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-19T19:56:28.005Z,1537386988.005 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-19T19:56:28.499Z,1537386988.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-19T19:56:28.499Z,1537386988.499 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-19T19:56:28.607Z,1537386988.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-19T19:56:28.608Z,1537386988.608 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-09-19T19:56:28.609Z,1537386988.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-09-19T19:56:28.840Z,1537386988.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-19T19:56:28.841Z,1537386988.841 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-09-19T19:56:28.939Z,1537386988.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-09-19T19:56:29.033Z,1537386989.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-09-19T19:56:29.135Z,1537386989.135 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-09-19T19:56:29.221Z,1537386989.221 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-09-19T19:56:29.356Z,1537386989.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-09-19T19:56:29.524Z,1537386989.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-09-19T19:56:29.635Z,1537386989.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-09-19T19:56:29.719Z,1537386989.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-09-19T19:56:29.803Z,1537386989.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-09-19T19:56:29.995Z,1537386989.995 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-09-19T19:56:29.995Z,1537386989.995 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-09-19T19:56:29.999Z,1537386989.999 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-19T19:56:30.131Z,1537386990.131 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-19T19:56:30.162Z,1537386990.162 [SBIT] Loaded
2018-09-19T19:56:30.162Z,1537386990.162 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-19T19:56:30.163Z,1537386990.163 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-19T19:56:30.191Z,1537386990.191 [IBIT] Loaded
2018-09-19T19:56:30.191Z,1537386990.191 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-19T19:56:30.195Z,1537386990.195 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-19T19:56:30.353Z,1537386990.353 [CBIT] Loaded
2018-09-19T19:56:30.353Z,1537386990.353 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-19T19:56:30.354Z,1537386990.354 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-19T19:56:30.354Z,1537386990.354 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-19T19:56:30.423Z,1537386990.423 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-19T19:56:30.526Z,1537386990.526 [VerticalControl] Loaded
2018-09-19T19:56:30.526Z,1537386990.526 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-19T19:56:30.527Z,1537386990.527 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-19T19:56:30.589Z,1537386990.589 [HorizontalControl] Loaded
2018-09-19T19:56:30.589Z,1537386990.589 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-19T19:56:30.590Z,1537386990.590 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-19T19:56:30.596Z,1537386990.596 [SpeedControl] Loaded
2018-09-19T19:56:30.596Z,1537386990.596 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-19T19:56:30.597Z,1537386990.597 [LoopControl](DEBUG): Construct LoopControl.
2018-09-19T19:56:30.597Z,1537386990.597 [LoopControl] Loaded
2018-09-19T19:56:30.598Z,1537386990.598 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-19T19:56:30.598Z,1537386990.598 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-19T19:56:30.599Z,1537386990.599 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-19T19:56:30.640Z,1537386990.640 [DepthRateCalculator] Loaded
2018-09-19T19:56:30.641Z,1537386990.641 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-19T19:56:30.647Z,1537386990.647 [PitchRateCalculator] Loaded
2018-09-19T19:56:30.647Z,1537386990.647 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-19T19:56:30.663Z,1537386990.663 [SpeedCalculator] Loaded
2018-09-19T19:56:30.663Z,1537386990.663 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-19T19:56:30.685Z,1537386990.685 [TempGradientCalculator] Loaded
2018-09-19T19:56:30.685Z,1537386990.685 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-19T19:56:30.691Z,1537386990.691 [YawRateCalculator] Loaded
2018-09-19T19:56:30.691Z,1537386990.691 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-19T19:56:30.721Z,1537386990.721 [ElevatorOffsetCalculator] Loaded
2018-09-19T19:56:30.721Z,1537386990.721 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-19T19:56:30.722Z,1537386990.722 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-19T19:56:30.722Z,1537386990.722 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-19T19:56:30.788Z,1537386990.788 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-19T19:56:30.788Z,1537386990.788 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-19T19:56:31.037Z,1537386991.037 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-19T19:56:31.037Z,1537386991.037 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-19T19:56:31.136Z,1537386991.136 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-09-19T19:56:31.136Z,1537386991.136 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-09-19T19:56:31.192Z,1537386991.192 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-19T19:56:31.192Z,1537386991.192 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-19T19:56:31.209Z,1537386991.209 [NavChart] Loaded
2018-09-19T19:56:31.209Z,1537386991.209 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-19T19:56:31.213Z,1537386991.213 [UniversalFixResidualReporter] Loaded
2018-09-19T19:56:31.214Z,1537386991.214 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-19T19:56:31.214Z,1537386991.214 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-19T19:56:31.215Z,1537386991.215 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-19T19:56:31.228Z,1537386991.228 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-19T19:56:31.229Z,1537386991.229 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-19T19:56:31.386Z,1537386991.386 [Aanderaa_O2] Loaded
2018-09-19T19:56:31.386Z,1537386991.386 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-09-19T19:56:31.433Z,1537386991.433 [WetLabsSeaOWL_UV_A] Loaded
2018-09-19T19:56:31.433Z,1537386991.433 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-09-19T19:56:31.435Z,1537386991.435 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406D54E0
2018-09-19T19:56:31.435Z,1537386991.435 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1457
2018-09-19T19:56:31.435Z,1537386991.435 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-19T19:56:31.436Z,1537386991.436 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-19T19:56:31.780Z,1537386991.780 [AHRS_M2] Loaded
2018-09-19T19:56:31.780Z,1537386991.780 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-09-19T19:56:31.850Z,1537386991.850 [DataOverHttps] Loaded
2018-09-19T19:56:31.850Z,1537386991.850 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-19T19:56:31.864Z,1537386991.864 [Depth_Keller] Loaded
2018-09-19T19:56:31.864Z,1537386991.864 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-19T19:56:31.869Z,1537386991.869 [DropWeight] Loaded
2018-09-19T19:56:31.869Z,1537386991.869 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-19T19:56:31.962Z,1537386991.962 [NAL9602] Loaded
2018-09-19T19:56:31.962Z,1537386991.962 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-19T19:56:31.968Z,1537386991.968 [Onboard] Loaded
2018-09-19T19:56:31.968Z,1537386991.968 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-19T19:56:31.975Z,1537386991.975 [Radio_Surface] Loaded
2018-09-19T19:56:31.975Z,1537386991.975 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-19T19:56:31.976Z,1537386991.976 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409364E0
2018-09-19T19:56:31.977Z,1537386991.977 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1458
2018-09-19T19:56:32.006Z,1537386992.006 [RDI_Pathfinder] Loaded
2018-09-19T19:56:32.007Z,1537386992.007 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-09-19T19:56:32.029Z,1537386992.029 [RDI_PathfinderUp] Loaded
2018-09-19T19:56:32.030Z,1537386992.030 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2018-09-19T19:56:32.036Z,1537386992.036 [SCPI] Loaded
2018-09-19T19:56:32.036Z,1537386992.036 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-09-19T19:56:32.060Z,1537386992.060 [BPC1](INFO): BPC1A: got serial number for stick 1: 0140
2018-09-19T19:56:32.061Z,1537386992.061 [BPC1](INFO): BPC1A: got serial number for stick 2: 0163
2018-09-19T19:56:32.062Z,1537386992.062 [BPC1](INFO): BPC1A: got serial number for stick 3: 0157
2018-09-19T19:56:32.063Z,1537386992.063 [BPC1](INFO): BPC1A: got serial number for stick 4: 0159
2018-09-19T19:56:32.064Z,1537386992.064 [BPC1](INFO): BPC1A: got serial number for stick 5: 016E
2018-09-19T19:56:32.065Z,1537386992.065 [BPC1](INFO): BPC1A: got serial number for stick 6: 0160
2018-09-19T19:56:32.065Z,1537386992.065 [BPC1](INFO): BPC1A: got serial number for stick 7: 015C
2018-09-19T19:56:32.066Z,1537386992.066 [BPC1](INFO): BPC1A: got serial number for stick 8: 016D
2018-09-19T19:56:32.067Z,1537386992.067 [BPC1](INFO): BPC1A: got serial number for stick 9: 012D
2018-09-19T19:56:32.068Z,1537386992.068 [BPC1](INFO): BPC1A: got serial number for stick 10: 015E
2018-09-19T19:56:32.068Z,1537386992.068 [BPC1](INFO): BPC1A: got serial number for stick 11: 0164
2018-09-19T19:56:32.069Z,1537386992.069 [BPC1](INFO): BPC1A: got serial number for stick 12: 015B
2018-09-19T19:56:32.070Z,1537386992.070 [BPC1](INFO): BPC1A: got serial number for stick 13: 0172
2018-09-19T19:56:32.071Z,1537386992.071 [BPC1](INFO): BPC1A: got serial number for stick 14: 0166
2018-09-19T19:56:32.071Z,1537386992.071 [BPC1](INFO): BPC1A: got serial number for stick 15: 0170
2018-09-19T19:56:32.072Z,1537386992.072 [BPC1](INFO): BPC1A: got serial number for stick 16: 0148
2018-09-19T19:56:32.073Z,1537386992.073 [BPC1](INFO): BPC1A: got serial number for stick 17: 0130
2018-09-19T19:56:32.074Z,1537386992.074 [BPC1](INFO): BPC1A: got serial number for stick 18: 0169
2018-09-19T19:56:32.075Z,1537386992.075 [BPC1](INFO): BPC1A: got serial number for stick 19: 0158
2018-09-19T19:56:32.075Z,1537386992.075 [BPC1](INFO): BPC1A: got serial number for stick 20: 0162
2018-09-19T19:56:32.076Z,1537386992.076 [BPC1](INFO): BPC1A: got serial number for stick 21: 0150
2018-09-19T19:56:32.077Z,1537386992.077 [BPC1](INFO): BPC1A: got serial number for stick 22: 015F
2018-09-19T19:56:32.078Z,1537386992.078 [BPC1](INFO): BPC1A: got serial number for stick 23: 0171
2018-09-19T19:56:32.079Z,1537386992.079 [BPC1](INFO): BPC1A: got serial number for stick 24: 0143
2018-09-19T19:56:32.079Z,1537386992.079 [BPC1](INFO): BPC1A: got serial number for stick 25: 0144
2018-09-19T19:56:32.080Z,1537386992.080 [BPC1](INFO): BPC1A: got serial number for stick 26: 016A
2018-09-19T19:56:32.081Z,1537386992.081 [BPC1](INFO): BPC1A: got serial number for stick 27: 0155
2018-09-19T19:56:32.082Z,1537386992.082 [BPC1](INFO): BPC1A: got serial number for stick 28: 0151
2018-09-19T19:56:32.082Z,1537386992.082 [BPC1](INFO): BPC1A: got serial number for stick 29: 0154
2018-09-19T19:56:32.083Z,1537386992.083 [BPC1](INFO): BPC1A: got serial number for stick 30: 0161
2018-09-19T19:56:32.084Z,1537386992.084 [BPC1](INFO): BPC1A: got serial number for stick 31: 0138
2018-09-19T19:56:32.086Z,1537386992.086 [BPC1](INFO): BPC1B: got serial number for stick 32: 0165
2018-09-19T19:56:32.086Z,1537386992.086 [BPC1](INFO): BPC1B: got serial number for stick 33: 0135
2018-09-19T19:56:32.087Z,1537386992.087 [BPC1](INFO): BPC1B: got serial number for stick 34: 0149
2018-09-19T19:56:32.088Z,1537386992.088 [BPC1](INFO): BPC1B: got serial number for stick 35: 0146
2018-09-19T19:56:32.089Z,1537386992.089 [BPC1](INFO): BPC1B: got serial number for stick 36: 013D
2018-09-19T19:56:32.090Z,1537386992.090 [BPC1](INFO): BPC1B: got serial number for stick 37: 014D
2018-09-19T19:56:32.091Z,1537386992.091 [BPC1](INFO): BPC1B: got serial number for stick 38: 0156
2018-09-19T19:56:32.091Z,1537386992.091 [BPC1](INFO): BPC1B: got serial number for stick 39: 0133
2018-09-19T19:56:32.092Z,1537386992.092 [BPC1](INFO): BPC1B: got serial number for stick 40: 0131
2018-09-19T19:56:32.093Z,1537386992.093 [BPC1](INFO): BPC1B: got serial number for stick 41: 0145
2018-09-19T19:56:32.094Z,1537386992.094 [BPC1](INFO): BPC1B: got serial number for stick 42: 014A
2018-09-19T19:56:32.094Z,1537386992.094 [BPC1](INFO): BPC1B: got serial number for stick 43: 013C
2018-09-19T19:56:32.095Z,1537386992.095 [BPC1](INFO): BPC1B: got serial number for stick 44: 013B
2018-09-19T19:56:32.096Z,1537386992.096 [BPC1](INFO): BPC1B: got serial number for stick 45: 0132
2018-09-19T19:56:32.097Z,1537386992.097 [BPC1](INFO): BPC1B: got serial number for stick 46: 0134
2018-09-19T19:56:32.097Z,1537386992.097 [BPC1](INFO): BPC1B: got serial number for stick 47: 0153
2018-09-19T19:56:32.098Z,1537386992.098 [BPC1](INFO): BPC1B: got serial number for stick 48: 014B
2018-09-19T19:56:32.099Z,1537386992.099 [BPC1](INFO): BPC1B: got serial number for stick 49: 013A
2018-09-19T19:56:32.100Z,1537386992.100 [BPC1](INFO): BPC1B: got serial number for stick 50: 0136
2018-09-19T19:56:32.101Z,1537386992.101 [BPC1](INFO): BPC1B: got serial number for stick 51: 014F
2018-09-19T19:56:32.101Z,1537386992.101 [BPC1](INFO): BPC1B: got serial number for stick 52: 014E
2018-09-19T19:56:32.102Z,1537386992.102 [BPC1](INFO): BPC1B: got serial number for stick 53: 013F
2018-09-19T19:56:32.103Z,1537386992.103 [BPC1](INFO): BPC1B: got serial number for stick 54: 0152
2018-09-19T19:56:32.104Z,1537386992.104 [BPC1](INFO): BPC1B: got serial number for stick 55: 014C
2018-09-19T19:56:32.105Z,1537386992.105 [BPC1](INFO): BPC1B: got serial number for stick 56: 016B
2018-09-19T19:56:32.105Z,1537386992.105 [BPC1](INFO): BPC1B: got serial number for stick 57: 0141
2018-09-19T19:56:32.106Z,1537386992.106 [BPC1](INFO): BPC1B: got serial number for stick 58: 0139
2018-09-19T19:56:32.107Z,1537386992.107 [BPC1](INFO): BPC1B: got serial number for stick 59: 0137
2018-09-19T19:56:32.108Z,1537386992.108 [BPC1](INFO): BPC1B: got serial number for stick 60: 0142
2018-09-19T19:56:32.108Z,1537386992.108 [BPC1](INFO): BPC1B: got serial number for stick 61: 013E
2018-09-19T19:56:32.109Z,1537386992.109 [BPC1](INFO): BPC1B: got serial number for stick 62: 0147
2018-09-19T19:56:34.190Z,1537386994.190 [BPC1] Loaded
2018-09-19T19:56:34.191Z,1537386994.191 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-19T19:56:34.191Z,1537386994.191 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-19T19:56:34.192Z,1537386994.192 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-19T19:56:34.294Z,1537386994.294 [BuoyancyServo] Loaded
2018-09-19T19:56:34.294Z,1537386994.294 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-19T19:56:34.306Z,1537386994.306 [ElevatorServo] Loaded
2018-09-19T19:56:34.306Z,1537386994.306 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-09-19T19:56:34.317Z,1537386994.317 [MassServo] Loaded
2018-09-19T19:56:34.318Z,1537386994.318 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-09-19T19:56:34.329Z,1537386994.329 [RudderServo] Loaded
2018-09-19T19:56:34.330Z,1537386994.330 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-09-19T19:56:34.341Z,1537386994.341 [ThrusterServo] Loaded
2018-09-19T19:56:34.341Z,1537386994.341 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-09-19T19:56:34.342Z,1537386994.342 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-19T19:56:34.342Z,1537386994.342 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-19T19:56:34.445Z,1537386994.445 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-19T19:56:34.446Z,1537386994.446 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-19T19:56:34.470Z,1537386994.470 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-19T19:56:34.474Z,1537386994.474 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-19T19:56:34.475Z,1537386994.475 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-19T19:56:34.482Z,1537386994.482 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-19T19:56:34.483Z,1537386994.483 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409DF4E0
2018-09-19T19:56:34.483Z,1537386994.483 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1459
2018-09-19T19:56:34.488Z,1537386994.488 [Supervisor](INFO): Main Thread ID is 800
2018-09-19T19:56:34.488Z,1537386994.488 [Supervisor](DEBUG): Running supervisor.
2018-09-19T19:56:34.489Z,1537386994.489 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1460
2018-09-19T19:56:34.491Z,1537386994.491 [controlThread ThreadHandler](INFO): Handler Thread ID is 1461
2018-09-19T19:56:34.492Z,1537386994.492 [controlThread](DEBUG): Initializing ControlThread
2018-09-19T19:56:34.492Z,1537386994.492 [SBIT](INFO): Initialize SBIT Component.
2018-09-19T19:56:34.493Z,1537386994.493 [SBIT](IMPORTANT): git: 2018-09-06-21-g021ff34
2018-09-19T19:56:34.493Z,1537386994.493 [SBIT](INFO): git hash: 021ff3480491a97d99bb6df627398514e33207a2
2018-09-19T19:56:34.493Z,1537386994.493 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-19T19:56:34.494Z,1537386994.494 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-09-19T19:56:34.495Z,1537386994.495 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-09-19T19:56:34.495Z,1537386994.495 [IBIT](INFO): Initialize IBIT Component.
2018-09-19T19:56:34.496Z,1537386994.496 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-19T19:56:34.497Z,1537386994.497 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-09-19T19:56:34.497Z,1537386994.497 [logger ThreadHandler](INFO): Handler Thread ID is 1462
2018-09-19T19:56:34.523Z,1537386994.523 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1463
2018-09-19T19:56:34.524Z,1537386994.524 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-19T19:56:34.552Z,1537386994.552 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1464
2018-09-19T19:56:34.558Z,1537386994.558 [Radio_Surface](INFO): Powering up
2018-09-19T19:56:34.567Z,1537386994.567 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1465
2018-09-19T19:56:34.570Z,1537386994.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-19T19:56:34.570Z,1537386994.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-19T19:56:34.571Z,1537386994.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-19T19:56:34.571Z,1537386994.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-19T19:56:34.571Z,1537386994.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-19T19:56:34.571Z,1537386994.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-19T19:56:34.571Z,1537386994.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-19T19:56:34.571Z,1537386994.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-19T19:56:34.572Z,1537386994.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-19T19:56:34.572Z,1537386994.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-19T19:56:34.572Z,1537386994.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-19T19:56:34.572Z,1537386994.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-19T19:56:34.572Z,1537386994.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-19T19:56:34.573Z,1537386994.573 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-19T19:56:34.573Z,1537386994.573 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-19T19:56:34.573Z,1537386994.573 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-19T19:56:34.603Z,1537386994.603 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-19T19:56:34.604Z,1537386994.604 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-19T19:56:34.605Z,1537386994.605 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-19T19:56:34.606Z,1537386994.606 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-19T19:56:34.606Z,1537386994.606 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-19T19:56:34.606Z,1537386994.606 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-19T19:56:34.607Z,1537386994.607 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-19T19:56:34.607Z,1537386994.607 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-19T19:56:34.608Z,1537386994.608 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-19T19:56:34.608Z,1537386994.608 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-19T19:56:34.609Z,1537386994.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T19:56:34.609Z,1537386994.609 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T19:56:34.610Z,1537386994.610 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T19:56:34.610Z,1537386994.610 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T19:56:34.611Z,1537386994.611 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T19:56:34.611Z,1537386994.611 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T19:56:34.612Z,1537386994.612 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-19T19:56:34.612Z,1537386994.612 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-19T19:56:34.619Z,1537386994.619 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-19T19:56:34.651Z,1537386994.651 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-19T19:56:34.683Z,1537386994.683 [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-19T19:56:34.684Z,1537386994.684 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-19T19:56:34.779Z,1537386994.779 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-19T19:56:34.780Z,1537386994.780 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-19T19:56:34.782Z,1537386994.782 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-19T19:56:34.797Z,1537386994.797 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-19T19:56:34.819Z,1537386994.819 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-19T19:56:34.825Z,1537386994.825 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-19T19:56:34.845Z,1537386994.845 [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-19T19:56:34.850Z,1537386994.850 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-19T19:56:34.921Z,1537386994.921 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-09-19T19:56:34.951Z,1537386994.951 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-19T19:56:34.983Z,1537386994.983 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-09-19T19:56:35.239Z,1537386995.239 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-19T19:56:35.305Z,1537386995.305 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:35.445Z,1537386995.445 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:35.679Z,1537386995.679 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-19T19:56:35.687Z,1537386995.687 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-19T19:56:35.697Z,1537386995.697 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-09-19T19:56:35.703Z,1537386995.703 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-09-19T19:56:35.724Z,1537386995.724 [MassServo](DEBUG): Initializing EZServoServo.
2018-09-19T19:56:35.731Z,1537386995.731 [MassServo](DEBUG): Initializing MassServo.
2018-09-19T19:56:35.736Z,1537386995.736 [RudderServo](DEBUG): Initializing EZServoServo.
2018-09-19T19:56:35.743Z,1537386995.743 [RudderServo](DEBUG): Initializing RudderServo.
2018-09-19T19:56:35.749Z,1537386995.749 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-19T19:56:35.755Z,1537386995.755 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-19T19:56:35.940Z,1537386995.940 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-09-19T19:56:35.940Z,1537386995.940 [DropWeight] Hardware Fault, FailCount= 1
2018-09-19T19:56:35.940Z,1537386995.940 [DropWeight](ERROR): Hardware Fault
2018-09-19T19:56:36.030Z,1537386996.030 [RDI_PathfinderUp](INFO): Powering down
2018-09-19T19:56:36.078Z,1537386996.078 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-19T19:56:36.081Z,1537386996.081 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:36.082Z,1537386996.082 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:36.109Z,1537386996.109 [CommandLine](FAULT): Scheduling is paused
2018-09-19T19:56:36.110Z,1537386996.110 [CBIT](INFO): Critical error at 20180919T195635
2018-09-19T19:56:36.110Z,1537386996.110 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-09-19T19:56:36.113Z,1537386996.113 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-09-19T19:56:36.114Z,1537386996.114 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-09-19T19:56:36.271Z,1537386996.271 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-19T19:56:36.273Z,1537386996.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:36.275Z,1537386996.275 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:36.606Z,1537386996.606 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-19T19:56:36.607Z,1537386996.607 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-19T19:56:36.607Z,1537386996.607 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-19T19:56:36.607Z,1537386996.607 [BuoyancyServo](ERROR): Communications Fault
2018-09-19T19:56:36.816Z,1537386996.816 [CBIT](INFO): Critical error at 20180919T195636
2018-09-19T19:56:36.819Z,1537386996.819 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-19T19:56:36.882Z,1537386996.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:36.884Z,1537386996.884 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:36.920Z,1537386996.920 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-19T19:56:36.921Z,1537386996.921 [BuoyancyServo](INFO): Powering down
2018-09-19T19:56:37.262Z,1537386997.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:37.263Z,1537386997.263 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:37.663Z,1537386997.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:37.664Z,1537386997.664 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:38.146Z,1537386998.146 [Aanderaa_O2](INFO): Powering down
2018-09-19T19:56:38.274Z,1537386998.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:38.282Z,1537386998.282 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:38.490Z,1537386998.490 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-09-19T19:56:38.530Z,1537386998.530 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:38.531Z,1537386998.531 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T19:56:39.443Z,1537386999.443 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-19T19:56:39.443Z,1537386999.443 [BuoyancyServo] No Fault, FailCount= 1
2018-09-19T19:56:39.686Z,1537386999.686 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-19T19:56:39.808Z,1537386999.808 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-19T19:56:48.567Z,1537387008.567 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-19T19:56:58.993Z,1537387018.993 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-19T19:56:59.016Z,1537387019.016 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-19T19:57:02.260Z,1537387022.260 [NAL9602](INFO): Powering up NAL9602
2018-09-19T19:57:09.972Z,1537387029.972 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008905
CHAN A1 (24V): -0.012447
CHAN A2 (12V): -0.007326
CHAN A3 (5V): -0.002099
CHAN B0 (3.3V): 0.000300
CHAN B1 (3.15aV): 0.000286
CHAN B2 (3.15bV): -0.000019
CHAN B3 (GND): 0.002212
OPEN: 0.005005
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-19T19:57:13.440Z,1537387033.440 [NAL9602](INFO): NAL9602 initialized
2018-09-19T19:57:36.110Z,1537387056.110 [BPC1](INFO): BPC1A: initialized.
2018-09-19T19:57:36.122Z,1537387056.122 [BPC1](INFO): BPC1B: initialized.
2018-09-19T19:57:43.834Z,1537387063.834 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T19:57:43.835Z,1537387063.835 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T19:57:48.670Z,1537387068.670 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T19:57:48.747Z,1537387068.747 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-19T19:57:48.748Z,1537387068.748 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T19:57:48.816Z,1537387068.816 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-19T19:57:49.029Z,1537387069.029 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-19T19:57:49.030Z,1537387069.030 [BPC1](ERROR): BPC1A: No match for stick with serial number:014B in the onboard configuration file.
2018-09-19T19:57:49.038Z,1537387069.038 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-19T19:57:49.039Z,1537387069.039 [BPC1](ERROR): BPC1B: No match for stick with serial number:0130 in the onboard configuration file.
2018-09-19T19:57:49.040Z,1537387069.040 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-09-19T19:57:49.040Z,1537387069.040 [BPC1] Data Fault, FailCount= 1
2018-09-19T19:57:49.040Z,1537387069.040 [BPC1](ERROR): Data Fault
2018-09-19T19:57:49.091Z,1537387069.091 [CBIT](ERROR): Data Fault in component: BPC1
2018-09-19T19:57:53.077Z,1537387073.077 [SBIT](IMPORTANT): SBIT PASSED
2018-09-19T19:57:53.139Z,1537387073.139 [CommandLine](IMPORTANT): got command configSet list
2018-09-19T19:57:53.140Z,1537387073.140 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-19T19:57:53.141Z,1537387073.141 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter;

2018-09-19T19:57:53.141Z,1537387073.141 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter;

2018-09-19T19:57:53.482Z,1537387073.482 [MissionManager](IMPORTANT): Started mission Startup
2018-09-19T19:57:53.483Z,1537387073.483 [Startup] Running Loop=1
2018-09-19T19:57:53.483Z,1537387073.483 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-19T19:57:53.483Z,1537387073.483 [Startup:A.GoToSurface] Running Loop=1
2018-09-19T19:57:53.483Z,1537387073.483 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-19T19:57:53.484Z,1537387073.484 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-19T19:57:53.484Z,1537387073.484 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-19T19:57:53.484Z,1537387073.484 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-19T19:57:53.485Z,1537387073.485 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-19T19:57:53.485Z,1537387073.485 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-19T19:57:53.511Z,1537387073.511 [Startup:StartupSatComms] Running Loop=1
2018-09-19T19:57:53.511Z,1537387073.511 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-19T19:57:53.511Z,1537387073.511 [Startup:StartupSatComms:A] Running Loop=1
2018-09-19T19:57:53.871Z,1537387073.871 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-19T19:58:53.859Z,1537387133.859 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-19T19:57:53.5Z
2018-09-19T19:58:53.859Z,1537387133.859 [Startup:StartupSatComms:A] Stopped
2018-09-19T19:58:53.859Z,1537387133.859 [Startup:StartupSatComms:B] Running Loop=1
2018-09-19T19:58:54.251Z,1537387134.251 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-19T19:59:34.667Z,1537387174.667 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T19:59:34.667Z,1537387174.667 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-09-19T19:59:34.667Z,1537387174.667 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-19T19:59:34.669Z,1537387174.669 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T19:59:34.669Z,1537387174.669 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-09-19T19:59:34.669Z,1537387174.669 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-19T19:59:34.702Z,1537387174.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-19T19:59:34.711Z,1537387174.711 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-19T19:59:35.064Z,1537387175.064 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-19T19:59:35.064Z,1537387175.064 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-09-19T19:59:35.064Z,1537387175.064 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-19T19:59:35.065Z,1537387175.065 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-09-19T19:59:35.484Z,1537387175.484 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T19:59:35.485Z,1537387175.485 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T19:59:35.485Z,1537387175.485 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T19:59:35.485Z,1537387175.485 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T19:59:35.486Z,1537387175.486 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T19:59:35.486Z,1537387175.486 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T19:59:40.310Z,1537387180.310 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004705
2018-09-19T19:59:44.415Z,1537387184.415 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20180919T190807/Courier0004.lzma
2018-09-19T19:59:45.193Z,1537387185.193 [DataOverHttps](INFO): Moved sent file to Logs/20180919T190807/Courier0004.lzma.bak
2018-09-19T19:59:45.194Z,1537387185.194 [DataOverHttps](INFO): SBD MOMSN=8539006
2018-09-19T19:59:50.512Z,1537387190.512 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,181.00
2018-09-19T19:59:54.096Z,1537387194.096 [Startup:StartupSatComms:B](INFO): Timed out from 2018-09-19T19:58:53.9Z
2018-09-19T19:59:54.096Z,1537387194.096 [Startup:StartupSatComms:B] Stopped
2018-09-19T19:59:54.096Z,1537387194.096 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-19T19:59:54.096Z,1537387194.096 [Startup:StartupSatComms] Stopped
2018-09-19T19:59:54.097Z,1537387194.097 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-19T19:59:54.098Z,1537387194.098 [Startup](INFO): Completed Startup
2018-09-19T19:59:54.098Z,1537387194.098 [MissionManager](INFO): Startup is completed.
2018-09-19T19:59:54.098Z,1537387194.098 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-19T19:59:54.098Z,1537387194.098 [Startup] Stopped
2018-09-19T19:59:54.098Z,1537387194.098 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-19T19:59:54.098Z,1537387194.098 [Startup:A.GoToSurface] Stopped
2018-09-19T19:59:54.098Z,1537387194.098 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-19T19:59:54.130Z,1537387194.130 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20180919T195624/Courier0000.lzma
2018-09-19T19:59:54.230Z,1537387194.230 [MissionManager](IMPORTANT): Started mission Default
2018-09-19T19:59:54.234Z,1537387194.234 [Default] Running Loop=1
2018-09-19T19:59:54.235Z,1537387194.235 [Default](DEBUG): Aggregate::initialize Default
2018-09-19T19:59:54.235Z,1537387194.235 [Default:B.GoToSurface] Running Loop=1
2018-09-19T19:59:54.235Z,1537387194.235 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-19T19:59:54.235Z,1537387194.235 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-19T19:59:54.235Z,1537387194.235 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-19T19:59:54.236Z,1537387194.236 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-19T19:59:54.236Z,1537387194.236 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-19T19:59:54.236Z,1537387194.236 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-19T19:59:54.237Z,1537387194.237 [Default:A.Wait] Running Loop=1
2018-09-19T19:59:54.237Z,1537387194.237 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-19T19:59:54.925Z,1537387194.925 [DataOverHttps](INFO): Moved sent file to Logs/20180919T195624/Courier0000.lzma.bak
2018-09-19T19:59:54.925Z,1537387194.925 [DataOverHttps](INFO): SBD MOMSN=8539011
2018-09-19T20:00:07.447Z,1537387207.447 [Default:A.Wait](INFO): Done Waiting.
2018-09-19T20:00:07.447Z,1537387207.447 [Default:A.Wait] Stopped
2018-09-19T20:00:07.447Z,1537387207.447 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-19T20:00:07.860Z,1537387207.860 [Default:CheckIn] Running Loop=1
2018-09-19T20:00:07.860Z,1537387207.860 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-19T20:00:07.860Z,1537387207.860 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-19T20:00:08.272Z,1537387208.272 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-19T20:00:44.903Z,1537387244.903 [CommandLine](IMPORTANT): got command failComponent
2018-09-19T20:00:44.903Z,1537387244.903 [CommandLine](IMPORTANT): Failed components:
2018-09-19T20:00:44.904Z,1537387244.904 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-09-19T20:00:44.904Z,1537387244.904 [CommandLine](IMPORTANT): BPC1: Data Fault
2018-09-19T20:00:47.895Z,1537387247.895 [CommandLine](IMPORTANT): got command failComponent none BPC1
2018-09-19T20:00:47.896Z,1537387247.896 [BPC1] No Fault, FailCount= 1
2018-09-19T20:00:47.896Z,1537387247.896 [CommandLine](IMPORTANT): BPC1 failureMode is No Fault
2018-09-19T20:02:17.144Z,1537387337.144 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-09-19T20:02:35.597Z,1537387355.597 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:02:35.597Z,1537387355.597 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-09-19T20:02:35.597Z,1537387355.597 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-19T20:02:35.599Z,1537387355.599 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:02:35.599Z,1537387355.599 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-09-19T20:02:35.599Z,1537387355.599 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-19T20:02:35.613Z,1537387355.613 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-19T20:02:35.613Z,1537387355.613 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-19T20:02:36.020Z,1537387356.020 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-19T20:02:36.020Z,1537387356.020 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-09-19T20:02:36.020Z,1537387356.020 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-19T20:02:36.020Z,1537387356.020 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-09-19T20:02:36.399Z,1537387356.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T20:02:36.400Z,1537387356.400 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:02:36.400Z,1537387356.400 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:02:36.400Z,1537387356.400 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T20:02:36.401Z,1537387356.401 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:02:36.401Z,1537387356.401 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:05:07.955Z,1537387507.955 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-19T20:00:07.9Z
2018-09-19T20:05:07.955Z,1537387507.955 [Default:CheckIn:Read_GPS] Stopped
2018-09-19T20:05:07.955Z,1537387507.955 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-19T20:05:08.362Z,1537387508.362 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-19T20:05:26.989Z,1537387526.989 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T195624/Courier0004.lzma
2018-09-19T20:05:27.761Z,1537387527.761 [DataOverHttps](INFO): Moved sent file to Logs/20180919T195624/Courier0004.lzma.bak
2018-09-19T20:05:27.762Z,1537387527.762 [DataOverHttps](INFO): SBD MOMSN=8539056
2018-09-19T20:05:36.675Z,1537387536.675 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:05:36.675Z,1537387536.675 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-09-19T20:05:36.675Z,1537387536.675 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-19T20:05:36.677Z,1537387536.677 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:05:36.677Z,1537387536.677 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-09-19T20:05:36.677Z,1537387536.677 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-19T20:05:36.690Z,1537387536.690 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-19T20:05:36.690Z,1537387536.690 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-19T20:05:36.760Z,1537387536.760 [DataOverHttps](INFO): Sending 651 bytes from file Logs/20180919T181706/Express0014.lzma
2018-09-19T20:05:36.844Z,1537387536.844 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-19T20:05:36.844Z,1537387536.844 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-09-19T20:05:36.844Z,1537387536.844 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-19T20:05:36.844Z,1537387536.844 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-09-19T20:05:37.159Z,1537387537.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T20:05:37.160Z,1537387537.160 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:05:37.160Z,1537387537.160 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:05:37.160Z,1537387537.160 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T20:05:37.161Z,1537387537.161 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:05:37.161Z,1537387537.161 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:05:37.505Z,1537387537.505 [DataOverHttps](INFO): Moved sent file to Logs/20180919T181706/Express0014.lzma.bak
2018-09-19T20:05:37.505Z,1537387537.505 [DataOverHttps](INFO): SBD MOMSN=8539058
2018-09-19T20:05:46.450Z,1537387546.450 [DataOverHttps](INFO): Sending 925 bytes from file Logs/20180919T190807/Express0001.lzma
2018-09-19T20:05:47.221Z,1537387547.221 [DataOverHttps](INFO): Moved sent file to Logs/20180919T190807/Express0001.lzma.bak
2018-09-19T20:05:47.221Z,1537387547.221 [DataOverHttps](INFO): SBD MOMSN=8539102
2018-09-19T20:05:56.164Z,1537387556.164 [DataOverHttps](INFO): Sending 718 bytes from file Logs/20180919T190807/Express0005.lzma
2018-09-19T20:05:56.929Z,1537387556.929 [DataOverHttps](INFO): Moved sent file to Logs/20180919T190807/Express0005.lzma.bak
2018-09-19T20:05:56.929Z,1537387556.929 [DataOverHttps](INFO): SBD MOMSN=8539126
2018-09-19T20:06:06.704Z,1537387566.704 [DataOverHttps](INFO): Sending 788 bytes from file Logs/20180919T195624/Express0001.lzma
2018-09-19T20:06:07.457Z,1537387567.457 [DataOverHttps](INFO): Moved sent file to Logs/20180919T195624/Express0001.lzma.bak
2018-09-19T20:06:07.457Z,1537387567.457 [DataOverHttps](INFO): SBD MOMSN=8539158
2018-09-19T20:06:16.409Z,1537387576.409 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20180919T195624/Express0005.lzma
2018-09-19T20:06:17.189Z,1537387577.189 [DataOverHttps](INFO): Moved sent file to Logs/20180919T195624/Express0005.lzma.bak
2018-09-19T20:06:17.189Z,1537387577.189 [DataOverHttps](INFO): SBD MOMSN=8539176
2018-09-19T20:06:17.741Z,1537387577.741 [Default:CheckIn:Read_Iridium] Stopped
2018-09-19T20:06:17.741Z,1537387577.741 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-19T20:06:17.741Z,1537387577.741 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-19T20:06:22.528Z,1537387582.528 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-19T20:07:14.894Z,1537387634.894 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-19T20:07:14.894Z,1537387634.894 [NAL9602] Data Fault, FailCount= 1
2018-09-19T20:07:14.894Z,1537387634.894 [NAL9602](ERROR): Data Fault
2018-09-19T20:07:14.968Z,1537387634.968 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-19T20:07:15.292Z,1537387635.292 [NAL9602](INFO): Powering down
2018-09-19T20:07:16.174Z,1537387636.174 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-19T20:07:16.174Z,1537387636.174 [NAL9602] No Fault, FailCount= 1
2018-09-19T20:07:45.344Z,1537387665.344 [NAL9602](INFO): Powering up NAL9602
2018-09-19T20:07:56.153Z,1537387676.153 [NAL9602](INFO): NAL9602 initialized
2018-09-19T20:08:37.461Z,1537387717.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:08:37.461Z,1537387717.461 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-09-19T20:08:37.461Z,1537387717.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-19T20:08:37.463Z,1537387717.463 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:08:37.463Z,1537387717.463 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-09-19T20:08:37.463Z,1537387717.463 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-19T20:08:37.484Z,1537387717.484 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-19T20:08:37.485Z,1537387717.485 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-19T20:08:37.898Z,1537387717.898 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-19T20:08:37.898Z,1537387717.898 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-09-19T20:08:37.903Z,1537387717.903 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-19T20:08:37.903Z,1537387717.903 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-09-19T20:08:38.302Z,1537387718.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T20:08:38.303Z,1537387718.303 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:08:38.303Z,1537387718.303 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:08:38.303Z,1537387718.303 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T20:08:38.304Z,1537387718.304 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:08:38.304Z,1537387718.304 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:08:51.490Z,1537387731.490 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:08:51.506Z,1537387731.506 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:08:53.046Z,1537387733.046 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:08:56.670Z,1537387736.670 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:08:56.704Z,1537387736.704 [BPC1](INFO): BPC1B: no match for SMBUS stick msg of size 1549 bytes.
2018-09-19T20:08:56.705Z,1537387736.705 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
Got:
$B11,02,000A,01,02A8,03,0001,08,0BB4,09,3E8D,0A,045D,0B,041F%43
$B11,0C,0001,0D,0052,0E,004E,0F,14AF,10,$B12,02,000A,01,02A8,03,0001,08,0BB1,09,3E87,0A,0468,0B,0429%35
$B12,0C,0001,0D,0052,0E,004E,0F,14B5,10,1987,11,FFFF,12,FFFF%45
$B12,13,0069,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%4D
$B12,1A,0031,1B,4ADA,1C,016A%34
$B13,02,000A,01,02A8,03,0001,08,0BB0,09,3E7B,0A,043E,0B,0406%3A
$B13,0C,0001,0D,0051,0E,004D,0F,146D,10,1973,11,FFFF,12,FFFF%48
$B13,13,006E,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%30
$B13,1A,0031,1B,4ADA,1C,0155%42
$B14,02,000A,01,02A8,03,0001,08,0BAF,09,3E79,0A,0446,0B,040D%35
$B14,0C,0001,0D,0051,0E,004F,0F,14EE,10,1A05,11,FFFF,12,FFFF%46
$B14,13,006F,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%34
$B14,1A,0031,1B,4ADA,1C,0151%41
$B15,02,000A,01,02A8,03,0001,08,0BB4,09,3EA4,0A,044D,0B,0418%31
$B15,0C,0001,0D,0051,0E,004E,0F,1492,10,196C,11,FFFF,12,FFFF%47
$B15,13,006A,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%32
$B15,1A,0031,1B,4ADA,1C,0154%45
$B16,02,000A,01,02A8,03,0001,08,0BAA,09,3EA5,0A,0455,0B,0424%3A
$B16,0C,0001,0D,0052,0E,004E,0F,1494,10,1958,11,FFFF,12,FFFF%39
$B16,13,0068,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%48
$B16,1A,0031,1B,4ADA,1C,1717%46
$B17,02,000A,01,02A8,03,0001,08,0BA8,09,3EEA,0A,0491,0B,0464%3E
$B17,0C,0001,0D,0053,0E,004F,0F,14EB,10,195B,11,FFFF,12,FFFF%4A
$B17,13,0061,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%40
$B17,1A,0031,1B,4ACF,1C,0138%4D

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

->0,19,3840%33
$B17,1A,0031,1B,4ADA,1C,0171%40
$B18,02,000A,01,02A8,03,0001,08,0BB9,09,3EDC,0A,01FB,0B,01F0%48
$B18,0C,0001,0D,0058,0E,0054,0F,1650,10,1974,11,FFFF,12,FFFF%49
$B18,13,0072,14,0BB8,15,41A0,16,0080,17,0001,18,1A90,19,3840%4E
$B18,1A,0031,1B,4ACF,1C,0143%4E

2018-09-19T20:08:57.486Z,1537387737.486 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:08:59.063Z,1537387739.063 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:09:03.870Z,1537387743.870 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T20:09:03.955Z,1537387743.955 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:09:04.257Z,1537387744.257 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-19T20:09:04.258Z,1537387744.258 [BPC1](ERROR): BPC1A: No match for stick with serial number:014B in the onboard configuration file.
2018-09-19T20:09:04.258Z,1537387744.258 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:09:05.071Z,1537387745.071 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:09:08.650Z,1537387748.650 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:09:08.731Z,1537387748.731 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-19T20:09:09.065Z,1537387749.065 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-19T20:09:09.066Z,1537387749.066 [BPC1](ERROR): BPC1B: No match for stick with serial number:0144 in the onboard configuration file.
2018-09-19T20:09:09.886Z,1537387749.886 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:09:12.672Z,1537387752.672 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:09:16.666Z,1537387756.666 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T20:09:16.748Z,1537387756.748 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:09:17.054Z,1537387757.054 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-19T20:09:17.055Z,1537387757.055 [BPC1](ERROR): BPC1A: No match for stick with serial number:016B in the onboard configuration file.
2018-09-19T20:09:17.056Z,1537387757.056 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:09:17.886Z,1537387757.886 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:09:21.877Z,1537387761.877 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:09:21.878Z,1537387761.878 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:09:21.963Z,1537387761.963 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:09:22.073Z,1537387762.073 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-19T20:09:22.075Z,1537387762.075 [BPC1](ERROR): BPC1B: No match for stick with serial number:0144 in the onboard configuration file.
2018-09-19T20:09:22.867Z,1537387762.867 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:09:23.288Z,1537387763.288 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T20:09:23.320Z,1537387763.320 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:09:23.642Z,1537387763.642 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-19T20:09:23.643Z,1537387763.643 [BPC1](ERROR): BPC1A: No match for stick with serial number:016B in the onboard configuration file.
2018-09-19T20:09:24.502Z,1537387764.502 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:09:30.069Z,1537387770.069 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:09:31.642Z,1537387771.642 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:09:34.477Z,1537387774.477 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:09:34.554Z,1537387774.554 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-19T20:09:34.850Z,1537387774.850 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-19T20:09:34.851Z,1537387774.851 [BPC1](ERROR): BPC1B: No match for stick with serial number:0144 in the onboard configuration file.
2018-09-19T20:09:35.660Z,1537387775.660 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T20:09:35.742Z,1537387775.742 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:09:35.754Z,1537387775.754 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:09:36.064Z,1537387776.064 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-19T20:09:36.065Z,1537387776.065 [BPC1](ERROR): BPC1A: No match for stick with serial number:016B in the onboard configuration file.
2018-09-19T20:09:36.883Z,1537387776.883 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:09:42.486Z,1537387782.486 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:09:43.665Z,1537387783.665 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:09:46.452Z,1537387786.452 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:09:46.522Z,1537387786.522 [BPC1](INFO): BPC1B: no match for SMBUS stick msg of size 1606 bytes.
2018-09-19T20:09:46.523Z,1537387786.523 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
Got:
$B11,02,000A,01,02A8,03,0001,08,0BB4,09,3EA6,0A,0440,0B,043E%3C
$B11,0C,0001,0D,0052,0E,004F,0F,14BE,10,1976,11,FFFF,12,FFFF%3B
$B11,13,0066,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%41
$B11,1A,0031,1B,4ACF,1C,0144%40
$B12,02,000A,01,02A8,03,0001,08,0BB1,09,3EA2,0A,044A,0B,0447%3A
$B12,0C,0001,0D,0052,0E,004F,0F,14C5,10,1987,11,FFFF,12,FFFF%47
$B12,13,0066,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%42
$B12,1A,0031,1B,4ADA,1C,016A%34
$B13,02,000A,01,02A8,03,0001,08,0BB1,09,3E94,0A,0426,0B,0423%36
$B13,0C,0001,0D,0051,0E,004E,0F,147C,10,1973,11,FFFF,12,FFFF%4F
$B13,13,006B,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%37
$B13,1A,0031,1B,4ADA,1C,0155%42
$B14,02,000A,01,02A8,03,0001,08,0BB0,09,3E92,0A,042E,0B,042A%37
$B14,0C,0001,0D,0051,0E,0050,0F,14FD,10,1A05,11,FFFF,12,FFFF%33
$B14,13,006C,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%31
$B14,1A,0031,1B,4ADA,1C,0151%41
$B15,02,000A,01,02A8,03,0001,08,0BB4,09,3EBD,0A,0433,0B,042F%4F
$B15,0C,0001,0D,0052,0E,004E,0F,14A1,10,196C,11,FFFF,12,FFFF%3F
$B15,13,0068,14,0BB8,15,41A0,16,$B16,02,000A,01,02A8,03,0001,08,0BAB,09,3EC0,0A,0439,0B,0436%37
$B16,0C,0001,0D,0052,0E,004E,0F,14A3,10,1958,11,FFFF,12,FFFF%46
$B16,13,0067,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%47
$B16,1A,0031,1B,4ADA,1C,0161%40
$B17,02,000A,01,02A8,03,0001,08,0BA9,09,3F05,0A,046A,0B,0468%4E
$B17,0C,0001,0D,0053,0E,004F,0F,14FB,10,195B,11,FFFF,12,FFFF%49
$B17,13,0060,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%41
$B17,1A,0031,1B,0000,1C,0138%3D

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

->0A,01,02A8,03,0001,08,0BB9,09,3EDC,0A,01FB,0B,01F0%48
$B18,0C,0001,0D,0058,0E,0054,0F,1650,10,1974,11,FFFF,12,FFFF%49
$B18,13,0072,14,0BB8,15,41A0,16,0080,17,0001,18,1A90,19,3840%4E
$B18,1A,0031,1B,4ACF,1C,0143%4E

2018-09-19T20:09:47.274Z,1537387787.274 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:09:48.072Z,1537387788.072 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T20:09:48.136Z,1537387788.136 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:09:48.480Z,1537387788.480 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-19T20:09:48.480Z,1537387788.480 [BPC1](ERROR): BPC1A: No match for stick with serial number:016B in the onboard configuration file.
2018-09-19T20:09:49.298Z,1537387789.298 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:09:54.056Z,1537387794.056 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:09:56.046Z,1537387796.046 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:09:58.480Z,1537387798.480 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:09:58.560Z,1537387798.560 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-19T20:09:58.864Z,1537387798.864 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-19T20:09:58.865Z,1537387798.865 [BPC1](ERROR): BPC1B: No match for stick with serial number:0144 in the onboard configuration file.
2018-09-19T20:09:59.658Z,1537387799.658 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:10:00.534Z,1537387800.534 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T20:10:00.608Z,1537387800.608 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:10:00.879Z,1537387800.879 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-19T20:10:00.880Z,1537387800.880 [BPC1](ERROR): BPC1A: No match for stick with serial number:016B in the onboard configuration file.
2018-09-19T20:10:01.658Z,1537387801.658 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:10:06.880Z,1537387806.880 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:10:08.851Z,1537387808.851 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:10:10.854Z,1537387810.854 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:10:10.923Z,1537387810.923 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:10:11.280Z,1537387811.280 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-19T20:10:11.281Z,1537387811.281 [BPC1](ERROR): BPC1B: No match for stick with serial number:0144 in the onboard configuration file.
2018-09-19T20:10:12.090Z,1537387812.090 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:10:13.288Z,1537387813.288 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T20:10:13.368Z,1537387813.368 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:10:13.666Z,1537387813.666 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-19T20:10:13.667Z,1537387813.667 [BPC1](ERROR): BPC1A: No match for stick with serial number:016B in the onboard configuration file.
2018-09-19T20:10:14.482Z,1537387814.482 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:10:22.050Z,1537387822.050 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:10:22.050Z,1537387822.050 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:10:23.372Z,1537387823.372 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:10:23.452Z,1537387823.452 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with LONG_IBPS_MENU.
2018-09-19T20:10:23.757Z,1537387823.757 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-19T20:10:23.758Z,1537387823.758 [BPC1](ERROR): BPC1B: No match for stick with serial number:0144 in the onboard configuration file.
2018-09-19T20:10:24.578Z,1537387824.578 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:10:25.780Z,1537387825.780 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T20:10:25.853Z,1537387825.853 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:10:26.153Z,1537387826.153 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-19T20:10:26.154Z,1537387826.154 [BPC1](ERROR): BPC1A: No match for stick with serial number:016B in the onboard configuration file.
2018-09-19T20:10:26.974Z,1537387826.974 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:10:31.765Z,1537387831.765 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:10:33.744Z,1537387833.744 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:10:35.798Z,1537387835.798 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:10:35.000Z,1537387836.000 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:10:36.149Z,1537387836.149 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-19T20:10:36.150Z,1537387836.150 [BPC1](ERROR): BPC1B: No match for stick with serial number:0144 in the onboard configuration file.
2018-09-19T20:10:36.991Z,1537387836.991 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:10:38.158Z,1537387838.158 [BPC1](INFO): BPC1A: validating data format.
2018-09-19T20:10:38.360Z,1537387838.360 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-19T20:10:38.637Z,1537387838.637 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-19T20:10:38.651Z,1537387838.651 [BPC1](ERROR): BPC1A: No match for stick with serial number:016B in the onboard configuration file.
2018-09-19T20:10:38.651Z,1537387838.651 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-19T20:10:39.387Z,1537387839.387 [BPC1](INFO): BPC1A: initialized.
2018-09-19T20:10:42.944Z,1537387842.944 [BPC1](INFO): BPC1B: validating data format.
2018-09-19T20:10:42.983Z,1537387842.983 [BPC1](INFO): BPC1B: no match for SMBUS stick msg of size 1908 bytes.
2018-09-19T20:10:42.984Z,1537387842.984 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
Got:
$B11,02,000A,01,02A8,03,0001,08,0BAF,09,3E60,0A,0484,0B,00DE%47
$B11,0C,0001,0D,0051,0E,004D,0F,145A,10,1959,11,FFFF,12,$B12,02,000A,01,02A8,03,0001,08,0BAE,09,3E5D,0A,0491,0B,00E1%41
$B12,0C,0001,0D,0051,0E,004D,0F,1450,10,1952,11,FFFF,12,FFFF%3D
$B12,13,010F,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%35
$B12,1A,0031,1B,4ADA,1C,0163%46
$B13,02,000A,01,02A8,03,0001,08,0BB4,09,3E51,0A,0460,0B,0113%3E
$B13,0C,0001,0D,0050,0E,004D,0F,1451,10,1984,11,FFFF,12,FFFF%37
$B13,13,0125,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%45
$B13,1A,0031,1B,4ADA,1C,0157%40
$B14,02,000A,01,02A8,03,0001,08,0BB1,09,3E57,0A,0472,0B,0117%3D
$B14,0C,0001,0D,0050,0E,004D,0F,1457,10,1981,11,FFFF,12,FFFF%33
$B14,13,00F1,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%33
$B14,1A,0031,1B,4ADA,1C,0159%49
$B15,02,000A,01,02A8,03,0001,08,0BB2,09,3E7C,0A,0479,0B,0153%42
$B15,0C,0001,0D,0052,0E,004E,0F,14B5,10,1981,11,FFFF,12,FFFF%44
$B15,13,00DE,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%44
$B15,1A,0031,1B,4ADA,1C,016E%37
$B16,02,000A,01,02A8,03,0001,08,0BA8,09,3E80,0A,0487,0B,018E%4E
$B16,0C,0001,0D,0052,0E,004E,0F,1496,10,1953,11,FFFF,12,FFFF%30
$B16,13,00BD,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%40
$B16,1A,0031,1B,4ADA,1C,0160%41
$B17,02,000A,01,02A8,03,0001,08,0BAD,09,3E9F,0A,046D,0B,016B%30
$B17,0C,0001,0D,0053,0E,0050,0F,1507,10,198E,11,FFFF,12,FFFF%36
$B17,13,00AF,14,0BB8,15,41A0,16,0080,17,0002,18,1A90,19,3840%40
$B17,1A,0031,1B,4ADA,1C,015C%30
$B18,02,000A,01,02A8,03,0001,08,0BB6,09,3EE6,0A,0202,0B,00A6%36
$B18,0C,0001,0D,0058,0E,0054,0F,1626,10,1975,11,FFFF,12,FFFF%49
$B18,13,0132,14,0BB8,15,41A0,16,0080,17,0001,18,1A90,19,3840%4B
$B18,1A,0031,1B,4ADA,1C,016D%3B

=====================================
IBPS  V2.10
OceanServer Technology, Inc (c) 2007
 www.ocean-server.com

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

->
2018-09-19T20:10:43.821Z,1537387843.821 [BPC1](INFO): BPC1B: initialized.
2018-09-19T20:10:46.381Z,1537387846.381 [CommandLine](IMPORTANT): got command restart application
2018-09-19T20:10:46.576Z,1537387846.576 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-19T20:10:47.391Z,1537387847.391 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:10:47.391Z,1537387847.391 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:47.502Z,1537387847.502 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-19T20:10:47.502Z,1537387847.502 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:47.503Z,1537387847.503 [CommandLine](INFO): Join timeout helper Thread ID is 1502
2018-09-19T20:10:47.504Z,1537387847.504 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-19T20:10:47.504Z,1537387847.504 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:47.504Z,1537387847.504 [NavChartDb](INFO): Join timeout helper Thread ID is 1503
2018-09-19T20:10:47.551Z,1537387847.551 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:10:47.552Z,1537387847.552 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:47.566Z,1537387847.566 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-19T20:10:47.567Z,1537387847.567 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:47.568Z,1537387847.568 [Radio_Surface](INFO): Join timeout helper Thread ID is 1504
2018-09-19T20:10:47.709Z,1537387847.709 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:10:47.709Z,1537387847.709 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:47.710Z,1537387847.710 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-09-19T20:10:47.711Z,1537387847.711 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:47.711Z,1537387847.711 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1505
2018-09-19T20:10:48.107Z,1537387848.107 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:10:48.107Z,1537387848.107 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-19T20:10:48.108Z,1537387848.108 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:48.122Z,1537387848.122 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-19T20:10:48.123Z,1537387848.123 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:48.123Z,1537387848.123 [logger](INFO): Join timeout helper Thread ID is 1506
2018-09-19T20:10:48.169Z,1537387848.169 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:10:48.170Z,1537387848.170 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:48.187Z,1537387848.187 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-19T20:10:48.187Z,1537387848.187 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:48.188Z,1537387848.188 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-19T20:10:48.188Z,1537387848.188 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:48.188Z,1537387848.188 [controlThread](INFO): Join timeout helper Thread ID is 1507
2018-09-19T20:10:48.189Z,1537387848.189 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:10:48.189Z,1537387848.189 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-19T20:10:48.190Z,1537387848.190 [Aanderaa_O2](INFO): Powering down
2018-09-19T20:10:48.191Z,1537387848.191 [AHRS_M2](INFO): Powering down
2018-09-19T20:10:48.263Z,1537387848.263 [NAL9602](INFO): Powering down
2018-09-19T20:10:48.335Z,1537387848.335 [RDI_Pathfinder](INFO): Powering down
2018-09-19T20:10:48.336Z,1537387848.336 [RDI_PathfinderUp](INFO): Powering down
2018-09-19T20:10:48.337Z,1537387848.337 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-19T20:10:48.338Z,1537387848.338 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-19T20:10:48.339Z,1537387848.339 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-19T20:10:48.340Z,1537387848.340 [MissionManager](INFO): Uninitializing Mission Default
2018-09-19T20:10:48.340Z,1537387848.340 [Default] Stopped
2018-09-19T20:10:48.340Z,1537387848.340 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-19T20:10:48.340Z,1537387848.340 [Default:B.GoToSurface] Stopped
2018-09-19T20:10:48.340Z,1537387848.340 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-19T20:10:48.340Z,1537387848.340 [Default:CheckIn] Stopped
2018-09-19T20:10:48.340Z,1537387848.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-19T20:10:48.340Z,1537387848.340 [Default:CheckIn:C.Wait] Stopped
2018-09-19T20:10:48.340Z,1537387848.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-19T20:10:48.344Z,1537387848.344 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-19T20:10:48.344Z,1537387848.344 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-19T20:10:48.344Z,1537387848.344 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-19T20:10:48.344Z,1537387848.344 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-19T20:10:48.345Z,1537387848.345 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-19T20:10:48.345Z,1537387848.345 [BuoyancyServo](INFO): Powering down
2018-09-19T20:10:48.358Z,1537387848.358 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-09-19T20:10:48.359Z,1537387848.359 [ElevatorServo](INFO): Powering down
2018-09-19T20:10:48.360Z,1537387848.360 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-09-19T20:10:48.360Z,1537387848.360 [MassServo](INFO): Powering down
2018-09-19T20:10:48.360Z,1537387848.360 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-09-19T20:10:48.361Z,1537387848.361 [RudderServo](INFO): Powering down
2018-09-19T20:10:48.361Z,1537387848.361 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-19T20:10:48.362Z,1537387848.362 [ThrusterServo](INFO): Powering down
2018-09-19T20:10:48.363Z,1537387848.363 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-19T20:10:48.363Z,1537387848.363 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-19T20:10:48.363Z,1537387848.363 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-19T20:10:48.364Z,1537387848.364 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:48.419Z,1537387848.419 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:48.482Z,1537387848.482 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:48.528Z,1537387848.528 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:10:48.586Z,1537387848.586 [logger ThreadHandler](INFO): Thread cancelled.