2019-04-09T15:39:52.462Z,1554824392.462 [Supervisor](DEBUG): Initializing supervisor. 2019-04-09T15:39:52.465Z,1554824392.465 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-04-09T15:39:52.466Z,1554824392.466 [SyncHandler](INFO): Protected caller Thread ID is 2968 2019-04-09T15:39:52.466Z,1554824392.466 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-04-09T15:39:52.467Z,1554824392.467 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-04-09T15:39:52.467Z,1554824392.467 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2969 2019-04-09T15:39:52.470Z,1554824392.470 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-04-09T15:39:52.482Z,1554824392.482 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-04-09T15:39:52.483Z,1554824392.483 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-04-09T15:39:52.483Z,1554824392.483 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2970 2019-04-09T15:39:52.484Z,1554824392.484 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-04-09T15:39:52.485Z,1554824392.485 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-04-09T15:39:52.486Z,1554824392.486 [logger ThreadHandler](INFO): Protected caller Thread ID is 2971 2019-04-09T15:39:52.488Z,1554824392.488 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-04-09T15:39:52.488Z,1554824392.488 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-04-09T15:39:52.489Z,1554824392.490 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-04-09T15:39:52.585Z,1554824392.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-04-09T15:39:52.585Z,1554824392.585 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-04-09T15:39:52.789Z,1554824392.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-04-09T15:39:52.789Z,1554824392.789 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-04-09T15:39:52.927Z,1554824392.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-04-09T15:39:52.928Z,1554824392.928 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-04-09T15:39:53.394Z,1554824393.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-04-09T15:39:53.394Z,1554824393.394 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-04-09T15:39:53.846Z,1554824393.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-04-09T15:39:53.847Z,1554824393.847 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-04-09T15:39:54.326Z,1554824394.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-04-09T15:39:54.327Z,1554824394.327 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-04-09T15:39:54.637Z,1554824394.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-04-09T15:39:54.637Z,1554824394.637 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-04-09T15:39:54.986Z,1554824394.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-04-09T15:39:54.987Z,1554824394.987 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-04-09T15:39:55.391Z,1554824395.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-04-09T15:39:55.391Z,1554824395.391 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-04-09T15:39:55.543Z,1554824395.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-04-09T15:39:55.544Z,1554824395.544 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-04-09T15:39:55.651Z,1554824395.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-04-09T15:39:55.651Z,1554824395.651 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-04-09T15:39:55.732Z,1554824395.732 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-04-09T15:39:55.834Z,1554824395.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-04-09T15:39:55.834Z,1554824395.834 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-04-09T15:39:56.101Z,1554824396.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-04-09T15:39:56.102Z,1554824396.102 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-04-09T15:39:56.636Z,1554824396.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-04-09T15:39:56.638Z,1554824396.638 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-04-09T15:39:56.639Z,1554824396.639 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-04-09T15:39:56.769Z,1554824396.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-04-09T15:39:57.013Z,1554824397.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-04-09T15:39:57.014Z,1554824397.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-04-09T15:39:57.109Z,1554824397.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-04-09T15:39:57.284Z,1554824397.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-04-09T15:39:57.499Z,1554824397.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-04-09T15:39:57.584Z,1554824397.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-04-09T15:39:57.682Z,1554824397.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-04-09T15:39:57.775Z,1554824397.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-04-09T15:39:57.927Z,1554824397.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-04-09T15:39:58.032Z,1554824398.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-04-09T15:39:58.129Z,1554824398.129 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-04-09T15:39:58.133Z,1554824398.133 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-04-09T15:39:58.242Z,1554824398.242 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-04-09T15:39:58.242Z,1554824398.242 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-04-09T15:39:58.377Z,1554824398.377 [BuoyancyServo] Loaded 2019-04-09T15:39:58.377Z,1554824398.377 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-04-09T15:39:58.393Z,1554824398.393 [ElevatorServo] Loaded 2019-04-09T15:39:58.393Z,1554824398.393 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-04-09T15:39:58.409Z,1554824398.409 [MassServo] Loaded 2019-04-09T15:39:58.409Z,1554824398.409 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-04-09T15:39:58.425Z,1554824398.425 [RudderServo] Loaded 2019-04-09T15:39:58.425Z,1554824398.425 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-04-09T15:39:58.440Z,1554824398.440 [ThrusterServo] Loaded 2019-04-09T15:39:58.440Z,1554824398.440 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-04-09T15:39:58.441Z,1554824398.441 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-04-09T15:39:58.441Z,1554824398.441 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-04-09T15:39:58.590Z,1554824398.590 [SBIT](DEBUG): Construct Startup Built In Test. 2019-04-09T15:39:58.619Z,1554824398.619 [SBIT] Loaded 2019-04-09T15:39:58.619Z,1554824398.619 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-04-09T15:39:58.620Z,1554824398.620 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-04-09T15:39:58.649Z,1554824398.649 [IBIT] Loaded 2019-04-09T15:39:58.649Z,1554824398.649 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-04-09T15:39:58.652Z,1554824398.652 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-04-09T15:39:59.060Z,1554824399.060 [CBIT] Loaded 2019-04-09T15:39:59.060Z,1554824399.060 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-04-09T15:39:59.061Z,1554824399.061 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-04-09T15:39:59.061Z,1554824399.061 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-04-09T15:39:59.259Z,1554824399.259 [Aanderaa_O2] Loaded 2019-04-09T15:39:59.260Z,1554824399.260 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-04-09T15:39:59.269Z,1554824399.269 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-04-09T15:39:59.275Z,1554824399.275 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-04-09T15:39:59.277Z,1554824399.277 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-04-09T15:39:59.282Z,1554824399.282 [CTD_Seabird](INFO): created writer for : depth 2019-04-09T15:39:59.282Z,1554824399.282 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-04-09T15:39:59.288Z,1554824399.288 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-04-09T15:39:59.289Z,1554824399.289 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-04-09T15:39:59.294Z,1554824399.294 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-04-09T15:39:59.295Z,1554824399.295 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-04-09T15:39:59.300Z,1554824399.300 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-04-09T15:39:59.301Z,1554824399.301 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-04-09T15:39:59.307Z,1554824399.307 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-04-09T15:39:59.307Z,1554824399.307 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-04-09T15:39:59.313Z,1554824399.313 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-04-09T15:39:59.341Z,1554824399.341 [CTD_Seabird] Loaded 2019-04-09T15:39:59.341Z,1554824399.341 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-04-09T15:39:59.342Z,1554824399.342 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0 2019-04-09T15:39:59.343Z,1554824399.343 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3050 2019-04-09T15:39:59.369Z,1554824399.369 [ESPComponent] Loaded 2019-04-09T15:39:59.369Z,1554824399.369 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-04-09T15:39:59.384Z,1554824399.384 [PAR_Licor] Loaded 2019-04-09T15:39:59.384Z,1554824399.384 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-04-09T15:39:59.434Z,1554824399.434 [WetLabsBB2FL] Loaded 2019-04-09T15:39:59.434Z,1554824399.434 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-04-09T15:39:59.435Z,1554824399.435 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0 2019-04-09T15:39:59.436Z,1554824399.436 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3051 2019-04-09T15:39:59.437Z,1554824399.437 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-04-09T15:39:59.437Z,1554824399.437 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-04-09T15:39:59.480Z,1554824399.480 [DepthRateCalculator] Loaded 2019-04-09T15:39:59.481Z,1554824399.481 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-04-09T15:39:59.487Z,1554824399.487 [PitchRateCalculator] Loaded 2019-04-09T15:39:59.487Z,1554824399.487 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-04-09T15:39:59.504Z,1554824399.504 [SpeedCalculator] Loaded 2019-04-09T15:39:59.504Z,1554824399.504 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-04-09T15:39:59.527Z,1554824399.527 [TempGradientCalculator] Loaded 2019-04-09T15:39:59.527Z,1554824399.527 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-04-09T15:39:59.533Z,1554824399.533 [YawRateCalculator] Loaded 2019-04-09T15:39:59.533Z,1554824399.533 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-04-09T15:39:59.567Z,1554824399.567 [ElevatorOffsetCalculator] Loaded 2019-04-09T15:39:59.568Z,1554824399.568 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-04-09T15:39:59.568Z,1554824399.568 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-04-09T15:39:59.569Z,1554824399.569 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-04-09T15:39:59.597Z,1554824399.597 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-04-09T15:39:59.597Z,1554824399.597 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-04-09T15:39:59.705Z,1554824399.705 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-04-09T15:39:59.706Z,1554824399.706 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-04-09T15:39:59.768Z,1554824399.768 [DeadReckonUsingSpeedCalculator] Loaded 2019-04-09T15:39:59.769Z,1554824399.769 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-04-09T15:39:59.831Z,1554824399.831 [DeadReckonWithRespectToSeafloor] Loaded 2019-04-09T15:39:59.832Z,1554824399.832 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-04-09T15:39:59.853Z,1554824399.853 [NavChart] Loaded 2019-04-09T15:39:59.853Z,1554824399.853 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-04-09T15:39:59.858Z,1554824399.858 [UniversalFixResidualReporter] Loaded 2019-04-09T15:39:59.858Z,1554824399.858 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-04-09T15:39:59.858Z,1554824399.858 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-04-09T15:39:59.859Z,1554824399.859 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-04-09T15:40:00.193Z,1554824400.193 [AcousticModem_Benthos_ATM900] Loaded 2019-04-09T15:40:00.194Z,1554824400.194 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2019-04-09T15:40:00.266Z,1554824400.266 [DataOverHttps] Loaded 2019-04-09T15:40:00.266Z,1554824400.266 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-04-09T15:40:00.267Z,1554824400.267 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0 2019-04-09T15:40:00.268Z,1554824400.268 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3052 2019-04-09T15:40:00.281Z,1554824400.281 [Depth_Keller] Loaded 2019-04-09T15:40:00.282Z,1554824400.282 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-04-09T15:40:00.379Z,1554824400.379 [NAL9602] Loaded 2019-04-09T15:40:00.379Z,1554824400.379 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-04-09T15:40:00.385Z,1554824400.385 [Onboard] Loaded 2019-04-09T15:40:00.385Z,1554824400.385 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-04-09T15:40:00.388Z,1554824400.388 [Radio_Surface] Loaded 2019-04-09T15:40:00.389Z,1554824400.389 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-04-09T15:40:00.390Z,1554824400.390 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0 2019-04-09T15:40:00.390Z,1554824400.390 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3053 2019-04-09T15:40:02.149Z,1554824402.149 [BPC1] Loaded 2019-04-09T15:40:02.149Z,1554824402.149 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-04-09T15:40:02.181Z,1554824402.181 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange 2019-04-09T15:40:02.183Z,1554824402.183 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange 2019-04-09T15:40:02.185Z,1554824402.185 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed 2019-04-09T15:40:02.187Z,1554824402.187 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode 2019-04-09T15:40:02.190Z,1554824402.190 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex 2019-04-09T15:40:02.192Z,1554824402.192 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence 2019-04-09T15:40:02.194Z,1554824402.194 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable 2019-04-09T15:40:02.196Z,1554824402.196 [BR_Ping1D] Loaded 2019-04-09T15:40:02.196Z,1554824402.196 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread. 2019-04-09T15:40:02.197Z,1554824402.197 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-04-09T15:40:02.197Z,1554824402.197 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-04-09T15:40:02.267Z,1554824402.267 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-04-09T15:40:02.267Z,1554824402.267 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-04-09T15:40:02.340Z,1554824402.340 [VerticalControl](DEBUG): Construct VerticalControl. 2019-04-09T15:40:02.427Z,1554824402.427 [VerticalControl] Loaded 2019-04-09T15:40:02.428Z,1554824402.428 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-04-09T15:40:02.428Z,1554824402.428 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-04-09T15:40:02.488Z,1554824402.488 [HorizontalControl] Loaded 2019-04-09T15:40:02.488Z,1554824402.488 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-04-09T15:40:02.489Z,1554824402.489 [SpeedControl](DEBUG): Construct SpeedControl. 2019-04-09T15:40:02.491Z,1554824402.491 [SpeedControl] Loaded 2019-04-09T15:40:02.491Z,1554824402.491 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-04-09T15:40:02.492Z,1554824402.492 [LoopControl](DEBUG): Construct LoopControl. 2019-04-09T15:40:02.492Z,1554824402.492 [LoopControl] Loaded 2019-04-09T15:40:02.492Z,1554824402.492 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-04-09T15:40:02.493Z,1554824402.493 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-04-09T15:40:02.493Z,1554824402.493 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-04-09T15:40:02.507Z,1554824402.507 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-04-09T15:40:02.508Z,1554824402.508 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-04-09T15:40:02.811Z,1554824402.811 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-04-09T15:40:02.815Z,1554824402.815 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-04-09T15:40:02.816Z,1554824402.816 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-04-09T15:40:02.822Z,1554824402.822 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-04-09T15:40:02.823Z,1554824402.823 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0 2019-04-09T15:40:02.824Z,1554824402.824 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3054 2019-04-09T15:40:02.829Z,1554824402.829 [Supervisor](INFO): Main Thread ID is 4347 2019-04-09T15:40:02.829Z,1554824402.829 [Supervisor](DEBUG): Running supervisor. 2019-04-09T15:40:02.829Z,1554824402.829 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3055 2019-04-09T15:40:02.832Z,1554824402.832 [controlThread ThreadHandler](INFO): Handler Thread ID is 3056 2019-04-09T15:40:02.832Z,1554824402.832 [controlThread](DEBUG): Initializing ControlThread 2019-04-09T15:40:02.834Z,1554824402.834 [SBIT](INFO): Initialize SBIT Component. 2019-04-09T15:40:02.834Z,1554824402.834 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc 2019-04-09T15:40:02.835Z,1554824402.835 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725 2019-04-09T15:40:02.835Z,1554824402.835 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-04-09T15:40:02.836Z,1554824402.836 [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-04-09T15:40:02.838Z,1554824402.838 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2019-04-09T15:40:02.838Z,1554824402.838 [IBIT](INFO): Initialize IBIT Component. 2019-04-09T15:40:02.839Z,1554824402.839 [CBIT](DEBUG): Initialize CBIT Component. 2019-04-09T15:40:02.840Z,1554824402.840 [logger ThreadHandler](INFO): Handler Thread ID is 3057 2019-04-09T15:40:02.850Z,1554824402.850 [CBIT](DEBUG): Initialized mux pins. 2019-04-09T15:40:02.850Z,1554824402.850 [CBIT](DEBUG): Initializing the watchdog timer. 2019-04-09T15:40:02.858Z,1554824402.858 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3058 2019-04-09T15:40:02.859Z,1554824402.859 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-04-09T15:40:02.863Z,1554824402.863 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-04-09T15:40:02.864Z,1554824402.864 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3060 2019-04-09T15:40:02.865Z,1554824402.865 [WetLabsBB2FL](INFO): Powering down 2019-04-09T15:40:02.875Z,1554824402.875 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-04-09T15:40:02.875Z,1554824402.875 [CBIT](DEBUG): Initializing heartbeat. 2019-04-09T15:40:02.894Z,1554824402.894 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3061 2019-04-09T15:40:02.895Z,1554824402.895 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-04-09T15:40:02.904Z,1554824402.904 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3062 2019-04-09T15:40:02.922Z,1554824402.922 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3063 2019-04-09T15:40:02.925Z,1554824402.925 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-04-09T15:40:02.926Z,1554824402.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-04-09T15:40:02.926Z,1554824402.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-04-09T15:40:02.926Z,1554824402.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-04-09T15:40:02.926Z,1554824402.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-04-09T15:40:02.926Z,1554824402.926 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-04-09T15:40:02.927Z,1554824402.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-04-09T15:40:02.927Z,1554824402.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-04-09T15:40:02.927Z,1554824402.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-04-09T15:40:02.927Z,1554824402.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-04-09T15:40:02.927Z,1554824402.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-04-09T15:40:02.928Z,1554824402.928 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-04-09T15:40:02.928Z,1554824402.928 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-04-09T15:40:02.928Z,1554824402.928 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-04-09T15:40:02.928Z,1554824402.928 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-04-09T15:40:02.928Z,1554824402.928 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-04-09T15:40:02.946Z,1554824402.946 [CBIT](DEBUG): Deactivating GF circuits. 2019-04-09T15:40:02.946Z,1554824402.946 [CBIT](DEBUG): Deactivating emergency mode. 2019-04-09T15:40:02.982Z,1554824402.982 [CBIT](DEBUG): Backplane powered. 2019-04-09T15:40:02.986Z,1554824402.986 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-04-09T15:40:02.987Z,1554824402.987 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-04-09T15:40:02.987Z,1554824402.987 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-04-09T15:40:02.987Z,1554824402.987 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-04-09T15:40:02.988Z,1554824402.988 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-04-09T15:40:02.988Z,1554824402.988 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-04-09T15:40:02.989Z,1554824402.989 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-04-09T15:40:02.990Z,1554824402.990 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-04-09T15:40:02.991Z,1554824402.991 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-04-09T15:40:02.995Z,1554824402.995 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-04-09T15:40:02.997Z,1554824402.997 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-04-09T15:40:02.998Z,1554824402.998 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-04-09T15:40:02.998Z,1554824402.998 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-04-09T15:40:02.999Z,1554824402.999 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-04-09T15:40:03.021Z,1554824403.021 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-04-09T15:40:03.066Z,1554824403.066 [MissionManager](DEBUG): 2019-04-09T15:40:03.067Z,1554824403.067 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-04-09T15:40:03.146Z,1554824403.146 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-04-09T15:40:03.147Z,1554824403.147 [Default:A.Wait](DEBUG): Construct Wait. 2019-04-09T15:40:03.149Z,1554824403.149 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-04-09T15:40:03.191Z,1554824403.191 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-04-09T15:40:03.194Z,1554824403.194 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-04-09T15:40:03.231Z,1554824403.231 [Default:E.Execute](DEBUG): Construct Execute. 2019-04-09T15:40:03.234Z,1554824403.234 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-04-09T15:40:03.254Z,1554824403.254 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,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-04-09T15:40:03.267Z,1554824403.267 [ESPComponent](INFO): powering down ESP 2019-04-09T15:40:03.306Z,1554824403.306 [Radio_Surface](INFO): Powering up 2019-04-09T15:40:03.686Z,1554824403.686 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms 2019-04-09T15:40:03.687Z,1554824403.687 [PAR_Licor] Data Fault, FailCount= 1 2019-04-09T15:40:03.687Z,1554824403.687 [PAR_Licor](ERROR): Data Fault 2019-04-09T15:40:03.687Z,1554824403.687 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-09T15:40:03.688Z,1554824403.688 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-09T15:40:03.712Z,1554824403.712 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar 2019-04-09T15:40:03.759Z,1554824403.759 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-04-09T15:40:03.796Z,1554824403.796 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:03.802Z,1554824403.802 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-04-09T15:40:03.804Z,1554824403.804 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:03.814Z,1554824403.814 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-04-09T15:40:03.815Z,1554824403.815 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:03.822Z,1554824403.822 [MassServo](DEBUG): Initializing MassServo. 2019-04-09T15:40:03.823Z,1554824403.823 [RudderServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:03.830Z,1554824403.830 [RudderServo](DEBUG): Initializing RudderServo. 2019-04-09T15:40:03.831Z,1554824403.831 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:03.838Z,1554824403.838 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-04-09T15:40:03.841Z,1554824403.841 [CommandLine](FAULT): Scheduling is paused 2019-04-09T15:40:03.841Z,1554824403.841 [CBIT](INFO): Critical error at 20190409T154002 2019-04-09T15:40:03.841Z,1554824403.841 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-04-09T15:40:03.842Z,1554824403.842 [CBIT](FAULT): Main Battery Failure. Count: 1 2019-04-09T15:40:03.846Z,1554824403.846 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-09T15:40:03.846Z,1554824403.846 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-09T15:40:03.846Z,1554824403.846 [PAR_Licor] No Fault, FailCount= 1 2019-04-09T15:40:04.382Z,1554824404.382 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms 2019-04-09T15:40:04.382Z,1554824404.382 [PAR_Licor] Data Fault, FailCount= 2 2019-04-09T15:40:04.382Z,1554824404.382 [PAR_Licor](ERROR): Data Fault 2019-04-09T15:40:04.411Z,1554824404.411 [BR_Ping1D](INFO): startup: 0 2019-04-09T15:40:04.411Z,1554824404.411 [BR_Ping1D](INFO): UART setting parameters ... 2019-04-09T15:40:04.422Z,1554824404.422 [BR_Ping1D](INFO): sending request for Ping Interval: 500 2019-04-09T15:40:04.422Z,1554824404.422 [BR_Ping1D](INFO): sending request for Auto Mode: 1 2019-04-09T15:40:04.423Z,1554824404.423 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000 2019-04-09T15:40:04.423Z,1554824404.423 [BR_Ping1D](INFO): sending request for Ping Enable: 1 2019-04-09T15:40:04.758Z,1554824404.758 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-04-09T15:40:04.758Z,1554824404.758 [RudderServo](FAULT): Rudder failed to initialize 2019-04-09T15:40:04.758Z,1554824404.758 [RudderServo] Communications Fault, FailCount= 1 2019-04-09T15:40:04.758Z,1554824404.758 [RudderServo](ERROR): Communications Fault 2019-04-09T15:40:04.869Z,1554824404.869 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-04-09T15:40:04.870Z,1554824404.870 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-09T15:40:04.870Z,1554824404.870 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-09T15:40:04.870Z,1554824404.870 [PAR_Licor] No Fault, FailCount= 2 2019-04-09T15:40:04.909Z,1554824404.909 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:05.059Z,1554824405.059 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-04-09T15:40:05.059Z,1554824405.059 [RudderServo](INFO): Powering down 2019-04-09T15:40:05.310Z,1554824405.310 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:05.310Z,1554824405.310 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:05.311Z,1554824405.311 [BR_Ping1D](INFO): startup: 1 2019-04-09T15:40:05.311Z,1554824405.311 [BR_Ping1D](INFO): UART requesting status 2019-04-09T15:40:05.822Z,1554824405.822 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms 2019-04-09T15:40:05.822Z,1554824405.822 [PAR_Licor] Data Fault, FailCount= 1 2019-04-09T15:40:05.822Z,1554824405.822 [PAR_Licor](ERROR): Data Fault 2019-04-09T15:40:05.843Z,1554824405.843 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:05.843Z,1554824405.843 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:05.843Z,1554824405.843 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:05.843Z,1554824405.843 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:05.844Z,1554824405.844 [BR_Ping1D](INFO): elapsed 1.4323 2019-04-09T15:40:05.844Z,1554824405.844 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:05.845Z,1554824405.845 [BR_Ping1D](INFO): received full packet, type: 1215 2019-04-09T15:40:05.845Z,1554824405.845 [BR_Ping1D](INFO): CRC error in received UART packet 2019-04-09T15:40:05.846Z,1554824405.846 [BR_Ping1D](INFO): received UART packets: 0 2019-04-09T15:40:05.846Z,1554824405.846 [BR_Ping1D](INFO): not all values verified yet 2019-04-09T15:40:05.875Z,1554824405.875 [RudderServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:05.995Z,1554824405.995 [RudderServo](DEBUG): Initializing RudderServo. 2019-04-09T15:40:05.999Z,1554824405.999 [CBIT](INFO): Clearing failed state for component RudderServo 2019-04-09T15:40:05.999Z,1554824405.999 [RudderServo] No Fault, FailCount= 1 2019-04-09T15:40:05.999Z,1554824405.999 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-09T15:40:05.000Z,1554824406.000 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-09T15:40:05.000Z,1554824406.000 [PAR_Licor] No Fault, FailCount= 1 2019-04-09T15:40:06.106Z,1554824406.106 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:06.106Z,1554824406.106 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:06.106Z,1554824406.106 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:06.106Z,1554824406.106 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:06.107Z,1554824406.107 [BR_Ping1D](INFO): elapsed 1.6953 2019-04-09T15:40:06.107Z,1554824406.107 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:06.107Z,1554824406.107 [BR_Ping1D](INFO): received full packet, type: 1203 2019-04-09T15:40:06.107Z,1554824406.107 [BR_Ping1D](INFO): parsing data, res: 14 2019-04-09T15:40:06.108Z,1554824406.108 [BR_Ping1D](INFO): Parsing UART packet of type 1203 2019-04-09T15:40:06.108Z,1554824406.108 [BR_Ping1D](INFO): No UART data 2019-04-09T15:40:06.108Z,1554824406.108 [BR_Ping1D](INFO): received UART packets: 1 2019-04-09T15:40:06.108Z,1554824406.108 [BR_Ping1D](INFO): not all values verified yet 2019-04-09T15:40:06.463Z,1554824406.463 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-09T15:40:06.463Z,1554824406.463 [MassServo] Communications Fault, FailCount= 1 2019-04-09T15:40:06.463Z,1554824406.463 [MassServo](ERROR): Communications Fault 2019-04-09T15:40:06.493Z,1554824406.493 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-09T15:40:06.544Z,1554824406.544 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:06.544Z,1554824406.544 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:06.544Z,1554824406.544 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:06.544Z,1554824406.544 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:06.544Z,1554824406.544 [BR_Ping1D](INFO): elapsed 2.1332 2019-04-09T15:40:06.545Z,1554824406.545 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:06.545Z,1554824406.545 [BR_Ping1D](INFO): No UART data 2019-04-09T15:40:06.545Z,1554824406.545 [BR_Ping1D](INFO): received UART packets: 0 2019-04-09T15:40:06.545Z,1554824406.545 [BR_Ping1D](INFO): not all values verified yet 2019-04-09T15:40:06.622Z,1554824406.622 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-09T15:40:06.622Z,1554824406.622 [MassServo](INFO): Powering down 2019-04-09T15:40:06.925Z,1554824406.925 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:06.925Z,1554824406.925 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:06.925Z,1554824406.925 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:06.926Z,1554824406.926 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:06.926Z,1554824406.926 [BR_Ping1D](INFO): elapsed 2.5145 2019-04-09T15:40:06.926Z,1554824406.926 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:06.926Z,1554824406.926 [BR_Ping1D](INFO): No UART data 2019-04-09T15:40:06.927Z,1554824406.927 [BR_Ping1D](INFO): received UART packets: 0 2019-04-09T15:40:06.927Z,1554824406.927 [BR_Ping1D](INFO): not all values verified yet 2019-04-09T15:40:07.446Z,1554824407.446 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms 2019-04-09T15:40:07.446Z,1554824407.446 [PAR_Licor] Data Fault, FailCount= 1 2019-04-09T15:40:07.446Z,1554824407.446 [PAR_Licor](ERROR): Data Fault 2019-04-09T15:40:07.467Z,1554824407.467 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:07.467Z,1554824407.467 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:07.467Z,1554824407.467 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:07.467Z,1554824407.467 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:07.468Z,1554824407.468 [BR_Ping1D](INFO): elapsed 3.0563 2019-04-09T15:40:07.468Z,1554824407.468 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:07.468Z,1554824407.468 [BR_Ping1D](INFO): No UART data 2019-04-09T15:40:07.468Z,1554824407.468 [BR_Ping1D](INFO): received UART packets: 0 2019-04-09T15:40:07.468Z,1554824407.468 [BR_Ping1D](INFO): not all values verified yet 2019-04-09T15:40:07.544Z,1554824407.544 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:07.663Z,1554824407.663 [MassServo](DEBUG): Initializing MassServo. 2019-04-09T15:40:07.814Z,1554824407.814 [CBIT](INFO): Clearing failed state for component MassServo 2019-04-09T15:40:07.814Z,1554824407.814 [MassServo] No Fault, FailCount= 1 2019-04-09T15:40:07.814Z,1554824407.814 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-09T15:40:07.815Z,1554824407.815 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-09T15:40:07.815Z,1554824407.815 [PAR_Licor] No Fault, FailCount= 1 2019-04-09T15:40:07.856Z,1554824407.856 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:07.856Z,1554824407.856 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:07.856Z,1554824407.856 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:07.856Z,1554824407.856 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:07.856Z,1554824407.856 [BR_Ping1D](INFO): elapsed 3.4452 2019-04-09T15:40:07.857Z,1554824407.857 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:07.857Z,1554824407.857 [BR_Ping1D](INFO): No UART data 2019-04-09T15:40:07.857Z,1554824407.857 [BR_Ping1D](INFO): received UART packets: 0 2019-04-09T15:40:07.857Z,1554824407.857 [BR_Ping1D](INFO): not all values verified yet 2019-04-09T15:40:07.901Z,1554824407.901 [CBIT](FAULT): Main Battery Failure. Count: 11 2019-04-09T15:40:08.366Z,1554824408.366 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms 2019-04-09T15:40:08.366Z,1554824408.366 [PAR_Licor] Data Fault, FailCount= 2 2019-04-09T15:40:08.366Z,1554824408.366 [PAR_Licor](ERROR): Data Fault 2019-04-09T15:40:08.401Z,1554824408.401 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:08.402Z,1554824408.402 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:08.402Z,1554824408.402 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:08.402Z,1554824408.402 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:08.402Z,1554824408.402 [BR_Ping1D](INFO): elapsed 3.9910 2019-04-09T15:40:08.402Z,1554824408.402 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:08.403Z,1554824408.403 [BR_Ping1D](INFO): No UART data 2019-04-09T15:40:08.403Z,1554824408.403 [BR_Ping1D](INFO): received UART packets: 0 2019-04-09T15:40:08.403Z,1554824408.403 [BR_Ping1D](INFO): not all values verified yet 2019-04-09T15:40:08.474Z,1554824408.474 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-09T15:40:08.474Z,1554824408.474 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-09T15:40:08.474Z,1554824408.474 [PAR_Licor] No Fault, FailCount= 2 2019-04-09T15:40:08.650Z,1554824408.650 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:08.650Z,1554824408.650 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:08.650Z,1554824408.650 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:08.650Z,1554824408.650 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:08.650Z,1554824408.650 [BR_Ping1D](INFO): elapsed 4.2392 2019-04-09T15:40:08.651Z,1554824408.651 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:08.651Z,1554824408.651 [BR_Ping1D](INFO): No UART data 2019-04-09T15:40:08.651Z,1554824408.651 [BR_Ping1D](INFO): received UART packets: 0 2019-04-09T15:40:08.651Z,1554824408.651 [BR_Ping1D](INFO): not all values verified yet 2019-04-09T15:40:09.170Z,1554824409.170 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms 2019-04-09T15:40:09.170Z,1554824409.170 [PAR_Licor] Data Fault, FailCount= 3 2019-04-09T15:40:09.170Z,1554824409.170 [PAR_Licor](ERROR): Data Fault 2019-04-09T15:40:09.211Z,1554824409.211 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:09.211Z,1554824409.211 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:09.211Z,1554824409.211 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:09.211Z,1554824409.211 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:09.211Z,1554824409.211 [BR_Ping1D](INFO): elapsed 4.8002 2019-04-09T15:40:09.212Z,1554824409.212 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:09.212Z,1554824409.212 [BR_Ping1D](INFO): No UART data 2019-04-09T15:40:09.212Z,1554824409.212 [BR_Ping1D](INFO): received UART packets: 0 2019-04-09T15:40:09.212Z,1554824409.212 [BR_Ping1D](INFO): not all values verified yet 2019-04-09T15:40:09.291Z,1554824409.291 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-09T15:40:09.291Z,1554824409.291 [CBIT](CRITICAL): Data Fault in component: PAR_Licor 2019-04-09T15:40:09.453Z,1554824409.453 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:09.453Z,1554824409.453 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:09.454Z,1554824409.454 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:09.454Z,1554824409.454 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:09.454Z,1554824409.454 [BR_Ping1D](INFO): elapsed 5.0428 2019-04-09T15:40:09.454Z,1554824409.454 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:09.454Z,1554824409.454 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization. 2019-04-09T15:40:09.454Z,1554824409.454 [BR_Ping1D] Data Fault, FailCount= 1 2019-04-09T15:40:09.455Z,1554824409.455 [BR_Ping1D](ERROR): Data Fault 2019-04-09T15:40:09.784Z,1554824409.784 [CBIT](INFO): Critical error at 20190409T154009 2019-04-09T15:40:09.790Z,1554824409.790 [CBIT](ERROR): Data Fault in component: BR_Ping1D 2019-04-09T15:40:09.856Z,1554824409.856 [BR_Ping1D](INFO): Stop 2019-04-09T15:40:09.856Z,1554824409.856 [BR_Ping1D](INFO): uninitialize 2019-04-09T15:40:09.856Z,1554824409.856 [BR_Ping1D](INFO): Powering down 2019-04-09T15:40:10.294Z,1554824410.294 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-09T15:40:10.294Z,1554824410.294 [MassServo] Communications Fault, FailCount= 2 2019-04-09T15:40:10.294Z,1554824410.294 [MassServo](ERROR): Communications Fault 2019-04-09T15:40:10.299Z,1554824410.299 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-09T15:40:10.332Z,1554824410.332 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:10.351Z,1554824410.351 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-09T15:40:10.351Z,1554824410.351 [MassServo](INFO): Powering down 2019-04-09T15:40:10.726Z,1554824410.726 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:11.129Z,1554824411.129 [BR_Ping1D](INFO): Stopped 2019-04-09T15:40:11.143Z,1554824411.143 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:11.263Z,1554824411.263 [MassServo](DEBUG): Initializing MassServo. 2019-04-09T15:40:11.267Z,1554824411.267 [CBIT](INFO): Clearing failed state for component MassServo 2019-04-09T15:40:11.267Z,1554824411.267 [MassServo] No Fault, FailCount= 2 2019-04-09T15:40:11.268Z,1554824411.268 [CBIT](INFO): Clearing failed state for component BR_Ping1D 2019-04-09T15:40:11.268Z,1554824411.268 [BR_Ping1D] No Fault, FailCount= 1 2019-04-09T15:40:11.534Z,1554824411.534 [BR_Ping1D](INFO): Start 2019-04-09T15:40:11.938Z,1554824411.938 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:11.969Z,1554824411.969 [CBIT](FAULT): Main Battery Failure. Count: 21 2019-04-09T15:40:12.342Z,1554824412.342 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:12.342Z,1554824412.342 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:12.342Z,1554824412.342 [BR_Ping1D](INFO): startup: 0 2019-04-09T15:40:12.342Z,1554824412.342 [BR_Ping1D](INFO): UART setting parameters ... 2019-04-09T15:40:12.354Z,1554824412.354 [BR_Ping1D](INFO): sending request for Ping Interval: 500 2019-04-09T15:40:12.354Z,1554824412.354 [BR_Ping1D](INFO): sending request for Auto Mode: 1 2019-04-09T15:40:12.354Z,1554824412.354 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000 2019-04-09T15:40:12.355Z,1554824412.355 [BR_Ping1D](INFO): sending request for Ping Enable: 1 2019-04-09T15:40:12.746Z,1554824412.746 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:13.150Z,1554824413.150 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:13.150Z,1554824413.150 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:13.150Z,1554824413.150 [BR_Ping1D](INFO): startup: 1 2019-04-09T15:40:13.150Z,1554824413.150 [BR_Ping1D](INFO): UART requesting status 2019-04-09T15:40:13.200Z,1554824413.200 [CBIT](CRITICAL): Environmental Failure. Press:14.902433 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION 2019-04-09T15:40:13.535Z,1554824413.535 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-04-09T15:40:13.535Z,1554824413.535 [Aanderaa_O2] Communications Fault, FailCount= 1 2019-04-09T15:40:13.535Z,1554824413.535 [Aanderaa_O2](ERROR): Communications Fault 2019-04-09T15:40:13.553Z,1554824413.553 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:13.553Z,1554824413.553 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:13.554Z,1554824413.554 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:13.554Z,1554824413.554 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:13.554Z,1554824413.554 [BR_Ping1D](INFO): elapsed 1.2114 2019-04-09T15:40:13.554Z,1554824413.554 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:13.555Z,1554824413.555 [BR_Ping1D](INFO): received full packet, type: 1210 2019-04-09T15:40:13.555Z,1554824413.555 [BR_Ping1D](INFO): parsing data, res: 20 2019-04-09T15:40:13.555Z,1554824413.555 [BR_Ping1D](INFO): Parsing UART packet of type 1210 2019-04-09T15:40:13.555Z,1554824413.555 [BR_Ping1D](INFO): voltage: 5056 2019-04-09T15:40:13.555Z,1554824413.555 [BR_Ping1D](INFO): interval: 7681 2019-04-09T15:40:13.555Z,1554824413.555 [BR_Ping1D](INFO): gain: 1 2019-04-09T15:40:13.556Z,1554824413.556 [BR_Ping1D](INFO): auto: 1 2019-04-09T15:40:13.556Z,1554824413.556 [BR_Ping1D](INFO): received full packet, type: 1215 2019-04-09T15:40:13.556Z,1554824413.556 [BR_Ping1D](INFO): CRC error in received UART packet 2019-04-09T15:40:13.556Z,1554824413.556 [BR_Ping1D](INFO): received UART packets: 1 2019-04-09T15:40:13.556Z,1554824413.556 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ... 2019-04-09T15:40:13.557Z,1554824413.557 [BR_Ping1D](INFO): () <-> 2019-04-09T15:40:13.557Z,1554824413.557 [BR_Ping1D](INFO): (ping interval) 500 <-> 0 2019-04-09T15:40:13.557Z,1554824413.557 [BR_Ping1D](INFO): (auto mode) 1 <-> 1 2019-04-09T15:40:13.557Z,1554824413.557 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 58208 2019-04-09T15:40:13.558Z,1554824413.558 [BR_Ping1D](INFO): (ping enable) 1 <-> 0 2019-04-09T15:40:13.894Z,1554824413.894 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-09T15:40:13.894Z,1554824413.894 [MassServo] Communications Fault, FailCount= 3 2019-04-09T15:40:13.894Z,1554824413.894 [MassServo](ERROR): Communications Fault 2019-04-09T15:40:13.896Z,1554824413.896 [CBIT](INFO): Critical error at 20190409T154013 2019-04-09T15:40:13.899Z,1554824413.899 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-09T15:40:13.899Z,1554824413.899 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-04-09T15:40:14.058Z,1554824414.058 [Aanderaa_O2](INFO): Powering down 2019-04-09T15:40:14.146Z,1554824414.146 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:14.146Z,1554824414.146 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:14.146Z,1554824414.146 [BR_Ping1D](INFO): startup: 0 2019-04-09T15:40:14.146Z,1554824414.146 [BR_Ping1D](INFO): UART setting parameters ... 2019-04-09T15:40:14.158Z,1554824414.158 [BR_Ping1D](INFO): sending request for Ping Interval: 500 2019-04-09T15:40:14.158Z,1554824414.158 [BR_Ping1D](INFO): sending request for Auto Mode: 1 2019-04-09T15:40:14.158Z,1554824414.158 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000 2019-04-09T15:40:14.159Z,1554824414.159 [BR_Ping1D](INFO): sending request for Ping Enable: 1 2019-04-09T15:40:14.202Z,1554824414.202 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-09T15:40:14.202Z,1554824414.202 [MassServo](INFO): Powering down 2019-04-09T15:40:14.361Z,1554824414.361 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:14.766Z,1554824414.766 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:14.766Z,1554824414.766 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:14.766Z,1554824414.766 [BR_Ping1D](INFO): startup: 1 2019-04-09T15:40:14.766Z,1554824414.766 [BR_Ping1D](INFO): UART requesting status 2019-04-09T15:40:14.804Z,1554824414.804 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:14.923Z,1554824414.923 [MassServo](DEBUG): Initializing MassServo. 2019-04-09T15:40:14.927Z,1554824414.927 [CBIT](INFO): Clearing failed state for component MassServo 2019-04-09T15:40:14.927Z,1554824414.927 [MassServo] No Fault, FailCount= 3 2019-04-09T15:40:14.928Z,1554824414.928 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-04-09T15:40:14.928Z,1554824414.928 [Aanderaa_O2] No Fault, FailCount= 1 2019-04-09T15:40:15.170Z,1554824415.170 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:15.170Z,1554824415.170 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:15.170Z,1554824415.170 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:15.170Z,1554824415.170 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:15.171Z,1554824415.171 [BR_Ping1D](INFO): elapsed 1.0241 2019-04-09T15:40:15.171Z,1554824415.171 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:15.172Z,1554824415.172 [BR_Ping1D](INFO): received full packet, type: 1215 2019-04-09T15:40:15.172Z,1554824415.172 [BR_Ping1D](INFO): CRC error in received UART packet 2019-04-09T15:40:15.172Z,1554824415.172 [BR_Ping1D](INFO): received UART packets: 0 2019-04-09T15:40:15.172Z,1554824415.172 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ... 2019-04-09T15:40:15.173Z,1554824415.173 [BR_Ping1D](INFO): () <-> 2019-04-09T15:40:15.173Z,1554824415.173 [BR_Ping1D](INFO): (ping interval) 500 <-> 0 2019-04-09T15:40:15.173Z,1554824415.173 [BR_Ping1D](INFO): (auto mode) 1 <-> 1 2019-04-09T15:40:15.173Z,1554824415.173 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 58208 2019-04-09T15:40:15.174Z,1554824415.174 [BR_Ping1D](INFO): (ping enable) 1 <-> 0 2019-04-09T15:40:15.586Z,1554824415.586 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:15.586Z,1554824415.586 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:15.586Z,1554824415.586 [BR_Ping1D](INFO): startup: 0 2019-04-09T15:40:15.586Z,1554824415.586 [BR_Ping1D](INFO): UART setting parameters ... 2019-04-09T15:40:15.598Z,1554824415.598 [BR_Ping1D](INFO): sending request for Ping Interval: 500 2019-04-09T15:40:15.598Z,1554824415.598 [BR_Ping1D](INFO): sending request for Auto Mode: 1 2019-04-09T15:40:15.598Z,1554824415.598 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000 2019-04-09T15:40:15.599Z,1554824415.599 [BR_Ping1D](INFO): sending request for Ping Enable: 1 2019-04-09T15:40:15.914Z,1554824415.914 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-04-09T15:40:15.914Z,1554824415.914 [CTD_Seabird] Communications Fault, FailCount= 1 2019-04-09T15:40:15.914Z,1554824415.914 [CTD_Seabird](ERROR): Communications Fault 2019-04-09T15:40:15.978Z,1554824415.978 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:16.018Z,1554824416.018 [CBIT](FAULT): Main Battery Failure. Count: 31 2019-04-09T15:40:16.021Z,1554824416.021 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-04-09T15:40:16.318Z,1554824416.318 [CTD_Seabird](INFO): Powering down 2019-04-09T15:40:16.382Z,1554824416.382 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:16.382Z,1554824416.382 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:16.383Z,1554824416.383 [BR_Ping1D](INFO): startup: 1 2019-04-09T15:40:16.383Z,1554824416.383 [BR_Ping1D](INFO): UART requesting status 2019-04-09T15:40:16.785Z,1554824416.785 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:16.785Z,1554824416.785 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:16.786Z,1554824416.786 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:16.786Z,1554824416.786 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:16.786Z,1554824416.786 [BR_Ping1D](INFO): elapsed 1.1997 2019-04-09T15:40:16.786Z,1554824416.786 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:16.787Z,1554824416.787 [BR_Ping1D](INFO): received full packet, type: 1210 2019-04-09T15:40:16.787Z,1554824416.787 [BR_Ping1D](INFO): parsing data, res: 20 2019-04-09T15:40:16.787Z,1554824416.787 [BR_Ping1D](INFO): Parsing UART packet of type 1210 2019-04-09T15:40:16.787Z,1554824416.787 [BR_Ping1D](INFO): voltage: 5056 2019-04-09T15:40:16.787Z,1554824416.787 [BR_Ping1D](INFO): interval: 7681 2019-04-09T15:40:16.788Z,1554824416.788 [BR_Ping1D](INFO): gain: 1 2019-04-09T15:40:16.788Z,1554824416.788 [BR_Ping1D](INFO): auto: 1 2019-04-09T15:40:16.788Z,1554824416.788 [BR_Ping1D](INFO): received full packet, type: 1215 2019-04-09T15:40:16.788Z,1554824416.788 [BR_Ping1D](INFO): CRC error in received UART packet 2019-04-09T15:40:16.788Z,1554824416.788 [BR_Ping1D](INFO): received UART packets: 1 2019-04-09T15:40:16.789Z,1554824416.789 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ... 2019-04-09T15:40:16.789Z,1554824416.789 [BR_Ping1D](INFO): () <-> 2019-04-09T15:40:16.789Z,1554824416.789 [BR_Ping1D](INFO): (ping interval) 500 <-> 0 2019-04-09T15:40:16.789Z,1554824416.789 [BR_Ping1D](INFO): (auto mode) 1 <-> 1 2019-04-09T15:40:16.790Z,1554824416.790 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 58208 2019-04-09T15:40:16.790Z,1554824416.790 [BR_Ping1D](INFO): (ping enable) 1 <-> 0 2019-04-09T15:40:17.190Z,1554824417.190 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:17.190Z,1554824417.190 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:17.190Z,1554824417.190 [BR_Ping1D](INFO): startup: 0 2019-04-09T15:40:17.190Z,1554824417.190 [BR_Ping1D](INFO): UART setting parameters ... 2019-04-09T15:40:17.202Z,1554824417.202 [BR_Ping1D](INFO): sending request for Ping Interval: 500 2019-04-09T15:40:17.202Z,1554824417.202 [BR_Ping1D](INFO): sending request for Auto Mode: 1 2019-04-09T15:40:17.202Z,1554824417.202 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000 2019-04-09T15:40:17.203Z,1554824417.203 [BR_Ping1D](INFO): sending request for Ping Enable: 1 2019-04-09T15:40:17.554Z,1554824417.554 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-09T15:40:17.554Z,1554824417.554 [MassServo] Communications Fault, FailCount= 4 2019-04-09T15:40:17.554Z,1554824417.554 [MassServo](ERROR): Communications Fault 2019-04-09T15:40:17.559Z,1554824417.559 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-09T15:40:17.559Z,1554824417.559 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-04-09T15:40:17.559Z,1554824417.559 [CTD_Seabird] No Fault, FailCount= 1 2019-04-09T15:40:17.594Z,1554824417.594 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:17.623Z,1554824417.623 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-09T15:40:17.623Z,1554824417.623 [MassServo](INFO): Powering down 2019-04-09T15:40:17.690Z,1554824417.690 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-04-09T15:40:17.692Z,1554824417.692 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-04-09T15:40:17.998Z,1554824417.998 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:17.998Z,1554824417.998 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:17.999Z,1554824417.999 [BR_Ping1D](INFO): startup: 1 2019-04-09T15:40:17.999Z,1554824417.999 [BR_Ping1D](INFO): UART requesting status 2019-04-09T15:40:18.401Z,1554824418.401 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:18.401Z,1554824418.401 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:18.401Z,1554824418.401 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:18.402Z,1554824418.402 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:18.402Z,1554824418.402 [BR_Ping1D](INFO): elapsed 1.2115 2019-04-09T15:40:18.402Z,1554824418.402 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:18.403Z,1554824418.403 [BR_Ping1D](INFO): received full packet, type: 1210 2019-04-09T15:40:18.403Z,1554824418.403 [BR_Ping1D](INFO): parsing data, res: 20 2019-04-09T15:40:18.403Z,1554824418.403 [BR_Ping1D](INFO): Parsing UART packet of type 1210 2019-04-09T15:40:18.403Z,1554824418.403 [BR_Ping1D](INFO): voltage: 5056 2019-04-09T15:40:18.403Z,1554824418.403 [BR_Ping1D](INFO): interval: 7681 2019-04-09T15:40:18.403Z,1554824418.403 [BR_Ping1D](INFO): gain: 1 2019-04-09T15:40:18.404Z,1554824418.404 [BR_Ping1D](INFO): auto: 1 2019-04-09T15:40:18.404Z,1554824418.404 [BR_Ping1D](INFO): received full packet, type: 1215 2019-04-09T15:40:18.404Z,1554824418.404 [BR_Ping1D](INFO): CRC error in received UART packet 2019-04-09T15:40:18.404Z,1554824418.404 [BR_Ping1D](INFO): received UART packets: 1 2019-04-09T15:40:18.404Z,1554824418.404 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ... 2019-04-09T15:40:18.404Z,1554824418.404 [BR_Ping1D](INFO): () <-> 2019-04-09T15:40:18.405Z,1554824418.405 [BR_Ping1D](INFO): (ping interval) 500 <-> 0 2019-04-09T15:40:18.405Z,1554824418.405 [BR_Ping1D](INFO): (auto mode) 1 <-> 1 2019-04-09T15:40:18.405Z,1554824418.405 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 58208 2019-04-09T15:40:18.406Z,1554824418.406 [BR_Ping1D](INFO): (ping enable) 1 <-> 0 2019-04-09T15:40:18.406Z,1554824418.406 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up! 2019-04-09T15:40:18.406Z,1554824418.406 [BR_Ping1D] Data Fault, FailCount= 2 2019-04-09T15:40:18.406Z,1554824418.406 [BR_Ping1D](ERROR): Data Fault 2019-04-09T15:40:18.435Z,1554824418.435 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:18.555Z,1554824418.555 [MassServo](DEBUG): Initializing MassServo. 2019-04-09T15:40:18.559Z,1554824418.559 [CBIT](INFO): Clearing failed state for component MassServo 2019-04-09T15:40:18.559Z,1554824418.559 [MassServo] No Fault, FailCount= 4 2019-04-09T15:40:18.560Z,1554824418.560 [CBIT](ERROR): Data Fault in component: BR_Ping1D 2019-04-09T15:40:18.806Z,1554824418.806 [BR_Ping1D](INFO): Stop 2019-04-09T15:40:18.806Z,1554824418.806 [BR_Ping1D](INFO): uninitialize 2019-04-09T15:40:18.806Z,1554824418.806 [BR_Ping1D](INFO): Powering down 2019-04-09T15:40:19.209Z,1554824419.209 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:19.613Z,1554824419.613 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:20.018Z,1554824420.018 [BR_Ping1D](INFO): Stopped 2019-04-09T15:40:20.041Z,1554824420.041 [CBIT](FAULT): Main Battery Failure. Count: 41 2019-04-09T15:40:20.060Z,1554824420.060 [CBIT](INFO): Clearing failed state for component BR_Ping1D 2019-04-09T15:40:20.060Z,1554824420.060 [BR_Ping1D] No Fault, FailCount= 2 2019-04-09T15:40:20.422Z,1554824420.422 [BR_Ping1D](INFO): Start 2019-04-09T15:40:20.826Z,1554824420.826 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:21.154Z,1554824421.154 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-09T15:40:21.154Z,1554824421.154 [MassServo] Communications Fault, FailCount= 5 2019-04-09T15:40:21.154Z,1554824421.154 [MassServo](ERROR): Communications Fault 2019-04-09T15:40:21.159Z,1554824421.159 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-09T15:40:21.159Z,1554824421.159 [CBIT](CRITICAL): Communications Fault in component: MassServo 2019-04-09T15:40:21.234Z,1554824421.234 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:21.234Z,1554824421.234 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:21.235Z,1554824421.235 [BR_Ping1D](INFO): startup: 0 2019-04-09T15:40:21.235Z,1554824421.235 [BR_Ping1D](INFO): UART setting parameters ... 2019-04-09T15:40:21.246Z,1554824421.246 [BR_Ping1D](INFO): sending request for Ping Interval: 500 2019-04-09T15:40:21.246Z,1554824421.246 [BR_Ping1D](INFO): sending request for Auto Mode: 1 2019-04-09T15:40:21.247Z,1554824421.247 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000 2019-04-09T15:40:21.247Z,1554824421.247 [BR_Ping1D](INFO): sending request for Ping Enable: 1 2019-04-09T15:40:21.301Z,1554824421.301 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-09T15:40:21.301Z,1554824421.301 [MassServo](INFO): Powering down 2019-04-09T15:40:21.315Z,1554824421.315 [CBIT](INFO): Critical error at 20190409T154021 2019-04-09T15:40:21.633Z,1554824421.633 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:22.038Z,1554824422.038 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:22.038Z,1554824422.038 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:22.038Z,1554824422.038 [BR_Ping1D](INFO): startup: 1 2019-04-09T15:40:22.038Z,1554824422.038 [BR_Ping1D](INFO): UART requesting status 2019-04-09T15:40:22.076Z,1554824422.076 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-09T15:40:22.195Z,1554824422.195 [MassServo](DEBUG): Initializing MassServo. 2019-04-09T15:40:22.442Z,1554824422.442 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:22.442Z,1554824422.442 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:22.442Z,1554824422.442 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:22.442Z,1554824422.442 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:22.443Z,1554824422.443 [BR_Ping1D](INFO): elapsed 1.2075 2019-04-09T15:40:22.443Z,1554824422.443 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:22.443Z,1554824422.443 [BR_Ping1D](INFO): received full packet, type: 1210 2019-04-09T15:40:22.443Z,1554824422.443 [BR_Ping1D](INFO): parsing data, res: 20 2019-04-09T15:40:22.444Z,1554824422.444 [BR_Ping1D](INFO): Parsing UART packet of type 1210 2019-04-09T15:40:22.444Z,1554824422.444 [BR_Ping1D](INFO): voltage: 5056 2019-04-09T15:40:22.444Z,1554824422.444 [BR_Ping1D](INFO): interval: 7681 2019-04-09T15:40:22.444Z,1554824422.444 [BR_Ping1D](INFO): gain: 1 2019-04-09T15:40:22.444Z,1554824422.444 [BR_Ping1D](INFO): auto: 1 2019-04-09T15:40:22.445Z,1554824422.445 [BR_Ping1D](INFO): received full packet, type: 1215 2019-04-09T15:40:22.445Z,1554824422.445 [BR_Ping1D](INFO): CRC error in received UART packet 2019-04-09T15:40:22.445Z,1554824422.445 [BR_Ping1D](INFO): received UART packets: 1 2019-04-09T15:40:22.445Z,1554824422.445 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ... 2019-04-09T15:40:22.445Z,1554824422.445 [BR_Ping1D](INFO): () <-> 2019-04-09T15:40:22.445Z,1554824422.445 [BR_Ping1D](INFO): (ping interval) 500 <-> 0 2019-04-09T15:40:22.446Z,1554824422.446 [BR_Ping1D](INFO): (auto mode) 1 <-> 1 2019-04-09T15:40:22.446Z,1554824422.446 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 58208 2019-04-09T15:40:22.447Z,1554824422.447 [BR_Ping1D](INFO): (ping enable) 1 <-> 0 2019-04-09T15:40:22.447Z,1554824422.447 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up! 2019-04-09T15:40:22.447Z,1554824422.447 [BR_Ping1D] Data Fault, FailCount= 3 2019-04-09T15:40:22.447Z,1554824422.447 [BR_Ping1D](ERROR): Data Fault 2019-04-09T15:40:22.500Z,1554824422.500 [CBIT](ERROR): Data Fault in component: BR_Ping1D 2019-04-09T15:40:22.846Z,1554824422.846 [BR_Ping1D](INFO): Stop 2019-04-09T15:40:22.846Z,1554824422.846 [BR_Ping1D](INFO): uninitialize 2019-04-09T15:40:22.846Z,1554824422.846 [BR_Ping1D](INFO): Powering down 2019-04-09T15:40:23.261Z,1554824423.261 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:23.658Z,1554824423.658 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:24.039Z,1554824424.039 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-09T15:40:24.039Z,1554824424.039 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1 2019-04-09T15:40:24.039Z,1554824424.039 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-09T15:40:24.058Z,1554824424.058 [BR_Ping1D](INFO): Stopped 2019-04-09T15:40:24.088Z,1554824424.088 [CBIT](FAULT): Main Battery Failure. Count: 51 2019-04-09T15:40:24.090Z,1554824424.090 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-09T15:40:24.091Z,1554824424.091 [CBIT](INFO): Clearing failed state for component BR_Ping1D 2019-04-09T15:40:24.091Z,1554824424.091 [BR_Ping1D] No Fault, FailCount= 3 2019-04-09T15:40:24.443Z,1554824424.443 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-09T15:40:24.605Z,1554824424.605 [BR_Ping1D](INFO): Start 2019-04-09T15:40:24.867Z,1554824424.867 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:25.250Z,1554824425.250 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-04-09T15:40:25.250Z,1554824425.250 [Aanderaa_O2] Communications Fault, FailCount= 2 2019-04-09T15:40:25.250Z,1554824425.250 [Aanderaa_O2](ERROR): Communications Fault 2019-04-09T15:40:25.270Z,1554824425.270 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:25.270Z,1554824425.270 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:25.270Z,1554824425.270 [BR_Ping1D](INFO): startup: 0 2019-04-09T15:40:25.270Z,1554824425.270 [BR_Ping1D](INFO): UART setting parameters ... 2019-04-09T15:40:25.282Z,1554824425.282 [BR_Ping1D](INFO): sending request for Ping Interval: 500 2019-04-09T15:40:25.282Z,1554824425.282 [BR_Ping1D](INFO): sending request for Auto Mode: 1 2019-04-09T15:40:25.282Z,1554824425.282 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000 2019-04-09T15:40:25.283Z,1554824425.283 [BR_Ping1D](INFO): sending request for Ping Enable: 1 2019-04-09T15:40:25.377Z,1554824425.377 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-04-09T15:40:25.377Z,1554824425.377 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-09T15:40:25.377Z,1554824425.377 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1 2019-04-09T15:40:25.774Z,1554824425.774 [Aanderaa_O2](INFO): Powering down 2019-04-09T15:40:25.794Z,1554824425.794 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:25.794Z,1554824425.794 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:25.794Z,1554824425.794 [BR_Ping1D](INFO): startup: 1 2019-04-09T15:40:25.794Z,1554824425.794 [BR_Ping1D](INFO): UART requesting status 2019-04-09T15:40:26.078Z,1554824426.078 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:26.078Z,1554824426.078 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:26.078Z,1554824426.078 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:26.078Z,1554824426.078 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:26.079Z,1554824426.079 [BR_Ping1D](INFO): elapsed 0.8082 2019-04-09T15:40:26.079Z,1554824426.079 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:26.079Z,1554824426.079 [BR_Ping1D](INFO): received full packet, type: 1210 2019-04-09T15:40:26.079Z,1554824426.079 [BR_Ping1D](INFO): parsing data, res: 20 2019-04-09T15:40:26.080Z,1554824426.080 [BR_Ping1D](INFO): Parsing UART packet of type 1210 2019-04-09T15:40:26.080Z,1554824426.080 [BR_Ping1D](INFO): voltage: 5054 2019-04-09T15:40:26.080Z,1554824426.080 [BR_Ping1D](INFO): interval: 7681 2019-04-09T15:40:26.080Z,1554824426.080 [BR_Ping1D](INFO): gain: 1 2019-04-09T15:40:26.080Z,1554824426.080 [BR_Ping1D](INFO): auto: 1 2019-04-09T15:40:26.081Z,1554824426.081 [BR_Ping1D](INFO): received full packet, type: 1215 2019-04-09T15:40:26.081Z,1554824426.081 [BR_Ping1D](INFO): CRC error in received UART packet 2019-04-09T15:40:26.081Z,1554824426.081 [BR_Ping1D](INFO): received UART packets: 1 2019-04-09T15:40:26.081Z,1554824426.081 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ... 2019-04-09T15:40:26.081Z,1554824426.081 [BR_Ping1D](INFO): () <-> 2019-04-09T15:40:26.081Z,1554824426.081 [BR_Ping1D](INFO): (ping interval) 500 <-> 0 2019-04-09T15:40:26.082Z,1554824426.082 [BR_Ping1D](INFO): (auto mode) 1 <-> 1 2019-04-09T15:40:26.082Z,1554824426.082 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 58208 2019-04-09T15:40:26.083Z,1554824426.083 [BR_Ping1D](INFO): (ping enable) 1 <-> 0 2019-04-09T15:40:26.083Z,1554824426.083 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up! 2019-04-09T15:40:26.083Z,1554824426.083 [BR_Ping1D] Data Fault, FailCount= 4 2019-04-09T15:40:26.083Z,1554824426.083 [BR_Ping1D](ERROR): Data Fault 2019-04-09T15:40:26.115Z,1554824426.115 [CBIT](ERROR): Data Fault in component: BR_Ping1D 2019-04-09T15:40:26.481Z,1554824426.481 [BR_Ping1D](INFO): Stop 2019-04-09T15:40:26.481Z,1554824426.481 [BR_Ping1D](INFO): uninitialize 2019-04-09T15:40:26.481Z,1554824426.481 [BR_Ping1D](INFO): Powering down 2019-04-09T15:40:26.577Z,1554824426.577 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-04-09T15:40:26.577Z,1554824426.577 [Aanderaa_O2] No Fault, FailCount= 2 2019-04-09T15:40:26.893Z,1554824426.893 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:27.306Z,1554824427.306 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:27.324Z,1554824427.324 [SBIT](IMPORTANT): Beginning Startup BIT 2019-04-09T15:40:27.333Z,1554824427.333 [CBIT](IMPORTANT): Beginning ground fault scan 2019-04-09T15:40:27.674Z,1554824427.674 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-09T15:40:27.675Z,1554824427.675 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-09T15:40:27.714Z,1554824427.714 [BR_Ping1D](INFO): Stopped 2019-04-09T15:40:27.811Z,1554824427.811 [CBIT](INFO): Clearing failed state for component BR_Ping1D 2019-04-09T15:40:27.812Z,1554824427.812 [BR_Ping1D] No Fault, FailCount= 4 2019-04-09T15:40:28.097Z,1554824428.097 [BR_Ping1D](INFO): Start 2019-04-09T15:40:28.164Z,1554824428.164 [CBIT](FAULT): Main Battery Failure. Count: 61 2019-04-09T15:40:28.504Z,1554824428.504 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:28.905Z,1554824428.905 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:28.905Z,1554824428.905 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:28.906Z,1554824428.906 [BR_Ping1D](INFO): startup: 0 2019-04-09T15:40:28.906Z,1554824428.906 [BR_Ping1D](INFO): UART setting parameters ... 2019-04-09T15:40:28.918Z,1554824428.918 [BR_Ping1D](INFO): sending request for Ping Interval: 500 2019-04-09T15:40:28.918Z,1554824428.918 [BR_Ping1D](INFO): sending request for Auto Mode: 1 2019-04-09T15:40:28.918Z,1554824428.918 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000 2019-04-09T15:40:28.919Z,1554824428.919 [BR_Ping1D](INFO): sending request for Ping Enable: 1 2019-04-09T15:40:29.310Z,1554824429.310 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:29.713Z,1554824429.713 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:29.714Z,1554824429.714 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:29.714Z,1554824429.714 [BR_Ping1D](INFO): startup: 1 2019-04-09T15:40:29.714Z,1554824429.714 [BR_Ping1D](INFO): UART requesting status 2019-04-09T15:40:30.118Z,1554824430.118 [BR_Ping1D](INFO): Starting 2019-04-09T15:40:30.118Z,1554824430.118 [BR_Ping1D](INFO): readConfig 2019-04-09T15:40:30.118Z,1554824430.118 [BR_Ping1D](INFO): startup: 2 2019-04-09T15:40:30.118Z,1554824430.118 [BR_Ping1D](INFO): UART waiting for response 2019-04-09T15:40:30.119Z,1554824430.119 [BR_Ping1D](INFO): elapsed 1.2127 2019-04-09T15:40:30.119Z,1554824430.119 [BR_Ping1D](INFO): timeout 5.0000 2019-04-09T15:40:30.119Z,1554824430.119 [BR_Ping1D](INFO): received full packet, type: 1210 2019-04-09T15:40:30.119Z,1554824430.119 [BR_Ping1D](INFO): parsing data, res: 20 2019-04-09T15:40:30.120Z,1554824430.120 [BR_Ping1D](INFO): Parsing UART packet of type 1210 2019-04-09T15:40:30.120Z,1554824430.120 [BR_Ping1D](INFO): voltage: 5056 2019-04-09T15:40:30.120Z,1554824430.120 [BR_Ping1D](INFO): interval: 7681 2019-04-09T15:40:30.120Z,1554824430.120 [BR_Ping1D](INFO): gain: 1 2019-04-09T15:40:30.120Z,1554824430.120 [BR_Ping1D](INFO): auto: 1 2019-04-09T15:40:30.121Z,1554824430.121 [BR_Ping1D](INFO): received full packet, type: 1215 2019-04-09T15:40:30.121Z,1554824430.121 [BR_Ping1D](INFO): CRC error in received UART packet 2019-04-09T15:40:30.121Z,1554824430.121 [BR_Ping1D](INFO): received UART packets: 1 2019-04-09T15:40:30.121Z,1554824430.121 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ... 2019-04-09T15:40:30.121Z,1554824430.121 [BR_Ping1D](INFO): () <-> 2019-04-09T15:40:30.121Z,1554824430.121 [BR_Ping1D](INFO): (ping interval) 500 <-> 0 2019-04-09T15:40:30.122Z,1554824430.122 [BR_Ping1D](INFO): (auto mode) 1 <-> 1 2019-04-09T15:40:30.122Z,1554824430.122 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 58208 2019-04-09T15:40:30.122Z,1554824430.122 [BR_Ping1D](INFO): (ping enable) 1 <-> 0 2019-04-09T15:40:30.123Z,1554824430.123 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up! 2019-04-09T15:40:30.123Z,1554824430.123 [BR_Ping1D] Data Fault, FailCount= 5 2019-04-09T15:40:30.123Z,1554824430.123 [BR_Ping1D](ERROR): Data Fault 2019-04-09T15:40:30.207Z,1554824430.207 [CBIT](ERROR): Data Fault in component: BR_Ping1D 2019-04-09T15:40:30.207Z,1554824430.207 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D 2019-04-09T15:40:30.507Z,1554824430.507 [NAL9602](INFO): Powering up NAL9602 2019-04-09T15:40:30.523Z,1554824430.523 [BR_Ping1D](INFO): Stop 2019-04-09T15:40:30.523Z,1554824430.523 [BR_Ping1D](INFO): uninitialize 2019-04-09T15:40:30.523Z,1554824430.523 [BR_Ping1D](INFO): Powering down 2019-04-09T15:40:30.721Z,1554824430.721 [CBIT](INFO): Critical error at 20190409T154030 2019-04-09T15:40:30.742Z,1554824430.742 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-04-09T15:40:30.742Z,1554824430.742 [CTD_Seabird] Communications Fault, FailCount= 2 2019-04-09T15:40:30.742Z,1554824430.742 [CTD_Seabird](ERROR): Communications Fault 2019-04-09T15:40:30.930Z,1554824430.930 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:31.095Z,1554824431.095 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-04-09T15:40:31.146Z,1554824431.146 [CTD_Seabird](INFO): Powering down 2019-04-09T15:40:31.329Z,1554824431.329 [BR_Ping1D](INFO): Stopping 2019-04-09T15:40:31.734Z,1554824431.734 [BR_Ping1D](INFO): Stopped 2019-04-09T15:40:32.268Z,1554824432.268 [CBIT](FAULT): Main Battery Failure. Count: 71 2019-04-09T15:40:32.271Z,1554824432.271 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-04-09T15:40:32.271Z,1554824432.271 [CTD_Seabird] No Fault, FailCount= 2 2019-04-09T15:40:32.490Z,1554824432.490 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-04-09T15:40:32.498Z,1554824432.498 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-04-09T15:40:36.189Z,1554824436.189 [CBIT](FAULT): Main Battery Failure. Count: 81 2019-04-09T15:40:36.966Z,1554824436.966 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-04-09T15:40:36.967Z,1554824436.967 [Aanderaa_O2] Communications Fault, FailCount= 3 2019-04-09T15:40:36.967Z,1554824436.967 [Aanderaa_O2](ERROR): Communications Fault 2019-04-09T15:40:37.004Z,1554824437.004 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-04-09T15:40:37.005Z,1554824437.005 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2019-04-09T15:40:37.490Z,1554824437.490 [Aanderaa_O2](INFO): Powering down 2019-04-09T15:40:38.235Z,1554824438.235 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.006522 CHAN A1 (24V): -0.127646 CHAN A2 (12V): -0.005187 CHAN A3 (5V): -0.002593 CHAN B0 (3.3V): -0.001343 CHAN B1 (3.15aV): -0.001308 CHAN B2 (3.15bV): -0.000860 CHAN B3 (GND): 0.000283 OPEN: -0.000827 Full Scale Calc: 4.765 mA, -1.589 mA 2019-04-09T15:40:40.229Z,1554824440.229 [CBIT](FAULT): Main Battery Failure. Count: 91 2019-04-09T15:40:40.636Z,1554824440.636 [SBIT](ERROR): Could not read massPosReader_. 2019-04-09T15:40:41.415Z,1554824441.415 [NAL9602](INFO): NAL9602 initialized 2019-04-09T15:40:42.234Z,1554824442.234 [NAL9602](DEBUG): Fix Requested 2019-04-09T15:40:44.325Z,1554824444.325 [CBIT](FAULT): Main Battery Failure. Count: 101 2019-04-09T15:40:45.542Z,1554824445.542 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-04-09T15:40:45.542Z,1554824445.542 [CTD_Seabird] Communications Fault, FailCount= 3 2019-04-09T15:40:45.542Z,1554824445.542 [CTD_Seabird](ERROR): Communications Fault 2019-04-09T15:40:45.555Z,1554824445.555 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-04-09T15:40:45.556Z,1554824445.556 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2019-04-09T15:40:45.946Z,1554824445.946 [CTD_Seabird](INFO): Powering down 2019-04-09T15:40:47.875Z,1554824447.875 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-09T15:40:47.875Z,1554824447.875 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2 2019-04-09T15:40:47.875Z,1554824447.875 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-09T15:40:48.067Z,1554824448.067 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-09T15:40:48.279Z,1554824448.279 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-09T15:40:48.672Z,1554824448.672 [CBIT](FAULT): Main Battery Failure. Count: 111 2019-04-09T15:40:49.150Z,1554824449.150 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-09T15:40:49.150Z,1554824449.150 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2 2019-04-09T15:40:51.507Z,1554824451.507 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-09T15:40:51.507Z,1554824451.507 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-09T15:40:52.345Z,1554824452.345 [CBIT](FAULT): Main Battery Failure. Count: 121 2019-04-09T15:40:56.405Z,1554824456.405 [CBIT](FAULT): Main Battery Failure. Count: 131 2019-04-09T15:41:00.441Z,1554824460.441 [CBIT](FAULT): Main Battery Failure. Count: 141 2019-04-09T15:41:04.469Z,1554824464.469 [CBIT](FAULT): Main Battery Failure. Count: 151 2019-04-09T15:41:07.293Z,1554824467.293 [SBIT](ERROR): Could not read massPosReader_. 2019-04-09T15:41:07.294Z,1554824467.294 [SBIT](FAULT): Control surface position failure. 2019-04-09T15:41:08.513Z,1554824468.513 [CBIT](FAULT): Main Battery Failure. Count: 161 2019-04-09T15:41:11.708Z,1554824471.708 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-09T15:41:11.708Z,1554824471.708 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3 2019-04-09T15:41:11.708Z,1554824471.708 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-09T15:41:11.807Z,1554824471.807 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-09T15:41:12.110Z,1554824472.110 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-09T15:41:12.796Z,1554824472.796 [CBIT](FAULT): Main Battery Failure. Count: 171 2019-04-09T15:41:12.968Z,1554824472.968 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-09T15:41:12.968Z,1554824472.968 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3 2019-04-09T15:41:15.342Z,1554824475.342 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-09T15:41:15.343Z,1554824475.343 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-09T15:41:16.606Z,1554824476.606 [CBIT](FAULT): Main Battery Failure. Count: 181 2019-04-09T15:41:20.630Z,1554824480.630 [CBIT](FAULT): Main Battery Failure. Count: 191 2019-04-09T15:41:21.034Z,1554824481.034 [SBIT](ERROR): Could not read massPosReader_. 2019-04-09T15:41:21.034Z,1554824481.034 [SBIT](FAULT): Control surface position failure. 2019-04-09T15:41:21.447Z,1554824481.447 [SBIT](CRITICAL): SBIT FAILED 2019-04-09T15:41:21.462Z,1554824481.462 [CBIT](INFO): Critical error at 20190409T154121 2019-04-09T15:41:21.483Z,1554824481.483 [CommandLine](IMPORTANT): got command configSet list 2019-04-09T15:41:21.483Z,1554824481.483 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-04-09T15:41:21.484Z,1554824481.484 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-04-09T15:41:21.484Z,1554824481.484 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool; 2019-04-09T15:41:21.851Z,1554824481.851 [MissionManager](IMPORTANT): Started mission Startup 2019-04-09T15:41:21.851Z,1554824481.851 [Startup] Running Loop=1 2019-04-09T15:41:21.852Z,1554824481.852 [Startup](DEBUG): Aggregate::initialize Startup 2019-04-09T15:41:21.852Z,1554824481.852 [Startup:A.GoToSurface] Running Loop=1 2019-04-09T15:41:21.852Z,1554824481.852 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-09T15:41:21.852Z,1554824481.852 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-04-09T15:41:21.853Z,1554824481.853 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-04-09T15:41:21.853Z,1554824481.853 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-04-09T15:41:21.854Z,1554824481.854 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-09T15:41:21.854Z,1554824481.854 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-09T15:41:21.856Z,1554824481.856 [Startup:StartupSatComms] Running Loop=1 2019-04-09T15:41:21.856Z,1554824481.856 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-04-09T15:41:21.856Z,1554824481.856 [Startup:StartupSatComms:A] Running Loop=1 2019-04-09T15:41:22.252Z,1554824482.252 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-04-09T15:41:24.670Z,1554824484.670 [CBIT](FAULT): Main Battery Failure. Count: 201 2019-04-09T15:41:28.709Z,1554824488.709 [CBIT](FAULT): Main Battery Failure. Count: 211 2019-04-09T15:41:32.769Z,1554824492.769 [CBIT](FAULT): Main Battery Failure. Count: 221 2019-04-09T15:41:35.542Z,1554824495.542 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-09T15:41:35.543Z,1554824495.543 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4 2019-04-09T15:41:35.543Z,1554824495.543 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-09T15:41:35.581Z,1554824495.581 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-09T15:41:35.946Z,1554824495.946 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-09T15:41:36.792Z,1554824496.792 [CBIT](FAULT): Main Battery Failure. Count: 231 2019-04-09T15:41:36.795Z,1554824496.795 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-09T15:41:36.795Z,1554824496.795 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4 2019-04-09T15:41:39.179Z,1554824499.179 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-09T15:41:39.179Z,1554824499.179 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-09T15:41:40.822Z,1554824500.822 [CBIT](FAULT): Main Battery Failure. Count: 241 2019-04-09T15:41:44.870Z,1554824504.870 [CBIT](FAULT): Main Battery Failure. Count: 251 2019-04-09T15:41:48.920Z,1554824508.920 [CBIT](FAULT): Main Battery Failure. Count: 261 2019-04-09T15:41:52.964Z,1554824512.964 [CBIT](FAULT): Main Battery Failure. Count: 271 2019-04-09T15:41:56.988Z,1554824516.988 [CBIT](FAULT): Main Battery Failure. Count: 281 2019-04-09T15:41:59.379Z,1554824519.379 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-09T15:41:59.379Z,1554824519.379 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5 2019-04-09T15:41:59.379Z,1554824519.379 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-09T15:41:59.432Z,1554824519.432 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-09T15:41:59.782Z,1554824519.782 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-09T15:42:00.632Z,1554824520.632 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-09T15:42:00.632Z,1554824520.632 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5 2019-04-09T15:42:01.032Z,1554824521.032 [CBIT](FAULT): Main Battery Failure. Count: 291 2019-04-09T15:42:03.015Z,1554824523.015 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-09T15:42:03.015Z,1554824523.015 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-09T15:42:03.065Z,1554824523.065 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-04-09T15:42:03.065Z,1554824523.065 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-04-09T15:42:03.065Z,1554824523.065 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-04-09T15:42:03.067Z,1554824523.067 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-04-09T15:42:03.067Z,1554824523.067 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-04-09T15:42:03.067Z,1554824523.067 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-04-09T15:42:03.068Z,1554824523.068 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-04-09T15:42:03.069Z,1554824523.069 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-04-09T15:42:03.069Z,1554824523.069 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-04-09T15:42:03.080Z,1554824523.080 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-04-09T15:42:03.080Z,1554824523.080 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-04-09T15:42:03.080Z,1554824523.080 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-04-09T15:42:03.471Z,1554824523.471 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-04-09T15:42:03.471Z,1554824523.471 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-04-09T15:42:03.471Z,1554824523.471 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-04-09T15:42:03.471Z,1554824523.471 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-04-09T15:42:03.472Z,1554824523.472 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-04-09T15:42:03.472Z,1554824523.472 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-04-09T15:42:03.865Z,1554824523.865 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-04-09T15:42:05.063Z,1554824525.063 [CBIT](FAULT): Main Battery Failure. Count: 301 2019-04-09T15:42:09.125Z,1554824529.125 [CBIT](FAULT): Main Battery Failure. Count: 311 2019-04-09T15:42:13.149Z,1554824533.149 [CBIT](FAULT): Main Battery Failure. Count: 321 2019-04-09T15:42:17.183Z,1554824537.183 [CBIT](FAULT): Main Battery Failure. Count: 331 2019-04-09T15:42:21.231Z,1554824541.231 [CBIT](FAULT): Main Battery Failure. Count: 341 2019-04-09T15:42:22.031Z,1554824542.031 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T15:41:21.9Z 2019-04-09T15:42:22.031Z,1554824542.031 [Startup:StartupSatComms:A] Stopped 2019-04-09T15:42:22.031Z,1554824542.031 [Startup:StartupSatComms:B] Running Loop=1 2019-04-09T15:42:22.436Z,1554824542.436 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-04-09T15:42:23.214Z,1554824543.214 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-09T15:42:23.215Z,1554824543.215 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6 2019-04-09T15:42:23.215Z,1554824543.215 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-09T15:42:23.253Z,1554824543.253 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-09T15:42:23.618Z,1554824543.618 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-09T15:42:24.474Z,1554824544.474 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-09T15:42:24.475Z,1554824544.475 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6 2019-04-09T15:42:25.285Z,1554824545.285 [CBIT](FAULT): Main Battery Failure. Count: 351 2019-04-09T15:42:26.851Z,1554824546.851 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-09T15:42:26.851Z,1554824546.851 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-09T15:42:29.309Z,1554824549.309 [CBIT](FAULT): Main Battery Failure. Count: 361 2019-04-09T15:42:33.350Z,1554824553.350 [CBIT](FAULT): Main Battery Failure. Count: 371 2019-04-09T15:42:37.386Z,1554824557.386 [CBIT](FAULT): Main Battery Failure. Count: 381 2019-04-09T15:42:40.723Z,1554824560.723 [CommandLine](IMPORTANT): got command restart application 2019-04-09T15:42:41.436Z,1554824561.436 [CBIT](FAULT): Main Battery Failure. Count: 391 2019-04-09T15:42:41.726Z,1554824561.726 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-09T15:42:41.726Z,1554824561.726 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:41.754Z,1554824561.754 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-04-09T15:42:41.754Z,1554824561.754 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:41.755Z,1554824561.755 [CommandLine](INFO): Join timeout helper Thread ID is 3106 2019-04-09T15:42:41.755Z,1554824561.755 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-04-09T15:42:41.755Z,1554824561.755 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:41.756Z,1554824561.756 [NavChartDb](INFO): Join timeout helper Thread ID is 3107 2019-04-09T15:42:41.990Z,1554824561.990 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-09T15:42:41.990Z,1554824561.990 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:41.994Z,1554824561.994 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-04-09T15:42:41.994Z,1554824561.994 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:41.994Z,1554824561.994 [Radio_Surface](INFO): Join timeout helper Thread ID is 3108 2019-04-09T15:42:42.090Z,1554824562.090 [Radio_Surface](INFO): Powering down 2019-04-09T15:42:42.091Z,1554824562.091 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-09T15:42:42.091Z,1554824562.091 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.094Z,1554824562.094 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-04-09T15:42:42.094Z,1554824562.094 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.095Z,1554824562.095 [DataOverHttps](INFO): Join timeout helper Thread ID is 3109 2019-04-09T15:42:42.490Z,1554824562.490 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-09T15:42:42.495Z,1554824562.495 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.498Z,1554824562.498 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-04-09T15:42:42.498Z,1554824562.498 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.498Z,1554824562.498 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3110 2019-04-09T15:42:42.678Z,1554824562.678 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-09T15:42:42.679Z,1554824562.679 [WetLabsBB2FL](INFO): Powering down 2019-04-09T15:42:42.680Z,1554824562.680 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.699Z,1554824562.699 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-04-09T15:42:42.699Z,1554824562.699 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.699Z,1554824562.699 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3111 2019-04-09T15:42:42.842Z,1554824562.842 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-09T15:42:42.842Z,1554824562.842 [CTD_Seabird](INFO): Powering down 2019-04-09T15:42:42.854Z,1554824562.854 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.871Z,1554824562.871 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-04-09T15:42:42.871Z,1554824562.871 [logger ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.871Z,1554824562.871 [logger](INFO): Join timeout helper Thread ID is 3112 2019-04-09T15:42:42.942Z,1554824562.942 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-09T15:42:42.942Z,1554824562.942 [logger ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.951Z,1554824562.951 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-04-09T15:42:42.951Z,1554824562.951 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.951Z,1554824562.951 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-04-09T15:42:42.951Z,1554824562.951 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:42.951Z,1554824562.951 [controlThread](INFO): Join timeout helper Thread ID is 3113 2019-04-09T15:42:43.006Z,1554824563.006 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-09T15:42:43.006Z,1554824563.006 [controlThread](DEBUG): Uninitializing ControlThread 2019-04-09T15:42:43.007Z,1554824563.007 [Aanderaa_O2](INFO): Powering down 2019-04-09T15:42:43.009Z,1554824563.009 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-09T15:42:43.126Z,1554824563.126 [NAL9602](INFO): Powering down 2019-04-09T15:42:43.128Z,1554824563.128 [BR_Ping1D](INFO): uninitialize 2019-04-09T15:42:43.128Z,1554824563.128 [BR_Ping1D](INFO): Powering down 2019-04-09T15:42:43.130Z,1554824563.130 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-04-09T15:42:43.131Z,1554824563.131 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-04-09T15:42:43.132Z,1554824563.132 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-04-09T15:42:43.132Z,1554824563.132 [MissionManager](INFO): Uninitializing Mission Default 2019-04-09T15:42:43.134Z,1554824563.134 [MissionManager](INFO): Uninitializing Mission Startup 2019-04-09T15:42:43.135Z,1554824563.135 [Startup] Stopped 2019-04-09T15:42:43.135Z,1554824563.135 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-04-09T15:42:43.135Z,1554824563.135 [Startup:A.GoToSurface] Stopped 2019-04-09T15:42:43.135Z,1554824563.135 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-09T15:42:43.135Z,1554824563.135 [Startup:StartupSatComms] Stopped 2019-04-09T15:42:43.135Z,1554824563.135 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-04-09T15:42:43.135Z,1554824563.135 [Startup:StartupSatComms:B] Stopped 2019-04-09T15:42:43.136Z,1554824563.136 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-04-09T15:42:43.137Z,1554824563.137 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-04-09T15:42:43.137Z,1554824563.137 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-04-09T15:42:43.137Z,1554824563.137 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-04-09T15:42:43.137Z,1554824563.137 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-04-09T15:42:43.138Z,1554824563.138 [BuoyancyServo](INFO): Powering down 2019-04-09T15:42:43.150Z,1554824563.150 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-04-09T15:42:43.150Z,1554824563.150 [ElevatorServo](INFO): Powering down 2019-04-09T15:42:43.151Z,1554824563.151 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-09T15:42:43.151Z,1554824563.151 [MassServo](INFO): Powering down 2019-04-09T15:42:43.152Z,1554824563.152 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-04-09T15:42:43.152Z,1554824563.152 [RudderServo](INFO): Powering down 2019-04-09T15:42:43.153Z,1554824563.153 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-04-09T15:42:43.153Z,1554824563.153 [ThrusterServo](INFO): Powering down 2019-04-09T15:42:43.154Z,1554824563.154 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-04-09T15:42:43.154Z,1554824563.154 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-04-09T15:42:43.154Z,1554824563.154 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-04-09T15:42:43.154Z,1554824563.154 [CBIT](DEBUG): Powering off loads. 2019-04-09T15:42:43.166Z,1554824563.166 [CBIT](DEBUG): Disabling WDT. 2019-04-09T15:42:43.178Z,1554824563.178 [CBIT](DEBUG): Opening all GF detection circuits. 2019-04-09T15:42:43.179Z,1554824563.179 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:43.333Z,1554824563.333 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:43.338Z,1554824563.338 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:43.406Z,1554824563.406 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:43.410Z,1554824563.411 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:43.475Z,1554824563.475 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-09T15:42:43.546Z,1554824563.546 [logger ThreadHandler](INFO): Thread cancelled.