2018-10-08T16:30:06.205Z,1539016206.205 [Supervisor](DEBUG): Initializing supervisor.
2018-10-08T16:30:06.208Z,1539016206.208 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-08T16:30:06.208Z,1539016206.208 [SyncHandler](INFO): Protected caller Thread ID is 7516
2018-10-08T16:30:06.209Z,1539016206.209 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-08T16:30:06.210Z,1539016206.210 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-08T16:30:06.210Z,1539016206.210 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7517
2018-10-08T16:30:06.214Z,1539016206.214 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-08T16:30:06.227Z,1539016206.227 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-08T16:30:06.228Z,1539016206.228 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-08T16:30:06.228Z,1539016206.228 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7518
2018-10-08T16:30:06.229Z,1539016206.229 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-08T16:30:06.230Z,1539016206.230 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-08T16:30:06.230Z,1539016206.230 [logger ThreadHandler](INFO): Protected caller Thread ID is 7519
2018-10-08T16:30:06.232Z,1539016206.232 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-08T16:30:06.233Z,1539016206.233 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-08T16:30:06.234Z,1539016206.234 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-08T16:30:06.521Z,1539016206.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-08T16:30:06.521Z,1539016206.521 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-08T16:30:06.670Z,1539016206.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-08T16:30:06.670Z,1539016206.670 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-08T16:30:07.033Z,1539016207.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-08T16:30:07.034Z,1539016207.034 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-08T16:30:07.176Z,1539016207.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-08T16:30:07.177Z,1539016207.177 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-08T16:30:07.260Z,1539016207.260 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-08T16:30:07.469Z,1539016207.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-08T16:30:07.470Z,1539016207.470 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-08T16:30:07.571Z,1539016207.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-08T16:30:07.571Z,1539016207.571 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-08T16:30:07.929Z,1539016207.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-08T16:30:07.930Z,1539016207.930 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-08T16:30:08.425Z,1539016208.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-08T16:30:08.426Z,1539016208.426 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-08T16:30:08.755Z,1539016208.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-08T16:30:08.755Z,1539016208.755 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-08T16:30:09.278Z,1539016209.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-08T16:30:09.278Z,1539016209.278 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-08T16:30:09.497Z,1539016209.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-08T16:30:09.498Z,1539016209.498 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-08T16:30:09.598Z,1539016209.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-08T16:30:09.599Z,1539016209.599 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-08T16:30:10.182Z,1539016210.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-08T16:30:10.182Z,1539016210.182 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-08T16:30:19.883Z,1539016219.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-08T16:30:19.885Z,1539016219.885 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-10-08T16:30:19.886Z,1539016219.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-10-08T16:30:20.122Z,1539016220.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-08T16:30:20.123Z,1539016220.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-10-08T16:30:20.226Z,1539016220.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-10-08T16:30:20.322Z,1539016220.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-10-08T16:30:20.428Z,1539016220.428 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-10-08T16:30:20.516Z,1539016220.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-10-08T16:30:20.655Z,1539016220.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-10-08T16:30:20.832Z,1539016220.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-10-08T16:30:20.946Z,1539016220.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-10-08T16:30:21.050Z,1539016221.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-10-08T16:30:21.136Z,1539016221.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-10-08T16:30:21.337Z,1539016221.337 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-10-08T16:30:21.338Z,1539016221.338 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-10-08T16:30:21.348Z,1539016221.348 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-08T16:30:21.502Z,1539016221.502 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-08T16:30:21.534Z,1539016221.534 [SBIT] Loaded
2018-10-08T16:30:21.534Z,1539016221.534 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-08T16:30:21.535Z,1539016221.535 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-08T16:30:21.564Z,1539016221.564 [IBIT] Loaded
2018-10-08T16:30:21.564Z,1539016221.564 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-08T16:30:21.567Z,1539016221.567 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-08T16:30:21.726Z,1539016221.726 [CBIT] Loaded
2018-10-08T16:30:21.726Z,1539016221.726 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-08T16:30:21.727Z,1539016221.727 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-08T16:30:21.728Z,1539016221.728 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-08T16:30:21.815Z,1539016221.815 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-08T16:30:21.919Z,1539016221.919 [VerticalControl] Loaded
2018-10-08T16:30:21.920Z,1539016221.920 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-08T16:30:21.920Z,1539016221.920 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-08T16:30:21.983Z,1539016221.983 [HorizontalControl] Loaded
2018-10-08T16:30:21.983Z,1539016221.983 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-08T16:30:21.984Z,1539016221.984 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-08T16:30:21.990Z,1539016221.990 [SpeedControl] Loaded
2018-10-08T16:30:21.990Z,1539016221.990 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-08T16:30:21.991Z,1539016221.991 [LoopControl](DEBUG): Construct LoopControl.
2018-10-08T16:30:21.991Z,1539016221.991 [LoopControl] Loaded
2018-10-08T16:30:21.992Z,1539016221.992 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-08T16:30:21.992Z,1539016221.992 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-08T16:30:21.993Z,1539016221.993 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-08T16:30:22.050Z,1539016222.050 [DepthRateCalculator] Loaded
2018-10-08T16:30:22.050Z,1539016222.050 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-08T16:30:22.056Z,1539016222.056 [PitchRateCalculator] Loaded
2018-10-08T16:30:22.056Z,1539016222.056 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-08T16:30:22.072Z,1539016222.072 [SpeedCalculator] Loaded
2018-10-08T16:30:22.073Z,1539016222.073 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-08T16:30:22.095Z,1539016222.095 [TempGradientCalculator] Loaded
2018-10-08T16:30:22.095Z,1539016222.095 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-08T16:30:22.100Z,1539016222.100 [YawRateCalculator] Loaded
2018-10-08T16:30:22.100Z,1539016222.100 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-08T16:30:22.131Z,1539016222.131 [ElevatorOffsetCalculator] Loaded
2018-10-08T16:30:22.131Z,1539016222.131 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-08T16:30:22.131Z,1539016222.131 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-08T16:30:22.132Z,1539016222.132 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-08T16:30:22.236Z,1539016222.236 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-08T16:30:22.237Z,1539016222.237 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-08T16:30:22.523Z,1539016222.523 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-08T16:30:22.524Z,1539016222.524 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-08T16:30:22.639Z,1539016222.639 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-10-08T16:30:22.639Z,1539016222.639 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-10-08T16:30:22.695Z,1539016222.695 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-08T16:30:22.696Z,1539016222.696 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-08T16:30:22.713Z,1539016222.713 [NavChart] Loaded
2018-10-08T16:30:22.713Z,1539016222.713 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-08T16:30:22.718Z,1539016222.718 [UniversalFixResidualReporter] Loaded
2018-10-08T16:30:22.718Z,1539016222.718 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-08T16:30:22.718Z,1539016222.718 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-08T16:30:22.719Z,1539016222.719 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-08T16:30:22.744Z,1539016222.744 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-08T16:30:22.744Z,1539016222.744 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-08T16:30:22.964Z,1539016222.964 [Aanderaa_O2] Loaded
2018-10-08T16:30:22.964Z,1539016222.964 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-10-08T16:30:23.044Z,1539016223.044 [CTD_NeilBrown] Loaded
2018-10-08T16:30:23.044Z,1539016223.044 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-10-08T16:30:23.045Z,1539016223.045 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406DB4E0
2018-10-08T16:30:23.046Z,1539016223.046 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 7598
2018-10-08T16:30:23.092Z,1539016223.092 [WetLabsSeaOWL_UV_A] Loaded
2018-10-08T16:30:23.092Z,1539016223.092 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-10-08T16:30:23.094Z,1539016223.094 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4070B4E0
2018-10-08T16:30:23.094Z,1539016223.094 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 7599
2018-10-08T16:30:23.095Z,1539016223.095 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-08T16:30:23.095Z,1539016223.095 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-08T16:30:23.515Z,1539016223.515 [AHRS_M2] Loaded
2018-10-08T16:30:23.515Z,1539016223.515 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-10-08T16:30:23.588Z,1539016223.588 [DataOverHttps] Loaded
2018-10-08T16:30:23.588Z,1539016223.588 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-08T16:30:23.602Z,1539016223.602 [Depth_Keller] Loaded
2018-10-08T16:30:23.602Z,1539016223.602 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-08T16:30:23.607Z,1539016223.607 [DropWeight] Loaded
2018-10-08T16:30:23.607Z,1539016223.607 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-08T16:30:23.700Z,1539016223.700 [NAL9602] Loaded
2018-10-08T16:30:23.701Z,1539016223.701 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-08T16:30:23.706Z,1539016223.706 [Onboard] Loaded
2018-10-08T16:30:23.706Z,1539016223.706 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-10-08T16:30:23.713Z,1539016223.713 [Radio_Surface] Loaded
2018-10-08T16:30:23.714Z,1539016223.714 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-08T16:30:23.715Z,1539016223.715 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096C4E0
2018-10-08T16:30:23.715Z,1539016223.715 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7600
2018-10-08T16:30:23.744Z,1539016223.744 [RDI_Pathfinder] Loaded
2018-10-08T16:30:23.744Z,1539016223.744 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-10-08T16:30:23.751Z,1539016223.751 [SCPI] Loaded
2018-10-08T16:30:23.751Z,1539016223.751 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-10-08T16:30:23.776Z,1539016223.776 [BPC1](INFO): BPC1A: got serial number for stick 1: 0165
2018-10-08T16:30:23.777Z,1539016223.777 [BPC1](INFO): BPC1A: got serial number for stick 2: 0135
2018-10-08T16:30:23.778Z,1539016223.778 [BPC1](INFO): BPC1A: got serial number for stick 3: 0149
2018-10-08T16:30:23.779Z,1539016223.779 [BPC1](INFO): BPC1A: got serial number for stick 4: 0146
2018-10-08T16:30:23.780Z,1539016223.780 [BPC1](INFO): BPC1A: got serial number for stick 5: 013D
2018-10-08T16:30:23.781Z,1539016223.781 [BPC1](INFO): BPC1A: got serial number for stick 6: 014D
2018-10-08T16:30:23.782Z,1539016223.782 [BPC1](INFO): BPC1A: got serial number for stick 7: 0156
2018-10-08T16:30:23.783Z,1539016223.783 [BPC1](INFO): BPC1A: got serial number for stick 8: 0133
2018-10-08T16:30:23.783Z,1539016223.783 [BPC1](INFO): BPC1A: marked stick 8 as reserve.
2018-10-08T16:30:23.784Z,1539016223.784 [BPC1](INFO): BPC1A: got serial number for stick 9: 0131
2018-10-08T16:30:23.785Z,1539016223.785 [BPC1](INFO): BPC1A: got serial number for stick 10: 0145
2018-10-08T16:30:23.785Z,1539016223.785 [BPC1](INFO): BPC1A: got serial number for stick 11: 014A
2018-10-08T16:30:23.786Z,1539016223.786 [BPC1](INFO): BPC1A: got serial number for stick 12: 013C
2018-10-08T16:30:23.787Z,1539016223.787 [BPC1](INFO): BPC1A: got serial number for stick 13: 013B
2018-10-08T16:30:23.788Z,1539016223.788 [BPC1](INFO): BPC1A: got serial number for stick 14: 0132
2018-10-08T16:30:23.788Z,1539016223.788 [BPC1](INFO): BPC1A: got serial number for stick 15: 0134
2018-10-08T16:30:23.789Z,1539016223.789 [BPC1](INFO): BPC1A: got serial number for stick 16: 0153
2018-10-08T16:30:23.790Z,1539016223.790 [BPC1](INFO): BPC1A: marked stick 16 as reserve.
2018-10-08T16:30:23.790Z,1539016223.790 [BPC1](INFO): BPC1A: got serial number for stick 17: 014B
2018-10-08T16:30:23.791Z,1539016223.791 [BPC1](INFO): BPC1A: got serial number for stick 18: 013A
2018-10-08T16:30:23.792Z,1539016223.792 [BPC1](INFO): BPC1A: got serial number for stick 19: 0136
2018-10-08T16:30:23.793Z,1539016223.793 [BPC1](INFO): BPC1A: got serial number for stick 20: 014F
2018-10-08T16:30:23.794Z,1539016223.794 [BPC1](INFO): BPC1A: got serial number for stick 21: 014E
2018-10-08T16:30:23.795Z,1539016223.795 [BPC1](INFO): BPC1A: got serial number for stick 22: 013F
2018-10-08T16:30:23.795Z,1539016223.795 [BPC1](INFO): BPC1A: got serial number for stick 23: 0152
2018-10-08T16:30:23.796Z,1539016223.796 [BPC1](INFO): BPC1A: got serial number for stick 24: 014C
2018-10-08T16:30:23.796Z,1539016223.796 [BPC1](INFO): BPC1A: marked stick 24 as reserve.
2018-10-08T16:30:23.797Z,1539016223.797 [BPC1](INFO): BPC1A: got serial number for stick 25: 016B
2018-10-08T16:30:23.798Z,1539016223.798 [BPC1](INFO): BPC1A: got serial number for stick 26: 0141
2018-10-08T16:30:23.799Z,1539016223.799 [BPC1](INFO): BPC1A: got serial number for stick 27: 0139
2018-10-08T16:30:23.800Z,1539016223.800 [BPC1](INFO): BPC1A: got serial number for stick 28: 0137
2018-10-08T16:30:23.800Z,1539016223.800 [BPC1](INFO): BPC1A: got serial number for stick 29: 0142
2018-10-08T16:30:23.801Z,1539016223.801 [BPC1](INFO): BPC1A: got serial number for stick 30: 013E
2018-10-08T16:30:23.802Z,1539016223.802 [BPC1](INFO): BPC1A: got serial number for stick 31: 0147
2018-10-08T16:30:23.804Z,1539016223.804 [BPC1](INFO): BPC1B: got serial number for stick 32: 0140
2018-10-08T16:30:23.804Z,1539016223.804 [BPC1](INFO): BPC1B: got serial number for stick 33: 0163
2018-10-08T16:30:23.805Z,1539016223.805 [BPC1](INFO): BPC1B: got serial number for stick 34: 0157
2018-10-08T16:30:23.806Z,1539016223.806 [BPC1](INFO): BPC1B: got serial number for stick 35: 0159
2018-10-08T16:30:23.807Z,1539016223.807 [BPC1](INFO): BPC1B: got serial number for stick 36: 016E
2018-10-08T16:30:23.808Z,1539016223.808 [BPC1](INFO): BPC1B: got serial number for stick 37: 0160
2018-10-08T16:30:23.809Z,1539016223.809 [BPC1](INFO): BPC1B: got serial number for stick 38: 015C
2018-10-08T16:30:23.810Z,1539016223.810 [BPC1](INFO): BPC1B: got serial number for stick 39: 016D
2018-10-08T16:30:23.810Z,1539016223.810 [BPC1](INFO): BPC1B: marked stick 39 as reserve.
2018-10-08T16:30:23.811Z,1539016223.811 [BPC1](INFO): BPC1B: got serial number for stick 40: 012D
2018-10-08T16:30:23.812Z,1539016223.812 [BPC1](INFO): BPC1B: got serial number for stick 41: 015E
2018-10-08T16:30:23.812Z,1539016223.812 [BPC1](INFO): BPC1B: got serial number for stick 42: 0164
2018-10-08T16:30:23.813Z,1539016223.813 [BPC1](INFO): BPC1B: got serial number for stick 43: 015B
2018-10-08T16:30:23.814Z,1539016223.814 [BPC1](INFO): BPC1B: got serial number for stick 44: 0172
2018-10-08T16:30:23.815Z,1539016223.815 [BPC1](INFO): BPC1B: got serial number for stick 45: 0166
2018-10-08T16:30:23.816Z,1539016223.816 [BPC1](INFO): BPC1B: got serial number for stick 46: 0170
2018-10-08T16:30:23.816Z,1539016223.816 [BPC1](INFO): BPC1B: got serial number for stick 47: 0148
2018-10-08T16:30:23.817Z,1539016223.817 [BPC1](INFO): BPC1B: marked stick 47 as reserve.
2018-10-08T16:30:23.818Z,1539016223.818 [BPC1](INFO): BPC1B: got serial number for stick 48: 0130
2018-10-08T16:30:23.819Z,1539016223.819 [BPC1](INFO): BPC1B: got serial number for stick 49: 0169
2018-10-08T16:30:23.819Z,1539016223.819 [BPC1](INFO): BPC1B: got serial number for stick 50: 0158
2018-10-08T16:30:23.820Z,1539016223.820 [BPC1](INFO): BPC1B: got serial number for stick 51: 0162
2018-10-08T16:30:23.821Z,1539016223.821 [BPC1](INFO): BPC1B: got serial number for stick 52: 0150
2018-10-08T16:30:23.822Z,1539016223.822 [BPC1](INFO): BPC1B: got serial number for stick 53: 015F
2018-10-08T16:30:23.823Z,1539016223.823 [BPC1](INFO): BPC1B: got serial number for stick 54: 0171
2018-10-08T16:30:23.823Z,1539016223.823 [BPC1](INFO): BPC1B: got serial number for stick 55: 0143
2018-10-08T16:30:23.824Z,1539016223.824 [BPC1](INFO): BPC1B: marked stick 55 as reserve.
2018-10-08T16:30:23.825Z,1539016223.825 [BPC1](INFO): BPC1B: got serial number for stick 56: 0144
2018-10-08T16:30:23.825Z,1539016223.825 [BPC1](INFO): BPC1B: got serial number for stick 57: 016A
2018-10-08T16:30:23.826Z,1539016223.826 [BPC1](INFO): BPC1B: got serial number for stick 58: 0155
2018-10-08T16:30:23.827Z,1539016223.827 [BPC1](INFO): BPC1B: got serial number for stick 59: 0151
2018-10-08T16:30:23.828Z,1539016223.828 [BPC1](INFO): BPC1B: got serial number for stick 60: 0154
2018-10-08T16:30:23.829Z,1539016223.829 [BPC1](INFO): BPC1B: got serial number for stick 61: 0161
2018-10-08T16:30:23.830Z,1539016223.830 [BPC1](INFO): BPC1B: got serial number for stick 62: 0138
2018-10-08T16:30:25.735Z,1539016225.735 [BPC1] Loaded
2018-10-08T16:30:25.736Z,1539016225.736 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-10-08T16:30:25.736Z,1539016225.736 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-08T16:30:25.737Z,1539016225.737 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-08T16:30:25.896Z,1539016225.896 [BuoyancyServo] Loaded
2018-10-08T16:30:25.896Z,1539016225.896 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-08T16:30:25.908Z,1539016225.908 [ElevatorServo] Loaded
2018-10-08T16:30:25.908Z,1539016225.908 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-08T16:30:25.920Z,1539016225.920 [MassServo] Loaded
2018-10-08T16:30:25.920Z,1539016225.920 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-08T16:30:25.932Z,1539016225.932 [RudderServo] Loaded
2018-10-08T16:30:25.932Z,1539016225.932 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-08T16:30:25.944Z,1539016225.944 [ThrusterServo] Loaded
2018-10-08T16:30:25.944Z,1539016225.944 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-08T16:30:25.944Z,1539016225.944 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-08T16:30:25.945Z,1539016225.945 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-08T16:30:27.536Z,1539016227.536 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-08T16:30:27.537Z,1539016227.537 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-08T16:30:27.574Z,1539016227.574 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-08T16:30:27.578Z,1539016227.578 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-08T16:30:27.579Z,1539016227.579 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-08T16:30:27.586Z,1539016227.586 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-08T16:30:27.588Z,1539016227.588 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2018-10-08T16:30:27.588Z,1539016227.588 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7601
2018-10-08T16:30:27.593Z,1539016227.593 [Supervisor](INFO): Main Thread ID is 796
2018-10-08T16:30:27.593Z,1539016227.593 [Supervisor](DEBUG): Running supervisor.
2018-10-08T16:30:27.594Z,1539016227.594 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7602
2018-10-08T16:30:27.597Z,1539016227.597 [controlThread ThreadHandler](INFO): Handler Thread ID is 7603
2018-10-08T16:30:27.597Z,1539016227.597 [controlThread](DEBUG): Initializing ControlThread
2018-10-08T16:30:27.598Z,1539016227.598 [SBIT](INFO): Initialize SBIT Component.
2018-10-08T16:30:27.599Z,1539016227.599 [SBIT](IMPORTANT): git: 2018-09-26-9-g14f2005
2018-10-08T16:30:27.599Z,1539016227.599 [SBIT](INFO): git hash: 14f2005f6a70b793a05ede5ccfbd62015cc8442d
2018-10-08T16:30:27.599Z,1539016227.599 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-08T16:30:27.599Z,1539016227.599 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-08T16:30:27.601Z,1539016227.601 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-10-08T16:30:27.601Z,1539016227.601 [IBIT](INFO): Initialize IBIT Component.
2018-10-08T16:30:27.602Z,1539016227.602 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-08T16:30:27.602Z,1539016227.602 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-10-08T16:30:27.603Z,1539016227.603 [logger ThreadHandler](INFO): Handler Thread ID is 7604
2018-10-08T16:30:27.629Z,1539016227.629 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 7605
2018-10-08T16:30:27.630Z,1539016227.630 [CTD_NeilBrown](INFO): Powering down
2018-10-08T16:30:27.673Z,1539016227.673 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 7606
2018-10-08T16:30:27.674Z,1539016227.674 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-10-08T16:30:27.702Z,1539016227.702 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7607
2018-10-08T16:30:27.707Z,1539016227.707 [Radio_Surface](INFO): Powering up
2018-10-08T16:30:27.709Z,1539016227.709 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-08T16:30:27.711Z,1539016227.711 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-08T16:30:27.712Z,1539016227.712 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-08T16:30:27.712Z,1539016227.712 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-08T16:30:27.713Z,1539016227.713 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-08T16:30:27.713Z,1539016227.713 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-08T16:30:27.714Z,1539016227.714 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-08T16:30:27.714Z,1539016227.714 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-08T16:30:27.714Z,1539016227.714 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-08T16:30:27.715Z,1539016227.715 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-08T16:30:27.715Z,1539016227.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-08T16:30:27.716Z,1539016227.716 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:30:27.716Z,1539016227.716 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:30:27.717Z,1539016227.717 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-08T16:30:27.717Z,1539016227.717 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:30:27.718Z,1539016227.718 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:30:27.718Z,1539016227.718 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-08T16:30:27.719Z,1539016227.719 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-08T16:30:27.730Z,1539016227.730 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-08T16:30:27.737Z,1539016227.737 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7608
2018-10-08T16:30:27.745Z,1539016227.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-08T16:30:27.745Z,1539016227.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-08T16:30:27.745Z,1539016227.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-08T16:30:27.745Z,1539016227.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-08T16:30:27.746Z,1539016227.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-08T16:30:27.746Z,1539016227.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-08T16:30:27.746Z,1539016227.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-08T16:30:27.746Z,1539016227.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-08T16:30:27.746Z,1539016227.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-08T16:30:27.747Z,1539016227.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-08T16:30:27.747Z,1539016227.747 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-08T16:30:27.747Z,1539016227.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-08T16:30:27.747Z,1539016227.747 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-08T16:30:27.747Z,1539016227.747 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-08T16:30:27.748Z,1539016227.748 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-08T16:30:27.748Z,1539016227.748 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-08T16:30:27.779Z,1539016227.779 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-08T16:30:27.818Z,1539016227.818 [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-10-08T16:30:27.819Z,1539016227.819 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-08T16:30:27.901Z,1539016227.901 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-08T16:30:27.902Z,1539016227.902 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-08T16:30:27.904Z,1539016227.904 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-08T16:30:27.935Z,1539016227.935 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-08T16:30:27.953Z,1539016227.953 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-08T16:30:27.959Z,1539016227.959 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-08T16:30:27.975Z,1539016227.975 [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-10-08T16:30:27.980Z,1539016227.980 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,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-10-08T16:30:28.043Z,1539016228.043 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-10-08T16:30:28.102Z,1539016228.102 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-08T16:30:28.154Z,1539016228.154 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-10-08T16:30:28.293Z,1539016228.293 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-08T16:30:28.375Z,1539016228.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:28.403Z,1539016228.403 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:28.527Z,1539016228.527 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-08T16:30:28.533Z,1539016228.533 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-08T16:30:28.555Z,1539016228.555 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-08T16:30:28.561Z,1539016228.561 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-08T16:30:28.571Z,1539016228.571 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-08T16:30:28.590Z,1539016228.590 [MassServo](DEBUG): Initializing MassServo.
2018-10-08T16:30:28.596Z,1539016228.596 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-08T16:30:28.601Z,1539016228.601 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-08T16:30:28.615Z,1539016228.615 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-08T16:30:28.621Z,1539016228.621 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-08T16:30:28.672Z,1539016228.672 [CommandLine](FAULT): Scheduling is paused
2018-10-08T16:30:28.672Z,1539016228.672 [CBIT](INFO): Critical error at 20181008T163027
2018-10-08T16:30:28.673Z,1539016228.673 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-10-08T16:30:28.845Z,1539016228.845 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-08T16:30:28.848Z,1539016228.848 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:28.865Z,1539016228.865 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:29.156Z,1539016229.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:29.157Z,1539016229.157 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:29.505Z,1539016229.505 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-10-08T16:30:29.505Z,1539016229.505 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-10-08T16:30:29.505Z,1539016229.505 [BuoyancyServo] Communications Fault, FailCount= 1
2018-10-08T16:30:29.505Z,1539016229.505 [BuoyancyServo](ERROR): Communications Fault
2018-10-08T16:30:29.717Z,1539016229.717 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-10-08T16:30:29.761Z,1539016229.761 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:29.762Z,1539016229.762 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:29.783Z,1539016229.783 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-08T16:30:29.783Z,1539016229.783 [BuoyancyServo](INFO): Powering down
2018-10-08T16:30:30.160Z,1539016230.160 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:30.164Z,1539016230.164 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:30.560Z,1539016230.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:30.561Z,1539016230.561 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:30.956Z,1539016230.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:30.958Z,1539016230.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:31.445Z,1539016231.445 [Aanderaa_O2](INFO): Powering down
2018-10-08T16:30:31.560Z,1539016231.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:31.569Z,1539016231.569 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:31.789Z,1539016231.789 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-10-08T16:30:31.828Z,1539016231.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:31.830Z,1539016231.830 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-10-08T16:30:31.878Z,1539016231.878 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-08T16:30:31.878Z,1539016231.878 [BuoyancyServo] No Fault, FailCount= 1
2018-10-08T16:30:32.165Z,1539016232.165 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-08T16:30:32.286Z,1539016232.286 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-08T16:30:41.414Z,1539016241.414 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-10-08T16:30:53.787Z,1539016253.787 [NAL9602](INFO): Powering up NAL9602
2018-10-08T16:30:56.231Z,1539016256.231 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-08T16:30:56.240Z,1539016256.240 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-08T16:31:04.707Z,1539016264.707 [NAL9602](INFO): NAL9602 initialized
2018-10-08T16:31:07.231Z,1539016267.231 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009922
CHAN A1 (24V): 0.005203
CHAN A2 (12V): -0.007471
CHAN A3 (5V): -0.001983
CHAN B0 (3.3V): 0.000379
CHAN B1 (3.15aV): 0.000099
CHAN B2 (3.15bV): 0.000284
CHAN B3 (GND): 0.002313
OPEN: 0.005359
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-08T16:31:29.361Z,1539016289.361 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:31:29.373Z,1539016289.373 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:31:30.958Z,1539016290.958 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:31:30.958Z,1539016290.958 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:31:35.349Z,1539016295.349 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:31:35.404Z,1539016295.404 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:31:35.419Z,1539016295.419 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:31:35.419Z,1539016295.419 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:31:35.452Z,1539016295.452 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:31:35.452Z,1539016295.452 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:31:35.784Z,1539016295.784 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:31:35.816Z,1539016295.816 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:31:36.565Z,1539016296.565 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:31:36.577Z,1539016296.577 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:31:44.334Z,1539016304.334 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:31:44.715Z,1539016304.715 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:31:49.135Z,1539016309.135 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:31:49.206Z,1539016309.206 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:31:49.207Z,1539016309.207 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:31:49.207Z,1539016309.207 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:31:49.293Z,1539016309.293 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:31:49.294Z,1539016309.294 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:31:49.534Z,1539016309.534 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:31:49.554Z,1539016309.554 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:31:50.332Z,1539016310.332 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:31:50.345Z,1539016310.345 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:31:50.367Z,1539016310.367 [SBIT](IMPORTANT): SBIT PASSED
2018-10-08T16:31:50.426Z,1539016310.426 [CommandLine](IMPORTANT): got command configSet list
2018-10-08T16:31:50.426Z,1539016310.426 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-08T16:31:50.428Z,1539016310.428 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 hour;

2018-10-08T16:31:50.428Z,1539016310.428 [CommandLine](IMPORTANT): CTD_NeilBrown.minSalinityBound=-10 practical_salinity_unit;

2018-10-08T16:31:50.428Z,1539016310.428 [CommandLine](IMPORTANT): Express linearApproximation concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 1.000000 part_per_billion;

2018-10-08T16:31:50.428Z,1539016310.428 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold=13.7 volt;

2018-10-08T16:31:50.428Z,1539016310.428 [CommandLine](IMPORTANT): NAL9602.gpsFailTimeout=10 minute;

2018-10-08T16:31:50.429Z,1539016310.429 [CommandLine](IMPORTANT): RDI_PathfinderUp.loadAtStartup=0 bool;

2018-10-08T16:31:50.429Z,1539016310.429 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=200 cubic_centimeter;

2018-10-08T16:31:50.429Z,1539016310.429 [CommandLine](IMPORTANT): VerticalControl.massDefault=2 millimeter;

2018-10-08T16:31:50.772Z,1539016310.772 [MissionManager](IMPORTANT): Started mission Startup
2018-10-08T16:31:50.772Z,1539016310.772 [Startup] Running Loop=1
2018-10-08T16:31:50.772Z,1539016310.772 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-08T16:31:50.772Z,1539016310.772 [Startup:A.GoToSurface] Running Loop=1
2018-10-08T16:31:50.773Z,1539016310.773 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-08T16:31:50.773Z,1539016310.773 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-08T16:31:50.774Z,1539016310.774 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-08T16:31:50.774Z,1539016310.774 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-08T16:31:50.775Z,1539016310.775 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-08T16:31:50.775Z,1539016310.775 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-08T16:31:50.782Z,1539016310.782 [Startup:StartupSatComms] Running Loop=1
2018-10-08T16:31:50.782Z,1539016310.782 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-10-08T16:31:50.782Z,1539016310.782 [Startup:StartupSatComms:A] Running Loop=1
2018-10-08T16:31:51.130Z,1539016311.130 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-10-08T16:31:57.523Z,1539016317.523 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:31:57.916Z,1539016317.916 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:32:02.306Z,1539016322.306 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:32:02.413Z,1539016322.413 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:02.413Z,1539016322.413 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:32:02.729Z,1539016322.729 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:32:02.817Z,1539016322.817 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:02.817Z,1539016322.817 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:32:02.825Z,1539016322.825 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:32:03.119Z,1539016323.119 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:32:03.557Z,1539016323.557 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:32:03.945Z,1539016323.945 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:32:10.749Z,1539016330.749 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:32:11.530Z,1539016331.530 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:32:15.549Z,1539016335.549 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:32:15.630Z,1539016335.630 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:15.630Z,1539016335.630 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:32:15.935Z,1539016335.935 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:32:16.724Z,1539016336.724 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:32:16.809Z,1539016336.809 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:16.810Z,1539016336.810 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:32:16.821Z,1539016336.821 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:32:17.124Z,1539016337.124 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:32:17.921Z,1539016337.921 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:32:24.315Z,1539016344.315 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:32:25.539Z,1539016345.539 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:32:29.105Z,1539016349.105 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:32:29.187Z,1539016349.187 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:29.187Z,1539016349.187 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:32:29.514Z,1539016349.514 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:32:30.323Z,1539016350.323 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:32:30.393Z,1539016350.393 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:30.394Z,1539016350.394 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:32:30.405Z,1539016350.405 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:32:30.763Z,1539016350.763 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:32:31.533Z,1539016351.533 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:32:37.910Z,1539016357.910 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:32:38.321Z,1539016358.321 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:32:42.322Z,1539016362.322 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:32:42.474Z,1539016362.474 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:42.481Z,1539016362.481 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:32:42.737Z,1539016362.737 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:32:43.112Z,1539016363.112 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:32:43.191Z,1539016363.191 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:43.191Z,1539016363.191 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:32:43.569Z,1539016363.569 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:32:43.581Z,1539016363.581 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:32:44.345Z,1539016364.345 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:32:44.730Z,1539016364.730 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-10-08T16:32:44.730Z,1539016364.730 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18100809374891,35.0, -0.1,  8.9,  0
2018-10-08T16:32:52.777Z,1539016372.777 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:32:52.777Z,1539016372.777 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:32:52.787Z,1539016372.787 [Startup:StartupSatComms:A](INFO): Timed out from 2018-10-08T16:31:50.8Z
2018-10-08T16:32:52.787Z,1539016372.787 [Startup:StartupSatComms:A] Stopped
2018-10-08T16:32:52.787Z,1539016372.787 [Startup:StartupSatComms:B] Running Loop=1
2018-10-08T16:32:52.929Z,1539016372.929 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-08T16:32:53.644Z,1539016373.644 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004953
2018-10-08T16:32:57.836Z,1539016377.836 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:32:57.917Z,1539016377.917 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:57.918Z,1539016377.918 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:32:57.918Z,1539016377.918 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:32:57.989Z,1539016377.989 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:32:57.989Z,1539016377.989 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:32:58.073Z,1539016378.073 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181008T074106/Courier0153.lzma
2018-10-08T16:32:58.137Z,1539016378.137 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:32:58.160Z,1539016378.160 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:32:58.824Z,1539016378.824 [DataOverHttps](INFO): Moved sent file to Logs/20181008T074106/Courier0153.lzma.bak
2018-10-08T16:32:58.824Z,1539016378.824 [DataOverHttps](INFO): SBD MOMSN=8614054
2018-10-08T16:32:58.913Z,1539016378.913 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:32:58.925Z,1539016378.925 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:33:07.817Z,1539016387.817 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:33:07.818Z,1539016387.818 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:33:07.900Z,1539016387.900 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20181008T163006/Courier0000.lzma
2018-10-08T16:33:08.683Z,1539016388.683 [DataOverHttps](INFO): Moved sent file to Logs/20181008T163006/Courier0000.lzma.bak
2018-10-08T16:33:08.683Z,1539016388.683 [DataOverHttps](INFO): SBD MOMSN=8614056
2018-10-08T16:33:10.780Z,1539016390.780 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:33:10.858Z,1539016390.858 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:33:10.859Z,1539016390.859 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:33:13.982Z,1539016393.982 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:33:14.060Z,1539016394.060 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:33:14.061Z,1539016394.061 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:33:14.069Z,1539016394.069 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:33:14.203Z,1539016394.203 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:33:14.581Z,1539016394.581 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:33:14.957Z,1539016394.957 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:33:19.013Z,1539016399.013 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20181008T074106/Express0154.lzma
2018-10-08T16:33:19.792Z,1539016399.792 [DataOverHttps](INFO): Moved sent file to Logs/20181008T074106/Express0154.lzma.bak
2018-10-08T16:33:19.792Z,1539016399.792 [DataOverHttps](INFO): SBD MOMSN=8614059
2018-10-08T16:33:24.223Z,1539016404.223 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:33:24.224Z,1539016404.224 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:33:29.482Z,1539016409.482 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:33:29.560Z,1539016409.560 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:33:29.561Z,1539016409.561 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:33:29.561Z,1539016409.561 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:33:29.636Z,1539016409.636 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:33:29.636Z,1539016409.636 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:33:29.643Z,1539016409.643 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-08T16:33:29.643Z,1539016409.643 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-10-08T16:33:29.643Z,1539016409.643 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-08T16:33:29.645Z,1539016409.645 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-08T16:33:29.645Z,1539016409.645 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-08T16:33:29.646Z,1539016409.646 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-08T16:33:29.658Z,1539016409.658 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-08T16:33:29.659Z,1539016409.659 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-08T16:33:29.728Z,1539016409.728 [DataOverHttps](INFO): Sending 1007 bytes from file Logs/20181008T163006/Express0001.lzma
2018-10-08T16:33:29.824Z,1539016409.824 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:33:29.847Z,1539016409.847 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:33:29.886Z,1539016409.886 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-08T16:33:29.886Z,1539016409.886 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-10-08T16:33:29.887Z,1539016409.887 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-08T16:33:29.887Z,1539016409.887 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-08T16:33:30.166Z,1539016410.166 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-08T16:33:30.166Z,1539016410.166 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:33:30.167Z,1539016410.167 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:33:30.167Z,1539016410.167 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-08T16:33:30.167Z,1539016410.167 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:33:30.168Z,1539016410.168 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:33:30.483Z,1539016410.483 [DataOverHttps](INFO): Moved sent file to Logs/20181008T163006/Express0001.lzma.bak
2018-10-08T16:33:30.483Z,1539016410.483 [DataOverHttps](INFO): SBD MOMSN=8614061
2018-10-08T16:33:30.541Z,1539016410.541 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:33:31.609Z,1539016411.609 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:33:31.660Z,1539016411.660 [Startup:StartupSatComms:B] Stopped
2018-10-08T16:33:31.661Z,1539016411.661 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-10-08T16:33:31.665Z,1539016411.665 [Startup:StartupSatComms] Stopped
2018-10-08T16:33:31.665Z,1539016411.665 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-10-08T16:33:31.666Z,1539016411.666 [Startup](INFO): Completed Startup
2018-10-08T16:33:31.666Z,1539016411.666 [MissionManager](INFO): Startup is completed.
2018-10-08T16:33:31.667Z,1539016411.667 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-08T16:33:31.667Z,1539016411.667 [Startup] Stopped
2018-10-08T16:33:31.667Z,1539016411.667 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-08T16:33:31.667Z,1539016411.667 [Startup:A.GoToSurface] Stopped
2018-10-08T16:33:31.667Z,1539016411.667 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-08T16:33:31.828Z,1539016411.828 [MissionManager](IMPORTANT): Started mission Default
2018-10-08T16:33:31.837Z,1539016411.837 [Default] Running Loop=1
2018-10-08T16:33:31.837Z,1539016411.837 [Default](DEBUG): Aggregate::initialize Default
2018-10-08T16:33:31.837Z,1539016411.837 [Default:B.GoToSurface] Running Loop=1
2018-10-08T16:33:31.837Z,1539016411.837 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-08T16:33:31.838Z,1539016411.838 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-08T16:33:31.838Z,1539016411.838 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-08T16:33:31.838Z,1539016411.838 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-08T16:33:31.839Z,1539016411.839 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-08T16:33:31.839Z,1539016411.839 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-08T16:33:31.839Z,1539016411.839 [Default:A.Wait] Running Loop=1
2018-10-08T16:33:31.839Z,1539016411.839 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-10-08T16:33:37.906Z,1539016417.906 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:33:38.712Z,1539016418.712 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:33:43.105Z,1539016423.105 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:33:43.172Z,1539016423.172 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:33:43.173Z,1539016423.173 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:33:43.520Z,1539016423.520 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:33:43.909Z,1539016423.909 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:33:43.975Z,1539016423.975 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:33:43.975Z,1539016423.975 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:33:44.321Z,1539016424.321 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:33:44.338Z,1539016424.338 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:33:45.119Z,1539016425.119 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:33:45.133Z,1539016425.133 [Default:A.Wait](INFO): Done Waiting.
2018-10-08T16:33:45.134Z,1539016425.134 [Default:A.Wait] Stopped
2018-10-08T16:33:45.134Z,1539016425.134 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-10-08T16:33:45.529Z,1539016425.529 [Default:CheckIn] Running Loop=1
2018-10-08T16:33:45.529Z,1539016425.529 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-08T16:33:45.529Z,1539016425.529 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-08T16:33:46.002Z,1539016426.002 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-10-08T16:33:51.525Z,1539016431.525 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:33:52.290Z,1539016432.290 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:33:56.304Z,1539016436.304 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:33:56.404Z,1539016436.404 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:33:56.405Z,1539016436.405 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:33:56.743Z,1539016436.743 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:33:57.117Z,1539016437.117 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:33:57.182Z,1539016437.182 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:33:57.182Z,1539016437.182 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:33:57.525Z,1539016437.525 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:33:57.546Z,1539016437.546 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:33:58.310Z,1539016438.310 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:34:04.704Z,1539016444.704 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:34:05.510Z,1539016445.510 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:34:10.354Z,1539016450.354 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:34:10.431Z,1539016450.431 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:34:10.432Z,1539016450.432 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:34:10.432Z,1539016450.432 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:34:10.511Z,1539016450.511 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:34:10.511Z,1539016450.511 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:34:10.665Z,1539016450.665 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:34:10.679Z,1539016450.679 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:34:11.449Z,1539016451.449 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:34:11.461Z,1539016451.461 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:34:18.607Z,1539016458.607 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:34:18.988Z,1539016458.988 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:34:23.419Z,1539016463.419 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:34:23.499Z,1539016463.499 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:34:23.499Z,1539016463.499 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:34:23.810Z,1539016463.810 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:34:23.810Z,1539016463.810 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:34:23.903Z,1539016463.903 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:34:23.903Z,1539016463.903 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:34:24.209Z,1539016464.209 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:34:24.621Z,1539016464.621 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:34:25.001Z,1539016465.001 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:34:31.801Z,1539016471.801 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:34:32.205Z,1539016472.205 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:34:36.617Z,1539016476.617 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:34:36.696Z,1539016476.696 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:34:36.696Z,1539016476.696 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:34:37.019Z,1539016477.019 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:34:37.019Z,1539016477.019 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:34:37.116Z,1539016477.116 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:34:37.116Z,1539016477.116 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:34:37.437Z,1539016477.437 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:34:37.833Z,1539016477.833 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:34:38.233Z,1539016478.233 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:34:45.469Z,1539016485.469 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:34:45.469Z,1539016485.469 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:34:49.979Z,1539016489.979 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:34:50.062Z,1539016490.062 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:34:50.062Z,1539016490.062 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:34:50.393Z,1539016490.393 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:34:50.394Z,1539016490.394 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:34:50.491Z,1539016490.491 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:34:50.491Z,1539016490.491 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:34:50.833Z,1539016490.833 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:34:51.197Z,1539016491.197 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:34:51.609Z,1539016491.609 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:34:58.778Z,1539016498.778 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:34:59.163Z,1539016499.163 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:35:03.571Z,1539016503.571 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:35:03.668Z,1539016503.668 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:03.668Z,1539016503.668 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:35:03.669Z,1539016503.669 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:35:03.745Z,1539016503.745 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:03.745Z,1539016503.745 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:35:03.965Z,1539016503.965 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:35:03.973Z,1539016503.973 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:35:04.773Z,1539016504.773 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:35:04.785Z,1539016504.785 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:35:11.975Z,1539016511.975 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:35:11.975Z,1539016511.975 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:35:16.779Z,1539016516.779 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:35:16.851Z,1539016516.851 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:16.852Z,1539016516.852 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:35:16.852Z,1539016516.852 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:35:16.926Z,1539016516.926 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:16.926Z,1539016516.926 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:35:17.194Z,1539016517.194 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:35:17.203Z,1539016517.203 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:35:20.781Z,1539016520.781 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:35:20.793Z,1539016520.793 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:35:28.067Z,1539016528.067 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:35:28.067Z,1539016528.067 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:35:32.879Z,1539016532.879 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:35:32.950Z,1539016532.950 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:32.951Z,1539016532.951 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:35:32.951Z,1539016532.951 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:35:33.025Z,1539016533.025 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:33.025Z,1539016533.025 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:35:33.283Z,1539016533.283 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:35:33.306Z,1539016533.306 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:35:34.077Z,1539016534.077 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:35:34.089Z,1539016534.089 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:35:41.260Z,1539016541.260 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:35:41.261Z,1539016541.261 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:35:46.066Z,1539016546.066 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:35:46.142Z,1539016546.142 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:46.142Z,1539016546.142 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:35:46.143Z,1539016546.143 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:35:46.221Z,1539016546.221 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:46.222Z,1539016546.222 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:35:46.489Z,1539016546.489 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:35:46.506Z,1539016546.506 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:35:47.293Z,1539016547.293 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:35:47.701Z,1539016547.701 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:35:56.104Z,1539016556.104 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:35:56.104Z,1539016556.104 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:35:59.403Z,1539016559.403 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:35:59.471Z,1539016559.471 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:59.471Z,1539016559.471 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:35:59.821Z,1539016559.821 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:35:59.821Z,1539016559.821 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:35:59.928Z,1539016559.928 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:35:59.928Z,1539016559.928 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:36:00.221Z,1539016560.221 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:36:00.609Z,1539016560.609 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:36:01.021Z,1539016561.021 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:36:07.767Z,1539016567.767 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-10-08T16:36:07.816Z,1539016567.816 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:36:08.200Z,1539016568.200 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:36:12.610Z,1539016572.610 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:36:12.689Z,1539016572.689 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:36:12.689Z,1539016572.689 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:36:13.013Z,1539016573.013 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:36:13.014Z,1539016573.014 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:36:13.105Z,1539016573.105 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:36:13.105Z,1539016573.105 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:36:13.437Z,1539016573.437 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:36:13.797Z,1539016573.797 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:36:14.229Z,1539016574.229 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:36:21.427Z,1539016581.427 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:36:21.786Z,1539016581.786 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:36:26.208Z,1539016586.208 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:36:26.330Z,1539016586.330 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:36:26.330Z,1539016586.330 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:36:26.647Z,1539016586.647 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:36:26.647Z,1539016586.647 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:36:26.819Z,1539016586.819 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:36:26.820Z,1539016586.820 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:36:27.013Z,1539016587.013 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:36:27.409Z,1539016587.409 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:36:27.825Z,1539016587.825 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:36:35.032Z,1539016595.032 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:36:35.032Z,1539016595.032 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:36:35.040Z,1539016595.040 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-08T16:36:35.040Z,1539016595.040 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-10-08T16:36:35.040Z,1539016595.040 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-08T16:36:35.042Z,1539016595.042 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-08T16:36:35.042Z,1539016595.042 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-10-08T16:36:35.042Z,1539016595.042 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-08T16:36:35.056Z,1539016595.056 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-08T16:36:35.056Z,1539016595.056 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-08T16:36:35.203Z,1539016595.203 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-08T16:36:35.203Z,1539016595.203 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-10-08T16:36:35.203Z,1539016595.203 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-08T16:36:35.203Z,1539016595.203 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-10-08T16:36:35.577Z,1539016595.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-08T16:36:35.578Z,1539016595.578 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:36:35.578Z,1539016595.578 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:36:35.579Z,1539016595.579 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-08T16:36:35.579Z,1539016595.579 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:36:35.579Z,1539016595.579 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:36:39.534Z,1539016599.534 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:36:39.718Z,1539016599.718 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:36:39.718Z,1539016599.718 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:36:39.982Z,1539016599.982 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:36:39.983Z,1539016599.983 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:36:40.156Z,1539016600.156 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:36:40.157Z,1539016600.157 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:36:40.361Z,1539016600.361 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:36:40.777Z,1539016600.777 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:36:41.157Z,1539016601.157 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:36:47.921Z,1539016607.921 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:36:47.922Z,1539016607.922 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:36:52.754Z,1539016612.754 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:36:52.832Z,1539016612.832 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:36:52.832Z,1539016612.832 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:36:53.135Z,1539016613.135 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:36:53.212Z,1539016613.212 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:36:53.212Z,1539016613.212 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:36:53.220Z,1539016613.220 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:36:53.543Z,1539016613.543 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:36:53.945Z,1539016613.945 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:36:54.357Z,1539016614.357 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:37:01.131Z,1539016621.131 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:37:01.550Z,1539016621.550 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:37:06.760Z,1539016626.760 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:37:06.835Z,1539016626.835 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:37:06.836Z,1539016626.836 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:37:06.836Z,1539016626.836 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:37:06.909Z,1539016626.909 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:37:06.910Z,1539016626.910 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:37:07.047Z,1539016627.047 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:37:07.072Z,1539016627.072 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:37:07.833Z,1539016627.833 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:37:07.845Z,1539016627.845 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:37:14.969Z,1539016634.969 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:37:14.970Z,1539016634.970 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:37:19.790Z,1539016639.790 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:37:19.878Z,1539016639.878 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:37:19.879Z,1539016639.879 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:37:19.879Z,1539016639.879 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:37:19.954Z,1539016639.954 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:37:19.954Z,1539016639.954 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:37:20.186Z,1539016640.186 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:37:20.203Z,1539016640.203 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:37:21.014Z,1539016641.014 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:37:21.029Z,1539016641.029 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:37:28.180Z,1539016648.180 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:37:28.180Z,1539016648.180 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:37:33.410Z,1539016653.410 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:37:33.496Z,1539016653.496 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:37:33.497Z,1539016653.497 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:37:33.497Z,1539016653.497 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:37:33.570Z,1539016653.570 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:37:33.571Z,1539016653.571 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2018-10-08T16:37:33.803Z,1539016653.803 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:37:33.824Z,1539016653.824 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:37:34.609Z,1539016654.609 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:37:34.621Z,1539016654.621 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:37:41.823Z,1539016661.823 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:37:41.823Z,1539016661.823 [BPC1](INFO): BPC1B: sending IBPS break.
2018-10-08T16:37:46.346Z,1539016666.346 [BPC1](INFO): BPC1B: validating data format.
2018-10-08T16:37:46.422Z,1539016666.422 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:37:46.423Z,1539016666.423 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2018-10-08T16:37:46.740Z,1539016666.740 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:37:46.822Z,1539016666.822 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:37:46.822Z,1539016666.822 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2018-10-08T16:37:46.830Z,1539016666.830 [BPC1](INFO): BPC1B: data parsed.
2018-10-08T16:37:47.159Z,1539016667.159 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:37:47.581Z,1539016667.581 [BPC1](INFO): BPC1B: initialized.
2018-10-08T16:37:47.957Z,1539016667.957 [BPC1](INFO): BPC1A: initialized.
2018-10-08T16:37:49.536Z,1539016669.536 [BPC1](INFO): BPC1A: sending IBPS break.
2018-10-08T16:37:53.139Z,1539016673.139 [BPC1](INFO): BPC1A: validating data format.
2018-10-08T16:37:53.165Z,1539016673.165 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-10-08T16:37:53.165Z,1539016673.165 [BPC1](INFO): BPC1A: got valid message (1596 bytes).
2018-10-08T16:37:53.559Z,1539016673.559 [BPC1](INFO): BPC1A: data parsed.
2018-10-08T16:37:53.561Z,1539016673.561 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-10-08T16:38:45.708Z,1539016725.708 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-08T16:33:45.5Z
2018-10-08T16:38:45.708Z,1539016725.708 [Default:CheckIn:Read_GPS] Stopped
2018-10-08T16:38:45.708Z,1539016725.708 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-08T16:38:46.102Z,1539016726.102 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-08T16:38:50.232Z,1539016730.232 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20181008T163006/Courier0004.lzma
2018-10-08T16:38:50.987Z,1539016730.987 [DataOverHttps](INFO): Moved sent file to Logs/20181008T163006/Courier0004.lzma.bak
2018-10-08T16:38:50.987Z,1539016730.987 [DataOverHttps](INFO): SBD MOMSN=8614063
2018-10-08T16:39:00.267Z,1539016740.267 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20181008T163006/Express0005.lzma
2018-10-08T16:39:01.024Z,1539016741.024 [DataOverHttps](INFO): Moved sent file to Logs/20181008T163006/Express0005.lzma.bak
2018-10-08T16:39:01.024Z,1539016741.024 [DataOverHttps](INFO): SBD MOMSN=8614065
2018-10-08T16:39:02.078Z,1539016742.078 [Default:CheckIn:Read_Iridium] Stopped
2018-10-08T16:39:02.078Z,1539016742.078 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-08T16:39:02.079Z,1539016742.079 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-08T16:39:35.851Z,1539016775.851 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-08T16:39:35.851Z,1539016775.851 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-10-08T16:39:35.851Z,1539016775.851 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-08T16:39:35.853Z,1539016775.853 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-08T16:39:35.853Z,1539016775.853 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-10-08T16:39:35.853Z,1539016775.853 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-08T16:39:35.891Z,1539016775.891 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-08T16:39:35.891Z,1539016775.891 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-08T16:39:36.258Z,1539016776.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-08T16:39:36.258Z,1539016776.258 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-10-08T16:39:36.258Z,1539016776.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-08T16:39:36.258Z,1539016776.258 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-10-08T16:39:36.655Z,1539016776.655 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-08T16:39:36.655Z,1539016776.655 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:39:36.656Z,1539016776.656 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:39:36.656Z,1539016776.656 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-08T16:39:36.656Z,1539016776.656 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:39:36.661Z,1539016776.661 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:41:06.139Z,1539016866.139 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-08T16:41:06.139Z,1539016866.139 [NAL9602] Data Fault, FailCount= 1
2018-10-08T16:41:06.139Z,1539016866.139 [NAL9602](ERROR): Data Fault
2018-10-08T16:41:06.201Z,1539016866.201 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-08T16:41:06.539Z,1539016866.539 [NAL9602](INFO): Powering down
2018-10-08T16:41:07.419Z,1539016867.419 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-08T16:41:07.419Z,1539016867.419 [NAL9602] No Fault, FailCount= 1
2018-10-08T16:41:36.731Z,1539016896.731 [NAL9602](INFO): Powering up NAL9602
2018-10-08T16:41:47.931Z,1539016907.931 [NAL9602](INFO): NAL9602 initialized
2018-10-08T16:42:38.100Z,1539016958.100 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-08T16:42:38.100Z,1539016958.100 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-10-08T16:42:38.100Z,1539016958.100 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-08T16:42:38.102Z,1539016958.102 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-10-08T16:42:38.102Z,1539016958.102 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-10-08T16:42:38.102Z,1539016958.102 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-08T16:42:38.116Z,1539016958.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-08T16:42:38.116Z,1539016958.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-08T16:42:38.244Z,1539016958.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-08T16:42:38.244Z,1539016958.244 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-10-08T16:42:38.253Z,1539016958.253 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-08T16:42:38.253Z,1539016958.253 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-10-08T16:42:38.605Z,1539016958.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-08T16:42:38.606Z,1539016958.606 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:42:38.606Z,1539016958.606 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:42:38.607Z,1539016958.607 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-08T16:42:38.607Z,1539016958.607 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-08T16:42:38.608Z,1539016958.608 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-08T16:44:02.399Z,1539017042.399 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-08T16:44:02.399Z,1539017042.399 [Default:CheckIn:C.Wait] Stopped
2018-10-08T16:44:02.399Z,1539017042.399 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-08T16:44:02.399Z,1539017042.399 [Default:CheckIn:D] Running Loop=1
2018-10-08T16:44:02.775Z,1539017042.775 [Default:CheckIn:D] Stopped
2018-10-08T16:44:02.775Z,1539017042.775 [Default:CheckIn:E] Running Loop=1
2018-10-08T16:44:03.173Z,1539017043.173 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.515666 min 
2018-10-08T16:44:03.175Z,1539017043.175 [Default:CheckIn:E] Stopped
2018-10-08T16:44:03.175Z,1539017043.175 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-08T16:44:03.175Z,1539017043.175 [Default:CheckIn] Stopped
2018-10-08T16:44:03.175Z,1539017043.175 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-08T16:44:03.176Z,1539017043.176 [Default:CheckIn](INFO): Running loop #2
2018-10-08T16:44:03.176Z,1539017043.176 [Default:CheckIn] Running Loop=2
2018-10-08T16:44:03.176Z,1539017043.176 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-08T16:44:03.176Z,1539017043.176 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-08T16:44:12.375Z,1539017052.375 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-10-08T16:44:12.375Z,1539017052.375 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.0  +0.00,        +0.00,   0.00,810.97
2018-10-08T16:44:49.356Z,1539017089.356 [CommandLine](IMPORTANT): got command configSet BPC1.batterySamplingInterval 1.000000 minute persist
2018-10-08T16:44:56.382Z,1539017096.382 [CommandLine](IMPORTANT): got command restart application
2018-10-08T16:44:57.391Z,1539017097.391 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-08T16:44:57.391Z,1539017097.391 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:57.549Z,1539017097.549 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-08T16:44:57.549Z,1539017097.549 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:57.550Z,1539017097.550 [CommandLine](INFO): Join timeout helper Thread ID is 7660
2018-10-08T16:44:57.561Z,1539017097.561 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-08T16:44:57.561Z,1539017097.561 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:57.561Z,1539017097.561 [NavChartDb](INFO): Join timeout helper Thread ID is 7661
2018-10-08T16:44:57.753Z,1539017097.753 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-08T16:44:57.753Z,1539017097.753 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:57.765Z,1539017097.765 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-08T16:44:57.765Z,1539017097.765 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:57.765Z,1539017097.765 [Radio_Surface](INFO): Join timeout helper Thread ID is 7662
2018-10-08T16:44:57.973Z,1539017097.973 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-08T16:44:57.973Z,1539017097.973 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:57.985Z,1539017097.985 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-10-08T16:44:57.985Z,1539017097.985 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:57.985Z,1539017097.985 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7663
2018-10-08T16:44:58.281Z,1539017098.281 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-08T16:44:58.281Z,1539017098.281 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-10-08T16:44:58.282Z,1539017098.282 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:58.285Z,1539017098.285 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-10-08T16:44:58.285Z,1539017098.285 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:58.286Z,1539017098.286 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7664
2018-10-08T16:44:58.634Z,1539017098.634 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-08T16:44:58.635Z,1539017098.635 [CTD_NeilBrown](INFO): Powering down
2018-10-08T16:44:58.638Z,1539017098.638 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:58.658Z,1539017098.658 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-08T16:44:58.658Z,1539017098.658 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:58.658Z,1539017098.658 [logger](INFO): Join timeout helper Thread ID is 7665
2018-10-08T16:44:58.697Z,1539017098.697 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-08T16:44:58.697Z,1539017098.697 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:58.710Z,1539017098.710 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-08T16:44:58.710Z,1539017098.710 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:58.710Z,1539017098.710 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-08T16:44:58.710Z,1539017098.710 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:58.710Z,1539017098.710 [controlThread](INFO): Join timeout helper Thread ID is 7666
2018-10-08T16:44:59.050Z,1539017099.050 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-08T16:44:59.050Z,1539017099.050 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-08T16:44:59.051Z,1539017099.051 [Aanderaa_O2](INFO): Powering down
2018-10-08T16:44:59.052Z,1539017099.052 [AHRS_M2](INFO): Powering down
2018-10-08T16:44:59.124Z,1539017099.124 [NAL9602](INFO): Powering down
2018-10-08T16:44:59.197Z,1539017099.197 [RDI_Pathfinder](INFO): Powering down
2018-10-08T16:44:59.199Z,1539017099.199 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-08T16:44:59.199Z,1539017099.199 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-08T16:44:59.200Z,1539017099.200 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-08T16:44:59.201Z,1539017099.201 [MissionManager](INFO): Uninitializing Mission Default
2018-10-08T16:44:59.201Z,1539017099.201 [Default] Stopped
2018-10-08T16:44:59.201Z,1539017099.201 [Default](DEBUG): Aggregate::uninitialize Default
2018-10-08T16:44:59.201Z,1539017099.201 [Default:B.GoToSurface] Stopped
2018-10-08T16:44:59.201Z,1539017099.201 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-08T16:44:59.201Z,1539017099.201 [Default:CheckIn] Stopped
2018-10-08T16:44:59.202Z,1539017099.202 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-08T16:44:59.202Z,1539017099.202 [Default:CheckIn:Read_GPS] Stopped
2018-10-08T16:44:59.205Z,1539017099.205 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-08T16:44:59.206Z,1539017099.206 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-08T16:44:59.206Z,1539017099.206 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-08T16:44:59.206Z,1539017099.206 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-08T16:44:59.206Z,1539017099.206 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-08T16:44:59.207Z,1539017099.207 [BuoyancyServo](INFO): Powering down
2018-10-08T16:44:59.221Z,1539017099.221 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-08T16:44:59.221Z,1539017099.221 [ElevatorServo](INFO): Powering down
2018-10-08T16:44:59.222Z,1539017099.222 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-08T16:44:59.222Z,1539017099.222 [MassServo](INFO): Powering down
2018-10-08T16:44:59.223Z,1539017099.223 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-08T16:44:59.223Z,1539017099.223 [RudderServo](INFO): Powering down
2018-10-08T16:44:59.224Z,1539017099.224 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-08T16:44:59.224Z,1539017099.224 [ThrusterServo](INFO): Powering down
2018-10-08T16:44:59.225Z,1539017099.225 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-08T16:44:59.225Z,1539017099.225 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-08T16:44:59.226Z,1539017099.226 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-08T16:44:59.227Z,1539017099.227 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:59.302Z,1539017099.302 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:59.305Z,1539017099.305 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:59.371Z,1539017099.371 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:59.422Z,1539017099.422 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-08T16:44:59.482Z,1539017099.482 [logger ThreadHandler](INFO): Thread cancelled.