2019-05-01T20:55:33.432Z,1556744133.432 [Supervisor](DEBUG): Initializing supervisor.
2019-05-01T20:55:33.435Z,1556744133.435 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-01T20:55:33.435Z,1556744133.435 [SyncHandler](INFO): Protected caller Thread ID is 4341
2019-05-01T20:55:33.436Z,1556744133.436 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-01T20:55:33.437Z,1556744133.437 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-01T20:55:33.437Z,1556744133.437 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4342
2019-05-01T20:55:33.439Z,1556744133.439 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-01T20:55:33.452Z,1556744133.452 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-01T20:55:33.453Z,1556744133.453 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-01T20:55:33.453Z,1556744133.453 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4343
2019-05-01T20:55:33.454Z,1556744133.454 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-01T20:55:33.455Z,1556744133.455 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-01T20:55:33.455Z,1556744133.455 [logger ThreadHandler](INFO): Protected caller Thread ID is 4344
2019-05-01T20:55:33.457Z,1556744133.457 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-01T20:55:33.458Z,1556744133.458 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-01T20:55:33.459Z,1556744133.459 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-01T20:55:33.555Z,1556744133.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-01T20:55:33.555Z,1556744133.555 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-01T20:55:33.757Z,1556744133.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-01T20:55:33.757Z,1556744133.757 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-01T20:55:33.894Z,1556744133.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-01T20:55:33.895Z,1556744133.895 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-01T20:55:34.386Z,1556744134.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-01T20:55:34.386Z,1556744134.386 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-01T20:55:34.843Z,1556744134.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-01T20:55:34.843Z,1556744134.843 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-01T20:55:35.315Z,1556744135.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-01T20:55:35.316Z,1556744135.316 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-01T20:55:35.622Z,1556744135.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-01T20:55:35.623Z,1556744135.623 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-01T20:55:35.955Z,1556744135.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-01T20:55:35.956Z,1556744135.956 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-01T20:55:36.357Z,1556744136.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-01T20:55:36.358Z,1556744136.358 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-01T20:55:36.510Z,1556744136.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-01T20:55:36.510Z,1556744136.510 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-01T20:55:36.615Z,1556744136.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-01T20:55:36.616Z,1556744136.616 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-01T20:55:36.698Z,1556744136.698 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-01T20:55:36.799Z,1556744136.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-01T20:55:36.800Z,1556744136.800 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-01T20:55:36.996Z,1556744136.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-01T20:55:36.996Z,1556744136.996 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-01T20:55:37.206Z,1556744137.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-01T20:55:37.207Z,1556744137.207 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-05-01T20:55:37.208Z,1556744137.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-05-01T20:55:37.291Z,1556744137.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-05-01T20:55:37.521Z,1556744137.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-01T20:55:37.522Z,1556744137.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-05-01T20:55:37.655Z,1556744137.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-05-01T20:55:38.448Z,1556744138.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-05-01T20:55:38.660Z,1556744138.660 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-05-01T20:55:38.745Z,1556744138.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-05-01T20:55:38.843Z,1556744138.843 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-05-01T20:55:38.936Z,1556744138.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-05-01T20:55:39.088Z,1556744139.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-05-01T20:55:39.193Z,1556744139.193 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-05-01T20:55:39.292Z,1556744139.292 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-05-01T20:55:39.292Z,1556744139.292 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-01T20:55:39.296Z,1556744139.296 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-01T20:55:39.392Z,1556744139.392 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-01T20:55:39.392Z,1556744139.392 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-01T20:55:39.519Z,1556744139.519 [BuoyancyServo] Loaded
2019-05-01T20:55:39.519Z,1556744139.519 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-01T20:55:39.534Z,1556744139.534 [ElevatorServo] Loaded
2019-05-01T20:55:39.534Z,1556744139.534 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-01T20:55:39.549Z,1556744139.549 [MassServo] Loaded
2019-05-01T20:55:39.549Z,1556744139.549 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-01T20:55:39.564Z,1556744139.564 [RudderServo] Loaded
2019-05-01T20:55:39.564Z,1556744139.564 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-01T20:55:39.579Z,1556744139.579 [ThrusterServo] Loaded
2019-05-01T20:55:39.579Z,1556744139.579 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-01T20:55:39.579Z,1556744139.579 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-01T20:55:39.580Z,1556744139.580 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-01T20:55:39.711Z,1556744139.711 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-01T20:55:39.740Z,1556744139.740 [SBIT] Loaded
2019-05-01T20:55:39.740Z,1556744139.740 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-01T20:55:39.741Z,1556744139.741 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-01T20:55:39.768Z,1556744139.768 [IBIT] Loaded
2019-05-01T20:55:39.768Z,1556744139.768 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-01T20:55:39.772Z,1556744139.772 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-01T20:55:40.156Z,1556744140.156 [CBIT] Loaded
2019-05-01T20:55:40.157Z,1556744140.157 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-01T20:55:40.157Z,1556744140.157 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-01T20:55:40.158Z,1556744140.158 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-01T20:55:40.335Z,1556744140.335 [Aanderaa_O2] Loaded
2019-05-01T20:55:40.336Z,1556744140.336 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-05-01T20:55:40.345Z,1556744140.345 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-01T20:55:40.350Z,1556744140.350 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-01T20:55:40.352Z,1556744140.352 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-01T20:55:40.357Z,1556744140.357 [CTD_Seabird](INFO): created writer for : depth
2019-05-01T20:55:40.357Z,1556744140.357 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-01T20:55:40.362Z,1556744140.362 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-01T20:55:40.363Z,1556744140.363 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-01T20:55:40.368Z,1556744140.368 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-01T20:55:40.369Z,1556744140.369 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-01T20:55:40.374Z,1556744140.374 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-01T20:55:40.375Z,1556744140.375 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-01T20:55:40.380Z,1556744140.380 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-01T20:55:40.380Z,1556744140.380 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-01T20:55:40.385Z,1556744140.385 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-01T20:55:40.412Z,1556744140.412 [CTD_Seabird] Loaded
2019-05-01T20:55:40.412Z,1556744140.412 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-01T20:55:40.413Z,1556744140.413 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4065E4E0
2019-05-01T20:55:40.414Z,1556744140.414 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4423
2019-05-01T20:55:40.439Z,1556744140.439 [ESPComponent] Loaded
2019-05-01T20:55:40.439Z,1556744140.439 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-05-01T20:55:40.453Z,1556744140.453 [PAR_Licor] Loaded
2019-05-01T20:55:40.453Z,1556744140.453 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-01T20:55:40.500Z,1556744140.500 [WetLabsBB2FL] Loaded
2019-05-01T20:55:40.500Z,1556744140.500 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-01T20:55:40.501Z,1556744140.501 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4068E4E0
2019-05-01T20:55:40.502Z,1556744140.502 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4424
2019-05-01T20:55:40.502Z,1556744140.502 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-01T20:55:40.503Z,1556744140.503 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-01T20:55:40.542Z,1556744140.542 [DepthRateCalculator] Loaded
2019-05-01T20:55:40.542Z,1556744140.542 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-01T20:55:40.548Z,1556744140.548 [PitchRateCalculator] Loaded
2019-05-01T20:55:40.548Z,1556744140.548 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-01T20:55:40.564Z,1556744140.564 [SpeedCalculator] Loaded
2019-05-01T20:55:40.565Z,1556744140.565 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-01T20:55:40.586Z,1556744140.586 [TempGradientCalculator] Loaded
2019-05-01T20:55:40.586Z,1556744140.586 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-01T20:55:40.592Z,1556744140.592 [YawRateCalculator] Loaded
2019-05-01T20:55:40.592Z,1556744140.592 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-01T20:55:40.624Z,1556744140.624 [ElevatorOffsetCalculator] Loaded
2019-05-01T20:55:40.625Z,1556744140.625 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-01T20:55:40.625Z,1556744140.625 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-01T20:55:40.626Z,1556744140.626 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-01T20:55:40.652Z,1556744140.652 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-01T20:55:40.652Z,1556744140.652 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-01T20:55:40.753Z,1556744140.753 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-01T20:55:40.754Z,1556744140.754 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-01T20:55:40.812Z,1556744140.812 [DeadReckonUsingSpeedCalculator] Loaded
2019-05-01T20:55:40.813Z,1556744140.813 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-05-01T20:55:40.871Z,1556744140.871 [DeadReckonWithRespectToSeafloor] Loaded
2019-05-01T20:55:40.872Z,1556744140.872 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-05-01T20:55:40.892Z,1556744140.892 [NavChart] Loaded
2019-05-01T20:55:40.892Z,1556744140.892 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-01T20:55:40.896Z,1556744140.896 [UniversalFixResidualReporter] Loaded
2019-05-01T20:55:40.896Z,1556744140.896 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-01T20:55:40.897Z,1556744140.897 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-01T20:55:40.897Z,1556744140.897 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-01T20:55:41.204Z,1556744141.204 [AcousticModem_Benthos_ATM900] Loaded
2019-05-01T20:55:41.204Z,1556744141.204 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-05-01T20:55:41.284Z,1556744141.284 [DataOverHttps] Loaded
2019-05-01T20:55:41.285Z,1556744141.285 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-01T20:55:41.286Z,1556744141.286 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2019-05-01T20:55:41.286Z,1556744141.286 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4425
2019-05-01T20:55:41.299Z,1556744141.299 [Depth_Keller] Loaded
2019-05-01T20:55:41.300Z,1556744141.300 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-01T20:55:41.390Z,1556744141.390 [NAL9602] Loaded
2019-05-01T20:55:41.390Z,1556744141.390 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-01T20:55:41.395Z,1556744141.395 [Onboard] Loaded
2019-05-01T20:55:41.395Z,1556744141.395 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-01T20:55:41.399Z,1556744141.399 [Radio_Surface] Loaded
2019-05-01T20:55:41.399Z,1556744141.399 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-01T20:55:41.400Z,1556744141.400 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099E4E0
2019-05-01T20:55:41.400Z,1556744141.400 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4426
2019-05-01T20:55:41.447Z,1556744141.447 [PNI_TCM] Loaded
2019-05-01T20:55:41.447Z,1556744141.447 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-05-01T20:55:42.719Z,1556744142.719 [BPC1] Loaded
2019-05-01T20:55:42.720Z,1556744142.720 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-01T20:55:42.721Z,1556744142.721 [BR_Ping1D](CRITICAL): Could not find element BR_Ping1D.loadControl
2019-05-01T20:55:42.722Z,1556744142.722 [BR_Ping1D](ERROR): Could not open device '/dev/invalid' at BR_Ping1D.loadControl
2019-05-01T20:55:42.722Z,1556744142.722 [BR_Ping1D](CRITICAL): Could not find element BR_Ping1D.uart
2019-05-01T20:55:42.722Z,1556744142.722 [BR_Ping1D](CRITICAL): Could not find element BR_Ping1D.baud
2019-05-01T20:55:42.732Z,1556744142.732 [BR_Ping1D] Loaded
2019-05-01T20:55:42.732Z,1556744142.732 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-05-01T20:55:42.732Z,1556744142.732 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-01T20:55:42.733Z,1556744142.733 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-01T20:55:42.875Z,1556744142.875 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-01T20:55:42.876Z,1556744142.876 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-01T20:55:43.141Z,1556744143.141 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-01T20:55:43.224Z,1556744143.224 [VerticalControl] Loaded
2019-05-01T20:55:43.224Z,1556744143.224 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-01T20:55:43.225Z,1556744143.225 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-01T20:55:43.286Z,1556744143.286 [HorizontalControl] Loaded
2019-05-01T20:55:43.287Z,1556744143.287 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-01T20:55:43.287Z,1556744143.287 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-01T20:55:43.289Z,1556744143.289 [SpeedControl] Loaded
2019-05-01T20:55:43.289Z,1556744143.289 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-01T20:55:43.290Z,1556744143.290 [LoopControl](DEBUG): Construct LoopControl.
2019-05-01T20:55:43.291Z,1556744143.291 [LoopControl] Loaded
2019-05-01T20:55:43.291Z,1556744143.291 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-01T20:55:43.291Z,1556744143.291 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-01T20:55:43.292Z,1556744143.292 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-01T20:55:43.305Z,1556744143.305 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-01T20:55:43.305Z,1556744143.305 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-01T20:55:43.573Z,1556744143.573 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-01T20:55:43.576Z,1556744143.576 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-01T20:55:43.577Z,1556744143.577 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-01T20:55:43.584Z,1556744143.584 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-01T20:55:43.585Z,1556744143.585 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD14E0
2019-05-01T20:55:43.586Z,1556744143.586 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4427
2019-05-01T20:55:43.590Z,1556744143.590 [Supervisor](INFO): Main Thread ID is 804
2019-05-01T20:55:43.590Z,1556744143.590 [Supervisor](DEBUG): Running supervisor.
2019-05-01T20:55:43.591Z,1556744143.591 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4428
2019-05-01T20:55:43.594Z,1556744143.594 [controlThread ThreadHandler](INFO): Handler Thread ID is 4429
2019-05-01T20:55:43.595Z,1556744143.595 [controlThread](DEBUG): Initializing ControlThread
2019-05-01T20:55:43.597Z,1556744143.597 [SBIT](INFO): Initialize SBIT Component.
2019-05-01T20:55:43.597Z,1556744143.597 [SBIT](IMPORTANT): git: 2019-04-10-2-gf3e4ef2
2019-05-01T20:55:43.597Z,1556744143.597 [SBIT](INFO): git hash: f3e4ef2c5f3aa5e841d57d486bee5a5f25629231
2019-05-01T20:55:43.598Z,1556744143.598 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-01T20:55:43.599Z,1556744143.599 [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-01T20:55:43.600Z,1556744143.600 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-05-01T20:55:43.601Z,1556744143.601 [IBIT](INFO): Initialize IBIT Component.
2019-05-01T20:55:43.601Z,1556744143.601 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-01T20:55:43.602Z,1556744143.602 [logger ThreadHandler](INFO): Handler Thread ID is 4430
2019-05-01T20:55:43.613Z,1556744143.613 [CBIT](DEBUG): Initialized mux pins.
2019-05-01T20:55:43.613Z,1556744143.613 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-01T20:55:43.621Z,1556744143.621 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4431
2019-05-01T20:55:43.622Z,1556744143.622 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-01T20:55:43.626Z,1556744143.626 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-01T20:55:43.627Z,1556744143.627 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4433
2019-05-01T20:55:43.628Z,1556744143.628 [WetLabsBB2FL](INFO): Powering down
2019-05-01T20:55:43.637Z,1556744143.637 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-05-01T20:55:43.637Z,1556744143.637 [CBIT](DEBUG): Initializing heartbeat.
2019-05-01T20:55:43.657Z,1556744143.657 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4434
2019-05-01T20:55:43.658Z,1556744143.658 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-01T20:55:43.669Z,1556744143.669 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4435
2019-05-01T20:55:43.685Z,1556744143.685 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4436
2019-05-01T20:55:43.688Z,1556744143.688 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-01T20:55:43.689Z,1556744143.689 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-01T20:55:43.689Z,1556744143.689 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-01T20:55:43.689Z,1556744143.689 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-01T20:55:43.689Z,1556744143.689 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-01T20:55:43.689Z,1556744143.689 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-01T20:55:43.690Z,1556744143.690 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-01T20:55:43.690Z,1556744143.690 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-01T20:55:43.690Z,1556744143.690 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-01T20:55:43.690Z,1556744143.690 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-01T20:55:43.690Z,1556744143.690 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-01T20:55:43.691Z,1556744143.691 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-01T20:55:43.691Z,1556744143.691 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-01T20:55:43.691Z,1556744143.691 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-01T20:55:43.691Z,1556744143.691 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-01T20:55:43.691Z,1556744143.691 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-01T20:55:43.709Z,1556744143.709 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-01T20:55:43.709Z,1556744143.709 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-01T20:55:43.745Z,1556744143.745 [CBIT](DEBUG): Backplane powered.
2019-05-01T20:55:43.749Z,1556744143.749 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-01T20:55:43.750Z,1556744143.750 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-01T20:55:43.750Z,1556744143.750 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-01T20:55:43.750Z,1556744143.750 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-01T20:55:43.751Z,1556744143.751 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-01T20:55:43.751Z,1556744143.751 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-01T20:55:43.752Z,1556744143.752 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-01T20:55:43.753Z,1556744143.753 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-01T20:55:43.754Z,1556744143.754 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-01T20:55:43.759Z,1556744143.759 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-01T20:55:43.760Z,1556744143.760 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-01T20:55:43.765Z,1556744143.765 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-01T20:55:43.766Z,1556744143.766 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-01T20:55:43.767Z,1556744143.767 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-01T20:55:43.801Z,1556744143.801 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-01T20:55:43.828Z,1556744143.828 [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-01T20:55:43.837Z,1556744143.837 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-01T20:55:43.914Z,1556744143.914 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-01T20:55:43.915Z,1556744143.915 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-01T20:55:43.933Z,1556744143.933 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-01T20:55:43.960Z,1556744143.960 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-01T20:55:43.963Z,1556744143.963 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-01T20:55:43.980Z,1556744143.980 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-01T20:55:43.983Z,1556744143.983 [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-01T20:55:43.998Z,1556744143.998 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,BR_Ping1D,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-01T20:55:44.038Z,1556744144.038 [ESPComponent](INFO): powering down ESP
2019-05-01T20:55:44.073Z,1556744144.073 [Radio_Surface](INFO): Powering up
2019-05-01T20:55:44.473Z,1556744144.473 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms

2019-05-01T20:55:44.474Z,1556744144.474 [PAR_Licor] Data Fault, FailCount= 1
2019-05-01T20:55:44.474Z,1556744144.474 [PAR_Licor](ERROR): Data Fault
2019-05-01T20:55:44.474Z,1556744144.474 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-01T20:55:44.474Z,1556744144.474 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-01T20:55:44.506Z,1556744144.506 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-05-01T20:55:44.558Z,1556744144.558 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T20:55:44.558Z,1556744144.558 [BR_Ping1D] Communications Fault, FailCount= 1
2019-05-01T20:55:44.558Z,1556744144.558 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T20:55:44.561Z,1556744144.561 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-01T20:55:44.584Z,1556744144.584 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-01T20:55:44.589Z,1556744144.589 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-01T20:55:44.590Z,1556744144.590 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-01T20:55:44.597Z,1556744144.597 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-01T20:55:44.598Z,1556744144.598 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-01T20:55:44.605Z,1556744144.605 [MassServo](DEBUG): Initializing MassServo.
2019-05-01T20:55:44.606Z,1556744144.606 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-01T20:55:44.613Z,1556744144.613 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-01T20:55:44.614Z,1556744144.614 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-01T20:55:44.621Z,1556744144.621 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-01T20:55:44.624Z,1556744144.624 [CommandLine](FAULT): Scheduling is paused
2019-05-01T20:55:44.624Z,1556744144.624 [CBIT](INFO): Critical error at 20190501T205543
2019-05-01T20:55:44.624Z,1556744144.624 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-01T20:55:44.629Z,1556744144.629 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-01T20:55:44.629Z,1556744144.629 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-01T20:55:44.629Z,1556744144.629 [PAR_Licor] No Fault, FailCount= 1
2019-05-01T20:55:44.630Z,1556744144.630 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T20:55:44.752Z,1556744144.752 [BR_Ping1D](INFO): Powering down
2019-05-01T20:55:45.181Z,1556744145.181 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-05-01T20:55:45.181Z,1556744145.181 [PAR_Licor] Data Fault, FailCount= 2
2019-05-01T20:55:45.181Z,1556744145.181 [PAR_Licor](ERROR): Data Fault
2019-05-01T20:55:45.573Z,1556744145.573 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-05-01T20:55:45.573Z,1556744145.573 [RudderServo](FAULT): Rudder failed to initialize
2019-05-01T20:55:45.573Z,1556744145.573 [RudderServo] Communications Fault, FailCount= 1
2019-05-01T20:55:45.573Z,1556744145.573 [RudderServo](ERROR): Communications Fault
2019-05-01T20:55:45.684Z,1556744145.684 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-01T20:55:45.685Z,1556744145.685 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-01T20:55:45.685Z,1556744145.685 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-01T20:55:45.685Z,1556744145.685 [PAR_Licor] No Fault, FailCount= 2
2019-05-01T20:55:45.866Z,1556744145.866 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-01T20:55:45.866Z,1556744145.866 [RudderServo](INFO): Powering down
2019-05-01T20:55:46.150Z,1556744146.150 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-01T20:55:46.150Z,1556744146.150 [BR_Ping1D] No Fault, FailCount= 1
2019-05-01T20:55:46.633Z,1556744146.633 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-05-01T20:55:46.634Z,1556744146.634 [PAR_Licor] Data Fault, FailCount= 1
2019-05-01T20:55:46.634Z,1556744146.634 [PAR_Licor](ERROR): Data Fault
2019-05-01T20:55:46.692Z,1556744146.692 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T20:55:46.694Z,1556744146.694 [BR_Ping1D] Communications Fault, FailCount= 2
2019-05-01T20:55:46.694Z,1556744146.694 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T20:55:46.730Z,1556744146.730 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-01T20:55:46.850Z,1556744146.850 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-01T20:55:46.854Z,1556744146.854 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-01T20:55:46.854Z,1556744146.854 [RudderServo] No Fault, FailCount= 1
2019-05-01T20:55:46.854Z,1556744146.854 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-01T20:55:46.855Z,1556744146.855 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-01T20:55:46.855Z,1556744146.855 [PAR_Licor] No Fault, FailCount= 1
2019-05-01T20:55:46.855Z,1556744146.855 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T20:55:46.919Z,1556744146.919 [BR_Ping1D](INFO): Powering down
2019-05-01T20:55:47.449Z,1556744147.449 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-05-01T20:55:47.449Z,1556744147.449 [PAR_Licor] Data Fault, FailCount= 2
2019-05-01T20:55:47.449Z,1556744147.449 [PAR_Licor](ERROR): Data Fault
2019-05-01T20:55:47.665Z,1556744147.665 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-01T20:55:47.665Z,1556744147.665 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-01T20:55:47.665Z,1556744147.665 [PAR_Licor] No Fault, FailCount= 2
2019-05-01T20:55:47.785Z,1556744147.785 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-01T20:55:47.785Z,1556744147.785 [BR_Ping1D] No Fault, FailCount= 2
2019-05-01T20:55:48.249Z,1556744148.249 [PAR_Licor](INFO): A/D timeout, 12 tries over 134 ms

2019-05-01T20:55:48.249Z,1556744148.249 [PAR_Licor] Data Fault, FailCount= 3
2019-05-01T20:55:48.249Z,1556744148.249 [PAR_Licor](ERROR): Data Fault
2019-05-01T20:55:48.270Z,1556744148.270 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T20:55:48.270Z,1556744148.270 [BR_Ping1D] Communications Fault, FailCount= 3
2019-05-01T20:55:48.270Z,1556744148.270 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T20:55:48.529Z,1556744148.529 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-01T20:55:48.529Z,1556744148.529 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-05-01T20:55:48.530Z,1556744148.530 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T20:55:48.572Z,1556744148.572 [BR_Ping1D](INFO): Powering down
2019-05-01T20:55:48.647Z,1556744148.647 [CBIT](INFO): Critical error at 20190501T205548
2019-05-01T20:55:49.814Z,1556744149.814 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-01T20:55:49.814Z,1556744149.814 [BR_Ping1D] No Fault, FailCount= 3
2019-05-01T20:55:50.169Z,1556744150.169 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T20:55:50.169Z,1556744150.169 [BR_Ping1D] Communications Fault, FailCount= 4
2019-05-01T20:55:50.169Z,1556744150.169 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T20:55:50.478Z,1556744150.478 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T20:55:50.573Z,1556744150.573 [BR_Ping1D](INFO): Powering down
2019-05-01T20:55:51.784Z,1556744151.784 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T20:55:51.784Z,1556744151.784 [PNI_TCM] Data Fault, FailCount= 1
2019-05-01T20:55:51.784Z,1556744151.784 [PNI_TCM](ERROR): Data Fault
2019-05-01T20:55:51.858Z,1556744151.858 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T20:55:51.858Z,1556744151.858 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-01T20:55:51.858Z,1556744151.858 [BR_Ping1D] No Fault, FailCount= 4
2019-05-01T20:55:52.189Z,1556744152.189 [PNI_TCM](INFO): Powering down
2019-05-01T20:55:52.302Z,1556744152.302 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T20:55:52.302Z,1556744152.302 [BR_Ping1D] Communications Fault, FailCount= 5
2019-05-01T20:55:52.302Z,1556744152.302 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T20:55:52.402Z,1556744152.402 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T20:55:52.402Z,1556744152.402 [CBIT](CRITICAL): Communications Fault in component: BR_Ping1D
2019-05-01T20:55:52.592Z,1556744152.592 [BR_Ping1D](INFO): Powering down
2019-05-01T20:55:52.656Z,1556744152.656 [CBIT](INFO): Critical error at 20190501T205552
2019-05-01T20:55:53.558Z,1556744153.558 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-01T20:55:53.558Z,1556744153.558 [PNI_TCM] No Fault, FailCount= 1
2019-05-01T20:55:53.846Z,1556744153.846 [CBIT](CRITICAL): Environmental Failure. Press:14.797614 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-05-01T20:55:54.189Z,1556744154.189 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-01T20:55:54.189Z,1556744154.189 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-01T20:55:54.189Z,1556744154.189 [Aanderaa_O2](ERROR): Communications Fault
2019-05-01T20:55:54.252Z,1556744154.252 [CBIT](INFO): Critical error at 20190501T205553
2019-05-01T20:55:54.294Z,1556744154.294 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-01T20:55:54.713Z,1556744154.713 [Aanderaa_O2](INFO): Powering down
2019-05-01T20:55:55.458Z,1556744155.458 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-01T20:55:55.458Z,1556744155.458 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-01T20:55:56.673Z,1556744156.673 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-01T20:55:56.673Z,1556744156.673 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-01T20:55:56.673Z,1556744156.673 [CTD_Seabird](ERROR): Communications Fault
2019-05-01T20:55:57.063Z,1556744157.063 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-01T20:55:57.077Z,1556744157.077 [CTD_Seabird](INFO): Powering down
2019-05-01T20:55:58.313Z,1556744158.313 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-01T20:55:58.313Z,1556744158.313 [CTD_Seabird] No Fault, FailCount= 1
2019-05-01T20:55:58.449Z,1556744158.449 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-01T20:55:58.451Z,1556744158.451 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-01T20:56:01.058Z,1556744161.058 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-05-01T20:56:01.077Z,1556744161.077 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T20:56:01.077Z,1556744161.077 [PNI_TCM] Data Fault, FailCount= 2
2019-05-01T20:56:01.077Z,1556744161.077 [PNI_TCM](ERROR): Data Fault
2019-05-01T20:56:01.094Z,1556744161.094 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T20:56:01.480Z,1556744161.480 [PNI_TCM](INFO): Powering down
2019-05-01T20:56:02.711Z,1556744162.711 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-01T20:56:02.711Z,1556744162.711 [PNI_TCM] No Fault, FailCount= 2
2019-05-01T20:56:04.694Z,1556744164.694 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize; deviceResponse_ loaded: 
, available: Teledyne Benthos DAT-900 Series
a5
2019-05-01T20:56:04.694Z,1556744164.694 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-05-01T20:56:04.694Z,1556744164.694 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-01T20:56:04.743Z,1556744164.743 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-01T20:56:05.098Z,1556744165.098 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-01T20:56:05.905Z,1556744165.905 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-01T20:56:05.905Z,1556744165.905 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-01T20:56:05.905Z,1556744165.905 [Aanderaa_O2](ERROR): Communications Fault
2019-05-01T20:56:05.962Z,1556744165.962 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-01T20:56:05.962Z,1556744165.962 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-01T20:56:05.962Z,1556744165.962 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-05-01T20:56:06.429Z,1556744166.429 [Aanderaa_O2](INFO): Powering down
2019-05-01T20:56:07.150Z,1556744167.150 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-01T20:56:07.150Z,1556744167.150 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-01T20:56:08.329Z,1556744168.329 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-01T20:56:08.330Z,1556744168.330 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-01T20:56:08.391Z,1556744168.391 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-01T20:56:08.396Z,1556744168.395 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-01T20:56:10.368Z,1556744170.368 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T20:56:10.368Z,1556744170.368 [PNI_TCM] Data Fault, FailCount= 3
2019-05-01T20:56:10.368Z,1556744170.368 [PNI_TCM](ERROR): Data Fault
2019-05-01T20:56:10.858Z,1556744170.858 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T20:56:10.890Z,1556744170.890 [PNI_TCM](INFO): Powering down
2019-05-01T20:56:11.501Z,1556744171.501 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-01T20:56:11.501Z,1556744171.501 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-01T20:56:11.501Z,1556744171.501 [CTD_Seabird](ERROR): Communications Fault
2019-05-01T20:56:11.674Z,1556744171.674 [NAL9602](INFO): Powering up NAL9602
2019-05-01T20:56:11.826Z,1556744171.826 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-01T20:56:11.905Z,1556744171.905 [CTD_Seabird](INFO): Powering down
2019-05-01T20:56:12.286Z,1556744172.286 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-01T20:56:12.286Z,1556744172.286 [PNI_TCM] No Fault, FailCount= 3
2019-05-01T20:56:13.030Z,1556744173.030 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-01T20:56:13.030Z,1556744173.030 [CTD_Seabird] No Fault, FailCount= 2
2019-05-01T20:56:13.245Z,1556744173.245 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-01T20:56:13.253Z,1556744173.253 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-01T20:56:17.729Z,1556744177.729 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-01T20:56:17.730Z,1556744177.730 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-01T20:56:17.730Z,1556744177.730 [Aanderaa_O2](ERROR): Communications Fault
2019-05-01T20:56:17.834Z,1556744177.834 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-01T20:56:17.834Z,1556744177.834 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-01T20:56:18.253Z,1556744178.253 [Aanderaa_O2](INFO): Powering down
2019-05-01T20:56:19.346Z,1556744179.346 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-05-01T20:56:19.431Z,1556744179.431 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007356
CHAN A1 (24V): 0.015469
CHAN A2 (12V): -0.005463
CHAN A3 (5V): -0.002351
CHAN B0 (3.3V): -0.001226
CHAN B1 (3.15aV): -0.000966
CHAN B2 (3.15bV): -0.001137
CHAN B3 (GND): 0.000020
OPEN: -0.001227
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-01T20:56:19.770Z,1556744179.770 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T20:56:19.770Z,1556744179.770 [PNI_TCM] Data Fault, FailCount= 4
2019-05-01T20:56:19.770Z,1556744179.770 [PNI_TCM](ERROR): Data Fault
2019-05-01T20:56:19.822Z,1556744179.822 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T20:56:20.175Z,1556744180.175 [PNI_TCM](INFO): Powering down
2019-05-01T20:56:21.526Z,1556744181.526 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-01T20:56:21.526Z,1556744181.526 [PNI_TCM] No Fault, FailCount= 4
2019-05-01T20:56:22.582Z,1556744182.582 [NAL9602](INFO): NAL9602 initialized
2019-05-01T20:56:23.401Z,1556744183.401 [NAL9602](DEBUG): Fix Requested
2019-05-01T20:56:26.313Z,1556744186.313 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-01T20:56:26.313Z,1556744186.313 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-01T20:56:26.313Z,1556744186.313 [CTD_Seabird](ERROR): Communications Fault
2019-05-01T20:56:26.506Z,1556744186.506 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-01T20:56:26.506Z,1556744186.506 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-01T20:56:26.717Z,1556744186.717 [CTD_Seabird](INFO): Powering down
2019-05-01T20:56:28.390Z,1556744188.390 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize; deviceResponse_ loaded: 
, available: Teledyne Benthos DAT-900 Series
ù,
2019-05-01T20:56:28.390Z,1556744188.390 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-05-01T20:56:28.390Z,1556744188.390 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-01T20:56:28.554Z,1556744188.554 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-01T20:56:28.794Z,1556744188.794 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-01T20:56:29.217Z,1556744189.217 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T20:56:29.217Z,1556744189.217 [PNI_TCM] Data Fault, FailCount= 5
2019-05-01T20:56:29.217Z,1556744189.217 [PNI_TCM](ERROR): Data Fault
2019-05-01T20:56:29.538Z,1556744189.538 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T20:56:29.539Z,1556744189.539 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-05-01T20:56:29.620Z,1556744189.620 [PNI_TCM](INFO): Powering down
2019-05-01T20:56:29.772Z,1556744189.772 [CBIT](INFO): Critical error at 20190501T205629
2019-05-01T20:56:29.775Z,1556744189.775 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-01T20:56:29.775Z,1556744189.775 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-05-01T20:56:32.025Z,1556744192.025 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-01T20:56:32.025Z,1556744192.025 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-01T20:56:51.120Z,1556744211.120 [BPC1](ERROR): BPC1A: No match for serial number 0441 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-05-01T20:56:51.122Z,1556744211.122 [BPC1](ERROR): BPC1B: No match for serial number 0480 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-05-01T20:56:51.122Z,1556744211.122 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-05-01T20:56:51.122Z,1556744211.122 [BPC1] Data Fault, FailCount= 1
2019-05-01T20:56:51.122Z,1556744211.122 [BPC1](ERROR): Data Fault
2019-05-01T20:56:51.250Z,1556744211.250 [CBIT](ERROR): Data Fault in component: BPC1
2019-05-01T20:56:52.302Z,1556744212.302 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-01T20:56:52.302Z,1556744212.302 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-05-01T20:56:52.302Z,1556744212.302 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-01T20:56:52.454Z,1556744212.454 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-01T20:56:52.705Z,1556744212.705 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-01T20:56:53.589Z,1556744213.589 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-01T20:56:53.589Z,1556744213.589 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-05-01T20:56:55.937Z,1556744215.937 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-01T20:56:55.938Z,1556744215.938 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-01T20:57:01.639Z,1556744221.639 [SBIT](IMPORTANT): SBIT PASSED
2019-05-01T20:57:01.718Z,1556744221.718 [CommandLine](IMPORTANT): got command configSet list
2019-05-01T20:57:01.719Z,1556744221.719 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-01T20:57:01.719Z,1556744221.719 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-01T20:57:01.720Z,1556744221.720 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-05-01T20:57:02.033Z,1556744222.033 [MissionManager](IMPORTANT): Started mission Startup
2019-05-01T20:57:02.033Z,1556744222.033 [Startup] Running Loop=1
2019-05-01T20:57:02.033Z,1556744222.033 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-01T20:57:02.033Z,1556744222.033 [Startup:A.GoToSurface] Running Loop=1
2019-05-01T20:57:02.033Z,1556744222.033 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-01T20:57:02.034Z,1556744222.034 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-01T20:57:02.034Z,1556744222.034 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-01T20:57:02.035Z,1556744222.035 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-01T20:57:02.035Z,1556744222.035 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-01T20:57:02.035Z,1556744222.035 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-01T20:57:02.037Z,1556744222.037 [Startup:StartupSatComms] Running Loop=1
2019-05-01T20:57:02.037Z,1556744222.037 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-01T20:57:02.037Z,1556744222.037 [Startup:StartupSatComms:A] Running Loop=1
2019-05-01T20:57:02.440Z,1556744222.440 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-01T20:57:06.846Z,1556744226.846 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-05-01T20:57:16.138Z,1556744236.138 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize; deviceResponse_ loaded: 
, available: Teledyne Benthos DAT-900 Series
22
2019-05-01T20:57:16.138Z,1556744236.138 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-05-01T20:57:16.138Z,1556744236.138 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-01T20:57:16.215Z,1556744236.215 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-01T20:57:16.541Z,1556744236.541 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-01T20:57:17.393Z,1556744237.393 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-01T20:57:17.393Z,1556744237.393 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-05-01T20:57:19.773Z,1556744239.773 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-01T20:57:19.774Z,1556744239.774 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-01T20:57:39.975Z,1556744259.975 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-01T20:57:39.975Z,1556744259.975 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-05-01T20:57:39.975Z,1556744259.975 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-01T20:57:40.015Z,1556744260.015 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-01T20:57:40.377Z,1556744260.377 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-01T20:57:41.218Z,1556744261.218 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-01T20:57:41.218Z,1556744261.218 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-05-01T20:57:43.609Z,1556744263.609 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-01T20:57:43.610Z,1556744263.610 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-01T20:57:44.051Z,1556744264.051 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-01T20:57:44.051Z,1556744264.051 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-01T20:57:44.051Z,1556744264.051 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-01T20:57:44.052Z,1556744264.052 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-01T20:57:44.053Z,1556744264.053 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-05-01T20:57:44.053Z,1556744264.053 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-01T20:57:44.054Z,1556744264.054 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-01T20:57:44.054Z,1556744264.054 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-05-01T20:57:44.054Z,1556744264.054 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-05-01T20:57:44.064Z,1556744264.064 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-01T20:57:44.065Z,1556744264.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-01T20:57:44.065Z,1556744264.065 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-05-01T20:57:44.462Z,1556744264.462 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-01T20:57:44.462Z,1556744264.462 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-01T20:57:44.462Z,1556744264.462 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-01T20:57:44.462Z,1556744264.462 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-05-01T20:57:44.463Z,1556744264.463 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-05-01T20:57:44.463Z,1556744264.463 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-05-01T20:57:44.851Z,1556744264.851 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-01T20:57:54.518Z,1556744274.518 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-05-01T20:58:02.222Z,1556744282.222 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-01T20:57:02.0Z
2019-05-01T20:58:02.222Z,1556744282.222 [Startup:StartupSatComms:A] Stopped
2019-05-01T20:58:02.222Z,1556744282.222 [Startup:StartupSatComms:B] Running Loop=1
2019-05-01T20:58:02.620Z,1556744282.620 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-01T20:58:03.810Z,1556744283.810 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize; deviceResponse_ loaded: 
, available: Teledyne Benthos DAT-900 Series
ù3
2019-05-01T20:58:03.810Z,1556744283.810 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-05-01T20:58:03.810Z,1556744283.810 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-01T20:58:03.863Z,1556744283.863 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-01T20:58:04.213Z,1556744284.213 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-01T20:58:05.073Z,1556744285.073 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-01T20:58:05.073Z,1556744285.073 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-05-01T20:58:07.445Z,1556744287.445 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-01T20:58:07.446Z,1556744287.446 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-01T20:58:23.059Z,1556744303.059 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004878
2019-05-01T20:58:27.645Z,1556744307.645 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-01T20:58:27.645Z,1556744307.645 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-05-01T20:58:27.645Z,1556744307.645 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-01T20:58:27.692Z,1556744307.692 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-01T20:58:28.049Z,1556744308.049 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-01T20:58:28.898Z,1556744308.898 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-01T20:58:28.898Z,1556744308.898 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-05-01T20:58:29.759Z,1556744309.759 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20190501T182402/Courier0046.lzma
2019-05-01T20:58:30.563Z,1556744310.563 [DataOverHttps](INFO): Moved sent file to Logs/20190501T182402/Courier0046.lzma.bak
2019-05-01T20:58:30.563Z,1556744310.563 [DataOverHttps](INFO): SBD MOMSN=10892589
2019-05-01T20:58:31.282Z,1556744311.282 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-01T20:58:31.282Z,1556744311.282 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-01T20:58:42.190Z,1556744322.190 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-05-01T20:58:44.265Z,1556744324.265 [DataOverHttps](INFO): Sending 323 bytes from file Logs/20190501T205533/Courier0000.lzma
2019-05-01T20:58:45.070Z,1556744325.070 [DataOverHttps](INFO): Moved sent file to Logs/20190501T205533/Courier0000.lzma.bak
2019-05-01T20:58:45.070Z,1556744325.070 [DataOverHttps](INFO): SBD MOMSN=10892594
2019-05-01T20:58:47.879Z,1556744327.879 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-01T20:58:47.879Z,1556744327.879 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-01T20:58:51.482Z,1556744331.482 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize; deviceResponse_ loaded: 
, available: Teledyne Benthos DAT-900 Series
û4
2019-05-01T20:58:51.482Z,1556744331.482 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-05-01T20:58:51.482Z,1556744331.482 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-01T20:58:51.531Z,1556744331.531 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-01T20:58:51.531Z,1556744331.531 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-01T20:58:51.886Z,1556744331.886 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-01T20:58:56.382Z,1556744336.382 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-01T20:58:56.382Z,1556744336.382 [CTD_Seabird] No Fault, FailCount= 3
2019-05-01T20:58:56.755Z,1556744336.755 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-01T20:58:56.759Z,1556744336.759 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-01T20:58:58.349Z,1556744338.349 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-01T20:58:58.349Z,1556744338.349 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-01T20:58:58.349Z,1556744338.349 [Aanderaa_O2](ERROR): Communications Fault
2019-05-01T20:58:58.395Z,1556744338.395 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-01T20:58:58.873Z,1556744338.873 [Aanderaa_O2](INFO): Powering down
2019-05-01T20:58:59.599Z,1556744339.599 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-01T20:58:59.599Z,1556744339.599 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-01T20:59:00.046Z,1556744340.046 [DataOverHttps](INFO): Sending 784 bytes from file Logs/20190501T182402/Express0047.lzma
2019-05-01T20:59:00.850Z,1556744340.850 [DataOverHttps](INFO): Moved sent file to Logs/20190501T182402/Express0047.lzma.bak
2019-05-01T20:59:00.851Z,1556744340.851 [DataOverHttps](INFO): SBD MOMSN=10892605
2019-05-01T20:59:02.430Z,1556744342.430 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-01T20:58:02.2Z
2019-05-01T20:59:02.430Z,1556744342.430 [Startup:StartupSatComms:B] Stopped
2019-05-01T20:59:02.431Z,1556744342.431 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-01T20:59:02.431Z,1556744342.431 [Startup:StartupSatComms] Stopped
2019-05-01T20:59:02.431Z,1556744342.431 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-01T20:59:02.431Z,1556744342.431 [Startup](INFO): Completed Startup
2019-05-01T20:59:02.432Z,1556744342.432 [MissionManager](INFO): Startup is completed.
2019-05-01T20:59:02.432Z,1556744342.432 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-01T20:59:02.432Z,1556744342.432 [Startup] Stopped
2019-05-01T20:59:02.432Z,1556744342.432 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-01T20:59:02.432Z,1556744342.432 [Startup:A.GoToSurface] Stopped
2019-05-01T20:59:02.432Z,1556744342.432 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-01T20:59:02.830Z,1556744342.830 [MissionManager](IMPORTANT): Started mission Default
2019-05-01T20:59:02.830Z,1556744342.830 [Default] Running Loop=1
2019-05-01T20:59:02.830Z,1556744342.830 [Default](DEBUG): Aggregate::initialize Default
2019-05-01T20:59:02.831Z,1556744342.831 [Default:B.GoToSurface] Running Loop=1
2019-05-01T20:59:02.831Z,1556744342.831 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-01T20:59:02.831Z,1556744342.831 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-01T20:59:02.831Z,1556744342.831 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-01T20:59:02.831Z,1556744342.831 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-01T20:59:02.832Z,1556744342.832 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-01T20:59:02.832Z,1556744342.832 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-01T20:59:02.832Z,1556744342.832 [Default:A.Wait] Running Loop=1
2019-05-01T20:59:02.832Z,1556744342.832 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-01T20:59:09.810Z,1556744349.810 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-01T20:59:09.810Z,1556744349.810 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-01T20:59:09.810Z,1556744349.810 [CTD_Seabird](ERROR): Communications Fault
2019-05-01T20:59:10.065Z,1556744350.065 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-01T20:59:10.065Z,1556744350.065 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-01T20:59:10.065Z,1556744350.065 [Aanderaa_O2](ERROR): Communications Fault
2019-05-01T20:59:10.130Z,1556744350.130 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-01T20:59:10.130Z,1556744350.130 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-01T20:59:10.214Z,1556744350.214 [CTD_Seabird](INFO): Powering down
2019-05-01T20:59:10.589Z,1556744350.589 [Aanderaa_O2](INFO): Powering down
2019-05-01T20:59:11.314Z,1556744351.314 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-01T20:59:11.314Z,1556744351.314 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-01T20:59:11.314Z,1556744351.314 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-01T20:59:11.314Z,1556744351.314 [CTD_Seabird] No Fault, FailCount= 1
2019-05-01T20:59:11.557Z,1556744351.557 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-01T20:59:11.559Z,1556744351.559 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-01T20:59:16.154Z,1556744356.154 [Default:A.Wait](INFO): Done Waiting.
2019-05-01T20:59:16.154Z,1556744356.154 [Default:A.Wait] Stopped
2019-05-01T20:59:16.154Z,1556744356.154 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-01T20:59:16.554Z,1556744356.554 [Default:CheckIn] Running Loop=1
2019-05-01T20:59:16.554Z,1556744356.554 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-01T20:59:16.554Z,1556744356.554 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-01T20:59:16.967Z,1556744356.967 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-01T20:59:21.781Z,1556744361.781 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-01T20:59:21.781Z,1556744361.781 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-01T20:59:21.781Z,1556744361.781 [Aanderaa_O2](ERROR): Communications Fault
2019-05-01T20:59:21.812Z,1556744361.812 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-01T20:59:21.813Z,1556744361.813 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-01T20:59:22.305Z,1556744362.305 [Aanderaa_O2](INFO): Powering down
2019-05-01T20:59:24.609Z,1556744364.609 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-01T20:59:24.609Z,1556744364.609 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-01T20:59:24.609Z,1556744364.609 [CTD_Seabird](ERROR): Communications Fault
2019-05-01T20:59:24.680Z,1556744364.680 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-01T20:59:25.013Z,1556744365.013 [CTD_Seabird](INFO): Powering down
2019-05-01T20:59:26.255Z,1556744366.255 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-01T20:59:26.256Z,1556744366.256 [CTD_Seabird] No Fault, FailCount= 2
2019-05-01T20:59:26.357Z,1556744366.357 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-01T20:59:26.359Z,1556744366.359 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-01T20:59:38.711Z,1556744378.711 [DataOverHttps](INFO): Sending 1206 bytes from file Logs/20190501T205533/Express0001.lzma
2019-05-01T20:59:39.409Z,1556744379.409 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-01T20:59:39.409Z,1556744379.409 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-01T20:59:39.409Z,1556744379.409 [CTD_Seabird](ERROR): Communications Fault
2019-05-01T20:59:39.514Z,1556744379.514 [DataOverHttps](INFO): Moved sent file to Logs/20190501T205533/Express0001.lzma.bak
2019-05-01T20:59:39.514Z,1556744379.514 [DataOverHttps](INFO): SBD MOMSN=10892652
2019-05-01T20:59:39.595Z,1556744379.595 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-01T20:59:39.595Z,1556744379.595 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-01T20:59:39.813Z,1556744379.813 [CTD_Seabird](INFO): Powering down
2019-05-01T20:59:45.265Z,1556744385.265 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-01T20:59:45.265Z,1556744385.265 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-01T20:59:45.265Z,1556744385.265 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-01T20:59:45.281Z,1556744385.281 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-01T20:59:45.281Z,1556744385.281 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-05-01T20:59:45.281Z,1556744385.281 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-01T20:59:45.283Z,1556744385.283 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-01T20:59:45.283Z,1556744385.283 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-05-01T20:59:45.283Z,1556744385.283 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-05-01T20:59:45.428Z,1556744385.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-01T20:59:45.428Z,1556744385.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-01T20:59:45.428Z,1556744385.428 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-05-01T20:59:45.673Z,1556744385.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-01T20:59:45.673Z,1556744385.673 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-01T20:59:45.673Z,1556744385.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-01T20:59:45.674Z,1556744385.674 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-05-01T20:59:45.674Z,1556744385.674 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-05-01T20:59:45.674Z,1556744385.674 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-05-01T20:59:46.045Z,1556744386.045 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-01T21:00:52.347Z,1556744452.347 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-01T21:00:52.347Z,1556744452.347 [BR_Ping1D] No Fault, FailCount= 5
2019-05-01T21:00:52.713Z,1556744452.713 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T21:00:52.713Z,1556744452.713 [BR_Ping1D] Communications Fault, FailCount= 1
2019-05-01T21:00:52.713Z,1556744452.713 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T21:00:52.793Z,1556744452.793 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T21:00:53.107Z,1556744453.107 [BR_Ping1D](INFO): Powering down
2019-05-01T21:00:54.339Z,1556744454.339 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-01T21:00:54.339Z,1556744454.339 [BR_Ping1D] No Fault, FailCount= 1
2019-05-01T21:00:54.738Z,1556744454.738 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T21:00:54.738Z,1556744454.738 [BR_Ping1D] Communications Fault, FailCount= 2
2019-05-01T21:00:54.738Z,1556744454.738 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T21:00:54.762Z,1556744454.762 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T21:00:55.124Z,1556744455.124 [BR_Ping1D](INFO): Powering down
2019-05-01T21:00:56.359Z,1556744456.359 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-01T21:00:56.359Z,1556744456.359 [BR_Ping1D] No Fault, FailCount= 2
2019-05-01T21:00:56.767Z,1556744456.767 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T21:00:56.767Z,1556744456.767 [BR_Ping1D] Communications Fault, FailCount= 3
2019-05-01T21:00:56.767Z,1556744456.767 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T21:00:56.782Z,1556744456.782 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T21:00:57.156Z,1556744457.156 [BR_Ping1D](INFO): Powering down
2019-05-01T21:00:58.372Z,1556744458.372 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-01T21:00:58.372Z,1556744458.372 [BR_Ping1D] No Fault, FailCount= 3
2019-05-01T21:00:58.761Z,1556744458.761 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T21:00:58.761Z,1556744458.761 [BR_Ping1D] Communications Fault, FailCount= 4
2019-05-01T21:00:58.761Z,1556744458.761 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T21:00:58.776Z,1556744458.776 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T21:00:59.169Z,1556744459.169 [BR_Ping1D](INFO): Powering down
2019-05-01T21:01:00.413Z,1556744460.413 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-01T21:01:00.413Z,1556744460.413 [BR_Ping1D] No Fault, FailCount= 4
2019-05-01T21:01:00.784Z,1556744460.784 [BR_Ping1D](ERROR): Error opening port: cannot open serial port
2019-05-01T21:01:00.784Z,1556744460.784 [BR_Ping1D] Communications Fault, FailCount= 5
2019-05-01T21:01:00.784Z,1556744460.784 [BR_Ping1D](ERROR): Communications Fault
2019-05-01T21:01:00.816Z,1556744460.816 [CBIT](ERROR): Communications Fault in component: BR_Ping1D
2019-05-01T21:01:00.816Z,1556744460.816 [CBIT](CRITICAL): Communications Fault in component: BR_Ping1D
2019-05-01T21:01:01.190Z,1556744461.190 [BR_Ping1D](INFO): Powering down
2019-05-01T21:01:01.204Z,1556744461.204 [CBIT](INFO): Critical error at 20190501T210100
2019-05-01T21:01:25.417Z,1556744485.417 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-05-01T21:01:26.229Z,1556744486.229 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:29.503Z,1556744489.503 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-01T21:01:29.503Z,1556744489.503 [PNI_TCM] No Fault, FailCount= 5
2019-05-01T21:01:30.270Z,1556744490.270 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:33.101Z,1556744493.101 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:36.329Z,1556744496.329 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:37.152Z,1556744497.152 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T21:01:37.152Z,1556744497.152 [PNI_TCM] Data Fault, FailCount= 1
2019-05-01T21:01:37.152Z,1556744497.152 [PNI_TCM](ERROR): Data Fault
2019-05-01T21:01:37.175Z,1556744497.175 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T21:01:37.545Z,1556744497.545 [PNI_TCM](INFO): Powering down
2019-05-01T21:01:38.775Z,1556744498.775 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-01T21:01:38.775Z,1556744498.775 [PNI_TCM] No Fault, FailCount= 1
2019-05-01T21:01:39.157Z,1556744499.157 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:42.393Z,1556744502.393 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:45.221Z,1556744505.221 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:46.051Z,1556744506.051 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-01T21:01:46.051Z,1556744506.051 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-05-01T21:01:46.051Z,1556744506.051 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-01T21:01:46.077Z,1556744506.077 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-01T21:01:46.078Z,1556744506.078 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-05-01T21:01:46.078Z,1556744506.078 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-05-01T21:01:46.079Z,1556744506.079 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-01T21:01:46.079Z,1556744506.079 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-05-01T21:01:46.079Z,1556744506.079 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-05-01T21:01:46.100Z,1556744506.100 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-01T21:01:46.100Z,1556744506.100 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-05-01T21:01:46.100Z,1556744506.100 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-05-01T21:01:46.433Z,1556744506.433 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T21:01:46.433Z,1556744506.433 [PNI_TCM] Data Fault, FailCount= 2
2019-05-01T21:01:46.433Z,1556744506.433 [PNI_TCM](ERROR): Data Fault
2019-05-01T21:01:46.453Z,1556744506.453 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-01T21:01:46.454Z,1556744506.454 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-05-01T21:01:46.454Z,1556744506.454 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-05-01T21:01:46.454Z,1556744506.454 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-05-01T21:01:46.454Z,1556744506.454 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-05-01T21:01:46.455Z,1556744506.455 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-05-01T21:01:46.455Z,1556744506.455 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T21:01:46.836Z,1556744506.836 [PNI_TCM](INFO): Powering down
2019-05-01T21:01:46.936Z,1556744506.936 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-05-01T21:01:48.045Z,1556744508.045 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:48.088Z,1556744508.088 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-01T21:01:48.088Z,1556744508.088 [PNI_TCM] No Fault, FailCount= 2
2019-05-01T21:01:50.069Z,1556744510.069 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:52.089Z,1556744512.089 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:52.124Z,1556744512.124 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-01T21:01:52.124Z,1556744512.124 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-01T21:01:55.321Z,1556744515.321 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:01:55.746Z,1556744515.746 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T21:01:55.746Z,1556744515.746 [PNI_TCM] Data Fault, FailCount= 3
2019-05-01T21:01:55.746Z,1556744515.746 [PNI_TCM](ERROR): Data Fault
2019-05-01T21:01:55.761Z,1556744515.761 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T21:01:56.139Z,1556744516.139 [PNI_TCM](INFO): Powering down
2019-05-01T21:01:57.359Z,1556744517.359 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-01T21:01:57.359Z,1556744517.359 [PNI_TCM] No Fault, FailCount= 3
2019-05-01T21:01:58.149Z,1556744518.149 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:01.377Z,1556744521.377 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:02.573Z,1556744522.573 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-01T21:02:02.573Z,1556744522.573 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-01T21:02:02.573Z,1556744522.573 [Aanderaa_O2](ERROR): Communications Fault
2019-05-01T21:02:02.631Z,1556744522.631 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-01T21:02:03.097Z,1556744523.097 [Aanderaa_O2](INFO): Powering down
2019-05-01T21:02:03.822Z,1556744523.822 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-01T21:02:03.822Z,1556744523.822 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-01T21:02:04.205Z,1556744524.205 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:05.035Z,1556744525.035 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T21:02:05.035Z,1556744525.035 [PNI_TCM] Data Fault, FailCount= 4
2019-05-01T21:02:05.035Z,1556744525.035 [PNI_TCM](ERROR): Data Fault
2019-05-01T21:02:05.052Z,1556744525.052 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T21:02:05.421Z,1556744525.421 [PNI_TCM](INFO): Powering down
2019-05-01T21:02:06.651Z,1556744526.651 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-01T21:02:06.651Z,1556744526.651 [PNI_TCM] No Fault, FailCount= 4
2019-05-01T21:02:07.437Z,1556744527.437 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:09.490Z,1556744529.490 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-01T21:02:09.490Z,1556744529.490 [CTD_Seabird] No Fault, FailCount= 3
2019-05-01T21:02:09.829Z,1556744529.829 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-01T21:02:09.831Z,1556744529.831 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-01T21:02:10.265Z,1556744530.265 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:13.097Z,1556744533.097 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:14.293Z,1556744534.293 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-01T21:02:14.293Z,1556744534.293 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-01T21:02:14.294Z,1556744534.294 [Aanderaa_O2](ERROR): Communications Fault
2019-05-01T21:02:14.317Z,1556744534.317 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-01T21:02:14.317Z,1556744534.317 [PNI_TCM] Data Fault, FailCount= 5
2019-05-01T21:02:14.317Z,1556744534.317 [PNI_TCM](ERROR): Data Fault
2019-05-01T21:02:14.339Z,1556744534.339 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-01T21:02:14.339Z,1556744534.339 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-01T21:02:14.339Z,1556744534.339 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-05-01T21:02:14.813Z,1556744534.813 [Aanderaa_O2](INFO): Powering down
2019-05-01T21:02:14.837Z,1556744534.837 [PNI_TCM](INFO): Powering down
2019-05-01T21:02:14.940Z,1556744534.940 [CBIT](INFO): Critical error at 20190501T210214
2019-05-01T21:02:15.117Z,1556744535.117 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:15.553Z,1556744535.553 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-01T21:02:15.553Z,1556744535.553 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-01T21:02:17.137Z,1556744537.137 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:20.365Z,1556744540.365 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:22.881Z,1556744542.881 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-01T21:02:22.881Z,1556744542.881 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-01T21:02:22.881Z,1556744542.881 [CTD_Seabird](ERROR): Communications Fault
2019-05-01T21:02:23.193Z,1556744543.193 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:23.228Z,1556744543.228 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-01T21:02:23.285Z,1556744543.285 [CTD_Seabird](INFO): Powering down
2019-05-01T21:02:24.419Z,1556744544.419 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-01T21:02:24.419Z,1556744544.419 [CTD_Seabird] No Fault, FailCount= 1
2019-05-01T21:02:24.625Z,1556744544.625 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-01T21:02:24.627Z,1556744544.627 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-01T21:02:26.005Z,1556744546.005 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-01T21:02:26.005Z,1556744546.005 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-01T21:02:26.005Z,1556744546.005 [Aanderaa_O2](ERROR): Communications Fault
2019-05-01T21:02:26.058Z,1556744546.058 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-01T21:02:26.058Z,1556744546.058 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-01T21:02:26.529Z,1556744546.529 [Aanderaa_O2](INFO): Powering down
2019-05-01T21:02:26.557Z,1556744546.557 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:29.261Z,1556744549.261 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:32.489Z,1556744552.489 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:35.317Z,1556744555.317 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:36.707Z,1556744556.707 [CommandLine](IMPORTANT): got command restart application
2019-05-01T21:02:37.677Z,1556744557.677 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-01T21:02:37.677Z,1556744557.677 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-01T21:02:37.677Z,1556744557.677 [CTD_Seabird](ERROR): Communications Fault
2019-05-01T21:02:37.709Z,1556744557.709 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-01T21:02:37.709Z,1556744557.709 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:37.763Z,1556744557.763 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-01T21:02:37.837Z,1556744557.837 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-01T21:02:37.837Z,1556744557.837 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:37.838Z,1556744557.838 [CommandLine](INFO): Join timeout helper Thread ID is 4495
2019-05-01T21:02:37.845Z,1556744557.845 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-01T21:02:37.845Z,1556744557.845 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:37.846Z,1556744557.846 [NavChartDb](INFO): Join timeout helper Thread ID is 4496
2019-05-01T21:02:37.925Z,1556744557.925 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-01T21:02:37.925Z,1556744557.925 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:37.933Z,1556744557.933 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-01T21:02:37.933Z,1556744557.933 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:37.933Z,1556744557.933 [Radio_Surface](INFO): Join timeout helper Thread ID is 4497
2019-05-01T21:02:38.081Z,1556744558.081 [CTD_Seabird](INFO): Powering down
2019-05-01T21:02:38.141Z,1556744558.141 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:38.293Z,1556744558.293 [Radio_Surface](INFO): Powering down
2019-05-01T21:02:38.294Z,1556744558.294 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-01T21:02:38.294Z,1556744558.294 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:38.305Z,1556744558.305 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-01T21:02:38.305Z,1556744558.305 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:38.305Z,1556744558.305 [DataOverHttps](INFO): Join timeout helper Thread ID is 4498
2019-05-01T21:02:39.383Z,1556744559.383 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-01T21:02:39.383Z,1556744559.383 [CTD_Seabird] No Fault, FailCount= 2
2019-05-01T21:02:39.425Z,1556744559.425 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-01T21:02:39.433Z,1556744559.433 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-01T21:02:39.513Z,1556744559.513 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-01T21:02:39.516Z,1556744559.516 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:39.517Z,1556744559.517 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-01T21:02:39.517Z,1556744559.517 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:39.518Z,1556744559.518 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4500
2019-05-01T21:02:39.997Z,1556744559.997 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-01T21:02:39.998Z,1556744559.998 [WetLabsBB2FL](INFO): Powering down
2019-05-01T21:02:39.998Z,1556744559.998 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.006Z,1556744560.006 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-05-01T21:02:40.006Z,1556744560.006 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.006Z,1556744560.006 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4501
2019-05-01T21:02:40.161Z,1556744560.161 [NAL9602](DEBUG): Fix Requested
2019-05-01T21:02:40.429Z,1556744560.429 [CTD_Seabird](INFO): Powering down
2019-05-01T21:02:40.561Z,1556744560.561 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-01T21:02:40.561Z,1556744560.561 [CTD_Seabird](INFO): Powering down
2019-05-01T21:02:40.573Z,1556744560.573 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.578Z,1556744560.578 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-01T21:02:40.578Z,1556744560.578 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.578Z,1556744560.578 [logger](INFO): Join timeout helper Thread ID is 4502
2019-05-01T21:02:40.585Z,1556744560.585 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-01T21:02:40.586Z,1556744560.586 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.598Z,1556744560.598 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-01T21:02:40.598Z,1556744560.598 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.598Z,1556744560.598 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-01T21:02:40.598Z,1556744560.598 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.599Z,1556744560.599 [controlThread](INFO): Join timeout helper Thread ID is 4503
2019-05-01T21:02:40.614Z,1556744560.614 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-01T21:02:40.614Z,1556744560.614 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-01T21:02:40.614Z,1556744560.614 [Aanderaa_O2](INFO): Powering down
2019-05-01T21:02:40.617Z,1556744560.617 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-01T21:02:40.618Z,1556744560.618 [NAL9602](INFO): Powering down
2019-05-01T21:02:40.619Z,1556744560.619 [PNI_TCM](INFO): Powering down
2019-05-01T21:02:40.620Z,1556744560.620 [BR_Ping1D](INFO): Powering down
2019-05-01T21:02:40.621Z,1556744560.621 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-01T21:02:40.622Z,1556744560.622 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-01T21:02:40.623Z,1556744560.623 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-01T21:02:40.623Z,1556744560.623 [MissionManager](INFO): Uninitializing Mission Default
2019-05-01T21:02:40.623Z,1556744560.623 [Default] Stopped
2019-05-01T21:02:40.624Z,1556744560.624 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-01T21:02:40.624Z,1556744560.624 [Default:B.GoToSurface] Stopped
2019-05-01T21:02:40.624Z,1556744560.624 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-01T21:02:40.624Z,1556744560.624 [Default:CheckIn] Stopped
2019-05-01T21:02:40.624Z,1556744560.624 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-01T21:02:40.624Z,1556744560.624 [Default:CheckIn:Read_GPS] Stopped
2019-05-01T21:02:40.626Z,1556744560.626 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-01T21:02:40.627Z,1556744560.627 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-01T21:02:40.627Z,1556744560.627 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-01T21:02:40.627Z,1556744560.627 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-01T21:02:40.627Z,1556744560.627 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-01T21:02:40.627Z,1556744560.627 [BuoyancyServo](INFO): Powering down
2019-05-01T21:02:40.641Z,1556744560.641 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-01T21:02:40.641Z,1556744560.641 [ElevatorServo](INFO): Powering down
2019-05-01T21:02:40.642Z,1556744560.642 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-01T21:02:40.642Z,1556744560.642 [MassServo](INFO): Powering down
2019-05-01T21:02:40.643Z,1556744560.643 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-01T21:02:40.643Z,1556744560.643 [RudderServo](INFO): Powering down
2019-05-01T21:02:40.644Z,1556744560.644 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-01T21:02:40.644Z,1556744560.644 [ThrusterServo](INFO): Powering down
2019-05-01T21:02:40.645Z,1556744560.645 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-01T21:02:40.645Z,1556744560.645 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-01T21:02:40.645Z,1556744560.645 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-01T21:02:40.645Z,1556744560.645 [CBIT](DEBUG): Powering off loads.
2019-05-01T21:02:40.657Z,1556744560.657 [CBIT](DEBUG): Disabling WDT.
2019-05-01T21:02:40.669Z,1556744560.669 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-01T21:02:40.670Z,1556744560.670 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.704Z,1556744560.704 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.709Z,1556744560.709 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.776Z,1556744560.776 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.780Z,1556744560.780 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.828Z,1556744560.828 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-01T21:02:40.885Z,1556744560.885 [logger ThreadHandler](INFO): Thread cancelled.