2019-03-22T23:19:51.856Z,1553296791.856 [Supervisor](DEBUG): Initializing supervisor.
2019-03-22T23:19:51.858Z,1553296791.858 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-22T23:19:51.859Z,1553296791.859 [SyncHandler](INFO): Protected caller Thread ID is 6373
2019-03-22T23:19:51.860Z,1553296791.860 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-22T23:19:51.861Z,1553296791.861 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-22T23:19:51.861Z,1553296791.861 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6374
2019-03-22T23:19:51.864Z,1553296791.864 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-22T23:19:51.876Z,1553296791.876 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-22T23:19:51.877Z,1553296791.877 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-22T23:19:51.877Z,1553296791.877 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6375
2019-03-22T23:19:51.878Z,1553296791.878 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-22T23:19:51.879Z,1553296791.879 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-22T23:19:51.879Z,1553296791.879 [logger ThreadHandler](INFO): Protected caller Thread ID is 6376
2019-03-22T23:19:51.881Z,1553296791.881 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-22T23:19:51.882Z,1553296791.882 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-22T23:19:51.883Z,1553296791.883 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-22T23:19:52.301Z,1553296792.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-22T23:19:52.302Z,1553296792.302 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-22T23:19:52.446Z,1553296792.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-22T23:19:52.446Z,1553296792.446 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-22T23:19:52.527Z,1553296792.527 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-22T23:19:52.627Z,1553296792.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-22T23:19:52.627Z,1553296792.627 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-22T23:19:52.723Z,1553296792.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-22T23:19:52.724Z,1553296792.724 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-22T23:19:53.089Z,1553296793.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-22T23:19:53.089Z,1553296793.089 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-22T23:19:53.192Z,1553296793.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-22T23:19:53.193Z,1553296793.193 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-22T23:19:53.646Z,1553296793.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-22T23:19:53.646Z,1553296793.646 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-22T23:19:53.846Z,1553296793.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-22T23:19:53.847Z,1553296793.847 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-22T23:19:54.148Z,1553296794.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-22T23:19:54.149Z,1553296794.149 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-22T23:19:54.360Z,1553296794.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-22T23:19:54.361Z,1553296794.361 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-22T23:19:54.502Z,1553296794.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-22T23:19:54.503Z,1553296794.503 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-22T23:19:54.988Z,1553296794.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-22T23:19:54.989Z,1553296794.989 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-22T23:19:55.360Z,1553296795.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-22T23:19:55.360Z,1553296795.360 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-22T23:19:55.874Z,1553296795.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-22T23:19:55.876Z,1553296795.876 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-03-22T23:19:55.877Z,1553296795.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-03-22T23:19:56.136Z,1553296796.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-03-22T23:19:56.220Z,1553296796.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-03-22T23:19:56.334Z,1553296796.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-03-22T23:19:56.421Z,1553296796.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-03-22T23:19:56.646Z,1553296796.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-22T23:19:56.647Z,1553296796.647 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-03-22T23:19:56.746Z,1553296796.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-03-22T23:19:56.855Z,1553296796.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-03-22T23:19:56.950Z,1553296796.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-03-22T23:19:57.101Z,1553296797.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-03-22T23:19:57.203Z,1553296797.203 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-03-22T23:19:57.303Z,1553296797.303 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-03-22T23:19:57.303Z,1553296797.303 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-22T23:19:57.314Z,1553296797.314 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-22T23:19:57.327Z,1553296797.327 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-22T23:19:57.327Z,1553296797.327 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-22T23:19:57.392Z,1553296797.392 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-22T23:19:57.502Z,1553296797.502 [VerticalControl] Loaded
2019-03-22T23:19:57.502Z,1553296797.502 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-22T23:19:57.503Z,1553296797.503 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-22T23:19:57.570Z,1553296797.570 [HorizontalControl] Loaded
2019-03-22T23:19:57.570Z,1553296797.570 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-22T23:19:57.570Z,1553296797.570 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-22T23:19:57.576Z,1553296797.576 [SpeedControl] Loaded
2019-03-22T23:19:57.576Z,1553296797.576 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-22T23:19:57.577Z,1553296797.577 [LoopControl](DEBUG): Construct LoopControl.
2019-03-22T23:19:57.577Z,1553296797.577 [LoopControl] Loaded
2019-03-22T23:19:57.577Z,1553296797.577 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-22T23:19:57.578Z,1553296797.578 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-22T23:19:57.578Z,1553296797.578 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-22T23:19:57.690Z,1553296797.690 [ElevatorServo] Loaded
2019-03-22T23:19:57.691Z,1553296797.691 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-22T23:19:57.701Z,1553296797.701 [MassServo] Loaded
2019-03-22T23:19:57.702Z,1553296797.702 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-22T23:19:57.712Z,1553296797.712 [ThrusterServo] Loaded
2019-03-22T23:19:57.713Z,1553296797.713 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-22T23:19:57.713Z,1553296797.713 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-22T23:19:57.714Z,1553296797.714 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-22T23:19:57.753Z,1553296797.753 [DepthRateCalculator] Loaded
2019-03-22T23:19:57.753Z,1553296797.753 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-22T23:19:57.758Z,1553296797.758 [PitchRateCalculator] Loaded
2019-03-22T23:19:57.758Z,1553296797.758 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-22T23:19:57.773Z,1553296797.773 [SpeedCalculator] Loaded
2019-03-22T23:19:57.773Z,1553296797.773 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-22T23:19:57.793Z,1553296797.793 [TempGradientCalculator] Loaded
2019-03-22T23:19:57.794Z,1553296797.794 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-22T23:19:57.798Z,1553296797.798 [YawRateCalculator] Loaded
2019-03-22T23:19:57.799Z,1553296797.799 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-22T23:19:57.827Z,1553296797.827 [ElevatorOffsetCalculator] Loaded
2019-03-22T23:19:57.827Z,1553296797.827 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-22T23:19:57.828Z,1553296797.828 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-22T23:19:57.828Z,1553296797.828 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-22T23:19:57.929Z,1553296797.929 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-22T23:19:57.930Z,1553296797.930 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-22T23:19:57.986Z,1553296797.986 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-22T23:19:57.987Z,1553296797.987 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-22T23:19:58.007Z,1553296798.007 [NavChart] Loaded
2019-03-22T23:19:58.007Z,1553296798.007 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-22T23:19:58.011Z,1553296798.011 [UniversalFixResidualReporter] Loaded
2019-03-22T23:19:58.011Z,1553296798.011 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-22T23:19:58.012Z,1553296798.012 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-22T23:19:58.012Z,1553296798.012 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-22T23:19:58.318Z,1553296798.318 [DataOverHttps] Loaded
2019-03-22T23:19:58.318Z,1553296798.318 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-22T23:19:58.319Z,1553296798.319 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408004E0
2019-03-22T23:19:58.320Z,1553296798.320 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6455
2019-03-22T23:19:58.333Z,1553296798.333 [Depth_Keller] Loaded
2019-03-22T23:19:58.333Z,1553296798.333 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-22T23:19:58.427Z,1553296798.427 [NAL9602] Loaded
2019-03-22T23:19:58.427Z,1553296798.427 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-22T23:19:58.442Z,1553296798.442 [Onboard] Loaded
2019-03-22T23:19:58.442Z,1553296798.442 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-22T23:19:58.445Z,1553296798.445 [Radio_Surface] Loaded
2019-03-22T23:19:58.446Z,1553296798.446 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-22T23:19:58.447Z,1553296798.447 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408304E0
2019-03-22T23:19:58.447Z,1553296798.447 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6456
2019-03-22T23:19:58.493Z,1553296798.493 [PNI_TCM] Loaded
2019-03-22T23:19:58.493Z,1553296798.493 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-03-22T23:19:58.550Z,1553296798.550 [Rowe_600LCM] Loaded
2019-03-22T23:19:58.550Z,1553296798.550 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-03-22T23:19:58.551Z,1553296798.551 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408604E0
2019-03-22T23:19:58.552Z,1553296798.552 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6457
2019-03-22T23:19:58.575Z,1553296798.575 [BPC1](INFO): BPC1A: got serial number for stick 1: 023F
2019-03-22T23:19:58.576Z,1553296798.576 [BPC1](INFO): BPC1A: got serial number for stick 2: 02A8
2019-03-22T23:19:58.577Z,1553296798.577 [BPC1](INFO): BPC1A: got serial number for stick 3: 01D7
2019-03-22T23:19:58.578Z,1553296798.578 [BPC1](INFO): BPC1A: got serial number for stick 4: 01EF
2019-03-22T23:19:58.579Z,1553296798.579 [BPC1](INFO): BPC1A: got serial number for stick 5: 0299
2019-03-22T23:19:58.580Z,1553296798.580 [BPC1](INFO): BPC1A: got serial number for stick 6: 0292
2019-03-22T23:19:58.581Z,1553296798.581 [BPC1](INFO): BPC1A: got serial number for stick 7: 0296
2019-03-22T23:19:58.581Z,1553296798.581 [BPC1](INFO): BPC1A: got serial number for stick 8: 0291
2019-03-22T23:19:58.582Z,1553296798.582 [BPC1](INFO): BPC1A: marked stick 8 as reserve.
2019-03-22T23:19:58.582Z,1553296798.582 [BPC1](INFO): BPC1A: got serial number for stick 9: 00BB
2019-03-22T23:19:58.583Z,1553296798.583 [BPC1](INFO): BPC1A: got serial number for stick 10: 025B
2019-03-22T23:19:58.584Z,1553296798.584 [BPC1](INFO): BPC1A: got serial number for stick 11: 024E
2019-03-22T23:19:58.585Z,1553296798.585 [BPC1](INFO): BPC1A: got serial number for stick 12: 029C
2019-03-22T23:19:58.586Z,1553296798.586 [BPC1](INFO): BPC1A: got serial number for stick 13: 0242
2019-03-22T23:19:58.586Z,1553296798.586 [BPC1](INFO): BPC1A: got serial number for stick 14: 01FA
2019-03-22T23:19:58.587Z,1553296798.587 [BPC1](INFO): BPC1A: got serial number for stick 15: 0269
2019-03-22T23:19:58.588Z,1553296798.588 [BPC1](INFO): BPC1A: got serial number for stick 16: 02AD
2019-03-22T23:19:58.588Z,1553296798.588 [BPC1](INFO): BPC1A: marked stick 16 as reserve.
2019-03-22T23:19:58.589Z,1553296798.589 [BPC1](INFO): BPC1A: got serial number for stick 17: 0295
2019-03-22T23:19:58.590Z,1553296798.590 [BPC1](INFO): BPC1A: got serial number for stick 18: 0224
2019-03-22T23:19:58.591Z,1553296798.591 [BPC1](INFO): BPC1A: got serial number for stick 19: 00F6
2019-03-22T23:19:58.591Z,1553296798.591 [BPC1](INFO): BPC1A: got serial number for stick 20: 0294
2019-03-22T23:19:58.592Z,1553296798.592 [BPC1](INFO): BPC1A: got serial number for stick 21: 0271
2019-03-22T23:19:58.593Z,1553296798.593 [BPC1](INFO): BPC1A: got serial number for stick 22: 0206
2019-03-22T23:19:58.594Z,1553296798.594 [BPC1](INFO): BPC1A: got serial number for stick 23: 02B0
2019-03-22T23:19:58.594Z,1553296798.594 [BPC1](INFO): BPC1A: got serial number for stick 24: 020C
2019-03-22T23:19:58.595Z,1553296798.595 [BPC1](INFO): BPC1A: marked stick 24 as reserve.
2019-03-22T23:19:58.595Z,1553296798.595 [BPC1](INFO): BPC1A: got serial number for stick 25: 016F
2019-03-22T23:19:58.596Z,1553296798.596 [BPC1](INFO): BPC1A: got serial number for stick 26: 026A
2019-03-22T23:19:58.597Z,1553296798.597 [BPC1](INFO): BPC1A: got serial number for stick 27: 01C6
2019-03-22T23:19:58.598Z,1553296798.598 [BPC1](INFO): BPC1A: got serial number for stick 28: 0246
2019-03-22T23:19:58.598Z,1553296798.598 [BPC1](INFO): BPC1A: got serial number for stick 29: 02D3
2019-03-22T23:19:58.599Z,1553296798.599 [BPC1](INFO): BPC1A: got serial number for stick 30: 01C0
2019-03-22T23:19:58.600Z,1553296798.600 [BPC1](INFO): BPC1A: got serial number for stick 31: 01F3
2019-03-22T23:19:58.601Z,1553296798.601 [BPC1](INFO): BPC1B: got serial number for stick 32: 0163
2019-03-22T23:19:58.602Z,1553296798.602 [BPC1](INFO): BPC1B: got serial number for stick 33: 01AA
2019-03-22T23:19:58.603Z,1553296798.603 [BPC1](INFO): BPC1B: got serial number for stick 34: 0197
2019-03-22T23:19:58.604Z,1553296798.604 [BPC1](INFO): BPC1B: got serial number for stick 35: 0215
2019-03-22T23:19:58.604Z,1553296798.604 [BPC1](INFO): BPC1B: got serial number for stick 36: 020E
2019-03-22T23:19:58.605Z,1553296798.605 [BPC1](INFO): BPC1B: got serial number for stick 37: 0228
2019-03-22T23:19:58.606Z,1553296798.606 [BPC1](INFO): BPC1B: got serial number for stick 38: 02B4
2019-03-22T23:19:58.607Z,1553296798.607 [BPC1](INFO): BPC1B: got serial number for stick 39: 01AC
2019-03-22T23:19:58.607Z,1553296798.607 [BPC1](INFO): BPC1B: marked stick 39 as reserve.
2019-03-22T23:19:58.608Z,1553296798.608 [BPC1](INFO): BPC1B: got serial number for stick 40: 022A
2019-03-22T23:19:58.609Z,1553296798.609 [BPC1](INFO): BPC1B: got serial number for stick 41: 0232
2019-03-22T23:19:58.609Z,1553296798.609 [BPC1](INFO): BPC1B: got serial number for stick 42: 0219
2019-03-22T23:19:58.610Z,1553296798.610 [BPC1](INFO): BPC1B: got serial number for stick 43: 0229
2019-03-22T23:19:58.611Z,1553296798.611 [BPC1](INFO): BPC1B: got serial number for stick 44: 0253
2019-03-22T23:19:58.612Z,1553296798.612 [BPC1](INFO): BPC1B: got serial number for stick 45: 02A1
2019-03-22T23:19:58.612Z,1553296798.612 [BPC1](INFO): BPC1B: got serial number for stick 46: 028A
2019-03-22T23:19:58.613Z,1553296798.613 [BPC1](INFO): BPC1B: got serial number for stick 47: 019A
2019-03-22T23:19:58.614Z,1553296798.614 [BPC1](INFO): BPC1B: marked stick 47 as reserve.
2019-03-22T23:19:58.614Z,1553296798.614 [BPC1](INFO): BPC1B: got serial number for stick 48: 0202
2019-03-22T23:19:58.616Z,1553296798.616 [BPC1](INFO): BPC1B: got serial number for stick 49: 027C
2019-03-22T23:19:58.616Z,1553296798.616 [BPC1](INFO): BPC1B: got serial number for stick 50: 022F
2019-03-22T23:19:58.617Z,1553296798.617 [BPC1](INFO): BPC1B: got serial number for stick 51: 01FF
2019-03-22T23:19:58.618Z,1553296798.618 [BPC1](INFO): BPC1B: got serial number for stick 52: 0203
2019-03-22T23:19:58.619Z,1553296798.619 [BPC1](INFO): BPC1B: got serial number for stick 53: 02A6
2019-03-22T23:19:58.619Z,1553296798.619 [BPC1](INFO): BPC1B: got serial number for stick 54: 01D4
2019-03-22T23:19:58.620Z,1553296798.620 [BPC1](INFO): BPC1B: got serial number for stick 55: 017F
2019-03-22T23:19:58.620Z,1553296798.620 [BPC1](INFO): BPC1B: marked stick 55 as reserve.
2019-03-22T23:19:58.621Z,1553296798.621 [BPC1](INFO): BPC1B: got serial number for stick 56: 021D
2019-03-22T23:19:58.622Z,1553296798.622 [BPC1](INFO): BPC1B: got serial number for stick 57: 01BF
2019-03-22T23:19:58.623Z,1553296798.623 [BPC1](INFO): BPC1B: got serial number for stick 58: 0216
2019-03-22T23:19:58.623Z,1553296798.623 [BPC1](INFO): BPC1B: got serial number for stick 59: 024A
2019-03-22T23:19:58.624Z,1553296798.624 [BPC1](INFO): BPC1B: got serial number for stick 60: 01DA
2019-03-22T23:19:58.625Z,1553296798.625 [BPC1](INFO): BPC1B: got serial number for stick 61: 01DF
2019-03-22T23:19:58.626Z,1553296798.626 [BPC1](INFO): BPC1B: got serial number for stick 62: 0205
2019-03-22T23:20:00.040Z,1553296800.040 [BPC1] Loaded
2019-03-22T23:20:00.040Z,1553296800.040 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-22T23:20:00.040Z,1553296800.040 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-22T23:20:00.041Z,1553296800.041 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-22T23:20:00.103Z,1553296800.103 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-22T23:20:00.104Z,1553296800.104 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-22T23:20:00.195Z,1553296800.195 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-22T23:20:00.196Z,1553296800.196 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-22T23:20:00.221Z,1553296800.221 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-22T23:20:00.222Z,1553296800.222 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-22T23:20:00.463Z,1553296800.463 [CTD_NeilBrown] Loaded
2019-03-22T23:20:00.463Z,1553296800.463 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-22T23:20:00.464Z,1553296800.464 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409ED4E0
2019-03-22T23:20:00.465Z,1553296800.465 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6458
2019-03-22T23:20:00.512Z,1553296800.512 [WetLabsBB2FL] Loaded
2019-03-22T23:20:00.512Z,1553296800.512 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-03-22T23:20:00.513Z,1553296800.513 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A1D4E0
2019-03-22T23:20:00.513Z,1553296800.513 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6459
2019-03-22T23:20:00.514Z,1553296800.514 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-22T23:20:00.515Z,1553296800.515 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-22T23:20:00.943Z,1553296800.943 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-22T23:20:00.949Z,1553296800.949 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-22T23:20:01.198Z,1553296801.198 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-22T23:20:01.209Z,1553296801.209 [SBIT] Loaded
2019-03-22T23:20:01.209Z,1553296801.209 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-22T23:20:01.210Z,1553296801.210 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-22T23:20:01.225Z,1553296801.225 [IBIT] Loaded
2019-03-22T23:20:01.225Z,1553296801.225 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-22T23:20:01.228Z,1553296801.228 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-22T23:20:01.378Z,1553296801.378 [CBIT] Loaded
2019-03-22T23:20:01.378Z,1553296801.378 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-22T23:20:01.379Z,1553296801.379 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-22T23:20:01.382Z,1553296801.382 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-22T23:20:01.383Z,1553296801.383 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-22T23:20:01.390Z,1553296801.390 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-22T23:20:01.391Z,1553296801.391 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0
2019-03-22T23:20:01.391Z,1553296801.391 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6461
2019-03-22T23:20:01.396Z,1553296801.396 [Supervisor](INFO): Main Thread ID is 5682
2019-03-22T23:20:01.396Z,1553296801.396 [Supervisor](DEBUG): Running supervisor.
2019-03-22T23:20:01.396Z,1553296801.396 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6462
2019-03-22T23:20:01.399Z,1553296801.399 [controlThread ThreadHandler](INFO): Handler Thread ID is 6463
2019-03-22T23:20:01.399Z,1553296801.399 [controlThread](DEBUG): Initializing ControlThread
2019-03-22T23:20:01.400Z,1553296801.400 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-22T23:20:01.402Z,1553296801.402 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-22T23:20:01.402Z,1553296801.402 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-22T23:20:01.403Z,1553296801.403 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-22T23:20:01.404Z,1553296801.404 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-22T23:20:01.404Z,1553296801.404 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-22T23:20:01.404Z,1553296801.404 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-22T23:20:01.405Z,1553296801.405 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-22T23:20:01.405Z,1553296801.405 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-22T23:20:01.405Z,1553296801.405 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-22T23:20:01.406Z,1553296801.406 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T23:20:01.407Z,1553296801.407 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-22T23:20:01.407Z,1553296801.407 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-22T23:20:01.410Z,1553296801.410 [SBIT](INFO): Initialize SBIT Component.
2019-03-22T23:20:01.410Z,1553296801.410 [SBIT](IMPORTANT): git: 2019-03-18-18-g6cf6cc5
2019-03-22T23:20:01.411Z,1553296801.411 [SBIT](INFO): git hash: 6cf6cc5c1826bb1f8f9a0dab23a9d4b1019792a5
2019-03-22T23:20:01.411Z,1553296801.411 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-22T23:20:01.412Z,1553296801.412 [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:20:01.413Z,1553296801.413 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-22T23:20:01.414Z,1553296801.414 [IBIT](INFO): Initialize IBIT Component.
2019-03-22T23:20:01.414Z,1553296801.414 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-22T23:20:01.415Z,1553296801.415 [logger ThreadHandler](INFO): Handler Thread ID is 6464
2019-03-22T23:20:01.429Z,1553296801.429 [CBIT](DEBUG): Initialized mux pins.
2019-03-22T23:20:01.429Z,1553296801.429 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-22T23:20:01.433Z,1553296801.433 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6465
2019-03-22T23:20:01.434Z,1553296801.434 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-22T23:20:01.441Z,1553296801.441 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6466
2019-03-22T23:20:01.449Z,1553296801.449 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6467
2019-03-22T23:20:01.450Z,1553296801.450 [Rowe_600LCM](INFO): Initializing
2019-03-22T23:20:01.453Z,1553296801.453 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-22T23:20:01.453Z,1553296801.453 [CBIT](DEBUG): Initializing heartbeat.
2019-03-22T23:20:01.465Z,1553296801.465 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6468
2019-03-22T23:20:01.473Z,1553296801.473 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6469
2019-03-22T23:20:01.474Z,1553296801.474 [WetLabsBB2FL](INFO): Powering down
2019-03-22T23:20:01.500Z,1553296801.500 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6470
2019-03-22T23:20:01.512Z,1553296801.512 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-22T23:20:01.512Z,1553296801.512 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-22T23:20:01.512Z,1553296801.512 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-22T23:20:01.512Z,1553296801.512 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-22T23:20:01.512Z,1553296801.512 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-22T23:20:01.513Z,1553296801.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-22T23:20:01.513Z,1553296801.513 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-22T23:20:01.513Z,1553296801.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-22T23:20:01.513Z,1553296801.513 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-22T23:20:01.513Z,1553296801.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-22T23:20:01.514Z,1553296801.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-22T23:20:01.514Z,1553296801.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-22T23:20:01.514Z,1553296801.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-22T23:20:01.514Z,1553296801.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-22T23:20:01.515Z,1553296801.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-22T23:20:01.515Z,1553296801.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-22T23:20:01.525Z,1553296801.525 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-22T23:20:01.525Z,1553296801.525 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-22T23:20:01.561Z,1553296801.561 [CBIT](DEBUG): Backplane powered.
2019-03-22T23:20:01.562Z,1553296801.562 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-22T23:20:01.571Z,1553296801.571 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-22T23:20:01.590Z,1553296801.590 [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:20:01.590Z,1553296801.590 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-22T23:20:01.653Z,1553296801.653 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-22T23:20:01.654Z,1553296801.654 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-22T23:20:01.656Z,1553296801.656 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-22T23:20:01.695Z,1553296801.695 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-22T23:20:01.714Z,1553296801.714 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-22T23:20:01.719Z,1553296801.719 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-22T23:20:01.734Z,1553296801.734 [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:20:01.740Z,1553296801.740 [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:20:01.810Z,1553296801.810 [BPC1](INFO): Start
2019-03-22T23:20:01.845Z,1553296801.845 [Radio_Surface](INFO): Powering up
2019-03-22T23:20:01.905Z,1553296801.905 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:20:01.913Z,1553296801.913 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-22T23:20:01.915Z,1553296801.915 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:20:01.929Z,1553296801.929 [MassServo](DEBUG): Initializing MassServo.
2019-03-22T23:20:01.930Z,1553296801.930 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-22T23:20:01.941Z,1553296801.941 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-22T23:20:02.116Z,1553296802.116 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T23:20:02.228Z,1553296802.228 [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:20:02.302Z,1553296802.302 [BPC1](INFO): Starting
2019-03-22T23:20:02.585Z,1553296802.585 [BPC1](INFO): Starting
2019-03-22T23:20:02.973Z,1553296802.973 [BPC1](INFO): Starting
2019-03-22T23:20:03.376Z,1553296803.376 [BPC1](INFO): Starting
2019-03-22T23:20:03.793Z,1553296803.793 [BPC1](INFO): Starting
2019-03-22T23:20:04.184Z,1553296804.184 [BPC1](INFO): Starting
2019-03-22T23:20:04.589Z,1553296804.589 [BPC1](INFO): Starting
2019-03-22T23:20:05.005Z,1553296805.005 [BPC1](INFO): Starting
2019-03-22T23:20:05.408Z,1553296805.408 [BPC1](INFO): Starting
2019-03-22T23:20:05.808Z,1553296805.808 [BPC1](INFO): Starting
2019-03-22T23:20:06.233Z,1553296806.233 [BPC1](INFO): Starting
2019-03-22T23:20:06.616Z,1553296806.616 [BPC1](INFO): Starting
2019-03-22T23:20:07.021Z,1553296807.021 [BPC1](INFO): Starting
2019-03-22T23:20:07.433Z,1553296807.433 [BPC1](INFO): Starting
2019-03-22T23:20:07.829Z,1553296807.829 [BPC1](INFO): Starting
2019-03-22T23:20:08.232Z,1553296808.232 [BPC1](INFO): Starting
2019-03-22T23:20:08.644Z,1553296808.644 [BPC1](INFO): Starting
2019-03-22T23:20:09.062Z,1553296809.062 [BPC1](INFO): Starting
2019-03-22T23:20:09.445Z,1553296809.445 [BPC1](INFO): Starting
2019-03-22T23:20:09.856Z,1553296809.856 [BPC1](INFO): Starting
2019-03-22T23:20:10.269Z,1553296810.269 [BPC1](INFO): Starting
2019-03-22T23:20:10.692Z,1553296810.692 [BPC1](INFO): Starting
2019-03-22T23:20:11.080Z,1553296811.080 [BPC1](INFO): Starting
2019-03-22T23:20:11.478Z,1553296811.478 [BPC1](INFO): Starting
2019-03-22T23:20:11.868Z,1553296811.868 [BPC1](INFO): Starting
2019-03-22T23:20:12.279Z,1553296812.279 [BPC1](INFO): Starting
2019-03-22T23:20:12.693Z,1553296812.693 [BPC1](INFO): Starting
2019-03-22T23:20:13.080Z,1553296813.080 [BPC1](INFO): Starting
2019-03-22T23:20:13.484Z,1553296813.484 [BPC1](INFO): Starting
2019-03-22T23:20:13.892Z,1553296813.892 [BPC1](INFO): Starting
2019-03-22T23:20:14.296Z,1553296814.296 [BPC1](INFO): Starting
2019-03-22T23:20:14.702Z,1553296814.702 [BPC1](INFO): Starting
2019-03-22T23:20:15.142Z,1553296815.142 [BPC1](INFO): Starting
2019-03-22T23:20:15.505Z,1553296815.505 [BPC1](INFO): Starting
2019-03-22T23:20:15.908Z,1553296815.908 [BPC1](INFO): Starting
2019-03-22T23:20:16.317Z,1553296816.317 [BPC1](INFO): Starting
2019-03-22T23:20:16.716Z,1553296816.716 [BPC1](INFO): Starting
2019-03-22T23:20:17.124Z,1553296817.124 [BPC1](INFO): Starting
2019-03-22T23:20:17.525Z,1553296817.525 [BPC1](INFO): Starting
2019-03-22T23:20:17.928Z,1553296817.928 [BPC1](INFO): Starting
2019-03-22T23:20:18.332Z,1553296818.332 [BPC1](INFO): Starting
2019-03-22T23:20:18.738Z,1553296818.738 [BPC1](INFO): Starting
2019-03-22T23:20:19.140Z,1553296819.140 [BPC1](INFO): Starting
2019-03-22T23:20:19.545Z,1553296819.545 [BPC1](INFO): Starting
2019-03-22T23:20:19.949Z,1553296819.949 [BPC1](INFO): Starting
2019-03-22T23:20:20.352Z,1553296820.352 [BPC1](INFO): Starting
2019-03-22T23:20:20.756Z,1553296820.756 [BPC1](INFO): Starting
2019-03-22T23:20:21.161Z,1553296821.161 [BPC1](INFO): Starting
2019-03-22T23:20:21.565Z,1553296821.565 [BPC1](INFO): Starting
2019-03-22T23:20:21.968Z,1553296821.968 [BPC1](INFO): Starting
2019-03-22T23:20:22.373Z,1553296822.373 [BPC1](INFO): Starting
2019-03-22T23:20:22.776Z,1553296822.776 [BPC1](INFO): Starting
2019-03-22T23:20:23.181Z,1553296823.181 [BPC1](INFO): Starting
2019-03-22T23:20:23.593Z,1553296823.593 [BPC1](INFO): Starting
2019-03-22T23:20:23.988Z,1553296823.988 [BPC1](INFO): Starting
2019-03-22T23:20:24.396Z,1553296824.396 [BPC1](INFO): Starting
2019-03-22T23:20:24.797Z,1553296824.797 [BPC1](INFO): Starting
2019-03-22T23:20:25.200Z,1553296825.200 [BPC1](INFO): Starting
2019-03-22T23:20:25.604Z,1553296825.604 [BPC1](INFO): Starting
2019-03-22T23:20:26.008Z,1553296826.008 [BPC1](INFO): Starting
2019-03-22T23:20:26.412Z,1553296826.412 [BPC1](INFO): Starting
2019-03-22T23:20:26.816Z,1553296826.816 [BPC1](INFO): Starting
2019-03-22T23:20:27.227Z,1553296827.227 [BPC1](INFO): Starting
2019-03-22T23:20:27.627Z,1553296827.627 [BPC1](INFO): Starting
2019-03-22T23:20:28.028Z,1553296828.028 [BPC1](INFO): Starting
2019-03-22T23:20:28.432Z,1553296828.432 [BPC1](INFO): Starting
2019-03-22T23:20:28.814Z,1553296828.814 [NAL9602](INFO): Powering up NAL9602
2019-03-22T23:20:28.838Z,1553296828.838 [BPC1](INFO): Starting
2019-03-22T23:20:29.240Z,1553296829.240 [BPC1](INFO): Starting
2019-03-22T23:20:29.645Z,1553296829.645 [BPC1](INFO): Starting
2019-03-22T23:20:30.049Z,1553296830.049 [BPC1](INFO): Starting
2019-03-22T23:20:30.095Z,1553296830.095 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-22T23:20:30.099Z,1553296830.099 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-22T23:20:30.454Z,1553296830.454 [BPC1](INFO): Starting
2019-03-22T23:20:30.861Z,1553296830.861 [BPC1](INFO): Starting
2019-03-22T23:20:31.266Z,1553296831.266 [BPC1](INFO): Starting
2019-03-22T23:20:31.666Z,1553296831.666 [BPC1](INFO): Starting
2019-03-22T23:20:32.075Z,1553296832.075 [BPC1](INFO): Starting
2019-03-22T23:20:32.623Z,1553296832.623 [BPC1](INFO): Starting
2019-03-22T23:20:32.986Z,1553296832.986 [BPC1](INFO): Starting
2019-03-22T23:20:33.402Z,1553296833.402 [BPC1](INFO): Starting
2019-03-22T23:20:33.795Z,1553296833.795 [BPC1](INFO): Starting
2019-03-22T23:20:34.198Z,1553296834.198 [BPC1](INFO): Starting
2019-03-22T23:20:34.620Z,1553296834.620 [BPC1](INFO): Starting
2019-03-22T23:20:35.012Z,1553296835.012 [BPC1](INFO): Starting
2019-03-22T23:20:35.410Z,1553296835.410 [BPC1](INFO): Starting
2019-03-22T23:20:35.826Z,1553296835.826 [BPC1](INFO): Starting
2019-03-22T23:20:36.218Z,1553296836.218 [BPC1](INFO): Starting
2019-03-22T23:20:36.623Z,1553296836.623 [BPC1](INFO): Starting
2019-03-22T23:20:37.034Z,1553296837.034 [BPC1](INFO): Starting
2019-03-22T23:20:37.431Z,1553296837.431 [BPC1](INFO): Starting
2019-03-22T23:20:37.836Z,1553296837.836 [BPC1](INFO): Starting
2019-03-22T23:20:38.273Z,1553296838.273 [BPC1](INFO): Starting
2019-03-22T23:20:38.657Z,1553296838.657 [BPC1](INFO): Starting
2019-03-22T23:20:39.051Z,1553296839.051 [BPC1](INFO): Starting
2019-03-22T23:20:39.488Z,1553296839.488 [BPC1](INFO): Starting
2019-03-22T23:20:39.831Z,1553296839.831 [NAL9602](INFO): NAL9602 initialized
2019-03-22T23:20:39.873Z,1553296839.873 [BPC1](INFO): Starting
2019-03-22T23:20:40.289Z,1553296840.289 [BPC1](INFO): Starting
2019-03-22T23:20:40.657Z,1553296840.657 [NAL9602](DEBUG): Fix Requested
2019-03-22T23:20:40.680Z,1553296840.680 [BPC1](INFO): Starting
2019-03-22T23:20:41.088Z,1553296841.088 [BPC1](INFO): Starting
2019-03-22T23:20:41.188Z,1553296841.188 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.021169
CHAN A1 (24V): 0.000812
CHAN A2 (12V): -0.067494
CHAN A3 (5V): -0.002692
CHAN B0 (3.3V): -0.000695
CHAN B1 (3.15aV): -0.001352
CHAN B2 (3.15bV): -0.000584
CHAN B3 (GND): -0.000353
OPEN: -0.001041
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-22T23:20:41.533Z,1553296841.533 [BPC1](INFO): Starting
2019-03-22T23:20:41.887Z,1553296841.887 [BPC1](INFO): Starting
2019-03-22T23:20:42.278Z,1553296842.278 [BPC1](INFO): Starting
2019-03-22T23:20:42.696Z,1553296842.696 [BPC1](INFO): Starting
2019-03-22T23:20:43.100Z,1553296843.100 [BPC1](INFO): Starting
2019-03-22T23:20:43.168Z,1553296843.168 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T23:20:43.504Z,1553296843.504 [BPC1](INFO): Starting
2019-03-22T23:20:43.908Z,1553296843.908 [BPC1](INFO): Starting
2019-03-22T23:20:44.302Z,1553296844.302 [BPC1](INFO): Starting
2019-03-22T23:20:44.708Z,1553296844.708 [BPC1](INFO): Starting
2019-03-22T23:20:45.113Z,1553296845.113 [BPC1](INFO): Starting
2019-03-22T23:20:45.534Z,1553296845.534 [BPC1](INFO): Starting
2019-03-22T23:20:46.023Z,1553296846.023 [BPC1](INFO): Starting
2019-03-22T23:20:46.418Z,1553296846.418 [BPC1](INFO): Starting
2019-03-22T23:20:46.814Z,1553296846.814 [BPC1](INFO): Starting
2019-03-22T23:20:47.217Z,1553296847.217 [BPC1](INFO): Starting
2019-03-22T23:20:47.648Z,1553296847.648 [BPC1](INFO): Starting
2019-03-22T23:20:48.052Z,1553296848.052 [BPC1](INFO): Starting
2019-03-22T23:20:48.445Z,1553296848.445 [BPC1](INFO): Starting
2019-03-22T23:20:48.854Z,1553296848.854 [BPC1](INFO): Starting
2019-03-22T23:20:49.239Z,1553296849.239 [BPC1](INFO): Starting
2019-03-22T23:20:49.642Z,1553296849.642 [BPC1](INFO): Starting
2019-03-22T23:20:50.047Z,1553296850.047 [BPC1](INFO): Starting
2019-03-22T23:20:50.450Z,1553296850.450 [BPC1](INFO): Starting
2019-03-22T23:20:50.853Z,1553296850.853 [BPC1](INFO): Starting
2019-03-22T23:20:51.266Z,1553296851.266 [BPC1](INFO): Starting
2019-03-22T23:20:51.662Z,1553296851.662 [BPC1](INFO): Starting
2019-03-22T23:20:52.065Z,1553296852.065 [BPC1](INFO): Starting
2019-03-22T23:20:52.482Z,1553296852.482 [BPC1](INFO): Starting
2019-03-22T23:20:52.884Z,1553296852.884 [BPC1](INFO): Starting
2019-03-22T23:20:53.288Z,1553296853.288 [BPC1](INFO): Starting
2019-03-22T23:20:53.695Z,1553296853.695 [BPC1](INFO): Starting
2019-03-22T23:20:54.101Z,1553296854.101 [BPC1](INFO): Starting
2019-03-22T23:20:54.493Z,1553296854.493 [BPC1](INFO): Starting
2019-03-22T23:20:54.900Z,1553296854.900 [BPC1](INFO): Starting
2019-03-22T23:20:55.298Z,1553296855.298 [BPC1](INFO): Starting
2019-03-22T23:20:55.701Z,1553296855.701 [BPC1](INFO): Starting
2019-03-22T23:20:56.106Z,1553296856.106 [BPC1](INFO): Starting
2019-03-22T23:20:56.510Z,1553296856.510 [BPC1](INFO): Starting
2019-03-22T23:20:56.916Z,1553296856.916 [BPC1](INFO): Starting
2019-03-22T23:20:57.318Z,1553296857.318 [BPC1](INFO): Starting
2019-03-22T23:20:57.724Z,1553296857.724 [BPC1](INFO): Starting
2019-03-22T23:20:58.141Z,1553296858.141 [BPC1](INFO): Starting
2019-03-22T23:20:58.545Z,1553296858.545 [BPC1](INFO): Starting
2019-03-22T23:20:58.960Z,1553296858.960 [BPC1](INFO): Starting
2019-03-22T23:20:59.352Z,1553296859.352 [BPC1](INFO): Starting
2019-03-22T23:20:59.747Z,1553296859.747 [BPC1](INFO): Starting
2019-03-22T23:21:00.150Z,1553296860.150 [BPC1](INFO): Starting
2019-03-22T23:21:00.554Z,1553296860.554 [BPC1](INFO): Starting
2019-03-22T23:21:00.974Z,1553296860.974 [BPC1](INFO): Starting
2019-03-22T23:21:01.366Z,1553296861.366 [BPC1](INFO): Starting
2019-03-22T23:21:01.765Z,1553296861.765 [BPC1](INFO): Starting
2019-03-22T23:21:02.171Z,1553296862.171 [BPC1](INFO): Starting
2019-03-22T23:21:02.171Z,1553296862.171 [BPC1](INFO): Initialized.
2019-03-22T23:21:02.993Z,1553296862.993 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:21:03.429Z,1553296863.429 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:21:05.935Z,1553296865.935 [CommandLine](IMPORTANT): got command configSet Rowe_600LCM.loadAtStartup 1.000000 bool persist
2019-03-22T23:21:05.935Z,1553296865.935 [CommandLine](IMPORTANT): configSet Rowe_600LCM.loadAtStartup requires a restart to take effect.
2019-03-22T23:21:09.899Z,1553296869.899 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T23:21:09.899Z,1553296869.899 [SBIT](FAULT): Control surface position failure.
2019-03-22T23:21:10.270Z,1553296870.270 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:21:11.083Z,1553296871.083 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:21:14.450Z,1553296874.450 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:21:14.598Z,1553296874.598 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:21:14.598Z,1553296874.598 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:21:14.834Z,1553296874.834 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3AAB,0A,FFB8,0B,FFA9%4B
$B11,0C,0001,0D,003A,0E,0036,0F,0E47,10,18E5,11,0BE6,12,09D9%48
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%30
$B11,1A,0031,1B,458F,1C,0163%4A
$B12,02,000A,01,02A8,03,0001,08,0B97,09,3A58,0A,0000,0B,0000%4B
$B12,0C,0001,0D,0037,0E,0033,0F,0D7B,10,189B,11,FFFF,12,FFFF%31
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%33
$B12,1A,0031,1B,458F,1C,01AA%4C
$B15,02,000A,01,02A8,03,0001,08,0B98,09,3AA5,0A,FF8F,0B,FF95%48
$B15,0C,0001,0D,003C,0E,0039,0F,0F22,10,196E,11,0809,12,087C%4B
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%39
$B15,1A,0031,1B,4B19,1C,020E%3C
$B16,02,000A,01,02A8,03,0001,08,0B97,09,3AB9,0A,0000,0B,0000%39
$B16,0C,0001,0D,003C,0E,003A,0F,0F66,10,19D3,11,FFFF,12,FFFF%49
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3A
$B16,1A,0031,1B,4B19,1C,0228%40
$B17,02,000A,01,02A8,03,0001,08,0B95,09,3ABA,0A,0000,0B,0000%42
$B17,0C,0001,0D,003C,0E,003B,0F,0F69,10,19D6,11,FFFF,12,FFFF%41
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3B
$B17,1A,0031,1B,4B19,1C,02B4%3D
$B18,02,C0E0,01,02A8,03,0001,08,0B93,09,3B81,0A,0000,0B,0000%35
$B18,0C,0001,0D,003E,0E,0039,0F,0F17,10,1880,11,FFFF,12,FFFF%41
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%39
$B18,1A,0031,1B,458F,1C,01AC%44

2019-03-22T23:21:15.227Z,1553296875.227 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:21:15.486Z,1553296875.486 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:21:15.486Z,1553296875.486 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:21:15.705Z,1553296875.705 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:21:15.717Z,1553296875.717 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:21:16.469Z,1553296876.469 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:21:23.345Z,1553296883.345 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:21:23.345Z,1553296883.345 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:21:23.383Z,1553296883.383 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-22T23:21:23.383Z,1553296883.383 [SBIT](FAULT): Control surface position failure.
2019-03-22T23:21:23.782Z,1553296883.782 [SBIT](CRITICAL): SBIT FAILED
2019-03-22T23:21:23.812Z,1553296883.812 [CommandLine](IMPORTANT): got command configSet list
2019-03-22T23:21:23.812Z,1553296883.812 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-22T23:21:23.815Z,1553296883.815 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-22T23:21:23.815Z,1553296883.815 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=10 minute;

2019-03-22T23:21:23.815Z,1553296883.815 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool;

2019-03-22T23:21:23.815Z,1553296883.815 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-03-22T23:21:23.815Z,1553296883.815 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2019-03-22T23:21:23.815Z,1553296883.815 [CommandLine](IMPORTANT): Depth_Keller.offset=-0.13 decibar;

2019-03-22T23:21:23.815Z,1553296883.815 [CommandLine](IMPORTANT): Depth_Keller.scale=45.92 micropascal;

2019-03-22T23:21:23.815Z,1553296883.815 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-03-22T23:21:23.816Z,1553296883.816 [CommandLine](IMPORTANT): PNI_TCM.loadAtStartup=1 bool;

2019-03-22T23:21:23.816Z,1553296883.816 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2019-03-22T23:21:23.816Z,1553296883.816 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2019-03-22T23:21:23.826Z,1553296883.826 [CommandLine](FAULT): Scheduling is paused
2019-03-22T23:21:23.826Z,1553296883.826 [CBIT](INFO): Critical error at 20190322T232123
2019-03-22T23:21:23.827Z,1553296883.827 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-22T23:21:24.189Z,1553296884.189 [MissionManager](IMPORTANT): Started mission Startup
2019-03-22T23:21:24.190Z,1553296884.190 [Startup] Running Loop=1
2019-03-22T23:21:24.190Z,1553296884.190 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-22T23:21:24.190Z,1553296884.190 [Startup:A.GoToSurface] Running Loop=1
2019-03-22T23:21:24.190Z,1553296884.190 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-22T23:21:24.191Z,1553296884.191 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-22T23:21:24.191Z,1553296884.191 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-22T23:21:24.191Z,1553296884.191 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-22T23:21:24.192Z,1553296884.192 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-22T23:21:24.192Z,1553296884.192 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-22T23:21:24.206Z,1553296884.206 [Startup:StartupSatComms] Running Loop=1
2019-03-22T23:21:24.206Z,1553296884.206 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-22T23:21:24.206Z,1553296884.206 [Startup:StartupSatComms:A] Running Loop=1
2019-03-22T23:21:24.557Z,1553296884.557 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-22T23:21:26.559Z,1553296886.559 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:21:26.668Z,1553296886.668 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:21:26.673Z,1553296886.673 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:21:26.960Z,1553296886.960 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3AAB,0A,FFBC,0B,FFAB%4B
$B11,0C,0001,0D,003A,0E,0036,0F,0E47,10,18E5,11,0C99,12,0A14%3A
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%30
$B11,1A,0031,1B,458F,1C,0163%4A
$B12,02,000A,01,02A8,03,0001,08,0B97,09,3A58,0A,0000,0B,0000%4B
$B12,0C,0001,0D,0037,0E,0033,0F,0D7B,10,189B,11,FFFF,12,FFFF%31
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%33
$B12,1A,0031,1B,458F,1C,01AA%4C
$B15,02,000A,01,02A8,03,0001,08,0B98,09,3AA6,0A,FFAF,0B,FF9B%45
$B15,0C,0001,0D,003C,0E,0039,0F,0F21,10,196E,11,0B35,12,08FD%4B
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%39
$B15,1A,0031,1B,4B19,1C,020E%3C
$B16,02,000A,01,02A8,03,0001,08,0B97,09,3AB8,0A,0000,0B,0000%38
$B16,0C,0001,0D,003C,0E,003A,0F,0F66,10,19D3,11,FFFF,12,FFFF%49
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3A
$B16,1A,0031,1B,4B19,1C,0228%40
$B17,02,000A,01,02A8,03,0001,08,0B95,09,3AB9,0A,0000,0B,0000%3A
$B17,0C,0001,0D,003C,0E,003B,0F,0F69,10,19D6,11,FFFF,12,FFFF%41
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3B
$B17,1A,0031,1B,4B19,1C,02B4%3D
$B18,02,C0E0,01,02A8,03,0001,08,0B93,09,3B81,0A,0000,0B,0000%35
$B18,0C,0001,0D,003E,0E,0039,0F,0F17,10,1880,11,FFFF,12,FFFF%41
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%39
$B18,1A,0031,1B,458F,1C,01AC%44

2019-03-22T23:21:28.170Z,1553296888.170 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:21:28.315Z,1553296888.315 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:21:28.315Z,1553296888.315 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:21:28.329Z,1553296888.329 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:21:28.581Z,1553296888.581 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:21:29.405Z,1553296889.405 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:21:35.864Z,1553296895.864 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:21:36.666Z,1553296896.666 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:21:39.483Z,1553296899.483 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:21:39.601Z,1553296899.601 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:21:39.602Z,1553296899.602 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:21:39.904Z,1553296899.904 [BPC1](ERROR): BPC1B expecting battery number:3 and read number: read:$B11,02,000A,01,02A8,03,0001,08,0B98,09,3AAC,0A,FFC1,0B,FFB6%4E
$B11,0C,0001,0D,003A,0E,0036,0F,0E47,10,18E5,11,0D63,12,0B94%33
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%30
$B11,1A,0031,1B,458F,1C,0163%4A
$B12,02,000A,01,02A8,03,0001,08,0B97,09,3A58,0A,0000,0B,0000%4B
$B12,0C,0001,0D,0037,0E,0033,0F,0D7B,10,189B,11,FFFF,12,FFFF%31
$B12,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%33
$B12,1A,0031,1B,458F,1C,01AA%4C
$B15,02,000A,01,02A8,03,0001,08,0B98,09,3AA6,0A,FFAF,0B,FFA6%49
$B15,0C,0001,0D,003C,0E,0039,0F,0F21,10,196E,11,0AF0,12,0A33%43
$B15,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%39
$B15,1A,0031,1B,4B19,1C,020E%3C
$B16,02,000A,01,02A8,03,0001,08,0B97,09,3AB8,0A,0000,0B,0000%38
$B16,0C,0001,0D,003C,0E,003A,0F,0F66,10,19D3,11,FFFF,12,FFFF%49
$B16,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3A
$B16,1A,0031,1B,4B19,1C,0228%40
$B17,02,000A,01,02A8,03,0001,08,0B95,09,3AB9,0A,0000,0B,0000%3A
$B17,0C,0001,0D,003C,0E,003B,0F,0F69,10,19D6,11,FFFF,12,FFFF%41
$B17,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0005,18,1A90,19,3840%3B
$B17,1A,0031,1B,4B19,1C,02B4%3D
$B18,02,C0E0,01,02A8,03,0001,08,0B93,09,3B81,0A,0000,0B,0000%35
$B18,0C,0001,0D,003E,0E,0039,0F,0F17,10,1880,11,FFFF,12,FFFF%41
$B18,13,FFFF,14,0BB8,15,41A0,16,00C0,17,0008,18,1A90,19,3840%39
$B18,1A,0031,1B,458F,1C,01AC%44

2019-03-22T23:21:40.721Z,1553296900.721 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:21:41.907Z,1553296901.907 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:21:42.047Z,1553296902.047 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:21:42.047Z,1553296902.047 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:21:42.326Z,1553296902.326 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:21:43.129Z,1553296903.129 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:21:46.717Z,1553296906.717 [CommandLine](IMPORTANT): got command get Rowe_600LCM.baud
2019-03-22T23:21:46.718Z,1553296906.718 [CommandLine](IMPORTANT): Rowe_600LCM.baud 9600.000000 bps
2019-03-22T23:21:49.178Z,1553296909.178 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:21:50.400Z,1553296910.400 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:21:52.410Z,1553296912.410 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:21:52.508Z,1553296912.508 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:21:52.508Z,1553296912.508 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:21:52.858Z,1553296912.858 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:21:53.642Z,1553296913.642 [CommandLine](IMPORTANT): got command get Rowe_600LCM.uart
2019-03-22T23:21:53.642Z,1553296913.642 [CommandLine](IMPORTANT): Rowe_600LCM.uart /dev/ttyB4
2019-03-22T23:21:53.661Z,1553296913.661 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:21:55.242Z,1553296915.242 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:21:55.368Z,1553296915.368 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:21:55.368Z,1553296915.368 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:21:55.671Z,1553296915.671 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:21:56.473Z,1553296916.473 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:22:00.597Z,1553296920.597 [CommandLine](IMPORTANT): got command failComponent
2019-03-22T23:22:00.598Z,1553296920.598 [CommandLine](IMPORTANT): Failed components:
2019-03-22T23:22:00.598Z,1553296920.598 [CommandLine](IMPORTANT): No failed Components.
2019-03-22T23:22:02.137Z,1553296922.137 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T23:22:02.137Z,1553296922.137 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-03-22T23:22:02.137Z,1553296922.137 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T23:22:02.153Z,1553296922.153 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T23:22:02.541Z,1553296922.541 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T23:22:03.726Z,1553296923.726 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:22:03.726Z,1553296923.726 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:22:04.142Z,1553296924.142 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T23:22:04.142Z,1553296924.142 [Rowe_600LCM] No Fault, FailCount= 1
2019-03-22T23:22:04.325Z,1553296924.325 [Rowe_600LCM](INFO): Initializing
2019-03-22T23:22:04.808Z,1553296924.808 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T23:22:04.872Z,1553296924.872 [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:22:07.367Z,1553296927.367 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:22:07.409Z,1553296927.409 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:22:07.410Z,1553296927.410 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:22:07.767Z,1553296927.767 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:22:08.578Z,1553296928.578 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:22:08.641Z,1553296928.641 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:22:08.641Z,1553296928.641 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:22:08.653Z,1553296928.653 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:22:08.980Z,1553296928.980 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:22:09.797Z,1553296929.797 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:22:11.310Z,1553296931.310 [CommandLine](IMPORTANT): got command failComponent hardware Rowe_600LCM
2019-03-22T23:22:11.310Z,1553296931.310 [Rowe_600LCM] Hardware Fault, FailCount= 2
2019-03-22T23:22:11.310Z,1553296931.310 [Rowe_600LCM](ERROR): Hardware Fault
2019-03-22T23:22:11.310Z,1553296931.310 [CommandLine](IMPORTANT): Rowe_600LCM failureMode is Hardware Fault
2019-03-22T23:22:11.459Z,1553296931.459 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM
2019-03-22T23:22:12.113Z,1553296932.113 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T23:22:13.836Z,1553296933.836 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T23:22:13.836Z,1553296933.836 [Rowe_600LCM] No Fault, FailCount= 2
2019-03-22T23:22:13.877Z,1553296933.877 [Rowe_600LCM](INFO): Initializing
2019-03-22T23:22:14.390Z,1553296934.390 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T23:22:14.448Z,1553296934.448 [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:22:16.664Z,1553296936.664 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:22:19.084Z,1553296939.084 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:22:21.915Z,1553296941.915 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:22:21.973Z,1553296941.973 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:22:21.974Z,1553296941.974 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:22:22.326Z,1553296942.326 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:22:22.741Z,1553296942.741 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:22:22.782Z,1553296942.782 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:22:22.782Z,1553296942.782 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:22:23.137Z,1553296943.137 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:22:23.142Z,1553296943.142 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:22:23.933Z,1553296943.933 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:22:24.351Z,1553296944.351 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-22T23:21:24.2Z
2019-03-22T23:22:24.351Z,1553296944.351 [Startup:StartupSatComms:A] Stopped
2019-03-22T23:22:24.351Z,1553296944.351 [Startup:StartupSatComms:B] Running Loop=1
2019-03-22T23:22:24.752Z,1553296944.752 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-22T23:22:28.539Z,1553296948.539 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004846
2019-03-22T23:22:30.485Z,1553296950.485 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:22:34.521Z,1553296954.521 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:22:35.334Z,1553296955.334 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:22:35.477Z,1553296955.477 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:22:35.478Z,1553296955.478 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:22:35.748Z,1553296955.748 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:22:36.553Z,1553296956.553 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:22:36.700Z,1553296956.700 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190322T231427/Courier0004.lzma
2019-03-22T23:22:37.512Z,1553296957.512 [DataOverHttps](INFO): Moved sent file to Logs/20190322T231427/Courier0004.lzma.bak
2019-03-22T23:22:37.512Z,1553296957.512 [DataOverHttps](INFO): SBD MOMSN=10217375
2019-03-22T23:22:38.167Z,1553296958.167 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:22:38.210Z,1553296958.210 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:22:38.211Z,1553296958.211 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:22:38.580Z,1553296958.580 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:22:40.189Z,1553296960.189 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:22:43.841Z,1553296963.841 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:22:49.079Z,1553296969.079 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:22:49.276Z,1553296969.276 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:22:49.285Z,1553296969.285 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:22:49.484Z,1553296969.484 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:22:50.289Z,1553296970.289 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:22:50.289Z,1553296970.289 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:22:54.328Z,1553296974.328 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:22:54.441Z,1553296974.441 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:22:54.441Z,1553296974.441 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:22:54.732Z,1553296974.732 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:22:54.796Z,1553296974.796 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20190322T231951/Courier0000.lzma
2019-03-22T23:22:55.602Z,1553296975.602 [DataOverHttps](INFO): Moved sent file to Logs/20190322T231951/Courier0000.lzma.bak
2019-03-22T23:22:55.602Z,1553296975.602 [DataOverHttps](INFO): SBD MOMSN=10217377
2019-03-22T23:22:56.349Z,1553296976.349 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:22:57.550Z,1553296977.550 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:23:01.617Z,1553296981.617 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-22T23:23:01.617Z,1553296981.617 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-22T23:23:01.618Z,1553296981.618 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-22T23:23:01.618Z,1553296981.618 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-22T23:23:01.638Z,1553296981.638 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-22T23:23:01.638Z,1553296981.638 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-22T23:23:02.053Z,1553296982.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-22T23:23:02.053Z,1553296982.053 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-22T23:23:02.054Z,1553296982.054 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-22T23:23:02.054Z,1553296982.054 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-22T23:23:02.419Z,1553296982.419 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:23:02.702Z,1553296982.702 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:23:02.703Z,1553296982.703 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:23:02.720Z,1553296982.720 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-22T23:23:02.858Z,1553296982.858 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:23:03.633Z,1553296983.633 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:23:06.843Z,1553296986.843 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:23:10.522Z,1553296990.522 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:23:10.522Z,1553296990.522 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:23:10.681Z,1553296990.681 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:23:10.681Z,1553296990.681 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:23:10.887Z,1553296990.887 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:23:11.709Z,1553296991.709 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:23:15.734Z,1553296995.734 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:23:15.928Z,1553296995.928 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:23:15.928Z,1553296995.928 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:23:16.140Z,1553296996.140 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:23:16.412Z,1553296996.412 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20190322T231427/Express0005.lzma
2019-03-22T23:23:16.953Z,1553296996.953 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:23:17.218Z,1553296997.218 [DataOverHttps](INFO): Moved sent file to Logs/20190322T231427/Express0005.lzma.bak
2019-03-22T23:23:17.219Z,1553296997.219 [DataOverHttps](INFO): SBD MOMSN=10217402
2019-03-22T23:23:22.603Z,1553297002.603 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:23:24.214Z,1553297004.214 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:23:24.647Z,1553297004.647 [Startup:StartupSatComms:B](INFO): Timed out from 2019-03-22T23:22:24.4Z
2019-03-22T23:23:24.647Z,1553297004.647 [Startup:StartupSatComms:B] Stopped
2019-03-22T23:23:24.647Z,1553297004.647 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-22T23:23:24.647Z,1553297004.647 [Startup:StartupSatComms] Stopped
2019-03-22T23:23:24.647Z,1553297004.647 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-22T23:23:24.648Z,1553297004.648 [Startup](INFO): Completed Startup
2019-03-22T23:23:24.648Z,1553297004.648 [MissionManager](INFO): Startup is completed.
2019-03-22T23:23:24.648Z,1553297004.648 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-22T23:23:24.648Z,1553297004.648 [Startup] Stopped
2019-03-22T23:23:24.648Z,1553297004.648 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-22T23:23:24.649Z,1553297004.649 [Startup:A.GoToSurface] Stopped
2019-03-22T23:23:24.649Z,1553297004.649 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-22T23:23:25.060Z,1553297005.060 [MissionManager](IMPORTANT): Started mission Default
2019-03-22T23:23:25.060Z,1553297005.060 [Default] Running Loop=1
2019-03-22T23:23:25.061Z,1553297005.061 [Default](DEBUG): Aggregate::initialize Default
2019-03-22T23:23:25.061Z,1553297005.061 [Default:B.GoToSurface] Running Loop=1
2019-03-22T23:23:25.061Z,1553297005.061 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-22T23:23:25.061Z,1553297005.061 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-22T23:23:25.061Z,1553297005.061 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-22T23:23:25.062Z,1553297005.062 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-22T23:23:25.062Z,1553297005.062 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-22T23:23:25.062Z,1553297005.062 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-22T23:23:25.063Z,1553297005.063 [Default:A.Wait] Running Loop=1
2019-03-22T23:23:25.063Z,1553297005.063 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-22T23:23:26.240Z,1553297006.240 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:23:26.371Z,1553297006.371 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:23:26.371Z,1553297006.371 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:23:26.674Z,1553297006.674 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:23:28.269Z,1553297008.269 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:23:29.082Z,1553297009.082 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:23:29.214Z,1553297009.214 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:23:29.214Z,1553297009.214 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:23:29.480Z,1553297009.480 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:23:30.297Z,1553297010.297 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:23:36.918Z,1553297016.918 [DataOverHttps](INFO): Sending 1064 bytes from file Logs/20190322T231951/Express0001.lzma
2019-03-22T23:23:37.722Z,1553297017.722 [DataOverHttps](INFO): Moved sent file to Logs/20190322T231951/Express0001.lzma.bak
2019-03-22T23:23:37.723Z,1553297017.723 [DataOverHttps](INFO): SBD MOMSN=10217407
2019-03-22T23:23:37.949Z,1553297017.949 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:23:38.371Z,1553297018.371 [Default:A.Wait](INFO): Done Waiting.
2019-03-22T23:23:38.371Z,1553297018.371 [Default:A.Wait] Stopped
2019-03-22T23:23:38.371Z,1553297018.371 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-22T23:23:38.759Z,1553297018.759 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:23:38.775Z,1553297018.775 [Default:CheckIn] Running Loop=1
2019-03-22T23:23:38.775Z,1553297018.775 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-22T23:23:38.776Z,1553297018.776 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-22T23:23:39.175Z,1553297019.175 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-22T23:23:42.408Z,1553297022.408 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:23:42.578Z,1553297022.578 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:23:42.578Z,1553297022.578 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:23:42.798Z,1553297022.798 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:23:42.913Z,1553297022.913 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:23:42.913Z,1553297022.913 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:23:42.939Z,1553297022.939 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:23:43.226Z,1553297023.226 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:23:43.633Z,1553297023.633 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:23:44.026Z,1553297024.026 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:23:50.893Z,1553297030.893 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:23:54.139Z,1553297034.139 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:23:56.141Z,1553297036.141 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:23:56.291Z,1553297036.291 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:23:56.292Z,1553297036.292 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:23:56.560Z,1553297036.560 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:23:57.369Z,1553297037.369 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:23:57.753Z,1553297037.753 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:23:57.831Z,1553297037.831 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:23:57.831Z,1553297037.831 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:23:58.181Z,1553297038.181 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:23:58.969Z,1553297038.969 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:24:04.624Z,1553297044.624 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:24:09.079Z,1553297049.079 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:24:09.477Z,1553297049.477 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:24:09.659Z,1553297049.659 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:24:09.659Z,1553297049.659 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:24:09.876Z,1553297049.876 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:24:10.689Z,1553297050.689 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:24:12.705Z,1553297052.705 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:24:12.803Z,1553297052.803 [BPC1](INFO): BPC1B: got 6 stick msg (1368 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:24:12.803Z,1553297052.803 [BPC1](INFO): BPC1B: got valid message (1368 bytes).
2019-03-22T23:24:13.114Z,1553297053.114 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:24:13.881Z,1553297053.881 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-03-22T23:24:13.881Z,1553297053.881 [Rowe_600LCM] Communications Fault, FailCount= 3
2019-03-22T23:24:13.881Z,1553297053.881 [Rowe_600LCM](ERROR): Communications Fault
2019-03-22T23:24:13.921Z,1553297053.921 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:24:13.952Z,1553297053.952 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-03-22T23:24:14.285Z,1553297054.285 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T23:24:15.121Z,1553297055.121 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:24:15.944Z,1553297055.944 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-03-22T23:24:15.944Z,1553297055.944 [Rowe_600LCM] No Fault, FailCount= 3
2019-03-22T23:24:16.113Z,1553297056.113 [Rowe_600LCM](INFO): Initializing
2019-03-22T23:24:16.602Z,1553297056.602 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-03-22T23:24:16.660Z,1553297056.660 [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:24:18.369Z,1553297058.369 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:24:19.566Z,1553297059.566 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:24:19.582Z,1553297059.582 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:24:19.583Z,1553297059.583 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T23:24:19.972Z,1553297059.972 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:24:20.789Z,1553297060.789 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:24:22.807Z,1553297062.807 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:24:22.880Z,1553297062.880 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:24:22.881Z,1553297062.881 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:24:23.213Z,1553297063.213 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-03-22T23:24:24.017Z,1553297064.017 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:24:28.464Z,1553297068.464 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:24:31.279Z,1553297071.279 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:24:32.907Z,1553297072.907 [BPC1](INFO): BPC1B: validating data format.
2019-03-22T23:24:32.982Z,1553297072.982 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:24:32.982Z,1553297072.982 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2019-03-22T23:24:33.319Z,1553297073.319 [BPC1](INFO): BPC1B: data parsed.
2019-03-22T23:24:34.133Z,1553297074.133 [BPC1](INFO): BPC1B: initialized.
2019-03-22T23:24:36.525Z,1553297076.525 [BPC1](INFO): BPC1A: validating data format.
2019-03-22T23:24:36.602Z,1553297076.602 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2019-03-22T23:24:36.603Z,1553297076.603 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2019-03-22T23:24:36.939Z,1553297076.939 [BPC1](INFO): BPC1A: data parsed.
2019-03-22T23:24:37.761Z,1553297077.761 [BPC1](INFO): BPC1A: initialized.
2019-03-22T23:24:41.812Z,1553297081.812 [BPC1](INFO): BPC1B: sending IBPS break.
2019-03-22T23:24:42.034Z,1553297082.034 [CommandLine](IMPORTANT): got command restart system
2019-03-22T23:24:44.341Z,1553297084.341 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:24:44.341Z,1553297084.341 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:44.345Z,1553297084.345 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-22T23:24:44.345Z,1553297084.345 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:44.346Z,1553297084.346 [CommandLine](INFO): Join timeout helper Thread ID is 6517
2019-03-22T23:24:44.353Z,1553297084.353 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-22T23:24:44.353Z,1553297084.353 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:44.354Z,1553297084.354 [NavChartDb](INFO): Join timeout helper Thread ID is 6518
2019-03-22T23:24:44.361Z,1553297084.361 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:24:44.361Z,1553297084.361 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:44.362Z,1553297084.362 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-03-22T23:24:44.362Z,1553297084.362 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:44.362Z,1553297084.362 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6519
2019-03-22T23:24:44.789Z,1553297084.789 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:24:44.789Z,1553297084.789 [WetLabsBB2FL](INFO): Powering down
2019-03-22T23:24:44.790Z,1553297084.790 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:44.801Z,1553297084.801 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-22T23:24:44.801Z,1553297084.801 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:44.801Z,1553297084.801 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6520
2019-03-22T23:24:44.905Z,1553297084.905 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:24:44.905Z,1553297084.905 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:44.921Z,1553297084.921 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-03-22T23:24:44.921Z,1553297084.921 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:44.921Z,1553297084.921 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6521
2019-03-22T23:24:45.026Z,1553297085.026 [BPC1](INFO): BPC1A: sending IBPS break.
2019-03-22T23:24:45.205Z,1553297085.205 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:24:45.205Z,1553297085.205 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-03-22T23:24:45.366Z,1553297085.366 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.374Z,1553297085.374 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-22T23:24:45.374Z,1553297085.374 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.375Z,1553297085.375 [Radio_Surface](INFO): Join timeout helper Thread ID is 6523
2019-03-22T23:24:45.577Z,1553297085.577 [Radio_Surface](INFO): Powering down
2019-03-22T23:24:45.578Z,1553297085.578 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:24:45.578Z,1553297085.578 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.598Z,1553297085.598 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-22T23:24:45.598Z,1553297085.598 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.598Z,1553297085.598 [DataOverHttps](INFO): Join timeout helper Thread ID is 6524
2019-03-22T23:24:45.653Z,1553297085.653 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:24:45.653Z,1553297085.653 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.658Z,1553297085.658 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-22T23:24:45.658Z,1553297085.658 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.658Z,1553297085.658 [logger](INFO): Join timeout helper Thread ID is 6525
2019-03-22T23:24:45.729Z,1553297085.729 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:24:45.729Z,1553297085.729 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.738Z,1553297085.738 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-22T23:24:45.738Z,1553297085.738 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.738Z,1553297085.738 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-22T23:24:45.738Z,1553297085.738 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.738Z,1553297085.738 [controlThread](INFO): Join timeout helper Thread ID is 6526
2019-03-22T23:24:45.789Z,1553297085.789 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-22T23:24:45.789Z,1553297085.789 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-22T23:24:45.790Z,1553297085.790 [NAL9602](INFO): Powering down
2019-03-22T23:24:45.791Z,1553297085.791 [PNI_TCM](INFO): Powering down
2019-03-22T23:24:45.877Z,1553297085.877 [BPC1](INFO): uninitialize
2019-03-22T23:24:45.878Z,1553297085.878 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-22T23:24:45.879Z,1553297085.879 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-22T23:24:45.880Z,1553297085.880 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-22T23:24:45.880Z,1553297085.880 [MissionManager](INFO): Uninitializing Mission Default
2019-03-22T23:24:45.880Z,1553297085.880 [Default] Stopped
2019-03-22T23:24:45.881Z,1553297085.881 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-22T23:24:45.881Z,1553297085.881 [Default:B.GoToSurface] Stopped
2019-03-22T23:24:45.881Z,1553297085.881 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-22T23:24:45.881Z,1553297085.881 [Default:CheckIn] Stopped
2019-03-22T23:24:45.881Z,1553297085.881 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-22T23:24:45.881Z,1553297085.881 [Default:CheckIn:Read_GPS] Stopped
2019-03-22T23:24:45.884Z,1553297085.884 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-22T23:24:45.884Z,1553297085.884 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-22T23:24:45.884Z,1553297085.884 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-22T23:24:45.885Z,1553297085.885 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-22T23:24:45.885Z,1553297085.885 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-22T23:24:45.885Z,1553297085.885 [ElevatorServo](INFO): Powering down
2019-03-22T23:24:45.886Z,1553297085.886 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-22T23:24:45.887Z,1553297085.887 [MassServo](INFO): Powering down
2019-03-22T23:24:45.887Z,1553297085.887 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-22T23:24:45.887Z,1553297085.887 [ThrusterServo](INFO): Powering down
2019-03-22T23:24:45.888Z,1553297085.888 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-22T23:24:45.889Z,1553297085.889 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-22T23:24:45.889Z,1553297085.889 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-22T23:24:45.889Z,1553297085.889 [CBIT](DEBUG): Powering off loads.
2019-03-22T23:24:45.901Z,1553297085.901 [CBIT](DEBUG): Disabling WDT.
2019-03-22T23:24:45.913Z,1553297085.913 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-22T23:24:45.913Z,1553297085.913 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:45.963Z,1553297085.963 [BPC1](INFO): uninitialize
2019-03-22T23:24:46.003Z,1553297086.003 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:46.009Z,1553297086.009 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:46.014Z,1553297086.014 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:46.047Z,1553297086.047 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:46.049Z,1553297086.049 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:46.082Z,1553297086.082 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-22T23:24:46.142Z,1553297086.142 [logger ThreadHandler](INFO): Thread cancelled.