2019-09-06T22:11:18.381Z,1567807878.381 [Supervisor](DEBUG): Initializing supervisor. 2019-09-06T22:11:18.384Z,1567807878.384 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-09-06T22:11:18.385Z,1567807878.385 [SyncHandler](INFO): Protected caller Thread ID is 3111 2019-09-06T22:11:18.386Z,1567807878.386 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-09-06T22:11:18.386Z,1567807878.386 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-09-06T22:11:18.387Z,1567807878.387 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3112 2019-09-06T22:11:18.390Z,1567807878.390 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-09-06T22:11:18.402Z,1567807878.402 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-09-06T22:11:18.403Z,1567807878.403 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-09-06T22:11:18.403Z,1567807878.403 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3113 2019-09-06T22:11:18.404Z,1567807878.404 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-09-06T22:11:18.405Z,1567807878.405 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-09-06T22:11:18.405Z,1567807878.405 [logger ThreadHandler](INFO): Protected caller Thread ID is 3114 2019-09-06T22:11:18.407Z,1567807878.407 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-09-06T22:11:18.408Z,1567807878.408 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-09-06T22:11:18.409Z,1567807878.409 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-09-06T22:11:18.502Z,1567807878.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-09-06T22:11:18.503Z,1567807878.503 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-09-06T22:11:19.150Z,1567807879.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-09-06T22:11:19.150Z,1567807879.150 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-09-06T22:11:19.247Z,1567807879.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-09-06T22:11:19.248Z,1567807879.248 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-09-06T22:11:19.348Z,1567807879.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-09-06T22:11:19.348Z,1567807879.348 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-09-06T22:11:19.427Z,1567807879.427 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-09-06T22:11:19.564Z,1567807879.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-09-06T22:11:19.564Z,1567807879.564 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-09-06T22:11:19.857Z,1567807879.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-09-06T22:11:19.858Z,1567807879.858 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-09-06T22:11:20.311Z,1567807880.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-09-06T22:11:20.311Z,1567807880.311 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-09-06T22:11:20.455Z,1567807880.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-09-06T22:11:20.456Z,1567807880.456 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-09-06T22:11:20.655Z,1567807880.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-09-06T22:11:20.656Z,1567807880.656 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-09-06T22:11:21.106Z,1567807881.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-09-06T22:11:21.107Z,1567807881.107 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-09-06T22:11:21.319Z,1567807881.319 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-09-06T22:11:21.319Z,1567807881.319 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-09-06T22:11:21.518Z,1567807881.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-09-06T22:11:21.519Z,1567807881.519 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-09-06T22:11:21.907Z,1567807881.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-09-06T22:11:21.908Z,1567807881.908 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-09-06T22:11:22.240Z,1567807882.240 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-09-06T22:11:22.242Z,1567807882.242 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2019-09-06T22:11:22.243Z,1567807882.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2019-09-06T22:11:22.324Z,1567807882.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2019-09-06T22:11:22.479Z,1567807882.479 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2019-09-06T22:11:22.586Z,1567807882.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2019-09-06T22:11:22.669Z,1567807882.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2019-09-06T22:11:22.762Z,1567807882.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2019-09-06T22:11:22.954Z,1567807882.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2019-09-06T22:11:23.178Z,1567807883.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-09-06T22:11:23.179Z,1567807883.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2019-09-06T22:11:23.271Z,1567807883.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2019-09-06T22:11:23.367Z,1567807883.367 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2019-09-06T22:11:23.484Z,1567807883.484 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2019-09-06T22:11:23.584Z,1567807883.584 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2019-09-06T22:11:23.584Z,1567807883.584 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-09-06T22:11:23.588Z,1567807883.588 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-09-06T22:11:23.656Z,1567807883.656 [VerticalControl](DEBUG): Construct VerticalControl. 2019-09-06T22:11:23.769Z,1567807883.769 [VerticalControl] Loaded 2019-09-06T22:11:23.769Z,1567807883.769 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-09-06T22:11:23.770Z,1567807883.770 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-09-06T22:11:23.838Z,1567807883.838 [HorizontalControl] Loaded 2019-09-06T22:11:23.839Z,1567807883.839 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-09-06T22:11:23.839Z,1567807883.839 [SpeedControl](DEBUG): Construct SpeedControl. 2019-09-06T22:11:23.845Z,1567807883.845 [SpeedControl] Loaded 2019-09-06T22:11:23.845Z,1567807883.845 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-09-06T22:11:23.846Z,1567807883.846 [LoopControl](DEBUG): Construct LoopControl. 2019-09-06T22:11:23.847Z,1567807883.847 [LoopControl] Loaded 2019-09-06T22:11:23.847Z,1567807883.847 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-09-06T22:11:23.847Z,1567807883.847 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-09-06T22:11:23.848Z,1567807883.848 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-09-06T22:11:23.889Z,1567807883.889 [DepthRateCalculator] Loaded 2019-09-06T22:11:23.889Z,1567807883.889 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-09-06T22:11:23.894Z,1567807883.894 [PitchRateCalculator] Loaded 2019-09-06T22:11:23.895Z,1567807883.895 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-09-06T22:11:23.910Z,1567807883.910 [SpeedCalculator] Loaded 2019-09-06T22:11:23.910Z,1567807883.910 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-09-06T22:11:23.931Z,1567807883.931 [TempGradientCalculator] Loaded 2019-09-06T22:11:23.931Z,1567807883.931 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-09-06T22:11:23.936Z,1567807883.936 [YawRateCalculator] Loaded 2019-09-06T22:11:23.936Z,1567807883.936 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-09-06T22:11:23.966Z,1567807883.966 [ElevatorOffsetCalculator] Loaded 2019-09-06T22:11:23.966Z,1567807883.966 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-09-06T22:11:23.967Z,1567807883.967 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-09-06T22:11:23.967Z,1567807883.967 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-09-06T22:11:23.999Z,1567807883.999 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-09-06T22:11:23.000Z,1567807884.000 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-09-06T22:11:24.069Z,1567807884.069 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-09-06T22:11:24.070Z,1567807884.070 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-09-06T22:11:24.358Z,1567807884.358 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-09-06T22:11:24.358Z,1567807884.358 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-09-06T22:11:24.526Z,1567807884.526 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-09-06T22:11:24.526Z,1567807884.526 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-09-06T22:11:25.353Z,1567807885.353 [AHRS_M2] Loaded 2019-09-06T22:11:25.353Z,1567807885.353 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-09-06T22:11:25.426Z,1567807885.426 [DataOverHttps] Loaded 2019-09-06T22:11:25.426Z,1567807885.426 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-09-06T22:11:25.427Z,1567807885.427 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BB4E0 2019-09-06T22:11:25.428Z,1567807885.428 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3193 2019-09-06T22:11:25.441Z,1567807885.441 [Depth_Keller] Loaded 2019-09-06T22:11:25.441Z,1567807885.441 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-09-06T22:11:25.446Z,1567807885.446 [DropWeight] Loaded 2019-09-06T22:11:25.446Z,1567807885.446 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-09-06T22:11:25.543Z,1567807885.543 [NAL9602] Loaded 2019-09-06T22:11:25.544Z,1567807885.544 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-09-06T22:11:25.559Z,1567807885.559 [Onboard] Loaded 2019-09-06T22:11:25.560Z,1567807885.560 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-09-06T22:11:25.563Z,1567807885.563 [Radio_Surface] Loaded 2019-09-06T22:11:25.563Z,1567807885.563 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-09-06T22:11:25.564Z,1567807885.564 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408EB4E0 2019-09-06T22:11:25.565Z,1567807885.565 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3194 2019-09-06T22:11:25.610Z,1567807885.610 [Rowe_600] Loaded 2019-09-06T22:11:25.611Z,1567807885.611 [ComponentRegistry](DEBUG): SyncComponent "Rowe_600" handled in the control thread. 2019-09-06T22:11:27.098Z,1567807887.098 [BPC1] Loaded 2019-09-06T22:11:27.098Z,1567807887.098 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-09-06T22:11:27.099Z,1567807887.099 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-09-06T22:11:27.099Z,1567807887.099 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-09-06T22:11:27.202Z,1567807887.202 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-09-06T22:11:27.202Z,1567807887.202 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-09-06T22:11:27.223Z,1567807887.223 [NavChart] Loaded 2019-09-06T22:11:27.223Z,1567807887.223 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-09-06T22:11:27.227Z,1567807887.227 [UniversalFixResidualReporter] Loaded 2019-09-06T22:11:27.227Z,1567807887.227 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-09-06T22:11:27.228Z,1567807887.228 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-09-06T22:11:27.228Z,1567807887.228 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-09-06T22:11:27.359Z,1567807887.359 [SBIT](DEBUG): Construct Startup Built In Test. 2019-09-06T22:11:27.370Z,1567807887.370 [SBIT] Loaded 2019-09-06T22:11:27.371Z,1567807887.371 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-09-06T22:11:27.371Z,1567807887.371 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-09-06T22:11:27.383Z,1567807887.383 [IBIT] Loaded 2019-09-06T22:11:27.383Z,1567807887.383 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-09-06T22:11:27.387Z,1567807887.387 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-09-06T22:11:27.525Z,1567807887.525 [CBIT] Loaded 2019-09-06T22:11:27.525Z,1567807887.525 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-09-06T22:11:27.526Z,1567807887.526 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-09-06T22:11:27.526Z,1567807887.526 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-09-06T22:11:27.627Z,1567807887.627 [BuoyancyServo] Loaded 2019-09-06T22:11:27.628Z,1567807887.628 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-09-06T22:11:27.639Z,1567807887.639 [ElevatorServo] Loaded 2019-09-06T22:11:27.639Z,1567807887.639 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-09-06T22:11:27.650Z,1567807887.650 [MassServo] Loaded 2019-09-06T22:11:27.650Z,1567807887.650 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-09-06T22:11:27.661Z,1567807887.661 [RudderServo] Loaded 2019-09-06T22:11:27.662Z,1567807887.662 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-09-06T22:11:27.672Z,1567807887.672 [ThrusterServo] Loaded 2019-09-06T22:11:27.673Z,1567807887.673 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-09-06T22:11:27.673Z,1567807887.673 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-09-06T22:11:27.674Z,1567807887.674 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-09-06T22:11:27.686Z,1567807887.686 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-09-06T22:11:27.687Z,1567807887.687 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-09-06T22:11:27.859Z,1567807887.859 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-09-06T22:11:27.865Z,1567807887.865 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-09-06T22:11:27.866Z,1567807887.866 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-09-06T22:11:27.871Z,1567807887.871 [CTD_Seabird](INFO): created writer for : depth 2019-09-06T22:11:27.872Z,1567807887.872 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-09-06T22:11:27.877Z,1567807887.877 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-09-06T22:11:27.877Z,1567807887.877 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-09-06T22:11:27.882Z,1567807887.882 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-09-06T22:11:27.883Z,1567807887.883 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-09-06T22:11:27.888Z,1567807887.888 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-09-06T22:11:27.889Z,1567807887.889 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-09-06T22:11:27.894Z,1567807887.894 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-09-06T22:11:27.894Z,1567807887.894 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-09-06T22:11:27.900Z,1567807887.900 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-09-06T22:11:27.927Z,1567807887.927 [CTD_Seabird] Loaded 2019-09-06T22:11:27.927Z,1567807887.927 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-09-06T22:11:27.928Z,1567807887.928 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A6F4E0 2019-09-06T22:11:27.929Z,1567807887.929 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3195 2019-09-06T22:11:27.977Z,1567807887.977 [WetLabsBB2FL] Loaded 2019-09-06T22:11:27.977Z,1567807887.977 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-09-06T22:11:27.979Z,1567807887.979 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A9F4E0 2019-09-06T22:11:27.979Z,1567807887.979 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3196 2019-09-06T22:11:27.980Z,1567807887.980 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-09-06T22:11:27.983Z,1567807887.983 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-09-06T22:11:27.984Z,1567807887.984 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-09-06T22:11:27.991Z,1567807887.991 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-09-06T22:11:27.992Z,1567807887.992 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACF4E0 2019-09-06T22:11:27.992Z,1567807887.992 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3197 2019-09-06T22:11:27.997Z,1567807887.997 [Supervisor](INFO): Main Thread ID is 2963 2019-09-06T22:11:27.997Z,1567807887.997 [Supervisor](DEBUG): Running supervisor. 2019-09-06T22:11:27.997Z,1567807887.997 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3198 2019-09-06T22:11:27.000Z,1567807888.000 [controlThread ThreadHandler](INFO): Handler Thread ID is 3199 2019-09-06T22:11:28.000Z,1567807888.000 [controlThread](DEBUG): Initializing ControlThread 2019-09-06T22:11:28.001Z,1567807888.001 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-09-06T22:11:28.003Z,1567807888.003 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-09-06T22:11:28.003Z,1567807888.003 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-09-06T22:11:28.004Z,1567807888.004 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-09-06T22:11:28.004Z,1567807888.004 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-09-06T22:11:28.005Z,1567807888.005 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-09-06T22:11:28.005Z,1567807888.005 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-09-06T22:11:28.005Z,1567807888.005 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-09-06T22:11:28.006Z,1567807888.006 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-09-06T22:11:28.006Z,1567807888.006 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-09-06T22:11:28.011Z,1567807888.011 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-09-06T22:11:28.012Z,1567807888.012 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-09-06T22:11:28.012Z,1567807888.012 [SBIT](INFO): Initialize SBIT Component. 2019-09-06T22:11:28.013Z,1567807888.013 [SBIT](IMPORTANT): git: 2019-08-21a 2019-09-06T22:11:28.013Z,1567807888.013 [SBIT](INFO): git hash: 91726968a91bb65c09438a13bfa0101a916cbc64 2019-09-06T22:11:28.013Z,1567807888.013 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-09-06T22:11:28.013Z,1567807888.013 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-09-06T22:11:28.014Z,1567807888.014 [SBIT](INFO): Beginning SBIT in 59.000000 seconds. 2019-09-06T22:11:28.015Z,1567807888.015 [IBIT](INFO): Initialize IBIT Component. 2019-09-06T22:11:28.016Z,1567807888.016 [CBIT](DEBUG): Initialize CBIT Component. 2019-09-06T22:11:28.017Z,1567807888.017 [logger ThreadHandler](INFO): Handler Thread ID is 3200 2019-09-06T22:11:28.029Z,1567807888.029 [CBIT](DEBUG): Initialized mux pins. 2019-09-06T22:11:28.029Z,1567807888.029 [CBIT](DEBUG): Initializing the watchdog timer. 2019-09-06T22:11:28.037Z,1567807888.037 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3201 2019-09-06T22:11:28.038Z,1567807888.038 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-09-06T22:11:28.049Z,1567807888.049 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3202 2019-09-06T22:11:28.053Z,1567807888.053 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-09-06T22:11:28.053Z,1567807888.053 [CBIT](DEBUG): Initializing heartbeat. 2019-09-06T22:11:28.061Z,1567807888.061 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3203 2019-09-06T22:11:28.062Z,1567807888.062 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-09-06T22:11:28.065Z,1567807888.065 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-09-06T22:11:28.066Z,1567807888.066 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3205 2019-09-06T22:11:28.068Z,1567807888.068 [WetLabsBB2FL](INFO): Powering down 2019-09-06T22:11:28.097Z,1567807888.097 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3206 2019-09-06T22:11:28.100Z,1567807888.100 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-09-06T22:11:28.101Z,1567807888.101 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-09-06T22:11:28.101Z,1567807888.101 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-09-06T22:11:28.101Z,1567807888.101 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-09-06T22:11:28.101Z,1567807888.101 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-09-06T22:11:28.101Z,1567807888.101 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-09-06T22:11:28.101Z,1567807888.101 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-09-06T22:11:28.102Z,1567807888.102 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-09-06T22:11:28.102Z,1567807888.102 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-09-06T22:11:28.102Z,1567807888.102 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-09-06T22:11:28.102Z,1567807888.102 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-09-06T22:11:28.102Z,1567807888.102 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-09-06T22:11:28.102Z,1567807888.102 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-09-06T22:11:28.103Z,1567807888.103 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-09-06T22:11:28.103Z,1567807888.103 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-09-06T22:11:28.103Z,1567807888.103 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-09-06T22:11:28.125Z,1567807888.125 [CBIT](DEBUG): Deactivating GF circuits. 2019-09-06T22:11:28.125Z,1567807888.125 [CBIT](DEBUG): Deactivating emergency mode. 2019-09-06T22:11:28.161Z,1567807888.161 [CBIT](DEBUG): Backplane powered. 2019-09-06T22:11:28.163Z,1567807888.163 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-09-06T22:11:28.172Z,1567807888.172 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-09-06T22:11:28.183Z,1567807888.183 [MissionManager](DEBUG): 2019-09-06T22:11:28.183Z,1567807888.183 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-09-06T22:11:28.250Z,1567807888.250 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-09-06T22:11:28.251Z,1567807888.251 [Default:A.Wait](DEBUG): Construct Wait. 2019-09-06T22:11:28.252Z,1567807888.252 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-09-06T22:11:28.295Z,1567807888.295 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-09-06T22:11:28.313Z,1567807888.313 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-09-06T22:11:28.319Z,1567807888.319 [Default:E.Execute](DEBUG): Construct Execute. 2019-09-06T22:11:28.340Z,1567807888.340 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-09-06T22:11:28.354Z,1567807888.354 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,Rowe_600,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-09-06T22:11:28.373Z,1567807888.373 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-09-06T22:11:28.407Z,1567807888.407 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2019-09-06T22:11:28.453Z,1567807888.453 [Radio_Surface](INFO): Powering up 2019-09-06T22:11:28.473Z,1567807888.473 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-09-06T22:11:28.512Z,1567807888.512 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-09-06T22:11:28.517Z,1567807888.517 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-09-06T22:11:28.518Z,1567807888.518 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-09-06T22:11:28.525Z,1567807888.525 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-09-06T22:11:28.526Z,1567807888.526 [MassServo](DEBUG): Initializing EZServoServo. 2019-09-06T22:11:28.533Z,1567807888.533 [MassServo](DEBUG): Initializing MassServo. 2019-09-06T22:11:28.534Z,1567807888.534 [RudderServo](DEBUG): Initializing EZServoServo. 2019-09-06T22:11:28.541Z,1567807888.541 [RudderServo](DEBUG): Initializing RudderServo. 2019-09-06T22:11:28.542Z,1567807888.542 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-09-06T22:11:28.549Z,1567807888.549 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-09-06T22:11:29.505Z,1567807889.505 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-09-06T22:11:29.505Z,1567807889.505 [RudderServo](FAULT): Rudder failed to initialize 2019-09-06T22:11:29.505Z,1567807889.505 [RudderServo] Communications Fault, FailCount= 1 2019-09-06T22:11:29.505Z,1567807889.505 [RudderServo](ERROR): Communications Fault 2019-09-06T22:11:29.616Z,1567807889.616 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-09-06T22:11:29.802Z,1567807889.802 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-09-06T22:11:29.802Z,1567807889.802 [RudderServo](INFO): Powering down 2019-09-06T22:11:30.464Z,1567807890.464 [RudderServo](DEBUG): Initializing EZServoServo. 2019-09-06T22:11:30.582Z,1567807890.582 [RudderServo](DEBUG): Initializing RudderServo. 2019-09-06T22:11:30.586Z,1567807890.586 [CBIT](INFO): Clearing failed state for component RudderServo 2019-09-06T22:11:30.586Z,1567807890.586 [RudderServo] No Fault, FailCount= 1 2019-09-06T22:11:33.721Z,1567807893.721 [Rowe_600](ERROR): Failed to parse:Copyright (c) 2009-2019 Rowe Technologies Inc. All rights reserved. 2019-09-06T22:11:34.565Z,1567807894.565 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error 2019-09-06T22:11:34.565Z,1567807894.565 [Rowe_600](ERROR): Failed to parse: :TS,19090615093700,35.0,+15.0, 0.0,1498.0, C 2019-09-06T22:11:35.375Z,1567807895.375 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error 2019-09-06T22:11:35.375Z,1567807895.375 [Rowe_600](ERROR): Failed to parse: :TS,19090615093800,35.0,+15.0, 0.0,1498.0, C 2019-09-06T22:11:36.571Z,1567807896.571 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error 2019-09-06T22:11:36.571Z,1567807896.571 [Rowe_600](ERROR): Failed to parse: :TS,19090615093900,35.0,+15.0, 0.0,1498.0, C 2019-09-06T22:11:37.363Z,1567807897.363 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:11:37.366Z,1567807897.366 [Rowe_600](DEBUG): line: :BI, -8, +13, -10,-32768,A 2019-09-06T22:11:37.366Z,1567807897.366 [Rowe_600](INFO): Inst Velocity X:8.000000 2019-09-06T22:11:37.366Z,1567807897.366 [Rowe_600](INFO): Inst Velocity Y:13.000000 2019-09-06T22:11:38.574Z,1567807898.574 [Rowe_600](DEBUG): line: :BI, +6, +0, +0,-32768,A 2019-09-06T22:11:38.574Z,1567807898.574 [Rowe_600](INFO): Inst Velocity X:-6.000000 2019-09-06T22:11:38.574Z,1567807898.574 [Rowe_600](INFO): Inst Velocity Y:0.000000 2019-09-06T22:11:39.386Z,1567807899.386 [Rowe_600](DEBUG): line: :BI, -1, +3, -2,-32768,A 2019-09-06T22:11:39.387Z,1567807899.387 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:11:39.387Z,1567807899.387 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:11:40.593Z,1567807900.593 [Rowe_600](DEBUG): line: :BI, -7, +7, -4,-32768,A 2019-09-06T22:11:40.594Z,1567807900.594 [Rowe_600](INFO): Inst Velocity X:7.000000 2019-09-06T22:11:40.594Z,1567807900.594 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:11:41.402Z,1567807901.402 [Rowe_600](DEBUG): line: :BI, -9, +6, -3,-32768,A 2019-09-06T22:11:41.402Z,1567807901.402 [Rowe_600](INFO): Inst Velocity X:9.000000 2019-09-06T22:11:41.403Z,1567807901.403 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:11:42.614Z,1567807902.614 [Rowe_600](DEBUG): line: :BI, -6, +1, -1,-32768,A 2019-09-06T22:11:42.615Z,1567807902.615 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:11:42.615Z,1567807902.615 [Rowe_600](INFO): Inst Velocity Y:1.000000 2019-09-06T22:11:43.422Z,1567807903.422 [Rowe_600](DEBUG): line: :BI, -1, -4, +2,-32768,A 2019-09-06T22:11:43.423Z,1567807903.423 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:11:43.423Z,1567807903.423 [Rowe_600](INFO): Inst Velocity Y:-4.000000 2019-09-06T22:11:44.634Z,1567807904.634 [Rowe_600](DEBUG): line: :BI, +5, -4, +2,-32768,A 2019-09-06T22:11:44.634Z,1567807904.634 [Rowe_600](INFO): Inst Velocity X:-5.000000 2019-09-06T22:11:44.634Z,1567807904.634 [Rowe_600](INFO): Inst Velocity Y:-4.000000 2019-09-06T22:11:45.442Z,1567807905.442 [Rowe_600](DEBUG): line: :BI, +7, -6, +2,-32768,A 2019-09-06T22:11:45.443Z,1567807905.443 [Rowe_600](INFO): Inst Velocity X:-7.000000 2019-09-06T22:11:45.443Z,1567807905.443 [Rowe_600](INFO): Inst Velocity Y:-6.000000 2019-09-06T22:11:46.285Z,1567807906.285 [Rowe_600](DEBUG): line: :BI, +6, -4, +1,-32768 -4, +6, +1,A 2019-09-06T22:11:46.286Z,1567807906.286 [Rowe_600](INFO): Inst Velocity X:-6.000000 2019-09-06T22:11:46.286Z,1567807906.286 [Rowe_600](INFO): Inst Velocity Y:-4.000000 2019-09-06T22:11:46.286Z,1567807906.286 [Rowe_600](ERROR): only read 3 of 4 data items 2019-09-06T22:11:46.286Z,1567807906.286 [Rowe_600](ERROR): Failed to parse: :BI, +6, -4, +1,-32768 -4, +6, +1,A 2019-09-06T22:11:47.474Z,1567807907.474 [Rowe_600](DEBUG): line: :BI, +2, -1, -1,-32768,A 2019-09-06T22:11:47.475Z,1567807907.475 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:11:47.475Z,1567807907.475 [Rowe_600](INFO): Inst Velocity Y:-1.000000 2019-09-06T22:11:48.281Z,1567807908.281 [Rowe_600](DEBUG): line: :BI, -6, +3, -2,-32768,A 2019-09-06T22:11:48.281Z,1567807908.281 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:11:48.282Z,1567807908.282 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:11:49.485Z,1567807909.485 [Rowe_600](DEBUG): line: :BI, -8, +7, -3,-32768,A 2019-09-06T22:11:49.485Z,1567807909.485 [Rowe_600](INFO): Inst Velocity X:8.000000 2019-09-06T22:11:49.485Z,1567807909.485 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:11:50.287Z,1567807910.287 [Rowe_600](DEBUG): line: :BI, -6, +4, -2,-32768,A 2019-09-06T22:11:50.287Z,1567807910.287 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:11:50.287Z,1567807910.287 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:11:51.506Z,1567807911.506 [Rowe_600](DEBUG): line: :BI, -3, +3, -1,-32768,A 2019-09-06T22:11:51.507Z,1567807911.507 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:11:51.507Z,1567807911.507 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:11:52.310Z,1567807912.310 [Rowe_600](DEBUG): line: :BI, -1, +0, +0,-32768,A 2019-09-06T22:11:52.310Z,1567807912.310 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:11:52.311Z,1567807912.311 [Rowe_600](INFO): Inst Velocity Y:0.000000 2019-09-06T22:11:53.528Z,1567807913.528 [Rowe_600](DEBUG): line: :BI, +2, +0, +0,-32768,A 2019-09-06T22:11:53.528Z,1567807913.528 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:11:53.533Z,1567807913.533 [Rowe_600](INFO): Inst Velocity Y:0.000000 2019-09-06T22:11:54.332Z,1567807914.332 [Rowe_600](DEBUG): line: :BI, +4, -1, +0,-32768,A 2019-09-06T22:11:54.333Z,1567807914.333 [Rowe_600](INFO): Inst Velocity X:-4.000000 2019-09-06T22:11:54.333Z,1567807914.333 [Rowe_600](INFO): Inst Velocity Y:-1.000000 2019-09-06T22:11:55.548Z,1567807915.548 [Rowe_600](DEBUG): line: :BI, +1, +4, -2,-32768,A 2019-09-06T22:11:55.548Z,1567807915.548 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:11:55.548Z,1567807915.548 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:11:55.926Z,1567807915.926 [NAL9602](INFO): Powering up NAL9602 2019-09-06T22:11:56.351Z,1567807916.351 [Rowe_600](DEBUG): line: :BI, -2, +5, -2,-32768,A 2019-09-06T22:11:56.351Z,1567807916.351 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:11:56.351Z,1567807916.351 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:11:57.556Z,1567807917.556 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:11:57.559Z,1567807917.559 [Rowe_600](DEBUG): line: :BI,-32768,-32768,-32768,-32768,V 2019-09-06T22:11:57.559Z,1567807917.559 [Rowe_600](INFO): Inst Velocity X:32768.000000 2019-09-06T22:11:57.559Z,1567807917.559 [Rowe_600](INFO): Inst Velocity Y:-32768.000000 2019-09-06T22:11:58.367Z,1567807918.367 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:11:58.370Z,1567807918.370 [Rowe_600](DEBUG): line: :BI,-32768,-32768,-32768,-32768,V 2019-09-06T22:11:58.370Z,1567807918.370 [Rowe_600](INFO): Inst Velocity X:32768.000000 2019-09-06T22:11:58.371Z,1567807918.371 [Rowe_600](INFO): Inst Velocity Y:-32768.000000 2019-09-06T22:11:59.580Z,1567807919.580 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:11:59.583Z,1567807919.583 [Rowe_600](DEBUG): line: :BI,-32768,-32768,-32768,-32768,V 2019-09-06T22:11:59.583Z,1567807919.583 [Rowe_600](INFO): Inst Velocity X:32768.000000 2019-09-06T22:11:59.583Z,1567807919.583 [Rowe_600](INFO): Inst Velocity Y:-32768.000000 2019-09-06T22:12:00.384Z,1567807920.384 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:12:00.387Z,1567807920.387 [Rowe_600](DEBUG): line: :BI,-32768,-32768,-32768,-32768,V 2019-09-06T22:12:00.387Z,1567807920.387 [Rowe_600](INFO): Inst Velocity X:32768.000000 2019-09-06T22:12:00.387Z,1567807920.387 [Rowe_600](INFO): Inst Velocity Y:-32768.000000 2019-09-06T22:12:01.603Z,1567807921.603 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:12:01.606Z,1567807921.606 [Rowe_600](DEBUG): line: :BI, -2, -1, -1,-32768,A 2019-09-06T22:12:01.606Z,1567807921.606 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:12:01.606Z,1567807921.606 [Rowe_600](INFO): Inst Velocity Y:-1.000000 2019-09-06T22:12:02.407Z,1567807922.407 [Rowe_600](DEBUG): line: :BI, -5, +3, +0,-32768,A 2019-09-06T22:12:02.407Z,1567807922.407 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:02.407Z,1567807922.407 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:12:03.617Z,1567807923.617 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:12:03.619Z,1567807923.619 [Rowe_600](DEBUG): line: :BI,-32768,-32768,-32768,-32768,V 2019-09-06T22:12:03.620Z,1567807923.620 [Rowe_600](INFO): Inst Velocity X:32768.000000 2019-09-06T22:12:03.620Z,1567807923.620 [Rowe_600](INFO): Inst Velocity Y:-32768.000000 2019-09-06T22:12:04.425Z,1567807924.425 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:12:04.427Z,1567807924.427 [Rowe_600](DEBUG): line: :BI,-32768,-32768,-32768,-32768,V 2019-09-06T22:12:04.427Z,1567807924.427 [Rowe_600](INFO): Inst Velocity X:32768.000000 2019-09-06T22:12:04.428Z,1567807924.428 [Rowe_600](INFO): Inst Velocity Y:-32768.000000 2019-09-06T22:12:05.257Z,1567807925.257 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:12:05.260Z,1567807925.260 [Rowe_600](DEBUG): line: :BI, -4, +9, -1,-32768,A 2019-09-06T22:12:05.260Z,1567807925.260 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:05.260Z,1567807925.260 [Rowe_600](INFO): Inst Velocity Y:9.000000 2019-09-06T22:12:06.461Z,1567807926.461 [Rowe_600](DEBUG): line: :BI, -6, +7, -1,-32768,A 2019-09-06T22:12:06.461Z,1567807926.461 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:12:06.461Z,1567807926.461 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:12:06.838Z,1567807926.838 [NAL9602](INFO): NAL9602 initialized 2019-09-06T22:12:07.296Z,1567807927.296 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:12:07.299Z,1567807927.299 [Rowe_600](DEBUG): line: :BI,-32768,-32768,-32768,-32768,V 2019-09-06T22:12:07.299Z,1567807927.299 [Rowe_600](INFO): Inst Velocity X:32768.000000 2019-09-06T22:12:07.299Z,1567807927.299 [Rowe_600](INFO): Inst Velocity Y:-32768.000000 2019-09-06T22:12:07.653Z,1567807927.653 [NAL9602](DEBUG): Fix Requested 2019-09-06T22:12:08.493Z,1567807928.493 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:12:08.495Z,1567807928.495 [Rowe_600](DEBUG): line: :BI, -6, +6, -1,-32768,A 2019-09-06T22:12:08.496Z,1567807928.496 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:12:08.496Z,1567807928.496 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:09.283Z,1567807929.283 [Rowe_600](DEBUG): line: :BI, -6, +7, -1,-32768,A 2019-09-06T22:12:09.283Z,1567807929.283 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:12:09.283Z,1567807929.283 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:12:10.503Z,1567807930.503 [Rowe_600](DEBUG): line: :BI, -6, +8, -1,-32768,A 2019-09-06T22:12:10.504Z,1567807930.504 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:12:10.504Z,1567807930.504 [Rowe_600](INFO): Inst Velocity Y:8.000000 2019-09-06T22:12:11.303Z,1567807931.303 [Rowe_600](DEBUG): line: :BI, -5, +6, -1,-32768,A 2019-09-06T22:12:11.304Z,1567807931.304 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:11.304Z,1567807931.304 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:12.508Z,1567807932.508 [Rowe_600](DEBUG): line: :BI, -4, +3, +0,-32768,A 2019-09-06T22:12:12.508Z,1567807932.508 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:12.509Z,1567807932.509 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:12:13.316Z,1567807933.316 [Rowe_600](DEBUG): line: :BI, -5, +6, +0,-32768,A 2019-09-06T22:12:13.316Z,1567807933.316 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:13.316Z,1567807933.316 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:14.540Z,1567807934.540 [Rowe_600](DEBUG): line: :BI, -4, +8, -1,-32768,A 2019-09-06T22:12:14.541Z,1567807934.541 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:14.541Z,1567807934.541 [Rowe_600](INFO): Inst Velocity Y:8.000000 2019-09-06T22:12:15.343Z,1567807935.343 [Rowe_600](DEBUG): line: :BI, -3, +7, -2,-32768,A 2019-09-06T22:12:15.343Z,1567807935.343 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:15.343Z,1567807935.343 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:12:16.547Z,1567807936.547 [Rowe_600](DEBUG): line: :BI, -3, +5, +0,-32768,A 2019-09-06T22:12:16.547Z,1567807936.547 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:16.548Z,1567807936.548 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:12:17.356Z,1567807937.356 [Rowe_600](DEBUG): line: :BI, -4, +6, -1,-32768,A 2019-09-06T22:12:17.356Z,1567807937.356 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:17.356Z,1567807937.356 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:18.574Z,1567807938.574 [Rowe_600](DEBUG): line: :BI, -5, +8, -1,-32768,A 2019-09-06T22:12:18.574Z,1567807938.574 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:18.575Z,1567807938.575 [Rowe_600](INFO): Inst Velocity Y:8.000000 2019-09-06T22:12:19.377Z,1567807939.377 [Rowe_600](DEBUG): line: :BI, -5, +5, +0,-32768,A 2019-09-06T22:12:19.377Z,1567807939.377 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:19.377Z,1567807939.377 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:12:20.588Z,1567807940.588 [Rowe_600](DEBUG): line: :BI, -5, +5, +0,-32768,A 2019-09-06T22:12:20.588Z,1567807940.588 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:20.588Z,1567807940.588 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:12:21.395Z,1567807941.395 [Rowe_600](DEBUG): line: :BI, -5, +6, +0,-32768,A 2019-09-06T22:12:21.396Z,1567807941.396 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:21.396Z,1567807941.396 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:22.606Z,1567807942.606 [Rowe_600](DEBUG): line: :BI, -6, +9, -1,-32768,A 2019-09-06T22:12:22.606Z,1567807942.606 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:12:22.606Z,1567807942.606 [Rowe_600](INFO): Inst Velocity Y:9.000000 2019-09-06T22:12:23.415Z,1567807943.415 [Rowe_600](DEBUG): line: :BI, -4, +6, -1,-32768,A 2019-09-06T22:12:23.416Z,1567807943.416 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:23.416Z,1567807943.416 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:24.635Z,1567807944.635 [Rowe_600](DEBUG): line: :BI, -3, +8, +0,-32768,A 2019-09-06T22:12:24.636Z,1567807944.636 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:24.636Z,1567807944.636 [Rowe_600](INFO): Inst Velocity Y:8.000000 2019-09-06T22:12:25.442Z,1567807945.442 [Rowe_600](DEBUG): line: :BI, -5, +7, +0,-32768,A 2019-09-06T22:12:25.443Z,1567807945.443 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:25.443Z,1567807945.443 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:12:26.645Z,1567807946.645 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error 2019-09-06T22:12:26.645Z,1567807946.645 [Rowe_600](ERROR): Failed to parse: :TS,19090615102900,.0, 0.0,1498.0, 0 2019-09-06T22:12:27.455Z,1567807947.455 [Rowe_600](DEBUG): line: :BI, -6, +9, -2,-32768,A 2019-09-06T22:12:27.455Z,1567807947.455 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:12:27.455Z,1567807947.455 [Rowe_600](INFO): Inst Velocity Y:9.000000 2019-09-06T22:12:27.475Z,1567807947.475 [SBIT](IMPORTANT): Beginning Startup BIT 2019-09-06T22:12:27.479Z,1567807947.479 [CBIT](IMPORTANT): Beginning ground fault scan 2019-09-06T22:12:27.844Z,1567807947.844 [NAL9602](INFO): SBD MO Status=2, MOMSN=646, MT Status=2, MTMSN=0 2019-09-06T22:12:27.844Z,1567807947.844 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-09-06T22:12:28.296Z,1567807948.296 [Rowe_600](DEBUG): line: :BI, -5, +8, -1,-32768,A 2019-09-06T22:12:28.296Z,1567807948.296 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:28.296Z,1567807948.296 [Rowe_600](INFO): Inst Velocity Y:8.000000 2019-09-06T22:12:29.520Z,1567807949.520 [Rowe_600](DEBUG): line: :BI, -4, +9, -2,-32768,A 2019-09-06T22:12:29.520Z,1567807949.520 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:29.525Z,1567807949.525 [Rowe_600](INFO): Inst Velocity Y:9.000000 2019-09-06T22:12:30.464Z,1567807950.464 [Rowe_600](DEBUG): line: :BI, -4, +6, -1,-32768,A 2019-09-06T22:12:30.464Z,1567807950.464 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:30.464Z,1567807950.464 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:31.287Z,1567807951.287 [Rowe_600](DEBUG): line: :BI, -4, +7, -1,-32768,A 2019-09-06T22:12:31.288Z,1567807951.288 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:31.288Z,1567807951.288 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:12:32.488Z,1567807952.488 [Rowe_600](DEBUG): line: :BI, -3, +4, -1,-32768,A 2019-09-06T22:12:32.488Z,1567807952.488 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:32.489Z,1567807952.489 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:12:33.282Z,1567807953.282 [Rowe_600](DEBUG): line: :BI, -3, +3, +0,-32768,A 2019-09-06T22:12:33.282Z,1567807953.282 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:33.282Z,1567807953.282 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:12:34.490Z,1567807954.490 [Rowe_600](DEBUG): line: :BI, -2, +6, -3,-32768,A 2019-09-06T22:12:34.490Z,1567807954.490 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:12:34.490Z,1567807954.490 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:35.310Z,1567807955.310 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:12:35.313Z,1567807955.313 [Rowe_600](DEBUG): line: :BI,-32768,-32768,-32768,-32768,V 2019-09-06T22:12:35.313Z,1567807955.313 [Rowe_600](INFO): Inst Velocity X:32768.000000 2019-09-06T22:12:35.313Z,1567807955.313 [Rowe_600](INFO): Inst Velocity Y:-32768.000000 2019-09-06T22:12:36.508Z,1567807956.508 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4 2019-09-06T22:12:36.511Z,1567807956.511 [Rowe_600](DEBUG): line: :BI, -3, +7, -4,-32768,A 2019-09-06T22:12:36.511Z,1567807956.511 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:36.511Z,1567807956.511 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:12:37.331Z,1567807957.331 [Rowe_600](DEBUG): line: :BI, -3, +5, -3,-32768,A 2019-09-06T22:12:37.331Z,1567807957.331 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:37.331Z,1567807957.331 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:12:38.539Z,1567807958.539 [Rowe_600](DEBUG): line: :BI, +0, -4, +1,-32768,A 2019-09-06T22:12:38.539Z,1567807958.539 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:12:38.539Z,1567807958.539 [Rowe_600](INFO): Inst Velocity Y:-4.000000 2019-09-06T22:12:38.609Z,1567807958.609 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.007838 CHAN A1 (24V): 0.035898 CHAN A2 (12V): -0.007704 CHAN A3 (5V): -0.002863 CHAN B0 (3.3V): 0.000288 CHAN B1 (3.15aV): -0.000411 CHAN B2 (3.15bV): -0.000637 CHAN B3 (GND): 0.003940 OPEN: 0.007047 Full Scale Calc: 4.765 mA, -1.589 mA 2019-09-06T22:12:39.351Z,1567807959.351 [Rowe_600](DEBUG): line: :BI, -1, -8, +2,-32768,A 2019-09-06T22:12:39.351Z,1567807959.351 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:12:39.352Z,1567807959.352 [Rowe_600](INFO): Inst Velocity Y:-8.000000 2019-09-06T22:12:40.561Z,1567807960.561 [Rowe_600](DEBUG): line: :BI, -4, -3, +0,-32768,A 2019-09-06T22:12:40.561Z,1567807960.561 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:40.561Z,1567807960.561 [Rowe_600](INFO): Inst Velocity Y:-3.000000 2019-09-06T22:12:41.384Z,1567807961.384 [Rowe_600](DEBUG): line: :BI, -6, +1, -1,-32768,A 2019-09-06T22:12:41.384Z,1567807961.384 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:12:41.384Z,1567807961.384 [Rowe_600](INFO): Inst Velocity Y:1.000000 2019-09-06T22:12:42.570Z,1567807962.570 [Rowe_600](DEBUG): line: :BI, -3, +3, -2,-32768,A 2019-09-06T22:12:42.570Z,1567807962.570 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:42.571Z,1567807962.571 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:12:43.483Z,1567807963.483 [Rowe_600](DEBUG): line: :BI, -4, +1, +0,-32768,A 2019-09-06T22:12:43.483Z,1567807963.483 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:43.483Z,1567807963.483 [Rowe_600](INFO): Inst Velocity Y:1.000000 2019-09-06T22:12:44.298Z,1567807964.298 [Rowe_600](DEBUG): line: :BI, -2, -3, +1,-32768,A 2019-09-06T22:12:44.298Z,1567807964.298 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:12:44.299Z,1567807964.299 [Rowe_600](INFO): Inst Velocity Y:-3.000000 2019-09-06T22:12:45.506Z,1567807965.506 [Rowe_600](DEBUG): line: :BI, -1, -5, +2,-32768,A 2019-09-06T22:12:45.506Z,1567807965.506 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:12:45.506Z,1567807965.506 [Rowe_600](INFO): Inst Velocity Y:-5.000000 2019-09-06T22:12:46.312Z,1567807966.312 [Rowe_600](DEBUG): line: :BI, -3, -5, +2,-32768,A 2019-09-06T22:12:46.312Z,1567807966.312 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:46.312Z,1567807966.312 [Rowe_600](INFO): Inst Velocity Y:-5.000000 2019-09-06T22:12:47.509Z,1567807967.509 [Rowe_600](DEBUG): line: :BI, -3, -2, +0,-32768,A 2019-09-06T22:12:47.509Z,1567807967.509 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:47.509Z,1567807967.509 [Rowe_600](INFO): Inst Velocity Y:-2.000000 2019-09-06T22:12:48.333Z,1567807968.333 [Rowe_600](DEBUG): line: :BI, -4, +0, -1,-32768,A 2019-09-06T22:12:48.333Z,1567807968.333 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:12:48.333Z,1567807968.333 [Rowe_600](INFO): Inst Velocity Y:0.000000 2019-09-06T22:12:49.529Z,1567807969.529 [Rowe_600](DEBUG): line: :BI, -6, +1, -1,-32768,A 2019-09-06T22:12:49.530Z,1567807969.530 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:12:49.530Z,1567807969.530 [Rowe_600](INFO): Inst Velocity Y:1.000000 2019-09-06T22:12:50.344Z,1567807970.344 [Rowe_600](DEBUG): line: :BI, -2, +3, +0,-32768,A 2019-09-06T22:12:50.345Z,1567807970.345 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:12:50.345Z,1567807970.345 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:12:51.558Z,1567807971.558 [Rowe_600](DEBUG): line: :BI, -3, +1, +0,-32768,A 2019-09-06T22:12:51.559Z,1567807971.559 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:51.559Z,1567807971.559 [Rowe_600](INFO): Inst Velocity Y:1.000000 2019-09-06T22:12:52.361Z,1567807972.361 [Rowe_600](DEBUG): line: :BI, -3, +4, +0,-32768,A 2019-09-06T22:12:52.361Z,1567807972.361 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:12:52.361Z,1567807972.361 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:12:53.579Z,1567807973.579 [Rowe_600](DEBUG): line: :BI, -5, +6, -1,-32768,A 2019-09-06T22:12:53.579Z,1567807973.579 [Rowe_600](INFO): Inst Velocity X:5.000000 2019-09-06T22:12:53.579Z,1567807973.579 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:54.382Z,1567807974.382 [Rowe_600](DEBUG): line: :BI, -6, +11, -1,-32768,A 2019-09-06T22:12:54.382Z,1567807974.382 [Rowe_600](INFO): Inst Velocity X:6.000000 2019-09-06T22:12:54.382Z,1567807974.382 [Rowe_600](INFO): Inst Velocity Y:11.000000 2019-09-06T22:12:55.594Z,1567807975.594 [Rowe_600](DEBUG): line: :BI, -2, +6, -1,-32768,A 2019-09-06T22:12:55.594Z,1567807975.594 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:12:55.594Z,1567807975.594 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:12:56.402Z,1567807976.402 [Rowe_600](DEBUG): line: :BI, +1, +1, +0,-32768,A 2019-09-06T22:12:56.402Z,1567807976.402 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:12:56.402Z,1567807976.402 [Rowe_600](INFO): Inst Velocity Y:1.000000 2019-09-06T22:12:57.631Z,1567807977.631 [Rowe_600](DEBUG): line: :BI, -1, +7, -1,-32768,A 2019-09-06T22:12:57.631Z,1567807977.631 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:12:57.631Z,1567807977.631 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:12:58.418Z,1567807978.418 [Rowe_600](DEBUG): line: :BI, -2, +4, -1,-32768,A 2019-09-06T22:12:58.418Z,1567807978.418 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:12:58.418Z,1567807978.418 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:12:59.634Z,1567807979.634 [Rowe_600](DEBUG): line: :BI, +0, +4, +0,-32768,A 2019-09-06T22:12:59.634Z,1567807979.634 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:12:59.634Z,1567807979.634 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:00.448Z,1567807980.448 [Rowe_600](DEBUG): line: :BI, +0, +7, -2,-32768,A 2019-09-06T22:13:00.448Z,1567807980.448 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:13:00.448Z,1567807980.448 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:13:01.274Z,1567807981.274 [Rowe_600](DEBUG): line: :BI, +4, -1,-32768,A +4, +0, -1,A 2019-09-06T22:13:01.274Z,1567807981.274 [Rowe_600](INFO): Inst Velocity X:-4.000000 2019-09-06T22:13:01.275Z,1567807981.275 [Rowe_600](INFO): Inst Velocity Y:-1.000000 2019-09-06T22:13:01.275Z,1567807981.275 [Rowe_600](ERROR): only read 3 of 4 data items 2019-09-06T22:13:01.275Z,1567807981.275 [Rowe_600](ERROR): Failed to parse: :BI, +4, -1,-32768,A +4, +0, -1,A 2019-09-06T22:13:02.458Z,1567807982.458 [Rowe_600](DEBUG): line: :BI, +0, +4, -1,-32768,A 2019-09-06T22:13:02.458Z,1567807982.458 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:13:02.458Z,1567807982.458 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:03.279Z,1567807983.279 [Rowe_600](DEBUG): line: :BI, +2, +4, -2,-32768,A 2019-09-06T22:13:03.280Z,1567807983.280 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:13:03.280Z,1567807983.280 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:04.479Z,1567807984.479 [Rowe_600](DEBUG): line: :BI, +2, +5, -2,-32768,A 2019-09-06T22:13:04.479Z,1567807984.479 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:13:04.479Z,1567807984.479 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:05.286Z,1567807985.286 [Rowe_600](DEBUG): line: :BI, +3, +4, -2,-32768,A 2019-09-06T22:13:05.287Z,1567807985.287 [Rowe_600](INFO): Inst Velocity X:-3.000000 2019-09-06T22:13:05.287Z,1567807985.287 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:06.498Z,1567807986.498 [Rowe_600](DEBUG): line: :BI, +2, +5, -2,-32768,A 2019-09-06T22:13:06.498Z,1567807986.498 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:13:06.498Z,1567807986.498 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:07.317Z,1567807987.317 [Rowe_600](DEBUG): line: :BI, +1, +4, -2,-32768,A 2019-09-06T22:13:07.317Z,1567807987.317 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:07.317Z,1567807987.317 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:08.526Z,1567807988.526 [Rowe_600](DEBUG): line: :BI, +2, +5, -2,-32768,A 2019-09-06T22:13:08.526Z,1567807988.526 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:13:08.526Z,1567807988.526 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:09.330Z,1567807989.330 [Rowe_600](DEBUG): line: :BI, +1, +8, -3,-32768,A 2019-09-06T22:13:09.330Z,1567807989.330 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:09.330Z,1567807989.330 [Rowe_600](INFO): Inst Velocity Y:8.000000 2019-09-06T22:13:10.627Z,1567807990.627 [Rowe_600](DEBUG): line: :BI, +0, +6, -2,-32768,A 2019-09-06T22:13:10.627Z,1567807990.627 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:13:10.627Z,1567807990.627 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:13:11.431Z,1567807991.431 [Rowe_600](DEBUG): line: :BI, +1, +7, -3,-32768,A 2019-09-06T22:13:11.431Z,1567807991.431 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:11.431Z,1567807991.431 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:13:12.639Z,1567807992.639 [Rowe_600](DEBUG): line: :BI, +1, +3, -2,-32768,A 2019-09-06T22:13:12.639Z,1567807992.639 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:12.639Z,1567807992.639 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:13:13.458Z,1567807993.458 [Rowe_600](DEBUG): line: :BI, +2, +4, -1,-32768,A 2019-09-06T22:13:13.459Z,1567807993.459 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:13:13.459Z,1567807993.459 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:14.285Z,1567807994.285 [Rowe_600](DEBUG): line: :BI, +5, +0, +0,-32768,A 2019-09-06T22:13:14.285Z,1567807994.285 [Rowe_600](INFO): Inst Velocity X:-5.000000 2019-09-06T22:13:14.285Z,1567807994.285 [Rowe_600](INFO): Inst Velocity Y:0.000000 2019-09-06T22:13:15.467Z,1567807995.467 [Rowe_600](DEBUG): line: :BI, -1, +6, -1,-32768,A 2019-09-06T22:13:15.467Z,1567807995.467 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:15.467Z,1567807995.467 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:13:16.304Z,1567807996.304 [Rowe_600](DEBUG): line: :BI, -2, +5, -1,-32768,A 2019-09-06T22:13:16.305Z,1567807996.305 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:13:16.305Z,1567807996.305 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:17.491Z,1567807997.491 [Rowe_600](DEBUG): line: :BI, -4, +7, -1,-32768,A 2019-09-06T22:13:17.491Z,1567807997.491 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:13:17.492Z,1567807997.492 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:13:18.294Z,1567807998.294 [Rowe_600](DEBUG): line: :BI, -3, +7, -2,-32768,A 2019-09-06T22:13:18.294Z,1567807998.294 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:13:18.294Z,1567807998.294 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:13:19.507Z,1567807999.507 [Rowe_600](DEBUG): line: :BI, -3, +5, -1,-32768,A 2019-09-06T22:13:19.507Z,1567807999.507 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:13:19.508Z,1567807999.508 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:20.332Z,1567808000.332 [Rowe_600](DEBUG): line: :BI, -3, +6, -2,-32768,A 2019-09-06T22:13:20.332Z,1567808000.332 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:13:20.333Z,1567808000.333 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:13:21.128Z,1567808001.128 [SBIT](IMPORTANT): SBIT PASSED 2019-09-06T22:13:21.179Z,1567808001.179 [CommandLine](IMPORTANT): got command configSet list 2019-09-06T22:13:21.180Z,1567808001.180 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-09-06T22:13:21.182Z,1567808001.182 [CommandLine](IMPORTANT): Micromodem.loadAtStartup=0 bool; 2019-09-06T22:13:21.182Z,1567808001.182 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter; 2019-09-06T22:13:21.527Z,1567808001.527 [Rowe_600](DEBUG): line: :BI, -3, +4, -1,-32768,A 2019-09-06T22:13:21.528Z,1567808001.528 [Rowe_600](INFO): Inst Velocity X:3.000000 2019-09-06T22:13:21.528Z,1567808001.528 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:21.697Z,1567808001.697 [MissionManager](IMPORTANT): Started mission Startup 2019-09-06T22:13:21.697Z,1567808001.697 [Startup] Running Loop=1 2019-09-06T22:13:21.697Z,1567808001.697 [Startup](DEBUG): Aggregate::initialize Startup 2019-09-06T22:13:21.697Z,1567808001.697 [Startup:A.GoToSurface] Running Loop=1 2019-09-06T22:13:21.697Z,1567808001.697 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-09-06T22:13:21.698Z,1567808001.698 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-09-06T22:13:21.698Z,1567808001.698 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-09-06T22:13:21.699Z,1567808001.699 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-09-06T22:13:21.699Z,1567808001.699 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-09-06T22:13:21.699Z,1567808001.699 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-09-06T22:13:21.701Z,1567808001.701 [Startup:StartupSatComms] Running Loop=1 2019-09-06T22:13:21.701Z,1567808001.701 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-09-06T22:13:21.702Z,1567808001.702 [Startup:StartupSatComms:A] Running Loop=1 2019-09-06T22:13:21.950Z,1567808001.950 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-09-06T22:13:22.334Z,1567808002.334 [Rowe_600](DEBUG): line: :BI, -4, +6, -1,-32768,A 2019-09-06T22:13:22.334Z,1567808002.334 [Rowe_600](INFO): Inst Velocity X:4.000000 2019-09-06T22:13:22.335Z,1567808002.335 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:13:23.558Z,1567808003.558 [Rowe_600](DEBUG): line: :BI, -1, +6, -1,-32768,A 2019-09-06T22:13:23.558Z,1567808003.558 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:23.558Z,1567808003.558 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:13:24.362Z,1567808004.362 [Rowe_600](DEBUG): line: :BI, -2, +5, -1,-32768,A 2019-09-06T22:13:24.362Z,1567808004.362 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:13:24.362Z,1567808004.362 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:25.584Z,1567808005.584 [Rowe_600](DEBUG): line: :BI, -1, +7, -1,-32768,A 2019-09-06T22:13:25.585Z,1567808005.585 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:25.585Z,1567808005.585 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:13:26.378Z,1567808006.378 [Rowe_600](DEBUG): line: :BI, -1, +9, -2,-32768,A 2019-09-06T22:13:26.378Z,1567808006.378 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:26.378Z,1567808006.378 [Rowe_600](INFO): Inst Velocity Y:9.000000 2019-09-06T22:13:27.586Z,1567808007.586 [Rowe_600](DEBUG): line: :BI, -1, +7, -2,-32768,A 2019-09-06T22:13:27.586Z,1567808007.586 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:27.586Z,1567808007.586 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:13:28.394Z,1567808008.394 [Rowe_600](DEBUG): line: :BI, -2, +6, -1,-32768,A 2019-09-06T22:13:28.394Z,1567808008.394 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:13:28.394Z,1567808008.394 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:13:29.620Z,1567808009.620 [Rowe_600](DEBUG): line: :BI, -2, +7, -1,-32768,A 2019-09-06T22:13:29.621Z,1567808009.621 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:13:29.621Z,1567808009.621 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:13:30.435Z,1567808010.435 [Rowe_600](DEBUG): line: :BI, -1, +9, -1,-32768,A 2019-09-06T22:13:30.436Z,1567808010.436 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:30.436Z,1567808010.436 [Rowe_600](INFO): Inst Velocity Y:9.000000 2019-09-06T22:13:31.626Z,1567808011.626 [Rowe_600](DEBUG): line: :BI, -2, +9, -2,-32768,A 2019-09-06T22:13:31.626Z,1567808011.626 [Rowe_600](INFO): Inst Velocity X:2.000000 2019-09-06T22:13:31.626Z,1567808011.626 [Rowe_600](INFO): Inst Velocity Y:9.000000 2019-09-06T22:13:32.434Z,1567808012.434 [Rowe_600](DEBUG): line: :BI, +0, +7, -1,-32768,A 2019-09-06T22:13:32.434Z,1567808012.434 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:13:32.434Z,1567808012.434 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:13:33.261Z,1567808013.261 [Rowe_600](ERROR): Failed to parse: :2, +9, -2,-32768,A 2019-09-06T22:13:34.484Z,1567808014.484 [Rowe_600](DEBUG): line: :BI, +1, +8, -1,-32768,A 2019-09-06T22:13:34.484Z,1567808014.484 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:34.484Z,1567808014.484 [Rowe_600](INFO): Inst Velocity Y:8.000000 2019-09-06T22:13:35.292Z,1567808015.292 [Rowe_600](DEBUG): line: :BI, -1, +6, -2,-32768,A 2019-09-06T22:13:35.293Z,1567808015.293 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:35.293Z,1567808015.293 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:13:36.474Z,1567808016.474 [Rowe_600](DEBUG): line: :BI, -1, +6, -1,-32768,A 2019-09-06T22:13:36.474Z,1567808016.474 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:36.474Z,1567808016.474 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:13:37.297Z,1567808017.297 [Rowe_600](DEBUG): line: :BI, +1, +4, -1,-32768,A 2019-09-06T22:13:37.297Z,1567808017.297 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:37.297Z,1567808017.297 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:38.494Z,1567808018.494 [Rowe_600](DEBUG): line: :BI, +1, +3, -1,-32768,A 2019-09-06T22:13:38.495Z,1567808018.495 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:38.495Z,1567808018.495 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:13:39.302Z,1567808019.302 [Rowe_600](DEBUG): line: :BI, +0, +2, -1,-32768,A 2019-09-06T22:13:39.302Z,1567808019.302 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:13:39.303Z,1567808019.303 [Rowe_600](INFO): Inst Velocity Y:2.000000 2019-09-06T22:13:40.548Z,1567808020.548 [Rowe_600](DEBUG): line: :BI, +0, +4, -1,-32768,A 2019-09-06T22:13:40.548Z,1567808020.548 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:13:40.548Z,1567808020.548 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:41.334Z,1567808021.334 [Rowe_600](DEBUG): line: :BI, +0, +6, -1,-32768,A 2019-09-06T22:13:41.334Z,1567808021.334 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:13:41.334Z,1567808021.334 [Rowe_600](INFO): Inst Velocity Y:6.000000 2019-09-06T22:13:42.538Z,1567808022.538 [Rowe_600](DEBUG): line: :BI, -1, +4, -1,-32768,A 2019-09-06T22:13:42.538Z,1567808022.538 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:42.539Z,1567808022.539 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:43.345Z,1567808023.345 [Rowe_600](DEBUG): line: :BI, +3, +2, -1,-32768,A 2019-09-06T22:13:43.345Z,1567808023.345 [Rowe_600](INFO): Inst Velocity X:-3.000000 2019-09-06T22:13:43.346Z,1567808023.346 [Rowe_600](INFO): Inst Velocity Y:2.000000 2019-09-06T22:13:44.558Z,1567808024.558 [Rowe_600](DEBUG): line: :BI, +1, +1, -2,-32768,A 2019-09-06T22:13:44.558Z,1567808024.558 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:44.558Z,1567808024.558 [Rowe_600](INFO): Inst Velocity Y:1.000000 2019-09-06T22:13:45.376Z,1567808025.376 [Rowe_600](DEBUG): line: :BI, -1, +4, -1,-32768,A 2019-09-06T22:13:45.376Z,1567808025.376 [Rowe_600](INFO): Inst Velocity X:1.000000 2019-09-06T22:13:45.376Z,1567808025.376 [Rowe_600](INFO): Inst Velocity Y:4.000000 2019-09-06T22:13:46.582Z,1567808026.582 [Rowe_600](DEBUG): line: :BI, +1, +5, -2,-32768,A 2019-09-06T22:13:46.582Z,1567808026.582 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:46.583Z,1567808026.583 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:47.390Z,1567808027.390 [Rowe_600](DEBUG): line: :BI, +1, +3, -2,-32768,A 2019-09-06T22:13:47.391Z,1567808027.391 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:47.391Z,1567808027.391 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:13:48.596Z,1567808028.596 [Rowe_600](DEBUG): line: :BI, +0, +7, -2,-32768,A 2019-09-06T22:13:48.597Z,1567808028.597 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:13:48.597Z,1567808028.597 [Rowe_600](INFO): Inst Velocity Y:7.000000 2019-09-06T22:13:49.406Z,1567808029.406 [Rowe_600](DEBUG): line: :BI, +2, -1, -1,-32768,A 2019-09-06T22:13:49.406Z,1567808029.406 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:13:49.406Z,1567808029.406 [Rowe_600](INFO): Inst Velocity Y:-1.000000 2019-09-06T22:13:50.626Z,1567808030.626 [Rowe_600](DEBUG): line: :BI, +2, +1, -1,-32768,A 2019-09-06T22:13:50.627Z,1567808030.627 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:13:50.627Z,1567808030.627 [Rowe_600](INFO): Inst Velocity Y:1.000000 2019-09-06T22:13:51.426Z,1567808031.426 [Rowe_600](DEBUG): line: :BI, +1, +3, -1,-32768,A 2019-09-06T22:13:51.426Z,1567808031.426 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:51.427Z,1567808031.427 [Rowe_600](INFO): Inst Velocity Y:3.000000 2019-09-06T22:13:52.264Z,1567808032.264 [Rowe_600](DEBUG): line: :BI, +2, +5, -1,-327S, +5 -1, +1, +5, +3,A 2019-09-06T22:13:52.264Z,1567808032.264 [Rowe_600](INFO): Inst Velocity X:-2.000000 2019-09-06T22:13:52.264Z,1567808032.264 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:52.273Z,1567808032.273 [Rowe_600](ERROR): only read 3 of 4 data items 2019-09-06T22:13:52.273Z,1567808032.273 [Rowe_600](ERROR): Failed to parse: :BI, +2, +5, -1,-327S, +5 -1, +1, +5, +3,A 2019-09-06T22:13:53.447Z,1567808033.447 [Rowe_600](DEBUG): line: :BI, +1, +0, -1,-32768,A 2019-09-06T22:13:53.447Z,1567808033.447 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:53.448Z,1567808033.448 [Rowe_600](INFO): Inst Velocity Y:0.000000 2019-09-06T22:13:54.346Z,1567808034.346 [Rowe_600](DEBUG): line: :BI, +1, +2, -2,-32768,A 2019-09-06T22:13:54.346Z,1567808034.346 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:54.346Z,1567808034.346 [Rowe_600](INFO): Inst Velocity Y:2.000000 2019-09-06T22:13:55.361Z,1567808035.361 [CommandLine](IMPORTANT): got command restart application 2019-09-06T22:13:55.464Z,1567808035.464 [Rowe_600](DEBUG): line: :BI, +0, +5, -2,-32768,A 2019-09-06T22:13:55.464Z,1567808035.464 [Rowe_600](INFO): Inst Velocity X:-0.000000 2019-09-06T22:13:55.464Z,1567808035.464 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:56.318Z,1567808036.318 [Rowe_600](DEBUG): line: :BI, +4, -1, -1,-32768,A 2019-09-06T22:13:56.318Z,1567808036.318 [Rowe_600](INFO): Inst Velocity X:-4.000000 2019-09-06T22:13:56.318Z,1567808036.318 [Rowe_600](INFO): Inst Velocity Y:-1.000000 2019-09-06T22:13:56.365Z,1567808036.365 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2019-09-06T22:13:56.365Z,1567808036.365 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-06T22:13:56.365Z,1567808036.365 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:56.493Z,1567808036.493 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-09-06T22:13:56.493Z,1567808036.493 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:56.494Z,1567808036.494 [CommandLine](INFO): Join timeout helper Thread ID is 3236 2019-09-06T22:13:56.505Z,1567808036.505 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-09-06T22:13:56.505Z,1567808036.505 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:56.505Z,1567808036.505 [NavChartDb](INFO): Join timeout helper Thread ID is 3237 2019-09-06T22:13:56.621Z,1567808036.621 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-06T22:13:56.621Z,1567808036.621 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:56.625Z,1567808036.625 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-09-06T22:13:56.625Z,1567808036.625 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:56.625Z,1567808036.625 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3238 2019-09-06T22:13:56.705Z,1567808036.705 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004864 2019-09-06T22:13:56.789Z,1567808036.789 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-06T22:13:56.790Z,1567808036.790 [WetLabsBB2FL](INFO): Powering down 2019-09-06T22:13:56.790Z,1567808036.790 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:56.809Z,1567808036.809 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-09-06T22:13:56.809Z,1567808036.809 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:56.809Z,1567808036.809 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3239 2019-09-06T22:13:57.484Z,1567808037.484 [Rowe_600](DEBUG): line: :BI, +3, +5, -2,-32768,A 2019-09-06T22:13:57.485Z,1567808037.485 [Rowe_600](INFO): Inst Velocity X:-3.000000 2019-09-06T22:13:57.486Z,1567808037.486 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:57.657Z,1567808037.657 [CTD_Seabird](INFO): Powering down 2019-09-06T22:13:57.669Z,1567808037.669 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-06T22:13:57.669Z,1567808037.669 [CTD_Seabird](INFO): Powering down 2019-09-06T22:13:57.681Z,1567808037.681 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:57.697Z,1567808037.697 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-09-06T22:13:57.697Z,1567808037.697 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:57.697Z,1567808037.697 [Radio_Surface](INFO): Join timeout helper Thread ID is 3240 2019-09-06T22:13:57.969Z,1567808037.969 [Radio_Surface](INFO): Powering down 2019-09-06T22:13:57.970Z,1567808037.970 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-06T22:13:57.970Z,1567808037.970 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:57.986Z,1567808037.986 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-09-06T22:13:57.986Z,1567808037.986 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:57.986Z,1567808037.986 [DataOverHttps](INFO): Join timeout helper Thread ID is 3241 2019-09-06T22:13:58.307Z,1567808038.307 [Rowe_600](DEBUG): line: :BI, +1, +5, -1,-32768,A 2019-09-06T22:13:58.308Z,1567808038.308 [Rowe_600](INFO): Inst Velocity X:-1.000000 2019-09-06T22:13:58.308Z,1567808038.308 [Rowe_600](INFO): Inst Velocity Y:5.000000 2019-09-06T22:13:58.313Z,1567808038.313 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-06T22:13:58.313Z,1567808038.313 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:58.334Z,1567808038.334 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-09-06T22:13:58.334Z,1567808038.334 [logger ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:58.334Z,1567808038.334 [logger](INFO): Join timeout helper Thread ID is 3242 2019-09-06T22:13:58.357Z,1567808038.357 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-06T22:13:58.357Z,1567808038.357 [logger ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:58.370Z,1567808038.370 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-09-06T22:13:58.370Z,1567808038.370 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:58.370Z,1567808038.370 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-09-06T22:13:58.370Z,1567808038.370 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:58.370Z,1567808038.370 [controlThread](INFO): Join timeout helper Thread ID is 3243 2019-09-06T22:13:58.510Z,1567808038.510 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-06T22:13:58.510Z,1567808038.510 [controlThread](DEBUG): Uninitializing ControlThread 2019-09-06T22:13:58.510Z,1567808038.510 [AHRS_M2](INFO): Powering down 2019-09-06T22:13:58.581Z,1567808038.581 [NAL9602](INFO): Powering down 2019-09-06T22:13:58.653Z,1567808038.653 [Rowe_600](INFO): Powering down 2019-09-06T22:13:58.655Z,1567808038.655 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-09-06T22:13:58.656Z,1567808038.656 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-09-06T22:13:58.656Z,1567808038.656 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-09-06T22:13:58.657Z,1567808038.657 [MissionManager](INFO): Uninitializing Mission Default 2019-09-06T22:13:58.659Z,1567808038.659 [MissionManager](INFO): Uninitializing Mission Startup 2019-09-06T22:13:58.659Z,1567808038.659 [Startup] Stopped 2019-09-06T22:13:58.659Z,1567808038.659 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-09-06T22:13:58.659Z,1567808038.659 [Startup:A.GoToSurface] Stopped 2019-09-06T22:13:58.659Z,1567808038.659 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-09-06T22:13:58.659Z,1567808038.659 [Startup:StartupSatComms] Stopped 2019-09-06T22:13:58.659Z,1567808038.659 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-09-06T22:13:58.659Z,1567808038.659 [Startup:StartupSatComms:A] Stopped 2019-09-06T22:13:58.661Z,1567808038.661 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-09-06T22:13:58.661Z,1567808038.661 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-09-06T22:13:58.661Z,1567808038.661 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-09-06T22:13:58.662Z,1567808038.662 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-09-06T22:13:58.662Z,1567808038.662 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-09-06T22:13:58.662Z,1567808038.662 [BuoyancyServo](INFO): Powering down 2019-09-06T22:13:58.677Z,1567808038.677 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-09-06T22:13:58.677Z,1567808038.677 [ElevatorServo](INFO): Powering down 2019-09-06T22:13:58.678Z,1567808038.678 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-09-06T22:13:58.678Z,1567808038.678 [MassServo](INFO): Powering down 2019-09-06T22:13:58.679Z,1567808038.679 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-09-06T22:13:58.679Z,1567808038.679 [RudderServo](INFO): Powering down 2019-09-06T22:13:58.680Z,1567808038.680 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-09-06T22:13:58.680Z,1567808038.680 [ThrusterServo](INFO): Powering down 2019-09-06T22:13:58.681Z,1567808038.681 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-09-06T22:13:58.681Z,1567808038.681 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-09-06T22:13:58.681Z,1567808038.681 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-09-06T22:13:58.681Z,1567808038.681 [CBIT](DEBUG): Powering off loads. 2019-09-06T22:13:58.697Z,1567808038.697 [CBIT](DEBUG): Disabling WDT. 2019-09-06T22:13:58.709Z,1567808038.709 [CBIT](DEBUG): Opening all GF detection circuits. 2019-09-06T22:13:58.710Z,1567808038.710 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:58.866Z,1567808038.866 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:58.872Z,1567808038.872 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:59.031Z,1567808039.031 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:59.043Z,1567808039.043 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:59.052Z,1567808039.052 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-09-06T22:13:59.174Z,1567808039.174 [logger ThreadHandler](INFO): Thread cancelled.