2019-02-08T00:03:49.945Z,1549584229.945 [Supervisor](DEBUG): Initializing supervisor. 2019-02-08T00:03:49.949Z,1549584229.949 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-02-08T00:03:49.949Z,1549584229.949 [SyncHandler](INFO): Protected caller Thread ID is 8649 2019-02-08T00:03:49.950Z,1549584229.950 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-02-08T00:03:49.951Z,1549584229.951 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-02-08T00:03:49.951Z,1549584229.951 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8650 2019-02-08T00:03:49.954Z,1549584229.954 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-02-08T00:03:49.966Z,1549584229.966 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-02-08T00:03:49.967Z,1549584229.967 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-02-08T00:03:49.967Z,1549584229.967 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8651 2019-02-08T00:03:49.968Z,1549584229.968 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-02-08T00:03:49.969Z,1549584229.969 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-02-08T00:03:49.969Z,1549584229.969 [logger ThreadHandler](INFO): Protected caller Thread ID is 8652 2019-02-08T00:03:49.971Z,1549584229.971 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-02-08T00:03:49.972Z,1549584229.972 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-02-08T00:03:49.973Z,1549584229.973 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-02-08T00:03:50.318Z,1549584230.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-02-08T00:03:50.318Z,1549584230.318 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-02-08T00:03:50.757Z,1549584230.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-02-08T00:03:50.757Z,1549584230.757 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-02-08T00:03:51.079Z,1549584231.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-02-08T00:03:51.080Z,1549584231.080 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-02-08T00:03:51.270Z,1549584231.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-02-08T00:03:51.271Z,1549584231.271 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-02-08T00:03:51.708Z,1549584231.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-02-08T00:03:51.709Z,1549584231.709 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-02-08T00:03:52.179Z,1549584232.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-02-08T00:03:52.179Z,1549584232.179 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-02-08T00:03:52.282Z,1549584232.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-02-08T00:03:52.282Z,1549584232.282 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-02-08T00:03:52.471Z,1549584232.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-02-08T00:03:52.472Z,1549584232.472 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-02-08T00:03:52.614Z,1549584232.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-02-08T00:03:52.615Z,1549584232.615 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-02-08T00:03:52.695Z,1549584232.695 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-02-08T00:03:52.913Z,1549584232.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-02-08T00:03:52.914Z,1549584232.914 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-02-08T00:03:53.213Z,1549584233.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-02-08T00:03:53.213Z,1549584233.213 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-02-08T00:03:53.310Z,1549584233.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-02-08T00:03:53.311Z,1549584233.311 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-02-08T00:03:53.456Z,1549584233.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-02-08T00:03:53.456Z,1549584233.456 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-02-08T00:03:53.771Z,1549584233.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-02-08T00:03:53.772Z,1549584233.772 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2019-02-08T00:03:53.773Z,1549584233.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2019-02-08T00:03:53.907Z,1549584233.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2019-02-08T00:03:54.135Z,1549584234.135 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-02-08T00:03:54.135Z,1549584234.135 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2019-02-08T00:03:54.300Z,1549584234.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2019-02-08T00:03:54.399Z,1549584234.399 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2019-02-08T00:03:54.496Z,1549584234.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2019-02-08T00:03:54.687Z,1549584234.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2019-02-08T00:03:54.771Z,1549584234.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2019-02-08T00:03:54.868Z,1549584234.868 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2019-02-08T00:03:54.960Z,1549584234.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2019-02-08T00:03:55.041Z,1549584235.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2019-02-08T00:03:55.149Z,1549584235.149 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2019-02-08T00:03:55.149Z,1549584235.149 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-02-08T00:03:55.153Z,1549584235.153 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-02-08T00:03:55.287Z,1549584235.287 [SBIT](DEBUG): Construct Startup Built In Test. 2019-02-08T00:03:55.317Z,1549584235.317 [SBIT] Loaded 2019-02-08T00:03:55.317Z,1549584235.317 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-02-08T00:03:55.318Z,1549584235.318 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-02-08T00:03:55.346Z,1549584235.346 [IBIT] Loaded 2019-02-08T00:03:55.346Z,1549584235.346 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-02-08T00:03:55.349Z,1549584235.349 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-02-08T00:03:55.732Z,1549584235.732 [CBIT] Loaded 2019-02-08T00:03:55.732Z,1549584235.732 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-02-08T00:03:55.733Z,1549584235.733 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-02-08T00:03:55.733Z,1549584235.733 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-02-08T00:03:55.860Z,1549584235.860 [BuoyancyServo] Loaded 2019-02-08T00:03:55.860Z,1549584235.860 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-02-08T00:03:55.874Z,1549584235.874 [ElevatorServo] Loaded 2019-02-08T00:03:55.875Z,1549584235.875 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-02-08T00:03:55.889Z,1549584235.889 [MassServo] Loaded 2019-02-08T00:03:55.889Z,1549584235.889 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-02-08T00:03:55.904Z,1549584235.904 [RudderServo] Loaded 2019-02-08T00:03:55.904Z,1549584235.904 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-02-08T00:03:55.918Z,1549584235.918 [ThrusterServo] Loaded 2019-02-08T00:03:55.919Z,1549584235.919 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-02-08T00:03:55.919Z,1549584235.919 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-02-08T00:03:55.920Z,1549584235.920 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-02-08T00:03:56.026Z,1549584236.026 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-02-08T00:03:56.027Z,1549584236.027 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-02-08T00:03:56.085Z,1549584236.085 [DeadReckonUsingSpeedCalculator] Loaded 2019-02-08T00:03:56.086Z,1549584236.086 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-02-08T00:03:56.144Z,1549584236.144 [DeadReckonWithRespectToSeafloor] Loaded 2019-02-08T00:03:56.144Z,1549584236.144 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-02-08T00:03:56.160Z,1549584236.160 [NavChart] Loaded 2019-02-08T00:03:56.160Z,1549584236.160 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-02-08T00:03:56.164Z,1549584236.164 [UniversalFixResidualReporter] Loaded 2019-02-08T00:03:56.165Z,1549584236.165 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-02-08T00:03:56.165Z,1549584236.165 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-02-08T00:03:56.166Z,1549584236.166 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-02-08T00:03:56.179Z,1549584236.179 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-02-08T00:03:56.179Z,1549584236.179 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-02-08T00:03:56.413Z,1549584236.413 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-02-08T00:03:56.413Z,1549584236.413 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-02-08T00:03:56.747Z,1549584236.747 [AHRS_M2] Loaded 2019-02-08T00:03:56.748Z,1549584236.748 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-02-08T00:03:56.816Z,1549584236.816 [DataOverHttps] Loaded 2019-02-08T00:03:56.816Z,1549584236.816 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2019-02-08T00:03:56.829Z,1549584236.829 [Depth_Keller] Loaded 2019-02-08T00:03:56.829Z,1549584236.829 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-02-08T00:03:56.834Z,1549584236.834 [DropWeight] Loaded 2019-02-08T00:03:56.834Z,1549584236.834 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-02-08T00:03:56.885Z,1549584236.885 [DUSBL_Hydroid] Loaded 2019-02-08T00:03:56.886Z,1549584236.886 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2019-02-08T00:03:56.975Z,1549584236.975 [NAL9602] Loaded 2019-02-08T00:03:56.975Z,1549584236.975 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-02-08T00:03:56.980Z,1549584236.980 [Onboard] Loaded 2019-02-08T00:03:56.980Z,1549584236.980 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-02-08T00:03:56.987Z,1549584236.987 [Radio_Surface] Loaded 2019-02-08T00:03:56.987Z,1549584236.987 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-02-08T00:03:56.989Z,1549584236.989 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408494E0 2019-02-08T00:03:56.989Z,1549584236.989 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8731 2019-02-08T00:03:57.017Z,1549584237.017 [RDI_Pathfinder] Loaded 2019-02-08T00:03:57.018Z,1549584237.018 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-02-08T00:03:57.039Z,1549584237.039 [RDI_PathfinderUp] Loaded 2019-02-08T00:03:57.040Z,1549584237.040 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2019-02-08T00:03:58.584Z,1549584238.584 [BPC1] Loaded 2019-02-08T00:03:58.585Z,1549584238.585 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-02-08T00:03:58.585Z,1549584238.585 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-02-08T00:03:58.586Z,1549584238.586 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-02-08T00:03:58.878Z,1549584238.878 [VerticalControl](DEBUG): Construct VerticalControl. 2019-02-08T00:03:59.001Z,1549584239.001 [VerticalControl] Loaded 2019-02-08T00:03:59.002Z,1549584239.002 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-02-08T00:03:59.002Z,1549584239.002 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-02-08T00:03:59.060Z,1549584239.060 [HorizontalControl] Loaded 2019-02-08T00:03:59.061Z,1549584239.061 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-02-08T00:03:59.061Z,1549584239.061 [SpeedControl](DEBUG): Construct SpeedControl. 2019-02-08T00:03:59.064Z,1549584239.064 [SpeedControl] Loaded 2019-02-08T00:03:59.064Z,1549584239.064 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-02-08T00:03:59.065Z,1549584239.065 [LoopControl](DEBUG): Construct LoopControl. 2019-02-08T00:03:59.065Z,1549584239.065 [LoopControl] Loaded 2019-02-08T00:03:59.065Z,1549584239.065 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-02-08T00:03:59.066Z,1549584239.066 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-02-08T00:03:59.066Z,1549584239.066 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-02-08T00:03:59.422Z,1549584239.422 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-02-08T00:03:59.423Z,1549584239.423 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-02-08T00:03:59.600Z,1549584239.600 [Aanderaa_O2] Loaded 2019-02-08T00:03:59.600Z,1549584239.600 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-02-08T00:03:59.685Z,1549584239.685 [CTD_NeilBrown] Loaded 2019-02-08T00:03:59.685Z,1549584239.685 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-02-08T00:03:59.686Z,1549584239.686 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095A4E0 2019-02-08T00:03:59.686Z,1549584239.686 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8732 2019-02-08T00:03:59.732Z,1549584239.732 [WetLabsSeaOWL_UV_A] Loaded 2019-02-08T00:03:59.732Z,1549584239.732 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2019-02-08T00:03:59.733Z,1549584239.733 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0 2019-02-08T00:03:59.733Z,1549584239.733 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8733 2019-02-08T00:03:59.734Z,1549584239.734 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-02-08T00:03:59.735Z,1549584239.735 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-02-08T00:03:59.798Z,1549584239.798 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-02-08T00:03:59.799Z,1549584239.799 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-02-08T00:03:59.839Z,1549584239.839 [DepthRateCalculator] Loaded 2019-02-08T00:03:59.839Z,1549584239.839 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-02-08T00:03:59.844Z,1549584239.844 [PitchRateCalculator] Loaded 2019-02-08T00:03:59.844Z,1549584239.844 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-02-08T00:03:59.856Z,1549584239.856 [SpeedCalculator] Loaded 2019-02-08T00:03:59.856Z,1549584239.856 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-02-08T00:03:59.878Z,1549584239.878 [TempGradientCalculator] Loaded 2019-02-08T00:03:59.878Z,1549584239.878 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-02-08T00:03:59.883Z,1549584239.883 [YawRateCalculator] Loaded 2019-02-08T00:03:59.883Z,1549584239.883 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-02-08T00:03:59.913Z,1549584239.913 [ElevatorOffsetCalculator] Loaded 2019-02-08T00:03:59.914Z,1549584239.914 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-02-08T00:03:59.914Z,1549584239.914 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-02-08T00:03:59.915Z,1549584239.915 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-02-08T00:03:59.941Z,1549584239.941 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-02-08T00:03:59.945Z,1549584239.945 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-02-08T00:03:59.946Z,1549584239.946 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-02-08T00:03:59.952Z,1549584239.952 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-02-08T00:03:59.953Z,1549584239.953 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2019-02-08T00:03:59.954Z,1549584239.954 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8734 2019-02-08T00:03:59.958Z,1549584239.958 [Supervisor](INFO): Main Thread ID is 8078 2019-02-08T00:03:59.958Z,1549584239.958 [Supervisor](DEBUG): Running supervisor. 2019-02-08T00:03:59.959Z,1549584239.959 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8735 2019-02-08T00:03:59.961Z,1549584239.961 [controlThread ThreadHandler](INFO): Handler Thread ID is 8736 2019-02-08T00:03:59.962Z,1549584239.962 [controlThread](DEBUG): Initializing ControlThread 2019-02-08T00:03:59.963Z,1549584239.963 [SBIT](INFO): Initialize SBIT Component. 2019-02-08T00:03:59.963Z,1549584239.963 [SBIT](IMPORTANT): git: 2019-01-14A-54-g0d094c9 2019-02-08T00:03:59.963Z,1549584239.963 [SBIT](INFO): git hash: 0d094c9fe207dedbc4f0c5a93b42d412b4ef2ca6 2019-02-08T00:03:59.964Z,1549584239.964 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-02-08T00:03:59.964Z,1549584239.964 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2019-02-08T00:03:59.965Z,1549584239.965 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-02-08T00:03:59.966Z,1549584239.966 [IBIT](INFO): Initialize IBIT Component. 2019-02-08T00:03:59.966Z,1549584239.966 [CBIT](DEBUG): Initialize CBIT Component. 2019-02-08T00:03:59.967Z,1549584239.967 [logger ThreadHandler](INFO): Handler Thread ID is 8737 2019-02-08T00:03:59.978Z,1549584239.978 [CBIT](DEBUG): Initialized mux pins. 2019-02-08T00:03:59.978Z,1549584239.978 [CBIT](DEBUG): Initializing the watchdog timer. 2019-02-08T00:03:59.986Z,1549584239.986 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8738 2019-02-08T00:03:59.992Z,1549584239.992 [Radio_Surface](INFO): Powering up 2019-02-08T00:03:59.998Z,1549584239.998 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8739 2019-02-08T00:03:59.999Z,1549584239.999 [CTD_NeilBrown](INFO): Powering down 2019-02-08T00:04:00.002Z,1549584240.002 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-02-08T00:04:00.002Z,1549584240.002 [CBIT](DEBUG): Initializing heartbeat. 2019-02-08T00:04:00.034Z,1549584240.034 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8740 2019-02-08T00:04:00.035Z,1549584240.035 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-02-08T00:04:00.066Z,1549584240.066 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8741 2019-02-08T00:04:00.069Z,1549584240.069 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-08T00:04:00.070Z,1549584240.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-02-08T00:04:00.070Z,1549584240.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-08T00:04:00.070Z,1549584240.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-02-08T00:04:00.070Z,1549584240.070 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-08T00:04:00.070Z,1549584240.070 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-02-08T00:04:00.071Z,1549584240.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-08T00:04:00.071Z,1549584240.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-02-08T00:04:00.071Z,1549584240.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-08T00:04:00.071Z,1549584240.071 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-02-08T00:04:00.071Z,1549584240.071 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-08T00:04:00.072Z,1549584240.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-02-08T00:04:00.072Z,1549584240.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-08T00:04:00.072Z,1549584240.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-02-08T00:04:00.072Z,1549584240.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-08T00:04:00.072Z,1549584240.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-02-08T00:04:00.074Z,1549584240.074 [CBIT](DEBUG): Deactivating GF circuits. 2019-02-08T00:04:00.074Z,1549584240.074 [CBIT](DEBUG): Deactivating emergency mode. 2019-02-08T00:04:00.111Z,1549584240.111 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-08T00:04:00.112Z,1549584240.112 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-02-08T00:04:00.112Z,1549584240.112 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-02-08T00:04:00.117Z,1549584240.117 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-02-08T00:04:00.119Z,1549584240.119 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-02-08T00:04:00.119Z,1549584240.119 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-02-08T00:04:00.120Z,1549584240.120 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-02-08T00:04:00.121Z,1549584240.121 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-02-08T00:04:00.121Z,1549584240.121 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-02-08T00:04:00.121Z,1549584240.121 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-02-08T00:04:00.122Z,1549584240.122 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-02-08T00:04:00.123Z,1549584240.123 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-02-08T00:04:00.123Z,1549584240.123 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-02-08T00:04:00.124Z,1549584240.124 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-02-08T00:04:00.142Z,1549584240.142 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-08T00:04:00.170Z,1549584240.170 [MissionManager](DEBUG): 2019-02-08T00:04:00.171Z,1549584240.171 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-02-08T00:04:00.248Z,1549584240.248 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-02-08T00:04:00.249Z,1549584240.249 [Default:A.Wait](DEBUG): Construct Wait. 2019-02-08T00:04:00.267Z,1549584240.267 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-08T00:04:00.294Z,1549584240.294 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-02-08T00:04:00.296Z,1549584240.296 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-02-08T00:04:00.323Z,1549584240.323 [Default:E.Execute](DEBUG): Construct Execute. 2019-02-08T00:04:00.337Z,1549584240.337 [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:04:00.342Z,1549584240.342 [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:04:00.354Z,1549584240.354 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-02-08T00:04:00.395Z,1549584240.395 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-02-08T00:04:00.403Z,1549584240.403 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2019-02-08T00:04:00.438Z,1549584240.438 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:00.438Z,1549584240.438 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:04:00.438Z,1549584240.438 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:04:00.598Z,1549584240.598 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-08T00:04:00.826Z,1549584240.826 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:04:00.834Z,1549584240.834 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-08T00:04:00.857Z,1549584240.857 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:04:00.863Z,1549584240.863 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-02-08T00:04:00.884Z,1549584240.884 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:04:00.890Z,1549584240.890 [MassServo](DEBUG): Initializing MassServo. 2019-02-08T00:04:00.896Z,1549584240.896 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:04:00.902Z,1549584240.902 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-08T00:04:00.923Z,1549584240.923 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:04:00.930Z,1549584240.930 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-02-08T00:04:01.172Z,1549584241.172 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:01.274Z,1549584241.274 [RDI_PathfinderUp](INFO): Powering down 2019-02-08T00:04:01.428Z,1549584241.428 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:01.798Z,1549584241.798 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2019-02-08T00:04:01.798Z,1549584241.798 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2019-02-08T00:04:01.798Z,1549584241.798 [BuoyancyServo] Communications Fault, FailCount= 1 2019-02-08T00:04:01.798Z,1549584241.798 [BuoyancyServo](ERROR): Communications Fault 2019-02-08T00:04:02.014Z,1549584242.014 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2019-02-08T00:04:02.023Z,1549584242.023 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:02.427Z,1549584242.427 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:02.827Z,1549584242.827 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:03.227Z,1549584243.227 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:03.696Z,1549584243.696 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:03.838Z,1549584243.838 [Aanderaa_O2](INFO): Powering down 2019-02-08T00:04:04.051Z,1549584244.051 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:04.123Z,1549584244.123 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2019-02-08T00:04:04.123Z,1549584244.123 [BuoyancyServo] No Fault, FailCount= 1 2019-02-08T00:04:04.452Z,1549584244.452 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:04.480Z,1549584244.480 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-08T00:04:04.599Z,1549584244.599 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-08T00:04:04.915Z,1549584244.915 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:05.347Z,1549584245.347 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:05.759Z,1549584245.759 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:06.147Z,1549584246.147 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:06.559Z,1549584246.559 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:06.947Z,1549584246.947 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:07.359Z,1549584247.359 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:07.759Z,1549584247.759 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:08.159Z,1549584248.159 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:08.548Z,1549584248.548 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:08.952Z,1549584248.952 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:09.335Z,1549584249.335 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:09.741Z,1549584249.741 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:10.128Z,1549584250.128 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:10.540Z,1549584250.540 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:10.939Z,1549584250.939 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:11.327Z,1549584251.327 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:11.727Z,1549584251.727 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:12.150Z,1549584252.150 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:12.527Z,1549584252.527 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:12.939Z,1549584252.939 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:13.327Z,1549584253.327 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:13.727Z,1549584253.727 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:13.757Z,1549584253.757 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-02-08T00:04:14.127Z,1549584254.127 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:14.535Z,1549584254.535 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:14.928Z,1549584254.928 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:15.330Z,1549584255.330 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:15.732Z,1549584255.732 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:16.132Z,1549584256.132 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:16.531Z,1549584256.531 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:16.939Z,1549584256.939 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:17.330Z,1549584257.330 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:17.730Z,1549584257.730 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:18.130Z,1549584258.130 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:18.529Z,1549584258.529 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:18.929Z,1549584258.929 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:19.331Z,1549584259.331 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:19.731Z,1549584259.731 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:20.131Z,1549584260.131 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:20.531Z,1549584260.531 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:20.532Z,1549584260.532 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:04:20.532Z,1549584260.532 [DUSBL_Hydroid] Communications Fault, FailCount= 1 2019-02-08T00:04:20.532Z,1549584260.532 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:04:20.583Z,1549584260.583 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:04:20.936Z,1549584260.936 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:04:20.936Z,1549584260.936 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:04:20.936Z,1549584260.936 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:04:21.331Z,1549584261.331 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:04:21.732Z,1549584261.732 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:04:21.795Z,1549584261.795 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:04:21.795Z,1549584261.795 [DUSBL_Hydroid] No Fault, FailCount= 1 2019-02-08T00:04:22.131Z,1549584262.131 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:22.531Z,1549584262.531 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:22.932Z,1549584262.932 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:23.332Z,1549584263.332 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:23.731Z,1549584263.731 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:24.140Z,1549584264.140 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:24.140Z,1549584264.140 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:04:24.140Z,1549584264.140 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:04:24.531Z,1549584264.531 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:24.931Z,1549584264.931 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:25.331Z,1549584265.331 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:25.727Z,1549584265.727 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:26.127Z,1549584266.127 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:26.527Z,1549584266.527 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:26.927Z,1549584266.927 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:27.327Z,1549584267.327 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:27.328Z,1549584267.328 [NAL9602](INFO): Powering up NAL9602 2019-02-08T00:04:27.727Z,1549584267.727 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:28.127Z,1549584268.127 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:28.535Z,1549584268.535 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:28.588Z,1549584268.588 [SBIT](IMPORTANT): Beginning Startup BIT 2019-02-08T00:04:28.610Z,1549584268.610 [CBIT](IMPORTANT): Beginning ground fault scan 2019-02-08T00:04:28.927Z,1549584268.927 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:29.327Z,1549584269.327 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:29.736Z,1549584269.736 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:30.127Z,1549584270.127 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:30.527Z,1549584270.527 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:31.043Z,1549584271.043 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:31.443Z,1549584271.443 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:31.843Z,1549584271.843 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:32.253Z,1549584272.253 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:32.661Z,1549584272.661 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:33.047Z,1549584273.047 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:33.453Z,1549584273.453 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:33.860Z,1549584273.860 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:34.243Z,1549584274.243 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:34.679Z,1549584274.679 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:35.043Z,1549584275.043 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:35.443Z,1549584275.443 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:35.843Z,1549584275.843 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:36.260Z,1549584276.260 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:36.675Z,1549584276.675 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:37.043Z,1549584277.043 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:37.451Z,1549584277.451 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:37.843Z,1549584277.843 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:38.243Z,1549584278.243 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:38.244Z,1549584278.244 [NAL9602](INFO): NAL9602 initialized 2019-02-08T00:04:38.657Z,1549584278.657 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:39.051Z,1549584279.051 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:39.082Z,1549584279.082 [NAL9602](DEBUG): Fix Requested 2019-02-08T00:04:39.443Z,1549584279.443 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:39.595Z,1549584279.595 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.008639 CHAN A1 (24V): -0.025326 CHAN A2 (12V): -0.007235 CHAN A3 (5V): -0.003076 CHAN B0 (3.3V): -0.000345 CHAN B1 (3.15aV): -0.000325 CHAN B2 (3.15bV): -0.000005 CHAN B3 (GND): 0.000629 OPEN: 0.009190 Full Scale Calc: 4.765 mA, -1.589 mA 2019-02-08T00:04:39.843Z,1549584279.843 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:40.239Z,1549584280.239 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:40.657Z,1549584280.657 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:41.068Z,1549584281.068 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:41.448Z,1549584281.448 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:41.853Z,1549584281.853 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:42.256Z,1549584282.256 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:42.679Z,1549584282.679 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:43.043Z,1549584283.043 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:43.443Z,1549584283.443 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:43.839Z,1549584283.839 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:44.455Z,1549584284.455 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:44.456Z,1549584284.456 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:04:44.456Z,1549584284.456 [DUSBL_Hydroid] Communications Fault, FailCount= 2 2019-02-08T00:04:44.456Z,1549584284.456 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:04:44.683Z,1549584284.683 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:04:44.867Z,1549584284.867 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:04:44.867Z,1549584284.867 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:04:44.867Z,1549584284.867 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:04:45.267Z,1549584285.267 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:04:45.667Z,1549584285.667 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:04:45.863Z,1549584285.863 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:04:45.863Z,1549584285.863 [DUSBL_Hydroid] No Fault, FailCount= 2 2019-02-08T00:04:46.063Z,1549584286.063 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:46.467Z,1549584286.467 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:46.864Z,1549584286.864 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:47.272Z,1549584287.272 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:47.675Z,1549584287.675 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:48.067Z,1549584288.067 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:04:48.067Z,1549584288.067 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:04:48.068Z,1549584288.068 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:04:48.459Z,1549584288.459 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:48.859Z,1549584288.859 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:49.267Z,1549584289.267 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:49.667Z,1549584289.667 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:50.071Z,1549584290.071 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:50.467Z,1549584290.467 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:50.873Z,1549584290.873 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:51.267Z,1549584291.267 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:51.683Z,1549584291.683 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:52.072Z,1549584292.072 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:52.479Z,1549584292.479 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:52.859Z,1549584292.859 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:53.267Z,1549584293.267 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:53.667Z,1549584293.667 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:54.059Z,1549584294.059 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:54.467Z,1549584294.467 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:54.867Z,1549584294.867 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:55.267Z,1549584295.267 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:55.683Z,1549584295.683 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:56.078Z,1549584296.078 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:56.459Z,1549584296.459 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:56.867Z,1549584296.867 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:57.267Z,1549584297.267 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:57.679Z,1549584297.679 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:58.067Z,1549584298.067 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:58.459Z,1549584298.459 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:58.871Z,1549584298.871 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:59.279Z,1549584299.279 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:04:59.682Z,1549584299.682 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:00.059Z,1549584300.059 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:00.475Z,1549584300.475 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:00.861Z,1549584300.861 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:01.259Z,1549584301.259 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:01.679Z,1549584301.679 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:02.059Z,1549584302.059 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:02.480Z,1549584302.480 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:02.867Z,1549584302.867 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:03.260Z,1549584303.260 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:03.675Z,1549584303.675 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:04.066Z,1549584304.066 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:04.462Z,1549584304.462 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:04.864Z,1549584304.864 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:05.264Z,1549584305.264 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:05.668Z,1549584305.668 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:06.071Z,1549584306.071 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:06.463Z,1549584306.463 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:06.870Z,1549584306.870 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:07.267Z,1549584307.267 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:07.667Z,1549584307.667 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:08.071Z,1549584308.071 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:08.467Z,1549584308.467 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:08.468Z,1549584308.468 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:05:08.468Z,1549584308.468 [DUSBL_Hydroid] Communications Fault, FailCount= 3 2019-02-08T00:05:08.468Z,1549584308.468 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:05:08.559Z,1549584308.559 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:05:08.867Z,1549584308.867 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:05:08.867Z,1549584308.867 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:05:08.868Z,1549584308.868 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:05:09.267Z,1549584309.267 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:05:09.503Z,1549584309.503 [CommandLine](IMPORTANT): got command show variable DUSBL_hydroid.uart 2019-02-08T00:05:09.580Z,1549584309.580 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none) 2019-02-08T00:05:09.667Z,1549584309.667 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:05:09.776Z,1549584309.776 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:05:09.776Z,1549584309.776 [DUSBL_Hydroid] No Fault, FailCount= 3 2019-02-08T00:05:10.071Z,1549584310.071 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:10.463Z,1549584310.463 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:10.860Z,1549584310.860 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:11.375Z,1549584311.375 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:11.779Z,1549584311.779 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:12.183Z,1549584312.183 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:12.183Z,1549584312.183 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:05:12.184Z,1549584312.184 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:05:12.583Z,1549584312.583 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:12.979Z,1549584312.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:13.383Z,1549584313.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:13.779Z,1549584313.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:14.191Z,1549584314.191 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:14.587Z,1549584314.587 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:14.987Z,1549584314.987 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:15.383Z,1549584315.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:15.783Z,1549584315.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:16.183Z,1549584316.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:16.579Z,1549584316.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:17.004Z,1549584317.004 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:17.383Z,1549584317.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:17.783Z,1549584317.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:18.179Z,1549584318.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:18.600Z,1549584318.600 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:18.993Z,1549584318.993 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:19.379Z,1549584319.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:19.499Z,1549584319.499 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.uart 2019-02-08T00:05:19.499Z,1549584319.499 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart /dev/ttyC4 2019-02-08T00:05:19.787Z,1549584319.787 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:20.183Z,1549584320.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:20.579Z,1549584320.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:20.988Z,1549584320.988 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:21.383Z,1549584321.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:21.779Z,1549584321.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:22.183Z,1549584322.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:22.579Z,1549584322.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:22.669Z,1549584322.669 [SBIT](IMPORTANT): SBIT PASSED 2019-02-08T00:05:22.706Z,1549584322.706 [CommandLine](IMPORTANT): got command configSet list 2019-02-08T00:05:22.706Z,1549584322.706 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-02-08T00:05:22.707Z,1549584322.707 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool; 2019-02-08T00:05:22.707Z,1549584322.707 [CommandLine](IMPORTANT): NAL9602.requestGGA=1 bool; 2019-02-08T00:05:22.990Z,1549584322.990 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:23.040Z,1549584323.040 [MissionManager](IMPORTANT): Started mission Startup 2019-02-08T00:05:23.040Z,1549584323.040 [Startup] Running Loop=1 2019-02-08T00:05:23.040Z,1549584323.040 [Startup](DEBUG): Aggregate::initialize Startup 2019-02-08T00:05:23.040Z,1549584323.040 [Startup:A.GoToSurface] Running Loop=1 2019-02-08T00:05:23.040Z,1549584323.040 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-08T00:05:23.041Z,1549584323.041 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-08T00:05:23.041Z,1549584323.041 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-08T00:05:23.042Z,1549584323.042 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-08T00:05:23.042Z,1549584323.042 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-08T00:05:23.042Z,1549584323.042 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-08T00:05:23.048Z,1549584323.048 [Startup:StartupSatComms] Running Loop=1 2019-02-08T00:05:23.048Z,1549584323.048 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-02-08T00:05:23.048Z,1549584323.048 [Startup:StartupSatComms:A] Running Loop=1 2019-02-08T00:05:23.387Z,1549584323.387 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:23.423Z,1549584323.423 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-02-08T00:05:23.783Z,1549584323.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:24.179Z,1549584324.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:24.583Z,1549584324.583 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:24.991Z,1549584324.991 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:25.379Z,1549584325.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:25.783Z,1549584325.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:26.179Z,1549584326.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:26.583Z,1549584326.583 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:26.983Z,1549584326.983 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:27.379Z,1549584327.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:27.780Z,1549584327.780 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:28.183Z,1549584328.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:28.579Z,1549584328.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:28.983Z,1549584328.983 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:29.383Z,1549584329.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:29.783Z,1549584329.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:30.179Z,1549584330.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:30.583Z,1549584330.583 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:30.989Z,1549584330.989 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:31.388Z,1549584331.388 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:31.780Z,1549584331.780 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:32.179Z,1549584332.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:32.579Z,1549584332.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:32.580Z,1549584332.580 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:05:32.580Z,1549584332.580 [DUSBL_Hydroid] Communications Fault, FailCount= 4 2019-02-08T00:05:32.580Z,1549584332.580 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:05:32.693Z,1549584332.693 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:05:32.979Z,1549584332.979 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:05:32.979Z,1549584332.979 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:05:32.979Z,1549584332.979 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:05:33.383Z,1549584333.383 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:05:33.779Z,1549584333.779 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:05:33.867Z,1549584333.867 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:05:33.867Z,1549584333.867 [DUSBL_Hydroid] No Fault, FailCount= 4 2019-02-08T00:05:34.179Z,1549584334.179 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:34.583Z,1549584334.583 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:34.979Z,1549584334.979 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:35.376Z,1549584335.376 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:35.783Z,1549584335.783 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:36.183Z,1549584336.183 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:36.183Z,1549584336.183 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:05:36.184Z,1549584336.184 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:05:36.579Z,1549584336.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:36.980Z,1549584336.980 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:37.383Z,1549584337.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:37.779Z,1549584337.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:38.183Z,1549584338.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:38.579Z,1549584338.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:38.988Z,1549584338.988 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:39.383Z,1549584339.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:39.789Z,1549584339.789 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:40.191Z,1549584340.191 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:40.583Z,1549584340.583 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:40.979Z,1549584340.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:41.383Z,1549584341.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:41.779Z,1549584341.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:42.179Z,1549584342.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:42.579Z,1549584342.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:42.988Z,1549584342.988 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:43.379Z,1549584343.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:43.783Z,1549584343.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:44.192Z,1549584344.192 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:44.579Z,1549584344.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:44.979Z,1549584344.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:45.379Z,1549584345.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:45.783Z,1549584345.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:46.180Z,1549584346.180 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:46.579Z,1549584346.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:46.979Z,1549584346.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:47.376Z,1549584347.376 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:47.779Z,1549584347.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:48.179Z,1549584348.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:48.576Z,1549584348.576 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:48.987Z,1549584348.987 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:49.379Z,1549584349.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:49.779Z,1549584349.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:50.183Z,1549584350.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:50.579Z,1549584350.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:50.979Z,1549584350.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:51.383Z,1549584351.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:51.783Z,1549584351.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:52.189Z,1549584352.189 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:52.583Z,1549584352.583 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:52.979Z,1549584352.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:53.379Z,1549584353.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:53.779Z,1549584353.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:54.179Z,1549584354.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:54.579Z,1549584354.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:54.979Z,1549584354.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:55.389Z,1549584355.389 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:55.787Z,1549584355.787 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:56.179Z,1549584356.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:56.579Z,1549584356.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:05:56.580Z,1549584356.580 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:05:56.580Z,1549584356.580 [DUSBL_Hydroid] Communications Fault, FailCount= 5 2019-02-08T00:05:56.580Z,1549584356.580 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:05:56.709Z,1549584356.709 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:05:56.979Z,1549584356.979 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:05:56.979Z,1549584356.979 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:05:56.979Z,1549584356.979 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:05:57.379Z,1549584357.379 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:05:57.779Z,1549584357.779 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:05:57.847Z,1549584357.847 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:05:57.848Z,1549584357.848 [DUSBL_Hydroid] No Fault, FailCount= 5 2019-02-08T00:05:58.179Z,1549584358.179 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:58.579Z,1549584358.579 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:58.979Z,1549584358.979 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:59.388Z,1549584359.388 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:05:59.787Z,1549584359.787 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:06:00.179Z,1549584360.179 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:06:00.179Z,1549584360.179 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:06:00.180Z,1549584360.180 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:06:00.576Z,1549584360.576 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:00.979Z,1549584360.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:01.387Z,1549584361.387 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:01.783Z,1549584361.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:02.179Z,1549584362.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:02.579Z,1549584362.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:02.979Z,1549584362.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:03.379Z,1549584363.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:03.779Z,1549584363.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:04.179Z,1549584364.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:04.583Z,1549584364.583 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:04.991Z,1549584364.991 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:05.388Z,1549584365.388 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:05.783Z,1549584365.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:06.183Z,1549584366.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:06.579Z,1549584366.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:06.983Z,1549584366.983 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:07.383Z,1549584367.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:07.780Z,1549584367.780 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:08.184Z,1549584368.184 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:08.579Z,1549584368.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:08.979Z,1549584368.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:09.379Z,1549584369.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:09.789Z,1549584369.789 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:10.183Z,1549584370.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:10.580Z,1549584370.580 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:10.984Z,1549584370.984 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:11.379Z,1549584371.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:11.779Z,1549584371.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:12.183Z,1549584372.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:12.579Z,1549584372.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:12.988Z,1549584372.988 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:13.383Z,1549584373.383 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:13.779Z,1549584373.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:14.179Z,1549584374.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:14.580Z,1549584374.580 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:14.979Z,1549584374.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:15.379Z,1549584375.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:15.783Z,1549584375.783 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:16.179Z,1549584376.179 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:16.579Z,1549584376.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:16.979Z,1549584376.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:17.379Z,1549584377.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:17.800Z,1549584377.800 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:18.183Z,1549584378.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:18.579Z,1549584378.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:18.979Z,1549584378.979 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:19.380Z,1549584379.380 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:19.779Z,1549584379.779 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:20.183Z,1549584380.183 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:20.579Z,1549584380.579 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:20.580Z,1549584380.580 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:06:20.580Z,1549584380.580 [DUSBL_Hydroid] Communications Fault, FailCount= 6 2019-02-08T00:06:20.580Z,1549584380.580 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:06:20.746Z,1549584380.746 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:06:20.980Z,1549584380.980 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:06:20.980Z,1549584380.980 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:06:20.980Z,1549584380.980 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:06:21.388Z,1549584381.388 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:06:26.318Z,1549584386.318 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:06:26.492Z,1549584386.492 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-08T00:05:23.0Z 2019-02-08T00:06:26.493Z,1549584386.493 [Startup:StartupSatComms:A] Stopped 2019-02-08T00:06:26.493Z,1549584386.493 [Startup:StartupSatComms:B] Running Loop=1 2019-02-08T00:06:26.503Z,1549584386.503 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:06:26.504Z,1549584386.504 [DUSBL_Hydroid] No Fault, FailCount= 6 2019-02-08T00:06:26.561Z,1549584386.561 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:06:26.566Z,1549584386.566 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:06:26.566Z,1549584386.566 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:06:26.690Z,1549584386.690 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-08T00:06:26.927Z,1549584386.927 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:27.323Z,1549584387.323 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004934 2019-02-08T00:06:27.331Z,1549584387.331 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:27.727Z,1549584387.727 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:28.127Z,1549584388.127 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:31.379Z,1549584391.379 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:31.509Z,1549584391.509 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190207T235802/Courier0007.lzma 2019-02-08T00:06:31.528Z,1549584391.528 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:31.912Z,1549584391.912 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:32.312Z,1549584392.312 [DataOverHttps](INFO): Moved sent file to Logs/20190207T235802/Courier0007.lzma.bak 2019-02-08T00:06:32.312Z,1549584392.312 [DataOverHttps](INFO): SBD MOMSN=9641416 2019-02-08T00:06:32.329Z,1549584392.329 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:32.719Z,1549584392.719 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:33.115Z,1549584393.115 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:39.288Z,1549584399.288 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:39.554Z,1549584399.554 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:39.919Z,1549584399.919 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:40.323Z,1549584400.323 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:40.719Z,1549584400.719 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:41.119Z,1549584401.119 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:44.403Z,1549584404.403 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:44.567Z,1549584404.567 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20190207T235802/Express0008.lzma 2019-02-08T00:06:44.571Z,1549584404.571 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:44.935Z,1549584404.935 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:45.319Z,1549584405.319 [DataOverHttps](INFO): Moved sent file to Logs/20190207T235802/Express0008.lzma.bak 2019-02-08T00:06:45.319Z,1549584405.319 [DataOverHttps](INFO): SBD MOMSN=9641418 2019-02-08T00:06:45.332Z,1549584405.332 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:45.733Z,1549584405.733 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:46.127Z,1549584406.127 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:49.401Z,1549584409.401 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:49.401Z,1549584409.401 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0 2019-02-08T00:06:49.401Z,1549584409.401 [DUSBL_Hydroid] Communications Fault, FailCount= 7 2019-02-08T00:06:49.423Z,1549584409.423 [DUSBL_Hydroid](ERROR): Communications Fault 2019-02-08T00:06:49.538Z,1549584409.538 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid 2019-02-08T00:06:49.571Z,1549584409.571 [DUSBL_Hydroid](INFO): Stop 2019-02-08T00:06:49.571Z,1549584409.571 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:06:49.571Z,1549584409.571 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:06:49.959Z,1549584409.959 [DUSBL_Hydroid](INFO): Stopping 2019-02-08T00:06:50.359Z,1549584410.359 [DUSBL_Hydroid](INFO): Stopped 2019-02-08T00:06:50.449Z,1549584410.449 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid 2019-02-08T00:06:50.449Z,1549584410.449 [DUSBL_Hydroid] No Fault, FailCount= 7 2019-02-08T00:06:50.755Z,1549584410.755 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:06:51.165Z,1549584411.165 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:06:54.402Z,1549584414.402 [DUSBL_Hydroid](INFO): Start 2019-02-08T00:06:54.403Z,1549584414.403 [DUSBL_Hydroid](INFO): Powering up 2019-02-08T00:06:54.403Z,1549584414.403 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-02-08T00:06:54.639Z,1549584414.639 [DataOverHttps](INFO): Sending 856 bytes from file Logs/20190208T000349/Express0001.lzma 2019-02-08T00:06:54.663Z,1549584414.663 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:55.007Z,1549584415.007 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:55.399Z,1549584415.399 [DataOverHttps](INFO): Moved sent file to Logs/20190208T000349/Express0001.lzma.bak 2019-02-08T00:06:55.399Z,1549584415.399 [DataOverHttps](INFO): SBD MOMSN=9641421 2019-02-08T00:06:55.407Z,1549584415.407 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:55.823Z,1549584415.823 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:55.875Z,1549584415.875 [Startup:StartupSatComms:B] Stopped 2019-02-08T00:06:55.875Z,1549584415.875 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-02-08T00:06:55.875Z,1549584415.875 [Startup:StartupSatComms] Stopped 2019-02-08T00:06:55.875Z,1549584415.875 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-02-08T00:06:55.876Z,1549584415.876 [Startup](INFO): Completed Startup 2019-02-08T00:06:55.876Z,1549584415.876 [MissionManager](INFO): Startup is completed. 2019-02-08T00:06:55.877Z,1549584415.877 [MissionManager](INFO): Uninitializing Mission Startup 2019-02-08T00:06:55.877Z,1549584415.877 [Startup] Stopped 2019-02-08T00:06:55.877Z,1549584415.877 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-02-08T00:06:55.877Z,1549584415.877 [Startup:A.GoToSurface] Stopped 2019-02-08T00:06:55.877Z,1549584415.877 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-08T00:06:56.215Z,1549584416.215 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:56.258Z,1549584416.258 [MissionManager](IMPORTANT): Started mission Default 2019-02-08T00:06:56.258Z,1549584416.258 [Default] Running Loop=1 2019-02-08T00:06:56.259Z,1549584416.259 [Default](DEBUG): Aggregate::initialize Default 2019-02-08T00:06:56.259Z,1549584416.259 [Default:B.GoToSurface] Running Loop=1 2019-02-08T00:06:56.259Z,1549584416.259 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-08T00:06:56.259Z,1549584416.259 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-08T00:06:56.259Z,1549584416.259 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-08T00:06:56.260Z,1549584416.260 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-08T00:06:56.260Z,1549584416.260 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-08T00:06:56.260Z,1549584416.260 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-08T00:06:56.261Z,1549584416.261 [Default:A.Wait] Running Loop=1 2019-02-08T00:06:56.261Z,1549584416.261 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-02-08T00:06:58.095Z,1549584418.095 [CommandLine](IMPORTANT): got command restart system 2019-02-08T00:06:59.613Z,1549584419.613 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:06:59.851Z,1549584419.851 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:07:00.211Z,1549584420.211 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:07:00.251Z,1549584420.251 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-02-08T00:07:00.251Z,1549584420.251 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-08T00:07:00.252Z,1549584420.252 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-02-08T00:07:00.253Z,1549584420.253 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-08T00:07:00.258Z,1549584420.258 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-02-08T00:07:00.258Z,1549584420.258 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-08T00:07:00.284Z,1549584420.284 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-08T00:07:00.284Z,1549584420.284 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-08T00:07:00.284Z,1549584420.284 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-08T00:07:00.354Z,1549584420.354 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2019-02-08T00:07:00.358Z,1549584420.358 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:07:00.358Z,1549584420.358 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:00.458Z,1549584420.458 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-02-08T00:07:00.458Z,1549584420.458 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:00.459Z,1549584420.459 [CommandLine](INFO): Join timeout helper Thread ID is 8786 2019-02-08T00:07:00.459Z,1549584420.459 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-02-08T00:07:00.459Z,1549584420.459 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:00.460Z,1549584420.460 [NavChartDb](INFO): Join timeout helper Thread ID is 8787 2019-02-08T00:07:00.616Z,1549584420.616 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:07:00.706Z,1549584420.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-08T00:07:00.706Z,1549584420.706 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-02-08T00:07:00.706Z,1549584420.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-08T00:07:00.706Z,1549584420.706 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-02-08T00:07:00.707Z,1549584420.707 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-08T00:07:00.707Z,1549584420.707 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-02-08T00:07:00.710Z,1549584420.710 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:07:00.710Z,1549584420.710 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:00.718Z,1549584420.718 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2019-02-08T00:07:00.718Z,1549584420.718 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:00.718Z,1549584420.718 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8788 2019-02-08T00:07:00.746Z,1549584420.746 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:07:00.746Z,1549584420.746 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-02-08T00:07:00.747Z,1549584420.747 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:00.750Z,1549584420.750 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-02-08T00:07:00.750Z,1549584420.750 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:00.750Z,1549584420.750 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8789 2019-02-08T00:07:00.846Z,1549584420.846 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:07:00.846Z,1549584420.846 [CTD_NeilBrown](INFO): Powering down 2019-02-08T00:07:00.858Z,1549584420.858 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:00.874Z,1549584420.874 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-02-08T00:07:00.874Z,1549584420.874 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:00.874Z,1549584420.874 [Radio_Surface](INFO): Join timeout helper Thread ID is 8790 2019-02-08T00:07:01.011Z,1549584421.011 [DUSBL_Hydroid](INFO): Starting 2019-02-08T00:07:01.067Z,1549584421.067 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-08T00:07:01.182Z,1549584421.182 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:07:01.182Z,1549584421.182 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.195Z,1549584421.195 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-02-08T00:07:01.195Z,1549584421.195 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.195Z,1549584421.195 [logger](INFO): Join timeout helper Thread ID is 8791 2019-02-08T00:07:01.238Z,1549584421.238 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:07:01.238Z,1549584421.238 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.254Z,1549584421.254 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-02-08T00:07:01.255Z,1549584421.255 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.255Z,1549584421.255 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-02-08T00:07:01.255Z,1549584421.255 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.255Z,1549584421.255 [controlThread](INFO): Join timeout helper Thread ID is 8792 2019-02-08T00:07:01.394Z,1549584421.394 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-08T00:07:01.394Z,1549584421.394 [controlThread](DEBUG): Uninitializing ControlThread 2019-02-08T00:07:01.395Z,1549584421.395 [AHRS_M2](INFO): Powering down 2019-02-08T00:07:01.467Z,1549584421.467 [DUSBL_Hydroid](INFO): uninitialize 2019-02-08T00:07:01.467Z,1549584421.467 [DUSBL_Hydroid](INFO): Powering down 2019-02-08T00:07:01.538Z,1549584421.538 [NAL9602](INFO): Powering down 2019-02-08T00:07:01.610Z,1549584421.610 [RDI_Pathfinder](INFO): Powering down 2019-02-08T00:07:01.611Z,1549584421.611 [RDI_PathfinderUp](INFO): Powering down 2019-02-08T00:07:01.612Z,1549584421.612 [Aanderaa_O2](INFO): Powering down 2019-02-08T00:07:01.613Z,1549584421.613 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-02-08T00:07:01.615Z,1549584421.615 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-02-08T00:07:01.615Z,1549584421.615 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-02-08T00:07:01.616Z,1549584421.616 [MissionManager](INFO): Uninitializing Mission Default 2019-02-08T00:07:01.616Z,1549584421.616 [Default] Stopped 2019-02-08T00:07:01.616Z,1549584421.616 [Default](DEBUG): Aggregate::uninitialize Default 2019-02-08T00:07:01.616Z,1549584421.616 [Default:A.Wait] Stopped 2019-02-08T00:07:01.616Z,1549584421.616 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-02-08T00:07:01.616Z,1549584421.616 [Default:B.GoToSurface] Stopped 2019-02-08T00:07:01.616Z,1549584421.616 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-08T00:07:01.619Z,1549584421.619 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-02-08T00:07:01.619Z,1549584421.619 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-02-08T00:07:01.619Z,1549584421.619 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-02-08T00:07:01.620Z,1549584421.620 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-02-08T00:07:01.620Z,1549584421.620 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-02-08T00:07:01.620Z,1549584421.620 [BuoyancyServo](INFO): Powering down 2019-02-08T00:07:01.634Z,1549584421.634 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-02-08T00:07:01.634Z,1549584421.634 [ElevatorServo](INFO): Powering down 2019-02-08T00:07:01.635Z,1549584421.635 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-08T00:07:01.635Z,1549584421.635 [MassServo](INFO): Powering down 2019-02-08T00:07:01.636Z,1549584421.636 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-02-08T00:07:01.636Z,1549584421.636 [RudderServo](INFO): Powering down 2019-02-08T00:07:01.637Z,1549584421.637 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-02-08T00:07:01.637Z,1549584421.637 [ThrusterServo](INFO): Powering down 2019-02-08T00:07:01.638Z,1549584421.638 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-02-08T00:07:01.638Z,1549584421.638 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-02-08T00:07:01.638Z,1549584421.638 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-02-08T00:07:01.639Z,1549584421.639 [CBIT](DEBUG): Powering off loads. 2019-02-08T00:07:01.650Z,1549584421.650 [CBIT](DEBUG): Disabling WDT. 2019-02-08T00:07:01.662Z,1549584421.662 [CBIT](DEBUG): Opening all GF detection circuits. 2019-02-08T00:07:01.663Z,1549584421.663 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.771Z,1549584421.771 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.816Z,1549584421.816 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.819Z,1549584421.819 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.842Z,1549584421.842 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-08T00:07:01.901Z,1549584421.901 [logger ThreadHandler](INFO): Thread cancelled.