2019-03-22T23:25:52.626Z,1553297152.626 [Supervisor](DEBUG): Initializing supervisor.
2019-03-22T23:25:52.629Z,1553297152.629 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-22T23:25:52.630Z,1553297152.630 [SyncHandler](INFO): Protected caller Thread ID is 805
2019-03-22T23:25:52.630Z,1553297152.630 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-22T23:25:52.631Z,1553297152.631 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-22T23:25:52.632Z,1553297152.632 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 806
2019-03-22T23:25:52.634Z,1553297152.634 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-22T23:25:52.647Z,1553297152.647 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-22T23:25:52.648Z,1553297152.648 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-22T23:25:52.648Z,1553297152.648 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 807
2019-03-22T23:25:52.649Z,1553297152.649 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-22T23:25:52.650Z,1553297152.650 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-22T23:25:52.650Z,1553297152.650 [logger ThreadHandler](INFO): Protected caller Thread ID is 808
2019-03-22T23:25:52.652Z,1553297152.652 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-22T23:25:52.653Z,1553297152.653 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-22T23:25:52.656Z,1553297152.656 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-22T23:25:53.095Z,1553297153.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-22T23:25:53.097Z,1553297153.097 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-22T23:25:53.244Z,1553297153.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-22T23:25:53.246Z,1553297153.246 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-22T23:25:53.327Z,1553297153.327 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-22T23:25:53.427Z,1553297153.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-22T23:25:53.428Z,1553297153.428 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-22T23:25:53.525Z,1553297153.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-22T23:25:53.525Z,1553297153.525 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-22T23:25:53.912Z,1553297153.912 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-22T23:25:53.912Z,1553297153.912 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-22T23:25:54.017Z,1553297154.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-22T23:25:54.018Z,1553297154.018 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-22T23:25:54.499Z,1553297154.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-22T23:25:54.499Z,1553297154.499 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-22T23:25:54.711Z,1553297154.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-22T23:25:54.712Z,1553297154.712 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-22T23:25:55.063Z,1553297155.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-22T23:25:55.064Z,1553297155.064 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-22T23:25:55.340Z,1553297155.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-22T23:25:55.340Z,1553297155.340 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-22T23:25:55.486Z,1553297155.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-22T23:25:55.487Z,1553297155.487 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-22T23:25:56.002Z,1553297156.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-22T23:25:56.089Z,1553297156.089 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-22T23:25:56.805Z,1553297156.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-22T23:25:56.806Z,1553297156.806 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-22T23:25:57.019Z,1553297157.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-22T23:25:57.021Z,1553297157.021 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-03-22T23:25:57.024Z,1553297157.024 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-03-22T23:25:57.220Z,1553297157.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-03-22T23:25:57.303Z,1553297157.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-03-22T23:25:57.421Z,1553297157.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-03-22T23:25:57.508Z,1553297157.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-03-22T23:25:57.740Z,1553297157.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-22T23:25:57.741Z,1553297157.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-03-22T23:25:57.842Z,1553297157.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-03-22T23:25:57.954Z,1553297157.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-03-22T23:25:58.049Z,1553297158.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-03-22T23:25:58.205Z,1553297158.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-03-22T23:25:58.307Z,1553297158.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-03-22T23:25:58.409Z,1553297158.409 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-03-22T23:25:58.409Z,1553297158.409 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-22T23:25:58.425Z,1553297158.425 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-22T23:25:58.449Z,1553297158.449 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-22T23:25:58.450Z,1553297158.450 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-22T23:25:58.540Z,1553297158.540 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-22T23:25:58.656Z,1553297158.656 [VerticalControl] Loaded
2019-03-22T23:25:58.656Z,1553297158.656 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-22T23:25:58.657Z,1553297158.657 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-22T23:25:58.727Z,1553297158.727 [HorizontalControl] Loaded
2019-03-22T23:25:58.727Z,1553297158.727 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-22T23:25:58.728Z,1553297158.728 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-22T23:25:58.733Z,1553297158.733 [SpeedControl] Loaded
2019-03-22T23:25:58.734Z,1553297158.734 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-22T23:25:58.735Z,1553297158.735 [LoopControl](DEBUG): Construct LoopControl.
2019-03-22T23:25:58.735Z,1553297158.735 [LoopControl] Loaded
2019-03-22T23:25:58.735Z,1553297158.735 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-22T23:25:58.736Z,1553297158.736 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-22T23:25:58.736Z,1553297158.736 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-22T23:25:58.875Z,1553297158.875 [ElevatorServo] Loaded
2019-03-22T23:25:58.875Z,1553297158.875 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-22T23:25:58.887Z,1553297158.887 [MassServo] Loaded
2019-03-22T23:25:58.887Z,1553297158.887 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-22T23:25:58.898Z,1553297158.898 [ThrusterServo] Loaded
2019-03-22T23:25:58.899Z,1553297158.899 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-22T23:25:58.899Z,1553297158.899 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-22T23:25:58.900Z,1553297158.900 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-22T23:25:58.955Z,1553297158.955 [DepthRateCalculator] Loaded
2019-03-22T23:25:58.956Z,1553297158.956 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-22T23:25:58.961Z,1553297158.961 [PitchRateCalculator] Loaded
2019-03-22T23:25:58.961Z,1553297158.961 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-22T23:25:58.976Z,1553297158.976 [SpeedCalculator] Loaded
2019-03-22T23:25:58.977Z,1553297158.977 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-22T23:25:58.998Z,1553297158.998 [TempGradientCalculator] Loaded
2019-03-22T23:25:58.998Z,1553297158.998 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-22T23:25:59.004Z,1553297159.004 [YawRateCalculator] Loaded
2019-03-22T23:25:59.004Z,1553297159.004 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-22T23:25:59.034Z,1553297159.034 [ElevatorOffsetCalculator] Loaded
2019-03-22T23:25:59.034Z,1553297159.034 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-22T23:25:59.034Z,1553297159.034 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-22T23:25:59.035Z,1553297159.035 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-22T23:25:59.159Z,1553297159.159 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-22T23:25:59.159Z,1553297159.159 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-22T23:25:59.219Z,1553297159.219 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-22T23:25:59.219Z,1553297159.219 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-22T23:25:59.240Z,1553297159.240 [NavChart] Loaded
2019-03-22T23:25:59.241Z,1553297159.241 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-22T23:25:59.245Z,1553297159.245 [UniversalFixResidualReporter] Loaded
2019-03-22T23:25:59.245Z,1553297159.245 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-22T23:25:59.246Z,1553297159.246 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-22T23:25:59.246Z,1553297159.246 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-22T23:25:59.858Z,1553297159.858 [DataOverHttps] Loaded
2019-03-22T23:25:59.858Z,1553297159.858 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-22T23:25:59.860Z,1553297159.860 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408004E0
2019-03-22T23:25:59.860Z,1553297159.860 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 888
2019-03-22T23:25:59.874Z,1553297159.874 [Depth_Keller] Loaded
2019-03-22T23:25:59.874Z,1553297159.874 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-22T23:25:59.972Z,1553297159.972 [NAL9602] Loaded
2019-03-22T23:25:59.972Z,1553297159.972 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-22T23:25:59.988Z,1553297159.988 [Onboard] Loaded
2019-03-22T23:25:59.988Z,1553297159.988 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-22T23:25:59.992Z,1553297159.992 [Radio_Surface] Loaded
2019-03-22T23:25:59.992Z,1553297159.992 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-22T23:25:59.993Z,1553297159.993 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408304E0
2019-03-22T23:25:59.993Z,1553297159.993 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 889
2019-03-22T23:26:00.148Z,1553297160.148 [PNI_TCM] Loaded
2019-03-22T23:26:00.148Z,1553297160.148 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-03-22T23:26:00.208Z,1553297160.208 [Rowe_600LCM] Loaded
2019-03-22T23:26:00.208Z,1553297160.208 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-03-22T23:26:00.209Z,1553297160.209 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408604E0
2019-03-22T23:26:00.210Z,1553297160.210 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 890
2019-03-22T23:26:00.234Z,1553297160.234 [BPC1](INFO): BPC1A: got serial number for stick 1: 023F
2019-03-22T23:26:00.235Z,1553297160.235 [BPC1](INFO): BPC1A: got serial number for stick 2: 02A8
2019-03-22T23:26:00.236Z,1553297160.236 [BPC1](INFO): BPC1A: got serial number for stick 3: 01D7
2019-03-22T23:26:00.237Z,1553297160.237 [BPC1](INFO): BPC1A: got serial number for stick 4: 01EF
2019-03-22T23:26:00.238Z,1553297160.238 [BPC1](INFO): BPC1A: got serial number for stick 5: 0299
2019-03-22T23:26:00.239Z,1553297160.239 [BPC1](INFO): BPC1A: got serial number for stick 6: 0292
2019-03-22T23:26:00.240Z,1553297160.240 [BPC1](INFO): BPC1A: got serial number for stick 7: 0296
2019-03-22T23:26:00.241Z,1553297160.241 [BPC1](INFO): BPC1A: got serial number for stick 8: 0291
2019-03-22T23:26:00.241Z,1553297160.241 [BPC1](INFO): BPC1A: marked stick 8 as reserve.
2019-03-22T23:26:00.242Z,1553297160.242 [BPC1](INFO): BPC1A: got serial number for stick 9: 00BB
2019-03-22T23:26:00.243Z,1553297160.243 [BPC1](INFO): BPC1A: got serial number for stick 10: 025B
2019-03-22T23:26:00.244Z,1553297160.244 [BPC1](INFO): BPC1A: got serial number for stick 11: 024E
2019-03-22T23:26:00.244Z,1553297160.244 [BPC1](INFO): BPC1A: got serial number for stick 12: 029C
2019-03-22T23:26:00.245Z,1553297160.245 [BPC1](INFO): BPC1A: got serial number for stick 13: 0242
2019-03-22T23:26:00.246Z,1553297160.246 [BPC1](INFO): BPC1A: got serial number for stick 14: 01FA
2019-03-22T23:26:00.247Z,1553297160.247 [BPC1](INFO): BPC1A: got serial number for stick 15: 0269
2019-03-22T23:26:00.248Z,1553297160.248 [BPC1](INFO): BPC1A: got serial number for stick 16: 02AD
2019-03-22T23:26:00.248Z,1553297160.248 [BPC1](INFO): BPC1A: marked stick 16 as reserve.
2019-03-22T23:26:00.249Z,1553297160.249 [BPC1](INFO): BPC1A: got serial number for stick 17: 0295
2019-03-22T23:26:00.250Z,1553297160.250 [BPC1](INFO): BPC1A: got serial number for stick 18: 0224
2019-03-22T23:26:00.251Z,1553297160.251 [BPC1](INFO): BPC1A: got serial number for stick 19: 00F6
2019-03-22T23:26:00.252Z,1553297160.252 [BPC1](INFO): BPC1A: got serial number for stick 20: 0294
2019-03-22T23:26:00.252Z,1553297160.252 [BPC1](INFO): BPC1A: got serial number for stick 21: 0271
2019-03-22T23:26:00.253Z,1553297160.253 [BPC1](INFO): BPC1A: got serial number for stick 22: 0206
2019-03-22T23:26:00.254Z,1553297160.254 [BPC1](INFO): BPC1A: got serial number for stick 23: 02B0
2019-03-22T23:26:00.255Z,1553297160.255 [BPC1](INFO): BPC1A: got serial number for stick 24: 020C
2019-03-22T23:26:00.255Z,1553297160.255 [BPC1](INFO): BPC1A: marked stick 24 as reserve.
2019-03-22T23:26:00.256Z,1553297160.256 [BPC1](INFO): BPC1A: got serial number for stick 25: 016F
2019-03-22T23:26:00.257Z,1553297160.257 [BPC1](INFO): BPC1A: got serial number for stick 26: 026A
2019-03-22T23:26:00.257Z,1553297160.257 [BPC1](INFO): BPC1A: got serial number for stick 27: 01C6
2019-03-22T23:26:00.258Z,1553297160.258 [BPC1](INFO): BPC1A: got serial number for stick 28: 0246
2019-03-22T23:26:00.259Z,1553297160.259 [BPC1](INFO): BPC1A: got serial number for stick 29: 02D3
2019-03-22T23:26:00.260Z,1553297160.260 [BPC1](INFO): BPC1A: got serial number for stick 30: 01C0
2019-03-22T23:26:00.260Z,1553297160.260 [BPC1](INFO): BPC1A: got serial number for stick 31: 01F3
2019-03-22T23:26:00.262Z,1553297160.262 [BPC1](INFO): BPC1B: got serial number for stick 32: 0163
2019-03-22T23:26:00.263Z,1553297160.263 [BPC1](INFO): BPC1B: got serial number for stick 33: 01AA
2019-03-22T23:26:00.264Z,1553297160.264 [BPC1](INFO): BPC1B: got serial number for stick 34: 0197
2019-03-22T23:26:00.265Z,1553297160.265 [BPC1](INFO): BPC1B: got serial number for stick 35: 0215
2019-03-22T23:26:00.265Z,1553297160.265 [BPC1](INFO): BPC1B: got serial number for stick 36: 020E
2019-03-22T23:26:00.266Z,1553297160.266 [BPC1](INFO): BPC1B: got serial number for stick 37: 0228
2019-03-22T23:26:00.267Z,1553297160.267 [BPC1](INFO): BPC1B: got serial number for stick 38: 02B4
2019-03-22T23:26:00.268Z,1553297160.268 [BPC1](INFO): BPC1B: got serial number for stick 39: 01AC
2019-03-22T23:26:00.268Z,1553297160.268 [BPC1](INFO): BPC1B: marked stick 39 as reserve.
2019-03-22T23:26:00.269Z,1553297160.269 [BPC1](INFO): BPC1B: got serial number for stick 40: 022A
2019-03-22T23:26:00.270Z,1553297160.270 [BPC1](INFO): BPC1B: got serial number for stick 41: 0232
2019-03-22T23:26:00.271Z,1553297160.271 [BPC1](INFO): BPC1B: got serial number for stick 42: 0219
2019-03-22T23:26:00.272Z,1553297160.272 [BPC1](INFO): BPC1B: got serial number for stick 43: 0229
2019-03-22T23:26:00.272Z,1553297160.272 [BPC1](INFO): BPC1B: got serial number for stick 44: 0253
2019-03-22T23:26:00.273Z,1553297160.273 [BPC1](INFO): BPC1B: got serial number for stick 45: 02A1
2019-03-22T23:26:00.274Z,1553297160.274 [BPC1](INFO): BPC1B: got serial number for stick 46: 028A
2019-03-22T23:26:00.275Z,1553297160.275 [BPC1](INFO): BPC1B: got serial number for stick 47: 019A
2019-03-22T23:26:00.275Z,1553297160.275 [BPC1](INFO): BPC1B: marked stick 47 as reserve.
2019-03-22T23:26:00.276Z,1553297160.276 [BPC1](INFO): BPC1B: got serial number for stick 48: 0202
2019-03-22T23:26:00.277Z,1553297160.277 [BPC1](INFO): BPC1B: got serial number for stick 49: 027C
2019-03-22T23:26:00.278Z,1553297160.278 [BPC1](INFO): BPC1B: got serial number for stick 50: 022F
2019-03-22T23:26:00.279Z,1553297160.279 [BPC1](INFO): BPC1B: got serial number for stick 51: 01FF
2019-03-22T23:26:00.279Z,1553297160.279 [BPC1](INFO): BPC1B: got serial number for stick 52: 0203
2019-03-22T23:26:00.280Z,1553297160.280 [BPC1](INFO): BPC1B: got serial number for stick 53: 02A6
2019-03-22T23:26:00.281Z,1553297160.281 [BPC1](INFO): BPC1B: got serial number for stick 54: 01D4
2019-03-22T23:26:00.282Z,1553297160.282 [BPC1](INFO): BPC1B: got serial number for stick 55: 017F
2019-03-22T23:26:00.282Z,1553297160.282 [BPC1](INFO): BPC1B: marked stick 55 as reserve.
2019-03-22T23:26:00.283Z,1553297160.283 [BPC1](INFO): BPC1B: got serial number for stick 56: 021D
2019-03-22T23:26:00.284Z,1553297160.284 [BPC1](INFO): BPC1B: got serial number for stick 57: 01BF
2019-03-22T23:26:00.285Z,1553297160.285 [BPC1](INFO): BPC1B: got serial number for stick 58: 0216
2019-03-22T23:26:00.285Z,1553297160.285 [BPC1](INFO): BPC1B: got serial number for stick 59: 024A
2019-03-22T23:26:00.286Z,1553297160.286 [BPC1](INFO): BPC1B: got serial number for stick 60: 01DA
2019-03-22T23:26:00.287Z,1553297160.287 [BPC1](INFO): BPC1B: got serial number for stick 61: 01DF
2019-03-22T23:26:00.288Z,1553297160.288 [BPC1](INFO): BPC1B: got serial number for stick 62: 0205
2019-03-22T23:26:02.034Z,1553297162.034 [BPC1] Loaded
2019-03-22T23:26:02.035Z,1553297162.035 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-22T23:26:02.035Z,1553297162.035 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-22T23:26:02.036Z,1553297162.036 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-22T23:26:02.160Z,1553297162.160 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-22T23:26:02.161Z,1553297162.161 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-22T23:26:02.285Z,1553297162.285 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-22T23:26:02.286Z,1553297162.286 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-22T23:26:02.327Z,1553297162.327 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-22T23:26:02.328Z,1553297162.328 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-22T23:26:02.655Z,1553297162.655 [CTD_NeilBrown] Loaded
2019-03-22T23:26:02.655Z,1553297162.655 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-22T23:26:02.656Z,1553297162.656 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409ED4E0
2019-03-22T23:26:02.656Z,1553297162.656 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 891
2019-03-22T23:26:02.706Z,1553297162.706 [WetLabsBB2FL] Loaded
2019-03-22T23:26:02.706Z,1553297162.706 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-03-22T23:26:02.707Z,1553297162.707 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A1D4E0
2019-03-22T23:26:02.708Z,1553297162.708 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 892
2019-03-22T23:26:02.708Z,1553297162.708 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-22T23:26:02.709Z,1553297162.709 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-22T23:26:03.034Z,1553297163.034 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-22T23:26:03.036Z,1553297163.036 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-22T23:26:03.196Z,1553297163.196 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-22T23:26:03.207Z,1553297163.207 [SBIT] Loaded
2019-03-22T23:26:03.208Z,1553297163.208 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-22T23:26:03.209Z,1553297163.209 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-22T23:26:03.221Z,1553297163.221 [IBIT] Loaded
2019-03-22T23:26:03.221Z,1553297163.221 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-22T23:26:03.224Z,1553297163.224 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-22T23:26:03.368Z,1553297163.368 [CBIT] Loaded
2019-03-22T23:26:03.369Z,1553297163.369 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-22T23:26:03.369Z,1553297163.369 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-22T23:26:03.373Z,1553297163.373 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-22T23:26:03.374Z,1553297163.374 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-22T23:26:03.381Z,1553297163.381 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-22T23:26:03.382Z,1553297163.382 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0
2019-03-22T23:26:03.382Z,1553297163.382 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 893
2019-03-22T23:26:03.387Z,1553297163.387 [Supervisor](INFO): Main Thread ID is 801
2019-03-22T23:26:03.387Z,1553297163.387 [Supervisor](DEBUG): Running supervisor.
2019-03-22T23:26:03.388Z,1553297163.388 [CommandLine ThreadHandler](INFO): Handler Thread ID is 894
2019-03-22T23:26:03.390Z,1553297163.390 [controlThread ThreadHandler](INFO): Handler Thread ID is 895
2019-03-22T23:26:03.390Z,1553297163.390 [controlThread](DEBUG): Initializing ControlThread
2019-03-22T23:26:03.391Z,1553297163.391 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-22T23:26:03.393Z,1553297163.393 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-22T23:26:03.393Z,1553297163.393 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-22T23:26:03.394Z,1553297163.394 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-22T23:26:03.395Z,1553297163.395 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-22T23:26:03.395Z,1553297163.395 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-22T23:26:03.395Z,1553297163.395 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-22T23:26:03.396Z,1553297163.396 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-22T23:26:03.396Z,1553297163.396 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-22T23:26:03.397Z,1553297163.397 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-22T23:26:03.398Z,1553297163.398 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T23:26:03.398Z,1553297163.398 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-22T23:26:03.399Z,1553297163.399 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-22T23:26:03.402Z,1553297163.402 [SBIT](INFO): Initialize SBIT Component.
2019-03-22T23:26:03.403Z,1553297163.403 [SBIT](IMPORTANT): git: 2019-03-18-18-g6cf6cc5
2019-03-22T23:26:03.403Z,1553297163.403 [SBIT](INFO): git hash: 6cf6cc5c1826bb1f8f9a0dab23a9d4b1019792a5
2019-03-22T23:26:03.403Z,1553297163.403 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-22T23:26:03.404Z,1553297163.404 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-03-22T23:26:03.405Z,1553297163.405 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-22T23:26:03.406Z,1553297163.406 [IBIT](INFO): Initialize IBIT Component.
2019-03-22T23:26:03.407Z,1553297163.407 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-22T23:26:03.408Z,1553297163.408 [logger ThreadHandler](INFO): Handler Thread ID is 896
2019-03-22T23:26:03.421Z,1553297163.421 [CBIT](DEBUG): Initialized mux pins.
2019-03-22T23:26:03.421Z,1553297163.421 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-22T23:26:03.425Z,1553297163.425 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 897
2019-03-22T23:26:03.426Z,1553297163.426 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-22T23:26:03.433Z,1553297163.433 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 898
2019-03-22T23:26:03.441Z,1553297163.441 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 899
2019-03-22T23:26:03.442Z,1553297163.442 [Rowe_600LCM](INFO): Initializing
2019-03-22T23:26:03.445Z,1553297163.445 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-22T23:26:03.445Z,1553297163.445 [CBIT](DEBUG): Initializing heartbeat.
2019-03-22T23:26:03.517Z,1553297163.517 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-22T23:26:03.541Z,1553297163.541 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-22T23:26:03.542Z,1553297163.542 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 900
2019-03-22T23:26:03.549Z,1553297163.549 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 901
2019-03-22T23:26:03.551Z,1553297163.551 [WetLabsBB2FL](INFO): Powering down
2019-03-22T23:26:03.581Z,1553297163.581 [CBIT](DEBUG): Backplane powered.
2019-03-22T23:26:03.697Z,1553297163.697 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-22T23:26:03.708Z,1553297163.708 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 902
2019-03-22T23:26:03.811Z,1553297163.811 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-22T23:26:03.811Z,1553297163.811 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-22T23:26:03.812Z,1553297163.812 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-22T23:26:03.812Z,1553297163.812 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-22T23:26:03.812Z,1553297163.812 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-22T23:26:03.812Z,1553297163.812 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-22T23:26:03.812Z,1553297163.812 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-22T23:26:03.813Z,1553297163.813 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-22T23:26:03.813Z,1553297163.813 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-22T23:26:03.813Z,1553297163.813 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-22T23:26:03.813Z,1553297163.813 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-22T23:26:03.813Z,1553297163.813 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-22T23:26:03.814Z,1553297163.814 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-22T23:26:03.814Z,1553297163.814 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-22T23:26:03.814Z,1553297163.814 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-22T23:26:03.814Z,1553297163.814 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-22T23:26:03.837Z,1553297163.837 [Radio_Surface](INFO): Powering up
2019-03-22T23:26:03.878Z,1553297163.878 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-22T23:26:03.917Z,1553297163.917 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-03-22T23:26:03.918Z,1553297163.918 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-22T23:26:03.994Z,1553297163.994 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-22T23:26:03.995Z,1553297163.995 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-22T23:26:03.997Z,1553297163.997 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-22T23:26:04.034Z,1553297164.034 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-22T23:26:04.049Z,1553297164.049 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-22T23:26:04.071Z,1553297164.071 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-22T23:26:04.082Z,1553297164.082 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-03-22T23:26:04.086Z,1553297164.086 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,ElevatorServo,MassServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-22T23:26:04.104Z,1553297164.104 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-03-22T23:26:04.146Z,1553297164.146 [BPC1](INFO): Start
2019-03-22T23:26:04.177Z,1553297164.177 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-22T23:26:04.294Z,1553297164.294 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:26:04.333Z,1553297164.333 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-22T23:26:04.334Z,1553297164.334 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:26:04.345Z,1553297164.345 [MassServo](DEBUG): Initializing MassServo.
2019-03-22T23:26:04.346Z,1553297164.346 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:26:04.358Z,1553297164.358 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-22T23:26:04.542Z,1553297164.542 [BPC1](INFO): Starting
2019-03-22T23:26:04.549Z,1553297164.549 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T23:26:04.676Z,1553297164.676 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./auv-shared/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-03-22T23:26:04.929Z,1553297164.929 [BPC1](INFO): Starting
2019-03-22T23:26:05.269Z,1553297165.269 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2019-03-22T23:26:05.341Z,1553297165.341 [ThrusterServo](FAULT): Thruster failed to initialize
2019-03-22T23:26:05.341Z,1553297165.341 [ThrusterServo] Communications Fault, FailCount= 1
2019-03-22T23:26:05.341Z,1553297165.341 [ThrusterServo](ERROR): Communications Fault
2019-03-22T23:26:05.345Z,1553297165.345 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2019-03-22T23:26:05.370Z,1553297165.370 [BPC1](INFO): Starting
2019-03-22T23:26:05.450Z,1553297165.450 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-22T23:26:05.450Z,1553297165.450 [ThrusterServo](INFO): Powering down
2019-03-22T23:26:05.772Z,1553297165.772 [BPC1](INFO): Starting
2019-03-22T23:26:06.189Z,1553297166.189 [BPC1](INFO): Starting
2019-03-22T23:26:06.208Z,1553297166.208 [CBIT](INFO): Clearing failed state for component ThrusterServo
2019-03-22T23:26:06.208Z,1553297166.208 [ThrusterServo] No Fault, FailCount= 1
2019-03-22T23:26:06.581Z,1553297166.581 [BPC1](INFO): Starting
2019-03-22T23:26:06.593Z,1553297166.593 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:26:06.593Z,1553297166.593 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-22T23:26:06.985Z,1553297166.985 [BPC1](INFO): Starting
2019-03-22T23:26:07.401Z,1553297167.401 [BPC1](INFO): Starting
2019-03-22T23:26:07.837Z,1553297167.837 [BPC1](INFO): Starting
2019-03-22T23:26:08.204Z,1553297168.204 [BPC1](INFO): Starting
2019-03-22T23:26:08.661Z,1553297168.661 [BPC1](INFO): Starting
2019-03-22T23:26:09.012Z,1553297169.012 [BPC1](INFO): Starting
2019-03-22T23:26:09.416Z,1553297169.416 [BPC1](INFO): Starting
2019-03-22T23:26:09.841Z,1553297169.841 [BPC1](INFO): Starting
2019-03-22T23:26:10.225Z,1553297170.225 [BPC1](INFO): Starting
2019-03-22T23:26:10.628Z,1553297170.628 [BPC1](INFO): Starting
2019-03-22T23:26:11.039Z,1553297171.039 [BPC1](INFO): Starting
2019-03-22T23:26:11.437Z,1553297171.437 [BPC1](INFO): Starting
2019-03-22T23:26:11.840Z,1553297171.840 [BPC1](INFO): Starting
2019-03-22T23:26:12.251Z,1553297172.251 [BPC1](INFO): Starting
2019-03-22T23:26:12.652Z,1553297172.652 [BPC1](INFO): Starting
2019-03-22T23:26:13.066Z,1553297173.066 [BPC1](INFO): Starting
2019-03-22T23:26:13.464Z,1553297173.464 [BPC1](INFO): Starting
2019-03-22T23:26:13.870Z,1553297173.870 [BPC1](INFO): Starting
2019-03-22T23:26:14.264Z,1553297174.264 [BPC1](INFO): Starting
2019-03-22T23:26:14.676Z,1553297174.676 [BPC1](INFO): Starting
2019-03-22T23:26:15.081Z,1553297175.081 [BPC1](INFO): Starting
2019-03-22T23:26:15.476Z,1553297175.476 [BPC1](INFO): Starting
2019-03-22T23:26:15.881Z,1553297175.881 [BPC1](INFO): Starting
2019-03-22T23:26:16.290Z,1553297176.290 [BPC1](INFO): Starting
2019-03-22T23:26:16.686Z,1553297176.686 [BPC1](INFO): Starting
2019-03-22T23:26:17.093Z,1553297177.093 [BPC1](INFO): Starting
2019-03-22T23:26:17.497Z,1553297177.497 [BPC1](INFO): Starting
2019-03-22T23:26:17.901Z,1553297177.901 [BPC1](INFO): Starting
2019-03-22T23:26:18.305Z,1553297178.305 [BPC1](INFO): Starting
2019-03-22T23:26:18.708Z,1553297178.708 [BPC1](INFO): Starting
2019-03-22T23:26:19.112Z,1553297179.112 [BPC1](INFO): Starting
2019-03-22T23:26:19.516Z,1553297179.516 [BPC1](INFO): Starting
2019-03-22T23:26:19.921Z,1553297179.921 [BPC1](INFO): Starting
2019-03-22T23:26:20.328Z,1553297180.328 [BPC1](INFO): Starting
2019-03-22T23:26:20.729Z,1553297180.729 [BPC1](INFO): Starting
2019-03-22T23:26:21.132Z,1553297181.132 [BPC1](INFO): Starting
2019-03-22T23:26:21.536Z,1553297181.536 [BPC1](INFO): Starting
2019-03-22T23:26:21.942Z,1553297181.942 [BPC1](INFO): Starting
2019-03-22T23:26:22.345Z,1553297182.345 [BPC1](INFO): Starting
2019-03-22T23:26:22.748Z,1553297182.748 [BPC1](INFO): Starting
2019-03-22T23:26:23.154Z,1553297183.154 [BPC1](INFO): Starting
2019-03-22T23:26:23.557Z,1553297183.557 [BPC1](INFO): Starting
2019-03-22T23:26:23.962Z,1553297183.962 [BPC1](INFO): Starting
2019-03-22T23:26:24.365Z,1553297184.365 [BPC1](INFO): Starting
2019-03-22T23:26:24.770Z,1553297184.770 [BPC1](INFO): Starting
2019-03-22T23:26:25.172Z,1553297185.172 [BPC1](INFO): Starting
2019-03-22T23:26:25.576Z,1553297185.576 [BPC1](INFO): Starting
2019-03-22T23:26:25.984Z,1553297185.984 [BPC1](INFO): Starting
2019-03-22T23:26:26.384Z,1553297186.384 [BPC1](INFO): Starting
2019-03-22T23:26:26.788Z,1553297186.788 [BPC1](INFO): Starting
2019-03-22T23:26:27.192Z,1553297187.192 [BPC1](INFO): Starting
2019-03-22T23:26:27.596Z,1553297187.596 [BPC1](INFO): Starting
2019-03-22T23:26:28.000Z,1553297188.000 [BPC1](INFO): Starting
2019-03-22T23:26:28.404Z,1553297188.404 [BPC1](INFO): Starting
2019-03-22T23:26:28.812Z,1553297188.812 [BPC1](INFO): Starting
2019-03-22T23:26:29.216Z,1553297189.216 [BPC1](INFO): Starting
2019-03-22T23:26:29.628Z,1553297189.628 [BPC1](INFO): Starting
2019-03-22T23:26:29.998Z,1553297189.998 [NAL9602](INFO): Powering up NAL9602
2019-03-22T23:26:30.021Z,1553297190.021 [BPC1](INFO): Starting
2019-03-22T23:26:30.425Z,1553297190.425 [BPC1](INFO): Starting
2019-03-22T23:26:30.828Z,1553297190.828 [BPC1](INFO): Starting
2019-03-22T23:26:31.232Z,1553297191.232 [BPC1](INFO): Starting
2019-03-22T23:26:31.636Z,1553297191.636 [BPC1](INFO): Starting
2019-03-22T23:26:32.042Z,1553297192.042 [BPC1](INFO): Starting
2019-03-22T23:26:32.063Z,1553297192.063 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-22T23:26:32.071Z,1553297192.071 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-22T23:26:32.448Z,1553297192.448 [BPC1](INFO): Starting
2019-03-22T23:26:32.872Z,1553297192.872 [BPC1](INFO): Starting
2019-03-22T23:26:33.254Z,1553297193.254 [BPC1](INFO): Starting
2019-03-22T23:26:33.657Z,1553297193.657 [BPC1](INFO): Starting
2019-03-22T23:26:34.061Z,1553297194.061 [BPC1](INFO): Starting
2019-03-22T23:26:34.571Z,1553297194.571 [BPC1](INFO): Starting
2019-03-22T23:26:34.964Z,1553297194.964 [BPC1](INFO): Starting
2019-03-22T23:26:35.355Z,1553297195.355 [BPC1](INFO): Starting
2019-03-22T23:26:35.759Z,1553297195.759 [BPC1](INFO): Starting
2019-03-22T23:26:36.163Z,1553297196.163 [BPC1](INFO): Starting
2019-03-22T23:26:36.566Z,1553297196.566 [BPC1](INFO): Starting
2019-03-22T23:26:36.976Z,1553297196.976 [BPC1](INFO): Starting
2019-03-22T23:26:37.374Z,1553297197.374 [BPC1](INFO): Starting
2019-03-22T23:26:37.779Z,1553297197.779 [BPC1](INFO): Starting
2019-03-22T23:26:38.186Z,1553297198.186 [BPC1](INFO): Starting
2019-03-22T23:26:38.592Z,1553297198.592 [BPC1](INFO): Starting
2019-03-22T23:26:38.990Z,1553297198.990 [BPC1](INFO): Starting
2019-03-22T23:26:39.394Z,1553297199.394 [BPC1](INFO): Starting
2019-03-22T23:26:39.798Z,1553297199.798 [BPC1](INFO): Starting
2019-03-22T23:26:40.202Z,1553297200.202 [BPC1](INFO): Starting
2019-03-22T23:26:40.618Z,1553297200.618 [BPC1](INFO): Starting
2019-03-22T23:26:40.986Z,1553297200.986 [NAL9602](INFO): NAL9602 initialized
2019-03-22T23:26:41.011Z,1553297201.011 [BPC1](INFO): Starting
2019-03-22T23:26:41.434Z,1553297201.434 [BPC1](INFO): Starting
2019-03-22T23:26:41.805Z,1553297201.805 [NAL9602](DEBUG): Fix Requested
2019-03-22T23:26:41.837Z,1553297201.837 [BPC1](INFO): Starting
2019-03-22T23:26:42.241Z,1553297202.241 [BPC1](INFO): Starting
2019-03-22T23:26:42.672Z,1553297202.672 [BPC1](INFO): Starting
2019-03-22T23:26:43.049Z,1553297203.049 [BPC1](INFO): Starting
2019-03-22T23:26:43.142Z,1553297203.142 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.021453
CHAN A1 (24V): 0.001094
CHAN A2 (12V): -0.068059
CHAN A3 (5V): -0.002415
CHAN B0 (3.3V): -0.001793
CHAN B1 (3.15aV): -0.001288
CHAN B2 (3.15bV): -0.000431
CHAN B3 (GND): -0.000142
OPEN: -0.001327
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-22T23:26:43.445Z,1553297203.445 [BPC1](INFO): Starting
2019-03-22T23:26:43.840Z,1553297203.840 [BPC1](INFO): Starting
2019-03-22T23:26:44.259Z,1553297204.259 [BPC1](INFO): Starting
2019-03-22T23:26:44.662Z,1553297204.662 [BPC1](INFO): Starting
2019-03-22T23:26:45.081Z,1553297205.081 [BPC1](INFO): Starting
2019-03-22T23:26:45.108Z,1553297205.108 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T23:26:45.474Z,1553297205.474 [BPC1](INFO): Starting
2019-03-22T23:26:45.889Z,1553297205.889 [BPC1](INFO): Starting
2019-03-22T23:26:46.267Z,1553297206.267 [BPC1](INFO): Starting
2019-03-22T23:26:46.682Z,1553297206.682 [BPC1](INFO): Starting
2019-03-22T23:26:47.072Z,1553297207.072 [BPC1](INFO): Starting
2019-03-22T23:26:47.614Z,1553297207.614 [BPC1](INFO): Starting
2019-03-22T23:26:48.006Z,1553297208.006 [BPC1](INFO): Starting
2019-03-22T23:26:48.410Z,1553297208.410 [BPC1](INFO): Starting
2019-03-22T23:26:48.824Z,1553297208.824 [BPC1](INFO): Starting
2019-03-22T23:26:49.229Z,1553297209.229 [BPC1](INFO): Starting
2019-03-22T23:26:49.636Z,1553297209.636 [BPC1](INFO): Starting
2019-03-22T23:26:50.045Z,1553297210.045 [BPC1](INFO): Starting
2019-03-22T23:26:50.431Z,1553297210.431 [BPC1](INFO): Starting
2019-03-22T23:26:50.834Z,1553297210.834 [BPC1](INFO): Starting
2019-03-22T23:26:51.237Z,1553297211.237 [BPC1](INFO): Starting
2019-03-22T23:26:51.642Z,1553297211.642 [BPC1](INFO): Starting
2019-03-22T23:26:52.050Z,1553297212.050 [BPC1](INFO): Starting
2019-03-22T23:26:52.459Z,1553297212.459 [BPC1](INFO): Starting
2019-03-22T23:26:52.856Z,1553297212.856 [BPC1](INFO): Starting
2019-03-22T23:26:53.281Z,1553297213.281 [BPC1](INFO): Starting
2019-03-22T23:26:53.665Z,1553297213.665 [BPC1](INFO): Starting
2019-03-22T23:26:54.077Z,1553297214.077 [BPC1](INFO): Starting
2019-03-22T23:26:54.481Z,1553297214.481 [BPC1](INFO): Starting
2019-03-22T23:26:54.884Z,1553297214.884 [BPC1](INFO): Starting
2019-03-22T23:26:55.288Z,1553297215.288 [BPC1](INFO): Starting
2019-03-22T23:26:55.682Z,1553297215.682 [BPC1](INFO): Starting
2019-03-22T23:26:56.089Z,1553297216.089 [BPC1](INFO): Starting
2019-03-22T23:26:56.494Z,1553297216.494 [BPC1](INFO): Starting
2019-03-22T23:26:56.894Z,1553297216.894 [BPC1](INFO): Starting
2019-03-22T23:26:57.298Z,1553297217.298 [BPC1](INFO): Starting
2019-03-22T23:26:57.702Z,1553297217.702 [BPC1](INFO): Starting
2019-03-22T23:26:58.106Z,1553297218.106 [BPC1](INFO): Starting
2019-03-22T23:26:58.510Z,1553297218.510 [BPC1](INFO): Starting
2019-03-22T23:26:58.913Z,1553297218.913 [BPC1](INFO): Starting
2019-03-22T23:26:59.327Z,1553297219.327 [BPC1](INFO): Starting
2019-03-22T23:26:59.733Z,1553297219.733 [BPC1](INFO): Starting
2019-03-22T23:27:00.139Z,1553297220.139 [BPC1](INFO): Starting
2019-03-22T23:27:00.545Z,1553297220.545 [BPC1](INFO): Starting
2019-03-22T23:27:00.933Z,1553297220.933 [BPC1](INFO): Starting
2019-03-22T23:27:01.337Z,1553297221.337 [BPC1](INFO): Starting
2019-03-22T23:27:01.744Z,1553297221.744 [BPC1](INFO): Starting
2019-03-22T23:27:02.146Z,1553297222.146 [BPC1](INFO): Starting
2019-03-22T23:27:02.550Z,1553297222.550 [BPC1](INFO): Starting
2019-03-22T23:27:02.977Z,1553297222.977 [BPC1](INFO): Starting
2019-03-22T23:27:03.361Z,1553297223.361 [BPC1](INFO): Starting
2019-03-22T23:27:03.770Z,1553297223.770 [BPC1](INFO): Starting
2019-03-22T23:27:04.166Z,1553297224.166 [BPC1](INFO): Starting
2019-03-22T23:27:04.592Z,1553297224.592 [BPC1](INFO): Starting
2019-03-22T23:27:04.617Z,1553297224.617 [BPC1](INFO): Initialized.
2019-03-22T23:27:05.401Z,1553297225.401 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:27:07.035Z,1553297227.035 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:27:10.667Z,1553297230.667 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:27:10.706Z,1553297230.706 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:27:10.706Z,1553297230.706 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:27:11.076Z,1553297231.076 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:27:11.857Z,1553297231.857 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:27:11.908Z,1553297231.908 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T23:27:11.908Z,1553297231.908 [SBIT](FAULT): Control surface position failure.
2019-03-22T23:27:18.806Z,1553297238.806 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:27:23.251Z,1553297243.251 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:27:23.429Z,1553297243.429 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:27:23.429Z,1553297243.429 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:27:23.680Z,1553297243.680 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:27:24.481Z,1553297244.481 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:27:25.314Z,1553297245.314 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T23:27:25.314Z,1553297245.314 [SBIT](FAULT): Control surface position failure.
2019-03-22T23:27:25.732Z,1553297245.732 [SBIT](CRITICAL): SBIT FAILED
2019-03-22T23:27:25.781Z,1553297245.781 [CommandLine](IMPORTANT): got command configSet list
2019-03-22T23:27:25.781Z,1553297245.781 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-22T23:27:25.782Z,1553297245.782 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-22T23:27:25.782Z,1553297245.782 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=10 minute;

2019-03-22T23:27:25.782Z,1553297245.782 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool;

2019-03-22T23:27:25.782Z,1553297245.782 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-03-22T23:27:25.783Z,1553297245.783 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-03-22T23:27:25.783Z,1553297245.783 [CommandLine](IMPORTANT): Depth_Keller.offset=-0.13 decibar;

2019-03-22T23:27:25.783Z,1553297245.783 [CommandLine](IMPORTANT): Depth_Keller.scale=45.92 micropascal;

2019-03-22T23:27:25.783Z,1553297245.783 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-03-22T23:27:25.783Z,1553297245.783 [CommandLine](IMPORTANT): PNI_TCM.loadAtStartup=1 bool;

2019-03-22T23:27:25.783Z,1553297245.783 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2019-03-22T23:27:25.783Z,1553297245.783 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2019-03-22T23:27:25.794Z,1553297245.794 [CommandLine](FAULT): Scheduling is paused
2019-03-22T23:27:25.794Z,1553297245.794 [CBIT](INFO): Critical error at 20190322T232725
2019-03-22T23:27:25.795Z,1553297245.795 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-22T23:27:26.127Z,1553297246.127 [MissionManager](IMPORTANT): Started mission Startup
2019-03-22T23:27:26.127Z,1553297246.127 [Startup] Running Loop=1
2019-03-22T23:27:26.127Z,1553297246.127 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-22T23:27:26.127Z,1553297246.127 [Startup:A.GoToSurface] Running Loop=1
2019-03-22T23:27:26.127Z,1553297246.127 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-22T23:27:26.128Z,1553297246.128 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-22T23:27:26.128Z,1553297246.128 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-22T23:27:26.153Z,1553297246.153 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-22T23:27:26.153Z,1553297246.153 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-22T23:27:26.154Z,1553297246.154 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-22T23:27:26.155Z,1553297246.155 [Startup:StartupSatComms] Running Loop=1
2019-03-22T23:27:26.155Z,1553297246.155 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-22T23:27:26.155Z,1553297246.155 [Startup:StartupSatComms:A] Running Loop=1
2019-03-22T23:27:26.531Z,1553297246.531 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-22T23:27:30.941Z,1553297250.941 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:27:34.967Z,1553297254.967 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:27:35.083Z,1553297255.083 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:27:35.084Z,1553297255.084 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:27:35.386Z,1553297255.386 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:27:36.201Z,1553297256.201 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:27:42.669Z,1553297262.669 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:27:47.097Z,1553297267.097 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:27:47.210Z,1553297267.210 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:27:47.210Z,1553297267.210 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:27:47.516Z,1553297267.516 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:27:48.317Z,1553297268.317 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:27:55.178Z,1553297275.178 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:27:59.206Z,1553297279.206 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:27:59.321Z,1553297279.321 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:27:59.321Z,1553297279.321 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:27:59.630Z,1553297279.630 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:28:00.429Z,1553297280.429 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:28:00.770Z,1553297280.770 [CommandLine](IMPORTANT): got command get Rowe_600LCM.simulateHardware
2019-03-22T23:28:00.770Z,1553297280.770 [CommandLine](IMPORTANT): Rowe_600LCM.simulateHardware 1 bool
2019-03-22T23:28:04.021Z,1553297284.021 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T23:28:04.021Z,1553297284.021 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-03-22T23:28:04.021Z,1553297284.021 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T23:28:04.106Z,1553297284.106 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T23:28:04.435Z,1553297284.435 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T23:28:06.090Z,1553297286.090 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T23:28:06.090Z,1553297286.090 [Rowe_600LCM] No Fault, FailCount= 1
2019-03-22T23:28:06.309Z,1553297286.309 [Rowe_600LCM](INFO): Initializing
2019-03-22T23:28:06.835Z,1553297286.835 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T23:28:06.964Z,1553297286.964 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./auv-shared/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-03-22T23:28:07.312Z,1553297287.312 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:28:11.332Z,1553297291.332 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:28:11.408Z,1553297291.408 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:28:11.408Z,1553297291.408 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:28:11.740Z,1553297291.740 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:28:12.549Z,1553297292.549 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:28:19.415Z,1553297299.415 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:28:20.143Z,1553297300.143 [CommandLine](IMPORTANT): got command configSet Rowe_600LCM.simulateHardware 0.000000 bool persist
2019-03-22T23:28:20.143Z,1553297300.143 [CommandLine](IMPORTANT): configSet Rowe_600LCM.simulateHardware requires a restart to take effect.
2019-03-22T23:28:23.865Z,1553297303.865 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:28:23.923Z,1553297303.923 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:28:23.924Z,1553297303.924 [BPC1](INFO): BPC1B: got valid message (1596 bytes).
2019-03-22T23:28:24.285Z,1553297304.285 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:28:24.618Z,1553297304.618 [CommandLine](IMPORTANT): got command restart application
2019-03-22T23:28:25.085Z,1553297305.085 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:28:25.621Z,1553297305.621 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:28:25.621Z,1553297305.621 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:25.813Z,1553297305.813 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-22T23:28:25.813Z,1553297305.813 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:25.814Z,1553297305.814 [CommandLine](INFO): Join timeout helper Thread ID is 924
2019-03-22T23:28:25.814Z,1553297305.814 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-22T23:28:25.814Z,1553297305.814 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:25.815Z,1553297305.815 [NavChartDb](INFO): Join timeout helper Thread ID is 925
2019-03-22T23:28:26.193Z,1553297306.193 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:28:26.193Z,1553297306.193 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.201Z,1553297306.201 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-03-22T23:28:26.201Z,1553297306.201 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.201Z,1553297306.201 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 926
2019-03-22T23:28:26.245Z,1553297306.245 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:28:26.246Z,1553297306.246 [WetLabsBB2FL](INFO): Powering down
2019-03-22T23:28:26.247Z,1553297306.247 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.261Z,1553297306.261 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-22T23:28:26.261Z,1553297306.261 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.262Z,1553297306.262 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 927
2019-03-22T23:28:26.277Z,1553297306.277 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:28:26.277Z,1553297306.277 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.281Z,1553297306.281 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-03-22T23:28:26.281Z,1553297306.281 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.281Z,1553297306.281 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 928
2019-03-22T23:28:26.286Z,1553297306.286 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-22T23:27:26.2Z
2019-03-22T23:28:26.286Z,1553297306.286 [Startup:StartupSatComms:A] Stopped
2019-03-22T23:28:26.286Z,1553297306.286 [Startup:StartupSatComms:B] Running Loop=1
2019-03-22T23:28:26.666Z,1553297306.666 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:28:26.666Z,1553297306.666 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T23:28:26.725Z,1553297306.725 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-22T23:28:26.872Z,1553297306.872 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.878Z,1553297306.878 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-22T23:28:26.878Z,1553297306.878 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.879Z,1553297306.879 [Radio_Surface](INFO): Join timeout helper Thread ID is 930
2019-03-22T23:28:26.879Z,1553297306.879 [Radio_Surface](INFO): Powering down
2019-03-22T23:28:26.880Z,1553297306.880 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:28:26.881Z,1553297306.881 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.883Z,1553297306.883 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-22T23:28:26.883Z,1553297306.883 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:26.883Z,1553297306.883 [DataOverHttps](INFO): Join timeout helper Thread ID is 931
2019-03-22T23:28:27.269Z,1553297307.269 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:28:27.269Z,1553297307.269 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.278Z,1553297307.278 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-22T23:28:27.278Z,1553297307.278 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.278Z,1553297307.278 [logger](INFO): Join timeout helper Thread ID is 936
2019-03-22T23:28:27.281Z,1553297307.281 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:28:27.281Z,1553297307.281 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.297Z,1553297307.297 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-22T23:28:27.298Z,1553297307.298 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.298Z,1553297307.298 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-22T23:28:27.298Z,1553297307.298 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.298Z,1553297307.298 [controlThread](INFO): Join timeout helper Thread ID is 937
2019-03-22T23:28:27.561Z,1553297307.561 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:28:27.561Z,1553297307.561 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-22T23:28:27.562Z,1553297307.562 [NAL9602](INFO): Powering down
2019-03-22T23:28:27.563Z,1553297307.563 [PNI_TCM](INFO): Powering down
2019-03-22T23:28:27.649Z,1553297307.649 [BPC1](INFO): uninitialize
2019-03-22T23:28:27.650Z,1553297307.650 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-22T23:28:27.651Z,1553297307.651 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-22T23:28:27.652Z,1553297307.652 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-22T23:28:27.652Z,1553297307.652 [MissionManager](INFO): Uninitializing Mission Default
2019-03-22T23:28:27.655Z,1553297307.655 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-22T23:28:27.655Z,1553297307.655 [Startup] Stopped
2019-03-22T23:28:27.655Z,1553297307.655 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-22T23:28:27.655Z,1553297307.655 [Startup:A.GoToSurface] Stopped
2019-03-22T23:28:27.655Z,1553297307.655 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-22T23:28:27.655Z,1553297307.655 [Startup:StartupSatComms] Stopped
2019-03-22T23:28:27.655Z,1553297307.655 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-22T23:28:27.655Z,1553297307.655 [Startup:StartupSatComms:B] Stopped
2019-03-22T23:28:27.657Z,1553297307.657 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-22T23:28:27.657Z,1553297307.657 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-22T23:28:27.657Z,1553297307.657 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-22T23:28:27.658Z,1553297307.658 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-22T23:28:27.658Z,1553297307.658 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-22T23:28:27.658Z,1553297307.658 [ElevatorServo](INFO): Powering down
2019-03-22T23:28:27.659Z,1553297307.659 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-22T23:28:27.659Z,1553297307.659 [MassServo](INFO): Powering down
2019-03-22T23:28:27.660Z,1553297307.660 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-22T23:28:27.660Z,1553297307.660 [ThrusterServo](INFO): Powering down
2019-03-22T23:28:27.661Z,1553297307.661 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-22T23:28:27.661Z,1553297307.661 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-22T23:28:27.662Z,1553297307.662 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-22T23:28:27.662Z,1553297307.662 [CBIT](DEBUG): Powering off loads.
2019-03-22T23:28:27.673Z,1553297307.673 [CBIT](DEBUG): Disabling WDT.
2019-03-22T23:28:27.685Z,1553297307.685 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-22T23:28:27.685Z,1553297307.685 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.742Z,1553297307.742 [BPC1](INFO): uninitialize
2019-03-22T23:28:27.784Z,1553297307.784 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.790Z,1553297307.790 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.795Z,1553297307.795 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.827Z,1553297307.827 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.829Z,1553297307.829 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.862Z,1553297307.862 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:28:27.925Z,1553297307.925 [logger ThreadHandler](INFO): Thread cancelled.