2019-02-08T00:08:15.242Z,1549584495.242 [Supervisor](DEBUG): Initializing supervisor. 2019-02-08T00:08:15.245Z,1549584495.245 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-02-08T00:08:15.245Z,1549584495.245 [SyncHandler](INFO): Protected caller Thread ID is 800 2019-02-08T00:08:15.246Z,1549584495.246 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-02-08T00:08:15.246Z,1549584495.246 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-02-08T00:08:15.247Z,1549584495.247 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 801 2019-02-08T00:08:15.249Z,1549584495.249 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-02-08T00:08:15.261Z,1549584495.261 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-02-08T00:08:15.262Z,1549584495.262 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-02-08T00:08:15.262Z,1549584495.262 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802 2019-02-08T00:08:15.263Z,1549584495.263 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-02-08T00:08:15.264Z,1549584495.264 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-02-08T00:08:15.264Z,1549584495.264 [logger ThreadHandler](INFO): Protected caller Thread ID is 803 2019-02-08T00:08:15.266Z,1549584495.266 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-02-08T00:08:15.266Z,1549584495.266 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-02-08T00:08:15.272Z,1549584495.272 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-02-08T00:08:15.621Z,1549584495.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-02-08T00:08:15.623Z,1549584495.623 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-02-08T00:08:16.069Z,1549584496.069 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-02-08T00:08:16.070Z,1549584496.070 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-02-08T00:08:16.394Z,1549584496.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-02-08T00:08:16.395Z,1549584496.395 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-02-08T00:08:16.587Z,1549584496.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-02-08T00:08:16.588Z,1549584496.588 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-02-08T00:08:17.030Z,1549584497.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-02-08T00:08:17.031Z,1549584497.031 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-02-08T00:08:17.507Z,1549584497.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-02-08T00:08:17.507Z,1549584497.507 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-02-08T00:08:17.612Z,1549584497.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-02-08T00:08:17.613Z,1549584497.613 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-02-08T00:08:17.806Z,1549584497.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-02-08T00:08:17.807Z,1549584497.807 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-02-08T00:08:17.951Z,1549584497.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-02-08T00:08:17.952Z,1549584497.952 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-02-08T00:08:18.033Z,1549584498.033 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-02-08T00:08:18.241Z,1549584498.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-02-08T00:08:18.241Z,1549584498.241 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-02-08T00:08:18.343Z,1549584498.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-02-08T00:08:18.344Z,1549584498.344 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-02-08T00:08:18.441Z,1549584498.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-02-08T00:08:18.441Z,1549584498.441 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-02-08T00:08:18.611Z,1549584498.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-02-08T00:08:18.611Z,1549584498.611 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-02-08T00:08:19.799Z,1549584499.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-02-08T00:08:19.801Z,1549584499.801 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2019-02-08T00:08:19.804Z,1549584499.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2019-02-08T00:08:19.939Z,1549584499.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2019-02-08T00:08:20.170Z,1549584500.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-02-08T00:08:20.170Z,1549584500.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2019-02-08T00:08:20.337Z,1549584500.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2019-02-08T00:08:20.438Z,1549584500.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2019-02-08T00:08:20.536Z,1549584500.536 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2019-02-08T00:08:20.731Z,1549584500.731 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2019-02-08T00:08:20.819Z,1549584500.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2019-02-08T00:08:20.916Z,1549584500.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2019-02-08T00:08:21.010Z,1549584501.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2019-02-08T00:08:21.093Z,1549584501.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2019-02-08T00:08:21.202Z,1549584501.202 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-02-08T00:08:21.210Z,1549584501.210 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-02-08T00:08:21.375Z,1549584501.375 [SBIT](DEBUG): Construct Startup Built In Test. 2019-02-08T00:08:21.406Z,1549584501.406 [SBIT] Loaded 2019-02-08T00:08:21.406Z,1549584501.406 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-02-08T00:08:21.407Z,1549584501.407 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-02-08T00:08:21.435Z,1549584501.435 [IBIT] Loaded 2019-02-08T00:08:21.435Z,1549584501.435 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-02-08T00:08:21.439Z,1549584501.439 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-02-08T00:08:21.830Z,1549584501.830 [CBIT] Loaded 2019-02-08T00:08:21.831Z,1549584501.831 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-02-08T00:08:21.831Z,1549584501.831 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-02-08T00:08:21.833Z,1549584501.833 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-02-08T00:08:21.983Z,1549584501.983 [BuoyancyServo] Loaded 2019-02-08T00:08:21.983Z,1549584501.983 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-02-08T00:08:21.998Z,1549584501.998 [ElevatorServo] Loaded 2019-02-08T00:08:21.998Z,1549584501.998 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-02-08T00:08:22.012Z,1549584502.012 [MassServo] Loaded 2019-02-08T00:08:22.013Z,1549584502.013 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-02-08T00:08:22.028Z,1549584502.028 [RudderServo] Loaded 2019-02-08T00:08:22.028Z,1549584502.028 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-02-08T00:08:22.043Z,1549584502.043 [ThrusterServo] Loaded 2019-02-08T00:08:22.043Z,1549584502.043 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-02-08T00:08:22.043Z,1549584502.043 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-02-08T00:08:22.044Z,1549584502.044 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-02-08T00:08:22.210Z,1549584502.210 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-02-08T00:08:22.210Z,1549584502.210 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-02-08T00:08:22.270Z,1549584502.270 [DeadReckonUsingSpeedCalculator] Loaded 2019-02-08T00:08:22.270Z,1549584502.270 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-02-08T00:08:22.330Z,1549584502.330 [DeadReckonWithRespectToSeafloor] Loaded 2019-02-08T00:08:22.330Z,1549584502.330 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-02-08T00:08:22.346Z,1549584502.346 [NavChart] Loaded 2019-02-08T00:08:22.347Z,1549584502.347 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-02-08T00:08:22.351Z,1549584502.351 [UniversalFixResidualReporter] Loaded 2019-02-08T00:08:22.351Z,1549584502.351 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-02-08T00:08:22.351Z,1549584502.351 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-02-08T00:08:22.353Z,1549584502.353 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-02-08T00:08:22.377Z,1549584502.377 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-02-08T00:08:22.378Z,1549584502.378 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-02-08T00:08:22.661Z,1549584502.661 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-02-08T00:08:22.661Z,1549584502.661 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-02-08T00:08:23.318Z,1549584503.318 [AHRS_M2] Loaded 2019-02-08T00:08:23.318Z,1549584503.318 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-02-08T00:08:23.452Z,1549584503.452 [DataOverHttps] Loaded 2019-02-08T00:08:23.452Z,1549584503.452 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2019-02-08T00:08:23.466Z,1549584503.466 [Depth_Keller] Loaded 2019-02-08T00:08:23.466Z,1549584503.466 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-02-08T00:08:23.471Z,1549584503.471 [DropWeight] Loaded 2019-02-08T00:08:23.471Z,1549584503.471 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-02-08T00:08:23.524Z,1549584503.524 [DUSBL_Hydroid] Loaded 2019-02-08T00:08:23.524Z,1549584503.524 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2019-02-08T00:08:23.615Z,1549584503.615 [NAL9602] Loaded 2019-02-08T00:08:23.615Z,1549584503.615 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-02-08T00:08:23.621Z,1549584503.621 [Onboard] Loaded 2019-02-08T00:08:23.621Z,1549584503.621 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-02-08T00:08:23.628Z,1549584503.628 [Radio_Surface] Loaded 2019-02-08T00:08:23.628Z,1549584503.628 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-02-08T00:08:23.629Z,1549584503.629 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408494E0 2019-02-08T00:08:23.629Z,1549584503.629 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883 2019-02-08T00:08:23.658Z,1549584503.658 [RDI_Pathfinder] Loaded 2019-02-08T00:08:23.658Z,1549584503.658 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-02-08T00:08:23.681Z,1549584503.681 [RDI_PathfinderUp] Loaded 2019-02-08T00:08:23.681Z,1549584503.681 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2019-02-08T00:08:25.255Z,1549584505.255 [BPC1] Loaded 2019-02-08T00:08:25.255Z,1549584505.255 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-02-08T00:08:25.256Z,1549584505.256 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-02-08T00:08:25.257Z,1549584505.257 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-02-08T00:08:25.788Z,1549584505.788 [VerticalControl](DEBUG): Construct VerticalControl. 2019-02-08T00:08:25.990Z,1549584505.990 [VerticalControl] Loaded 2019-02-08T00:08:25.990Z,1549584505.990 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-02-08T00:08:25.991Z,1549584505.991 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-02-08T00:08:26.049Z,1549584506.049 [HorizontalControl] Loaded 2019-02-08T00:08:26.050Z,1549584506.050 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-02-08T00:08:26.050Z,1549584506.050 [SpeedControl](DEBUG): Construct SpeedControl. 2019-02-08T00:08:26.052Z,1549584506.052 [SpeedControl] Loaded 2019-02-08T00:08:26.053Z,1549584506.053 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-02-08T00:08:26.053Z,1549584506.053 [LoopControl](DEBUG): Construct LoopControl. 2019-02-08T00:08:26.054Z,1549584506.054 [LoopControl] Loaded 2019-02-08T00:08:26.054Z,1549584506.054 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-02-08T00:08:26.055Z,1549584506.055 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-02-08T00:08:26.055Z,1549584506.055 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-02-08T00:08:26.185Z,1549584506.185 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-02-08T00:08:26.186Z,1549584506.186 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-02-08T00:08:26.426Z,1549584506.426 [Aanderaa_O2] Loaded 2019-02-08T00:08:26.427Z,1549584506.427 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-02-08T00:08:26.512Z,1549584506.512 [CTD_NeilBrown] Loaded 2019-02-08T00:08:26.512Z,1549584506.512 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-02-08T00:08:26.513Z,1549584506.513 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095A4E0 2019-02-08T00:08:26.513Z,1549584506.513 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 884 2019-02-08T00:08:26.559Z,1549584506.559 [WetLabsSeaOWL_UV_A] Loaded 2019-02-08T00:08:26.560Z,1549584506.560 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2019-02-08T00:08:26.561Z,1549584506.561 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0 2019-02-08T00:08:26.561Z,1549584506.561 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 885 2019-02-08T00:08:26.562Z,1549584506.562 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-02-08T00:08:26.563Z,1549584506.563 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-02-08T00:08:26.666Z,1549584506.666 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-02-08T00:08:26.667Z,1549584506.667 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-02-08T00:08:26.722Z,1549584506.722 [DepthRateCalculator] Loaded 2019-02-08T00:08:26.722Z,1549584506.722 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-02-08T00:08:26.728Z,1549584506.728 [PitchRateCalculator] Loaded 2019-02-08T00:08:26.728Z,1549584506.728 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-02-08T00:08:26.740Z,1549584506.740 [SpeedCalculator] Loaded 2019-02-08T00:08:26.740Z,1549584506.740 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-02-08T00:08:26.762Z,1549584506.762 [TempGradientCalculator] Loaded 2019-02-08T00:08:26.762Z,1549584506.762 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-02-08T00:08:26.767Z,1549584506.767 [YawRateCalculator] Loaded 2019-02-08T00:08:26.768Z,1549584506.768 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-02-08T00:08:26.798Z,1549584506.798 [ElevatorOffsetCalculator] Loaded 2019-02-08T00:08:26.798Z,1549584506.798 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-02-08T00:08:26.799Z,1549584506.799 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-02-08T00:08:26.799Z,1549584506.799 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-02-08T00:08:26.841Z,1549584506.841 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-02-08T00:08:26.845Z,1549584506.845 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-02-08T00:08:26.845Z,1549584506.845 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-02-08T00:08:26.852Z,1549584506.852 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-02-08T00:08:26.853Z,1549584506.853 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2019-02-08T00:08:26.853Z,1549584506.853 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 886 2019-02-08T00:08:26.858Z,1549584506.858 [Supervisor](INFO): Main Thread ID is 796 2019-02-08T00:08:26.858Z,1549584506.858 [Supervisor](DEBUG): Running supervisor. 2019-02-08T00:08:26.859Z,1549584506.859 [CommandLine ThreadHandler](INFO): Handler Thread ID is 887 2019-02-08T00:08:26.861Z,1549584506.861 [controlThread ThreadHandler](INFO): Handler Thread ID is 888 2019-02-08T00:08:26.861Z,1549584506.861 [controlThread](DEBUG): Initializing ControlThread 2019-02-08T00:08:26.862Z,1549584506.862 [SBIT](INFO): Initialize SBIT Component. 2019-02-08T00:08:26.863Z,1549584506.863 [SBIT](IMPORTANT): git: 2019-01-14A-54-g0d094c9 2019-02-08T00:08:26.863Z,1549584506.863 [SBIT](INFO): git hash: 0d094c9fe207dedbc4f0c5a93b42d412b4ef2ca6 2019-02-08T00:08:26.863Z,1549584506.863 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-02-08T00:08:26.863Z,1549584506.863 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2019-02-08T00:08:26.864Z,1549584506.864 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-02-08T00:08:26.865Z,1549584506.865 [IBIT](INFO): Initialize IBIT Component. 2019-02-08T00:08:26.866Z,1549584506.866 [CBIT](DEBUG): Initialize CBIT Component. 2019-02-08T00:08:26.867Z,1549584506.867 [logger ThreadHandler](INFO): Handler Thread ID is 889 2019-02-08T00:08:26.877Z,1549584506.877 [CBIT](DEBUG): Initialized mux pins. 2019-02-08T00:08:26.877Z,1549584506.877 [CBIT](DEBUG): Initializing the watchdog timer. 2019-02-08T00:08:26.885Z,1549584506.885 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 890 2019-02-08T00:08:26.891Z,1549584506.891 [Radio_Surface](INFO): Powering up 2019-02-08T00:08:26.897Z,1549584506.897 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 891 2019-02-08T00:08:26.898Z,1549584506.898 [CTD_NeilBrown](INFO): Powering down 2019-02-08T00:08:26.901Z,1549584506.901 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-02-08T00:08:26.901Z,1549584506.901 [CBIT](DEBUG): Initializing heartbeat. 2019-02-08T00:08:26.933Z,1549584506.933 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 892 2019-02-08T00:08:26.934Z,1549584506.934 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-02-08T00:08:26.969Z,1549584506.969 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 893 2019-02-08T00:08:26.973Z,1549584506.973 [CBIT](DEBUG): Deactivating GF circuits. 2019-02-08T00:08:26.973Z,1549584506.973 [CBIT](DEBUG): Deactivating emergency mode. 2019-02-08T00:08:26.979Z,1549584506.979 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-08T00:08:26.980Z,1549584506.980 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-02-08T00:08:26.980Z,1549584506.980 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-08T00:08:26.980Z,1549584506.980 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-02-08T00:08:26.980Z,1549584506.980 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-08T00:08:26.980Z,1549584506.980 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-02-08T00:08:26.981Z,1549584506.981 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-08T00:08:26.981Z,1549584506.981 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-02-08T00:08:26.981Z,1549584506.981 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-08T00:08:26.981Z,1549584506.981 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-02-08T00:08:26.981Z,1549584506.981 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-08T00:08:26.981Z,1549584506.981 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-02-08T00:08:26.982Z,1549584506.982 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-08T00:08:26.982Z,1549584506.982 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-02-08T00:08:26.982Z,1549584506.982 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-08T00:08:26.982Z,1549584506.982 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-02-08T00:08:27.010Z,1549584507.010 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-08T00:08:27.011Z,1549584507.011 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-02-08T00:08:27.012Z,1549584507.012 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-02-08T00:08:27.020Z,1549584507.020 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-02-08T00:08:27.030Z,1549584507.030 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-02-08T00:08:27.030Z,1549584507.030 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-02-08T00:08:27.031Z,1549584507.031 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-02-08T00:08:27.031Z,1549584507.031 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-02-08T00:08:27.032Z,1549584507.032 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-02-08T00:08:27.032Z,1549584507.032 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-02-08T00:08:27.033Z,1549584507.033 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-02-08T00:08:27.033Z,1549584507.033 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-02-08T00:08:27.034Z,1549584507.034 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-02-08T00:08:27.035Z,1549584507.035 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-02-08T00:08:27.085Z,1549584507.085 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-08T00:08:27.104Z,1549584507.104 [MissionManager](DEBUG): 2019-02-08T00:08:27.129Z,1549584507.129 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-02-08T00:08:27.197Z,1549584507.197 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-02-08T00:08:27.198Z,1549584507.198 [Default:A.Wait](DEBUG): Construct Wait. 2019-02-08T00:08:27.200Z,1549584507.200 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-08T00:08:27.250Z,1549584507.250 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-02-08T00:08:27.252Z,1549584507.252 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-02-08T00:08:27.274Z,1549584507.274 [Default:E.Execute](DEBUG): Construct Execute. 2019-02-08T00:08:27.277Z,1549584507.277 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-02-08T00:08:27.283Z,1549584507.283 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,DUSBL_Hydroid,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-02-08T00:08:27.329Z,1549584507.329 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-02-08T00:08:27.438Z,1549584507.438 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-02-08T00:08:27.454Z,1549584507.454 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2019-02-08T00:08:27.478Z,1549584507.478 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:08:27.478Z,1549584507.478 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:08:27.478Z,1549584507.478 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:08:27.649Z,1549584507.649 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-08T00:08:27.893Z,1549584507.893 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:08:27.901Z,1549584507.901 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-08T00:08:27.923Z,1549584507.923 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:08:27.929Z,1549584507.929 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-02-08T00:08:27.943Z,1549584507.943 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:08:27.949Z,1549584507.949 [MassServo](DEBUG): Initializing MassServo. 2019-02-08T00:08:27.955Z,1549584507.955 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:08:27.961Z,1549584507.961 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-08T00:08:27.971Z,1549584507.971 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:08:27.977Z,1549584507.977 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-02-08T00:08:28.095Z,1549584508.095 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:28.185Z,1549584508.185 [RDI_PathfinderUp](INFO): Powering down 2019-02-08T00:08:28.237Z,1549584508.237 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-08T00:08:28.446Z,1549584508.446 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:28.465Z,1549584508.465 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-08T00:08:28.817Z,1549584508.817 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2019-02-08T00:08:28.817Z,1549584508.817 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2019-02-08T00:08:28.817Z,1549584508.817 [BuoyancyServo] Communications Fault, FailCount= 1 2019-02-08T00:08:28.817Z,1549584508.817 [BuoyancyServo](ERROR): Communications Fault 2019-02-08T00:08:29.017Z,1549584509.017 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2019-02-08T00:08:29.027Z,1549584509.027 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:29.430Z,1549584509.430 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:29.830Z,1549584509.830 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:30.230Z,1549584510.230 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:30.698Z,1549584510.698 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:31.054Z,1549584511.054 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:31.189Z,1549584511.189 [Aanderaa_O2](INFO): Powering down 2019-02-08T00:08:31.281Z,1549584511.281 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2019-02-08T00:08:31.281Z,1549584511.281 [BuoyancyServo] No Fault, FailCount= 1 2019-02-08T00:08:31.454Z,1549584511.454 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:31.491Z,1549584511.491 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:08:31.610Z,1549584511.610 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-08T00:08:31.922Z,1549584511.922 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:32.330Z,1549584512.330 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:32.730Z,1549584512.730 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:33.130Z,1549584513.130 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:33.530Z,1549584513.530 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:33.942Z,1549584513.942 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:34.342Z,1549584514.342 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:34.754Z,1549584514.754 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:35.142Z,1549584515.142 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:35.531Z,1549584515.531 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:35.934Z,1549584515.934 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:36.323Z,1549584516.323 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:36.723Z,1549584516.723 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:37.110Z,1549584517.110 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:37.521Z,1549584517.521 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:37.922Z,1549584517.922 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:38.310Z,1549584518.310 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:38.713Z,1549584518.713 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:39.110Z,1549584519.110 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:39.522Z,1549584519.522 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:39.910Z,1549584519.910 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:40.322Z,1549584520.322 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:40.726Z,1549584520.726 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:40.785Z,1549584520.785 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-02-08T00:08:41.113Z,1549584521.113 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:41.515Z,1549584521.515 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:41.914Z,1549584521.914 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:42.314Z,1549584522.314 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:42.714Z,1549584522.714 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:43.113Z,1549584523.113 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:43.512Z,1549584523.512 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:43.912Z,1549584523.912 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:44.311Z,1549584524.311 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:44.716Z,1549584524.716 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:45.114Z,1549584525.114 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:45.514Z,1549584525.514 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:45.926Z,1549584525.926 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:46.318Z,1549584526.318 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:46.718Z,1549584526.718 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:47.114Z,1549584527.114 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:47.134Z,1549584527.134 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-02-08T00:08:47.134Z,1549584527.134 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19020717154616,35.0, -0.,1448.9, 0 2019-02-08T00:08:47.518Z,1549584527.518 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:47.519Z,1549584527.519 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:08:47.519Z,1549584527.519 [DUSBL_Hydroid] Communications Fault, FailCount= 1 2019-02-08T00:08:47.519Z,1549584527.519 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:08:47.570Z,1549584527.570 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:08:47.914Z,1549584527.914 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:08:47.914Z,1549584527.914 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:08:47.914Z,1549584527.914 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:08:48.314Z,1549584528.314 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:08:48.716Z,1549584528.716 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:08:48.808Z,1549584528.808 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:08:48.808Z,1549584528.808 [DUSBL_Hydroid] No Fault, FailCount= 1 2019-02-08T00:08:49.114Z,1549584529.114 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:08:49.526Z,1549584529.526 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:08:49.914Z,1549584529.914 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:08:50.314Z,1549584530.314 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:08:50.712Z,1549584530.712 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:08:51.114Z,1549584531.114 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:08:51.114Z,1549584531.114 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:08:51.115Z,1549584531.115 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:08:51.510Z,1549584531.510 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:51.910Z,1549584531.910 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:52.310Z,1549584532.310 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:52.712Z,1549584532.712 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:53.110Z,1549584533.110 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:53.510Z,1549584533.510 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:53.910Z,1549584533.910 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:53.911Z,1549584533.911 [NAL9602](INFO): Powering up NAL9602 2019-02-08T00:08:54.310Z,1549584534.310 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:54.711Z,1549584534.711 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:55.110Z,1549584535.110 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:55.510Z,1549584535.510 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:55.544Z,1549584535.544 [SBIT](IMPORTANT): Beginning Startup BIT 2019-02-08T00:08:55.552Z,1549584535.552 [CBIT](IMPORTANT): Beginning ground fault scan 2019-02-08T00:08:55.918Z,1549584535.918 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:56.310Z,1549584536.310 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:56.712Z,1549584536.712 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:57.110Z,1549584537.110 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:57.510Z,1549584537.510 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:58.034Z,1549584538.034 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:58.442Z,1549584538.442 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:58.846Z,1549584538.846 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:59.234Z,1549584539.234 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:08:59.652Z,1549584539.652 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:00.048Z,1549584540.048 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:00.434Z,1549584540.434 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:00.834Z,1549584540.834 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:01.234Z,1549584541.234 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:01.634Z,1549584541.634 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:02.034Z,1549584542.034 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:02.434Z,1549584542.434 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:02.834Z,1549584542.834 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:03.234Z,1549584543.234 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:03.634Z,1549584543.634 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:04.034Z,1549584544.034 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:04.434Z,1549584544.434 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:04.850Z,1549584544.850 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:04.851Z,1549584544.851 [NAL9602](INFO): NAL9602 initialized 2019-02-08T00:09:05.234Z,1549584545.234 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:05.642Z,1549584545.642 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:05.672Z,1549584545.672 [NAL9602](DEBUG): Fix Requested 2019-02-08T00:09:06.038Z,1549584546.038 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:06.434Z,1549584546.434 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:06.594Z,1549584546.594 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.008524 CHAN A1 (24V): -0.174004 CHAN A2 (12V): -0.006105 CHAN A3 (5V): -0.002571 CHAN B0 (3.3V): -0.000219 CHAN B1 (3.15aV): -0.000755 CHAN B2 (3.15bV): 0.000010 CHAN B3 (GND): 0.000546 OPEN: 0.009210 Full Scale Calc: 4.765 mA, -1.589 mA 2019-02-08T00:09:06.834Z,1549584546.834 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:07.238Z,1549584547.238 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:07.634Z,1549584547.634 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:08.034Z,1549584548.034 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:08.434Z,1549584548.434 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:08.834Z,1549584548.834 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:09.234Z,1549584549.234 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:09.638Z,1549584549.638 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:10.034Z,1549584550.034 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:10.434Z,1549584550.434 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:10.847Z,1549584550.847 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:11.500Z,1549584551.500 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:11.500Z,1549584551.500 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:09:11.500Z,1549584551.500 [DUSBL_Hydroid] Communications Fault, FailCount= 2 2019-02-08T00:09:11.500Z,1549584551.500 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:09:11.667Z,1549584551.667 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:09:11.882Z,1549584551.882 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:09:11.882Z,1549584551.882 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:09:11.882Z,1549584551.882 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:09:12.287Z,1549584552.287 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:09:12.686Z,1549584552.686 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:09:12.890Z,1549584552.890 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:09:12.890Z,1549584552.890 [DUSBL_Hydroid] No Fault, FailCount= 2 2019-02-08T00:09:13.086Z,1549584553.086 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:13.486Z,1549584553.486 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:13.886Z,1549584553.886 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:14.278Z,1549584554.278 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:14.678Z,1549584554.678 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:15.094Z,1549584555.094 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:15.094Z,1549584555.094 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:09:15.095Z,1549584555.095 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:09:15.486Z,1549584555.486 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:15.878Z,1549584555.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:16.278Z,1549584556.278 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:16.678Z,1549584556.678 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:17.078Z,1549584557.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:17.478Z,1549584557.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:17.878Z,1549584557.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:18.286Z,1549584558.286 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:18.678Z,1549584558.678 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:19.078Z,1549584559.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:19.478Z,1549584559.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:19.882Z,1549584559.882 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:20.290Z,1549584560.290 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:20.686Z,1549584560.686 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:21.078Z,1549584561.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:21.478Z,1549584561.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:21.878Z,1549584561.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:22.278Z,1549584562.278 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:22.705Z,1549584562.705 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:23.105Z,1549584563.105 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:23.484Z,1549584563.484 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:23.892Z,1549584563.892 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:24.295Z,1549584564.295 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:24.678Z,1549584564.678 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:25.086Z,1549584565.086 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:25.478Z,1549584565.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:25.878Z,1549584565.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:26.278Z,1549584566.278 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:26.678Z,1549584566.678 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:27.078Z,1549584567.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:27.486Z,1549584567.486 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:27.882Z,1549584567.882 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:28.278Z,1549584568.278 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:28.682Z,1549584568.682 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:29.078Z,1549584569.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:29.482Z,1549584569.482 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:29.888Z,1549584569.888 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:30.282Z,1549584570.282 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:30.688Z,1549584570.688 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:31.082Z,1549584571.082 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:31.483Z,1549584571.483 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:31.878Z,1549584571.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:32.278Z,1549584572.278 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:32.678Z,1549584572.678 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:33.078Z,1549584573.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:33.478Z,1549584573.478 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:33.878Z,1549584573.878 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:34.275Z,1549584574.275 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:34.686Z,1549584574.686 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:35.086Z,1549584575.086 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:35.482Z,1549584575.482 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:35.483Z,1549584575.483 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:09:35.483Z,1549584575.483 [DUSBL_Hydroid] Communications Fault, FailCount= 3 2019-02-08T00:09:35.483Z,1549584575.483 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:09:35.552Z,1549584575.552 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:09:35.878Z,1549584575.878 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:09:35.878Z,1549584575.878 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:09:35.878Z,1549584575.878 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:09:36.278Z,1549584576.278 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:09:36.678Z,1549584576.678 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:09:36.764Z,1549584576.764 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:09:36.764Z,1549584576.764 [DUSBL_Hydroid] No Fault, FailCount= 3 2019-02-08T00:09:37.086Z,1549584577.086 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:37.482Z,1549584577.482 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:37.878Z,1549584577.878 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:38.413Z,1549584578.413 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:38.806Z,1549584578.806 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:39.206Z,1549584579.206 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:09:39.206Z,1549584579.206 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:09:39.207Z,1549584579.207 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:09:39.606Z,1549584579.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:40.006Z,1549584580.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:40.406Z,1549584580.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:40.810Z,1549584580.810 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:41.206Z,1549584581.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:41.610Z,1549584581.610 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:42.010Z,1549584582.010 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:42.406Z,1549584582.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:42.810Z,1549584582.810 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:43.210Z,1549584583.210 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:43.606Z,1549584583.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:44.006Z,1549584584.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:44.410Z,1549584584.410 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:44.806Z,1549584584.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:45.206Z,1549584585.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:45.606Z,1549584585.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:46.006Z,1549584586.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:46.410Z,1549584586.410 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:46.806Z,1549584586.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:47.210Z,1549584587.210 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:47.606Z,1549584587.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:48.006Z,1549584588.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:48.406Z,1549584588.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:48.806Z,1549584588.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:49.214Z,1549584589.214 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:49.606Z,1549584589.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:49.680Z,1549584589.680 [SBIT](IMPORTANT): SBIT PASSED 2019-02-08T00:09:49.755Z,1549584589.755 [CommandLine](IMPORTANT): got command configSet list 2019-02-08T00:09:49.755Z,1549584589.755 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-02-08T00:09:49.758Z,1549584589.758 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool; 2019-02-08T00:09:49.758Z,1549584589.758 [CommandLine](IMPORTANT): NAL9602.requestGGA=1 bool; 2019-02-08T00:09:50.006Z,1549584590.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:50.077Z,1549584590.077 [MissionManager](IMPORTANT): Started mission Startup 2019-02-08T00:09:50.078Z,1549584590.078 [Startup] Running Loop=1 2019-02-08T00:09:50.078Z,1549584590.078 [Startup](DEBUG): Aggregate::initialize Startup 2019-02-08T00:09:50.078Z,1549584590.078 [Startup:A.GoToSurface] Running Loop=1 2019-02-08T00:09:50.078Z,1549584590.078 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-08T00:09:50.079Z,1549584590.079 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-08T00:09:50.079Z,1549584590.079 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-08T00:09:50.079Z,1549584590.079 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-08T00:09:50.080Z,1549584590.080 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-08T00:09:50.080Z,1549584590.080 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-08T00:09:50.086Z,1549584590.086 [Startup:StartupSatComms] Running Loop=1 2019-02-08T00:09:50.086Z,1549584590.086 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-02-08T00:09:50.086Z,1549584590.086 [Startup:StartupSatComms:A] Running Loop=1 2019-02-08T00:09:50.406Z,1549584590.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:50.438Z,1549584590.438 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-02-08T00:09:50.815Z,1549584590.815 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:51.206Z,1549584591.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:51.614Z,1549584591.614 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:52.006Z,1549584592.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:52.406Z,1549584592.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:52.806Z,1549584592.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:53.206Z,1549584593.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:53.606Z,1549584593.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:54.010Z,1549584594.010 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:54.406Z,1549584594.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:54.806Z,1549584594.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:55.206Z,1549584595.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:55.610Z,1549584595.610 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:56.010Z,1549584596.010 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:56.410Z,1549584596.410 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:56.807Z,1549584596.807 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:57.210Z,1549584597.210 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:57.606Z,1549584597.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:58.006Z,1549584598.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:58.406Z,1549584598.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:58.810Z,1549584598.810 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:59.214Z,1549584599.214 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:09:59.215Z,1549584599.215 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:09:59.215Z,1549584599.215 [DUSBL_Hydroid] Communications Fault, FailCount= 4 2019-02-08T00:09:59.215Z,1549584599.215 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:09:59.296Z,1549584599.296 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:09:59.606Z,1549584599.606 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:09:59.606Z,1549584599.606 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:09:59.606Z,1549584599.606 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:10:00.010Z,1549584600.010 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:10:00.410Z,1549584600.410 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:10:00.460Z,1549584600.460 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:10:00.460Z,1549584600.460 [DUSBL_Hydroid] No Fault, FailCount= 4 2019-02-08T00:10:00.806Z,1549584600.806 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:01.206Z,1549584601.206 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:01.606Z,1549584601.606 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:02.006Z,1549584602.006 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:02.416Z,1549584602.416 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:02.810Z,1549584602.810 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:02.810Z,1549584602.810 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:10:02.811Z,1549584602.811 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:10:03.206Z,1549584603.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:03.610Z,1549584603.610 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:04.010Z,1549584604.010 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:04.407Z,1549584604.407 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:04.811Z,1549584604.811 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:05.206Z,1549584605.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:05.606Z,1549584605.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:06.007Z,1549584606.007 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:06.410Z,1549584606.410 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:06.806Z,1549584606.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:07.214Z,1549584607.214 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:07.610Z,1549584607.610 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:08.006Z,1549584608.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:08.406Z,1549584608.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:08.409Z,1549584608.409 [NAL9602](INFO): SBD MO Status=2, MOMSN=2138, MT Status=2, MTMSN=0 2019-02-08T00:10:08.409Z,1549584608.409 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-02-08T00:10:08.810Z,1549584608.810 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:09.206Z,1549584609.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:09.606Z,1549584609.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:10.010Z,1549584610.010 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:10.406Z,1549584610.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:10.806Z,1549584610.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:11.206Z,1549584611.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:11.606Z,1549584611.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:12.018Z,1549584612.018 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:12.410Z,1549584612.410 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:12.807Z,1549584612.807 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:13.206Z,1549584613.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:13.610Z,1549584613.610 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:14.006Z,1549584614.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:14.406Z,1549584614.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:14.806Z,1549584614.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:15.206Z,1549584615.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:15.607Z,1549584615.607 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:16.006Z,1549584616.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:16.406Z,1549584616.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:16.810Z,1549584616.810 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:17.214Z,1549584617.214 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:17.606Z,1549584617.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:18.006Z,1549584618.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:18.410Z,1549584618.410 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:18.806Z,1549584618.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:19.210Z,1549584619.210 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:19.606Z,1549584619.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:20.006Z,1549584620.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:20.406Z,1549584620.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:20.806Z,1549584620.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:21.206Z,1549584621.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:21.610Z,1549584621.610 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:22.006Z,1549584622.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:22.410Z,1549584622.410 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:22.806Z,1549584622.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:23.210Z,1549584623.210 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:23.211Z,1549584623.211 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:10:23.211Z,1549584623.211 [DUSBL_Hydroid] Communications Fault, FailCount= 5 2019-02-08T00:10:23.211Z,1549584623.211 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:10:23.365Z,1549584623.365 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:10:23.606Z,1549584623.606 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:10:23.606Z,1549584623.606 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:10:23.606Z,1549584623.606 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:10:24.006Z,1549584624.006 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:10:24.410Z,1549584624.410 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:10:24.479Z,1549584624.479 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:10:24.479Z,1549584624.479 [DUSBL_Hydroid] No Fault, FailCount= 5 2019-02-08T00:10:24.815Z,1549584624.815 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:25.206Z,1549584625.206 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:25.606Z,1549584625.606 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:26.006Z,1549584626.006 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:26.406Z,1549584626.406 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:26.810Z,1549584626.810 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:26.810Z,1549584626.810 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:10:26.811Z,1549584626.811 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:10:27.206Z,1549584627.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:27.606Z,1549584627.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:28.006Z,1549584628.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:28.416Z,1549584628.416 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:28.810Z,1549584628.810 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:29.214Z,1549584629.214 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:29.606Z,1549584629.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:30.006Z,1549584630.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:30.406Z,1549584630.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:30.810Z,1549584630.810 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:31.206Z,1549584631.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:31.606Z,1549584631.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:32.010Z,1549584632.010 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:32.406Z,1549584632.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:32.806Z,1549584632.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:33.211Z,1549584633.211 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:33.610Z,1549584633.610 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:34.003Z,1549584634.003 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:34.406Z,1549584634.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:34.810Z,1549584634.810 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:35.218Z,1549584635.218 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:35.606Z,1549584635.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:36.006Z,1549584636.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:36.406Z,1549584636.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:36.806Z,1549584636.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:37.206Z,1549584637.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:37.610Z,1549584637.610 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:38.006Z,1549584638.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:38.406Z,1549584638.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:38.806Z,1549584638.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:39.206Z,1549584639.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:39.606Z,1549584639.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:40.006Z,1549584640.006 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:40.406Z,1549584640.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:40.806Z,1549584640.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:41.206Z,1549584641.206 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:41.606Z,1549584641.606 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:42.014Z,1549584642.014 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:42.416Z,1549584642.416 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:42.817Z,1549584642.817 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:43.210Z,1549584643.210 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:43.607Z,1549584643.607 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:44.011Z,1549584644.011 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:44.406Z,1549584644.406 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:44.806Z,1549584644.806 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:45.207Z,1549584645.207 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:45.615Z,1549584645.615 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:48.834Z,1549584648.834 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:48.835Z,1549584648.835 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:10:48.835Z,1549584648.835 [DUSBL_Hydroid] Communications Fault, FailCount= 6 2019-02-08T00:10:48.835Z,1549584648.835 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:10:49.074Z,1549584649.074 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:10:49.146Z,1549584649.146 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:10:49.146Z,1549584649.146 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:10:49.146Z,1549584649.146 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:10:49.494Z,1549584649.494 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:10:49.890Z,1549584649.890 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004721 2019-02-08T00:10:49.898Z,1549584649.898 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:10:50.028Z,1549584650.028 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:10:50.033Z,1549584650.033 [DUSBL_Hydroid] No Fault, FailCount= 6 2019-02-08T00:10:50.290Z,1549584650.290 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:50.338Z,1549584650.338 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-08T00:09:50.1Z 2019-02-08T00:10:50.338Z,1549584650.338 [Startup:StartupSatComms:A] Stopped 2019-02-08T00:10:50.338Z,1549584650.338 [Startup:StartupSatComms:B] Running Loop=1 2019-02-08T00:10:50.694Z,1549584650.694 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:50.769Z,1549584650.769 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-08T00:10:51.105Z,1549584651.105 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:51.494Z,1549584651.494 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:54.778Z,1549584654.778 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:10:54.779Z,1549584654.779 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:10:54.779Z,1549584654.779 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:10:54.941Z,1549584654.941 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190208T000349/Courier0004.lzma 2019-02-08T00:10:54.945Z,1549584654.945 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:55.318Z,1549584655.318 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:55.730Z,1549584655.730 [DataOverHttps](INFO): Moved sent file to Logs/20190208T000349/Courier0004.lzma.bak 2019-02-08T00:10:55.731Z,1549584655.731 [DataOverHttps](INFO): SBD MOMSN=9641448 2019-02-08T00:10:55.774Z,1549584655.774 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:56.149Z,1549584656.149 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:56.518Z,1549584656.518 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:59.752Z,1549584659.752 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:10:59.910Z,1549584659.910 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:00.290Z,1549584660.290 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:00.694Z,1549584660.694 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:01.086Z,1549584661.086 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:01.490Z,1549584661.490 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:05.419Z,1549584665.419 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:05.646Z,1549584665.646 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20190208T000349/Express0005.lzma 2019-02-08T00:11:05.659Z,1549584665.659 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:06.031Z,1549584666.031 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:06.423Z,1549584666.423 [DataOverHttps](INFO): Moved sent file to Logs/20190208T000349/Express0005.lzma.bak 2019-02-08T00:11:06.424Z,1549584666.424 [DataOverHttps](INFO): SBD MOMSN=9641450 2019-02-08T00:11:06.431Z,1549584666.431 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:06.838Z,1549584666.838 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:07.226Z,1549584667.226 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:12.041Z,1549584672.041 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:12.239Z,1549584672.239 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:12.587Z,1549584672.587 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:12.986Z,1549584672.986 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:13.378Z,1549584673.378 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:13.786Z,1549584673.786 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:16.993Z,1549584676.993 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:16.993Z,1549584676.993 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:11:16.993Z,1549584676.993 [DUSBL_Hydroid] Communications Fault, FailCount= 7 2019-02-08T00:11:16.993Z,1549584676.993 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:11:17.146Z,1549584677.146 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:11:17.207Z,1549584677.207 [DataOverHttps](INFO): Sending 797 bytes from file Logs/20190208T000815/Express0001.lzma 2019-02-08T00:11:17.231Z,1549584677.231 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:11:17.231Z,1549584677.231 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:11:17.231Z,1549584677.231 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:11:17.572Z,1549584677.572 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:11:17.958Z,1549584677.958 [DataOverHttps](INFO): Moved sent file to Logs/20190208T000815/Express0001.lzma.bak 2019-02-08T00:11:17.958Z,1549584677.958 [DataOverHttps](INFO): SBD MOMSN=9641455 2019-02-08T00:11:17.991Z,1549584677.991 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:11:18.061Z,1549584678.061 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:11:18.061Z,1549584678.061 [DUSBL_Hydroid] No Fault, FailCount= 7 2019-02-08T00:11:18.384Z,1549584678.384 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:11:18.410Z,1549584678.410 [Startup:StartupSatComms:B] Stopped 2019-02-08T00:11:18.410Z,1549584678.410 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-02-08T00:11:18.410Z,1549584678.410 [Startup:StartupSatComms] Stopped 2019-02-08T00:11:18.410Z,1549584678.410 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-02-08T00:11:18.411Z,1549584678.411 [Startup](INFO): Completed Startup 2019-02-08T00:11:18.411Z,1549584678.411 [MissionManager](INFO): Startup is completed. 2019-02-08T00:11:18.412Z,1549584678.412 [MissionManager](INFO): Uninitializing Mission Startup 2019-02-08T00:11:18.412Z,1549584678.412 [Startup] Stopped 2019-02-08T00:11:18.412Z,1549584678.412 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-02-08T00:11:18.412Z,1549584678.412 [Startup:A.GoToSurface] Stopped 2019-02-08T00:11:18.412Z,1549584678.412 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-08T00:11:18.774Z,1549584678.774 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:11:18.837Z,1549584678.837 [MissionManager](IMPORTANT): Started mission Default 2019-02-08T00:11:18.838Z,1549584678.838 [Default] Running Loop=1 2019-02-08T00:11:18.838Z,1549584678.838 [Default](DEBUG): Aggregate::initialize Default 2019-02-08T00:11:18.838Z,1549584678.838 [Default:B.GoToSurface] Running Loop=1 2019-02-08T00:11:18.838Z,1549584678.838 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-08T00:11:18.838Z,1549584678.838 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-08T00:11:18.839Z,1549584678.839 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-08T00:11:18.839Z,1549584678.839 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-08T00:11:18.839Z,1549584678.839 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-08T00:11:18.840Z,1549584678.840 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-08T00:11:18.840Z,1549584678.840 [Default:A.Wait] Running Loop=1 2019-02-08T00:11:18.840Z,1549584678.840 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-02-08T00:11:21.959Z,1549584681.959 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:11:21.959Z,1549584681.959 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:11:21.960Z,1549584681.960 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:11:22.140Z,1549584682.140 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:22.507Z,1549584682.507 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:22.906Z,1549584682.906 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:23.308Z,1549584683.308 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:23.706Z,1549584683.706 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:24.098Z,1549584684.098 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:24.502Z,1549584684.502 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:24.902Z,1549584684.902 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:25.298Z,1549584685.298 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:25.698Z,1549584685.698 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:26.098Z,1549584686.098 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:26.498Z,1549584686.498 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:26.898Z,1549584686.898 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:27.294Z,1549584687.294 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:27.342Z,1549584687.342 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-02-08T00:11:27.342Z,1549584687.342 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-08T00:11:27.343Z,1549584687.343 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-02-08T00:11:27.343Z,1549584687.343 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-08T00:11:27.345Z,1549584687.345 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-02-08T00:11:27.345Z,1549584687.345 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-08T00:11:27.370Z,1549584687.370 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-08T00:11:27.370Z,1549584687.370 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-08T00:11:27.371Z,1549584687.371 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-08T00:11:27.694Z,1549584687.694 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:27.805Z,1549584687.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-08T00:11:27.805Z,1549584687.805 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-02-08T00:11:27.805Z,1549584687.805 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-08T00:11:27.805Z,1549584687.805 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-02-08T00:11:27.806Z,1549584687.806 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-08T00:11:27.806Z,1549584687.806 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-02-08T00:11:28.098Z,1549584688.098 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:28.147Z,1549584688.147 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-08T00:11:28.494Z,1549584688.494 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:28.898Z,1549584688.898 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:29.290Z,1549584689.290 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:29.691Z,1549584689.691 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:30.090Z,1549584690.090 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:30.490Z,1549584690.490 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:30.894Z,1549584690.894 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:31.290Z,1549584691.290 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:31.690Z,1549584691.690 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:32.094Z,1549584692.094 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:32.151Z,1549584692.151 [Default:A.Wait](INFO): Done Waiting. 2019-02-08T00:11:32.152Z,1549584692.152 [Default:A.Wait] Stopped 2019-02-08T00:11:32.152Z,1549584692.152 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-02-08T00:11:32.490Z,1549584692.490 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:32.543Z,1549584692.543 [Default:CheckIn] Running Loop=1 2019-02-08T00:11:32.543Z,1549584692.543 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-02-08T00:11:32.543Z,1549584692.543 [Default:CheckIn:Read_GPS] Running Loop=1 2019-02-08T00:11:32.890Z,1549584692.890 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:32.947Z,1549584692.947 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-02-08T00:11:33.290Z,1549584693.290 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:33.690Z,1549584693.690 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:34.102Z,1549584694.102 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:34.498Z,1549584694.498 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:34.890Z,1549584694.890 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:35.290Z,1549584695.290 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:35.690Z,1549584695.690 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:36.090Z,1549584696.090 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:36.490Z,1549584696.490 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:36.891Z,1549584696.891 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:37.299Z,1549584697.299 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:37.694Z,1549584697.694 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:38.094Z,1549584698.094 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:38.490Z,1549584698.490 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:38.890Z,1549584698.890 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:39.294Z,1549584699.294 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:39.690Z,1549584699.690 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:40.090Z,1549584700.090 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:40.494Z,1549584700.494 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:40.894Z,1549584700.894 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:41.300Z,1549584701.300 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:41.698Z,1549584701.698 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:42.090Z,1549584702.090 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:11:42.091Z,1549584702.091 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:11:42.091Z,1549584702.091 [DUSBL_Hydroid] Communications Fault, FailCount= 8 2019-02-08T00:11:42.091Z,1549584702.091 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:11:42.202Z,1549584702.202 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:11:42.202Z,1549584702.202 [CBIT](CRITICAL): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:11:42.490Z,1549584702.490 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:11:42.490Z,1549584702.490 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:11:42.490Z,1549584702.490 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:11:42.661Z,1549584702.661 [CommandLine](FAULT): Scheduling is paused 2019-02-08T00:11:42.661Z,1549584702.661 [CBIT](INFO): Critical error at 20190208T001142 2019-02-08T00:11:42.661Z,1549584702.661 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-02-08T00:11:42.890Z,1549584702.890 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:11:43.298Z,1549584703.298 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:11:48.892Z,1549584708.892 [NAL9602](INFO): SBD MO Status=2, MOMSN=2138, MT Status=2, MTMSN=0 2019-02-08T00:11:48.892Z,1549584708.892 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-02-08T00:14:08.258Z,1549584848.258 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-02-08T00:14:09.073Z,1549584849.073 [NAL9602](DEBUG): Fix Requested 2019-02-08T00:14:14.677Z,1549584854.677 [NAL9602](DEBUG): Fix Requested 2019-02-08T00:14:15.068Z,1549584855.068 [NAL9602](DEBUG): Parsing GGA sentence: $GPGGA,001324.00,3648.16136,N,12147.28329,W,1,5,1.68,8.4,M,-30.7,M,,*5C 2019-02-08T00:14:15.072Z,1549584855.072 [NAL9602](INFO): GPS fix at 20190208T001324: (36.802689, -121.788055) 2019-02-08T00:14:15.167Z,1549584855.167 [Default:CheckIn:Read_GPS] Stopped 2019-02-08T00:14:15.167Z,1549584855.167 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-02-08T00:14:15.530Z,1549584855.530 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-08T00:14:20.690Z,1549584860.690 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20190208T000815/Courier0004.lzma 2019-02-08T00:14:21.443Z,1549584861.443 [DataOverHttps](INFO): Moved sent file to Logs/20190208T000815/Courier0004.lzma.bak 2019-02-08T00:14:21.444Z,1549584861.444 [DataOverHttps](INFO): SBD MOMSN=9641478 2019-02-08T00:14:38.925Z,1549584878.925 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-02-08T00:14:38.925Z,1549584878.925 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-02-08T00:14:38.926Z,1549584878.926 [RDI_Pathfinder](ERROR): Communications Fault 2019-02-08T00:14:38.939Z,1549584878.939 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-02-08T00:14:38.939Z,1549584878.939 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-08T00:14:38.953Z,1549584878.953 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-08T00:14:38.954Z,1549584878.954 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-02-08T00:14:39.033Z,1549584879.033 [DataOverHttps](INFO): Sending 376 bytes from file Logs/20190208T000815/Express0005.lzma 2019-02-08T00:14:39.129Z,1549584879.129 [RDI_Pathfinder](INFO): Powering down 2019-02-08T00:14:39.175Z,1549584879.175 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-08T00:14:39.175Z,1549584879.175 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-02-08T00:14:39.446Z,1549584879.446 [CommandLine](IMPORTANT): got command restart application 2019-02-08T00:14:39.802Z,1549584879.802 [DataOverHttps](INFO): Moved sent file to Logs/20190208T000815/Express0005.lzma.bak 2019-02-08T00:14:39.803Z,1549584879.803 [DataOverHttps](INFO): SBD MOMSN=9641482 2019-02-08T00:14:39.862Z,1549584879.862 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-02-08T00:14:39.862Z,1549584879.862 [RDI_Pathfinder] No Fault, FailCount= 1 2019-02-08T00:14:40.285Z,1549584880.285 [BPC1](ERROR): BPC1B buffer size (966 bytes) exceeded the max device response limit. 2019-02-08T00:14:40.285Z,1549584880.285 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-02-08T00:14:40.298Z,1549584880.298 [Default:CheckIn:Read_Iridium] Stopped 2019-02-08T00:14:40.298Z,1549584880.298 [Default:CheckIn:C.Wait] Running Loop=1 2019-02-08T00:14:40.298Z,1549584880.298 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-02-08T00:14:40.452Z,1549584880.452 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:14:40.452Z,1549584880.452 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:40.453Z,1549584880.453 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-02-08T00:14:40.453Z,1549584880.453 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:40.453Z,1549584880.453 [CommandLine](INFO): Join timeout helper Thread ID is 945 2019-02-08T00:14:40.454Z,1549584880.454 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-02-08T00:14:40.454Z,1549584880.454 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:40.454Z,1549584880.454 [NavChartDb](INFO): Join timeout helper Thread ID is 946 2019-02-08T00:14:40.797Z,1549584880.797 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:14:40.797Z,1549584880.797 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:40.817Z,1549584880.817 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2019-02-08T00:14:40.817Z,1549584880.817 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:40.817Z,1549584880.817 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 947 2019-02-08T00:14:40.833Z,1549584880.833 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:14:40.833Z,1549584880.833 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-02-08T00:14:40.834Z,1549584880.834 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:40.837Z,1549584880.837 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-02-08T00:14:40.837Z,1549584880.837 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:40.837Z,1549584880.837 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 948 2019-02-08T00:14:40.977Z,1549584880.977 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:14:40.977Z,1549584880.977 [CTD_NeilBrown](INFO): Powering down 2019-02-08T00:14:40.989Z,1549584880.989 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:41.001Z,1549584881.001 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-02-08T00:14:41.001Z,1549584881.001 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:41.001Z,1549584881.001 [Radio_Surface](INFO): Join timeout helper Thread ID is 949 2019-02-08T00:14:41.169Z,1549584881.169 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:14:41.169Z,1549584881.169 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:41.182Z,1549584881.182 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-02-08T00:14:41.182Z,1549584881.182 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:41.182Z,1549584881.182 [logger](INFO): Join timeout helper Thread ID is 950 2019-02-08T00:14:41.197Z,1549584881.197 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:14:41.197Z,1549584881.197 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:41.213Z,1549584881.213 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-02-08T00:14:41.214Z,1549584881.214 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:41.214Z,1549584881.214 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-02-08T00:14:41.214Z,1549584881.214 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:41.214Z,1549584881.214 [controlThread](INFO): Join timeout helper Thread ID is 951 2019-02-08T00:14:43.752Z,1549584883.752 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:14:43.752Z,1549584883.752 [controlThread](DEBUG): Uninitializing ControlThread 2019-02-08T00:14:43.753Z,1549584883.753 [AHRS_M2](INFO): Powering down 2019-02-08T00:14:43.828Z,1549584883.828 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:14:43.828Z,1549584883.828 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:14:43.829Z,1549584883.829 [NAL9602](INFO): Powering down 2019-02-08T00:14:43.901Z,1549584883.901 [RDI_Pathfinder](INFO): Powering down 2019-02-08T00:14:43.902Z,1549584883.902 [RDI_PathfinderUp](INFO): Powering down 2019-02-08T00:14:43.903Z,1549584883.903 [Aanderaa_O2](INFO): Powering down 2019-02-08T00:14:43.904Z,1549584883.904 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-02-08T00:14:43.905Z,1549584883.905 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-02-08T00:14:43.906Z,1549584883.906 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-02-08T00:14:43.907Z,1549584883.907 [MissionManager](INFO): Uninitializing Mission Default 2019-02-08T00:14:43.907Z,1549584883.907 [Default] Stopped 2019-02-08T00:14:43.907Z,1549584883.907 [Default](DEBUG): Aggregate::uninitialize Default 2019-02-08T00:14:43.907Z,1549584883.907 [Default:B.GoToSurface] Stopped 2019-02-08T00:14:43.907Z,1549584883.907 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-08T00:14:43.907Z,1549584883.907 [Default:CheckIn] Stopped 2019-02-08T00:14:43.907Z,1549584883.907 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-02-08T00:14:43.907Z,1549584883.907 [Default:CheckIn:C.Wait] Stopped 2019-02-08T00:14:43.907Z,1549584883.907 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-02-08T00:14:43.910Z,1549584883.910 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-02-08T00:14:43.910Z,1549584883.910 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-02-08T00:14:43.911Z,1549584883.911 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-02-08T00:14:43.911Z,1549584883.911 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-02-08T00:14:43.911Z,1549584883.911 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-02-08T00:14:43.912Z,1549584883.912 [BuoyancyServo](INFO): Powering down 2019-02-08T00:14:43.925Z,1549584883.925 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-02-08T00:14:43.925Z,1549584883.925 [ElevatorServo](INFO): Powering down 2019-02-08T00:14:43.926Z,1549584883.926 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-08T00:14:43.926Z,1549584883.926 [MassServo](INFO): Powering down 2019-02-08T00:14:43.927Z,1549584883.927 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-02-08T00:14:43.927Z,1549584883.927 [RudderServo](INFO): Powering down 2019-02-08T00:14:43.928Z,1549584883.928 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-02-08T00:14:43.928Z,1549584883.928 [ThrusterServo](INFO): Powering down 2019-02-08T00:14:43.929Z,1549584883.929 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-02-08T00:14:43.929Z,1549584883.929 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-02-08T00:14:43.929Z,1549584883.929 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-02-08T00:14:43.929Z,1549584883.929 [CBIT](DEBUG): Powering off loads. 2019-02-08T00:14:43.941Z,1549584883.941 [CBIT](DEBUG): Disabling WDT. 2019-02-08T00:14:43.953Z,1549584883.953 [CBIT](DEBUG): Opening all GF detection circuits. 2019-02-08T00:14:43.962Z,1549584883.962 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:44.072Z,1549584884.072 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:44.118Z,1549584884.118 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:44.120Z,1549584884.120 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:44.144Z,1549584884.144 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:14:44.204Z,1549584884.204 [logger ThreadHandler](INFO): Thread cancelled.