2019-01-08T18:04:24.090Z,1546970664.090 [Supervisor](DEBUG): Initializing supervisor.
2019-01-08T18:04:24.093Z,1546970664.093 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-01-08T18:04:24.094Z,1546970664.094 [SyncHandler](INFO): Protected caller Thread ID is 1155
2019-01-08T18:04:24.094Z,1546970664.094 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-01-08T18:04:24.095Z,1546970664.095 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-01-08T18:04:24.096Z,1546970664.096 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1156
2019-01-08T18:04:24.099Z,1546970664.099 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-01-08T18:04:24.111Z,1546970664.111 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-01-08T18:04:24.112Z,1546970664.112 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-01-08T18:04:24.112Z,1546970664.112 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1157
2019-01-08T18:04:24.113Z,1546970664.113 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-01-08T18:04:24.114Z,1546970664.114 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-01-08T18:04:24.114Z,1546970664.114 [logger ThreadHandler](INFO): Protected caller Thread ID is 1158
2019-01-08T18:04:24.116Z,1546970664.116 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-01-08T18:04:24.116Z,1546970664.116 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-01-08T18:04:24.118Z,1546970664.118 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-01-08T18:04:24.439Z,1546970664.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-01-08T18:04:24.439Z,1546970664.439 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-01-08T18:04:24.885Z,1546970664.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-01-08T18:04:24.886Z,1546970664.886 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-01-08T18:04:25.230Z,1546970665.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-01-08T18:04:25.230Z,1546970665.230 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-01-08T18:04:25.425Z,1546970665.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-01-08T18:04:25.425Z,1546970665.425 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-01-08T18:04:25.959Z,1546970665.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-01-08T18:04:25.960Z,1546970665.960 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-01-08T18:04:26.495Z,1546970666.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-01-08T18:04:26.495Z,1546970666.495 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-01-08T18:04:26.599Z,1546970666.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-01-08T18:04:26.600Z,1546970666.600 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-01-08T18:04:26.798Z,1546970666.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-01-08T18:04:26.798Z,1546970666.798 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-01-08T18:04:26.942Z,1546970666.942 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-01-08T18:04:26.942Z,1546970666.942 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-01-08T18:04:27.023Z,1546970667.023 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-01-08T18:04:27.240Z,1546970667.240 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-01-08T18:04:27.241Z,1546970667.241 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-01-08T18:04:27.341Z,1546970667.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-01-08T18:04:27.342Z,1546970667.342 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-01-08T18:04:27.437Z,1546970667.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-01-08T18:04:27.438Z,1546970667.438 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-01-08T18:04:27.587Z,1546970667.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-01-08T18:04:27.588Z,1546970667.588 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-01-08T18:04:27.903Z,1546970667.903 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-01-08T18:04:27.906Z,1546970667.906 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-01-08T18:04:27.907Z,1546970667.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-01-08T18:04:28.038Z,1546970668.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-01-08T18:04:28.263Z,1546970668.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-01-08T18:04:28.264Z,1546970668.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-01-08T18:04:28.437Z,1546970668.437 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-01-08T18:04:28.537Z,1546970668.537 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-01-08T18:04:28.635Z,1546970668.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-01-08T18:04:28.829Z,1546970668.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-01-08T18:04:28.913Z,1546970668.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-01-08T18:04:29.011Z,1546970669.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-01-08T18:04:29.104Z,1546970669.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-01-08T18:04:29.186Z,1546970669.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-01-08T18:04:29.295Z,1546970669.295 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-01-08T18:04:29.295Z,1546970669.295 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-01-08T18:04:29.299Z,1546970669.299 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-01-08T18:04:29.434Z,1546970669.434 [SBIT](DEBUG): Construct Startup Built In Test.
2019-01-08T18:04:29.464Z,1546970669.464 [SBIT] Loaded
2019-01-08T18:04:29.464Z,1546970669.464 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-01-08T18:04:29.465Z,1546970669.465 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-01-08T18:04:29.492Z,1546970669.492 [IBIT] Loaded
2019-01-08T18:04:29.493Z,1546970669.493 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-01-08T18:04:29.496Z,1546970669.496 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-01-08T18:04:29.651Z,1546970669.651 [CBIT] Loaded
2019-01-08T18:04:29.652Z,1546970669.652 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-01-08T18:04:29.652Z,1546970669.652 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-01-08T18:04:29.653Z,1546970669.653 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-01-08T18:04:29.772Z,1546970669.772 [BuoyancyServo] Loaded
2019-01-08T18:04:29.772Z,1546970669.772 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-01-08T18:04:29.787Z,1546970669.787 [ElevatorServo] Loaded
2019-01-08T18:04:29.787Z,1546970669.787 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-01-08T18:04:29.801Z,1546970669.801 [MassServo] Loaded
2019-01-08T18:04:29.801Z,1546970669.801 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-01-08T18:04:29.816Z,1546970669.816 [RudderServo] Loaded
2019-01-08T18:04:29.817Z,1546970669.817 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-01-08T18:04:29.831Z,1546970669.831 [ThrusterServo] Loaded
2019-01-08T18:04:29.832Z,1546970669.832 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-01-08T18:04:29.832Z,1546970669.832 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-01-08T18:04:29.833Z,1546970669.833 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-01-08T18:04:29.933Z,1546970669.933 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-01-08T18:04:29.934Z,1546970669.934 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-01-08T18:04:29.988Z,1546970669.988 [DeadReckonUsingSpeedCalculator] Loaded
2019-01-08T18:04:29.988Z,1546970669.988 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-01-08T18:04:30.005Z,1546970670.005 [NavChart] Loaded
2019-01-08T18:04:30.005Z,1546970670.005 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-01-08T18:04:30.009Z,1546970670.009 [UniversalFixResidualReporter] Loaded
2019-01-08T18:04:30.009Z,1546970670.009 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-01-08T18:04:30.009Z,1546970670.009 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-01-08T18:04:30.010Z,1546970670.010 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-01-08T18:04:30.023Z,1546970670.023 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-01-08T18:04:30.024Z,1546970670.024 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-01-08T18:04:30.277Z,1546970670.277 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-01-08T18:04:30.278Z,1546970670.278 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-01-08T18:04:30.588Z,1546970670.588 [AHRS_M2] Loaded
2019-01-08T18:04:30.588Z,1546970670.588 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-01-08T18:04:30.658Z,1546970670.658 [DataOverHttps] Loaded
2019-01-08T18:04:30.659Z,1546970670.659 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-01-08T18:04:30.672Z,1546970670.672 [Depth_Keller] Loaded
2019-01-08T18:04:30.672Z,1546970670.672 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-01-08T18:04:30.677Z,1546970670.677 [DropWeight] Loaded
2019-01-08T18:04:30.677Z,1546970670.677 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-01-08T18:04:30.770Z,1546970670.770 [NAL9602] Loaded
2019-01-08T18:04:30.770Z,1546970670.770 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-01-08T18:04:30.775Z,1546970670.775 [Onboard] Loaded
2019-01-08T18:04:30.775Z,1546970670.775 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-01-08T18:04:30.782Z,1546970670.782 [Radio_Surface] Loaded
2019-01-08T18:04:30.782Z,1546970670.782 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-01-08T18:04:30.783Z,1546970670.783 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408364E0
2019-01-08T18:04:30.784Z,1546970670.784 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1237
2019-01-08T18:04:30.812Z,1546970670.812 [RDI_Pathfinder] Loaded
2019-01-08T18:04:30.812Z,1546970670.812 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-01-08T18:04:30.835Z,1546970670.835 [RDI_PathfinderUp] Loaded
2019-01-08T18:04:30.835Z,1546970670.835 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-01-08T18:04:30.841Z,1546970670.841 [SCPI] Loaded
2019-01-08T18:04:30.841Z,1546970670.841 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2019-01-08T18:04:30.842Z,1546970670.842 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-01-08T18:04:30.843Z,1546970670.843 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-01-08T18:04:30.914Z,1546970670.914 [VerticalControl](DEBUG): Construct VerticalControl.
2019-01-08T18:04:31.002Z,1546970671.002 [VerticalControl] Loaded
2019-01-08T18:04:31.003Z,1546970671.003 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-01-08T18:04:31.003Z,1546970671.003 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-01-08T18:04:31.061Z,1546970671.061 [HorizontalControl] Loaded
2019-01-08T18:04:31.061Z,1546970671.061 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-01-08T18:04:31.062Z,1546970671.062 [SpeedControl](DEBUG): Construct SpeedControl.
2019-01-08T18:04:31.064Z,1546970671.064 [SpeedControl] Loaded
2019-01-08T18:04:31.064Z,1546970671.064 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-01-08T18:04:31.065Z,1546970671.065 [LoopControl](DEBUG): Construct LoopControl.
2019-01-08T18:04:31.066Z,1546970671.066 [LoopControl] Loaded
2019-01-08T18:04:31.066Z,1546970671.066 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-01-08T18:04:31.066Z,1546970671.066 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-01-08T18:04:31.067Z,1546970671.067 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-01-08T18:04:31.274Z,1546970671.274 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-01-08T18:04:31.274Z,1546970671.274 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-01-08T18:04:31.598Z,1546970671.598 [Aanderaa_O2] Loaded
2019-01-08T18:04:31.598Z,1546970671.598 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-01-08T18:04:31.753Z,1546970671.753 [CTD_NeilBrown] Loaded
2019-01-08T18:04:31.753Z,1546970671.753 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-01-08T18:04:31.754Z,1546970671.754 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409374E0
2019-01-08T18:04:31.755Z,1546970671.755 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1238
2019-01-08T18:04:31.805Z,1546970671.805 [WetLabsSeaOWL_UV_A] Loaded
2019-01-08T18:04:31.805Z,1546970671.805 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-01-08T18:04:31.806Z,1546970671.806 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409674E0
2019-01-08T18:04:31.807Z,1546970671.807 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1239
2019-01-08T18:04:31.807Z,1546970671.807 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-01-08T18:04:31.808Z,1546970671.808 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-01-08T18:04:31.876Z,1546970671.876 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-01-08T18:04:31.876Z,1546970671.876 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-01-08T18:04:31.919Z,1546970671.919 [DepthRateCalculator] Loaded
2019-01-08T18:04:31.919Z,1546970671.919 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-01-08T18:04:31.925Z,1546970671.925 [PitchRateCalculator] Loaded
2019-01-08T18:04:31.926Z,1546970671.926 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-01-08T18:04:31.938Z,1546970671.938 [SpeedCalculator] Loaded
2019-01-08T18:04:31.938Z,1546970671.938 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-01-08T18:04:31.959Z,1546970671.959 [TempGradientCalculator] Loaded
2019-01-08T18:04:31.959Z,1546970671.959 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-01-08T18:04:31.965Z,1546970671.965 [YawRateCalculator] Loaded
2019-01-08T18:04:31.965Z,1546970671.965 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-01-08T18:04:31.995Z,1546970671.995 [ElevatorOffsetCalculator] Loaded
2019-01-08T18:04:31.995Z,1546970671.995 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-01-08T18:04:31.996Z,1546970671.996 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-01-08T18:04:31.996Z,1546970671.996 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-01-08T18:04:32.022Z,1546970672.022 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-01-08T18:04:32.025Z,1546970672.025 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-01-08T18:04:32.026Z,1546970672.026 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-01-08T18:04:32.033Z,1546970672.033 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-01-08T18:04:32.034Z,1546970672.034 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0
2019-01-08T18:04:32.034Z,1546970672.034 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1240
2019-01-08T18:04:32.039Z,1546970672.039 [Supervisor](INFO): Main Thread ID is 996
2019-01-08T18:04:32.039Z,1546970672.039 [Supervisor](DEBUG): Running supervisor.
2019-01-08T18:04:32.039Z,1546970672.039 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1241
2019-01-08T18:04:32.042Z,1546970672.042 [controlThread ThreadHandler](INFO): Handler Thread ID is 1242
2019-01-08T18:04:32.042Z,1546970672.042 [controlThread](DEBUG): Initializing ControlThread
2019-01-08T18:04:32.043Z,1546970672.043 [SBIT](INFO): Initialize SBIT Component.
2019-01-08T18:04:32.044Z,1546970672.044 [SBIT](IMPORTANT): git: 2018-11-05-2-g6a71760
2019-01-08T18:04:32.044Z,1546970672.044 [SBIT](INFO): git hash: 6a71760e9f7f683776be04bba2e56bc8c8a5e6e9
2019-01-08T18:04:32.044Z,1546970672.044 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-01-08T18:04:32.044Z,1546970672.044 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-01-08T18:04:32.046Z,1546970672.046 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-01-08T18:04:32.046Z,1546970672.046 [IBIT](INFO): Initialize IBIT Component.
2019-01-08T18:04:32.047Z,1546970672.047 [CBIT](DEBUG): Initialize CBIT Component.
2019-01-08T18:04:32.047Z,1546970672.047 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-01-08T18:04:32.048Z,1546970672.048 [logger ThreadHandler](INFO): Handler Thread ID is 1243
2019-01-08T18:04:32.065Z,1546970672.065 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1244
2019-01-08T18:04:32.071Z,1546970672.071 [Radio_Surface](INFO): Powering up
2019-01-08T18:04:32.077Z,1546970672.077 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1245
2019-01-08T18:04:32.078Z,1546970672.078 [CTD_NeilBrown](INFO): Powering down
2019-01-08T18:04:32.109Z,1546970672.109 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1246
2019-01-08T18:04:32.110Z,1546970672.110 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-01-08T18:04:32.141Z,1546970672.141 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1247
2019-01-08T18:04:32.145Z,1546970672.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-01-08T18:04:32.145Z,1546970672.145 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-01-08T18:04:32.145Z,1546970672.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-01-08T18:04:32.145Z,1546970672.145 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-01-08T18:04:32.145Z,1546970672.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-01-08T18:04:32.146Z,1546970672.146 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-01-08T18:04:32.146Z,1546970672.146 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-01-08T18:04:32.146Z,1546970672.146 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-01-08T18:04:32.146Z,1546970672.146 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-01-08T18:04:32.146Z,1546970672.146 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-01-08T18:04:32.147Z,1546970672.147 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-01-08T18:04:32.147Z,1546970672.147 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-01-08T18:04:32.147Z,1546970672.147 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-01-08T18:04:32.147Z,1546970672.147 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-01-08T18:04:32.147Z,1546970672.147 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-01-08T18:04:32.147Z,1546970672.147 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-01-08T18:04:32.154Z,1546970672.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-08T18:04:32.155Z,1546970672.155 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:04:32.155Z,1546970672.155 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:04:32.156Z,1546970672.156 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-08T18:04:32.156Z,1546970672.156 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:04:32.156Z,1546970672.156 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:04:32.157Z,1546970672.157 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-01-08T18:04:32.157Z,1546970672.157 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-01-08T18:04:32.163Z,1546970672.163 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-01-08T18:04:32.170Z,1546970672.170 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-01-08T18:04:32.170Z,1546970672.170 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-01-08T18:04:32.171Z,1546970672.171 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-01-08T18:04:32.171Z,1546970672.171 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-01-08T18:04:32.172Z,1546970672.172 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-01-08T18:04:32.172Z,1546970672.172 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-01-08T18:04:32.172Z,1546970672.172 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-01-08T18:04:32.181Z,1546970672.181 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-01-08T18:04:32.182Z,1546970672.182 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-01-08T18:04:32.183Z,1546970672.183 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-01-08T18:04:32.230Z,1546970672.230 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-08T18:04:32.253Z,1546970672.253 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-01-08T18:04:32.254Z,1546970672.254 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-01-08T18:04:32.332Z,1546970672.332 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-01-08T18:04:32.349Z,1546970672.349 [Default:A.Wait](DEBUG): Construct Wait.
2019-01-08T18:04:32.351Z,1546970672.351 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-08T18:04:32.378Z,1546970672.378 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-01-08T18:04:32.397Z,1546970672.397 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-01-08T18:04:32.402Z,1546970672.402 [Default:E.Execute](DEBUG): Construct Execute.
2019-01-08T18:04:32.426Z,1546970672.426 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-01-08T18:04:32.431Z,1546970672.431 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-01-08T18:04:32.452Z,1546970672.452 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:04:32.516Z,1546970672.516 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-01-08T18:04:32.544Z,1546970672.544 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-01-08T18:04:32.699Z,1546970672.699 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-08T18:04:32.889Z,1546970672.889 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:04:32.897Z,1546970672.897 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-08T18:04:32.919Z,1546970672.919 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:04:32.925Z,1546970672.925 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-01-08T18:04:32.939Z,1546970672.939 [MassServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:04:32.945Z,1546970672.945 [MassServo](DEBUG): Initializing MassServo.
2019-01-08T18:04:32.959Z,1546970672.959 [RudderServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:04:32.965Z,1546970672.965 [RudderServo](DEBUG): Initializing RudderServo.
2019-01-08T18:04:32.987Z,1546970672.987 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:04:32.993Z,1546970672.993 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-01-08T18:04:33.149Z,1546970673.149 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-01-08T18:04:33.150Z,1546970673.150 [DropWeight] Hardware Fault, FailCount= 1
2019-01-08T18:04:33.150Z,1546970673.150 [DropWeight](ERROR): Hardware Fault
2019-01-08T18:04:33.241Z,1546970673.241 [RDI_PathfinderUp](INFO): Powering down
2019-01-08T18:04:33.301Z,1546970673.301 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-08T18:04:33.330Z,1546970673.330 [CommandLine](FAULT): Scheduling is paused
2019-01-08T18:04:33.330Z,1546970673.330 [CBIT](INFO): Critical error at 20190108T180433
2019-01-08T18:04:33.330Z,1546970673.330 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-01-08T18:04:33.333Z,1546970673.333 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-01-08T18:04:33.333Z,1546970673.333 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-01-08T18:04:33.497Z,1546970673.497 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-08T18:04:33.833Z,1546970673.833 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-01-08T18:04:33.833Z,1546970673.833 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-01-08T18:04:33.833Z,1546970673.833 [BuoyancyServo] Communications Fault, FailCount= 1
2019-01-08T18:04:33.833Z,1546970673.833 [BuoyancyServo](ERROR): Communications Fault
2019-01-08T18:04:34.039Z,1546970674.039 [CBIT](INFO): Critical error at 20190108T180433
2019-01-08T18:04:34.041Z,1546970674.041 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-01-08T18:04:36.121Z,1546970676.121 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2019-01-08T18:04:36.214Z,1546970676.214 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-01-08T18:04:36.214Z,1546970676.214 [BuoyancyServo] No Fault, FailCount= 1
2019-01-08T18:04:36.507Z,1546970676.507 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:04:36.626Z,1546970676.626 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-08T18:04:37.721Z,1546970677.721 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2019-01-08T18:04:42.138Z,1546970682.138 [CBIT](CRITICAL): Environmental Failure. Press:14.824734 PSI. Humidity:37%. Temp:25 C. ABORTING MISSION
2019-01-08T18:04:42.499Z,1546970682.499 [CBIT](INFO): Critical error at 20190108T180442
2019-01-08T18:04:42.882Z,1546970682.882 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-08T18:04:42.882Z,1546970682.882 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-08T18:04:42.882Z,1546970682.882 [Aanderaa_O2](ERROR): Communications Fault
2019-01-08T18:04:43.082Z,1546970683.082 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-08T18:04:43.401Z,1546970683.401 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:04:44.128Z,1546970684.128 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-08T18:04:44.128Z,1546970684.128 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-08T18:04:46.061Z,1546970686.061 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-01-08T18:04:46.061Z,1546970686.061 [AHRS_M2] Data Fault, FailCount= 1
2019-01-08T18:04:46.061Z,1546970686.061 [AHRS_M2](ERROR): Data Fault
2019-01-08T18:04:46.165Z,1546970686.165 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-01-08T18:04:46.450Z,1546970686.450 [AHRS_M2](INFO): Powering down
2019-01-08T18:04:49.702Z,1546970689.702 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadB3
2019-01-08T18:04:50.104Z,1546970690.104 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-01-08T18:04:50.104Z,1546970690.104 [AHRS_M2] No Fault, FailCount= 1
2019-01-08T18:04:50.450Z,1546970690.450 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:04:53.973Z,1546970693.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:54.399Z,1546970694.399 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:54.813Z,1546970694.813 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:54.834Z,1546970694.834 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-08T18:04:54.834Z,1546970694.834 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-08T18:04:54.834Z,1546970694.834 [Aanderaa_O2](ERROR): Communications Fault
2019-01-08T18:04:54.917Z,1546970694.917 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-08T18:04:55.241Z,1546970695.241 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:55.261Z,1546970695.261 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-01-08T18:04:55.261Z,1546970695.261 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-01-08T18:04:55.261Z,1546970695.261 [RDI_Pathfinder](ERROR): Communications Fault
2019-01-08T18:04:55.377Z,1546970695.377 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:04:55.406Z,1546970695.406 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-01-08T18:04:55.761Z,1546970695.761 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:55.865Z,1546970695.865 [RDI_Pathfinder](INFO): Powering down
2019-01-08T18:04:56.245Z,1546970696.245 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:56.320Z,1546970696.320 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-08T18:04:56.320Z,1546970696.320 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-08T18:04:56.681Z,1546970696.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:56.773Z,1546970696.773 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-01-08T18:04:56.773Z,1546970696.773 [RDI_Pathfinder] No Fault, FailCount= 1
2019-01-08T18:04:57.125Z,1546970697.125 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:57.565Z,1546970697.565 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:58.017Z,1546970698.017 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:58.421Z,1546970698.421 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:58.829Z,1546970698.829 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:59.249Z,1546970699.249 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:04:59.657Z,1546970699.657 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:00.081Z,1546970700.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:00.509Z,1546970700.509 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:00.509Z,1546970700.509 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-01-08T18:05:00.509Z,1546970700.509 [AHRS_M2] Communications Fault, FailCount= 2
2019-01-08T18:05:00.509Z,1546970700.509 [AHRS_M2](ERROR): Communications Fault
2019-01-08T18:05:00.593Z,1546970700.593 [SBIT](IMPORTANT): Beginning Startup BIT
2019-01-08T18:05:00.597Z,1546970700.597 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-01-08T18:05:00.598Z,1546970700.598 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-08T18:05:00.617Z,1546970700.617 [AHRS_M2](INFO): Powering down
2019-01-08T18:05:01.030Z,1546970701.030 [NAL9602](INFO): Powering up NAL9602
2019-01-08T18:05:04.451Z,1546970704.451 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-01-08T18:05:04.451Z,1546970704.451 [AHRS_M2] No Fault, FailCount= 2
2019-01-08T18:05:04.730Z,1546970704.730 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:05:06.747Z,1546970706.747 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-08T18:05:06.747Z,1546970706.747 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-08T18:05:06.748Z,1546970706.748 [Aanderaa_O2](ERROR): Communications Fault
2019-01-08T18:05:06.863Z,1546970706.863 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-08T18:05:06.863Z,1546970706.863 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-08T18:05:07.265Z,1546970707.265 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:05:08.245Z,1546970708.245 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:08.693Z,1546970708.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:09.229Z,1546970709.229 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:09.673Z,1546970709.673 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:10.117Z,1546970710.117 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:10.669Z,1546970710.669 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:11.113Z,1546970711.113 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:11.553Z,1546970711.553 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:12.101Z,1546970712.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:12.115Z,1546970712.115 [NAL9602](INFO): NAL9602 initialized
2019-01-08T18:05:12.326Z,1546970712.326 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.022424
CHAN A1 (24V): 0.058694
CHAN A2 (12V): -0.006964
CHAN A3 (5V): -0.002036
CHAN B0 (3.3V): 0.000390
CHAN B1 (3.15aV): 0.000005
CHAN B2 (3.15bV): -0.000119
CHAN B3 (GND): 0.001974
OPEN: -0.000133
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-08T18:05:12.725Z,1546970712.725 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:13.137Z,1546970713.137 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:13.573Z,1546970713.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:14.041Z,1546970714.041 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:14.477Z,1546970714.477 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:14.905Z,1546970714.905 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:14.905Z,1546970714.905 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-01-08T18:05:14.905Z,1546970714.905 [AHRS_M2] Communications Fault, FailCount= 3
2019-01-08T18:05:14.905Z,1546970714.905 [AHRS_M2](ERROR): Communications Fault
2019-01-08T18:05:14.990Z,1546970714.990 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-01-08T18:05:14.996Z,1546970714.996 [AHRS_M2](INFO): Powering down
2019-01-08T18:05:18.930Z,1546970718.930 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-01-08T18:05:18.930Z,1546970718.930 [AHRS_M2] No Fault, FailCount= 3
2019-01-08T18:05:19.172Z,1546970719.172 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:05:19.591Z,1546970719.591 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-01-08T18:05:19.591Z,1546970719.591 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-01-08T18:05:19.591Z,1546970719.591 [RDI_Pathfinder](ERROR): Communications Fault
2019-01-08T18:05:19.714Z,1546970719.714 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-01-08T18:05:20.073Z,1546970720.073 [RDI_Pathfinder](INFO): Powering down
2019-01-08T18:05:21.042Z,1546970721.042 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-01-08T18:05:21.042Z,1546970721.042 [RDI_Pathfinder] No Fault, FailCount= 2
2019-01-08T18:05:22.693Z,1546970722.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:22.870Z,1546970722.870 [CommandLine](IMPORTANT): got command gfscan
2019-01-08T18:05:23.157Z,1546970723.157 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:23.283Z,1546970723.283 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-08T18:05:23.605Z,1546970723.605 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:24.045Z,1546970724.045 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:24.493Z,1546970724.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:24.933Z,1546970724.933 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:25.457Z,1546970725.457 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:25.897Z,1546970725.897 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:26.329Z,1546970726.329 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:26.869Z,1546970726.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:27.290Z,1546970727.290 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:27.701Z,1546970727.701 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:28.221Z,1546970728.221 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:28.645Z,1546970728.645 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:29.065Z,1546970729.065 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:29.585Z,1546970729.585 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:29.585Z,1546970729.585 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-01-08T18:05:29.585Z,1546970729.585 [AHRS_M2] Communications Fault, FailCount= 4
2019-01-08T18:05:29.585Z,1546970729.585 [AHRS_M2](ERROR): Communications Fault
2019-01-08T18:05:29.699Z,1546970729.699 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-01-08T18:05:29.721Z,1546970729.721 [AHRS_M2](INFO): Powering down
2019-01-08T18:05:33.375Z,1546970733.375 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-01-08T18:05:33.375Z,1546970733.375 [AHRS_M2] No Fault, FailCount= 4
2019-01-08T18:05:33.718Z,1546970733.718 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:05:35.391Z,1546970735.391 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.021892
CHAN A1 (24V): 0.046074
CHAN A2 (12V): -0.007144
CHAN A3 (5V): -0.002181
CHAN B0 (3.3V): 0.000317
CHAN B1 (3.15aV): -0.000129
CHAN B2 (3.15bV): 0.000317
CHAN B3 (GND): 0.002195
OPEN: 0.000018
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-08T18:05:37.233Z,1546970737.233 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:37.657Z,1546970737.657 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:38.081Z,1546970738.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:38.481Z,1546970738.481 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:38.885Z,1546970738.885 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:39.289Z,1546970739.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:39.717Z,1546970739.717 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:40.117Z,1546970740.117 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:40.505Z,1546970740.505 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:40.921Z,1546970740.921 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:41.329Z,1546970741.329 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:41.753Z,1546970741.753 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:42.173Z,1546970742.173 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:42.753Z,1546970742.753 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:43.597Z,1546970743.597 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:43.638Z,1546970743.638 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-01-08T18:05:43.638Z,1546970743.638 [RDI_Pathfinder] Communications Fault, FailCount= 3
2019-01-08T18:05:43.638Z,1546970743.638 [RDI_Pathfinder](ERROR): Communications Fault
2019-01-08T18:05:43.774Z,1546970743.774 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-01-08T18:05:43.774Z,1546970743.774 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2019-01-08T18:05:44.101Z,1546970744.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:05:44.101Z,1546970744.101 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-01-08T18:05:44.101Z,1546970744.101 [AHRS_M2] Communications Fault, FailCount= 5
2019-01-08T18:05:44.101Z,1546970744.101 [AHRS_M2](ERROR): Communications Fault
2019-01-08T18:05:44.209Z,1546970744.209 [RDI_Pathfinder](INFO): Powering down
2019-01-08T18:05:44.344Z,1546970744.344 [CBIT](INFO): Critical error at 20190108T180543
2019-01-08T18:05:44.346Z,1546970744.346 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-01-08T18:05:44.347Z,1546970744.347 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-01-08T18:05:44.354Z,1546970744.354 [AHRS_M2](INFO): Powering down
2019-01-08T18:05:44.568Z,1546970744.568 [CBIT](INFO): Critical error at 20190108T180544
2019-01-08T18:05:45.168Z,1546970745.168 [CommandLine](IMPORTANT): got command gfscan
2019-01-08T18:05:45.386Z,1546970745.386 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-08T18:05:54.012Z,1546970754.012 [SBIT](IMPORTANT): SBIT PASSED
2019-01-08T18:05:54.070Z,1546970754.070 [CommandLine](IMPORTANT): got command configSet list
2019-01-08T18:05:54.070Z,1546970754.070 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-01-08T18:05:54.071Z,1546970754.071 [CommandLine](IMPORTANT): 

2019-01-08T18:05:54.071Z,1546970754.071 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2019-01-08T18:05:54.406Z,1546970754.406 [MissionManager](IMPORTANT): Started mission Startup
2019-01-08T18:05:54.407Z,1546970754.407 [Startup] Running Loop=1
2019-01-08T18:05:54.407Z,1546970754.407 [Startup](DEBUG): Aggregate::initialize Startup
2019-01-08T18:05:54.407Z,1546970754.407 [Startup:A.GoToSurface] Running Loop=1
2019-01-08T18:05:54.407Z,1546970754.407 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-08T18:05:54.408Z,1546970754.408 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-08T18:05:54.408Z,1546970754.408 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-08T18:05:54.408Z,1546970754.408 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-08T18:05:54.409Z,1546970754.409 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-08T18:05:54.409Z,1546970754.409 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-08T18:05:54.415Z,1546970754.415 [Startup:StartupSatComms] Running Loop=1
2019-01-08T18:05:54.415Z,1546970754.415 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-01-08T18:05:54.415Z,1546970754.415 [Startup:StartupSatComms:A] Running Loop=1
2019-01-08T18:05:54.785Z,1546970754.785 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-01-08T18:05:56.428Z,1546970756.428 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.022507
CHAN A1 (24V): 0.036726
CHAN A2 (12V): -0.007497
CHAN A3 (5V): -0.002036
CHAN B0 (3.3V): 0.000244
CHAN B1 (3.15aV): 0.000213
CHAN B2 (3.15bV): -0.000010
CHAN B3 (GND): 0.002036
OPEN: -0.000185
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-08T18:06:02.902Z,1546970762.902 [CommandLine](IMPORTANT): got command gfscan
2019-01-08T18:06:03.224Z,1546970763.224 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-08T18:06:14.004Z,1546970774.004 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.022281
CHAN A1 (24V): 0.030158
CHAN A2 (12V): -0.007377
CHAN A3 (5V): -0.001948
CHAN B0 (3.3V): 0.000526
CHAN B1 (3.15aV): -0.000229
CHAN B2 (3.15bV): 0.000081
CHAN B3 (GND): 0.002316
OPEN: -0.000023
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-08T18:06:20.541Z,1546970780.541 [CommandLine](IMPORTANT): got command gfscan
2019-01-08T18:06:20.808Z,1546970780.808 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-08T18:06:31.620Z,1546970791.620 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.021992
CHAN A1 (24V): 0.025487
CHAN A2 (12V): -0.007097
CHAN A3 (5V): -0.002227
CHAN B0 (3.3V): 0.000225
CHAN B1 (3.15aV): 0.000631
CHAN B2 (3.15bV): 0.000016
CHAN B3 (GND): 0.002401
OPEN: -0.000236
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-08T18:06:32.404Z,1546970792.404 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-01-08T18:06:32.404Z,1546970792.404 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-01-08T18:06:32.404Z,1546970792.404 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-08T18:06:32.406Z,1546970792.406 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-01-08T18:06:32.406Z,1546970792.406 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-01-08T18:06:32.406Z,1546970792.406 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-08T18:06:32.419Z,1546970792.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-08T18:06:32.419Z,1546970792.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-08T18:06:32.815Z,1546970792.815 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-08T18:06:32.815Z,1546970792.815 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-01-08T18:06:32.816Z,1546970792.816 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-08T18:06:32.816Z,1546970792.816 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-01-08T18:06:33.208Z,1546970793.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-08T18:06:33.209Z,1546970793.209 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:06:33.209Z,1546970793.209 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:06:33.210Z,1546970793.210 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-08T18:06:33.210Z,1546970793.210 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:06:33.210Z,1546970793.210 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:06:41.198Z,1546970801.198 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB3
2019-01-08T18:06:43.234Z,1546970803.234 [CommandLine](IMPORTANT): got command gfscan
2019-01-08T18:06:43.613Z,1546970803.613 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-08T18:06:56.679Z,1546970816.679 [Startup:StartupSatComms:A](INFO): Timed out from 2019-01-08T18:05:54.4Z
2019-01-08T18:06:56.679Z,1546970816.679 [Startup:StartupSatComms:A] Stopped
2019-01-08T18:06:56.679Z,1546970816.679 [Startup:StartupSatComms:B] Running Loop=1
2019-01-08T18:06:56.856Z,1546970816.856 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-01-08T18:06:56.873Z,1546970816.873 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.022307
CHAN A1 (24V): 0.172666
CHAN A2 (12V): -0.007354
CHAN A3 (5V): -0.001931
CHAN B0 (3.3V): 0.000085
CHAN B1 (3.15aV): -0.000208
CHAN B2 (3.15bV): 0.000248
CHAN B3 (GND): 0.002328
OPEN: 0.000144
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-08T18:06:57.607Z,1546970817.607 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003946
2019-01-08T18:07:02.031Z,1546970822.031 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20190108T175312/Courier0007.lzma
2019-01-08T18:07:02.831Z,1546970822.831 [DataOverHttps](INFO): Moved sent file to Logs/20190108T175312/Courier0007.lzma.bak
2019-01-08T18:07:02.831Z,1546970822.831 [DataOverHttps](INFO): SBD MOMSN=9158063
2019-01-08T18:07:05.249Z,1546970825.249 [CommandLine](IMPORTANT): got command gfscan
2019-01-08T18:07:06.866Z,1546970826.866 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-08T18:07:11.695Z,1546970831.695 [DataOverHttps](INFO): Sending 261 bytes from file Logs/20190108T180424/Courier0000.lzma
2019-01-08T18:07:12.495Z,1546970832.495 [DataOverHttps](INFO): Moved sent file to Logs/20190108T180424/Courier0000.lzma.bak
2019-01-08T18:07:12.495Z,1546970832.495 [DataOverHttps](INFO): SBD MOMSN=9158066
2019-01-08T18:07:21.961Z,1546970841.961 [DataOverHttps](INFO): Sending 759 bytes from file Logs/20190108T175312/Express0008.lzma
2019-01-08T18:07:22.759Z,1546970842.759 [DataOverHttps](INFO): Moved sent file to Logs/20190108T175312/Express0008.lzma.bak
2019-01-08T18:07:22.759Z,1546970842.759 [DataOverHttps](INFO): SBD MOMSN=9158074
2019-01-08T18:07:27.478Z,1546970847.478 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.022581
CHAN A1 (24V): 0.172387
CHAN A2 (12V): -0.007432
CHAN A3 (5V): -0.002096
CHAN B0 (3.3V): 0.000335
CHAN B1 (3.15aV): 0.000378
CHAN B2 (3.15bV): 0.000529
CHAN B3 (GND): 0.002330
OPEN: -0.000118
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-08T18:07:32.961Z,1546970852.961 [DataOverHttps](INFO): Sending 1270 bytes from file Logs/20190108T180424/Express0001.lzma
2019-01-08T18:07:33.739Z,1546970853.739 [DataOverHttps](INFO): Moved sent file to Logs/20190108T180424/Express0001.lzma.bak
2019-01-08T18:07:33.739Z,1546970853.739 [DataOverHttps](INFO): SBD MOMSN=9158103
2019-01-08T18:07:35.035Z,1546970855.035 [Startup:StartupSatComms:B] Stopped
2019-01-08T18:07:35.035Z,1546970855.035 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-01-08T18:07:35.035Z,1546970855.035 [Startup:StartupSatComms] Stopped
2019-01-08T18:07:35.035Z,1546970855.035 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-01-08T18:07:35.036Z,1546970855.036 [Startup](INFO): Completed Startup
2019-01-08T18:07:35.036Z,1546970855.036 [MissionManager](INFO): Startup is completed.
2019-01-08T18:07:35.037Z,1546970855.037 [MissionManager](INFO): Uninitializing Mission Startup
2019-01-08T18:07:35.037Z,1546970855.037 [Startup] Stopped
2019-01-08T18:07:35.037Z,1546970855.037 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-01-08T18:07:35.037Z,1546970855.037 [Startup:A.GoToSurface] Stopped
2019-01-08T18:07:35.037Z,1546970855.037 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-08T18:07:35.151Z,1546970855.151 [MissionManager](IMPORTANT): Started mission Default
2019-01-08T18:07:35.151Z,1546970855.151 [Default] Running Loop=1
2019-01-08T18:07:35.151Z,1546970855.151 [Default](DEBUG): Aggregate::initialize Default
2019-01-08T18:07:35.151Z,1546970855.151 [Default:B.GoToSurface] Running Loop=1
2019-01-08T18:07:35.152Z,1546970855.152 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-08T18:07:35.152Z,1546970855.152 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-08T18:07:35.152Z,1546970855.152 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-08T18:07:35.157Z,1546970855.157 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-08T18:07:35.157Z,1546970855.157 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-08T18:07:35.157Z,1546970855.157 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-08T18:07:35.158Z,1546970855.158 [Default:A.Wait] Running Loop=1
2019-01-08T18:07:35.158Z,1546970855.158 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-01-08T18:07:38.198Z,1546970858.198 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-08T18:07:38.198Z,1546970858.198 [Aanderaa_O2] No Fault, FailCount= 3
2019-01-08T18:07:44.675Z,1546970864.675 [CommandLine](IMPORTANT): got command show variable gf24
2019-01-08T18:07:44.716Z,1546970864.716 [CommandLine](IMPORTANT): CBIT.gf24Offset (microampere)
2019-01-08T18:07:48.260Z,1546970868.260 [Default:A.Wait](INFO): Done Waiting.
2019-01-08T18:07:48.260Z,1546970868.260 [Default:A.Wait] Stopped
2019-01-08T18:07:48.260Z,1546970868.260 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-01-08T18:07:48.640Z,1546970868.640 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-08T18:07:48.640Z,1546970868.640 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-08T18:07:48.640Z,1546970868.640 [Aanderaa_O2](ERROR): Communications Fault
2019-01-08T18:07:48.679Z,1546970868.679 [Default:CheckIn] Running Loop=1
2019-01-08T18:07:48.679Z,1546970868.679 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-08T18:07:48.679Z,1546970868.679 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-08T18:07:48.690Z,1546970868.690 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-08T18:07:49.153Z,1546970869.153 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:07:49.167Z,1546970869.167 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-01-08T18:07:49.843Z,1546970869.843 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-08T18:07:49.843Z,1546970869.843 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-08T18:07:57.071Z,1546970877.071 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 170.000000 milliampere persist
2019-01-08T18:07:59.115Z,1546970879.115 [CommandLine](IMPORTANT): got command restart application
2019-01-08T18:08:00.120Z,1546970880.120 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:08:00.120Z,1546970880.120 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:00.234Z,1546970880.234 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-08T18:08:00.234Z,1546970880.234 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-08T18:08:00.234Z,1546970880.234 [Aanderaa_O2](ERROR): Communications Fault
2019-01-08T18:08:00.253Z,1546970880.253 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-08T18:08:00.314Z,1546970880.314 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-01-08T18:08:00.314Z,1546970880.314 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:00.315Z,1546970880.315 [CommandLine](INFO): Join timeout helper Thread ID is 1278
2019-01-08T18:08:00.315Z,1546970880.315 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-01-08T18:08:00.315Z,1546970880.315 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:00.316Z,1546970880.316 [NavChartDb](INFO): Join timeout helper Thread ID is 1279
2019-01-08T18:08:00.389Z,1546970880.389 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:08:00.389Z,1546970880.389 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:00.393Z,1546970880.393 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-01-08T18:08:00.393Z,1546970880.393 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:00.393Z,1546970880.393 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1280
2019-01-08T18:08:00.629Z,1546970880.629 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:08:00.629Z,1546970880.629 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-01-08T18:08:00.630Z,1546970880.630 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:00.633Z,1546970880.633 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-01-08T18:08:00.633Z,1546970880.633 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:00.634Z,1546970880.634 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1281
2019-01-08T18:08:00.749Z,1546970880.749 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:08:00.829Z,1546970880.829 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:08:00.829Z,1546970880.829 [CTD_NeilBrown](INFO): Powering down
2019-01-08T18:08:00.841Z,1546970880.841 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:00.861Z,1546970880.861 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-01-08T18:08:00.861Z,1546970880.861 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:00.861Z,1546970880.861 [Radio_Surface](INFO): Join timeout helper Thread ID is 1282
2019-01-08T18:08:00.993Z,1546970880.993 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:08:00.993Z,1546970880.993 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.002Z,1546970881.002 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-01-08T18:08:01.002Z,1546970881.002 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.002Z,1546970881.002 [logger](INFO): Join timeout helper Thread ID is 1283
2019-01-08T18:08:01.014Z,1546970881.014 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:08:01.014Z,1546970881.014 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.030Z,1546970881.030 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-01-08T18:08:01.030Z,1546970881.030 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.030Z,1546970881.030 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-01-08T18:08:01.030Z,1546970881.030 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.030Z,1546970881.030 [controlThread](INFO): Join timeout helper Thread ID is 1284
2019-01-08T18:08:01.048Z,1546970881.048 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:08:01.048Z,1546970881.048 [controlThread](DEBUG): Uninitializing ControlThread
2019-01-08T18:08:01.048Z,1546970881.048 [AHRS_M2](INFO): Powering down
2019-01-08T18:08:01.050Z,1546970881.050 [NAL9602](INFO): Powering down
2019-01-08T18:08:01.051Z,1546970881.051 [RDI_Pathfinder](INFO): Powering down
2019-01-08T18:08:01.052Z,1546970881.052 [RDI_PathfinderUp](INFO): Powering down
2019-01-08T18:08:01.053Z,1546970881.053 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:08:01.054Z,1546970881.054 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-01-08T18:08:01.055Z,1546970881.055 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-01-08T18:08:01.056Z,1546970881.056 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-01-08T18:08:01.056Z,1546970881.056 [MissionManager](INFO): Uninitializing Mission Default
2019-01-08T18:08:01.056Z,1546970881.056 [Default] Stopped
2019-01-08T18:08:01.056Z,1546970881.056 [Default](DEBUG): Aggregate::uninitialize Default
2019-01-08T18:08:01.056Z,1546970881.056 [Default:B.GoToSurface] Stopped
2019-01-08T18:08:01.057Z,1546970881.057 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-08T18:08:01.057Z,1546970881.057 [Default:CheckIn] Stopped
2019-01-08T18:08:01.057Z,1546970881.057 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-08T18:08:01.057Z,1546970881.057 [Default:CheckIn:Read_GPS] Stopped
2019-01-08T18:08:01.060Z,1546970881.060 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-01-08T18:08:01.060Z,1546970881.060 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-01-08T18:08:01.060Z,1546970881.060 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-01-08T18:08:01.061Z,1546970881.061 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-01-08T18:08:01.061Z,1546970881.061 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-01-08T18:08:01.061Z,1546970881.061 [BuoyancyServo](INFO): Powering down
2019-01-08T18:08:01.073Z,1546970881.073 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-01-08T18:08:01.073Z,1546970881.073 [ElevatorServo](INFO): Powering down
2019-01-08T18:08:01.074Z,1546970881.074 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-01-08T18:08:01.074Z,1546970881.074 [MassServo](INFO): Powering down
2019-01-08T18:08:01.075Z,1546970881.075 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-01-08T18:08:01.075Z,1546970881.075 [RudderServo](INFO): Powering down
2019-01-08T18:08:01.076Z,1546970881.076 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-01-08T18:08:01.076Z,1546970881.076 [ThrusterServo](INFO): Powering down
2019-01-08T18:08:01.077Z,1546970881.077 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-01-08T18:08:01.077Z,1546970881.077 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-01-08T18:08:01.077Z,1546970881.077 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-01-08T18:08:01.078Z,1546970881.078 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.128Z,1546970881.128 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.172Z,1546970881.172 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.174Z,1546970881.174 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.196Z,1546970881.196 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:08:01.249Z,1546970881.249 [logger ThreadHandler](INFO): Thread cancelled.