2019-02-19T22:30:59.248Z,1550615459.248 [Supervisor](DEBUG): Initializing supervisor. 2019-02-19T22:30:59.251Z,1550615459.251 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-02-19T22:30:59.251Z,1550615459.251 [SyncHandler](INFO): Protected caller Thread ID is 7825 2019-02-19T22:30:59.252Z,1550615459.252 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-02-19T22:30:59.253Z,1550615459.253 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-02-19T22:30:59.254Z,1550615459.254 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7826 2019-02-19T22:30:59.257Z,1550615459.257 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-02-19T22:30:59.268Z,1550615459.268 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-02-19T22:30:59.269Z,1550615459.269 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-02-19T22:30:59.269Z,1550615459.269 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7827 2019-02-19T22:30:59.270Z,1550615459.270 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-02-19T22:30:59.271Z,1550615459.271 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-02-19T22:30:59.272Z,1550615459.272 [logger ThreadHandler](INFO): Protected caller Thread ID is 7828 2019-02-19T22:30:59.274Z,1550615459.274 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-02-19T22:30:59.274Z,1550615459.274 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-02-19T22:30:59.275Z,1550615459.275 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-02-19T22:30:59.680Z,1550615459.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-02-19T22:30:59.681Z,1550615459.681 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-02-19T22:30:59.777Z,1550615459.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-02-19T22:30:59.778Z,1550615459.778 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-02-19T22:31:00.095Z,1550615460.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-02-19T22:31:00.095Z,1550615460.095 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-02-19T22:31:00.232Z,1550615460.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-02-19T22:31:00.232Z,1550615460.232 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-02-19T22:31:00.423Z,1550615460.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-02-19T22:31:00.424Z,1550615460.424 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-02-19T22:31:00.877Z,1550615460.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-02-19T22:31:00.878Z,1550615460.878 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-02-19T22:31:01.079Z,1550615461.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-02-19T22:31:01.079Z,1550615461.079 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-02-19T22:31:01.222Z,1550615461.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-02-19T22:31:01.223Z,1550615461.223 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-02-19T22:31:01.412Z,1550615461.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-02-19T22:31:01.412Z,1550615461.412 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-02-19T22:31:01.506Z,1550615461.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-02-19T22:31:01.507Z,1550615461.507 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-02-19T22:31:01.814Z,1550615461.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-02-19T22:31:01.814Z,1550615461.814 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-02-19T22:31:01.893Z,1550615461.893 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-02-19T22:31:01.995Z,1550615461.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-02-19T22:31:01.995Z,1550615461.995 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-02-19T22:31:02.475Z,1550615462.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-02-19T22:31:02.475Z,1550615462.475 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-02-19T22:31:02.865Z,1550615462.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-02-19T22:31:02.867Z,1550615462.867 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2019-02-19T22:31:02.868Z,1550615462.868 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2019-02-19T22:31:03.081Z,1550615463.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2019-02-19T22:31:03.179Z,1550615463.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2019-02-19T22:31:03.275Z,1550615463.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2019-02-19T22:31:03.498Z,1550615463.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-02-19T22:31:03.499Z,1550615463.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2019-02-19T22:31:03.582Z,1550615463.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2019-02-19T22:31:03.674Z,1550615463.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2019-02-19T22:31:03.771Z,1550615463.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2019-02-19T22:31:03.851Z,1550615463.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2019-02-19T22:31:03.958Z,1550615463.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2019-02-19T22:31:04.289Z,1550615464.289 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2019-02-19T22:31:04.587Z,1550615464.587 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2019-02-19T22:31:04.587Z,1550615464.587 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-02-19T22:31:04.589Z,1550615464.589 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-02-19T22:31:05.180Z,1550615465.180 [AHRS_M2] Loaded 2019-02-19T22:31:05.181Z,1550615465.181 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-02-19T22:31:05.251Z,1550615465.251 [DataOverHttps] Loaded 2019-02-19T22:31:05.251Z,1550615465.251 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2019-02-19T22:31:05.264Z,1550615465.264 [Depth_Keller] Loaded 2019-02-19T22:31:05.264Z,1550615465.264 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-02-19T22:31:05.269Z,1550615465.269 [DropWeight] Loaded 2019-02-19T22:31:05.269Z,1550615465.269 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-02-19T22:31:05.317Z,1550615465.317 [DUSBL_Hydroid] Loaded 2019-02-19T22:31:05.317Z,1550615465.317 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2019-02-19T22:31:05.319Z,1550615465.319 [Micromodem](CRITICAL): No configuration setting for: Micromodem.verbosity 2019-02-19T22:31:05.321Z,1550615465.321 [Micromodem](CRITICAL): No configuration setting for: Micromodem.localAddress 2019-02-19T22:31:05.359Z,1550615465.359 [Micromodem] Loaded 2019-02-19T22:31:05.359Z,1550615465.359 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread. 2019-02-19T22:31:05.456Z,1550615465.456 [NAL9602] Loaded 2019-02-19T22:31:05.457Z,1550615465.457 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-02-19T22:31:05.472Z,1550615465.472 [Onboard] Loaded 2019-02-19T22:31:05.473Z,1550615465.473 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-02-19T22:31:05.483Z,1550615465.483 [Radio_Surface] Loaded 2019-02-19T22:31:05.483Z,1550615465.483 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-02-19T22:31:05.484Z,1550615465.484 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407524E0 2019-02-19T22:31:05.484Z,1550615465.484 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7907 2019-02-19T22:31:05.512Z,1550615465.512 [RDI_Pathfinder] Loaded 2019-02-19T22:31:05.513Z,1550615465.513 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-02-19T22:31:05.534Z,1550615465.534 [RDI_PathfinderUp] Loaded 2019-02-19T22:31:05.534Z,1550615465.534 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2019-02-19T22:31:07.312Z,1550615467.312 [BPC1] Loaded 2019-02-19T22:31:07.312Z,1550615467.312 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-02-19T22:31:07.312Z,1550615467.312 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-02-19T22:31:07.313Z,1550615467.313 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-02-19T22:31:07.326Z,1550615467.326 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-02-19T22:31:07.327Z,1550615467.327 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-02-19T22:31:07.431Z,1550615467.431 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-02-19T22:31:07.431Z,1550615467.431 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-02-19T22:31:07.490Z,1550615467.490 [DeadReckonUsingSpeedCalculator] Loaded 2019-02-19T22:31:07.490Z,1550615467.490 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-02-19T22:31:07.549Z,1550615467.549 [DeadReckonWithRespectToSeafloor] Loaded 2019-02-19T22:31:07.549Z,1550615467.549 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-02-19T22:31:07.565Z,1550615467.565 [NavChart] Loaded 2019-02-19T22:31:07.565Z,1550615467.565 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-02-19T22:31:07.569Z,1550615467.569 [UniversalFixResidualReporter] Loaded 2019-02-19T22:31:07.570Z,1550615467.570 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-02-19T22:31:07.570Z,1550615467.570 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-02-19T22:31:07.571Z,1550615467.571 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-02-19T22:31:07.672Z,1550615467.672 [BuoyancyServo] Loaded 2019-02-19T22:31:07.672Z,1550615467.672 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-02-19T22:31:07.687Z,1550615467.687 [ElevatorServo] Loaded 2019-02-19T22:31:07.688Z,1550615467.688 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-02-19T22:31:07.702Z,1550615467.702 [MassServo] Loaded 2019-02-19T22:31:07.703Z,1550615467.703 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-02-19T22:31:07.718Z,1550615467.718 [RudderServo] Loaded 2019-02-19T22:31:07.719Z,1550615467.719 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-02-19T22:31:07.733Z,1550615467.733 [ThrusterServo] Loaded 2019-02-19T22:31:07.733Z,1550615467.733 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-02-19T22:31:07.734Z,1550615467.734 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-02-19T22:31:07.734Z,1550615467.734 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-02-19T22:31:07.909Z,1550615467.909 [Aanderaa_O2] Loaded 2019-02-19T22:31:07.909Z,1550615467.909 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-02-19T22:31:07.992Z,1550615467.992 [CTD_NeilBrown] Loaded 2019-02-19T22:31:07.992Z,1550615467.992 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-02-19T22:31:07.993Z,1550615467.993 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408614E0 2019-02-19T22:31:07.994Z,1550615467.994 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 7908 2019-02-19T22:31:08.038Z,1550615468.038 [WetLabsSeaOWL_UV_A] Loaded 2019-02-19T22:31:08.038Z,1550615468.038 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2019-02-19T22:31:08.039Z,1550615468.039 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408914E0 2019-02-19T22:31:08.040Z,1550615468.040 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 7909 2019-02-19T22:31:08.040Z,1550615468.040 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-02-19T22:31:08.041Z,1550615468.041 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-02-19T22:31:08.275Z,1550615468.275 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-02-19T22:31:08.276Z,1550615468.276 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-02-19T22:31:08.316Z,1550615468.316 [DepthRateCalculator] Loaded 2019-02-19T22:31:08.317Z,1550615468.317 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-02-19T22:31:08.322Z,1550615468.322 [PitchRateCalculator] Loaded 2019-02-19T22:31:08.323Z,1550615468.323 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-02-19T22:31:08.335Z,1550615468.335 [SpeedCalculator] Loaded 2019-02-19T22:31:08.335Z,1550615468.335 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-02-19T22:31:08.356Z,1550615468.356 [TempGradientCalculator] Loaded 2019-02-19T22:31:08.356Z,1550615468.356 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-02-19T22:31:08.362Z,1550615468.362 [YawRateCalculator] Loaded 2019-02-19T22:31:08.362Z,1550615468.362 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-02-19T22:31:08.402Z,1550615468.402 [ElevatorOffsetCalculator] Loaded 2019-02-19T22:31:08.403Z,1550615468.403 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-02-19T22:31:08.403Z,1550615468.403 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-02-19T22:31:08.404Z,1550615468.404 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-02-19T22:31:08.539Z,1550615468.539 [SBIT](DEBUG): Construct Startup Built In Test. 2019-02-19T22:31:08.561Z,1550615468.561 [SBIT] Loaded 2019-02-19T22:31:08.561Z,1550615468.561 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-02-19T22:31:08.562Z,1550615468.562 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-02-19T22:31:08.573Z,1550615468.573 [IBIT] Loaded 2019-02-19T22:31:08.574Z,1550615468.574 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-02-19T22:31:08.577Z,1550615468.577 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-02-19T22:31:08.714Z,1550615468.714 [CBIT] Loaded 2019-02-19T22:31:08.715Z,1550615468.715 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-02-19T22:31:08.715Z,1550615468.715 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-02-19T22:31:08.716Z,1550615468.716 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-02-19T22:31:08.779Z,1550615468.779 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-02-19T22:31:08.779Z,1550615468.779 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-02-19T22:31:08.879Z,1550615468.879 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-02-19T22:31:08.879Z,1550615468.879 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-02-19T22:31:08.945Z,1550615468.945 [VerticalControl](DEBUG): Construct VerticalControl. 2019-02-19T22:31:09.028Z,1550615469.028 [VerticalControl] Loaded 2019-02-19T22:31:09.028Z,1550615469.028 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-02-19T22:31:09.029Z,1550615469.029 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-02-19T22:31:09.086Z,1550615469.086 [HorizontalControl] Loaded 2019-02-19T22:31:09.087Z,1550615469.087 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-02-19T22:31:09.087Z,1550615469.087 [SpeedControl](DEBUG): Construct SpeedControl. 2019-02-19T22:31:09.089Z,1550615469.089 [SpeedControl] Loaded 2019-02-19T22:31:09.089Z,1550615469.089 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-02-19T22:31:09.090Z,1550615469.090 [LoopControl](DEBUG): Construct LoopControl. 2019-02-19T22:31:09.091Z,1550615469.091 [LoopControl] Loaded 2019-02-19T22:31:09.091Z,1550615469.091 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-02-19T22:31:09.091Z,1550615469.091 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-02-19T22:31:09.092Z,1550615469.092 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-02-19T22:31:09.118Z,1550615469.118 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-02-19T22:31:09.122Z,1550615469.122 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-02-19T22:31:09.123Z,1550615469.123 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-02-19T22:31:09.129Z,1550615469.129 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-02-19T22:31:09.130Z,1550615469.130 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4E4E0 2019-02-19T22:31:09.131Z,1550615469.131 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7910 2019-02-19T22:31:09.135Z,1550615469.135 [Supervisor](INFO): Main Thread ID is 7824 2019-02-19T22:31:09.135Z,1550615469.135 [Supervisor](DEBUG): Running supervisor. 2019-02-19T22:31:09.136Z,1550615469.136 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7911 2019-02-19T22:31:09.138Z,1550615469.138 [controlThread ThreadHandler](INFO): Handler Thread ID is 7912 2019-02-19T22:31:09.139Z,1550615469.139 [controlThread](DEBUG): Initializing ControlThread 2019-02-19T22:31:09.144Z,1550615469.144 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-19T22:31:09.145Z,1550615469.145 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-02-19T22:31:09.145Z,1550615469.145 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-02-19T22:31:09.146Z,1550615469.146 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-02-19T22:31:09.147Z,1550615469.147 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-02-19T22:31:09.147Z,1550615469.147 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-02-19T22:31:09.147Z,1550615469.147 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-02-19T22:31:09.148Z,1550615469.148 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-02-19T22:31:09.148Z,1550615469.148 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-02-19T22:31:09.149Z,1550615469.149 [SBIT](INFO): Initialize SBIT Component. 2019-02-19T22:31:09.149Z,1550615469.149 [SBIT](IMPORTANT): git: 2019-02-11-5-g1cf9f4d 2019-02-19T22:31:09.150Z,1550615469.150 [SBIT](INFO): git hash: 1cf9f4d7aba313398a625168a3064bcf57d75169 2019-02-19T22:31:09.150Z,1550615469.150 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-02-19T22:31:09.150Z,1550615469.150 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2019-02-19T22:31:09.151Z,1550615469.151 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-02-19T22:31:09.152Z,1550615469.152 [IBIT](INFO): Initialize IBIT Component. 2019-02-19T22:31:09.153Z,1550615469.153 [CBIT](DEBUG): Initialize CBIT Component. 2019-02-19T22:31:09.154Z,1550615469.154 [logger ThreadHandler](INFO): Handler Thread ID is 7913 2019-02-19T22:31:09.165Z,1550615469.165 [CBIT](DEBUG): Initialized mux pins. 2019-02-19T22:31:09.165Z,1550615469.165 [CBIT](DEBUG): Initializing the watchdog timer. 2019-02-19T22:31:09.173Z,1550615469.173 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7914 2019-02-19T22:31:09.174Z,1550615469.174 [Radio_Surface](INFO): Powering up 2019-02-19T22:31:09.185Z,1550615469.185 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 7915 2019-02-19T22:31:09.186Z,1550615469.186 [CTD_NeilBrown](INFO): Powering down 2019-02-19T22:31:09.193Z,1550615469.193 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-02-19T22:31:09.193Z,1550615469.193 [CBIT](DEBUG): Initializing heartbeat. 2019-02-19T22:31:09.213Z,1550615469.213 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 7916 2019-02-19T22:31:09.214Z,1550615469.214 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-02-19T22:31:09.237Z,1550615469.237 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7917 2019-02-19T22:31:09.240Z,1550615469.240 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2019-02-19T22:31:09.246Z,1550615469.246 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-19T22:31:09.247Z,1550615469.247 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2019-02-19T22:31:09.247Z,1550615469.247 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-19T22:31:09.249Z,1550615469.249 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2019-02-19T22:31:09.249Z,1550615469.249 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-19T22:31:09.250Z,1550615469.250 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2019-02-19T22:31:09.251Z,1550615469.251 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-19T22:31:09.252Z,1550615469.252 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2019-02-19T22:31:09.252Z,1550615469.252 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-19T22:31:09.254Z,1550615469.254 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2019-02-19T22:31:09.254Z,1550615469.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-19T22:31:09.255Z,1550615469.255 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2019-02-19T22:31:09.256Z,1550615469.256 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-19T22:31:09.257Z,1550615469.257 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2019-02-19T22:31:09.257Z,1550615469.257 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-19T22:31:09.259Z,1550615469.259 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2019-02-19T22:31:09.265Z,1550615469.265 [CBIT](DEBUG): Deactivating GF circuits. 2019-02-19T22:31:09.265Z,1550615469.265 [CBIT](DEBUG): Deactivating emergency mode. 2019-02-19T22:31:09.301Z,1550615469.301 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-02-19T22:31:09.303Z,1550615469.303 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-02-19T22:31:09.303Z,1550615469.303 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-02-19T22:31:09.304Z,1550615469.304 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-02-19T22:31:09.305Z,1550615469.305 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-02-19T22:31:09.315Z,1550615469.315 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-19T22:31:09.345Z,1550615469.345 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-19T22:31:09.407Z,1550615469.407 [MissionManager](DEBUG): 2019-02-19T22:31:09.408Z,1550615469.408 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-02-19T22:31:09.485Z,1550615469.485 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-02-19T22:31:09.486Z,1550615469.486 [Default:A.Wait](DEBUG): Construct Wait. 2019-02-19T22:31:09.488Z,1550615469.488 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-19T22:31:09.527Z,1550615469.527 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-02-19T22:31:09.538Z,1550615469.538 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-02-19T22:31:09.543Z,1550615469.543 [Default:E.Execute](DEBUG): Construct Execute. 2019-02-19T22:31:09.547Z,1550615469.547 [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-19T22:31:09.573Z,1550615469.573 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,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-19T22:31:09.586Z,1550615469.586 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-02-19T22:31:09.624Z,1550615469.624 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-02-19T22:31:09.650Z,1550615469.650 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2019-02-19T22:31:09.651Z,1550615469.651 [DUSBL_Hydroid](INFO): Powering up 2019-02-19T22:31:09.651Z,1550615469.651 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-19T22:31:09.749Z,1550615469.749 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-19T22:31:09.806Z,1550615469.806 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-19T22:31:09.813Z,1550615469.813 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-19T22:31:09.814Z,1550615469.814 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-02-19T22:31:09.817Z,1550615469.817 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-02-19T22:31:09.818Z,1550615469.818 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-19T22:31:09.825Z,1550615469.825 [MassServo](DEBUG): Initializing MassServo. 2019-02-19T22:31:09.826Z,1550615469.826 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-19T22:31:09.833Z,1550615469.833 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-19T22:31:09.834Z,1550615469.834 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-02-19T22:31:09.841Z,1550615469.841 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-02-19T22:31:09.843Z,1550615469.843 [CommandLine](FAULT): Scheduling is paused 2019-02-19T22:31:09.844Z,1550615469.844 [CBIT](INFO): Critical error at 20190219T223105 2019-02-19T22:31:09.844Z,1550615469.844 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-02-19T22:31:10.022Z,1550615470.022 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:10.022Z,1550615470.022 [Micromodem](INFO): Powering up 2019-02-19T22:31:10.023Z,1550615470.023 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-19T22:31:10.117Z,1550615470.117 [RDI_PathfinderUp](INFO): Powering down 2019-02-19T22:31:14.830Z,1550615474.830 [Radio_Surface](INFO): Powering down 2019-02-19T22:31:18.634Z,1550615478.634 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:18.636Z,1550615478.636 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015109,INIT,2.0.27690*41 2019-02-19T22:31:18.637Z,1550615478.637 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015109,SLOT1,2.0.27690*6E 2019-02-19T22:31:18.638Z,1550615478.638 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015109,AUV,2.0.27690*19 2019-02-19T22:31:18.639Z,1550615478.639 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015109,COPROC,0.10.0.46*4C 2019-02-19T22:31:18.639Z,1550615478.639 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:19.076Z,1550615479.076 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2019-02-19T22:31:19.081Z,1550615479.081 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:19.442Z,1550615479.442 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:19.581Z,1550615479.581 [Aanderaa_O2](INFO): Powering down 2019-02-19T22:31:19.843Z,1550615479.843 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004554 2019-02-19T22:31:19.846Z,1550615479.846 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:20.290Z,1550615480.290 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:20.646Z,1550615480.646 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-02-19T22:31:20.646Z,1550615480.646 [AHRS_M2] Communications Fault, FailCount= 1 2019-02-19T22:31:20.646Z,1550615480.646 [AHRS_M2](ERROR): Communications Fault 2019-02-19T22:31:20.650Z,1550615480.650 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:20.736Z,1550615480.736 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-02-19T22:31:20.925Z,1550615480.925 [Radio_Surface](INFO): Powering up 2019-02-19T22:31:21.024Z,1550615481.024 [AHRS_M2](INFO): Powering down 2019-02-19T22:31:21.127Z,1550615481.127 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:21.442Z,1550615481.442 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:21.863Z,1550615481.863 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:22.072Z,1550615482.072 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-02-19T22:31:22.073Z,1550615482.073 [AHRS_M2] No Fault, FailCount= 1 2019-02-19T22:31:22.236Z,1550615482.236 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-02-19T22:31:22.270Z,1550615482.270 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:22.646Z,1550615482.646 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:23.050Z,1550615483.050 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:23.084Z,1550615483.084 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-02-19T22:31:23.455Z,1550615483.455 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:23.821Z,1550615483.821 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-19T22:31:23.870Z,1550615483.870 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:24.267Z,1550615484.267 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:24.268Z,1550615484.268 [Micromodem](INFO): unknown deviceResponse_: $CATMG,1902-02-23T01:51:09Z,RTC,RTC*7B 2019-02-19T22:31:24.269Z,1550615484.269 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015119,AUV,2.0.27690*18 2019-02-19T22:31:24.666Z,1550615484.666 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:25.070Z,1550615485.070 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:25.498Z,1550615485.498 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:25.902Z,1550615485.902 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:26.314Z,1550615486.314 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:26.712Z,1550615486.712 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:27.116Z,1550615487.116 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:27.526Z,1550615487.526 [DUSBL_Hydroid](INFO): DUSBL Version:O 2019-02-19T22:31:27.541Z,1550615487.541 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:27.922Z,1550615487.922 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:28.326Z,1550615488.326 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:28.794Z,1550615488.794 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:29.122Z,1550615489.122 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:29.530Z,1550615489.530 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:29.930Z,1550615489.930 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:29.931Z,1550615489.931 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,015119,AUV,2.0.27690*18 , available: $CAREV,015119,COPROC,0.10.0.46*4D 2019-02-19T22:31:29.931Z,1550615489.931 [Micromodem] Communications Fault, FailCount= 1 2019-02-19T22:31:29.931Z,1550615489.931 [Micromodem](ERROR): Communications Fault 2019-02-19T22:31:29.962Z,1550615489.962 [CBIT](ERROR): Communications Fault in component: Micromodem 2019-02-19T22:31:30.334Z,1550615490.334 [Micromodem](INFO): Powering down 2019-02-19T22:31:31.225Z,1550615491.225 [CBIT](INFO): Clearing failed state for component Micromodem 2019-02-19T22:31:31.225Z,1550615491.225 [Micromodem] No Fault, FailCount= 1 2019-02-19T22:31:33.970Z,1550615493.970 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:33.970Z,1550615493.970 [Micromodem](INFO): Powering up 2019-02-19T22:31:33.971Z,1550615493.971 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-19T22:31:34.382Z,1550615494.382 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:34.802Z,1550615494.802 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:35.179Z,1550615495.179 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:35.595Z,1550615495.595 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:35.596Z,1550615495.596 [NAL9602](INFO): Powering up NAL9602 2019-02-19T22:31:35.990Z,1550615495.990 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:36.394Z,1550615496.394 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:36.802Z,1550615496.802 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:37.206Z,1550615497.206 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:37.607Z,1550615497.607 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:37.657Z,1550615497.657 [SBIT](IMPORTANT): Beginning Startup BIT 2019-02-19T22:31:37.668Z,1550615497.668 [CBIT](IMPORTANT): Beginning ground fault scan 2019-02-19T22:31:38.010Z,1550615498.010 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:38.012Z,1550615498.012 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015133,INIT,2.0.27690*48 2019-02-19T22:31:38.013Z,1550615498.013 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015133,SLOT1,2.0.27690*67 2019-02-19T22:31:38.014Z,1550615498.014 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015133,AUV,2.0.27690*10 2019-02-19T22:31:38.015Z,1550615498.015 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015133,COPROC,0.10.0.46*45 2019-02-19T22:31:38.409Z,1550615498.409 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2019-02-19T22:31:38.409Z,1550615498.409 [AHRS_M2] Data Fault, FailCount= 2 2019-02-19T22:31:38.409Z,1550615498.409 [AHRS_M2](ERROR): Data Fault 2019-02-19T22:31:38.414Z,1550615498.414 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:38.529Z,1550615498.529 [CBIT](ERROR): Data Fault in component: AHRS_M2 2019-02-19T22:31:38.800Z,1550615498.800 [AHRS_M2](INFO): Powering down 2019-02-19T22:31:38.878Z,1550615498.878 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:38.878Z,1550615498.878 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:39.226Z,1550615499.226 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:39.622Z,1550615499.622 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:40.114Z,1550615500.114 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-02-19T22:31:40.114Z,1550615500.114 [AHRS_M2] No Fault, FailCount= 2 2019-02-19T22:31:40.119Z,1550615500.119 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-02-19T22:31:40.129Z,1550615500.129 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:40.526Z,1550615500.526 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:40.938Z,1550615500.938 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:41.334Z,1550615501.334 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:41.738Z,1550615501.738 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:42.142Z,1550615502.142 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:42.265Z,1550615502.265 [RudderServo](FAULT): Overload Error 2019-02-19T22:31:42.265Z,1550615502.265 [RudderServo] Hardware Fault, FailCount= 1 2019-02-19T22:31:42.265Z,1550615502.265 [RudderServo](ERROR): Hardware Fault 2019-02-19T22:31:42.310Z,1550615502.310 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-02-19T22:31:42.546Z,1550615502.546 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:42.678Z,1550615502.678 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-02-19T22:31:42.678Z,1550615502.678 [RudderServo](INFO): Powering down 2019-02-19T22:31:42.950Z,1550615502.950 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:43.673Z,1550615503.673 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:43.675Z,1550615503.675 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:43.769Z,1550615503.769 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-19T22:31:43.886Z,1550615503.886 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-19T22:31:43.890Z,1550615503.890 [CBIT](INFO): Clearing failed state for component RudderServo 2019-02-19T22:31:43.890Z,1550615503.890 [RudderServo] No Fault, FailCount= 1 2019-02-19T22:31:44.217Z,1550615504.217 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:44.222Z,1550615504.222 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:44.677Z,1550615504.677 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:44.682Z,1550615504.682 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:45.137Z,1550615505.137 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:45.142Z,1550615505.142 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:45.713Z,1550615505.713 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:46.585Z,1550615506.585 [NavChartDb](INFO): # of records loaded: 5000 2019-02-19T22:31:50.393Z,1550615510.393 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:50.394Z,1550615510.394 [Micromodem](INFO): unknown deviceResponse_: $CATMG,1902-02-23T01:51:33Z,RTC,RTC*72 2019-02-19T22:31:50.395Z,1550615510.395 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015143,AUV,2.0.27690*17 2019-02-19T22:31:50.993Z,1550615510.993 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:51.002Z,1550615511.002 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:51.221Z,1550615511.221 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-02-19T22:31:51.545Z,1550615511.545 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:51.548Z,1550615511.548 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:51.578Z,1550615511.578 [NAL9602](INFO): NAL9602 initialized 2019-02-19T22:31:51.977Z,1550615511.977 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:51.983Z,1550615511.983 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:52.537Z,1550615512.537 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:52.542Z,1550615512.542 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:52.573Z,1550615512.573 [NAL9602](DEBUG): Fix Requested 2019-02-19T22:31:53.073Z,1550615513.073 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-02-19T22:31:53.073Z,1550615513.073 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-02-19T22:31:53.073Z,1550615513.073 [AHRS_M2] Communications Fault, FailCount= 3 2019-02-19T22:31:53.073Z,1550615513.073 [AHRS_M2](ERROR): Communications Fault 2019-02-19T22:31:53.078Z,1550615513.078 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:53.598Z,1550615513.598 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-02-19T22:31:53.702Z,1550615513.702 [AHRS_M2](INFO): Powering down 2019-02-19T22:31:53.778Z,1550615513.778 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:53.779Z,1550615513.779 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,015143,AUV,2.0.27690*17 , available: $CAREV,015143,COPROC,0.10.0.46*42 2019-02-19T22:31:53.779Z,1550615513.779 [Micromodem] Communications Fault, FailCount= 2 2019-02-19T22:31:53.779Z,1550615513.779 [Micromodem](ERROR): Communications Fault 2019-02-19T22:31:53.970Z,1550615513.970 [CBIT](ERROR): Communications Fault in component: Micromodem 2019-02-19T22:31:53.990Z,1550615513.990 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.010919 CHAN A1 (24V): -0.027652 CHAN A2 (12V): -0.007604 CHAN A3 (5V): -0.002048 CHAN B0 (3.3V): 0.000349 CHAN B1 (3.15aV): -0.000132 CHAN B2 (3.15bV): 0.000134 CHAN B3 (GND): 0.001951 OPEN: 0.005261 Full Scale Calc: 4.765 mA, -1.589 mA 2019-02-19T22:31:54.106Z,1550615514.106 [Micromodem](INFO): Powering down 2019-02-19T22:31:54.646Z,1550615514.646 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-02-19T22:31:54.646Z,1550615514.646 [AHRS_M2] No Fault, FailCount= 3 2019-02-19T22:31:54.908Z,1550615514.908 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-02-19T22:31:55.077Z,1550615515.077 [CBIT](INFO): Clearing failed state for component Micromodem 2019-02-19T22:31:55.077Z,1550615515.077 [Micromodem] No Fault, FailCount= 2 2019-02-19T22:31:57.742Z,1550615517.742 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:57.742Z,1550615517.742 [Micromodem](INFO): Powering up 2019-02-19T22:31:57.742Z,1550615517.742 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-19T22:31:58.170Z,1550615518.170 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:58.638Z,1550615518.638 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:58.976Z,1550615518.976 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:59.434Z,1550615519.434 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:31:59.838Z,1550615519.838 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:00.238Z,1550615520.238 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:00.582Z,1550615520.582 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:00.986Z,1550615520.986 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:01.390Z,1550615521.390 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:01.802Z,1550615521.802 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:01.803Z,1550615521.803 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015157,INIT,2.0.27690*4A 2019-02-19T22:32:01.805Z,1550615521.805 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015157,SLOT1,2.0.27690*65 2019-02-19T22:32:01.806Z,1550615521.806 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015157,AUV,2.0.27690*12 2019-02-19T22:32:01.807Z,1550615521.807 [Micromodem](INFO): unknown deviceResponse_: $CAREV,015157,COPROC,0.10.0.46*47 2019-02-19T22:32:02.214Z,1550615522.214 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:02.606Z,1550615522.606 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:02.606Z,1550615522.606 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:03.006Z,1550615523.006 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:03.414Z,1550615523.414 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:03.818Z,1550615523.818 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:04.218Z,1550615524.218 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:04.622Z,1550615524.622 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:05.026Z,1550615525.026 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:05.449Z,1550615525.449 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:05.836Z,1550615525.836 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:06.238Z,1550615526.238 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:06.634Z,1550615526.634 [CommandLine](IMPORTANT): got command stop 2019-02-19T22:32:06.634Z,1550615526.634 [CommandLine](IMPORTANT): Scheduling is paused 2019-02-19T22:32:06.654Z,1550615526.654 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:07.046Z,1550615527.046 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:07.458Z,1550615527.458 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:07.861Z,1550615527.861 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:08.253Z,1550615528.253 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2019-02-19T22:32:08.253Z,1550615528.253 [AHRS_M2] Data Fault, FailCount= 4 2019-02-19T22:32:08.253Z,1550615528.253 [AHRS_M2](ERROR): Data Fault 2019-02-19T22:32:08.262Z,1550615528.262 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:08.438Z,1550615528.438 [CBIT](ERROR): Data Fault in component: AHRS_M2 2019-02-19T22:32:08.644Z,1550615528.644 [AHRS_M2](INFO): Powering down 2019-02-19T22:32:08.722Z,1550615528.722 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:09.054Z,1550615529.054 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:09.130Z,1550615529.130 [CommandLine](IMPORTANT): got command quit 2019-02-19T22:32:09.455Z,1550615529.455 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:09.487Z,1550615529.487 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-02-19T22:32:09.487Z,1550615529.487 [AHRS_M2] No Fault, FailCount= 4 2019-02-19T22:32:09.856Z,1550615529.856 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-02-19T22:32:09.870Z,1550615529.870 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:10.133Z,1550615530.133 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-19T22:32:10.133Z,1550615530.133 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:10.266Z,1550615530.266 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:10.325Z,1550615530.325 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-02-19T22:32:10.325Z,1550615530.325 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:10.326Z,1550615530.326 [CommandLine](INFO): Join timeout helper Thread ID is 7940 2019-02-19T22:32:10.367Z,1550615530.367 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-02-19T22:32:10.367Z,1550615530.367 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:10.367Z,1550615530.367 [NavChartDb](INFO): Join timeout helper Thread ID is 7941 2019-02-19T22:32:10.369Z,1550615530.369 [NavChartDb](INFO): # of records loaded: 7537 2019-02-19T22:32:10.376Z,1550615530.376 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000 2019-02-19T22:32:10.376Z,1550615530.376 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-19T22:32:10.377Z,1550615530.377 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:10.393Z,1550615530.393 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2019-02-19T22:32:10.393Z,1550615530.393 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:10.393Z,1550615530.393 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7942 2019-02-19T22:32:10.670Z,1550615530.670 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:10.697Z,1550615530.697 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-19T22:32:10.697Z,1550615530.697 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-02-19T22:32:10.698Z,1550615530.698 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:10.717Z,1550615530.717 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-02-19T22:32:10.717Z,1550615530.717 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:10.717Z,1550615530.717 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7943 2019-02-19T22:32:10.873Z,1550615530.873 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-19T22:32:10.873Z,1550615530.873 [CTD_NeilBrown](INFO): Powering down 2019-02-19T22:32:10.885Z,1550615530.885 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:10.893Z,1550615530.893 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-02-19T22:32:10.893Z,1550615530.893 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:10.893Z,1550615530.893 [Radio_Surface](INFO): Join timeout helper Thread ID is 7944 2019-02-19T22:32:11.069Z,1550615531.069 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-19T22:32:11.069Z,1550615531.069 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:11.074Z,1550615531.074 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-02-19T22:32:11.074Z,1550615531.074 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:11.074Z,1550615531.074 [logger](INFO): Join timeout helper Thread ID is 7945 2019-02-19T22:32:11.082Z,1550615531.082 [Micromodem](ERROR): Could not read configuration setting for local address, using0 2019-02-19T22:32:11.161Z,1550615531.161 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-19T22:32:11.161Z,1550615531.161 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:11.183Z,1550615531.183 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-02-19T22:32:11.183Z,1550615531.183 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:11.184Z,1550615531.184 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-02-19T22:32:11.184Z,1550615531.184 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:11.185Z,1550615531.185 [controlThread](INFO): Join timeout helper Thread ID is 7946 2019-02-19T22:32:11.469Z,1550615531.469 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-19T22:32:11.469Z,1550615531.469 [controlThread](DEBUG): Uninitializing ControlThread 2019-02-19T22:32:11.469Z,1550615531.469 [AHRS_M2](INFO): Powering down 2019-02-19T22:32:11.542Z,1550615531.542 [DUSBL_Hydroid](INFO): Powering down 2019-02-19T22:32:11.633Z,1550615531.633 [Micromodem](INFO): Powering down 2019-02-19T22:32:11.729Z,1550615531.729 [NAL9602](INFO): Powering down 2019-02-19T22:32:11.801Z,1550615531.801 [RDI_Pathfinder](INFO): Powering down 2019-02-19T22:32:11.802Z,1550615531.802 [RDI_PathfinderUp](INFO): Powering down 2019-02-19T22:32:11.803Z,1550615531.803 [Aanderaa_O2](INFO): Powering down 2019-02-19T22:32:11.804Z,1550615531.804 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-02-19T22:32:11.805Z,1550615531.805 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-02-19T22:32:11.806Z,1550615531.806 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-02-19T22:32:11.807Z,1550615531.807 [MissionManager](INFO): Uninitializing Mission Default 2019-02-19T22:32:11.809Z,1550615531.809 [MissionManager](INFO): Uninitializing Mission Startup 2019-02-19T22:32:11.810Z,1550615531.810 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-02-19T22:32:11.811Z,1550615531.811 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-02-19T22:32:11.811Z,1550615531.811 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-02-19T22:32:11.811Z,1550615531.811 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-02-19T22:32:11.811Z,1550615531.811 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-02-19T22:32:11.811Z,1550615531.811 [BuoyancyServo](INFO): Powering down 2019-02-19T22:32:11.825Z,1550615531.825 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-02-19T22:32:11.825Z,1550615531.825 [ElevatorServo](INFO): Powering down 2019-02-19T22:32:11.826Z,1550615531.826 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-19T22:32:11.826Z,1550615531.826 [MassServo](INFO): Powering down 2019-02-19T22:32:11.827Z,1550615531.827 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-02-19T22:32:11.827Z,1550615531.827 [RudderServo](INFO): Powering down 2019-02-19T22:32:11.827Z,1550615531.827 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-02-19T22:32:11.828Z,1550615531.828 [ThrusterServo](INFO): Powering down 2019-02-19T22:32:11.828Z,1550615531.828 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-02-19T22:32:11.829Z,1550615531.829 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-02-19T22:32:11.829Z,1550615531.829 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-02-19T22:32:11.829Z,1550615531.829 [CBIT](DEBUG): Powering off loads. 2019-02-19T22:32:11.841Z,1550615531.841 [CBIT](DEBUG): Disabling WDT. 2019-02-19T22:32:11.853Z,1550615531.853 [CBIT](DEBUG): Opening all GF detection circuits. 2019-02-19T22:32:11.853Z,1550615531.853 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:11.919Z,1550615531.919 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:11.968Z,1550615531.968 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:11.971Z,1550615531.971 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:12.023Z,1550615532.023 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-19T22:32:12.065Z,1550615532.065 [logger ThreadHandler](INFO): Thread cancelled.