2019-03-07T22:16:25.162Z,1551996985.162 [Supervisor](DEBUG): Initializing supervisor.
2019-03-07T22:16:25.165Z,1551996985.165 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-07T22:16:25.166Z,1551996985.166 [SyncHandler](INFO): Protected caller Thread ID is 814
2019-03-07T22:16:25.167Z,1551996985.167 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-07T22:16:25.168Z,1551996985.168 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-07T22:16:25.168Z,1551996985.168 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 815
2019-03-07T22:16:25.171Z,1551996985.171 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-07T22:16:25.183Z,1551996985.183 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-07T22:16:25.184Z,1551996985.184 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-07T22:16:25.184Z,1551996985.184 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 816
2019-03-07T22:16:25.185Z,1551996985.185 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-07T22:16:25.186Z,1551996985.186 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-07T22:16:25.187Z,1551996985.187 [logger ThreadHandler](INFO): Protected caller Thread ID is 817
2019-03-07T22:16:25.188Z,1551996985.188 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-07T22:16:25.189Z,1551996985.189 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-07T22:16:25.190Z,1551996985.190 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-07T22:16:25.627Z,1551996985.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-07T22:16:25.628Z,1551996985.628 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-07T22:16:25.726Z,1551996985.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-07T22:16:25.727Z,1551996985.727 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-07T22:16:26.065Z,1551996986.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-07T22:16:26.065Z,1551996986.065 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-07T22:16:26.211Z,1551996986.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-07T22:16:26.211Z,1551996986.211 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-07T22:16:26.409Z,1551996986.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-07T22:16:26.409Z,1551996986.409 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-07T22:16:26.883Z,1551996986.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-07T22:16:26.884Z,1551996986.884 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-07T22:16:27.100Z,1551996987.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-07T22:16:27.101Z,1551996987.101 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-07T22:16:27.251Z,1551996987.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-07T22:16:27.251Z,1551996987.251 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-07T22:16:27.455Z,1551996987.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-07T22:16:27.456Z,1551996987.456 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-07T22:16:27.552Z,1551996987.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-07T22:16:27.552Z,1551996987.552 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-07T22:16:27.873Z,1551996987.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-07T22:16:27.873Z,1551996987.873 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-07T22:16:27.954Z,1551996987.954 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-07T22:16:28.063Z,1551996988.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-07T22:16:28.063Z,1551996988.063 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-07T22:16:28.590Z,1551996988.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-07T22:16:28.590Z,1551996988.590 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-07T22:16:29.451Z,1551996989.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-07T22:16:29.453Z,1551996989.453 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-07T22:16:29.454Z,1551996989.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-07T22:16:29.670Z,1551996989.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-07T22:16:29.771Z,1551996989.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-07T22:16:29.871Z,1551996989.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-07T22:16:30.101Z,1551996990.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-07T22:16:30.102Z,1551996990.102 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-07T22:16:30.187Z,1551996990.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-07T22:16:30.282Z,1551996990.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-07T22:16:30.381Z,1551996990.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-07T22:16:30.464Z,1551996990.464 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-07T22:16:30.574Z,1551996990.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-07T22:16:30.754Z,1551996990.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-07T22:16:30.890Z,1551996990.890 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-07T22:16:30.890Z,1551996990.890 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-07T22:16:30.894Z,1551996990.894 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-07T22:16:31.213Z,1551996991.213 [DataOverHttps] Loaded
2019-03-07T22:16:31.213Z,1551996991.213 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-07T22:16:31.214Z,1551996991.214 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0
2019-03-07T22:16:31.214Z,1551996991.214 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 896
2019-03-07T22:16:31.252Z,1551996991.252 [Depth_Keller] Loaded
2019-03-07T22:16:31.253Z,1551996991.253 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-07T22:16:31.257Z,1551996991.257 [DropWeight] Loaded
2019-03-07T22:16:31.258Z,1551996991.258 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-07T22:16:31.305Z,1551996991.305 [DUSBL_Hydroid] Loaded
2019-03-07T22:16:31.306Z,1551996991.306 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-07T22:16:31.346Z,1551996991.346 [Micromodem] Loaded
2019-03-07T22:16:31.346Z,1551996991.346 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-07T22:16:31.442Z,1551996991.442 [NAL9602] Loaded
2019-03-07T22:16:31.442Z,1551996991.442 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-07T22:16:31.457Z,1551996991.457 [Onboard] Loaded
2019-03-07T22:16:31.458Z,1551996991.458 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-07T22:16:31.464Z,1551996991.464 [Radio_Surface] Loaded
2019-03-07T22:16:31.464Z,1551996991.464 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-07T22:16:31.465Z,1551996991.465 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0
2019-03-07T22:16:31.466Z,1551996991.466 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 897
2019-03-07T22:16:31.510Z,1551996991.510 [RDI_Pathfinder] Loaded
2019-03-07T22:16:31.510Z,1551996991.510 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-07T22:16:31.531Z,1551996991.531 [RDI_PathfinderUp] Loaded
2019-03-07T22:16:31.532Z,1551996991.532 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-07T22:16:33.298Z,1551996993.298 [BPC1] Loaded
2019-03-07T22:16:33.298Z,1551996993.298 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-07T22:16:33.298Z,1551996993.298 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-07T22:16:33.299Z,1551996993.299 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-07T22:16:33.312Z,1551996993.312 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-07T22:16:33.313Z,1551996993.313 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-07T22:16:33.417Z,1551996993.417 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-07T22:16:33.418Z,1551996993.418 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-07T22:16:33.476Z,1551996993.476 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-07T22:16:33.477Z,1551996993.477 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-07T22:16:33.535Z,1551996993.535 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-07T22:16:33.536Z,1551996993.536 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-07T22:16:33.556Z,1551996993.556 [NavChart] Loaded
2019-03-07T22:16:33.556Z,1551996993.556 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-07T22:16:33.560Z,1551996993.560 [UniversalFixResidualReporter] Loaded
2019-03-07T22:16:33.560Z,1551996993.560 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-07T22:16:33.561Z,1551996993.561 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-07T22:16:33.561Z,1551996993.561 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-07T22:16:33.751Z,1551996993.751 [BuoyancyServo] Loaded
2019-03-07T22:16:33.751Z,1551996993.751 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-07T22:16:33.767Z,1551996993.767 [ElevatorServo] Loaded
2019-03-07T22:16:33.767Z,1551996993.767 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-07T22:16:33.782Z,1551996993.782 [MassServo] Loaded
2019-03-07T22:16:33.782Z,1551996993.782 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-07T22:16:33.797Z,1551996993.797 [RudderServo] Loaded
2019-03-07T22:16:33.797Z,1551996993.797 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-07T22:16:33.838Z,1551996993.838 [ThrusterServo] Loaded
2019-03-07T22:16:33.838Z,1551996993.838 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-07T22:16:33.839Z,1551996993.839 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-07T22:16:33.839Z,1551996993.839 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-07T22:16:34.175Z,1551996994.175 [Aanderaa_O2] Loaded
2019-03-07T22:16:34.176Z,1551996994.176 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-03-07T22:16:34.264Z,1551996994.264 [CTD_NeilBrown] Loaded
2019-03-07T22:16:34.264Z,1551996994.264 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-07T22:16:34.265Z,1551996994.265 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2019-03-07T22:16:34.266Z,1551996994.266 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 898
2019-03-07T22:16:34.310Z,1551996994.310 [WetLabsSeaOWL_UV_A] Loaded
2019-03-07T22:16:34.310Z,1551996994.310 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-07T22:16:34.311Z,1551996994.311 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2019-03-07T22:16:34.312Z,1551996994.312 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 899
2019-03-07T22:16:34.312Z,1551996994.312 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-07T22:16:34.313Z,1551996994.313 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-07T22:16:34.548Z,1551996994.548 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-07T22:16:34.548Z,1551996994.548 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-07T22:16:34.588Z,1551996994.588 [DepthRateCalculator] Loaded
2019-03-07T22:16:34.588Z,1551996994.588 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-07T22:16:34.594Z,1551996994.594 [PitchRateCalculator] Loaded
2019-03-07T22:16:34.595Z,1551996994.595 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-07T22:16:34.607Z,1551996994.607 [SpeedCalculator] Loaded
2019-03-07T22:16:34.608Z,1551996994.608 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-07T22:16:34.629Z,1551996994.629 [TempGradientCalculator] Loaded
2019-03-07T22:16:34.629Z,1551996994.629 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-07T22:16:34.635Z,1551996994.635 [YawRateCalculator] Loaded
2019-03-07T22:16:34.635Z,1551996994.635 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-07T22:16:34.676Z,1551996994.676 [ElevatorOffsetCalculator] Loaded
2019-03-07T22:16:34.676Z,1551996994.676 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-07T22:16:34.676Z,1551996994.676 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-07T22:16:34.677Z,1551996994.677 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-07T22:16:34.808Z,1551996994.808 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-07T22:16:34.831Z,1551996994.831 [SBIT] Loaded
2019-03-07T22:16:34.831Z,1551996994.831 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-07T22:16:34.832Z,1551996994.832 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-07T22:16:34.843Z,1551996994.843 [IBIT] Loaded
2019-03-07T22:16:34.844Z,1551996994.844 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-07T22:16:34.847Z,1551996994.847 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-07T22:16:34.985Z,1551996994.985 [CBIT] Loaded
2019-03-07T22:16:34.985Z,1551996994.985 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-07T22:16:34.985Z,1551996994.985 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-07T22:16:34.986Z,1551996994.986 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-07T22:16:35.048Z,1551996995.048 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-07T22:16:35.049Z,1551996995.049 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-07T22:16:35.145Z,1551996995.145 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-07T22:16:35.145Z,1551996995.145 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-07T22:16:35.210Z,1551996995.210 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-07T22:16:35.294Z,1551996995.294 [VerticalControl] Loaded
2019-03-07T22:16:35.295Z,1551996995.295 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-07T22:16:35.295Z,1551996995.295 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-07T22:16:35.352Z,1551996995.352 [HorizontalControl] Loaded
2019-03-07T22:16:35.352Z,1551996995.352 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-07T22:16:35.353Z,1551996995.353 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-07T22:16:35.355Z,1551996995.355 [SpeedControl] Loaded
2019-03-07T22:16:35.355Z,1551996995.355 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-07T22:16:35.356Z,1551996995.356 [LoopControl](DEBUG): Construct LoopControl.
2019-03-07T22:16:35.356Z,1551996995.356 [LoopControl] Loaded
2019-03-07T22:16:35.356Z,1551996995.356 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-07T22:16:35.357Z,1551996995.357 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-07T22:16:35.358Z,1551996995.358 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-07T22:16:35.384Z,1551996995.384 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-07T22:16:35.388Z,1551996995.388 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-07T22:16:35.389Z,1551996995.389 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-07T22:16:35.396Z,1551996995.396 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-07T22:16:35.397Z,1551996995.397 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2019-03-07T22:16:35.397Z,1551996995.397 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 900
2019-03-07T22:16:35.402Z,1551996995.402 [Supervisor](INFO): Main Thread ID is 8039
2019-03-07T22:16:35.402Z,1551996995.402 [Supervisor](DEBUG): Running supervisor.
2019-03-07T22:16:35.402Z,1551996995.402 [CommandLine ThreadHandler](INFO): Handler Thread ID is 901
2019-03-07T22:16:35.405Z,1551996995.405 [controlThread ThreadHandler](INFO): Handler Thread ID is 902
2019-03-07T22:16:35.405Z,1551996995.405 [controlThread](DEBUG): Initializing ControlThread
2019-03-07T22:16:35.412Z,1551996995.412 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-07T22:16:35.413Z,1551996995.413 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-07T22:16:35.413Z,1551996995.413 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-07T22:16:35.415Z,1551996995.415 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-07T22:16:35.415Z,1551996995.415 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-07T22:16:35.415Z,1551996995.415 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-07T22:16:35.415Z,1551996995.415 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-07T22:16:35.416Z,1551996995.416 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-07T22:16:35.416Z,1551996995.416 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-07T22:16:35.417Z,1551996995.417 [SBIT](INFO): Initialize SBIT Component.
2019-03-07T22:16:35.418Z,1551996995.418 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3
2019-03-07T22:16:35.418Z,1551996995.418 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa
2019-03-07T22:16:35.418Z,1551996995.418 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-07T22:16:35.418Z,1551996995.418 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-07T22:16:35.419Z,1551996995.419 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-07T22:16:35.420Z,1551996995.420 [IBIT](INFO): Initialize IBIT Component.
2019-03-07T22:16:35.421Z,1551996995.421 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-07T22:16:35.422Z,1551996995.422 [logger ThreadHandler](INFO): Handler Thread ID is 903
2019-03-07T22:16:35.433Z,1551996995.433 [CBIT](DEBUG): Initialized mux pins.
2019-03-07T22:16:35.433Z,1551996995.433 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-07T22:16:35.441Z,1551996995.441 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 904
2019-03-07T22:16:35.442Z,1551996995.442 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-07T22:16:35.453Z,1551996995.453 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 905
2019-03-07T22:16:35.457Z,1551996995.457 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-07T22:16:35.457Z,1551996995.457 [CBIT](DEBUG): Initializing heartbeat.
2019-03-07T22:16:35.465Z,1551996995.465 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 906
2019-03-07T22:16:35.466Z,1551996995.466 [CTD_NeilBrown](INFO): Powering down
2019-03-07T22:16:35.493Z,1551996995.493 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 907
2019-03-07T22:16:35.494Z,1551996995.494 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-07T22:16:35.529Z,1551996995.529 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-07T22:16:35.529Z,1551996995.529 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-07T22:16:35.530Z,1551996995.530 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 908
2019-03-07T22:16:35.533Z,1551996995.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-07T22:16:35.533Z,1551996995.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-07T22:16:35.533Z,1551996995.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-07T22:16:35.533Z,1551996995.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-07T22:16:35.534Z,1551996995.534 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-07T22:16:35.534Z,1551996995.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-07T22:16:35.534Z,1551996995.534 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-07T22:16:35.534Z,1551996995.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-07T22:16:35.534Z,1551996995.534 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-07T22:16:35.534Z,1551996995.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-07T22:16:35.535Z,1551996995.535 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-07T22:16:35.535Z,1551996995.535 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-07T22:16:35.535Z,1551996995.535 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-07T22:16:35.535Z,1551996995.535 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-07T22:16:35.535Z,1551996995.535 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-07T22:16:35.536Z,1551996995.536 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-07T22:16:35.565Z,1551996995.565 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-07T22:16:35.567Z,1551996995.567 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-07T22:16:35.567Z,1551996995.567 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-07T22:16:35.568Z,1551996995.568 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-07T22:16:35.569Z,1551996995.569 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-07T22:16:35.578Z,1551996995.578 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-07T22:16:35.610Z,1551996995.610 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-03-07T22:16:35.610Z,1551996995.610 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-07T22:16:35.677Z,1551996995.677 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-07T22:16:35.678Z,1551996995.678 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-07T22:16:35.680Z,1551996995.680 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-07T22:16:35.706Z,1551996995.706 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-07T22:16:35.725Z,1551996995.725 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-07T22:16:35.730Z,1551996995.730 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-07T22:16:35.753Z,1551996995.753 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-03-07T22:16:35.767Z,1551996995.767 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-07T22:16:35.798Z,1551996995.798 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-07T22:16:35.799Z,1551996995.799 [DUSBL_Hydroid](INFO): Powering up
2019-03-07T22:16:35.800Z,1551996995.800 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-07T22:16:35.817Z,1551996995.817 [Micromodem](INFO): Start
2019-03-07T22:16:35.857Z,1551996995.857 [Radio_Surface](INFO): Powering up
2019-03-07T22:16:35.882Z,1551996995.882 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-07T22:16:35.926Z,1551996995.926 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-07T22:16:35.933Z,1551996995.933 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-07T22:16:35.934Z,1551996995.934 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-07T22:16:35.957Z,1551996995.957 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-07T22:16:35.958Z,1551996995.958 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-07T22:16:35.965Z,1551996995.965 [MassServo](DEBUG): Initializing MassServo.
2019-03-07T22:16:35.966Z,1551996995.966 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-07T22:16:35.973Z,1551996995.973 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-07T22:16:35.974Z,1551996995.974 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-07T22:16:35.981Z,1551996995.981 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-07T22:16:36.190Z,1551996996.190 [Micromodem](INFO): Starting
2019-03-07T22:16:36.191Z,1551996996.191 [Micromodem](INFO): Powering up
2019-03-07T22:16:36.191Z,1551996996.191 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-07T22:16:36.293Z,1551996996.293 [RDI_PathfinderUp](INFO): Powering down
2019-03-07T22:16:36.369Z,1551996996.369 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-07T22:16:36.582Z,1551996996.582 [Micromodem](INFO): Starting
2019-03-07T22:16:36.601Z,1551996996.601 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-07T22:16:36.933Z,1551996996.933 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-07T22:16:36.933Z,1551996996.933 [RudderServo](FAULT): Rudder failed to initialize
2019-03-07T22:16:36.933Z,1551996996.933 [RudderServo] Communications Fault, FailCount= 1
2019-03-07T22:16:36.933Z,1551996996.933 [RudderServo](ERROR): Communications Fault
2019-03-07T22:16:37.044Z,1551996997.044 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-07T22:16:37.055Z,1551996997.055 [Micromodem](INFO): Starting
2019-03-07T22:16:37.218Z,1551996997.218 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-07T22:16:37.218Z,1551996997.218 [RudderServo](INFO): Powering down
2019-03-07T22:16:37.458Z,1551996997.458 [Micromodem](INFO): Starting
2019-03-07T22:16:37.862Z,1551996997.862 [Micromodem](INFO): Starting
2019-03-07T22:16:37.890Z,1551996997.890 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-07T22:16:38.010Z,1551996998.010 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-07T22:16:38.014Z,1551996998.014 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-07T22:16:38.014Z,1551996998.014 [RudderServo] No Fault, FailCount= 1
2019-03-07T22:16:38.266Z,1551996998.266 [Micromodem](INFO): Starting
2019-03-07T22:16:38.670Z,1551996998.670 [Micromodem](INFO): Starting
2019-03-07T22:16:39.075Z,1551996999.075 [Micromodem](INFO): Starting
2019-03-07T22:16:39.213Z,1551996999.213 [Aanderaa_O2](INFO): Powering down
2019-03-07T22:16:39.478Z,1551996999.478 [Micromodem](INFO): Starting
2019-03-07T22:16:39.894Z,1551996999.894 [Micromodem](INFO): Starting
2019-03-07T22:16:40.286Z,1551997000.286 [Micromodem](INFO): Starting
2019-03-07T22:16:40.690Z,1551997000.690 [Micromodem](INFO): Starting
2019-03-07T22:16:41.094Z,1551997001.094 [Micromodem](INFO): Starting
2019-03-07T22:16:41.095Z,1551997001.095 [Micromodem](INFO): Starting
2019-03-07T22:16:41.095Z,1551997001.095 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37

2019-03-07T22:16:41.095Z,1551997001.095 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,CST,1*37

2019-03-07T22:16:41.095Z,1551997001.095 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-03-07T22:16:41.096Z,1551997001.096 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-07T22:16:41.096Z,1551997001.096 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-07T22:16:41.096Z,1551997001.096 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32

2019-03-07T22:16:41.096Z,1551997001.096 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,REV,1*32

2019-03-07T22:16:41.096Z,1551997001.096 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29

2019-03-07T22:16:41.098Z,1551997001.098 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,RXP,1*29

2019-03-07T22:16:41.498Z,1551997001.498 [Micromodem](INFO): Starting
2019-03-07T22:16:41.954Z,1551997001.954 [Micromodem](INFO): Starting
2019-03-07T22:16:42.350Z,1551997002.350 [Micromodem](INFO): Starting
2019-03-07T22:16:42.755Z,1551997002.755 [Micromodem](INFO): Starting
2019-03-07T22:16:43.158Z,1551997003.158 [Micromodem](INFO): Starting
2019-03-07T22:16:43.159Z,1551997003.159 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-07T22:16:43.562Z,1551997003.562 [Micromodem](INFO): Starting
2019-03-07T22:16:43.967Z,1551997003.967 [Micromodem](INFO): Starting
2019-03-07T22:16:44.374Z,1551997004.374 [Micromodem](INFO): Starting
2019-03-07T22:16:44.774Z,1551997004.774 [Micromodem](INFO): Starting
2019-03-07T22:16:45.179Z,1551997005.179 [Micromodem](INFO): Starting
2019-03-07T22:16:45.582Z,1551997005.582 [Micromodem](INFO): Starting
2019-03-07T22:16:45.986Z,1551997005.986 [Micromodem](INFO): Starting
2019-03-07T22:16:46.391Z,1551997006.391 [Micromodem](INFO): Starting
2019-03-07T22:16:46.794Z,1551997006.794 [Micromodem](INFO): Starting
2019-03-07T22:16:47.198Z,1551997007.198 [Micromodem](INFO): Starting
2019-03-07T22:16:47.603Z,1551997007.603 [Micromodem](INFO): Starting
2019-03-07T22:16:48.006Z,1551997008.006 [Micromodem](INFO): Starting
2019-03-07T22:16:48.410Z,1551997008.410 [Micromodem](INFO): Starting
2019-03-07T22:16:48.827Z,1551997008.827 [Micromodem](INFO): Starting
2019-03-07T22:16:49.218Z,1551997009.218 [Micromodem](INFO): Starting
2019-03-07T22:16:49.244Z,1551997009.244 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-07T22:16:49.622Z,1551997009.622 [Micromodem](INFO): Starting
2019-03-07T22:16:50.027Z,1551997010.027 [Micromodem](INFO): Starting
2019-03-07T22:16:50.430Z,1551997010.430 [Micromodem](INFO): Starting
2019-03-07T22:16:50.834Z,1551997010.834 [Micromodem](INFO): Starting
2019-03-07T22:16:51.239Z,1551997011.239 [Micromodem](INFO): Starting
2019-03-07T22:16:51.239Z,1551997011.239 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32

2019-03-07T22:16:51.642Z,1551997011.642 [Micromodem](INFO): Starting
2019-03-07T22:16:52.046Z,1551997012.046 [Micromodem](INFO): Starting
2019-03-07T22:16:52.451Z,1551997012.451 [Micromodem](INFO): Starting
2019-03-07T22:16:52.854Z,1551997012.854 [Micromodem](INFO): Starting
2019-03-07T22:16:53.259Z,1551997013.259 [Micromodem](INFO): Starting
2019-03-07T22:16:53.663Z,1551997013.663 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-07T22:16:53.677Z,1551997013.677 [Micromodem](INFO): Starting
2019-03-07T22:16:54.066Z,1551997014.066 [Micromodem](INFO): Starting
2019-03-07T22:16:54.470Z,1551997014.470 [Micromodem](INFO): Starting
2019-03-07T22:16:54.878Z,1551997014.878 [Micromodem](INFO): Starting
2019-03-07T22:16:55.278Z,1551997015.278 [Micromodem](INFO): Starting
2019-03-07T22:16:55.682Z,1551997015.682 [Micromodem](INFO): Starting
2019-03-07T22:16:56.086Z,1551997016.086 [Micromodem](INFO): Starting
2019-03-07T22:16:56.494Z,1551997016.494 [Micromodem](INFO): Starting
2019-03-07T22:16:56.894Z,1551997016.894 [Micromodem](INFO): Starting
2019-03-07T22:16:57.298Z,1551997017.298 [Micromodem](INFO): Starting
2019-03-07T22:16:57.702Z,1551997017.702 [Micromodem](INFO): Starting
2019-03-07T22:16:58.106Z,1551997018.106 [Micromodem](INFO): Starting
2019-03-07T22:16:58.510Z,1551997018.510 [Micromodem](INFO): Starting
2019-03-07T22:16:58.914Z,1551997018.914 [Micromodem](INFO): Starting
2019-03-07T22:16:59.321Z,1551997019.321 [Micromodem](INFO): Starting
2019-03-07T22:16:59.722Z,1551997019.722 [Micromodem](INFO): Starting
2019-03-07T22:17:00.126Z,1551997020.126 [Micromodem](INFO): Starting
2019-03-07T22:17:00.530Z,1551997020.530 [Micromodem](INFO): Starting
2019-03-07T22:17:00.942Z,1551997020.942 [Micromodem](INFO): Starting
2019-03-07T22:17:01.335Z,1551997021.335 [Micromodem](INFO): Starting
2019-03-07T22:17:01.335Z,1551997021.335 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29

2019-03-07T22:17:01.742Z,1551997021.742 [Micromodem](INFO): Starting
2019-03-07T22:17:01.744Z,1551997021.744 [NAL9602](INFO): Powering up NAL9602
2019-03-07T22:17:02.146Z,1551997022.146 [Micromodem](INFO): Starting
2019-03-07T22:17:02.550Z,1551997022.550 [Micromodem](INFO): Starting
2019-03-07T22:17:02.957Z,1551997022.957 [Micromodem](INFO): Starting
2019-03-07T22:17:03.358Z,1551997023.358 [Micromodem](INFO): Starting
2019-03-07T22:17:03.762Z,1551997023.762 [Micromodem](INFO): Starting
2019-03-07T22:17:04.166Z,1551997024.166 [Micromodem](INFO): Starting
2019-03-07T22:17:04.214Z,1551997024.214 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-07T22:17:04.246Z,1551997024.246 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-07T22:17:04.570Z,1551997024.570 [Micromodem](INFO): Starting
2019-03-07T22:17:04.985Z,1551997024.985 [Micromodem](INFO): Starting
2019-03-07T22:17:05.378Z,1551997025.378 [Micromodem](INFO): Starting
2019-03-07T22:17:05.782Z,1551997025.782 [Micromodem](INFO): Starting
2019-03-07T22:17:06.186Z,1551997026.186 [Micromodem](INFO): Starting
2019-03-07T22:17:06.590Z,1551997026.590 [Micromodem](INFO): Starting
2019-03-07T22:17:07.200Z,1551997027.200 [Micromodem](INFO): Starting
2019-03-07T22:17:07.602Z,1551997027.602 [Micromodem](INFO): Starting
2019-03-07T22:17:08.006Z,1551997028.006 [Micromodem](INFO): Starting
2019-03-07T22:17:08.410Z,1551997028.410 [Micromodem](INFO): Starting
2019-03-07T22:17:08.812Z,1551997028.812 [Micromodem](INFO): Starting
2019-03-07T22:17:09.218Z,1551997029.218 [Micromodem](INFO): Starting
2019-03-07T22:17:09.622Z,1551997029.622 [Micromodem](INFO): Starting
2019-03-07T22:17:10.026Z,1551997030.026 [Micromodem](INFO): Starting
2019-03-07T22:17:10.430Z,1551997030.430 [Micromodem](INFO): Starting
2019-03-07T22:17:10.834Z,1551997030.834 [Micromodem](INFO): Starting
2019-03-07T22:17:11.235Z,1551997031.235 [Micromodem](INFO): Starting
2019-03-07T22:17:11.236Z,1551997031.236 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-07T22:17:12Z,0*7F

2019-03-07T22:17:11.236Z,1551997031.236 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-07T22:17:12Z,0*7F

2019-03-07T22:17:11.236Z,1551997031.236 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-07T22:17:12Z,0*7F

2019-03-07T22:17:11.642Z,1551997031.642 [Micromodem](INFO): Starting
2019-03-07T22:17:12.046Z,1551997032.046 [Micromodem](INFO): Starting
2019-03-07T22:17:12.452Z,1551997032.452 [Micromodem](INFO): Starting
2019-03-07T22:17:12.453Z,1551997032.453 [Micromodem](DEBUG): Nmea buf: $CCCFQ,TOP*33

2019-03-07T22:17:12.453Z,1551997032.453 [Micromodem](IMPORTANT): Nmea buf: $CCCFQ,TOP*33

2019-03-07T22:17:12.453Z,1551997032.453 [Micromodem](INFO): Nmea out: $CCCFQ,TOP*33

2019-03-07T22:17:12.682Z,1551997032.682 [CBIT](INFO): A/D timeout, 5 tries over 128 ms

2019-03-07T22:17:12.855Z,1551997032.855 [Micromodem](INFO): Starting
2019-03-07T22:17:12.861Z,1551997032.861 [NAL9602](INFO): NAL9602 initialized
2019-03-07T22:17:13.259Z,1551997033.259 [Micromodem](INFO): Starting
2019-03-07T22:17:13.685Z,1551997033.685 [NAL9602](DEBUG): Fix Requested
2019-03-07T22:17:15.483Z,1551997035.483 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011720
CHAN A1 (24V): -0.026009
CHAN A2 (12V): -0.006514
CHAN A3 (5V): -0.002648
CHAN B0 (3.3V): -0.000561
CHAN B1 (3.15aV): nan
CHAN B2 (3.15bV): -0.000143
CHAN B3 (GND): 0.001068
OPEN: 0.009291
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-07T22:17:17.051Z,1551997037.051 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-03-07T22:17:46.667Z,1551997066.667 [CommandLine](IMPORTANT): got command show stack
2019-03-07T22:17:46.667Z,1551997066.667 [CommandLine](IMPORTANT): Behavior Stack:

2019-03-07T22:17:46.667Z,1551997066.667 [MissionManager](IMPORTANT): Mission loaded, but not running.
2019-03-07T22:17:47.992Z,1551997067.992 [CommandLine](IMPORTANT): got command failComponent
2019-03-07T22:17:47.992Z,1551997067.992 [CommandLine](IMPORTANT): Failed components:
2019-03-07T22:17:47.993Z,1551997067.993 [CommandLine](IMPORTANT): No failed Components.
2019-03-07T22:17:51.739Z,1551997071.739 [CommandLine](IMPORTANT): got command show variable USBL
2019-03-07T22:17:51.797Z,1551997071.797 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-07T22:17:51.798Z,1551997071.798 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-07T22:17:51.798Z,1551997071.798 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-07T22:17:51.798Z,1551997071.798 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-07T22:17:51.799Z,1551997071.799 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-07T22:17:51.799Z,1551997071.799 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-07T22:17:51.799Z,1551997071.799 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-07T22:17:51.800Z,1551997071.800 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-07T22:17:51.800Z,1551997071.800 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-07T22:17:51.800Z,1551997071.800 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-07T22:17:51.801Z,1551997071.801 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-07T22:17:51.801Z,1551997071.801 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-07T22:17:51.811Z,1551997071.811 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-07T22:17:51.812Z,1551997071.812 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-07T22:17:51.812Z,1551997071.812 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-07T22:17:51.815Z,1551997071.815 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-07T22:17:51.815Z,1551997071.815 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-07T22:17:51.816Z,1551997071.816 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-07T22:17:51.816Z,1551997071.816 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-07T22:17:51.816Z,1551997071.816 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-07T22:17:51.818Z,1551997071.818 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-07T22:17:51.818Z,1551997071.818 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-07T22:17:51.818Z,1551997071.818 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-07T22:17:51.819Z,1551997071.819 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-07T22:17:51.819Z,1551997071.819 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-07T22:17:57.764Z,1551997077.764 [SBIT](IMPORTANT): SBIT PASSED
2019-03-07T22:17:57.854Z,1551997077.854 [CommandLine](IMPORTANT): got command configSet list
2019-03-07T22:17:57.854Z,1551997077.854 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-07T22:17:57.855Z,1551997077.855 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-07T22:17:57.855Z,1551997077.855 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity=1 enum;

2019-03-07T22:17:58.163Z,1551997078.163 [MissionManager](IMPORTANT): Started mission Startup
2019-03-07T22:17:58.163Z,1551997078.163 [Startup] Running Loop=1
2019-03-07T22:17:58.163Z,1551997078.163 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-07T22:17:58.163Z,1551997078.163 [Startup:A.GoToSurface] Running Loop=1
2019-03-07T22:17:58.163Z,1551997078.163 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-07T22:17:58.164Z,1551997078.164 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-07T22:17:58.164Z,1551997078.164 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-07T22:17:58.165Z,1551997078.165 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-07T22:17:58.165Z,1551997078.165 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-07T22:17:58.166Z,1551997078.166 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-07T22:17:58.167Z,1551997078.167 [Startup:StartupSatComms] Running Loop=1
2019-03-07T22:17:58.167Z,1551997078.167 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-07T22:17:58.167Z,1551997078.167 [Startup:StartupSatComms:A] Running Loop=1
2019-03-07T22:17:58.547Z,1551997078.547 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-07T22:18:05.275Z,1551997085.275 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.direction
2019-03-07T22:18:09.747Z,1551997089.747 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.range
2019-03-07T22:18:11.815Z,1551997091.815 [CommandLine](IMPORTANT): got command show stack
2019-03-07T22:18:11.815Z,1551997091.815 [CommandLine](IMPORTANT): Behavior Stack:

2019-03-07T22:18:11.815Z,1551997091.815 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2019-03-07T22:18:11.815Z,1551997091.815 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:A
2019-03-07T22:18:20.718Z,1551997100.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=2189, MT Status=2, MTMSN=0
2019-03-07T22:18:20.719Z,1551997100.719 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-07T22:18:22.331Z,1551997102.331 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-03-07T22:18:22.331Z,1551997102.331 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-07T22:18:22.429Z,1551997102.429 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-07T22:18:22.432Z,1551997102.432 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-07T22:18:22.440Z,1551997102.440 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-07T22:18:22.448Z,1551997102.448 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-07T22:18:22.456Z,1551997102.456 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-07T22:18:22.462Z,1551997102.462 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-07T22:18:22.481Z,1551997102.481 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-07T22:18:22.494Z,1551997102.494 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-07T22:18:22.511Z,1551997102.511 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="NumberOfRequests"><Description>
        Number of pings to requst.
    </Description><Units:count/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>   

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>      
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Science:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <ReadData>
        <While>
            <Arg Name="EnabledDUSBL"/>
        </While>
        <Sensor:DUSBL_Hydroid.range/>
        <Sensor:DUSBL_Hydroid.direction/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

        <Sequence Repeat="NumberOfRequests"/>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.transponderCode/><Arg Name="TransponderCode"/></Assign>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.numberOfPingsRequested/><Arg Name="NumberOfPings"/></Assign>
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>10</Value></Setting>
          </Guidance:Wait>
      </Aggregate>    
</Mission>


2019-03-07T22:18:22.517Z,1551997102.517 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-03-07T22:18:31.970Z,1551997111.970 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2019-03-07T22:18:35.688Z,1551997115.688 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-07T22:18:35.688Z,1551997115.688 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-07T22:18:35.688Z,1551997115.688 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-07T22:18:35.690Z,1551997115.690 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-07T22:18:35.690Z,1551997115.690 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-07T22:18:35.690Z,1551997115.690 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-07T22:18:35.692Z,1551997115.692 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-07T22:18:35.692Z,1551997115.692 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-07T22:18:35.692Z,1551997115.692 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-07T22:18:35.743Z,1551997115.743 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-07T22:18:35.743Z,1551997115.743 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-07T22:18:35.744Z,1551997115.744 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-07T22:18:36.111Z,1551997116.111 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-07T22:18:36.111Z,1551997116.111 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-07T22:18:36.111Z,1551997116.111 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-07T22:18:36.111Z,1551997116.111 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-07T22:18:36.112Z,1551997116.112 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-07T22:18:36.112Z,1551997116.112 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-07T22:18:36.496Z,1551997116.496 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-07T22:18:40.463Z,1551997120.463 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2019-03-07T22:18:58.324Z,1551997138.324 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-07T22:17:58.2Z
2019-03-07T22:18:58.324Z,1551997138.324 [Startup:StartupSatComms:A] Stopped
2019-03-07T22:18:58.329Z,1551997138.329 [Startup:StartupSatComms:B] Running Loop=1
2019-03-07T22:18:58.730Z,1551997138.730 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-07T22:19:01.591Z,1551997141.591 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004986
2019-03-07T22:19:07.784Z,1551997147.784 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190307T203608/Courier0031.lzma
2019-03-07T22:19:08.263Z,1551997148.263 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2019-03-07T22:19:08.418Z,1551997148.418 [Startup:StartupSatComms:B] Stopped
2019-03-07T22:19:08.418Z,1551997148.418 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-07T22:19:08.418Z,1551997148.418 [Startup:StartupSatComms] Stopped
2019-03-07T22:19:08.418Z,1551997148.418 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-07T22:19:08.419Z,1551997148.419 [Startup](INFO): Completed Startup
2019-03-07T22:19:08.419Z,1551997148.419 [MissionManager](INFO): Startup is completed.
2019-03-07T22:19:08.420Z,1551997148.420 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-07T22:19:08.420Z,1551997148.420 [Startup] Stopped
2019-03-07T22:19:08.420Z,1551997148.420 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-07T22:19:08.420Z,1551997148.420 [Startup:A.GoToSurface] Stopped
2019-03-07T22:19:08.420Z,1551997148.420 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-07T22:19:08.590Z,1551997148.590 [DataOverHttps](INFO): Moved sent file to Logs/20190307T203608/Courier0031.lzma.bak
2019-03-07T22:19:08.591Z,1551997148.591 [DataOverHttps](INFO): SBD MOMSN=10096028
2019-03-07T22:19:08.825Z,1551997148.825 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-07T22:19:08.825Z,1551997148.825 [DUSBL] Running Loop=1
2019-03-07T22:19:08.825Z,1551997148.825 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-07T22:19:08.826Z,1551997148.826 [DUSBL:A.Pitch] Running Loop=1
2019-03-07T22:19:08.826Z,1551997148.826 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-07T22:19:08.826Z,1551997148.826 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-07T22:19:08.826Z,1551997148.826 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-07T22:19:08.826Z,1551997148.826 [DUSBL:C] Running Loop=1
2019-03-07T22:19:08.826Z,1551997148.826 [DUSBL:RequestRepeater] Running Loop=1
2019-03-07T22:19:08.826Z,1551997148.826 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-07T22:19:08.826Z,1551997148.826 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-07T22:19:08.827Z,1551997148.827 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-07T22:19:08.827Z,1551997148.827 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-07T22:19:08.827Z,1551997148.827 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-07T22:19:08.827Z,1551997148.827 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-07T22:19:08.827Z,1551997148.827 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-07T22:19:08.828Z,1551997148.828 [DUSBL:C] Running Loop=1
2019-03-07T22:19:08.828Z,1551997148.828 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-07T22:19:08.829Z,1551997148.829 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-07T22:19:08.829Z,1551997148.829 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-07T22:19:08.830Z,1551997148.830 [DUSBL:A.Pitch] Running Loop=1
2019-03-07T22:19:09.195Z,1551997149.195 [Micromodem](FAULT): LCB 2 fault: 
2019-03-07T22:19:09.195Z,1551997149.195 [Micromodem] Hardware Fault, FailCount= 1
2019-03-07T22:19:09.195Z,1551997149.195 [Micromodem](ERROR): Hardware Fault
2019-03-07T22:19:09.195Z,1551997149.195 [Micromodem](INFO): Stop
2019-03-07T22:19:09.195Z,1551997149.195 [Micromodem](INFO): uninitialize
2019-03-07T22:19:09.195Z,1551997149.195 [Micromodem](INFO): Powering down
2019-03-07T22:19:09.330Z,1551997149.330 [CBIT](ERROR): Hardware Fault in component: Micromodem
2019-03-07T22:19:09.598Z,1551997149.598 [Micromodem](INFO): Stop
2019-03-07T22:19:09.598Z,1551997149.598 [Micromodem](INFO): uninitialize
2019-03-07T22:19:09.599Z,1551997149.599 [Micromodem](INFO): Powering down
2019-03-07T22:19:10.002Z,1551997150.002 [Micromodem](INFO): Stopping
2019-03-07T22:19:10.407Z,1551997150.407 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-03-07T22:19:10.407Z,1551997150.407 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-03-07T22:19:10.407Z,1551997150.407 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-03-07T22:19:10.407Z,1551997150.407 [DUSBL_Hydroid](DEBUG): Arming DUSBL
2019-03-07T22:19:10.408Z,1551997150.408 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-07T22:19:10.409Z,1551997150.409 [Micromodem](INFO): Stopped
2019-03-07T22:19:10.460Z,1551997150.460 [CBIT](INFO): Clearing failed state for component Micromodem
2019-03-07T22:19:10.460Z,1551997150.460 [Micromodem] No Fault, FailCount= 1
2019-03-07T22:19:10.810Z,1551997150.810 [Micromodem](INFO): Start
2019-03-07T22:19:11.224Z,1551997151.224 [Micromodem](INFO): Start
2019-03-07T22:19:11.618Z,1551997151.618 [Micromodem](INFO): Start
2019-03-07T22:19:12.022Z,1551997152.022 [Micromodem](INFO): Start
2019-03-07T22:19:12.436Z,1551997152.436 [Micromodem](INFO): Start
2019-03-07T22:19:12.830Z,1551997152.830 [Micromodem](INFO): Start
2019-03-07T22:19:13.234Z,1551997153.234 [Micromodem](INFO): Starting
2019-03-07T22:19:13.234Z,1551997153.234 [Micromodem](INFO): Powering up
2019-03-07T22:19:13.235Z,1551997153.235 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-07T22:19:13.639Z,1551997153.639 [Micromodem](INFO): Starting
2019-03-07T22:19:14.044Z,1551997154.044 [Micromodem](INFO): Starting
2019-03-07T22:19:14.446Z,1551997154.446 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-03-07T22:19:14.446Z,1551997154.446 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-03-07T22:19:14.447Z,1551997154.447 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-03-07T22:19:14.447Z,1551997154.447 [DUSBL_Hydroid](DEBUG): Arming DUSBL
2019-03-07T22:19:14.448Z,1551997154.448 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-07T22:19:14.450Z,1551997154.450 [Micromodem](INFO): Starting
2019-03-07T22:19:14.851Z,1551997154.851 [Micromodem](INFO): Starting
2019-03-07T22:19:15.258Z,1551997155.258 [Micromodem](INFO): Starting
2019-03-07T22:19:15.658Z,1551997155.658 [Micromodem](INFO): Starting
2019-03-07T22:19:16.063Z,1551997156.063 [Micromodem](INFO): Starting
2019-03-07T22:19:16.470Z,1551997156.470 [Micromodem](INFO): Starting
2019-03-07T22:19:16.871Z,1551997156.871 [Micromodem](INFO): Starting
2019-03-07T22:19:17.279Z,1551997157.279 [Micromodem](INFO): Starting
2019-03-07T22:19:17.678Z,1551997157.678 [Micromodem](INFO): Starting
2019-03-07T22:19:18.086Z,1551997158.086 [Micromodem](INFO): Starting
2019-03-07T22:19:18.087Z,1551997158.087 [Micromodem](INFO): Starting
2019-03-07T22:19:18.087Z,1551997158.087 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37

2019-03-07T22:19:18.087Z,1551997158.087 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,CST,1*37

2019-03-07T22:19:18.087Z,1551997158.087 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-03-07T22:19:18.089Z,1551997158.089 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-07T22:19:18.090Z,1551997158.090 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-07T22:19:18.091Z,1551997158.091 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32

2019-03-07T22:19:18.091Z,1551997158.091 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,REV,1*32

2019-03-07T22:19:18.092Z,1551997158.092 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29

2019-03-07T22:19:18.093Z,1551997158.093 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,RXP,1*29

2019-03-07T22:19:18.486Z,1551997158.486 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-03-07T22:19:18.486Z,1551997158.486 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-03-07T22:19:18.487Z,1551997158.487 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-03-07T22:19:18.487Z,1551997158.487 [DUSBL_Hydroid](DEBUG): Arming DUSBL
2019-03-07T22:19:18.487Z,1551997158.487 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-07T22:19:18.488Z,1551997158.488 [Micromodem](INFO): Starting
2019-03-07T22:19:18.806Z,1551997158.806 [CommandLine](IMPORTANT): got command stop
2019-03-07T22:19:18.806Z,1551997158.806 [CommandLine](IMPORTANT): Scheduling is paused
2019-03-07T22:19:18.806Z,1551997158.806 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-03-07T22:19:18.895Z,1551997158.895 [Micromodem](INFO): Starting
2019-03-07T22:19:18.958Z,1551997158.958 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-07T22:19:18.958Z,1551997158.958 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-07T22:19:18.958Z,1551997158.958 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-07T22:19:18.960Z,1551997158.960 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-07T22:19:18.960Z,1551997158.960 [DUSBL:RequestRepeater] Stopped
2019-03-07T22:19:18.960Z,1551997158.960 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-07T22:19:18.961Z,1551997158.961 [DUSBL:RequestRepeater:A] Stopped
2019-03-07T22:19:18.961Z,1551997158.961 [DUSBL:RequestRepeater:B] Stopped
2019-03-07T22:19:18.961Z,1551997158.961 [DUSBL:RequestRepeater](INFO): Running loop #2
2019-03-07T22:19:18.962Z,1551997158.962 [DUSBL:RequestRepeater] Running Loop=2
2019-03-07T22:19:18.962Z,1551997158.962 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-07T22:19:18.962Z,1551997158.962 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-07T22:19:18.962Z,1551997158.962 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-07T22:19:18.963Z,1551997158.963 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-07T22:19:18.963Z,1551997158.963 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-07T22:19:18.964Z,1551997158.964 [MissionManager](INFO): MissionManager is completed.
2019-03-07T22:19:18.965Z,1551997158.965 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-07T22:19:18.965Z,1551997158.965 [DUSBL] Stopped
2019-03-07T22:19:18.965Z,1551997158.965 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-07T22:19:18.966Z,1551997158.966 [DUSBL:A.Pitch] Stopped
2019-03-07T22:19:18.966Z,1551997158.966 [DUSBL:B.SetSpeed] Stopped
2019-03-07T22:19:18.966Z,1551997158.966 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-07T22:19:18.966Z,1551997158.966 [DUSBL:C] Stopped
2019-03-07T22:19:18.966Z,1551997158.966 [DUSBL:RequestRepeater] Stopped
2019-03-07T22:19:18.967Z,1551997158.967 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-07T22:19:18.967Z,1551997158.967 [DUSBL:RequestRepeater:A] Stopped
2019-03-07T22:19:18.967Z,1551997158.967 [DUSBL:RequestRepeater:B] Stopped
2019-03-07T22:19:18.967Z,1551997158.967 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-07T22:19:18.967Z,1551997158.967 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-07T22:19:19.294Z,1551997159.294 [Micromodem](INFO): Starting
2019-03-07T22:19:19.339Z,1551997159.339 [MissionManager](IMPORTANT): Started mission Default
2019-03-07T22:19:19.340Z,1551997159.340 [Default] Running Loop=1
2019-03-07T22:19:19.340Z,1551997159.340 [Default](DEBUG): Aggregate::initialize Default
2019-03-07T22:19:19.340Z,1551997159.340 [Default:B.GoToSurface] Running Loop=1
2019-03-07T22:19:19.340Z,1551997159.340 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-07T22:19:19.340Z,1551997159.340 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-07T22:19:19.340Z,1551997159.340 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-07T22:19:19.341Z,1551997159.341 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-07T22:19:19.341Z,1551997159.341 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-07T22:19:19.342Z,1551997159.342 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-07T22:19:19.342Z,1551997159.342 [Default:A.Wait] Running Loop=1
2019-03-07T22:19:19.342Z,1551997159.342 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-07T22:19:19.698Z,1551997159.698 [Micromodem](INFO): Starting
2019-03-07T22:19:20.102Z,1551997160.102 [Micromodem](INFO): Starting
2019-03-07T22:19:20.506Z,1551997160.506 [Micromodem](INFO): Starting
2019-03-07T22:19:20.912Z,1551997160.912 [Micromodem](INFO): Starting
2019-03-07T22:19:21.311Z,1551997161.311 [Micromodem](INFO): Starting
2019-03-07T22:19:21.718Z,1551997161.718 [Micromodem](INFO): Starting
2019-03-07T22:19:22.126Z,1551997162.126 [Micromodem](INFO): Starting
2019-03-07T22:19:22.530Z,1551997162.530 [Micromodem](INFO): Starting
2019-03-07T22:19:22.931Z,1551997162.931 [Micromodem](INFO): Starting
2019-03-07T22:19:23.334Z,1551997163.334 [Micromodem](INFO): Starting
2019-03-07T22:19:23.738Z,1551997163.738 [Micromodem](INFO): Starting
2019-03-07T22:19:24.144Z,1551997164.144 [Micromodem](INFO): Starting
2019-03-07T22:19:24.546Z,1551997164.546 [Micromodem](INFO): Starting
2019-03-07T22:19:24.947Z,1551997164.947 [Micromodem](INFO): Starting
2019-03-07T22:19:25.354Z,1551997165.354 [Micromodem](INFO): Starting
2019-03-07T22:19:25.758Z,1551997165.758 [Micromodem](INFO): Starting
2019-03-07T22:19:26.166Z,1551997166.166 [Micromodem](INFO): Starting
2019-03-07T22:19:26.570Z,1551997166.570 [Micromodem](INFO): Starting
2019-03-07T22:19:26.977Z,1551997166.977 [Micromodem](INFO): Starting
2019-03-07T22:19:27.378Z,1551997167.378 [Micromodem](INFO): Starting
2019-03-07T22:19:27.778Z,1551997167.778 [Micromodem](INFO): Starting
2019-03-07T22:19:28.183Z,1551997168.183 [Micromodem](INFO): Starting
2019-03-07T22:19:28.586Z,1551997168.586 [Micromodem](INFO): Starting
2019-03-07T22:19:28.992Z,1551997168.992 [Micromodem](INFO): Starting
2019-03-07T22:19:29.399Z,1551997169.399 [Micromodem](INFO): Starting
2019-03-07T22:19:29.399Z,1551997169.399 [Micromodem](ERROR): Nmea resend: $CCCFG,CST,1*37

2019-03-07T22:19:29.802Z,1551997169.802 [Micromodem](INFO): Starting
2019-03-07T22:19:30.202Z,1551997170.202 [Micromodem](INFO): Starting
2019-03-07T22:19:30.610Z,1551997170.610 [Micromodem](INFO): Starting
2019-03-07T22:19:31.012Z,1551997171.012 [Micromodem](INFO): Starting
2019-03-07T22:19:31.418Z,1551997171.418 [Micromodem](INFO): Starting
2019-03-07T22:19:31.822Z,1551997171.822 [Micromodem](INFO): Starting
2019-03-07T22:19:32.226Z,1551997172.226 [Micromodem](INFO): Starting
2019-03-07T22:19:32.630Z,1551997172.630 [Micromodem](INFO): Starting
2019-03-07T22:19:32.667Z,1551997172.667 [Default:A.Wait](INFO): Done Waiting.
2019-03-07T22:19:32.667Z,1551997172.667 [Default:A.Wait] Stopped
2019-03-07T22:19:32.667Z,1551997172.667 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-07T22:19:33.030Z,1551997173.030 [Micromodem](INFO): Starting
2019-03-07T22:19:33.067Z,1551997173.067 [Default:CheckIn] Running Loop=1
2019-03-07T22:19:33.067Z,1551997173.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-07T22:19:33.067Z,1551997173.067 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-07T22:19:33.434Z,1551997173.434 [Micromodem](INFO): Starting
2019-03-07T22:19:33.463Z,1551997173.463 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-07T22:19:33.838Z,1551997173.838 [Micromodem](INFO): Starting
2019-03-07T22:19:34.244Z,1551997174.244 [Micromodem](INFO): Starting
2019-03-07T22:19:34.646Z,1551997174.646 [Micromodem](INFO): Starting
2019-03-07T22:19:35.050Z,1551997175.050 [Micromodem](INFO): Starting
2019-03-07T22:19:35.454Z,1551997175.454 [Micromodem](INFO): Starting
2019-03-07T22:19:35.858Z,1551997175.858 [Micromodem](INFO): Starting
2019-03-07T22:19:36.262Z,1551997176.262 [Micromodem](INFO): Starting
2019-03-07T22:19:36.666Z,1551997176.666 [Micromodem](INFO): Starting
2019-03-07T22:19:37.070Z,1551997177.070 [Micromodem](INFO): Starting
2019-03-07T22:19:37.474Z,1551997177.474 [Micromodem](INFO): Starting
2019-03-07T22:19:37.878Z,1551997177.878 [Micromodem](INFO): Starting
2019-03-07T22:19:38.282Z,1551997178.282 [Micromodem](INFO): Starting
2019-03-07T22:19:38.686Z,1551997178.686 [Micromodem](INFO): Starting
2019-03-07T22:19:39.090Z,1551997179.090 [Micromodem](INFO): Starting
2019-03-07T22:19:39.494Z,1551997179.494 [Micromodem](INFO): Starting
2019-03-07T22:19:39.898Z,1551997179.898 [Micromodem](INFO): Starting
2019-03-07T22:19:40.302Z,1551997180.302 [Micromodem](INFO): Starting
2019-03-07T22:19:40.708Z,1551997180.708 [Micromodem](INFO): Starting
2019-03-07T22:19:40.708Z,1551997180.708 [Micromodem](ERROR): Nmea resend: $CCCFG,CST,1*37

2019-03-07T22:19:41.114Z,1551997181.114 [Micromodem](INFO): Starting
2019-03-07T22:19:41.514Z,1551997181.514 [Micromodem](INFO): Starting
2019-03-07T22:19:41.922Z,1551997181.922 [Micromodem](INFO): Starting
2019-03-07T22:19:42.326Z,1551997182.326 [Micromodem](INFO): Starting
2019-03-07T22:19:42.726Z,1551997182.726 [Micromodem](INFO): Starting
2019-03-07T22:19:43.130Z,1551997183.130 [Micromodem](INFO): Starting
2019-03-07T22:19:43.542Z,1551997183.542 [Micromodem](INFO): Starting
2019-03-07T22:19:43.946Z,1551997183.946 [Micromodem](INFO): Starting
2019-03-07T22:19:44.354Z,1551997184.354 [Micromodem](INFO): Starting
2019-03-07T22:19:44.764Z,1551997184.764 [Micromodem](INFO): Starting
2019-03-07T22:19:45.174Z,1551997185.174 [Micromodem](INFO): Starting
2019-03-07T22:19:45.562Z,1551997185.562 [Micromodem](INFO): Starting
2019-03-07T22:19:45.972Z,1551997185.972 [Micromodem](INFO): Starting
2019-03-07T22:19:46.370Z,1551997186.370 [Micromodem](INFO): Starting
2019-03-07T22:19:46.774Z,1551997186.774 [Micromodem](INFO): Starting
2019-03-07T22:19:47.178Z,1551997187.178 [Micromodem](INFO): Starting
2019-03-07T22:19:47.582Z,1551997187.582 [Micromodem](INFO): Starting
2019-03-07T22:19:47.994Z,1551997187.994 [Micromodem](INFO): Starting
2019-03-07T22:19:48.390Z,1551997188.390 [Micromodem](INFO): Starting
2019-03-07T22:19:48.391Z,1551997188.391 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-07T22:19:48.794Z,1551997188.794 [Micromodem](INFO): Starting
2019-03-07T22:19:49.195Z,1551997189.195 [Micromodem](INFO): Starting
2019-03-07T22:19:49.606Z,1551997189.606 [Micromodem](INFO): Starting
2019-03-07T22:19:50.007Z,1551997190.007 [Micromodem](INFO): Starting
2019-03-07T22:19:50.410Z,1551997190.410 [Micromodem](INFO): Starting
2019-03-07T22:19:50.814Z,1551997190.814 [Micromodem](INFO): Starting
2019-03-07T22:19:51.218Z,1551997191.218 [Micromodem](INFO): Starting
2019-03-07T22:19:51.622Z,1551997191.622 [Micromodem](INFO): Starting
2019-03-07T22:19:52.030Z,1551997192.030 [Micromodem](INFO): Starting
2019-03-07T22:19:52.430Z,1551997192.430 [Micromodem](INFO): Starting
2019-03-07T22:19:52.838Z,1551997192.838 [Micromodem](INFO): Starting
2019-03-07T22:19:53.238Z,1551997193.238 [Micromodem](INFO): Starting
2019-03-07T22:19:53.642Z,1551997193.642 [Micromodem](INFO): Starting
2019-03-07T22:19:54.050Z,1551997194.050 [Micromodem](INFO): Starting
2019-03-07T22:19:54.454Z,1551997194.454 [Micromodem](INFO): Starting
2019-03-07T22:19:54.856Z,1551997194.856 [Micromodem](INFO): Starting
2019-03-07T22:19:55.258Z,1551997195.258 [Micromodem](INFO): Starting
2019-03-07T22:19:55.666Z,1551997195.666 [Micromodem](INFO): Starting
2019-03-07T22:19:56.070Z,1551997196.070 [Micromodem](INFO): Starting
2019-03-07T22:19:56.474Z,1551997196.474 [Micromodem](INFO): Starting
2019-03-07T22:19:56.874Z,1551997196.874 [Micromodem](INFO): Starting
2019-03-07T22:19:57.282Z,1551997197.282 [Micromodem](INFO): Starting
2019-03-07T22:19:57.682Z,1551997197.682 [Micromodem](INFO): Starting
2019-03-07T22:19:58.090Z,1551997198.090 [Micromodem](INFO): Starting
2019-03-07T22:19:58.490Z,1551997198.490 [Micromodem](INFO): Starting
2019-03-07T22:19:58.491Z,1551997198.491 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32

2019-03-07T22:19:58.898Z,1551997198.898 [Micromodem](INFO): Starting
2019-03-07T22:19:59.302Z,1551997199.302 [Micromodem](INFO): Starting
2019-03-07T22:19:59.702Z,1551997199.702 [Micromodem](INFO): Starting
2019-03-07T22:20:00.110Z,1551997200.110 [Micromodem](INFO): Starting
2019-03-07T22:20:00.510Z,1551997200.510 [Micromodem](INFO): Starting
2019-03-07T22:20:00.914Z,1551997200.914 [Micromodem](INFO): Starting
2019-03-07T22:20:01.322Z,1551997201.322 [Micromodem](INFO): Starting
2019-03-07T22:20:01.728Z,1551997201.728 [Micromodem](INFO): Starting
2019-03-07T22:20:02.126Z,1551997202.126 [Micromodem](INFO): Starting
2019-03-07T22:20:02.527Z,1551997202.527 [Micromodem](INFO): Starting
2019-03-07T22:20:02.936Z,1551997202.936 [Micromodem](INFO): Starting
2019-03-07T22:20:03.342Z,1551997203.342 [Micromodem](INFO): Starting
2019-03-07T22:20:03.742Z,1551997203.742 [Micromodem](INFO): Starting
2019-03-07T22:20:04.146Z,1551997204.146 [Micromodem](INFO): Starting
2019-03-07T22:20:04.550Z,1551997204.550 [Micromodem](INFO): Starting
2019-03-07T22:20:04.962Z,1551997204.962 [Micromodem](INFO): Starting
2019-03-07T22:20:05.362Z,1551997205.362 [Micromodem](INFO): Starting
2019-03-07T22:20:05.766Z,1551997205.766 [Micromodem](INFO): Starting
2019-03-07T22:20:06.163Z,1551997206.163 [Micromodem](INFO): Starting
2019-03-07T22:20:06.574Z,1551997206.574 [Micromodem](INFO): Starting
2019-03-07T22:20:06.985Z,1551997206.985 [Micromodem](INFO): Starting
2019-03-07T22:20:07.378Z,1551997207.378 [Micromodem](INFO): Starting
2019-03-07T22:20:07.783Z,1551997207.783 [Micromodem](INFO): Starting
2019-03-07T22:20:08.187Z,1551997208.187 [Micromodem](INFO): Starting
2019-03-07T22:20:08.188Z,1551997208.188 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29

2019-03-07T22:20:08.590Z,1551997208.590 [Micromodem](INFO): Starting
2019-03-07T22:20:08.998Z,1551997208.998 [Micromodem](INFO): Starting
2019-03-07T22:20:09.398Z,1551997209.398 [Micromodem](INFO): Starting
2019-03-07T22:20:09.806Z,1551997209.806 [Micromodem](INFO): Starting
2019-03-07T22:20:10.210Z,1551997210.210 [Micromodem](INFO): Starting
2019-03-07T22:20:10.610Z,1551997210.610 [Micromodem](INFO): Starting
2019-03-07T22:20:11.018Z,1551997211.018 [Micromodem](INFO): Starting
2019-03-07T22:20:11.422Z,1551997211.422 [Micromodem](INFO): Starting
2019-03-07T22:20:11.826Z,1551997211.826 [Micromodem](INFO): Starting
2019-03-07T22:20:12.226Z,1551997212.226 [Micromodem](INFO): Starting
2019-03-07T22:20:12.634Z,1551997212.634 [Micromodem](INFO): Starting
2019-03-07T22:20:13.034Z,1551997213.034 [Micromodem](INFO): Starting
2019-03-07T22:20:13.442Z,1551997213.442 [Micromodem](INFO): Starting
2019-03-07T22:20:13.846Z,1551997213.846 [Micromodem](INFO): Starting
2019-03-07T22:20:14.246Z,1551997214.246 [Micromodem](INFO): Starting
2019-03-07T22:20:14.650Z,1551997214.650 [Micromodem](INFO): Starting
2019-03-07T22:20:15.054Z,1551997215.054 [Micromodem](INFO): Starting
2019-03-07T22:20:15.464Z,1551997215.464 [Micromodem](INFO): Starting
2019-03-07T22:20:15.862Z,1551997215.862 [Micromodem](INFO): Starting
2019-03-07T22:20:16.266Z,1551997216.266 [Micromodem](INFO): Starting
2019-03-07T22:20:16.670Z,1551997216.670 [Micromodem](INFO): Starting
2019-03-07T22:20:17.071Z,1551997217.071 [Micromodem](INFO): Starting
2019-03-07T22:20:17.478Z,1551997217.478 [Micromodem](INFO): Starting
2019-03-07T22:20:17.882Z,1551997217.882 [Micromodem](INFO): Starting
2019-03-07T22:20:18.286Z,1551997218.286 [Micromodem](INFO): Starting
2019-03-07T22:20:18.287Z,1551997218.287 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-07T22:20:19Z,0*70

2019-03-07T22:20:18.287Z,1551997218.287 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-07T22:20:19Z,0*70

2019-03-07T22:20:18.288Z,1551997218.288 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-07T22:20:19Z,0*70

2019-03-07T22:20:18.692Z,1551997218.692 [Micromodem](INFO): Starting
2019-03-07T22:20:19.094Z,1551997219.094 [Micromodem](INFO): Starting
2019-03-07T22:20:19.502Z,1551997219.502 [Micromodem](INFO): Starting
2019-03-07T22:20:19.503Z,1551997219.503 [Micromodem](DEBUG): Nmea buf: $CCCFQ,TOP*33

2019-03-07T22:20:19.503Z,1551997219.503 [Micromodem](IMPORTANT): Nmea buf: $CCCFQ,TOP*33

2019-03-07T22:20:19.503Z,1551997219.503 [Micromodem](INFO): Nmea out: $CCCFQ,TOP*33

2019-03-07T22:20:19.907Z,1551997219.907 [Micromodem](INFO): Starting
2019-03-07T22:20:20.307Z,1551997220.307 [Micromodem](INFO): Starting
2019-03-07T22:20:22.390Z,1551997222.390 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2019-03-07T22:20:22.390Z,1551997222.390 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   +2,    +5,-32768,A
2019-03-07T22:20:28.714Z,1551997228.714 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-03-07T22:20:28.715Z,1551997228.715 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-07T22:20:28.738Z,1551997228.738 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-07T22:20:28.740Z,1551997228.740 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-07T22:20:28.742Z,1551997228.742 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-07T22:20:28.744Z,1551997228.744 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-07T22:20:28.747Z,1551997228.747 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-07T22:20:28.748Z,1551997228.748 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-07T22:20:28.752Z,1551997228.752 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-07T22:20:28.759Z,1551997228.759 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-07T22:20:28.763Z,1551997228.763 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="NumberOfRequests"><Description>
        Number of pings to requst.
    </Description><Units:count/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>   

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>      
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Science:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <ReadData>
        <While>
            <Arg Name="EnabledDUSBL"/>
        </While>
        <Sensor:DUSBL_Hydroid.range/>
        <Sensor:DUSBL_Hydroid.direction/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

        <Sequence Repeat="NumberOfRequests"/>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.transponderCode/><Arg Name="TransponderCode"/></Assign>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.numberOfPingsRequested/><Arg Name="NumberOfPings"/></Assign>
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>10</Value></Setting>
          </Guidance:Wait>
      </Aggregate>    
</Mission>


2019-03-07T22:20:28.764Z,1551997228.764 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-03-07T22:20:28.888Z,1551997228.888 [Default] Stopped
2019-03-07T22:20:28.888Z,1551997228.888 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-07T22:20:28.888Z,1551997228.888 [Default:B.GoToSurface] Stopped
2019-03-07T22:20:28.888Z,1551997228.888 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-07T22:20:28.888Z,1551997228.888 [Default:CheckIn] Stopped
2019-03-07T22:20:28.888Z,1551997228.888 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-07T22:20:28.888Z,1551997228.888 [Default:CheckIn:Read_GPS] Stopped
2019-03-07T22:20:28.888Z,1551997228.888 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-07T22:20:28.889Z,1551997228.889 [DUSBL] Running Loop=1
2019-03-07T22:20:28.889Z,1551997228.889 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-07T22:20:28.889Z,1551997228.889 [DUSBL:A.Pitch] Running Loop=1
2019-03-07T22:20:28.889Z,1551997228.889 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-07T22:20:28.890Z,1551997228.890 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-07T22:20:28.890Z,1551997228.890 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-07T22:20:28.890Z,1551997228.890 [DUSBL:C] Running Loop=1
2019-03-07T22:20:28.890Z,1551997228.890 [DUSBL:RequestRepeater] Running Loop=1
2019-03-07T22:20:28.890Z,1551997228.890 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-07T22:20:28.890Z,1551997228.890 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-07T22:20:28.890Z,1551997228.890 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-07T22:20:28.890Z,1551997228.890 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-07T22:20:28.891Z,1551997228.891 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-07T22:20:28.891Z,1551997228.891 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-07T22:20:28.891Z,1551997228.891 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-07T22:20:28.891Z,1551997228.891 [DUSBL:C] Running Loop=1
2019-03-07T22:20:28.892Z,1551997228.892 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-07T22:20:28.892Z,1551997228.892 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-07T22:20:28.897Z,1551997228.897 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-07T22:20:28.897Z,1551997228.897 [DUSBL:A.Pitch] Running Loop=1
2019-03-07T22:20:30.406Z,1551997230.406 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-03-07T22:20:30.407Z,1551997230.407 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-03-07T22:20:30.407Z,1551997230.407 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-03-07T22:20:30.407Z,1551997230.407 [DUSBL_Hydroid](DEBUG): Arming DUSBL
2019-03-07T22:20:30.408Z,1551997230.408 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-07T22:20:30.418Z,1551997230.418 [Micromodem](INFO): IS COMMANDED  IN Runnable
2019-03-07T22:20:30.418Z,1551997230.418 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-07T22:20:30.418Z,1551997230.418 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-07T22:20:30.419Z,1551997230.419 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-07T22:20:30.817Z,1551997230.817 [Micromodem](ERROR): Response from modem unexpected: $CACFG,SCG,0*27

2019-03-07T22:20:30.823Z,1551997230.823 [Micromodem](ERROR): Response from modem unexpected: $CACFG,SGP,0*34

2019-03-07T22:20:31.224Z,1551997231.224 [Micromodem](ERROR): Response from modem unexpected: $CACFG,SHF,0*2D

2019-03-07T22:20:31.231Z,1551997231.231 [Micromodem](ERROR): Response from modem unexpected: $CACFG,SNR,0*3F

2019-03-07T22:20:31.625Z,1551997231.625 [Micromodem](ERROR): Response from modem unexpected: $CACFG,SNV,0*3B

2019-03-07T22:20:31.631Z,1551997231.631 [Micromodem](ERROR): Response from modem unexpected: $CACFG,SRC,1*33

2019-03-07T22:20:32.035Z,1551997232.035 [Micromodem](ERROR): Response from modem unexpected: $CACFG,TAT,50*04

2019-03-07T22:20:32.042Z,1551997232.042 [Micromodem](ERROR): Response from modem unexpected: $CACFG,TOA,0*2A

2019-03-07T22:20:32.433Z,1551997232.433 [Micromodem](ERROR): Response from modem unexpected: $CACFG,TXD,600*3E

2019-03-07T22:20:32.440Z,1551997232.440 [Micromodem](ERROR): Response from modem unexpected: $CACFG,TXP,1*2D

2019-03-07T22:20:32.841Z,1551997232.841 [Micromodem](ERROR): Response from modem unexpected: $CACFG,TXF,1*3B

2019-03-07T22:20:32.847Z,1551997232.847 [Micromodem](ERROR): Response from modem unexpected: $CACFG,XST,1*2E

2019-03-07T22:20:33.250Z,1551997233.250 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart1.bitrate,19200*0A

2019-03-07T22:20:33.261Z,1551997233.261 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart2.bitrate,19200*09

2019-03-07T22:20:33.425Z,1551997233.425 [CommandLine](IMPORTANT): got command stop
2019-03-07T22:20:33.426Z,1551997233.426 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-03-07T22:20:33.655Z,1551997233.655 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart3.bitrate,19200*08

2019-03-07T22:20:33.666Z,1551997233.666 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart4.bitrate,19200*0F

2019-03-07T22:20:33.715Z,1551997233.715 [MissionManager](INFO): MissionManager is completed.
2019-03-07T22:20:33.715Z,1551997233.715 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-07T22:20:33.715Z,1551997233.715 [DUSBL] Stopped
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:A.Pitch] Stopped
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:B.SetSpeed] Stopped
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:C] Stopped
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:RequestRepeater] Stopped
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:RequestRepeater:A] Stopped
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:RequestRepeater:B] Stopped
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-07T22:20:33.716Z,1551997233.716 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-07T22:20:34.055Z,1551997234.055 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart1.task,1*71

2019-03-07T22:20:34.064Z,1551997234.064 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart2.task,1*72

2019-03-07T22:20:34.095Z,1551997234.095 [MissionManager](IMPORTANT): Started mission Default
2019-03-07T22:20:34.095Z,1551997234.095 [Default] Running Loop=1
2019-03-07T22:20:34.096Z,1551997234.096 [Default](DEBUG): Aggregate::initialize Default
2019-03-07T22:20:34.096Z,1551997234.096 [Default:B.GoToSurface] Running Loop=1
2019-03-07T22:20:34.096Z,1551997234.096 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-07T22:20:34.096Z,1551997234.096 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-07T22:20:34.096Z,1551997234.096 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-07T22:20:34.097Z,1551997234.097 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-07T22:20:34.097Z,1551997234.097 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-07T22:20:34.097Z,1551997234.097 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-07T22:20:34.098Z,1551997234.098 [Default:A.Wait] Running Loop=1
2019-03-07T22:20:34.098Z,1551997234.098 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-07T22:20:34.459Z,1551997234.459 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart3.task,1*73

2019-03-07T22:20:34.468Z,1551997234.468 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart4.task,1*74

2019-03-07T22:20:34.860Z,1551997234.860 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart1.parse_gps,0*33

2019-03-07T22:20:34.871Z,1551997234.871 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart2.parse_gps,0*30

2019-03-07T22:20:35.267Z,1551997235.267 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart3.parse_gps,0*31

2019-03-07T22:20:35.278Z,1551997235.278 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart4.parse_gps,0*36

2019-03-07T22:20:35.672Z,1551997235.672 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart1.show_gps,0*45

2019-03-07T22:20:35.700Z,1551997235.700 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart2.show_gps,0*46

2019-03-07T22:20:36.072Z,1551997236.072 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart3.show_gps,0*47

2019-03-07T22:20:36.082Z,1551997236.082 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart4.show_gps,0*40

2019-03-07T22:20:36.482Z,1551997236.482 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart1.set_clk_GPS,0*3F

2019-03-07T22:20:36.493Z,1551997236.493 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart2.set_clk_GPS,0*3C

2019-03-07T22:20:36.540Z,1551997236.540 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-07T22:20:36.540Z,1551997236.540 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-07T22:20:36.541Z,1551997236.541 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-07T22:20:36.542Z,1551997236.542 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-07T22:20:36.542Z,1551997236.542 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-07T22:20:36.542Z,1551997236.542 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-07T22:20:36.544Z,1551997236.544 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-07T22:20:36.544Z,1551997236.544 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-07T22:20:36.544Z,1551997236.544 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-07T22:20:36.555Z,1551997236.555 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-07T22:20:36.556Z,1551997236.556 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-07T22:20:36.556Z,1551997236.556 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-07T22:20:36.886Z,1551997236.886 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart3.set_clk_GPS,0*3D

2019-03-07T22:20:36.898Z,1551997236.898 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart4.set_clk_GPS,0*3A

2019-03-07T22:20:36.994Z,1551997236.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-07T22:20:36.994Z,1551997236.994 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-07T22:20:36.994Z,1551997236.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-07T22:20:36.994Z,1551997236.994 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-07T22:20:36.995Z,1551997236.995 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-07T22:20:36.995Z,1551997236.995 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-07T22:20:37.285Z,1551997237.285 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart1.flowcontrol,0*08

2019-03-07T22:20:37.296Z,1551997237.296 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart2.flowcontrol,0*0B

2019-03-07T22:20:37.327Z,1551997237.327 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-07T22:20:37.693Z,1551997237.693 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart3.flowcontrol,0*0A

2019-03-07T22:20:37.704Z,1551997237.704 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart4.flowcontrol,0*0D

2019-03-07T22:20:38.095Z,1551997238.095 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart4.rs485,0*40

2019-03-07T22:20:38.105Z,1551997238.105 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart1.crc32,0*0E

2019-03-07T22:20:38.498Z,1551997238.498 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart2.crc32,0*0D

2019-03-07T22:20:38.507Z,1551997238.507 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart3.crc32,0*0C

2019-03-07T22:20:38.899Z,1551997238.899 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart4.crc32,0*0B

2019-03-07T22:20:38.932Z,1551997238.932 [Micromodem](ERROR): Response from modem unexpected: $CACFG,uart1.iridium_hangup_minutes,0*62

2019-03-07T22:20:39.306Z,1551997239.306 [Micromodem](ERROR): Response from modem unexpected: $CACFG,sport3.bitrate,19200*70

2019-03-07T22:20:39.316Z,1551997239.316 [Micromodem](ERROR): Response from modem unexpected: $CACFG,log.nmea.level,0*65

2019-03-07T22:20:39.709Z,1551997239.709 [Micromodem](ERROR): Response from modem unexpected: $CACFG,log.nmea.location,1*0F

2019-03-07T22:20:39.722Z,1551997239.722 [Micromodem](ERROR): Response from modem unexpected: $CACFG,log.nmea.lastretrieval,0*7F

2019-03-07T22:20:40.119Z,1551997240.119 [Micromodem](ERROR): Response from modem unexpected: $CACFG,log.nmea.currentdbSize,0*59

2019-03-07T22:20:40.131Z,1551997240.131 [Micromodem](ERROR): Response from modem unexpected: $CACFG,log.nmea.output_rate,0*51

2019-03-07T22:20:40.515Z,1551997240.515 [Micromodem](ERROR): Response from modem unexpected: $CACFG,rec.state,0*5D

2019-03-07T22:20:40.526Z,1551997240.526 [Micromodem](ERROR): Response from modem unexpected: $CACFG,rec.start_time,-1*2C

2019-03-07T22:20:40.920Z,1551997240.920 [Micromodem](ERROR): Response from modem unexpected: $CACFG,rec.end_time,-1*23

2019-03-07T22:20:40.933Z,1551997240.933 [Micromodem](ERROR): Response from modem unexpected: $CACFG,fsk.packet.modulation,0*04

2019-03-07T22:20:41.329Z,1551997241.329 [Micromodem](ERROR): Response from modem unexpected: $CACFG,fsk.packet.bandwidth_Hz,4000*30

2019-03-07T22:20:41.342Z,1551997241.342 [Micromodem](ERROR): Response from modem unexpected: $CACFG,fsk.packet.carrier_Hz,25120*13

2019-03-07T22:20:41.738Z,1551997241.738 [Micromodem](ERROR): Response from modem unexpected: $CACFG,fsk.packet.nulltime_ms,200*4B

2019-03-07T22:20:41.738Z,1551997241.738 [Micromodem](ERROR): Nmea resend: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-07T22:20:41.774Z,1551997241.774 [Micromodem](ERROR): Response from modem unexpected: $CACFG,fsk.packet.legacy_addressing,0*5A

2019-03-07T22:20:42.136Z,1551997242.136 [Micromodem](ERROR): Response from modem unexpected: $CACFG,psk.packet.mod_hdr_version,0*72

2019-03-07T22:20:42.164Z,1551997242.164 [Micromodem](ERROR): Response from modem unexpected: $CACFG,psk.packet.modulation,0*12

2019-03-07T22:20:42.544Z,1551997242.544 [Micromodem](ERROR): Response from modem unexpected: $CACFG,psk.packet.bandwidth_Hz,5000*27

2019-03-07T22:20:42.572Z,1551997242.572 [Micromodem](ERROR): Response from modem unexpected: $CACFG,psk.packet.carrier_Hz,25000*06

2019-03-07T22:20:42.950Z,1551997242.950 [Micromodem](ERROR): Response from modem unexpected: $CACFG,psk.packet.bandwidth0_Hz,5000*17

2019-03-07T22:20:43.005Z,1551997243.005 [Micromodem](ERROR): Response from modem unexpected: $CACFG,psk.packet.carrier0_Hz,25000*36

2019-03-07T22:20:43.348Z,1551997243.348 [Micromodem](ERROR): Response from modem unexpected: $CACFG,psk.packet.nulltime_ms,250*58

2019-03-07T22:20:43.361Z,1551997243.361 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector.power_thresh,-20*6B

2019-03-07T22:20:43.755Z,1551997243.755 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector.noise_thresh,50*40

2019-03-07T22:20:43.766Z,1551997243.766 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector1.recv,0*77

2019-03-07T22:20:44.155Z,1551997244.155 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector1.channel_mask,1*5C

2019-03-07T22:20:44.166Z,1551997244.166 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector1.type,0*6D

2019-03-07T22:20:44.557Z,1551997244.557 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector1.fm_dir,0*5E

2019-03-07T22:20:44.570Z,1551997244.570 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector1.length_sym,40*65

2019-03-07T22:20:44.970Z,1551997244.970 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector1.fm_bw_Hz,4000*6D

2019-03-07T22:20:44.000Z,1551997245.000 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector1.carrier_Hz,25120*60

2019-03-07T22:20:45.368Z,1551997245.368 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector1.thresh,90*5C

2019-03-07T22:20:45.379Z,1551997245.379 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector2.recv,1*75

2019-03-07T22:20:45.771Z,1551997245.771 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector2.channel_mask,1*5F

2019-03-07T22:20:45.794Z,1551997245.794 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector2.type,0*6E

2019-03-07T22:20:46.177Z,1551997246.177 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector2.fm_dir,1*5C

2019-03-07T22:20:46.190Z,1551997246.190 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector2.length_sym,200*50

2019-03-07T22:20:46.579Z,1551997246.579 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector2.fm_bw_Hz,4000*6E

2019-03-07T22:20:46.593Z,1551997246.593 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector2.carrier_Hz,25120*63

2019-03-07T22:20:47.003Z,1551997247.003 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector2.thresh,90*5F

2019-03-07T22:20:47.013Z,1551997247.013 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector3.recv,2*77

2019-03-07T22:20:47.387Z,1551997247.387 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector3.channel_mask,1*5E

2019-03-07T22:20:47.397Z,1551997247.397 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector3.type,1*6E

2019-03-07T22:20:47.427Z,1551997247.427 [Default:A.Wait](INFO): Done Waiting.
2019-03-07T22:20:47.427Z,1551997247.427 [Default:A.Wait] Stopped
2019-03-07T22:20:47.427Z,1551997247.427 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-07T22:20:47.790Z,1551997247.790 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector3.fm_dir,0*5C

2019-03-07T22:20:47.803Z,1551997247.803 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector3.length_sym,200*51

2019-03-07T22:20:47.826Z,1551997247.826 [Default:CheckIn] Running Loop=1
2019-03-07T22:20:47.826Z,1551997247.826 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-07T22:20:47.826Z,1551997247.826 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-07T22:20:48.198Z,1551997248.198 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector3.fm_bw_Hz,4000*6F

2019-03-07T22:20:48.212Z,1551997248.212 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector3.carrier_Hz,25120*62

2019-03-07T22:20:48.603Z,1551997248.603 [Micromodem](ERROR): Response from modem unexpected: $CACFG,detector3.thresh,120*64

2019-03-07T22:20:48.628Z,1551997248.628 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.mca.gain,250*1B

2019-03-07T22:20:49.011Z,1551997249.011 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.onboard_gain,250*7E

2019-03-07T22:20:49.041Z,1551997249.041 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.onboard_gain_dB,32*31

2019-03-07T22:20:49.407Z,1551997249.407 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.onboard_equalizer,0*10

2019-03-07T22:20:49.418Z,1551997249.418 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.agn_legacy,1*7F

2019-03-07T22:20:49.812Z,1551997249.812 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.p2b_chmask,1*3D

2019-03-07T22:20:49.825Z,1551997249.825 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.agc_length_ms,100*3A

2019-03-07T22:20:50.213Z,1551997250.213 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.agc_legacy,1*72

2019-03-07T22:20:50.225Z,1551997250.225 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.digital_rcvr,0*6C

2019-03-07T22:20:50.616Z,1551997250.616 [Micromodem](ERROR): Response from modem unexpected: $CACFG,recv.base64data,0*5B

2019-03-07T22:20:50.629Z,1551997250.629 [Micromodem](ERROR): Response from modem unexpected: $CACFG,xmit.poweramp_gating,0*18

2019-03-07T22:20:51.024Z,1551997251.024 [Micromodem](ERROR): Response from modem unexpected: $CACFG,xmit.txinhibit,0*23

2019-03-07T22:20:51.038Z,1551997251.038 [Micromodem](ERROR): Response from modem unexpected: $CACFG,xmit.uart_disable_mask,15*10

2019-03-07T22:20:51.432Z,1551997251.432 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.clock_source,2*66

2019-03-07T22:20:51.444Z,1551997251.444 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.PPS_source,1*5E

2019-03-07T22:20:51.828Z,1551997251.828 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.syncnav,0*30

2019-03-07T22:20:51.852Z,1551997251.852 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.scg_timeout,0*1D

2019-03-07T22:20:52.237Z,1551997252.237 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.pps_timeout,3*1A

2019-03-07T22:20:52.264Z,1551997252.264 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.extpps_enable,1*0B

2019-03-07T22:20:52.640Z,1551997252.640 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.extpps_en_after_n,0*0A

2019-03-07T22:20:52.653Z,1551997252.653 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.setclk_after_n,0*42

2019-03-07T22:20:53.045Z,1551997253.045 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.extpps_disable_after_n,0*75

2019-03-07T22:20:53.045Z,1551997253.045 [Micromodem](ERROR): Nmea resend: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-07T22:20:53.075Z,1551997253.075 [Micromodem](ERROR): Response from modem unexpected: $CACFG,timing.debug,0*3F

2019-03-07T22:20:53.447Z,1551997253.447 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hibernate.wake_interval,0*6C

2019-03-07T22:20:53.461Z,1551997253.461 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hibernate.wake_reference,0*18

2019-03-07T22:20:53.856Z,1551997253.856 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hibernate.hibernate_after,0*65

2019-03-07T22:20:53.869Z,1551997253.869 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.soundspeed_mps,1430*24

2019-03-07T22:20:54.250Z,1551997254.250 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.nst,0*4E

2019-03-07T22:20:54.260Z,1551997254.260 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.mode,0*02

2019-03-07T22:20:54.658Z,1551997254.658 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.dbg,0*60

2019-03-07T22:20:54.670Z,1551997254.670 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_type,0*3F

2019-03-07T22:20:55.072Z,1551997255.072 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs1_x_mm,-15*64

2019-03-07T22:20:55.087Z,1551997255.087 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs1_y_mm,15*48

2019-03-07T22:20:55.468Z,1551997255.468 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs1_z_mm,0*7F

2019-03-07T22:20:55.511Z,1551997255.511 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs2_x_mm,15*4A

2019-03-07T22:20:55.872Z,1551997255.872 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs2_y_mm,15*4B

2019-03-07T22:20:55.898Z,1551997255.898 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs2_z_mm,0*7C

2019-03-07T22:20:56.276Z,1551997256.276 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs3_x_mm,15*4B

2019-03-07T22:20:56.299Z,1551997256.299 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs3_y_mm,-15*67

2019-03-07T22:20:56.679Z,1551997256.679 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs3_z_mm,0*7D

2019-03-07T22:20:56.710Z,1551997256.710 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs4_x_mm,-15*61

2019-03-07T22:20:57.084Z,1551997257.084 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs4_y_mm,-15*60

2019-03-07T22:20:57.109Z,1551997257.109 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.array_locs4_z_mm,0*7A

2019-03-07T22:20:57.486Z,1551997257.486 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.usbl.channel_mask,30*1A

2019-03-07T22:20:57.501Z,1551997257.501 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.mode,0*75

2019-03-07T22:20:57.893Z,1551997257.893 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.carrier_Hz,13500*60

2019-03-07T22:20:57.923Z,1551997257.923 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.cycles_per_sym,7*72

2019-03-07T22:20:58.292Z,1551997258.292 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.data,0*66

2019-03-07T22:20:58.320Z,1551997258.320 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.sequence_len,47*62

2019-03-07T22:20:58.698Z,1551997258.698 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.timing_mode,0*3A

2019-03-07T22:20:58.730Z,1551997258.730 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.period,1*72

2019-03-07T22:20:59.108Z,1551997259.108 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.reference_time,2015-12-04T00:00:00Z*68

2019-03-07T22:20:59.156Z,1551997259.156 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.bandwidth_Hz,4000*40

2019-03-07T22:20:59.506Z,1551997259.506 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.trackping.duration_msx10,35*66

2019-03-07T22:20:59.514Z,1551997259.514 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.dt.mode,0*1A

2019-03-07T22:20:59.910Z,1551997259.910 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.dt.type,1*00

2019-03-07T22:20:59.920Z,1551997259.920 [Micromodem](ERROR): Response from modem unexpected: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-03-07T22:21:00.312Z,1551997260.312 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.part_number,201005*2D

2019-03-07T22:21:00.322Z,1551997260.322 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.serial_number,673*0E

2019-03-07T22:21:00.714Z,1551997260.714 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.board_rev,D*60

2019-03-07T22:21:00.723Z,1551997260.723 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.bom_variant,M*65

2019-03-07T22:21:01.119Z,1551997261.119 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.bom_rev,1*0F

2019-03-07T22:21:01.131Z,1551997261.131 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.fpga_version,2.0.18017*68

2019-03-07T22:21:01.530Z,1551997261.530 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.fpga_api_level,3*4D

2019-03-07T22:21:01.541Z,1551997261.541 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.fpga_flashrom_version,2*38

2019-03-07T22:21:01.931Z,1551997261.931 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.build_oem,A*6F

2019-03-07T22:21:01.967Z,1551997261.967 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.build_year,2016*43

2019-03-07T22:21:02.334Z,1551997262.334 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.build_month,5*0C

2019-03-07T22:21:02.343Z,1551997262.343 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.build_options,0*09

2019-03-07T22:21:02.741Z,1551997262.741 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.coproc_version,0.10.0.46.0 *54

2019-03-07T22:21:02.752Z,1551997262.752 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.firmware_version,2.0.27690*6C

2019-03-07T22:21:03.144Z,1551997263.144 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.loader_version,17175*61

2019-03-07T22:21:03.151Z,1551997263.151 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.booted_slot,1*1D

2019-03-07T22:21:03.541Z,1551997263.541 [Micromodem](ERROR): Response from modem unexpected: $CACFG,info.debugio,6*21

2019-03-07T22:21:03.550Z,1551997263.550 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.batt_v,23.611503*10

2019-03-07T22:21:03.948Z,1551997263.948 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.aux_adc_v,0.088000*77

2019-03-07T22:21:03.963Z,1551997263.963 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.aux_adc_gain,1*2F

2019-03-07T22:21:04.351Z,1551997264.351 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.aux_adc_res,12*78

2019-03-07T22:21:04.351Z,1551997264.351 [Micromodem](ERROR): Nmea resend: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-07T22:21:04.378Z,1551997264.378 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.aux33V_on,1*40

2019-03-07T22:21:04.754Z,1551997264.754 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.aux33V_fault,0*2A

2019-03-07T22:21:04.778Z,1551997264.778 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.stack33V_on,1*42

2019-03-07T22:21:05.158Z,1551997265.158 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.stack33V_fault,0*28

2019-03-07T22:21:05.165Z,1551997265.165 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.Vpwramp_on,1*25

2019-03-07T22:21:05.562Z,1551997265.562 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.Vpwramp_fault,0*4F

2019-03-07T22:21:05.568Z,1551997265.568 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.Vpreamp_on,1*37

2019-03-07T22:21:05.978Z,1551997265.978 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.Vpreamp_fault,0*5D

2019-03-07T22:21:05.991Z,1551997265.991 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.VSDcard_on,0*2E

2019-03-07T22:21:06.369Z,1551997266.369 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.VSDcard_fault,0*45

2019-03-07T22:21:06.375Z,1551997266.375 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.gpio1,0*05

2019-03-07T22:21:06.771Z,1551997266.771 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.gpio2,0*06

2019-03-07T22:21:06.776Z,1551997266.776 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.gpio3,0*07

2019-03-07T22:21:07.175Z,1551997267.175 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.gpio4,1*01

2019-03-07T22:21:07.181Z,1551997267.181 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.gpio5,0*01

2019-03-07T22:21:07.580Z,1551997267.580 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.txinhibit,0*50

2019-03-07T22:21:07.587Z,1551997267.587 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.temp,27.812500*3C

2019-03-07T22:21:08.022Z,1551997268.022 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.mm_rom_valid,0*35

2019-03-07T22:21:08.029Z,1551997268.029 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.mm_rom,0*1C

2019-03-07T22:21:08.394Z,1551997268.394 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.pa_rom_valid,0*24

2019-03-07T22:21:08.400Z,1551997268.400 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.pa_rom,0*0D

2019-03-07T22:21:08.798Z,1551997268.798 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.pa_rom_boot_search,0*15

2019-03-07T22:21:08.806Z,1551997268.806 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux1_rom_valid,0*68

2019-03-07T22:21:09.200Z,1551997269.200 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux1_rom,0*41

2019-03-07T22:21:09.208Z,1551997269.208 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux1_rom_boot_search,0*59

2019-03-07T22:21:09.602Z,1551997269.602 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux2_rom_valid,0*6B

2019-03-07T22:21:09.608Z,1551997269.608 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux2_rom,0*42

2019-03-07T22:21:10.006Z,1551997270.006 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux2_rom_boot_search,0*5A

2019-03-07T22:21:10.014Z,1551997270.014 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux3_rom_valid,0*6A

2019-03-07T22:21:10.408Z,1551997270.408 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux3_rom,0*43

2019-03-07T22:21:10.416Z,1551997270.416 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux3_rom_boot_search,0*5B

2019-03-07T22:21:10.817Z,1551997270.817 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux4_rom_valid,0*6D

2019-03-07T22:21:10.823Z,1551997270.823 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux4_rom,0*44

2019-03-07T22:21:11.223Z,1551997271.223 [Micromodem](ERROR): Response from modem unexpected: $CACFG,hwd.1wire.aux4_rom_boot_search,0*5C

2019-03-07T22:21:11.231Z,1551997271.231 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.temperature_degC,22.750000*5D

2019-03-07T22:21:11.619Z,1551997271.619 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.vbat,24.136003*49

2019-03-07T22:21:11.625Z,1551997271.625 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.vtransmit,0.000000*05

2019-03-07T22:21:12.029Z,1551997272.029 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.iout_sense_amps,0.000000*1F

2019-03-07T22:21:12.034Z,1551997272.034 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.txlevel,3*2E

2019-03-07T22:21:12.427Z,1551997272.427 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.stackpower_conf,1*1C

2019-03-07T22:21:12.432Z,1551997272.432 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.VPS_on,1*5D

2019-03-07T22:21:12.834Z,1551997272.834 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.gpio5,3*70

2019-03-07T22:21:12.838Z,1551997272.838 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.gpio6,3*73

2019-03-07T22:21:13.234Z,1551997273.234 [Micromodem](ERROR): Response from modem unexpected: $CACFG,pwramp.fault,0*3D

2019-03-07T22:21:13.238Z,1551997273.238 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.active,0*5D

2019-03-07T22:21:13.638Z,1551997273.638 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.comports,15*6E

2019-03-07T22:21:13.642Z,1551997273.642 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v0,0*17

2019-03-07T22:21:14.041Z,1551997274.041 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v1,0*16

2019-03-07T22:21:14.044Z,1551997274.044 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v2,0*15

2019-03-07T22:21:14.451Z,1551997274.451 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v3,0*14

2019-03-07T22:21:14.455Z,1551997274.455 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v4,0*13

2019-03-07T22:21:14.849Z,1551997274.849 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v5,0*12

2019-03-07T22:21:14.852Z,1551997274.852 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v6,0*11

2019-03-07T22:21:15.257Z,1551997275.257 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v7,0*10

2019-03-07T22:21:15.260Z,1551997275.260 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v8,0*1F

2019-03-07T22:21:15.661Z,1551997275.661 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.v9,0*1E

2019-03-07T22:21:15.661Z,1551997275.661 [Micromodem](ERROR): Nmea resend: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54

2019-03-07T22:21:15.676Z,1551997275.676 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.vA,0*66

2019-03-07T22:21:16.061Z,1551997276.061 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.vB,0*65

2019-03-07T22:21:16.065Z,1551997276.065 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.vC,0*64

2019-03-07T22:21:16.465Z,1551997276.465 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.vD,0*63

2019-03-07T22:21:16.468Z,1551997276.468 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.vE,0*62

2019-03-07T22:21:16.878Z,1551997276.878 [Micromodem](ERROR): Response from modem unexpected: $CACFG,script.vF,0*61

2019-03-07T22:21:24.111Z,1551997284.111 [CommandLine](IMPORTANT): got command show variable micromodem
2019-03-07T22:21:24.208Z,1551997284.208 [CommandLine](IMPORTANT): Micromodem.loadAtStartup (bool)
2019-03-07T22:21:24.217Z,1551997284.217 [CommandLine](IMPORTANT): Micromodem.simulateHardware (bool)
2019-03-07T22:21:24.217Z,1551997284.217 [CommandLine](IMPORTANT): Micromodem.localAddress (enum)
2019-03-07T22:21:24.255Z,1551997284.255 [CommandLine](IMPORTANT): Micromodem.loadControl (none)
2019-03-07T22:21:24.255Z,1551997284.255 [CommandLine](IMPORTANT): Micromodem.loadControl2 (none)
2019-03-07T22:21:24.256Z,1551997284.256 [CommandLine](IMPORTANT): Micromodem.uart (none)
2019-03-07T22:21:24.256Z,1551997284.256 [CommandLine](IMPORTANT): Micromodem.baud (bit_per_second)
2019-03-07T22:21:24.263Z,1551997284.263 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-07T22:21:24.264Z,1551997284.264 [CommandLine](IMPORTANT): Micromodem.acoustic_wakeup (count)
2019-03-07T22:21:24.264Z,1551997284.264 [CommandLine](IMPORTANT): Micromodem.acoustic_receive_time (epoch_second)
2019-03-07T22:21:24.264Z,1551997284.264 [CommandLine](IMPORTANT): Micromodem.acoustic_transmit_time (epoch_second)
2019-03-07T22:21:24.269Z,1551997284.269 [CommandLine](IMPORTANT): Micromodem.range_request (count)
2019-03-07T22:21:24.269Z,1551997284.269 [CommandLine](IMPORTANT): Micromodem.remote_address (count)
2019-03-07T22:21:24.270Z,1551997284.270 [CommandLine](IMPORTANT): Micromodem.local_address (count)
2019-03-07T22:21:24.270Z,1551997284.270 [CommandLine](IMPORTANT): Micromodem.range (meter)
2019-03-07T22:21:24.340Z,1551997284.340 [CommandLine](IMPORTANT): Micromodem.component_voltage (volt)
2019-03-07T22:21:24.357Z,1551997284.357 [CommandLine](IMPORTANT): Micromodem.component_avgVoltage (volt)
2019-03-07T22:21:24.358Z,1551997284.358 [CommandLine](IMPORTANT): Micromodem.component_current (milliampere)
2019-03-07T22:21:24.373Z,1551997284.373 [CommandLine](IMPORTANT): Micromodem.component_avgCurrent (milliampere)
2019-03-07T22:21:43.366Z,1551997303.366 [CommandLine](IMPORTANT): got command get Micromodem.uart
2019-03-07T22:21:43.366Z,1551997303.366 [CommandLine](IMPORTANT): Micromodem.uart /dev/ttyA7
2019-03-07T22:21:47.719Z,1551997307.719 [CommandLine](IMPORTANT): got command get Micromodem.baud
2019-03-07T22:21:47.719Z,1551997307.719 [CommandLine](IMPORTANT): Micromodem.baud 19200.000000 bps
2019-03-07T22:21:48.379Z,1551997308.379 [Micromodem](FAULT): LCB 2 fault: 
2019-03-07T22:21:48.379Z,1551997308.379 [Micromodem] Hardware Fault, FailCount= 2
2019-03-07T22:21:48.379Z,1551997308.379 [Micromodem](ERROR): Hardware Fault
2019-03-07T22:21:48.379Z,1551997308.379 [Micromodem](INFO): Stop
2019-03-07T22:21:48.379Z,1551997308.379 [Micromodem](INFO): uninitialize
2019-03-07T22:21:48.379Z,1551997308.379 [Micromodem](INFO): Powering down
2019-03-07T22:21:48.528Z,1551997308.528 [CBIT](ERROR): Hardware Fault in component: Micromodem
2019-03-07T22:21:48.782Z,1551997308.782 [Micromodem](INFO): Stop
2019-03-07T22:21:48.782Z,1551997308.782 [Micromodem](INFO): uninitialize
2019-03-07T22:21:48.782Z,1551997308.782 [Micromodem](INFO): Powering down
2019-03-07T22:21:49.186Z,1551997309.186 [Micromodem](INFO): Stopping
2019-03-07T22:21:49.590Z,1551997309.590 [Micromodem](INFO): Stopped
2019-03-07T22:21:49.655Z,1551997309.655 [CBIT](INFO): Clearing failed state for component Micromodem
2019-03-07T22:21:49.655Z,1551997309.655 [Micromodem] No Fault, FailCount= 2
2019-03-07T22:21:49.994Z,1551997309.994 [Micromodem](INFO): Start
2019-03-07T22:21:50.395Z,1551997310.395 [Micromodem](INFO): Start
2019-03-07T22:21:50.802Z,1551997310.802 [Micromodem](INFO): Start
2019-03-07T22:21:51.206Z,1551997311.206 [Micromodem](INFO): Start
2019-03-07T22:21:51.610Z,1551997311.610 [Micromodem](INFO): Start
2019-03-07T22:21:52.018Z,1551997312.018 [Micromodem](INFO): Start
2019-03-07T22:21:52.422Z,1551997312.422 [Micromodem](INFO): Starting
2019-03-07T22:21:52.422Z,1551997312.422 [Micromodem](INFO): Powering up
2019-03-07T22:21:52.423Z,1551997312.423 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-07T22:21:52.826Z,1551997312.826 [Micromodem](INFO): Starting
2019-03-07T22:21:53.226Z,1551997313.226 [Micromodem](INFO): Starting
2019-03-07T22:21:53.630Z,1551997313.630 [Micromodem](INFO): Starting
2019-03-07T22:21:54.031Z,1551997314.031 [Micromodem](INFO): Starting
2019-03-07T22:21:54.442Z,1551997314.442 [Micromodem](INFO): Starting
2019-03-07T22:21:54.842Z,1551997314.842 [Micromodem](INFO): Starting
2019-03-07T22:21:55.246Z,1551997315.246 [Micromodem](INFO): Starting
2019-03-07T22:21:55.654Z,1551997315.654 [Micromodem](INFO): Starting
2019-03-07T22:21:55.714Z,1551997315.714 [CommandLine](IMPORTANT): got command quit
2019-03-07T22:21:56.058Z,1551997316.058 [Micromodem](INFO): Starting
2019-03-07T22:21:56.458Z,1551997316.458 [Micromodem](INFO): Starting
2019-03-07T22:21:56.717Z,1551997316.717 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T22:21:56.717Z,1551997316.717 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:56.825Z,1551997316.825 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-07T22:21:56.825Z,1551997316.825 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:56.826Z,1551997316.826 [CommandLine](INFO): Join timeout helper Thread ID is 936
2019-03-07T22:21:56.826Z,1551997316.826 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-07T22:21:56.826Z,1551997316.826 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:56.827Z,1551997316.827 [NavChartDb](INFO): Join timeout helper Thread ID is 937
2019-03-07T22:21:56.866Z,1551997316.866 [Micromodem](INFO): Starting
2019-03-07T22:21:56.966Z,1551997316.966 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T22:21:56.966Z,1551997316.966 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:56.973Z,1551997316.973 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-07T22:21:56.973Z,1551997316.973 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:56.974Z,1551997316.974 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 938
2019-03-07T22:21:57.181Z,1551997317.181 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T22:21:57.181Z,1551997317.181 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-07T22:21:57.182Z,1551997317.182 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.193Z,1551997317.193 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-07T22:21:57.193Z,1551997317.193 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.193Z,1551997317.193 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 939
2019-03-07T22:21:57.245Z,1551997317.245 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T22:21:57.245Z,1551997317.245 [CTD_NeilBrown](INFO): Powering down
2019-03-07T22:21:57.257Z,1551997317.257 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.257Z,1551997317.257 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-07T22:21:57.257Z,1551997317.257 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.258Z,1551997317.258 [Radio_Surface](INFO): Join timeout helper Thread ID is 940
2019-03-07T22:21:57.266Z,1551997317.266 [Micromodem](INFO): Starting
2019-03-07T22:21:57.266Z,1551997317.266 [Micromodem](INFO): Starting
2019-03-07T22:21:57.267Z,1551997317.267 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37

2019-03-07T22:21:57.267Z,1551997317.267 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,CST,1*37

2019-03-07T22:21:57.267Z,1551997317.267 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-03-07T22:21:57.267Z,1551997317.267 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-07T22:21:57.267Z,1551997317.267 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-07T22:21:57.268Z,1551997317.268 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32

2019-03-07T22:21:57.268Z,1551997317.268 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,REV,1*32

2019-03-07T22:21:57.268Z,1551997317.268 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29

2019-03-07T22:21:57.268Z,1551997317.268 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,RXP,1*29

2019-03-07T22:21:57.465Z,1551997317.465 [Radio_Surface](INFO): Powering down
2019-03-07T22:21:57.466Z,1551997317.466 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T22:21:57.466Z,1551997317.466 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.474Z,1551997317.474 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-07T22:21:57.474Z,1551997317.474 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.474Z,1551997317.474 [DataOverHttps](INFO): Join timeout helper Thread ID is 941
2019-03-07T22:21:57.670Z,1551997317.670 [Micromodem](INFO): Starting
2019-03-07T22:21:57.845Z,1551997317.845 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T22:21:57.845Z,1551997317.845 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.866Z,1551997317.866 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-07T22:21:57.866Z,1551997317.866 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.866Z,1551997317.866 [logger](INFO): Join timeout helper Thread ID is 942
2019-03-07T22:21:57.957Z,1551997317.957 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T22:21:57.958Z,1551997317.958 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.968Z,1551997317.968 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-07T22:21:57.968Z,1551997317.968 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.971Z,1551997317.971 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-07T22:21:57.971Z,1551997317.971 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:57.972Z,1551997317.972 [controlThread](INFO): Join timeout helper Thread ID is 943
2019-03-07T22:21:58.065Z,1551997318.065 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-07T22:21:58.065Z,1551997318.065 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-07T22:21:58.066Z,1551997318.066 [DUSBL_Hydroid](INFO): Powering down
2019-03-07T22:21:58.137Z,1551997318.137 [Micromodem](INFO): uninitialize
2019-03-07T22:21:58.137Z,1551997318.137 [Micromodem](INFO): Powering down
2019-03-07T22:21:58.233Z,1551997318.233 [NAL9602](INFO): Powering down
2019-03-07T22:21:58.305Z,1551997318.305 [RDI_Pathfinder](INFO): Powering down
2019-03-07T22:21:58.306Z,1551997318.306 [RDI_PathfinderUp](INFO): Powering down
2019-03-07T22:21:58.307Z,1551997318.307 [Aanderaa_O2](INFO): Powering down
2019-03-07T22:21:58.308Z,1551997318.308 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-07T22:21:58.309Z,1551997318.309 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-07T22:21:58.310Z,1551997318.310 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-07T22:21:58.311Z,1551997318.311 [MissionManager](INFO): Uninitializing Mission Default
2019-03-07T22:21:58.311Z,1551997318.311 [Default] Stopped
2019-03-07T22:21:58.311Z,1551997318.311 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-07T22:21:58.311Z,1551997318.311 [Default:B.GoToSurface] Stopped
2019-03-07T22:21:58.311Z,1551997318.311 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-07T22:21:58.311Z,1551997318.311 [Default:CheckIn] Stopped
2019-03-07T22:21:58.311Z,1551997318.311 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-07T22:21:58.311Z,1551997318.311 [Default:CheckIn:Read_GPS] Stopped
2019-03-07T22:21:58.314Z,1551997318.314 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-07T22:21:58.314Z,1551997318.314 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-07T22:21:58.314Z,1551997318.314 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-07T22:21:58.315Z,1551997318.315 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-07T22:21:58.315Z,1551997318.315 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-07T22:21:58.315Z,1551997318.315 [BuoyancyServo](INFO): Powering down
2019-03-07T22:21:58.329Z,1551997318.329 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-07T22:21:58.329Z,1551997318.329 [ElevatorServo](INFO): Powering down
2019-03-07T22:21:58.330Z,1551997318.330 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-07T22:21:58.330Z,1551997318.330 [MassServo](INFO): Powering down
2019-03-07T22:21:58.331Z,1551997318.331 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-07T22:21:58.331Z,1551997318.331 [RudderServo](INFO): Powering down
2019-03-07T22:21:58.332Z,1551997318.332 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-07T22:21:58.332Z,1551997318.332 [ThrusterServo](INFO): Powering down
2019-03-07T22:21:58.333Z,1551997318.333 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-07T22:21:58.333Z,1551997318.333 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-07T22:21:58.333Z,1551997318.333 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-07T22:21:58.333Z,1551997318.333 [CBIT](DEBUG): Powering off loads.
2019-03-07T22:21:58.345Z,1551997318.345 [CBIT](DEBUG): Disabling WDT.
2019-03-07T22:21:58.357Z,1551997318.357 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-07T22:21:58.358Z,1551997318.358 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:58.419Z,1551997318.419 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:58.427Z,1551997318.427 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:58.466Z,1551997318.466 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:58.469Z,1551997318.469 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:58.522Z,1551997318.522 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-07T22:21:58.578Z,1551997318.578 [logger ThreadHandler](INFO): Thread cancelled.