2019-02-21T20:48:47.797Z,1550782127.797 [Supervisor](DEBUG): Initializing supervisor.
2019-02-21T20:48:47.800Z,1550782127.800 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-21T20:48:47.800Z,1550782127.800 [SyncHandler](INFO): Protected caller Thread ID is 3300
2019-02-21T20:48:47.801Z,1550782127.801 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-21T20:48:47.802Z,1550782127.802 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-21T20:48:47.802Z,1550782127.802 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3301
2019-02-21T20:48:47.805Z,1550782127.805 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-21T20:48:48.018Z,1550782128.018 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-21T20:48:48.019Z,1550782128.019 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-21T20:48:48.019Z,1550782128.019 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3302
2019-02-21T20:48:48.020Z,1550782128.020 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-21T20:48:48.021Z,1550782128.021 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-21T20:48:48.021Z,1550782128.021 [logger ThreadHandler](INFO): Protected caller Thread ID is 3303
2019-02-21T20:48:48.023Z,1550782128.023 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-21T20:48:48.023Z,1550782128.023 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-21T20:48:48.025Z,1550782128.025 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-21T20:48:48.432Z,1550782128.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-21T20:48:48.432Z,1550782128.432 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-21T20:48:48.531Z,1550782128.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-21T20:48:48.531Z,1550782128.531 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-21T20:48:48.850Z,1550782128.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-21T20:48:48.851Z,1550782128.851 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-21T20:48:48.990Z,1550782128.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-21T20:48:48.991Z,1550782128.991 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-21T20:48:49.182Z,1550782129.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-21T20:48:49.183Z,1550782129.183 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-21T20:48:49.638Z,1550782129.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-21T20:48:49.638Z,1550782129.638 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-21T20:48:49.842Z,1550782129.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-21T20:48:49.843Z,1550782129.843 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-21T20:48:49.988Z,1550782129.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-21T20:48:49.988Z,1550782129.988 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-21T20:48:50.179Z,1550782130.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-21T20:48:50.180Z,1550782130.180 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-21T20:48:50.277Z,1550782130.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-21T20:48:50.278Z,1550782130.278 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-21T20:48:50.587Z,1550782130.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-21T20:48:50.587Z,1550782130.587 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-21T20:48:50.669Z,1550782130.669 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-21T20:48:50.773Z,1550782130.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-21T20:48:50.774Z,1550782130.774 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-21T20:48:51.258Z,1550782131.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-21T20:48:51.258Z,1550782131.258 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-21T20:48:51.650Z,1550782131.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-21T20:48:51.652Z,1550782131.652 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-21T20:48:51.653Z,1550782131.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-21T20:48:51.860Z,1550782131.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-21T20:48:51.961Z,1550782131.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-21T20:48:52.060Z,1550782132.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-21T20:48:52.286Z,1550782132.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-21T20:48:52.287Z,1550782132.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-21T20:48:52.373Z,1550782132.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-21T20:48:52.467Z,1550782132.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-21T20:48:52.616Z,1550782132.616 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-21T20:48:52.836Z,1550782132.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-21T20:48:53.145Z,1550782133.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-21T20:48:53.523Z,1550782133.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-21T20:48:53.667Z,1550782133.667 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/LOGIN/
2019-02-21T20:48:53.667Z,1550782133.667 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-21T20:48:53.668Z,1550782133.668 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-21T20:48:53.973Z,1550782133.973 [DataOverHttps] Loaded
2019-02-21T20:48:53.973Z,1550782133.973 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-21T20:48:53.987Z,1550782133.987 [Depth_Keller] Loaded
2019-02-21T20:48:53.987Z,1550782133.987 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-21T20:48:53.992Z,1550782133.992 [DropWeight] Loaded
2019-02-21T20:48:53.992Z,1550782133.992 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-21T20:48:54.040Z,1550782134.040 [DUSBL_Hydroid] Loaded
2019-02-21T20:48:54.040Z,1550782134.040 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-02-21T20:48:54.080Z,1550782134.080 [Micromodem] Loaded
2019-02-21T20:48:54.080Z,1550782134.080 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-02-21T20:48:54.176Z,1550782134.176 [NAL9602] Loaded
2019-02-21T20:48:54.176Z,1550782134.176 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-21T20:48:54.192Z,1550782134.192 [Onboard] Loaded
2019-02-21T20:48:54.192Z,1550782134.192 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-21T20:48:54.202Z,1550782134.202 [Radio_Surface] Loaded
2019-02-21T20:48:54.202Z,1550782134.202 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-21T20:48:54.203Z,1550782134.203 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407534E0
2019-02-21T20:48:54.204Z,1550782134.204 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3382
2019-02-21T20:48:54.232Z,1550782134.232 [RDI_Pathfinder] Loaded
2019-02-21T20:48:54.232Z,1550782134.232 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-21T20:48:54.254Z,1550782134.254 [RDI_PathfinderUp] Loaded
2019-02-21T20:48:54.254Z,1550782134.254 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-21T20:48:56.027Z,1550782136.027 [BPC1] Loaded
2019-02-21T20:48:56.027Z,1550782136.027 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-21T20:48:56.028Z,1550782136.028 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-21T20:48:56.028Z,1550782136.028 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-21T20:48:56.041Z,1550782136.041 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-21T20:48:56.042Z,1550782136.042 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-21T20:48:56.147Z,1550782136.147 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-21T20:48:56.147Z,1550782136.147 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-21T20:48:56.205Z,1550782136.205 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-21T20:48:56.205Z,1550782136.205 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-21T20:48:56.263Z,1550782136.263 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-21T20:48:56.263Z,1550782136.263 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-21T20:48:56.279Z,1550782136.279 [NavChart] Loaded
2019-02-21T20:48:56.279Z,1550782136.279 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-21T20:48:56.283Z,1550782136.283 [UniversalFixResidualReporter] Loaded
2019-02-21T20:48:56.284Z,1550782136.284 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-21T20:48:56.284Z,1550782136.284 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-21T20:48:56.285Z,1550782136.285 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-21T20:48:56.386Z,1550782136.386 [BuoyancyServo] Loaded
2019-02-21T20:48:56.386Z,1550782136.386 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-21T20:48:56.401Z,1550782136.401 [ElevatorServo] Loaded
2019-02-21T20:48:56.402Z,1550782136.402 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-21T20:48:56.416Z,1550782136.416 [MassServo] Loaded
2019-02-21T20:48:56.417Z,1550782136.417 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-21T20:48:56.432Z,1550782136.432 [RudderServo] Loaded
2019-02-21T20:48:56.432Z,1550782136.432 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-21T20:48:56.446Z,1550782136.446 [ThrusterServo] Loaded
2019-02-21T20:48:56.447Z,1550782136.447 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-21T20:48:56.447Z,1550782136.447 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-21T20:48:56.448Z,1550782136.448 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-21T20:48:56.622Z,1550782136.622 [Aanderaa_O2] Loaded
2019-02-21T20:48:56.623Z,1550782136.623 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-21T20:48:56.706Z,1550782136.706 [CTD_NeilBrown] Loaded
2019-02-21T20:48:56.707Z,1550782136.707 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-21T20:48:56.708Z,1550782136.708 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-21T20:48:56.708Z,1550782136.708 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3383
2019-02-21T20:48:56.752Z,1550782136.752 [WetLabsSeaOWL_UV_A] Loaded
2019-02-21T20:48:56.752Z,1550782136.752 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-21T20:48:56.753Z,1550782136.753 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-21T20:48:56.754Z,1550782136.754 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 3384
2019-02-21T20:48:56.754Z,1550782136.754 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-21T20:48:56.755Z,1550782136.755 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-21T20:48:56.989Z,1550782136.989 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-21T20:48:56.990Z,1550782136.990 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-21T20:48:57.030Z,1550782137.030 [DepthRateCalculator] Loaded
2019-02-21T20:48:57.031Z,1550782137.031 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-21T20:48:57.037Z,1550782137.037 [PitchRateCalculator] Loaded
2019-02-21T20:48:57.037Z,1550782137.037 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-21T20:48:57.049Z,1550782137.049 [SpeedCalculator] Loaded
2019-02-21T20:48:57.049Z,1550782137.049 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-21T20:48:57.070Z,1550782137.070 [TempGradientCalculator] Loaded
2019-02-21T20:48:57.070Z,1550782137.070 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-21T20:48:57.076Z,1550782137.076 [YawRateCalculator] Loaded
2019-02-21T20:48:57.076Z,1550782137.076 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-21T20:48:57.115Z,1550782137.115 [ElevatorOffsetCalculator] Loaded
2019-02-21T20:48:57.116Z,1550782137.116 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-21T20:48:57.116Z,1550782137.116 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-21T20:48:57.117Z,1550782137.117 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-21T20:48:57.248Z,1550782137.248 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-21T20:48:57.270Z,1550782137.270 [SBIT] Loaded
2019-02-21T20:48:57.270Z,1550782137.270 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-21T20:48:57.271Z,1550782137.271 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-21T20:48:57.283Z,1550782137.283 [IBIT] Loaded
2019-02-21T20:48:57.283Z,1550782137.283 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-21T20:48:57.286Z,1550782137.286 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-21T20:48:57.425Z,1550782137.425 [CBIT] Loaded
2019-02-21T20:48:57.425Z,1550782137.425 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-21T20:48:57.425Z,1550782137.425 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-21T20:48:57.426Z,1550782137.426 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-21T20:48:57.489Z,1550782137.489 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-21T20:48:57.489Z,1550782137.489 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-21T20:48:57.649Z,1550782137.649 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-21T20:48:57.681Z,1550782137.681 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-21T20:48:57.847Z,1550782137.847 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-21T20:48:58.042Z,1550782138.042 [VerticalControl] Loaded
2019-02-21T20:48:58.042Z,1550782138.042 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-21T20:48:58.043Z,1550782138.043 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-21T20:48:58.100Z,1550782138.100 [HorizontalControl] Loaded
2019-02-21T20:48:58.100Z,1550782138.100 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-21T20:48:58.101Z,1550782138.101 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-21T20:48:58.103Z,1550782138.103 [SpeedControl] Loaded
2019-02-21T20:48:58.103Z,1550782138.103 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-21T20:48:58.104Z,1550782138.104 [LoopControl](DEBUG): Construct LoopControl.
2019-02-21T20:48:58.104Z,1550782138.104 [LoopControl] Loaded
2019-02-21T20:48:58.104Z,1550782138.104 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-21T20:48:58.105Z,1550782138.105 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-21T20:48:58.105Z,1550782138.105 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-21T20:48:58.133Z,1550782138.133 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-21T20:48:58.136Z,1550782138.136 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-21T20:48:58.137Z,1550782138.137 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-21T20:48:58.144Z,1550782138.144 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-21T20:48:58.145Z,1550782138.145 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0
2019-02-21T20:48:58.145Z,1550782138.145 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3385
2019-02-21T20:48:58.150Z,1550782138.150 [Supervisor](INFO): Main Thread ID is 3299
2019-02-21T20:48:58.150Z,1550782138.150 [Supervisor](DEBUG): Running supervisor.
2019-02-21T20:48:58.151Z,1550782138.151 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3386
2019-02-21T20:48:58.153Z,1550782138.153 [controlThread ThreadHandler](INFO): Handler Thread ID is 3387
2019-02-21T20:48:58.153Z,1550782138.153 [controlThread](DEBUG): Initializing ControlThread
2019-02-21T20:48:58.160Z,1550782138.160 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T20:48:58.161Z,1550782138.161 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-21T20:48:58.161Z,1550782138.161 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-21T20:48:58.163Z,1550782138.163 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-21T20:48:58.163Z,1550782138.163 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-21T20:48:58.163Z,1550782138.163 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-21T20:48:58.163Z,1550782138.163 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-21T20:48:58.164Z,1550782138.164 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-21T20:48:58.164Z,1550782138.164 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-21T20:48:58.165Z,1550782138.165 [SBIT](INFO): Initialize SBIT Component.
2019-02-21T20:48:58.166Z,1550782138.166 [SBIT](IMPORTANT): git: 2019-02-11-13-g62924c4
2019-02-21T20:48:58.166Z,1550782138.166 [SBIT](INFO): git hash: 62924c492e86bb9d7cd72440302a80a745358b26
2019-02-21T20:48:58.166Z,1550782138.166 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-21T20:48:58.166Z,1550782138.166 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-21T20:48:58.167Z,1550782138.167 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-21T20:48:58.168Z,1550782138.168 [IBIT](INFO): Initialize IBIT Component.
2019-02-21T20:48:58.169Z,1550782138.169 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-21T20:48:58.170Z,1550782138.170 [logger ThreadHandler](INFO): Handler Thread ID is 3388
2019-02-21T20:48:58.181Z,1550782138.181 [CBIT](DEBUG): Initialized mux pins.
2019-02-21T20:48:58.181Z,1550782138.181 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-21T20:48:58.189Z,1550782138.189 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3389
2019-02-21T20:48:58.190Z,1550782138.190 [Radio_Surface](INFO): Powering up
2019-02-21T20:48:58.201Z,1550782138.201 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3390
2019-02-21T20:48:58.202Z,1550782138.202 [CTD_NeilBrown](INFO): Powering down
2019-02-21T20:48:58.209Z,1550782138.209 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-21T20:48:58.209Z,1550782138.209 [CBIT](DEBUG): Initializing heartbeat.
2019-02-21T20:48:58.229Z,1550782138.229 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 3391
2019-02-21T20:48:58.230Z,1550782138.230 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T20:48:58.260Z,1550782138.260 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3392
2019-02-21T20:48:58.264Z,1550782138.264 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-21T20:48:58.264Z,1550782138.264 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-21T20:48:58.264Z,1550782138.264 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-21T20:48:58.264Z,1550782138.264 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-21T20:48:58.264Z,1550782138.264 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-21T20:48:58.264Z,1550782138.264 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-21T20:48:58.265Z,1550782138.265 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-21T20:48:58.265Z,1550782138.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-21T20:48:58.265Z,1550782138.265 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-21T20:48:58.265Z,1550782138.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-21T20:48:58.266Z,1550782138.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-21T20:48:58.266Z,1550782138.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-21T20:48:58.266Z,1550782138.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-21T20:48:58.266Z,1550782138.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-21T20:48:58.266Z,1550782138.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T20:48:58.266Z,1550782138.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-21T20:48:58.281Z,1550782138.281 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-21T20:48:58.281Z,1550782138.281 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-21T20:48:58.317Z,1550782138.317 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-21T20:48:58.319Z,1550782138.319 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-21T20:48:58.319Z,1550782138.319 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-21T20:48:58.320Z,1550782138.320 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-21T20:48:58.321Z,1550782138.321 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-21T20:48:58.332Z,1550782138.332 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T20:48:58.358Z,1550782138.358 [MissionManager](DEBUG):
2019-02-21T20:48:58.359Z,1550782138.359 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-21T20:48:58.431Z,1550782138.431 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-21T20:48:58.432Z,1550782138.432 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-21T20:48:58.434Z,1550782138.434 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T20:48:58.456Z,1550782138.456 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-21T20:48:58.476Z,1550782138.476 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-21T20:48:58.491Z,1550782138.491 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-21T20:48:58.514Z,1550782138.514 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-02-21T20:48:58.519Z,1550782138.519 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,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-02-21T20:48:58.537Z,1550782138.537 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-21T20:48:58.558Z,1550782138.558 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-21T20:48:58.558Z,1550782138.558 [DUSBL_Hydroid](INFO): Powering up
2019-02-21T20:48:58.559Z,1550782138.559 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-21T20:48:58.577Z,1550782138.577 [Micromodem](INFO): Start
2019-02-21T20:48:58.641Z,1550782138.641 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T20:48:58.683Z,1550782138.683 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:48:58.689Z,1550782138.689 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-21T20:48:58.690Z,1550782138.690 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:48:58.697Z,1550782138.697 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-21T20:48:58.698Z,1550782138.698 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:48:58.705Z,1550782138.705 [MassServo](DEBUG): Initializing MassServo.
2019-02-21T20:48:58.706Z,1550782138.706 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:48:58.713Z,1550782138.713 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-21T20:48:58.714Z,1550782138.714 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-21T20:48:58.721Z,1550782138.721 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-21T20:48:58.966Z,1550782138.966 [Micromodem](INFO): Starting
2019-02-21T20:48:58.967Z,1550782138.967 [Micromodem](INFO): Powering up
2019-02-21T20:48:58.967Z,1550782138.967 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:48:59.073Z,1550782139.073 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T20:48:59.129Z,1550782139.129 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T20:49:07.716Z,1550782147.716 [Micromodem](INFO): Starting
2019-02-21T20:49:07.716Z,1550782147.716 [Micromodem](INFO): Starting
2019-02-21T20:49:07.716Z,1550782147.716 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:07.716Z,1550782147.716 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45
2019-02-21T20:49:07.717Z,1550782147.717 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45
2019-02-21T20:49:07.725Z,1550782147.725 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:07.726Z,1550782147.726 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53
2019-02-21T20:49:07.726Z,1550782147.726 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:07.726Z,1550782147.726 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51
2019-02-21T20:49:07.726Z,1550782147.726 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:07.726Z,1550782147.726 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A
2019-02-21T20:49:07.727Z,1550782147.727 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T20:49:07.727Z,1550782147.727 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40
2019-02-21T20:49:07.757Z,1550782147.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T20:49:08.068Z,1550782148.068 [Micromodem](INFO): Starting
2019-02-21T20:49:08.466Z,1550782148.466 [Micromodem](INFO): Starting
2019-02-21T20:49:08.601Z,1550782148.601 [Aanderaa_O2](INFO): Powering down
2019-02-21T20:49:08.870Z,1550782148.870 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004669
2019-02-21T20:49:08.874Z,1550782148.874 [Micromodem](INFO): Starting
2019-02-21T20:49:08.875Z,1550782148.875 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T20:49:08.875Z,1550782148.875 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T20:49:09Z,2*8B
2019-02-21T20:49:09.274Z,1550782149.274 [Micromodem](INFO): Starting
2019-02-21T20:49:09.678Z,1550782149.678 [Micromodem](INFO): Starting
2019-02-21T20:49:10.082Z,1550782150.082 [Micromodem](INFO): Starting
2019-02-21T20:49:10.486Z,1550782150.486 [Micromodem](INFO): Starting
2019-02-21T20:49:10.890Z,1550782150.890 [Micromodem](INFO): Starting
2019-02-21T20:49:11.298Z,1550782151.298 [Micromodem](INFO): Starting
2019-02-21T20:49:11.698Z,1550782151.698 [Micromodem](INFO): Starting
2019-02-21T20:49:12.102Z,1550782152.102 [Micromodem](INFO): Starting
2019-02-21T20:49:12.128Z,1550782152.128 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-21T20:49:12.506Z,1550782152.506 [Micromodem](INFO): Starting
2019-02-21T20:49:12.910Z,1550782152.910 [Micromodem](INFO): Starting
2019-02-21T20:49:13.314Z,1550782153.314 [Micromodem](INFO): Starting
2019-02-21T20:49:13.718Z,1550782153.718 [Micromodem](INFO): Starting
2019-02-21T20:49:14.122Z,1550782154.122 [Micromodem](INFO): Starting
2019-02-21T20:49:14.526Z,1550782154.526 [Micromodem](INFO): Starting
2019-02-21T20:49:14.938Z,1550782154.938 [Micromodem](INFO): Starting
2019-02-21T20:49:15.334Z,1550782155.334 [Micromodem](INFO): Starting
2019-02-21T20:49:15.739Z,1550782155.739 [Micromodem](INFO): Starting
2019-02-21T20:49:16.143Z,1550782156.143 [Micromodem](INFO): Starting
2019-02-21T20:49:16.547Z,1550782156.547 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-02-21T20:49:16.561Z,1550782156.561 [Micromodem](INFO): Starting
2019-02-21T20:49:16.950Z,1550782156.950 [Micromodem](INFO): Starting
2019-02-21T20:49:17.366Z,1550782157.366 [Micromodem](INFO): Starting
2019-02-21T20:49:17.762Z,1550782157.762 [Micromodem](INFO): Starting
2019-02-21T20:49:18.162Z,1550782158.162 [Micromodem](INFO): Starting
2019-02-21T20:49:18.578Z,1550782158.578 [Micromodem](INFO): Starting
2019-02-21T20:49:18.579Z,1550782158.579 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available:
2019-02-21T20:49:18.579Z,1550782158.579 [Micromodem] Communications Fault, FailCount= 1
2019-02-21T20:49:18.579Z,1550782158.579 [Micromodem](ERROR): Communications Fault
2019-02-21T20:49:18.637Z,1550782158.637 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T20:49:18.970Z,1550782158.970 [Micromodem](INFO): Stop
2019-02-21T20:49:18.970Z,1550782158.970 [Micromodem](INFO): uninitialize
2019-02-21T20:49:18.970Z,1550782158.970 [Micromodem](INFO): Powering down
2019-02-21T20:49:19.374Z,1550782159.374 [Micromodem](INFO): Stopping
2019-02-21T20:49:19.778Z,1550782159.778 [Micromodem](INFO): Stopped
2019-02-21T20:49:19.818Z,1550782159.818 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T20:49:19.818Z,1550782159.818 [Micromodem] No Fault, FailCount= 1
2019-02-21T20:49:20.182Z,1550782160.182 [Micromodem](INFO): Start
2019-02-21T20:49:20.588Z,1550782160.588 [Micromodem](INFO): Start
2019-02-21T20:49:20.990Z,1550782160.990 [Micromodem](INFO): Start
2019-02-21T20:49:21.394Z,1550782161.394 [Micromodem](INFO): Start
2019-02-21T20:49:21.798Z,1550782161.798 [Micromodem](INFO): Start
2019-02-21T20:49:22.202Z,1550782162.202 [Micromodem](INFO): Start
2019-02-21T20:49:22.602Z,1550782162.602 [Micromodem](INFO): Starting
2019-02-21T20:49:22.602Z,1550782162.602 [Micromodem](INFO): Powering up
2019-02-21T20:49:22.603Z,1550782162.603 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:49:23.006Z,1550782163.006 [Micromodem](INFO): Starting
2019-02-21T20:49:23.414Z,1550782163.414 [Micromodem](INFO): Starting
2019-02-21T20:49:23.818Z,1550782163.818 [Micromodem](INFO): Starting
2019-02-21T20:49:24.218Z,1550782164.218 [Micromodem](INFO): Starting
2019-02-21T20:49:24.219Z,1550782164.219 [NAL9602](INFO): Powering up NAL9602
2019-02-21T20:49:24.638Z,1550782164.638 [Micromodem](INFO): Starting
2019-02-21T20:49:25.030Z,1550782165.030 [Micromodem](INFO): Starting
2019-02-21T20:49:25.434Z,1550782165.434 [Micromodem](INFO): Starting
2019-02-21T20:49:25.838Z,1550782165.838 [Micromodem](INFO): Starting
2019-02-21T20:49:26.238Z,1550782166.238 [Micromodem](INFO): Starting
2019-02-21T20:49:26.646Z,1550782166.646 [Micromodem](INFO): Starting
2019-02-21T20:49:26.687Z,1550782166.687 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-21T20:49:26.692Z,1550782166.692 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-21T20:49:27.050Z,1550782167.050 [Micromodem](INFO): Starting
2019-02-21T20:49:27.454Z,1550782167.454 [Micromodem](INFO): Starting
2019-02-21T20:49:27.454Z,1550782167.454 [Micromodem](INFO): Starting
2019-02-21T20:49:27.454Z,1550782167.454 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:27.455Z,1550782167.455 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45
2019-02-21T20:49:27.455Z,1550782167.455 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45
2019-02-21T20:49:27.455Z,1550782167.455 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:27.455Z,1550782167.455 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53
2019-02-21T20:49:27.456Z,1550782167.456 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:27.456Z,1550782167.456 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51
2019-02-21T20:49:27.456Z,1550782167.456 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:27.456Z,1550782167.456 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A
2019-02-21T20:49:27.456Z,1550782167.456 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T20:49:27.457Z,1550782167.457 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40
2019-02-21T20:49:27.858Z,1550782167.858 [Micromodem](INFO): Starting
2019-02-21T20:49:28.262Z,1550782168.262 [Micromodem](INFO): Starting
2019-02-21T20:49:28.666Z,1550782168.666 [Micromodem](INFO): Starting
2019-02-21T20:49:28.666Z,1550782168.666 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T20:49:28.667Z,1550782168.667 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T20:49:29Z,2*80
2019-02-21T20:49:29.154Z,1550782169.154 [Micromodem](INFO): Starting
2019-02-21T20:49:29.170Z,1550782169.170 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-02-21T20:49:29.170Z,1550782169.170 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-02-21T20:49:29.170Z,1550782169.170 [RDI_Pathfinder](ERROR): Communications Fault
2019-02-21T20:49:29.318Z,1550782169.318 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-02-21T20:49:29.554Z,1550782169.554 [Micromodem](INFO): Starting
2019-02-21T20:49:29.645Z,1550782169.645 [RDI_Pathfinder](INFO): Powering down
2019-02-21T20:49:29.958Z,1550782169.958 [Micromodem](INFO): Starting
2019-02-21T20:49:30.358Z,1550782170.358 [Micromodem](INFO): Starting
2019-02-21T20:49:30.566Z,1550782170.566 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-02-21T20:49:30.566Z,1550782170.566 [RDI_Pathfinder] No Fault, FailCount= 1
2019-02-21T20:49:30.762Z,1550782170.762 [Micromodem](INFO): Starting
2019-02-21T20:49:31.166Z,1550782171.166 [Micromodem](INFO): Starting
2019-02-21T20:49:31.570Z,1550782171.570 [Micromodem](INFO): Starting
2019-02-21T20:49:31.978Z,1550782171.978 [Micromodem](INFO): Starting
2019-02-21T20:49:32.382Z,1550782172.382 [Micromodem](INFO): Starting
2019-02-21T20:49:32.790Z,1550782172.790 [Micromodem](INFO): Starting
2019-02-21T20:49:33.190Z,1550782173.190 [Micromodem](INFO): Starting
2019-02-21T20:49:33.594Z,1550782173.594 [Micromodem](INFO): Starting
2019-02-21T20:49:33.997Z,1550782173.997 [Micromodem](INFO): Starting
2019-02-21T20:49:34.402Z,1550782174.402 [Micromodem](INFO): Starting
2019-02-21T20:49:34.806Z,1550782174.806 [Micromodem](INFO): Starting
2019-02-21T20:49:40.483Z,1550782180.483 [Micromodem](INFO): Starting
2019-02-21T20:49:40.505Z,1550782180.505 [NAL9602](INFO): NAL9602 initialized
2019-02-21T20:49:40.741Z,1550782180.741 [Micromodem](INFO): Starting
2019-02-21T20:49:41.130Z,1550782181.130 [Micromodem](INFO): Starting
2019-02-21T20:49:41.145Z,1550782181.145 [NAL9602](DEBUG): Fix Requested
2019-02-21T20:49:41.546Z,1550782181.546 [Micromodem](INFO): Starting
2019-02-21T20:49:41.934Z,1550782181.934 [Micromodem](INFO): Starting
2019-02-21T20:49:42.342Z,1550782182.342 [Micromodem](INFO): Starting
2019-02-21T20:49:42.343Z,1550782182.343 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available:
2019-02-21T20:49:42.343Z,1550782182.343 [Micromodem] Communications Fault, FailCount= 2
2019-02-21T20:49:42.343Z,1550782182.343 [Micromodem](ERROR): Communications Fault
2019-02-21T20:49:42.566Z,1550782182.566 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T20:49:42.569Z,1550782182.569 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.023087
CHAN A1 (24V): -0.194456
CHAN A2 (12V): -0.007105
CHAN A3 (5V): -0.001871
CHAN B0 (3.3V): 0.000151
CHAN B1 (3.15aV): 0.000617
CHAN B2 (3.15bV): 0.000416
CHAN B3 (GND): 0.002404
OPEN: 0.005436
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-21T20:49:42.758Z,1550782182.758 [Micromodem](INFO): Stop
2019-02-21T20:49:42.758Z,1550782182.758 [Micromodem](INFO): uninitialize
2019-02-21T20:49:42.758Z,1550782182.758 [Micromodem](INFO): Powering down
2019-02-21T20:49:43.338Z,1550782183.338 [Micromodem](INFO): Stopping
2019-02-21T20:49:43.738Z,1550782183.738 [Micromodem](INFO): Stopped
2019-02-21T20:49:43.890Z,1550782183.890 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T20:49:43.890Z,1550782183.890 [Micromodem] No Fault, FailCount= 2
2019-02-21T20:49:44.142Z,1550782184.142 [Micromodem](INFO): Start
2019-02-21T20:49:44.546Z,1550782184.546 [Micromodem](INFO): Start
2019-02-21T20:49:44.950Z,1550782184.950 [Micromodem](INFO): Start
2019-02-21T20:49:45.354Z,1550782185.354 [Micromodem](INFO): Start
2019-02-21T20:49:45.758Z,1550782185.758 [Micromodem](INFO): Start
2019-02-21T20:49:46.162Z,1550782186.162 [Micromodem](INFO): Start
2019-02-21T20:49:46.562Z,1550782186.562 [Micromodem](INFO): Starting
2019-02-21T20:49:46.562Z,1550782186.562 [Micromodem](INFO): Powering up
2019-02-21T20:49:46.563Z,1550782186.563 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:49:46.970Z,1550782186.970 [Micromodem](INFO): Starting
2019-02-21T20:49:47.382Z,1550782187.382 [Micromodem](INFO): Starting
2019-02-21T20:49:47.778Z,1550782187.778 [Micromodem](INFO): Starting
2019-02-21T20:49:48.182Z,1550782188.182 [Micromodem](INFO): Starting
2019-02-21T20:49:48.607Z,1550782188.607 [Micromodem](INFO): Starting
2019-02-21T20:49:48.990Z,1550782188.990 [Micromodem](INFO): Starting
2019-02-21T20:49:49.394Z,1550782189.394 [Micromodem](INFO): Starting
2019-02-21T20:49:49.798Z,1550782189.798 [Micromodem](INFO): Starting
2019-02-21T20:49:50.206Z,1550782190.206 [Micromodem](INFO): Starting
2019-02-21T20:49:50.606Z,1550782190.606 [Micromodem](INFO): Starting
2019-02-21T20:49:51.010Z,1550782191.010 [Micromodem](INFO): Starting
2019-02-21T20:49:51.419Z,1550782191.419 [Micromodem](INFO): Starting
2019-02-21T20:49:51.420Z,1550782191.420 [Micromodem](INFO): Starting
2019-02-21T20:49:51.420Z,1550782191.420 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:51.420Z,1550782191.420 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45
2019-02-21T20:49:51.420Z,1550782191.420 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45
2019-02-21T20:49:51.422Z,1550782191.422 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:51.423Z,1550782191.423 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53
2019-02-21T20:49:51.424Z,1550782191.424 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:51.425Z,1550782191.425 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51
2019-02-21T20:49:51.426Z,1550782191.426 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T20:49:51.426Z,1550782191.426 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A
2019-02-21T20:49:51.428Z,1550782191.428 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T20:49:51.428Z,1550782191.428 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40
2019-02-21T20:49:51.818Z,1550782191.818 [Micromodem](INFO): Starting
2019-02-21T20:49:52.222Z,1550782192.222 [Micromodem](INFO): Starting
2019-02-21T20:49:52.630Z,1550782192.630 [Micromodem](INFO): Starting
2019-02-21T20:49:52.631Z,1550782192.631 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T20:49:52.631Z,1550782192.631 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T20:49:53Z,2*84
2019-02-21T20:49:53.034Z,1550782193.034 [Micromodem](INFO): Starting
2019-02-21T20:49:53.050Z,1550782193.050 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-02-21T20:49:53.050Z,1550782193.050 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-02-21T20:49:53.050Z,1550782193.050 [RDI_Pathfinder](ERROR): Communications Fault
2019-02-21T20:49:53.110Z,1550782193.110 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-02-21T20:49:53.438Z,1550782193.438 [Micromodem](INFO): Starting
2019-02-21T20:49:53.525Z,1550782193.525 [RDI_Pathfinder](INFO): Powering down
2019-02-21T20:49:53.838Z,1550782193.838 [Micromodem](INFO): Starting
2019-02-21T20:49:54.242Z,1550782194.242 [Micromodem](INFO): Starting
2019-02-21T20:49:54.342Z,1550782194.342 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-02-21T20:49:54.342Z,1550782194.342 [RDI_Pathfinder] No Fault, FailCount= 2
2019-02-21T20:49:54.647Z,1550782194.647 [Micromodem](INFO): Starting
2019-02-21T20:49:55.054Z,1550782195.054 [Micromodem](INFO): Starting
2019-02-21T20:49:55.454Z,1550782195.454 [Micromodem](INFO): Starting
2019-02-21T20:49:55.858Z,1550782195.858 [Micromodem](INFO): Starting
2019-02-21T20:49:56.262Z,1550782196.262 [Micromodem](INFO): Starting
2019-02-21T20:49:56.670Z,1550782196.670 [Micromodem](INFO): Starting
2019-02-21T20:49:57.070Z,1550782197.070 [Micromodem](INFO): Starting
2019-02-21T20:49:57.478Z,1550782197.478 [Micromodem](INFO): Starting
2019-02-21T20:49:57.880Z,1550782197.880 [Micromodem](INFO): Starting
2019-02-21T20:49:58.282Z,1550782198.282 [Micromodem](INFO): Starting
2019-02-21T20:49:58.686Z,1550782198.686 [Micromodem](INFO): Starting
2019-02-21T20:49:59.090Z,1550782199.090 [Micromodem](INFO): Starting
2019-02-21T20:49:59.498Z,1550782199.498 [Micromodem](INFO): Starting
2019-02-21T20:49:59.902Z,1550782199.902 [Micromodem](INFO): Starting
2019-02-21T20:50:00.306Z,1550782200.306 [Micromodem](INFO): Starting
2019-02-21T20:50:00.706Z,1550782200.706 [Micromodem](INFO): Starting
2019-02-21T20:50:01.114Z,1550782201.114 [Micromodem](INFO): Starting
2019-02-21T20:50:01.514Z,1550782201.514 [Micromodem](INFO): Starting
2019-02-21T20:50:01.918Z,1550782201.918 [Micromodem](INFO): Starting
2019-02-21T20:50:02.322Z,1550782202.322 [Micromodem](INFO): Starting
2019-02-21T20:50:02.730Z,1550782202.730 [Micromodem](INFO): Starting
2019-02-21T20:50:03.134Z,1550782203.134 [Micromodem](INFO): Starting
2019-02-21T20:50:03.538Z,1550782203.538 [Micromodem](INFO): Starting
2019-02-21T20:50:03.938Z,1550782203.938 [Micromodem](INFO): Starting
2019-02-21T20:50:04.342Z,1550782204.342 [Micromodem](INFO): Starting
2019-02-21T20:50:04.747Z,1550782204.747 [Micromodem](INFO): Starting
2019-02-21T20:50:05.154Z,1550782205.154 [Micromodem](INFO): Starting
2019-02-21T20:50:05.554Z,1550782205.554 [Micromodem](INFO): Starting
2019-02-21T20:50:05.962Z,1550782205.962 [Micromodem](INFO): Starting
2019-02-21T20:50:06.362Z,1550782206.362 [Micromodem](INFO): Starting
2019-02-21T20:50:06.363Z,1550782206.363 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: , available:
2019-02-21T20:50:06.363Z,1550782206.363 [Micromodem] Communications Fault, FailCount= 3
2019-02-21T20:50:06.363Z,1550782206.363 [Micromodem](ERROR): Communications Fault
2019-02-21T20:50:06.407Z,1550782206.407 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T20:50:06.766Z,1550782206.766 [Micromodem](INFO): Stop
2019-02-21T20:50:06.766Z,1550782206.766 [Micromodem](INFO): uninitialize
2019-02-21T20:50:06.766Z,1550782206.766 [Micromodem](INFO): Powering down
2019-02-21T20:50:07.174Z,1550782207.174 [Micromodem](INFO): Stopping
2019-02-21T20:50:07.574Z,1550782207.574 [Micromodem](INFO): Stopped
2019-02-21T20:50:07.607Z,1550782207.607 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T20:50:07.607Z,1550782207.607 [Micromodem] No Fault, FailCount= 3
2019-02-21T20:50:07.978Z,1550782207.978 [Micromodem](INFO): Start
2019-02-21T20:50:08.386Z,1550782208.386 [Micromodem](INFO): Start
2019-02-21T20:50:08.786Z,1550782208.786 [Micromodem](INFO): Start
2019-02-21T20:50:09.194Z,1550782209.194 [Micromodem](INFO): Start
2019-02-21T20:50:09.598Z,1550782209.598 [Micromodem](INFO): Start
2019-02-21T20:50:10.082Z,1550782210.082 [Micromodem](INFO): Start
2019-02-21T20:50:11.089Z,1550782211.089 [CommandLine](IMPORTANT): got command quit
2019-02-21T20:50:12.093Z,1550782212.093 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-02-21T20:50:12.093Z,1550782212.093 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:50:12.093Z,1550782212.093 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.157Z,1550782212.157 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-02-21T20:50:12.157Z,1550782212.157 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.158Z,1550782212.158 [CommandLine](INFO): Join timeout helper Thread ID is 3415
2019-02-21T20:50:12.177Z,1550782212.177 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-21T20:50:12.177Z,1550782212.177 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.177Z,1550782212.177 [NavChartDb](INFO): Join timeout helper Thread ID is 3416
2019-02-21T20:50:12.558Z,1550782212.558 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:50:12.558Z,1550782212.558 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.569Z,1550782212.569 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-21T20:50:12.569Z,1550782212.569 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.569Z,1550782212.569 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 3417
2019-02-21T20:50:12.593Z,1550782212.593 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:50:12.593Z,1550782212.593 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T20:50:12.594Z,1550782212.594 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.601Z,1550782212.601 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-21T20:50:12.601Z,1550782212.601 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.601Z,1550782212.601 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3418
2019-02-21T20:50:12.741Z,1550782212.741 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:50:12.741Z,1550782212.741 [CTD_NeilBrown](INFO): Powering down
2019-02-21T20:50:12.753Z,1550782212.753 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.773Z,1550782212.773 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-21T20:50:12.773Z,1550782212.773 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.773Z,1550782212.773 [Radio_Surface](INFO): Join timeout helper Thread ID is 3419
2019-02-21T20:50:12.961Z,1550782212.961 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:50:12.961Z,1550782212.961 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.974Z,1550782212.974 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-21T20:50:12.974Z,1550782212.974 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:12.974Z,1550782212.974 [logger](INFO): Join timeout helper Thread ID is 3420
2019-02-21T20:50:13.073Z,1550782213.073 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:50:13.073Z,1550782213.073 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:13.086Z,1550782213.086 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-21T20:50:13.086Z,1550782213.086 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:13.086Z,1550782213.086 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-21T20:50:13.086Z,1550782213.086 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:13.086Z,1550782213.086 [controlThread](INFO): Join timeout helper Thread ID is 3421
2019-02-21T20:50:13.168Z,1550782213.168 [Micromodem](INFO): Starting
2019-02-21T20:50:13.168Z,1550782213.168 [Micromodem](INFO): Powering up
2019-02-21T20:50:13.168Z,1550782213.168 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T20:50:13.390Z,1550782213.390 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T20:50:13.391Z,1550782213.391 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-21T20:50:13.394Z,1550782213.394 [DUSBL_Hydroid](INFO): Powering down
2019-02-21T20:50:13.485Z,1550782213.485 [Micromodem](INFO): uninitialize
2019-02-21T20:50:13.485Z,1550782213.485 [Micromodem](INFO): Powering down
2019-02-21T20:50:13.581Z,1550782213.581 [NAL9602](INFO): Powering down
2019-02-21T20:50:13.653Z,1550782213.653 [RDI_Pathfinder](INFO): Powering down
2019-02-21T20:50:13.654Z,1550782213.654 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T20:50:13.655Z,1550782213.655 [Aanderaa_O2](INFO): Powering down
2019-02-21T20:50:13.656Z,1550782213.656 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-21T20:50:13.657Z,1550782213.657 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-21T20:50:13.658Z,1550782213.658 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-21T20:50:13.659Z,1550782213.659 [MissionManager](INFO): Uninitializing Mission Default
2019-02-21T20:50:13.661Z,1550782213.661 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-21T20:50:13.662Z,1550782213.662 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-21T20:50:13.663Z,1550782213.663 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-21T20:50:13.663Z,1550782213.663 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-21T20:50:13.663Z,1550782213.663 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-21T20:50:13.663Z,1550782213.663 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-21T20:50:13.664Z,1550782213.664 [BuoyancyServo](INFO): Powering down
2019-02-21T20:50:13.677Z,1550782213.677 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-21T20:50:13.677Z,1550782213.677 [ElevatorServo](INFO): Powering down
2019-02-21T20:50:13.678Z,1550782213.678 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-21T20:50:13.678Z,1550782213.678 [MassServo](INFO): Powering down
2019-02-21T20:50:13.679Z,1550782213.679 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-21T20:50:13.679Z,1550782213.679 [RudderServo](INFO): Powering down
2019-02-21T20:50:13.680Z,1550782213.680 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-21T20:50:13.680Z,1550782213.680 [ThrusterServo](INFO): Powering down
2019-02-21T20:50:13.681Z,1550782213.681 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-21T20:50:13.681Z,1550782213.681 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-21T20:50:13.681Z,1550782213.681 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-21T20:50:13.682Z,1550782213.682 [CBIT](DEBUG): Powering off loads.
2019-02-21T20:50:13.693Z,1550782213.693 [CBIT](DEBUG): Disabling WDT.
2019-02-21T20:50:13.705Z,1550782213.705 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-21T20:50:13.706Z,1550782213.706 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:13.768Z,1550782213.768 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:14.170Z,1550782214.170 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:14.172Z,1550782214.172 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:14.226Z,1550782214.226 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T20:50:14.281Z,1550782214.281 [logger ThreadHandler](INFO): Thread cancelled.