2019-05-07T23:03:42.981Z,1557270222.981 [Supervisor](DEBUG): Initializing supervisor.
2019-05-07T23:03:42.984Z,1557270222.984 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-07T23:03:42.984Z,1557270222.984 [SyncHandler](INFO): Protected caller Thread ID is 9515
2019-05-07T23:03:42.985Z,1557270222.985 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-07T23:03:42.986Z,1557270222.986 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-07T23:03:42.987Z,1557270222.987 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9516
2019-05-07T23:03:42.989Z,1557270222.989 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-07T23:03:43.002Z,1557270223.002 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-07T23:03:43.002Z,1557270223.002 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-07T23:03:43.003Z,1557270223.003 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9517
2019-05-07T23:03:43.004Z,1557270223.004 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-07T23:03:43.005Z,1557270223.005 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-07T23:03:43.005Z,1557270223.005 [logger ThreadHandler](INFO): Protected caller Thread ID is 9518
2019-05-07T23:03:43.007Z,1557270223.007 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-07T23:03:43.007Z,1557270223.007 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-07T23:03:43.009Z,1557270223.009 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-07T23:03:43.106Z,1557270223.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-07T23:03:43.107Z,1557270223.107 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-07T23:03:43.309Z,1557270223.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-07T23:03:43.310Z,1557270223.310 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-07T23:03:43.448Z,1557270223.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-07T23:03:43.449Z,1557270223.449 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-07T23:03:43.943Z,1557270223.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-07T23:03:43.943Z,1557270223.943 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-07T23:03:44.401Z,1557270224.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-07T23:03:44.402Z,1557270224.402 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-07T23:03:44.873Z,1557270224.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-07T23:03:44.874Z,1557270224.874 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-07T23:03:45.181Z,1557270225.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-07T23:03:45.182Z,1557270225.182 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-07T23:03:45.514Z,1557270225.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-07T23:03:45.514Z,1557270225.514 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-07T23:03:45.917Z,1557270225.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-07T23:03:45.918Z,1557270225.918 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-07T23:03:46.071Z,1557270226.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-07T23:03:46.072Z,1557270226.072 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-07T23:03:46.178Z,1557270226.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-07T23:03:46.179Z,1557270226.179 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-07T23:03:46.261Z,1557270226.261 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-07T23:03:46.365Z,1557270226.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-07T23:03:46.366Z,1557270226.366 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-07T23:03:46.561Z,1557270226.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-07T23:03:46.562Z,1557270226.562 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-07T23:03:46.772Z,1557270226.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-07T23:03:46.774Z,1557270226.774 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-05-07T23:03:46.775Z,1557270226.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-05-07T23:03:46.860Z,1557270226.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-05-07T23:03:47.094Z,1557270227.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-07T23:03:47.094Z,1557270227.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-05-07T23:03:47.195Z,1557270227.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-05-07T23:03:47.795Z,1557270227.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-05-07T23:03:48.158Z,1557270228.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-05-07T23:03:48.245Z,1557270228.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-05-07T23:03:48.345Z,1557270228.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-05-07T23:03:48.439Z,1557270228.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-05-07T23:03:48.591Z,1557270228.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-05-07T23:03:48.698Z,1557270228.698 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-05-07T23:03:48.798Z,1557270228.798 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-05-07T23:03:48.798Z,1557270228.798 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-07T23:03:48.804Z,1557270228.804 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-07T23:03:48.901Z,1557270228.901 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-07T23:03:48.902Z,1557270228.902 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-07T23:03:49.031Z,1557270229.031 [BuoyancyServo] Loaded
2019-05-07T23:03:49.031Z,1557270229.031 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-07T23:03:49.046Z,1557270229.046 [ElevatorServo] Loaded
2019-05-07T23:03:49.046Z,1557270229.046 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-07T23:03:49.061Z,1557270229.061 [MassServo] Loaded
2019-05-07T23:03:49.061Z,1557270229.061 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-07T23:03:49.076Z,1557270229.076 [RudderServo] Loaded
2019-05-07T23:03:49.076Z,1557270229.076 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-07T23:03:49.091Z,1557270229.091 [ThrusterServo] Loaded
2019-05-07T23:03:49.091Z,1557270229.091 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-07T23:03:49.091Z,1557270229.091 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-07T23:03:49.092Z,1557270229.092 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-07T23:03:49.225Z,1557270229.225 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-07T23:03:49.254Z,1557270229.254 [SBIT] Loaded
2019-05-07T23:03:49.254Z,1557270229.254 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-07T23:03:49.255Z,1557270229.255 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-07T23:03:49.282Z,1557270229.282 [IBIT] Loaded
2019-05-07T23:03:49.283Z,1557270229.283 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-07T23:03:49.286Z,1557270229.286 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-07T23:03:49.674Z,1557270229.674 [CBIT] Loaded
2019-05-07T23:03:49.674Z,1557270229.674 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-07T23:03:49.675Z,1557270229.675 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-07T23:03:49.675Z,1557270229.675 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-07T23:03:49.855Z,1557270229.855 [Aanderaa_O2] Loaded
2019-05-07T23:03:49.855Z,1557270229.855 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-05-07T23:03:49.865Z,1557270229.865 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-07T23:03:49.870Z,1557270229.870 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-07T23:03:49.872Z,1557270229.872 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-07T23:03:49.877Z,1557270229.877 [CTD_Seabird](INFO): created writer for : depth
2019-05-07T23:03:49.877Z,1557270229.877 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-07T23:03:49.882Z,1557270229.882 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-07T23:03:49.883Z,1557270229.883 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-07T23:03:49.888Z,1557270229.888 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-07T23:03:49.889Z,1557270229.889 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-07T23:03:49.894Z,1557270229.894 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-07T23:03:49.895Z,1557270229.895 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-07T23:03:49.900Z,1557270229.900 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-07T23:03:49.901Z,1557270229.901 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-07T23:03:49.906Z,1557270229.906 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-07T23:03:49.932Z,1557270229.932 [CTD_Seabird] Loaded
2019-05-07T23:03:49.933Z,1557270229.933 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-07T23:03:49.934Z,1557270229.934 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4065E4E0
2019-05-07T23:03:49.934Z,1557270229.934 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9597
2019-05-07T23:03:49.960Z,1557270229.960 [ESPComponent] Loaded
2019-05-07T23:03:49.960Z,1557270229.960 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-05-07T23:03:49.974Z,1557270229.974 [PAR_Licor] Loaded
2019-05-07T23:03:49.974Z,1557270229.974 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-07T23:03:50.023Z,1557270230.023 [WetLabsBB2FL] Loaded
2019-05-07T23:03:50.024Z,1557270230.024 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-07T23:03:50.024Z,1557270230.024 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4068E4E0
2019-05-07T23:03:50.025Z,1557270230.025 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9598
2019-05-07T23:03:50.026Z,1557270230.026 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-07T23:03:50.026Z,1557270230.026 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-07T23:03:50.066Z,1557270230.066 [DepthRateCalculator] Loaded
2019-05-07T23:03:50.066Z,1557270230.066 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-07T23:03:50.072Z,1557270230.072 [PitchRateCalculator] Loaded
2019-05-07T23:03:50.072Z,1557270230.072 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-07T23:03:50.088Z,1557270230.088 [SpeedCalculator] Loaded
2019-05-07T23:03:50.089Z,1557270230.089 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-07T23:03:50.110Z,1557270230.110 [TempGradientCalculator] Loaded
2019-05-07T23:03:50.110Z,1557270230.110 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-07T23:03:50.116Z,1557270230.116 [YawRateCalculator] Loaded
2019-05-07T23:03:50.116Z,1557270230.116 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-07T23:03:50.149Z,1557270230.149 [ElevatorOffsetCalculator] Loaded
2019-05-07T23:03:50.149Z,1557270230.149 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-07T23:03:50.150Z,1557270230.150 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-07T23:03:50.150Z,1557270230.150 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-07T23:03:50.176Z,1557270230.176 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-07T23:03:50.177Z,1557270230.177 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-07T23:03:50.280Z,1557270230.280 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-07T23:03:50.280Z,1557270230.280 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-07T23:03:50.341Z,1557270230.341 [DeadReckonUsingSpeedCalculator] Loaded
2019-05-07T23:03:50.341Z,1557270230.341 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-05-07T23:03:50.400Z,1557270230.400 [DeadReckonWithRespectToSeafloor] Loaded
2019-05-07T23:03:50.401Z,1557270230.401 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-05-07T23:03:50.421Z,1557270230.421 [NavChart] Loaded
2019-05-07T23:03:50.421Z,1557270230.421 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-07T23:03:50.425Z,1557270230.425 [UniversalFixResidualReporter] Loaded
2019-05-07T23:03:50.426Z,1557270230.426 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-07T23:03:50.426Z,1557270230.426 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-07T23:03:50.427Z,1557270230.427 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-07T23:03:50.739Z,1557270230.739 [AcousticModem_Benthos_ATM900] Loaded
2019-05-07T23:03:50.739Z,1557270230.739 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-05-07T23:03:50.813Z,1557270230.813 [DataOverHttps] Loaded
2019-05-07T23:03:50.813Z,1557270230.813 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-07T23:03:50.814Z,1557270230.814 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2019-05-07T23:03:50.815Z,1557270230.815 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9599
2019-05-07T23:03:50.828Z,1557270230.828 [Depth_Keller] Loaded
2019-05-07T23:03:50.829Z,1557270230.829 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-07T23:03:50.921Z,1557270230.921 [NAL9602] Loaded
2019-05-07T23:03:50.921Z,1557270230.921 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-07T23:03:50.926Z,1557270230.926 [Onboard] Loaded
2019-05-07T23:03:50.927Z,1557270230.927 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-07T23:03:50.931Z,1557270230.931 [Radio_Surface] Loaded
2019-05-07T23:03:50.931Z,1557270230.931 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-07T23:03:50.932Z,1557270230.932 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099E4E0
2019-05-07T23:03:50.932Z,1557270230.932 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9600
2019-05-07T23:03:50.979Z,1557270230.979 [PNI_TCM] Loaded
2019-05-07T23:03:50.979Z,1557270230.979 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-05-07T23:03:51.157Z,1557270231.157 [Rowe_600] Loaded
2019-05-07T23:03:51.158Z,1557270231.158 [ComponentRegistry](DEBUG): Component "Rowe_600" handled in its own thread.
2019-05-07T23:03:51.159Z,1557270231.159 [Rowe_600 ThreadHandler](DEBUG): Created PCaller Thread at 409CE4E0
2019-05-07T23:03:51.159Z,1557270231.159 [Rowe_600 ThreadHandler](INFO): Protected caller Thread ID is 9601
2019-05-07T23:03:52.466Z,1557270232.466 [BPC1] Loaded
2019-05-07T23:03:52.467Z,1557270232.467 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-07T23:03:52.467Z,1557270232.467 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-07T23:03:52.468Z,1557270232.468 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-07T23:03:52.826Z,1557270232.826 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-07T23:03:52.826Z,1557270232.826 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-07T23:03:52.917Z,1557270232.917 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-07T23:03:53.052Z,1557270233.052 [VerticalControl] Loaded
2019-05-07T23:03:53.052Z,1557270233.052 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-07T23:03:53.053Z,1557270233.053 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-07T23:03:53.112Z,1557270233.112 [HorizontalControl] Loaded
2019-05-07T23:03:53.112Z,1557270233.112 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-07T23:03:53.113Z,1557270233.113 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-07T23:03:53.114Z,1557270233.114 [SpeedControl] Loaded
2019-05-07T23:03:53.115Z,1557270233.115 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-07T23:03:53.115Z,1557270233.115 [LoopControl](DEBUG): Construct LoopControl.
2019-05-07T23:03:53.116Z,1557270233.116 [LoopControl] Loaded
2019-05-07T23:03:53.116Z,1557270233.116 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-07T23:03:53.117Z,1557270233.117 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-07T23:03:53.117Z,1557270233.117 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-07T23:03:53.130Z,1557270233.130 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-07T23:03:53.131Z,1557270233.131 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-07T23:03:53.405Z,1557270233.405 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-07T23:03:53.409Z,1557270233.409 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-07T23:03:53.410Z,1557270233.410 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-07T23:03:53.417Z,1557270233.417 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-07T23:03:53.418Z,1557270233.418 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B014E0
2019-05-07T23:03:53.418Z,1557270233.418 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9602
2019-05-07T23:03:53.423Z,1557270233.423 [Supervisor](INFO): Main Thread ID is 7672
2019-05-07T23:03:53.423Z,1557270233.423 [Supervisor](DEBUG): Running supervisor.
2019-05-07T23:03:53.423Z,1557270233.423 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9603
2019-05-07T23:03:53.426Z,1557270233.426 [controlThread ThreadHandler](INFO): Handler Thread ID is 9604
2019-05-07T23:03:53.426Z,1557270233.426 [controlThread](DEBUG): Initializing ControlThread
2019-05-07T23:03:53.428Z,1557270233.428 [SBIT](INFO): Initialize SBIT Component.
2019-05-07T23:03:53.428Z,1557270233.428 [SBIT](IMPORTANT): git: 2019-04-10-2-gf3e4ef2
2019-05-07T23:03:53.429Z,1557270233.429 [SBIT](INFO): git hash: f3e4ef2c5f3aa5e841d57d486bee5a5f25629231
2019-05-07T23:03:53.429Z,1557270233.429 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-07T23:03:53.430Z,1557270233.430 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-07T23:03:53.432Z,1557270233.432 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-05-07T23:03:53.432Z,1557270233.432 [IBIT](INFO): Initialize IBIT Component.
2019-05-07T23:03:53.433Z,1557270233.433 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-07T23:03:53.434Z,1557270233.434 [logger ThreadHandler](INFO): Handler Thread ID is 9605
2019-05-07T23:03:53.445Z,1557270233.445 [CBIT](DEBUG): Initialized mux pins.
2019-05-07T23:03:53.445Z,1557270233.445 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-07T23:03:53.453Z,1557270233.453 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9606
2019-05-07T23:03:53.454Z,1557270233.454 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T23:03:53.457Z,1557270233.457 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T23:03:53.459Z,1557270233.459 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9608
2019-05-07T23:03:53.460Z,1557270233.460 [WetLabsBB2FL](INFO): Powering down
2019-05-07T23:03:53.469Z,1557270233.469 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-07T23:03:53.469Z,1557270233.469 [CBIT](DEBUG): Initializing heartbeat.
2019-05-07T23:03:53.489Z,1557270233.489 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9609
2019-05-07T23:03:53.490Z,1557270233.490 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-07T23:03:53.501Z,1557270233.501 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9610
2019-05-07T23:03:53.517Z,1557270233.517 [Rowe_600 ThreadHandler](INFO): Handler Thread ID is 9611
2019-05-07T23:03:53.529Z,1557270233.529 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9612
2019-05-07T23:03:53.533Z,1557270233.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-07T23:03:53.533Z,1557270233.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-07T23:03:53.533Z,1557270233.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-07T23:03:53.533Z,1557270233.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-07T23:03:53.533Z,1557270233.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-07T23:03:53.534Z,1557270233.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-07T23:03:53.534Z,1557270233.534 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-07T23:03:53.534Z,1557270233.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-07T23:03:53.534Z,1557270233.534 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-07T23:03:53.534Z,1557270233.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-07T23:03:53.535Z,1557270233.535 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-07T23:03:53.535Z,1557270233.535 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-07T23:03:53.535Z,1557270233.535 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-07T23:03:53.535Z,1557270233.535 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-07T23:03:53.536Z,1557270233.536 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-07T23:03:53.536Z,1557270233.536 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-07T23:03:53.541Z,1557270233.541 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-07T23:03:53.541Z,1557270233.541 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-07T23:03:53.577Z,1557270233.577 [CBIT](DEBUG): Backplane powered.
2019-05-07T23:03:53.581Z,1557270233.581 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-07T23:03:53.581Z,1557270233.581 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-07T23:03:53.582Z,1557270233.582 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-07T23:03:53.582Z,1557270233.582 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-07T23:03:53.583Z,1557270233.583 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-07T23:03:53.583Z,1557270233.583 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-07T23:03:53.584Z,1557270233.584 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-07T23:03:53.585Z,1557270233.585 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-07T23:03:53.586Z,1557270233.586 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-07T23:03:53.590Z,1557270233.590 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-07T23:03:53.592Z,1557270233.592 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-07T23:03:53.593Z,1557270233.593 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-07T23:03:53.593Z,1557270233.593 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-07T23:03:53.594Z,1557270233.594 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-07T23:03:53.621Z,1557270233.621 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-07T23:03:53.648Z,1557270233.648 [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-05-07T23:03:53.657Z,1557270233.657 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-07T23:03:53.741Z,1557270233.741 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-07T23:03:53.742Z,1557270233.742 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-07T23:03:53.744Z,1557270233.744 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-07T23:03:53.793Z,1557270233.793 [Rowe_600](INFO): Powering down
2019-05-07T23:03:53.799Z,1557270233.799 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-07T23:03:53.841Z,1557270233.841 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-07T23:03:53.846Z,1557270233.846 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-07T23:03:53.870Z,1557270233.870 [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-05-07T23:03:53.875Z,1557270233.875 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-07T23:03:53.894Z,1557270233.894 [ESPComponent](INFO): powering down ESP
2019-05-07T23:03:53.905Z,1557270233.905 [Radio_Surface](INFO): Powering up
2019-05-07T23:03:54.021Z,1557270234.021 [Rowe_600](INFO): Data requested. STOPPED ==> START
2019-05-07T23:03:54.022Z,1557270234.022 [Rowe_600](INFO): Initializing
2019-05-07T23:03:54.121Z,1557270234.121 [Rowe_600](INFO): Powering up
2019-05-07T23:03:54.258Z,1557270234.258 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-07T23:03:54.258Z,1557270234.258 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-07T23:03:54.278Z,1557270234.278 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-05-07T23:03:54.345Z,1557270234.345 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-07T23:03:54.376Z,1557270234.376 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-07T23:03:54.381Z,1557270234.381 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-07T23:03:54.382Z,1557270234.382 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-07T23:03:54.389Z,1557270234.389 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-07T23:03:54.390Z,1557270234.390 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-07T23:03:54.397Z,1557270234.397 [MassServo](DEBUG): Initializing MassServo.
2019-05-07T23:03:54.398Z,1557270234.398 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-07T23:03:54.405Z,1557270234.405 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-07T23:03:54.406Z,1557270234.406 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-07T23:03:54.413Z,1557270234.413 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-07T23:04:01.312Z,1557270241.312 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:04:01.312Z,1557270241.312 [PNI_TCM] Data Fault, FailCount= 1
2019-05-07T23:04:01.312Z,1557270241.312 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:04:01.401Z,1557270241.401 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:04:01.716Z,1557270241.716 [PNI_TCM](INFO): Powering down
2019-05-07T23:04:03.193Z,1557270243.193 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T23:04:03.193Z,1557270243.193 [PNI_TCM] No Fault, FailCount= 1
2019-05-07T23:04:03.438Z,1557270243.438 [CBIT](CRITICAL): Environmental Failure. Press:14.774634 PSI. Humidity:36%. Temp:26 C. ABORTING MISSION
2019-05-07T23:04:03.438Z,1557270243.438 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2019-05-07T23:04:03.763Z,1557270243.763 [CommandLine](FAULT): Scheduling is paused
2019-05-07T23:04:03.764Z,1557270243.764 [CBIT](INFO): Critical error at 20190507T230403
2019-05-07T23:04:04.117Z,1557270244.117 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T23:04:04.117Z,1557270244.117 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-07T23:04:04.117Z,1557270244.117 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T23:04:04.202Z,1557270244.202 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T23:04:04.641Z,1557270244.641 [Aanderaa_O2](INFO): Powering down
2019-05-07T23:04:05.413Z,1557270245.413 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T23:04:05.413Z,1557270245.413 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-07T23:04:06.505Z,1557270246.505 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T23:04:06.505Z,1557270246.505 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-07T23:04:06.505Z,1557270246.505 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T23:04:06.610Z,1557270246.610 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T23:04:06.910Z,1557270246.910 [CTD_Seabird](INFO): Powering down
2019-05-07T23:04:08.246Z,1557270248.246 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T23:04:08.247Z,1557270248.247 [CTD_Seabird] No Fault, FailCount= 1
2019-05-07T23:04:08.425Z,1557270248.425 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T23:04:08.427Z,1557270248.427 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T23:04:10.623Z,1557270250.623 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:04:10.623Z,1557270250.623 [PNI_TCM] Data Fault, FailCount= 2
2019-05-07T23:04:10.623Z,1557270250.623 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:04:10.655Z,1557270250.655 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:04:11.030Z,1557270251.030 [PNI_TCM](INFO): Powering down
2019-05-07T23:04:12.258Z,1557270252.258 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T23:04:12.258Z,1557270252.258 [PNI_TCM] No Fault, FailCount= 2
2019-05-07T23:04:14.626Z,1557270254.626 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-07T23:04:14.626Z,1557270254.626 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-05-07T23:04:14.626Z,1557270254.626 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-07T23:04:14.699Z,1557270254.699 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-07T23:04:15.034Z,1557270255.034 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-07T23:04:15.833Z,1557270255.833 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T23:04:15.833Z,1557270255.833 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-07T23:04:15.833Z,1557270255.833 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T23:04:15.942Z,1557270255.942 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T23:04:15.942Z,1557270255.942 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-07T23:04:15.943Z,1557270255.943 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-05-07T23:04:16.357Z,1557270256.357 [Aanderaa_O2](INFO): Powering down
2019-05-07T23:04:17.094Z,1557270257.094 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T23:04:17.094Z,1557270257.094 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-07T23:04:17.959Z,1557270257.959 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-07T23:04:17.967Z,1557270257.967 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-07T23:04:18.261Z,1557270258.261 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-07T23:04:18.262Z,1557270258.262 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-07T23:04:19.910Z,1557270259.910 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:04:19.910Z,1557270259.910 [PNI_TCM] Data Fault, FailCount= 3
2019-05-07T23:04:19.910Z,1557270259.910 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:04:20.530Z,1557270260.530 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:04:20.560Z,1557270260.560 [PNI_TCM](INFO): Powering down
2019-05-07T23:04:20.968Z,1557270260.968 [NAL9602](INFO): Powering up NAL9602
2019-05-07T23:04:21.477Z,1557270261.477 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T23:04:21.477Z,1557270261.477 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-07T23:04:21.477Z,1557270261.477 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T23:04:21.582Z,1557270261.582 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T23:04:21.882Z,1557270261.882 [CTD_Seabird](INFO): Powering down
2019-05-07T23:04:21.891Z,1557270261.891 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T23:04:21.891Z,1557270261.891 [PNI_TCM] No Fault, FailCount= 3
2019-05-07T23:04:23.242Z,1557270263.242 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T23:04:23.242Z,1557270263.242 [CTD_Seabird] No Fault, FailCount= 2
2019-05-07T23:04:23.629Z,1557270263.629 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T23:04:23.631Z,1557270263.631 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T23:04:27.805Z,1557270267.805 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T23:04:27.805Z,1557270267.805 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-07T23:04:27.805Z,1557270267.805 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T23:04:27.951Z,1557270267.951 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T23:04:27.952Z,1557270267.952 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-07T23:04:28.329Z,1557270268.329 [Aanderaa_O2](INFO): Powering down
2019-05-07T23:04:28.708Z,1557270268.708 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.009154
CHAN A1 (24V): 0.041051
CHAN A2 (12V): -0.005078
CHAN A3 (5V): -0.002802
CHAN B0 (3.3V): -0.001066
CHAN B1 (3.15aV): -0.001034
CHAN B2 (3.15bV): -0.001002
CHAN B3 (GND): 0.000367
OPEN: 0.006681
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-07T23:04:29.444Z,1557270269.444 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:04:29.444Z,1557270269.444 [PNI_TCM] Data Fault, FailCount= 4
2019-05-07T23:04:29.444Z,1557270269.444 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:04:29.546Z,1557270269.546 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:04:29.848Z,1557270269.848 [PNI_TCM](INFO): Powering down
2019-05-07T23:04:31.238Z,1557270271.238 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T23:04:31.238Z,1557270271.238 [PNI_TCM] No Fault, FailCount= 4
2019-05-07T23:04:31.854Z,1557270271.854 [NAL9602](INFO): NAL9602 initialized
2019-05-07T23:04:32.673Z,1557270272.673 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:04:36.681Z,1557270276.681 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T23:04:36.681Z,1557270276.681 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-07T23:04:36.681Z,1557270276.681 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T23:04:37.087Z,1557270277.087 [CTD_Seabird](INFO): Powering down
2019-05-07T23:04:37.094Z,1557270277.094 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T23:04:37.094Z,1557270277.094 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-07T23:04:38.478Z,1557270278.478 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-07T23:04:38.478Z,1557270278.478 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-05-07T23:04:38.478Z,1557270278.478 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-07T23:04:38.738Z,1557270278.738 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-07T23:04:38.873Z,1557270278.873 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-07T23:04:39.023Z,1557270279.023 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:04:39.023Z,1557270279.023 [PNI_TCM] Data Fault, FailCount= 5
2019-05-07T23:04:39.023Z,1557270279.023 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:04:39.210Z,1557270279.210 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:04:39.211Z,1557270279.211 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-05-07T23:04:39.308Z,1557270279.308 [PNI_TCM](INFO): Powering down
2019-05-07T23:04:39.452Z,1557270279.452 [CBIT](INFO): Critical error at 20190507T230439
2019-05-07T23:04:39.794Z,1557270279.794 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-07T23:04:39.794Z,1557270279.794 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-05-07T23:04:42.114Z,1557270282.114 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-07T23:04:42.114Z,1557270282.114 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-07T23:04:43.143Z,1557270283.143 [CommandLine](IMPORTANT): got command show variable rowe_600
2019-05-07T23:04:43.175Z,1557270283.175 [CommandLine](IMPORTANT): Rowe_600.loadAtStartup (bool)
2019-05-07T23:04:43.175Z,1557270283.175 [CommandLine](IMPORTANT): Rowe_600.simulateHardware (bool)
2019-05-07T23:04:43.175Z,1557270283.175 [CommandLine](IMPORTANT): Rowe_600.acousticBlankingDistance (meter)
2019-05-07T23:04:43.176Z,1557270283.176 [CommandLine](IMPORTANT): Rowe_600.altitudeAccuracy (meter)
2019-05-07T23:04:43.176Z,1557270283.176 [CommandLine](IMPORTANT): Rowe_600.bottomTrackVelocityAccuracy (meter_per_second)
2019-05-07T23:04:43.213Z,1557270283.213 [CommandLine](IMPORTANT): Rowe_600.headingOffset (degree)
2019-05-07T23:04:43.213Z,1557270283.213 [CommandLine](IMPORTANT): Rowe_600.maxSpeed (meter_per_second)
2019-05-07T23:04:43.214Z,1557270283.214 [CommandLine](IMPORTANT): Rowe_600.numberOfBeams (count)
2019-05-07T23:04:43.214Z,1557270283.214 [CommandLine](IMPORTANT): Rowe_600.numberOfBins (count)
2019-05-07T23:04:43.214Z,1557270283.214 [CommandLine](IMPORTANT): Rowe_600.pausePeriod (millisecond)
2019-05-07T23:04:43.215Z,1557270283.215 [CommandLine](IMPORTANT): Rowe_600.pitchOffset (degree)
2019-05-07T23:04:43.215Z,1557270283.215 [CommandLine](IMPORTANT): Rowe_600.rollOffset (degree)
2019-05-07T23:04:43.216Z,1557270283.216 [CommandLine](IMPORTANT): Rowe_600.sampleTime (second)
2019-05-07T23:04:43.216Z,1557270283.216 [CommandLine](IMPORTANT): Rowe_600.verbosity (count)
2019-05-07T23:04:43.216Z,1557270283.216 [CommandLine](IMPORTANT): Rowe_600.waterReferenceLayerBin (count)
2019-05-07T23:04:43.221Z,1557270283.221 [CommandLine](IMPORTANT): Rowe_600.waterTrackVelocityAccuracy (meter_per_second)
2019-05-07T23:04:43.221Z,1557270283.221 [CommandLine](IMPORTANT): Rowe_600.writeAmplitudeProfile (bool)
2019-05-07T23:04:43.222Z,1557270283.222 [CommandLine](IMPORTANT): Rowe_600.writeBeamVelocityProfile (bool)
2019-05-07T23:04:43.222Z,1557270283.222 [CommandLine](IMPORTANT): Rowe_600.writeCorrelationProfile (bool)
2019-05-07T23:04:43.222Z,1557270283.222 [CommandLine](IMPORTANT): Rowe_600.writeEarthVelocityProfile (bool)
2019-05-07T23:04:43.223Z,1557270283.223 [CommandLine](IMPORTANT): Rowe_600.writeGoodBeamPingsProfile (bool)
2019-05-07T23:04:43.223Z,1557270283.223 [CommandLine](IMPORTANT): Rowe_600.writeGoodEarthPingsProfile (bool)
2019-05-07T23:04:43.224Z,1557270283.224 [CommandLine](IMPORTANT): Rowe_600.writeInstrumentVelocityProfile (bool)
2019-05-07T23:04:43.224Z,1557270283.224 [CommandLine](IMPORTANT): Rowe_600.writeRawEnsemble (bool)
2019-05-07T23:04:43.224Z,1557270283.224 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup (bool)
2019-05-07T23:04:43.229Z,1557270283.229 [CommandLine](IMPORTANT): Rowe_600LCM.simulateHardware (bool)
2019-05-07T23:04:43.229Z,1557270283.229 [CommandLine](IMPORTANT): Rowe_600LCM.altitudeAccuracy (meter)
2019-05-07T23:04:43.230Z,1557270283.230 [CommandLine](IMPORTANT): Rowe_600LCM.bottomTrackVelocityAccuracy (meter_per_second)
2019-05-07T23:04:43.230Z,1557270283.230 [CommandLine](IMPORTANT): Rowe_600LCM.waterTrackVelocityAccuracy (meter_per_second)
2019-05-07T23:04:43.251Z,1557270283.251 [CommandLine](IMPORTANT): Rowe_600.loadControl (none)
2019-05-07T23:04:43.252Z,1557270283.252 [CommandLine](IMPORTANT): Rowe_600.uart (none)
2019-05-07T23:04:43.252Z,1557270283.252 [CommandLine](IMPORTANT): Rowe_600.baud (bit_per_second)
2019-05-07T23:04:43.252Z,1557270283.252 [CommandLine](IMPORTANT): Rowe_600LCM.loadControl (none)
2019-05-07T23:04:43.257Z,1557270283.257 [CommandLine](IMPORTANT): Rowe_600LCM.uart (none)
2019-05-07T23:04:43.258Z,1557270283.258 [CommandLine](IMPORTANT): Rowe_600LCM.baud (bit_per_second)
2019-05-07T23:04:43.258Z,1557270283.258 [CommandLine](IMPORTANT): Rowe_600LCM.lcmApplication (none)
2019-05-07T23:04:43.258Z,1557270283.258 [CommandLine](IMPORTANT): Rowe_600LCM.lcmChannelBottom (none)
2019-05-07T23:04:43.259Z,1557270283.259 [CommandLine](IMPORTANT): Rowe_600LCM.lcmChannelDVL (none)
2019-05-07T23:04:43.259Z,1557270283.259 [CommandLine](IMPORTANT): Rowe_600LCM.lcmChannelWater (none)
2019-05-07T23:04:43.259Z,1557270283.259 [CommandLine](IMPORTANT): Rowe_600LCM.maxSpeed (meter_per_second)
2019-05-07T23:04:43.612Z,1557270283.612 [CommandLine](IMPORTANT): Rowe_600.height_above_sea_floor (meter)
2019-05-07T23:04:43.613Z,1557270283.613 [CommandLine](IMPORTANT): Rowe_600.platform_velocity_wrt_ground (meter_per_second)
2019-05-07T23:04:43.613Z,1557270283.613 [CommandLine](IMPORTANT): Rowe_600.platform_velocity_wrt_sea_water (meter_per_second)
2019-05-07T23:04:43.614Z,1557270283.614 [CommandLine](IMPORTANT): Rowe_600.first_bottom_track_ping_time (second)
2019-05-07T23:04:43.614Z,1557270283.614 [CommandLine](IMPORTANT): Rowe_600.last_bottom_track_ping_time (second)
2019-05-07T23:04:43.614Z,1557270283.614 [CommandLine](IMPORTANT): Rowe_600.heading (radian)
2019-05-07T23:04:43.615Z,1557270283.615 [CommandLine](IMPORTANT): Rowe_600.pitch (radian)
2019-05-07T23:04:43.615Z,1557270283.615 [CommandLine](IMPORTANT): Rowe_600.roll (radian)
2019-05-07T23:04:43.615Z,1557270283.615 [CommandLine](IMPORTANT): Rowe_600.water_temperature (celsius)
2019-05-07T23:04:43.616Z,1557270283.616 [CommandLine](IMPORTANT): Rowe_600.system_temperature (celsius)
2019-05-07T23:04:43.616Z,1557270283.616 [CommandLine](IMPORTANT): Rowe_600.salinity (part_per_thousand)
2019-05-07T23:04:43.616Z,1557270283.616 [CommandLine](IMPORTANT): Rowe_600.pressure (pascal)
2019-05-07T23:04:43.633Z,1557270283.633 [CommandLine](IMPORTANT): Rowe_600.depth (meter)
2019-05-07T23:04:43.634Z,1557270283.634 [CommandLine](IMPORTANT): Rowe_600.soundspeed (meter_per_second)
2019-05-07T23:04:43.634Z,1557270283.634 [CommandLine](IMPORTANT): Rowe_600.status_or (bool)
2019-05-07T23:04:43.634Z,1557270283.634 [CommandLine](IMPORTANT): Rowe_600.number_of_bottom_track_beams (count)
2019-05-07T23:04:43.635Z,1557270283.635 [CommandLine](IMPORTANT): Rowe_600.ping_count (count)
2019-05-07T23:04:43.635Z,1557270283.635 [CommandLine](IMPORTANT): Rowe_600.vertical_range (meter)
2019-05-07T23:04:43.635Z,1557270283.635 [CommandLine](IMPORTANT): Rowe_600.signal_to_noise (none)
2019-05-07T23:04:43.636Z,1557270283.636 [CommandLine](IMPORTANT): Rowe_600.bottom_track_amplitude (count)
2019-05-07T23:04:43.636Z,1557270283.636 [CommandLine](IMPORTANT): Rowe_600.bottom_track_correlation (count)
2019-05-07T23:04:43.637Z,1557270283.637 [CommandLine](IMPORTANT): Rowe_600.bottom_track_beam_velocity (meter_per_second)
2019-05-07T23:04:43.637Z,1557270283.637 [CommandLine](IMPORTANT): Rowe_600.bottom_track_number_of_pings_averaged (count)
2019-05-07T23:04:43.637Z,1557270283.637 [CommandLine](IMPORTANT): Rowe_600.bottom_track_instrument_velocity (meter_per_second)
2019-05-07T23:04:43.638Z,1557270283.638 [CommandLine](IMPORTANT): Rowe_600.bottom_track_number_of_instrument_solutions_averaged (count)
2019-05-07T23:04:43.638Z,1557270283.638 [CommandLine](IMPORTANT): Rowe_600.bottom_track_earth_velocity (meter_per_second)
2019-05-07T23:04:43.639Z,1557270283.639 [CommandLine](IMPORTANT): Rowe_600.bottom_track_number_of_earth_solutions_averaged (count)
2019-05-07T23:04:43.639Z,1557270283.639 [CommandLine](IMPORTANT): Rowe_600.ensemble_number (count)
2019-05-07T23:04:43.645Z,1557270283.645 [CommandLine](IMPORTANT): Rowe_600.payload_size (byte)
2019-05-07T23:04:43.646Z,1557270283.646 [CommandLine](IMPORTANT): Rowe_600.number_of_beams (count)
2019-05-07T23:04:43.647Z,1557270283.647 [CommandLine](IMPORTANT): Rowe_600.number_of_bins (count)
2019-05-07T23:04:43.647Z,1557270283.647 [CommandLine](IMPORTANT): Rowe_600.number_of_desired_pings (count)
2019-05-07T23:04:43.647Z,1557270283.647 [CommandLine](IMPORTANT): Rowe_600.number_of_acquired_pings (count)
2019-05-07T23:04:43.648Z,1557270283.648 [CommandLine](IMPORTANT): Rowe_600.status (bool)
2019-05-07T23:04:43.648Z,1557270283.648 [CommandLine](IMPORTANT): Rowe_600.ensemble_timestamp (second)
2019-05-07T23:04:43.649Z,1557270283.649 [CommandLine](IMPORTANT): Rowe_600.beam_velocity (meter_per_second)
2019-05-07T23:04:43.649Z,1557270283.649 [CommandLine](IMPORTANT): Rowe_600.instrument_velocity (meter_per_second)
2019-05-07T23:04:43.649Z,1557270283.649 [CommandLine](IMPORTANT): Rowe_600.earth_velocity (meter_per_second)
2019-05-07T23:04:43.650Z,1557270283.650 [CommandLine](IMPORTANT): Rowe_600.amplitude (count)
2019-05-07T23:04:43.650Z,1557270283.650 [CommandLine](IMPORTANT): Rowe_600.correlation (count)
2019-05-07T23:04:43.651Z,1557270283.651 [CommandLine](IMPORTANT): Rowe_600.good_beam_pings (count)
2019-05-07T23:04:43.651Z,1557270283.651 [CommandLine](IMPORTANT): Rowe_600.good_earth_pings (count)
2019-05-07T23:04:43.762Z,1557270283.762 [CommandLine](IMPORTANT): Rowe_600.component_voltage (volt)
2019-05-07T23:04:43.762Z,1557270283.762 [CommandLine](IMPORTANT): Rowe_600.component_avgVoltage (volt)
2019-05-07T23:04:43.762Z,1557270283.762 [CommandLine](IMPORTANT): Rowe_600.component_current (milliampere)
2019-05-07T23:04:43.763Z,1557270283.763 [CommandLine](IMPORTANT): Rowe_600.component_avgCurrent (milliampere)
2019-05-07T23:05:01.632Z,1557270301.632 [BPC1](ERROR): BPC1A: No match for serial number 04CC in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-05-07T23:05:02.052Z,1557270302.052 [BPC1](ERROR): BPC1B: No match for serial number 018D in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-05-07T23:05:02.052Z,1557270302.052 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-05-07T23:05:02.052Z,1557270302.052 [BPC1] Data Fault, FailCount= 1
2019-05-07T23:05:02.052Z,1557270302.052 [BPC1](ERROR): Data Fault
2019-05-07T23:05:02.210Z,1557270302.210 [CBIT](ERROR): Data Fault in component: BPC1
2019-05-07T23:05:02.410Z,1557270302.410 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-07T23:05:02.410Z,1557270302.410 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-05-07T23:05:02.410Z,1557270302.410 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-07T23:05:02.542Z,1557270302.542 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-07T23:05:02.814Z,1557270302.814 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-07T23:05:03.694Z,1557270303.694 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-07T23:05:03.694Z,1557270303.694 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-05-07T23:05:06.048Z,1557270306.048 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-07T23:05:06.048Z,1557270306.048 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-07T23:05:11.756Z,1557270311.756 [SBIT](IMPORTANT): SBIT PASSED
2019-05-07T23:05:11.850Z,1557270311.850 [CommandLine](IMPORTANT): got command configSet list
2019-05-07T23:05:11.850Z,1557270311.850 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-07T23:05:11.851Z,1557270311.851 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-07T23:05:11.851Z,1557270311.851 [CommandLine](IMPORTANT): PAR_Licor.adcCal=8.765e-06 microampere_per_count;

2019-05-07T23:05:11.851Z,1557270311.851 [CommandLine](IMPORTANT): PAR_Licor.darkCount=0 count;

2019-05-07T23:05:11.851Z,1557270311.851 [CommandLine](IMPORTANT): PAR_Licor.multiplier=190.3 micromole_per_second_per_square_meter_per_microampere;

2019-05-07T23:05:11.851Z,1557270311.851 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-05-07T23:05:12.149Z,1557270312.149 [MissionManager](IMPORTANT): Started mission Startup
2019-05-07T23:05:12.149Z,1557270312.149 [Startup] Running Loop=1
2019-05-07T23:05:12.150Z,1557270312.150 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-07T23:05:12.150Z,1557270312.150 [Startup:A.GoToSurface] Running Loop=1
2019-05-07T23:05:12.150Z,1557270312.150 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-07T23:05:12.150Z,1557270312.150 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-07T23:05:12.151Z,1557270312.151 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-07T23:05:12.151Z,1557270312.151 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-07T23:05:12.152Z,1557270312.152 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-07T23:05:12.152Z,1557270312.152 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-07T23:05:12.154Z,1557270312.154 [Startup:StartupSatComms] Running Loop=1
2019-05-07T23:05:12.154Z,1557270312.154 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-07T23:05:12.154Z,1557270312.154 [Startup:StartupSatComms:A] Running Loop=1
2019-05-07T23:05:12.539Z,1557270312.539 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-07T23:05:26.242Z,1557270326.242 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-07T23:05:26.242Z,1557270326.242 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-05-07T23:05:26.243Z,1557270326.243 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-07T23:05:26.275Z,1557270326.275 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-07T23:05:26.649Z,1557270326.649 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-07T23:05:27.505Z,1557270327.505 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-07T23:05:27.505Z,1557270327.505 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-05-07T23:05:29.885Z,1557270329.885 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-07T23:05:29.886Z,1557270329.886 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-07T23:05:50.086Z,1557270350.086 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-07T23:05:50.086Z,1557270350.086 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-05-07T23:05:50.086Z,1557270350.086 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-07T23:05:50.117Z,1557270350.117 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-07T23:05:50.489Z,1557270350.489 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-07T23:05:51.332Z,1557270351.332 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-07T23:05:51.332Z,1557270351.332 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-05-07T23:05:53.717Z,1557270353.717 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-07T23:05:53.718Z,1557270353.718 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-07T23:05:53.771Z,1557270353.771 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T23:05:53.771Z,1557270353.771 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-07T23:05:53.771Z,1557270353.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-07T23:05:53.773Z,1557270353.773 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T23:05:53.773Z,1557270353.773 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-05-07T23:05:53.773Z,1557270353.773 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-07T23:05:53.774Z,1557270353.774 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T23:05:53.775Z,1557270353.775 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-05-07T23:05:53.775Z,1557270353.775 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-05-07T23:05:53.785Z,1557270353.785 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-07T23:05:53.785Z,1557270353.785 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-07T23:05:53.786Z,1557270353.786 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-05-07T23:05:54.160Z,1557270354.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-07T23:05:54.160Z,1557270354.160 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-07T23:05:54.160Z,1557270354.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-07T23:05:54.161Z,1557270354.161 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-05-07T23:05:54.161Z,1557270354.161 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-05-07T23:05:54.161Z,1557270354.161 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-05-07T23:05:54.564Z,1557270354.564 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-07T23:05:58.986Z,1557270358.986 [CommandLine](IMPORTANT): got command failComponent
2019-05-07T23:05:58.987Z,1557270358.987 [CommandLine](IMPORTANT): Failed components:
2019-05-07T23:05:58.987Z,1557270358.987 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2019-05-07T23:05:58.987Z,1557270358.987 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2019-05-07T23:05:58.987Z,1557270358.987 [CommandLine](IMPORTANT): PNI_TCM: Data Fault
2019-05-07T23:05:58.988Z,1557270358.988 [CommandLine](IMPORTANT): BPC1: Data Fault
2019-05-07T23:06:12.321Z,1557270372.321 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-07T23:05:12.2Z
2019-05-07T23:06:12.321Z,1557270372.321 [Startup:StartupSatComms:A] Stopped
2019-05-07T23:06:12.321Z,1557270372.321 [Startup:StartupSatComms:B] Running Loop=1
2019-05-07T23:06:12.726Z,1557270372.726 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-07T23:06:13.922Z,1557270373.922 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-07T23:06:13.922Z,1557270373.922 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-05-07T23:06:13.922Z,1557270373.922 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-07T23:06:13.972Z,1557270373.972 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-07T23:06:14.322Z,1557270374.322 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-07T23:06:15.177Z,1557270375.177 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-07T23:06:15.177Z,1557270375.177 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-05-07T23:06:17.558Z,1557270377.558 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-07T23:06:17.558Z,1557270377.558 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-07T23:06:32.419Z,1557270392.419 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004986
2019-05-07T23:06:37.757Z,1557270397.757 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-07T23:06:37.758Z,1557270397.758 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-05-07T23:06:37.758Z,1557270397.758 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-07T23:06:37.818Z,1557270397.818 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-07T23:06:38.157Z,1557270398.157 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-07T23:06:39.045Z,1557270399.045 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-07T23:06:39.045Z,1557270399.045 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-05-07T23:06:41.389Z,1557270401.389 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-07T23:06:41.390Z,1557270401.390 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-07T23:06:42.780Z,1557270402.780 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20190507T224155/Courier0010.lzma
2019-05-07T23:06:43.159Z,1557270403.159 [CommandLine](IMPORTANT): got command failComponent hardware Rowe_600
2019-05-07T23:06:43.159Z,1557270403.159 [Rowe_600] Hardware Fault, FailCount= 1
2019-05-07T23:06:43.159Z,1557270403.159 [Rowe_600](ERROR): Hardware Fault
2019-05-07T23:06:43.159Z,1557270403.159 [CommandLine](IMPORTANT): Rowe_600 failureMode is Hardware Fault
2019-05-07T23:06:43.451Z,1557270403.451 [CBIT](ERROR): Hardware Fault in component: Rowe_600
2019-05-07T23:06:43.588Z,1557270403.588 [DataOverHttps](INFO): Moved sent file to Logs/20190507T224155/Courier0010.lzma.bak
2019-05-07T23:06:43.588Z,1557270403.588 [DataOverHttps](INFO): SBD MOMSN=10960832
2019-05-07T23:06:43.729Z,1557270403.729 [Rowe_600](INFO): Powering down
2019-05-07T23:06:45.417Z,1557270405.417 [Rowe_600](INFO): Data requested. STOPPED ==> START
2019-05-07T23:06:45.479Z,1557270405.479 [CBIT](INFO): Clearing failed state for component Rowe_600
2019-05-07T23:06:45.479Z,1557270405.479 [Rowe_600] No Fault, FailCount= 1
2019-05-07T23:06:45.821Z,1557270405.821 [Rowe_600](INFO): Initializing
2019-05-07T23:06:45.833Z,1557270405.833 [Rowe_600](INFO): Powering up
2019-05-07T23:06:58.000Z,1557270418.000 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T23:06:58.000Z,1557270418.000 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-07T23:07:01.592Z,1557270421.592 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-07T23:07:01.592Z,1557270421.592 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-05-07T23:07:01.592Z,1557270421.592 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-07T23:07:01.720Z,1557270421.720 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-07T23:07:01.729Z,1557270421.729 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-07T23:07:01.991Z,1557270421.991 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-07T23:07:03.724Z,1557270423.724 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20190507T230342/Courier0000.lzma
2019-05-07T23:07:05.403Z,1557270425.403 [DataOverHttps](INFO): Moved sent file to Logs/20190507T230342/Courier0000.lzma.bak
2019-05-07T23:07:05.404Z,1557270425.404 [DataOverHttps](INFO): SBD MOMSN=10960835
2019-05-07T23:07:06.938Z,1557270426.938 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T23:07:06.938Z,1557270426.938 [CTD_Seabird] No Fault, FailCount= 3
2019-05-07T23:07:07.101Z,1557270427.101 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T23:07:07.103Z,1557270427.103 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T23:07:08.453Z,1557270428.453 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T23:07:08.453Z,1557270428.453 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-07T23:07:08.453Z,1557270428.453 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T23:07:08.559Z,1557270428.559 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T23:07:08.977Z,1557270428.977 [Aanderaa_O2](INFO): Powering down
2019-05-07T23:07:09.699Z,1557270429.699 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T23:07:09.699Z,1557270429.699 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-07T23:07:12.574Z,1557270432.574 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-07T23:06:12.3Z
2019-05-07T23:07:12.574Z,1557270432.574 [Startup:StartupSatComms:B] Stopped
2019-05-07T23:07:12.574Z,1557270432.574 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-07T23:07:12.574Z,1557270432.574 [Startup:StartupSatComms] Stopped
2019-05-07T23:07:12.575Z,1557270432.575 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-07T23:07:12.576Z,1557270432.576 [Startup](INFO): Completed Startup
2019-05-07T23:07:12.576Z,1557270432.576 [MissionManager](INFO): Startup is completed.
2019-05-07T23:07:12.581Z,1557270432.581 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-07T23:07:12.581Z,1557270432.581 [Startup] Stopped
2019-05-07T23:07:12.581Z,1557270432.581 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-07T23:07:12.581Z,1557270432.581 [Startup:A.GoToSurface] Stopped
2019-05-07T23:07:12.582Z,1557270432.582 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-07T23:07:12.962Z,1557270432.962 [MissionManager](IMPORTANT): Started mission Default
2019-05-07T23:07:12.962Z,1557270432.962 [Default] Running Loop=1
2019-05-07T23:07:12.962Z,1557270432.962 [Default](DEBUG): Aggregate::initialize Default
2019-05-07T23:07:12.962Z,1557270432.962 [Default:B.GoToSurface] Running Loop=1
2019-05-07T23:07:12.963Z,1557270432.963 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-07T23:07:12.963Z,1557270432.963 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-07T23:07:12.963Z,1557270432.963 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-07T23:07:12.963Z,1557270432.963 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-07T23:07:12.964Z,1557270432.964 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-07T23:07:12.964Z,1557270432.964 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-07T23:07:12.964Z,1557270432.964 [Default:A.Wait] Running Loop=1
2019-05-07T23:07:12.964Z,1557270432.964 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-07T23:07:20.165Z,1557270440.165 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T23:07:20.165Z,1557270440.165 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-07T23:07:20.165Z,1557270440.165 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T23:07:20.170Z,1557270440.170 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T23:07:20.170Z,1557270440.170 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-07T23:07:20.170Z,1557270440.170 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T23:07:20.218Z,1557270440.218 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T23:07:20.219Z,1557270440.219 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T23:07:20.579Z,1557270440.579 [CTD_Seabird](INFO): Powering down
2019-05-07T23:07:20.697Z,1557270440.697 [Aanderaa_O2](INFO): Powering down
2019-05-07T23:07:21.421Z,1557270441.421 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T23:07:21.421Z,1557270441.421 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-07T23:07:21.659Z,1557270441.659 [DataOverHttps](INFO): Sending 293 bytes from file Logs/20190507T224155/Express0011.lzma
2019-05-07T23:07:21.825Z,1557270441.825 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T23:07:21.825Z,1557270441.825 [CTD_Seabird] No Fault, FailCount= 1
2019-05-07T23:07:21.925Z,1557270441.925 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T23:07:21.927Z,1557270441.927 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T23:07:22.462Z,1557270442.462 [DataOverHttps](INFO): Moved sent file to Logs/20190507T224155/Express0011.lzma.bak
2019-05-07T23:07:22.462Z,1557270442.462 [DataOverHttps](INFO): SBD MOMSN=10960840
2019-05-07T23:07:26.259Z,1557270446.259 [Default:A.Wait](INFO): Done Waiting.
2019-05-07T23:07:26.259Z,1557270446.259 [Default:A.Wait] Stopped
2019-05-07T23:07:26.260Z,1557270446.260 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-07T23:07:26.675Z,1557270446.675 [Default:CheckIn] Running Loop=1
2019-05-07T23:07:26.675Z,1557270446.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-07T23:07:26.675Z,1557270446.675 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-07T23:07:27.068Z,1557270447.068 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-07T23:07:31.885Z,1557270451.885 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T23:07:31.885Z,1557270451.885 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-07T23:07:31.885Z,1557270451.885 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T23:07:31.943Z,1557270451.943 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T23:07:31.944Z,1557270451.944 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-07T23:07:32.409Z,1557270452.409 [Aanderaa_O2](INFO): Powering down
2019-05-07T23:07:34.977Z,1557270454.977 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T23:07:34.977Z,1557270454.977 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-07T23:07:34.977Z,1557270454.977 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T23:07:35.154Z,1557270455.154 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T23:07:35.381Z,1557270455.381 [CTD_Seabird](INFO): Powering down
2019-05-07T23:07:36.362Z,1557270456.362 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T23:07:36.362Z,1557270456.362 [CTD_Seabird] No Fault, FailCount= 2
2019-05-07T23:07:36.725Z,1557270456.725 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T23:07:36.727Z,1557270456.727 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T23:07:43.411Z,1557270463.411 [CommandLine](IMPORTANT): got command report touch Rowe_600.pitch
2019-05-07T23:07:49.777Z,1557270469.777 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T23:07:49.777Z,1557270469.777 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-07T23:07:49.777Z,1557270469.777 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T23:07:50.123Z,1557270470.123 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T23:07:50.124Z,1557270470.124 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-07T23:07:50.181Z,1557270470.181 [CTD_Seabird](INFO): Powering down
2019-05-07T23:07:54.958Z,1557270474.958 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T23:07:54.959Z,1557270474.959 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-07T23:07:54.959Z,1557270474.959 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-07T23:07:54.960Z,1557270474.960 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T23:07:54.960Z,1557270474.960 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-05-07T23:07:54.960Z,1557270474.960 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-07T23:07:54.962Z,1557270474.962 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T23:07:54.962Z,1557270474.962 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-05-07T23:07:54.962Z,1557270474.962 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-05-07T23:07:54.972Z,1557270474.972 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-07T23:07:54.972Z,1557270474.972 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-07T23:07:54.973Z,1557270474.973 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-05-07T23:07:55.378Z,1557270475.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-07T23:07:55.378Z,1557270475.378 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-07T23:07:55.379Z,1557270475.379 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-07T23:07:55.379Z,1557270475.379 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-05-07T23:07:55.379Z,1557270475.379 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-05-07T23:07:55.380Z,1557270475.380 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-05-07T23:07:55.756Z,1557270475.756 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-07T23:08:05.959Z,1557270485.959 [CommandLine](IMPORTANT): got command report mod Rowe_600.pitch
2019-05-07T23:08:06.281Z,1557270486.281 [Reporter](INFO): Rowe_600.pitch no_value
2019-05-07T23:08:44.155Z,1557270524.155 [CommandLine](IMPORTANT): got command report mod Rowe_600.component_avgCurrent
2019-05-07T23:08:44.262Z,1557270524.262 [Reporter](INFO): Rowe_600.component_avgCurrent no_value
2019-05-07T23:09:34.705Z,1557270574.705 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-05-07T23:09:35.533Z,1557270575.533 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:09:38.353Z,1557270578.353 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:09:39.199Z,1557270579.199 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T23:09:39.199Z,1557270579.199 [PNI_TCM] No Fault, FailCount= 5
2019-05-07T23:09:41.593Z,1557270581.593 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:09:44.417Z,1557270584.417 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:09:46.853Z,1557270586.853 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:09:46.853Z,1557270586.853 [PNI_TCM] Data Fault, FailCount= 1
2019-05-07T23:09:46.853Z,1557270586.853 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:09:46.881Z,1557270586.881 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:09:47.248Z,1557270587.248 [PNI_TCM](INFO): Powering down
2019-05-07T23:09:47.653Z,1557270587.653 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:09:48.494Z,1557270588.494 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T23:09:48.495Z,1557270588.495 [PNI_TCM] No Fault, FailCount= 1
2019-05-07T23:09:50.473Z,1557270590.473 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:09:53.709Z,1557270593.709 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:09:56.140Z,1557270596.140 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:09:56.140Z,1557270596.140 [PNI_TCM] Data Fault, FailCount= 2
2019-05-07T23:09:56.140Z,1557270596.140 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:09:56.145Z,1557270596.145 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T23:09:56.145Z,1557270596.145 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-05-07T23:09:56.145Z,1557270596.145 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-07T23:09:56.146Z,1557270596.146 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T23:09:56.146Z,1557270596.146 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-05-07T23:09:56.147Z,1557270596.147 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-07T23:09:56.148Z,1557270596.148 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T23:09:56.148Z,1557270596.148 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-05-07T23:09:56.148Z,1557270596.148 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-05-07T23:09:56.158Z,1557270596.158 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-07T23:09:56.159Z,1557270596.159 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-07T23:09:56.159Z,1557270596.159 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-05-07T23:09:56.159Z,1557270596.159 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:09:56.541Z,1557270596.541 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:09:56.556Z,1557270596.556 [PNI_TCM](INFO): Powering down
2019-05-07T23:09:56.670Z,1557270596.670 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-07T23:09:56.670Z,1557270596.670 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-05-07T23:09:56.670Z,1557270596.670 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-07T23:09:56.670Z,1557270596.670 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-05-07T23:09:56.671Z,1557270596.671 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-05-07T23:09:56.671Z,1557270596.671 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-05-07T23:09:56.968Z,1557270596.968 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-07T23:09:57.786Z,1557270597.786 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T23:09:57.786Z,1557270597.786 [PNI_TCM] No Fault, FailCount= 2
2019-05-07T23:09:59.361Z,1557270599.361 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:02.208Z,1557270602.208 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T23:10:02.208Z,1557270602.208 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-07T23:10:02.597Z,1557270602.597 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:05.421Z,1557270605.421 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:05.435Z,1557270605.435 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:10:05.435Z,1557270605.435 [PNI_TCM] Data Fault, FailCount= 3
2019-05-07T23:10:05.435Z,1557270605.435 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:10:05.456Z,1557270605.456 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:10:05.861Z,1557270605.861 [PNI_TCM](INFO): Powering down
2019-05-07T23:10:07.063Z,1557270607.063 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T23:10:07.063Z,1557270607.063 [PNI_TCM] No Fault, FailCount= 3
2019-05-07T23:10:08.653Z,1557270608.653 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:11.493Z,1557270611.493 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:12.689Z,1557270612.689 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T23:10:12.689Z,1557270612.689 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-07T23:10:12.689Z,1557270612.689 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T23:10:12.722Z,1557270612.722 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T23:10:13.213Z,1557270613.213 [Aanderaa_O2](INFO): Powering down
2019-05-07T23:10:13.956Z,1557270613.956 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T23:10:13.956Z,1557270613.956 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-07T23:10:14.737Z,1557270614.737 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:14.751Z,1557270614.751 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:10:14.751Z,1557270614.751 [PNI_TCM] Data Fault, FailCount= 4
2019-05-07T23:10:14.752Z,1557270614.752 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:10:14.777Z,1557270614.777 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:10:15.155Z,1557270615.155 [PNI_TCM](INFO): Powering down
2019-05-07T23:10:16.365Z,1557270616.365 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T23:10:16.365Z,1557270616.365 [PNI_TCM] No Fault, FailCount= 4
2019-05-07T23:10:17.557Z,1557270617.557 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:20.015Z,1557270620.015 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T23:10:20.015Z,1557270620.015 [CTD_Seabird] No Fault, FailCount= 3
2019-05-07T23:10:20.197Z,1557270620.197 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T23:10:20.199Z,1557270620.199 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T23:10:20.385Z,1557270620.385 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:23.621Z,1557270623.621 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:24.039Z,1557270624.039 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T23:10:24.039Z,1557270624.039 [PNI_TCM] Data Fault, FailCount= 5
2019-05-07T23:10:24.039Z,1557270624.039 [PNI_TCM](ERROR): Data Fault
2019-05-07T23:10:24.054Z,1557270624.054 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T23:10:24.054Z,1557270624.054 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-05-07T23:10:24.405Z,1557270624.405 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T23:10:24.405Z,1557270624.405 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-07T23:10:24.405Z,1557270624.405 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T23:10:24.439Z,1557270624.439 [PNI_TCM](INFO): Powering down
2019-05-07T23:10:24.549Z,1557270624.549 [CBIT](INFO): Critical error at 20190507T231024
2019-05-07T23:10:24.552Z,1557270624.552 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T23:10:24.933Z,1557270624.933 [Aanderaa_O2](INFO): Powering down
2019-05-07T23:10:25.661Z,1557270625.661 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T23:10:25.661Z,1557270625.661 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-07T23:10:26.461Z,1557270626.461 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:29.681Z,1557270629.681 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:32.509Z,1557270632.509 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:33.249Z,1557270633.249 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T23:10:33.249Z,1557270633.249 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-07T23:10:33.249Z,1557270633.249 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T23:10:33.369Z,1557270633.369 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T23:10:33.653Z,1557270633.653 [CTD_Seabird](INFO): Powering down
2019-05-07T23:10:34.966Z,1557270634.966 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T23:10:34.967Z,1557270634.967 [CTD_Seabird] No Fault, FailCount= 1
2019-05-07T23:10:34.994Z,1557270634.994 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T23:10:34.995Z,1557270634.995 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T23:10:35.733Z,1557270635.733 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:36.121Z,1557270636.121 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T23:10:36.121Z,1557270636.121 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-07T23:10:36.121Z,1557270636.121 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T23:10:36.193Z,1557270636.193 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T23:10:36.193Z,1557270636.193 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-07T23:10:36.645Z,1557270636.645 [Aanderaa_O2](INFO): Powering down
2019-05-07T23:10:38.569Z,1557270638.569 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:41.801Z,1557270641.801 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:44.629Z,1557270644.629 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:47.449Z,1557270647.449 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:48.045Z,1557270648.045 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T23:10:48.045Z,1557270648.045 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-07T23:10:48.045Z,1557270648.045 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T23:10:48.345Z,1557270648.345 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T23:10:48.449Z,1557270648.449 [CTD_Seabird](INFO): Powering down
2019-05-07T23:10:49.519Z,1557270649.519 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T23:10:49.519Z,1557270649.519 [CTD_Seabird] No Fault, FailCount= 2
2019-05-07T23:10:49.793Z,1557270649.793 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T23:10:49.795Z,1557270649.795 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T23:10:50.693Z,1557270650.693 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:53.509Z,1557270653.509 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:56.749Z,1557270656.749 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:10:59.569Z,1557270659.569 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:02.809Z,1557270662.809 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:02.845Z,1557270662.845 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T23:11:02.845Z,1557270662.845 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-07T23:11:02.845Z,1557270662.845 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T23:11:03.243Z,1557270663.243 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T23:11:03.243Z,1557270663.243 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-07T23:11:03.249Z,1557270663.249 [CTD_Seabird](INFO): Powering down
2019-05-07T23:11:05.629Z,1557270665.629 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:08.457Z,1557270668.457 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:11.689Z,1557270671.689 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:14.517Z,1557270674.517 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:17.749Z,1557270677.749 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:20.581Z,1557270680.581 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:23.817Z,1557270683.817 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:26.645Z,1557270686.645 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:29.869Z,1557270689.869 [NAL9602](DEBUG): Fix Requested
2019-05-07T23:11:32.697Z,1557270692.697 [NAL9602](DEBUG): Fix Requested
20