2019-02-21T23:01:19.489Z,1550790079.489 [Supervisor](DEBUG): Initializing supervisor.
2019-02-21T23:01:19.509Z,1550790079.509 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-21T23:01:19.509Z,1550790079.509 [SyncHandler](INFO): Protected caller Thread ID is 7861
2019-02-21T23:01:19.510Z,1550790079.510 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-21T23:01:19.549Z,1550790079.549 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-21T23:01:19.549Z,1550790079.549 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7862
2019-02-21T23:01:19.552Z,1550790079.552 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-21T23:01:19.594Z,1550790079.594 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-21T23:01:19.613Z,1550790079.613 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-21T23:01:19.614Z,1550790079.614 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7863
2019-02-21T23:01:19.614Z,1550790079.614 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-21T23:01:19.641Z,1550790079.641 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-21T23:01:19.641Z,1550790079.641 [logger ThreadHandler](INFO): Protected caller Thread ID is 7864
2019-02-21T23:01:19.643Z,1550790079.643 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-21T23:01:19.644Z,1550790079.644 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-21T23:01:19.648Z,1550790079.648 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-21T23:01:20.099Z,1550790080.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-21T23:01:20.100Z,1550790080.100 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-21T23:01:20.270Z,1550790080.270 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-21T23:01:21.337Z,1550790081.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-21T23:01:21.337Z,1550790081.337 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-21T23:01:22.098Z,1550790082.098 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-21T23:01:22.099Z,1550790082.099 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-21T23:01:22.310Z,1550790082.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-21T23:01:22.310Z,1550790082.310 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-21T23:01:23.122Z,1550790083.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-21T23:01:23.123Z,1550790083.123 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-21T23:01:23.846Z,1550790083.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-21T23:01:23.847Z,1550790083.847 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-21T23:01:25.310Z,1550790085.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-21T23:01:25.311Z,1550790085.311 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-21T23:01:25.518Z,1550790085.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-21T23:01:25.519Z,1550790085.519 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-21T23:01:25.743Z,1550790085.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-21T23:01:25.744Z,1550790085.744 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-21T23:01:27.043Z,1550790087.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-21T23:01:27.043Z,1550790087.043 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-21T23:01:28.347Z,1550790088.347 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-21T23:01:28.348Z,1550790088.348 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-21T23:01:29.106Z,1550790089.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-21T23:01:29.107Z,1550790089.107 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-21T23:01:29.580Z,1550790089.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-21T23:01:29.581Z,1550790089.581 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-21T23:01:29.895Z,1550790089.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-21T23:01:29.897Z,1550790089.897 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2019-02-21T23:01:29.901Z,1550790089.901 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2019-02-21T23:01:30.093Z,1550790090.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2019-02-21T23:01:30.270Z,1550790090.270 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2019-02-21T23:01:30.558Z,1550790090.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2019-02-21T23:01:30.785Z,1550790090.785 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2019-02-21T23:01:31.070Z,1550790091.070 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2019-02-21T23:01:31.247Z,1550790091.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2019-02-21T23:01:31.592Z,1550790091.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2019-02-21T23:01:31.811Z,1550790091.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2019-02-21T23:01:32.148Z,1550790092.148 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2019-02-21T23:01:32.604Z,1550790092.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2019-02-21T23:01:33.048Z,1550790093.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2019-02-21T23:01:33.866Z,1550790093.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-21T23:01:33.866Z,1550790093.866 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-21T23:01:34.250Z,1550790094.250 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-21T23:01:34.409Z,1550790094.409 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-21T23:01:34.722Z,1550790094.722 [VerticalControl] Loaded
2019-02-21T23:01:34.722Z,1550790094.722 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-21T23:01:34.723Z,1550790094.723 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-21T23:01:34.916Z,1550790094.916 [HorizontalControl] Loaded
2019-02-21T23:01:34.916Z,1550790094.916 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-21T23:01:34.917Z,1550790094.917 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-21T23:01:34.922Z,1550790094.922 [SpeedControl] Loaded
2019-02-21T23:01:34.922Z,1550790094.922 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-21T23:01:34.923Z,1550790094.923 [LoopControl](DEBUG): Construct LoopControl.
2019-02-21T23:01:34.924Z,1550790094.924 [LoopControl] Loaded
2019-02-21T23:01:34.925Z,1550790094.925 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-21T23:01:34.925Z,1550790094.925 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-21T23:01:34.926Z,1550790094.926 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-21T23:01:34.983Z,1550790094.983 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-21T23:01:34.983Z,1550790094.983 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-21T23:01:35.244Z,1550790095.244 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-21T23:01:35.244Z,1550790095.244 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-21T23:01:35.598Z,1550790095.598 [BuoyancyServo] Loaded
2019-02-21T23:01:35.598Z,1550790095.598 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-21T23:01:35.617Z,1550790095.617 [ElevatorServo] Loaded
2019-02-21T23:01:35.617Z,1550790095.617 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-21T23:01:35.628Z,1550790095.628 [MassServo] Loaded
2019-02-21T23:01:35.628Z,1550790095.628 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-21T23:01:35.642Z,1550790095.642 [RudderServo] Loaded
2019-02-21T23:01:35.642Z,1550790095.642 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-21T23:01:35.653Z,1550790095.653 [ThrusterServo] Loaded
2019-02-21T23:01:35.653Z,1550790095.653 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-21T23:01:35.654Z,1550790095.654 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-21T23:01:35.654Z,1550790095.654 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-21T23:01:35.962Z,1550790095.962 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-21T23:01:35.962Z,1550790095.962 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-21T23:01:36.130Z,1550790096.130 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-21T23:01:36.130Z,1550790096.130 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-21T23:01:36.256Z,1550790096.256 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-21T23:01:36.256Z,1550790096.256 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-21T23:01:36.272Z,1550790096.272 [NavChart] Loaded
2019-02-21T23:01:36.273Z,1550790096.273 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-21T23:01:36.301Z,1550790096.301 [UniversalFixResidualReporter] Loaded
2019-02-21T23:01:36.301Z,1550790096.301 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-21T23:01:36.302Z,1550790096.302 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-21T23:01:36.302Z,1550790096.302 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-21T23:01:36.476Z,1550790096.476 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-21T23:01:36.477Z,1550790096.477 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-21T23:01:37.701Z,1550790097.701 [DataOverHttps] Loaded
2019-02-21T23:01:37.701Z,1550790097.701 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-21T23:01:37.718Z,1550790097.718 [Depth_Keller] Loaded
2019-02-21T23:01:37.718Z,1550790097.718 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-21T23:01:37.723Z,1550790097.723 [DropWeight] Loaded
2019-02-21T23:01:37.723Z,1550790097.723 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-21T23:01:38.052Z,1550790098.052 [NAL9602] Loaded
2019-02-21T23:01:38.052Z,1550790098.052 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-21T23:01:38.104Z,1550790098.104 [Onboard] Loaded
2019-02-21T23:01:38.104Z,1550790098.104 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-21T23:01:38.117Z,1550790098.117 [Radio_Surface] Loaded
2019-02-21T23:01:38.117Z,1550790098.117 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-21T23:01:38.139Z,1550790098.139 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408624E0
2019-02-21T23:01:38.139Z,1550790098.139 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8050
2019-02-21T23:01:38.292Z,1550790098.292 [PNI_TCM] Loaded
2019-02-21T23:01:38.293Z,1550790098.293 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-21T23:01:38.476Z,1550790098.476 [Rowe_600LCM] Loaded
2019-02-21T23:01:38.476Z,1550790098.476 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-02-21T23:01:38.486Z,1550790098.486 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2019-02-21T23:01:38.486Z,1550790098.486 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 8051
2019-02-21T23:01:38.487Z,1550790098.487 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-21T23:01:38.487Z,1550790098.487 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-21T23:01:38.626Z,1550790098.626 [DepthRateCalculator] Loaded
2019-02-21T23:01:38.626Z,1550790098.626 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-21T23:01:38.631Z,1550790098.631 [PitchRateCalculator] Loaded
2019-02-21T23:01:38.632Z,1550790098.632 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-21T23:01:38.676Z,1550790098.676 [SpeedCalculator] Loaded
2019-02-21T23:01:38.676Z,1550790098.676 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-21T23:01:38.764Z,1550790098.764 [TempGradientCalculator] Loaded
2019-02-21T23:01:38.765Z,1550790098.765 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-21T23:01:38.770Z,1550790098.770 [YawRateCalculator] Loaded
2019-02-21T23:01:38.770Z,1550790098.770 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-21T23:01:38.862Z,1550790098.862 [ElevatorOffsetCalculator] Loaded
2019-02-21T23:01:38.862Z,1550790098.862 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-21T23:01:38.862Z,1550790098.862 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-21T23:01:38.863Z,1550790098.863 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-21T23:01:39.317Z,1550790099.317 [Aanderaa_O2] Loaded
2019-02-21T23:01:39.317Z,1550790099.317 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-21T23:01:39.569Z,1550790099.569 [CTD_NeilBrown] Loaded
2019-02-21T23:01:39.569Z,1550790099.569 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-21T23:01:39.593Z,1550790099.593 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0
2019-02-21T23:01:39.593Z,1550790099.593 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8060
2019-02-21T23:01:39.622Z,1550790099.622 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-21T23:01:39.627Z,1550790099.627 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-21T23:01:39.643Z,1550790099.643 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-21T23:01:39.648Z,1550790099.648 [CTD_Seabird](INFO): created writer for : depth
2019-02-21T23:01:39.649Z,1550790099.649 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-21T23:01:39.660Z,1550790099.660 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-21T23:01:39.661Z,1550790099.661 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-21T23:01:39.666Z,1550790099.666 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-21T23:01:39.666Z,1550790099.666 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-21T23:01:39.691Z,1550790099.691 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-21T23:01:39.692Z,1550790099.692 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-21T23:01:39.710Z,1550790099.710 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-21T23:01:39.710Z,1550790099.710 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-21T23:01:39.715Z,1550790099.715 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-21T23:01:39.780Z,1550790099.780 [CTD_Seabird] Loaded
2019-02-21T23:01:39.780Z,1550790099.780 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-21T23:01:39.785Z,1550790099.785 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4098C4E0
2019-02-21T23:01:39.785Z,1550790099.785 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8061
2019-02-21T23:01:39.800Z,1550790099.800 [PAR_Licor] Loaded
2019-02-21T23:01:39.800Z,1550790099.800 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-21T23:01:39.897Z,1550790099.897 [WetLabsBB2FL] Loaded
2019-02-21T23:01:39.898Z,1550790099.898 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-21T23:01:39.909Z,1550790099.909 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409BC4E0
2019-02-21T23:01:39.909Z,1550790099.909 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8064
2019-02-21T23:01:39.910Z,1550790099.910 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-21T23:01:39.910Z,1550790099.910 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-21T23:01:39.988Z,1550790099.988 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-21T23:01:39.988Z,1550790099.988 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-21T23:01:40.652Z,1550790100.652 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-21T23:01:40.653Z,1550790100.653 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-21T23:01:41.054Z,1550790101.054 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-21T23:01:41.065Z,1550790101.065 [SBIT] Loaded
2019-02-21T23:01:41.065Z,1550790101.065 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-21T23:01:41.066Z,1550790101.066 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-21T23:01:41.103Z,1550790101.103 [IBIT] Loaded
2019-02-21T23:01:41.103Z,1550790101.103 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-21T23:01:41.106Z,1550790101.106 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-21T23:01:42.313Z,1550790102.313 [CBIT] Loaded
2019-02-21T23:01:42.314Z,1550790102.314 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-21T23:01:42.314Z,1550790102.314 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-21T23:01:42.318Z,1550790102.318 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-21T23:01:42.319Z,1550790102.319 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-21T23:01:42.342Z,1550790102.342 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-21T23:01:42.352Z,1550790102.352 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA64E0
2019-02-21T23:01:42.352Z,1550790102.352 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8079
2019-02-21T23:01:42.377Z,1550790102.377 [Supervisor](INFO): Main Thread ID is 7854
2019-02-21T23:01:42.377Z,1550790102.377 [Supervisor](DEBUG): Running supervisor.
2019-02-21T23:01:42.378Z,1550790102.378 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8080
2019-02-21T23:01:42.393Z,1550790102.393 [controlThread ThreadHandler](INFO): Handler Thread ID is 8081
2019-02-21T23:01:42.394Z,1550790102.394 [controlThread](DEBUG): Initializing ControlThread
2019-02-21T23:01:42.395Z,1550790102.395 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-21T23:01:42.396Z,1550790102.396 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-21T23:01:42.397Z,1550790102.397 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-21T23:01:42.397Z,1550790102.397 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-21T23:01:42.399Z,1550790102.399 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:01:42.400Z,1550790102.400 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-21T23:01:42.400Z,1550790102.400 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-21T23:01:42.407Z,1550790102.407 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-21T23:01:42.407Z,1550790102.407 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-21T23:01:42.408Z,1550790102.408 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-21T23:01:42.408Z,1550790102.408 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-21T23:01:42.409Z,1550790102.409 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-21T23:01:42.409Z,1550790102.409 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-21T23:01:42.413Z,1550790102.413 [SBIT](INFO): Initialize SBIT Component.
2019-02-21T23:01:42.413Z,1550790102.413 [SBIT](IMPORTANT): git: 2019-01-14A-50-g9cfb34a
2019-02-21T23:01:42.414Z,1550790102.414 [SBIT](INFO): git hash: 9cfb34a5380b67c155f39980646156fa9a5482a4
2019-02-21T23:01:42.414Z,1550790102.414 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-21T23:01:42.415Z,1550790102.415 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-02-21T23:01:42.416Z,1550790102.416 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2019-02-21T23:01:42.417Z,1550790102.417 [IBIT](INFO): Initialize IBIT Component.
2019-02-21T23:01:42.418Z,1550790102.418 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-21T23:01:42.429Z,1550790102.429 [CBIT](DEBUG): Initialized mux pins.
2019-02-21T23:01:42.429Z,1550790102.429 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-21T23:01:42.430Z,1550790102.430 [logger ThreadHandler](INFO): Handler Thread ID is 8082
2019-02-21T23:01:42.457Z,1550790102.457 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-21T23:01:42.457Z,1550790102.457 [CBIT](DEBUG): Initializing heartbeat.
2019-02-21T23:01:42.457Z,1550790102.457 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8083
2019-02-21T23:01:42.463Z,1550790102.463 [Radio_Surface](INFO): Powering up
2019-02-21T23:01:42.477Z,1550790102.477 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 8084
2019-02-21T23:01:42.478Z,1550790102.478 [Rowe_600LCM](INFO): Initializing
2019-02-21T23:01:42.487Z,1550790102.487 [Rowe_600LCM](INFO): Powering up
2019-02-21T23:01:42.510Z,1550790102.510 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8085
2019-02-21T23:01:42.511Z,1550790102.511 [CTD_NeilBrown](INFO): Powering down
2019-02-21T23:01:42.529Z,1550790102.529 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-21T23:01:42.529Z,1550790102.529 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-21T23:01:42.549Z,1550790102.549 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8086
2019-02-21T23:01:42.550Z,1550790102.550 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-21T23:01:42.553Z,1550790102.553 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-21T23:01:42.571Z,1550790102.571 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-21T23:01:42.597Z,1550790102.597 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8088
2019-02-21T23:01:42.598Z,1550790102.598 [WetLabsBB2FL](INFO): Powering down
2019-02-21T23:01:42.637Z,1550790102.637 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8089
2019-02-21T23:01:42.729Z,1550790102.729 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T23:01:42.757Z,1550790102.757 [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-02-21T23:01:42.757Z,1550790102.757 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-21T23:01:42.879Z,1550790102.879 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-21T23:01:42.879Z,1550790102.879 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-21T23:01:42.879Z,1550790102.879 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-21T23:01:42.879Z,1550790102.879 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-21T23:01:42.879Z,1550790102.879 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-21T23:01:42.880Z,1550790102.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-21T23:01:42.880Z,1550790102.880 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-21T23:01:42.880Z,1550790102.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-21T23:01:42.880Z,1550790102.880 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-21T23:01:42.880Z,1550790102.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-21T23:01:42.881Z,1550790102.881 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-21T23:01:42.881Z,1550790102.881 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-21T23:01:42.881Z,1550790102.881 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-21T23:01:42.881Z,1550790102.881 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-21T23:01:42.882Z,1550790102.882 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-21T23:01:42.882Z,1550790102.882 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-21T23:01:43.002Z,1550790103.002 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-21T23:01:43.004Z,1550790103.004 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-21T23:01:43.014Z,1550790103.014 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-21T23:01:43.050Z,1550790103.050 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-21T23:01:43.115Z,1550790103.115 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-21T23:01:43.167Z,1550790103.167 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-21T23:01:43.221Z,1550790103.221 [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-02-21T23:01:43.249Z,1550790103.249 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-21T23:01:43.298Z,1550790103.298 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-21T23:01:43.353Z,1550790103.353 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2019-02-21T23:01:43.609Z,1550790103.609 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-21T23:01:44.118Z,1550790104.118 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:01:44.137Z,1550790104.137 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-21T23:01:44.156Z,1550790104.156 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:01:44.193Z,1550790104.193 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-21T23:01:44.218Z,1550790104.218 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:01:44.233Z,1550790104.233 [MassServo](DEBUG): Initializing MassServo.
2019-02-21T23:01:44.259Z,1550790104.259 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:01:44.269Z,1550790104.269 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-21T23:01:44.299Z,1550790104.299 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:01:44.313Z,1550790104.313 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-21T23:01:44.695Z,1550790104.695 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-02-21T23:01:44.695Z,1550790104.695 [DropWeight] Hardware Fault, FailCount= 1
2019-02-21T23:01:44.695Z,1550790104.695 [DropWeight](ERROR): Hardware Fault
2019-02-21T23:01:45.257Z,1550790105.257 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-02-21T23:01:45.257Z,1550790105.257 [RudderServo](FAULT): Rudder failed to initialize
2019-02-21T23:01:45.257Z,1550790105.257 [RudderServo] Communications Fault, FailCount= 1
2019-02-21T23:01:45.257Z,1550790105.257 [RudderServo](ERROR): Communications Fault
2019-02-21T23:01:45.382Z,1550790105.382 [CommandLine](FAULT): Scheduling is paused
2019-02-21T23:01:45.383Z,1550790105.383 [CBIT](INFO): Critical error at 20190221T230144
2019-02-21T23:01:45.383Z,1550790105.383 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-21T23:01:45.386Z,1550790105.386 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-02-21T23:01:45.386Z,1550790105.386 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-02-21T23:01:45.386Z,1550790105.386 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-02-21T23:01:45.514Z,1550790105.514 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-21T23:01:45.514Z,1550790105.514 [RudderServo](INFO): Powering down
2019-02-21T23:01:45.640Z,1550790105.640 [CBIT](INFO): Critical error at 20190221T230145
2019-02-21T23:01:46.347Z,1550790106.347 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-21T23:01:46.466Z,1550790106.466 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-21T23:01:46.471Z,1550790106.471 [CBIT](INFO): Clearing failed state for component RudderServo
2019-02-21T23:01:46.471Z,1550790106.471 [RudderServo] No Fault, FailCount= 1
2019-02-21T23:01:47.435Z,1550790107.435 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-21T23:01:47.655Z,1550790107.655 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-21T23:01:52.500Z,1550790112.500 [CBIT](CRITICAL): Environmental Failure. Press:14.499833 PSI. Humidity:19%. Temp:24 C. ABORTING MISSION
2019-02-21T23:01:52.948Z,1550790112.948 [CBIT](INFO): Critical error at 20190221T230152
2019-02-21T23:01:53.129Z,1550790113.129 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2019-02-21T23:01:53.129Z,1550790113.129 [PNI_TCM] Data Fault, FailCount= 1
2019-02-21T23:01:53.129Z,1550790113.129 [PNI_TCM](ERROR): Data Fault
2019-02-21T23:01:53.349Z,1550790113.349 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-21T23:01:53.510Z,1550790113.510 [PNI_TCM](INFO): Powering down
2019-02-21T23:01:53.911Z,1550790113.911 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-21T23:01:53.911Z,1550790113.911 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-21T23:01:53.911Z,1550790113.911 [Aanderaa_O2](ERROR): Communications Fault
2019-02-21T23:01:54.104Z,1550790114.104 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-21T23:01:54.315Z,1550790114.315 [Aanderaa_O2](INFO): Powering down
2019-02-21T23:01:54.801Z,1550790114.801 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-21T23:01:54.801Z,1550790114.801 [PNI_TCM] No Fault, FailCount= 1
2019-02-21T23:01:55.237Z,1550790115.237 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-21T23:01:55.237Z,1550790115.237 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-21T23:01:56.597Z,1550790116.597 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-21T23:01:56.597Z,1550790116.597 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-21T23:01:56.597Z,1550790116.597 [CTD_Seabird](ERROR): Communications Fault
2019-02-21T23:01:56.814Z,1550790116.814 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-21T23:01:57.001Z,1550790117.001 [CTD_Seabird](INFO): Powering down
2019-02-21T23:01:58.041Z,1550790118.041 [PAR_Licor](INFO): A/D timeout, 6 tries over 122 ms

2019-02-21T23:01:58.041Z,1550790118.041 [PAR_Licor] Data Fault, FailCount= 1
2019-02-21T23:01:58.041Z,1550790118.041 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:01:58.116Z,1550790118.116 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:01:58.116Z,1550790118.116 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:01:58.116Z,1550790118.116 [PAR_Licor] No Fault, FailCount= 1
2019-02-21T23:01:58.837Z,1550790118.837 [PAR_Licor](INFO): A/D timeout, 10 tries over 123 ms

2019-02-21T23:01:58.837Z,1550790118.837 [PAR_Licor] Data Fault, FailCount= 2
2019-02-21T23:01:58.837Z,1550790118.837 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:01:58.887Z,1550790118.887 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-21T23:01:58.887Z,1550790118.887 [CTD_Seabird] No Fault, FailCount= 1
2019-02-21T23:01:58.888Z,1550790118.888 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:01:58.888Z,1550790118.888 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-02-21T23:01:58.909Z,1550790118.909 [PAR_Licor] No Fault, FailCount= 2
2019-02-21T23:01:59.653Z,1550790119.653 [PAR_Licor](INFO): A/D timeout, 5 tries over 132 ms

2019-02-21T23:01:59.653Z,1550790119.653 [PAR_Licor] Data Fault, FailCount= 3
2019-02-21T23:01:59.653Z,1550790119.653 [PAR_Licor](ERROR): Data Fault
2019-02-21T23:01:59.749Z,1550790119.749 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-02-21T23:01:59.750Z,1550790119.750 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-02-21T23:01:59.849Z,1550790119.849 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-21T23:01:59.850Z,1550790119.850 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-21T23:01:59.960Z,1550790119.960 [CBIT](INFO): Critical error at 20190221T230159
2019-02-21T23:02:02.313Z,1550790122.313 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-21T23:02:02.313Z,1550790122.313 [PNI_TCM] Data Fault, FailCount= 2
2019-02-21T23:02:02.313Z,1550790122.313 [PNI_TCM](ERROR): Data Fault
2019-02-21T23:02:02.532Z,1550790122.532 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-21T23:02:02.710Z,1550790122.710 [PNI_TCM](INFO): Powering down
2019-02-21T23:02:03.962Z,1550790123.962 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-21T23:02:03.962Z,1550790123.962 [PNI_TCM] No Fault, FailCount= 2
2019-02-21T23:02:05.910Z,1550790125.910 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-21T23:02:05.910Z,1550790125.910 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-21T23:02:05.910Z,1550790125.910 [Aanderaa_O2](ERROR): Communications Fault
2019-02-21T23:02:05.962Z,1550790125.962 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-21T23:02:05.990Z,1550790125.990 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-21T23:02:05.991Z,1550790125.991 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-21T23:02:06.326Z,1550790126.326 [Aanderaa_O2](INFO): Powering down
2019-02-21T23:02:07.251Z,1550790127.251 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-21T23:02:07.251Z,1550790127.251 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-21T23:02:08.558Z,1550790128.558 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:02:11.395Z,1550790131.395 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-21T23:02:11.395Z,1550790131.395 [PNI_TCM] Data Fault, FailCount= 3
2019-02-21T23:02:11.395Z,1550790131.395 [PNI_TCM](ERROR): Data Fault
2019-02-21T23:02:11.510Z,1550790131.510 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-21T23:02:11.740Z,1550790131.740 [PNI_TCM](INFO): Powering down
2019-02-21T23:02:12.994Z,1550790132.994 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-21T23:02:12.994Z,1550790132.994 [PNI_TCM] No Fault, FailCount= 3
2019-02-21T23:02:13.893Z,1550790133.893 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-21T23:02:13.893Z,1550790133.893 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-21T23:02:13.893Z,1550790133.893 [CTD_Seabird](ERROR): Communications Fault
2019-02-21T23:02:14.231Z,1550790134.231 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-21T23:02:14.297Z,1550790134.297 [CTD_Seabird](INFO): Powering down
2019-02-21T23:02:16.163Z,1550790136.163 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-21T23:02:16.163Z,1550790136.163 [CTD_Seabird] No Fault, FailCount= 2
2019-02-21T23:02:17.011Z,1550790137.011 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001688
CHAN A1 (24V): -0.005961
CHAN A2 (12V): -0.001797
CHAN A3 (5V): -0.000847
CHAN B0 (3.3V): 0.000016
CHAN B1 (3.15aV): -0.000978
CHAN B2 (3.15bV): 0.000220
CHAN B3 (GND): -0.000350
OPEN: -0.000323
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-21T23:02:17.125Z,1550790137.125 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-21T23:02:17.126Z,1550790137.126 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-21T23:02:17.730Z,1550790137.730 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-21T23:02:17.730Z,1550790137.730 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-21T23:02:17.730Z,1550790137.730 [Aanderaa_O2](ERROR): Communications Fault
2019-02-21T23:02:17.751Z,1550790137.751 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-21T23:02:17.751Z,1550790137.751 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-21T23:02:18.131Z,1550790138.131 [Aanderaa_O2](INFO): Powering down
2019-02-21T23:02:20.531Z,1550790140.531 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-21T23:02:20.531Z,1550790140.531 [PNI_TCM] Data Fault, FailCount= 4
2019-02-21T23:02:20.531Z,1550790140.531 [PNI_TCM](ERROR): Data Fault
2019-02-21T23:02:20.590Z,1550790140.590 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-21T23:02:20.930Z,1550790140.930 [PNI_TCM](INFO): Powering down
2019-02-21T23:02:22.046Z,1550790142.046 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-02-21T23:02:22.046Z,1550790142.046 [PNI_TCM] No Fault, FailCount= 4
2019-02-21T23:02:29.487Z,1550790149.487 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-21T23:02:29.487Z,1550790149.487 [NAL9602] Communications Fault, FailCount= 1
2019-02-21T23:02:29.487Z,1550790149.487 [NAL9602](ERROR): Communications Fault
2019-02-21T23:02:29.505Z,1550790149.505 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-02-21T23:02:29.505Z,1550790149.505 [PNI_TCM] Data Fault, FailCount= 5
2019-02-21T23:02:29.505Z,1550790149.505 [PNI_TCM](ERROR): Data Fault
2019-02-21T23:02:29.653Z,1550790149.653 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-21T23:02:29.653Z,1550790149.653 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-02-21T23:02:29.654Z,1550790149.654 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-02-21T23:02:29.886Z,1550790149.886 [NAL9602](INFO): Powering down
2019-02-21T23:02:29.903Z,1550790149.903 [PNI_TCM](INFO): Powering down
2019-02-21T23:02:30.001Z,1550790150.001 [CBIT](INFO): Critical error at 20190221T230229
2019-02-21T23:02:30.759Z,1550790150.759 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-21T23:02:30.759Z,1550790150.759 [NAL9602] No Fault, FailCount= 1
2019-02-21T23:02:31.185Z,1550790151.185 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-21T23:02:31.185Z,1550790151.185 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-21T23:02:31.185Z,1550790151.185 [CTD_Seabird](ERROR): Communications Fault
2019-02-21T23:02:31.589Z,1550790151.589 [CTD_Seabird](INFO): Powering down
2019-02-21T23:02:31.609Z,1550790151.609 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-21T23:02:31.610Z,1550790151.610 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-21T23:02:59.702Z,1550790179.702 [SBIT](IMPORTANT): SBIT PASSED
2019-02-21T23:02:59.793Z,1550790179.793 [CommandLine](IMPORTANT): got command configSet list
2019-02-21T23:02:59.793Z,1550790179.793 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-21T23:02:59.794Z,1550790179.794 [CommandLine](IMPORTANT): No configSet variables persisted
2019-02-21T23:03:00.023Z,1550790180.023 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:03:00.047Z,1550790180.047 [MissionManager](IMPORTANT): Started mission Startup
2019-02-21T23:03:00.048Z,1550790180.048 [Startup] Running Loop=1
2019-02-21T23:03:00.048Z,1550790180.048 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-21T23:03:00.048Z,1550790180.048 [Startup:A.GoToSurface] Running Loop=1
2019-02-21T23:03:00.048Z,1550790180.048 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-21T23:03:00.050Z,1550790180.050 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-21T23:03:00.050Z,1550790180.050 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-21T23:03:00.050Z,1550790180.050 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-21T23:03:00.051Z,1550790180.051 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-21T23:03:00.051Z,1550790180.051 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-21T23:03:00.087Z,1550790180.087 [Startup:StartupSatComms] Running Loop=1
2019-02-21T23:03:00.087Z,1550790180.087 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-21T23:03:00.087Z,1550790180.087 [Startup:StartupSatComms:A] Running Loop=1
2019-02-21T23:03:00.465Z,1550790180.465 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-21T23:03:21.222Z,1550790201.222 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-21T23:03:21.222Z,1550790201.222 [NAL9602] Communications Fault, FailCount= 2
2019-02-21T23:03:21.222Z,1550790201.222 [NAL9602](ERROR): Communications Fault
2019-02-21T23:03:21.342Z,1550790201.342 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-21T23:03:21.622Z,1550790201.622 [NAL9602](INFO): Powering down
2019-02-21T23:03:22.522Z,1550790202.522 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-21T23:03:22.522Z,1550790202.522 [NAL9602] No Fault, FailCount= 2
2019-02-21T23:03:42.445Z,1550790222.445 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-21T23:03:42.445Z,1550790222.445 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-21T23:03:42.445Z,1550790222.445 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:03:42.447Z,1550790222.447 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-21T23:03:42.447Z,1550790222.447 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-21T23:03:42.447Z,1550790222.447 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:03:42.465Z,1550790222.465 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-21T23:03:42.473Z,1550790222.473 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-21T23:03:42.473Z,1550790222.473 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:03:42.527Z,1550790222.527 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:03:42.527Z,1550790222.527 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:03:42.528Z,1550790222.528 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:03:42.808Z,1550790222.808 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-21T23:03:42.809Z,1550790222.809 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-02-21T23:03:42.809Z,1550790222.809 [Rowe_600LCM](ERROR): Communications Fault
2019-02-21T23:03:42.961Z,1550790222.961 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:03:42.961Z,1550790222.961 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-21T23:03:42.961Z,1550790222.961 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:03:42.961Z,1550790222.961 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-21T23:03:42.962Z,1550790222.962 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:03:42.962Z,1550790222.962 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-21T23:03:42.963Z,1550790222.963 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-21T23:03:43.222Z,1550790223.222 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-21T23:03:43.285Z,1550790223.285 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:03:43.753Z,1550790223.753 [Rowe_600LCM](INFO): Powering down
2019-02-21T23:03:59.038Z,1550790239.038 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:03:59.134Z,1550790239.134 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-21T23:03:59.134Z,1550790239.134 [Rowe_600LCM] No Fault, FailCount= 1
2019-02-21T23:03:59.509Z,1550790239.509 [Rowe_600LCM](INFO): Initializing
2019-02-21T23:03:59.509Z,1550790239.509 [Rowe_600LCM](INFO): Powering up
2019-02-21T23:03:59.949Z,1550790239.949 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004462
2019-02-21T23:04:00.391Z,1550790240.391 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-21T23:03:00.1Z
2019-02-21T23:04:00.391Z,1550790240.391 [Startup:StartupSatComms:A] Stopped
2019-02-21T23:04:00.392Z,1550790240.392 [Startup:StartupSatComms:B] Running Loop=1
2019-02-21T23:04:00.815Z,1550790240.815 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-21T23:04:03.790Z,1550790243.790 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-21T23:04:03.971Z,1550790243.971 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-21T23:04:17.750Z,1550790257.750 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190220T233757/Courier0388.lzma
2019-02-21T23:04:18.498Z,1550790258.498 [DataOverHttps](INFO): Moved sent file to Logs/20190220T233757/Courier0388.lzma.bak
2019-02-21T23:04:18.498Z,1550790258.498 [DataOverHttps](INFO): SBD MOMSN=10067334
2019-02-21T23:04:27.962Z,1550790267.962 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-21T23:04:27.962Z,1550790267.962 [NAL9602] Communications Fault, FailCount= 3
2019-02-21T23:04:27.962Z,1550790267.962 [NAL9602](ERROR): Communications Fault
2019-02-21T23:04:28.004Z,1550790268.004 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-21T23:04:28.362Z,1550790268.362 [NAL9602](INFO): Powering down
2019-02-21T23:04:29.202Z,1550790269.202 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-21T23:04:29.202Z,1550790269.202 [NAL9602] No Fault, FailCount= 3
2019-02-21T23:04:35.861Z,1550790275.861 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20190221T230119/Courier0000.lzma
2019-02-21T23:04:36.653Z,1550790276.653 [DataOverHttps](INFO): Moved sent file to Logs/20190221T230119/Courier0000.lzma.bak
2019-02-21T23:04:36.654Z,1550790276.654 [DataOverHttps](INFO): SBD MOMSN=10067336
2019-02-21T23:04:52.270Z,1550790292.270 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-21T23:04:52.270Z,1550790292.270 [Aanderaa_O2] No Fault, FailCount= 3
2019-02-21T23:04:52.318Z,1550790292.318 [DataOverHttps](INFO): Sending 336 bytes from file Logs/20190220T233757/Express0389.lzma
2019-02-21T23:04:53.102Z,1550790293.102 [DataOverHttps](INFO): Moved sent file to Logs/20190220T233757/Express0389.lzma.bak
2019-02-21T23:04:53.103Z,1550790293.103 [DataOverHttps](INFO): SBD MOMSN=10067344
2019-02-21T23:05:00.142Z,1550790300.142 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:05:00.684Z,1550790300.684 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-21T23:04:00.4Z
2019-02-21T23:05:00.685Z,1550790300.685 [Startup:StartupSatComms:B] Stopped
2019-02-21T23:05:00.685Z,1550790300.685 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-21T23:05:00.685Z,1550790300.685 [Startup:StartupSatComms] Stopped
2019-02-21T23:05:00.685Z,1550790300.685 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-21T23:05:00.686Z,1550790300.686 [Startup](INFO): Completed Startup
2019-02-21T23:05:00.686Z,1550790300.686 [MissionManager](INFO): Startup is completed.
2019-02-21T23:05:00.686Z,1550790300.686 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-21T23:05:00.686Z,1550790300.686 [Startup] Stopped
2019-02-21T23:05:00.686Z,1550790300.686 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-21T23:05:00.686Z,1550790300.686 [Startup:A.GoToSurface] Stopped
2019-02-21T23:05:00.687Z,1550790300.687 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-21T23:05:01.090Z,1550790301.090 [MissionManager](IMPORTANT): Started mission Default
2019-02-21T23:05:01.091Z,1550790301.091 [Default] Running Loop=1
2019-02-21T23:05:01.091Z,1550790301.091 [Default](DEBUG): Aggregate::initialize Default
2019-02-21T23:05:01.091Z,1550790301.091 [Default:B.GoToSurface] Running Loop=1
2019-02-21T23:05:01.091Z,1550790301.091 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-21T23:05:01.091Z,1550790301.091 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-21T23:05:01.092Z,1550790301.092 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-21T23:05:01.092Z,1550790301.092 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-21T23:05:01.092Z,1550790301.092 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-21T23:05:01.093Z,1550790301.093 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-21T23:05:01.094Z,1550790301.094 [Default:A.Wait] Running Loop=1
2019-02-21T23:05:01.094Z,1550790301.094 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-21T23:05:01.522Z,1550790301.522 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-21T23:05:01.522Z,1550790301.522 [CTD_Seabird] No Fault, FailCount= 3
2019-02-21T23:05:01.917Z,1550790301.917 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-21T23:05:01.941Z,1550790301.941 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-21T23:05:12.989Z,1550790312.989 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-21T23:05:12.989Z,1550790312.989 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-02-21T23:05:12.989Z,1550790312.989 [Aanderaa_O2](ERROR): Communications Fault
2019-02-21T23:05:13.125Z,1550790313.125 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-21T23:05:13.151Z,1550790313.151 [DataOverHttps](INFO): Sending 860 bytes from file Logs/20190221T230119/Express0001.lzma
2019-02-21T23:05:13.337Z,1550790313.337 [Aanderaa_O2](INFO): Powering down
2019-02-21T23:05:13.933Z,1550790313.933 [DataOverHttps](INFO): Moved sent file to Logs/20190221T230119/Express0001.lzma.bak
2019-02-21T23:05:13.934Z,1550790313.934 [DataOverHttps](INFO): SBD MOMSN=10067359
2019-02-21T23:05:13.986Z,1550790313.986 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-21T23:05:13.986Z,1550790313.986 [Aanderaa_O2] No Fault, FailCount= 1
2019-02-21T23:05:14.372Z,1550790314.372 [Default:A.Wait](INFO): Done Waiting.
2019-02-21T23:05:14.372Z,1550790314.372 [Default:A.Wait] Stopped
2019-02-21T23:05:14.372Z,1550790314.372 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-21T23:05:14.792Z,1550790314.792 [Default:CheckIn] Running Loop=1
2019-02-21T23:05:14.792Z,1550790314.792 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-21T23:05:14.792Z,1550790314.792 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-21T23:05:15.172Z,1550790315.172 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-21T23:05:15.981Z,1550790315.981 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-21T23:05:15.981Z,1550790315.981 [CTD_Seabird] Communications Fault, FailCount= 1
2019-02-21T23:05:15.981Z,1550790315.981 [CTD_Seabird](ERROR): Communications Fault
2019-02-21T23:05:16.385Z,1550790316.385 [CTD_Seabird](INFO): Powering down
2019-02-21T23:05:16.390Z,1550790316.390 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-21T23:05:18.389Z,1550790318.389 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-21T23:05:18.390Z,1550790318.390 [CTD_Seabird] No Fault, FailCount= 1
2019-02-21T23:05:19.341Z,1550790319.341 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-21T23:05:19.349Z,1550790319.349 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-21T23:05:23.538Z,1550790323.538 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-21T23:05:23.538Z,1550790323.538 [NAL9602] Communications Fault, FailCount= 4
2019-02-21T23:05:23.538Z,1550790323.538 [NAL9602](ERROR): Communications Fault
2019-02-21T23:05:23.574Z,1550790323.574 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-21T23:05:23.942Z,1550790323.942 [NAL9602](INFO): Powering down
2019-02-21T23:05:24.762Z,1550790324.762 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-21T23:05:24.762Z,1550790324.762 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-02-21T23:05:24.762Z,1550790324.762 [Aanderaa_O2](ERROR): Communications Fault
2019-02-21T23:05:24.818Z,1550790324.818 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-21T23:05:24.818Z,1550790324.818 [NAL9602] No Fault, FailCount= 4
2019-02-21T23:05:24.819Z,1550790324.819 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-21T23:05:25.321Z,1550790325.321 [Aanderaa_O2](INFO): Powering down
2019-02-21T23:05:25.986Z,1550790325.986 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-02-21T23:05:25.986Z,1550790325.986 [Aanderaa_O2] No Fault, FailCount= 2
2019-02-21T23:05:33.388Z,1550790333.388 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-21T23:05:33.389Z,1550790333.389 [CTD_Seabird] Communications Fault, FailCount= 2
2019-02-21T23:05:33.389Z,1550790333.389 [CTD_Seabird](ERROR): Communications Fault
2019-02-21T23:05:33.592Z,1550790333.592 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-21T23:05:33.793Z,1550790333.793 [CTD_Seabird](INFO): Powering down
2019-02-21T23:05:35.994Z,1550790335.994 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-02-21T23:05:35.994Z,1550790335.994 [CTD_Seabird] No Fault, FailCount= 2
2019-02-21T23:05:36.742Z,1550790336.742 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-21T23:05:36.745Z,1550790336.745 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-21T23:05:36.756Z,1550790336.756 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-02-21T23:05:36.756Z,1550790336.756 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-02-21T23:05:36.756Z,1550790336.756 [Aanderaa_O2](ERROR): Communications Fault
2019-02-21T23:05:36.858Z,1550790336.858 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-02-21T23:05:36.859Z,1550790336.859 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-02-21T23:05:37.277Z,1550790337.277 [Aanderaa_O2](INFO): Powering down
2019-02-21T23:05:43.584Z,1550790343.584 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-21T23:05:43.584Z,1550790343.584 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-02-21T23:05:43.584Z,1550790343.584 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-21T23:05:43.593Z,1550790343.593 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-21T23:05:43.593Z,1550790343.593 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-02-21T23:05:43.593Z,1550790343.593 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-21T23:05:43.594Z,1550790343.594 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-02-21T23:05:43.595Z,1550790343.595 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-21T23:05:43.595Z,1550790343.595 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-21T23:05:43.639Z,1550790343.639 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-21T23:05:43.640Z,1550790343.640 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-21T23:05:43.640Z,1550790343.640 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-21T23:05:43.972Z,1550790343.972 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-21T23:05:43.972Z,1550790343.972 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-02-21T23:05:43.984Z,1550790343.984 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-21T23:05:43.985Z,1550790343.985 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-02-21T23:05:43.985Z,1550790343.985 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-21T23:05:43.985Z,1550790343.985 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-21T23:05:44.360Z,1550790344.360 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-21T23:05:50.800Z,1550790350.800 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-02-21T23:05:50.801Z,1550790350.801 [CTD_Seabird] Communications Fault, FailCount= 3
2019-02-21T23:05:50.801Z,1550790350.801 [CTD_Seabird](ERROR): Communications Fault
2019-02-21T23:05:51.205Z,1550790351.205 [CTD_Seabird](INFO): Powering down
2019-02-21T23:05:51.207Z,1550790351.207 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-02-21T23:05:51.207Z,1550790351.207 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-02-21T23:05:54.338Z,1550790354.338 [NAL9602](INFO): Powering up NAL9602
2019-02-21T23:05:59.649Z,1550790359.649 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-21T23:05:59.649Z,1550790359.649 [Rowe_600LCM] Communications Fault, FailCount= 2
2019-02-21T23:05:59.649Z,1550790359.649 [Rowe_600LCM](ERROR): Communications Fault
2019-02-21T23:05:59.978Z,1550790359.978 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-21T23:06:00.053Z,1550790360.053 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-21T23:06:18.590Z,1550790378.590 [NAL9602](ERROR): NAL9602 initialization error.
2019-02-21T23:06:18.590Z,1550790378.590 [NAL9602] Communications Fault, FailCount= 5
2019-02-21T23:06:18.590Z,1550790378.590 [NAL9602](ERROR): Communications Fault
2019-02-21T23:06:18.666Z,1550790378.666 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-02-21T23:06:18.667Z,1550790378.667 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-02-21T23:06:18.990Z,1550790378.990 [NAL9602](INFO): Powering down
2019-02-21T23:06:19.065Z,1550790379.065 [CBIT](INFO): Critical