2019-10-01T18:09:13.237Z,1569953353.237 [Supervisor](DEBUG): Initializing supervisor.
2019-10-01T18:09:13.240Z,1569953353.240 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-01T18:09:13.240Z,1569953353.240 [SyncHandler](INFO): Protected caller Thread ID is 8400
2019-10-01T18:09:13.241Z,1569953353.241 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-01T18:09:13.242Z,1569953353.242 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-01T18:09:13.242Z,1569953353.242 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8401
2019-10-01T18:09:13.245Z,1569953353.245 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-01T18:09:13.256Z,1569953353.256 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-01T18:09:13.257Z,1569953353.257 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-01T18:09:13.257Z,1569953353.257 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8402
2019-10-01T18:09:13.258Z,1569953353.258 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-01T18:09:13.259Z,1569953353.259 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-01T18:09:13.259Z,1569953353.259 [logger ThreadHandler](INFO): Protected caller Thread ID is 8403
2019-10-01T18:09:13.261Z,1569953353.261 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-01T18:09:13.262Z,1569953353.262 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-01T18:09:13.263Z,1569953353.263 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-01T18:09:13.358Z,1569953353.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-01T18:09:13.358Z,1569953353.358 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-01T18:09:13.913Z,1569953353.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-01T18:09:13.914Z,1569953353.914 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-01T18:09:14.011Z,1569953354.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-01T18:09:14.012Z,1569953354.012 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-01T18:09:14.113Z,1569953354.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-01T18:09:14.113Z,1569953354.113 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-01T18:09:14.195Z,1569953354.195 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-01T18:09:14.330Z,1569953354.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-01T18:09:14.331Z,1569953354.331 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-01T18:09:14.626Z,1569953354.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-01T18:09:14.627Z,1569953354.627 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-01T18:09:15.091Z,1569953355.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-01T18:09:15.091Z,1569953355.091 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-01T18:09:15.235Z,1569953355.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-01T18:09:15.235Z,1569953355.235 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-01T18:09:15.423Z,1569953355.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-01T18:09:15.424Z,1569953355.424 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-01T18:09:15.869Z,1569953355.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-01T18:09:15.870Z,1569953355.870 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-01T18:09:16.079Z,1569953356.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-01T18:09:16.080Z,1569953356.080 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-01T18:09:16.283Z,1569953356.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-01T18:09:16.284Z,1569953356.284 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-01T18:09:16.680Z,1569953356.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-01T18:09:16.680Z,1569953356.680 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-01T18:09:17.013Z,1569953357.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-01T18:09:17.015Z,1569953357.015 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-10-01T18:09:17.016Z,1569953357.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-10-01T18:09:17.099Z,1569953357.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-10-01T18:09:17.251Z,1569953357.251 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-10-01T18:09:17.358Z,1569953357.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-10-01T18:09:17.444Z,1569953357.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-10-01T18:09:17.539Z,1569953357.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-10-01T18:09:17.727Z,1569953357.727 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-10-01T18:09:17.953Z,1569953357.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-01T18:09:17.954Z,1569953357.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-10-01T18:09:18.186Z,1569953358.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-10-01T18:09:18.405Z,1569953358.405 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-10-01T18:09:18.812Z,1569953358.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-10-01T18:09:19.071Z,1569953359.071 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-10-01T18:09:19.082Z,1569953359.082 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-01T18:09:19.129Z,1569953359.129 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-01T18:09:19.241Z,1569953359.241 [VerticalControl] Loaded
2019-10-01T18:09:19.241Z,1569953359.241 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-01T18:09:19.242Z,1569953359.242 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-01T18:09:19.311Z,1569953359.311 [HorizontalControl] Loaded
2019-10-01T18:09:19.311Z,1569953359.311 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-01T18:09:19.312Z,1569953359.312 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-01T18:09:19.318Z,1569953359.318 [SpeedControl] Loaded
2019-10-01T18:09:19.318Z,1569953359.318 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-01T18:09:19.319Z,1569953359.319 [LoopControl](DEBUG): Construct LoopControl.
2019-10-01T18:09:19.319Z,1569953359.319 [LoopControl] Loaded
2019-10-01T18:09:19.319Z,1569953359.319 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-01T18:09:19.320Z,1569953359.320 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-01T18:09:19.320Z,1569953359.320 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-01T18:09:19.359Z,1569953359.359 [DepthRateCalculator] Loaded
2019-10-01T18:09:19.359Z,1569953359.359 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-01T18:09:19.365Z,1569953359.365 [PitchRateCalculator] Loaded
2019-10-01T18:09:19.365Z,1569953359.365 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-01T18:09:19.380Z,1569953359.380 [SpeedCalculator] Loaded
2019-10-01T18:09:19.380Z,1569953359.380 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-01T18:09:19.401Z,1569953359.401 [TempGradientCalculator] Loaded
2019-10-01T18:09:19.402Z,1569953359.402 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-01T18:09:19.407Z,1569953359.407 [YawRateCalculator] Loaded
2019-10-01T18:09:19.407Z,1569953359.407 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-01T18:09:19.437Z,1569953359.437 [ElevatorOffsetCalculator] Loaded
2019-10-01T18:09:19.437Z,1569953359.437 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-01T18:09:19.438Z,1569953359.438 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-01T18:09:19.438Z,1569953359.438 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-01T18:09:19.470Z,1569953359.470 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-01T18:09:19.471Z,1569953359.471 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-01T18:09:19.538Z,1569953359.538 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-01T18:09:19.539Z,1569953359.539 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-01T18:09:19.817Z,1569953359.817 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-01T18:09:19.818Z,1569953359.818 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-01T18:09:19.912Z,1569953359.912 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-01T18:09:19.912Z,1569953359.912 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-01T18:09:20.213Z,1569953360.213 [DataOverHttps] Loaded
2019-10-01T18:09:20.214Z,1569953360.214 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-01T18:09:20.215Z,1569953360.215 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2019-10-01T18:09:20.215Z,1569953360.215 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8483
2019-10-01T18:09:20.229Z,1569953360.229 [Depth_Keller] Loaded
2019-10-01T18:09:20.229Z,1569953360.229 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-01T18:09:20.234Z,1569953360.234 [DropWeight] Loaded
2019-10-01T18:09:20.235Z,1569953360.235 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-01T18:09:20.331Z,1569953360.331 [NAL9602] Loaded
2019-10-01T18:09:20.332Z,1569953360.332 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-01T18:09:20.347Z,1569953360.347 [Onboard] Loaded
2019-10-01T18:09:20.348Z,1569953360.348 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-01T18:09:20.351Z,1569953360.351 [Radio_Surface] Loaded
2019-10-01T18:09:20.351Z,1569953360.351 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-01T18:09:20.352Z,1569953360.352 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2019-10-01T18:09:20.352Z,1569953360.352 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8484
2019-10-01T18:09:20.396Z,1569953360.396 [RDI_Pathfinder] Loaded
2019-10-01T18:09:20.396Z,1569953360.396 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-10-01T18:09:20.517Z,1569953360.517 [DAT] Loaded
2019-10-01T18:09:20.517Z,1569953360.517 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-01T18:09:22.001Z,1569953362.001 [BPC1] Loaded
2019-10-01T18:09:22.001Z,1569953362.001 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-01T18:09:22.002Z,1569953362.002 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-01T18:09:22.002Z,1569953362.002 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-01T18:09:22.104Z,1569953362.104 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-01T18:09:22.104Z,1569953362.104 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-01T18:09:22.125Z,1569953362.125 [NavChart] Loaded
2019-10-01T18:09:22.125Z,1569953362.125 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-01T18:09:22.129Z,1569953362.129 [UniversalFixResidualReporter] Loaded
2019-10-01T18:09:22.129Z,1569953362.129 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-01T18:09:22.130Z,1569953362.130 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-01T18:09:22.130Z,1569953362.130 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-01T18:09:22.259Z,1569953362.259 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-01T18:09:22.270Z,1569953362.270 [SBIT] Loaded
2019-10-01T18:09:22.270Z,1569953362.270 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-01T18:09:22.271Z,1569953362.271 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-01T18:09:22.282Z,1569953362.282 [IBIT] Loaded
2019-10-01T18:09:22.282Z,1569953362.282 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-01T18:09:22.286Z,1569953362.286 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-01T18:09:22.424Z,1569953362.424 [CBIT] Loaded
2019-10-01T18:09:22.425Z,1569953362.425 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-01T18:09:22.425Z,1569953362.425 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-01T18:09:22.426Z,1569953362.426 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-01T18:09:22.526Z,1569953362.526 [BuoyancyServo] Loaded
2019-10-01T18:09:22.526Z,1569953362.526 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-01T18:09:22.538Z,1569953362.538 [ElevatorServo] Loaded
2019-10-01T18:09:22.538Z,1569953362.538 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-01T18:09:22.549Z,1569953362.549 [MassServo] Loaded
2019-10-01T18:09:22.549Z,1569953362.549 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-01T18:09:22.560Z,1569953362.560 [RudderServo] Loaded
2019-10-01T18:09:22.560Z,1569953362.560 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-01T18:09:22.571Z,1569953362.571 [ThrusterServo] Loaded
2019-10-01T18:09:22.571Z,1569953362.571 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-01T18:09:22.572Z,1569953362.572 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-01T18:09:22.572Z,1569953362.572 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-01T18:09:22.585Z,1569953362.585 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-01T18:09:22.586Z,1569953362.586 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-01T18:09:22.763Z,1569953362.763 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-01T18:09:22.768Z,1569953362.768 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-01T18:09:22.770Z,1569953362.770 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-01T18:09:22.774Z,1569953362.774 [CTD_Seabird](INFO): created writer for : depth
2019-10-01T18:09:22.775Z,1569953362.775 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-01T18:09:22.780Z,1569953362.780 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-01T18:09:22.780Z,1569953362.780 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-01T18:09:22.786Z,1569953362.786 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-01T18:09:22.786Z,1569953362.786 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-01T18:09:22.791Z,1569953362.791 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-01T18:09:22.792Z,1569953362.792 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-01T18:09:22.798Z,1569953362.798 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-01T18:09:22.798Z,1569953362.798 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-01T18:09:22.803Z,1569953362.803 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-01T18:09:22.831Z,1569953362.831 [CTD_Seabird] Loaded
2019-10-01T18:09:22.831Z,1569953362.831 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-01T18:09:22.832Z,1569953362.832 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A6A4E0
2019-10-01T18:09:22.833Z,1569953362.833 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8485
2019-10-01T18:09:22.847Z,1569953362.847 [PAR_Licor] Loaded
2019-10-01T18:09:22.847Z,1569953362.847 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-01T18:09:22.896Z,1569953362.896 [WetLabsBB2FL] Loaded
2019-10-01T18:09:22.896Z,1569953362.896 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-01T18:09:22.897Z,1569953362.897 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A9A4E0
2019-10-01T18:09:22.897Z,1569953362.897 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8486
2019-10-01T18:09:22.912Z,1569953362.912 [WetLabsUBAT..SerialDriver](INFO): Created logger
2019-10-01T18:09:22.912Z,1569953362.912 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2019-10-01T18:09:22.913Z,1569953362.913 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2019-10-01T18:09:22.918Z,1569953362.918 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest
2019-10-01T18:09:22.918Z,1569953362.918 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2019-10-01T18:09:22.918Z,1569953362.918 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2019-10-01T18:09:22.923Z,1569953362.923 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2019-10-01T18:09:22.923Z,1569953362.923 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2019-10-01T18:09:22.927Z,1569953362.927 [WetLabsUBAT] Loaded
2019-10-01T18:09:22.928Z,1569953362.928 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2019-10-01T18:09:22.929Z,1569953362.929 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0
2019-10-01T18:09:22.929Z,1569953362.929 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 8487
2019-10-01T18:09:22.929Z,1569953362.929 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-01T18:09:22.933Z,1569953362.933 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-01T18:09:22.934Z,1569953362.934 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-01T18:09:22.941Z,1569953362.941 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-01T18:09:22.942Z,1569953362.942 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0
2019-10-01T18:09:22.942Z,1569953362.942 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8488
2019-10-01T18:09:22.947Z,1569953362.947 [Supervisor](INFO): Main Thread ID is 8399
2019-10-01T18:09:22.947Z,1569953362.947 [Supervisor](DEBUG): Running supervisor.
2019-10-01T18:09:22.947Z,1569953362.947 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8489
2019-10-01T18:09:22.950Z,1569953362.950 [controlThread ThreadHandler](INFO): Handler Thread ID is 8490
2019-10-01T18:09:22.951Z,1569953362.951 [controlThread](DEBUG): Initializing ControlThread
2019-10-01T18:09:22.952Z,1569953362.952 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-01T18:09:22.953Z,1569953362.953 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-01T18:09:22.954Z,1569953362.954 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-01T18:09:22.954Z,1569953362.954 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-01T18:09:22.955Z,1569953362.955 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-01T18:09:22.955Z,1569953362.955 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-01T18:09:22.955Z,1569953362.955 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-01T18:09:22.956Z,1569953362.956 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-01T18:09:22.956Z,1569953362.956 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-01T18:09:22.956Z,1569953362.956 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-01T18:09:22.964Z,1569953362.964 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-01T18:09:22.964Z,1569953362.964 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-01T18:09:22.964Z,1569953362.964 [SBIT](INFO): Initialize SBIT Component.
2019-10-01T18:09:22.965Z,1569953362.965 [SBIT](IMPORTANT): git: 2019-09-23_TRN-1-gc869e16
2019-10-01T18:09:22.965Z,1569953362.965 [SBIT](INFO): git hash: c869e1686da227878f18dc8214a531995b772cf5
2019-10-01T18:09:22.966Z,1569953362.966 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-01T18:09:22.966Z,1569953362.966 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-10-01T18:09:22.967Z,1569953362.967 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-10-01T18:09:22.967Z,1569953362.967 [IBIT](INFO): Initialize IBIT Component.
2019-10-01T18:09:22.968Z,1569953362.968 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-01T18:09:22.970Z,1569953362.970 [logger ThreadHandler](INFO): Handler Thread ID is 8491
2019-10-01T18:09:22.981Z,1569953362.981 [CBIT](DEBUG): Initialized mux pins.
2019-10-01T18:09:22.981Z,1569953362.981 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-01T18:09:22.989Z,1569953362.989 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8492
2019-10-01T18:09:22.990Z,1569953362.990 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-01T18:09:23.001Z,1569953363.001 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8493
2019-10-01T18:09:23.005Z,1569953363.005 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-01T18:09:23.005Z,1569953363.005 [CBIT](DEBUG): Initializing heartbeat.
2019-10-01T18:09:23.013Z,1569953363.013 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8494
2019-10-01T18:09:23.014Z,1569953363.014 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:09:23.017Z,1569953363.017 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:09:23.019Z,1569953363.019 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8496
2019-10-01T18:09:23.020Z,1569953363.020 [WetLabsBB2FL](INFO): Powering down
2019-10-01T18:09:23.049Z,1569953363.049 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 8497
2019-10-01T18:09:23.061Z,1569953363.061 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8498
2019-10-01T18:09:23.064Z,1569953363.064 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-01T18:09:23.064Z,1569953363.064 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-01T18:09:23.065Z,1569953363.065 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-01T18:09:23.065Z,1569953363.065 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-01T18:09:23.077Z,1569953363.077 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-01T18:09:23.077Z,1569953363.077 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-01T18:09:23.089Z,1569953363.089 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-01T18:09:23.089Z,1569953363.089 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-01T18:09:23.089Z,1569953363.089 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-01T18:09:23.089Z,1569953363.089 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-01T18:09:23.090Z,1569953363.090 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-01T18:09:23.090Z,1569953363.090 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-01T18:09:23.090Z,1569953363.090 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-01T18:09:23.090Z,1569953363.090 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-01T18:09:23.090Z,1569953363.090 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-01T18:09:23.091Z,1569953363.091 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-01T18:09:23.091Z,1569953363.091 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-01T18:09:23.091Z,1569953363.091 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-01T18:09:23.113Z,1569953363.113 [CBIT](DEBUG): Backplane powered.
2019-10-01T18:09:23.118Z,1569953363.118 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-01T18:09:23.128Z,1569953363.128 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-01T18:09:23.199Z,1569953363.199 [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-10-01T18:09:23.200Z,1569953363.200 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-01T18:09:23.246Z,1569953363.246 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-01T18:09:23.248Z,1569953363.248 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-01T18:09:23.257Z,1569953363.257 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-01T18:09:23.276Z,1569953363.276 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-01T18:09:23.287Z,1569953363.287 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-01T18:09:23.300Z,1569953363.300 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-01T18:09:23.312Z,1569953363.312 [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-10-01T18:09:23.317Z,1569953363.317 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-01T18:09:23.331Z,1569953363.331 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2019-10-01T18:09:23.360Z,1569953363.360 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-01T18:09:23.360Z,1569953363.360 [DAT](INFO): Powering up
2019-10-01T18:09:23.365Z,1569953363.365 [DAT](DEBUG): Initializing DAT.
2019-10-01T18:09:23.405Z,1569953363.405 [Radio_Surface](INFO): Powering up
2019-10-01T18:09:23.433Z,1569953363.433 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-01T18:09:23.450Z,1569953363.450 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-01T18:09:23.504Z,1569953363.504 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-01T18:09:23.513Z,1569953363.513 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-01T18:09:23.514Z,1569953363.514 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-01T18:09:23.525Z,1569953363.525 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-01T18:09:23.526Z,1569953363.526 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-01T18:09:23.538Z,1569953363.538 [MassServo](DEBUG): Initializing MassServo.
2019-10-01T18:09:23.539Z,1569953363.539 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-01T18:09:23.545Z,1569953363.545 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-01T18:09:23.546Z,1569953363.546 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-01T18:09:23.553Z,1569953363.553 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-01T18:09:23.745Z,1569953363.745 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-10-01T18:09:23.745Z,1569953363.745 [DropWeight] Hardware Fault, FailCount= 1
2019-10-01T18:09:23.745Z,1569953363.745 [DropWeight](ERROR): Hardware Fault
2019-10-01T18:09:23.801Z,1569953363.801 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-01T18:09:23.811Z,1569953363.811 [CommandLine](FAULT): Scheduling is paused
2019-10-01T18:09:23.812Z,1569953363.812 [CBIT](INFO): Critical error at 20191001T180923
2019-10-01T18:09:23.812Z,1569953363.812 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-01T18:09:23.814Z,1569953363.814 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-10-01T18:09:23.815Z,1569953363.815 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-10-01T18:09:24.517Z,1569953364.517 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-01T18:09:24.517Z,1569953364.517 [RudderServo](FAULT): Rudder failed to initialize
2019-10-01T18:09:24.517Z,1569953364.517 [RudderServo] Communications Fault, FailCount= 1
2019-10-01T18:09:24.517Z,1569953364.517 [RudderServo](ERROR): Communications Fault
2019-10-01T18:09:24.626Z,1569953364.626 [CBIT](INFO): Critical error at 20191001T180923
2019-10-01T18:09:24.629Z,1569953364.629 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-01T18:09:24.842Z,1569953364.842 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-01T18:09:24.842Z,1569953364.842 [RudderServo](INFO): Powering down
2019-10-01T18:09:25.483Z,1569953365.483 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-01T18:09:25.602Z,1569953365.602 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-01T18:09:25.606Z,1569953365.606 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-01T18:09:25.606Z,1569953365.606 [RudderServo] No Fault, FailCount= 1
2019-10-01T18:09:33.194Z,1569953373.194 [CBIT](CRITICAL): Environmental Failure. Press:14.433263 PSI. Humidity:43%. Temp:21 C. ABORTING MISSION
2019-10-01T18:09:33.619Z,1569953373.619 [CBIT](INFO): Critical error at 20191001T180933
2019-10-01T18:09:36.065Z,1569953376.065 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:09:36.065Z,1569953376.065 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-01T18:09:36.065Z,1569953376.065 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:09:36.449Z,1569953376.449 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:09:36.469Z,1569953376.469 [CTD_Seabird](INFO): Powering down
2019-10-01T18:09:37.599Z,1569953377.599 [DAT](INFO): setting local address to 4
2019-10-01T18:09:37.667Z,1569953377.667 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:09:37.667Z,1569953377.667 [CTD_Seabird] No Fault, FailCount= 1
2019-10-01T18:09:37.818Z,1569953377.818 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004587
2019-10-01T18:09:37.837Z,1569953377.837 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:09:37.838Z,1569953377.838 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:09:38.006Z,1569953378.006 [DAT](INFO): set local address to 4
2019-10-01T18:09:45.661Z,1569953385.661 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:09:45.661Z,1569953385.661 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-10-01T18:09:45.661Z,1569953385.661 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:09:45.725Z,1569953385.725 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:09:46.149Z,1569953386.149 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:09:46.945Z,1569953386.945 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:09:46.945Z,1569953386.945 [RDI_Pathfinder] No Fault, FailCount= 1
2019-10-01T18:09:50.490Z,1569953390.490 [NAL9602](INFO): Powering up NAL9602
2019-10-01T18:09:50.889Z,1569953390.889 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:09:50.889Z,1569953390.889 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-01T18:09:50.889Z,1569953390.889 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:09:51.009Z,1569953391.009 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:09:51.298Z,1569953391.298 [CTD_Seabird](INFO): Powering down
2019-10-01T18:09:52.621Z,1569953392.621 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:09:52.621Z,1569953392.621 [CTD_Seabird] No Fault, FailCount= 2
2019-10-01T18:09:52.645Z,1569953392.645 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:09:52.647Z,1569953392.647 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:10:01.398Z,1569953401.398 [NAL9602](INFO): NAL9602 initialized
2019-10-01T18:10:02.217Z,1569953402.217 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:10:05.697Z,1569953405.697 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:10:05.697Z,1569953405.697 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-01T18:10:05.697Z,1569953405.697 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:10:05.933Z,1569953405.933 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:10:05.933Z,1569953405.933 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-01T18:10:06.101Z,1569953406.101 [CTD_Seabird](INFO): Powering down
2019-10-01T18:10:09.514Z,1569953409.514 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:10:09.515Z,1569953409.515 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-10-01T18:10:09.515Z,1569953409.515 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:10:09.550Z,1569953409.550 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:10:09.981Z,1569953409.981 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:10:10.762Z,1569953410.762 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:10:10.762Z,1569953410.762 [RDI_Pathfinder] No Fault, FailCount= 2
2019-10-01T18:10:22.443Z,1569953422.443 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-01T18:10:22.447Z,1569953422.447 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-01T18:10:33.460Z,1569953433.460 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:10:33.460Z,1569953433.460 [RDI_Pathfinder] Communications Fault, FailCount= 3
2019-10-01T18:10:33.460Z,1569953433.460 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:10:33.557Z,1569953433.557 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:10:33.558Z,1569953433.558 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:10:33.566Z,1569953433.566 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000941
CHAN A1 (24V): -0.000387
CHAN A2 (12V): -0.007804
CHAN A3 (5V): -0.002246
CHAN B0 (3.3V): -0.000522
CHAN B1 (3.15aV): -0.000019
CHAN B2 (3.15bV): -0.000116
CHAN B3 (GND): 0.002127
OPEN: -0.000336
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-01T18:10:33.929Z,1569953433.929 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:10:34.017Z,1569953434.017 [CBIT](INFO): Critical error at 20191001T181033
2019-10-01T18:11:16.459Z,1569953476.459 [SBIT](IMPORTANT): SBIT PASSED
2019-10-01T18:11:16.483Z,1569953476.483 [CommandLine](IMPORTANT): got command configSet list
2019-10-01T18:11:16.483Z,1569953476.483 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-01T18:11:16.484Z,1569953476.484 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-10-01T18:11:16.484Z,1569953476.484 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere;

2019-10-01T18:11:16.484Z,1569953476.484 [CommandLine](IMPORTANT): Express none RDI_Pathfinder.height_above_sea_floor;

2019-10-01T18:11:16.484Z,1569953476.484 [CommandLine](IMPORTANT): Express linearApproximation Tracking.range_to_contact 10.000000 meter;

2019-10-01T18:11:16.484Z,1569953476.484 [CommandLine](IMPORTANT): Express none WetLabsUBAT.biolum_potential;

2019-10-01T18:11:16.484Z,1569953476.484 [CommandLine](IMPORTANT): Express none WetLabsUBAT.flow_rate;

2019-10-01T18:11:16.484Z,1569953476.484 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;

2019-10-01T18:11:16.485Z,1569953476.485 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsUBAT.biolum_potential 1000000.000000 none;

2019-10-01T18:11:16.493Z,1569953476.493 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-10-01T18:11:16.493Z,1569953476.493 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=80 cubic_centimeter;

2019-10-01T18:11:16.493Z,1569953476.493 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter;

2019-10-01T18:11:16.878Z,1569953476.878 [MissionManager](IMPORTANT): Started mission Startup
2019-10-01T18:11:16.878Z,1569953476.878 [Startup] Running Loop=1
2019-10-01T18:11:16.878Z,1569953476.878 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-01T18:11:16.879Z,1569953476.879 [Startup:A.GoToSurface] Running Loop=1
2019-10-01T18:11:16.879Z,1569953476.879 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-01T18:11:16.879Z,1569953476.879 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-01T18:11:16.880Z,1569953476.880 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-01T18:11:16.880Z,1569953476.880 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-01T18:11:16.880Z,1569953476.880 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-01T18:11:16.881Z,1569953476.881 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-01T18:11:16.882Z,1569953476.882 [Startup:StartupSatComms] Running Loop=1
2019-10-01T18:11:16.882Z,1569953476.882 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-01T18:11:16.882Z,1569953476.882 [Startup:StartupSatComms:A] Running Loop=1
2019-10-01T18:11:17.286Z,1569953477.286 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-01T18:11:22.998Z,1569953482.998 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:11:22.998Z,1569953482.998 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-01T18:11:22.998Z,1569953482.998 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:11:23.008Z,1569953483.008 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:11:23.350Z,1569953483.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:11:23.350Z,1569953483.350 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-01T18:11:38.886Z,1569953498.886 [CommandLine](INFO): End of History
2019-10-01T18:11:38.893Z,1569953498.893 [CommandLine](INFO): End of History
2019-10-01T18:11:39.046Z,1569953499.046 [CommandLine](INFO): End of History
2019-10-01T18:11:39.048Z,1569953499.048 [CommandLine](INFO): End of History
2019-10-01T18:11:39.060Z,1569953499.060 [CommandLine](INFO): End of History
2019-10-01T18:11:39.062Z,1569953499.062 [CommandLine](INFO): End of History
2019-10-01T18:11:48.981Z,1569953508.981 [CommandLine](IMPORTANT): got command gfscan
2019-10-01T18:11:49.218Z,1569953509.218 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-01T18:12:00.136Z,1569953520.136 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002509
CHAN A1 (24V): -0.000229
CHAN A2 (12V): -0.007026
CHAN A3 (5V): -0.002411
CHAN B0 (3.3V): -0.000166
CHAN B1 (3.15aV): -0.000667
CHAN B2 (3.15bV): -0.000340
CHAN B3 (GND): 0.002022
OPEN: -0.000568
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-01T18:12:16.479Z,1569953536.479 [CommandLine](IMPORTANT): got command get CBIT.gf24Offset
2019-10-01T18:12:16.480Z,1569953536.480 [CommandLine](IMPORTANT): CBIT.gf24Offset 143.000000 uA
2019-10-01T18:12:17.126Z,1569953537.126 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-01T18:11:16.9Z
2019-10-01T18:12:17.127Z,1569953537.127 [Startup:StartupSatComms:A] Stopped
2019-10-01T18:12:17.127Z,1569953537.127 [Startup:StartupSatComms:B] Running Loop=1
2019-10-01T18:12:17.498Z,1569953537.498 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-01T18:12:23.705Z,1569953543.705 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20191001T175701/Courier0000.lzma
2019-10-01T18:12:25.710Z,1569953545.710 [DataOverHttps](INFO): Moved sent file to Logs/20191001T175701/Courier0000.lzma.bak
2019-10-01T18:12:25.711Z,1569953545.711 [DataOverHttps](INFO): SBD MOMSN=11836166
2019-10-01T18:12:35.749Z,1569953555.749 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:12:35.749Z,1569953555.749 [CTD_Seabird] No Fault, FailCount= 3
2019-10-01T18:12:35.797Z,1569953555.797 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:12:35.799Z,1569953555.799 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:12:42.604Z,1569953562.604 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191001T175701/Courier0004.lzma
2019-10-01T18:12:44.611Z,1569953564.611 [DataOverHttps](INFO): Moved sent file to Logs/20191001T175701/Courier0004.lzma.bak
2019-10-01T18:12:44.611Z,1569953564.611 [DataOverHttps](INFO): SBD MOMSN=11836172
2019-10-01T18:12:48.865Z,1569953568.865 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:12:48.865Z,1569953568.865 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-01T18:12:48.865Z,1569953568.865 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:12:49.093Z,1569953569.093 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:12:49.269Z,1569953569.269 [CTD_Seabird](INFO): Powering down
2019-10-01T18:12:50.282Z,1569953570.282 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:12:50.283Z,1569953570.283 [CTD_Seabird] No Fault, FailCount= 1
2019-10-01T18:12:50.613Z,1569953570.613 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:12:50.621Z,1569953570.621 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:13:01.177Z,1569953581.177 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20191001T180913/Courier0000.lzma
2019-10-01T18:13:03.182Z,1569953583.182 [DataOverHttps](INFO): Moved sent file to Logs/20191001T180913/Courier0000.lzma.bak
2019-10-01T18:13:03.183Z,1569953583.183 [DataOverHttps](INFO): SBD MOMSN=11836174
2019-10-01T18:13:04.669Z,1569953584.669 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:13:04.669Z,1569953584.669 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-01T18:13:04.669Z,1569953584.669 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:13:04.822Z,1569953584.822 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:13:05.073Z,1569953585.073 [CTD_Seabird](INFO): Powering down
2019-10-01T18:13:06.028Z,1569953586.028 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:13:06.028Z,1569953586.028 [CTD_Seabird] No Fault, FailCount= 2
2019-10-01T18:13:06.417Z,1569953586.417 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:13:06.419Z,1569953586.419 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:13:17.361Z,1569953597.361 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-01T18:12:17.1Z
2019-10-01T18:13:17.361Z,1569953597.361 [Startup:StartupSatComms:B] Stopped
2019-10-01T18:13:17.361Z,1569953597.361 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-01T18:13:17.362Z,1569953597.362 [Startup:StartupSatComms] Stopped
2019-10-01T18:13:17.362Z,1569953597.362 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-01T18:13:17.362Z,1569953597.362 [Startup](INFO): Completed Startup
2019-10-01T18:13:17.363Z,1569953597.363 [MissionManager](INFO): Startup is completed.
2019-10-01T18:13:17.363Z,1569953597.363 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-01T18:13:17.363Z,1569953597.363 [Startup] Stopped
2019-10-01T18:13:17.363Z,1569953597.363 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-01T18:13:17.363Z,1569953597.363 [Startup:A.GoToSurface] Stopped
2019-10-01T18:13:17.363Z,1569953597.363 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-01T18:13:17.753Z,1569953597.753 [MissionManager](IMPORTANT): Started mission Default
2019-10-01T18:13:17.754Z,1569953597.754 [Default] Running Loop=1
2019-10-01T18:13:17.754Z,1569953597.754 [Default](DEBUG): Aggregate::initialize Default
2019-10-01T18:13:17.754Z,1569953597.754 [Default:B.GoToSurface] Running Loop=1
2019-10-01T18:13:17.754Z,1569953597.754 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-01T18:13:17.754Z,1569953597.754 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-01T18:13:17.755Z,1569953597.755 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-01T18:13:17.755Z,1569953597.755 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-01T18:13:17.755Z,1569953597.755 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-01T18:13:17.755Z,1569953597.755 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-01T18:13:17.756Z,1569953597.756 [Default:A.Wait] Running Loop=1
2019-10-01T18:13:17.756Z,1569953597.756 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-01T18:13:19.469Z,1569953599.469 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:13:19.469Z,1569953599.469 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-01T18:13:19.469Z,1569953599.469 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:13:19.653Z,1569953599.653 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20191001T060045/Express0005.lzma
2019-10-01T18:13:19.772Z,1569953599.772 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:13:19.772Z,1569953599.772 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-01T18:13:19.873Z,1569953599.873 [CTD_Seabird](INFO): Powering down
2019-10-01T18:13:21.658Z,1569953601.658 [DataOverHttps](INFO): Moved sent file to Logs/20191001T060045/Express0005.lzma.bak
2019-10-01T18:13:21.658Z,1569953601.658 [DataOverHttps](INFO): SBD MOMSN=11836182
2019-10-01T18:13:23.925Z,1569953603.925 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:13:23.925Z,1569953603.925 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-01T18:13:23.925Z,1569953603.925 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:13:23.936Z,1569953603.936 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:13:24.218Z,1569953604.218 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:13:24.218Z,1569953604.218 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-01T18:13:31.065Z,1569953611.065 [Default:A.Wait](INFO): Done Waiting.
2019-10-01T18:13:31.065Z,1569953611.065 [Default:A.Wait] Stopped
2019-10-01T18:13:31.065Z,1569953611.065 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-01T18:13:31.477Z,1569953611.477 [Default:CheckIn] Running Loop=1
2019-10-01T18:13:31.478Z,1569953611.478 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-01T18:13:31.478Z,1569953611.478 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-01T18:13:31.872Z,1569953611.872 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-01T18:13:33.515Z,1569953613.515 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:13:33.515Z,1569953613.515 [RDI_Pathfinder] No Fault, FailCount= 3
2019-10-01T18:13:46.412Z,1569953626.412 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2019-10-01T18:13:46.412Z,1569953626.412 [RDI_Pathfinder] Data Fault, FailCount= 1
2019-10-01T18:13:46.412Z,1569953626.412 [RDI_Pathfinder](ERROR): Data Fault
2019-10-01T18:13:46.428Z,1569953626.428 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2019-10-01T18:13:46.881Z,1569953626.881 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:13:47.636Z,1569953627.636 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:13:47.637Z,1569953627.637 [RDI_Pathfinder] No Fault, FailCount= 1
2019-10-01T18:14:10.252Z,1569953650.252 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:14:10.252Z,1569953650.252 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-10-01T18:14:10.252Z,1569953650.252 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:14:10.275Z,1569953650.275 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:14:10.729Z,1569953650.729 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:14:11.472Z,1569953651.472 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:14:11.472Z,1569953651.472 [RDI_Pathfinder] No Fault, FailCount= 2
2019-10-01T18:14:34.082Z,1569953674.082 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:14:34.082Z,1569953674.082 [RDI_Pathfinder] Communications Fault, FailCount= 3
2019-10-01T18:14:34.082Z,1569953674.082 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:14:34.098Z,1569953674.098 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:14:34.099Z,1569953674.099 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:14:34.577Z,1569953674.577 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:14:34.620Z,1569953674.620 [CBIT](INFO): Critical error at 20191001T181434
2019-10-01T18:15:04.827Z,1569953704.827 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-01T18:15:05.633Z,1569953705.633 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:09.277Z,1569953709.277 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:12.097Z,1569953712.097 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:12.306Z,1569953712.306 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft
2019-10-01T18:15:12.307Z,1569953712.307 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -20.000000 mm
2019-10-01T18:15:14.921Z,1569953714.921 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:16.957Z,1569953716.957 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:17.622Z,1569953717.622 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2019-10-01T18:15:17.622Z,1569953717.622 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 40.000000 mm
2019-10-01T18:15:20.177Z,1569953720.177 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:23.005Z,1569953723.005 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:24.649Z,1569953724.649 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:15:24.649Z,1569953724.649 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-10-01T18:15:24.649Z,1569953724.649 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:15:24.659Z,1569953724.659 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:15:25.085Z,1569953725.085 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:15:25.085Z,1569953725.085 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-10-01T18:15:26.237Z,1569953726.237 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:29.065Z,1569953729.065 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:32.293Z,1569953732.293 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:35.121Z,1569953735.121 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:37.969Z,1569953737.969 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:41.213Z,1569953741.213 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:44.021Z,1569953744.021 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:47.257Z,1569953747.257 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:47.474Z,1569953747.474 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 35.000000 millimeter
2019-10-01T18:15:47.476Z,1569953747.476 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2019-10-01T18:15:47.749Z,1569953747.749 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-01T18:15:50.125Z,1569953750.125 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:15:50.125Z,1569953750.125 [CTD_Seabird] No Fault, FailCount= 3
2019-10-01T18:15:50.149Z,1569953750.149 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:50.345Z,1569953750.345 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:15:50.347Z,1569953750.347 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:15:52.977Z,1569953752.977 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:56.209Z,1569953756.209 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:15:59.041Z,1569953759.041 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:02.273Z,1569953762.273 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:03.405Z,1569953763.405 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:16:03.405Z,1569953763.405 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-01T18:16:03.405Z,1569953763.405 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:16:03.621Z,1569953763.621 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:16:03.809Z,1569953763.809 [CTD_Seabird](INFO): Powering down
2019-10-01T18:16:04.791Z,1569953764.791 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:16:04.791Z,1569953764.791 [CTD_Seabird] No Fault, FailCount= 1
2019-10-01T18:16:05.101Z,1569953765.101 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:05.153Z,1569953765.153 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:16:05.155Z,1569953765.155 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:16:07.925Z,1569953767.925 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:09.945Z,1569953769.945 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:13.181Z,1569953773.181 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:16.009Z,1569953776.009 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:18.205Z,1569953778.205 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:16:18.205Z,1569953778.205 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-01T18:16:18.205Z,1569953778.205 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:16:18.519Z,1569953778.519 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:16:18.609Z,1569953778.609 [CTD_Seabird](INFO): Powering down
2019-10-01T18:16:19.237Z,1569953779.237 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:19.742Z,1569953779.742 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:16:19.743Z,1569953779.743 [CTD_Seabird] No Fault, FailCount= 2
2019-10-01T18:16:19.949Z,1569953779.949 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:16:19.951Z,1569953779.951 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:16:22.065Z,1569953782.065 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:25.305Z,1569953785.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:28.129Z,1569953788.129 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:30.957Z,1569953790.957 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:32.315Z,1569953792.315 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 40.000000 millimeter
2019-10-01T18:16:33.017Z,1569953793.017 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:16:33.017Z,1569953793.017 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-01T18:16:33.018Z,1569953793.018 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:16:33.402Z,1569953793.402 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:16:33.402Z,1569953793.402 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-01T18:16:33.453Z,1569953793.453 [CTD_Seabird](INFO): Powering down
2019-10-01T18:16:34.189Z,1569953794.189 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:37.049Z,1569953797.049 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:40.285Z,1569953800.285 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:43.115Z,1569953803.115 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:45.945Z,1569953805.945 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:47.961Z,1569953807.961 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:49.675Z,1569953809.675 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -15.000000 millimeter
2019-10-01T18:16:51.193Z,1569953811.193 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:54.025Z,1569953814.025 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:16:56.053Z,1569953816.053 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-10-01T18:16:56.056Z,1569953816.056 [BPC1](INFO): Received data from all battery sticks.
2019-10-01T18:16:57.261Z,1569953817.261 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:00.081Z,1569953820.081 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:02.913Z,1569953822.913 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:04.929Z,1569953824.929 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:06.949Z,1569953826.949 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:10.181Z,1569953830.181 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:13.009Z,1569953833.009 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:16.241Z,1569953836.241 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:19.065Z,1569953839.065 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:22.297Z,1569953842.297 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:25.125Z,1569953845.125 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:25.561Z,1569953845.561 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:17:25.561Z,1569953845.561 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-10-01T18:17:25.561Z,1569953845.561 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:17:25.610Z,1569953845.610 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:17:26.021Z,1569953846.021 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:17:26.021Z,1569953846.021 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-10-01T18:17:27.961Z,1569953847.961 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:31.189Z,1569953851.189 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:34.017Z,1569953854.017 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:34.126Z,1569953854.126 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:17:34.126Z,1569953854.126 [RDI_Pathfinder] No Fault, FailCount= 3
2019-10-01T18:17:37.249Z,1569953857.249 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:40.077Z,1569953860.077 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:42.909Z,1569953862.909 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:44.921Z,1569953864.921 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:46.945Z,1569953866.945 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:46.959Z,1569953866.959 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2019-10-01T18:17:46.960Z,1569953866.960 [RDI_Pathfinder] Data Fault, FailCount= 1
2019-10-01T18:17:46.960Z,1569953866.960 [RDI_Pathfinder](ERROR): Data Fault
2019-10-01T18:17:47.046Z,1569953867.046 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2019-10-01T18:17:47.433Z,1569953867.433 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:17:48.232Z,1569953868.232 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:17:48.232Z,1569953868.232 [RDI_Pathfinder] No Fault, FailCount= 1
2019-10-01T18:17:48.969Z,1569953868.969 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:52.201Z,1569953872.201 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:55.025Z,1569953875.025 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:17:58.253Z,1569953878.253 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:01.081Z,1569953881.081 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:03.913Z,1569953883.913 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:05.933Z,1569953885.933 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:07.955Z,1569953887.955 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:10.784Z,1569953890.784 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:18:10.784Z,1569953890.784 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-10-01T18:18:10.784Z,1569953890.784 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:18:10.888Z,1569953890.888 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:18:11.181Z,1569953891.181 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:11.273Z,1569953891.273 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:18:12.038Z,1569953892.038 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:18:12.038Z,1569953892.038 [RDI_Pathfinder] No Fault, FailCount= 2
2019-10-01T18:18:12.747Z,1569953892.747 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -20.000000 millimeter
2019-10-01T18:18:14.013Z,1569953894.013 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:17.277Z,1569953897.277 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:20.109Z,1569953900.109 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:22.941Z,1569953902.941 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:26.165Z,1569953906.165 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:29.001Z,1569953909.001 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:31.837Z,1569953911.837 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-01T18:13:31.5Z
2019-10-01T18:18:31.837Z,1569953911.837 [Default:CheckIn:Read_GPS] Stopped
2019-10-01T18:18:31.837Z,1569953911.837 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-01T18:18:32.225Z,1569953912.225 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:32.251Z,1569953912.251 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-01T18:18:34.298Z,1569953914.298 [CommandLine](IMPORTANT): got command maintain clear
2019-10-01T18:18:34.665Z,1569953914.665 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:18:34.665Z,1569953914.665 [RDI_Pathfinder] Communications Fault, FailCount= 3
2019-10-01T18:18:34.665Z,1569953914.665 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:18:34.739Z,1569953914.739 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:18:34.740Z,1569953914.740 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:18:34.744Z,1569953914.744 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-01T18:18:35.069Z,1569953915.069 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:35.165Z,1569953915.165 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:18:35.180Z,1569953915.180 [CBIT](INFO): Critical error at 20191001T181834
2019-10-01T18:18:37.945Z,1569953917.945 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:38.673Z,1569953918.673 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20191001T180913/Courier0004.lzma
2019-10-01T18:18:39.953Z,1569953919.953 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:40.678Z,1569953920.678 [DataOverHttps](INFO): Moved sent file to Logs/20191001T180913/Courier0004.lzma.bak
2019-10-01T18:18:40.679Z,1569953920.679 [DataOverHttps](INFO): SBD MOMSN=11836191
2019-10-01T18:18:43.189Z,1569953923.189 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:46.013Z,1569953926.013 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:49.251Z,1569953929.251 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:52.081Z,1569953932.081 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:54.901Z,1569953934.901 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:56.925Z,1569953936.925 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:57.219Z,1569953937.219 [DataOverHttps](INFO): Sending 964 bytes from file Logs/20191001T145158/Express0001.lzma
2019-10-01T18:18:58.945Z,1569953938.945 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:18:59.222Z,1569953939.222 [DataOverHttps](INFO): Moved sent file to Logs/20191001T145158/Express0001.lzma.bak
2019-10-01T18:18:59.222Z,1569953939.222 [DataOverHttps](INFO): SBD MOMSN=11836194
2019-10-01T18:19:02.177Z,1569953942.177 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:03.096Z,1569953943.096 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:19:03.096Z,1569953943.096 [CTD_Seabird] No Fault, FailCount= 3
2019-10-01T18:19:03.489Z,1569953943.489 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:19:03.491Z,1569953943.491 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:19:05.001Z,1569953945.001 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:08.237Z,1569953948.237 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:11.061Z,1569953951.061 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:14.293Z,1569953954.293 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:15.701Z,1569953955.701 [DataOverHttps](INFO): Sending 1482 bytes from file Logs/20191001T175701/Express0001.lzma
2019-10-01T18:19:16.549Z,1569953956.549 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:19:16.549Z,1569953956.549 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-01T18:19:16.549Z,1569953956.549 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:19:16.736Z,1569953956.736 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:19:16.953Z,1569953956.953 [CTD_Seabird](INFO): Powering down
2019-10-01T18:19:17.121Z,1569953957.121 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:17.702Z,1569953957.702 [DataOverHttps](INFO): Moved sent file to Logs/20191001T175701/Express0001.lzma.bak
2019-10-01T18:19:17.703Z,1569953957.703 [DataOverHttps](INFO): SBD MOMSN=11836226
2019-10-01T18:19:17.971Z,1569953957.971 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:19:17.971Z,1569953957.971 [CTD_Seabird] No Fault, FailCount= 1
2019-10-01T18:19:18.297Z,1569953958.297 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:19:18.298Z,1569953958.298 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:19:19.957Z,1569953959.957 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:23.185Z,1569953963.185 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:26.013Z,1569953966.013 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:26.445Z,1569953966.445 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:19:26.445Z,1569953966.445 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-10-01T18:19:26.445Z,1569953966.445 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:19:26.456Z,1569953966.456 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:19:26.853Z,1569953966.853 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:19:26.853Z,1569953966.853 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-10-01T18:19:29.249Z,1569953969.249 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:31.349Z,1569953971.349 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:19:31.349Z,1569953971.349 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-01T18:19:31.349Z,1569953971.349 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:19:31.721Z,1569953971.721 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:19:31.754Z,1569953971.754 [CTD_Seabird](INFO): Powering down
2019-10-01T18:19:32.069Z,1569953972.069 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:32.950Z,1569953972.950 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:19:32.950Z,1569953972.950 [CTD_Seabird] No Fault, FailCount= 2
2019-10-01T18:19:33.093Z,1569953973.093 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:19:33.095Z,1569953973.095 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:19:34.116Z,1569953974.116 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191001T175701/Express0005.lzma
2019-10-01T18:19:34.897Z,1569953974.897 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:36.122Z,1569953976.122 [DataOverHttps](INFO): Moved sent file to Logs/20191001T175701/Express0005.lzma.bak
2019-10-01T18:19:36.123Z,1569953976.123 [DataOverHttps](INFO): SBD MOMSN=11836279
2019-10-01T18:19:36.921Z,1569953976.921 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:38.941Z,1569953978.941 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:42.169Z,1569953982.169 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:44.997Z,1569953984.997 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:46.149Z,1569953986.149 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:19:46.149Z,1569953986.149 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-01T18:19:46.150Z,1569953986.150 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:19:46.248Z,1569953986.248 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:19:46.249Z,1569953986.249 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-01T18:19:46.553Z,1569953986.553 [CTD_Seabird](INFO): Powering down
2019-10-01T18:19:48.233Z,1569953988.233 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:51.061Z,1569953991.061 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:52.420Z,1569953992.420 [DataOverHttps](INFO): Sending 1249 bytes from file Logs/20191001T180913/Express0001.lzma
2019-10-01T18:19:54.293Z,1569953994.293 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:54.424Z,1569953994.424 [DataOverHttps](INFO): Moved sent file to Logs/20191001T180913/Express0001.lzma.bak
2019-10-01T18:19:54.424Z,1569953994.424 [DataOverHttps](INFO): SBD MOMSN=11836282
2019-10-01T18:19:57.121Z,1569953997.121 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:19:59.945Z,1569953999.945 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:20:03.166Z,1569954003.166 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-01T18:20:03.166Z,1569954003.166 [NAL9602] Data Fault, FailCount= 1
2019-10-01T18:20:03.166Z,1569954003.166 [NAL9602](ERROR): Data Fault
2019-10-01T18:20:03.218Z,1569954003.218 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-01T18:20:03.570Z,1569954003.570 [NAL9602](INFO): Powering down
2019-10-01T18:20:04.470Z,1569954004.470 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-01T18:20:04.470Z,1569954004.470 [NAL9602] No Fault, FailCount= 1
2019-10-01T18:20:12.626Z,1569954012.626 [DataOverHttps](INFO): Sending 561 bytes from file Logs/20191001T180913/Express0005.lzma
2019-10-01T18:20:14.630Z,1569954014.630 [DataOverHttps](INFO): Moved sent file to Logs/20191001T180913/Express0005.lzma.bak
2019-10-01T18:20:14.630Z,1569954014.630 [DataOverHttps](INFO): SBD MOMSN=11836319
2019-10-01T18:20:16.122Z,1569954016.122 [Default:CheckIn:Read_Iridium] Stopped
2019-10-01T18:20:16.122Z,1569954016.122 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-01T18:20:16.122Z,1569954016.122 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-01T18:20:33.874Z,1569954033.874 [NAL9602](INFO): Powering up NAL9602
2019-10-01T18:20:44.782Z,1569954044.782 [NAL9602](INFO): NAL9602 initialized
2019-10-01T18:20:45.601Z,1569954045.601 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:20:48.429Z,1569954048.429 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:20:51.661Z,1569954051.661 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:20:54.485Z,1569954054.485 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:20:57.313Z,1569954057.313 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:20:59.337Z,1569954059.337 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:02.569Z,1569954062.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:05.397Z,1569954065.397 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:08.629Z,1569954068.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:11.457Z,1569954071.457 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:14.689Z,1569954074.689 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:17.517Z,1569954077.517 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:20.341Z,1569954080.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:23.577Z,1569954083.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:26.405Z,1569954086.405 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:27.241Z,1569954087.241 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:21:27.241Z,1569954087.241 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-10-01T18:21:27.241Z,1569954087.241 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:21:27.252Z,1569954087.252 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:21:27.647Z,1569954087.647 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:21:27.647Z,1569954087.647 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-10-01T18:21:29.637Z,1569954089.637 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:32.461Z,1569954092.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:34.909Z,1569954094.909 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:21:34.909Z,1569954094.909 [RDI_Pathfinder] No Fault, FailCount= 3
2019-10-01T18:21:35.289Z,1569954095.289 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:37.313Z,1569954097.313 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:39.333Z,1569954099.333 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:42.561Z,1569954102.561 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:45.391Z,1569954105.391 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:47.818Z,1569954107.818 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2019-10-01T18:21:47.818Z,1569954107.818 [RDI_Pathfinder] Data Fault, FailCount= 1
2019-10-01T18:21:47.818Z,1569954107.818 [RDI_Pathfinder](ERROR): Data Fault
2019-10-01T18:21:47.868Z,1569954107.868 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2019-10-01T18:21:48.289Z,1569954108.289 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:21:48.621Z,1569954108.621 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:49.066Z,1569954109.066 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:21:49.067Z,1569954109.067 [RDI_Pathfinder] No Fault, FailCount= 1
2019-10-01T18:21:51.453Z,1569954111.453 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:54.681Z,1569954114.681 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:21:57.513Z,1569954117.513 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:00.341Z,1569954120.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:03.569Z,1569954123.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:06.397Z,1569954126.397 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:09.633Z,1569954129.633 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:11.657Z,1569954131.657 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:22:11.657Z,1569954131.657 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-10-01T18:22:11.657Z,1569954131.657 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:22:11.701Z,1569954131.701 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:22:12.129Z,1569954132.129 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:22:12.457Z,1569954132.457 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:12.897Z,1569954132.897 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:22:12.897Z,1569954132.897 [RDI_Pathfinder] No Fault, FailCount= 2
2019-10-01T18:22:15.289Z,1569954135.289 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:16.524Z,1569954136.524 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:22:16.524Z,1569954136.524 [CTD_Seabird] No Fault, FailCount= 3
2019-10-01T18:22:16.605Z,1569954136.605 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:22:16.606Z,1569954136.606 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:22:17.305Z,1569954137.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:19.333Z,1569954139.333 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:22.561Z,1569954142.561 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:25.385Z,1569954145.385 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:28.617Z,1569954148.617 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:29.657Z,1569954149.657 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:22:29.657Z,1569954149.657 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-01T18:22:29.657Z,1569954149.657 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:22:29.856Z,1569954149.856 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:22:30.061Z,1569954150.061 [CTD_Seabird](INFO): Powering down
2019-10-01T18:22:31.064Z,1569954151.064 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:22:31.064Z,1569954151.064 [CTD_Seabird] No Fault, FailCount= 1
2019-10-01T18:22:31.401Z,1569954151.401 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:22:31.403Z,1569954151.403 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:22:31.445Z,1569954151.445 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:34.685Z,1569954154.685 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:35.507Z,1569954155.507 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:22:35.507Z,1569954155.507 [RDI_Pathfinder] Communications Fault, FailCount= 3
2019-10-01T18:22:35.507Z,1569954155.507 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:22:35.527Z,1569954155.527 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:22:35.527Z,1569954155.527 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:22:35.961Z,1569954155.961 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:22:35.988Z,1569954155.988 [CBIT](INFO): Critical error at 20191001T182235
2019-10-01T18:22:37.505Z,1569954157.505 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:40.337Z,1569954160.337 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:43.573Z,1569954163.573 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:44.453Z,1569954164.453 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:22:44.453Z,1569954164.453 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-01T18:22:44.453Z,1569954164.453 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:22:44.796Z,1569954164.796 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:22:44.857Z,1569954164.857 [CTD_Seabird](INFO): Powering down
2019-10-01T18:22:46.024Z,1569954166.024 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:22:46.024Z,1569954166.024 [CTD_Seabird] No Fault, FailCount= 2
2019-10-01T18:22:46.197Z,1569954166.197 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:22:46.198Z,1569954166.198 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:22:46.393Z,1569954166.393 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:49.625Z,1569954169.625 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:52.453Z,1569954172.453 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:55.685Z,1569954175.685 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:58.521Z,1569954178.521 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:22:59.249Z,1569954179.249 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:22:59.249Z,1569954179.249 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-01T18:22:59.249Z,1569954179.249 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:22:59.363Z,1569954179.363 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:22:59.363Z,1569954179.363 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-01T18:22:59.653Z,1569954179.653 [CTD_Seabird](INFO): Powering down
2019-10-01T18:23:01.341Z,1569954181.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:04.577Z,1569954184.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:07.405Z,1569954187.405 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:10.633Z,1569954190.633 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:13.465Z,1569954193.465 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:16.289Z,1569954196.289 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:18.309Z,1569954198.309 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:20.333Z,1569954200.333 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:23.565Z,1569954203.565 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:26.393Z,1569954206.393 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:28.422Z,1569954208.422 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:23:28.422Z,1569954208.422 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-10-01T18:23:28.422Z,1569954208.422 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:23:28.433Z,1569954208.433 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:23:28.838Z,1569954208.838 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:23:28.838Z,1569954208.838 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-10-01T18:23:29.625Z,1569954209.625 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:32.449Z,1569954212.449 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:35.689Z,1569954215.689 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:38.513Z,1569954218.513 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:41.341Z,1569954221.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:44.569Z,1569954224.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:47.397Z,1569954227.397 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:50.633Z,1569954230.633 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:53.457Z,1569954233.457 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:56.289Z,1569954236.289 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:23:58.305Z,1569954238.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:00.329Z,1569954240.329 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:03.561Z,1569954243.561 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:06.389Z,1569954246.389 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:09.622Z,1569954249.622 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:12.453Z,1569954252.453 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:15.685Z,1569954255.685 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:18.517Z,1569954258.517 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:21.337Z,1569954261.337 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:23.007Z,1569954263.007 [CBIT](FAULT): WATER ALARM BOW.
2019-10-01T18:24:23.008Z,1569954263.008 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2019-10-01T18:24:23.422Z,1569954263.422 [CBIT](FAULT): WATER ALARM BOW.
2019-10-01T18:24:23.424Z,1569954263.424 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2019-10-01T18:24:23.783Z,1569954263.783 [CBIT](FAULT): WATER ALARM BOW.
2019-10-01T18:24:23.784Z,1569954263.784 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2019-10-01T18:24:23.785Z,1569954263.785 [CBIT](INFO): Clearing failed state for component DropWeight
2019-10-01T18:24:23.785Z,1569954263.785 [DropWeight] No Fault, FailCount= 1
2019-10-01T18:24:24.197Z,1569954264.197 [CBIT](FAULT): WATER ALARM BOW.
2019-10-01T18:24:24.209Z,1569954264.209 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2019-10-01T18:24:24.209Z,1569954264.209 [CBIT](IMPORTANT): No ESP to power off.
2019-10-01T18:24:24.569Z,1569954264.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:24.615Z,1569954264.615 [CBIT](INFO): Critical error at 20191001T182424
2019-10-01T18:24:25.370Z,1569954265.370 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-10-01T18:24:25.370Z,1569954265.370 [DropWeight] Hardware Fault, FailCount= 1
2019-10-01T18:24:25.370Z,1569954265.370 [DropWeight](ERROR): Hardware Fault
2019-10-01T18:24:25.459Z,1569954265.459 [CBIT](INFO): Critical error at 20191001T182425
2019-10-01T18:24:25.483Z,1569954265.483 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-10-01T18:24:25.483Z,1569954265.483 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-10-01T18:24:25.821Z,1569954265.821 [CBIT](INFO): Critical error at 20191001T182425
2019-10-01T18:24:27.397Z,1569954267.397 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:30.629Z,1569954270.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:33.461Z,1569954273.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:36.289Z,1569954276.289 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:38.305Z,1569954278.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:40.325Z,1569954280.325 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:43.561Z,1569954283.561 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:46.389Z,1569954286.389 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:49.621Z,1569954289.621 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:52.449Z,1569954292.449 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:55.681Z,1569954295.681 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:24:58.505Z,1569954298.505 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:01.333Z,1569954301.333 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:04.569Z,1569954304.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:07.397Z,1569954307.397 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:10.629Z,1569954310.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:13.457Z,1569954313.457 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:14.963Z,1569954314.963 [CommandLine](IMPORTANT): got command burn off
2019-10-01T18:25:14.963Z,1569954314.963 [CommandLine](IMPORTANT): Deactivating dropweight wire
2019-10-01T18:25:16.685Z,1569954316.685 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:16.710Z,1569954316.710 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-01T18:25:16.710Z,1569954316.710 [Default:CheckIn:C.Wait] Stopped
2019-10-01T18:25:16.710Z,1569954316.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-01T18:25:16.710Z,1569954316.710 [Default:CheckIn:D] Running Loop=1
2019-10-01T18:25:17.118Z,1569954317.118 [Default:CheckIn:D] Stopped
2019-10-01T18:25:17.118Z,1569954317.118 [Default:CheckIn:E] Running Loop=1
2019-10-01T18:25:17.526Z,1569954317.526 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.989403 min 
2019-10-01T18:25:17.527Z,1569954317.527 [Default:CheckIn:E] Stopped
2019-10-01T18:25:17.527Z,1569954317.527 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-01T18:25:17.527Z,1569954317.527 [Default:CheckIn] Stopped
2019-10-01T18:25:17.527Z,1569954317.527 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-01T18:25:17.527Z,1569954317.527 [Default:CheckIn](INFO): Running loop #2
2019-10-01T18:25:17.527Z,1569954317.527 [Default:CheckIn] Running Loop=2
2019-10-01T18:25:17.527Z,1569954317.527 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-01T18:25:17.527Z,1569954317.527 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-01T18:25:19.513Z,1569954319.513 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:22.345Z,1569954322.345 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:25.577Z,1569954325.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:28.409Z,1569954328.409 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:29.237Z,1569954329.237 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:25:29.238Z,1569954329.238 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-10-01T18:25:29.238Z,1569954329.238 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:25:29.248Z,1569954329.248 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:25:29.639Z,1569954329.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:25:29.639Z,1569954329.639 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-10-01T18:25:29.640Z,1569954329.640 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:25:29.640Z,1569954329.640 [CTD_Seabird] No Fault, FailCount= 3
2019-10-01T18:25:29.721Z,1569954329.721 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:25:29.722Z,1569954329.722 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:25:31.633Z,1569954331.633 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:34.461Z,1569954334.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:35.698Z,1569954335.698 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:25:35.698Z,1569954335.698 [RDI_Pathfinder] No Fault, FailCount= 3
2019-10-01T18:25:37.293Z,1569954337.293 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:39.313Z,1569954339.313 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:41.333Z,1569954341.333 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:42.773Z,1569954342.773 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:25:42.773Z,1569954342.773 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-01T18:25:42.773Z,1569954342.773 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:25:42.979Z,1569954342.979 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:25:43.177Z,1569954343.177 [CTD_Seabird](INFO): Powering down
2019-10-01T18:25:44.184Z,1569954344.184 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:25:44.184Z,1569954344.184 [CTD_Seabird] No Fault, FailCount= 1
2019-10-01T18:25:44.521Z,1569954344.521 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:25:44.522Z,1569954344.522 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:25:44.563Z,1569954344.563 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:47.393Z,1569954347.393 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:48.609Z,1569954348.609 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2019-10-01T18:25:48.609Z,1569954348.609 [RDI_Pathfinder] Data Fault, FailCount= 1
2019-10-01T18:25:48.609Z,1569954348.609 [RDI_Pathfinder](ERROR): Data Fault
2019-10-01T18:25:48.653Z,1569954348.653 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2019-10-01T18:25:49.077Z,1569954349.077 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:25:49.839Z,1569954349.839 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:25:49.839Z,1569954349.839 [RDI_Pathfinder] No Fault, FailCount= 1
2019-10-01T18:25:50.625Z,1569954350.625 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:53.453Z,1569954353.453 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:56.285Z,1569954356.285 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:57.573Z,1569954357.573 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:25:57.573Z,1569954357.573 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-01T18:25:57.573Z,1569954357.573 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:25:57.809Z,1569954357.809 [CommandLine](IMPORTANT): got command failComponent
2019-10-01T18:25:57.810Z,1569954357.810 [CommandLine](IMPORTANT): Failed components:
2019-10-01T18:25:57.810Z,1569954357.810 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2019-10-01T18:25:57.810Z,1569954357.810 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2019-10-01T18:25:57.919Z,1569954357.920 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:25:57.977Z,1569954357.977 [CTD_Seabird](INFO): Powering down
2019-10-01T18:25:58.297Z,1569954358.297 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:25:59.152Z,1569954359.152 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:25:59.152Z,1569954359.152 [CTD_Seabird] No Fault, FailCount= 2
2019-10-01T18:25:59.321Z,1569954359.321 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:25:59.323Z,1569954359.323 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:26:00.321Z,1569954360.321 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:02.337Z,1569954362.337 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:05.569Z,1569954365.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:08.401Z,1569954368.401 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:11.637Z,1569954371.637 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:12.373Z,1569954372.373 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:26:12.373Z,1569954372.373 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-01T18:26:12.373Z,1569954372.373 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:26:12.458Z,1569954372.458 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:26:12.458Z,1569954372.458 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-10-01T18:26:12.458Z,1569954372.458 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:26:12.494Z,1569954372.494 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:26:12.494Z,1569954372.494 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:26:12.495Z,1569954372.495 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-01T18:26:12.777Z,1569954372.777 [CTD_Seabird](INFO): Powering down
2019-10-01T18:26:12.929Z,1569954372.929 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:26:13.690Z,1569954373.690 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:26:13.690Z,1569954373.690 [RDI_Pathfinder] No Fault, FailCount= 2
2019-10-01T18:26:14.457Z,1569954374.457 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:17.289Z,1569954377.289 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:19.309Z,1569954379.309 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:21.329Z,1569954381.329 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:24.561Z,1569954384.561 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:27.388Z,1569954387.388 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:30.617Z,1569954390.617 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:33.449Z,1569954393.449 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:36.281Z,1569954396.281 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-10-01T18:26:36.281Z,1569954396.281 [RDI_Pathfinder] Communications Fault, FailCount= 3
2019-10-01T18:26:36.281Z,1569954396.281 [RDI_Pathfinder](ERROR): Communications Fault
2019-10-01T18:26:36.328Z,1569954396.328 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:26:36.329Z,1569954396.329 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2019-10-01T18:26:36.677Z,1569954396.677 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:36.761Z,1569954396.761 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:26:36.781Z,1569954396.781 [CBIT](INFO): Critical error at 20191001T182636
2019-10-01T18:26:39.505Z,1569954399.505 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:42.341Z,1569954402.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:45.569Z,1569954405.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:46.611Z,1569954406.611 [CommandLine](IMPORTANT): got command failComponent none DropWeight
2019-10-01T18:26:46.611Z,1569954406.611 [DropWeight] No Fault, FailCount= 1
2019-10-01T18:26:46.611Z,1569954406.611 [CommandLine](IMPORTANT): DropWeight failureMode is No Fault
2019-10-01T18:26:48.393Z,1569954408.393 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:51.636Z,1569954411.636 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:54.461Z,1569954414.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:57.285Z,1569954417.285 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:26:59.309Z,1569954419.309 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:01.325Z,1569954421.325 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:03.341Z,1569954423.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:06.577Z,1569954426.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:09.405Z,1569954429.405 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:12.637Z,1569954432.637 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:15.465Z,1569954435.465 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:18.293Z,1569954438.293 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:20.309Z,1569954440.309 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:22.329Z,1569954442.329 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:25.569Z,1569954445.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:28.393Z,1569954448.393 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:30.431Z,1569954450.431 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:27:30.431Z,1569954450.431 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-10-01T18:27:30.431Z,1569954450.431 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:27:30.441Z,1569954450.441 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:27:30.852Z,1569954450.852 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:27:30.852Z,1569954450.852 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-10-01T18:27:31.629Z,1569954451.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:34.453Z,1569954454.453 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:37.681Z,1569954457.681 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:40.513Z,1569954460.513 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:43.345Z,1569954463.345 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:46.577Z,1569954466.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:49.401Z,1569954469.401 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:52.633Z,1569954472.633 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:55.461Z,1569954475.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:27:58.285Z,1569954478.285 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:00.305Z,1569954480.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:02.329Z,1569954482.329 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:05.565Z,1569954485.565 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:08.389Z,1569954488.389 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:11.621Z,1569954491.621 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:14.453Z,1569954494.453 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:17.681Z,1569954497.681 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:20.509Z,1569954500.509 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:23.333Z,1569954503.333 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:26.569Z,1569954506.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:29.397Z,1569954509.397 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:32.629Z,1569954512.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:35.453Z,1569954515.453 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:38.289Z,1569954518.289 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:40.305Z,1569954520.305 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:42.325Z,1569954522.325 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:42.770Z,1569954522.770 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:28:42.770Z,1569954522.770 [CTD_Seabird] No Fault, FailCount= 3
2019-10-01T18:28:42.833Z,1569954522.833 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:28:42.835Z,1569954522.835 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:28:44.349Z,1569954524.349 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:47.577Z,1569954527.577 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:50.405Z,1569954530.405 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:53.641Z,1569954533.641 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:55.885Z,1569954535.885 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:28:55.885Z,1569954535.885 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-01T18:28:55.885Z,1569954535.885 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:28:56.084Z,1569954536.084 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:28:56.289Z,1569954536.289 [CTD_Seabird](INFO): Powering down
2019-10-01T18:28:56.461Z,1569954536.461 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:28:57.302Z,1569954537.302 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:28:57.302Z,1569954537.302 [CTD_Seabird] No Fault, FailCount= 1
2019-10-01T18:28:57.633Z,1569954537.633 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:28:57.635Z,1569954537.635 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:28:59.293Z,1569954539.293 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:01.313Z,1569954541.313 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:03.333Z,1569954543.333 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:06.565Z,1569954546.565 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:09.389Z,1569954549.389 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:10.689Z,1569954550.689 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:29:10.689Z,1569954550.689 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-01T18:29:10.689Z,1569954550.689 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:29:11.032Z,1569954551.032 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:29:11.093Z,1569954551.093 [CTD_Seabird](INFO): Powering down
2019-10-01T18:29:12.241Z,1569954552.241 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-01T18:29:12.241Z,1569954552.241 [CTD_Seabird] No Fault, FailCount= 2
2019-10-01T18:29:12.437Z,1569954552.437 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T18:29:12.438Z,1569954552.438 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T18:29:12.621Z,1569954552.621 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:15.457Z,1569954555.457 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:18.689Z,1569954558.689 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:21.513Z,1569954561.513 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:24.341Z,1569954564.341 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:25.489Z,1569954565.489 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-01T18:29:25.489Z,1569954565.489 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-01T18:29:25.489Z,1569954565.489 [CTD_Seabird](ERROR): Communications Fault
2019-10-01T18:29:25.575Z,1569954565.575 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-01T18:29:25.575Z,1569954565.575 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-01T18:29:25.893Z,1569954565.893 [CTD_Seabird](INFO): Powering down
2019-10-01T18:29:27.569Z,1569954567.569 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:30.405Z,1569954570.405 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:31.623Z,1569954571.623 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-01T18:29:31.623Z,1569954571.623 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-10-01T18:29:31.623Z,1569954571.623 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T18:29:31.633Z,1569954571.633 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T18:29:32.038Z,1569954572.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T18:29:32.039Z,1569954572.039 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-10-01T18:29:33.629Z,1569954573.629 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:36.465Z,1569954576.465 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:36.494Z,1569954576.494 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:29:36.494Z,1569954576.494 [RDI_Pathfinder] No Fault, FailCount= 3
2019-10-01T18:29:39.289Z,1569954579.289 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:41.313Z,1569954581.313 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:43.325Z,1569954583.325 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:46.565Z,1569954586.565 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:49.385Z,1569954589.385 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:49.399Z,1569954589.399 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2019-10-01T18:29:49.399Z,1569954589.399 [RDI_Pathfinder] Data Fault, FailCount= 1
2019-10-01T18:29:49.400Z,1569954589.400 [RDI_Pathfinder](ERROR): Data Fault
2019-10-01T18:29:49.456Z,1569954589.456 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2019-10-01T18:29:49.877Z,1569954589.877 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:29:50.629Z,1569954590.629 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-10-01T18:29:50.629Z,1569954590.629 [RDI_Pathfinder] No Fault, FailCount= 1
2019-10-01T18:29:52.621Z,1569954592.621 [NAL9602](DEBUG): Fix Requested
2019-10-01T18:29:52.727Z,1569954592.727 [CommandLine](IMPORTANT): got command restart application
2019-10-01T18:29:53.733Z,1569954593.733 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T18:29:53.733Z,1569954593.733 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:53.769Z,1569954593.769 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-01T18:29:53.769Z,1569954593.769 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:53.770Z,1569954593.770 [CommandLine](INFO): Join timeout helper Thread ID is 8597
2019-10-01T18:29:53.770Z,1569954593.770 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-01T18:29:53.770Z,1569954593.770 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:53.771Z,1569954593.771 [NavChartDb](INFO): Join timeout helper Thread ID is 8598
2019-10-01T18:29:53.965Z,1569954593.965 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T18:29:53.965Z,1569954593.965 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:53.973Z,1569954593.973 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2019-10-01T18:29:53.973Z,1569954593.973 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:53.973Z,1569954593.973 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 8599
2019-10-01T18:29:54.361Z,1569954594.361 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T18:29:54.361Z,1569954594.361 [WetLabsUBAT](INFO): Powering down
2019-10-01T18:29:54.381Z,1569954594.381 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:54.393Z,1569954594.393 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-01T18:29:54.393Z,1569954594.393 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:54.393Z,1569954594.393 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8600
2019-10-01T18:29:54.845Z,1569954594.845 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T18:29:54.846Z,1569954594.846 [WetLabsBB2FL](INFO): Powering down
2019-10-01T18:29:54.846Z,1569954594.846 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:54.861Z,1569954594.861 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-01T18:29:54.861Z,1569954594.861 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:54.861Z,1569954594.861 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8601
2019-10-01T18:29:55.125Z,1569954595.125 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T18:29:55.125Z,1569954595.125 [CTD_Seabird](INFO): Powering down
2019-10-01T18:29:55.137Z,1569954595.137 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.142Z,1569954595.142 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-01T18:29:55.142Z,1569954595.142 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.142Z,1569954595.142 [Radio_Surface](INFO): Join timeout helper Thread ID is 8602
2019-10-01T18:29:55.181Z,1569954595.181 [Radio_Surface](INFO): Powering down
2019-10-01T18:29:55.182Z,1569954595.182 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T18:29:55.182Z,1569954595.182 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.186Z,1569954595.186 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-01T18:29:55.186Z,1569954595.186 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.186Z,1569954595.186 [DataOverHttps](INFO): Join timeout helper Thread ID is 8603
2019-10-01T18:29:55.257Z,1569954595.257 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T18:29:55.257Z,1569954595.257 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.266Z,1569954595.266 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-01T18:29:55.266Z,1569954595.266 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.266Z,1569954595.266 [logger](INFO): Join timeout helper Thread ID is 8604
2019-10-01T18:29:55.313Z,1569954595.313 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T18:29:55.313Z,1569954595.313 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.326Z,1569954595.326 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-01T18:29:55.326Z,1569954595.326 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.326Z,1569954595.326 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-01T18:29:55.326Z,1569954595.326 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.326Z,1569954595.326 [controlThread](INFO): Join timeout helper Thread ID is 8605
2019-10-01T18:29:55.429Z,1569954595.429 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T18:29:55.429Z,1569954595.429 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-01T18:29:55.430Z,1569954595.430 [NAL9602](INFO): Powering down
2019-10-01T18:29:55.501Z,1569954595.501 [RDI_Pathfinder](INFO): Powering down
2019-10-01T18:29:55.502Z,1569954595.502 [DAT](INFO): Powering down
2019-10-01T18:29:55.622Z,1569954595.622 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-01T18:29:55.623Z,1569954595.623 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-01T18:29:55.623Z,1569954595.623 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-01T18:29:55.624Z,1569954595.624 [MissionManager](INFO): Uninitializing Mission Default
2019-10-01T18:29:55.624Z,1569954595.624 [Default] Stopped
2019-10-01T18:29:55.624Z,1569954595.624 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-01T18:29:55.624Z,1569954595.624 [Default:B.GoToSurface] Stopped
2019-10-01T18:29:55.624Z,1569954595.624 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-01T18:29:55.624Z,1569954595.624 [Default:CheckIn] Stopped
2019-10-01T18:29:55.624Z,1569954595.624 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-01T18:29:55.624Z,1569954595.624 [Default:CheckIn:Read_GPS] Stopped
2019-10-01T18:29:55.627Z,1569954595.627 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-01T18:29:55.627Z,1569954595.627 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-01T18:29:55.627Z,1569954595.627 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-01T18:29:55.628Z,1569954595.628 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-01T18:29:55.628Z,1569954595.628 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-01T18:29:55.628Z,1569954595.628 [BuoyancyServo](INFO): Powering down
2019-10-01T18:29:55.641Z,1569954595.641 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-01T18:29:55.641Z,1569954595.641 [ElevatorServo](INFO): Powering down
2019-10-01T18:29:55.642Z,1569954595.642 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-01T18:29:55.642Z,1569954595.642 [MassServo](INFO): Powering down
2019-10-01T18:29:55.643Z,1569954595.643 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-01T18:29:55.643Z,1569954595.643 [RudderServo](INFO): Powering down
2019-10-01T18:29:55.643Z,1569954595.643 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-01T18:29:55.644Z,1569954595.644 [ThrusterServo](INFO): Powering down
2019-10-01T18:29:55.644Z,1569954595.644 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-01T18:29:55.645Z,1569954595.645 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-01T18:29:55.645Z,1569954595.645 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-01T18:29:55.645Z,1569954595.645 [CBIT](DEBUG): Powering off loads.
2019-10-01T18:29:55.657Z,1569954595.657 [CBIT](DEBUG): Disabling WDT.
2019-10-01T18:29:55.669Z,1569954595.669 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-01T18:29:55.670Z,1569954595.670 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.763Z,1569954595.763 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.769Z,1569954595.769 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.820Z,1569954595.820 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.821Z,1569954595.821 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2019-10-01T18:29:55.821Z,1569954595.821 [WetLabsUBAT](INFO): WetLabs destructor: done
2019-10-01T18:29:55.824Z,1569954595.824 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.827Z,1569954595.827 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.837Z,1569954595.837 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-01T18:29:55.896Z,1569954595.896 [logger ThreadHandler](INFO): Thread cancelled.