2018-09-18T20:43:45.061Z,1537303425.061 [Supervisor](DEBUG): Initializing supervisor.
2018-09-18T20:43:45.064Z,1537303425.064 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-18T20:43:45.064Z,1537303425.064 [SyncHandler](INFO): Protected caller Thread ID is 9936
2018-09-18T20:43:45.065Z,1537303425.065 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-18T20:43:45.066Z,1537303425.066 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-18T20:43:45.066Z,1537303425.066 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9937
2018-09-18T20:43:45.069Z,1537303425.069 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-18T20:43:45.082Z,1537303425.082 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-18T20:43:45.083Z,1537303425.083 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-18T20:43:45.083Z,1537303425.083 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9938
2018-09-18T20:43:45.084Z,1537303425.084 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-18T20:43:45.085Z,1537303425.085 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-18T20:43:45.085Z,1537303425.085 [logger ThreadHandler](INFO): Protected caller Thread ID is 9939
2018-09-18T20:43:45.087Z,1537303425.087 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-18T20:43:45.087Z,1537303425.087 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-18T20:43:45.089Z,1537303425.089 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-18T20:43:45.433Z,1537303425.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-18T20:43:45.433Z,1537303425.433 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-18T20:43:45.637Z,1537303425.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-18T20:43:45.638Z,1537303425.638 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-18T20:43:45.941Z,1537303425.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-18T20:43:45.941Z,1537303425.941 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-18T20:43:46.083Z,1537303426.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-18T20:43:46.083Z,1537303426.083 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-18T20:43:46.181Z,1537303426.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-18T20:43:46.182Z,1537303426.182 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-18T20:43:46.659Z,1537303426.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-18T20:43:46.660Z,1537303426.660 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-18T20:43:47.105Z,1537303427.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-18T20:43:47.105Z,1537303427.105 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-18T20:43:47.760Z,1537303427.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-18T20:43:47.761Z,1537303427.761 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-18T20:43:47.866Z,1537303427.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-18T20:43:47.867Z,1537303427.867 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-18T20:43:47.965Z,1537303427.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-18T20:43:47.965Z,1537303427.965 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-18T20:43:48.173Z,1537303428.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-18T20:43:48.174Z,1537303428.174 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-18T20:43:48.378Z,1537303428.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-18T20:43:48.379Z,1537303428.379 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-18T20:43:48.732Z,1537303428.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-18T20:43:48.732Z,1537303428.732 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-18T20:43:48.876Z,1537303428.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-18T20:43:48.876Z,1537303428.876 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-18T20:43:48.959Z,1537303428.959 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-09-18T20:43:48.959Z,1537303428.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-09-18T20:43:49.063Z,1537303429.063 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-09-18T20:43:49.184Z,1537303429.184 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-09-18T20:43:49.278Z,1537303429.278 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-09-18T20:43:49.364Z,1537303429.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-09-18T20:43:49.523Z,1537303429.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-09-18T20:43:49.703Z,1537303429.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-09-18T20:43:49.779Z,1537303429.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-09-18T20:43:50.010Z,1537303430.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-18T20:43:50.010Z,1537303430.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-09-18T20:43:50.093Z,1537303430.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-09-18T20:43:50.185Z,1537303430.185 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-09-18T20:43:50.287Z,1537303430.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-09-18T20:43:50.416Z,1537303430.416 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-09-18T20:43:50.417Z,1537303430.417 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-09-18T20:43:50.433Z,1537303430.433 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-18T20:43:50.671Z,1537303430.671 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-18T20:43:50.672Z,1537303430.672 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-18T20:43:50.715Z,1537303430.715 [DepthRateCalculator] Loaded
2018-09-18T20:43:50.715Z,1537303430.715 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-18T20:43:50.721Z,1537303430.721 [PitchRateCalculator] Loaded
2018-09-18T20:43:50.721Z,1537303430.721 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-18T20:43:50.737Z,1537303430.737 [SpeedCalculator] Loaded
2018-09-18T20:43:50.738Z,1537303430.738 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-18T20:43:50.759Z,1537303430.759 [TempGradientCalculator] Loaded
2018-09-18T20:43:50.759Z,1537303430.759 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-18T20:43:50.775Z,1537303430.775 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-09-18T20:43:50.775Z,1537303430.775 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-09-18T20:43:50.781Z,1537303430.781 [YawRateCalculator] Loaded
2018-09-18T20:43:50.781Z,1537303430.781 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-18T20:43:50.821Z,1537303430.821 [ElevatorOffsetCalculator] Loaded
2018-09-18T20:43:50.822Z,1537303430.822 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-18T20:43:50.822Z,1537303430.822 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-18T20:43:50.823Z,1537303430.823 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-18T20:43:50.887Z,1537303430.887 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-18T20:43:50.888Z,1537303430.888 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-18T20:43:51.002Z,1537303431.002 [BuoyancyServo] Loaded
2018-09-18T20:43:51.002Z,1537303431.002 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-18T20:43:51.003Z,1537303431.003 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-18T20:43:51.003Z,1537303431.003 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-18T20:43:51.017Z,1537303431.017 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-18T20:43:51.018Z,1537303431.018 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-18T20:43:51.118Z,1537303431.118 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-18T20:43:51.119Z,1537303431.119 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-18T20:43:51.413Z,1537303431.413 [DataOverHttps] Loaded
2018-09-18T20:43:51.413Z,1537303431.413 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-18T20:43:51.427Z,1537303431.427 [Depth_Keller] Loaded
2018-09-18T20:43:51.427Z,1537303431.427 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-18T20:43:51.432Z,1537303431.432 [DropWeight] Loaded
2018-09-18T20:43:51.433Z,1537303431.433 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-18T20:43:51.531Z,1537303431.531 [NAL9602] Loaded
2018-09-18T20:43:51.531Z,1537303431.531 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-18T20:43:51.548Z,1537303431.548 [Onboard] Loaded
2018-09-18T20:43:51.548Z,1537303431.548 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-18T20:43:51.559Z,1537303431.559 [Radio_Surface] Loaded
2018-09-18T20:43:51.559Z,1537303431.559 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-18T20:43:51.561Z,1537303431.561 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088A4E0
2018-09-18T20:43:51.561Z,1537303431.561 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 321
2018-09-18T20:43:51.610Z,1537303431.610 [PNI_TCM] Loaded
2018-09-18T20:43:51.610Z,1537303431.610 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-09-18T20:43:51.631Z,1537303431.631 [BPC1](CRITICAL): No configuration setting for: BPC1.batteryCommsTimout
2018-09-18T20:43:51.635Z,1537303431.635 [BPC1](INFO): BPC1A: got serial number for stick 1: 0138
2018-09-18T20:43:51.636Z,1537303431.636 [BPC1](INFO): BPC1A: got serial number for stick 2: 0125
2018-09-18T20:43:51.637Z,1537303431.637 [BPC1](INFO): BPC1A: got serial number for stick 3: 00EF
2018-09-18T20:43:51.638Z,1537303431.638 [BPC1](INFO): BPC1A: got serial number for stick 4: 0158
2018-09-18T20:43:51.639Z,1537303431.639 [BPC1](INFO): BPC1A: got serial number for stick 5: 0088
2018-09-18T20:43:51.640Z,1537303431.640 [BPC1](INFO): BPC1A: got serial number for stick 6: 00A3
2018-09-18T20:43:51.640Z,1537303431.640 [BPC1](INFO): BPC1A: got serial number for stick 7: 012D
2018-09-18T20:43:51.641Z,1537303431.641 [BPC1](INFO): BPC1A: got serial number for stick 8: 00E7
2018-09-18T20:43:51.642Z,1537303431.642 [BPC1](INFO): BPC1A: got serial number for stick 9: 0100
2018-09-18T20:43:51.643Z,1537303431.643 [BPC1](INFO): BPC1A: got serial number for stick 10: 00B9
2018-09-18T20:43:51.643Z,1537303431.643 [BPC1](INFO): BPC1A: got serial number for stick 11: 008C
2018-09-18T20:43:51.644Z,1537303431.644 [BPC1](INFO): BPC1A: got serial number for stick 12: 0117
2018-09-18T20:43:51.645Z,1537303431.645 [BPC1](INFO): BPC1A: got serial number for stick 13: 00E4
2018-09-18T20:43:51.646Z,1537303431.646 [BPC1](INFO): BPC1A: got serial number for stick 14: 011D
2018-09-18T20:43:51.646Z,1537303431.646 [BPC1](INFO): BPC1A: got serial number for stick 15: 014E
2018-09-18T20:43:51.647Z,1537303431.647 [BPC1](INFO): BPC1A: got serial number for stick 16: 00EC
2018-09-18T20:43:51.648Z,1537303431.648 [BPC1](INFO): BPC1A: got serial number for stick 17: 008A
2018-09-18T20:43:51.649Z,1537303431.649 [BPC1](INFO): BPC1A: got serial number for stick 18: 0140
2018-09-18T20:43:51.650Z,1537303431.650 [BPC1](INFO): BPC1A: got serial number for stick 19: 00E5
2018-09-18T20:43:51.650Z,1537303431.650 [BPC1](INFO): BPC1A: got serial number for stick 20: 00E0
2018-09-18T20:43:51.651Z,1537303431.651 [BPC1](INFO): BPC1A: got serial number for stick 21: 00CD
2018-09-18T20:43:51.652Z,1537303431.652 [BPC1](INFO): BPC1A: got serial number for stick 22: 009F
2018-09-18T20:43:51.653Z,1537303431.653 [BPC1](INFO): BPC1A: got serial number for stick 23: 009C
2018-09-18T20:43:51.653Z,1537303431.653 [BPC1](INFO): BPC1A: got serial number for stick 24: 00A6
2018-09-18T20:43:51.654Z,1537303431.654 [BPC1](INFO): BPC1A: got serial number for stick 25: 00E8
2018-09-18T20:43:51.655Z,1537303431.655 [BPC1](INFO): BPC1A: got serial number for stick 26: 00D1
2018-09-18T20:43:51.655Z,1537303431.655 [BPC1](INFO): BPC1A: got serial number for stick 27: 00F3
2018-09-18T20:43:51.656Z,1537303431.656 [BPC1](INFO): BPC1A: got serial number for stick 28: 0092
2018-09-18T20:43:51.657Z,1537303431.657 [BPC1](INFO): BPC1A: got serial number for stick 29: 00AD
2018-09-18T20:43:51.658Z,1537303431.658 [BPC1](INFO): BPC1A: got serial number for stick 30: 00E9
2018-09-18T20:43:51.658Z,1537303431.658 [BPC1](INFO): BPC1A: got serial number for stick 31: 0126
2018-09-18T20:43:51.660Z,1537303431.660 [BPC1](INFO): BPC1B: got serial number for stick 32: 0157
2018-09-18T20:43:51.661Z,1537303431.661 [BPC1](INFO): BPC1B: got serial number for stick 33: 00F1
2018-09-18T20:43:51.662Z,1537303431.662 [BPC1](INFO): BPC1B: got serial number for stick 34: 00BF
2018-09-18T20:43:51.662Z,1537303431.662 [BPC1](INFO): BPC1B: got serial number for stick 35: 0089
2018-09-18T20:43:51.663Z,1537303431.663 [BPC1](INFO): BPC1B: got serial number for stick 36: 00C2
2018-09-18T20:43:51.664Z,1537303431.664 [BPC1](INFO): BPC1B: got serial number for stick 37: 00CA
2018-09-18T20:43:51.665Z,1537303431.665 [BPC1](INFO): BPC1B: got serial number for stick 38: 011A
2018-09-18T20:43:51.665Z,1537303431.665 [BPC1](INFO): BPC1B: got serial number for stick 39: 00FD
2018-09-18T20:43:51.666Z,1537303431.666 [BPC1](INFO): BPC1B: got serial number for stick 40: 0095
2018-09-18T20:43:51.667Z,1537303431.667 [BPC1](INFO): BPC1B: got serial number for stick 41: 00BE
2018-09-18T20:43:51.668Z,1537303431.668 [BPC1](INFO): BPC1B: got serial number for stick 42: 0086
2018-09-18T20:43:51.668Z,1537303431.668 [BPC1](INFO): BPC1B: got serial number for stick 43: 00AE
2018-09-18T20:43:51.669Z,1537303431.669 [BPC1](INFO): BPC1B: got serial number for stick 44: 00CB
2018-09-18T20:43:51.670Z,1537303431.670 [BPC1](INFO): BPC1B: got serial number for stick 45: 00F7
2018-09-18T20:43:51.671Z,1537303431.671 [BPC1](INFO): BPC1B: got serial number for stick 46: 00A1
2018-09-18T20:43:51.671Z,1537303431.671 [BPC1](INFO): BPC1B: got serial number for stick 47: 00F0
2018-09-18T20:43:51.672Z,1537303431.672 [BPC1](INFO): BPC1B: got serial number for stick 48: 00C9
2018-09-18T20:43:51.673Z,1537303431.673 [BPC1](INFO): BPC1B: got serial number for stick 49: 0103
2018-09-18T20:43:51.674Z,1537303431.674 [BPC1](INFO): BPC1B: got serial number for stick 50: 0152
2018-09-18T20:43:51.674Z,1537303431.674 [BPC1](INFO): BPC1B: got serial number for stick 51: 014A
2018-09-18T20:43:51.675Z,1537303431.675 [BPC1](INFO): BPC1B: got serial number for stick 52: 0114
2018-09-18T20:43:51.676Z,1537303431.676 [BPC1](INFO): BPC1B: got serial number for stick 53: 015E
2018-09-18T20:43:51.677Z,1537303431.677 [BPC1](INFO): BPC1B: got serial number for stick 54: 008E
2018-09-18T20:43:51.677Z,1537303431.677 [BPC1](INFO): BPC1B: got serial number for stick 55: 00AC
2018-09-18T20:43:51.678Z,1537303431.678 [BPC1](INFO): BPC1B: got serial number for stick 56: 0091
2018-09-18T20:43:51.679Z,1537303431.679 [BPC1](INFO): BPC1B: got serial number for stick 57: 0153
2018-09-18T20:43:51.680Z,1537303431.680 [BPC1](INFO): BPC1B: got serial number for stick 58: 00AF
2018-09-18T20:43:51.680Z,1537303431.680 [BPC1](INFO): BPC1B: got serial number for stick 59: 0151
2018-09-18T20:43:51.681Z,1537303431.681 [BPC1](INFO): BPC1B: got serial number for stick 60: 00DC
2018-09-18T20:43:51.682Z,1537303431.682 [BPC1](INFO): BPC1B: got serial number for stick 61: 00BA
2018-09-18T20:43:51.683Z,1537303431.683 [BPC1](INFO): BPC1B: got serial number for stick 62: 00DA
2018-09-18T20:43:53.971Z,1537303433.971 [BPC1] Loaded
2018-09-18T20:43:53.971Z,1537303433.971 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-18T20:43:53.971Z,1537303433.971 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-18T20:43:53.972Z,1537303433.972 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-18T20:43:54.104Z,1537303434.104 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-18T20:43:54.127Z,1537303434.127 [SBIT] Loaded
2018-09-18T20:43:54.127Z,1537303434.127 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-18T20:43:54.128Z,1537303434.128 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-18T20:43:54.139Z,1537303434.139 [IBIT] Loaded
2018-09-18T20:43:54.140Z,1537303434.140 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-18T20:43:54.143Z,1537303434.143 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-18T20:43:54.292Z,1537303434.292 [CBIT] Loaded
2018-09-18T20:43:54.293Z,1537303434.293 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-18T20:43:54.293Z,1537303434.293 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-18T20:43:54.294Z,1537303434.294 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-18T20:43:54.442Z,1537303434.442 [Aanderaa_O2] Loaded
2018-09-18T20:43:54.443Z,1537303434.443 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-09-18T20:43:54.521Z,1537303434.521 [CTD_NeilBrown] Loaded
2018-09-18T20:43:54.522Z,1537303434.522 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-09-18T20:43:54.523Z,1537303434.523 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4094E4E0
2018-09-18T20:43:54.523Z,1537303434.523 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 322
2018-09-18T20:43:54.574Z,1537303434.574 [CTD_Seabird] Loaded
2018-09-18T20:43:54.575Z,1537303434.575 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-09-18T20:43:54.576Z,1537303434.576 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4097E4E0
2018-09-18T20:43:54.576Z,1537303434.576 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 323
2018-09-18T20:43:54.594Z,1537303434.594 [ESPComponent] Loaded
2018-09-18T20:43:54.594Z,1537303434.594 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-09-18T20:43:54.608Z,1537303434.608 [PAR_Licor] Loaded
2018-09-18T20:43:54.608Z,1537303434.608 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-09-18T20:43:54.658Z,1537303434.658 [WetLabsBB2FL] Loaded
2018-09-18T20:43:54.658Z,1537303434.658 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-09-18T20:43:54.659Z,1537303434.659 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409AE4E0
2018-09-18T20:43:54.659Z,1537303434.659 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 324
2018-09-18T20:43:54.660Z,1537303434.660 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-18T20:43:54.660Z,1537303434.660 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-18T20:43:54.728Z,1537303434.728 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-18T20:43:54.820Z,1537303434.820 [VerticalControl] Loaded
2018-09-18T20:43:54.821Z,1537303434.821 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-18T20:43:54.821Z,1537303434.821 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-18T20:43:54.883Z,1537303434.883 [HorizontalControl] Loaded
2018-09-18T20:43:54.883Z,1537303434.883 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-18T20:43:54.884Z,1537303434.884 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-18T20:43:54.889Z,1537303434.889 [SpeedControl] Loaded
2018-09-18T20:43:54.889Z,1537303434.889 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-18T20:43:54.890Z,1537303434.890 [LoopControl](DEBUG): Construct LoopControl.
2018-09-18T20:43:54.890Z,1537303434.890 [LoopControl] Loaded
2018-09-18T20:43:54.891Z,1537303434.891 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-18T20:43:54.891Z,1537303434.891 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-18T20:43:54.892Z,1537303434.892 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-18T20:43:54.916Z,1537303434.916 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-18T20:43:54.916Z,1537303434.916 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-18T20:43:55.008Z,1537303435.008 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-18T20:43:55.008Z,1537303435.008 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-18T20:43:55.025Z,1537303435.025 [NavChart] Loaded
2018-09-18T20:43:55.025Z,1537303435.025 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-18T20:43:55.029Z,1537303435.029 [UniversalFixResidualReporter] Loaded
2018-09-18T20:43:55.029Z,1537303435.029 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-18T20:43:55.030Z,1537303435.030 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-18T20:43:55.034Z,1537303435.034 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-18T20:43:55.034Z,1537303435.034 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-18T20:43:55.042Z,1537303435.042 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-18T20:43:55.043Z,1537303435.043 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A3A4E0
2018-09-18T20:43:55.043Z,1537303435.043 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 325
2018-09-18T20:43:55.048Z,1537303435.048 [Supervisor](INFO): Main Thread ID is 9268
2018-09-18T20:43:55.048Z,1537303435.048 [Supervisor](DEBUG): Running supervisor.
2018-09-18T20:43:55.048Z,1537303435.048 [CommandLine ThreadHandler](INFO): Handler Thread ID is 326
2018-09-18T20:43:55.051Z,1537303435.051 [controlThread ThreadHandler](INFO): Handler Thread ID is 327
2018-09-18T20:43:55.051Z,1537303435.051 [controlThread](DEBUG): Initializing ControlThread
2018-09-18T20:43:55.052Z,1537303435.052 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-18T20:43:55.053Z,1537303435.053 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-18T20:43:55.053Z,1537303435.053 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-18T20:43:55.053Z,1537303435.053 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-18T20:43:55.054Z,1537303435.054 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-18T20:43:55.055Z,1537303435.055 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-18T20:43:55.055Z,1537303435.055 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-18T20:43:55.063Z,1537303435.063 [SBIT](INFO): Initialize SBIT Component.
2018-09-18T20:43:55.063Z,1537303435.063 [SBIT](IMPORTANT): git: 2018-09-06-6-gbaf5c46
2018-09-18T20:43:55.064Z,1537303435.064 [SBIT](INFO): git hash: baf5c469f70e7902a2cb0333843877cb31121fce
2018-09-18T20:43:55.064Z,1537303435.064 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-18T20:43:55.065Z,1537303435.065 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-09-18T20:43:55.066Z,1537303435.066 [SBIT](INFO): Beginning SBIT in 160.000000 seconds.
2018-09-18T20:43:55.067Z,1537303435.067 [IBIT](INFO): Initialize IBIT Component.
2018-09-18T20:43:55.068Z,1537303435.068 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-18T20:43:55.068Z,1537303435.068 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-09-18T20:43:55.069Z,1537303435.069 [logger ThreadHandler](INFO): Handler Thread ID is 328
2018-09-18T20:43:55.089Z,1537303435.089 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 329
2018-09-18T20:43:55.095Z,1537303435.095 [Radio_Surface](INFO): Powering up
2018-09-18T20:43:55.101Z,1537303435.101 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 330
2018-09-18T20:43:55.102Z,1537303435.102 [CTD_NeilBrown](INFO): Powering down
2018-09-18T20:43:55.129Z,1537303435.129 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 331
2018-09-18T20:43:55.130Z,1537303435.130 [CTD_Seabird](INFO): Initializing
2018-09-18T20:43:55.130Z,1537303435.130 [CTD_Seabird](INFO): Checking LCM
2018-09-18T20:43:55.139Z,1537303435.139 [CTD_Seabird](INFO): LCM OK
2018-09-18T20:43:55.139Z,1537303435.139 [CTD_Seabird](INFO): Powering up
2018-09-18T20:43:55.149Z,1537303435.149 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 332
2018-09-18T20:43:55.150Z,1537303435.150 [WetLabsBB2FL](INFO): Powering down
2018-09-18T20:43:55.181Z,1537303435.181 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-18T20:43:55.183Z,1537303435.183 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-18T20:43:55.183Z,1537303435.183 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-18T20:43:55.184Z,1537303435.184 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-18T20:43:55.185Z,1537303435.185 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T20:43:55.185Z,1537303435.185 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T20:43:55.186Z,1537303435.186 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T20:43:55.186Z,1537303435.186 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-18T20:43:55.187Z,1537303435.187 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-18T20:43:55.188Z,1537303435.188 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-18T20:43:55.197Z,1537303435.197 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 333
2018-09-18T20:43:55.200Z,1537303435.200 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-18T20:43:55.201Z,1537303435.201 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-18T20:43:55.201Z,1537303435.201 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-18T20:43:55.201Z,1537303435.201 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-18T20:43:55.201Z,1537303435.201 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-18T20:43:55.201Z,1537303435.201 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-18T20:43:55.202Z,1537303435.202 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-18T20:43:55.202Z,1537303435.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-18T20:43:55.202Z,1537303435.202 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-18T20:43:55.202Z,1537303435.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-18T20:43:55.202Z,1537303435.202 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-18T20:43:55.203Z,1537303435.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-18T20:43:55.203Z,1537303435.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-18T20:43:55.203Z,1537303435.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-18T20:43:55.203Z,1537303435.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-18T20:43:55.203Z,1537303435.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-18T20:43:55.236Z,1537303435.236 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-18T20:43:55.268Z,1537303435.268 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-09-18T20:43:55.268Z,1537303435.268 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-18T20:43:55.356Z,1537303435.356 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-18T20:43:55.377Z,1537303435.377 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-18T20:43:55.378Z,1537303435.378 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-18T20:43:55.398Z,1537303435.398 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-18T20:43:55.424Z,1537303435.424 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-18T20:43:55.430Z,1537303435.430 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-18T20:43:55.450Z,1537303435.450 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-09-18T20:43:55.455Z,1537303435.455 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-18T20:43:55.489Z,1537303435.489 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-18T20:43:55.498Z,1537303435.498 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-09-18T20:43:55.782Z,1537303435.782 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-18T20:43:55.881Z,1537303435.881 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:43:56.009Z,1537303436.009 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-18T20:43:56.017Z,1537303436.017 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-18T20:43:56.067Z,1537303436.067 [CommandLine](FAULT): Scheduling is paused
2018-09-18T20:43:56.067Z,1537303436.067 [CBIT](INFO): Critical error at 20180918T204355
2018-09-18T20:43:56.067Z,1537303436.067 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-09-18T20:43:56.263Z,1537303436.263 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-09-18T20:43:56.263Z,1537303436.263 [DropWeight] Hardware Fault, FailCount= 1
2018-09-18T20:43:56.263Z,1537303436.263 [DropWeight](ERROR): Hardware Fault
2018-09-18T20:43:56.317Z,1537303436.317 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:43:56.332Z,1537303436.332 [CBIT](INFO): Critical error at 20180918T204356
2018-09-18T20:43:56.335Z,1537303436.335 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-09-18T20:43:56.336Z,1537303436.336 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-09-18T20:43:56.601Z,1537303436.601 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:43:56.933Z,1537303436.933 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-18T20:43:56.933Z,1537303436.933 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-18T20:43:56.933Z,1537303436.933 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-18T20:43:56.933Z,1537303436.933 [BuoyancyServo](ERROR): Communications Fault
2018-09-18T20:43:56.935Z,1537303436.935 [CBIT](INFO): Critical error at 20180918T204356
2018-09-18T20:43:56.938Z,1537303436.938 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-18T20:43:56.999Z,1537303436.999 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:43:57.015Z,1537303437.015 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-18T20:43:57.015Z,1537303437.015 [BuoyancyServo](INFO): Powering down
2018-09-18T20:43:57.421Z,1537303437.421 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:43:57.782Z,1537303437.782 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:43:58.200Z,1537303438.200 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:43:58.599Z,1537303438.599 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-18T20:43:59.097Z,1537303439.097 [Aanderaa_O2](INFO): Powering down
2018-09-18T20:43:59.191Z,1537303439.191 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-18T20:43:59.191Z,1537303439.191 [BuoyancyServo] No Fault, FailCount= 1
2018-09-18T20:43:59.426Z,1537303439.426 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-18T20:43:59.545Z,1537303439.545 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-18T20:44:00.647Z,1537303440.647 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-18T20:44:00.767Z,1537303440.767 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-09-18T20:44:00.818Z,1537303440.818 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-09-18T20:44:01.182Z,1537303441.182 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-18T20:44:01.182Z,1537303441.182 [CTD_Seabird](INFO): Powering down
2018-09-18T20:44:21.750Z,1537303461.750 [NAL9602](INFO): Powering up NAL9602
2018-09-18T20:44:32.950Z,1537303472.950 [NAL9602](INFO): NAL9602 initialized
2018-09-18T20:44:56.601Z,1537303496.601 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:44:56.617Z,1537303496.617 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:44:57.787Z,1537303497.787 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:44:57.787Z,1537303497.787 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:45:01.799Z,1537303501.799 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:45:01.815Z,1537303501.815 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:02.200Z,1537303502.200 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:45:02.216Z,1537303502.216 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:02.217Z,1537303502.217 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:45:02.226Z,1537303502.226 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:45:02.586Z,1537303502.586 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:45:02.610Z,1537303502.610 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:45:03.020Z,1537303503.020 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:45:03.404Z,1537303503.404 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:45:10.184Z,1537303510.184 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:45:10.987Z,1537303510.987 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:45:14.984Z,1537303514.984 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:45:15.058Z,1537303515.058 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:15.385Z,1537303515.385 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:45:15.393Z,1537303515.393 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:45:15.786Z,1537303515.786 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:45:15.862Z,1537303515.862 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:16.186Z,1537303516.186 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:45:16.194Z,1537303516.194 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:45:16.208Z,1537303516.208 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:45:17.012Z,1537303517.012 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:45:23.390Z,1537303523.390 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:45:24.185Z,1537303524.185 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:45:28.201Z,1537303528.201 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:45:28.283Z,1537303528.283 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:28.599Z,1537303528.599 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:45:28.610Z,1537303528.610 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:45:29.386Z,1537303529.386 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:45:29.464Z,1537303529.464 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:29.482Z,1537303529.482 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:45:29.784Z,1537303529.784 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:45:29.792Z,1537303529.792 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:45:30.600Z,1537303530.600 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:45:36.990Z,1537303536.990 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:45:38.199Z,1537303538.199 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:45:41.784Z,1537303541.784 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:45:41.869Z,1537303541.869 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:42.180Z,1537303542.180 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:45:42.189Z,1537303542.189 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:45:42.575Z,1537303542.575 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:45:42.660Z,1537303542.660 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:42.990Z,1537303542.990 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:45:42.997Z,1537303542.997 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:45:43.012Z,1537303543.012 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:45:43.812Z,1537303543.812 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:45:50.183Z,1537303550.183 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:45:50.582Z,1537303550.582 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:45:54.190Z,1537303554.190 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:45:54.270Z,1537303554.270 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:54.594Z,1537303554.594 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:45:54.600Z,1537303554.600 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:45:55.386Z,1537303555.386 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:45:55.459Z,1537303555.459 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:45:55.473Z,1537303555.473 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:45:55.783Z,1537303555.783 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:45:55.791Z,1537303555.791 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:45:56.596Z,1537303556.596 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:46:02.180Z,1537303562.180 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:46:03.794Z,1537303563.794 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:46:06.181Z,1537303566.181 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:46:06.257Z,1537303566.257 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:06.587Z,1537303566.587 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:46:06.594Z,1537303566.594 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:46:07.389Z,1537303567.389 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:46:08.579Z,1537303568.579 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:46:08.669Z,1537303568.669 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:08.997Z,1537303568.997 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:46:09.014Z,1537303569.014 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:46:09.014Z,1537303569.014 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:46:09.800Z,1537303569.800 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:46:13.387Z,1537303573.387 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:46:13.404Z,1537303573.404 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:13.775Z,1537303573.775 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:46:13.795Z,1537303573.795 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:46:14.601Z,1537303574.601 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:46:16.979Z,1537303576.979 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:46:21.784Z,1537303581.784 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:46:21.863Z,1537303581.863 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:21.863Z,1537303581.863 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:46:22.187Z,1537303582.187 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:46:22.195Z,1537303582.195 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:46:23.000Z,1537303583.000 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:46:26.575Z,1537303586.575 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:46:26.661Z,1537303586.661 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:26.988Z,1537303586.988 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:46:26.996Z,1537303586.996 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:46:27.789Z,1537303587.789 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:46:30.198Z,1537303590.198 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:46:34.583Z,1537303594.583 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:46:34.661Z,1537303594.661 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:34.998Z,1537303594.998 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:46:35.033Z,1537303595.033 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:46:35.034Z,1537303595.034 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:46:35.438Z,1537303595.438 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-18T20:46:35.443Z,1537303595.443 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-18T20:46:35.805Z,1537303595.805 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:46:37.387Z,1537303597.387 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:46:39.789Z,1537303599.789 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:46:39.867Z,1537303599.867 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:40.198Z,1537303600.198 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:46:40.206Z,1537303600.206 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:46:41.002Z,1537303601.002 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:46:41.017Z,1537303601.017 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:41.029Z,1537303601.029 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:46:41.397Z,1537303601.397 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:46:41.405Z,1537303601.405 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:46:42.197Z,1537303602.197 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:46:46.245Z,1537303606.245 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000923
CHAN A1 (24V): -0.004494
CHAN A2 (12V): -0.002635
CHAN A3 (5V): -0.000957
CHAN B0 (3.3V): -0.000143
CHAN B1 (3.15aV): -0.000084
CHAN B2 (3.15bV): -0.000043
CHAN B3 (GND): 0.000096
OPEN: -0.000388
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-18T20:46:48.185Z,1537303608.185 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:46:48.604Z,1537303608.604 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-18T20:46:48.604Z,1537303608.604 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-18T20:46:48.604Z,1537303608.604 [SBIT](ERROR): Could not read massPosReader_.
2018-09-18T20:46:48.991Z,1537303608.991 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:46:52.179Z,1537303612.179 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:46:52.264Z,1537303612.264 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:52.577Z,1537303612.577 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:46:52.607Z,1537303612.607 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:46:53.400Z,1537303613.400 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:46:53.776Z,1537303613.776 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:46:53.860Z,1537303613.860 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:46:54.180Z,1537303614.180 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:46:54.212Z,1537303614.212 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:46:54.988Z,1537303614.988 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:46:55.405Z,1537303615.405 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T20:46:55.405Z,1537303615.405 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-09-18T20:46:55.405Z,1537303615.405 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T20:46:55.429Z,1537303615.429 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T20:46:55.837Z,1537303615.837 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T20:46:55.837Z,1537303615.837 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-09-18T20:46:56.226Z,1537303616.226 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T20:46:56.227Z,1537303616.227 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T20:46:56.227Z,1537303616.227 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T20:47:00.163Z,1537303620.163 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008021
2018-09-18T20:47:00.191Z,1537303620.191 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:47:02.596Z,1537303622.596 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:47:04.192Z,1537303624.192 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:47:04.262Z,1537303624.262 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:04.592Z,1537303624.592 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:47:04.599Z,1537303624.599 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:47:05.404Z,1537303625.404 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:47:07.007Z,1537303627.007 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:47:07.407Z,1537303627.407 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:47:07.481Z,1537303627.481 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:07.783Z,1537303627.783 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:47:07.810Z,1537303627.810 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:47:08.604Z,1537303628.604 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:47:11.399Z,1537303631.399 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:47:11.415Z,1537303631.415 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:11.801Z,1537303631.801 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:47:11.809Z,1537303631.809 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:47:12.616Z,1537303632.616 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:47:15.019Z,1537303635.019 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-18T20:47:15.019Z,1537303635.019 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-18T20:47:15.019Z,1537303635.019 [SBIT](ERROR): Could not read massPosReader_.
2018-09-18T20:47:15.019Z,1537303635.019 [SBIT](FAULT): Control surface position failure.
2018-09-18T20:47:16.184Z,1537303636.184 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:47:19.796Z,1537303639.796 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:47:20.998Z,1537303640.998 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:47:21.078Z,1537303641.078 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:21.395Z,1537303641.395 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:47:21.412Z,1537303641.412 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:47:22.220Z,1537303642.220 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:47:24.599Z,1537303644.599 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:47:24.676Z,1537303644.676 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:24.999Z,1537303644.999 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:47:25.007Z,1537303645.007 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:47:25.797Z,1537303645.797 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:47:28.615Z,1537303648.615 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-09-18T20:47:28.615Z,1537303648.615 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-09-18T20:47:28.616Z,1537303648.616 [SBIT](ERROR): Could not read massPosReader_.
2018-09-18T20:47:28.616Z,1537303648.616 [SBIT](FAULT): Control surface position failure.
2018-09-18T20:47:29.022Z,1537303649.022 [SBIT](CRITICAL): SBIT FAILED
2018-09-18T20:47:29.064Z,1537303649.064 [CommandLine](IMPORTANT): got command configSet list
2018-09-18T20:47:29.065Z,1537303649.065 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-18T20:47:29.066Z,1537303649.066 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2018-09-18T20:47:29.066Z,1537303649.066 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2018-09-18T20:47:29.066Z,1537303649.066 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2018-09-18T20:47:29.066Z,1537303649.066 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2018-09-18T20:47:29.066Z,1537303649.066 [CommandLine](IMPORTANT): ElevatorServo.loadAtStartup=0 bool;

2018-09-18T20:47:29.066Z,1537303649.066 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_salinity;

2018-09-18T20:47:29.067Z,1537303649.067 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_mean_sea_water_temperature;

2018-09-18T20:47:29.067Z,1537303649.067 [CommandLine](IMPORTANT): Express none CTD_NeilBrown.bin_median_sea_water_temperature;

2018-09-18T20:47:29.067Z,1537303649.067 [CommandLine](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2018-09-18T20:47:29.067Z,1537303649.067 [CommandLine](IMPORTANT): Express none Rowe_600LCM.height_above_sea_floor;

2018-09-18T20:47:29.067Z,1537303649.067 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-09-18T20:47:29.067Z,1537303649.067 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-09-18T20:47:29.067Z,1537303649.067 [CommandLine](IMPORTANT): MassServo.loadAtStartup=0 bool;

2018-09-18T20:47:29.067Z,1537303649.067 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-09-18T20:47:29.068Z,1537303649.068 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2018-09-18T20:47:29.068Z,1537303649.068 [CommandLine](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2018-09-18T20:47:29.068Z,1537303649.068 [CommandLine](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2018-09-18T20:47:29.068Z,1537303649.068 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=497.852 cubic_centimeter;

2018-09-18T20:47:29.068Z,1537303649.068 [CommandLine](IMPORTANT): VerticalControl.massDefault=-35 millimeter;

2018-09-18T20:47:29.074Z,1537303649.074 [CBIT](INFO): Critical error at 20180918T204729
2018-09-18T20:47:29.402Z,1537303649.402 [MissionManager](IMPORTANT): Started mission Startup
2018-09-18T20:47:29.402Z,1537303649.402 [Startup] Running Loop=1
2018-09-18T20:47:29.402Z,1537303649.402 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-18T20:47:29.402Z,1537303649.402 [Startup:A.GoToSurface] Running Loop=1
2018-09-18T20:47:29.402Z,1537303649.402 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-18T20:47:29.403Z,1537303649.403 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-18T20:47:29.404Z,1537303649.404 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-18T20:47:29.404Z,1537303649.404 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-18T20:47:29.405Z,1537303649.405 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-18T20:47:29.405Z,1537303649.405 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-18T20:47:29.411Z,1537303649.411 [Startup:StartupSatComms] Running Loop=1
2018-09-18T20:47:29.412Z,1537303649.412 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-18T20:47:29.412Z,1537303649.412 [Startup:StartupSatComms:A] Running Loop=1
2018-09-18T20:47:29.786Z,1537303649.786 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:47:29.841Z,1537303649.841 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-18T20:47:33.412Z,1537303653.412 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:47:33.801Z,1537303653.801 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:47:33.868Z,1537303653.868 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:34.184Z,1537303654.184 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:47:34.208Z,1537303654.208 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:47:35.005Z,1537303655.005 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:47:38.203Z,1537303658.203 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:47:38.285Z,1537303658.285 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:38.601Z,1537303658.601 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:47:38.609Z,1537303658.609 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:47:39.404Z,1537303659.404 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:47:41.795Z,1537303661.795 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:47:46.184Z,1537303666.184 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:47:46.265Z,1537303666.265 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:46.266Z,1537303666.266 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:47:46.583Z,1537303666.583 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:47:46.602Z,1537303666.602 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:47:47.397Z,1537303667.397 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:47:49.004Z,1537303669.004 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:47:50.983Z,1537303670.983 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:47:51.066Z,1537303671.066 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:51.387Z,1537303671.387 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:47:51.395Z,1537303671.395 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:47:52.200Z,1537303672.200 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:47:53.394Z,1537303673.394 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:47:53.419Z,1537303673.419 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:47:53.798Z,1537303673.798 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:47:53.806Z,1537303673.806 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:47:54.605Z,1537303674.605 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:47:59.311Z,1537303679.311 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:48:02.212Z,1537303682.212 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:48:03.813Z,1537303683.813 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:48:03.887Z,1537303683.887 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:48:04.230Z,1537303684.230 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:48:04.254Z,1537303684.254 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:48:05.037Z,1537303685.037 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:48:07.012Z,1537303687.012 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:48:07.088Z,1537303687.088 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:48:07.421Z,1537303687.421 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:48:07.430Z,1537303687.430 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:48:08.224Z,1537303688.224 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:48:12.204Z,1537303692.204 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:48:15.429Z,1537303695.429 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:48:17.413Z,1537303697.413 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:48:17.493Z,1537303697.493 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:48:17.814Z,1537303697.814 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:48:17.823Z,1537303697.823 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:48:18.621Z,1537303698.621 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:48:20.623Z,1537303700.623 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:48:20.708Z,1537303700.708 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:48:21.015Z,1537303701.015 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:48:21.049Z,1537303701.049 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:48:21.820Z,1537303701.820 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:48:25.822Z,1537303705.822 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:48:28.607Z,1537303708.607 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:48:29.428Z,1537303709.428 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-18T20:47:29.4Z
2018-09-18T20:48:29.428Z,1537303709.428 [Startup:StartupSatComms:A] Stopped
2018-09-18T20:48:29.428Z,1537303709.428 [Startup:StartupSatComms:B] Running Loop=1
2018-09-18T20:48:29.822Z,1537303709.822 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-18T20:48:30.625Z,1537303710.625 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:48:30.702Z,1537303710.702 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:48:33.770Z,1537303713.770 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:48:33.842Z,1537303713.842 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:48:33.842Z,1537303713.842 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:48:33.850Z,1537303713.850 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:48:33.931Z,1537303713.931 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20180918T194140/Courier0019.lzma
2018-09-18T20:48:34.044Z,1537303714.044 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:48:34.051Z,1537303714.051 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:48:34.396Z,1537303714.396 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:48:34.732Z,1537303714.732 [DataOverHttps](INFO): Moved sent file to Logs/20180918T194140/Courier0019.lzma.bak
2018-09-18T20:48:34.732Z,1537303714.732 [DataOverHttps](INFO): SBD MOMSN=8531671
2018-09-18T20:48:34.800Z,1537303714.800 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:48:43.548Z,1537303723.548 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:48:43.548Z,1537303723.548 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:48:43.612Z,1537303723.612 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20180918T204345/Courier0000.lzma
2018-09-18T20:48:44.411Z,1537303724.411 [DataOverHttps](INFO): Moved sent file to Logs/20180918T204345/Courier0000.lzma.bak
2018-09-18T20:48:44.412Z,1537303724.412 [DataOverHttps](INFO): SBD MOMSN=8531674
2018-09-18T20:48:48.378Z,1537303728.378 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:48:48.453Z,1537303728.453 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:48:48.454Z,1537303728.454 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:48:48.524Z,1537303728.524 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:48:48.654Z,1537303728.654 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:48:48.662Z,1537303728.662 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:48:48.663Z,1537303728.663 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:48:48.671Z,1537303728.671 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:48:49.476Z,1537303729.476 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:48:49.840Z,1537303729.840 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:48:53.542Z,1537303733.542 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20180918T194140/Express0020.lzma
2018-09-18T20:48:54.308Z,1537303734.308 [DataOverHttps](INFO): Moved sent file to Logs/20180918T194140/Express0020.lzma.bak
2018-09-18T20:48:54.308Z,1537303734.308 [DataOverHttps](INFO): SBD MOMSN=8531682
2018-09-18T20:48:58.297Z,1537303738.297 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:48:58.297Z,1537303738.297 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:49:03.150Z,1537303743.150 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:49:03.231Z,1537303743.231 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:03.232Z,1537303743.232 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:49:03.301Z,1537303743.301 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:03.379Z,1537303743.379 [DataOverHttps](INFO): Sending 1033 bytes from file Logs/20180918T204345/Express0001.lzma
2018-09-18T20:49:03.449Z,1537303743.449 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:49:03.457Z,1537303743.457 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:49:03.458Z,1537303743.458 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:49:03.466Z,1537303743.466 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:49:04.163Z,1537303744.163 [DataOverHttps](INFO): Moved sent file to Logs/20180918T204345/Express0001.lzma.bak
2018-09-18T20:49:04.164Z,1537303744.164 [DataOverHttps](INFO): SBD MOMSN=8531688
2018-09-18T20:49:04.240Z,1537303744.240 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:49:04.252Z,1537303744.252 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:49:04.951Z,1537303744.951 [Startup:StartupSatComms:B] Stopped
2018-09-18T20:49:04.952Z,1537303744.952 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-18T20:49:04.952Z,1537303744.952 [Startup:StartupSatComms] Stopped
2018-09-18T20:49:04.952Z,1537303744.952 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-18T20:49:04.953Z,1537303744.953 [Startup](INFO): Completed Startup
2018-09-18T20:49:04.953Z,1537303744.953 [MissionManager](INFO): Startup is completed.
2018-09-18T20:49:04.953Z,1537303744.953 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-18T20:49:04.953Z,1537303744.953 [Startup] Stopped
2018-09-18T20:49:04.954Z,1537303744.954 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-18T20:49:04.954Z,1537303744.954 [Startup:A.GoToSurface] Stopped
2018-09-18T20:49:04.954Z,1537303744.954 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-18T20:49:05.103Z,1537303745.103 [MissionManager](IMPORTANT): Started mission Default
2018-09-18T20:49:05.103Z,1537303745.103 [Default] Running Loop=1
2018-09-18T20:49:05.103Z,1537303745.103 [Default](DEBUG): Aggregate::initialize Default
2018-09-18T20:49:05.103Z,1537303745.103 [Default:B.GoToSurface] Running Loop=1
2018-09-18T20:49:05.103Z,1537303745.103 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-18T20:49:05.104Z,1537303745.104 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-18T20:49:05.104Z,1537303745.104 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-18T20:49:05.108Z,1537303745.108 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-18T20:49:05.109Z,1537303745.109 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-18T20:49:05.109Z,1537303745.109 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-18T20:49:05.110Z,1537303745.110 [Default:A.Wait] Running Loop=1
2018-09-18T20:49:05.110Z,1537303745.110 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-18T20:49:11.437Z,1537303751.437 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:49:11.829Z,1537303751.829 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:49:16.234Z,1537303756.234 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:49:16.306Z,1537303756.306 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:16.619Z,1537303756.619 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:49:16.704Z,1537303756.704 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:16.705Z,1537303756.705 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:49:16.713Z,1537303756.713 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:49:17.021Z,1537303757.021 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:49:17.029Z,1537303757.029 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:49:17.452Z,1537303757.452 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:49:17.844Z,1537303757.844 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:49:18.265Z,1537303758.265 [Default:A.Wait](INFO): Done Waiting.
2018-09-18T20:49:18.265Z,1537303758.265 [Default:A.Wait] Stopped
2018-09-18T20:49:18.265Z,1537303758.265 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-18T20:49:18.659Z,1537303758.659 [Default:CheckIn] Running Loop=1
2018-09-18T20:49:18.659Z,1537303758.659 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-18T20:49:18.659Z,1537303758.659 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-18T20:49:19.063Z,1537303759.063 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-18T20:49:24.636Z,1537303764.636 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:49:25.027Z,1537303765.027 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:49:29.042Z,1537303769.042 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:49:29.111Z,1537303769.111 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:29.447Z,1537303769.447 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:49:29.455Z,1537303769.455 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:49:29.857Z,1537303769.857 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:49:29.935Z,1537303769.935 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:30.246Z,1537303770.246 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:49:30.247Z,1537303770.247 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:49:30.254Z,1537303770.254 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:49:31.044Z,1537303771.044 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:49:35.794Z,1537303775.794 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-09-18T20:49:36.644Z,1537303776.644 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:49:38.229Z,1537303778.229 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:49:41.441Z,1537303781.441 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:49:41.519Z,1537303781.519 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:41.849Z,1537303781.849 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:49:41.857Z,1537303781.857 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:49:42.648Z,1537303782.648 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:49:42.649Z,1537303782.649 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:49:42.723Z,1537303782.723 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:43.039Z,1537303783.039 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:49:43.047Z,1537303783.047 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:49:43.856Z,1537303783.856 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:49:49.840Z,1537303789.840 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:49:50.628Z,1537303790.628 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:49:54.634Z,1537303794.634 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:49:54.695Z,1537303794.695 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:55.042Z,1537303795.042 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:49:55.120Z,1537303795.120 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:49:55.121Z,1537303795.121 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:49:55.128Z,1537303795.128 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:49:55.441Z,1537303795.441 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:49:55.449Z,1537303795.449 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:49:55.860Z,1537303795.860 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:49:56.248Z,1537303796.248 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:49:56.263Z,1537303796.263 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-18T20:49:56.263Z,1537303796.263 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-09-18T20:49:56.264Z,1537303796.264 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-18T20:49:56.300Z,1537303796.300 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-18T20:49:56.653Z,1537303796.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-18T20:49:56.653Z,1537303796.653 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-09-18T20:49:57.058Z,1537303797.058 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-18T20:49:57.059Z,1537303797.059 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-18T20:49:57.059Z,1537303797.059 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-18T20:49:57.445Z,1537303797.445 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:50:01.847Z,1537303801.847 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:50:01.863Z,1537303801.863 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:02.230Z,1537303802.230 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:50:02.266Z,1537303802.266 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:50:03.060Z,1537303803.060 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:50:03.446Z,1537303803.446 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:50:08.572Z,1537303808.572 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:50:08.657Z,1537303808.657 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:08.769Z,1537303808.769 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:50:08.777Z,1537303808.777 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:50:09.540Z,1537303809.540 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:50:10.328Z,1537303810.328 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:50:15.115Z,1537303815.115 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:50:15.205Z,1537303815.205 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:15.523Z,1537303815.523 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:50:15.532Z,1537303815.532 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:50:16.341Z,1537303816.341 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:50:16.730Z,1537303816.730 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:50:21.516Z,1537303821.516 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:50:21.596Z,1537303821.596 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:21.919Z,1537303821.919 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:50:21.950Z,1537303821.950 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:50:22.752Z,1537303822.752 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:50:23.521Z,1537303823.521 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:50:23.918Z,1537303823.918 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:50:27.930Z,1537303827.930 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:50:27.946Z,1537303827.946 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:28.338Z,1537303828.338 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:50:28.346Z,1537303828.346 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:50:28.346Z,1537303828.346 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:50:28.463Z,1537303828.463 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:28.749Z,1537303828.749 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:50:28.757Z,1537303828.757 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:50:29.137Z,1537303829.137 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:50:29.541Z,1537303829.541 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:50:36.324Z,1537303836.324 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:50:36.731Z,1537303836.731 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:50:40.740Z,1537303840.740 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:50:40.808Z,1537303840.808 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:41.141Z,1537303841.141 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:50:41.211Z,1537303841.211 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:41.211Z,1537303841.211 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:50:41.238Z,1537303841.238 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:50:41.519Z,1537303841.519 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:50:41.526Z,1537303841.526 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:50:41.959Z,1537303841.959 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:50:42.340Z,1537303842.340 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:50:48.724Z,1537303848.724 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:50:49.935Z,1537303849.935 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:50:52.736Z,1537303852.736 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:50:52.807Z,1537303852.807 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:53.139Z,1537303853.139 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:50:53.147Z,1537303853.147 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:50:53.929Z,1537303853.929 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:50:54.715Z,1537303854.715 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:50:54.802Z,1537303854.802 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:50:55.136Z,1537303855.136 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:50:55.145Z,1537303855.145 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:50:55.549Z,1537303855.549 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:50:55.936Z,1537303855.936 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:50:59.924Z,1537303859.924 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:50:59.940Z,1537303859.940 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:51:00.328Z,1537303860.328 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:51:00.336Z,1537303860.336 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:51:01.148Z,1537303861.148 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:51:03.115Z,1537303863.115 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:51:08.650Z,1537303868.650 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:51:08.726Z,1537303868.726 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:51:08.726Z,1537303868.726 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:51:08.857Z,1537303868.857 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:51:08.867Z,1537303868.867 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:51:09.653Z,1537303869.653 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:51:13.624Z,1537303873.624 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:51:13.699Z,1537303873.699 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:51:14.023Z,1537303874.023 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:51:14.046Z,1537303874.046 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:51:14.840Z,1537303874.840 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:51:16.435Z,1537303876.435 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:51:20.837Z,1537303880.837 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:51:20.903Z,1537303880.903 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:51:21.212Z,1537303881.212 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:51:21.219Z,1537303881.219 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:51:22.045Z,1537303882.045 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:51:22.429Z,1537303882.429 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:51:27.224Z,1537303887.224 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:51:27.300Z,1537303887.300 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:51:27.619Z,1537303887.619 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:51:27.627Z,1537303887.627 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:51:28.444Z,1537303888.444 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:51:28.831Z,1537303888.831 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:51:33.619Z,1537303893.619 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:51:33.690Z,1537303893.690 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:51:34.030Z,1537303894.030 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:51:34.038Z,1537303894.038 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:51:34.844Z,1537303894.844 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:51:35.613Z,1537303895.613 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:51:40.030Z,1537303900.030 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:51:40.106Z,1537303900.106 [BPC1](INFO): BPC1B: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:51:40.431Z,1537303900.431 [BPC1](INFO): BPC1B valid message: 1596 bytes.
2018-09-18T20:51:40.454Z,1537303900.454 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:51:41.232Z,1537303901.232 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:51:42.027Z,1537303902.027 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:51:46.830Z,1537303906.830 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:51:46.908Z,1537303906.908 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:51:47.225Z,1537303907.225 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:51:47.233Z,1537303907.233 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:51:48.040Z,1537303908.040 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:51:48.041Z,1537303908.041 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:51:52.831Z,1537303912.831 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:51:52.905Z,1537303912.905 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:51:53.211Z,1537303913.211 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:51:53.219Z,1537303913.219 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:51:54.032Z,1537303914.032 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:51:55.614Z,1537303915.614 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:52:00.432Z,1537303920.432 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:52:00.506Z,1537303920.506 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:52:00.843Z,1537303920.843 [BPC1](INFO): BPC1A valid message: 1824 bytes.
2018-09-18T20:52:00.851Z,1537303920.851 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:52:01.245Z,1537303921.245 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:52:01.633Z,1537303921.633 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:52:10.755Z,1537303930.755 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:52:10.755Z,1537303930.755 [BPC1](INFO): BPC1B: validating data format.
2018-09-18T20:52:10.845Z,1537303930.845 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:52:10.997Z,1537303930.997 [BPC1](INFO): BPC1B valid message: 1824 bytes.
2018-09-18T20:52:11.006Z,1537303931.006 [BPC1](INFO): BPC1B: data parsed.
2018-09-18T20:52:12.148Z,1537303932.148 [BPC1](INFO): BPC1B: initialized.
2018-09-18T20:52:13.752Z,1537303933.752 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:52:13.818Z,1537303933.818 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:52:14.134Z,1537303934.134 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:52:14.157Z,1537303934.157 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:52:14.944Z,1537303934.944 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:52:16.136Z,1537303936.136 [BPC1](INFO): BPC1A: sending IBPS break.
2018-09-18T20:52:19.338Z,1537303939.338 [BPC1](INFO): BPC1B: sending IBPS break.
2018-09-18T20:52:20.134Z,1537303940.134 [BPC1](INFO): BPC1A: validating data format.
2018-09-18T20:52:20.150Z,1537303940.150 [BPC1](INFO): BPC1A: got 7 stick msg (1596 bytes) with SHORT_IBPS_MENU.
2018-09-18T20:52:20.534Z,1537303940.534 [BPC1](INFO): BPC1A valid message: 1596 bytes.
2018-09-18T20:52:20.542Z,1537303940.542 [BPC1](INFO): BPC1A: data parsed.
2018-09-18T20:52:21.348Z,1537303941.348 [BPC1](INFO): BPC1A: initialized.
2018-09-18T20:52:21.553Z,1537303941.553 [CommandLine](IMPORTANT): got command restart application
2018-09-18T20:52:22.560Z,1537303942.560 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T20:52:22.560Z,1537303942.560 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:22.571Z,1537303942.571 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-18T20:52:22.571Z,1537303942.571 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:22.572Z,1537303942.572 [CommandLine](INFO): Join timeout helper Thread ID is 349
2018-09-18T20:52:22.585Z,1537303942.585 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-18T20:52:22.585Z,1537303942.585 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:22.585Z,1537303942.585 [NavChartDb](INFO): Join timeout helper Thread ID is 350
2018-09-18T20:52:22.941Z,1537303942.941 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T20:52:22.941Z,1537303942.941 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:22.957Z,1537303942.957 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-09-18T20:52:22.957Z,1537303942.957 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:22.957Z,1537303942.957 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 351
2018-09-18T20:52:22.985Z,1537303942.985 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T20:52:22.985Z,1537303942.985 [WetLabsBB2FL](INFO): Powering down
2018-09-18T20:52:22.986Z,1537303942.986 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:22.993Z,1537303942.993 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-09-18T20:52:22.993Z,1537303942.993 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:22.993Z,1537303942.993 [CTD_Seabird](INFO): Join timeout helper Thread ID is 352
2018-09-18T20:52:23.245Z,1537303943.245 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T20:52:23.414Z,1537303943.414 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-18T20:52:23.415Z,1537303943.415 [CTD_Seabird](INFO): Powering down
2018-09-18T20:52:23.416Z,1537303943.416 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:23.429Z,1537303943.429 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-09-18T20:52:23.429Z,1537303943.429 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:23.430Z,1537303943.430 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 354
2018-09-18T20:52:23.637Z,1537303943.637 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T20:52:23.637Z,1537303943.637 [CTD_NeilBrown](INFO): Powering down
2018-09-18T20:52:23.638Z,1537303943.638 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:23.649Z,1537303943.649 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-18T20:52:23.650Z,1537303943.650 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:23.650Z,1537303943.650 [Radio_Surface](INFO): Join timeout helper Thread ID is 355
2018-09-18T20:52:23.973Z,1537303943.973 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T20:52:23.973Z,1537303943.973 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:23.990Z,1537303943.990 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-18T20:52:23.990Z,1537303943.990 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:23.991Z,1537303943.991 [logger](INFO): Join timeout helper Thread ID is 356
2018-09-18T20:52:23.991Z,1537303943.991 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T20:52:23.991Z,1537303943.991 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:23.993Z,1537303943.993 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-18T20:52:23.993Z,1537303943.993 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:23.993Z,1537303943.993 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-18T20:52:23.993Z,1537303943.993 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:23.994Z,1537303943.994 [controlThread](INFO): Join timeout helper Thread ID is 357
2018-09-18T20:52:24.089Z,1537303944.089 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-18T20:52:24.089Z,1537303944.089 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-18T20:52:24.090Z,1537303944.090 [NAL9602](INFO): Powering down
2018-09-18T20:52:24.091Z,1537303944.091 [PNI_TCM](INFO): Powering down
2018-09-18T20:52:24.181Z,1537303944.181 [Aanderaa_O2](INFO): Powering down
2018-09-18T20:52:24.183Z,1537303944.183 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-18T20:52:24.185Z,1537303944.185 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-18T20:52:24.185Z,1537303944.185 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-18T20:52:24.186Z,1537303944.186 [MissionManager](INFO): Uninitializing Mission Default
2018-09-18T20:52:24.186Z,1537303944.186 [Default] Stopped
2018-09-18T20:52:24.186Z,1537303944.186 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-18T20:52:24.186Z,1537303944.186 [Default:B.GoToSurface] Stopped
2018-09-18T20:52:24.186Z,1537303944.186 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-18T20:52:24.186Z,1537303944.186 [Default:CheckIn] Stopped
2018-09-18T20:52:24.186Z,1537303944.186 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-18T20:52:24.187Z,1537303944.187 [Default:CheckIn:Read_GPS] Stopped
2018-09-18T20:52:24.190Z,1537303944.190 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-18T20:52:24.190Z,1537303944.190 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-18T20:52:24.191Z,1537303944.191 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-18T20:52:24.191Z,1537303944.191 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-18T20:52:24.191Z,1537303944.191 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-18T20:52:24.191Z,1537303944.191 [BuoyancyServo](INFO): Powering down
2018-09-18T20:52:24.205Z,1537303944.205 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-18T20:52:24.205Z,1537303944.205 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-18T20:52:24.205Z,1537303944.205 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-18T20:52:24.206Z,1537303944.206 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:24.323Z,1537303944.323 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:24.371Z,1537303944.371 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:24.375Z,1537303944.375 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:24.378Z,1537303944.378 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:24.408Z,1537303944.408 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-18T20:52:24.467Z,1537303944.467 [logger ThreadHandler](INFO): Thread cancelled.