2019-02-21T22:25:23.517Z,1550787923.517 [Supervisor](DEBUG): Initializing supervisor. 2019-02-21T22:25:23.529Z,1550787923.529 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-02-21T22:25:23.529Z,1550787923.529 [SyncHandler](INFO): Protected caller Thread ID is 806 2019-02-21T22:25:23.530Z,1550787923.530 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-02-21T22:25:23.541Z,1550787923.541 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-02-21T22:25:23.541Z,1550787923.541 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2019-02-21T22:25:23.544Z,1550787923.544 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-02-21T22:25:23.566Z,1550787923.566 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-02-21T22:25:23.577Z,1550787923.577 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-02-21T22:25:23.577Z,1550787923.577 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2019-02-21T22:25:23.578Z,1550787923.578 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-02-21T22:25:23.589Z,1550787923.589 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-02-21T22:25:23.589Z,1550787923.589 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2019-02-21T22:25:23.591Z,1550787923.591 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-02-21T22:25:23.592Z,1550787923.592 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-02-21T22:25:23.595Z,1550787923.595 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-02-21T22:25:24.292Z,1550787924.292 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-02-21T22:25:24.294Z,1550787924.294 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-02-21T22:25:24.393Z,1550787924.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-02-21T22:25:24.395Z,1550787924.395 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-02-21T22:25:24.726Z,1550787924.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-02-21T22:25:24.726Z,1550787924.726 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-02-21T22:25:24.867Z,1550787924.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-02-21T22:25:24.868Z,1550787924.868 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-02-21T22:25:25.063Z,1550787925.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-02-21T22:25:25.064Z,1550787925.064 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-02-21T22:25:25.922Z,1550787925.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-02-21T22:25:25.923Z,1550787925.923 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-02-21T22:25:26.131Z,1550787926.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-02-21T22:25:26.131Z,1550787926.131 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-02-21T22:25:26.279Z,1550787926.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-02-21T22:25:26.279Z,1550787926.279 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-02-21T22:25:26.479Z,1550787926.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-02-21T22:25:26.480Z,1550787926.480 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-02-21T22:25:26.577Z,1550787926.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-02-21T22:25:26.578Z,1550787926.578 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-02-21T22:25:26.895Z,1550787926.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-02-21T22:25:26.896Z,1550787926.896 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-02-21T22:25:26.978Z,1550787926.978 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-02-21T22:25:27.082Z,1550787927.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-02-21T22:25:27.083Z,1550787927.083 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-02-21T22:25:27.580Z,1550787927.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-02-21T22:25:27.580Z,1550787927.580 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-02-21T22:25:27.981Z,1550787927.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-02-21T22:25:27.983Z,1550787927.983 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2019-02-21T22:25:27.987Z,1550787927.987 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2019-02-21T22:25:28.200Z,1550787928.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2019-02-21T22:25:28.302Z,1550787928.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2019-02-21T22:25:28.402Z,1550787928.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2019-02-21T22:25:28.629Z,1550787928.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-02-21T22:25:28.630Z,1550787928.630 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2019-02-21T22:25:28.716Z,1550787928.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2019-02-21T22:25:28.810Z,1550787928.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2019-02-21T22:25:28.911Z,1550787928.911 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2019-02-21T22:25:28.994Z,1550787928.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2019-02-21T22:25:29.105Z,1550787929.105 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2019-02-21T22:25:29.278Z,1550787929.278 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2019-02-21T22:25:29.411Z,1550787929.411 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-02-21T22:25:29.415Z,1550787929.415 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-02-21T22:25:30.015Z,1550787930.015 [DataOverHttps] Loaded 2019-02-21T22:25:30.015Z,1550787930.015 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2019-02-21T22:25:30.052Z,1550787930.052 [Depth_Keller] Loaded 2019-02-21T22:25:30.052Z,1550787930.052 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-02-21T22:25:30.057Z,1550787930.057 [DropWeight] Loaded 2019-02-21T22:25:30.057Z,1550787930.057 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-02-21T22:25:30.105Z,1550787930.105 [DUSBL_Hydroid] Loaded 2019-02-21T22:25:30.105Z,1550787930.105 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2019-02-21T22:25:30.146Z,1550787930.146 [Micromodem] Loaded 2019-02-21T22:25:30.146Z,1550787930.146 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread. 2019-02-21T22:25:30.242Z,1550787930.242 [NAL9602] Loaded 2019-02-21T22:25:30.242Z,1550787930.242 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-02-21T22:25:30.258Z,1550787930.258 [Onboard] Loaded 2019-02-21T22:25:30.258Z,1550787930.258 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-02-21T22:25:30.268Z,1550787930.268 [Radio_Surface] Loaded 2019-02-21T22:25:30.269Z,1550787930.269 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-02-21T22:25:30.270Z,1550787930.270 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407534E0 2019-02-21T22:25:30.270Z,1550787930.270 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 888 2019-02-21T22:25:30.299Z,1550787930.299 [RDI_Pathfinder] Loaded 2019-02-21T22:25:30.299Z,1550787930.299 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-02-21T22:25:30.321Z,1550787930.321 [RDI_PathfinderUp] Loaded 2019-02-21T22:25:30.321Z,1550787930.321 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2019-02-21T22:25:32.928Z,1550787932.928 [BPC1] Loaded 2019-02-21T22:25:32.928Z,1550787932.928 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-02-21T22:25:32.929Z,1550787932.929 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-02-21T22:25:32.929Z,1550787932.929 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-02-21T22:25:32.953Z,1550787932.953 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-02-21T22:25:32.954Z,1550787932.954 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-02-21T22:25:33.077Z,1550787933.077 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-02-21T22:25:33.077Z,1550787933.077 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-02-21T22:25:33.136Z,1550787933.136 [DeadReckonUsingSpeedCalculator] Loaded 2019-02-21T22:25:33.136Z,1550787933.136 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-02-21T22:25:33.195Z,1550787933.195 [DeadReckonWithRespectToSeafloor] Loaded 2019-02-21T22:25:33.195Z,1550787933.195 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-02-21T22:25:33.211Z,1550787933.211 [NavChart] Loaded 2019-02-21T22:25:33.211Z,1550787933.211 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-02-21T22:25:33.215Z,1550787933.215 [UniversalFixResidualReporter] Loaded 2019-02-21T22:25:33.215Z,1550787933.215 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-02-21T22:25:33.216Z,1550787933.216 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-02-21T22:25:33.217Z,1550787933.217 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-02-21T22:25:33.339Z,1550787933.339 [BuoyancyServo] Loaded 2019-02-21T22:25:33.340Z,1550787933.340 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-02-21T22:25:33.355Z,1550787933.355 [ElevatorServo] Loaded 2019-02-21T22:25:33.355Z,1550787933.355 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-02-21T22:25:33.370Z,1550787933.370 [MassServo] Loaded 2019-02-21T22:25:33.370Z,1550787933.370 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-02-21T22:25:33.386Z,1550787933.386 [RudderServo] Loaded 2019-02-21T22:25:33.386Z,1550787933.386 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-02-21T22:25:33.400Z,1550787933.400 [ThrusterServo] Loaded 2019-02-21T22:25:33.401Z,1550787933.401 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-02-21T22:25:33.401Z,1550787933.401 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-02-21T22:25:33.402Z,1550787933.402 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-02-21T22:25:33.635Z,1550787933.635 [Aanderaa_O2] Loaded 2019-02-21T22:25:33.635Z,1550787933.635 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-02-21T22:25:33.719Z,1550787933.719 [CTD_NeilBrown] Loaded 2019-02-21T22:25:33.719Z,1550787933.719 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-02-21T22:25:33.720Z,1550787933.720 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408624E0 2019-02-21T22:25:33.721Z,1550787933.721 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 889 2019-02-21T22:25:33.765Z,1550787933.765 [WetLabsSeaOWL_UV_A] Loaded 2019-02-21T22:25:33.765Z,1550787933.765 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2019-02-21T22:25:33.766Z,1550787933.766 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408924E0 2019-02-21T22:25:33.767Z,1550787933.767 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 890 2019-02-21T22:25:33.767Z,1550787933.767 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-02-21T22:25:33.768Z,1550787933.768 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-02-21T22:25:34.049Z,1550787934.049 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-02-21T22:25:34.050Z,1550787934.050 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-02-21T22:25:34.105Z,1550787934.105 [DepthRateCalculator] Loaded 2019-02-21T22:25:34.105Z,1550787934.105 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-02-21T22:25:34.111Z,1550787934.111 [PitchRateCalculator] Loaded 2019-02-21T22:25:34.112Z,1550787934.112 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-02-21T22:25:34.124Z,1550787934.124 [SpeedCalculator] Loaded 2019-02-21T22:25:34.124Z,1550787934.124 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-02-21T22:25:34.145Z,1550787934.145 [TempGradientCalculator] Loaded 2019-02-21T22:25:34.145Z,1550787934.145 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-02-21T22:25:34.151Z,1550787934.151 [YawRateCalculator] Loaded 2019-02-21T22:25:34.152Z,1550787934.152 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-02-21T22:25:34.192Z,1550787934.192 [ElevatorOffsetCalculator] Loaded 2019-02-21T22:25:34.192Z,1550787934.192 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-02-21T22:25:34.193Z,1550787934.193 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-02-21T22:25:34.193Z,1550787934.193 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-02-21T22:25:34.352Z,1550787934.352 [SBIT](DEBUG): Construct Startup Built In Test. 2019-02-21T22:25:34.374Z,1550787934.374 [SBIT] Loaded 2019-02-21T22:25:34.375Z,1550787934.375 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-02-21T22:25:34.375Z,1550787934.375 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-02-21T22:25:34.387Z,1550787934.387 [IBIT] Loaded 2019-02-21T22:25:34.387Z,1550787934.387 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-02-21T22:25:34.390Z,1550787934.390 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-02-21T22:25:34.530Z,1550787934.530 [CBIT] Loaded 2019-02-21T22:25:34.530Z,1550787934.530 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-02-21T22:25:34.531Z,1550787934.531 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-02-21T22:25:34.532Z,1550787934.532 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-02-21T22:25:34.634Z,1550787934.634 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-02-21T22:25:34.635Z,1550787934.635 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-02-21T22:25:34.759Z,1550787934.759 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-02-21T22:25:34.759Z,1550787934.759 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-02-21T22:25:34.848Z,1550787934.848 [VerticalControl](DEBUG): Construct VerticalControl. 2019-02-21T22:25:34.931Z,1550787934.931 [VerticalControl] Loaded 2019-02-21T22:25:34.931Z,1550787934.931 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-02-21T22:25:34.932Z,1550787934.932 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-02-21T22:25:34.990Z,1550787934.990 [HorizontalControl] Loaded 2019-02-21T22:25:34.990Z,1550787934.990 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-02-21T22:25:34.991Z,1550787934.991 [SpeedControl](DEBUG): Construct SpeedControl. 2019-02-21T22:25:34.992Z,1550787934.992 [SpeedControl] Loaded 2019-02-21T22:25:34.993Z,1550787934.993 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-02-21T22:25:34.994Z,1550787934.994 [LoopControl](DEBUG): Construct LoopControl. 2019-02-21T22:25:34.994Z,1550787934.994 [LoopControl] Loaded 2019-02-21T22:25:34.994Z,1550787934.994 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-02-21T22:25:34.995Z,1550787934.995 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-02-21T22:25:34.995Z,1550787934.995 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-02-21T22:25:35.037Z,1550787935.037 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-02-21T22:25:35.041Z,1550787935.041 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-02-21T22:25:35.042Z,1550787935.042 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-02-21T22:25:35.048Z,1550787935.048 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-02-21T22:25:35.049Z,1550787935.049 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0 2019-02-21T22:25:35.050Z,1550787935.050 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 891 2019-02-21T22:25:35.055Z,1550787935.055 [Supervisor](INFO): Main Thread ID is 802 2019-02-21T22:25:35.055Z,1550787935.055 [Supervisor](DEBUG): Running supervisor. 2019-02-21T22:25:35.055Z,1550787935.055 [CommandLine ThreadHandler](INFO): Handler Thread ID is 892 2019-02-21T22:25:35.058Z,1550787935.058 [controlThread ThreadHandler](INFO): Handler Thread ID is 893 2019-02-21T22:25:35.058Z,1550787935.058 [controlThread](DEBUG): Initializing ControlThread 2019-02-21T22:25:35.064Z,1550787935.064 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-21T22:25:35.065Z,1550787935.065 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-02-21T22:25:35.066Z,1550787935.066 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-02-21T22:25:35.067Z,1550787935.067 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-02-21T22:25:35.068Z,1550787935.068 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-02-21T22:25:35.068Z,1550787935.068 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-02-21T22:25:35.068Z,1550787935.068 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-02-21T22:25:35.069Z,1550787935.069 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-02-21T22:25:35.069Z,1550787935.069 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-02-21T22:25:35.070Z,1550787935.070 [SBIT](INFO): Initialize SBIT Component. 2019-02-21T22:25:35.070Z,1550787935.070 [SBIT](IMPORTANT): git: 2019-02-11-13-g62924c4 2019-02-21T22:25:35.070Z,1550787935.070 [SBIT](INFO): git hash: 62924c492e86bb9d7cd72440302a80a745358b26 2019-02-21T22:25:35.071Z,1550787935.071 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-02-21T22:25:35.071Z,1550787935.071 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2019-02-21T22:25:35.072Z,1550787935.072 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-02-21T22:25:35.073Z,1550787935.073 [IBIT](INFO): Initialize IBIT Component. 2019-02-21T22:25:35.074Z,1550787935.074 [CBIT](DEBUG): Initialize CBIT Component. 2019-02-21T22:25:35.075Z,1550787935.075 [logger ThreadHandler](INFO): Handler Thread ID is 894 2019-02-21T22:25:35.085Z,1550787935.085 [CBIT](DEBUG): Initialized mux pins. 2019-02-21T22:25:35.085Z,1550787935.085 [CBIT](DEBUG): Initializing the watchdog timer. 2019-02-21T22:25:35.093Z,1550787935.093 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 895 2019-02-21T22:25:35.094Z,1550787935.094 [Radio_Surface](INFO): Powering up 2019-02-21T22:25:35.105Z,1550787935.105 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 896 2019-02-21T22:25:35.106Z,1550787935.106 [CTD_NeilBrown](INFO): Powering down 2019-02-21T22:25:35.113Z,1550787935.113 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-02-21T22:25:35.113Z,1550787935.113 [CBIT](DEBUG): Initializing heartbeat. 2019-02-21T22:25:35.133Z,1550787935.133 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 897 2019-02-21T22:25:35.134Z,1550787935.134 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-02-21T22:25:35.169Z,1550787935.169 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 898 2019-02-21T22:25:35.178Z,1550787935.178 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-21T22:25:35.178Z,1550787935.178 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-02-21T22:25:35.178Z,1550787935.178 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-21T22:25:35.178Z,1550787935.178 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-02-21T22:25:35.178Z,1550787935.178 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-21T22:25:35.178Z,1550787935.178 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-02-21T22:25:35.179Z,1550787935.179 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-21T22:25:35.179Z,1550787935.179 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-02-21T22:25:35.179Z,1550787935.179 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-21T22:25:35.179Z,1550787935.179 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-02-21T22:25:35.179Z,1550787935.179 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-21T22:25:35.180Z,1550787935.180 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-02-21T22:25:35.180Z,1550787935.180 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-21T22:25:35.180Z,1550787935.180 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-02-21T22:25:35.180Z,1550787935.180 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-21T22:25:35.180Z,1550787935.180 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-02-21T22:25:35.185Z,1550787935.185 [CBIT](DEBUG): Deactivating GF circuits. 2019-02-21T22:25:35.185Z,1550787935.185 [CBIT](DEBUG): Deactivating emergency mode. 2019-02-21T22:25:35.221Z,1550787935.221 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-02-21T22:25:35.223Z,1550787935.223 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-02-21T22:25:35.223Z,1550787935.223 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-02-21T22:25:35.224Z,1550787935.224 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-02-21T22:25:35.225Z,1550787935.225 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-02-21T22:25:35.248Z,1550787935.248 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-21T22:25:35.290Z,1550787935.290 [MissionManager](DEBUG): 2019-02-21T22:25:35.291Z,1550787935.291 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-02-21T22:25:35.348Z,1550787935.348 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-02-21T22:25:35.365Z,1550787935.365 [Default:A.Wait](DEBUG): Construct Wait. 2019-02-21T22:25:35.367Z,1550787935.367 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-21T22:25:35.405Z,1550787935.405 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-02-21T22:25:35.408Z,1550787935.408 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-02-21T22:25:35.430Z,1550787935.430 [Default:E.Execute](DEBUG): Construct Execute. 2019-02-21T22:25:35.449Z,1550787935.449 [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-21T22:25:35.454Z,1550787935.454 [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-21T22:25:35.459Z,1550787935.459 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-02-21T22:25:35.482Z,1550787935.482 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2019-02-21T22:25:35.483Z,1550787935.483 [DUSBL_Hydroid](INFO): Powering up 2019-02-21T22:25:35.483Z,1550787935.483 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-21T22:25:35.505Z,1550787935.505 [Micromodem](INFO): Start 2019-02-21T22:25:35.569Z,1550787935.569 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-21T22:25:35.903Z,1550787935.903 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-21T22:25:35.909Z,1550787935.909 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-21T22:25:35.910Z,1550787935.910 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-02-21T22:25:35.917Z,1550787935.917 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-02-21T22:25:35.918Z,1550787935.918 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-21T22:25:35.925Z,1550787935.925 [MassServo](DEBUG): Initializing MassServo. 2019-02-21T22:25:35.926Z,1550787935.926 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-21T22:25:35.933Z,1550787935.933 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-21T22:25:35.934Z,1550787935.934 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-02-21T22:25:35.941Z,1550787935.941 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-02-21T22:25:36.002Z,1550787936.002 [Micromodem](INFO): Starting 2019-02-21T22:25:36.002Z,1550787936.002 [Micromodem](INFO): Powering up 2019-02-21T22:25:36.003Z,1550787936.003 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-21T22:25:36.105Z,1550787936.105 [RDI_PathfinderUp](INFO): Powering down 2019-02-21T22:25:36.157Z,1550787936.157 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-21T22:25:36.358Z,1550787936.358 [Micromodem](INFO): Starting 2019-02-21T22:25:36.377Z,1550787936.377 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-21T22:25:36.763Z,1550787936.763 [Micromodem](INFO): Starting 2019-02-21T22:25:37.170Z,1550787937.170 [Micromodem](INFO): Starting 2019-02-21T22:25:37.570Z,1550787937.570 [Micromodem](INFO): Starting 2019-02-21T22:25:37.974Z,1550787937.974 [Micromodem](INFO): Starting 2019-02-21T22:25:38.378Z,1550787938.378 [Micromodem](INFO): Starting 2019-02-21T22:25:38.783Z,1550787938.783 [Micromodem](INFO): Starting 2019-02-21T22:25:38.921Z,1550787938.921 [Aanderaa_O2](INFO): Powering down 2019-02-21T22:25:39.186Z,1550787939.186 [Micromodem](INFO): Starting 2019-02-21T22:25:39.590Z,1550787939.590 [Micromodem](INFO): Starting 2019-02-21T22:25:39.000Z,1550787940.000 [Micromodem](INFO): Starting 2019-02-21T22:25:40.003Z,1550787940.003 [Micromodem](INFO): Nmea in: $CAREV,014343,INIT,2.0.27690*4C 2019-02-21T22:25:40.398Z,1550787940.398 [Micromodem](INFO): Starting 2019-02-21T22:25:40.400Z,1550787940.400 [Micromodem](INFO): Nmea in: $CAREV,014343,SLOT1,2.0.27690*63 2019-02-21T22:25:40.802Z,1550787940.802 [Micromodem](INFO): Starting 2019-02-21T22:25:40.803Z,1550787940.803 [Micromodem](INFO): Nmea in: $CAREV,014343,AUV,2.0.27690*14 2019-02-21T22:25:40.804Z,1550787940.804 [Micromodem](INFO): Starting 2019-02-21T22:25:40.805Z,1550787940.805 [Micromodem](INFO): Nmea in: $CAREV,014343,COPROC,0.10.0.46*41 2019-02-21T22:25:40.805Z,1550787940.805 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:25:40.805Z,1550787940.805 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45 2019-02-21T22:25:40.806Z,1550787940.806 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:25:40.807Z,1550787940.807 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:25:40.808Z,1550787940.808 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53 2019-02-21T22:25:40.811Z,1550787940.811 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:25:40.811Z,1550787940.811 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51 2019-02-21T22:25:40.813Z,1550787940.813 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:25:40.813Z,1550787940.813 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A 2019-02-21T22:25:40.814Z,1550787940.814 [Micromodem](INFO): Nmea cmd: $CCMSC 2019-02-21T22:25:40.814Z,1550787940.814 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40 2019-02-21T22:25:41.206Z,1550787941.206 [Micromodem](INFO): Starting 2019-02-21T22:25:41.208Z,1550787941.208 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:43:43Z,RTC,RTC*70 2019-02-21T22:25:41.209Z,1550787941.209 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:43:43Z,RTC,RTC*70 2019-02-21T22:25:41.610Z,1550787941.610 [Micromodem](INFO): Starting 2019-02-21T22:25:42.014Z,1550787942.014 [Micromodem](INFO): Starting 2019-02-21T22:25:42.015Z,1550787942.015 [Micromodem](INFO): Nmea cmd: $CCTMS 2019-02-21T22:25:42.015Z,1550787942.015 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:25:43Z,2*8D 2019-02-21T22:25:42.016Z,1550787942.016 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:25:42.418Z,1550787942.418 [Micromodem](INFO): Starting 2019-02-21T22:25:42.420Z,1550787942.420 [Micromodem](INFO): Nmea in: $CAERR,014344,NI ,17,Argument value out of range*00 2019-02-21T22:25:42.822Z,1550787942.822 [Micromodem](INFO): Starting 2019-02-21T22:25:43.226Z,1550787943.226 [Micromodem](INFO): Starting 2019-02-21T22:25:43.630Z,1550787943.630 [Micromodem](INFO): Starting 2019-02-21T22:25:44.034Z,1550787944.034 [Micromodem](INFO): Starting 2019-02-21T22:25:44.438Z,1550787944.438 [Micromodem](INFO): Starting 2019-02-21T22:25:44.842Z,1550787944.842 [Micromodem](INFO): Starting 2019-02-21T22:25:45.246Z,1550787945.246 [Micromodem](INFO): Starting 2019-02-21T22:25:45.650Z,1550787945.650 [Micromodem](INFO): Starting 2019-02-21T22:25:46.054Z,1550787946.054 [Micromodem](INFO): Starting 2019-02-21T22:25:46.458Z,1550787946.458 [Micromodem](INFO): Starting 2019-02-21T22:25:46.865Z,1550787946.865 [Micromodem](INFO): Starting 2019-02-21T22:25:47.266Z,1550787947.266 [Micromodem](INFO): Starting 2019-02-21T22:25:47.670Z,1550787947.670 [Micromodem](INFO): Starting 2019-02-21T22:25:48.074Z,1550787948.074 [Micromodem](INFO): Starting 2019-02-21T22:25:48.478Z,1550787948.478 [Micromodem](INFO): Starting 2019-02-21T22:25:48.883Z,1550787948.883 [Micromodem](INFO): Starting 2019-02-21T22:25:48.908Z,1550787948.908 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-02-21T22:25:49.287Z,1550787949.287 [Micromodem](INFO): Starting 2019-02-21T22:25:49.690Z,1550787949.690 [Micromodem](INFO): Starting 2019-02-21T22:25:50.094Z,1550787950.094 [Micromodem](INFO): Starting 2019-02-21T22:25:50.096Z,1550787950.096 [Micromodem](INFO): Nmea in: $CAERR,014345,NI ,17,Argument value out of range*01 2019-02-21T22:25:50.498Z,1550787950.498 [Micromodem](INFO): Starting 2019-02-21T22:25:50.499Z,1550787950.499 [Micromodem](INFO): Nmea in: $CAREV,014353,AUV,2.0.27690*15 2019-02-21T22:25:50.902Z,1550787950.902 [Micromodem](INFO): Starting 2019-02-21T22:25:51.306Z,1550787951.306 [Micromodem](INFO): Starting 2019-02-21T22:25:51.714Z,1550787951.714 [Micromodem](INFO): Starting 2019-02-21T22:25:52.114Z,1550787952.114 [Micromodem](INFO): Starting 2019-02-21T22:25:52.519Z,1550787952.519 [Micromodem](INFO): Starting 2019-02-21T22:25:52.935Z,1550787952.935 [Micromodem](INFO): Starting 2019-02-21T22:25:53.326Z,1550787953.326 [DUSBL_Hydroid](INFO): DUSBL Version:O 2019-02-21T22:25:53.341Z,1550787953.341 [Micromodem](INFO): Starting 2019-02-21T22:25:53.742Z,1550787953.742 [Micromodem](INFO): Starting 2019-02-21T22:25:54.134Z,1550787954.134 [Micromodem](INFO): Starting 2019-02-21T22:25:54.538Z,1550787954.538 [Micromodem](INFO): Starting 2019-02-21T22:25:54.943Z,1550787954.943 [Micromodem](INFO): Starting 2019-02-21T22:25:55.346Z,1550787955.346 [Micromodem](INFO): Starting 2019-02-21T22:25:55.754Z,1550787955.754 [Micromodem](INFO): Starting 2019-02-21T22:25:55.755Z,1550787955.755 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,014353,AUV,2.0.27690*15 , available: $CAREV,014353,COPROC,0.10.0.46*40 2019-02-21T22:25:55.755Z,1550787955.755 [Micromodem] Communications Fault, FailCount= 1 2019-02-21T22:25:55.755Z,1550787955.755 [Micromodem](ERROR): Communications Fault 2019-02-21T22:25:55.806Z,1550787955.806 [CBIT](ERROR): Communications Fault in component: Micromodem 2019-02-21T22:25:56.154Z,1550787956.154 [Micromodem](INFO): Stop 2019-02-21T22:25:56.154Z,1550787956.154 [Micromodem](INFO): uninitialize 2019-02-21T22:25:56.154Z,1550787956.154 [Micromodem](INFO): Powering down 2019-02-21T22:25:56.558Z,1550787956.558 [Micromodem](INFO): Stopping 2019-02-21T22:25:56.962Z,1550787956.962 [Micromodem](INFO): Stopped 2019-02-21T22:25:57.040Z,1550787957.040 [CBIT](INFO): Clearing failed state for component Micromodem 2019-02-21T22:25:57.040Z,1550787957.040 [Micromodem] No Fault, FailCount= 1 2019-02-21T22:25:57.366Z,1550787957.366 [Micromodem](INFO): Start 2019-02-21T22:25:57.770Z,1550787957.770 [Micromodem](INFO): Start 2019-02-21T22:25:58.174Z,1550787958.174 [Micromodem](INFO): Start 2019-02-21T22:25:58.578Z,1550787958.578 [Micromodem](INFO): Start 2019-02-21T22:25:58.991Z,1550787958.991 [Micromodem](INFO): Start 2019-02-21T22:25:59.386Z,1550787959.386 [Micromodem](INFO): Start 2019-02-21T22:25:59.790Z,1550787959.790 [Micromodem](INFO): Starting 2019-02-21T22:25:59.790Z,1550787959.790 [Micromodem](INFO): Powering up 2019-02-21T22:25:59.791Z,1550787959.791 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-21T22:26:00.194Z,1550787960.194 [Micromodem](INFO): Starting 2019-02-21T22:26:00.598Z,1550787960.598 [Micromodem](INFO): Starting 2019-02-21T22:26:00.599Z,1550787960.599 [NAL9602](INFO): Powering up NAL9602 2019-02-21T22:26:01.002Z,1550787961.002 [Micromodem](INFO): Starting 2019-02-21T22:26:01.406Z,1550787961.406 [Micromodem](INFO): Starting 2019-02-21T22:26:01.810Z,1550787961.810 [Micromodem](INFO): Starting 2019-02-21T22:26:02.214Z,1550787962.214 [Micromodem](INFO): Starting 2019-02-21T22:26:02.627Z,1550787962.627 [Micromodem](INFO): Starting 2019-02-21T22:26:03.018Z,1550787963.018 [Micromodem](INFO): Starting 2019-02-21T22:26:03.426Z,1550787963.426 [Micromodem](INFO): Starting 2019-02-21T22:26:03.482Z,1550787963.482 [SBIT](IMPORTANT): Beginning Startup BIT 2019-02-21T22:26:03.487Z,1550787963.487 [CBIT](IMPORTANT): Beginning ground fault scan 2019-02-21T22:26:03.830Z,1550787963.830 [Micromodem](INFO): Starting 2019-02-21T22:26:03.831Z,1550787963.831 [Micromodem](INFO): Nmea in: $CAREV,014407,INIT,2.0.27690*4B 2019-02-21T22:26:04.234Z,1550787964.234 [Micromodem](INFO): Starting 2019-02-21T22:26:04.235Z,1550787964.235 [Micromodem](INFO): Nmea in: $CAREV,014407,SLOT1,2.0.27690*64 2019-02-21T22:26:04.638Z,1550787964.638 [Micromodem](INFO): Starting 2019-02-21T22:26:04.639Z,1550787964.639 [Micromodem](INFO): Nmea in: $CAREV,014407,AUV,2.0.27690*13 2019-02-21T22:26:04.639Z,1550787964.639 [Micromodem](INFO): Starting 2019-02-21T22:26:04.640Z,1550787964.640 [Micromodem](INFO): Nmea in: $CAREV,014407,COPROC,0.10.0.46*46 2019-02-21T22:26:04.641Z,1550787964.641 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:04.641Z,1550787964.641 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45 2019-02-21T22:26:04.641Z,1550787964.641 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:26:04.641Z,1550787964.641 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:04.641Z,1550787964.641 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53 2019-02-21T22:26:04.642Z,1550787964.642 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:04.642Z,1550787964.642 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51 2019-02-21T22:26:04.642Z,1550787964.642 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:04.642Z,1550787964.642 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A 2019-02-21T22:26:04.642Z,1550787964.642 [Micromodem](INFO): Nmea cmd: $CCMSC 2019-02-21T22:26:04.643Z,1550787964.643 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40 2019-02-21T22:26:05.042Z,1550787965.042 [Micromodem](INFO): Starting 2019-02-21T22:26:05.043Z,1550787965.043 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:44:07Z,RTC,RTC*77 2019-02-21T22:26:05.045Z,1550787965.045 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:44:07Z,RTC,RTC*77 2019-02-21T22:26:05.663Z,1550787965.663 [Micromodem](INFO): Starting 2019-02-21T22:26:06.067Z,1550787966.067 [Micromodem](INFO): Starting 2019-02-21T22:26:06.068Z,1550787966.068 [Micromodem](INFO): Nmea cmd: $CCTMS 2019-02-21T22:26:06.068Z,1550787966.068 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:26:07Z,2*8E 2019-02-21T22:26:06.069Z,1550787966.069 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:26:06.470Z,1550787966.470 [Micromodem](INFO): Starting 2019-02-21T22:26:06.472Z,1550787966.472 [Micromodem](INFO): Nmea in: $CAERR,014408,NI ,17,Argument value out of range*0F 2019-02-21T22:26:06.874Z,1550787966.874 [Micromodem](INFO): Starting 2019-02-21T22:26:07.278Z,1550787967.278 [Micromodem](INFO): Starting 2019-02-21T22:26:07.682Z,1550787967.682 [Micromodem](INFO): Starting 2019-02-21T22:26:08.086Z,1550787968.086 [Micromodem](INFO): Starting 2019-02-21T22:26:08.490Z,1550787968.490 [Micromodem](INFO): Starting 2019-02-21T22:26:08.894Z,1550787968.894 [Micromodem](INFO): Starting 2019-02-21T22:26:09.298Z,1550787969.298 [Micromodem](INFO): Starting 2019-02-21T22:26:09.702Z,1550787969.702 [Micromodem](INFO): Starting 2019-02-21T22:26:10.106Z,1550787970.106 [Micromodem](INFO): Starting 2019-02-21T22:26:10.510Z,1550787970.510 [Micromodem](INFO): Starting 2019-02-21T22:26:10.914Z,1550787970.914 [Micromodem](INFO): Starting 2019-02-21T22:26:11.318Z,1550787971.318 [Micromodem](INFO): Starting 2019-02-21T22:26:11.733Z,1550787971.733 [Micromodem](INFO): Starting 2019-02-21T22:26:11.736Z,1550787971.736 [NAL9602](INFO): NAL9602 initialized 2019-02-21T22:26:12.126Z,1550787972.126 [Micromodem](INFO): Starting 2019-02-21T22:26:12.542Z,1550787972.542 [Micromodem](INFO): Starting 2019-02-21T22:26:12.557Z,1550787972.557 [NAL9602](DEBUG): Fix Requested 2019-02-21T22:26:12.934Z,1550787972.934 [Micromodem](INFO): Starting 2019-02-21T22:26:13.338Z,1550787973.338 [Micromodem](INFO): Starting 2019-02-21T22:26:13.749Z,1550787973.749 [Micromodem](INFO): Starting 2019-02-21T22:26:13.752Z,1550787973.752 [Micromodem](INFO): Nmea in: $CAERR,014409,NI ,17,Argument value out of range*0E 2019-02-21T22:26:14.146Z,1550787974.146 [Micromodem](INFO): Starting 2019-02-21T22:26:14.147Z,1550787974.147 [Micromodem](INFO): Nmea in: $CAREV,014417,AUV,2.0.27690*12 2019-02-21T22:26:14.550Z,1550787974.550 [Micromodem](INFO): Starting 2019-02-21T22:26:14.956Z,1550787974.956 [Micromodem](INFO): Starting 2019-02-21T22:26:15.050Z,1550787975.050 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005608 CHAN A1 (24V): -0.025636 CHAN A2 (12V): -0.007536 CHAN A3 (5V): -0.003126 CHAN B0 (3.3V): -0.000755 CHAN B1 (3.15aV): -0.000431 CHAN B2 (3.15bV): -0.000305 CHAN B3 (GND): 0.002492 OPEN: 0.012339 Full Scale Calc: 4.765 mA, -1.589 mA 2019-02-21T22:26:15.358Z,1550787975.358 [Micromodem](INFO): Starting 2019-02-21T22:26:15.762Z,1550787975.762 [Micromodem](INFO): Starting 2019-02-21T22:26:16.166Z,1550787976.166 [Micromodem](INFO): Starting 2019-02-21T22:26:16.570Z,1550787976.570 [Micromodem](INFO): Starting 2019-02-21T22:26:16.974Z,1550787976.974 [Micromodem](INFO): Starting 2019-02-21T22:26:17.382Z,1550787977.382 [Micromodem](INFO): Starting 2019-02-21T22:26:17.782Z,1550787977.782 [Micromodem](INFO): Starting 2019-02-21T22:26:18.186Z,1550787978.186 [Micromodem](INFO): Starting 2019-02-21T22:26:18.590Z,1550787978.590 [Micromodem](INFO): Starting 2019-02-21T22:26:19.147Z,1550787979.147 [Micromodem](INFO): Starting 2019-02-21T22:26:19.542Z,1550787979.542 [Micromodem](INFO): Starting 2019-02-21T22:26:19.543Z,1550787979.543 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,014417,AUV,2.0.27690*12 , available: $CAREV,014417,COPROC,0.10.0.46*47 2019-02-21T22:26:19.543Z,1550787979.543 [Micromodem] Communications Fault, FailCount= 2 2019-02-21T22:26:19.543Z,1550787979.543 [Micromodem](ERROR): Communications Fault 2019-02-21T22:26:19.678Z,1550787979.678 [CBIT](ERROR): Communications Fault in component: Micromodem 2019-02-21T22:26:19.950Z,1550787979.950 [Micromodem](INFO): Stop 2019-02-21T22:26:19.950Z,1550787979.950 [Micromodem](INFO): uninitialize 2019-02-21T22:26:19.950Z,1550787979.950 [Micromodem](INFO): Powering down 2019-02-21T22:26:20.363Z,1550787980.363 [Micromodem](INFO): Stopping 2019-02-21T22:26:20.758Z,1550787980.758 [Micromodem](INFO): Stopped 2019-02-21T22:26:20.910Z,1550787980.910 [CBIT](INFO): Clearing failed state for component Micromodem 2019-02-21T22:26:20.910Z,1550787980.910 [Micromodem] No Fault, FailCount= 2 2019-02-21T22:26:21.162Z,1550787981.162 [Micromodem](INFO): Start 2019-02-21T22:26:21.566Z,1550787981.566 [Micromodem](INFO): Start 2019-02-21T22:26:21.970Z,1550787981.970 [Micromodem](INFO): Start 2019-02-21T22:26:22.374Z,1550787982.374 [Micromodem](INFO): Start 2019-02-21T22:26:22.778Z,1550787982.778 [Micromodem](INFO): Start 2019-02-21T22:26:23.182Z,1550787983.182 [Micromodem](INFO): Start 2019-02-21T22:26:23.586Z,1550787983.586 [Micromodem](INFO): Starting 2019-02-21T22:26:23.586Z,1550787983.586 [Micromodem](INFO): Powering up 2019-02-21T22:26:23.586Z,1550787983.586 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-21T22:26:23.990Z,1550787983.990 [Micromodem](INFO): Starting 2019-02-21T22:26:24.394Z,1550787984.394 [Micromodem](INFO): Starting 2019-02-21T22:26:24.798Z,1550787984.798 [Micromodem](INFO): Starting 2019-02-21T22:26:25.202Z,1550787985.202 [Micromodem](INFO): Starting 2019-02-21T22:26:25.610Z,1550787985.610 [Micromodem](INFO): Starting 2019-02-21T22:26:26.014Z,1550787986.014 [Micromodem](INFO): Starting 2019-02-21T22:26:26.414Z,1550787986.414 [Micromodem](INFO): Starting 2019-02-21T22:26:26.818Z,1550787986.818 [Micromodem](INFO): Starting 2019-02-21T22:26:27.222Z,1550787987.222 [Micromodem](INFO): Starting 2019-02-21T22:26:27.623Z,1550787987.623 [Micromodem](INFO): Starting 2019-02-21T22:26:27.624Z,1550787987.624 [Micromodem](INFO): Nmea in: $CAREV,014430,INIT,2.0.27690*4F 2019-02-21T22:26:28.030Z,1550787988.030 [Micromodem](INFO): Starting 2019-02-21T22:26:28.031Z,1550787988.031 [Micromodem](INFO): Nmea in: $CAREV,014430,SLOT1,2.0.27690*60 2019-02-21T22:26:28.438Z,1550787988.438 [Micromodem](INFO): Starting 2019-02-21T22:26:28.439Z,1550787988.439 [Micromodem](INFO): Nmea in: $CAREV,014430,AUV,2.0.27690*17 2019-02-21T22:26:28.440Z,1550787988.440 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,014430,AUV,2.0.27690*17 2019-02-21T22:26:28.441Z,1550787988.441 [Micromodem](INFO): Starting 2019-02-21T22:26:28.442Z,1550787988.442 [Micromodem](INFO): Nmea in: $CAREV,014430,COPROC,0.10.0.46*42 2019-02-21T22:26:28.443Z,1550787988.443 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:28.443Z,1550787988.443 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45 2019-02-21T22:26:28.443Z,1550787988.443 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:26:28.445Z,1550787988.445 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:28.445Z,1550787988.445 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53 2019-02-21T22:26:28.447Z,1550787988.447 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:28.447Z,1550787988.447 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51 2019-02-21T22:26:28.481Z,1550787988.481 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:28.481Z,1550787988.481 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A 2019-02-21T22:26:28.482Z,1550787988.482 [Micromodem](INFO): Nmea cmd: $CCMSC 2019-02-21T22:26:28.482Z,1550787988.482 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40 2019-02-21T22:26:28.838Z,1550787988.838 [Micromodem](INFO): Starting 2019-02-21T22:26:28.839Z,1550787988.839 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:44:30Z,RTC,RTC*73 2019-02-21T22:26:28.841Z,1550787988.841 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:44:30Z,RTC,RTC*73 2019-02-21T22:26:29.246Z,1550787989.246 [Micromodem](INFO): Starting 2019-02-21T22:26:29.646Z,1550787989.646 [Micromodem](INFO): Starting 2019-02-21T22:26:29.647Z,1550787989.647 [Micromodem](INFO): Nmea cmd: $CCTMS 2019-02-21T22:26:29.647Z,1550787989.647 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:26:30Z,2*8A 2019-02-21T22:26:29.647Z,1550787989.647 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:26:30.050Z,1550787990.050 [Micromodem](INFO): Starting 2019-02-21T22:26:30.052Z,1550787990.052 [Micromodem](INFO): Nmea in: $CAERR,014431,NI ,17,Argument value out of range*05 2019-02-21T22:26:30.454Z,1550787990.454 [Micromodem](INFO): Starting 2019-02-21T22:26:30.858Z,1550787990.858 [Micromodem](INFO): Starting 2019-02-21T22:26:31.263Z,1550787991.263 [Micromodem](INFO): Starting 2019-02-21T22:26:31.666Z,1550787991.666 [Micromodem](INFO): Starting 2019-02-21T22:26:32.074Z,1550787992.074 [Micromodem](INFO): Starting 2019-02-21T22:26:32.478Z,1550787992.478 [Micromodem](INFO): Starting 2019-02-21T22:26:32.882Z,1550787992.882 [Micromodem](INFO): Starting 2019-02-21T22:26:33.286Z,1550787993.286 [Micromodem](INFO): Starting 2019-02-21T22:26:33.686Z,1550787993.686 [Micromodem](INFO): Starting 2019-02-21T22:26:34.094Z,1550787994.094 [Micromodem](INFO): Starting 2019-02-21T22:26:34.494Z,1550787994.494 [Micromodem](INFO): Starting 2019-02-21T22:26:34.894Z,1550787994.894 [Micromodem](INFO): Starting 2019-02-21T22:26:35.302Z,1550787995.302 [Micromodem](INFO): Starting 2019-02-21T22:26:35.706Z,1550787995.706 [Micromodem](INFO): Starting 2019-02-21T22:26:36.110Z,1550787996.110 [Micromodem](INFO): Starting 2019-02-21T22:26:36.514Z,1550787996.514 [Micromodem](INFO): Starting 2019-02-21T22:26:36.922Z,1550787996.922 [Micromodem](INFO): Starting 2019-02-21T22:26:37.326Z,1550787997.326 [Micromodem](INFO): Starting 2019-02-21T22:26:37.738Z,1550787997.738 [Micromodem](INFO): Starting 2019-02-21T22:26:37.742Z,1550787997.742 [Micromodem](INFO): Nmea in: $CAERR,014433,NI ,17,Argument value out of range*07 2019-02-21T22:26:37.744Z,1550787997.744 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,014433,NI ,17,Argument value out of range*07 2019-02-21T22:26:38.136Z,1550787998.136 [Micromodem](INFO): Starting 2019-02-21T22:26:38.136Z,1550787998.136 [Micromodem](INFO): Nmea in: $CAREV,014440,AUV,2.0.27690*10 2019-02-21T22:26:38.534Z,1550787998.534 [Micromodem](INFO): Starting 2019-02-21T22:26:38.938Z,1550787998.938 [Micromodem](INFO): Starting 2019-02-21T22:26:39.346Z,1550787999.346 [Micromodem](INFO): Starting 2019-02-21T22:26:39.755Z,1550787999.755 [Micromodem](INFO): Starting 2019-02-21T22:26:40.150Z,1550788000.150 [Micromodem](INFO): Starting 2019-02-21T22:26:40.554Z,1550788000.554 [Micromodem](INFO): Starting 2019-02-21T22:26:40.958Z,1550788000.958 [Micromodem](INFO): Starting 2019-02-21T22:26:41.362Z,1550788001.362 [Micromodem](INFO): Starting 2019-02-21T22:26:41.766Z,1550788001.766 [Micromodem](INFO): Starting 2019-02-21T22:26:42.174Z,1550788002.174 [Micromodem](INFO): Starting 2019-02-21T22:26:42.574Z,1550788002.574 [Micromodem](INFO): Starting 2019-02-21T22:26:42.978Z,1550788002.978 [Micromodem](INFO): Starting 2019-02-21T22:26:43.386Z,1550788003.386 [Micromodem](INFO): Starting 2019-02-21T22:26:43.387Z,1550788003.387 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,014440,AUV,2.0.27690*10 , available: $CAREV,014440,COPROC,0.10.0.46*45 2019-02-21T22:26:43.387Z,1550788003.387 [Micromodem] Communications Fault, FailCount= 3 2019-02-21T22:26:43.387Z,1550788003.387 [Micromodem](ERROR): Communications Fault 2019-02-21T22:26:43.439Z,1550788003.439 [CBIT](ERROR): Communications Fault in component: Micromodem 2019-02-21T22:26:43.786Z,1550788003.786 [Micromodem](INFO): Stop 2019-02-21T22:26:43.786Z,1550788003.786 [Micromodem](INFO): uninitialize 2019-02-21T22:26:43.786Z,1550788003.786 [Micromodem](INFO): Powering down 2019-02-21T22:26:44.190Z,1550788004.190 [Micromodem](INFO): Stopping 2019-02-21T22:26:44.595Z,1550788004.595 [Micromodem](INFO): Stopped 2019-02-21T22:26:44.635Z,1550788004.635 [CBIT](INFO): Clearing failed state for component Micromodem 2019-02-21T22:26:44.635Z,1550788004.635 [Micromodem] No Fault, FailCount= 3 2019-02-21T22:26:44.998Z,1550788004.998 [Micromodem](INFO): Start 2019-02-21T22:26:45.406Z,1550788005.406 [Micromodem](INFO): Start 2019-02-21T22:26:45.890Z,1550788005.890 [Micromodem](INFO): Start 2019-02-21T22:26:46.294Z,1550788006.294 [Micromodem](INFO): Start 2019-02-21T22:26:46.698Z,1550788006.698 [Micromodem](INFO): Start 2019-02-21T22:26:47.100Z,1550788007.100 [Micromodem](INFO): Start 2019-02-21T22:26:47.508Z,1550788007.508 [Micromodem](INFO): Starting 2019-02-21T22:26:47.508Z,1550788007.508 [Micromodem](INFO): Powering up 2019-02-21T22:26:47.508Z,1550788007.508 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-21T22:26:47.910Z,1550788007.910 [Micromodem](INFO): Starting 2019-02-21T22:26:48.314Z,1550788008.314 [Micromodem](INFO): Starting 2019-02-21T22:26:48.721Z,1550788008.721 [Micromodem](INFO): Starting 2019-02-21T22:26:49.122Z,1550788009.122 [Micromodem](INFO): Starting 2019-02-21T22:26:49.526Z,1550788009.526 [Micromodem](INFO): Starting 2019-02-21T22:26:49.930Z,1550788009.930 [Micromodem](INFO): Starting 2019-02-21T22:26:50.334Z,1550788010.334 [Micromodem](INFO): Starting 2019-02-21T22:26:50.751Z,1550788010.751 [Micromodem](INFO): Starting 2019-02-21T22:26:51.138Z,1550788011.138 [Micromodem](INFO): Starting 2019-02-21T22:26:51.546Z,1550788011.546 [Micromodem](INFO): Starting 2019-02-21T22:26:51.547Z,1550788011.547 [Micromodem](INFO): Nmea in: $CAREV,014454,INIT,2.0.27690*4D 2019-02-21T22:26:51.950Z,1550788011.950 [Micromodem](INFO): Starting 2019-02-21T22:26:51.951Z,1550788011.951 [Micromodem](INFO): Nmea in: $CAREV,014454,SLOT1,2.0.27690*62 2019-02-21T22:26:52.354Z,1550788012.354 [Micromodem](INFO): Starting 2019-02-21T22:26:52.355Z,1550788012.355 [Micromodem](INFO): Nmea in: $CAREV,014454,AUV,2.0.27690*15 2019-02-21T22:26:52.356Z,1550788012.356 [Micromodem](INFO): Starting 2019-02-21T22:26:52.357Z,1550788012.357 [Micromodem](INFO): Nmea in: $CAREV,014454,COPROC,0.10.0.46*40 2019-02-21T22:26:52.357Z,1550788012.357 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:52.357Z,1550788012.357 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45 2019-02-21T22:26:52.357Z,1550788012.357 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:26:52.359Z,1550788012.359 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:52.360Z,1550788012.360 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53 2019-02-21T22:26:52.361Z,1550788012.361 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:52.362Z,1550788012.362 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51 2019-02-21T22:26:52.363Z,1550788012.363 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:26:52.364Z,1550788012.364 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A 2019-02-21T22:26:52.365Z,1550788012.365 [Micromodem](INFO): Nmea cmd: $CCMSC 2019-02-21T22:26:52.366Z,1550788012.366 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40 2019-02-21T22:26:52.754Z,1550788012.754 [Micromodem](INFO): Starting 2019-02-21T22:26:52.755Z,1550788012.755 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:44:54Z,RTC,RTC*71 2019-02-21T22:26:52.757Z,1550788012.757 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:44:54Z,RTC,RTC*71 2019-02-21T22:26:53.158Z,1550788013.158 [Micromodem](INFO): Starting 2019-02-21T22:26:53.562Z,1550788013.562 [Micromodem](INFO): Starting 2019-02-21T22:26:53.563Z,1550788013.563 [Micromodem](INFO): Nmea cmd: $CCTMS 2019-02-21T22:26:53.563Z,1550788013.563 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:26:54Z,2*89 2019-02-21T22:26:53.563Z,1550788013.563 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:26:53.966Z,1550788013.966 [Micromodem](INFO): Starting 2019-02-21T22:26:53.968Z,1550788013.968 [Micromodem](INFO): Nmea in: $CAERR,014455,NI ,17,Argument value out of range*07 2019-02-21T22:26:53.969Z,1550788013.969 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,014455,NI ,17,Argument value out of range*07 2019-02-21T22:26:54.370Z,1550788014.370 [Micromodem](INFO): Starting 2019-02-21T22:26:54.778Z,1550788014.778 [Micromodem](INFO): Starting 2019-02-21T22:26:55.178Z,1550788015.178 [Micromodem](INFO): Starting 2019-02-21T22:26:55.586Z,1550788015.586 [Micromodem](INFO): Starting 2019-02-21T22:26:55.986Z,1550788015.986 [Micromodem](INFO): Starting 2019-02-21T22:26:56.390Z,1550788016.390 [Micromodem](INFO): Starting 2019-02-21T22:26:56.795Z,1550788016.795 [Micromodem](INFO): Starting 2019-02-21T22:26:57.198Z,1550788017.198 [Micromodem](INFO): Starting 2019-02-21T22:26:57.231Z,1550788017.231 [SBIT](IMPORTANT): SBIT PASSED 2019-02-21T22:26:57.246Z,1550788017.246 [CommandLine](IMPORTANT): got command configSet list 2019-02-21T22:26:57.247Z,1550788017.247 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-02-21T22:26:57.247Z,1550788017.247 [CommandLine](IMPORTANT): No configSet variables persisted 2019-02-21T22:26:57.602Z,1550788017.602 [Micromodem](INFO): Starting 2019-02-21T22:26:57.638Z,1550788017.638 [MissionManager](IMPORTANT): Started mission Startup 2019-02-21T22:26:57.638Z,1550788017.638 [Startup] Running Loop=1 2019-02-21T22:26:57.638Z,1550788017.638 [Startup](DEBUG): Aggregate::initialize Startup 2019-02-21T22:26:57.639Z,1550788017.639 [Startup:A.GoToSurface] Running Loop=1 2019-02-21T22:26:57.639Z,1550788017.639 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-21T22:26:57.639Z,1550788017.639 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-21T22:26:57.640Z,1550788017.640 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-21T22:26:57.640Z,1550788017.640 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-21T22:26:57.640Z,1550788017.640 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-21T22:26:57.641Z,1550788017.641 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-21T22:26:57.642Z,1550788017.642 [Startup:StartupSatComms] Running Loop=1 2019-02-21T22:26:57.642Z,1550788017.642 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-02-21T22:26:57.642Z,1550788017.642 [Startup:StartupSatComms:A] Running Loop=1 2019-02-21T22:26:58.006Z,1550788018.006 [Micromodem](INFO): Starting 2019-02-21T22:26:58.062Z,1550788018.062 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-02-21T22:26:58.410Z,1550788018.410 [Micromodem](INFO): Starting 2019-02-21T22:26:58.814Z,1550788018.814 [Micromodem](INFO): Starting 2019-02-21T22:26:59.214Z,1550788019.214 [Micromodem](INFO): Starting 2019-02-21T22:26:59.622Z,1550788019.622 [Micromodem](INFO): Starting 2019-02-21T22:27:00.026Z,1550788020.026 [Micromodem](INFO): Starting 2019-02-21T22:27:00.434Z,1550788020.434 [Micromodem](INFO): Starting 2019-02-21T22:27:00.834Z,1550788020.834 [Micromodem](INFO): Starting 2019-02-21T22:27:01.238Z,1550788021.238 [Micromodem](INFO): Starting 2019-02-21T22:27:01.646Z,1550788021.646 [Micromodem](INFO): Starting 2019-02-21T22:27:01.648Z,1550788021.648 [Micromodem](INFO): Nmea in: $CAERR,014456,NI ,17,Argument value out of range*04 2019-02-21T22:27:02.050Z,1550788022.050 [Micromodem](INFO): Starting 2019-02-21T22:27:02.051Z,1550788022.051 [Micromodem](INFO): Nmea in: $CAREV,014504,AUV,2.0.27690*11 2019-02-21T22:27:02.450Z,1550788022.450 [Micromodem](INFO): Starting 2019-02-21T22:27:02.858Z,1550788022.858 [Micromodem](INFO): Starting 2019-02-21T22:27:03.258Z,1550788023.258 [Micromodem](INFO): Starting 2019-02-21T22:27:03.662Z,1550788023.662 [Micromodem](INFO): Starting 2019-02-21T22:27:04.066Z,1550788024.066 [Micromodem](INFO): Starting 2019-02-21T22:27:04.472Z,1550788024.472 [Micromodem](INFO): Starting 2019-02-21T22:27:04.874Z,1550788024.874 [Micromodem](INFO): Starting 2019-02-21T22:27:05.282Z,1550788025.282 [Micromodem](INFO): Starting 2019-02-21T22:27:05.686Z,1550788025.686 [Micromodem](INFO): Starting 2019-02-21T22:27:06.090Z,1550788026.090 [Micromodem](INFO): Starting 2019-02-21T22:27:06.490Z,1550788026.490 [Micromodem](INFO): Starting 2019-02-21T22:27:06.894Z,1550788026.894 [Micromodem](INFO): Starting 2019-02-21T22:27:07.298Z,1550788027.298 [Micromodem](INFO): Starting 2019-02-21T22:27:07.299Z,1550788027.299 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,014504,AUV,2.0.27690*11 , available: $CAREV,014504,COPROC,0.10.0.46*44 2019-02-21T22:27:07.299Z,1550788027.299 [Micromodem] Communications Fault, FailCount= 4 2019-02-21T22:27:07.299Z,1550788027.299 [Micromodem](ERROR): Communications Fault 2019-02-21T22:27:07.375Z,1550788027.375 [CBIT](ERROR): Communications Fault in component: Micromodem 2019-02-21T22:27:07.702Z,1550788027.702 [Micromodem](INFO): Stop 2019-02-21T22:27:07.702Z,1550788027.702 [Micromodem](INFO): uninitialize 2019-02-21T22:27:07.702Z,1550788027.702 [Micromodem](INFO): Powering down 2019-02-21T22:27:08.112Z,1550788028.112 [Micromodem](INFO): Stopping 2019-02-21T22:27:08.514Z,1550788028.514 [Micromodem](INFO): Stopped 2019-02-21T22:27:08.553Z,1550788028.553 [CBIT](INFO): Clearing failed state for component Micromodem 2019-02-21T22:27:08.553Z,1550788028.553 [Micromodem] No Fault, FailCount= 4 2019-02-21T22:27:08.918Z,1550788028.918 [Micromodem](INFO): Start 2019-02-21T22:27:09.322Z,1550788029.322 [Micromodem](INFO): Start 2019-02-21T22:27:09.728Z,1550788029.728 [Micromodem](INFO): Start 2019-02-21T22:27:10.122Z,1550788030.122 [Micromodem](INFO): Start 2019-02-21T22:27:10.530Z,1550788030.530 [Micromodem](INFO): Start 2019-02-21T22:27:10.934Z,1550788030.934 [Micromodem](INFO): Start 2019-02-21T22:27:11.342Z,1550788031.342 [Micromodem](INFO): Starting 2019-02-21T22:27:11.342Z,1550788031.342 [Micromodem](INFO): Powering up 2019-02-21T22:27:11.343Z,1550788031.343 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-21T22:27:11.752Z,1550788031.752 [Micromodem](INFO): Starting 2019-02-21T22:27:12.146Z,1550788032.146 [Micromodem](INFO): Starting 2019-02-21T22:27:12.554Z,1550788032.554 [Micromodem](INFO): Starting 2019-02-21T22:27:12.958Z,1550788032.958 [Micromodem](INFO): Starting 2019-02-21T22:27:13.358Z,1550788033.358 [Micromodem](INFO): Starting 2019-02-21T22:27:13.766Z,1550788033.766 [Micromodem](INFO): Starting 2019-02-21T22:27:14.166Z,1550788034.166 [Micromodem](INFO): Starting 2019-02-21T22:27:14.570Z,1550788034.570 [Micromodem](INFO): Starting 2019-02-21T22:27:14.977Z,1550788034.977 [Micromodem](INFO): Starting 2019-02-21T22:27:15.378Z,1550788035.378 [Micromodem](INFO): Starting 2019-02-21T22:27:15.380Z,1550788035.380 [Micromodem](INFO): Nmea in: $CAREV,014518,INIT,2.0.27690*44 2019-02-21T22:27:15.782Z,1550788035.782 [Micromodem](INFO): Starting 2019-02-21T22:27:15.784Z,1550788035.784 [Micromodem](INFO): Nmea in: $CAREV,014518,SLOT1,2.0.27690*6B 2019-02-21T22:27:16.190Z,1550788036.190 [Micromodem](INFO): Starting 2019-02-21T22:27:16.191Z,1550788036.191 [Micromodem](INFO): Nmea in: $CAREV,014518,AUV,2.0.27690*1C 2019-02-21T22:27:16.191Z,1550788036.191 [Micromodem](INFO): Starting 2019-02-21T22:27:16.192Z,1550788036.192 [Micromodem](INFO): Nmea in: $CAREV,014518,COPROC,0.10.0.46*49 2019-02-21T22:27:16.194Z,1550788036.194 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,014518,COPROC,0.10.0.46*49 2019-02-21T22:27:16.194Z,1550788036.194 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:27:16.194Z,1550788036.194 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45 2019-02-21T22:27:16.194Z,1550788036.194 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:27:16.196Z,1550788036.196 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:27:16.197Z,1550788036.197 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53 2019-02-21T22:27:16.198Z,1550788036.198 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:27:16.199Z,1550788036.199 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51 2019-02-21T22:27:16.200Z,1550788036.200 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:27:16.201Z,1550788036.201 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A 2019-02-21T22:27:16.202Z,1550788036.202 [Micromodem](INFO): Nmea cmd: $CCMSC 2019-02-21T22:27:16.203Z,1550788036.203 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40 2019-02-21T22:27:16.595Z,1550788036.595 [Micromodem](INFO): Starting 2019-02-21T22:27:16.596Z,1550788036.596 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:45:18Z,RTC,RTC*78 2019-02-21T22:27:16.598Z,1550788036.598 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:45:18Z,RTC,RTC*78 2019-02-21T22:27:16.998Z,1550788036.998 [Micromodem](INFO): Starting 2019-02-21T22:27:17.398Z,1550788037.398 [Micromodem](INFO): Starting 2019-02-21T22:27:17.399Z,1550788037.399 [Micromodem](INFO): Nmea cmd: $CCTMS 2019-02-21T22:27:17.399Z,1550788037.399 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:27:18Z,2*81 2019-02-21T22:27:17.399Z,1550788037.399 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:27:17.806Z,1550788037.806 [Micromodem](INFO): Starting 2019-02-21T22:27:17.808Z,1550788037.808 [Micromodem](INFO): Nmea in: $CAERR,014519,NI ,17,Argument value out of range*0E 2019-02-21T22:27:18.212Z,1550788038.212 [Micromodem](INFO): Starting 2019-02-21T22:27:18.614Z,1550788038.614 [Micromodem](INFO): Starting 2019-02-21T22:27:19.018Z,1550788039.018 [Micromodem](INFO): Starting 2019-02-21T22:27:19.422Z,1550788039.422 [Micromodem](INFO): Starting 2019-02-21T22:27:19.818Z,1550788039.818 [Micromodem](INFO): Starting 2019-02-21T22:27:20.226Z,1550788040.226 [Micromodem](INFO): Starting 2019-02-21T22:27:20.630Z,1550788040.630 [Micromodem](INFO): Starting 2019-02-21T22:27:21.034Z,1550788041.034 [Micromodem](INFO): Starting 2019-02-21T22:27:21.440Z,1550788041.440 [Micromodem](INFO): Starting 2019-02-21T22:27:21.842Z,1550788041.842 [Micromodem](INFO): Starting 2019-02-21T22:27:22.250Z,1550788042.250 [Micromodem](INFO): Starting 2019-02-21T22:27:22.654Z,1550788042.654 [Micromodem](INFO): Starting 2019-02-21T22:27:23.054Z,1550788043.054 [Micromodem](INFO): Starting 2019-02-21T22:27:23.462Z,1550788043.462 [Micromodem](INFO): Starting 2019-02-21T22:27:23.862Z,1550788043.862 [Micromodem](INFO): Starting 2019-02-21T22:27:24.266Z,1550788044.266 [Micromodem](INFO): Starting 2019-02-21T22:27:24.670Z,1550788044.670 [Micromodem](INFO): Starting 2019-02-21T22:27:25.074Z,1550788045.074 [Micromodem](INFO): Starting 2019-02-21T22:27:25.482Z,1550788045.482 [Micromodem](INFO): Starting 2019-02-21T22:27:25.484Z,1550788045.484 [Micromodem](INFO): Nmea in: $CAERR,014520,NI ,17,Argument value out of range*04 2019-02-21T22:27:25.886Z,1550788045.886 [Micromodem](INFO): Starting 2019-02-21T22:27:25.887Z,1550788045.887 [Micromodem](INFO): Nmea in: $CAREV,014528,AUV,2.0.27690*1F 2019-02-21T22:27:26.286Z,1550788046.286 [Micromodem](INFO): Starting 2019-02-21T22:27:26.690Z,1550788046.690 [Micromodem](INFO): Starting 2019-02-21T22:27:27.098Z,1550788047.098 [Micromodem](INFO): Starting 2019-02-21T22:27:27.498Z,1550788047.498 [Micromodem](INFO): Starting 2019-02-21T22:27:27.902Z,1550788047.902 [Micromodem](INFO): Starting 2019-02-21T22:27:28.308Z,1550788048.308 [Micromodem](INFO): Starting 2019-02-21T22:27:28.716Z,1550788048.716 [Micromodem](INFO): Starting 2019-02-21T22:27:29.114Z,1550788049.114 [Micromodem](INFO): Starting 2019-02-21T22:27:29.522Z,1550788049.522 [Micromodem](INFO): Starting 2019-02-21T22:27:29.922Z,1550788049.922 [Micromodem](INFO): Starting 2019-02-21T22:27:30.330Z,1550788050.330 [Micromodem](INFO): Starting 2019-02-21T22:27:30.737Z,1550788050.737 [Micromodem](INFO): Starting 2019-02-21T22:27:31.134Z,1550788051.134 [Micromodem](INFO): Starting 2019-02-21T22:27:31.135Z,1550788051.135 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,014528,AUV,2.0.27690*1F , available: $CAREV,014528,COPROC,0.10.0.46*4A 2019-02-21T22:27:31.135Z,1550788051.135 [Micromodem] Communications Fault, FailCount= 5 2019-02-21T22:27:31.135Z,1550788051.135 [Micromodem](ERROR): Communications Fault 2019-02-21T22:27:31.261Z,1550788051.261 [CBIT](ERROR): Communications Fault in component: Micromodem 2019-02-21T22:27:31.538Z,1550788051.538 [Micromodem](INFO): Stop 2019-02-21T22:27:31.538Z,1550788051.538 [Micromodem](INFO): uninitialize 2019-02-21T22:27:31.539Z,1550788051.539 [Micromodem](INFO): Powering down 2019-02-21T22:27:31.949Z,1550788051.949 [Micromodem](INFO): Stopping 2019-02-21T22:27:32.346Z,1550788052.346 [Micromodem](INFO): Stopped 2019-02-21T22:27:32.415Z,1550788052.415 [CBIT](INFO): Clearing failed state for component Micromodem 2019-02-21T22:27:32.415Z,1550788052.415 [Micromodem] No Fault, FailCount= 5 2019-02-21T22:27:32.754Z,1550788052.754 [Micromodem](INFO): Start 2019-02-21T22:27:33.158Z,1550788053.158 [Micromodem](INFO): Start 2019-02-21T22:27:33.562Z,1550788053.562 [Micromodem](INFO): Start 2019-02-21T22:27:33.962Z,1550788053.962 [Micromodem](INFO): Start 2019-02-21T22:27:34.370Z,1550788054.370 [Micromodem](INFO): Start 2019-02-21T22:27:34.774Z,1550788054.774 [Micromodem](INFO): Start 2019-02-21T22:27:35.175Z,1550788055.175 [Micromodem](INFO): Starting 2019-02-21T22:27:35.176Z,1550788055.176 [Micromodem](INFO): Powering up 2019-02-21T22:27:35.176Z,1550788055.176 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-21T22:27:35.216Z,1550788055.216 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-02-21T22:27:35.216Z,1550788055.216 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-02-21T22:27:35.216Z,1550788055.216 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-21T22:27:35.218Z,1550788055.218 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-02-21T22:27:35.218Z,1550788055.218 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-02-21T22:27:35.218Z,1550788055.218 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-21T22:27:35.219Z,1550788055.219 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-02-21T22:27:35.219Z,1550788055.219 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-02-21T22:27:35.219Z,1550788055.219 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-21T22:27:35.230Z,1550788055.230 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-21T22:27:35.230Z,1550788055.230 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-21T22:27:35.230Z,1550788055.230 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-21T22:27:35.578Z,1550788055.578 [Micromodem](INFO): Starting 2019-02-21T22:27:35.619Z,1550788055.619 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-21T22:27:35.620Z,1550788055.620 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-02-21T22:27:35.620Z,1550788055.620 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-21T22:27:35.620Z,1550788055.620 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-02-21T22:27:35.621Z,1550788055.621 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-21T22:27:35.621Z,1550788055.621 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-02-21T22:27:35.987Z,1550788055.987 [Micromodem](INFO): Starting 2019-02-21T22:27:36.023Z,1550788056.023 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-21T22:27:36.398Z,1550788056.398 [Micromodem](INFO): Starting 2019-02-21T22:27:36.786Z,1550788056.786 [Micromodem](INFO): Starting 2019-02-21T22:27:37.198Z,1550788057.198 [Micromodem](INFO): Starting 2019-02-21T22:27:37.602Z,1550788057.602 [Micromodem](INFO): Starting 2019-02-21T22:27:38.006Z,1550788058.006 [Micromodem](INFO): Starting 2019-02-21T22:27:38.406Z,1550788058.406 [Micromodem](INFO): Starting 2019-02-21T22:27:38.810Z,1550788058.810 [Micromodem](INFO): Starting 2019-02-21T22:27:39.218Z,1550788059.218 [Micromodem](INFO): Starting 2019-02-21T22:27:39.219Z,1550788059.219 [Micromodem](INFO): Nmea in: $CAREV,014542,INIT,2.0.27690*4B 2019-02-21T22:27:39.618Z,1550788059.618 [Micromodem](INFO): Starting 2019-02-21T22:27:39.619Z,1550788059.619 [Micromodem](INFO): Nmea in: $CAREV,014542,SLOT1,2.0.27690*64 2019-02-21T22:27:40.022Z,1550788060.022 [Micromodem](INFO): Starting 2019-02-21T22:27:40.023Z,1550788060.023 [Micromodem](INFO): Nmea in: $CAREV,014542,AUV,2.0.27690*13 2019-02-21T22:27:40.023Z,1550788060.023 [Micromodem](INFO): Starting 2019-02-21T22:27:40.024Z,1550788060.024 [Micromodem](INFO): Nmea in: $CAREV,014542,COPROC,0.10.0.46*46 2019-02-21T22:27:40.024Z,1550788060.024 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:27:40.025Z,1550788060.025 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45 2019-02-21T22:27:40.025Z,1550788060.025 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:27:40.025Z,1550788060.025 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:27:40.025Z,1550788060.025 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53 2019-02-21T22:27:40.026Z,1550788060.026 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:27:40.026Z,1550788060.026 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51 2019-02-21T22:27:40.026Z,1550788060.026 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:27:40.026Z,1550788060.026 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A 2019-02-21T22:27:40.026Z,1550788060.026 [Micromodem](INFO): Nmea cmd: $CCMSC 2019-02-21T22:27:40.026Z,1550788060.026 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40 2019-02-21T22:27:40.426Z,1550788060.426 [Micromodem](INFO): Starting 2019-02-21T22:27:40.427Z,1550788060.427 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:45:42Z,RTC,RTC*77 2019-02-21T22:27:40.429Z,1550788060.429 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:45:42Z,RTC,RTC*77 2019-02-21T22:27:40.830Z,1550788060.830 [Micromodem](INFO): Starting 2019-02-21T22:27:41.234Z,1550788061.234 [Micromodem](INFO): Starting 2019-02-21T22:27:41.235Z,1550788061.235 [Micromodem](INFO): Nmea cmd: $CCTMS 2019-02-21T22:27:41.235Z,1550788061.235 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:27:42Z,2*8E 2019-02-21T22:27:41.235Z,1550788061.235 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:27:41.638Z,1550788061.638 [Micromodem](INFO): Starting 2019-02-21T22:27:41.640Z,1550788061.640 [Micromodem](INFO): Nmea in: $CAERR,014543,NI ,17,Argument value out of range*01 2019-02-21T22:27:42.050Z,1550788062.050 [Micromodem](INFO): Starting 2019-02-21T22:27:42.446Z,1550788062.446 [Micromodem](INFO): Starting 2019-02-21T22:27:42.854Z,1550788062.854 [Micromodem](INFO): Starting 2019-02-21T22:27:43.258Z,1550788063.258 [Micromodem](INFO): Starting 2019-02-21T22:27:43.662Z,1550788063.662 [Micromodem](INFO): Starting 2019-02-21T22:27:44.066Z,1550788064.066 [Micromodem](INFO): Starting 2019-02-21T22:27:44.470Z,1550788064.470 [Micromodem](INFO): Starting 2019-02-21T22:27:44.870Z,1550788064.870 [Micromodem](INFO): Starting 2019-02-21T22:27:45.274Z,1550788065.274 [Micromodem](INFO): Starting 2019-02-21T22:27:45.680Z,1550788065.680 [Micromodem](INFO): Starting 2019-02-21T22:27:46.082Z,1550788066.082 [Micromodem](INFO): Starting 2019-02-21T22:27:46.486Z,1550788066.486 [Micromodem](INFO): Starting 2019-02-21T22:27:46.894Z,1550788066.894 [Micromodem](INFO): Starting 2019-02-21T22:27:47.298Z,1550788067.298 [Micromodem](INFO): Starting 2019-02-21T22:27:47.698Z,1550788067.698 [Micromodem](INFO): Starting 2019-02-21T22:27:48.106Z,1550788068.106 [Micromodem](INFO): Starting 2019-02-21T22:27:48.506Z,1550788068.506 [Micromodem](INFO): Starting 2019-02-21T22:27:48.908Z,1550788068.908 [Micromodem](INFO): Starting 2019-02-21T22:27:49.314Z,1550788069.314 [Micromodem](INFO): Starting 2019-02-21T22:27:49.316Z,1550788069.316 [Micromodem](INFO): Nmea in: $CAERR,014544,NI ,17,Argument value out of range*06 2019-02-21T22:27:49.725Z,1550788069.725 [Micromodem](INFO): Starting 2019-02-21T22:27:49.727Z,1550788069.727 [Micromodem](INFO): Nmea in: $CAREV,014552,AUV,2.0.27690*12 2019-02-21T22:27:50.118Z,1550788070.118 [Micromodem](INFO): Starting 2019-02-21T22:27:50.530Z,1550788070.530 [Micromodem](INFO): Starting 2019-02-21T22:27:50.930Z,1550788070.930 [Micromodem](INFO): Starting 2019-02-21T22:27:51.334Z,1550788071.334 [Micromodem](INFO): Starting 2019-02-21T22:27:51.749Z,1550788071.749 [Micromodem](INFO): Starting 2019-02-21T22:27:52.142Z,1550788072.142 [Micromodem](INFO): Starting 2019-02-21T22:27:52.548Z,1550788072.548 [Micromodem](INFO): Starting 2019-02-21T22:27:52.950Z,1550788072.950 [Micromodem](INFO): Starting 2019-02-21T22:27:53.358Z,1550788073.358 [Micromodem](INFO): Starting 2019-02-21T22:27:53.762Z,1550788073.762 [Micromodem](INFO): Starting 2019-02-21T22:27:54.166Z,1550788074.166 [Micromodem](INFO): Starting 2019-02-21T22:27:54.566Z,1550788074.566 [Micromodem](INFO): Starting 2019-02-21T22:27:54.970Z,1550788074.970 [Micromodem](INFO): Starting 2019-02-21T22:27:54.971Z,1550788074.971 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,014552,AUV,2.0.27690*12 , available: $CAREV,014552,COPROC,0.10.0.46*47 2019-02-21T22:27:54.971Z,1550788074.971 [Micromodem] Communications Fault, FailCount= 6 2019-02-21T22:27:54.971Z,1550788074.971 [Micromodem](ERROR): Communications Fault 2019-02-21T22:27:55.018Z,1550788075.018 [CBIT](ERROR): Communications Fault in component: Micromodem 2019-02-21T22:27:55.383Z,1550788075.383 [Micromodem](INFO): Stop 2019-02-21T22:27:55.383Z,1550788075.383 [Micromodem](INFO): uninitialize 2019-02-21T22:27:55.383Z,1550788075.383 [Micromodem](INFO): Powering down 2019-02-21T22:27:55.776Z,1550788075.776 [Micromodem](INFO): Stopping 2019-02-21T22:27:56.189Z,1550788076.189 [Micromodem](INFO): Stopped 2019-02-21T22:27:56.232Z,1550788076.232 [CBIT](INFO): Clearing failed state for component Micromodem 2019-02-21T22:27:56.232Z,1550788076.232 [Micromodem] No Fault, FailCount= 6 2019-02-21T22:27:56.586Z,1550788076.586 [Micromodem](INFO): Start 2019-02-21T22:27:56.994Z,1550788076.994 [Micromodem](INFO): Start 2019-02-21T22:27:57.398Z,1550788077.398 [Micromodem](INFO): Start 2019-02-21T22:27:57.802Z,1550788077.802 [Micromodem](INFO): Start 2019-02-21T22:27:57.835Z,1550788077.835 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-21T22:26:57.6Z 2019-02-21T22:27:57.835Z,1550788077.835 [Startup:StartupSatComms:A] Stopped 2019-02-21T22:27:57.835Z,1550788077.835 [Startup:StartupSatComms:B] Running Loop=1 2019-02-21T22:27:58.202Z,1550788078.202 [Micromodem](INFO): Start 2019-02-21T22:27:58.243Z,1550788078.243 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-21T22:27:58.610Z,1550788078.610 [Micromodem](INFO): Start 2019-02-21T22:27:59.014Z,1550788079.014 [Micromodem](INFO): Starting 2019-02-21T22:27:59.014Z,1550788079.014 [Micromodem](INFO): Powering up 2019-02-21T22:27:59.015Z,1550788079.015 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-21T22:27:59.415Z,1550788079.415 [Micromodem](INFO): Starting 2019-02-21T22:27:59.818Z,1550788079.818 [Micromodem](INFO): Starting 2019-02-21T22:28:00.223Z,1550788080.223 [Micromodem](INFO): Starting 2019-02-21T22:28:00.638Z,1550788080.638 [Micromodem](INFO): Starting 2019-02-21T22:28:03.805Z,1550788083.805 [Micromodem](INFO): Starting 2019-02-21T22:28:03.806Z,1550788083.806 [Micromodem](INFO): Nmea in: $CAREV,014606,INIT,2.0.27690*48 2019-02-21T22:28:03.808Z,1550788083.808 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,014606,INIT,2.0.27690*48 2019-02-21T22:28:03.808Z,1550788083.808 [Micromodem](INFO): Starting 2019-02-21T22:28:03.810Z,1550788083.810 [Micromodem](INFO): Nmea in: $CAREV,014606,SLOT1,2.0.27690*67 2019-02-21T22:28:03.811Z,1550788083.811 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,014606,SLOT1,2.0.27690*67 2019-02-21T22:28:03.811Z,1550788083.811 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:28:03.811Z,1550788083.811 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45 2019-02-21T22:28:03.812Z,1550788083.812 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:28:03.813Z,1550788083.813 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:28:03.814Z,1550788083.814 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53 2019-02-21T22:28:03.815Z,1550788083.815 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:28:03.816Z,1550788083.816 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51 2019-02-21T22:28:03.817Z,1550788083.817 [Micromodem](INFO): Nmea cmd: $CCCFG 2019-02-21T22:28:03.819Z,1550788083.819 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A 2019-02-21T22:28:03.820Z,1550788083.820 [Micromodem](INFO): Nmea cmd: $CCMSC 2019-02-21T22:28:03.820Z,1550788083.820 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40 2019-02-21T22:28:03.979Z,1550788083.979 [Micromodem](INFO): Starting 2019-02-21T22:28:03.980Z,1550788083.980 [Micromodem](INFO): Nmea in: $CAREV,014606,AUV,2.0.27690*10 2019-02-21T22:28:04.374Z,1550788084.374 [Micromodem](INFO): Starting 2019-02-21T22:28:04.375Z,1550788084.375 [Micromodem](INFO): Nmea in: $CAREV,014606,COPROC,0.10.0.46*45 2019-02-21T22:28:04.778Z,1550788084.778 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004863 2019-02-21T22:28:04.786Z,1550788084.786 [Micromodem](INFO): Starting 2019-02-21T22:28:04.787Z,1550788084.787 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:46:06Z,RTC,RTC*74 2019-02-21T22:28:04.789Z,1550788084.789 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:46:06Z,RTC,RTC*74 2019-02-21T22:28:04.789Z,1550788084.789 [Micromodem](INFO): Nmea cmd: $CCTMS 2019-02-21T22:28:04.790Z,1550788084.790 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:28:05Z,2*82 2019-02-21T22:28:04.790Z,1550788084.790 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45 2019-02-21T22:28:05.186Z,1550788085.186 [Micromodem](INFO): Starting 2019-02-21T22:28:05.188Z,1550788085.188 [Micromodem](INFO): Nmea in: $CAERR,014607,NI ,17,Argument value out of range*02 2019-02-21T22:28:05.587Z,1550788085.587 [Micromodem](INFO): Starting 2019-02-21T22:28:08.653Z,1550788088.653 [Micromodem](INFO): Starting 2019-02-21T22:28:08.855Z,1550788088.855 [DataOverHttps](INFO): Sending 467 bytes from file Logs/20190221T221845/Express0001.lzma 2019-02-21T22:28:08.910Z,1550788088.910 [Micromodem](INFO): Starting 2019-02-21T22:28:09.267Z,1550788089.267 [Micromodem](INFO): Starting 2019-02-21T22:28:09.658Z,1550788089.658 [DataOverHttps](INFO): Moved sent file to Logs/20190221T221845/Express0001.lzma.bak 2019-02-21T22:28:09.658Z,1550788089.658 [DataOverHttps](INFO): SBD MOMSN=10066844 2019-02-21T22:28:09.670Z,1550788089.670 [Micromodem](INFO): Starting 2019-02-21T22:28:10.074Z,1550788090.074 [Micromodem](INFO): Starting 2019-02-21T22:28:10.095Z,1550788090.095 [RDI_Pathfinder](ERROR): Failed to parse: :BI, -2, -5, +0,-32768,A 2019-02-21T22:28:10.471Z,1550788090.471 [Micromodem](INFO): Starting 2019-02-21T22:28:13.538Z,1550788093.538 [Micromodem](INFO): Starting 2019-02-21T22:28:13.540Z,1550788093.540 [Micromodem](INFO): Nmea in: $CAERR,014608,NI ,17,Argument value out of range*0D 2019-02-21T22:28:13.678Z,1550788093.678 [Micromodem](INFO): Starting 2019-02-21T22:28:13.679Z,1550788093.679 [Micromodem](INFO): Nmea in: $CAREV,014616,AUV,2.0.27690*11 2019-02-21T22:28:14.030Z,1550788094.030 [Micromodem](INFO): Starting 2019-02-21T22:28:14.438Z,1550788094.438 [Micromodem](INFO): Starting 2019-02-21T22:28:14.842Z,1550788094.842 [Micromodem](INFO): Starting 2019-02-21T22:28:15.242Z,1550788095.242 [Micromodem](INFO): Starting 2019-02-21T22:28:18.345Z,1550788098.345 [Micromodem](INFO): Starting 2019-02-21T22:28:18.421Z,1550788098.421 [DataOverHttps](INFO): Sending 960 bytes from file Logs/20190221T222523/Express0001.lzma 2019-02-21T22:28:18.482Z,1550788098.482 [Micromodem](INFO): Starting 2019-02-21T22:28:18.826Z,1550788098.826 [Micromodem](INFO): Starting 2019-02-21T22:28:18.827Z,1550788098.827 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,014616,AUV,2.0.27690*11 , available: $CAREV,014616,COPROC,0.10.0.46*44 2019-02-21T22:28:18.827Z,1550788098.827 [Micromodem] Communications Fault, FailCount= 7 2019-02-21T22:28:18.827Z,1550788098.827 [Micromodem](ERROR): Communications Fault 2019-02-21T22:28:18.896Z,1550788098.896 [CBIT](ERROR): Communications Fault in component: Micromodem 2019-02-21T22:28:19.222Z,1550788099.222 [DataOverHttps](INFO): Moved sent file to Logs/20190221T222523/Express0001.lzma.bak 2019-02-21T22:28:19.222Z,1550788099.222 [DataOverHttps](INFO): SBD MOMSN=10066853 2019-02-21T22:28:19.234Z,1550788099.234 [Micromodem](INFO): Stop 2019-02-21T22:28:19.234Z,1550788099.234 [Micromodem](INFO): uninitialize 2019-02-21T22:28:19.234Z,1550788099.234 [Micromodem](INFO): Powering down 2019-02-21T22:28:19.638Z,1550788099.638 [Micromodem](INFO): Stopping 2019-02-21T22:28:19.661Z,1550788099.661 [Startup:StartupSatComms:B] Stopped 2019-02-21T22:28:19.662Z,1550788099.662 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-02-21T22:28:19.662Z,1550788099.662 [Startup:StartupSatComms] Stopped 2019-02-21T22:28:19.662Z,1550788099.662 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-02-21T22:28:19.663Z,1550788099.663 [Startup](INFO): Completed Startup 2019-02-21T22:28:19.663Z,1550788099.663 [MissionManager](INFO): Startup is completed. 2019-02-21T22:28:19.663Z,1550788099.663 [MissionManager](INFO): Uninitializing Mission Startup 2019-02-21T22:28:19.663Z,1550788099.663 [Startup] Stopped 2019-02-21T22:28:19.663Z,1550788099.663 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-02-21T22:28:19.663Z,1550788099.663 [Startup:A.GoToSurface] Stopped 2019-02-21T22:28:19.663Z,1550788099.663 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-21T22:28:20.042Z,1550788100.042 [Micromodem](INFO): Stopped 2019-02-21T22:28:20.074Z,1550788100.074 [MissionManager](IMPORTANT): Started mission Default 2019-02-21T22:28:20.075Z,1550788100.075 [Default] Running Loop=1 2019-02-21T22:28:20.075Z,1550788100.075 [Default](DEBUG): Aggregate::initialize Default 2019-02-21T22:28:20.075Z,1550788100.075 [Default:B.GoToSurface] Running Loop=1 2019-02-21T22:28:20.075Z,1550788100.075 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-21T22:28:20.075Z,1550788100.075 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-21T22:28:20.076Z,1550788100.076 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-21T22:28:20.076Z,1550788100.076 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-21T22:28:20.076Z,1550788100.076 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-21T22:28:20.077Z,1550788100.077 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-21T22:28:20.077Z,1550788100.077 [Default:A.Wait] Running Loop=1 2019-02-21T22:28:20.078Z,1550788100.078 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-02-21T22:28:20.087Z,1550788100.087 [CBIT](INFO): Clearing failed state for component Micromodem 2019-02-21T22:28:20.087Z,1550788100.087 [Micromodem] No Fault, FailCount= 7 2019-02-21T22:28:23.101Z,1550788103.101 [Micromodem](INFO): Start 2019-02-21T22:28:23.354Z,1550788103.354 [Micromodem](INFO): Starting 2019-02-21T22:28:23.354Z,1550788103.354 [Micromodem](INFO): Powering up 2019-02-21T22:28:23.355Z,1550788103.355 [Micromodem](DEBUG): Initializing Micromodem. 2019-02-21T22:28:23.696Z,1550788103.696 [Micromodem](INFO): Starting 2019-02-21T22:28:24.094Z,1550788104.094 [Micromodem](INFO): Starting 2019-02-21T22:28:24.498Z,1550788104.