2019-02-16T03:45:56.297Z,1550288756.297 [Supervisor](DEBUG): Initializing supervisor. 2019-02-16T03:45:56.300Z,1550288756.300 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-02-16T03:45:56.301Z,1550288756.301 [SyncHandler](INFO): Protected caller Thread ID is 7249 2019-02-16T03:45:56.301Z,1550288756.301 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-02-16T03:45:56.302Z,1550288756.302 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-02-16T03:45:56.302Z,1550288756.302 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7250 2019-02-16T03:45:56.306Z,1550288756.306 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-02-16T03:45:56.317Z,1550288756.317 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-02-16T03:45:56.318Z,1550288756.318 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-02-16T03:45:56.319Z,1550288756.319 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7251 2019-02-16T03:45:56.319Z,1550288756.319 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-02-16T03:45:56.320Z,1550288756.320 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-02-16T03:45:56.321Z,1550288756.321 [logger ThreadHandler](INFO): Protected caller Thread ID is 7252 2019-02-16T03:45:56.323Z,1550288756.323 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-02-16T03:45:56.323Z,1550288756.323 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-02-16T03:45:56.325Z,1550288756.325 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-02-16T03:45:56.419Z,1550288756.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-02-16T03:45:56.420Z,1550288756.420 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-02-16T03:45:56.620Z,1550288756.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-02-16T03:45:56.621Z,1550288756.621 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-02-16T03:45:56.757Z,1550288756.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-02-16T03:45:56.758Z,1550288756.758 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-02-16T03:45:57.227Z,1550288757.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-02-16T03:45:57.227Z,1550288757.227 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-02-16T03:45:57.708Z,1550288757.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-02-16T03:45:57.708Z,1550288757.708 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-02-16T03:45:58.562Z,1550288758.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-02-16T03:45:58.563Z,1550288758.563 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-02-16T03:45:58.870Z,1550288758.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-02-16T03:45:58.871Z,1550288758.871 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-02-16T03:45:59.221Z,1550288759.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-02-16T03:45:59.221Z,1550288759.221 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-02-16T03:45:59.612Z,1550288759.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-02-16T03:45:59.613Z,1550288759.613 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-02-16T03:45:59.763Z,1550288759.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-02-16T03:45:59.763Z,1550288759.763 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-02-16T03:45:59.870Z,1550288759.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-02-16T03:45:59.870Z,1550288759.870 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-02-16T03:45:59.951Z,1550288759.951 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-02-16T03:46:00.052Z,1550288760.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-02-16T03:46:00.052Z,1550288760.052 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-02-16T03:46:00.250Z,1550288760.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-02-16T03:46:00.250Z,1550288760.250 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-02-16T03:46:00.457Z,1550288760.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-02-16T03:46:00.459Z,1550288760.459 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-02-16T03:46:00.459Z,1550288760.459 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-02-16T03:46:00.543Z,1550288760.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-02-16T03:46:00.777Z,1550288760.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-02-16T03:46:00.778Z,1550288760.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-02-16T03:46:00.873Z,1550288760.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-02-16T03:46:01.033Z,1550288761.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-02-16T03:46:01.237Z,1550288761.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-02-16T03:46:01.321Z,1550288761.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-02-16T03:46:01.419Z,1550288761.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-02-16T03:46:01.511Z,1550288761.511 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-02-16T03:46:01.662Z,1550288761.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-02-16T03:46:01.766Z,1550288761.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-02-16T03:46:01.864Z,1550288761.864 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2019-02-16T03:46:01.865Z,1550288761.865 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-02-16T03:46:01.868Z,1550288761.868 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-02-16T03:46:01.972Z,1550288761.972 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-02-16T03:46:01.972Z,1550288761.972 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-02-16T03:46:02.104Z,1550288762.104 [BuoyancyServo] Loaded 2019-02-16T03:46:02.104Z,1550288762.104 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-02-16T03:46:02.120Z,1550288762.120 [ElevatorServo] Loaded 2019-02-16T03:46:02.120Z,1550288762.120 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-02-16T03:46:02.135Z,1550288762.135 [MassServo] Loaded 2019-02-16T03:46:02.135Z,1550288762.135 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-02-16T03:46:02.150Z,1550288762.150 [RudderServo] Loaded 2019-02-16T03:46:02.151Z,1550288762.151 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-02-16T03:46:02.165Z,1550288762.165 [ThrusterServo] Loaded 2019-02-16T03:46:02.165Z,1550288762.165 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-02-16T03:46:02.166Z,1550288762.166 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-02-16T03:46:02.166Z,1550288762.166 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-02-16T03:46:02.302Z,1550288762.302 [SBIT](DEBUG): Construct Startup Built In Test. 2019-02-16T03:46:02.331Z,1550288762.331 [SBIT] Loaded 2019-02-16T03:46:02.331Z,1550288762.331 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-02-16T03:46:02.332Z,1550288762.332 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-02-16T03:46:02.360Z,1550288762.360 [IBIT] Loaded 2019-02-16T03:46:02.360Z,1550288762.360 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-02-16T03:46:02.364Z,1550288762.364 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-02-16T03:46:02.773Z,1550288762.773 [CBIT] Loaded 2019-02-16T03:46:02.773Z,1550288762.773 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-02-16T03:46:02.774Z,1550288762.774 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-02-16T03:46:02.774Z,1550288762.774 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-02-16T03:46:03.479Z,1550288763.479 [Aanderaa_O2] Loaded 2019-02-16T03:46:03.479Z,1550288763.479 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-02-16T03:46:03.489Z,1550288763.489 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-02-16T03:46:03.494Z,1550288763.494 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-02-16T03:46:03.496Z,1550288763.496 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-02-16T03:46:03.502Z,1550288763.502 [CTD_Seabird](INFO): created writer for : depth 2019-02-16T03:46:03.502Z,1550288763.502 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-02-16T03:46:03.508Z,1550288763.508 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-02-16T03:46:03.508Z,1550288763.508 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-02-16T03:46:03.514Z,1550288763.514 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-02-16T03:46:03.514Z,1550288763.514 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-02-16T03:46:03.520Z,1550288763.520 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-02-16T03:46:03.520Z,1550288763.520 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-02-16T03:46:03.526Z,1550288763.526 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-02-16T03:46:03.526Z,1550288763.526 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-02-16T03:46:03.536Z,1550288763.536 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-02-16T03:46:03.562Z,1550288763.562 [CTD_Seabird] Loaded 2019-02-16T03:46:03.563Z,1550288763.563 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-02-16T03:46:03.564Z,1550288763.564 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4062F4E0 2019-02-16T03:46:03.564Z,1550288763.564 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7331 2019-02-16T03:46:03.586Z,1550288763.586 [ESPComponent] Loaded 2019-02-16T03:46:03.586Z,1550288763.586 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-02-16T03:46:03.600Z,1550288763.600 [PAR_Licor] Loaded 2019-02-16T03:46:03.600Z,1550288763.600 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-02-16T03:46:03.649Z,1550288763.649 [WetLabsBB2FL] Loaded 2019-02-16T03:46:03.650Z,1550288763.650 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-02-16T03:46:03.651Z,1550288763.651 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065F4E0 2019-02-16T03:46:03.651Z,1550288763.651 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7332 2019-02-16T03:46:03.652Z,1550288763.652 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-02-16T03:46:03.652Z,1550288763.652 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-02-16T03:46:03.695Z,1550288763.695 [DepthRateCalculator] Loaded 2019-02-16T03:46:03.695Z,1550288763.695 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-02-16T03:46:03.700Z,1550288763.700 [PitchRateCalculator] Loaded 2019-02-16T03:46:03.701Z,1550288763.701 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-02-16T03:46:03.717Z,1550288763.717 [SpeedCalculator] Loaded 2019-02-16T03:46:03.717Z,1550288763.717 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-02-16T03:46:03.738Z,1550288763.738 [TempGradientCalculator] Loaded 2019-02-16T03:46:03.738Z,1550288763.738 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-02-16T03:46:03.744Z,1550288763.744 [YawRateCalculator] Loaded 2019-02-16T03:46:03.744Z,1550288763.744 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-02-16T03:46:03.778Z,1550288763.778 [ElevatorOffsetCalculator] Loaded 2019-02-16T03:46:03.778Z,1550288763.778 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-02-16T03:46:03.779Z,1550288763.779 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-02-16T03:46:03.779Z,1550288763.779 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-02-16T03:46:03.808Z,1550288763.808 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-02-16T03:46:03.808Z,1550288763.808 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-02-16T03:46:03.912Z,1550288763.912 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-02-16T03:46:03.913Z,1550288763.913 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-02-16T03:46:03.972Z,1550288763.972 [DeadReckonUsingSpeedCalculator] Loaded 2019-02-16T03:46:03.973Z,1550288763.973 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-02-16T03:46:04.033Z,1550288764.033 [DeadReckonWithRespectToSeafloor] Loaded 2019-02-16T03:46:04.033Z,1550288764.033 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-02-16T03:46:04.050Z,1550288764.050 [NavChart] Loaded 2019-02-16T03:46:04.050Z,1550288764.050 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-02-16T03:46:04.054Z,1550288764.054 [UniversalFixResidualReporter] Loaded 2019-02-16T03:46:04.054Z,1550288764.054 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-02-16T03:46:04.055Z,1550288764.055 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-02-16T03:46:04.055Z,1550288764.055 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-02-16T03:46:04.344Z,1550288764.344 [AcousticModem_Benthos_ATM900] Loaded 2019-02-16T03:46:04.345Z,1550288764.345 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2019-02-16T03:46:04.423Z,1550288764.423 [DataOverHttps] Loaded 2019-02-16T03:46:04.423Z,1550288764.423 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-02-16T03:46:04.424Z,1550288764.424 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409264E0 2019-02-16T03:46:04.425Z,1550288764.425 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7333 2019-02-16T03:46:04.438Z,1550288764.438 [Depth_Keller] Loaded 2019-02-16T03:46:04.438Z,1550288764.438 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-02-16T03:46:04.530Z,1550288764.530 [NAL9602] Loaded 2019-02-16T03:46:04.530Z,1550288764.530 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-02-16T03:46:04.536Z,1550288764.536 [Onboard] Loaded 2019-02-16T03:46:04.536Z,1550288764.536 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-02-16T03:46:04.543Z,1550288764.543 [Radio_Surface] Loaded 2019-02-16T03:46:04.544Z,1550288764.544 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-02-16T03:46:04.545Z,1550288764.545 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409564E0 2019-02-16T03:46:04.545Z,1550288764.545 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7334 2019-02-16T03:46:04.593Z,1550288764.593 [PNI_TCM] Loaded 2019-02-16T03:46:04.593Z,1550288764.593 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2019-02-16T03:46:04.654Z,1550288764.654 [Rowe_600LCM] Loaded 2019-02-16T03:46:04.655Z,1550288764.655 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2019-02-16T03:46:04.656Z,1550288764.656 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409864E0 2019-02-16T03:46:04.656Z,1550288764.656 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 7335 2019-02-16T03:46:06.252Z,1550288766.252 [BPC1] Loaded 2019-02-16T03:46:06.253Z,1550288766.253 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-02-16T03:46:06.253Z,1550288766.253 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-02-16T03:46:06.254Z,1550288766.254 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-02-16T03:46:06.323Z,1550288766.323 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-02-16T03:46:06.324Z,1550288766.324 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-02-16T03:46:06.393Z,1550288766.393 [VerticalControl](DEBUG): Construct VerticalControl. 2019-02-16T03:46:06.478Z,1550288766.478 [VerticalControl] Loaded 2019-02-16T03:46:06.479Z,1550288766.479 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-02-16T03:46:06.479Z,1550288766.479 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-02-16T03:46:06.539Z,1550288766.539 [HorizontalControl] Loaded 2019-02-16T03:46:06.539Z,1550288766.539 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-02-16T03:46:06.540Z,1550288766.540 [SpeedControl](DEBUG): Construct SpeedControl. 2019-02-16T03:46:06.541Z,1550288766.541 [SpeedControl] Loaded 2019-02-16T03:46:06.542Z,1550288766.542 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-02-16T03:46:06.542Z,1550288766.542 [LoopControl](DEBUG): Construct LoopControl. 2019-02-16T03:46:06.543Z,1550288766.543 [LoopControl] Loaded 2019-02-16T03:46:06.543Z,1550288766.543 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-02-16T03:46:06.544Z,1550288766.544 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-02-16T03:46:06.544Z,1550288766.544 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-02-16T03:46:06.558Z,1550288766.558 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-02-16T03:46:06.559Z,1550288766.559 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-02-16T03:46:06.803Z,1550288766.803 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-02-16T03:46:06.806Z,1550288766.806 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-02-16T03:46:06.807Z,1550288766.807 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-02-16T03:46:06.815Z,1550288766.815 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-02-16T03:46:06.816Z,1550288766.816 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA84E0 2019-02-16T03:46:06.816Z,1550288766.816 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7336 2019-02-16T03:46:06.821Z,1550288766.821 [Supervisor](INFO): Main Thread ID is 7063 2019-02-16T03:46:06.821Z,1550288766.821 [Supervisor](DEBUG): Running supervisor. 2019-02-16T03:46:06.822Z,1550288766.822 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7337 2019-02-16T03:46:06.824Z,1550288766.824 [controlThread ThreadHandler](INFO): Handler Thread ID is 7338 2019-02-16T03:46:06.825Z,1550288766.825 [controlThread](DEBUG): Initializing ControlThread 2019-02-16T03:46:06.826Z,1550288766.826 [SBIT](INFO): Initialize SBIT Component. 2019-02-16T03:46:06.827Z,1550288766.827 [SBIT](IMPORTANT): git: 2019-02-11-18-g088f29c 2019-02-16T03:46:06.827Z,1550288766.827 [SBIT](INFO): git hash: 088f29c08f1651a006237423a6ffed1cbba6c6ba 2019-02-16T03:46:06.827Z,1550288766.827 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-02-16T03:46:06.829Z,1550288766.829 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 2019-02-16T03:46:06.830Z,1550288766.830 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2019-02-16T03:46:06.831Z,1550288766.831 [IBIT](INFO): Initialize IBIT Component. 2019-02-16T03:46:06.832Z,1550288766.832 [CBIT](DEBUG): Initialize CBIT Component. 2019-02-16T03:46:06.832Z,1550288766.832 [logger ThreadHandler](INFO): Handler Thread ID is 7339 2019-02-16T03:46:06.845Z,1550288766.845 [CBIT](DEBUG): Initialized mux pins. 2019-02-16T03:46:06.845Z,1550288766.845 [CBIT](DEBUG): Initializing the watchdog timer. 2019-02-16T03:46:06.858Z,1550288766.858 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7340 2019-02-16T03:46:06.858Z,1550288766.858 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-02-16T03:46:06.862Z,1550288766.862 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-02-16T03:46:06.863Z,1550288766.863 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7342 2019-02-16T03:46:06.864Z,1550288766.864 [WetLabsBB2FL](INFO): Powering down 2019-02-16T03:46:06.870Z,1550288766.870 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-02-16T03:46:06.870Z,1550288766.870 [CBIT](DEBUG): Initializing heartbeat. 2019-02-16T03:46:06.898Z,1550288766.898 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7343 2019-02-16T03:46:06.899Z,1550288766.899 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-02-16T03:46:06.918Z,1550288766.918 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7344 2019-02-16T03:46:06.934Z,1550288766.934 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 7345 2019-02-16T03:46:06.935Z,1550288766.935 [Rowe_600LCM](INFO): Initializing 2019-02-16T03:46:06.941Z,1550288766.941 [CBIT](DEBUG): Deactivating GF circuits. 2019-02-16T03:46:06.942Z,1550288766.942 [CBIT](DEBUG): Deactivating emergency mode. 2019-02-16T03:46:06.944Z,1550288766.944 [Rowe_600LCM](INFO): Powering up 2019-02-16T03:46:06.954Z,1550288766.954 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7346 2019-02-16T03:46:06.957Z,1550288766.957 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-16T03:46:06.958Z,1550288766.958 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-02-16T03:46:06.958Z,1550288766.958 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-16T03:46:06.958Z,1550288766.958 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-02-16T03:46:06.958Z,1550288766.958 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-16T03:46:06.958Z,1550288766.958 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-02-16T03:46:06.959Z,1550288766.959 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-16T03:46:06.959Z,1550288766.959 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-02-16T03:46:06.959Z,1550288766.959 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-16T03:46:06.959Z,1550288766.959 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-02-16T03:46:06.959Z,1550288766.959 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-16T03:46:06.959Z,1550288766.959 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-02-16T03:46:06.960Z,1550288766.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-16T03:46:06.960Z,1550288766.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-02-16T03:46:06.960Z,1550288766.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-16T03:46:06.960Z,1550288766.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-02-16T03:46:06.982Z,1550288766.982 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-02-16T03:46:06.982Z,1550288766.982 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-02-16T03:46:06.982Z,1550288766.982 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-02-16T03:46:06.983Z,1550288766.983 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-02-16T03:46:06.983Z,1550288766.983 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-02-16T03:46:06.984Z,1550288766.984 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-02-16T03:46:06.985Z,1550288766.985 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-16T03:46:06.986Z,1550288766.986 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-02-16T03:46:06.986Z,1550288766.986 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-02-16T03:46:06.999Z,1550288766.999 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-02-16T03:46:07.001Z,1550288767.001 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-02-16T03:46:07.002Z,1550288767.002 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-02-16T03:46:07.002Z,1550288767.002 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-02-16T03:46:07.003Z,1550288767.003 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-02-16T03:46:07.039Z,1550288767.039 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-16T03:46:07.074Z,1550288767.074 [MissionManager](DEBUG): 2019-02-16T03:46:07.075Z,1550288767.075 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-02-16T03:46:07.167Z,1550288767.167 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-02-16T03:46:07.169Z,1550288767.169 [Default:A.Wait](DEBUG): Construct Wait. 2019-02-16T03:46:07.170Z,1550288767.170 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-16T03:46:07.213Z,1550288767.213 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-02-16T03:46:07.216Z,1550288767.216 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-02-16T03:46:07.252Z,1550288767.252 [Default:E.Execute](DEBUG): Construct Execute. 2019-02-16T03:46:07.255Z,1550288767.255 [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-02-16T03:46:07.274Z,1550288767.274 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-02-16T03:46:07.312Z,1550288767.312 [ESPComponent](INFO): powering down ESP 2019-02-16T03:46:07.322Z,1550288767.322 [Radio_Surface](INFO): Powering up 2019-02-16T03:46:07.616Z,1550288767.616 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-02-16T03:46:07.616Z,1550288767.616 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-02-16T03:46:08.131Z,1550288768.131 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-16T03:46:08.138Z,1550288768.138 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-16T03:46:08.160Z,1550288768.160 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-02-16T03:46:08.166Z,1550288768.166 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-02-16T03:46:08.180Z,1550288768.180 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-16T03:46:08.186Z,1550288768.186 [MassServo](DEBUG): Initializing MassServo. 2019-02-16T03:46:08.200Z,1550288768.200 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-16T03:46:08.206Z,1550288768.206 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-16T03:46:08.228Z,1550288768.228 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-02-16T03:46:08.234Z,1550288768.234 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-02-16T03:46:09.109Z,1550288769.109 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2019-02-16T03:46:09.110Z,1550288769.110 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2019-02-16T03:46:09.110Z,1550288769.110 [BuoyancyServo] Communications Fault, FailCount= 1 2019-02-16T03:46:09.110Z,1550288769.110 [BuoyancyServo](ERROR): Communications Fault 2019-02-16T03:46:09.317Z,1550288769.317 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2019-02-16T03:46:10.654Z,1550288770.654 [Aanderaa_O2](INFO): Powering down 2019-02-16T03:46:11.239Z,1550288771.239 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T03:46:11.316Z,1550288771.316 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-02-16T03:46:11.551Z,1550288771.551 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2019-02-16T03:46:11.551Z,1550288771.551 [BuoyancyServo] No Fault, FailCount= 1 2019-02-16T03:46:11.828Z,1550288771.828 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-16T03:46:11.946Z,1550288771.946 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-16T03:46:15.008Z,1550288775.008 [AcousticModem_Benthos_ATM900](DEBUG): 2019-02-16T03:46:16.619Z,1550288776.619 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2019-02-16T03:46:16.621Z,1550288776.621 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2019-02-16T03:46:16.637Z,1550288776.637 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2019-02-16T03:46:16.638Z,1550288776.638 [AcousticModem_Benthos_ATM900](DEBUG): Feb 11 2019 20:45:13 2019-02-16T03:46:17.007Z,1550288777.007 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2019-02-16T03:46:17.295Z,1550288777.295 [CBIT](CRITICAL): Environmental Failure. Press:14.766769 PSI. Humidity:29%. Temp:25 C. ABORTING MISSION 2019-02-16T03:46:17.296Z,1550288777.296 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals 2019-02-16T03:46:17.515Z,1550288777.515 [CommandLine](FAULT): Scheduling is paused 2019-02-16T03:46:17.516Z,1550288777.516 [CBIT](INFO): Critical error at 20190216T034617 2019-02-16T03:46:17.814Z,1550288777.814 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2019-02-16T03:46:18.219Z,1550288778.219 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2019-02-16T03:46:18.221Z,1550288778.221 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2019-02-16T03:46:18.221Z,1550288778.221 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2019-02-16T03:46:18.622Z,1550288778.622 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2019-02-16T03:46:19.026Z,1550288779.026 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2019-02-16T03:46:19.027Z,1550288779.027 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2019-02-16T03:46:19.432Z,1550288779.432 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2019-02-16T03:46:31.230Z,1550288791.230 [SBIT](IMPORTANT): Beginning Startup BIT 2019-02-16T03:46:31.243Z,1550288791.243 [CBIT](IMPORTANT): Beginning ground fault scan 2019-02-16T03:46:33.131Z,1550288793.131 [CommandLine](IMPORTANT): got command report mod platform_surface_radio_power 2019-02-16T03:46:33.696Z,1550288793.696 [Reporter](INFO): platform_surface_radio_power 1 bool 2019-02-16T03:46:34.923Z,1550288794.923 [NAL9602](INFO): Powering up NAL9602 2019-02-16T03:46:42.299Z,1550288802.299 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.008736 CHAN A1 (24V): -0.012825 CHAN A2 (12V): -0.005378 CHAN A3 (5V): -0.002446 CHAN B0 (3.3V): -0.001407 CHAN B1 (3.15aV): -0.002261 CHAN B2 (3.15bV): -0.001099 CHAN B3 (GND): 0.000164 OPEN: 0.004293 Full Scale Calc: 4.765 mA, -1.589 mA 2019-02-16T03:46:45.831Z,1550288805.831 [NAL9602](INFO): NAL9602 initialized 2019-02-16T03:46:46.881Z,1550288806.881 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:47:16.047Z,1550288836.047 [CommandLine](IMPORTANT): got command failComponent hardware Radio_Surface 2019-02-16T03:47:16.048Z,1550288836.048 [Radio_Surface] Hardware Fault, FailCount= 1 2019-02-16T03:47:16.048Z,1550288836.048 [Radio_Surface](ERROR): Hardware Fault 2019-02-16T03:47:16.048Z,1550288836.048 [CommandLine](IMPORTANT): Radio_Surface failureMode is Hardware Fault 2019-02-16T03:47:16.410Z,1550288836.410 [Radio_Surface](INFO): Powering down 2019-02-16T03:47:16.412Z,1550288836.412 [CBIT](ERROR): Hardware Fault in component: Radio_Surface 2019-02-16T03:47:17.463Z,1550288837.463 [CBIT](INFO): Clearing failed state for component Radio_Surface 2019-02-16T03:47:17.463Z,1550288837.463 [Radio_Surface] No Fault, FailCount= 1 2019-02-16T03:47:17.621Z,1550288837.621 [Radio_Surface](INFO): Powering up 2019-02-16T03:47:24.611Z,1550288844.611 [SBIT](IMPORTANT): SBIT PASSED 2019-02-16T03:47:24.651Z,1550288844.651 [CommandLine](IMPORTANT): got command configSet list 2019-02-16T03:47:24.651Z,1550288844.651 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-02-16T03:47:24.652Z,1550288844.652 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-02-16T03:47:25.022Z,1550288845.022 [MissionManager](IMPORTANT): Started mission Startup 2019-02-16T03:47:25.026Z,1550288845.026 [Startup] Running Loop=1 2019-02-16T03:47:25.026Z,1550288845.026 [Startup](DEBUG): Aggregate::initialize Startup 2019-02-16T03:47:25.026Z,1550288845.026 [Startup:A.GoToSurface] Running Loop=1 2019-02-16T03:47:25.026Z,1550288845.026 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-16T03:47:25.027Z,1550288845.027 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-16T03:47:25.027Z,1550288845.027 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-16T03:47:25.027Z,1550288845.027 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-16T03:47:25.028Z,1550288845.028 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-16T03:47:25.028Z,1550288845.028 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-16T03:47:25.051Z,1550288845.051 [Startup:StartupSatComms] Running Loop=1 2019-02-16T03:47:25.051Z,1550288845.051 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-02-16T03:47:25.051Z,1550288845.051 [Startup:StartupSatComms:A] Running Loop=1 2019-02-16T03:47:25.434Z,1550288845.434 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-02-16T03:48:00.170Z,1550288880.170 [CommandLine](IMPORTANT): got command report touch platform_surface_radio_power 2019-02-16T03:48:00.565Z,1550288880.565 [Reporter](INFO): platform_surface_radio_power 1 bool 2019-02-16T03:48:07.629Z,1550288887.629 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-02-16T03:48:07.629Z,1550288887.629 [Rowe_600LCM] Communications Fault, FailCount= 1 2019-02-16T03:48:07.630Z,1550288887.630 [Rowe_600LCM](ERROR): Communications Fault 2019-02-16T03:48:07.868Z,1550288887.868 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-02-16T03:48:08.034Z,1550288888.034 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T03:48:08.128Z,1550288888.128 [Rowe_600LCM](INFO): Powering down 2019-02-16T03:48:09.470Z,1550288889.470 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-02-16T03:48:09.470Z,1550288889.470 [Rowe_600LCM] No Fault, FailCount= 1 2019-02-16T03:48:09.746Z,1550288889.746 [Rowe_600LCM](INFO): Initializing 2019-02-16T03:48:09.746Z,1550288889.746 [Rowe_600LCM](INFO): Powering up 2019-02-16T03:48:13.949Z,1550288893.949 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T03:48:14.069Z,1550288894.069 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-02-16T03:48:25.191Z,1550288905.191 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-16T03:47:25.1Z 2019-02-16T03:48:25.191Z,1550288905.191 [Startup:StartupSatComms:A] Stopped 2019-02-16T03:48:25.191Z,1550288905.191 [Startup:StartupSatComms:B] Running Loop=1 2019-02-16T03:48:25.598Z,1550288905.598 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-16T03:49:03.388Z,1550288943.388 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 2.000000 meter 2019-02-16T03:49:03.389Z,1550288943.389 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2019-02-16T03:49:03.590Z,1550288943.590 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-02-16T03:49:04.384Z,1550288944.384 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (1 active estimators). 2019-02-16T03:49:04.682Z,1550288944.682 [Radio_Surface](INFO): Powering down 2019-02-16T03:49:07.401Z,1550288947.401 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-02-16T03:49:07.401Z,1550288947.401 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-16T03:49:07.403Z,1550288947.403 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-02-16T03:49:07.403Z,1550288947.403 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-16T03:49:07.404Z,1550288947.404 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-02-16T03:49:07.404Z,1550288947.404 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-16T03:49:07.511Z,1550288947.511 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-16T03:49:07.511Z,1550288947.511 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-16T03:49:07.511Z,1550288947.511 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-16T03:49:07.842Z,1550288947.842 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-16T03:49:07.842Z,1550288947.842 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-02-16T03:49:07.843Z,1550288947.843 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-16T03:49:07.843Z,1550288947.843 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-02-16T03:49:07.843Z,1550288947.843 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-16T03:49:07.843Z,1550288947.843 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-02-16T03:49:08.147Z,1550288948.147 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-16T03:49:25.506Z,1550288965.506 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-16T03:48:25.2Z 2019-02-16T03:49:25.506Z,1550288965.506 [Startup:StartupSatComms:B] Stopped 2019-02-16T03:49:25.506Z,1550288965.506 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-02-16T03:49:25.506Z,1550288965.506 [Startup:StartupSatComms] Stopped 2019-02-16T03:49:25.506Z,1550288965.506 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-02-16T03:49:25.507Z,1550288965.507 [Startup](INFO): Completed Startup 2019-02-16T03:49:25.507Z,1550288965.507 [MissionManager](INFO): Startup is completed. 2019-02-16T03:49:25.508Z,1550288965.508 [MissionManager](INFO): Uninitializing Mission Startup 2019-02-16T03:49:25.508Z,1550288965.508 [Startup] Stopped 2019-02-16T03:49:25.508Z,1550288965.508 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-02-16T03:49:25.508Z,1550288965.508 [Startup:A.GoToSurface] Stopped 2019-02-16T03:49:25.508Z,1550288965.508 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-16T03:49:25.920Z,1550288965.920 [MissionManager](IMPORTANT): Started mission Default 2019-02-16T03:49:25.920Z,1550288965.920 [Default] Running Loop=1 2019-02-16T03:49:25.920Z,1550288965.920 [Default](DEBUG): Aggregate::initialize Default 2019-02-16T03:49:25.920Z,1550288965.920 [Default:B.GoToSurface] Running Loop=1 2019-02-16T03:49:25.920Z,1550288965.920 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-16T03:49:25.921Z,1550288965.921 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-16T03:49:25.921Z,1550288965.921 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-16T03:49:25.921Z,1550288965.921 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-16T03:49:25.922Z,1550288965.922 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-16T03:49:25.922Z,1550288965.922 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-16T03:49:25.922Z,1550288965.922 [Default:A.Wait] Running Loop=1 2019-02-16T03:49:25.923Z,1550288965.923 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-02-16T03:49:32.291Z,1550288972.291 [CommandLine](IMPORTANT): got command maintain clear 2019-02-16T03:49:32.435Z,1550288972.435 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-02-16T03:49:33.366Z,1550288973.366 [Radio_Surface](INFO): Powering up 2019-02-16T03:49:38.932Z,1550288978.932 [Default:A.Wait](INFO): Done Waiting. 2019-02-16T03:49:38.933Z,1550288978.933 [Default:A.Wait] Stopped 2019-02-16T03:49:38.933Z,1550288978.933 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-02-16T03:49:38.980Z,1550288978.980 [Reporter](INFO): platform_surface_radio_power 1 bool 2019-02-16T03:49:39.324Z,1550288979.324 [Default:CheckIn] Running Loop=1 2019-02-16T03:49:39.324Z,1550288979.324 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-02-16T03:49:39.324Z,1550288979.324 [Default:CheckIn:Read_GPS] Running Loop=1 2019-02-16T03:49:39.735Z,1550288979.735 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-02-16T03:50:10.321Z,1550289010.321 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-02-16T03:50:10.321Z,1550289010.321 [Rowe_600LCM] Communications Fault, FailCount= 2 2019-02-16T03:50:10.321Z,1550289010.321 [Rowe_600LCM](ERROR): Communications Fault 2019-02-16T03:50:10.450Z,1550289010.450 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-02-16T03:50:10.726Z,1550289010.726 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T03:50:10.885Z,1550289010.885 [Rowe_600LCM](INFO): Powering down 2019-02-16T03:50:12.488Z,1550289012.488 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-02-16T03:50:12.488Z,1550289012.488 [Rowe_600LCM] No Fault, FailCount= 2 2019-02-16T03:50:12.502Z,1550289012.502 [Rowe_600LCM](INFO): Initializing 2019-02-16T03:50:12.502Z,1550289012.502 [Rowe_600LCM](INFO): Powering up 2019-02-16T03:50:16.630Z,1550289016.630 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T03:50:16.692Z,1550289016.692 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-02-16T03:51:01.403Z,1550289061.403 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-02-16T03:51:12.825Z,1550289072.825 [CommandLine](IMPORTANT): got command report touch Radio_Surface.platform_surface_radio_power 2019-02-16T03:51:21.448Z,1550289081.448 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 2.000000 meter 2019-02-16T03:51:21.449Z,1550289081.449 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2019-02-16T03:51:21.575Z,1550289081.575 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-02-16T03:51:22.518Z,1550289082.518 [Radio_Surface](INFO): Powering down 2019-02-16T03:51:22.882Z,1550289082.882 [Reporter](INFO): Radio_Surface.platform_surface_radio_power no_value 2019-02-16T03:51:29.386Z,1550289089.386 [Radio_Surface](INFO): Powering up 2019-02-16T03:51:34.707Z,1550289094.707 [Reporter](INFO): platform_surface_radio_power 1 bool 2019-02-16T03:51:34.707Z,1550289094.707 [Reporter](INFO): Radio_Surface.platform_surface_radio_power 1 bool 2019-02-16T03:51:35.446Z,1550289095.446 [Radio_Surface](INFO): Powering down 2019-02-16T03:51:35.491Z,1550289095.491 [Reporter](INFO): Radio_Surface.platform_surface_radio_power no_value 2019-02-16T03:51:49.523Z,1550289109.523 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-02-16T03:51:50.337Z,1550289110.337 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:51:50.798Z,1550289110.798 [Radio_Surface](INFO): Powering up 2019-02-16T03:51:55.185Z,1550289115.185 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:51:56.075Z,1550289116.075 [Reporter](INFO): platform_surface_radio_power 1 bool 2019-02-16T03:51:56.075Z,1550289116.075 [Reporter](INFO): Radio_Surface.platform_surface_radio_power 1 bool 2019-02-16T03:51:56.858Z,1550289116.858 [Radio_Surface](INFO): Powering down 2019-02-16T03:51:56.867Z,1550289116.867 [Reporter](INFO): Radio_Surface.platform_surface_radio_power no_value 2019-02-16T03:51:58.009Z,1550289118.009 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:00.845Z,1550289120.845 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:02.854Z,1550289122.854 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:02.920Z,1550289122.920 [Radio_Surface](INFO): Powering up 2019-02-16T03:52:04.877Z,1550289124.877 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:06.893Z,1550289126.893 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:07.375Z,1550289127.375 [CommandLine](IMPORTANT): got command maintain clear 2019-02-16T03:52:07.403Z,1550289127.403 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-02-16T03:52:08.263Z,1550289128.263 [Reporter](INFO): platform_surface_radio_power 1 bool 2019-02-16T03:52:08.263Z,1550289128.263 [Reporter](INFO): Radio_Surface.platform_surface_radio_power 1 bool 2019-02-16T03:52:08.535Z,1550289128.535 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-02-16T03:52:08.535Z,1550289128.535 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-16T03:52:08.536Z,1550289128.536 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-02-16T03:52:08.536Z,1550289128.536 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-16T03:52:08.538Z,1550289128.538 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2019-02-16T03:52:08.538Z,1550289128.538 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-16T03:52:08.550Z,1550289128.550 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-16T03:52:08.551Z,1550289128.551 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-16T03:52:08.551Z,1550289128.551 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-16T03:52:08.921Z,1550289128.921 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:09.074Z,1550289129.074 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-16T03:52:09.074Z,1550289129.074 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-02-16T03:52:09.075Z,1550289129.075 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-16T03:52:09.075Z,1550289129.075 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-02-16T03:52:09.075Z,1550289129.075 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-16T03:52:09.075Z,1550289129.075 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2019-02-16T03:52:09.355Z,1550289129.355 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-16T03:52:12.233Z,1550289132.233 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:12.929Z,1550289132.929 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-02-16T03:52:12.929Z,1550289132.929 [Rowe_600LCM] Communications Fault, FailCount= 3 2019-02-16T03:52:12.929Z,1550289132.929 [Rowe_600LCM](ERROR): Communications Fault 2019-02-16T03:52:13.143Z,1550289133.143 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-02-16T03:52:13.334Z,1550289133.334 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T03:52:13.481Z,1550289133.481 [Rowe_600LCM](INFO): Powering down 2019-02-16T03:52:14.811Z,1550289134.811 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-02-16T03:52:14.811Z,1550289134.811 [Rowe_600LCM] No Fault, FailCount= 3 2019-02-16T03:52:15.069Z,1550289135.069 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:15.098Z,1550289135.098 [Rowe_600LCM](INFO): Initializing 2019-02-16T03:52:15.098Z,1550289135.098 [Rowe_600LCM](INFO): Powering up 2019-02-16T03:52:17.885Z,1550289137.885 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:19.270Z,1550289139.270 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T03:52:19.336Z,1550289139.336 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-02-16T03:52:19.909Z,1550289139.909 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:21.934Z,1550289141.934 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:25.161Z,1550289145.161 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:27.985Z,1550289147.985 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:31.222Z,1550289151.222 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:34.049Z,1550289154.049 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:37.277Z,1550289157.277 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:40.109Z,1550289160.109 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:42.933Z,1550289162.933 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:44.961Z,1550289164.961 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:46.977Z,1550289166.977 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:50.205Z,1550289170.205 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:53.037Z,1550289173.037 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:56.269Z,1550289176.269 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:52:59.101Z,1550289179.101 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:02.334Z,1550289182.334 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:05.161Z,1550289185.161 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:07.989Z,1550289187.989 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:10.005Z,1550289190.005 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:13.237Z,1550289193.237 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:16.065Z,1550289196.065 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:19.301Z,1550289199.301 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:22.125Z,1550289202.125 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:25.357Z,1550289205.357 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:28.189Z,1550289208.189 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:31.017Z,1550289211.017 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:33.033Z,1550289213.033 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:36.265Z,1550289216.265 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:39.093Z,1550289219.093 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:40.357Z,1550289220.357 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-02-16T03:53:42.321Z,1550289222.321 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:45.149Z,1550289225.149 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:48.385Z,1550289228.385 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:51.213Z,1550289231.213 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:54.045Z,1550289234.045 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:56.061Z,1550289236.061 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:53:58.081Z,1550289238.081 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:01.309Z,1550289241.309 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:04.146Z,1550289244.146 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:07.378Z,1550289247.378 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:10.205Z,1550289250.205 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:13.437Z,1550289253.437 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:15.621Z,1550289255.621 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-02-16T03:54:15.621Z,1550289255.621 [Rowe_600LCM] Communications Fault, FailCount= 4 2019-02-16T03:54:15.621Z,1550289255.621 [Rowe_600LCM](ERROR): Communications Fault 2019-02-16T03:54:15.899Z,1550289255.899 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-02-16T03:54:16.026Z,1550289256.026 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T03:54:16.110Z,1550289256.110 [Rowe_600LCM](INFO): Powering down 2019-02-16T03:54:16.269Z,1550289256.269 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:17.525Z,1550289257.525 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-02-16T03:54:17.526Z,1550289257.526 [Rowe_600LCM] No Fault, FailCount= 4 2019-02-16T03:54:17.725Z,1550289257.725 [Rowe_600LCM](INFO): Initializing 2019-02-16T03:54:17.726Z,1550289257.726 [Rowe_600LCM](INFO): Powering up 2019-02-16T03:54:19.089Z,1550289259.089 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:21.109Z,1550289261.109 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:21.854Z,1550289261.854 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T03:54:21.978Z,1550289261.978 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-02-16T03:54:24.337Z,1550289264.337 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:27.173Z,1550289267.173 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:30.405Z,1550289270.405 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:33.233Z,1550289273.233 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:36.457Z,1550289276.457 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:39.289Z,1550289279.289 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:54:39.734Z,1550289279.734 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-16T03:49:39.3Z 2019-02-16T03:54:39.734Z,1550289279.734 [Default:CheckIn:Read_GPS] Stopped 2019-02-16T03:54:39.734Z,1550289279.734 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-02-16T03:54:40.138Z,1550289280.138 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-16T03:55:09.686Z,1550289309.686 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-02-16T03:55:09.686Z,1550289309.686 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-16T03:55:09.687Z,1550289309.687 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-02-16T03:55:09.687Z,1550289309.687 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-16T03:55:09.689Z,1550289309.689 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2019-02-16T03:55:09.689Z,1550289309.689 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-16T03:55:09.709Z,1550289309.709 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-16T03:55:09.709Z,1550289309.709 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-16T03:55:09.709Z,1550289309.709 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-16T03:55:10.047Z,1550289310.047 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-16T03:55:10.047Z,1550289310.047 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-02-16T03:55:10.047Z,1550289310.047 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-16T03:55:10.047Z,1550289310.047 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2019-02-16T03:55:10.048Z,1550289310.048 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-16T03:55:10.048Z,1550289310.048 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2019-02-16T03:55:10.383Z,1550289310.383 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2019-02-16T03:55:10.466Z,1550289310.466 [NAL9602](ERROR): received: +CSQ:0 OK 2019-02-16T03:55:10.466Z,1550289310.466 [NAL9602] Data Fault, FailCount= 1 2019-02-16T03:55:10.466Z,1550289310.466 [NAL9602](ERROR): Data Fault 2019-02-16T03:55:10.535Z,1550289310.535 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-16T03:55:10.565Z,1550289310.565 [CBIT](ERROR): Data Fault in component: NAL9602 2019-02-16T03:55:10.787Z,1550289310.787 [NAL9602](INFO): Powering down 2019-02-16T03:55:11.687Z,1550289311.687 [CBIT](INFO): Clearing failed state for component NAL9602 2019-02-16T03:55:11.687Z,1550289311.687 [NAL9602] No Fault, FailCount= 1 2019-02-16T03:55:14.651Z,1550289314.651 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004362 2019-02-16T03:55:19.885Z,1550289319.885 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190216T033217/Courier0007.lzma 2019-02-16T03:55:20.691Z,1550289320.691 [DataOverHttps](INFO): Moved sent file to Logs/20190216T033217/Courier0007.lzma.bak 2019-02-16T03:55:20.691Z,1550289320.691 [DataOverHttps](INFO): SBD MOMSN=9925215 2019-02-16T03:55:34.109Z,1550289334.109 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20190216T034556/Courier0000.lzma 2019-02-16T03:55:34.915Z,1550289334.915 [DataOverHttps](INFO): Moved sent file to Logs/20190216T034556/Courier0000.lzma.bak 2019-02-16T03:55:34.915Z,1550289334.915 [DataOverHttps](INFO): SBD MOMSN=9925217 2019-02-16T03:55:41.099Z,1550289341.099 [NAL9602](INFO): Powering up NAL9602 2019-02-16T03:55:47.057Z,1550289347.057 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190216T034556/Courier0004.lzma 2019-02-16T03:55:47.863Z,1550289347.863 [DataOverHttps](INFO): Moved sent file to Logs/20190216T034556/Courier0004.lzma.bak 2019-02-16T03:55:47.863Z,1550289347.863 [DataOverHttps](INFO): SBD MOMSN=9925221 2019-02-16T03:55:52.011Z,1550289352.011 [NAL9602](INFO): NAL9602 initialized 2019-02-16T03:55:52.825Z,1550289352.825 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:55:55.653Z,1550289355.653 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:55:58.873Z,1550289358.873 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:00.789Z,1550289360.789 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20190216T033217/Express0008.lzma 2019-02-16T03:56:01.595Z,1550289361.595 [DataOverHttps](INFO): Moved sent file to Logs/20190216T033217/Express0008.lzma.bak 2019-02-16T03:56:01.595Z,1550289361.595 [DataOverHttps](INFO): SBD MOMSN=9925223 2019-02-16T03:56:01.709Z,1550289361.709 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:04.941Z,1550289364.941 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:07.769Z,1550289367.769 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:10.589Z,1550289370.589 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:12.626Z,1550289372.626 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:14.458Z,1550289374.458 [DataOverHttps](INFO): Sending 860 bytes from file Logs/20190216T034556/Express0001.lzma 2019-02-16T03:56:14.634Z,1550289374.634 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:15.263Z,1550289375.263 [DataOverHttps](INFO): Moved sent file to Logs/20190216T034556/Express0001.lzma.bak 2019-02-16T03:56:15.263Z,1550289375.263 [DataOverHttps](INFO): SBD MOMSN=9925230 2019-02-16T03:56:17.861Z,1550289377.861 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:18.209Z,1550289378.209 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-02-16T03:56:18.210Z,1550289378.210 [Rowe_600LCM] Communications Fault, FailCount= 5 2019-02-16T03:56:18.210Z,1550289378.210 [Rowe_600LCM](ERROR): Communications Fault 2019-02-16T03:56:18.364Z,1550289378.364 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-02-16T03:56:18.369Z,1550289378.369 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2019-02-16T03:56:18.613Z,1550289378.613 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T03:56:18.876Z,1550289378.876 [Rowe_600LCM](INFO): Powering down 2019-02-16T03:56:20.693Z,1550289380.693 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:23.929Z,1550289383.929 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:26.753Z,1550289386.753 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:28.985Z,1550289388.985 [DataOverHttps](INFO): Sending 373 bytes from file Logs/20190216T034556/Express0005.lzma 2019-02-16T03:56:29.585Z,1550289389.585 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:29.791Z,1550289389.791 [DataOverHttps](INFO): Moved sent file to Logs/20190216T034556/Express0005.lzma.bak 2019-02-16T03:56:29.791Z,1550289389.791 [DataOverHttps](INFO): SBD MOMSN=9925250 2019-02-16T03:56:30.435Z,1550289390.435 [Default:CheckIn:Read_Iridium] Stopped 2019-02-16T03:56:30.435Z,1550289390.435 [Default:CheckIn:C.Wait] Running Loop=1 2019-02-16T03:56:30.435Z,1550289390.435 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-02-16T03:56:31.601Z,1550289391.601 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:33.624Z,1550289393.624 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:35.637Z,1550289395.637 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:38.877Z,1550289398.877 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:41.701Z,1550289401.701 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:44.937Z,1550289404.937 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:56:47.751Z,1550289407.751 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-02-16T03:56:47.751Z,1550289407.751 [NAL9602] Data Fault, FailCount= 2 2019-02-16T03:56:47.751Z,1550289407.751 [NAL9602](ERROR): Data Fault 2019-02-16T03:56:47.823Z,1550289407.823 [CBIT](ERROR): Data Fault in component: NAL9602 2019-02-16T03:56:48.159Z,1550289408.159 [NAL9602](INFO): Powering down 2019-02-16T03:56:49.008Z,1550289409.008 [CBIT](INFO): Clearing failed state for component NAL9602 2019-02-16T03:56:49.009Z,1550289409.009 [NAL9602] No Fault, FailCount= 2 2019-02-16T03:57:18.455Z,1550289438.455 [NAL9602](INFO): Powering up NAL9602 2019-02-16T03:57:29.363Z,1550289449.363 [NAL9602](INFO): NAL9602 initialized 2019-02-16T03:57:30.186Z,1550289450.186 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:57:33.010Z,1550289453.010 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:57:36.250Z,1550289456.250 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:57:39.065Z,1550289459.065 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:57:42.305Z,1550289462.305 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:57:44.271Z,1550289464.271 [CommandLine](IMPORTANT): got command restart application 2019-02-16T03:57:45.129Z,1550289465.129 [NAL9602](DEBUG): Fix Requested 2019-02-16T03:57:45.281Z,1550289465.281 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T03:57:45.281Z,1550289465.281 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:45.469Z,1550289465.469 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-02-16T03:57:45.470Z,1550289465.470 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:45.470Z,1550289465.470 [CommandLine](INFO): Join timeout helper Thread ID is 7408 2019-02-16T03:57:45.471Z,1550289465.471 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-02-16T03:57:45.471Z,1550289465.471 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:45.471Z,1550289465.471 [NavChartDb](INFO): Join timeout helper Thread ID is 7409 2019-02-16T03:57:45.790Z,1550289465.790 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T03:57:45.790Z,1550289465.790 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:45.801Z,1550289465.801 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2019-02-16T03:57:45.802Z,1550289465.802 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:45.802Z,1550289465.802 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 7410 2019-02-16T03:57:46.146Z,1550289466.146 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T03:57:46.147Z,1550289466.147 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T03:57:46.226Z,1550289466.226 [Rowe_600LCM](INFO): Powering down 2019-02-16T03:57:46.227Z,1550289466.227 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.238Z,1550289466.238 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-02-16T03:57:46.238Z,1550289466.238 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.238Z,1550289466.238 [Radio_Surface](INFO): Join timeout helper Thread ID is 7412 2019-02-16T03:57:46.322Z,1550289466.322 [Radio_Surface](INFO): Powering down 2019-02-16T03:57:46.323Z,1550289466.323 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T03:57:46.323Z,1550289466.323 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.330Z,1550289466.330 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-02-16T03:57:46.330Z,1550289466.330 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.330Z,1550289466.330 [DataOverHttps](INFO): Join timeout helper Thread ID is 7413 2019-02-16T03:57:46.366Z,1550289466.366 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T03:57:46.366Z,1550289466.366 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.368Z,1550289466.368 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-02-16T03:57:46.368Z,1550289466.368 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.368Z,1550289466.368 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7414 2019-02-16T03:57:46.483Z,1550289466.483 [Reporter](INFO): Radio_Surface.platform_surface_radio_power no_value 2019-02-16T03:57:46.546Z,1550289466.546 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T03:57:46.546Z,1550289466.546 [WetLabsBB2FL](INFO): Powering down 2019-02-16T03:57:46.547Z,1550289466.547 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.578Z,1550289466.578 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-02-16T03:57:46.579Z,1550289466.579 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.579Z,1550289466.579 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7415 2019-02-16T03:57:46.686Z,1550289466.686 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T03:57:46.686Z,1550289466.686 [CTD_Seabird](INFO): Powering down 2019-02-16T03:57:46.697Z,1550289466.697 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.706Z,1550289466.706 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-02-16T03:57:46.706Z,1550289466.706 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.707Z,1550289466.707 [logger](INFO): Join timeout helper Thread ID is 7416 2019-02-16T03:57:46.770Z,1550289466.770 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T03:57:46.770Z,1550289466.770 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.774Z,1550289466.774 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-02-16T03:57:46.774Z,1550289466.774 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.775Z,1550289466.775 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-02-16T03:57:46.775Z,1550289466.775 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:46.775Z,1550289466.775 [controlThread](INFO): Join timeout helper Thread ID is 7417 2019-02-16T03:57:46.793Z,1550289466.793 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T03:57:46.793Z,1550289466.793 [controlThread](DEBUG): Uninitializing ControlThread 2019-02-16T03:57:46.793Z,1550289466.793 [Aanderaa_O2](INFO): Powering down 2019-02-16T03:57:46.796Z,1550289466.796 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-02-16T03:57:46.914Z,1550289466.914 [NAL9602](INFO): Powering down 2019-02-16T03:57:46.916Z,1550289466.916 [PNI_TCM](INFO): Powering down 2019-02-16T03:57:47.003Z,1550289467.003 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-02-16T03:57:47.004Z,1550289467.004 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-02-16T03:57:47.004Z,1550289467.004 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-02-16T03:57:47.005Z,1550289467.005 [MissionManager](INFO): Uninitializing Mission Default 2019-02-16T03:57:47.005Z,1550289467.005 [Default] Stopped 2019-02-16T03:57:47.005Z,1550289467.005 [Default](DEBUG): Aggregate::uninitialize Default 2019-02-16T03:57:47.005Z,1550289467.005 [Default:B.GoToSurface] Stopped 2019-02-16T03:57:47.005Z,1550289467.005 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-16T03:57:47.006Z,1550289467.006 [Default:CheckIn] Stopped 2019-02-16T03:57:47.006Z,1550289467.006 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-02-16T03:57:47.006Z,1550289467.006 [Default:CheckIn:C.Wait] Stopped 2019-02-16T03:57:47.006Z,1550289467.006 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-02-16T03:57:47.009Z,1550289467.009 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-02-16T03:57:47.009Z,1550289467.009 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-02-16T03:57:47.010Z,1550289467.010 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-02-16T03:57:47.010Z,1550289467.010 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-02-16T03:57:47.010Z,1550289467.010 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-02-16T03:57:47.011Z,1550289467.011 [BuoyancyServo](INFO): Powering down 2019-02-16T03:57:47.026Z,1550289467.026 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-02-16T03:57:47.026Z,1550289467.026 [ElevatorServo](INFO): Powering down 2019-02-16T03:57:47.027Z,1550289467.027 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-16T03:57:47.027Z,1550289467.027 [MassServo](INFO): Powering down 2019-02-16T03:57:47.028Z,1550289467.028 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-02-16T03:57:47.028Z,1550289467.028 [RudderServo](INFO): Powering down 2019-02-16T03:57:47.028Z,1550289467.028 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-02-16T03:57:47.029Z,1550289467.029 [ThrusterServo](INFO): Powering down 2019-02-16T03:57:47.030Z,1550289467.030 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-02-16T03:57:47.030Z,1550289467.030 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-02-16T03:57:47.030Z,1550289467.030 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-02-16T03:57:47.030Z,1550289467.030 [CBIT](DEBUG): Powering off loads. 2019-02-16T03:57:47.041Z,1550289467.041 [CBIT](DEBUG): Disabling WDT. 2019-02-16T03:57:47.053Z,1550289467.053 [CBIT](DEBUG): Opening all GF detection circuits. 2019-02-16T03:57:47.054Z,1550289467.054 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:47.106Z,1550289467.106 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:47.112Z,1550289467.112 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:47.194Z,1550289467.194 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:47.199Z,1550289467.199 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:47.205Z,1550289467.205 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:47.256Z,1550289467.256 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-16T03:57:47.313Z,1550289467.313 [logger ThreadHandler](INFO): Thread cancelled.