2019-02-21T23:45:01.516Z,1550792701.516 [Supervisor](DEBUG): Initializing supervisor.
2019-02-21T23:45:01.519Z,1550792701.519 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-21T23:45:01.519Z,1550792701.519 [SyncHandler](INFO): Protected caller Thread ID is 8502
2019-02-21T23:45:01.519Z,1550792701.519 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-21T23:45:01.520Z,1550792701.520 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-21T23:45:01.521Z,1550792701.521 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8503
2019-02-21T23:45:01.523Z,1550792701.523 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-21T23:45:01.634Z,1550792701.634 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-21T23:45:01.635Z,1550792701.635 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-21T23:45:01.635Z,1550792701.635 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8504
2019-02-21T23:45:01.636Z,1550792701.636 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-21T23:45:01.637Z,1550792701.637 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-21T23:45:01.637Z,1550792701.637 [logger ThreadHandler](INFO): Protected caller Thread ID is 8505
2019-02-21T23:45:01.639Z,1550792701.639 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-21T23:45:01.639Z,1550792701.639 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-21T23:45:01.641Z,1550792701.641 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-21T23:45:02.047Z,1550792702.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-21T23:45:02.047Z,1550792702.047 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-21T23:45:02.146Z,1550792702.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-21T23:45:02.146Z,1550792702.146 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-21T23:45:02.464Z,1550792702.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-21T23:45:02.465Z,1550792702.465 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-21T23:45:02.604Z,1550792702.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-21T23:45:02.605Z,1550792702.605 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-21T23:45:02.795Z,1550792702.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-21T23:45:02.796Z,1550792702.796 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-21T23:45:03.251Z,1550792703.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-21T23:45:03.252Z,1550792703.252 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-21T23:45:03.455Z,1550792703.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-21T23:45:03.456Z,1550792703.456 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-21T23:45:03.600Z,1550792703.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-21T23:45:03.601Z,1550792703.601 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-21T23:45:03.793Z,1550792703.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-21T23:45:03.793Z,1550792703.793 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-21T23:45:03.890Z,1550792703.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-21T23:45:03.891Z,1550792703.891 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-21T23:45:04.200Z,1550792704.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-21T23:45:04.200Z,1550792704.200 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-21T23:45:04.282Z,1550792704.282 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-21T23:45:04.386Z,1550792704.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-21T23:45:04.386Z,1550792704.386 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-21T23:45:04.892Z,1550792704.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-21T23:45:04.892Z,1550792704.892 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-21T23:45:05.284Z,1550792705.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-21T23:45:05.286Z,1550792705.286 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-21T23:45:05.287Z,1550792705.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-21T23:45:05.492Z,1550792705.492 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-21T23:45:05.593Z,1550792705.593 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-21T23:45:05.692Z,1550792705.692 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-21T23:45:05.918Z,1550792705.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-21T23:45:05.918Z,1550792705.918 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-21T23:45:06.004Z,1550792706.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-21T23:45:06.099Z,1550792706.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-21T23:45:06.198Z,1550792706.198 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-21T23:45:06.282Z,1550792706.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-21T23:45:06.390Z,1550792706.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-21T23:45:06.583Z,1550792706.583 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-21T23:45:07.025Z,1550792707.025 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/LOGIN/
2019-02-21T23:45:07.025Z,1550792707.025 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-21T23:45:07.027Z,1550792707.027 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-21T23:45:07.908Z,1550792707.908 [DataOverHttps] Loaded
2019-02-21T23:45:07.908Z,1550792707.908 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-21T23:45:07.922Z,1550792707.922 [Depth_Keller] Loaded
2019-02-21T23:45:07.923Z,1550792707.923 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-21T23:45:07.927Z,1550792707.927 [DropWeight] Loaded
2019-02-21T23:45:07.928Z,1550792707.928 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-21T23:45:07.975Z,1550792707.975 [DUSBL_Hydroid] Loaded
2019-02-21T23:45:07.976Z,1550792707.976 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-02-21T23:45:08.015Z,1550792708.015 [Micromodem] Loaded
2019-02-21T23:45:08.016Z,1550792708.016 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-02-21T23:45:08.112Z,1550792708.112 [NAL9602] Loaded
2019-02-21T23:45:08.112Z,1550792708.112 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-21T23:45:08.128Z,1550792708.128 [Onboard] Loaded
2019-02-21T23:45:08.128Z,1550792708.128 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-21T23:45:08.138Z,1550792708.138 [Radio_Surface] Loaded
2019-02-21T23:45:08.138Z,1550792708.138 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-21T23:45:08.139Z,1550792708.139 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407534E0
2019-02-21T23:45:08.140Z,1550792708.140 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8584
2019-02-21T23:45:08.168Z,1550792708.168 [RDI_Pathfinder] Loaded
2019-02-21T23:45:08.168Z,1550792708.168 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-21T23:45:08.190Z,1550792708.190 [RDI_PathfinderUp] Loaded
2019-02-21T23:45:08.190Z,1550792708.190 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-21T23:45:09.986Z,1550792709.986 [BPC1] Loaded
2019-02-21T23:45:09.986Z,1550792709.986 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-21T23:45:09.987Z,1550792709.987 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-21T23:45:09.987Z,1550792709.987 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-21T23:45:10.000Z,1550792710.000 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-21T23:45:10.001Z,1550792710.001 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-21T23:45:10.107Z,1550792710.107 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-21T23:45:10.108Z,1550792710.108 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-21T23:45:10.167Z,1550792710.167 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-21T23:45:10.167Z,1550792710.167 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-21T23:45:10.225Z,1550792710.225 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-21T23:45:10.226Z,1550792710.226 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-21T23:45:10.241Z,1550792710.241 [NavChart] Loaded
2019-02-21T23:45:10.241Z,1550792710.241 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-21T23:45:10.246Z,1550792710.246 [UniversalFixResidualReporter] Loaded
2019-02-21T23:45:10.246Z,1550792710.246 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-21T23:45:10.246Z,1550792710.246 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-21T23:45:10.247Z,1550792710.247 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-21T23:45:10.350Z,1550792710.350 [BuoyancyServo] Loaded
2019-02-21T23:45:10.350Z,1550792710.350 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-21T23:45:10.365Z,1550792710.365 [ElevatorServo] Loaded
2019-02-21T23:45:10.366Z,1550792710.366 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-21T23:45:10.380Z,1550792710.380 [MassServo] Loaded
2019-02-21T23:45:10.381Z,1550792710.381 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-21T23:45:10.396Z,1550792710.396 [RudderServo] Loaded
2019-02-21T23:45:10.396Z,1550792710.396 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-21T23:45:10.410Z,1550792710.410 [ThrusterServo] Loaded
2019-02-21T23:45:10.411Z,1550792710.411 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-21T23:45:10.411Z,1550792710.411 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-21T23:45:10.412Z,1550792710.412 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-21T23:45:10.590Z,1550792710.590 [Aanderaa_O2] Loaded
2019-02-21T23:45:10.590Z,1550792710.590 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-21T23:45:10.675Z,1550792710.675 [CTD_NeilBrown] Loaded
2019-02-21T23:45:10.675Z,1550792710.675 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-21T23:45:10.676Z,1550792710.676 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-21T23:45:10.676Z,1550792710.676 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8585
2019-02-21T23:45:10.720Z,1550792710.720 [WetLabsSeaOWL_UV_A] Loaded
2019-02-21T23:45:10.721Z,1550792710.721 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-21T23:45:10.722Z,1550792710.722 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-21T23:45:10.722Z,1550792710.722 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8586
2019-02-21T23:45:10.723Z,1550792710.723 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-21T23:45:10.723Z,1550792710.723 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-21T23:45:10.964Z,1550792710.964 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-21T23:45:10.965Z,1550792710.965 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-21T23:45:11.007Z,1550792711.007 [DepthRateCalculator] Loaded
2019-02-21T23:45:11.007Z,1550792711.007 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-21T23:45:11.013Z,1550792711.013 [PitchRateCalculator] Loaded
2019-02-21T23:45:11.013Z,1550792711.013 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-21T23:45:11.025Z,1550792711.025 [SpeedCalculator] Loaded
2019-02-21T23:45:11.026Z,1550792711.026 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-21T23:45:11.046Z,1550792711.046 [TempGradientCalculator] Loaded
2019-02-21T23:45:11.047Z,1550792711.047 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-21T23:45:11.052Z,1550792711.052 [YawRateCalculator] Loaded
2019-02-21T23:45:11.053Z,1550792711.053 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-21T23:45:11.092Z,1550792711.092 [ElevatorOffsetCalculator] Loaded
2019-02-21T23:45:11.092Z,1550792711.092 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-21T23:45:11.093Z,1550792711.093 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-21T23:45:11.094Z,1550792711.094 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-21T23:45:11.228Z,1550792711.228 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-21T23:45:11.250Z,1550792711.250 [SBIT] Loaded
2019-02-21T23:45:11.250Z,1550792711.250 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-21T23:45:11.251Z,1550792711.251 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-21T23:45:11.262Z,1550792711.262 [IBIT] Loaded
2019-02-21T23:45:11.263Z,1550792711.263 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-21T23:45:11.266Z,1550792711.266 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-21T23:45:11.404Z,1550792711.404 [CBIT] Loaded
2019-02-21T23:45:11.405Z,1550792711.405 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-21T23:45:11.405Z,1550792711.405 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-21T23:45:11.406Z,1550792711.406 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-21T23:45:11.469Z,1550792711.469 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-21T23:45:11.470Z,1550792711.470 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-21T23:45:11.568Z,1550792711.568 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-21T23:45:11.568Z,1550792711.568 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-21T23:45:11.636Z,1550792711.636 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-21T23:45:11.847Z,1550792711.847 [VerticalControl] Loaded
2019-02-21T23:45:11.847Z,1550792711.847 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-21T23:45:11.848Z,1550792711.848 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-21T23:45:11.908Z,1550792711.908 [HorizontalControl] Loaded
2019-02-21T23:45:11.908Z,1550792711.908 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-21T23:45:11.909Z,1550792711.909 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-21T23:45:11.910Z,1550792711.910 [SpeedControl] Loaded
2019-02-21T23:45:11.911Z,1550792711.911 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-21T23:45:11.911Z,1550792711.911 [LoopControl](DEBUG): Construct LoopControl.
2019-02-21T23:45:11.912Z,1550792711.912 [LoopControl] Loaded
2019-02-21T23:45:11.912Z,1550792711.912 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-21T23:45:11.912Z,1550792711.912 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-21T23:45:11.913Z,1550792711.913 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-21T23:45:11.941Z,1550792711.941 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-21T23:45:11.944Z,1550792711.944 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-21T23:45:11.945Z,1550792711.945 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-21T23:45:11.952Z,1550792711.952 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-21T23:45:11.953Z,1550792711.953 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0
2019-02-21T23:45:11.953Z,1550792711.953 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8587
2019-02-21T23:45:11.958Z,1550792711.958 [Supervisor](INFO): Main Thread ID is 8501
2019-02-21T23:45:11.958Z,1550792711.958 [Supervisor](DEBUG): Running supervisor.
2019-02-21T23:45:11.959Z,1550792711.959 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8588
2019-02-21T23:45:11.961Z,1550792711.961 [controlThread ThreadHandler](INFO): Handler Thread ID is 8589
2019-02-21T23:45:11.961Z,1550792711.961 [controlThread](DEBUG): Initializing ControlThread
2019-02-21T23:45:11.968Z,1550792711.968 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:45:11.969Z,1550792711.969 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-21T23:45:11.969Z,1550792711.969 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-21T23:45:11.971Z,1550792711.971 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-21T23:45:11.971Z,1550792711.971 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-21T23:45:11.971Z,1550792711.971 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-21T23:45:11.972Z,1550792711.972 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-21T23:45:11.972Z,1550792711.972 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-21T23:45:11.972Z,1550792711.972 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-21T23:45:11.973Z,1550792711.973 [SBIT](INFO): Initialize SBIT Component.
2019-02-21T23:45:11.974Z,1550792711.974 [SBIT](IMPORTANT): git: 2019-02-11-13-g62924c4
2019-02-21T23:45:11.974Z,1550792711.974 [SBIT](INFO): git hash: 62924c492e86bb9d7cd72440302a80a745358b26
2019-02-21T23:45:11.974Z,1550792711.974 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-21T23:45:11.974Z,1550792711.974 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-21T23:45:11.975Z,1550792711.975 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-21T23:45:11.976Z,1550792711.976 [IBIT](INFO): Initialize IBIT Component.
2019-02-21T23:45:11.977Z,1550792711.977 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-21T23:45:11.978Z,1550792711.978 [logger ThreadHandler](INFO): Handler Thread ID is 8590
2019-02-21T23:45:11.989Z,1550792711.989 [CBIT](DEBUG): Initialized mux pins.
2019-02-21T23:45:11.989Z,1550792711.989 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-21T23:45:11.997Z,1550792711.997 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8591
2019-02-21T23:45:11.999Z,1550792711.999 [Radio_Surface](INFO): Powering up
2019-02-21T23:45:12.009Z,1550792712.009 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8592
2019-02-21T23:45:12.010Z,1550792712.010 [CTD_NeilBrown](INFO): Powering down
2019-02-21T23:45:12.017Z,1550792712.017 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-21T23:45:12.017Z,1550792712.017 [CBIT](DEBUG): Initializing heartbeat.
2019-02-21T23:45:12.044Z,1550792712.044 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8593
2019-02-21T23:45:12.045Z,1550792712.045 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T23:45:12.073Z,1550792712.073 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8594
2019-02-21T23:45:12.076Z,1550792712.076 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-21T23:45:12.076Z,1550792712.076 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-21T23:45:12.076Z,1550792712.076 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-21T23:45:12.076Z,1550792712.076 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-21T23:45:12.077Z,1550792712.077 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-21T23:45:12.077Z,1550792712.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-21T23:45:12.077Z,1550792712.077 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-21T23:45:12.077Z,1550792712.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-21T23:45:12.077Z,1550792712.077 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-21T23:45:12.078Z,1550792712.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-21T23:45:12.078Z,1550792712.078 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-21T23:45:12.078Z,1550792712.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-21T23:45:12.078Z,1550792712.078 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-21T23:45:12.078Z,1550792712.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-21T23:45:12.079Z,1550792712.079 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T23:45:12.079Z,1550792712.079 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-21T23:45:12.089Z,1550792712.089 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-21T23:45:12.089Z,1550792712.089 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-21T23:45:12.125Z,1550792712.125 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-21T23:45:12.127Z,1550792712.127 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-21T23:45:12.128Z,1550792712.128 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-21T23:45:12.128Z,1550792712.128 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-21T23:45:12.129Z,1550792712.129 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-21T23:45:12.139Z,1550792712.139 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T23:45:12.166Z,1550792712.166 [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-21T23:45:12.166Z,1550792712.166 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-21T23:45:12.234Z,1550792712.234 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-21T23:45:12.236Z,1550792712.236 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-21T23:45:12.237Z,1550792712.237 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T23:45:12.272Z,1550792712.272 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-21T23:45:12.291Z,1550792712.291 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-21T23:45:12.296Z,1550792712.296 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-21T23:45:12.324Z,1550792712.324 [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-21T23:45:12.332Z,1550792712.332 [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-21T23:45:12.353Z,1550792712.353 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-21T23:45:12.358Z,1550792712.358 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-21T23:45:12.359Z,1550792712.359 [DUSBL_Hydroid](INFO): Powering up
2019-02-21T23:45:12.359Z,1550792712.359 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-02-21T23:45:12.377Z,1550792712.377 [Micromodem](INFO): Start
2019-02-21T23:45:12.433Z,1550792712.433 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T23:45:12.481Z,1550792712.481 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:45:12.489Z,1550792712.489 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-21T23:45:12.490Z,1550792712.490 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:45:12.497Z,1550792712.497 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-21T23:45:12.498Z,1550792712.498 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:45:12.505Z,1550792712.505 [MassServo](DEBUG): Initializing MassServo.
2019-02-21T23:45:12.506Z,1550792712.506 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:45:12.513Z,1550792712.513 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-21T23:45:12.514Z,1550792712.514 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:45:12.521Z,1550792712.521 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-21T23:45:12.778Z,1550792712.778 [Micromodem](INFO): Starting
2019-02-21T23:45:12.779Z,1550792712.779 [Micromodem](INFO): Powering up
2019-02-21T23:45:12.779Z,1550792712.779 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T23:45:12.885Z,1550792712.885 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T23:45:12.941Z,1550792712.941 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T23:45:21.702Z,1550792721.702 [Micromodem](INFO): Starting
2019-02-21T23:45:21.704Z,1550792721.704 [Micromodem](INFO): Nmea in: $CAREV,234430,INIT,2.0.27690*4F

2019-02-21T23:45:21.705Z,1550792721.705 [Micromodem](INFO): Starting
2019-02-21T23:45:21.707Z,1550792721.707 [Micromodem](INFO): Nmea in: $CAREV,234430,SLOT1,2.0.27690*60

2019-02-21T23:45:21.708Z,1550792721.708 [Micromodem](INFO): Nmea buf: $CCCFG,CST,1*37

2019-02-21T23:45:21.708Z,1550792721.708 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-02-21T23:45:21.710Z,1550792721.710 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,1*31

2019-02-21T23:45:21.711Z,1550792721.711 [Micromodem](INFO): Nmea buf: $CCCFG,REV,1*32

2019-02-21T23:45:21.712Z,1550792721.712 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,1*29

2019-02-21T23:45:21.717Z,1550792721.717 [Radio_Surface](INFO): Powering down
2019-02-21T23:45:21.732Z,1550792721.732 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,0*41

2019-02-21T23:45:22.101Z,1550792722.101 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-21T23:45:22.103Z,1550792722.103 [Micromodem](INFO): Starting
2019-02-21T23:45:22.104Z,1550792722.104 [Micromodem](INFO): Nmea in: $CAREV,234430,AUV,2.0.27690*17

2019-02-21T23:45:22.503Z,1550792722.503 [Micromodem](INFO): Starting
2019-02-21T23:45:22.504Z,1550792722.504 [Micromodem](INFO): Nmea in: $CAREV,234430,COPROC,0.10.0.46*42

2019-02-21T23:45:22.637Z,1550792722.637 [Aanderaa_O2](INFO): Powering down
2019-02-21T23:45:22.906Z,1550792722.906 [Micromodem](INFO): Starting
2019-02-21T23:45:22.907Z,1550792722.907 [Micromodem](INFO): Nmea in: $CATMG,2019-02-21T23:44:30Z,RTC,RTC*77

2019-02-21T23:45:22.908Z,1550792722.908 [Micromodem](INFO): Nmea buf: $CCTMS,2019-02-21T23:45:23Z,0*7E

2019-02-21T23:45:23.310Z,1550792723.310 [Micromodem](INFO): Starting
2019-02-21T23:45:23.714Z,1550792723.714 [Micromodem](INFO): Starting
2019-02-21T23:45:24.118Z,1550792724.118 [Micromodem](INFO): Starting
2019-02-21T23:45:24.522Z,1550792724.522 [Micromodem](INFO): Starting
2019-02-21T23:45:24.927Z,1550792724.927 [Micromodem](INFO): Starting
2019-02-21T23:45:25.330Z,1550792725.330 [Micromodem](INFO): Starting
2019-02-21T23:45:25.734Z,1550792725.734 [Micromodem](INFO): Starting
2019-02-21T23:45:25.760Z,1550792725.760 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-21T23:45:26.139Z,1550792726.139 [Micromodem](INFO): Starting
2019-02-21T23:45:26.551Z,1550792726.551 [Micromodem](INFO): Starting
2019-02-21T23:45:26.946Z,1550792726.946 [Micromodem](INFO): Starting
2019-02-21T23:45:26.947Z,1550792726.947 [Micromodem](INFO): Nmea in: $CACFG,CST,1*35

2019-02-21T23:45:26.947Z,1550792726.947 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-02-21T23:45:27.355Z,1550792727.355 [Micromodem](INFO): Starting
2019-02-21T23:45:27.356Z,1550792727.356 [Micromodem](INFO): Nmea in: $CAREV,234440,AUV,2.0.27690*10

2019-02-21T23:45:27.754Z,1550792727.754 [Micromodem](INFO): Starting
2019-02-21T23:45:27.755Z,1550792727.755 [Micromodem](INFO): Nmea in: $CAREV,234440,COPROC,0.10.0.46*45

2019-02-21T23:45:27.781Z,1550792727.781 [Radio_Surface](INFO): Powering up
2019-02-21T23:45:28.158Z,1550792728.158 [Micromodem](INFO): Starting
2019-02-21T23:45:28.569Z,1550792728.569 [Micromodem](INFO): Starting
2019-02-21T23:45:28.967Z,1550792728.967 [Micromodem](INFO): Starting
2019-02-21T23:45:29.372Z,1550792729.372 [Micromodem](INFO): Starting
2019-02-21T23:45:29.776Z,1550792729.776 [Micromodem](INFO): Starting
2019-02-21T23:45:30.179Z,1550792730.179 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-02-21T23:45:30.193Z,1550792730.193 [Micromodem](INFO): Starting
2019-02-21T23:45:30.582Z,1550792730.582 [Micromodem](INFO): Starting
2019-02-21T23:45:30.986Z,1550792730.986 [Micromodem](INFO): Starting
2019-02-21T23:45:31.390Z,1550792731.390 [Micromodem](INFO): Starting
2019-02-21T23:45:31.805Z,1550792731.805 [Micromodem](INFO): Starting
2019-02-21T23:45:32.198Z,1550792732.198 [Micromodem](INFO): Starting
2019-02-21T23:45:32.602Z,1550792732.602 [Micromodem](INFO): Starting
2019-02-21T23:45:33.006Z,1550792733.006 [Micromodem](INFO): Starting
2019-02-21T23:45:33.410Z,1550792733.410 [Micromodem](INFO): Starting
2019-02-21T23:45:33.814Z,1550792733.814 [Micromodem](INFO): Starting
2019-02-21T23:45:34.218Z,1550792734.218 [Micromodem](INFO): Starting
2019-02-21T23:45:34.622Z,1550792734.622 [Micromodem](INFO): Starting
2019-02-21T23:45:35.028Z,1550792735.028 [Micromodem](INFO): Starting
2019-02-21T23:45:35.430Z,1550792735.430 [Micromodem](INFO): Starting
2019-02-21T23:45:35.835Z,1550792735.835 [Micromodem](INFO): Starting
2019-02-21T23:45:36.238Z,1550792736.238 [Micromodem](INFO): Starting
2019-02-21T23:45:36.642Z,1550792736.642 [Micromodem](INFO): Starting
2019-02-21T23:45:37.046Z,1550792737.046 [Micromodem](INFO): Starting
2019-02-21T23:45:37.047Z,1550792737.047 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-02-21T23:45:37.047Z,1550792737.047 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32

2019-02-21T23:45:37.450Z,1550792737.450 [Micromodem](INFO): Starting
2019-02-21T23:45:37.451Z,1550792737.451 [Micromodem](INFO): Nmea in: $CAREV,234450,AUV,2.0.27690*11

2019-02-21T23:45:37.854Z,1550792737.854 [Micromodem](INFO): Starting
2019-02-21T23:45:37.855Z,1550792737.855 [Micromodem](INFO): Nmea in: $CAREV,234450,COPROC,0.10.0.46*44

2019-02-21T23:45:38.258Z,1550792738.258 [Micromodem](INFO): Starting
2019-02-21T23:45:38.259Z,1550792738.259 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:45:38.662Z,1550792738.662 [Micromodem](INFO): Starting
2019-02-21T23:45:39.062Z,1550792739.062 [Micromodem](INFO): Starting
2019-02-21T23:45:39.470Z,1550792739.470 [Micromodem](INFO): Starting
2019-02-21T23:45:39.874Z,1550792739.874 [Micromodem](INFO): Starting
2019-02-21T23:45:40.274Z,1550792740.274 [Micromodem](INFO): Starting
2019-02-21T23:45:40.686Z,1550792740.686 [Micromodem](INFO): Starting
2019-02-21T23:45:40.734Z,1550792740.734 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-21T23:45:40.742Z,1550792740.742 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-21T23:45:41.086Z,1550792741.086 [Micromodem](INFO): Starting
2019-02-21T23:45:41.490Z,1550792741.490 [Micromodem](INFO): Starting
2019-02-21T23:45:41.894Z,1550792741.894 [Micromodem](INFO): Starting
2019-02-21T23:45:42.298Z,1550792742.298 [Micromodem](INFO): Starting
2019-02-21T23:45:42.697Z,1550792742.697 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2019-02-21T23:45:42.706Z,1550792742.706 [Micromodem](INFO): Starting
2019-02-21T23:45:43.202Z,1550792743.202 [Micromodem](INFO): Starting
2019-02-21T23:45:43.606Z,1550792743.606 [Micromodem](INFO): Starting
2019-02-21T23:45:44.022Z,1550792744.022 [Micromodem](INFO): Starting
2019-02-21T23:45:44.410Z,1550792744.410 [Micromodem](INFO): Starting
2019-02-21T23:45:44.830Z,1550792744.830 [Micromodem](INFO): Starting
2019-02-21T23:45:45.234Z,1550792745.234 [Micromodem](INFO): Starting
2019-02-21T23:45:45.622Z,1550792745.622 [Micromodem](INFO): Starting
2019-02-21T23:45:46.030Z,1550792746.030 [Micromodem](INFO): Starting
2019-02-21T23:45:46.434Z,1550792746.434 [Micromodem](INFO): Starting
2019-02-21T23:45:46.834Z,1550792746.834 [Micromodem](INFO): Starting
2019-02-21T23:45:46.835Z,1550792746.835 [Micromodem](INFO): Nmea in: $CACFG,REV,1*30

2019-02-21T23:45:46.835Z,1550792746.835 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29

2019-02-21T23:45:47.254Z,1550792747.254 [Micromodem](INFO): Starting
2019-02-21T23:45:47.255Z,1550792747.255 [Micromodem](INFO): Nmea in: $CAREV,234500,AUV,2.0.27690*15

2019-02-21T23:45:47.646Z,1550792747.646 [Micromodem](INFO): Starting
2019-02-21T23:45:47.647Z,1550792747.647 [Micromodem](INFO): Nmea in: $CAREV,234500,COPROC,0.10.0.46*40

2019-02-21T23:45:48.050Z,1550792748.050 [Micromodem](INFO): Starting
2019-02-21T23:45:48.454Z,1550792748.454 [Micromodem](INFO): Starting
2019-02-21T23:45:48.858Z,1550792748.858 [Micromodem](INFO): Starting
2019-02-21T23:45:49.266Z,1550792749.266 [Micromodem](INFO): Starting
2019-02-21T23:45:49.267Z,1550792749.267 [NAL9602](INFO): NAL9602 initialized
2019-02-21T23:45:49.666Z,1550792749.666 [Micromodem](INFO): Starting
2019-02-21T23:45:50.070Z,1550792750.070 [Micromodem](INFO): Starting
2019-02-21T23:45:50.085Z,1550792750.085 [NAL9602](DEBUG): Fix Requested
2019-02-21T23:45:50.478Z,1550792750.478 [Micromodem](INFO): Starting
2019-02-21T23:45:50.878Z,1550792750.878 [Micromodem](INFO): Starting
2019-02-21T23:45:51.282Z,1550792751.282 [Micromodem](INFO): Starting
2019-02-21T23:45:51.686Z,1550792751.686 [Micromodem](INFO): Starting
2019-02-21T23:45:51.805Z,1550792751.805 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007796
CHAN A1 (24V): -0.026045
CHAN A2 (12V): -0.007194
CHAN A3 (5V): -0.003329
CHAN B0 (3.3V): -0.000699
CHAN B1 (3.15aV): -0.000449
CHAN B2 (3.15bV): -0.000322
CHAN B3 (GND): 0.001020
OPEN: 0.009076
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-21T23:45:52.100Z,1550792752.100 [Micromodem](INFO): Starting
2019-02-21T23:45:52.494Z,1550792752.494 [Micromodem](INFO): Starting
2019-02-21T23:45:52.898Z,1550792752.898 [Micromodem](INFO): Starting
2019-02-21T23:45:53.302Z,1550792753.302 [Micromodem](INFO): Starting
2019-02-21T23:45:53.706Z,1550792753.706 [Micromodem](INFO): Starting
2019-02-21T23:45:54.110Z,1550792754.110 [Micromodem](INFO): Starting
2019-02-21T23:45:54.516Z,1550792754.516 [Micromodem](INFO): Starting
2019-02-21T23:45:54.918Z,1550792754.918 [Micromodem](INFO): Starting
2019-02-21T23:45:55.326Z,1550792755.326 [Micromodem](INFO): Starting
2019-02-21T23:45:55.740Z,1550792755.740 [Micromodem](INFO): Starting
2019-02-21T23:45:56.310Z,1550792756.310 [Micromodem](INFO): Starting
2019-02-21T23:45:56.711Z,1550792756.711 [Micromodem](INFO): Starting
2019-02-21T23:45:56.711Z,1550792756.711 [Micromodem](INFO): Nmea in: $CACFG,RXP,1*2B

2019-02-21T23:45:56.712Z,1550792756.712 [Micromodem](INFO): Nmea out: $CCMSC,1,1,0*41

2019-02-21T23:45:57.114Z,1550792757.114 [Micromodem](INFO): Starting
2019-02-21T23:45:57.115Z,1550792757.115 [Micromodem](INFO): Nmea in: $CAREV,234510,AUV,2.0.27690*14

2019-02-21T23:45:57.115Z,1550792757.115 [Micromodem](INFO): Nmea out: $CCTMS,2019-02-21T23:45:23Z,0*7E

2019-02-21T23:45:57.518Z,1550792757.518 [Micromodem](INFO): Starting
2019-02-21T23:45:57.519Z,1550792757.519 [Micromodem](INFO): Nmea in: $CAREV,234510,COPROC,0.10.0.46*41

2019-02-21T23:45:57.930Z,1550792757.930 [Micromodem](INFO): Starting
2019-02-21T23:45:57.964Z,1550792757.964 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2019-02-21T23:45:57.964Z,1550792757.964 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    8,    -2,-32768,A
2019-02-21T23:45:58.326Z,1550792758.326 [Micromodem](INFO): Starting
2019-02-21T23:45:58.730Z,1550792758.730 [Micromodem](INFO): Starting
2019-02-21T23:45:59.134Z,1550792759.134 [Micromodem](INFO): Starting
2019-02-21T23:45:59.545Z,1550792759.545 [Micromodem](INFO): Starting
2019-02-21T23:45:59.577Z,1550792759.577 [RDI_Pathfinder](ERROR): Failed to parse:
   -0.02,       -0.08,       -0.02,   9.36,  0.00
2019-02-21T23:45:59.942Z,1550792759.942 [Micromodem](INFO): Starting
2019-02-21T23:46:00.346Z,1550792760.346 [Micromodem](INFO): Starting
2019-02-21T23:46:00.750Z,1550792760.750 [Micromodem](INFO): Starting
2019-02-21T23:46:01.154Z,1550792761.154 [Micromodem](INFO): Starting
2019-02-21T23:46:01.567Z,1550792761.567 [Micromodem](INFO): Starting
2019-02-21T23:46:01.962Z,1550792761.962 [Micromodem](INFO): Starting
2019-02-21T23:46:02.366Z,1550792762.366 [Micromodem](INFO): Starting
2019-02-21T23:46:02.770Z,1550792762.770 [Micromodem](INFO): Starting
2019-02-21T23:46:03.178Z,1550792763.178 [Micromodem](INFO): Starting
2019-02-21T23:46:03.582Z,1550792763.582 [Micromodem](INFO): Starting
2019-02-21T23:46:03.986Z,1550792763.986 [Micromodem](INFO): Starting
2019-02-21T23:46:04.390Z,1550792764.390 [Micromodem](INFO): Starting
2019-02-21T23:46:04.794Z,1550792764.794 [Micromodem](INFO): Starting
2019-02-21T23:46:05.194Z,1550792765.194 [Micromodem](INFO): Starting
2019-02-21T23:46:05.602Z,1550792765.602 [Micromodem](INFO): Starting
2019-02-21T23:46:05.998Z,1550792765.998 [Micromodem](INFO): Starting
2019-02-21T23:46:06.410Z,1550792766.410 [Micromodem](INFO): Starting
2019-02-21T23:46:06.810Z,1550792766.810 [Micromodem](INFO): Starting
2019-02-21T23:46:07.210Z,1550792767.210 [Micromodem](INFO): Starting
2019-02-21T23:46:07.618Z,1550792767.618 [Micromodem](INFO): Starting
2019-02-21T23:46:08.022Z,1550792768.022 [Micromodem](INFO): Starting
2019-02-21T23:46:08.432Z,1550792768.432 [Micromodem](INFO): Starting
2019-02-21T23:46:08.830Z,1550792768.830 [Micromodem](INFO): Starting
2019-02-21T23:46:09.238Z,1550792769.238 [Micromodem](INFO): Starting
2019-02-21T23:46:09.642Z,1550792769.642 [Micromodem](INFO): Starting
2019-02-21T23:46:10.046Z,1550792770.046 [Micromodem](INFO): Starting
2019-02-21T23:46:10.450Z,1550792770.450 [Micromodem](INFO): Starting
2019-02-21T23:46:10.850Z,1550792770.850 [Micromodem](INFO): Starting
2019-02-21T23:46:11.258Z,1550792771.258 [Micromodem](INFO): Starting
2019-02-21T23:46:11.658Z,1550792771.658 [Micromodem](INFO): Starting
2019-02-21T23:46:12.058Z,1550792772.058 [Micromodem](INFO): Starting
2019-02-21T23:46:12.466Z,1550792772.466 [Micromodem](INFO): Starting
2019-02-21T23:46:12.467Z,1550792772.467 [Micromodem](FAULT): failed to initialize; deviceResponse_ loaded: $CAREV,234510,COPROC,0.10.0.46*41
, available: $CAMSC,1,1,0*43
!
2019-02-21T23:46:12.467Z,1550792772.467 [Micromodem] Communications Fault, FailCount= 1
2019-02-21T23:46:12.467Z,1550792772.467 [Micromodem](ERROR): Communications Fault
2019-02-21T23:46:12.566Z,1550792772.566 [CBIT](ERROR): Communications Fault in component: Micromodem
2019-02-21T23:46:12.871Z,1550792772.871 [Micromodem](INFO): Stop
2019-02-21T23:46:12.871Z,1550792772.871 [Micromodem](INFO): uninitialize
2019-02-21T23:46:12.871Z,1550792772.871 [Micromodem](INFO): Powering down
2019-02-21T23:46:13.279Z,1550792773.279 [Micromodem](INFO): Stopping
2019-02-21T23:46:13.682Z,1550792773.682 [Micromodem](INFO): Stopped
2019-02-21T23:46:13.721Z,1550792773.721 [CBIT](INFO): Clearing failed state for component Micromodem
2019-02-21T23:46:13.722Z,1550792773.722 [Micromodem] No Fault, FailCount= 1
2019-02-21T23:46:14.082Z,1550792774.082 [Micromodem](INFO): Start
2019-02-21T23:46:14.486Z,1550792774.486 [Micromodem](INFO): Start
2019-02-21T23:46:14.890Z,1550792774.890 [Micromodem](INFO): Start
2019-02-21T23:46:15.300Z,1550792775.300 [Micromodem](INFO): Start
2019-02-21T23:46:15.702Z,1550792775.702 [Micromodem](INFO): Start
2019-02-21T23:46:16.106Z,1550792776.106 [Micromodem](INFO): Start
2019-02-21T23:46:16.511Z,1550792776.511 [Micromodem](INFO): Starting
2019-02-21T23:46:16.511Z,1550792776.511 [Micromodem](INFO): Powering up
2019-02-21T23:46:16.512Z,1550792776.512 [Micromodem](DEBUG): Initializing Micromodem.
2019-02-21T23:46:16.914Z,1550792776.914 [Micromodem](INFO): Starting
2019-02-21T23:46:17.314Z,1550792777.314 [Micromodem](INFO): Starting
2019-02-21T23:46:17.712Z,1550792777.712 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-02-21T23:46:17.722Z,1550792777.722 [Micromodem](INFO): Starting
2019-02-21T23:46:18.118Z,1550792778.118 [Micromodem](INFO): Starting
2019-02-21T23:46:18.526Z,1550792778.526 [Micromodem](INFO): Starting
2019-02-21T23:46:18.934Z,1550792778.934 [Micromodem](INFO): Starting
2019-02-21T23:46:19.330Z,1550792779.330 [Micromodem](INFO): Starting
2019-02-21T23:46:19.738Z,1550792779.738 [Micromodem](INFO): Starting
2019-02-21T23:46:20.142Z,1550792780.142 [Micromodem](INFO): Starting
2019-02-21T23:46:20.217Z,1550792780.217 [CommandLine](IMPORTANT): got command quit
2019-02-21T23:46:20.557Z,1550792780.557 [Micromodem](INFO): Starting
2019-02-21T23:46:20.560Z,1550792780.560 [Micromodem](INFO): Nmea in: $CAREV,234534,INIT,2.0.27690*4A

2019-02-21T23:46:20.954Z,1550792780.954 [Micromodem](INFO): Starting
2019-02-21T23:46:20.955Z,1550792780.955 [Micromodem](INFO): Nmea in: $CAREV,234534,SLOT1,2.0.27690*65

2019-02-21T23:46:21.221Z,1550792781.221 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-02-21T23:46:21.221Z,1550792781.221 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T23:46:21.222Z,1550792781.222 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.297Z,1550792781.297 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-21T23:46:21.297Z,1550792781.297 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.298Z,1550792781.298 [CommandLine](INFO): Join timeout helper Thread ID is 8611
2019-02-21T23:46:21.298Z,1550792781.298 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-21T23:46:21.298Z,1550792781.298 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.299Z,1550792781.299 [NavChartDb](INFO): Join timeout helper Thread ID is 8612
2019-02-21T23:46:21.317Z,1550792781.317 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T23:46:21.317Z,1550792781.317 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.340Z,1550792781.340 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-21T23:46:21.340Z,1550792781.340 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.340Z,1550792781.340 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8613
2019-02-21T23:46:21.354Z,1550792781.354 [Micromodem](INFO): Starting
2019-02-21T23:46:21.355Z,1550792781.355 [Micromodem](INFO): Nmea in: $CAREV,234534,AUV,2.0.27690*12

2019-02-21T23:46:21.355Z,1550792781.355 [Micromodem](INFO): Starting
2019-02-21T23:46:21.356Z,1550792781.356 [Micromodem](INFO): Nmea in: $CAREV,234534,COPROC,0.10.0.46*47

2019-02-21T23:46:21.357Z,1550792781.357 [Micromodem](INFO): Nmea buf: $CCCFG,CST,1*37

2019-02-21T23:46:21.357Z,1550792781.357 [Micromodem](INFO): Nmea out: $CCTMS,2019-02-21T23:45:23Z,0*7E

2019-02-21T23:46:21.357Z,1550792781.357 [Micromodem](INFO): Nmea buf: $CCCFG,SRC,1*31

2019-02-21T23:46:21.357Z,1550792781.357 [Micromodem](INFO): Nmea buf: $CCCFG,REV,1*32

2019-02-21T23:46:21.358Z,1550792781.358 [Micromodem](INFO): Nmea buf: $CCCFG,RXP,1*29

2019-02-21T23:46:21.358Z,1550792781.358 [Micromodem](INFO): Nmea buf: $CCMSC,1,1,0*41

2019-02-21T23:46:21.558Z,1550792781.558 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T23:46:21.558Z,1550792781.558 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-21T23:46:21.560Z,1550792781.560 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.565Z,1550792781.565 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-21T23:46:21.565Z,1550792781.565 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.567Z,1550792781.567 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8617
2019-02-21T23:46:21.653Z,1550792781.653 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T23:46:21.653Z,1550792781.653 [CTD_NeilBrown](INFO): Powering down
2019-02-21T23:46:21.665Z,1550792781.665 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.669Z,1550792781.669 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-21T23:46:21.669Z,1550792781.669 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.669Z,1550792781.669 [Radio_Surface](INFO): Join timeout helper Thread ID is 8618
2019-02-21T23:46:21.758Z,1550792781.758 [Micromodem](INFO): Starting
2019-02-21T23:46:21.917Z,1550792781.917 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T23:46:21.917Z,1550792781.917 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.938Z,1550792781.938 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-21T23:46:21.938Z,1550792781.938 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.938Z,1550792781.938 [logger](INFO): Join timeout helper Thread ID is 8619
2019-02-21T23:46:21.953Z,1550792781.953 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T23:46:21.953Z,1550792781.953 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.958Z,1550792781.958 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-21T23:46:21.958Z,1550792781.958 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.958Z,1550792781.958 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-21T23:46:21.958Z,1550792781.958 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:21.958Z,1550792781.958 [controlThread](INFO): Join timeout helper Thread ID is 8620
2019-02-21T23:46:22.153Z,1550792782.153 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-21T23:46:22.153Z,1550792782.153 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-21T23:46:22.154Z,1550792782.154 [DUSBL_Hydroid](INFO): Powering down
2019-02-21T23:46:22.245Z,1550792782.245 [Micromodem](INFO): uninitialize
2019-02-21T23:46:22.245Z,1550792782.245 [Micromodem](INFO): Powering down
2019-02-21T23:46:22.341Z,1550792782.341 [NAL9602](INFO): Powering down
2019-02-21T23:46:22.413Z,1550792782.413 [RDI_Pathfinder](INFO): Powering down
2019-02-21T23:46:22.414Z,1550792782.414 [RDI_PathfinderUp](INFO): Powering down
2019-02-21T23:46:22.415Z,1550792782.415 [Aanderaa_O2](INFO): Powering down
2019-02-21T23:46:22.416Z,1550792782.416 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-21T23:46:22.417Z,1550792782.417 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-21T23:46:22.418Z,1550792782.418 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-21T23:46:22.419Z,1550792782.419 [MissionManager](INFO): Uninitializing Mission Default
2019-02-21T23:46:22.421Z,1550792782.421 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-21T23:46:22.422Z,1550792782.422 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-21T23:46:22.423Z,1550792782.423 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-21T23:46:22.423Z,1550792782.423 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-21T23:46:22.423Z,1550792782.423 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-21T23:46:22.424Z,1550792782.424 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-21T23:46:22.424Z,1550792782.424 [BuoyancyServo](INFO): Powering down
2019-02-21T23:46:22.437Z,1550792782.437 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-21T23:46:22.437Z,1550792782.437 [ElevatorServo](INFO): Powering down
2019-02-21T23:46:22.438Z,1550792782.438 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-21T23:46:22.438Z,1550792782.438 [MassServo](INFO): Powering down
2019-02-21T23:46:22.439Z,1550792782.439 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-21T23:46:22.439Z,1550792782.439 [RudderServo](INFO): Powering down
2019-02-21T23:46:22.440Z,1550792782.440 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-21T23:46:22.440Z,1550792782.440 [ThrusterServo](INFO): Powering down
2019-02-21T23:46:22.441Z,1550792782.441 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-21T23:46:22.441Z,1550792782.441 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-21T23:46:22.441Z,1550792782.441 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-21T23:46:22.441Z,1550792782.441 [CBIT](DEBUG): Powering off loads.
2019-02-21T23:46:22.453Z,1550792782.453 [CBIT](DEBUG): Disabling WDT.
2019-02-21T23:46:22.465Z,1550792782.465 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-21T23:46:22.466Z,1550792782.466 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:22.520Z,1550792782.520 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:22.922Z,1550792782.922 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:22.924Z,1550792782.924 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:22.978Z,1550792782.978 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-21T23:46:23.033Z,1550792783.033 [logger ThreadHandler](INFO): Thread cancelled.