2019-02-21T22:03:53.543Z,1550786633.543 [Supervisor](DEBUG): Initializing supervisor.
2019-02-21T22:03:53.546Z,1550786633.546 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-21T22:03:53.547Z,1550786633.547 [SyncHandler](INFO): Protected caller Thread ID is 804
2019-02-21T22:03:53.547Z,1550786633.547 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-21T22:03:53.548Z,1550786633.548 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-21T22:03:53.548Z,1550786633.548 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 805
2019-02-21T22:03:53.551Z,1550786633.551 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-21T22:03:53.562Z,1550786633.562 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-21T22:03:53.563Z,1550786633.563 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-21T22:03:53.563Z,1550786633.563 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 806
2019-02-21T22:03:53.564Z,1550786633.564 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-21T22:03:53.565Z,1550786633.565 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-21T22:03:53.566Z,1550786633.566 [logger ThreadHandler](INFO): Protected caller Thread ID is 807
2019-02-21T22:03:53.567Z,1550786633.567 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-21T22:03:53.568Z,1550786633.568 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-21T22:03:53.571Z,1550786633.571 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-21T22:03:53.994Z,1550786633.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-21T22:03:53.996Z,1550786633.996 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-21T22:03:54.094Z,1550786634.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-21T22:03:54.096Z,1550786634.096 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-21T22:03:54.418Z,1550786634.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-21T22:03:54.419Z,1550786634.419 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-21T22:03:54.558Z,1550786634.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-21T22:03:54.559Z,1550786634.559 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-21T22:03:54.749Z,1550786634.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-21T22:03:54.750Z,1550786634.750 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-21T22:03:55.222Z,1550786635.222 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-21T22:03:55.223Z,1550786635.223 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-21T22:03:55.428Z,1550786635.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-21T22:03:55.429Z,1550786635.429 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-21T22:03:55.574Z,1550786635.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-21T22:03:55.575Z,1550786635.575 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-21T22:03:55.768Z,1550786635.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-21T22:03:55.768Z,1550786635.768 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-21T22:03:55.864Z,1550786635.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-21T22:03:55.865Z,1550786635.865 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-21T22:03:56.177Z,1550786636.177 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-21T22:03:56.178Z,1550786636.178 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-21T22:03:56.259Z,1550786636.259 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-21T22:03:56.514Z,1550786636.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-21T22:03:56.515Z,1550786636.515 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-21T22:03:57.676Z,1550786637.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-21T22:03:57.677Z,1550786637.677 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-21T22:03:58.074Z,1550786638.074 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-21T22:03:58.076Z,1550786638.076 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-21T22:03:58.079Z,1550786638.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-21T22:03:58.286Z,1550786638.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-21T22:03:58.388Z,1550786638.388 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-21T22:03:58.488Z,1550786638.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-21T22:03:58.717Z,1550786638.717 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-21T22:03:58.718Z,1550786638.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-21T22:03:58.803Z,1550786638.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-21T22:03:58.897Z,1550786638.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-21T22:03:58.996Z,1550786638.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-21T22:03:59.079Z,1550786639.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-21T22:03:59.188Z,1550786639.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-21T22:03:59.357Z,1550786639.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-21T22:03:59.487Z,1550786639.487 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-21T22:03:59.490Z,1550786639.490 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-21T22:04:00.098Z,1550786640.098 [DataOverHttps] Loaded
2019-02-21T22:04:00.098Z,1550786640.098 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-21T22:04:00.136Z,1550786640.136 [Depth_Keller] Loaded
2019-02-21T22:04:00.137Z,1550786640.137 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-21T22:04:00.142Z,1550786640.142 [DropWeight] Loaded
2019-02-21T22:04:00.142Z,1550786640.142 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-21T22:04:00.191Z,1550786640.191 [DUSBL_Hydroid] Loaded
2019-02-21T22:04:00.192Z,1550786640.192 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-02-21T22:04:00.233Z,1550786640.233 [Micromodem] Loaded
2019-02-21T22:04:00.233Z,1550786640.233 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-02-21T22:04:00.333Z,1550786640.333 [NAL9602] Loaded
2019-02-21T22:04:00.333Z,1550786640.333 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-21T22:04:00.349Z,1550786640.349 [Onboard] Loaded
2019-02-21T22:04:00.350Z,1550786640.350 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-21T22:04:00.360Z,1550786640.360 [Radio_Surface] Loaded
2019-02-21T22:04:00.360Z,1550786640.360 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-21T22:04:00.361Z,1550786640.361 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407534E0
2019-02-21T22:04:00.362Z,1550786640.362 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 886
2019-02-21T22:04:00.391Z,1550786640.391 [RDI_Pathfinder] Loaded
2019-02-21T22:04:00.391Z,1550786640.391 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-21T22:04:00.414Z,1550786640.414 [RDI_PathfinderUp] Loaded
2019-02-21T22:04:00.414Z,1550786640.414 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-21T22:04:02.239Z,1550786642.239 [BPC1] Loaded
2019-02-21T22:04:02.239Z,1550786642.239 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-21T22:04:02.239Z,1550786642.239 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-21T22:04:02.240Z,1550786642.240 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-21T22:04:02.285Z,1550786642.285 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-21T22:04:02.285Z,1550786642.285 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-21T22:04:03.325Z,1550786643.325 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-21T22:04:03.326Z,1550786643.326 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-21T22:04:03.386Z,1550786643.386 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-21T22:04:03.386Z,1550786643.386 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-21T22:04:03.446Z,1550786643.446 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-21T22:04:03.447Z,1550786643.447 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-21T22:04:03.463Z,1550786643.463 [NavChart] Loaded
2019-02-21T22:04:03.463Z,1550786643.463 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-21T22:04:03.467Z,1550786643.467 [UniversalFixResidualReporter] Loaded
2019-02-21T22:04:03.468Z,1550786643.468 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-21T22:04:03.468Z,1550786643.468 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-21T22:04:03.469Z,1550786643.469 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-21T22:04:03.595Z,1550786643.595 [BuoyancyServo] Loaded
2019-02-21T22:04:03.596Z,1550786643.596 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-21T22:04:03.611Z,1550786643.611 [ElevatorServo] Loaded
2019-02-21T22:04:03.612Z,1550786643.612 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-21T22:04:03.627Z,1550786643.627 [MassServo] Loaded
2019-02-21T22:04:03.627Z,1550786643.627 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-21T22:04:03.643Z,1550786643.643 [RudderServo] Loaded
2019-02-21T22:04:03.643Z,1550786643.643 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-21T22:04:03.658Z,1550786643.658 [ThrusterServo] Loaded
2019-02-21T22:04:03.658Z,1550786643.658 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-21T22:04:03.659Z,1550786643.659 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-21T22:04:03.659Z,1550786643.659 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-21T22:04:03.899Z,1550786643.899 [Aanderaa_O2] Loaded
2019-02-21T22:04:03.899Z,1550786643.899 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-21T22:04:03.987Z,1550786643.987 [CTD_NeilBrown] Loaded
2019-02-21T22:04:03.988Z,1550786643.988 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-21T22:04:03.989Z,1550786643.989 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-21T22:04:03.989Z,1550786643.989 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 887
2019-02-21T22:04:04.035Z,1550786644.035 [WetLabsSeaOWL_UV_A] Loaded
2019-02-21T22:04:04.035Z,1550786644.035 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-21T22:04:04.036Z,1550786644.036 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-21T22:04:04.037Z,1550786644.037 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 888
2019-02-21T22:04:04.037Z,1550786644.037 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-21T22:04:04.038Z,1550786644.038 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-21T22:04:04.328Z,1550786644.328 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-21T22:04:04.329Z,1550786644.329 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-21T22:04:04.386Z,1550786644.386 [DepthRateCalculator] Loaded
2019-02-21T22:04:04.386Z,1550786644.386 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-21T22:04:04.392Z,1550786644.392 [PitchRateCalculator] Loaded
2019-02-21T22:04:04.393Z,1550786644.393 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-21T22:04:04.405Z,1550786644.405 [SpeedCalculator] Loaded
2019-02-21T22:04:04.406Z,1550786644.406 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-21T22:04:04.427Z,1550786644.427 [TempGradientCalculator] Loaded
2019-02-21T22:04:04.427Z,1550786644.427 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-21T22:04:04.433Z,1550786644.433 [YawRateCalculator] Loaded
2019-02-21T22:04:04.433Z,1550786644.433 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-21T22:04:04.474Z,1550786644.474 [ElevatorOffsetCalculator] Loaded
2019-02-21T22:04:04.474Z,1550786644.474 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-21T22:04:04.475Z,1550786644.475 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-21T22:04:04.475Z,1550786644.475 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-21T22:04:04.640Z,1550786644.640 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-21T22:04:04.662Z,1550786644.662 [SBIT] Loaded
2019-02-21T22:04:04.662Z,1550786644.662 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-21T22:04:04.663Z,1550786644.663 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-21T22:04:04.675Z,1550786644.675 [IBIT] Loaded
2019-02-21T22:04:04.675Z,1550786644.675 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-21T22:04:04.678Z,1550786644.678 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-21T22:04:04.821Z,1550786644.821 [CBIT] Loaded
2019-02-21T22:04:04.822Z,1550786644.822 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-21T22:04:04.822Z,1550786644.822 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-21T22:04:04.823Z,1550786644.823 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-21T22:04:04.927Z,1550786644.927 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-21T22:04:04.928Z,1550786644.928 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-21T22:04:05.056Z,1550786645.056 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-21T22:04:05.057Z,1550786645.057 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-21T22:04:05.148Z,1550786645.148 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-21T22:04:05.233Z,1550786645.233 [VerticalControl] Loaded
2019-02-21T22:04:05.234Z,1550786645.234 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-21T22:04:05.234Z,1550786645.234 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-21T22:04:05.293Z,1550786645.293 [HorizontalControl] Loaded
2019-02-21T22:04:05.294Z,1550786645.294 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-21T22:04:05.294Z,1550786645.294 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-21T22:04:05.296Z,1550786645.296 [SpeedControl] Loaded
2019-02-21T22:04:05.296Z,1550786645.296 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-21T22:04:05.297Z,1550786645.297 [LoopControl](DEBUG): Construct LoopControl.
2019-02-21T22:04:05.297Z,1550786645.297 [LoopControl] Loaded
2019-02-21T22:04:05.298Z,1550786645.298 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-21T22:04:05.298Z,1550786645.298 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-21T22:04:05.299Z,1550786645.299 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-21T22:04:05.342Z,1550786645.342 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-21T22:04:05.345Z,1550786645.345 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-21T22:04:05.346Z,1550786645.346 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-21T22:04:05.353Z,1550786645.353 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-21T22:04:05.354Z,1550786645.354 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0
2019-02-21T22:04:05.354Z,1550786645.354 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 889
2019-02-21T22:04:05.359Z,1550786645.359 [Supervisor](INFO): Main Thread ID is 800
2019-02-21T22:04:05.359Z,1550786645.359 [Supervisor](DEBUG): Running supervisor.
2019-02-21T22:04:05.360Z,1550786645.360 [CommandLine ThreadHandler](INFO): Handler Thread ID is 890
2019-02-21T22:04:05.362Z,1550786645.362 [controlThread ThreadHandler](INFO): Handler Thread ID is 891
2019-02-21T22:04:05.363Z,1550786645.363 [controlThread](DEBUG): Initializing ControlThread
2019-02-21T22:04:05.368Z,1550786645.368 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T22:04:05.369Z,1550786645.369 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-21T22:04:05.369Z,1550786645.369 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-21T22:04:05.371Z,1550786645.371 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-21T22:04:05.371Z,1550786645.371 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-21T22:04:05.371Z,1550786645.371 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-21T22:04:05.371Z,1550786645.371 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-21T22:04:05.372Z,1550786645.372 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-21T22:04:05.372Z,1550786645.372 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-21T22:04:05.373Z,1550786645.373 [SBIT](INFO): Initialize SBIT Component.
2019-02-21T22:04:05.374Z,1550786645.374 [SBIT](IMPORTANT): git: 2019-02-11-13-g62924c4
2019-02-21T22:04:05.374Z,1550786645.374 [SBIT](INFO): git hash: 62924c492e86bb9d7cd72440302a80a745358b26
2019-02-21T22:04:05.374Z,1550786645.374 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-21T22:04:05.374Z,1550786645.374 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-21T22:04:05.375Z,1550786645.375 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-21T22:04:05.376Z,1550786645.376 [IBIT](INFO): Initialize IBIT Component.
2019-02-21T22:04:05.377Z,1550786645.377 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-21T22:04:05.378Z,1550786645.378 [logger ThreadHandler](INFO): Handler Thread ID is 892
2019-02-21T22:04:05.389Z,1550786645.389 [CBIT](DEBUG): Initialized mux pins.
2019-02-21T22:04:05.389Z,1550786645.389 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-21T22:04:05.397Z,1550786645.397 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 893
2019-02-21T22:04:05.398Z,1550786645.398 [Radio_Surface](INFO): Powering up
2019-02-21T22:04:05.409Z,1550786645.409 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 894
2019-02-21T22:04:05.410Z,1550786645.410 [CTD_NeilBrown](INFO): Powering down
2019-02-21T22:04:05.417Z,1550786645.417 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-21T22:04:05.417Z,1550786645.417 [CBIT](DEBUG): Initializing heartbeat.
2019-02-21T22:04:05.437Z,1550786645.437 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 895
2019-02-21T22:04:05.438Z,1550786645.438 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T22:04:05.473Z,1550786645.473 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 896
2019-02-21T22:04:05.482Z,1550786645.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-21T22:04:05.482Z,1550786645.482 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-21T22:04:05.482Z,1550786645.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-21T22:04:05.482Z,1550786645.482 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-21T22:04:05.482Z,1550786645.482 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-21T22:04:05.483Z,1550786645.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-21T22:04:05.483Z,1550786645.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-21T22:04:05.483Z,1550786645.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-21T22:04:05.483Z,1550786645.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-21T22:04:05.483Z,1550786645.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-21T22:04:05.483Z,1550786645.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-21T22:04:05.484Z,1550786645.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-21T22:04:05.484Z,1550786645.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-21T22:04:05.484Z,1550786645.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-21T22:04:05.484Z,1550786645.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T22:04:05.484Z,1550786645.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-21T22:04:05.489Z,1550786645.489 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-21T22:04:05.489Z,1550786645.489 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-21T22:04:05.525Z,1550786645.525 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-21T22:04:05.527Z,1550786645.527 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-21T22:04:05.528Z,1550786645.528 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-21T22:04:05.528Z,1550786645.528 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-21T22:04:05.529Z,1550786645.529 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-21T22:04:05.552Z,1550786645.552 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T22:04:05.595Z,1550786645.595 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-02-21T22:04:05.596Z,1550786645.596 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-21T22:04:05.671Z,1550786645.671 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-21T22:04:05.672Z,1550786645.672 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-21T22:04:05.690Z,1550786645.690 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T22:04:05.715Z,1550786645.715 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-21T22:04:05.734Z,1550786645.734 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-21T22:04:05.739Z,1550786645.739 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-21T22:04:05.763Z,1550786645.763 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-02-21T22:04:05.768Z,1550786645.768 [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:04:05.781Z,1550786645.781 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-21T22:04:05.784Z,1550786645.784 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-21T22:04:05.793Z,1550786645.793 [DUSBL_Hydroid](INFO): Powering up
2019-02-21T22:04:05.793Z,1550786645.793 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-21T22:04:05.813Z,1550786645.813 [Micromodem](INFO): Start
2019-02-21T22:04:05.877Z,1550786645.877 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T22:04:06.214Z,1550786646.214 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-21T22:04:06.221Z,1550786646.221 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-21T22:04:06.222Z,1550786646.222 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-21T22:04:06.229Z,1550786646.229 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-21T22:04:06.230Z,1550786646.230 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-21T22:04:06.237Z,1550786646.237 [MassServo](DEBUG): Initializing MassServo.
2019-02-21T22:04:06.238Z,1550786646.238 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-21T22:04:06.245Z,1550786646.245 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-21T22:04:06.246Z,1550786646.246 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-21T22:04:06.253Z,1550786646.253 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-21T22:04:06.334Z,1550786646.334 [Micromodem](INFO): Starting
2019-02-21T22:04:06.335Z,1550786646.335 [Micromodem](INFO): Powering up
2019-02-21T22:04:06.335Z,1550786646.335 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T22:04:06.437Z,1550786646.437 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T22:04:06.497Z,1550786646.497 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T22:04:06.670Z,1550786646.670 [Micromodem](INFO): Starting
2019-02-21T22:04:06.689Z,1550786646.689 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T22:04:07.075Z,1550786647.075 [Micromodem](INFO): Starting
2019-02-21T22:04:07.479Z,1550786647.479 [Micromodem](INFO): Starting
2019-02-21T22:04:07.883Z,1550786647.883 [Micromodem](INFO): Starting
2019-02-21T22:04:08.287Z,1550786648.287 [Micromodem](INFO): Starting
2019-02-21T22:04:08.691Z,1550786648.691 [Micromodem](INFO): Starting
2019-02-21T22:04:09.107Z,1550786649.107 [Micromodem](INFO): Starting
2019-02-21T22:04:09.245Z,1550786649.245 [Aanderaa_O2](INFO): Powering down
2019-02-21T22:04:09.499Z,1550786649.499 [Micromodem](INFO): Starting
2019-02-21T22:04:09.910Z,1550786649.910 [Micromodem](INFO): Starting
2019-02-21T22:04:10.313Z,1550786650.313 [Micromodem](INFO): Starting
2019-02-21T22:04:10.315Z,1550786650.315 [Micromodem](INFO): Nmea in: $CAREV,012213,INIT,2.0.27690*4E

2019-02-21T22:04:10.711Z,1550786650.711 [Micromodem](INFO): Starting
2019-02-21T22:04:10.712Z,1550786650.712 [Micromodem](INFO): Nmea in: $CAREV,012213,SLOT1,2.0.27690*61

2019-02-21T22:04:11.126Z,1550786651.126 [Micromodem](INFO): Starting
2019-02-21T22:04:11.127Z,1550786651.127 [Micromodem](INFO): Nmea in: $CAREV,012213,AUV,2.0.27690*16

2019-02-21T22:04:11.128Z,1550786651.128 [Micromodem](INFO): Starting
2019-02-21T22:04:11.129Z,1550786651.129 [Micromodem](INFO): Nmea in: $CAREV,012213,COPROC,0.10.0.46*43

2019-02-21T22:04:11.129Z,1550786651.129 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:11.129Z,1550786651.129 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T22:04:11.130Z,1550786651.130 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:04:11.131Z,1550786651.131 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:11.132Z,1550786651.132 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T22:04:11.137Z,1550786651.137 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:11.138Z,1550786651.138 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T22:04:11.138Z,1550786651.138 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:11.138Z,1550786651.138 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T22:04:11.138Z,1550786651.138 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T22:04:11.139Z,1550786651.139 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T22:04:11.519Z,1550786651.519 [Micromodem](INFO): Starting
2019-02-21T22:04:11.520Z,1550786651.520 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:22:13Z,RTC,RTC*72

2019-02-21T22:04:11.520Z,1550786651.520 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:22:13Z,RTC,RTC*72

2019-02-21T22:04:11.923Z,1550786651.923 [Micromodem](INFO): Starting
2019-02-21T22:04:12.339Z,1550786652.339 [Micromodem](INFO): Starting
2019-02-21T22:04:12.339Z,1550786652.339 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T22:04:12.340Z,1550786652.340 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:04:13Z,2*8B

2019-02-21T22:04:12.340Z,1550786652.340 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:04:12.731Z,1550786652.731 [Micromodem](INFO): Starting
2019-02-21T22:04:12.732Z,1550786652.732 [Micromodem](INFO): Nmea in: $CAERR,012214,NI  ,17,Argument value out of range*02

2019-02-21T22:04:13.138Z,1550786653.138 [Micromodem](INFO): Starting
2019-02-21T22:04:13.551Z,1550786653.551 [Micromodem](INFO): Starting
2019-02-21T22:04:13.943Z,1550786653.943 [Micromodem](INFO): Starting
2019-02-21T22:04:14.354Z,1550786654.354 [Micromodem](INFO): Starting
2019-02-21T22:04:14.763Z,1550786654.763 [Micromodem](INFO): Starting
2019-02-21T22:04:15.158Z,1550786655.158 [Micromodem](INFO): Starting
2019-02-21T22:04:15.559Z,1550786655.559 [Micromodem](INFO): Starting
2019-02-21T22:04:15.975Z,1550786655.975 [Micromodem](INFO): Starting
2019-02-21T22:04:16.370Z,1550786656.370 [Micromodem](INFO): Starting
2019-02-21T22:04:16.778Z,1550786656.778 [Micromodem](INFO): Starting
2019-02-21T22:04:17.187Z,1550786657.187 [Micromodem](INFO): Starting
2019-02-21T22:04:17.579Z,1550786657.579 [Micromodem](INFO): Starting
2019-02-21T22:04:17.983Z,1550786657.983 [Micromodem](INFO): Starting
2019-02-21T22:04:18.387Z,1550786658.387 [Micromodem](INFO): Starting
2019-02-21T22:04:18.794Z,1550786658.794 [Micromodem](INFO): Starting
2019-02-21T22:04:19.195Z,1550786659.195 [Micromodem](INFO): Starting
2019-02-21T22:04:19.220Z,1550786659.220 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-21T22:04:19.611Z,1550786659.611 [Micromodem](INFO): Starting
2019-02-21T22:04:20.008Z,1550786660.008 [Micromodem](INFO): Starting
2019-02-21T22:04:20.407Z,1550786660.407 [Micromodem](INFO): Starting
2019-02-21T22:04:20.408Z,1550786660.408 [Micromodem](INFO): Nmea in: $CAERR,012215,NI  ,17,Argument value out of range*03

2019-02-21T22:04:20.823Z,1550786660.823 [Micromodem](INFO): Starting
2019-02-21T22:04:20.823Z,1550786660.823 [Micromodem](INFO): Nmea in: $CAREV,012223,AUV,2.0.27690*15

2019-02-21T22:04:21.215Z,1550786661.215 [Micromodem](INFO): Starting
2019-02-21T22:04:21.622Z,1550786661.622 [Micromodem](INFO): Starting
2019-02-21T22:04:22.038Z,1550786662.038 [Micromodem](INFO): Starting
2019-02-21T22:04:22.434Z,1550786662.434 [Micromodem](INFO): Starting
2019-02-21T22:04:22.832Z,1550786662.832 [Micromodem](INFO): Starting
2019-02-21T22:04:23.247Z,1550786663.247 [Micromodem](INFO): Starting
2019-02-21T22:04:23.642Z,1550786663.642 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-02-21T22:04:23.653Z,1550786663.653 [Micromodem](INFO): Starting
2019-02-21T22:04:24.053Z,1550786664.053 [Micromodem](INFO): Starting
2019-02-21T22:04:24.447Z,1550786664.447 [Micromodem](INFO): Starting
2019-02-21T22:04:24.854Z,1550786664.854 [Micromodem](INFO): Starting
2019-02-21T22:04:25.254Z,1550786665.254 [Micromodem](INFO): Starting
2019-02-21T22:04:25.666Z,1550786665.666 [Micromodem](INFO): Starting
2019-02-21T22:04:26.066Z,1550786666.066 [Micromodem](INFO): Starting
2019-02-21T22:04:26.067Z,1550786666.067 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,012223,AUV,2.0.27690*15
, available: $CAREV,012223,COPROC,0.10.0.46*40

2019-02-21T22:04:26.067Z,1550786666.067 [Micromodem] Communications Fault, FailCount= 1
2019-02-21T22:04:26.067Z,1550786666.067 [Micromodem](ERROR): Communications Fault
2019-02-21T22:04:26.161Z,1550786666.161 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T22:04:26.466Z,1550786666.466 [Micromodem](INFO): Stop
2019-02-21T22:04:26.467Z,1550786666.467 [Micromodem](INFO): uninitialize
2019-02-21T22:04:26.467Z,1550786666.467 [Micromodem](INFO): Powering down
2019-02-21T22:04:26.878Z,1550786666.878 [Micromodem](INFO): Stopping
2019-02-21T22:04:27.274Z,1550786667.274 [Micromodem](INFO): Stopped
2019-02-21T22:04:27.326Z,1550786667.326 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T22:04:27.326Z,1550786667.326 [Micromodem] No Fault, FailCount= 1
2019-02-21T22:04:27.678Z,1550786667.678 [Micromodem](INFO): Start
2019-02-21T22:04:28.090Z,1550786668.090 [Micromodem](INFO): Start
2019-02-21T22:04:28.486Z,1550786668.486 [Micromodem](INFO): Start
2019-02-21T22:04:28.890Z,1550786668.890 [Micromodem](INFO): Start
2019-02-21T22:04:29.302Z,1550786669.302 [Micromodem](INFO): Start
2019-02-21T22:04:29.699Z,1550786669.699 [Micromodem](INFO): Start
2019-02-21T22:04:30.110Z,1550786670.110 [Micromodem](INFO): Starting
2019-02-21T22:04:30.110Z,1550786670.110 [Micromodem](INFO): Powering up
2019-02-21T22:04:30.111Z,1550786670.111 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T22:04:30.514Z,1550786670.514 [Micromodem](INFO): Starting
2019-02-21T22:04:30.515Z,1550786670.515 [NAL9602](INFO): Powering up NAL9602
2019-02-21T22:04:30.922Z,1550786670.922 [Micromodem](INFO): Starting
2019-02-21T22:04:31.314Z,1550786671.314 [Micromodem](INFO): Starting
2019-02-21T22:04:31.726Z,1550786671.726 [Micromodem](INFO): Starting
2019-02-21T22:04:32.123Z,1550786672.123 [Micromodem](INFO): Starting
2019-02-21T22:04:32.526Z,1550786672.526 [Micromodem](INFO): Starting
2019-02-21T22:04:32.938Z,1550786672.938 [Micromodem](INFO): Starting
2019-02-21T22:04:33.334Z,1550786673.334 [Micromodem](INFO): Starting
2019-02-21T22:04:33.756Z,1550786673.756 [Micromodem](INFO): Starting
2019-02-21T22:04:33.783Z,1550786673.783 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-21T22:04:33.788Z,1550786673.788 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-21T22:04:34.150Z,1550786674.150 [Micromodem](INFO): Starting
2019-02-21T22:04:34.152Z,1550786674.152 [Micromodem](INFO): Nmea in: $CAREV,012237,INIT,2.0.27690*48

2019-02-21T22:04:34.152Z,1550786674.152 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,012237,INIT,2.0.27690*48

2019-02-21T22:04:34.554Z,1550786674.554 [Micromodem](INFO): Starting
2019-02-21T22:04:34.555Z,1550786674.555 [Micromodem](INFO): Nmea in: $CAREV,012237,SLOT1,2.0.27690*67

2019-02-21T22:04:34.556Z,1550786674.556 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,012237,SLOT1,2.0.27690*67

2019-02-21T22:04:34.950Z,1550786674.950 [Micromodem](INFO): Starting
2019-02-21T22:04:34.952Z,1550786674.952 [Micromodem](INFO): Nmea in: $CAREV,012237,AUV,2.0.27690*10

2019-02-21T22:04:34.952Z,1550786674.952 [Micromodem](INFO): Starting
2019-02-21T22:04:34.953Z,1550786674.953 [Micromodem](INFO): Nmea in: $CAREV,012237,COPROC,0.10.0.46*45

2019-02-21T22:04:34.953Z,1550786674.953 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:34.953Z,1550786674.953 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T22:04:34.953Z,1550786674.953 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:04:34.954Z,1550786674.954 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:34.954Z,1550786674.954 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T22:04:34.954Z,1550786674.954 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:34.954Z,1550786674.954 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T22:04:34.954Z,1550786674.954 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:34.954Z,1550786674.954 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T22:04:34.955Z,1550786674.955 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T22:04:34.955Z,1550786674.955 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T22:04:35.362Z,1550786675.362 [Micromodem](INFO): Starting
2019-02-21T22:04:35.363Z,1550786675.363 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:22:37Z,RTC,RTC*74

2019-02-21T22:04:35.364Z,1550786675.364 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:22:37Z,RTC,RTC*74

2019-02-21T22:04:35.986Z,1550786675.986 [Micromodem](INFO): Starting
2019-02-21T22:04:36.378Z,1550786676.378 [Micromodem](INFO): Starting
2019-02-21T22:04:36.379Z,1550786676.379 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T22:04:36.379Z,1550786676.379 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:04:37Z,2*8D

2019-02-21T22:04:36.379Z,1550786676.379 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:04:36.782Z,1550786676.782 [Micromodem](INFO): Starting
2019-02-21T22:04:36.784Z,1550786676.784 [Micromodem](INFO): Nmea in: $CAERR,012238,NI  ,17,Argument value out of range*0C

2019-02-21T22:04:37.184Z,1550786677.184 [Micromodem](INFO): Starting
2019-02-21T22:04:37.590Z,1550786677.590 [Micromodem](INFO): Starting
2019-02-21T22:04:37.994Z,1550786677.994 [Micromodem](INFO): Starting
2019-02-21T22:04:38.398Z,1550786678.398 [Micromodem](INFO): Starting
2019-02-21T22:04:38.802Z,1550786678.802 [Micromodem](INFO): Starting
2019-02-21T22:04:39.214Z,1550786679.214 [Micromodem](INFO): Starting
2019-02-21T22:04:39.606Z,1550786679.606 [Micromodem](INFO): Starting
2019-02-21T22:04:40.014Z,1550786680.014 [Micromodem](INFO): Starting
2019-02-21T22:04:40.418Z,1550786680.418 [Micromodem](INFO): Starting
2019-02-21T22:04:40.822Z,1550786680.822 [Micromodem](INFO): Starting
2019-02-21T22:04:41.226Z,1550786681.226 [Micromodem](INFO): Starting
2019-02-21T22:04:41.630Z,1550786681.630 [Micromodem](INFO): Starting
2019-02-21T22:04:41.631Z,1550786681.631 [NAL9602](INFO): NAL9602 initialized
2019-02-21T22:04:42.045Z,1550786682.045 [Micromodem](INFO): Starting
2019-02-21T22:04:42.438Z,1550786682.438 [Micromodem](INFO): Starting
2019-02-21T22:04:42.453Z,1550786682.453 [NAL9602](DEBUG): Fix Requested
2019-02-21T22:04:42.842Z,1550786682.842 [Micromodem](INFO): Starting
2019-02-21T22:04:43.246Z,1550786683.246 [Micromodem](INFO): Starting
2019-02-21T22:04:43.650Z,1550786683.650 [Micromodem](INFO): Starting
2019-02-21T22:04:44.062Z,1550786684.062 [Micromodem](INFO): Starting
2019-02-21T22:04:44.064Z,1550786684.064 [Micromodem](INFO): Nmea in: $CAERR,012239,NI  ,17,Argument value out of range*0D

2019-02-21T22:04:44.458Z,1550786684.458 [Micromodem](INFO): Starting
2019-02-21T22:04:44.459Z,1550786684.459 [Micromodem](INFO): Nmea in: $CAREV,012247,AUV,2.0.27690*17

2019-02-21T22:04:44.459Z,1550786684.459 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,012247,AUV,2.0.27690*17

2019-02-21T22:04:44.862Z,1550786684.862 [Micromodem](INFO): Starting
2019-02-21T22:04:45.278Z,1550786685.278 [Micromodem](INFO): Starting
2019-02-21T22:04:45.349Z,1550786685.349 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005546
CHAN A1 (24V): -0.026054
CHAN A2 (12V): -0.007548
CHAN A3 (5V): -0.003242
CHAN B0 (3.3V): -0.000558
CHAN B1 (3.15aV): -0.000205
CHAN B2 (3.15bV): -0.000213
CHAN B3 (GND): 0.002575
OPEN: 0.012287
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-21T22:04:45.667Z,1550786685.667 [Micromodem](INFO): Starting
2019-02-21T22:04:46.074Z,1550786686.074 [Micromodem](INFO): Starting
2019-02-21T22:04:46.478Z,1550786686.478 [Micromodem](INFO): Starting
2019-02-21T22:04:46.882Z,1550786686.882 [Micromodem](INFO): Starting
2019-02-21T22:04:47.286Z,1550786687.286 [Micromodem](INFO): Starting
2019-02-21T22:04:47.690Z,1550786687.690 [Micromodem](INFO): Starting
2019-02-21T22:04:48.094Z,1550786688.094 [Micromodem](INFO): Starting
2019-02-21T22:04:48.498Z,1550786688.498 [Micromodem](INFO): Starting
2019-02-21T22:04:48.902Z,1550786688.902 [Micromodem](INFO): Starting
2019-02-21T22:04:49.454Z,1550786689.454 [Micromodem](INFO): Starting
2019-02-21T22:04:49.858Z,1550786689.858 [Micromodem](INFO): Starting
2019-02-21T22:04:49.859Z,1550786689.859 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,012247,AUV,2.0.27690*17
, available: $CAREV,012247,COPROC,0.10.0.46*42

2019-02-21T22:04:49.859Z,1550786689.859 [Micromodem] Communications Fault, FailCount= 2
2019-02-21T22:04:49.859Z,1550786689.859 [Micromodem](ERROR): Communications Fault
2019-02-21T22:04:49.997Z,1550786689.997 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T22:04:50.262Z,1550786690.262 [Micromodem](INFO): Stop
2019-02-21T22:04:50.262Z,1550786690.262 [Micromodem](INFO): uninitialize
2019-02-21T22:04:50.262Z,1550786690.262 [Micromodem](INFO): Powering down
2019-02-21T22:04:50.666Z,1550786690.666 [Micromodem](INFO): Stopping
2019-02-21T22:04:51.070Z,1550786691.070 [Micromodem](INFO): Stopped
2019-02-21T22:04:51.214Z,1550786691.214 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T22:04:51.214Z,1550786691.214 [Micromodem] No Fault, FailCount= 2
2019-02-21T22:04:51.474Z,1550786691.474 [Micromodem](INFO): Start
2019-02-21T22:04:51.879Z,1550786691.879 [Micromodem](INFO): Start
2019-02-21T22:04:52.290Z,1550786692.290 [Micromodem](INFO): Start
2019-02-21T22:04:52.694Z,1550786692.694 [Micromodem](INFO): Start
2019-02-21T22:04:53.091Z,1550786693.091 [Micromodem](INFO): Start
2019-02-21T22:04:53.502Z,1550786693.502 [Micromodem](INFO): Start
2019-02-21T22:04:53.906Z,1550786693.906 [Micromodem](INFO): Starting
2019-02-21T22:04:53.906Z,1550786693.906 [Micromodem](INFO): Powering up
2019-02-21T22:04:53.907Z,1550786693.907 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T22:04:54.311Z,1550786694.311 [Micromodem](INFO): Starting
2019-02-21T22:04:54.714Z,1550786694.714 [Micromodem](INFO): Starting
2019-02-21T22:04:55.118Z,1550786695.118 [Micromodem](INFO): Starting
2019-02-21T22:04:55.515Z,1550786695.515 [Micromodem](INFO): Starting
2019-02-21T22:04:55.920Z,1550786695.920 [Micromodem](INFO): Starting
2019-02-21T22:04:56.330Z,1550786696.330 [Micromodem](INFO): Starting
2019-02-21T22:04:56.727Z,1550786696.727 [Micromodem](INFO): Starting
2019-02-21T22:04:57.131Z,1550786697.131 [Micromodem](INFO): Starting
2019-02-21T22:04:57.535Z,1550786697.535 [Micromodem](INFO): Starting
2019-02-21T22:04:57.939Z,1550786697.939 [Micromodem](INFO): Starting
2019-02-21T22:04:57.940Z,1550786697.940 [Micromodem](INFO): Nmea in: $CAREV,012301,INIT,2.0.27690*4C

2019-02-21T22:04:58.346Z,1550786698.346 [Micromodem](INFO): Starting
2019-02-21T22:04:58.348Z,1550786698.348 [Micromodem](INFO): Nmea in: $CAREV,012301,SLOT1,2.0.27690*63

2019-02-21T22:04:58.750Z,1550786698.750 [Micromodem](INFO): Starting
2019-02-21T22:04:58.751Z,1550786698.751 [Micromodem](INFO): Nmea in: $CAREV,012301,AUV,2.0.27690*14

2019-02-21T22:04:58.751Z,1550786698.751 [Micromodem](INFO): Starting
2019-02-21T22:04:58.752Z,1550786698.752 [Micromodem](INFO): Nmea in: $CAREV,012301,COPROC,0.10.0.46*41

2019-02-21T22:04:58.753Z,1550786698.753 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:58.753Z,1550786698.753 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T22:04:58.753Z,1550786698.753 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:04:58.755Z,1550786698.755 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:58.755Z,1550786698.755 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T22:04:58.757Z,1550786698.757 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:58.758Z,1550786698.758 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T22:04:58.759Z,1550786698.759 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:04:58.759Z,1550786698.759 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T22:04:58.760Z,1550786698.760 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T22:04:58.761Z,1550786698.761 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T22:04:59.158Z,1550786699.158 [Micromodem](INFO): Starting
2019-02-21T22:04:59.159Z,1550786699.159 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:23:01Z,RTC,RTC*70

2019-02-21T22:04:59.160Z,1550786699.160 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:23:01Z,RTC,RTC*70

2019-02-21T22:04:59.162Z,1550786699.162 [NAL9602](INFO): SBD MO Status=2, MOMSN=2140, MT Status=2, MTMSN=0
2019-02-21T22:04:59.162Z,1550786699.162 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-21T22:04:59.560Z,1550786699.560 [Micromodem](INFO): Starting
2019-02-21T22:04:59.959Z,1550786699.959 [Micromodem](INFO): Starting
2019-02-21T22:04:59.959Z,1550786699.959 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T22:04:59.959Z,1550786699.959 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:05:00Z,2*89

2019-02-21T22:04:59.960Z,1550786699.960 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:05:00.363Z,1550786700.363 [Micromodem](INFO): Starting
2019-02-21T22:05:00.364Z,1550786700.364 [Micromodem](INFO): Nmea in: $CAERR,012302,NI  ,17,Argument value out of range*04

2019-02-21T22:05:00.767Z,1550786700.767 [Micromodem](INFO): Starting
2019-02-21T22:05:01.171Z,1550786701.171 [Micromodem](INFO): Starting
2019-02-21T22:05:01.575Z,1550786701.575 [Micromodem](INFO): Starting
2019-02-21T22:05:01.979Z,1550786701.979 [Micromodem](INFO): Starting
2019-02-21T22:05:02.383Z,1550786702.383 [Micromodem](INFO): Starting
2019-02-21T22:05:02.792Z,1550786702.792 [Micromodem](INFO): Starting
2019-02-21T22:05:03.191Z,1550786703.191 [Micromodem](INFO): Starting
2019-02-21T22:05:03.602Z,1550786703.602 [Micromodem](INFO): Starting
2019-02-21T22:05:03.999Z,1550786703.999 [Micromodem](INFO): Starting
2019-02-21T22:05:04.403Z,1550786704.403 [Micromodem](INFO): Starting
2019-02-21T22:05:04.807Z,1550786704.807 [Micromodem](INFO): Starting
2019-02-21T22:05:05.211Z,1550786705.211 [Micromodem](INFO): Starting
2019-02-21T22:05:05.610Z,1550786705.610 [Micromodem](INFO): Starting
2019-02-21T22:05:06.024Z,1550786706.024 [Micromodem](INFO): Starting
2019-02-21T22:05:06.423Z,1550786706.423 [Micromodem](INFO): Starting
2019-02-21T22:05:06.827Z,1550786706.827 [Micromodem](INFO): Starting
2019-02-21T22:05:07.231Z,1550786707.231 [Micromodem](INFO): Starting
2019-02-21T22:05:07.635Z,1550786707.635 [Micromodem](INFO): Starting
2019-02-21T22:05:08.048Z,1550786708.048 [Micromodem](INFO): Starting
2019-02-21T22:05:08.052Z,1550786708.052 [Micromodem](INFO): Nmea in: $CAERR,012303,NI  ,17,Argument value out of range*05

2019-02-21T22:05:08.443Z,1550786708.443 [Micromodem](INFO): Starting
2019-02-21T22:05:08.444Z,1550786708.444 [Micromodem](INFO): Nmea in: $CAREV,012311,AUV,2.0.27690*15

2019-02-21T22:05:08.847Z,1550786708.847 [Micromodem](INFO): Starting
2019-02-21T22:05:09.251Z,1550786709.251 [Micromodem](INFO): Starting
2019-02-21T22:05:09.655Z,1550786709.655 [Micromodem](INFO): Starting
2019-02-21T22:05:10.066Z,1550786710.066 [Micromodem](INFO): Starting
2019-02-21T22:05:10.463Z,1550786710.463 [Micromodem](INFO): Starting
2019-02-21T22:05:10.867Z,1550786710.867 [Micromodem](INFO): Starting
2019-02-21T22:05:11.271Z,1550786711.271 [Micromodem](INFO): Starting
2019-02-21T22:05:11.678Z,1550786711.678 [Micromodem](INFO): Starting
2019-02-21T22:05:12.079Z,1550786712.079 [Micromodem](INFO): Starting
2019-02-21T22:05:12.483Z,1550786712.483 [Micromodem](INFO): Starting
2019-02-21T22:05:12.884Z,1550786712.884 [Micromodem](INFO): Starting
2019-02-21T22:05:13.300Z,1550786713.300 [Micromodem](INFO): Starting
2019-02-21T22:05:13.695Z,1550786713.695 [Micromodem](INFO): Starting
2019-02-21T22:05:13.695Z,1550786713.695 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,012311,AUV,2.0.27690*15
, available: $CAREV,012311,COPROC,0.10.0.46*40

2019-02-21T22:05:13.696Z,1550786713.696 [Micromodem] Communications Fault, FailCount= 3
2019-02-21T22:05:13.696Z,1550786713.696 [Micromodem](ERROR): Communications Fault
2019-02-21T22:05:13.757Z,1550786713.757 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T22:05:14.099Z,1550786714.099 [Micromodem](INFO): Stop
2019-02-21T22:05:14.099Z,1550786714.099 [Micromodem](INFO): uninitialize
2019-02-21T22:05:14.099Z,1550786714.099 [Micromodem](INFO): Powering down
2019-02-21T22:05:14.503Z,1550786714.503 [Micromodem](INFO): Stopping
2019-02-21T22:05:14.910Z,1550786714.910 [Micromodem](INFO): Stopped
2019-02-21T22:05:14.947Z,1550786714.947 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T22:05:14.947Z,1550786714.947 [Micromodem] No Fault, FailCount= 3
2019-02-21T22:05:15.311Z,1550786715.311 [Micromodem](INFO): Start
2019-02-21T22:05:15.715Z,1550786715.715 [Micromodem](INFO): Start
2019-02-21T22:05:16.194Z,1550786716.194 [Micromodem](INFO): Start
2019-02-21T22:05:16.598Z,1550786716.598 [Micromodem](INFO): Start
2019-02-21T22:05:17.005Z,1550786717.005 [Micromodem](INFO): Start
2019-02-21T22:05:17.406Z,1550786717.406 [Micromodem](INFO): Start
2019-02-21T22:05:17.810Z,1550786717.810 [Micromodem](INFO): Starting
2019-02-21T22:05:17.810Z,1550786717.810 [Micromodem](INFO): Powering up
2019-02-21T22:05:17.811Z,1550786717.811 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T22:05:18.214Z,1550786718.214 [Micromodem](INFO): Starting
2019-02-21T22:05:18.618Z,1550786718.618 [Micromodem](INFO): Starting
2019-02-21T22:05:19.037Z,1550786719.037 [Micromodem](INFO): Starting
2019-02-21T22:05:19.426Z,1550786719.426 [Micromodem](INFO): Starting
2019-02-21T22:05:19.830Z,1550786719.830 [Micromodem](INFO): Starting
2019-02-21T22:05:20.234Z,1550786720.234 [Micromodem](INFO): Starting
2019-02-21T22:05:20.639Z,1550786720.639 [Micromodem](INFO): Starting
2019-02-21T22:05:20.640Z,1550786720.640 [Micromodem](INFO): Nmea in: $CAREV,012324,INIT,2.0.27690*4B

2019-02-21T22:05:21.046Z,1550786721.046 [Micromodem](INFO): Starting
2019-02-21T22:05:21.050Z,1550786721.050 [Micromodem](INFO): Nmea in: $CAREV,012324,SLOT1,2.0.27690*64

2019-02-21T22:05:21.446Z,1550786721.446 [Micromodem](INFO): Starting
2019-02-21T22:05:21.447Z,1550786721.447 [Micromodem](INFO): Nmea in: $CAREV,012324,AUV,2.0.27690*13

2019-02-21T22:05:21.846Z,1550786721.846 [Micromodem](INFO): Starting
2019-02-21T22:05:21.847Z,1550786721.847 [Micromodem](INFO): Nmea in: $CAREV,012324,COPROC,0.10.0.46*46

2019-02-21T22:05:21.849Z,1550786721.849 [NAL9602](INFO): SBD MO Status=2, MOMSN=2140, MT Status=2, MTMSN=0
2019-02-21T22:05:21.849Z,1550786721.849 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-21T22:05:22.252Z,1550786722.252 [Micromodem](INFO): Starting
2019-02-21T22:05:22.658Z,1550786722.658 [Micromodem](INFO): Starting
2019-02-21T22:05:22.658Z,1550786722.658 [Micromodem](INFO): Starting
2019-02-21T22:05:22.659Z,1550786722.659 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:05:22.659Z,1550786722.659 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T22:05:22.659Z,1550786722.659 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:05:22.660Z,1550786722.660 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:05:22.662Z,1550786722.662 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T22:05:22.663Z,1550786722.663 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:05:22.663Z,1550786722.663 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T22:05:22.664Z,1550786722.664 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:05:22.686Z,1550786722.686 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T22:05:22.686Z,1550786722.686 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T22:05:22.687Z,1550786722.687 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T22:05:23.062Z,1550786723.062 [Micromodem](INFO): Starting
2019-02-21T22:05:23.064Z,1550786723.064 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:23:24Z,RTC,RTC*77

2019-02-21T22:05:23.064Z,1550786723.064 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:23:24Z,RTC,RTC*77

2019-02-21T22:05:23.462Z,1550786723.462 [Micromodem](INFO): Starting
2019-02-21T22:05:23.870Z,1550786723.870 [Micromodem](INFO): Starting
2019-02-21T22:05:23.871Z,1550786723.871 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T22:05:23.871Z,1550786723.871 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:05:24Z,2*8E

2019-02-21T22:05:23.871Z,1550786723.871 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:05:24.274Z,1550786724.274 [Micromodem](INFO): Starting
2019-02-21T22:05:24.276Z,1550786724.276 [Micromodem](INFO): Nmea in: $CAERR,012326,NI  ,17,Argument value out of range*02

2019-02-21T22:05:24.678Z,1550786724.678 [Micromodem](INFO): Starting
2019-02-21T22:05:25.082Z,1550786725.082 [Micromodem](INFO): Starting
2019-02-21T22:05:25.482Z,1550786725.482 [Micromodem](INFO): Starting
2019-02-21T22:05:25.888Z,1550786725.888 [Micromodem](INFO): Starting
2019-02-21T22:05:26.290Z,1550786726.290 [Micromodem](INFO): Starting
2019-02-21T22:05:26.694Z,1550786726.694 [Micromodem](INFO): Starting
2019-02-21T22:05:27.102Z,1550786727.102 [Micromodem](INFO): Starting
2019-02-21T22:05:27.184Z,1550786727.184 [SBIT](IMPORTANT): SBIT PASSED
2019-02-21T22:05:27.242Z,1550786727.242 [CommandLine](IMPORTANT): got command configSet list
2019-02-21T22:05:27.242Z,1550786727.242 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-21T22:05:27.242Z,1550786727.242 [CommandLine](IMPORTANT): No configSet variables persisted
2019-02-21T22:05:27.502Z,1550786727.502 [Micromodem](INFO): Starting
2019-02-21T22:05:27.566Z,1550786727.566 [MissionManager](IMPORTANT): Started mission Startup
2019-02-21T22:05:27.567Z,1550786727.567 [Startup] Running Loop=1
2019-02-21T22:05:27.567Z,1550786727.567 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-21T22:05:27.567Z,1550786727.567 [Startup:A.GoToSurface] Running Loop=1
2019-02-21T22:05:27.567Z,1550786727.567 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-21T22:05:27.568Z,1550786727.568 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-21T22:05:27.568Z,1550786727.568 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-21T22:05:27.568Z,1550786727.568 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-21T22:05:27.569Z,1550786727.569 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-21T22:05:27.569Z,1550786727.569 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-21T22:05:27.571Z,1550786727.571 [Startup:StartupSatComms] Running Loop=1
2019-02-21T22:05:27.571Z,1550786727.571 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-21T22:05:27.571Z,1550786727.571 [Startup:StartupSatComms:A] Running Loop=1
2019-02-21T22:05:27.906Z,1550786727.906 [Micromodem](INFO): Starting
2019-02-21T22:05:27.935Z,1550786727.935 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-21T22:05:28.310Z,1550786728.310 [Micromodem](INFO): Starting
2019-02-21T22:05:28.714Z,1550786728.714 [Micromodem](INFO): Starting
2019-02-21T22:05:29.120Z,1550786729.120 [Micromodem](INFO): Starting
2019-02-21T22:05:29.522Z,1550786729.522 [Micromodem](INFO): Starting
2019-02-21T22:05:29.930Z,1550786729.930 [Micromodem](INFO): Starting
2019-02-21T22:05:30.334Z,1550786730.334 [Micromodem](INFO): Starting
2019-02-21T22:05:30.738Z,1550786730.738 [Micromodem](INFO): Starting
2019-02-21T22:05:30.740Z,1550786730.740 [Micromodem](INFO): Nmea in: $CAERR,012327,NI  ,17,Argument value out of range*03

2019-02-21T22:05:31.138Z,1550786731.138 [Micromodem](INFO): Starting
2019-02-21T22:05:31.139Z,1550786731.139 [Micromodem](INFO): Nmea in: $CAREV,012334,AUV,2.0.27690*12

2019-02-21T22:05:31.546Z,1550786731.546 [Micromodem](INFO): Starting
2019-02-21T22:05:31.946Z,1550786731.946 [Micromodem](INFO): Starting
2019-02-21T22:05:32.352Z,1550786732.352 [Micromodem](INFO): Starting
2019-02-21T22:05:32.754Z,1550786732.754 [Micromodem](INFO): Starting
2019-02-21T22:05:33.162Z,1550786733.162 [Micromodem](INFO): Starting
2019-02-21T22:05:33.562Z,1550786733.562 [Micromodem](INFO): Starting
2019-02-21T22:05:33.970Z,1550786733.970 [Micromodem](INFO): Starting
2019-02-21T22:05:34.370Z,1550786734.370 [Micromodem](INFO): Starting
2019-02-21T22:05:34.774Z,1550786734.774 [Micromodem](INFO): Starting
2019-02-21T22:05:35.178Z,1550786735.178 [Micromodem](INFO): Starting
2019-02-21T22:05:35.588Z,1550786735.588 [Micromodem](INFO): Starting
2019-02-21T22:05:35.986Z,1550786735.986 [Micromodem](INFO): Starting
2019-02-21T22:05:36.390Z,1550786736.390 [Micromodem](INFO): Starting
2019-02-21T22:05:36.798Z,1550786736.798 [Micromodem](INFO): Starting
2019-02-21T22:05:37.202Z,1550786737.202 [Micromodem](INFO): Starting
2019-02-21T22:05:37.606Z,1550786737.606 [Micromodem](INFO): Starting
2019-02-21T22:05:37.607Z,1550786737.607 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,012334,AUV,2.0.27690*12
, available: $CAREV,012334,COPROC,0.10.0.46*47

2019-02-21T22:05:37.607Z,1550786737.607 [Micromodem] Communications Fault, FailCount= 4
2019-02-21T22:05:37.607Z,1550786737.607 [Micromodem](ERROR): Communications Fault
2019-02-21T22:05:37.639Z,1550786737.639 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T22:05:38.007Z,1550786738.007 [Micromodem](INFO): Stop
2019-02-21T22:05:38.007Z,1550786738.007 [Micromodem](INFO): uninitialize
2019-02-21T22:05:38.007Z,1550786738.007 [Micromodem](INFO): Powering down
2019-02-21T22:05:38.410Z,1550786738.410 [Micromodem](INFO): Stopping
2019-02-21T22:05:38.816Z,1550786738.816 [Micromodem](INFO): Stopped
2019-02-21T22:05:38.851Z,1550786738.851 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T22:05:38.851Z,1550786738.851 [Micromodem] No Fault, FailCount= 4
2019-02-21T22:05:39.218Z,1550786739.218 [Micromodem](INFO): Start
2019-02-21T22:05:39.626Z,1550786739.626 [Micromodem](INFO): Start
2019-02-21T22:05:40.032Z,1550786740.032 [Micromodem](INFO): Start
2019-02-21T22:05:40.434Z,1550786740.434 [Micromodem](INFO): Start
2019-02-21T22:05:40.834Z,1550786740.834 [Micromodem](INFO): Start
2019-02-21T22:05:41.238Z,1550786741.238 [Micromodem](INFO): Start
2019-02-21T22:05:41.642Z,1550786741.642 [Micromodem](INFO): Starting
2019-02-21T22:05:41.642Z,1550786741.642 [Micromodem](INFO): Powering up
2019-02-21T22:05:41.643Z,1550786741.643 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T22:05:42.052Z,1550786742.052 [Micromodem](INFO): Starting
2019-02-21T22:05:42.450Z,1550786742.450 [Micromodem](INFO): Starting
2019-02-21T22:05:42.858Z,1550786742.858 [Micromodem](INFO): Starting
2019-02-21T22:05:43.262Z,1550786743.262 [Micromodem](INFO): Starting
2019-02-21T22:05:43.663Z,1550786743.663 [Micromodem](INFO): Starting
2019-02-21T22:05:44.066Z,1550786744.066 [Micromodem](INFO): Starting
2019-02-21T22:05:44.470Z,1550786744.470 [Micromodem](INFO): Starting
2019-02-21T22:05:44.874Z,1550786744.874 [Micromodem](INFO): Starting
2019-02-21T22:05:45.278Z,1550786745.278 [Micromodem](INFO): Starting
2019-02-21T22:05:45.688Z,1550786745.688 [Micromodem](INFO): Starting
2019-02-21T22:05:45.689Z,1550786745.689 [Micromodem](INFO): Nmea in: $CAREV,012349,INIT,2.0.27690*40

2019-02-21T22:05:46.090Z,1550786746.090 [Micromodem](INFO): Starting
2019-02-21T22:05:46.091Z,1550786746.091 [Micromodem](INFO): Nmea in: $CAREV,012349,SLOT1,2.0.27690*6F

2019-02-21T22:05:46.494Z,1550786746.494 [Micromodem](INFO): Starting
2019-02-21T22:05:46.495Z,1550786746.495 [Micromodem](INFO): Nmea in: $CAREV,012349,AUV,2.0.27690*18

2019-02-21T22:05:46.495Z,1550786746.495 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,012349,AUV,2.0.27690*18

2019-02-21T22:05:46.495Z,1550786746.495 [Micromodem](INFO): Starting
2019-02-21T22:05:46.496Z,1550786746.496 [Micromodem](INFO): Nmea in: $CAREV,012349,COPROC,0.10.0.46*4D

2019-02-21T22:05:46.497Z,1550786746.497 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:05:46.497Z,1550786746.497 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T22:05:46.497Z,1550786746.497 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:05:46.499Z,1550786746.499 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:05:46.499Z,1550786746.499 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T22:05:46.500Z,1550786746.500 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:05:46.501Z,1550786746.501 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T22:05:46.502Z,1550786746.502 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:05:46.503Z,1550786746.503 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T22:05:46.504Z,1550786746.504 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T22:05:46.505Z,1550786746.505 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T22:05:46.898Z,1550786746.898 [Micromodem](INFO): Starting
2019-02-21T22:05:46.899Z,1550786746.899 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:23:49Z,RTC,RTC*7C

2019-02-21T22:05:46.900Z,1550786746.900 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:23:49Z,RTC,RTC*7C

2019-02-21T22:05:47.302Z,1550786747.302 [Micromodem](INFO): Starting
2019-02-21T22:05:47.706Z,1550786747.706 [Micromodem](INFO): Starting
2019-02-21T22:05:47.707Z,1550786747.707 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T22:05:47.707Z,1550786747.707 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:05:48Z,2*84

2019-02-21T22:05:47.707Z,1550786747.707 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:05:48.107Z,1550786748.107 [Micromodem](INFO): Starting
2019-02-21T22:05:48.108Z,1550786748.108 [Micromodem](INFO): Nmea in: $CAERR,012350,NI  ,17,Argument value out of range*03

2019-02-21T22:05:48.515Z,1550786748.515 [Micromodem](INFO): Starting
2019-02-21T22:05:48.916Z,1550786748.916 [Micromodem](INFO): Starting
2019-02-21T22:05:49.318Z,1550786749.318 [Micromodem](INFO): Starting
2019-02-21T22:05:49.726Z,1550786749.726 [Micromodem](INFO): Starting
2019-02-21T22:05:50.130Z,1550786750.130 [Micromodem](INFO): Starting
2019-02-21T22:05:50.534Z,1550786750.534 [Micromodem](INFO): Starting
2019-02-21T22:05:50.938Z,1550786750.938 [Micromodem](INFO): Starting
2019-02-21T22:05:51.342Z,1550786751.342 [Micromodem](INFO): Starting
2019-02-21T22:05:51.746Z,1550786751.746 [Micromodem](INFO): Starting
2019-02-21T22:05:52.156Z,1550786752.156 [Micromodem](INFO): Starting
2019-02-21T22:05:52.550Z,1550786752.550 [Micromodem](INFO): Starting
2019-02-21T22:05:52.958Z,1550786752.958 [Micromodem](INFO): Starting
2019-02-21T22:05:53.362Z,1550786753.362 [Micromodem](INFO): Starting
2019-02-21T22:05:53.762Z,1550786753.762 [Micromodem](INFO): Starting
2019-02-21T22:05:54.170Z,1550786754.170 [Micromodem](INFO): Starting
2019-02-21T22:05:54.574Z,1550786754.574 [Micromodem](INFO): Starting
2019-02-21T22:05:54.974Z,1550786754.974 [Micromodem](INFO): Starting
2019-02-21T22:05:55.378Z,1550786755.378 [Micromodem](INFO): Starting
2019-02-21T22:05:55.782Z,1550786755.782 [Micromodem](INFO): Starting
2019-02-21T22:05:55.784Z,1550786755.784 [Micromodem](INFO): Nmea in: $CAERR,012351,NI  ,17,Argument value out of range*02

2019-02-21T22:05:56.186Z,1550786756.186 [Micromodem](INFO): Starting
2019-02-21T22:05:56.187Z,1550786756.187 [Micromodem](INFO): Nmea in: $CAREV,012359,AUV,2.0.27690*19

2019-02-21T22:05:56.187Z,1550786756.187 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,012359,AUV,2.0.27690*19

2019-02-21T22:05:56.594Z,1550786756.594 [Micromodem](INFO): Starting
2019-02-21T22:05:56.998Z,1550786756.998 [Micromodem](INFO): Starting
2019-02-21T22:05:57.398Z,1550786757.398 [Micromodem](INFO): Starting
2019-02-21T22:05:57.802Z,1550786757.802 [Micromodem](INFO): Starting
2019-02-21T22:05:58.206Z,1550786758.206 [Micromodem](INFO): Starting
2019-02-21T22:05:58.610Z,1550786758.610 [Micromodem](INFO): Starting
2019-02-21T22:05:59.014Z,1550786759.014 [Micromodem](INFO): Starting
2019-02-21T22:05:59.418Z,1550786759.418 [Micromodem](INFO): Starting
2019-02-21T22:05:59.822Z,1550786759.822 [Micromodem](INFO): Starting
2019-02-21T22:06:00.226Z,1550786760.226 [Micromodem](INFO): Starting
2019-02-21T22:06:00.630Z,1550786760.630 [Micromodem](INFO): Starting
2019-02-21T22:06:01.041Z,1550786761.041 [Micromodem](INFO): Starting
2019-02-21T22:06:01.446Z,1550786761.446 [Micromodem](INFO): Starting
2019-02-21T22:06:01.447Z,1550786761.447 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,012359,AUV,2.0.27690*19
, available: $CAREV,012359,COPROC,0.10.0.46*4C

2019-02-21T22:06:01.447Z,1550786761.447 [Micromodem] Communications Fault, FailCount= 5
2019-02-21T22:06:01.447Z,1550786761.447 [Micromodem](ERROR): Communications Fault
2019-02-21T22:06:01.480Z,1550786761.480 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T22:06:01.842Z,1550786761.842 [Micromodem](INFO): Stop
2019-02-21T22:06:01.842Z,1550786761.842 [Micromodem](INFO): uninitialize
2019-02-21T22:06:01.842Z,1550786761.842 [Micromodem](INFO): Powering down
2019-02-21T22:06:02.246Z,1550786762.246 [Micromodem](INFO): Stopping
2019-02-21T22:06:02.650Z,1550786762.650 [Micromodem](INFO): Stopped
2019-02-21T22:06:02.696Z,1550786762.696 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T22:06:02.697Z,1550786762.697 [Micromodem] No Fault, FailCount= 5
2019-02-21T22:06:03.055Z,1550786763.055 [Micromodem](INFO): Start
2019-02-21T22:06:03.458Z,1550786763.458 [Micromodem](INFO): Start
2019-02-21T22:06:03.670Z,1550786763.670 [CommandLine](IMPORTANT): got command get Onboard.Temperature
2019-02-21T22:06:03.670Z,1550786763.670 [CommandLine](IMPORTANT): Onboard.Temperature 21.237940 degC
2019-02-21T22:06:03.870Z,1550786763.870 [Micromodem](INFO): Start
2019-02-21T22:06:04.266Z,1550786764.266 [Micromodem](INFO): Start
2019-02-21T22:06:04.674Z,1550786764.674 [Micromodem](INFO): Start
2019-02-21T22:06:05.078Z,1550786765.078 [Micromodem](INFO): Start
2019-02-21T22:06:05.482Z,1550786765.482 [Micromodem](INFO): Starting
2019-02-21T22:06:05.483Z,1550786765.483 [Micromodem](INFO): Powering up
2019-02-21T22:06:05.483Z,1550786765.483 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T22:06:05.524Z,1550786765.524 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-21T22:06:05.524Z,1550786765.524 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-21T22:06:05.524Z,1550786765.524 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T22:06:05.526Z,1550786765.526 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-21T22:06:05.526Z,1550786765.526 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-21T22:06:05.526Z,1550786765.526 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T22:06:05.527Z,1550786765.527 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-21T22:06:05.527Z,1550786765.527 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-21T22:06:05.528Z,1550786765.528 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T22:06:05.539Z,1550786765.539 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T22:06:05.539Z,1550786765.539 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T22:06:05.539Z,1550786765.539 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T22:06:05.893Z,1550786765.893 [Micromodem](INFO): Starting
2019-02-21T22:06:05.923Z,1550786765.923 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T22:06:05.923Z,1550786765.923 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-21T22:06:05.923Z,1550786765.923 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T22:06:05.923Z,1550786765.923 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-21T22:06:05.924Z,1550786765.924 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T22:06:05.924Z,1550786765.924 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-21T22:06:06.286Z,1550786766.286 [Micromodem](INFO): Starting
2019-02-21T22:06:06.390Z,1550786766.390 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T22:06:06.695Z,1550786766.695 [Micromodem](INFO): Starting
2019-02-21T22:06:07.094Z,1550786767.094 [Micromodem](INFO): Starting
2019-02-21T22:06:07.498Z,1550786767.498 [Micromodem](INFO): Starting
2019-02-21T22:06:07.906Z,1550786767.906 [Micromodem](INFO): Starting
2019-02-21T22:06:08.306Z,1550786768.306 [Micromodem](INFO): Starting
2019-02-21T22:06:08.712Z,1550786768.712 [Micromodem](INFO): Starting
2019-02-21T22:06:09.114Z,1550786769.114 [Micromodem](INFO): Starting
2019-02-21T22:06:09.518Z,1550786769.518 [Micromodem](INFO): Starting
2019-02-21T22:06:09.519Z,1550786769.519 [Micromodem](INFO): Nmea in: $CAREV,012412,INIT,2.0.27690*49

2019-02-21T22:06:09.520Z,1550786769.520 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,012412,INIT,2.0.27690*49

2019-02-21T22:06:09.922Z,1550786769.922 [Micromodem](INFO): Starting
2019-02-21T22:06:09.923Z,1550786769.923 [Micromodem](INFO): Nmea in: $CAREV,012412,SLOT1,2.0.27690*66

2019-02-21T22:06:10.326Z,1550786770.326 [Micromodem](INFO): Starting
2019-02-21T22:06:10.327Z,1550786770.327 [Micromodem](INFO): Nmea in: $CAREV,012412,AUV,2.0.27690*11

2019-02-21T22:06:10.327Z,1550786770.327 [Micromodem](INFO): Starting
2019-02-21T22:06:10.328Z,1550786770.328 [Micromodem](INFO): Nmea in: $CAREV,012412,COPROC,0.10.0.46*44

2019-02-21T22:06:10.329Z,1550786770.329 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:06:10.329Z,1550786770.329 [Micromodem](INFO): Nmea buf: $CCCFG,CST,C*45

2019-02-21T22:06:10.329Z,1550786770.329 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:06:10.331Z,1550786770.331 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:06:10.331Z,1550786770.331 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,S*53

2019-02-21T22:06:10.332Z,1550786770.332 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:06:10.333Z,1550786770.333 [Micromodem](INFO): Nmea buf: $CCCFG,REV,R*51

2019-02-21T22:06:10.334Z,1550786770.334 [Micromodem](INFO): Nmea cmd: $CCCFG
2019-02-21T22:06:10.335Z,1550786770.335 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,R*4A

2019-02-21T22:06:10.336Z,1550786770.336 [Micromodem](INFO): Nmea cmd: $CCMSC
2019-02-21T22:06:10.337Z,1550786770.337 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,1*40

2019-02-21T22:06:10.730Z,1550786770.730 [Micromodem](INFO): Starting
2019-02-21T22:06:10.731Z,1550786770.731 [Micromodem](INFO): Nmea in: $CATMG,1902-02-25T01:24:12Z,RTC,RTC*75

2019-02-21T22:06:10.731Z,1550786770.731 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATMG,1902-02-25T01:24:12Z,RTC,RTC*75

2019-02-21T22:06:11.134Z,1550786771.134 [Micromodem](INFO): Starting
2019-02-21T22:06:11.538Z,1550786771.538 [Micromodem](INFO): Starting
2019-02-21T22:06:11.539Z,1550786771.539 [Micromodem](INFO): Nmea cmd: $CCTMS
2019-02-21T22:06:11.539Z,1550786771.539 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T22:06:12Z,2*89

2019-02-21T22:06:11.539Z,1550786771.539 [Micromodem](INFO): Nmea out: $CCCFG,CST,C*45

2019-02-21T22:06:11.942Z,1550786771.942 [Micromodem](INFO): Starting
2019-02-21T22:06:11.944Z,1550786771.944 [Micromodem](INFO): Nmea in: $CAERR,012413,NI  ,17,Argument value out of range*03

2019-02-21T22:06:12.350Z,1550786772.350 [Micromodem](INFO): Starting
2019-02-21T22:06:12.754Z,1550786772.754 [Micromodem](INFO): Starting
2019-02-21T22:06:13.158Z,1550786773.158 [Micromodem](INFO): Starting
2019-02-21T22:06:13.214Z,1550786773.214 [CommandLine](IMPORTANT): got command quit
2019-02-21T22:06:13.562Z,1550786773.562 [Micromodem](INFO): Starting
2019-02-21T22:06:13.966Z,1550786773.966 [Micromodem](INFO): Starting
2019-02-21T22:06:14.217Z,1550786774.217 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-02-21T22:06:14.217Z,1550786774.217 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T22:06:14.217Z,1550786774.217 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:14.269Z,1550786774.269 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-21T22:06:14.269Z,1550786774.269 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:14.270Z,1550786774.270 [CommandLine](INFO): Join timeout helper Thread ID is 926
2019-02-21T22:06:14.270Z,1550786774.270 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-21T22:06:14.270Z,1550786774.270 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:14.271Z,1550786774.271 [NavChartDb](INFO): Join timeout helper Thread ID is 927
2019-02-21T22:06:14.366Z,1550786774.366 [Micromodem](INFO): Starting
2019-02-21T22:06:14.549Z,1550786774.549 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T22:06:14.549Z,1550786774.549 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:14.557Z,1550786774.557 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-21T22:06:14.557Z,1550786774.557 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:14.557Z,1550786774.557 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 928
2019-02-21T22:06:14.745Z,1550786774.745 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T22:06:14.745Z,1550786774.745 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T22:06:14.746Z,1550786774.746 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:14.759Z,1550786774.759 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-21T22:06:14.759Z,1550786774.759 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:14.759Z,1550786774.759 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 929
2019-02-21T22:06:14.770Z,1550786774.770 [Micromodem](INFO): Starting
2019-02-21T22:06:14.862Z,1550786774.862 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T22:06:14.862Z,1550786774.862 [CTD_NeilBrown](INFO): Powering down
2019-02-21T22:06:14.873Z,1550786774.873 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:14.877Z,1550786774.877 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-21T22:06:14.877Z,1550786774.877 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:14.877Z,1550786774.877 [Radio_Surface](INFO): Join timeout helper Thread ID is 930
2019-02-21T22:06:15.101Z,1550786775.101 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T22:06:15.101Z,1550786775.101 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.106Z,1550786775.106 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-21T22:06:15.106Z,1550786775.106 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.106Z,1550786775.106 [logger](INFO): Join timeout helper Thread ID is 931
2019-02-21T22:06:15.174Z,1550786775.174 [Micromodem](INFO): Starting
2019-02-21T22:06:15.175Z,1550786775.175 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T22:06:15.176Z,1550786775.175 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.187Z,1550786775.187 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-21T22:06:15.187Z,1550786775.187 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.187Z,1550786775.187 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-21T22:06:15.187Z,1550786775.187 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.188Z,1550786775.188 [controlThread](INFO): Join timeout helper Thread ID is 932
2019-02-21T22:06:15.218Z,1550786775.218 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T22:06:15.218Z,1550786775.218 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-21T22:06:15.219Z,1550786775.219 [DUSBL_Hydroid](INFO): Powering down
2019-02-21T22:06:15.309Z,1550786775.309 [Micromodem](INFO): uninitialize
2019-02-21T22:06:15.309Z,1550786775.309 [Micromodem](INFO): Powering down
2019-02-21T22:06:15.405Z,1550786775.405 [NAL9602](INFO): Powering down
2019-02-21T22:06:15.477Z,1550786775.477 [RDI_Pathfinder](INFO): Powering down
2019-02-21T22:06:15.478Z,1550786775.478 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T22:06:15.479Z,1550786775.479 [Aanderaa_O2](INFO): Powering down
2019-02-21T22:06:15.480Z,1550786775.480 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-21T22:06:15.481Z,1550786775.481 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-21T22:06:15.482Z,1550786775.482 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-21T22:06:15.483Z,1550786775.483 [MissionManager](INFO): Uninitializing Mission Default
2019-02-21T22:06:15.485Z,1550786775.485 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-21T22:06:15.485Z,1550786775.485 [Startup] Stopped
2019-02-21T22:06:15.485Z,1550786775.485 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-21T22:06:15.485Z,1550786775.485 [Startup:A.GoToSurface] Stopped
2019-02-21T22:06:15.485Z,1550786775.485 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-21T22:06:15.485Z,1550786775.485 [Startup:StartupSatComms] Stopped
2019-02-21T22:06:15.485Z,1550786775.485 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-21T22:06:15.485Z,1550786775.485 [Startup:StartupSatComms:A] Stopped
2019-02-21T22:06:15.487Z,1550786775.487 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-21T22:06:15.487Z,1550786775.487 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-21T22:06:15.487Z,1550786775.487 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-21T22:06:15.488Z,1550786775.488 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-21T22:06:15.488Z,1550786775.488 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-21T22:06:15.488Z,1550786775.488 [BuoyancyServo](INFO): Powering down
2019-02-21T22:06:15.501Z,1550786775.501 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-21T22:06:15.501Z,1550786775.501 [ElevatorServo](INFO): Powering down
2019-02-21T22:06:15.502Z,1550786775.502 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-21T22:06:15.502Z,1550786775.502 [MassServo](INFO): Powering down
2019-02-21T22:06:15.503Z,1550786775.503 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-21T22:06:15.503Z,1550786775.503 [RudderServo](INFO): Powering down
2019-02-21T22:06:15.504Z,1550786775.504 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-21T22:06:15.504Z,1550786775.504 [ThrusterServo](INFO): Powering down
2019-02-21T22:06:15.505Z,1550786775.505 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-21T22:06:15.505Z,1550786775.505 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-21T22:06:15.505Z,1550786775.505 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-21T22:06:15.506Z,1550786775.506 [CBIT](DEBUG): Powering off loads.
2019-02-21T22:06:15.517Z,1550786775.517 [CBIT](DEBUG): Disabling WDT.
2019-02-21T22:06:15.529Z,1550786775.529 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-21T22:06:15.530Z,1550786775.530 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.595Z,1550786775.595 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.642Z,1550786775.642 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.644Z,1550786775.644 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.697Z,1550786775.697 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T22:06:15.754Z,1550786775.754 [logger ThreadHandler](INFO): Thread cancelled.