2019-11-12T22:33:08.414Z,1573597988.414 [Supervisor](DEBUG): Initializing supervisor.
2019-11-12T22:33:08.417Z,1573597988.417 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-11-12T22:33:08.418Z,1573597988.418 [SyncHandler](INFO): Protected caller Thread ID is 1252
2019-11-12T22:33:08.418Z,1573597988.418 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-11-12T22:33:08.419Z,1573597988.419 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-11-12T22:33:08.420Z,1573597988.420 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1253
2019-11-12T22:33:08.423Z,1573597988.423 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-11-12T22:33:08.435Z,1573597988.435 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-11-12T22:33:08.436Z,1573597988.436 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-11-12T22:33:08.436Z,1573597988.436 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1254
2019-11-12T22:33:08.437Z,1573597988.437 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-11-12T22:33:08.438Z,1573597988.438 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-11-12T22:33:08.438Z,1573597988.438 [logger ThreadHandler](INFO): Protected caller Thread ID is 1255
2019-11-12T22:33:08.441Z,1573597988.441 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-11-12T22:33:08.441Z,1573597988.441 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-11-12T22:33:08.442Z,1573597988.442 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-11-12T22:33:08.893Z,1573597988.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-11-12T22:33:08.894Z,1573597988.894 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-11-12T22:33:08.993Z,1573597988.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-11-12T22:33:08.994Z,1573597988.994 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-11-12T22:33:09.337Z,1573597989.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-11-12T22:33:09.337Z,1573597989.337 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-11-12T22:33:09.484Z,1573597989.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-11-12T22:33:09.485Z,1573597989.485 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-11-12T22:33:09.684Z,1573597989.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-11-12T22:33:09.685Z,1573597989.685 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-11-12T22:33:10.157Z,1573597990.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-11-12T22:33:10.158Z,1573597990.158 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-11-12T22:33:10.375Z,1573597990.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-11-12T22:33:10.376Z,1573597990.376 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-11-12T22:33:10.526Z,1573597990.526 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-11-12T22:33:10.527Z,1573597990.527 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-11-12T22:33:10.736Z,1573597990.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-11-12T22:33:10.736Z,1573597990.736 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-11-12T22:33:10.834Z,1573597990.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-11-12T22:33:10.835Z,1573597990.835 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-11-12T22:33:11.156Z,1573597991.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-11-12T22:33:11.156Z,1573597991.156 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-11-12T22:33:11.238Z,1573597991.238 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-11-12T22:33:11.343Z,1573597991.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-11-12T22:33:11.344Z,1573597991.344 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-11-12T22:33:12.035Z,1573597992.035 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-11-12T22:33:12.035Z,1573597992.035 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-11-12T22:33:12.447Z,1573597992.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-11-12T22:33:12.449Z,1573597992.449 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-11-12T22:33:12.450Z,1573597992.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-11-12T22:33:12.669Z,1573597992.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-11-12T22:33:12.772Z,1573597992.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-11-12T22:33:12.873Z,1573597992.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-11-12T22:33:13.103Z,1573597993.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-11-12T22:33:13.104Z,1573597993.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-11-12T22:33:13.189Z,1573597993.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-11-12T22:33:13.285Z,1573597993.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-11-12T22:33:13.395Z,1573597993.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-11-12T22:33:13.630Z,1573597993.630 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-11-12T22:33:13.896Z,1573597993.896 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-11-12T22:33:14.213Z,1573597994.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-11-12T22:33:14.354Z,1573597994.354 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-11-12T22:33:14.355Z,1573597994.355 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-11-12T22:33:14.366Z,1573597994.366 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-11-12T22:33:14.674Z,1573597994.674 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-11-12T22:33:14.680Z,1573597994.680 [AHRS_M2](INFO): created writer for : platform_orientation
2019-11-12T22:33:14.681Z,1573597994.681 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-11-12T22:33:14.686Z,1573597994.686 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-11-12T22:33:14.687Z,1573597994.687 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-11-12T22:33:14.692Z,1573597994.692 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-11-12T22:33:14.693Z,1573597994.693 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-11-12T22:33:14.698Z,1573597994.698 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-11-12T22:33:14.769Z,1573597994.769 [AHRS_M2] Loaded
2019-11-12T22:33:14.769Z,1573597994.769 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-11-12T22:33:14.846Z,1573597994.846 [DataOverHttps] Loaded
2019-11-12T22:33:14.847Z,1573597994.847 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-11-12T22:33:14.848Z,1573597994.848 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2019-11-12T22:33:14.848Z,1573597994.848 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1334
2019-11-12T22:33:14.868Z,1573597994.868 [DDM] Loaded
2019-11-12T22:33:14.868Z,1573597994.868 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2019-11-12T22:33:14.881Z,1573597994.881 [Depth_Keller] Loaded
2019-11-12T22:33:14.881Z,1573597994.881 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-11-12T22:33:14.886Z,1573597994.886 [DropWeight] Loaded
2019-11-12T22:33:14.886Z,1573597994.886 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-11-12T22:33:14.950Z,1573597994.950 [DUSBL_Hydroid] Loaded
2019-11-12T22:33:14.950Z,1573597994.950 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-11-12T22:33:14.997Z,1573597994.997 [Micromodem] Loaded
2019-11-12T22:33:14.998Z,1573597994.998 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-11-12T22:33:15.095Z,1573597995.095 [NAL9602] Loaded
2019-11-12T22:33:15.095Z,1573597995.095 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-11-12T22:33:15.111Z,1573597995.111 [Onboard] Loaded
2019-11-12T22:33:15.112Z,1573597995.112 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-11-12T22:33:15.118Z,1573597995.118 [PowerOnly] Loaded
2019-11-12T22:33:15.118Z,1573597995.118 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-11-12T22:33:15.124Z,1573597995.124 [Radio_Surface] Loaded
2019-11-12T22:33:15.125Z,1573597995.125 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-11-12T22:33:15.126Z,1573597995.126 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2019-11-12T22:33:15.126Z,1573597995.126 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1335
2019-11-12T22:33:15.170Z,1573597995.170 [RDI_Pathfinder] Loaded
2019-11-12T22:33:15.170Z,1573597995.170 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-11-12T22:33:16.644Z,1573597996.644 [BPC1] Loaded
2019-11-12T22:33:16.645Z,1573597996.645 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-11-12T22:33:16.645Z,1573597996.645 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-11-12T22:33:16.646Z,1573597996.646 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-11-12T22:33:16.659Z,1573597996.659 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-11-12T22:33:16.659Z,1573597996.659 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-11-12T22:33:16.764Z,1573597996.764 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-11-12T22:33:16.764Z,1573597996.764 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-11-12T22:33:16.785Z,1573597996.785 [NavChart] Loaded
2019-11-12T22:33:16.785Z,1573597996.785 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-11-12T22:33:16.789Z,1573597996.789 [UniversalFixResidualReporter] Loaded
2019-11-12T22:33:16.789Z,1573597996.789 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-11-12T22:33:16.789Z,1573597996.789 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-11-12T22:33:16.790Z,1573597996.790 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-11-12T22:33:16.896Z,1573597996.896 [BuoyancyServo] Loaded
2019-11-12T22:33:16.896Z,1573597996.896 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-11-12T22:33:16.912Z,1573597996.912 [ElevatorServo] Loaded
2019-11-12T22:33:16.912Z,1573597996.912 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-11-12T22:33:16.927Z,1573597996.927 [MassServo] Loaded
2019-11-12T22:33:16.928Z,1573597996.928 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-11-12T22:33:16.943Z,1573597996.943 [RudderServo] Loaded
2019-11-12T22:33:16.943Z,1573597996.943 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-11-12T22:33:16.958Z,1573597996.958 [ThrusterServo] Loaded
2019-11-12T22:33:16.958Z,1573597996.958 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-11-12T22:33:16.959Z,1573597996.959 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-11-12T22:33:16.960Z,1573597996.960 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-11-12T22:33:17.204Z,1573597997.204 [CTD_NeilBrown] Loaded
2019-11-12T22:33:17.204Z,1573597997.204 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-11-12T22:33:17.205Z,1573597997.205 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408E34E0
2019-11-12T22:33:17.206Z,1573597997.206 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1336
2019-11-12T22:33:17.221Z,1573597997.221 [PAR_Licor] Loaded
2019-11-12T22:33:17.221Z,1573597997.221 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-11-12T22:33:17.266Z,1573597997.266 [WetLabsSeaOWL_UV_A] Loaded
2019-11-12T22:33:17.267Z,1573597997.267 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-11-12T22:33:17.268Z,1573597997.268 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409134E0
2019-11-12T22:33:17.268Z,1573597997.268 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1337
2019-11-12T22:33:17.269Z,1573597997.269 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-11-12T22:33:17.269Z,1573597997.269 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-11-12T22:33:17.574Z,1573597997.574 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-11-12T22:33:17.575Z,1573597997.575 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-11-12T22:33:17.615Z,1573597997.615 [DepthRateCalculator] Loaded
2019-11-12T22:33:17.616Z,1573597997.616 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-11-12T22:33:17.621Z,1573597997.621 [PitchRateCalculator] Loaded
2019-11-12T22:33:17.622Z,1573597997.622 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-11-12T22:33:17.634Z,1573597997.634 [SpeedCalculator] Loaded
2019-11-12T22:33:17.634Z,1573597997.634 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-11-12T22:33:17.655Z,1573597997.655 [TempGradientCalculator] Loaded
2019-11-12T22:33:17.656Z,1573597997.656 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-11-12T22:33:17.661Z,1573597997.661 [YawRateCalculator] Loaded
2019-11-12T22:33:17.661Z,1573597997.661 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-11-12T22:33:17.702Z,1573597997.702 [ElevatorOffsetCalculator] Loaded
2019-11-12T22:33:17.702Z,1573597997.702 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-11-12T22:33:17.702Z,1573597997.702 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-11-12T22:33:17.703Z,1573597997.703 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-11-12T22:33:17.836Z,1573597997.836 [SBIT](DEBUG): Construct Startup Built In Test.
2019-11-12T22:33:17.859Z,1573597997.859 [SBIT] Loaded
2019-11-12T22:33:17.859Z,1573597997.859 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-11-12T22:33:17.860Z,1573597997.860 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-11-12T22:33:17.873Z,1573597997.873 [IBIT] Loaded
2019-11-12T22:33:17.873Z,1573597997.873 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-11-12T22:33:17.876Z,1573597997.876 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-11-12T22:33:18.016Z,1573597998.016 [CBIT] Loaded
2019-11-12T22:33:18.017Z,1573597998.017 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-11-12T22:33:18.017Z,1573597998.017 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-11-12T22:33:18.018Z,1573597998.018 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-11-12T22:33:18.100Z,1573597998.100 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-11-12T22:33:18.101Z,1573597998.101 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-11-12T22:33:18.199Z,1573597998.199 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-11-12T22:33:18.199Z,1573597998.199 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-11-12T22:33:18.267Z,1573597998.267 [VerticalControl](DEBUG): Construct VerticalControl.
2019-11-12T22:33:18.351Z,1573597998.351 [VerticalControl] Loaded
2019-11-12T22:33:18.351Z,1573597998.351 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-11-12T22:33:18.352Z,1573597998.352 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-11-12T22:33:18.409Z,1573597998.409 [HorizontalControl] Loaded
2019-11-12T22:33:18.409Z,1573597998.409 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-11-12T22:33:18.410Z,1573597998.410 [SpeedControl](DEBUG): Construct SpeedControl.
2019-11-12T22:33:18.412Z,1573597998.412 [SpeedControl] Loaded
2019-11-12T22:33:18.412Z,1573597998.412 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-11-12T22:33:18.413Z,1573597998.413 [LoopControl](DEBUG): Construct LoopControl.
2019-11-12T22:33:18.413Z,1573597998.413 [LoopControl] Loaded
2019-11-12T22:33:18.414Z,1573597998.414 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-11-12T22:33:18.414Z,1573597998.414 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-11-12T22:33:18.415Z,1573597998.415 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-11-12T22:33:18.542Z,1573597998.542 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-11-12T22:33:18.545Z,1573597998.545 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-11-12T22:33:18.546Z,1573597998.546 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-11-12T22:33:18.557Z,1573597998.557 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-11-12T22:33:18.559Z,1573597998.559 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF74E0
2019-11-12T22:33:18.566Z,1573597998.566 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1338
2019-11-12T22:33:18.572Z,1573597998.572 [Supervisor](INFO): Main Thread ID is 802
2019-11-12T22:33:18.572Z,1573597998.572 [Supervisor](DEBUG): Running supervisor.
2019-11-12T22:33:18.572Z,1573597998.572 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1339
2019-11-12T22:33:18.575Z,1573597998.575 [controlThread ThreadHandler](INFO): Handler Thread ID is 1340
2019-11-12T22:33:18.575Z,1573597998.575 [controlThread](DEBUG): Initializing ControlThread
2019-11-12T22:33:18.582Z,1573597998.582 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-11-12T22:33:18.583Z,1573597998.583 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-11-12T22:33:18.587Z,1573597998.587 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-11-12T22:33:18.588Z,1573597998.588 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-11-12T22:33:18.588Z,1573597998.588 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-11-12T22:33:18.588Z,1573597998.588 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-11-12T22:33:18.589Z,1573597998.589 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-11-12T22:33:18.589Z,1573597998.589 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-11-12T22:33:18.590Z,1573597998.590 [SBIT](INFO): Initialize SBIT Component.
2019-11-12T22:33:18.590Z,1573597998.590 [SBIT](IMPORTANT): git: 2019-11-04-75-gf9406cc
2019-11-12T22:33:18.590Z,1573597998.590 [SBIT](INFO): git hash: f9406ccb14a1e49e28e492e765e6b68e35ebc0b0
2019-11-12T22:33:18.591Z,1573597998.591 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-11-12T22:33:18.592Z,1573597998.592 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2019-11-12T22:33:18.593Z,1573597998.593 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-11-12T22:33:18.593Z,1573597998.593 [IBIT](INFO): Initialize IBIT Component.
2019-11-12T22:33:18.594Z,1573597998.594 [CBIT](DEBUG): Initialize CBIT Component.
2019-11-12T22:33:18.595Z,1573597998.595 [logger ThreadHandler](INFO): Handler Thread ID is 1341
2019-11-12T22:33:18.607Z,1573597998.607 [CBIT](DEBUG): Initialized mux pins.
2019-11-12T22:33:18.607Z,1573597998.607 [CBIT](DEBUG): Initializing the watchdog timer.
2019-11-12T22:33:18.618Z,1573597998.618 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1342
2019-11-12T22:33:18.619Z,1573597998.619 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-11-12T22:33:18.624Z,1573597998.624 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1343
2019-11-12T22:33:18.629Z,1573597998.629 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1344
2019-11-12T22:33:18.630Z,1573597998.630 [CTD_NeilBrown](INFO): Powering down
2019-11-12T22:33:18.631Z,1573597998.631 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-11-12T22:33:18.631Z,1573597998.631 [CBIT](DEBUG): Initializing heartbeat.
2019-11-12T22:33:18.658Z,1573597998.658 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1345
2019-11-12T22:33:18.663Z,1573597998.663 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-11-12T22:33:18.688Z,1573597998.688 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1346
2019-11-12T22:33:18.690Z,1573597998.690 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-11-12T22:33:18.691Z,1573597998.691 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-11-12T22:33:18.691Z,1573597998.691 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-11-12T22:33:18.691Z,1573597998.691 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-11-12T22:33:18.691Z,1573597998.691 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-11-12T22:33:18.691Z,1573597998.691 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-11-12T22:33:18.692Z,1573597998.692 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-11-12T22:33:18.692Z,1573597998.692 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-11-12T22:33:18.692Z,1573597998.692 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-11-12T22:33:18.692Z,1573597998.692 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-11-12T22:33:18.692Z,1573597998.692 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-11-12T22:33:18.693Z,1573597998.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-11-12T22:33:18.693Z,1573597998.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-11-12T22:33:18.693Z,1573597998.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-11-12T22:33:18.693Z,1573597998.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-11-12T22:33:18.693Z,1573597998.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-11-12T22:33:18.703Z,1573597998.703 [CBIT](DEBUG): Deactivating GF circuits.
2019-11-12T22:33:18.703Z,1573597998.703 [CBIT](DEBUG): Deactivating emergency mode.
2019-11-12T22:33:18.739Z,1573597998.739 [CBIT](DEBUG): Backplane powered.
2019-11-12T22:33:18.739Z,1573597998.739 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-11-12T22:33:18.741Z,1573597998.741 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-11-12T22:33:18.742Z,1573597998.742 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-11-12T22:33:18.742Z,1573597998.742 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-11-12T22:33:18.743Z,1573597998.743 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-11-12T22:33:18.753Z,1573597998.753 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-11-12T22:33:18.788Z,1573597998.788 [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-11-12T22:33:18.788Z,1573597998.788 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-11-12T22:33:18.858Z,1573597998.858 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-11-12T22:33:18.860Z,1573597998.860 [Default:A.Wait](DEBUG): Construct Wait.
2019-11-12T22:33:18.861Z,1573597998.861 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-11-12T22:33:18.904Z,1573597998.904 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-11-12T22:33:18.906Z,1573597998.906 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-11-12T22:33:18.928Z,1573597998.928 [Default:E.Execute](DEBUG): Construct Execute.
2019-11-12T22:33:18.942Z,1573597998.942 [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-11-12T22:33:18.956Z,1573597998.956 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,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-11-12T22:33:18.973Z,1573597998.973 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-11-12T22:33:19.004Z,1573597999.004 [DDM](INFO): Start
2019-11-12T22:33:19.004Z,1573597999.004 [DDM](INFO): Powering up
2019-11-12T22:33:19.004Z,1573597999.004 [DDM](DEBUG): Initializing DDM.
2019-11-12T22:33:19.027Z,1573597999.027 [Radio_Surface](INFO): Powering up
2019-11-12T22:33:19.029Z,1573597999.029 [Depth_Keller](ERROR): Pressure reading out of range: 957.962097 decibar
2019-11-12T22:33:19.029Z,1573597999.029 [DUSBL_Hydroid](INFO): Powering up
2019-11-12T22:33:19.030Z,1573597999.030 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-11-12T22:33:19.212Z,1573597999.212 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-11-12T22:33:19.236Z,1573597999.236 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-11-12T22:33:19.243Z,1573597999.243 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-11-12T22:33:19.244Z,1573597999.244 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-11-12T22:33:19.251Z,1573597999.251 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-11-12T22:33:19.252Z,1573597999.252 [MassServo](DEBUG): Initializing EZServoServo.
2019-11-12T22:33:19.259Z,1573597999.259 [MassServo](DEBUG): Initializing MassServo.
2019-11-12T22:33:19.260Z,1573597999.260 [RudderServo](DEBUG): Initializing EZServoServo.
2019-11-12T22:33:19.267Z,1573597999.267 [RudderServo](DEBUG): Initializing RudderServo.
2019-11-12T22:33:19.268Z,1573597999.268 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-11-12T22:33:19.275Z,1573597999.275 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-11-12T22:33:19.378Z,1573597999.378 [DDM](INFO): Starting
2019-11-12T22:33:19.383Z,1573597999.383 [Micromodem](INFO): Powering up
2019-11-12T22:33:19.383Z,1573597999.383 [Micromodem](DEBUG): Initializing Micromodem.
2019-11-12T22:33:19.767Z,1573597999.767 [DDM](INFO): Starting
2019-11-12T22:33:20.139Z,1573598000.139 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-11-12T22:33:20.139Z,1573598000.139 [RudderServo](FAULT): Rudder failed to initialize
2019-11-12T22:33:20.139Z,1573598000.139 [RudderServo] Communications Fault, FailCount= 1
2019-11-12T22:33:20.139Z,1573598000.139 [RudderServo](ERROR): Communications Fault
2019-11-12T22:33:20.250Z,1573598000.250 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-11-12T22:33:20.256Z,1573598000.256 [DDM](INFO): Starting
2019-11-12T22:33:20.408Z,1573598000.408 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-11-12T22:33:20.408Z,1573598000.408 [RudderServo](INFO): Powering down
2019-11-12T22:33:20.659Z,1573598000.659 [DDM](INFO): Starting
2019-11-12T22:33:21.063Z,1573598001.063 [DDM](INFO): Starting
2019-11-12T22:33:21.125Z,1573598001.125 [RudderServo](DEBUG): Initializing EZServoServo.
2019-11-12T22:33:21.244Z,1573598001.244 [RudderServo](DEBUG): Initializing RudderServo.
2019-11-12T22:33:21.248Z,1573598001.248 [CBIT](INFO): Clearing failed state for component RudderServo
2019-11-12T22:33:21.248Z,1573598001.248 [RudderServo] No Fault, FailCount= 1
2019-11-12T22:33:21.467Z,1573598001.467 [DDM](INFO): Starting
2019-11-12T22:33:21.871Z,1573598001.871 [DDM](INFO): Starting
2019-11-12T22:33:22.343Z,1573598002.343 [DDM](INFO): Starting
2019-11-12T22:33:22.715Z,1573598002.715 [DDM](INFO): Starting
2019-11-12T22:33:23.108Z,1573598003.108 [DDM](INFO): Starting
2019-11-12T22:33:23.575Z,1573598003.575 [DDM](INFO): Starting
2019-11-12T22:33:23.927Z,1573598003.927 [DDM](INFO): Starting
2019-11-12T22:33:24.335Z,1573598004.335 [DDM](INFO): Starting
2019-11-12T22:33:24.342Z,1573598004.342 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-11-12T22:33:24.735Z,1573598004.735 [DDM](INFO): Starting
2019-11-12T22:33:25.231Z,1573598005.231 [DDM](INFO): Starting
2019-11-12T22:33:25.635Z,1573598005.635 [DDM](INFO): Starting
2019-11-12T22:33:26.039Z,1573598006.039 [DDM](INFO): Starting
2019-11-12T22:33:26.443Z,1573598006.443 [DDM](INFO): Starting
2019-11-12T22:33:26.851Z,1573598006.851 [DDM](INFO): Starting
2019-11-12T22:33:27.240Z,1573598007.240 [DDM](INFO): Starting
2019-11-12T22:33:27.681Z,1573598007.681 [DDM](INFO): Starting
2019-11-12T22:33:28.035Z,1573598008.035 [DDM](INFO): Starting
2019-11-12T22:33:28.448Z,1573598008.448 [DDM](INFO): Starting
2019-11-12T22:33:28.454Z,1573598008.454 [Micromodem](INFO): Nmea in: $CATMG,2019-11-12T22:33:27.721336Z,RTC,RTC*5E

2019-11-12T22:33:28.454Z,1573598008.454 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2019-11-12T22:33:27.721336Z,RTC,RTC*5E

2019-11-12T22:33:28.843Z,1573598008.843 [DDM](INFO): Starting
2019-11-12T22:33:29.255Z,1573598009.255 [DDM](INFO): Starting
2019-11-12T22:33:29.660Z,1573598009.660 [DDM](INFO): Starting
2019-11-12T22:33:29.661Z,1573598009.661 [DDM](DEBUG): €!C REMUS Capture Rev 1.1
2019-11-12T22:33:29.661Z,1573598009.661 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2019-11-12T22:33:30.048Z,1573598010.048 [DDM](INFO): Runnable
2019-11-12T22:33:30.451Z,1573598010.451 [DDM](INFO): Pause
2019-11-12T22:33:30.855Z,1573598010.855 [DDM](INFO): Paused
2019-11-12T22:33:31.259Z,1573598011.259 [DDM](INFO): Paused
2019-11-12T22:33:31.663Z,1573598011.663 [DDM](INFO): Paused
2019-11-12T22:33:32.067Z,1573598012.067 [DDM](INFO): Paused
2019-11-12T22:33:32.471Z,1573598012.471 [DDM](INFO): Paused
2019-11-12T22:33:32.523Z,1573598012.523 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-11-12T22:33:32.875Z,1573598012.875 [DDM](INFO): Paused
2019-11-12T22:33:33.279Z,1573598013.279 [DDM](INFO): Paused
2019-11-12T22:33:33.682Z,1573598013.682 [DDM](INFO): Paused
2019-11-12T22:33:34.087Z,1573598014.087 [DDM](INFO): Paused
2019-11-12T22:33:34.491Z,1573598014.491 [DDM](INFO): Paused
2019-11-12T22:33:34.896Z,1573598014.896 [DDM](INFO): Paused
2019-11-12T22:33:35.299Z,1573598015.299 [DDM](INFO): Paused
2019-11-12T22:33:35.704Z,1573598015.704 [DDM](INFO): Paused
2019-11-12T22:33:35.709Z,1573598015.709 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-11-12T22:33:36.107Z,1573598016.107 [DDM](INFO): Paused
2019-11-12T22:33:36.113Z,1573598016.113 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-11-12T22:33:36.114Z,1573598016.114 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-11-12T22:33:36.511Z,1573598016.511 [DDM](INFO): Paused
2019-11-12T22:33:36.517Z,1573598016.517 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-11-12T22:33:36.517Z,1573598016.517 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-11-12T22:33:36.916Z,1573598016.916 [DDM](INFO): Paused
2019-11-12T22:33:36.921Z,1573598016.921 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-11-12T22:33:37.319Z,1573598017.319 [DDM](INFO): Paused
2019-11-12T22:33:37.723Z,1573598017.723 [DDM](INFO): Paused
2019-11-12T22:33:37.729Z,1573598017.729 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-11-12T22:33:37.730Z,1573598017.730 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2019-11-12T22:33:38.127Z,1573598018.127 [DDM](INFO): Paused
2019-11-12T22:33:38.133Z,1573598018.133 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2019-11-12T22:33:38.133Z,1573598018.133 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2019-11-12T22:33:38.531Z,1573598018.531 [DDM](INFO): Paused
2019-11-12T22:33:38.537Z,1573598018.537 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2019-11-12T22:33:38.537Z,1573598018.537 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2019-11-12T22:33:38.935Z,1573598018.935 [DDM](INFO): Paused
2019-11-12T22:33:38.941Z,1573598018.941 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2019-11-12T22:33:38.941Z,1573598018.941 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2019-11-12T22:33:39.359Z,1573598019.359 [DDM](INFO): Paused
2019-11-12T22:33:39.378Z,1573598019.378 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2019-11-12T22:33:39.378Z,1573598019.378 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2019-11-12T22:33:39.744Z,1573598019.744 [DDM](INFO): Paused
2019-11-12T22:33:39.750Z,1573598019.750 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2019-11-12T22:33:39.751Z,1573598019.751 [Micromodem](INFO): Nmea out: $CCCLK,2019,11,12,22,33,40*49

2019-11-12T22:33:40.160Z,1573598020.160 [DDM](INFO): Paused
2019-11-12T22:33:40.175Z,1573598020.175 [Micromodem](INFO): Nmea in: $CACLK,2019,11,12,22,33,40*4B

2019-11-12T22:33:40.551Z,1573598020.551 [DDM](INFO): Paused
2019-11-12T22:33:40.955Z,1573598020.955 [DDM](INFO): Paused
2019-11-12T22:33:40.974Z,1573598020.974 [Micromodem](INFO): Nmea in: $CATMS,0,2019-11-12T22:33:41Z*7A

2019-11-12T22:33:41.016Z,1573598021.016 [Micromodem](INFO): Nmea in: $CATMG,2019-11-12T22:33:41.026246Z,USER_CMD,RTC*19

2019-11-12T22:33:41.358Z,1573598021.358 [DDM](INFO): Paused
2019-11-12T22:33:41.763Z,1573598021.763 [DDM](INFO): Paused
2019-11-12T22:33:42.176Z,1573598022.176 [DDM](INFO): Paused
2019-11-12T22:33:42.571Z,1573598022.571 [DDM](INFO): Paused
2019-11-12T22:33:42.975Z,1573598022.975 [DDM](INFO): Paused
2019-11-12T22:33:43.379Z,1573598023.379 [DDM](INFO): Paused
2019-11-12T22:33:43.783Z,1573598023.783 [DDM](INFO): Paused
2019-11-12T22:33:44.198Z,1573598024.198 [DDM](INFO): Paused
2019-11-12T22:33:44.590Z,1573598024.590 [DDM](INFO): Paused
2019-11-12T22:33:44.994Z,1573598024.994 [DDM](INFO): Paused
2019-11-12T22:33:45.399Z,1573598025.399 [DDM](INFO): Paused
2019-11-12T22:33:45.405Z,1573598025.405 [NAL9602](INFO): Powering up NAL9602
2019-11-12T22:33:45.802Z,1573598025.802 [DDM](INFO): Paused
2019-11-12T22:33:46.206Z,1573598026.206 [DDM](INFO): Paused
2019-11-12T22:33:46.611Z,1573598026.611 [DDM](INFO): Paused
2019-11-12T22:33:47.014Z,1573598027.014 [DDM](INFO): Paused
2019-11-12T22:33:47.077Z,1573598027.077 [SBIT](IMPORTANT): Beginning Startup BIT
2019-11-12T22:33:47.089Z,1573598027.089 [CBIT](IMPORTANT): Beginning ground fault scan
2019-11-12T22:33:47.418Z,1573598027.418 [DDM](INFO): Paused
2019-11-12T22:33:47.823Z,1573598027.823 [DDM](INFO): Paused
2019-11-12T22:33:48.245Z,1573598028.245 [DDM](INFO): Paused
2019-11-12T22:33:48.631Z,1573598028.631 [DDM](INFO): Paused
2019-11-12T22:33:49.041Z,1573598029.041 [DDM](INFO): Paused
2019-11-12T22:33:49.439Z,1573598029.439 [DDM](INFO): Paused
2019-11-12T22:33:50.071Z,1573598030.071 [DDM](INFO): Paused
2019-11-12T22:33:50.471Z,1573598030.471 [DDM](INFO): Paused
2019-11-12T22:33:50.875Z,1573598030.875 [DDM](INFO): Paused
2019-11-12T22:33:51.292Z,1573598031.292 [DDM](INFO): Paused
2019-11-12T22:33:51.696Z,1573598031.696 [DDM](INFO): Paused
2019-11-12T22:33:52.099Z,1573598032.099 [DDM](INFO): Paused
2019-11-12T22:33:52.503Z,1573598032.503 [DDM](INFO): Paused
2019-11-12T22:33:52.907Z,1573598032.907 [DDM](INFO): Paused
2019-11-12T22:33:53.311Z,1573598033.311 [DDM](INFO): Paused
2019-11-12T22:33:53.714Z,1573598033.714 [DDM](INFO): Paused
2019-11-12T22:33:54.119Z,1573598034.119 [DDM](INFO): Paused
2019-11-12T22:33:54.523Z,1573598034.523 [DDM](INFO): Paused
2019-11-12T22:33:54.564Z,1573598034.564 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-11-12T22:33:54.564Z,1573598034.564 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19111215444349,35.0, -0.1,   0.0,1448.9,,  0.00,   0.00,   0.00,   0.00,   0.00
2019-11-12T22:33:54.926Z,1573598034.926 [DDM](INFO): Paused
2019-11-12T22:33:55.336Z,1573598035.336 [DDM](INFO): Paused
2019-11-12T22:33:55.735Z,1573598035.735 [DDM](INFO): Paused
2019-11-12T22:33:56.138Z,1573598036.138 [DDM](INFO): Paused
2019-11-12T22:33:56.543Z,1573598036.543 [DDM](INFO): Paused
2019-11-12T22:33:56.550Z,1573598036.550 [NAL9602](INFO): NAL9602 initialized
2019-11-12T22:33:56.947Z,1573598036.947 [DDM](INFO): Paused
2019-11-12T22:33:57.350Z,1573598037.350 [DDM](INFO): Paused
2019-11-12T22:33:57.755Z,1573598037.755 [DDM](INFO): Paused
2019-11-12T22:33:57.880Z,1573598037.880 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010684
CHAN A1 (24V): -0.029503
CHAN A2 (12V): -0.007570
CHAN A3 (5V): -0.002166
CHAN B0 (3.3V): -0.000068
CHAN B1 (3.15aV): 0.000227
CHAN B2 (3.15bV): 0.000352
CHAN B3 (GND): 0.002301
OPEN: 0.004741
Full Scale Calc: 4.765 mA, -1.589 mA
2019-11-12T22:33:58.159Z,1573598038.159 [DDM](INFO): Paused
2019-11-12T22:33:58.562Z,1573598038.562 [DDM](INFO): Paused
2019-11-12T22:33:58.967Z,1573598038.967 [DDM](INFO): Paused
2019-11-12T22:33:59.371Z,1573598039.371 [DDM](INFO): Paused
2019-11-12T22:33:59.775Z,1573598039.775 [DDM](INFO): Paused
2019-11-12T22:34:00.184Z,1573598040.184 [DDM](INFO): Paused
2019-11-12T22:34:00.583Z,1573598040.583 [DDM](INFO): Paused
2019-11-12T22:34:00.987Z,1573598040.987 [DDM](INFO): Paused
2019-11-12T22:34:01.391Z,1573598041.391 [DDM](INFO): Paused
2019-11-12T22:34:01.800Z,1573598041.800 [DDM](INFO): Paused
2019-11-12T22:34:02.208Z,1573598042.208 [DDM](INFO): Paused
2019-11-12T22:34:02.834Z,1573598042.834 [DDM](INFO): Paused
2019-11-12T22:34:03.235Z,1573598043.235 [DDM](INFO): Paused
2019-11-12T22:34:03.639Z,1573598043.639 [DDM](INFO): Paused
2019-11-12T22:34:04.043Z,1573598044.043 [DDM](INFO): Paused
2019-11-12T22:34:04.455Z,1573598044.455 [DDM](INFO): Paused
2019-11-12T22:34:04.851Z,1573598044.851 [DDM](INFO): Paused
2019-11-12T22:34:05.255Z,1573598045.255 [DDM](INFO): Paused
2019-11-12T22:34:05.659Z,1573598045.659 [DDM](INFO): Paused
2019-11-12T22:34:06.069Z,1573598046.069 [DDM](INFO): Paused
2019-11-12T22:34:06.467Z,1573598046.467 [DDM](INFO): Paused
2019-11-12T22:34:06.871Z,1573598046.871 [DDM](INFO): Paused
2019-11-12T22:34:07.281Z,1573598047.281 [DDM](INFO): Paused
2019-11-12T22:34:07.679Z,1573598047.679 [DDM](INFO): Paused
2019-11-12T22:34:08.083Z,1573598048.083 [DDM](INFO): Paused
2019-11-12T22:34:08.487Z,1573598048.487 [DDM](INFO): Paused
2019-11-12T22:34:08.896Z,1573598048.896 [DDM](INFO): Paused
2019-11-12T22:34:09.295Z,1573598049.295 [DDM](INFO): Paused
2019-11-12T22:34:09.699Z,1573598049.699 [DDM](INFO): Paused
2019-11-12T22:34:10.103Z,1573598050.103 [DDM](INFO): Paused
2019-11-12T22:34:10.520Z,1573598050.520 [DDM](INFO): Paused
2019-11-12T22:34:10.911Z,1573598050.911 [DDM](INFO): Paused
2019-11-12T22:34:11.315Z,1573598051.315 [DDM](INFO): Paused
2019-11-12T22:34:11.719Z,1573598051.719 [DDM](INFO): Paused
2019-11-12T22:34:12.123Z,1573598052.123 [DDM](INFO): Paused
2019-11-12T22:34:12.527Z,1573598052.527 [DDM](INFO): Paused
2019-11-12T22:34:12.931Z,1573598052.931 [DDM](INFO): Paused
2019-11-12T22:34:13.341Z,1573598053.341 [DDM](INFO): Paused
2019-11-12T22:34:13.739Z,1573598053.739 [DDM](INFO): Paused
2019-11-12T22:34:14.142Z,1573598054.142 [DDM](INFO): Paused
2019-11-12T22:34:14.547Z,1573598054.547 [DDM](INFO): Paused
2019-11-12T22:34:14.951Z,1573598054.951 [DDM](INFO): Paused
2019-11-12T22:34:15.355Z,1573598055.355 [DDM](INFO): Paused
2019-11-12T22:34:15.759Z,1573598055.759 [DDM](INFO): Paused
2019-11-12T22:34:16.176Z,1573598056.176 [DDM](INFO): Paused
2019-11-12T22:34:16.566Z,1573598056.566 [DDM](INFO): Paused
2019-11-12T22:34:16.971Z,1573598056.971 [DDM](INFO): Paused
2019-11-12T22:34:17.375Z,1573598057.375 [DDM](INFO): Paused
2019-11-12T22:34:17.778Z,1573598057.778 [DDM](INFO): Paused
2019-11-12T22:34:18.183Z,1573598058.183 [DDM](INFO): Paused
2019-11-12T22:34:18.587Z,1573598058.587 [DDM](INFO): Paused
2019-11-12T22:34:18.990Z,1573598058.990 [DDM](INFO): Paused
2019-11-12T22:34:19.395Z,1573598059.395 [DDM](INFO): Paused
2019-11-12T22:34:19.798Z,1573598059.798 [DDM](INFO): Paused
2019-11-12T22:34:20.202Z,1573598060.202 [DDM](INFO): Paused
2019-11-12T22:34:20.607Z,1573598060.607 [DDM](INFO): Paused
2019-11-12T22:34:21.011Z,1573598061.011 [DDM](INFO): Paused
2019-11-12T22:34:21.414Z,1573598061.414 [DDM](INFO): Paused
2019-11-12T22:34:21.819Z,1573598061.819 [DDM](INFO): Paused
2019-11-12T22:34:22.223Z,1573598062.223 [DDM](INFO): Paused
2019-11-12T22:34:22.626Z,1573598062.626 [DDM](INFO): Paused
2019-11-12T22:34:23.031Z,1573598063.031 [DDM](INFO): Paused
2019-11-12T22:34:23.435Z,1573598063.435 [DDM](INFO): Paused
2019-11-12T22:34:23.838Z,1573598063.838 [DDM](INFO): Paused
2019-11-12T22:34:24.243Z,1573598064.243 [DDM](INFO): Paused
2019-11-12T22:34:24.647Z,1573598064.647 [DDM](INFO): Paused
2019-11-12T22:34:25.050Z,1573598065.050 [DDM](INFO): Paused
2019-11-12T22:34:25.455Z,1573598065.455 [DDM](INFO): Paused
2019-11-12T22:34:25.858Z,1573598065.858 [DDM](INFO): Paused
2019-11-12T22:34:26.267Z,1573598066.267 [DDM](INFO): Paused
2019-11-12T22:34:26.667Z,1573598066.667 [DDM](INFO): Paused
2019-11-12T22:34:27.071Z,1573598067.071 [DDM](INFO): Paused
2019-11-12T22:34:27.474Z,1573598067.474 [DDM](INFO): Paused
2019-11-12T22:34:27.879Z,1573598067.879 [DDM](INFO): Paused
2019-11-12T22:34:28.283Z,1573598068.283 [DDM](INFO): Paused
2019-11-12T22:34:28.686Z,1573598068.686 [DDM](INFO): Paused
2019-11-12T22:34:29.091Z,1573598069.091 [DDM](INFO): Paused
2019-11-12T22:34:29.593Z,1573598069.593 [DDM](INFO): Paused
2019-11-12T22:34:29.995Z,1573598069.995 [DDM](INFO): Paused
2019-11-12T22:34:30.399Z,1573598070.399 [DDM](INFO): Paused
2019-11-12T22:34:30.803Z,1573598070.803 [DDM](INFO): Paused
2019-11-12T22:34:31.207Z,1573598071.207 [DDM](INFO): Paused
2019-11-12T22:34:31.611Z,1573598071.611 [DDM](INFO): Paused
2019-11-12T22:34:32.015Z,1573598072.015 [DDM](INFO): Paused
2019-11-12T22:34:32.418Z,1573598072.418 [DDM](INFO): Paused
2019-11-12T22:34:32.823Z,1573598072.823 [DDM](INFO): Paused
2019-11-12T22:34:33.227Z,1573598073.227 [DDM](INFO): Paused
2019-11-12T22:34:33.630Z,1573598073.630 [DDM](INFO): Paused
2019-11-12T22:34:34.035Z,1573598074.035 [DDM](INFO): Paused
2019-11-12T22:34:34.439Z,1573598074.439 [DDM](INFO): Paused
2019-11-12T22:34:34.843Z,1573598074.843 [DDM](INFO): Paused
2019-11-12T22:34:35.247Z,1573598075.247 [DDM](INFO): Paused
2019-11-12T22:34:35.651Z,1573598075.651 [DDM](INFO): Paused
2019-11-12T22:34:36.060Z,1573598076.060 [DDM](INFO): Paused
2019-11-12T22:34:36.459Z,1573598076.459 [DDM](INFO): Paused
2019-11-12T22:34:36.863Z,1573598076.863 [DDM](INFO): Paused
2019-11-12T22:34:37.267Z,1573598077.267 [DDM](INFO): Paused
2019-11-12T22:34:37.675Z,1573598077.675 [DDM](INFO): Paused
2019-11-12T22:34:38.074Z,1573598078.074 [DDM](INFO): Paused
2019-11-12T22:34:38.479Z,1573598078.479 [DDM](INFO): Paused
2019-11-12T22:34:38.883Z,1573598078.883 [DDM](INFO): Paused
2019-11-12T22:34:39.287Z,1573598079.287 [DDM](INFO): Paused
2019-11-12T22:34:39.690Z,1573598079.690 [DDM](INFO): Paused
2019-11-12T22:34:40.095Z,1573598080.095 [DDM](INFO): Paused
2019-11-12T22:34:40.499Z,1573598080.499 [DDM](INFO): Paused
2019-11-12T22:34:40.624Z,1573598080.624 [SBIT](IMPORTANT): SBIT PASSED
2019-11-12T22:34:40.712Z,1573598080.712 [CommandLine](IMPORTANT): got command configSet list
2019-11-12T22:34:40.712Z,1573598080.712 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-11-12T22:34:40.713Z,1573598080.713 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2019-11-12T22:34:40.713Z,1573598080.713 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=20 count;

2019-11-12T22:34:40.713Z,1573598080.713 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2019-11-12T22:34:40.713Z,1573598080.713 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity=3 enum;

2019-11-12T22:34:40.713Z,1573598080.713 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2019-11-12T22:34:40.713Z,1573598080.713 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 1.000000 degree;

2019-11-12T22:34:40.713Z,1573598080.713 [CommandLine](IMPORTANT): Express linearApproximation acoustic_contact_range 2.000000 meter;

2019-11-12T22:34:40.714Z,1573598080.714 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2019-11-12T22:34:40.714Z,1573598080.714 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2019-11-12T22:34:40.714Z,1573598080.714 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2019-11-12T22:34:40.714Z,1573598080.714 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=250 cubic_centimeter;

2019-11-12T22:34:40.903Z,1573598080.903 [DDM](INFO): Paused
2019-11-12T22:34:40.957Z,1573598080.957 [MissionManager](IMPORTANT): Started mission Startup
2019-11-12T22:34:40.957Z,1573598080.957 [Startup] Running Loop=1
2019-11-12T22:34:40.957Z,1573598080.957 [Startup](DEBUG): Aggregate::initialize Startup
2019-11-12T22:34:40.957Z,1573598080.957 [Startup:A.GoToSurface] Running Loop=1
2019-11-12T22:34:40.957Z,1573598080.957 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-11-12T22:34:40.958Z,1573598080.958 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-11-12T22:34:40.958Z,1573598080.958 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-11-12T22:34:40.958Z,1573598080.958 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-11-12T22:34:40.959Z,1573598080.959 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-11-12T22:34:40.959Z,1573598080.959 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-11-12T22:34:40.961Z,1573598080.961 [Startup:StartupSatComms] Running Loop=1
2019-11-12T22:34:40.961Z,1573598080.961 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-11-12T22:34:40.961Z,1573598080.961 [Startup:StartupSatComms:A] Running Loop=1
2019-11-12T22:34:41.307Z,1573598081.307 [DDM](INFO): Paused
2019-11-12T22:34:41.354Z,1573598081.354 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-11-12T22:34:41.710Z,1573598081.710 [DDM](INFO): Paused
2019-11-12T22:34:42.115Z,1573598082.115 [DDM](INFO): Paused
2019-11-12T22:34:42.519Z,1573598082.519 [DDM](INFO): Paused
2019-11-12T22:34:42.922Z,1573598082.922 [DDM](INFO): Paused
2019-11-12T22:34:43.326Z,1573598083.326 [DDM](INFO): Paused
2019-11-12T22:34:43.731Z,1573598083.731 [DDM](INFO): Paused
2019-11-12T22:34:44.138Z,1573598084.138 [DDM](INFO): Paused
2019-11-12T22:34:44.538Z,1573598084.538 [DDM](INFO): Paused
2019-11-12T22:34:44.943Z,1573598084.943 [DDM](INFO): Paused
2019-11-12T22:34:45.347Z,1573598085.347 [DDM](INFO): Paused
2019-11-12T22:34:45.750Z,1573598085.750 [DDM](INFO): Paused
2019-11-12T22:34:46.169Z,1573598086.169 [DDM](INFO): Paused
2019-11-12T22:34:46.558Z,1573598086.558 [DDM](INFO): Paused
2019-11-12T22:34:46.962Z,1573598086.962 [DDM](INFO): Paused
2019-11-12T22:34:47.367Z,1573598087.367 [DDM](INFO): Paused
2019-11-12T22:34:47.771Z,1573598087.771 [DDM](INFO): Paused
2019-11-12T22:34:48.174Z,1573598088.174 [DDM](INFO): Paused
2019-11-12T22:34:48.579Z,1573598088.579 [DDM](INFO): Paused
2019-11-12T22:34:48.983Z,1573598088.983 [DDM](INFO): Paused
2019-11-12T22:34:49.387Z,1573598089.387 [DDM](INFO): Paused
2019-11-12T22:34:49.791Z,1573598089.791 [DDM](INFO): Paused
2019-11-12T22:34:50.195Z,1573598090.195 [DDM](INFO): Paused
2019-11-12T22:34:50.598Z,1573598090.598 [DDM](INFO): Paused
2019-11-12T22:34:51.003Z,1573598091.003 [DDM](INFO): Paused
2019-11-12T22:34:51.407Z,1573598091.407 [DDM](INFO): Paused
2019-11-12T22:34:51.810Z,1573598091.810 [DDM](INFO): Paused
2019-11-12T22:34:52.215Z,1573598092.215 [DDM](INFO): Paused
2019-11-12T22:34:52.619Z,1573598092.619 [DDM](INFO): Paused
2019-11-12T22:34:53.023Z,1573598093.023 [DDM](INFO): Paused
2019-11-12T22:34:53.427Z,1573598093.427 [DDM](INFO): Paused
2019-11-12T22:34:53.830Z,1573598093.830 [DDM](INFO): Paused
2019-11-12T22:34:54.234Z,1573598094.234 [DDM](INFO): Paused
2019-11-12T22:34:54.639Z,1573598094.639 [DDM](INFO): Paused
2019-11-12T22:34:55.045Z,1573598095.045 [DDM](INFO): Paused
2019-11-12T22:34:55.446Z,1573598095.446 [DDM](INFO): Paused
2019-11-12T22:34:55.851Z,1573598095.851 [DDM](INFO): Paused
2019-11-12T22:34:56.255Z,1573598096.255 [DDM](INFO): Paused
2019-11-12T22:34:56.659Z,1573598096.659 [DDM](INFO): Paused
2019-11-12T22:34:57.063Z,1573598097.063 [DDM](INFO): Paused
2019-11-12T22:34:57.466Z,1573598097.466 [DDM](INFO): Paused
2019-11-12T22:34:57.870Z,1573598097.870 [DDM](INFO): Paused
2019-11-12T22:34:58.275Z,1573598098.275 [DDM](INFO): Paused
2019-11-12T22:34:58.678Z,1573598098.678 [DDM](INFO): Paused
2019-11-12T22:34:59.082Z,1573598099.082 [DDM](INFO): Paused
2019-11-12T22:34:59.487Z,1573598099.487 [DDM](INFO): Paused
2019-11-12T22:34:59.890Z,1573598099.890 [DDM](INFO): Paused
2019-11-12T22:35:00.294Z,1573598100.294 [DDM](INFO): Paused
2019-11-12T22:35:00.699Z,1573598100.699 [DDM](INFO): Paused
2019-11-12T22:35:01.102Z,1573598101.102 [DDM](INFO): Paused
2019-11-12T22:35:01.530Z,1573598101.530 [DDM](INFO): Paused
2019-11-12T22:35:01.920Z,1573598101.920 [DDM](INFO): Paused
2019-11-12T22:35:02.317Z,1573598102.317 [DDM](INFO): Paused
2019-11-12T22:35:02.724Z,1573598102.724 [DDM](INFO): Paused
2019-11-12T22:35:03.127Z,1573598103.127 [DDM](INFO): Paused
2019-11-12T22:35:03.527Z,1573598103.527 [DDM](INFO): Paused
2019-11-12T22:35:03.931Z,1573598103.931 [DDM](INFO): Paused
2019-11-12T22:35:04.335Z,1573598104.335 [DDM](INFO): Paused
2019-11-12T22:35:04.738Z,1573598104.738 [DDM](INFO): Paused
2019-11-12T22:35:05.145Z,1573598105.145 [DDM](INFO): Paused
2019-11-12T22:35:05.547Z,1573598105.547 [DDM](INFO): Paused
2019-11-12T22:35:05.951Z,1573598105.951 [DDM](INFO): Paused
2019-11-12T22:35:06.355Z,1573598106.355 [DDM](INFO): Paused
2019-11-12T22:35:06.759Z,1573598106.759 [DDM](INFO): Paused
2019-11-12T22:35:07.163Z,1573598107.163 [DDM](INFO): Paused
2019-11-12T22:35:07.566Z,1573598107.566 [DDM](INFO): Paused
2019-11-12T22:35:07.971Z,1573598107.971 [DDM](INFO): Paused
2019-11-12T22:35:08.375Z,1573598108.375 [DDM](INFO): Paused
2019-11-12T22:35:08.778Z,1573598108.778 [DDM](INFO): Paused
2019-11-12T22:35:09.183Z,1573598109.183 [DDM](INFO): Paused
2019-11-12T22:35:09.587Z,1573598109.587 [DDM](INFO): Paused
2019-11-12T22:35:09.990Z,1573598109.990 [DDM](INFO): Paused
2019-11-12T22:35:10.395Z,1573598110.395 [DDM](INFO): Paused
2019-11-12T22:35:10.799Z,1573598110.799 [DDM](INFO): Paused
2019-11-12T22:35:11.202Z,1573598111.202 [DDM](INFO): Paused
2019-11-12T22:35:11.607Z,1573598111.607 [DDM](INFO): Paused
2019-11-12T22:35:12.010Z,1573598112.010 [DDM](INFO): Paused
2019-11-12T22:35:12.415Z,1573598112.415 [DDM](INFO): Paused
2019-11-12T22:35:12.819Z,1573598112.819 [DDM](INFO): Paused
2019-11-12T22:35:13.223Z,1573598113.223 [DDM](INFO): Paused
2019-11-12T22:35:13.626Z,1573598113.626 [DDM](INFO): Paused
2019-11-12T22:35:14.031Z,1573598114.031 [DDM](INFO): Paused
2019-11-12T22:35:14.434Z,1573598114.434 [DDM](INFO): Paused
2019-11-12T22:35:14.838Z,1573598114.838 [DDM](INFO): Paused
2019-11-12T22:35:15.243Z,1573598115.243 [DDM](INFO): Paused
2019-11-12T22:35:15.647Z,1573598115.647 [DDM](INFO): Paused
2019-11-12T22:35:16.050Z,1573598116.050 [DDM](INFO): Paused
2019-11-12T22:35:16.455Z,1573598116.455 [DDM](INFO): Paused
2019-11-12T22:35:16.859Z,1573598116.859 [DDM](INFO): Paused
2019-11-12T22:35:17.263Z,1573598117.263 [DDM](INFO): Paused
2019-11-12T22:35:17.667Z,1573598117.667 [DDM](INFO): Paused
2019-11-12T22:35:18.071Z,1573598118.071 [DDM](INFO): Paused
2019-11-12T22:35:18.475Z,1573598118.475 [DDM](INFO): Paused
2019-11-12T22:35:18.879Z,1573598118.879 [DDM](INFO): Paused
2019-11-12T22:35:19.283Z,1573598119.283 [DDM](INFO): Paused
2019-11-12T22:35:19.687Z,1573598119.687 [DDM](INFO): Paused
2019-11-12T22:35:20.093Z,1573598120.093 [DDM](INFO): Paused
2019-11-12T22:35:20.494Z,1573598120.494 [DDM](INFO): Paused
2019-11-12T22:35:20.898Z,1573598120.898 [DDM](INFO): Paused
2019-11-12T22:35:21.303Z,1573598121.303 [DDM](INFO): Paused
2019-11-12T22:35:21.707Z,1573598121.707 [DDM](INFO): Paused
2019-11-12T22:35:22.110Z,1573598122.110 [DDM](INFO): Paused
2019-11-12T22:35:22.515Z,1573598122.515 [DDM](INFO): Paused
2019-11-12T22:35:22.918Z,1573598122.918 [DDM](INFO): Paused
2019-11-12T22:35:23.322Z,1573598123.322 [DDM](INFO): Paused
2019-11-12T22:35:23.727Z,1573598123.727 [DDM](INFO): Paused
2019-11-12T22:35:24.134Z,1573598124.134 [DDM](INFO): Paused
2019-11-12T22:35:24.535Z,1573598124.535 [DDM](INFO): Paused
2019-11-12T22:35:24.939Z,1573598124.939 [DDM](INFO): Paused
2019-11-12T22:35:25.343Z,1573598125.343 [DDM](INFO): Paused
2019-11-12T22:35:25.746Z,1573598125.746 [DDM](INFO): Paused
2019-11-12T22:35:26.166Z,1573598126.166 [DDM](INFO): Paused
2019-11-12T22:35:26.554Z,1573598126.554 [DDM](INFO): Paused
2019-11-12T22:35:26.958Z,1573598126.958 [DDM](INFO): Paused
2019-11-12T22:35:27.363Z,1573598127.363 [DDM](INFO): Paused
2019-11-12T22:35:27.767Z,1573598127.767 [DDM](INFO): Paused
2019-11-12T22:35:28.170Z,1573598128.170 [DDM](INFO): Paused
2019-11-12T22:35:28.575Z,1573598128.575 [DDM](INFO): Paused
2019-11-12T22:35:28.979Z,1573598128.979 [DDM](INFO): Paused
2019-11-12T22:35:29.383Z,1573598129.383 [DDM](INFO): Paused
2019-11-12T22:35:29.791Z,1573598129.791 [DDM](INFO): Paused
2019-11-12T22:35:30.191Z,1573598130.191 [DDM](INFO): Paused
2019-11-12T22:35:30.594Z,1573598130.594 [DDM](INFO): Paused
2019-11-12T22:35:30.999Z,1573598130.999 [DDM](INFO): Paused
2019-11-12T22:35:31.407Z,1573598131.407 [DDM](INFO): Paused
2019-11-12T22:35:31.806Z,1573598131.806 [DDM](INFO): Paused
2019-11-12T22:35:32.211Z,1573598132.211 [DDM](INFO): Paused
2019-11-12T22:35:32.614Z,1573598132.614 [DDM](INFO): Paused
2019-11-12T22:35:33.019Z,1573598133.019 [DDM](INFO): Paused
2019-11-12T22:35:33.423Z,1573598133.423 [DDM](INFO): Paused
2019-11-12T22:35:33.827Z,1573598133.827 [DDM](INFO): Paused
2019-11-12T22:35:34.230Z,1573598134.230 [DDM](INFO): Paused
2019-11-12T22:35:34.635Z,1573598134.635 [DDM](INFO): Paused
2019-11-12T22:35:35.039Z,1573598135.039 [DDM](INFO): Paused
2019-11-12T22:35:35.442Z,1573598135.442 [DDM](INFO): Paused
2019-11-12T22:35:35.855Z,1573598135.855 [DDM](INFO): Paused
2019-11-12T22:35:36.251Z,1573598136.251 [DDM](INFO): Paused
2019-11-12T22:35:36.654Z,1573598136.654 [DDM](INFO): Paused
2019-11-12T22:35:37.059Z,1573598137.059 [DDM](INFO): Paused
2019-11-12T22:35:37.472Z,1573598137.472 [DDM](INFO): Paused
2019-11-12T22:35:37.866Z,1573598137.866 [DDM](INFO): Paused
2019-11-12T22:35:38.279Z,1573598138.279 [DDM](INFO): Paused
2019-11-12T22:35:38.679Z,1573598138.679 [DDM](INFO): Paused
2019-11-12T22:35:39.080Z,1573598139.080 [DDM](INFO): Paused
2019-11-12T22:35:39.483Z,1573598139.483 [DDM](INFO): Paused
2019-11-12T22:35:39.887Z,1573598139.887 [DDM](INFO): Paused
2019-11-12T22:35:40.292Z,1573598140.292 [DDM](INFO): Paused
2019-11-12T22:35:40.695Z,1573598140.695 [DDM](INFO): Paused
2019-11-12T22:35:41.099Z,1573598141.099 [DDM](INFO): Paused
2019-11-12T22:35:41.157Z,1573598141.157 [Startup:StartupSatComms:A](INFO): Timed out from 2019-11-12T22:34:40.0Z
2019-11-12T22:35:41.157Z,1573598141.157 [Startup:StartupSatComms:A] Stopped
2019-11-12T22:35:41.157Z,1573598141.157 [Startup:StartupSatComms:B] Running Loop=1
2019-11-12T22:35:41.510Z,1573598141.510 [DDM](INFO): Paused
2019-11-12T22:35:41.574Z,1573598141.574 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-11-12T22:35:41.907Z,1573598141.907 [DDM](INFO): Paused
2019-11-12T22:35:42.311Z,1573598142.311 [DDM](INFO): Paused
2019-11-12T22:35:42.714Z,1573598142.714 [DDM](INFO): Paused
2019-11-12T22:35:43.119Z,1573598143.119 [DDM](INFO): Paused
2019-11-12T22:35:43.523Z,1573598143.523 [DDM](INFO): Paused
2019-11-12T22:35:43.926Z,1573598143.926 [DDM](INFO): Paused
2019-11-12T22:35:44.331Z,1573598144.331 [DDM](INFO): Paused
2019-11-12T22:35:44.735Z,1573598144.735 [DDM](INFO): Paused
2019-11-12T22:35:45.139Z,1573598145.139 [DDM](INFO): Paused
2019-11-12T22:35:45.550Z,1573598145.550 [DDM](INFO): Paused
2019-11-12T22:35:45.947Z,1573598145.947 [DDM](INFO): Paused
2019-11-12T22:35:46.351Z,1573598146.351 [DDM](INFO): Paused
2019-11-12T22:35:46.755Z,1573598146.755 [DDM](INFO): Paused
2019-11-12T22:35:47.161Z,1573598147.161 [DDM](INFO): Paused
2019-11-12T22:35:47.562Z,1573598147.562 [DDM](INFO): Paused
2019-11-12T22:35:47.595Z,1573598147.595 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-11-12T22:35:47.595Z,1573598147.595 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19111215463649,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-11-12T22:35:47.967Z,1573598147.967 [DDM](INFO): Paused
2019-11-12T22:35:48.370Z,1573598148.370 [DDM](INFO): Paused
2019-11-12T22:35:48.774Z,1573598148.774 [DDM](INFO): Paused
2019-11-12T22:35:49.179Z,1573598149.179 [DDM](INFO): Paused
2019-11-12T22:35:49.583Z,1573598149.583 [DDM](INFO): Paused
2019-11-12T22:35:49.986Z,1573598149.986 [DDM](INFO): Paused
2019-11-12T22:35:50.391Z,1573598150.391 [DDM](INFO): Paused
2019-11-12T22:35:50.795Z,1573598150.795 [DDM](INFO): Paused
2019-11-12T22:35:51.092Z,1573598151.092 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004358
2019-11-12T22:35:51.198Z,1573598151.198 [DDM](INFO): Paused
2019-11-12T22:35:51.603Z,1573598151.603 [DDM](INFO): Paused
2019-11-12T22:35:52.006Z,1573598152.006 [DDM](INFO): Paused
2019-11-12T22:35:52.410Z,1573598152.410 [DDM](INFO): Paused
2019-11-12T22:35:52.815Z,1573598152.815 [DDM](INFO): Paused
2019-11-12T22:35:53.219Z,1573598153.219 [DDM](INFO): Paused
2019-11-12T22:35:53.622Z,1573598153.622 [DDM](INFO): Paused
2019-11-12T22:35:54.027Z,1573598154.027 [DDM](INFO): Paused
2019-11-12T22:35:54.431Z,1573598154.431 [DDM](INFO): Paused
2019-11-12T22:35:54.835Z,1573598154.835 [DDM](INFO): Paused
2019-11-12T22:35:55.239Z,1573598155.239 [DDM](INFO): Paused
2019-11-12T22:35:55.643Z,1573598155.643 [DDM](INFO): Paused
2019-11-12T22:35:56.047Z,1573598156.047 [DDM](INFO): Paused
2019-11-12T22:35:56.452Z,1573598156.452 [DDM](INFO): Paused
2019-11-12T22:35:56.855Z,1573598156.855 [DDM](INFO): Paused
2019-11-12T22:35:57.259Z,1573598157.259 [DDM](INFO): Paused
2019-11-12T22:35:57.663Z,1573598157.663 [DDM](INFO): Paused
2019-11-12T22:35:58.067Z,1573598158.067 [DDM](INFO): Paused
2019-11-12T22:35:58.471Z,1573598158.471 [DDM](INFO): Paused
2019-11-12T22:35:58.875Z,1573598158.875 [DDM](INFO): Paused
2019-11-12T22:35:59.279Z,1573598159.279 [DDM](INFO): Paused
2019-11-12T22:35:59.683Z,1573598159.683 [DDM](INFO): Paused
2019-11-12T22:36:00.088Z,1573598160.088 [DDM](INFO): Paused
2019-11-12T22:36:00.493Z,1573598160.493 [DDM](INFO): Paused
2019-11-12T22:36:00.894Z,1573598160.894 [DDM](INFO): Paused
2019-11-12T22:36:01.305Z,1573598161.305 [DDM](INFO): Paused
2019-11-12T22:36:01.703Z,1573598161.703 [DDM](INFO): Paused
2019-11-12T22:36:02.106Z,1573598162.106 [DDM](INFO): Paused
2019-11-12T22:36:02.479Z,1573598162.479 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20191112T220258/Express0017.lzma
2019-11-12T22:36:02.511Z,1573598162.511 [DDM](INFO): Paused
2019-11-12T22:36:02.927Z,1573598162.927 [DDM](INFO): Paused
2019-11-12T22:36:03.318Z,1573598163.318 [DDM](INFO): Paused
2019-11-12T22:36:03.723Z,1573598163.723 [DDM](INFO): Paused
2019-11-12T22:36:04.129Z,1573598164.129 [DDM](INFO): Paused
2019-11-12T22:36:04.485Z,1573598164.485 [DataOverHttps](INFO): Moved sent file to Logs/20191112T220258/Express0017.lzma.bak
2019-11-12T22:36:04.485Z,1573598164.485 [DataOverHttps](INFO): SBD MOMSN=11974432
2019-11-12T22:36:04.538Z,1573598164.538 [DDM](INFO): Paused
2019-11-12T22:36:04.935Z,1573598164.935 [DDM](INFO): Paused
2019-11-12T22:36:05.338Z,1573598165.338 [DDM](INFO): Paused
2019-11-12T22:36:05.742Z,1573598165.742 [DDM](INFO): Paused
2019-11-12T22:36:06.159Z,1573598166.159 [DDM](INFO): Paused
2019-11-12T22:36:06.550Z,1573598166.550 [DDM](INFO): Paused
2019-11-12T22:36:06.954Z,1573598166.954 [DDM](INFO): Paused
2019-11-12T22:36:07.359Z,1573598167.359 [DDM](INFO): Paused
2019-11-12T22:36:07.762Z,1573598167.762 [DDM](INFO): Paused
2019-11-12T22:36:08.167Z,1573598168.167 [DDM](INFO): Paused
2019-11-12T22:36:08.571Z,1573598168.571 [DDM](INFO): Paused
2019-11-12T22:36:08.974Z,1573598168.974 [DDM](INFO): Paused
2019-11-12T22:36:09.378Z,1573598169.378 [DDM](INFO): Paused
2019-11-12T22:36:09.789Z,1573598169.789 [DDM](INFO): Paused
2019-11-12T22:36:10.187Z,1573598170.187 [DDM](INFO): Paused
2019-11-12T22:36:10.591Z,1573598170.591 [DDM](INFO): Paused
2019-11-12T22:36:10.995Z,1573598170.995 [DDM](INFO): Paused
2019-11-12T22:36:11.407Z,1573598171.407 [DDM](INFO): Paused
2019-11-12T22:36:11.802Z,1573598171.802 [DDM](INFO): Paused
2019-11-12T22:36:12.219Z,1573598172.219 [DDM](INFO): Paused
2019-11-12T22:36:12.611Z,1573598172.611 [DDM](INFO): Paused
2019-11-12T22:36:13.028Z,1573598173.028 [DDM](INFO): Paused
2019-11-12T22:36:13.419Z,1573598173.419 [DDM](INFO): Paused
2019-11-12T22:36:13.827Z,1573598173.827 [DDM](INFO): Paused
2019-11-12T22:36:14.232Z,1573598174.232 [DDM](INFO): Paused
2019-11-12T22:36:14.634Z,1573598174.634 [DDM](INFO): Paused
2019-11-12T22:36:15.035Z,1573598175.035 [DDM](INFO): Paused
2019-11-12T22:36:15.438Z,1573598175.438 [DDM](INFO): Paused
2019-11-12T22:36:15.843Z,1573598175.843 [DDM](INFO): Paused
2019-11-12T22:36:16.247Z,1573598176.247 [DDM](INFO): Paused
2019-11-12T22:36:16.650Z,1573598176.650 [DDM](INFO): Paused
2019-11-12T22:36:17.055Z,1573598177.055 [DDM](INFO): Paused
2019-11-12T22:36:17.459Z,1573598177.459 [DDM](INFO): Paused
2019-11-12T22:36:17.862Z,1573598177.862 [DDM](INFO): Paused
2019-11-12T22:36:18.267Z,1573598178.267 [DDM](INFO): Paused
2019-11-12T22:36:18.671Z,1573598178.671 [DDM](INFO): Paused
2019-11-12T22:36:18.736Z,1573598178.736 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-11-12T22:36:18.736Z,1573598178.736 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-11-12T22:36:18.750Z,1573598178.750 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-11-12T22:36:19.075Z,1573598179.075 [DDM](INFO): Paused
2019-11-12T22:36:19.152Z,1573598179.152 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-11-12T22:36:19.152Z,1573598179.152 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-11-12T22:36:19.485Z,1573598179.485 [DDM](INFO): Paused
2019-11-12T22:36:19.883Z,1573598179.883 [DDM](INFO): Paused
2019-11-12T22:36:20.286Z,1573598180.286 [DDM](INFO): Paused
2019-11-12T22:36:20.691Z,1573598180.691 [DDM](INFO): Paused
2019-11-12T22:36:21.096Z,1573598181.096 [DDM](INFO): Paused
2019-11-12T22:36:21.502Z,1573598181.502 [DDM](INFO): Paused
2019-11-12T22:36:21.903Z,1573598181.903 [DDM](INFO): Paused
2019-11-12T22:36:22.307Z,1573598182.307 [DDM](INFO): Paused
2019-11-12T22:36:22.711Z,1573598182.711 [DDM](INFO): Paused
2019-11-12T22:36:23.115Z,1573598183.115 [DDM](INFO): Paused
2019-11-12T22:36:23.519Z,1573598183.519 [DDM](INFO): Paused
2019-11-12T22:36:23.923Z,1573598183.923 [DDM](INFO): Paused
2019-11-12T22:36:24.328Z,1573598184.328 [DDM](INFO): Paused
2019-11-12T22:36:24.731Z,1573598184.731 [DDM](INFO): Paused
2019-11-12T22:36:25.136Z,1573598185.136 [DDM](INFO): Paused
2019-11-12T22:36:25.539Z,1573598185.539 [DDM](INFO): Paused
2019-11-12T22:36:25.943Z,1573598185.943 [DDM](INFO): Paused
2019-11-12T22:36:26.346Z,1573598186.346 [DDM](INFO): Paused
2019-11-12T22:36:26.751Z,1573598186.751 [DDM](INFO): Paused
2019-11-12T22:36:27.163Z,1573598187.163 [DDM](INFO): Paused
2019-11-12T22:36:27.559Z,1573598187.559 [DDM](INFO): Paused
2019-11-12T22:36:27.963Z,1573598187.963 [DDM](INFO): Paused
2019-11-12T22:36:28.367Z,1573598188.367 [DDM](INFO): Paused
2019-11-12T22:36:28.770Z,1573598188.770 [DDM](INFO): Paused
2019-11-12T22:36:28.848Z,1573598188.848 [DataOverHttps](INFO): Sending 880 bytes from file Logs/20191112T223308/Express0001.lzma
2019-11-12T22:36:29.175Z,1573598189.175 [DDM](INFO): Paused
2019-11-12T22:36:29.578Z,1573598189.579 [DDM](INFO): Paused
2019-11-12T22:36:29.982Z,1573598189.982 [DDM](INFO): Paused
2019-11-12T22:36:30.387Z,1573598190.387 [DDM](INFO): Paused
2019-11-12T22:36:30.791Z,1573598190.791 [DDM](INFO): Paused
2019-11-12T22:36:30.853Z,1573598190.853 [DataOverHttps](INFO): Moved sent file to Logs/20191112T223308/Express0001.lzma.bak
2019-11-12T22:36:30.853Z,1573598190.853 [DataOverHttps](INFO): SBD MOMSN=11974436
2019-11-12T22:36:31.194Z,1573598191.194 [DDM](INFO): Paused
2019-11-12T22:36:31.599Z,1573598191.599 [DDM](INFO): Paused
2019-11-12T22:36:32.003Z,1573598192.003 [DDM](INFO): Paused
2019-11-12T22:36:32.406Z,1573598192.406 [DDM](INFO): Paused
2019-11-12T22:36:32.441Z,1573598192.441 [Startup:StartupSatComms:B] Stopped
2019-11-12T22:36:32.441Z,1573598192.441 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-11-12T22:36:32.441Z,1573598192.441 [Startup:StartupSatComms] Stopped
2019-11-12T22:36:32.441Z,1573598192.441 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-11-12T22:36:32.442Z,1573598192.442 [Startup](INFO): Completed Startup
2019-11-12T22:36:32.442Z,1573598192.442 [MissionManager](INFO): Startup is completed.
2019-11-12T22:36:32.443Z,1573598192.443 [MissionManager](INFO): Uninitializing Mission Startup
2019-11-12T22:36:32.443Z,1573598192.443 [Startup] Stopped
2019-11-12T22:36:32.443Z,1573598192.443 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-11-12T22:36:32.463Z,1573598192.463 [Startup:A.GoToSurface] Stopped
2019-11-12T22:36:32.463Z,1573598192.463 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-11-12T22:36:32.811Z,1573598192.811 [DDM](INFO): Paused
2019-11-12T22:36:32.849Z,1573598192.849 [MissionManager](IMPORTANT): Started mission Default
2019-11-12T22:36:32.849Z,1573598192.849 [Default] Running Loop=1
2019-11-12T22:36:32.849Z,1573598192.849 [Default](DEBUG): Aggregate::initialize Default
2019-11-12T22:36:32.849Z,1573598192.849 [Default:B.GoToSurface] Running Loop=1
2019-11-12T22:36:32.849Z,1573598192.849 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-11-12T22:36:32.850Z,1573598192.850 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-11-12T22:36:32.850Z,1573598192.850 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-11-12T22:36:32.850Z,1573598192.850 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-11-12T22:36:32.851Z,1573598192.851 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-11-12T22:36:32.855Z,1573598192.855 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-11-12T22:36:32.855Z,1573598192.855 [Default:A.Wait] Running Loop=1
2019-11-12T22:36:32.856Z,1573598192.856 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-11-12T22:36:33.214Z,1573598193.214 [DDM](INFO): Paused
2019-11-12T22:36:33.618Z,1573598193.618 [DDM](INFO): Paused
2019-11-12T22:36:34.023Z,1573598194.023 [DDM](INFO): Paused
2019-11-12T22:36:34.427Z,1573598194.427 [DDM](INFO): Paused
2019-11-12T22:36:34.830Z,1573598194.830 [DDM](INFO): Paused
2019-11-12T22:36:35.235Z,1573598195.235 [DDM](INFO): Paused
2019-11-12T22:36:35.639Z,1573598195.639 [DDM](INFO): Paused
2019-11-12T22:36:36.042Z,1573598196.042 [DDM](INFO): Paused
2019-11-12T22:36:36.447Z,1573598196.447 [DDM](INFO): Paused
2019-11-12T22:36:36.851Z,1573598196.851 [DDM](INFO): Paused
2019-11-12T22:36:37.255Z,1573598197.255 [DDM](INFO): Paused
2019-11-12T22:36:37.665Z,1573598197.665 [DDM](INFO): Paused
2019-11-12T22:36:38.063Z,1573598198.063 [DDM](INFO): Paused
2019-11-12T22:36:38.467Z,1573598198.467 [DDM](INFO): Paused
2019-11-12T22:36:38.871Z,1573598198.871 [DDM](INFO): Paused
2019-11-12T22:36:39.280Z,1573598199.280 [DDM](INFO): Paused
2019-11-12T22:36:39.679Z,1573598199.679 [DDM](INFO): Paused
2019-11-12T22:36:40.083Z,1573598200.083 [DDM](INFO): Paused
2019-11-12T22:36:40.490Z,1573598200.490 [DDM](INFO): Paused
2019-11-12T22:36:40.896Z,1573598200.896 [DDM](INFO): Paused
2019-11-12T22:36:41.295Z,1573598201.295 [DDM](INFO): Paused
2019-11-12T22:36:41.699Z,1573598201.699 [DDM](INFO): Paused
2019-11-12T22:36:42.102Z,1573598202.102 [DDM](INFO): Paused
2019-11-12T22:36:42.507Z,1573598202.507 [DDM](INFO): Paused
2019-11-12T22:36:42.910Z,1573598202.910 [DDM](INFO): Paused
2019-11-12T22:36:43.314Z,1573598203.314 [DDM](INFO): Paused
2019-11-12T22:36:43.720Z,1573598203.720 [DDM](INFO): Paused
2019-11-12T22:36:44.123Z,1573598204.123 [DDM](INFO): Paused
2019-11-12T22:36:44.526Z,1573598204.526 [DDM](INFO): Paused
2019-11-12T22:36:44.931Z,1573598204.931 [DDM](INFO): Paused
2019-11-12T22:36:45.335Z,1573598205.335 [DDM](INFO): Paused
2019-11-12T22:36:45.738Z,1573598205.738 [DDM](INFO): Paused
2019-11-12T22:36:46.150Z,1573598206.150 [DDM](INFO): Paused
2019-11-12T22:36:46.222Z,1573598206.222 [Default:A.Wait](INFO): Done Waiting.
2019-11-12T22:36:46.222Z,1573598206.222 [Default:A.Wait] Stopped
2019-11-12T22:36:46.223Z,1573598206.223 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-11-12T22:36:46.546Z,1573598206.546 [DDM](INFO): Paused
2019-11-12T22:36:46.594Z,1573598206.594 [Default:CheckIn] Running Loop=1
2019-11-12T22:36:46.594Z,1573598206.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-12T22:36:46.594Z,1573598206.594 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-12T22:36:46.950Z,1573598206.950 [DDM](INFO): Paused
2019-11-12T22:36:46.985Z,1573598206.985 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-11-12T22:36:47.355Z,1573598207.355 [DDM](INFO): Paused
2019-11-12T22:36:47.767Z,1573598207.767 [DDM](INFO): Paused
2019-11-12T22:36:48.162Z,1573598208.162 [DDM](INFO): Paused
2019-11-12T22:36:48.575Z,1573598208.575 [DDM](INFO): Paused
2019-11-12T22:36:48.970Z,1573598208.970 [DDM](INFO): Paused
2019-11-12T22:36:49.374Z,1573598209.374 [DDM](INFO): Paused
2019-11-12T22:36:49.785Z,1573598209.785 [DDM](INFO): Paused
2019-11-12T22:36:50.187Z,1573598210.187 [DDM](INFO): Paused
2019-11-12T22:36:50.589Z,1573598210.589 [DDM](INFO): Paused
2019-11-12T22:36:50.991Z,1573598210.991 [DDM](INFO): Paused
2019-11-12T22:36:51.395Z,1573598211.395 [DDM](INFO): Paused
2019-11-12T22:36:51.798Z,1573598211.798 [DDM](INFO): Paused
2019-11-12T22:36:52.208Z,1573598212.208 [DDM](INFO): Paused
2019-11-12T22:36:52.607Z,1573598212.607 [DDM](INFO): Paused
2019-11-12T22:36:53.010Z,1573598213.010 [DDM](INFO): Paused
2019-11-12T22:36:53.415Z,1573598213.415 [DDM](INFO): Paused
2019-11-12T22:36:53.819Z,1573598213.819 [DDM](INFO): Paused
2019-11-12T22:36:54.222Z,1573598214.222 [DDM](INFO): Paused
2019-11-12T22:36:54.627Z,1573598214.627 [DDM](INFO): Paused
2019-11-12T22:36:55.031Z,1573598215.031 [DDM](INFO): Paused
2019-11-12T22:36:55.434Z,1573598215.434 [DDM](INFO): Paused
2019-11-12T22:36:55.839Z,1573598215.839 [DDM](INFO): Paused
2019-11-12T22:36:56.243Z,1573598216.243 [DDM](INFO): Paused
2019-11-12T22:36:56.646Z,1573598216.646 [DDM](INFO): Paused
2019-11-12T22:36:57.051Z,1573598217.051 [DDM](INFO): Paused
2019-11-12T22:36:57.455Z,1573598217.455 [DDM](INFO): Paused
2019-11-12T22:36:57.858Z,1573598217.858 [DDM](INFO): Paused
2019-11-12T22:36:58.263Z,1573598218.263 [DDM](INFO): Paused
2019-11-12T22:36:58.667Z,1573598218.667 [DDM](INFO): Paused
2019-11-12T22:36:59.070Z,1573598219.070 [DDM](INFO): Paused
2019-11-12T22:36:59.475Z,1573598219.475 [DDM](INFO): Paused
2019-11-12T22:36:59.878Z,1573598219.878 [DDM](INFO): Paused
2019-11-12T22:37:00.282Z,1573598220.282 [DDM](INFO): Paused
2019-11-12T22:37:00.687Z,1573598220.687 [DDM](INFO): Paused
2019-11-12T22:37:01.091Z,1573598221.091 [DDM](INFO): Paused
2019-11-12T22:37:01.494Z,1573598221.494 [DDM](INFO): Paused
2019-11-12T22:37:01.899Z,1573598221.899 [DDM](INFO): Paused
2019-11-12T22:37:02.303Z,1573598222.303 [DDM](INFO): Paused
2019-11-12T22:37:02.707Z,1573598222.707 [DDM](INFO): Paused
2019-11-12T22:37:03.112Z,1573598223.112 [DDM](INFO): Paused
2019-11-12T22:37:03.515Z,1573598223.515 [DDM](INFO): Paused
2019-11-12T22:37:03.918Z,1573598223.918 [DDM](INFO): Paused
2019-11-12T22:37:04.323Z,1573598224.323 [DDM](INFO): Paused
2019-11-12T22:37:04.726Z,1573598224.726 [DDM](INFO): Paused
2019-11-12T22:37:05.134Z,1573598225.134 [DDM](INFO): Paused
2019-11-12T22:37:05.541Z,1573598225.541 [DDM](INFO): Paused
2019-11-12T22:37:05.939Z,1573598225.939 [DDM](INFO): Paused
2019-11-12T22:37:06.342Z,1573598226.342 [DDM](INFO): Paused
2019-11-12T22:37:06.748Z,1573598226.748 [DDM](INFO): Paused
2019-11-12T22:37:07.154Z,1573598227.154 [DDM](INFO): Paused
2019-11-12T22:37:07.554Z,1573598227.554 [DDM](INFO): Paused
2019-11-12T22:37:07.959Z,1573598227.959 [DDM](INFO): Paused
2019-11-12T22:37:08.363Z,1573598228.363 [DDM](INFO): Paused
2019-11-12T22:37:08.766Z,1573598228.766 [DDM](INFO): Paused
2019-11-12T22:37:08.892Z,1573598228.892 [CommandLine](IMPORTANT): got command restart application
2019-11-12T22:37:09.171Z,1573598229.171 [DDM](INFO): Paused
2019-11-12T22:37:09.575Z,1573598229.575 [DDM](INFO): Paused
2019-11-12T22:37:09.895Z,1573598229.895 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-11-12T22:37:09.895Z,1573598229.895 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-12T22:37:09.895Z,1573598229.895 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:09.899Z,1573598229.899 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-11-12T22:37:09.899Z,1573598229.899 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:09.900Z,1573598229.900 [CommandLine](INFO): Join timeout helper Thread ID is 1381
2019-11-12T22:37:09.911Z,1573598229.911 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-11-12T22:37:09.911Z,1573598229.911 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:09.912Z,1573598229.912 [NavChartDb](INFO): Join timeout helper Thread ID is 1382
2019-11-12T22:37:09.979Z,1573598229.979 [DDM](INFO): Paused
2019-11-12T22:37:10.275Z,1573598230.275 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-12T22:37:10.276Z,1573598230.276 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:10.283Z,1573598230.283 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-11-12T22:37:10.283Z,1573598230.283 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:10.284Z,1573598230.284 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1383
2019-11-12T22:37:10.383Z,1573598230.383 [DDM](INFO): Paused
2019-11-12T22:37:10.607Z,1573598230.607 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-12T22:37:10.607Z,1573598230.607 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-11-12T22:37:10.608Z,1573598230.608 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:10.615Z,1573598230.615 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-11-12T22:37:10.615Z,1573598230.615 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:10.616Z,1573598230.616 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1384
2019-11-12T22:37:10.679Z,1573598230.679 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-12T22:37:10.680Z,1573598230.680 [CTD_NeilBrown](INFO): Powering down
2019-11-12T22:37:10.691Z,1573598230.691 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:10.706Z,1573598230.706 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-11-12T22:37:10.706Z,1573598230.706 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:10.706Z,1573598230.706 [Radio_Surface](INFO): Join timeout helper Thread ID is 1385
2019-11-12T22:37:10.787Z,1573598230.787 [DDM](INFO): Paused
2019-11-12T22:37:10.943Z,1573598230.943 [Radio_Surface](INFO): Powering down
2019-11-12T22:37:10.944Z,1573598230.944 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-12T22:37:10.944Z,1573598230.944 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:10.964Z,1573598230.964 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-11-12T22:37:10.964Z,1573598230.964 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:10.965Z,1573598230.965 [DataOverHttps](INFO): Join timeout helper Thread ID is 1386
2019-11-12T22:37:11.190Z,1573598231.190 [DDM](INFO): Paused
2019-11-12T22:37:11.595Z,1573598231.595 [DDM](INFO): Paused
2019-11-12T22:37:11.998Z,1573598231.998 [DDM](INFO): Paused
2019-11-12T22:37:12.402Z,1573598232.402 [DDM](INFO): Paused
2019-11-12T22:37:12.807Z,1573598232.807 [DDM](INFO): Paused
2019-11-12T22:37:13.211Z,1573598233.211 [DDM](INFO): Paused
2019-11-12T22:37:13.421Z,1573598233.421 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-12T22:37:13.424Z,1573598233.424 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:13.444Z,1573598233.444 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-11-12T22:37:13.444Z,1573598233.444 [logger ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:13.444Z,1573598233.444 [logger](INFO): Join timeout helper Thread ID is 1387
2019-11-12T22:37:13.459Z,1573598233.459 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-12T22:37:13.459Z,1573598233.459 [logger ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:13.464Z,1573598233.464 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-11-12T22:37:13.464Z,1573598233.464 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:13.464Z,1573598233.464 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-11-12T22:37:13.464Z,1573598233.464 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:13.465Z,1573598233.465 [controlThread](INFO): Join timeout helper Thread ID is 1388
2019-11-12T22:37:13.607Z,1573598233.607 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-12T22:37:13.607Z,1573598233.607 [controlThread](DEBUG): Uninitializing ControlThread
2019-11-12T22:37:13.608Z,1573598233.608 [AHRS_M2](INFO): Powering down
2019-11-12T22:37:13.679Z,1573598233.679 [DDM](INFO): uninitialize
2019-11-12T22:37:13.679Z,1573598233.679 [DDM](INFO): Powering down
2019-11-12T22:37:13.772Z,1573598233.772 [DUSBL_Hydroid](INFO): Powering down
2019-11-12T22:37:13.863Z,1573598233.863 [Micromodem](INFO): Powering down
2019-11-12T22:37:13.959Z,1573598233.959 [NAL9602](INFO): Powering down
2019-11-12T22:37:14.039Z,1573598234.039 [RDI_Pathfinder](INFO): Powering down
2019-11-12T22:37:14.041Z,1573598234.041 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-11-12T22:37:14.042Z,1573598234.042 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-11-12T22:37:14.042Z,1573598234.042 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-11-12T22:37:14.043Z,1573598234.043 [MissionManager](INFO): Uninitializing Mission Default
2019-11-12T22:37:14.043Z,1573598234.043 [Default] Stopped
2019-11-12T22:37:14.043Z,1573598234.043 [Default](DEBUG): Aggregate::uninitialize Default
2019-11-12T22:37:14.043Z,1573598234.043 [Default:B.GoToSurface] Stopped
2019-11-12T22:37:14.044Z,1573598234.044 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-11-12T22:37:14.044Z,1573598234.044 [Default:CheckIn] Stopped
2019-11-12T22:37:14.044Z,1573598234.044 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-12T22:37:14.044Z,1573598234.044 [Default:CheckIn:Read_GPS] Stopped
2019-11-12T22:37:14.046Z,1573598234.046 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-11-12T22:37:14.046Z,1573598234.046 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-11-12T22:37:14.047Z,1573598234.047 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-11-12T22:37:14.047Z,1573598234.047 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-11-12T22:37:14.048Z,1573598234.048 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-11-12T22:37:14.048Z,1573598234.048 [BuoyancyServo](INFO): Powering down
2019-11-12T22:37:14.059Z,1573598234.059 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-11-12T22:37:14.059Z,1573598234.059 [ElevatorServo](INFO): Powering down
2019-11-12T22:37:14.060Z,1573598234.060 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-11-12T22:37:14.060Z,1573598234.060 [MassServo](INFO): Powering down
2019-11-12T22:37:14.061Z,1573598234.061 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-11-12T22:37:14.061Z,1573598234.061 [RudderServo](INFO): Powering down
2019-11-12T22:37:14.062Z,1573598234.062 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-11-12T22:37:14.062Z,1573598234.062 [ThrusterServo](INFO): Powering down
2019-11-12T22:37:14.063Z,1573598234.063 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-11-12T22:37:14.063Z,1573598234.063 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-11-12T22:37:14.063Z,1573598234.063 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-11-12T22:37:14.063Z,1573598234.063 [CBIT](DEBUG): Powering off loads.
2019-11-12T22:37:14.075Z,1573598234.075 [CBIT](DEBUG): Disabling WDT.
2019-11-12T22:37:14.087Z,1573598234.087 [CBIT](DEBUG): Opening all GF detection circuits.
2019-11-12T22:37:14.088Z,1573598234.088 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:14.144Z,1573598234.144 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:14.154Z,1573598234.154 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:14.201Z,1573598234.201 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:14.204Z,1573598234.204 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:14.265Z,1573598234.265 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-12T22:37:14.320Z,1573598234.320 [logger ThreadHandler](INFO): Thread cancelled.