2018-09-19T20:37:43.314Z,1537389463.314 [Supervisor](DEBUG): Initializing supervisor. 2018-09-19T20:37:43.317Z,1537389463.317 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-09-19T20:37:43.318Z,1537389463.318 [SyncHandler](INFO): Protected caller Thread ID is 2146 2018-09-19T20:37:43.319Z,1537389463.319 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-09-19T20:37:43.320Z,1537389463.320 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-09-19T20:37:43.320Z,1537389463.320 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2147 2018-09-19T20:37:43.324Z,1537389463.324 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-09-19T20:37:43.337Z,1537389463.337 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-09-19T20:37:43.338Z,1537389463.338 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-09-19T20:37:43.338Z,1537389463.338 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2148 2018-09-19T20:37:43.339Z,1537389463.339 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-09-19T20:37:43.340Z,1537389463.340 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-09-19T20:37:43.340Z,1537389463.340 [logger ThreadHandler](INFO): Protected caller Thread ID is 2149 2018-09-19T20:37:43.342Z,1537389463.342 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-09-19T20:37:43.343Z,1537389463.343 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-09-19T20:37:43.344Z,1537389463.344 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-09-19T20:37:43.533Z,1537389463.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-09-19T20:37:43.533Z,1537389463.533 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-09-19T20:37:43.677Z,1537389463.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-09-19T20:37:43.678Z,1537389463.678 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-09-19T20:37:44.043Z,1537389464.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-09-19T20:37:44.044Z,1537389464.044 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-09-19T20:37:44.183Z,1537389464.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-09-19T20:37:44.183Z,1537389464.183 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-09-19T20:37:44.265Z,1537389464.265 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-09-19T20:37:44.467Z,1537389464.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-09-19T20:37:44.467Z,1537389464.467 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-09-19T20:37:44.567Z,1537389464.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-09-19T20:37:44.567Z,1537389464.567 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-09-19T20:37:44.909Z,1537389464.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-09-19T20:37:44.910Z,1537389464.910 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-09-19T20:37:45.367Z,1537389465.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-09-19T20:37:45.367Z,1537389465.367 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-09-19T20:37:45.681Z,1537389465.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-09-19T20:37:45.682Z,1537389465.682 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-09-19T20:37:46.182Z,1537389466.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-09-19T20:37:46.183Z,1537389466.183 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-09-19T20:37:46.391Z,1537389466.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-09-19T20:37:46.391Z,1537389466.391 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-09-19T20:37:46.490Z,1537389466.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-09-19T20:37:46.490Z,1537389466.490 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-09-19T20:37:46.985Z,1537389466.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-09-19T20:37:46.985Z,1537389466.985 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-09-19T20:37:47.093Z,1537389467.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-09-19T20:37:47.095Z,1537389467.095 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-09-19T20:37:47.096Z,1537389467.096 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-09-19T20:37:47.371Z,1537389467.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-09-19T20:37:47.371Z,1537389467.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-09-19T20:37:47.476Z,1537389467.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-09-19T20:37:47.570Z,1537389467.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-09-19T20:37:47.673Z,1537389467.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-09-19T20:37:47.760Z,1537389467.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-09-19T20:37:47.895Z,1537389467.895 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-09-19T20:37:48.064Z,1537389468.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-09-19T20:37:48.176Z,1537389468.176 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-09-19T20:37:48.276Z,1537389468.276 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-09-19T20:37:48.360Z,1537389468.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-09-19T20:37:48.552Z,1537389468.552 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2018-09-19T20:37:48.553Z,1537389468.553 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-09-19T20:37:48.557Z,1537389468.557 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-09-19T20:37:48.687Z,1537389468.687 [SBIT](DEBUG): Construct Startup Built In Test. 2018-09-19T20:37:48.717Z,1537389468.717 [SBIT] Loaded 2018-09-19T20:37:48.718Z,1537389468.718 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-09-19T20:37:48.718Z,1537389468.718 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-09-19T20:37:48.746Z,1537389468.746 [IBIT] Loaded 2018-09-19T20:37:48.746Z,1537389468.746 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-09-19T20:37:48.749Z,1537389468.749 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-09-19T20:37:48.904Z,1537389468.904 [CBIT] Loaded 2018-09-19T20:37:48.904Z,1537389468.904 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-09-19T20:37:48.905Z,1537389468.905 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-09-19T20:37:48.905Z,1537389468.905 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-09-19T20:37:48.974Z,1537389468.974 [VerticalControl](DEBUG): Construct VerticalControl. 2018-09-19T20:37:49.074Z,1537389469.074 [VerticalControl] Loaded 2018-09-19T20:37:49.074Z,1537389469.074 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-09-19T20:37:49.075Z,1537389469.075 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-09-19T20:37:49.136Z,1537389469.136 [HorizontalControl] Loaded 2018-09-19T20:37:49.137Z,1537389469.137 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-09-19T20:37:49.137Z,1537389469.137 [SpeedControl](DEBUG): Construct SpeedControl. 2018-09-19T20:37:49.143Z,1537389469.143 [SpeedControl] Loaded 2018-09-19T20:37:49.143Z,1537389469.143 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-09-19T20:37:49.144Z,1537389469.144 [LoopControl](DEBUG): Construct LoopControl. 2018-09-19T20:37:49.144Z,1537389469.144 [LoopControl] Loaded 2018-09-19T20:37:49.145Z,1537389469.145 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-09-19T20:37:49.145Z,1537389469.145 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-09-19T20:37:49.146Z,1537389469.146 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-09-19T20:37:49.186Z,1537389469.186 [DepthRateCalculator] Loaded 2018-09-19T20:37:49.187Z,1537389469.187 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-09-19T20:37:49.192Z,1537389469.192 [PitchRateCalculator] Loaded 2018-09-19T20:37:49.192Z,1537389469.192 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-09-19T20:37:49.208Z,1537389469.208 [SpeedCalculator] Loaded 2018-09-19T20:37:49.208Z,1537389469.208 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-09-19T20:37:49.229Z,1537389469.229 [TempGradientCalculator] Loaded 2018-09-19T20:37:49.230Z,1537389469.230 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-09-19T20:37:49.235Z,1537389469.235 [YawRateCalculator] Loaded 2018-09-19T20:37:49.235Z,1537389469.235 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-09-19T20:37:49.264Z,1537389469.264 [ElevatorOffsetCalculator] Loaded 2018-09-19T20:37:49.264Z,1537389469.264 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-09-19T20:37:49.265Z,1537389469.265 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-09-19T20:37:49.265Z,1537389469.265 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-09-19T20:37:49.330Z,1537389469.330 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-09-19T20:37:49.330Z,1537389469.330 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-09-19T20:37:49.753Z,1537389469.753 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-09-19T20:37:49.754Z,1537389469.754 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-09-19T20:37:49.850Z,1537389469.850 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-09-19T20:37:49.850Z,1537389469.850 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-09-19T20:37:49.905Z,1537389469.905 [DeadReckonUsingSpeedCalculator] Loaded 2018-09-19T20:37:49.905Z,1537389469.905 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-09-19T20:37:49.921Z,1537389469.921 [NavChart] Loaded 2018-09-19T20:37:49.922Z,1537389469.922 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-09-19T20:37:49.926Z,1537389469.926 [UniversalFixResidualReporter] Loaded 2018-09-19T20:37:49.926Z,1537389469.926 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-09-19T20:37:49.926Z,1537389469.926 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-09-19T20:37:49.927Z,1537389469.927 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-09-19T20:37:49.941Z,1537389469.941 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-09-19T20:37:49.941Z,1537389469.941 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-09-19T20:37:50.098Z,1537389470.098 [Aanderaa_O2] Loaded 2018-09-19T20:37:50.098Z,1537389470.098 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-09-19T20:37:50.144Z,1537389470.144 [WetLabsSeaOWL_UV_A] Loaded 2018-09-19T20:37:50.144Z,1537389470.144 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2018-09-19T20:37:50.145Z,1537389470.145 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406D54E0 2018-09-19T20:37:50.146Z,1537389470.146 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 2228 2018-09-19T20:37:50.146Z,1537389470.146 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-09-19T20:37:50.147Z,1537389470.147 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-09-19T20:37:50.454Z,1537389470.454 [AHRS_M2] Loaded 2018-09-19T20:37:50.454Z,1537389470.454 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-09-19T20:37:50.523Z,1537389470.523 [DataOverHttps] Loaded 2018-09-19T20:37:50.524Z,1537389470.524 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-09-19T20:37:50.537Z,1537389470.537 [Depth_Keller] Loaded 2018-09-19T20:37:50.538Z,1537389470.538 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-09-19T20:37:50.543Z,1537389470.543 [DropWeight] Loaded 2018-09-19T20:37:50.543Z,1537389470.543 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-09-19T20:37:50.633Z,1537389470.633 [NAL9602] Loaded 2018-09-19T20:37:50.634Z,1537389470.634 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-09-19T20:37:50.639Z,1537389470.639 [Onboard] Loaded 2018-09-19T20:37:50.639Z,1537389470.639 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-09-19T20:37:50.646Z,1537389470.646 [Radio_Surface] Loaded 2018-09-19T20:37:50.647Z,1537389470.647 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-09-19T20:37:50.648Z,1537389470.648 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409364E0 2018-09-19T20:37:50.648Z,1537389470.648 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2229 2018-09-19T20:37:50.677Z,1537389470.677 [RDI_Pathfinder] Loaded 2018-09-19T20:37:50.677Z,1537389470.677 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-09-19T20:37:50.700Z,1537389470.700 [RDI_PathfinderUp] Loaded 2018-09-19T20:37:50.700Z,1537389470.700 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2018-09-19T20:37:50.707Z,1537389470.707 [SCPI] Loaded 2018-09-19T20:37:50.707Z,1537389470.707 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-09-19T20:37:50.730Z,1537389470.730 [BPC1](INFO): BPC1A: got serial number for stick 1: 0140 2018-09-19T20:37:50.733Z,1537389470.733 [BPC1](INFO): BPC1A: got serial number for stick 2: 0163 2018-09-19T20:37:50.735Z,1537389470.735 [BPC1](INFO): BPC1A: got serial number for stick 3: 0157 2018-09-19T20:37:50.738Z,1537389470.738 [BPC1](INFO): BPC1A: got serial number for stick 4: 0159 2018-09-19T20:37:50.740Z,1537389470.740 [BPC1](INFO): BPC1A: got serial number for stick 5: 016E 2018-09-19T20:37:50.742Z,1537389470.742 [BPC1](INFO): BPC1A: got serial number for stick 6: 0160 2018-09-19T20:37:50.744Z,1537389470.744 [BPC1](INFO): BPC1A: got serial number for stick 7: 015C 2018-09-19T20:37:50.747Z,1537389470.747 [BPC1](INFO): BPC1A: got serial number for stick 8: 016D 2018-09-19T20:37:50.749Z,1537389470.749 [BPC1](INFO): BPC1A: got serial number for stick 9: 012D 2018-09-19T20:37:50.751Z,1537389470.751 [BPC1](INFO): BPC1A: got serial number for stick 10: 015E 2018-09-19T20:37:50.753Z,1537389470.753 [BPC1](INFO): BPC1A: got serial number for stick 11: 0164 2018-09-19T20:37:50.755Z,1537389470.755 [BPC1](INFO): BPC1A: got serial number for stick 12: 015B 2018-09-19T20:37:50.757Z,1537389470.757 [BPC1](INFO): BPC1A: got serial number for stick 13: 0172 2018-09-19T20:37:50.759Z,1537389470.759 [BPC1](INFO): BPC1A: got serial number for stick 14: 0166 2018-09-19T20:37:50.761Z,1537389470.761 [BPC1](INFO): BPC1A: got serial number for stick 15: 0170 2018-09-19T20:37:50.763Z,1537389470.763 [BPC1](INFO): BPC1A: got serial number for stick 16: 0148 2018-09-19T20:37:50.765Z,1537389470.765 [BPC1](INFO): BPC1A: got serial number for stick 17: 0130 2018-09-19T20:37:50.766Z,1537389470.766 [BPC1](INFO): BPC1A: got serial number for stick 18: 0169 2018-09-19T20:37:50.767Z,1537389470.767 [BPC1](INFO): BPC1A: got serial number for stick 19: 0158 2018-09-19T20:37:50.768Z,1537389470.768 [BPC1](INFO): BPC1A: got serial number for stick 20: 0162 2018-09-19T20:37:50.769Z,1537389470.769 [BPC1](INFO): BPC1A: got serial number for stick 21: 0150 2018-09-19T20:37:50.770Z,1537389470.770 [BPC1](INFO): BPC1A: got serial number for stick 22: 015F 2018-09-19T20:37:50.771Z,1537389470.771 [BPC1](INFO): BPC1A: got serial number for stick 23: 0171 2018-09-19T20:37:50.772Z,1537389470.772 [BPC1](INFO): BPC1A: got serial number for stick 24: 0143 2018-09-19T20:37:50.772Z,1537389470.772 [BPC1](INFO): BPC1A: got serial number for stick 25: 0144 2018-09-19T20:37:50.773Z,1537389470.773 [BPC1](INFO): BPC1A: got serial number for stick 26: 016A 2018-09-19T20:37:50.774Z,1537389470.774 [BPC1](INFO): BPC1A: got serial number for stick 27: 0155 2018-09-19T20:37:50.775Z,1537389470.775 [BPC1](INFO): BPC1A: got serial number for stick 28: 0151 2018-09-19T20:37:50.776Z,1537389470.776 [BPC1](INFO): BPC1A: got serial number for stick 29: 0154 2018-09-19T20:37:50.777Z,1537389470.777 [BPC1](INFO): BPC1A: got serial number for stick 30: 0161 2018-09-19T20:37:50.778Z,1537389470.778 [BPC1](INFO): BPC1A: got serial number for stick 31: 0138 2018-09-19T20:37:50.783Z,1537389470.783 [BPC1](INFO): BPC1B: got serial number for stick 32: 0165 2018-09-19T20:37:50.785Z,1537389470.785 [BPC1](INFO): BPC1B: got serial number for stick 33: 0135 2018-09-19T20:37:50.786Z,1537389470.786 [BPC1](INFO): BPC1B: got serial number for stick 34: 0149 2018-09-19T20:37:50.787Z,1537389470.787 [BPC1](INFO): BPC1B: got serial number for stick 35: 0146 2018-09-19T20:37:50.788Z,1537389470.788 [BPC1](INFO): BPC1B: got serial number for stick 36: 013D 2018-09-19T20:37:50.788Z,1537389470.788 [BPC1](INFO): BPC1B: got serial number for stick 37: 014D 2018-09-19T20:37:50.789Z,1537389470.789 [BPC1](INFO): BPC1B: got serial number for stick 38: 0156 2018-09-19T20:37:50.790Z,1537389470.790 [BPC1](INFO): BPC1B: got serial number for stick 39: 0133 2018-09-19T20:37:50.791Z,1537389470.791 [BPC1](INFO): BPC1B: got serial number for stick 40: 0131 2018-09-19T20:37:50.792Z,1537389470.792 [BPC1](INFO): BPC1B: got serial number for stick 41: 0145 2018-09-19T20:37:50.793Z,1537389470.793 [BPC1](INFO): BPC1B: got serial number for stick 42: 014A 2018-09-19T20:37:50.794Z,1537389470.794 [BPC1](INFO): BPC1B: got serial number for stick 43: 013C 2018-09-19T20:37:50.795Z,1537389470.795 [BPC1](INFO): BPC1B: got serial number for stick 44: 013B 2018-09-19T20:37:50.796Z,1537389470.796 [BPC1](INFO): BPC1B: got serial number for stick 45: 0132 2018-09-19T20:37:50.797Z,1537389470.797 [BPC1](INFO): BPC1B: got serial number for stick 46: 0134 2018-09-19T20:37:50.797Z,1537389470.797 [BPC1](INFO): BPC1B: got serial number for stick 47: 0153 2018-09-19T20:37:50.798Z,1537389470.798 [BPC1](INFO): BPC1B: got serial number for stick 48: 014B 2018-09-19T20:37:50.799Z,1537389470.799 [BPC1](INFO): BPC1B: got serial number for stick 49: 013A 2018-09-19T20:37:50.804Z,1537389470.804 [BPC1](INFO): BPC1B: got serial number for stick 50: 0136 2018-09-19T20:37:50.806Z,1537389470.806 [BPC1](INFO): BPC1B: got serial number for stick 51: 014F 2018-09-19T20:37:50.807Z,1537389470.807 [BPC1](INFO): BPC1B: got serial number for stick 52: 014E 2018-09-19T20:37:50.808Z,1537389470.808 [BPC1](INFO): BPC1B: got serial number for stick 53: 013F 2018-09-19T20:37:50.809Z,1537389470.809 [BPC1](INFO): BPC1B: got serial number for stick 54: 0152 2018-09-19T20:37:50.809Z,1537389470.809 [BPC1](INFO): BPC1B: got serial number for stick 55: 014C 2018-09-19T20:37:50.810Z,1537389470.810 [BPC1](INFO): BPC1B: got serial number for stick 56: 016B 2018-09-19T20:37:50.811Z,1537389470.811 [BPC1](INFO): BPC1B: got serial number for stick 57: 0141 2018-09-19T20:37:50.812Z,1537389470.812 [BPC1](INFO): BPC1B: got serial number for stick 58: 0139 2018-09-19T20:37:50.813Z,1537389470.813 [BPC1](INFO): BPC1B: got serial number for stick 59: 0137 2018-09-19T20:37:50.814Z,1537389470.814 [BPC1](INFO): BPC1B: got serial number for stick 60: 0142 2018-09-19T20:37:50.815Z,1537389470.815 [BPC1](INFO): BPC1B: got serial number for stick 61: 013E 2018-09-19T20:37:50.816Z,1537389470.816 [BPC1](INFO): BPC1B: got serial number for stick 62: 0147 2018-09-19T20:37:53.027Z,1537389473.027 [BPC1] Loaded 2018-09-19T20:37:53.027Z,1537389473.027 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-09-19T20:37:53.028Z,1537389473.028 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-09-19T20:37:53.028Z,1537389473.028 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-09-19T20:37:53.127Z,1537389473.127 [BuoyancyServo] Loaded 2018-09-19T20:37:53.127Z,1537389473.127 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-09-19T20:37:53.139Z,1537389473.139 [ElevatorServo] Loaded 2018-09-19T20:37:53.139Z,1537389473.139 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-09-19T20:37:53.150Z,1537389473.150 [MassServo] Loaded 2018-09-19T20:37:53.151Z,1537389473.151 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-09-19T20:37:53.162Z,1537389473.162 [RudderServo] Loaded 2018-09-19T20:37:53.162Z,1537389473.162 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-09-19T20:37:53.173Z,1537389473.173 [ThrusterServo] Loaded 2018-09-19T20:37:53.174Z,1537389473.174 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-09-19T20:37:53.174Z,1537389473.174 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-09-19T20:37:53.175Z,1537389473.175 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-09-19T20:37:53.274Z,1537389473.274 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-09-19T20:37:53.275Z,1537389473.275 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-09-19T20:37:53.299Z,1537389473.299 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-09-19T20:37:53.302Z,1537389473.302 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-09-19T20:37:53.303Z,1537389473.303 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-09-19T20:37:53.310Z,1537389473.310 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-09-19T20:37:53.311Z,1537389473.311 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409DF4E0 2018-09-19T20:37:53.311Z,1537389473.311 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2230 2018-09-19T20:37:53.316Z,1537389473.316 [Supervisor](INFO): Main Thread ID is 800 2018-09-19T20:37:53.316Z,1537389473.316 [Supervisor](DEBUG): Running supervisor. 2018-09-19T20:37:53.317Z,1537389473.317 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2231 2018-09-19T20:37:53.320Z,1537389473.320 [controlThread ThreadHandler](INFO): Handler Thread ID is 2232 2018-09-19T20:37:53.320Z,1537389473.320 [controlThread](DEBUG): Initializing ControlThread 2018-09-19T20:37:53.321Z,1537389473.321 [SBIT](INFO): Initialize SBIT Component. 2018-09-19T20:37:53.321Z,1537389473.321 [SBIT](IMPORTANT): git: 2018-09-06-21-g021ff34 2018-09-19T20:37:53.322Z,1537389473.322 [SBIT](INFO): git hash: 021ff3480491a97d99bb6df627398514e33207a2 2018-09-19T20:37:53.322Z,1537389473.322 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-09-19T20:37:53.322Z,1537389473.322 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-09-19T20:37:53.324Z,1537389473.324 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-09-19T20:37:53.324Z,1537389473.324 [IBIT](INFO): Initialize IBIT Component. 2018-09-19T20:37:53.325Z,1537389473.325 [CBIT](DEBUG): Initialize CBIT Component. 2018-09-19T20:37:53.325Z,1537389473.325 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-09-19T20:37:53.326Z,1537389473.326 [logger ThreadHandler](INFO): Handler Thread ID is 2233 2018-09-19T20:37:53.355Z,1537389473.355 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 2234 2018-09-19T20:37:53.356Z,1537389473.356 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-09-19T20:37:53.391Z,1537389473.391 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2235 2018-09-19T20:37:53.396Z,1537389473.396 [Radio_Surface](INFO): Powering up 2018-09-19T20:37:53.403Z,1537389473.403 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2236 2018-09-19T20:37:53.406Z,1537389473.406 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-09-19T20:37:53.407Z,1537389473.407 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-09-19T20:37:53.407Z,1537389473.407 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-09-19T20:37:53.407Z,1537389473.407 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-09-19T20:37:53.407Z,1537389473.407 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-09-19T20:37:53.407Z,1537389473.407 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-09-19T20:37:53.408Z,1537389473.408 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-09-19T20:37:53.408Z,1537389473.408 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-09-19T20:37:53.408Z,1537389473.408 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-09-19T20:37:53.408Z,1537389473.408 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-09-19T20:37:53.408Z,1537389473.408 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-09-19T20:37:53.408Z,1537389473.408 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-09-19T20:37:53.409Z,1537389473.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-09-19T20:37:53.409Z,1537389473.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-09-19T20:37:53.409Z,1537389473.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-09-19T20:37:53.409Z,1537389473.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-09-19T20:37:53.431Z,1537389473.431 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-09-19T20:37:53.433Z,1537389473.433 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-09-19T20:37:53.433Z,1537389473.433 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-09-19T20:37:53.434Z,1537389473.434 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-09-19T20:37:53.434Z,1537389473.434 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-09-19T20:37:53.434Z,1537389473.434 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-09-19T20:37:53.435Z,1537389473.435 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-09-19T20:37:53.435Z,1537389473.435 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-09-19T20:37:53.436Z,1537389473.436 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-09-19T20:37:53.436Z,1537389473.436 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-09-19T20:37:53.437Z,1537389473.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T20:37:53.438Z,1537389473.438 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:37:53.438Z,1537389473.438 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:37:53.438Z,1537389473.438 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T20:37:53.439Z,1537389473.439 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:37:53.439Z,1537389473.439 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:37:53.440Z,1537389473.440 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-09-19T20:37:53.440Z,1537389473.440 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-09-19T20:37:53.447Z,1537389473.447 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-09-19T20:37:53.476Z,1537389473.476 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-09-19T20:37:53.508Z,1537389473.508 [MissionManager](DEBUG): 2018-09-19T20:37:53.508Z,1537389473.508 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-09-19T20:37:53.608Z,1537389473.608 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-09-19T20:37:53.609Z,1537389473.609 [Default:A.Wait](DEBUG): Construct Wait. 2018-09-19T20:37:53.611Z,1537389473.611 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-09-19T20:37:53.626Z,1537389473.626 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-09-19T20:37:53.652Z,1537389473.652 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-09-19T20:37:53.658Z,1537389473.658 [Default:E.Execute](DEBUG): Construct Execute. 2018-09-19T20:37:53.682Z,1537389473.682 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-09-19T20:37:53.695Z,1537389473.695 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-09-19T20:37:53.726Z,1537389473.726 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-09-19T20:37:53.767Z,1537389473.767 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-09-19T20:37:53.787Z,1537389473.787 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-09-19T20:37:53.939Z,1537389473.939 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-09-19T20:37:54.119Z,1537389474.119 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:54.164Z,1537389474.164 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:54.308Z,1537389474.308 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-09-19T20:37:54.315Z,1537389474.315 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-09-19T20:37:54.321Z,1537389474.321 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-09-19T20:37:54.327Z,1537389474.327 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-09-19T20:37:54.333Z,1537389474.333 [MassServo](DEBUG): Initializing EZServoServo. 2018-09-19T20:37:54.339Z,1537389474.339 [MassServo](DEBUG): Initializing MassServo. 2018-09-19T20:37:54.344Z,1537389474.344 [RudderServo](DEBUG): Initializing EZServoServo. 2018-09-19T20:37:54.355Z,1537389474.355 [RudderServo](DEBUG): Initializing RudderServo. 2018-09-19T20:37:54.360Z,1537389474.360 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-09-19T20:37:54.367Z,1537389474.367 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-09-19T20:37:54.527Z,1537389474.527 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-09-19T20:37:54.527Z,1537389474.527 [DropWeight] Hardware Fault, FailCount= 1 2018-09-19T20:37:54.527Z,1537389474.527 [DropWeight](ERROR): Hardware Fault 2018-09-19T20:37:54.623Z,1537389474.623 [RDI_PathfinderUp](INFO): Powering down 2018-09-19T20:37:54.683Z,1537389474.683 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-09-19T20:37:54.685Z,1537389474.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:54.687Z,1537389474.687 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:54.713Z,1537389474.713 [CommandLine](FAULT): Scheduling is paused 2018-09-19T20:37:54.713Z,1537389474.713 [CBIT](INFO): Critical error at 20180919T203754 2018-09-19T20:37:54.713Z,1537389474.713 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-09-19T20:37:54.716Z,1537389474.716 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-09-19T20:37:54.716Z,1537389474.716 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-09-19T20:37:54.867Z,1537389474.867 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-09-19T20:37:54.870Z,1537389474.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:54.872Z,1537389474.872 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:55.206Z,1537389475.206 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-09-19T20:37:55.207Z,1537389475.207 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-09-19T20:37:55.207Z,1537389475.207 [BuoyancyServo] Communications Fault, FailCount= 1 2018-09-19T20:37:55.207Z,1537389475.207 [BuoyancyServo](ERROR): Communications Fault 2018-09-19T20:37:55.428Z,1537389475.428 [CBIT](INFO): Critical error at 20180919T203754 2018-09-19T20:37:55.431Z,1537389475.431 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-09-19T20:37:55.477Z,1537389475.477 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:55.494Z,1537389475.494 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:55.501Z,1537389475.501 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-09-19T20:37:55.502Z,1537389475.502 [BuoyancyServo](INFO): Powering down 2018-09-19T20:37:55.866Z,1537389475.866 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:55.867Z,1537389475.867 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:56.270Z,1537389476.270 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:56.271Z,1537389476.271 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:56.666Z,1537389476.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:56.667Z,1537389476.667 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:57.158Z,1537389477.158 [Aanderaa_O2](INFO): Powering down 2018-09-19T20:37:57.254Z,1537389477.254 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:57.262Z,1537389477.262 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:57.503Z,1537389477.503 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout 2018-09-19T20:37:57.546Z,1537389477.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:57.547Z,1537389477.547 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-09-19T20:37:57.604Z,1537389477.604 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-09-19T20:37:57.604Z,1537389477.604 [BuoyancyServo] No Fault, FailCount= 1 2018-09-19T20:37:57.892Z,1537389477.892 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-09-19T20:37:58.011Z,1537389478.011 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-09-19T20:38:07.088Z,1537389487.088 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2018-09-19T20:38:17.918Z,1537389497.918 [SBIT](IMPORTANT): Beginning Startup BIT 2018-09-19T20:38:17.939Z,1537389497.939 [CBIT](IMPORTANT): Beginning ground fault scan 2018-09-19T20:38:20.780Z,1537389500.780 [NAL9602](INFO): Powering up NAL9602 2018-09-19T20:38:28.868Z,1537389508.868 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.008708 CHAN A1 (24V): -0.012366 CHAN A2 (12V): -0.007591 CHAN A3 (5V): -0.001918 CHAN B0 (3.3V): 0.001641 CHAN B1 (3.15aV): 0.000134 CHAN B2 (3.15bV): 0.000302 CHAN B3 (GND): 0.002148 OPEN: 0.004932 Full Scale Calc: 4.765 mA, -1.589 mA 2018-09-19T20:38:31.984Z,1537389511.984 [NAL9602](INFO): NAL9602 initialized 2018-09-19T20:38:55.058Z,1537389535.058 [BPC1](INFO): BPC1A: initialized. 2018-09-19T20:38:55.070Z,1537389535.070 [BPC1](INFO): BPC1B: initialized. 2018-09-19T20:39:02.382Z,1537389542.382 [BPC1](INFO): BPC1A: sending IBPS break. 2018-09-19T20:39:02.382Z,1537389542.382 [BPC1](INFO): BPC1B: sending IBPS break. 2018-09-19T20:39:07.192Z,1537389547.192 [BPC1](INFO): BPC1A: validating data format. 2018-09-19T20:39:07.280Z,1537389547.280 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU. 2018-09-19T20:39:07.281Z,1537389547.281 [BPC1](INFO): BPC1B: validating data format. 2018-09-19T20:39:07.351Z,1537389547.351 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU. 2018-09-19T20:39:07.566Z,1537389547.566 [BPC1](INFO): BPC1A valid message: 1824 bytes. 2018-09-19T20:39:07.567Z,1537389547.567 [BPC1](INFO): BPC1A: Serial:305J:0 serial_:320 2018-09-19T20:39:07.568Z,1537389547.568 [BPC1](INFO): BPC1A: Serial:305J:1 serial_:355 2018-09-19T20:39:07.569Z,1537389547.569 [BPC1](INFO): BPC1A: Serial:305J:2 serial_:343 2018-09-19T20:39:07.569Z,1537389547.569 [BPC1](INFO): BPC1A: Serial:305J:3 serial_:345 2018-09-19T20:39:07.570Z,1537389547.570 [BPC1](INFO): BPC1A: Serial:305J:4 serial_:366 2018-09-19T20:39:07.570Z,1537389547.570 [BPC1](INFO): BPC1A: Serial:305J:5 serial_:352 2018-09-19T20:39:07.571Z,1537389547.571 [BPC1](INFO): BPC1A: Serial:305J:6 serial_:348 2018-09-19T20:39:07.572Z,1537389547.572 [BPC1](INFO): BPC1A: Serial:305J:7 serial_:365 2018-09-19T20:39:07.572Z,1537389547.572 [BPC1](INFO): BPC1A: Serial:305J:8 serial_:301 2018-09-19T20:39:07.573Z,1537389547.573 [BPC1](INFO): BPC1A: Serial:305J:9 serial_:350 2018-09-19T20:39:07.573Z,1537389547.573 [BPC1](INFO): BPC1A: Serial:305J:10 serial_:356 2018-09-19T20:39:07.574Z,1537389547.574 [BPC1](INFO): BPC1A: Serial:305J:11 serial_:347 2018-09-19T20:39:07.590Z,1537389547.590 [BPC1](INFO): BPC1A: Serial:305J:12 serial_:370 2018-09-19T20:39:07.590Z,1537389547.590 [BPC1](INFO): BPC1A: Serial:305J:13 serial_:358 2018-09-19T20:39:07.591Z,1537389547.591 [BPC1](INFO): BPC1A: Serial:305J:14 serial_:368 2018-09-19T20:39:07.592Z,1537389547.592 [BPC1](INFO): BPC1A: Serial:305J:15 serial_:328 2018-09-19T20:39:07.592Z,1537389547.592 [BPC1](INFO): BPC1A: Serial:305J:16 serial_:304 2018-09-19T20:39:07.593Z,1537389547.593 [BPC1](INFO): BPC1A: Serial:305J:17 serial_:361 2018-09-19T20:39:07.593Z,1537389547.593 [BPC1](INFO): BPC1A: Serial:305J:18 serial_:344 2018-09-19T20:39:07.594Z,1537389547.594 [BPC1](INFO): BPC1A: Serial:305J:19 serial_:354 2018-09-19T20:39:07.595Z,1537389547.595 [BPC1](INFO): BPC1A: Serial:305J:20 serial_:336 2018-09-19T20:39:07.595Z,1537389547.595 [BPC1](INFO): BPC1A: Serial:305J:21 serial_:351 2018-09-19T20:39:07.596Z,1537389547.596 [BPC1](INFO): BPC1A: Serial:305J:22 serial_:369 2018-09-19T20:39:07.596Z,1537389547.596 [BPC1](INFO): BPC1A: Serial:305J:23 serial_:323 2018-09-19T20:39:07.597Z,1537389547.597 [BPC1](INFO): BPC1A: Serial:305J:24 serial_:324 2018-09-19T20:39:07.598Z,1537389547.598 [BPC1](INFO): BPC1A: Serial:305J:25 serial_:362 2018-09-19T20:39:07.619Z,1537389547.619 [BPC1](INFO): BPC1A: Serial:305J:26 serial_:341 2018-09-19T20:39:07.620Z,1537389547.620 [BPC1](INFO): BPC1A: Serial:305J:27 serial_:337 2018-09-19T20:39:07.620Z,1537389547.620 [BPC1](INFO): BPC1A: Serial:305J:28 serial_:340 2018-09-19T20:39:07.621Z,1537389547.621 [BPC1](INFO): BPC1A: Serial:305J:29 serial_:353 2018-09-19T20:39:07.621Z,1537389547.621 [BPC1](INFO): BPC1A: Serial:305J:30 serial_:312 2018-09-19T20:39:07.622Z,1537389547.622 [BPC1](ERROR): BPC1A: No match for stick with serial number:0131 in the onboard configuration file. 2018-09-19T20:39:07.622Z,1537389547.622 [BPC1](INFO): BPC1B valid message: 1824 bytes. 2018-09-19T20:39:07.623Z,1537389547.623 [BPC1](INFO): BPC1B: Serial:301J:0 serial_:357 2018-09-19T20:39:07.624Z,1537389547.624 [BPC1](INFO): BPC1B: Serial:301J:1 serial_:309 2018-09-19T20:39:07.625Z,1537389547.625 [BPC1](INFO): BPC1B: Serial:301J:2 serial_:329 2018-09-19T20:39:07.625Z,1537389547.625 [BPC1](INFO): BPC1B: Serial:301J:3 serial_:326 2018-09-19T20:39:07.626Z,1537389547.626 [BPC1](INFO): BPC1B: Serial:301J:4 serial_:317 2018-09-19T20:39:07.627Z,1537389547.627 [BPC1](INFO): BPC1B: Serial:301J:5 serial_:333 2018-09-19T20:39:07.627Z,1537389547.627 [BPC1](INFO): BPC1B: Serial:301J:6 serial_:342 2018-09-19T20:39:07.628Z,1537389547.628 [BPC1](INFO): BPC1B: Serial:301J:7 serial_:307 2018-09-19T20:39:07.628Z,1537389547.628 [BPC1](INFO): BPC1B: Serial:301J:8 serial_:305 2018-09-19T20:39:07.629Z,1537389547.629 [BPC1](INFO): BPC1B: Serial:301J:9 serial_:325 2018-09-19T20:39:07.629Z,1537389547.629 [BPC1](INFO): BPC1B: Serial:301J:10 serial_:330 2018-09-19T20:39:07.654Z,1537389547.654 [BPC1](INFO): BPC1B: Serial:301J:11 serial_:316 2018-09-19T20:39:07.655Z,1537389547.655 [BPC1](INFO): BPC1B: Serial:301J:12 serial_:315 2018-09-19T20:39:07.656Z,1537389547.656 [BPC1](INFO): BPC1B: Serial:301J:13 serial_:306 2018-09-19T20:39:07.656Z,1537389547.656 [BPC1](INFO): BPC1B: Serial:301J:14 serial_:308 2018-09-19T20:39:07.657Z,1537389547.657 [BPC1](INFO): BPC1B: Serial:301J:15 serial_:339 2018-09-19T20:39:07.657Z,1537389547.657 [BPC1](INFO): BPC1B: Serial:301J:16 serial_:331 2018-09-19T20:39:07.658Z,1537389547.658 [BPC1](INFO): BPC1B: Serial:301J:17 serial_:314 2018-09-19T20:39:07.659Z,1537389547.659 [BPC1](INFO): BPC1B: Serial:301J:18 serial_:310 2018-09-19T20:39:07.659Z,1537389547.659 [BPC1](INFO): BPC1B: Serial:301J:19 serial_:335 2018-09-19T20:39:07.660Z,1537389547.660 [BPC1](INFO): BPC1B: Serial:301J:20 serial_:334 2018-09-19T20:39:07.660Z,1537389547.660 [BPC1](INFO): BPC1B: Serial:301J:21 serial_:319 2018-09-19T20:39:07.661Z,1537389547.661 [BPC1](INFO): BPC1B: Serial:301J:22 serial_:338 2018-09-19T20:39:07.662Z,1537389547.662 [BPC1](INFO): BPC1B: Serial:301J:23 serial_:332 2018-09-19T20:39:07.662Z,1537389547.662 [BPC1](INFO): BPC1B: Serial:301J:24 serial_:363 2018-09-19T20:39:07.663Z,1537389547.663 [BPC1](INFO): BPC1B: Serial:301J:25 serial_:321 2018-09-19T20:39:07.663Z,1537389547.663 [BPC1](INFO): BPC1B: Serial:301J:26 serial_:313 2018-09-19T20:39:07.664Z,1537389547.664 [BPC1](INFO): BPC1B: Serial:301J:27 serial_:311 2018-09-19T20:39:07.665Z,1537389547.665 [BPC1](INFO): BPC1B: Serial:301J:28 serial_:322 2018-09-19T20:39:07.665Z,1537389547.665 [BPC1](INFO): BPC1B: Serial:301J:29 serial_:318 2018-09-19T20:39:07.666Z,1537389547.666 [BPC1](INFO): BPC1B: Serial:301J:30 serial_:327 2018-09-19T20:39:07.680Z,1537389547.680 [BPC1](ERROR): BPC1B: No match for stick with serial number:012D in the onboard configuration file. 2018-09-19T20:39:07.680Z,1537389547.680 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-09-19T20:39:07.681Z,1537389547.681 [BPC1] Data Fault, FailCount= 1 2018-09-19T20:39:07.681Z,1537389547.681 [BPC1](ERROR): Data Fault 2018-09-19T20:39:07.729Z,1537389547.729 [CBIT](ERROR): Data Fault in component: BPC1 2018-09-19T20:39:12.038Z,1537389552.038 [SBIT](IMPORTANT): SBIT PASSED 2018-09-19T20:39:12.084Z,1537389552.084 [CommandLine](IMPORTANT): got command configSet list 2018-09-19T20:39:12.084Z,1537389552.084 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-09-19T20:39:12.085Z,1537389552.085 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter; 2018-09-19T20:39:12.085Z,1537389552.085 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter; 2018-09-19T20:39:12.403Z,1537389552.403 [MissionManager](IMPORTANT): Started mission Startup 2018-09-19T20:39:12.403Z,1537389552.403 [Startup] Running Loop=1 2018-09-19T20:39:12.403Z,1537389552.403 [Startup](DEBUG): Aggregate::initialize Startup 2018-09-19T20:39:12.403Z,1537389552.403 [Startup:A.GoToSurface] Running Loop=1 2018-09-19T20:39:12.403Z,1537389552.403 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-09-19T20:39:12.404Z,1537389552.404 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-09-19T20:39:12.404Z,1537389552.404 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-09-19T20:39:12.405Z,1537389552.405 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-09-19T20:39:12.405Z,1537389552.405 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-09-19T20:39:12.406Z,1537389552.406 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-09-19T20:39:12.456Z,1537389552.456 [Startup:StartupSatComms] Running Loop=1 2018-09-19T20:39:12.456Z,1537389552.456 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-09-19T20:39:12.456Z,1537389552.456 [Startup:StartupSatComms:A] Running Loop=1 2018-09-19T20:39:12.779Z,1537389552.779 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-09-19T20:40:12.779Z,1537389612.779 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-19T20:39:12.5Z 2018-09-19T20:40:12.779Z,1537389612.779 [Startup:StartupSatComms:A] Stopped 2018-09-19T20:40:12.779Z,1537389612.779 [Startup:StartupSatComms:B] Running Loop=1 2018-09-19T20:40:13.188Z,1537389613.188 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-09-19T20:40:53.576Z,1537389653.576 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:40:53.576Z,1537389653.576 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2018-09-19T20:40:53.577Z,1537389653.577 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T20:40:53.583Z,1537389653.583 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:40:53.585Z,1537389653.585 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-09-19T20:40:53.585Z,1537389653.585 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T20:40:53.623Z,1537389653.623 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T20:40:53.623Z,1537389653.623 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T20:40:53.996Z,1537389653.996 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T20:40:53.996Z,1537389653.996 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2018-09-19T20:40:53.996Z,1537389653.996 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T20:40:53.996Z,1537389653.996 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-09-19T20:40:54.382Z,1537389654.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T20:40:54.382Z,1537389654.382 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:40:54.383Z,1537389654.383 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:40:54.383Z,1537389654.383 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T20:40:54.383Z,1537389654.383 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:40:54.384Z,1537389654.384 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:40:58.864Z,1537389658.864 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003956 2018-09-19T20:40:59.331Z,1537389659.331 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-09-19T20:41:02.939Z,1537389662.939 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20180919T202920/Courier0000.lzma 2018-09-19T20:41:03.709Z,1537389663.709 [DataOverHttps](INFO): Moved sent file to Logs/20180919T202920/Courier0000.lzma.bak 2018-09-19T20:41:03.709Z,1537389663.709 [DataOverHttps](INFO): SBD MOMSN=8539807 2018-09-19T20:41:12.723Z,1537389672.723 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T202920/Courier0004.lzma 2018-09-19T20:41:12.781Z,1537389672.781 [Startup:StartupSatComms:B](INFO): Timed out from 2018-09-19T20:40:12.8Z 2018-09-19T20:41:12.781Z,1537389672.781 [Startup:StartupSatComms:B] Stopped 2018-09-19T20:41:12.781Z,1537389672.781 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-09-19T20:41:12.782Z,1537389672.782 [Startup:StartupSatComms] Stopped 2018-09-19T20:41:12.782Z,1537389672.782 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-09-19T20:41:12.791Z,1537389672.791 [Startup](INFO): Completed Startup 2018-09-19T20:41:12.791Z,1537389672.791 [MissionManager](INFO): Startup is completed. 2018-09-19T20:41:12.792Z,1537389672.792 [MissionManager](INFO): Uninitializing Mission Startup 2018-09-19T20:41:12.792Z,1537389672.792 [Startup] Stopped 2018-09-19T20:41:12.792Z,1537389672.792 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-09-19T20:41:12.792Z,1537389672.792 [Startup:A.GoToSurface] Stopped 2018-09-19T20:41:12.792Z,1537389672.792 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-09-19T20:41:13.132Z,1537389673.132 [MissionManager](IMPORTANT): Started mission Default 2018-09-19T20:41:13.132Z,1537389673.132 [Default] Running Loop=1 2018-09-19T20:41:13.132Z,1537389673.132 [Default](DEBUG): Aggregate::initialize Default 2018-09-19T20:41:13.132Z,1537389673.132 [Default:B.GoToSurface] Running Loop=1 2018-09-19T20:41:13.132Z,1537389673.132 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-09-19T20:41:13.133Z,1537389673.133 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-09-19T20:41:13.133Z,1537389673.133 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-09-19T20:41:13.133Z,1537389673.133 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-09-19T20:41:13.134Z,1537389673.134 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-09-19T20:41:13.138Z,1537389673.138 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-09-19T20:41:13.139Z,1537389673.139 [Default:A.Wait] Running Loop=1 2018-09-19T20:41:13.139Z,1537389673.139 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-09-19T20:41:13.437Z,1537389673.437 [DataOverHttps](INFO): Moved sent file to Logs/20180919T202920/Courier0004.lzma.bak 2018-09-19T20:41:13.437Z,1537389673.437 [DataOverHttps](INFO): SBD MOMSN=8539812 2018-09-19T20:41:26.373Z,1537389686.373 [Default:A.Wait](INFO): Done Waiting. 2018-09-19T20:41:26.373Z,1537389686.373 [Default:A.Wait] Stopped 2018-09-19T20:41:26.373Z,1537389686.373 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-09-19T20:41:26.770Z,1537389686.770 [Default:CheckIn] Running Loop=1 2018-09-19T20:41:26.771Z,1537389686.771 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-19T20:41:26.771Z,1537389686.771 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-19T20:41:27.165Z,1537389687.165 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-09-19T20:43:35.288Z,1537389815.288 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-09-19T20:43:54.565Z,1537389834.565 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:43:54.565Z,1537389834.565 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2018-09-19T20:43:54.565Z,1537389834.565 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T20:43:54.567Z,1537389834.567 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:43:54.567Z,1537389834.567 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-09-19T20:43:54.568Z,1537389834.568 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T20:43:54.580Z,1537389834.580 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T20:43:54.581Z,1537389834.581 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T20:43:54.949Z,1537389834.949 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T20:43:54.949Z,1537389834.949 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2018-09-19T20:43:54.950Z,1537389834.950 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T20:43:54.950Z,1537389834.950 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-09-19T20:43:55.340Z,1537389835.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T20:43:55.341Z,1537389835.341 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:43:55.347Z,1537389835.347 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:43:55.347Z,1537389835.347 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T20:43:55.348Z,1537389835.348 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:43:55.349Z,1537389835.349 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:44:44.087Z,1537389884.087 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-19T20:45:44.884Z,1537389944.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-19T20:46:18.485Z,1537389978.485 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.000687 2018-09-19T20:46:26.941Z,1537389986.941 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-19T20:41:26.8Z 2018-09-19T20:46:26.941Z,1537389986.941 [Default:CheckIn:Read_GPS] Stopped 2018-09-19T20:46:26.941Z,1537389986.941 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-19T20:46:27.356Z,1537389987.356 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-09-19T20:46:55.367Z,1537390015.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:46:55.368Z,1537390015.368 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2018-09-19T20:46:55.368Z,1537390015.368 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T20:46:55.370Z,1537390015.370 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:46:55.370Z,1537390015.370 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-09-19T20:46:55.374Z,1537390015.374 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T20:46:55.400Z,1537390015.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T20:46:55.400Z,1537390015.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T20:46:55.779Z,1537390015.779 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T20:46:55.779Z,1537390015.779 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2018-09-19T20:46:55.780Z,1537390015.780 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T20:46:55.780Z,1537390015.780 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-09-19T20:46:56.124Z,1537390016.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T20:46:56.124Z,1537390016.124 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:46:56.125Z,1537390016.125 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:46:56.125Z,1537390016.125 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T20:46:56.126Z,1537390016.126 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:46:56.126Z,1537390016.126 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:47:28.160Z,1537390048.160 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003037 2018-09-19T20:47:32.269Z,1537390052.269 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20180919T203411/Courier0000.lzma 2018-09-19T20:47:33.026Z,1537390053.026 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203411/Courier0000.lzma.bak 2018-09-19T20:47:33.030Z,1537390053.030 [DataOverHttps](INFO): SBD MOMSN=8539844 2018-09-19T20:47:41.979Z,1537390061.979 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T203411/Courier0004.lzma 2018-09-19T20:47:42.761Z,1537390062.761 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203411/Courier0004.lzma.bak 2018-09-19T20:47:42.761Z,1537390062.761 [DataOverHttps](INFO): SBD MOMSN=8539849 2018-09-19T20:47:51.721Z,1537390071.721 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20180919T203743/Courier0000.lzma 2018-09-19T20:47:52.481Z,1537390072.481 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Courier0000.lzma.bak 2018-09-19T20:47:52.481Z,1537390072.481 [DataOverHttps](INFO): SBD MOMSN=8539851 2018-09-19T20:48:01.445Z,1537390081.445 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T203743/Courier0004.lzma 2018-09-19T20:48:02.209Z,1537390082.209 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Courier0004.lzma.bak 2018-09-19T20:48:02.210Z,1537390082.210 [DataOverHttps](INFO): SBD MOMSN=8539856 2018-09-19T20:48:11.174Z,1537390091.174 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20180919T201414/Express0008.lzma 2018-09-19T20:48:11.929Z,1537390091.929 [DataOverHttps](INFO): Moved sent file to Logs/20180919T201414/Express0008.lzma.bak 2018-09-19T20:48:11.929Z,1537390091.929 [DataOverHttps](INFO): SBD MOMSN=8539858 2018-09-19T20:48:20.864Z,1537390100.864 [DataOverHttps](INFO): Sending 790 bytes from file Logs/20180919T202635/Express0001.lzma 2018-09-19T20:48:21.632Z,1537390101.632 [DataOverHttps](INFO): Moved sent file to Logs/20180919T202635/Express0001.lzma.bak 2018-09-19T20:48:21.632Z,1537390101.632 [DataOverHttps](INFO): SBD MOMSN=8539862 2018-09-19T20:48:30.578Z,1537390110.578 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20180919T202635/Express0005.lzma 2018-09-19T20:48:31.333Z,1537390111.333 [DataOverHttps](INFO): Moved sent file to Logs/20180919T202635/Express0005.lzma.bak 2018-09-19T20:48:31.333Z,1537390111.333 [DataOverHttps](INFO): SBD MOMSN=8539880 2018-09-19T20:48:35.808Z,1537390115.808 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-09-19T20:48:35.808Z,1537390115.808 [NAL9602] Data Fault, FailCount= 1 2018-09-19T20:48:35.808Z,1537390115.808 [NAL9602](ERROR): Data Fault 2018-09-19T20:48:35.859Z,1537390115.859 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-19T20:48:36.208Z,1537390116.208 [NAL9602](INFO): Powering down 2018-09-19T20:48:36.621Z,1537390116.621 [RDI_Pathfinder](ERROR): Failed to parse: 2018-09-19T20:48:37.090Z,1537390117.090 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-19T20:48:37.090Z,1537390117.090 [NAL9602] No Fault, FailCount= 1 2018-09-19T20:48:45.152Z,1537390125.152 [DataOverHttps](INFO): Sending 790 bytes from file Logs/20180919T202920/Express0001.lzma 2018-09-19T20:48:45.925Z,1537390125.925 [DataOverHttps](INFO): Moved sent file to Logs/20180919T202920/Express0001.lzma.bak 2018-09-19T20:48:45.925Z,1537390125.925 [DataOverHttps](INFO): SBD MOMSN=8539889 2018-09-19T20:48:54.893Z,1537390134.893 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20180919T202920/Express0005.lzma 2018-09-19T20:48:55.665Z,1537390135.665 [DataOverHttps](INFO): Moved sent file to Logs/20180919T202920/Express0005.lzma.bak 2018-09-19T20:48:55.665Z,1537390135.665 [DataOverHttps](INFO): SBD MOMSN=8539907 2018-09-19T20:49:04.705Z,1537390144.705 [DataOverHttps](INFO): Sending 790 bytes from file Logs/20180919T203411/Express0001.lzma 2018-09-19T20:49:05.477Z,1537390145.477 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203411/Express0001.lzma.bak 2018-09-19T20:49:05.477Z,1537390145.477 [DataOverHttps](INFO): SBD MOMSN=8539911 2018-09-19T20:49:06.288Z,1537390146.288 [NAL9602](INFO): Powering up NAL9602 2018-09-19T20:49:09.652Z,1537390149.652 [CBIT](INFO): Clearing failed state for component BPC1 2018-09-19T20:49:09.653Z,1537390149.653 [BPC1] No Fault, FailCount= 1 2018-09-19T20:49:14.451Z,1537390154.451 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20180919T203411/Express0005.lzma 2018-09-19T20:49:15.230Z,1537390155.230 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203411/Express0005.lzma.bak 2018-09-19T20:49:15.230Z,1537390155.230 [DataOverHttps](INFO): SBD MOMSN=8539929 2018-09-19T20:49:19.728Z,1537390159.728 [NAL9602](INFO): NAL9602 initialized 2018-09-19T20:49:24.235Z,1537390164.235 [DataOverHttps](INFO): Sending 786 bytes from file Logs/20180919T203743/Express0001.lzma 2018-09-19T20:49:25.009Z,1537390165.009 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Express0001.lzma.bak 2018-09-19T20:49:25.009Z,1537390165.009 [DataOverHttps](INFO): SBD MOMSN=8539932 2018-09-19T20:49:34.026Z,1537390174.026 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180919T203743/Express0005.lzma 2018-09-19T20:49:34.789Z,1537390174.789 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Express0005.lzma.bak 2018-09-19T20:49:34.789Z,1537390174.789 [DataOverHttps](INFO): SBD MOMSN=8539950 2018-09-19T20:49:35.319Z,1537390175.319 [Default:CheckIn:Read_Iridium] Stopped 2018-09-19T20:49:35.319Z,1537390175.319 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-19T20:49:35.319Z,1537390175.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-19T20:49:56.148Z,1537390196.148 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:49:56.149Z,1537390196.149 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2018-09-19T20:49:56.149Z,1537390196.149 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T20:49:56.167Z,1537390196.167 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:49:56.167Z,1537390196.167 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-09-19T20:49:56.167Z,1537390196.167 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T20:49:56.198Z,1537390196.198 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T20:49:56.198Z,1537390196.198 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T20:49:56.590Z,1537390196.590 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T20:49:56.590Z,1537390196.590 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2018-09-19T20:49:56.590Z,1537390196.590 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T20:49:56.590Z,1537390196.590 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-09-19T20:49:56.972Z,1537390196.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T20:49:56.972Z,1537390196.972 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:49:56.973Z,1537390196.973 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:49:56.974Z,1537390196.974 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T20:49:56.975Z,1537390196.975 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:49:56.976Z,1537390196.976 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:50:10.971Z,1537390210.971 [BPC1](INFO): BPC1A: initialized. 2018-09-19T20:50:10.983Z,1537390210.983 [BPC1](INFO): BPC1B: initialized. 2018-09-19T20:50:12.548Z,1537390212.548 [BPC1](INFO): BPC1A: sending IBPS break. 2018-09-19T20:50:12.549Z,1537390212.549 [BPC1](INFO): BPC1B: sending IBPS break. 2018-09-19T20:50:16.549Z,1537390216.549 [BPC1](INFO): BPC1A: validating data format. 2018-09-19T20:50:16.589Z,1537390216.589 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU. 2018-09-19T20:50:16.953Z,1537390216.953 [BPC1](INFO): BPC1A valid message: 1824 bytes. 2018-09-19T20:50:16.955Z,1537390216.955 [BPC1](INFO): BPC1A: Serial:357J:0 serial_:320 2018-09-19T20:50:16.956Z,1537390216.956 [BPC1](INFO): BPC1A: Serial:357J:1 serial_:355 2018-09-19T20:50:16.957Z,1537390216.957 [BPC1](INFO): BPC1A: Serial:357J:2 serial_:343 2018-09-19T20:50:16.966Z,1537390216.966 [BPC1](INFO): BPC1A: Serial:357J:3 serial_:345 2018-09-19T20:50:16.967Z,1537390216.967 [BPC1](INFO): BPC1A: Serial:357J:4 serial_:366 2018-09-19T20:50:16.968Z,1537390216.968 [BPC1](INFO): BPC1A: Serial:357J:5 serial_:352 2018-09-19T20:50:16.968Z,1537390216.968 [BPC1](INFO): BPC1A: Serial:357J:6 serial_:348 2018-09-19T20:50:16.969Z,1537390216.969 [BPC1](INFO): BPC1A: Serial:357J:7 serial_:365 2018-09-19T20:50:16.969Z,1537390216.969 [BPC1](INFO): BPC1A: Serial:357J:8 serial_:301 2018-09-19T20:50:16.970Z,1537390216.970 [BPC1](INFO): BPC1A: Serial:357J:9 serial_:350 2018-09-19T20:50:16.971Z,1537390216.971 [BPC1](INFO): BPC1A: Serial:357J:10 serial_:356 2018-09-19T20:50:16.971Z,1537390216.971 [BPC1](INFO): BPC1A: Serial:357J:11 serial_:347 2018-09-19T20:50:16.972Z,1537390216.972 [BPC1](INFO): BPC1A: Serial:357J:12 serial_:370 2018-09-19T20:50:16.973Z,1537390216.973 [BPC1](INFO): BPC1A: Serial:357J:13 serial_:358 2018-09-19T20:50:16.974Z,1537390216.974 [BPC1](INFO): BPC1A: Serial:357J:14 serial_:368 2018-09-19T20:50:16.987Z,1537390216.987 [BPC1](INFO): BPC1A: Serial:357J:15 serial_:328 2018-09-19T20:50:16.988Z,1537390216.988 [BPC1](INFO): BPC1A: Serial:357J:16 serial_:304 2018-09-19T20:50:16.990Z,1537390216.990 [BPC1](INFO): BPC1A: Serial:357J:17 serial_:361 2018-09-19T20:50:16.991Z,1537390216.991 [BPC1](INFO): BPC1A: Serial:357J:18 serial_:344 2018-09-19T20:50:16.992Z,1537390216.992 [BPC1](INFO): BPC1A: Serial:357J:19 serial_:354 2018-09-19T20:50:16.993Z,1537390216.993 [BPC1](INFO): BPC1A: Serial:357J:20 serial_:336 2018-09-19T20:50:16.995Z,1537390216.995 [BPC1](INFO): BPC1A: Serial:357J:21 serial_:351 2018-09-19T20:50:16.996Z,1537390216.996 [BPC1](INFO): BPC1A: Serial:357J:22 serial_:369 2018-09-19T20:50:16.997Z,1537390216.997 [BPC1](INFO): BPC1A: Serial:357J:23 serial_:323 2018-09-19T20:50:16.998Z,1537390216.998 [BPC1](INFO): BPC1A: Serial:357J:24 serial_:324 2018-09-19T20:50:17.019Z,1537390217.019 [BPC1](INFO): BPC1A: Serial:357J:25 serial_:362 2018-09-19T20:50:17.020Z,1537390217.020 [BPC1](INFO): BPC1A: Serial:357J:26 serial_:341 2018-09-19T20:50:17.020Z,1537390217.020 [BPC1](INFO): BPC1A: Serial:357J:27 serial_:337 2018-09-19T20:50:17.021Z,1537390217.021 [BPC1](INFO): BPC1A: Serial:357J:28 serial_:340 2018-09-19T20:50:17.022Z,1537390217.022 [BPC1](INFO): BPC1A: Serial:357J:29 serial_:353 2018-09-19T20:50:17.022Z,1537390217.022 [BPC1](INFO): BPC1A: Serial:357J:30 serial_:312 2018-09-19T20:50:17.023Z,1537390217.023 [BPC1](ERROR): BPC1A: No match for stick with serial number:0165 in the onboard configuration file. 2018-09-19T20:50:17.023Z,1537390217.023 [BPC1](INFO): BPC1B: validating data format. 2018-09-19T20:50:17.092Z,1537390217.092 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU. 2018-09-19T20:50:17.383Z,1537390217.383 [BPC1](INFO): BPC1B valid message: 1824 bytes. 2018-09-19T20:50:17.384Z,1537390217.384 [BPC1](INFO): BPC1B: Serial:320J:0 serial_:357 2018-09-19T20:50:17.384Z,1537390217.384 [BPC1](INFO): BPC1B: Serial:320J:1 serial_:309 2018-09-19T20:50:17.385Z,1537390217.385 [BPC1](INFO): BPC1B: Serial:320J:2 serial_:329 2018-09-19T20:50:17.386Z,1537390217.386 [BPC1](INFO): BPC1B: Serial:320J:3 serial_:326 2018-09-19T20:50:17.390Z,1537390217.390 [BPC1](INFO): BPC1B: Serial:320J:4 serial_:317 2018-09-19T20:50:17.391Z,1537390217.391 [BPC1](INFO): BPC1B: Serial:320J:5 serial_:333 2018-09-19T20:50:17.392Z,1537390217.392 [BPC1](INFO): BPC1B: Serial:320J:6 serial_:342 2018-09-19T20:50:17.392Z,1537390217.392 [BPC1](INFO): BPC1B: Serial:320J:7 serial_:307 2018-09-19T20:50:17.393Z,1537390217.393 [BPC1](INFO): BPC1B: Serial:320J:8 serial_:305 2018-09-19T20:50:17.393Z,1537390217.393 [BPC1](INFO): BPC1B: Serial:320J:9 serial_:325 2018-09-19T20:50:17.398Z,1537390217.398 [BPC1](INFO): BPC1B: Serial:320J:10 serial_:330 2018-09-19T20:50:17.399Z,1537390217.399 [BPC1](INFO): BPC1B: Serial:320J:11 serial_:316 2018-09-19T20:50:17.400Z,1537390217.400 [BPC1](INFO): BPC1B: Serial:320J:12 serial_:315 2018-09-19T20:50:17.400Z,1537390217.400 [BPC1](INFO): BPC1B: Serial:320J:13 serial_:306 2018-09-19T20:50:17.401Z,1537390217.401 [BPC1](INFO): BPC1B: Serial:320J:14 serial_:308 2018-09-19T20:50:17.401Z,1537390217.401 [BPC1](INFO): BPC1B: Serial:320J:15 serial_:339 2018-09-19T20:50:17.402Z,1537390217.402 [BPC1](INFO): BPC1B: Serial:320J:16 serial_:331 2018-09-19T20:50:17.407Z,1537390217.407 [BPC1](INFO): BPC1B: Serial:320J:17 serial_:314 2018-09-19T20:50:17.408Z,1537390217.408 [BPC1](INFO): BPC1B: Serial:320J:18 serial_:310 2018-09-19T20:50:17.408Z,1537390217.408 [BPC1](INFO): BPC1B: Serial:320J:19 serial_:335 2018-09-19T20:50:17.409Z,1537390217.409 [BPC1](INFO): BPC1B: Serial:320J:20 serial_:334 2018-09-19T20:50:17.409Z,1537390217.409 [BPC1](INFO): BPC1B: Serial:320J:21 serial_:319 2018-09-19T20:50:17.414Z,1537390217.414 [BPC1](INFO): BPC1B: Serial:320J:22 serial_:338 2018-09-19T20:50:17.415Z,1537390217.415 [BPC1](INFO): BPC1B: Serial:320J:23 serial_:332 2018-09-19T20:50:17.416Z,1537390217.416 [BPC1](INFO): BPC1B: Serial:320J:24 serial_:363 2018-09-19T20:50:17.416Z,1537390217.416 [BPC1](INFO): BPC1B: Serial:320J:25 serial_:321 2018-09-19T20:50:17.417Z,1537390217.417 [BPC1](INFO): BPC1B: Serial:320J:26 serial_:313 2018-09-19T20:50:17.417Z,1537390217.417 [BPC1](INFO): BPC1B: Serial:320J:27 serial_:311 2018-09-19T20:50:17.418Z,1537390217.418 [BPC1](INFO): BPC1B: Serial:320J:28 serial_:322 2018-09-19T20:50:17.423Z,1537390217.423 [BPC1](INFO): BPC1B: Serial:320J:29 serial_:318 2018-09-19T20:50:17.423Z,1537390217.423 [BPC1](INFO): BPC1B: Serial:320J:30 serial_:327 2018-09-19T20:50:17.424Z,1537390217.424 [BPC1](ERROR): BPC1B: No match for stick with serial number:0140 in the onboard configuration file. 2018-09-19T20:50:17.424Z,1537390217.424 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-09-19T20:50:17.424Z,1537390217.424 [BPC1] Data Fault, FailCount= 2 2018-09-19T20:50:17.424Z,1537390217.424 [BPC1](ERROR): Data Fault 2018-09-19T20:50:17.483Z,1537390217.483 [CBIT](ERROR): Data Fault in component: BPC1 2018-09-19T20:52:16.372Z,1537390336.372 [RDI_Pathfinder](ERROR): Failed to parse: +0.00, +0.00, +0.00, 0.00,848.97 2018-09-19T20:52:54.859Z,1537390374.859 [CBIT](INFO): Clearing failed state for component DropWeight 2018-09-19T20:52:54.859Z,1537390374.859 [DropWeight] No Fault, FailCount= 1 2018-09-19T20:52:57.237Z,1537390377.237 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:52:57.237Z,1537390377.237 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2018-09-19T20:52:57.238Z,1537390377.238 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T20:52:57.240Z,1537390377.240 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:52:57.240Z,1537390377.240 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-09-19T20:52:57.240Z,1537390377.240 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T20:52:57.253Z,1537390377.253 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T20:52:57.254Z,1537390377.254 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T20:52:57.639Z,1537390377.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T20:52:57.639Z,1537390377.639 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2018-09-19T20:52:57.639Z,1537390377.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T20:52:57.640Z,1537390377.640 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-09-19T20:52:58.044Z,1537390378.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T20:52:58.044Z,1537390378.044 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:52:58.045Z,1537390378.045 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:52:58.045Z,1537390378.045 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T20:52:58.046Z,1537390378.046 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:52:58.046Z,1537390378.046 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:54:35.778Z,1537390475.778 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-19T20:54:35.778Z,1537390475.778 [Default:CheckIn:C.Wait] Stopped 2018-09-19T20:54:35.778Z,1537390475.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-19T20:54:35.779Z,1537390475.779 [Default:CheckIn:D] Running Loop=1 2018-09-19T20:54:36.160Z,1537390476.160 [Default:CheckIn:D] Stopped 2018-09-19T20:54:36.160Z,1537390476.160 [Default:CheckIn:E] Running Loop=1 2018-09-19T20:54:39.357Z,1537390479.357 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.383765 min 2018-09-19T20:54:39.359Z,1537390479.359 [Default:CheckIn:E] Stopped 2018-09-19T20:54:39.359Z,1537390479.359 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-19T20:54:39.359Z,1537390479.359 [Default:CheckIn] Stopped 2018-09-19T20:54:39.359Z,1537390479.359 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-19T20:54:39.359Z,1537390479.359 [Default:CheckIn](INFO): Running loop #2 2018-09-19T20:54:39.360Z,1537390479.360 [Default:CheckIn] Running Loop=2 2018-09-19T20:54:39.360Z,1537390479.360 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-19T20:54:39.360Z,1537390479.360 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-19T20:55:58.388Z,1537390558.388 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:55:58.388Z,1537390558.388 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2018-09-19T20:55:58.388Z,1537390558.388 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T20:55:58.390Z,1537390558.390 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:55:58.390Z,1537390558.390 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2018-09-19T20:55:58.390Z,1537390558.390 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T20:55:58.415Z,1537390558.415 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T20:55:58.415Z,1537390558.415 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T20:55:58.800Z,1537390558.800 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T20:55:58.800Z,1537390558.800 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2018-09-19T20:55:58.800Z,1537390558.800 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T20:55:58.800Z,1537390558.800 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2018-09-19T20:55:59.177Z,1537390559.177 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T20:55:59.178Z,1537390559.178 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:55:59.178Z,1537390559.178 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:55:59.179Z,1537390559.179 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T20:55:59.179Z,1537390559.179 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:55:59.180Z,1537390559.180 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:56:30.367Z,1537390590.367 [RDI_Pathfinder](ERROR): only read 3 of 5 data items 2018-09-19T20:56:30.367Z,1537390590.367 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-3276,-32768,V 2018-09-19T20:58:59.432Z,1537390739.432 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:58:59.432Z,1537390739.432 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2018-09-19T20:58:59.432Z,1537390739.432 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T20:58:59.435Z,1537390739.435 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T20:58:59.435Z,1537390739.435 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2018-09-19T20:58:59.435Z,1537390739.435 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T20:58:59.472Z,1537390739.472 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T20:58:59.472Z,1537390739.472 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T20:58:59.841Z,1537390739.841 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T20:58:59.841Z,1537390739.841 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2018-09-19T20:58:59.842Z,1537390739.842 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T20:58:59.842Z,1537390739.842 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2018-09-19T20:59:00.241Z,1537390740.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T20:59:00.241Z,1537390740.241 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:59:00.242Z,1537390740.242 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:59:00.242Z,1537390740.242 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T20:59:00.243Z,1537390740.243 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T20:59:00.243Z,1537390740.243 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T20:59:21.000Z,1537390761.000 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-09-19T20:59:21.000Z,1537390761.000 [NAL9602] Data Fault, FailCount= 2 2018-09-19T20:59:21.000Z,1537390761.000 [NAL9602](ERROR): Data Fault 2018-09-19T20:59:21.088Z,1537390761.088 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-19T20:59:21.392Z,1537390761.392 [NAL9602](INFO): Powering down 2018-09-19T20:59:22.258Z,1537390762.258 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-19T20:59:22.258Z,1537390762.258 [NAL9602] No Fault, FailCount= 2 2018-09-19T20:59:39.808Z,1537390779.808 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-19T20:54:39.4Z 2018-09-19T20:59:39.808Z,1537390779.808 [Default:CheckIn:Read_GPS] Stopped 2018-09-19T20:59:39.808Z,1537390779.808 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-19T20:59:44.741Z,1537390784.741 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T203743/Courier0007.lzma 2018-09-19T20:59:45.497Z,1537390785.497 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Courier0007.lzma.bak 2018-09-19T20:59:45.498Z,1537390785.498 [DataOverHttps](INFO): SBD MOMSN=8539999 2018-09-19T20:59:54.354Z,1537390794.354 [NAL9602](INFO): Powering up NAL9602 2018-09-19T20:59:54.487Z,1537390794.487 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20180919T203743/Express0008.lzma 2018-09-19T20:59:55.261Z,1537390795.261 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Express0008.lzma.bak 2018-09-19T20:59:55.261Z,1537390795.261 [DataOverHttps](INFO): SBD MOMSN=8540001 2018-09-19T20:59:55.819Z,1537390795.819 [Default:CheckIn:Read_Iridium] Stopped 2018-09-19T20:59:55.819Z,1537390795.819 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-19T20:59:55.819Z,1537390795.819 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-19T21:00:05.352Z,1537390805.352 [NAL9602](INFO): NAL9602 initialized 2018-09-19T21:00:18.225Z,1537390818.225 [CBIT](INFO): Clearing failed state for component BPC1 2018-09-19T21:00:18.225Z,1537390818.225 [BPC1] No Fault, FailCount= 2 2018-09-19T21:01:19.462Z,1537390879.462 [BPC1](INFO): BPC1A: initialized. 2018-09-19T21:01:19.478Z,1537390879.478 [BPC1](INFO): BPC1B: initialized. 2018-09-19T21:01:21.068Z,1537390881.068 [BPC1](INFO): BPC1A: sending IBPS break. 2018-09-19T21:01:21.068Z,1537390881.068 [BPC1](INFO): BPC1B: sending IBPS break. 2018-09-19T21:01:25.470Z,1537390885.470 [BPC1](INFO): BPC1A: validating data format. 2018-09-19T21:01:25.499Z,1537390885.499 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU. 2018-09-19T21:01:25.499Z,1537390885.499 [BPC1](INFO): BPC1B: validating data format. 2018-09-19T21:01:25.536Z,1537390885.536 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU. 2018-09-19T21:01:25.849Z,1537390885.849 [BPC1](INFO): BPC1A valid message: 1824 bytes. 2018-09-19T21:01:25.850Z,1537390885.850 [BPC1](INFO): BPC1A: Serial:357J:0 serial_:320 2018-09-19T21:01:25.850Z,1537390885.850 [BPC1](INFO): BPC1A: Serial:357J:1 serial_:355 2018-09-19T21:01:25.851Z,1537390885.851 [BPC1](INFO): BPC1A: Serial:357J:2 serial_:343 2018-09-19T21:01:25.852Z,1537390885.852 [BPC1](INFO): BPC1A: Serial:357J:3 serial_:345 2018-09-19T21:01:25.852Z,1537390885.852 [BPC1](INFO): BPC1A: Serial:357J:4 serial_:366 2018-09-19T21:01:25.853Z,1537390885.853 [BPC1](INFO): BPC1A: Serial:357J:5 serial_:352 2018-09-19T21:01:25.853Z,1537390885.853 [BPC1](INFO): BPC1A: Serial:357J:6 serial_:348 2018-09-19T21:01:25.854Z,1537390885.854 [BPC1](INFO): BPC1A: Serial:357J:7 serial_:365 2018-09-19T21:01:25.859Z,1537390885.859 [BPC1](INFO): BPC1A: Serial:357J:8 serial_:301 2018-09-19T21:01:25.859Z,1537390885.859 [BPC1](INFO): BPC1A: Serial:357J:9 serial_:350 2018-09-19T21:01:25.860Z,1537390885.860 [BPC1](INFO): BPC1A: Serial:357J:10 serial_:356 2018-09-19T21:01:25.861Z,1537390885.861 [BPC1](INFO): BPC1A: Serial:357J:11 serial_:347 2018-09-19T21:01:25.861Z,1537390885.861 [BPC1](INFO): BPC1A: Serial:357J:12 serial_:370 2018-09-19T21:01:25.862Z,1537390885.862 [BPC1](INFO): BPC1A: Serial:357J:13 serial_:358 2018-09-19T21:01:25.867Z,1537390885.867 [BPC1](INFO): BPC1A: Serial:357J:14 serial_:368 2018-09-19T21:01:25.867Z,1537390885.867 [BPC1](INFO): BPC1A: Serial:357J:15 serial_:328 2018-09-19T21:01:25.868Z,1537390885.868 [BPC1](INFO): BPC1A: Serial:357J:16 serial_:304 2018-09-19T21:01:25.868Z,1537390885.868 [BPC1](INFO): BPC1A: Serial:357J:17 serial_:361 2018-09-19T21:01:25.869Z,1537390885.869 [BPC1](INFO): BPC1A: Serial:357J:18 serial_:344 2018-09-19T21:01:25.870Z,1537390885.870 [BPC1](INFO): BPC1A: Serial:357J:19 serial_:354 2018-09-19T21:01:25.874Z,1537390885.874 [BPC1](INFO): BPC1A: Serial:357J:20 serial_:336 2018-09-19T21:01:25.875Z,1537390885.875 [BPC1](INFO): BPC1A: Serial:357J:21 serial_:351 2018-09-19T21:01:25.876Z,1537390885.876 [BPC1](INFO): BPC1A: Serial:357J:22 serial_:369 2018-09-19T21:01:25.876Z,1537390885.876 [BPC1](INFO): BPC1A: Serial:357J:23 serial_:323 2018-09-19T21:01:25.877Z,1537390885.877 [BPC1](INFO): BPC1A: Serial:357J:24 serial_:324 2018-09-19T21:01:25.877Z,1537390885.877 [BPC1](INFO): BPC1A: Serial:357J:25 serial_:362 2018-09-19T21:01:25.878Z,1537390885.878 [BPC1](INFO): BPC1A: Serial:357J:26 serial_:341 2018-09-19T21:01:25.879Z,1537390885.879 [BPC1](INFO): BPC1A: Serial:357J:27 serial_:337 2018-09-19T21:01:25.879Z,1537390885.879 [BPC1](INFO): BPC1A: Serial:357J:28 serial_:340 2018-09-19T21:01:25.880Z,1537390885.880 [BPC1](INFO): BPC1A: Serial:357J:29 serial_:353 2018-09-19T21:01:25.881Z,1537390885.881 [BPC1](INFO): BPC1A: Serial:357J:30 serial_:312 2018-09-19T21:01:25.881Z,1537390885.881 [BPC1](ERROR): BPC1A: No match for stick with serial number:0165 in the onboard configuration file. 2018-09-19T21:01:25.881Z,1537390885.881 [BPC1](INFO): BPC1B valid message: 1824 bytes. 2018-09-19T21:01:25.887Z,1537390885.887 [BPC1](INFO): BPC1B: Serial:320J:0 serial_:357 2018-09-19T21:01:25.887Z,1537390885.887 [BPC1](INFO): BPC1B: Serial:320J:1 serial_:309 2018-09-19T21:01:25.888Z,1537390885.888 [BPC1](INFO): BPC1B: Serial:320J:2 serial_:329 2018-09-19T21:01:25.889Z,1537390885.889 [BPC1](INFO): BPC1B: Serial:320J:3 serial_:326 2018-09-19T21:01:25.889Z,1537390885.889 [BPC1](INFO): BPC1B: Serial:320J:4 serial_:317 2018-09-19T21:01:25.890Z,1537390885.890 [BPC1](INFO): BPC1B: Serial:320J:5 serial_:333 2018-09-19T21:01:25.894Z,1537390885.894 [BPC1](INFO): BPC1B: Serial:320J:6 serial_:342 2018-09-19T21:01:25.915Z,1537390885.915 [BPC1](INFO): BPC1B: Serial:320J:7 serial_:307 2018-09-19T21:01:25.915Z,1537390885.915 [BPC1](INFO): BPC1B: Serial:320J:8 serial_:305 2018-09-19T21:01:25.916Z,1537390885.916 [BPC1](INFO): BPC1B: Serial:320J:9 serial_:325 2018-09-19T21:01:25.916Z,1537390885.916 [BPC1](INFO): BPC1B: Serial:320J:10 serial_:330 2018-09-19T21:01:25.917Z,1537390885.917 [BPC1](INFO): BPC1B: Serial:320J:11 serial_:316 2018-09-19T21:01:25.918Z,1537390885.918 [BPC1](INFO): BPC1B: Serial:320J:12 serial_:315 2018-09-19T21:01:25.923Z,1537390885.923 [BPC1](INFO): BPC1B: Serial:320J:13 serial_:306 2018-09-19T21:01:25.923Z,1537390885.923 [BPC1](INFO): BPC1B: Serial:320J:14 serial_:308 2018-09-19T21:01:25.924Z,1537390885.924 [BPC1](INFO): BPC1B: Serial:320J:15 serial_:339 2018-09-19T21:01:25.924Z,1537390885.924 [BPC1](INFO): BPC1B: Serial:320J:16 serial_:331 2018-09-19T21:01:25.925Z,1537390885.925 [BPC1](INFO): BPC1B: Serial:320J:17 serial_:314 2018-09-19T21:01:25.926Z,1537390885.926 [BPC1](INFO): BPC1B: Serial:320J:18 serial_:310 2018-09-19T21:01:25.931Z,1537390885.931 [BPC1](INFO): BPC1B: Serial:320J:19 serial_:335 2018-09-19T21:01:25.931Z,1537390885.931 [BPC1](INFO): BPC1B: Serial:320J:20 serial_:334 2018-09-19T21:01:25.932Z,1537390885.932 [BPC1](INFO): BPC1B: Serial:320J:21 serial_:319 2018-09-19T21:01:25.932Z,1537390885.932 [BPC1](INFO): BPC1B: Serial:320J:22 serial_:338 2018-09-19T21:01:25.933Z,1537390885.933 [BPC1](INFO): BPC1B: Serial:320J:23 serial_:332 2018-09-19T21:01:25.934Z,1537390885.934 [BPC1](INFO): BPC1B: Serial:320J:24 serial_:363 2018-09-19T21:01:25.951Z,1537390885.951 [BPC1](INFO): BPC1B: Serial:320J:25 serial_:321 2018-09-19T21:01:25.952Z,1537390885.952 [BPC1](INFO): BPC1B: Serial:320J:26 serial_:313 2018-09-19T21:01:25.953Z,1537390885.953 [BPC1](INFO): BPC1B: Serial:320J:27 serial_:311 2018-09-19T21:01:25.953Z,1537390885.953 [BPC1](INFO): BPC1B: Serial:320J:28 serial_:322 2018-09-19T21:01:25.954Z,1537390885.954 [BPC1](INFO): BPC1B: Serial:320J:29 serial_:318 2018-09-19T21:01:25.955Z,1537390885.955 [BPC1](INFO): BPC1B: Serial:320J:30 serial_:327 2018-09-19T21:01:25.955Z,1537390885.955 [BPC1](ERROR): BPC1B: No match for stick with serial number:0140 in the onboard configuration file. 2018-09-19T21:01:25.956Z,1537390885.956 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-09-19T21:01:25.956Z,1537390885.956 [BPC1] Data Fault, FailCount= 3 2018-09-19T21:01:25.956Z,1537390885.956 [BPC1](ERROR): Data Fault 2018-09-19T21:01:26.007Z,1537390886.007 [CBIT](ERROR): Data Fault in component: BPC1 2018-09-19T21:02:00.370Z,1537390920.370 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:02:00.371Z,1537390920.371 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2018-09-19T21:02:00.371Z,1537390920.371 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:02:00.372Z,1537390920.372 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:02:00.373Z,1537390920.373 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2018-09-19T21:02:00.373Z,1537390920.373 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:02:00.385Z,1537390920.385 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:02:00.386Z,1537390920.386 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:02:00.776Z,1537390920.776 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:02:00.776Z,1537390920.776 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2018-09-19T21:02:00.776Z,1537390920.776 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:02:00.776Z,1537390920.776 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2018-09-19T21:02:01.168Z,1537390921.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:02:01.169Z,1537390921.169 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:02:01.169Z,1537390921.169 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:02:01.169Z,1537390921.169 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:02:01.170Z,1537390921.170 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:02:01.171Z,1537390921.171 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:04:56.547Z,1537391096.547 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-19T21:04:56.548Z,1537391096.548 [Default:CheckIn:C.Wait] Stopped 2018-09-19T21:04:56.548Z,1537391096.548 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-19T21:04:56.548Z,1537391096.548 [Default:CheckIn:D] Running Loop=1 2018-09-19T21:04:56.909Z,1537391096.909 [Default:CheckIn:D] Stopped 2018-09-19T21:04:56.909Z,1537391096.909 [Default:CheckIn:E] Running Loop=1 2018-09-19T21:05:00.100Z,1537391100.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.729616 min 2018-09-19T21:05:00.100Z,1537391100.100 [Default:CheckIn:E] Stopped 2018-09-19T21:05:00.100Z,1537391100.100 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-19T21:05:00.100Z,1537391100.100 [Default:CheckIn] Stopped 2018-09-19T21:05:00.100Z,1537391100.100 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-19T21:05:00.101Z,1537391100.101 [Default:CheckIn](INFO): Running loop #3 2018-09-19T21:05:00.101Z,1537391100.101 [Default:CheckIn] Running Loop=3 2018-09-19T21:05:00.101Z,1537391100.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-19T21:05:00.101Z,1537391100.101 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-19T21:05:01.367Z,1537391101.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:05:01.367Z,1537391101.367 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2018-09-19T21:05:01.367Z,1537391101.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:05:01.369Z,1537391101.369 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:05:01.369Z,1537391101.369 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2018-09-19T21:05:01.369Z,1537391101.369 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:05:01.395Z,1537391101.395 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:05:01.395Z,1537391101.395 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:05:01.796Z,1537391101.796 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:05:01.796Z,1537391101.796 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2018-09-19T21:05:01.796Z,1537391101.796 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:05:01.796Z,1537391101.796 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2018-09-19T21:05:02.174Z,1537391102.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:05:02.175Z,1537391102.175 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:05:02.175Z,1537391102.175 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:05:02.175Z,1537391102.175 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:05:02.176Z,1537391102.176 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:05:02.176Z,1537391102.176 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:08:02.461Z,1537391282.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:08:02.461Z,1537391282.461 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2018-09-19T21:08:02.461Z,1537391282.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:08:02.463Z,1537391282.463 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:08:02.463Z,1537391282.463 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2018-09-19T21:08:02.463Z,1537391282.463 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:08:02.476Z,1537391282.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:08:02.477Z,1537391282.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:08:02.891Z,1537391282.891 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:08:02.891Z,1537391282.891 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2018-09-19T21:08:02.891Z,1537391282.891 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:08:02.891Z,1537391282.891 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2018-09-19T21:08:03.268Z,1537391283.268 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:08:03.268Z,1537391283.268 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:08:03.269Z,1537391283.269 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:08:03.269Z,1537391283.269 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:08:03.269Z,1537391283.269 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:08:03.270Z,1537391283.270 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:10:00.537Z,1537391400.537 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-19T21:05:00.1Z 2018-09-19T21:10:00.537Z,1537391400.537 [Default:CheckIn:Read_GPS] Stopped 2018-09-19T21:10:00.538Z,1537391400.538 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-19T21:10:05.467Z,1537391405.467 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T203743/Courier0010.lzma 2018-09-19T21:10:06.273Z,1537391406.273 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Courier0010.lzma.bak 2018-09-19T21:10:06.273Z,1537391406.273 [DataOverHttps](INFO): SBD MOMSN=8540045 2018-09-19T21:10:07.048Z,1537391407.048 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-09-19T21:10:07.048Z,1537391407.048 [NAL9602] Data Fault, FailCount= 3 2018-09-19T21:10:07.048Z,1537391407.048 [NAL9602](ERROR): Data Fault 2018-09-19T21:10:07.177Z,1537391407.177 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-19T21:10:15.280Z,1537391415.280 [NAL9602](INFO): Powering down 2018-09-19T21:10:15.949Z,1537391415.949 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-19T21:10:15.949Z,1537391415.949 [NAL9602] No Fault, FailCount= 3 2018-09-19T21:10:26.239Z,1537391426.239 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20180919T203743/Express0011.lzma 2018-09-19T21:10:27.020Z,1537391427.020 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Express0011.lzma.bak 2018-09-19T21:10:27.020Z,1537391427.020 [DataOverHttps](INFO): SBD MOMSN=8540047 2018-09-19T21:10:27.512Z,1537391427.512 [Default:CheckIn:Read_Iridium] Stopped 2018-09-19T21:10:27.513Z,1537391427.513 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-19T21:10:27.513Z,1537391427.513 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-19T21:10:45.440Z,1537391445.440 [NAL9602](INFO): Powering up NAL9602 2018-09-19T21:10:56.253Z,1537391456.253 [NAL9602](INFO): NAL9602 initialized 2018-09-19T21:11:03.511Z,1537391463.511 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:11:03.511Z,1537391463.511 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2018-09-19T21:11:03.511Z,1537391463.511 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:11:03.513Z,1537391463.513 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:11:03.513Z,1537391463.513 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2018-09-19T21:11:03.514Z,1537391463.514 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:11:03.573Z,1537391463.573 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:11:03.573Z,1537391463.573 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:11:03.968Z,1537391463.968 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:11:03.968Z,1537391463.968 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2018-09-19T21:11:03.968Z,1537391463.968 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:11:03.969Z,1537391463.969 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2018-09-19T21:11:04.304Z,1537391464.304 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:11:04.304Z,1537391464.304 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:11:04.304Z,1537391464.304 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:11:04.305Z,1537391464.305 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:11:04.305Z,1537391464.305 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:11:04.306Z,1537391464.306 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:11:26.771Z,1537391486.771 [CBIT](INFO): Clearing failed state for component BPC1 2018-09-19T21:11:26.771Z,1537391486.771 [BPC1] No Fault, FailCount= 3 2018-09-19T21:12:28.090Z,1537391548.090 [BPC1](INFO): BPC1A: initialized. 2018-09-19T21:12:28.103Z,1537391548.103 [BPC1](INFO): BPC1B: initialized. 2018-09-19T21:12:31.719Z,1537391551.719 [BPC1](INFO): BPC1A: sending IBPS break. 2018-09-19T21:12:31.719Z,1537391551.719 [BPC1](INFO): BPC1B: sending IBPS break. 2018-09-19T21:12:33.787Z,1537391553.787 [BPC1](INFO): BPC1A: validating data format. 2018-09-19T21:12:33.803Z,1537391553.803 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU. 2018-09-19T21:12:33.804Z,1537391553.804 [BPC1](INFO): BPC1B: validating data format. 2018-09-19T21:12:33.837Z,1537391553.837 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU. 2018-09-19T21:12:34.198Z,1537391554.198 [BPC1](INFO): BPC1A valid message: 1824 bytes. 2018-09-19T21:12:34.199Z,1537391554.199 [BPC1](INFO): BPC1A: Serial:357J:0 serial_:320 2018-09-19T21:12:34.200Z,1537391554.200 [BPC1](INFO): BPC1A: Serial:357J:1 serial_:355 2018-09-19T21:12:34.200Z,1537391554.200 [BPC1](INFO): BPC1A: Serial:357J:2 serial_:343 2018-09-19T21:12:34.201Z,1537391554.201 [BPC1](INFO): BPC1A: Serial:357J:3 serial_:345 2018-09-19T21:12:34.201Z,1537391554.201 [BPC1](INFO): BPC1A: Serial:357J:4 serial_:366 2018-09-19T21:12:34.202Z,1537391554.202 [BPC1](INFO): BPC1A: Serial:357J:5 serial_:352 2018-09-19T21:12:34.203Z,1537391554.203 [BPC1](INFO): BPC1A: Serial:357J:6 serial_:348 2018-09-19T21:12:34.203Z,1537391554.203 [BPC1](INFO): BPC1A: Serial:357J:7 serial_:365 2018-09-19T21:12:34.204Z,1537391554.204 [BPC1](INFO): BPC1A: Serial:357J:8 serial_:301 2018-09-19T21:12:34.205Z,1537391554.205 [BPC1](INFO): BPC1A: Serial:357J:9 serial_:350 2018-09-19T21:12:34.205Z,1537391554.205 [BPC1](INFO): BPC1A: Serial:357J:10 serial_:356 2018-09-19T21:12:34.206Z,1537391554.206 [BPC1](INFO): BPC1A: Serial:357J:11 serial_:347 2018-09-19T21:12:34.207Z,1537391554.207 [BPC1](INFO): BPC1A: Serial:357J:12 serial_:370 2018-09-19T21:12:34.207Z,1537391554.207 [BPC1](INFO): BPC1A: Serial:357J:13 serial_:358 2018-09-19T21:12:34.208Z,1537391554.208 [BPC1](INFO): BPC1A: Serial:357J:14 serial_:368 2018-09-19T21:12:34.208Z,1537391554.208 [BPC1](INFO): BPC1A: Serial:357J:15 serial_:328 2018-09-19T21:12:34.209Z,1537391554.209 [BPC1](INFO): BPC1A: Serial:357J:16 serial_:304 2018-09-19T21:12:34.210Z,1537391554.210 [BPC1](INFO): BPC1A: Serial:357J:17 serial_:361 2018-09-19T21:12:34.210Z,1537391554.210 [BPC1](INFO): BPC1A: Serial:357J:18 serial_:344 2018-09-19T21:12:34.211Z,1537391554.211 [BPC1](INFO): BPC1A: Serial:357J:19 serial_:354 2018-09-19T21:12:34.211Z,1537391554.211 [BPC1](INFO): BPC1A: Serial:357J:20 serial_:336 2018-09-19T21:12:34.212Z,1537391554.212 [BPC1](INFO): BPC1A: Serial:357J:21 serial_:351 2018-09-19T21:12:34.213Z,1537391554.213 [BPC1](INFO): BPC1A: Serial:357J:22 serial_:369 2018-09-19T21:12:34.213Z,1537391554.213 [BPC1](INFO): BPC1A: Serial:357J:23 serial_:323 2018-09-19T21:12:34.214Z,1537391554.214 [BPC1](INFO): BPC1A: Serial:357J:24 serial_:324 2018-09-19T21:12:34.227Z,1537391554.227 [BPC1](INFO): BPC1A: Serial:357J:25 serial_:362 2018-09-19T21:12:34.227Z,1537391554.227 [BPC1](INFO): BPC1A: Serial:357J:26 serial_:341 2018-09-19T21:12:34.228Z,1537391554.228 [BPC1](INFO): BPC1A: Serial:357J:27 serial_:337 2018-09-19T21:12:34.228Z,1537391554.228 [BPC1](INFO): BPC1A: Serial:357J:28 serial_:340 2018-09-19T21:12:34.229Z,1537391554.229 [BPC1](INFO): BPC1A: Serial:357J:29 serial_:353 2018-09-19T21:12:34.230Z,1537391554.230 [BPC1](INFO): BPC1A: Serial:357J:30 serial_:312 2018-09-19T21:12:34.235Z,1537391554.235 [BPC1](ERROR): BPC1A: No match for stick with serial number:0165 in the onboard configuration file. 2018-09-19T21:12:34.236Z,1537391554.236 [BPC1](INFO): BPC1B valid message: 1824 bytes. 2018-09-19T21:12:34.237Z,1537391554.237 [BPC1](INFO): BPC1B: Serial:320J:0 serial_:357 2018-09-19T21:12:34.238Z,1537391554.238 [BPC1](INFO): BPC1B: Serial:320J:1 serial_:309 2018-09-19T21:12:34.263Z,1537391554.263 [BPC1](INFO): BPC1B: Serial:320J:2 serial_:329 2018-09-19T21:12:34.264Z,1537391554.264 [BPC1](INFO): BPC1B: Serial:320J:3 serial_:326 2018-09-19T21:12:34.264Z,1537391554.264 [BPC1](INFO): BPC1B: Serial:320J:4 serial_:317 2018-09-19T21:12:34.265Z,1537391554.265 [BPC1](INFO): BPC1B: Serial:320J:5 serial_:333 2018-09-19T21:12:34.265Z,1537391554.265 [BPC1](INFO): BPC1B: Serial:320J:6 serial_:342 2018-09-19T21:12:34.275Z,1537391554.275 [BPC1](INFO): BPC1B: Serial:320J:7 serial_:307 2018-09-19T21:12:34.276Z,1537391554.276 [BPC1](INFO): BPC1B: Serial:320J:8 serial_:305 2018-09-19T21:12:34.276Z,1537391554.276 [BPC1](INFO): BPC1B: Serial:320J:9 serial_:325 2018-09-19T21:12:34.277Z,1537391554.277 [BPC1](INFO): BPC1B: Serial:320J:10 serial_:330 2018-09-19T21:12:34.278Z,1537391554.278 [BPC1](INFO): BPC1B: Serial:320J:11 serial_:316 2018-09-19T21:12:34.283Z,1537391554.283 [BPC1](INFO): BPC1B: Serial:320J:12 serial_:315 2018-09-19T21:12:34.289Z,1537391554.289 [BPC1](INFO): BPC1B: Serial:320J:13 serial_:306 2018-09-19T21:12:34.290Z,1537391554.290 [BPC1](INFO): BPC1B: Serial:320J:14 serial_:308 2018-09-19T21:12:34.299Z,1537391554.299 [BPC1](INFO): BPC1B: Serial:320J:15 serial_:339 2018-09-19T21:12:34.300Z,1537391554.300 [BPC1](INFO): BPC1B: Serial:320J:16 serial_:331 2018-09-19T21:12:34.300Z,1537391554.300 [BPC1](INFO): BPC1B: Serial:320J:17 serial_:314 2018-09-19T21:12:34.301Z,1537391554.301 [BPC1](INFO): BPC1B: Serial:320J:18 serial_:310 2018-09-19T21:12:34.301Z,1537391554.301 [BPC1](INFO): BPC1B: Serial:320J:19 serial_:335 2018-09-19T21:12:34.302Z,1537391554.302 [BPC1](INFO): BPC1B: Serial:320J:20 serial_:334 2018-09-19T21:12:34.311Z,1537391554.311 [BPC1](INFO): BPC1B: Serial:320J:21 serial_:319 2018-09-19T21:12:34.311Z,1537391554.311 [BPC1](INFO): BPC1B: Serial:320J:22 serial_:338 2018-09-19T21:12:34.312Z,1537391554.312 [BPC1](INFO): BPC1B: Serial:320J:23 serial_:332 2018-09-19T21:12:34.313Z,1537391554.313 [BPC1](INFO): BPC1B: Serial:320J:24 serial_:363 2018-09-19T21:12:34.313Z,1537391554.313 [BPC1](INFO): BPC1B: Serial:320J:25 serial_:321 2018-09-19T21:12:34.314Z,1537391554.314 [BPC1](INFO): BPC1B: Serial:320J:26 serial_:313 2018-09-19T21:12:34.327Z,1537391554.327 [BPC1](INFO): BPC1B: Serial:320J:27 serial_:311 2018-09-19T21:12:34.327Z,1537391554.327 [BPC1](INFO): BPC1B: Serial:320J:28 serial_:322 2018-09-19T21:12:34.328Z,1537391554.328 [BPC1](INFO): BPC1B: Serial:320J:29 serial_:318 2018-09-19T21:12:34.329Z,1537391554.329 [BPC1](INFO): BPC1B: Serial:320J:30 serial_:327 2018-09-19T21:12:34.329Z,1537391554.329 [BPC1](ERROR): BPC1B: No match for stick with serial number:0140 in the onboard configuration file. 2018-09-19T21:12:34.329Z,1537391554.329 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-09-19T21:12:34.329Z,1537391554.329 [BPC1] Data Fault, FailCount= 4 2018-09-19T21:12:34.329Z,1537391554.329 [BPC1](ERROR): Data Fault 2018-09-19T21:12:34.369Z,1537391554.369 [CBIT](ERROR): Data Fault in component: BPC1 2018-09-19T21:14:04.669Z,1537391644.669 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:14:04.684Z,1537391644.684 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2018-09-19T21:14:04.685Z,1537391644.685 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:14:04.691Z,1537391644.691 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:14:04.691Z,1537391644.691 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2018-09-19T21:14:04.691Z,1537391644.691 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:14:04.726Z,1537391644.726 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:14:04.727Z,1537391644.727 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:14:05.082Z,1537391645.082 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:14:05.082Z,1537391645.082 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2018-09-19T21:14:05.082Z,1537391645.082 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:14:05.083Z,1537391645.083 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2018-09-19T21:14:05.464Z,1537391645.464 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:14:05.465Z,1537391645.465 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:14:05.465Z,1537391645.465 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:14:05.466Z,1537391645.466 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:14:05.466Z,1537391645.466 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:14:05.471Z,1537391645.471 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:15:27.962Z,1537391727.962 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-19T21:15:27.963Z,1537391727.963 [Default:CheckIn:C.Wait] Stopped 2018-09-19T21:15:27.963Z,1537391727.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-19T21:15:27.964Z,1537391727.964 [Default:CheckIn:D] Running Loop=1 2018-09-19T21:15:28.359Z,1537391728.359 [Default:CheckIn:D] Stopped 2018-09-19T21:15:28.359Z,1537391728.359 [Default:CheckIn:E] Running Loop=1 2018-09-19T21:15:28.744Z,1537391728.744 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.253780 min 2018-09-19T21:15:28.744Z,1537391728.744 [Default:CheckIn:E] Stopped 2018-09-19T21:15:28.744Z,1537391728.744 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-19T21:15:28.745Z,1537391728.745 [Default:CheckIn] Stopped 2018-09-19T21:15:28.745Z,1537391728.745 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-19T21:15:28.745Z,1537391728.745 [Default:CheckIn](INFO): Running loop #4 2018-09-19T21:15:28.745Z,1537391728.745 [Default:CheckIn] Running Loop=4 2018-09-19T21:15:28.745Z,1537391728.745 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-19T21:15:28.745Z,1537391728.745 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-19T21:17:05.708Z,1537391825.708 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:17:05.708Z,1537391825.708 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2018-09-19T21:17:05.708Z,1537391825.708 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:17:05.715Z,1537391825.715 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:17:05.715Z,1537391825.715 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2018-09-19T21:17:05.715Z,1537391825.715 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:17:05.740Z,1537391825.740 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:17:05.741Z,1537391825.741 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:17:06.132Z,1537391826.132 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:17:06.132Z,1537391826.132 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2018-09-19T21:17:06.133Z,1537391826.133 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:17:06.133Z,1537391826.133 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2018-09-19T21:17:06.532Z,1537391826.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:17:06.532Z,1537391826.532 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:17:06.533Z,1537391826.533 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:17:06.533Z,1537391826.533 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:17:06.534Z,1537391826.534 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:17:06.534Z,1537391826.534 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:20:06.778Z,1537392006.778 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:20:06.778Z,1537392006.778 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2018-09-19T21:20:06.778Z,1537392006.778 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-09-19T21:20:06.780Z,1537392006.780 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-09-19T21:20:06.780Z,1537392006.780 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2018-09-19T21:20:06.780Z,1537392006.780 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-09-19T21:20:06.815Z,1537392006.815 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-09-19T21:20:06.815Z,1537392006.815 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-09-19T21:20:07.229Z,1537392007.229 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-09-19T21:20:07.229Z,1537392007.229 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2018-09-19T21:20:07.230Z,1537392007.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-09-19T21:20:07.230Z,1537392007.230 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2018-09-19T21:20:07.582Z,1537392007.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-09-19T21:20:07.582Z,1537392007.582 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:20:07.583Z,1537392007.583 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:20:07.583Z,1537392007.583 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-09-19T21:20:07.584Z,1537392007.584 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-09-19T21:20:07.584Z,1537392007.584 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-09-19T21:20:28.800Z,1537392028.800 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-19T21:15:28.7Z 2018-09-19T21:20:28.800Z,1537392028.800 [Default:CheckIn:Read_GPS] Stopped 2018-09-19T21:20:28.800Z,1537392028.800 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-19T21:20:32.483Z,1537392032.483 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T203743/Courier0013.lzma 2018-09-19T21:20:33.241Z,1537392033.241 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Courier0013.lzma.bak 2018-09-19T21:20:33.242Z,1537392033.242 [DataOverHttps](INFO): SBD MOMSN=8540264 2018-09-19T21:20:42.240Z,1537392042.240 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20180919T203743/Express0014.lzma 2018-09-19T21:20:43.024Z,1537392043.024 [DataOverHttps](INFO): Moved sent file to Logs/20180919T203743/Express0014.lzma.bak 2018-09-19T21:20:43.024Z,1537392043.024 [DataOverHttps](INFO): SBD MOMSN=8540266 2018-09-19T21:20:43.555Z,1537392043.555 [Default:CheckIn:Read_Iridium] Stopped 2018-09-19T21:20:43.555Z,1537392043.555 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-19T21:20:43.555Z,1537392043.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-19T21:20:59.096Z,1537392059.096 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-09-19T21:20:59.096Z,1537392059.096 [NAL9602] Data Fault, FailCount= 4 2018-09-19T21:20:59.097Z,1537392059.097 [NAL9602](ERROR): Data Fault 2018-09-19T21:20:59.178Z,1537392059.178 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-19T21:20:59.496Z,1537392059.496 [NAL9602](INFO): Powering down 2018-09-19T21:21:00.373Z,1537392060.373 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-19T21:21:00.373Z,1537392060.373 [NAL9602] No Fault, FailCount= 4 2018-09-19T21:21:29.900Z,1537392089.900 [NAL9602](INFO): Powering up NAL9602 2018-09-19T21:21:36.560Z,1537392096.560 [CommandLine](IMPORTANT): got command restart application 2018-09-19T21:21:37.574Z,1537392097.574 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-19T21:21:37.574Z,1537392097.574 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:37.594Z,1537392097.594 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-09-19T21:21:37.595Z,1537392097.595 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:37.596Z,1537392097.596 [CommandLine](INFO): Join timeout helper Thread ID is 2313 2018-09-19T21:21:37.605Z,1537392097.605 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-09-19T21:21:37.605Z,1537392097.605 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:37.606Z,1537392097.606 [NavChartDb](INFO): Join timeout helper Thread ID is 2314 2018-09-19T21:21:37.619Z,1537392097.619 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-19T21:21:37.621Z,1537392097.621 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:37.626Z,1537392097.626 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-09-19T21:21:37.626Z,1537392097.626 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:37.627Z,1537392097.627 [Radio_Surface](INFO): Join timeout helper Thread ID is 2315 2018-09-19T21:21:38.003Z,1537392098.003 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-19T21:21:38.003Z,1537392098.003 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:38.006Z,1537392098.006 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2018-09-19T21:21:38.006Z,1537392098.006 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:38.007Z,1537392098.007 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 2316 2018-09-19T21:21:38.359Z,1537392098.359 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-19T21:21:38.359Z,1537392098.359 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-09-19T21:21:38.360Z,1537392098.360 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:38.370Z,1537392098.370 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-09-19T21:21:38.371Z,1537392098.371 [logger ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:38.371Z,1537392098.371 [logger](INFO): Join timeout helper Thread ID is 2317 2018-09-19T21:21:38.401Z,1537392098.401 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-19T21:21:38.402Z,1537392098.402 [logger ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:38.403Z,1537392098.403 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-09-19T21:21:38.404Z,1537392098.404 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:38.404Z,1537392098.404 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-09-19T21:21:38.404Z,1537392098.404 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:38.404Z,1537392098.404 [controlThread](INFO): Join timeout helper Thread ID is 2318 2018-09-19T21:21:38.682Z,1537392098.682 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-09-19T21:21:38.683Z,1537392098.683 [controlThread](DEBUG): Uninitializing ControlThread 2018-09-19T21:21:38.683Z,1537392098.683 [Aanderaa_O2](INFO): Powering down 2018-09-19T21:21:38.684Z,1537392098.684 [AHRS_M2](INFO): Powering down 2018-09-19T21:21:38.755Z,1537392098.755 [NAL9602](INFO): Powering down 2018-09-19T21:21:38.826Z,1537392098.826 [RDI_Pathfinder](INFO): Powering down 2018-09-19T21:21:38.827Z,1537392098.827 [RDI_PathfinderUp](INFO): Powering down 2018-09-19T21:21:38.829Z,1537392098.829 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-09-19T21:21:38.830Z,1537392098.830 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-09-19T21:21:38.831Z,1537392098.831 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-09-19T21:21:38.831Z,1537392098.831 [MissionManager](INFO): Uninitializing Mission Default 2018-09-19T21:21:38.831Z,1537392098.831 [Default] Stopped 2018-09-19T21:21:38.831Z,1537392098.831 [Default](DEBUG): Aggregate::uninitialize Default 2018-09-19T21:21:38.832Z,1537392098.832 [Default:B.GoToSurface] Stopped 2018-09-19T21:21:38.832Z,1537392098.832 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-09-19T21:21:38.832Z,1537392098.832 [Default:CheckIn] Stopped 2018-09-19T21:21:38.832Z,1537392098.832 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-19T21:21:38.832Z,1537392098.832 [Default:CheckIn:C.Wait] Stopped 2018-09-19T21:21:38.832Z,1537392098.832 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-19T21:21:38.836Z,1537392098.836 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-09-19T21:21:38.836Z,1537392098.836 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-09-19T21:21:38.836Z,1537392098.836 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-09-19T21:21:38.837Z,1537392098.837 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-09-19T21:21:38.837Z,1537392098.837 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-09-19T21:21:38.837Z,1537392098.837 [BuoyancyServo](INFO): Powering down 2018-09-19T21:21:38.850Z,1537392098.850 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-09-19T21:21:38.851Z,1537392098.851 [ElevatorServo](INFO): Powering down 2018-09-19T21:21:38.852Z,1537392098.852 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-09-19T21:21:38.852Z,1537392098.852 [MassServo](INFO): Powering down 2018-09-19T21:21:38.853Z,1537392098.853 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-09-19T21:21:38.853Z,1537392098.853 [RudderServo](INFO): Powering down 2018-09-19T21:21:38.853Z,1537392098.853 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-09-19T21:21:38.854Z,1537392098.854 [ThrusterServo](INFO): Powering down 2018-09-19T21:21:38.855Z,1537392098.855 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-09-19T21:21:38.855Z,1537392098.855 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-09-19T21:21:38.855Z,1537392098.855 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-09-19T21:21:38.856Z,1537392098.856 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:38.920Z,1537392098.920 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:38.983Z,1537392098.983 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:39.035Z,1537392099.035 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-09-19T21:21:39.093Z,1537392099.093 [logger ThreadHandler](INFO): Thread cancelled.