2019-02-16T16:29:12.351Z,1550334552.351 [Supervisor](DEBUG): Initializing supervisor. 2019-02-16T16:29:12.354Z,1550334552.354 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-02-16T16:29:12.355Z,1550334552.355 [SyncHandler](INFO): Protected caller Thread ID is 1348 2019-02-16T16:29:12.355Z,1550334552.355 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-02-16T16:29:12.356Z,1550334552.356 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-02-16T16:29:12.357Z,1550334552.357 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1349 2019-02-16T16:29:12.360Z,1550334552.360 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-02-16T16:29:12.371Z,1550334552.371 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-02-16T16:29:12.372Z,1550334552.372 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-02-16T16:29:12.373Z,1550334552.373 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1350 2019-02-16T16:29:12.373Z,1550334552.373 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-02-16T16:29:12.374Z,1550334552.374 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-02-16T16:29:12.375Z,1550334552.375 [logger ThreadHandler](INFO): Protected caller Thread ID is 1351 2019-02-16T16:29:12.377Z,1550334552.377 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-02-16T16:29:12.377Z,1550334552.377 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-02-16T16:29:12.379Z,1550334552.379 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-02-16T16:29:12.474Z,1550334552.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-02-16T16:29:12.475Z,1550334552.475 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-02-16T16:29:12.674Z,1550334552.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-02-16T16:29:12.675Z,1550334552.675 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-02-16T16:29:12.812Z,1550334552.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-02-16T16:29:12.812Z,1550334552.812 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-02-16T16:29:13.292Z,1550334553.292 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-02-16T16:29:13.293Z,1550334553.293 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-02-16T16:29:13.988Z,1550334553.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-02-16T16:29:13.988Z,1550334553.988 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-02-16T16:29:14.462Z,1550334554.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-02-16T16:29:14.463Z,1550334554.463 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-02-16T16:29:14.772Z,1550334554.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-02-16T16:29:14.773Z,1550334554.773 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-02-16T16:29:15.126Z,1550334555.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-02-16T16:29:15.126Z,1550334555.126 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-02-16T16:29:15.519Z,1550334555.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-02-16T16:29:15.520Z,1550334555.520 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-02-16T16:29:15.671Z,1550334555.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-02-16T16:29:15.671Z,1550334555.671 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-02-16T16:29:15.779Z,1550334555.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-02-16T16:29:15.780Z,1550334555.780 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-02-16T16:29:15.861Z,1550334555.861 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-02-16T16:29:15.963Z,1550334555.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-02-16T16:29:15.964Z,1550334555.964 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-02-16T16:29:16.163Z,1550334556.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-02-16T16:29:16.164Z,1550334556.164 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-02-16T16:29:16.373Z,1550334556.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-02-16T16:29:16.375Z,1550334556.375 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-02-16T16:29:16.376Z,1550334556.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-02-16T16:29:16.460Z,1550334556.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-02-16T16:29:16.694Z,1550334556.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-02-16T16:29:16.695Z,1550334556.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-02-16T16:29:16.790Z,1550334556.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-02-16T16:29:16.952Z,1550334556.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-02-16T16:29:17.160Z,1550334557.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-02-16T16:29:17.245Z,1550334557.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-02-16T16:29:17.345Z,1550334557.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-02-16T16:29:17.439Z,1550334557.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-02-16T16:29:17.592Z,1550334557.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-02-16T16:29:17.697Z,1550334557.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-02-16T16:29:17.796Z,1550334557.796 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2019-02-16T16:29:17.796Z,1550334557.796 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-02-16T16:29:17.800Z,1550334557.800 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-02-16T16:29:17.899Z,1550334557.899 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-02-16T16:29:17.899Z,1550334557.899 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-02-16T16:29:18.024Z,1550334558.024 [BuoyancyServo] Loaded 2019-02-16T16:29:18.024Z,1550334558.024 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-02-16T16:29:18.040Z,1550334558.040 [ElevatorServo] Loaded 2019-02-16T16:29:18.040Z,1550334558.040 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-02-16T16:29:18.054Z,1550334558.054 [MassServo] Loaded 2019-02-16T16:29:18.054Z,1550334558.054 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-02-16T16:29:18.069Z,1550334558.069 [RudderServo] Loaded 2019-02-16T16:29:18.070Z,1550334558.070 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-02-16T16:29:18.084Z,1550334558.084 [ThrusterServo] Loaded 2019-02-16T16:29:18.084Z,1550334558.084 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-02-16T16:29:18.084Z,1550334558.084 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-02-16T16:29:18.085Z,1550334558.085 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-02-16T16:29:18.216Z,1550334558.216 [SBIT](DEBUG): Construct Startup Built In Test. 2019-02-16T16:29:18.244Z,1550334558.244 [SBIT] Loaded 2019-02-16T16:29:18.245Z,1550334558.245 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-02-16T16:29:18.245Z,1550334558.245 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-02-16T16:29:18.273Z,1550334558.273 [IBIT] Loaded 2019-02-16T16:29:18.274Z,1550334558.274 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-02-16T16:29:18.277Z,1550334558.277 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-02-16T16:29:19.230Z,1550334559.230 [CBIT] Loaded 2019-02-16T16:29:19.230Z,1550334559.230 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-02-16T16:29:19.231Z,1550334559.231 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-02-16T16:29:19.231Z,1550334559.231 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-02-16T16:29:19.454Z,1550334559.454 [Aanderaa_O2] Loaded 2019-02-16T16:29:19.455Z,1550334559.455 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-02-16T16:29:19.464Z,1550334559.464 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-02-16T16:29:19.469Z,1550334559.469 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-02-16T16:29:19.471Z,1550334559.471 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-02-16T16:29:19.475Z,1550334559.475 [CTD_Seabird](INFO): created writer for : depth 2019-02-16T16:29:19.476Z,1550334559.476 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-02-16T16:29:19.481Z,1550334559.481 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-02-16T16:29:19.482Z,1550334559.482 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-02-16T16:29:19.487Z,1550334559.487 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-02-16T16:29:19.487Z,1550334559.487 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-02-16T16:29:19.493Z,1550334559.493 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-02-16T16:29:19.494Z,1550334559.494 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-02-16T16:29:19.499Z,1550334559.499 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-02-16T16:29:19.500Z,1550334559.500 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-02-16T16:29:19.505Z,1550334559.505 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-02-16T16:29:19.531Z,1550334559.531 [CTD_Seabird] Loaded 2019-02-16T16:29:19.531Z,1550334559.531 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-02-16T16:29:19.532Z,1550334559.532 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4062F4E0 2019-02-16T16:29:19.533Z,1550334559.533 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1430 2019-02-16T16:29:19.554Z,1550334559.554 [ESPComponent] Loaded 2019-02-16T16:29:19.554Z,1550334559.554 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-02-16T16:29:19.568Z,1550334559.568 [PAR_Licor] Loaded 2019-02-16T16:29:19.568Z,1550334559.568 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-02-16T16:29:19.616Z,1550334559.616 [WetLabsBB2FL] Loaded 2019-02-16T16:29:19.616Z,1550334559.616 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-02-16T16:29:19.617Z,1550334559.617 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065F4E0 2019-02-16T16:29:19.618Z,1550334559.618 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1431 2019-02-16T16:29:19.618Z,1550334559.618 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-02-16T16:29:19.619Z,1550334559.619 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-02-16T16:29:19.659Z,1550334559.659 [DepthRateCalculator] Loaded 2019-02-16T16:29:19.659Z,1550334559.659 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-02-16T16:29:19.665Z,1550334559.665 [PitchRateCalculator] Loaded 2019-02-16T16:29:19.665Z,1550334559.665 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-02-16T16:29:19.681Z,1550334559.681 [SpeedCalculator] Loaded 2019-02-16T16:29:19.681Z,1550334559.681 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-02-16T16:29:19.701Z,1550334559.701 [TempGradientCalculator] Loaded 2019-02-16T16:29:19.702Z,1550334559.702 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-02-16T16:29:19.707Z,1550334559.707 [YawRateCalculator] Loaded 2019-02-16T16:29:19.708Z,1550334559.708 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-02-16T16:29:19.741Z,1550334559.741 [ElevatorOffsetCalculator] Loaded 2019-02-16T16:29:19.741Z,1550334559.741 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-02-16T16:29:19.741Z,1550334559.741 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-02-16T16:29:19.742Z,1550334559.742 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-02-16T16:29:19.768Z,1550334559.768 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-02-16T16:29:19.769Z,1550334559.769 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-02-16T16:29:19.869Z,1550334559.869 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-02-16T16:29:19.870Z,1550334559.870 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-02-16T16:29:19.929Z,1550334559.929 [DeadReckonUsingSpeedCalculator] Loaded 2019-02-16T16:29:19.929Z,1550334559.929 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-02-16T16:29:19.988Z,1550334559.988 [DeadReckonWithRespectToSeafloor] Loaded 2019-02-16T16:29:19.988Z,1550334559.988 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-02-16T16:29:20.004Z,1550334560.004 [NavChart] Loaded 2019-02-16T16:29:20.004Z,1550334560.004 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-02-16T16:29:20.008Z,1550334560.008 [UniversalFixResidualReporter] Loaded 2019-02-16T16:29:20.009Z,1550334560.009 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-02-16T16:29:20.009Z,1550334560.009 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-02-16T16:29:20.010Z,1550334560.010 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-02-16T16:29:20.296Z,1550334560.296 [AcousticModem_Benthos_ATM900] Loaded 2019-02-16T16:29:20.296Z,1550334560.296 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2019-02-16T16:29:20.374Z,1550334560.374 [DataOverHttps] Loaded 2019-02-16T16:29:20.374Z,1550334560.374 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-02-16T16:29:20.375Z,1550334560.375 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409264E0 2019-02-16T16:29:20.376Z,1550334560.376 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1432 2019-02-16T16:29:20.389Z,1550334560.389 [Depth_Keller] Loaded 2019-02-16T16:29:20.389Z,1550334560.389 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-02-16T16:29:20.479Z,1550334560.479 [NAL9602] Loaded 2019-02-16T16:29:20.479Z,1550334560.479 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-02-16T16:29:20.484Z,1550334560.484 [Onboard] Loaded 2019-02-16T16:29:20.484Z,1550334560.484 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-02-16T16:29:20.487Z,1550334560.487 [Radio_Surface] Loaded 2019-02-16T16:29:20.488Z,1550334560.488 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-02-16T16:29:20.489Z,1550334560.489 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409564E0 2019-02-16T16:29:20.489Z,1550334560.489 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1433 2019-02-16T16:29:20.535Z,1550334560.535 [PNI_TCM] Loaded 2019-02-16T16:29:20.536Z,1550334560.536 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2019-02-16T16:29:20.596Z,1550334560.596 [Rowe_600LCM] Loaded 2019-02-16T16:29:20.596Z,1550334560.596 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2019-02-16T16:29:20.597Z,1550334560.597 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409864E0 2019-02-16T16:29:20.597Z,1550334560.597 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1434 2019-02-16T16:29:22.172Z,1550334562.172 [BPC1] Loaded 2019-02-16T16:29:22.172Z,1550334562.172 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-02-16T16:29:22.172Z,1550334562.172 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-02-16T16:29:22.173Z,1550334562.173 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-02-16T16:29:22.237Z,1550334562.237 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-02-16T16:29:22.237Z,1550334562.237 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-02-16T16:29:22.304Z,1550334562.304 [VerticalControl](DEBUG): Construct VerticalControl. 2019-02-16T16:29:22.388Z,1550334562.388 [VerticalControl] Loaded 2019-02-16T16:29:22.388Z,1550334562.388 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-02-16T16:29:22.389Z,1550334562.389 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-02-16T16:29:22.447Z,1550334562.447 [HorizontalControl] Loaded 2019-02-16T16:29:22.447Z,1550334562.447 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-02-16T16:29:22.447Z,1550334562.447 [SpeedControl](DEBUG): Construct SpeedControl. 2019-02-16T16:29:22.449Z,1550334562.449 [SpeedControl] Loaded 2019-02-16T16:29:22.449Z,1550334562.449 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-02-16T16:29:22.450Z,1550334562.450 [LoopControl](DEBUG): Construct LoopControl. 2019-02-16T16:29:22.451Z,1550334562.451 [LoopControl] Loaded 2019-02-16T16:29:22.451Z,1550334562.451 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-02-16T16:29:22.451Z,1550334562.451 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-02-16T16:29:22.452Z,1550334562.452 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-02-16T16:29:22.465Z,1550334562.465 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-02-16T16:29:22.465Z,1550334562.465 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-02-16T16:29:22.698Z,1550334562.698 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-02-16T16:29:22.702Z,1550334562.702 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-02-16T16:29:22.703Z,1550334562.703 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-02-16T16:29:22.709Z,1550334562.709 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-02-16T16:29:22.710Z,1550334562.710 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA84E0 2019-02-16T16:29:22.711Z,1550334562.711 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1435 2019-02-16T16:29:22.715Z,1550334562.715 [Supervisor](INFO): Main Thread ID is 807 2019-02-16T16:29:22.715Z,1550334562.715 [Supervisor](DEBUG): Running supervisor. 2019-02-16T16:29:22.716Z,1550334562.716 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1436 2019-02-16T16:29:22.719Z,1550334562.719 [controlThread ThreadHandler](INFO): Handler Thread ID is 1437 2019-02-16T16:29:22.719Z,1550334562.719 [controlThread](DEBUG): Initializing ControlThread 2019-02-16T16:29:22.721Z,1550334562.721 [SBIT](INFO): Initialize SBIT Component. 2019-02-16T16:29:22.721Z,1550334562.721 [SBIT](IMPORTANT): git: 2019-02-11-18-g088f29c 2019-02-16T16:29:22.721Z,1550334562.721 [SBIT](INFO): git hash: 088f29c08f1651a006237423a6ffed1cbba6c6ba 2019-02-16T16:29:22.722Z,1550334562.722 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-02-16T16:29:22.723Z,1550334562.723 [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-16T16:29:22.724Z,1550334562.724 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2019-02-16T16:29:22.725Z,1550334562.725 [IBIT](INFO): Initialize IBIT Component. 2019-02-16T16:29:22.726Z,1550334562.726 [CBIT](DEBUG): Initialize CBIT Component. 2019-02-16T16:29:22.727Z,1550334562.727 [logger ThreadHandler](INFO): Handler Thread ID is 1438 2019-02-16T16:29:22.738Z,1550334562.738 [CBIT](DEBUG): Initialized mux pins. 2019-02-16T16:29:22.738Z,1550334562.738 [CBIT](DEBUG): Initializing the watchdog timer. 2019-02-16T16:29:22.746Z,1550334562.746 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1439 2019-02-16T16:29:22.747Z,1550334562.747 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-02-16T16:29:22.751Z,1550334562.751 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-02-16T16:29:22.752Z,1550334562.752 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1441 2019-02-16T16:29:22.753Z,1550334562.753 [WetLabsBB2FL](INFO): Powering down 2019-02-16T16:29:22.766Z,1550334562.766 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-02-16T16:29:22.767Z,1550334562.767 [CBIT](DEBUG): Initializing heartbeat. 2019-02-16T16:29:22.815Z,1550334562.815 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1442 2019-02-16T16:29:22.816Z,1550334562.816 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-02-16T16:29:22.838Z,1550334562.838 [CBIT](DEBUG): Deactivating GF circuits. 2019-02-16T16:29:22.838Z,1550334562.838 [CBIT](DEBUG): Deactivating emergency mode. 2019-02-16T16:29:22.851Z,1550334562.851 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1443 2019-02-16T16:29:22.867Z,1550334562.867 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1444 2019-02-16T16:29:22.867Z,1550334562.867 [Rowe_600LCM](INFO): Initializing 2019-02-16T16:29:22.878Z,1550334562.878 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-02-16T16:29:22.879Z,1550334562.879 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-02-16T16:29:22.879Z,1550334562.879 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-02-16T16:29:22.879Z,1550334562.879 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-02-16T16:29:22.880Z,1550334562.880 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-02-16T16:29:22.880Z,1550334562.880 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-02-16T16:29:22.882Z,1550334562.882 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-16T16:29:22.884Z,1550334562.884 [Rowe_600LCM](INFO): Powering up 2019-02-16T16:29:22.886Z,1550334562.886 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-02-16T16:29:22.886Z,1550334562.886 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-02-16T16:29:22.899Z,1550334562.899 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-02-16T16:29:22.901Z,1550334562.901 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-02-16T16:29:22.910Z,1550334562.910 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-02-16T16:29:22.911Z,1550334562.911 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-02-16T16:29:22.912Z,1550334562.912 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-02-16T16:29:22.914Z,1550334562.914 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1445 2019-02-16T16:29:22.918Z,1550334562.918 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-16T16:29:22.918Z,1550334562.918 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-02-16T16:29:22.919Z,1550334562.919 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-16T16:29:22.919Z,1550334562.919 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-02-16T16:29:22.919Z,1550334562.919 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-16T16:29:22.919Z,1550334562.919 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-02-16T16:29:22.919Z,1550334562.919 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-16T16:29:22.920Z,1550334562.920 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-02-16T16:29:22.920Z,1550334562.920 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-16T16:29:22.920Z,1550334562.920 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-02-16T16:29:22.920Z,1550334562.920 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-16T16:29:22.920Z,1550334562.920 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-02-16T16:29:22.921Z,1550334562.921 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-16T16:29:22.921Z,1550334562.921 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-02-16T16:29:22.921Z,1550334562.921 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-16T16:29:22.921Z,1550334562.921 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-02-16T16:29:22.975Z,1550334562.975 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-16T16:29:23.010Z,1550334563.010 [MissionManager](DEBUG): 2019-02-16T16:29:23.011Z,1550334563.011 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-02-16T16:29:23.123Z,1550334563.123 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-02-16T16:29:23.124Z,1550334563.124 [Default:A.Wait](DEBUG): Construct Wait. 2019-02-16T16:29:23.150Z,1550334563.150 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-16T16:29:23.181Z,1550334563.181 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-02-16T16:29:23.184Z,1550334563.184 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-02-16T16:29:23.235Z,1550334563.235 [Default:E.Execute](DEBUG): Construct Execute. 2019-02-16T16:29:23.254Z,1550334563.254 [Radio_Surface](INFO): Powering up 2019-02-16T16:29:23.262Z,1550334563.262 [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-16T16:29:23.267Z,1550334563.267 [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-16T16:29:23.360Z,1550334563.360 [ESPComponent](INFO): powering down ESP 2019-02-16T16:29:23.820Z,1550334563.820 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-02-16T16:29:23.820Z,1550334563.820 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-02-16T16:29:24.454Z,1550334564.454 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-16T16:29:24.467Z,1550334564.467 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-16T16:29:24.497Z,1550334564.497 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-02-16T16:29:24.498Z,1550334564.498 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-02-16T16:29:24.529Z,1550334564.529 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-16T16:29:24.546Z,1550334564.546 [MassServo](DEBUG): Initializing MassServo. 2019-02-16T16:29:24.587Z,1550334564.587 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-16T16:29:24.597Z,1550334564.597 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-16T16:29:24.625Z,1550334564.625 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-02-16T16:29:24.642Z,1550334564.642 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-02-16T16:29:25.302Z,1550334565.302 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2019-02-16T16:29:25.302Z,1550334565.302 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2019-02-16T16:29:25.303Z,1550334565.303 [BuoyancyServo] Communications Fault, FailCount= 1 2019-02-16T16:29:25.303Z,1550334565.303 [BuoyancyServo](ERROR): Communications Fault 2019-02-16T16:29:25.594Z,1550334565.594 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2019-02-16T16:29:27.339Z,1550334567.339 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T16:29:27.498Z,1550334567.498 [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-16T16:29:27.815Z,1550334567.815 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2019-02-16T16:29:27.815Z,1550334567.815 [BuoyancyServo] No Fault, FailCount= 1 2019-02-16T16:29:28.154Z,1550334568.154 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-16T16:29:28.275Z,1550334568.275 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-16T16:29:28.614Z,1550334568.614 [Aanderaa_O2](INFO): Powering down 2019-02-16T16:29:29.783Z,1550334569.783 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2019-02-16T16:29:31.167Z,1550334571.167 [AcousticModem_Benthos_ATM900](DEBUG): 2019-02-16T16:29:32.784Z,1550334572.784 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2019-02-16T16:29:32.785Z,1550334572.785 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2019-02-16T16:29:32.785Z,1550334572.785 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2019-02-16T16:29:32.786Z,1550334572.786 [AcousticModem_Benthos_ATM900](DEBUG): Feb 12 2019 07:02:24 2019-02-16T16:29:32.910Z,1550334572.910 [CBIT](CRITICAL): Environmental Failure. Press:14.841851 PSI. Humidity:28%. Temp:26 C. ABORTING MISSION 2019-02-16T16:29:32.911Z,1550334572.911 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals 2019-02-16T16:29:33.200Z,1550334573.200 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2019-02-16T16:29:33.601Z,1550334573.601 [CommandLine](FAULT): Scheduling is paused 2019-02-16T16:29:33.601Z,1550334573.601 [CBIT](INFO): Critical error at 20190216T162932 2019-02-16T16:29:34.015Z,1550334574.015 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2019-02-16T16:29:34.431Z,1550334574.431 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2019-02-16T16:29:34.432Z,1550334574.432 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2019-02-16T16:29:34.432Z,1550334574.432 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2019-02-16T16:29:34.823Z,1550334574.823 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2019-02-16T16:29:35.227Z,1550334575.227 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2019-02-16T16:29:35.228Z,1550334575.228 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2019-02-16T16:29:35.631Z,1550334575.631 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2019-02-16T16:29:47.443Z,1550334587.443 [SBIT](IMPORTANT): Beginning Startup BIT 2019-02-16T16:29:47.456Z,1550334587.456 [CBIT](IMPORTANT): Beginning ground fault scan 2019-02-16T16:29:50.771Z,1550334590.771 [NAL9602](INFO): Powering up NAL9602 2019-02-16T16:29:58.960Z,1550334598.960 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.009886 CHAN A1 (24V): -0.012455 CHAN A2 (12V): -0.005811 CHAN A3 (5V): -0.002577 CHAN B0 (3.3V): -0.001014 CHAN B1 (3.15aV): -0.000963 CHAN B2 (3.15bV): -0.001199 CHAN B3 (GND): -0.000109 OPEN: 0.004441 Full Scale Calc: 4.765 mA, -1.589 mA 2019-02-16T16:30:01.679Z,1550334601.679 [NAL9602](INFO): NAL9602 initialized 2019-02-16T16:30:02.498Z,1550334602.498 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:30:36.532Z,1550334636.532 [CommandLine](IMPORTANT): got command report touch Radio_Surface.radio_surface_power 2019-02-16T16:30:40.810Z,1550334640.810 [SBIT](IMPORTANT): SBIT PASSED 2019-02-16T16:30:40.863Z,1550334640.863 [CommandLine](IMPORTANT): got command configSet list 2019-02-16T16:30:40.864Z,1550334640.864 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-02-16T16:30:40.864Z,1550334640.864 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-02-16T16:30:41.231Z,1550334641.231 [MissionManager](IMPORTANT): Started mission Startup 2019-02-16T16:30:41.231Z,1550334641.231 [Startup] Running Loop=1 2019-02-16T16:30:41.231Z,1550334641.231 [Startup](DEBUG): Aggregate::initialize Startup 2019-02-16T16:30:41.231Z,1550334641.231 [Startup:A.GoToSurface] Running Loop=1 2019-02-16T16:30:41.232Z,1550334641.232 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-16T16:30:41.232Z,1550334641.232 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-16T16:30:41.233Z,1550334641.233 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-16T16:30:41.233Z,1550334641.233 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-16T16:30:41.233Z,1550334641.233 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-16T16:30:41.234Z,1550334641.234 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-16T16:30:41.240Z,1550334641.240 [Startup:StartupSatComms] Running Loop=1 2019-02-16T16:30:41.240Z,1550334641.240 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-02-16T16:30:41.240Z,1550334641.240 [Startup:StartupSatComms:A] Running Loop=1 2019-02-16T16:30:41.624Z,1550334641.624 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-02-16T16:30:55.338Z,1550334655.338 [CommandLine](IMPORTANT): got command get Radio_Surface.radio_surface_power 2019-02-16T16:30:55.339Z,1550334655.339 [CommandLine](IMPORTANT): Radio_Surface.radio_surface_power 1 enum 2019-02-16T16:31:23.014Z,1550334683.014 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-02-16T16:31:23.014Z,1550334683.014 [Rowe_600LCM] Communications Fault, FailCount= 1 2019-02-16T16:31:23.014Z,1550334683.014 [Rowe_600LCM](ERROR): Communications Fault 2019-02-16T16:31:23.283Z,1550334683.283 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-02-16T16:31:23.418Z,1550334683.418 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T16:31:23.522Z,1550334683.522 [Rowe_600LCM](INFO): Powering down 2019-02-16T16:31:24.859Z,1550334684.859 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-02-16T16:31:24.859Z,1550334684.859 [Rowe_600LCM] No Fault, FailCount= 1 2019-02-16T16:31:25.138Z,1550334685.138 [Rowe_600LCM](INFO): Initializing 2019-02-16T16:31:25.139Z,1550334685.139 [Rowe_600LCM](INFO): Powering up 2019-02-16T16:31:27.699Z,1550334687.699 [CommandLine](IMPORTANT): got command maintain sensor Radio_Surface.radio_surface_power 0.000000 enum 2019-02-16T16:31:27.701Z,1550334687.701 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Radio_Surface.radio_surface_power" handled in the control thread. 2019-02-16T16:31:28.096Z,1550334688.096 [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_Radio_Surface.radio_surface_power,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-16T16:31:28.511Z,1550334688.511 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:28.878Z,1550334688.878 [DataOverHttps](INFO): RADIO_SURFACE_POWER state change: 0 2019-02-16T16:31:28.929Z,1550334688.929 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:29.320Z,1550334689.320 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T16:31:29.379Z,1550334689.379 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:29.451Z,1550334689.451 [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-16T16:31:29.747Z,1550334689.747 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:30.154Z,1550334690.154 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:30.587Z,1550334690.587 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:30.923Z,1550334690.923 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:31.415Z,1550334691.415 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:31.752Z,1550334691.752 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:32.155Z,1550334692.155 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:32.552Z,1550334692.552 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:32.950Z,1550334692.950 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:33.346Z,1550334693.346 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:33.759Z,1550334693.759 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:34.164Z,1550334694.164 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:34.568Z,1550334694.568 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:34.972Z,1550334694.972 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:35.374Z,1550334695.374 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:35.782Z,1550334695.782 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:36.193Z,1550334696.193 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:36.570Z,1550334696.570 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:36.977Z,1550334696.977 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:37.387Z,1550334697.387 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:37.803Z,1550334697.803 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:38.192Z,1550334698.192 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:38.605Z,1550334698.605 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:39.025Z,1550334699.025 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:39.432Z,1550334699.432 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:39.844Z,1550334699.844 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:40.211Z,1550334700.211 [CommandLine](IMPORTANT): got command maintain clear 2019-02-16T16:31:40.229Z,1550334700.229 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:31:40.251Z,1550334700.251 [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-16T16:31:41.413Z,1550334701.413 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-16T16:30:41.2Z 2019-02-16T16:31:41.413Z,1550334701.413 [Startup:StartupSatComms:A] Stopped 2019-02-16T16:31:41.413Z,1550334701.413 [Startup:StartupSatComms:B] Running Loop=1 2019-02-16T16:31:41.827Z,1550334701.827 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-16T16:32:23.019Z,1550334743.019 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-02-16T16:32:23.019Z,1550334743.019 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-16T16:32:23.021Z,1550334743.021 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-02-16T16:32:23.021Z,1550334743.021 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-16T16:32:23.022Z,1550334743.022 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-02-16T16:32:23.022Z,1550334743.022 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-16T16:32:23.035Z,1550334743.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-16T16:32:23.035Z,1550334743.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-16T16:32:23.035Z,1550334743.035 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-16T16:32:23.445Z,1550334743.445 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-16T16:32:23.445Z,1550334743.445 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-02-16T16:32:23.446Z,1550334743.446 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-16T16:32:23.446Z,1550334743.446 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-02-16T16:32:23.446Z,1550334743.446 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-16T16:32:23.446Z,1550334743.446 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-02-16T16:32:23.895Z,1550334743.895 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-16T16:32:32.608Z,1550334752.608 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004677 2019-02-16T16:32:38.874Z,1550334758.874 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190216T161737/Courier0004.lzma 2019-02-16T16:32:39.680Z,1550334759.680 [DataOverHttps](INFO): Moved sent file to Logs/20190216T161737/Courier0004.lzma.bak 2019-02-16T16:32:39.680Z,1550334759.680 [DataOverHttps](INFO): SBD MOMSN=9993331 2019-02-16T16:32:41.607Z,1550334761.607 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-16T16:31:41.4Z 2019-02-16T16:32:41.607Z,1550334761.607 [Startup:StartupSatComms:B] Stopped 2019-02-16T16:32:41.607Z,1550334761.607 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-02-16T16:32:41.607Z,1550334761.607 [Startup:StartupSatComms] Stopped 2019-02-16T16:32:41.607Z,1550334761.607 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-02-16T16:32:41.608Z,1550334761.608 [Startup](INFO): Completed Startup 2019-02-16T16:32:41.608Z,1550334761.608 [MissionManager](INFO): Startup is completed. 2019-02-16T16:32:41.608Z,1550334761.608 [MissionManager](INFO): Uninitializing Mission Startup 2019-02-16T16:32:41.608Z,1550334761.608 [Startup] Stopped 2019-02-16T16:32:41.608Z,1550334761.608 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-02-16T16:32:41.609Z,1550334761.609 [Startup:A.GoToSurface] Stopped 2019-02-16T16:32:41.609Z,1550334761.609 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-16T16:32:42.064Z,1550334762.064 [MissionManager](IMPORTANT): Started mission Default 2019-02-16T16:32:42.064Z,1550334762.064 [Default] Running Loop=1 2019-02-16T16:32:42.065Z,1550334762.065 [Default](DEBUG): Aggregate::initialize Default 2019-02-16T16:32:42.065Z,1550334762.065 [Default:B.GoToSurface] Running Loop=1 2019-02-16T16:32:42.065Z,1550334762.065 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-02-16T16:32:42.065Z,1550334762.065 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-02-16T16:32:42.065Z,1550334762.065 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-02-16T16:32:42.066Z,1550334762.066 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-02-16T16:32:42.066Z,1550334762.066 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-02-16T16:32:42.066Z,1550334762.066 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-02-16T16:32:42.067Z,1550334762.067 [Default:A.Wait] Running Loop=1 2019-02-16T16:32:42.067Z,1550334762.067 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-02-16T16:32:51.897Z,1550334771.897 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190216T161737/Courier0007.lzma 2019-02-16T16:32:52.703Z,1550334772.703 [DataOverHttps](INFO): Moved sent file to Logs/20190216T161737/Courier0007.lzma.bak 2019-02-16T16:32:52.704Z,1550334772.704 [DataOverHttps](INFO): SBD MOMSN=9993334 2019-02-16T16:32:55.342Z,1550334775.342 [Default:A.Wait](INFO): Done Waiting. 2019-02-16T16:32:55.342Z,1550334775.342 [Default:A.Wait] Stopped 2019-02-16T16:32:55.342Z,1550334775.342 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-02-16T16:32:55.751Z,1550334775.751 [Default:CheckIn] Running Loop=1 2019-02-16T16:32:55.751Z,1550334775.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-02-16T16:32:55.751Z,1550334775.751 [Default:CheckIn:Read_GPS] Running Loop=1 2019-02-16T16:32:56.160Z,1550334776.160 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-02-16T16:33:10.688Z,1550334790.688 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-02-16T16:33:17.400Z,1550334797.400 [CommandLine](IMPORTANT): got command configSet DataOverHttps.verbosity 3.000000 count 2019-02-16T16:33:17.750Z,1550334797.750 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:18.154Z,1550334798.154 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:18.558Z,1550334798.558 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:18.962Z,1550334798.962 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:19.367Z,1550334799.367 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:19.770Z,1550334799.770 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:20.174Z,1550334800.174 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:20.578Z,1550334800.578 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:20.982Z,1550334800.982 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:21.386Z,1550334801.386 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:21.790Z,1550334801.790 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:22.194Z,1550334802.194 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:22.598Z,1550334802.598 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:23.002Z,1550334803.002 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:23.406Z,1550334803.406 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:23.810Z,1550334803.810 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:24.214Z,1550334804.214 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:24.618Z,1550334804.618 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:25.022Z,1550334805.022 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:25.426Z,1550334805.426 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:25.734Z,1550334805.734 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-02-16T16:33:25.734Z,1550334805.734 [Rowe_600LCM] Communications Fault, FailCount= 2 2019-02-16T16:33:25.734Z,1550334805.734 [Rowe_600LCM](ERROR): Communications Fault 2019-02-16T16:33:25.830Z,1550334805.830 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:26.085Z,1550334806.085 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-02-16T16:33:26.138Z,1550334806.138 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T16:33:26.235Z,1550334806.235 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:26.280Z,1550334806.280 [Rowe_600LCM](INFO): Powering down 2019-02-16T16:33:26.638Z,1550334806.638 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:27.042Z,1550334807.042 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:27.446Z,1550334807.446 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:27.679Z,1550334807.679 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-02-16T16:33:27.679Z,1550334807.679 [Rowe_600LCM] No Fault, FailCount= 2 2019-02-16T16:33:27.850Z,1550334807.850 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:27.894Z,1550334807.894 [Rowe_600LCM](INFO): Initializing 2019-02-16T16:33:27.895Z,1550334807.895 [Rowe_600LCM](INFO): Powering up 2019-02-16T16:33:28.254Z,1550334808.254 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:28.658Z,1550334808.658 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:29.064Z,1550334809.064 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:29.467Z,1550334809.467 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:29.870Z,1550334809.870 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:30.274Z,1550334810.274 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:30.678Z,1550334810.678 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:31.082Z,1550334811.082 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:31.486Z,1550334811.486 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:31.890Z,1550334811.890 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:32.025Z,1550334812.025 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T16:33:32.149Z,1550334812.149 [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-16T16:33:32.295Z,1550334812.295 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:32.698Z,1550334812.698 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:33.102Z,1550334813.102 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:33.506Z,1550334813.506 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:33.910Z,1550334813.910 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:34.314Z,1550334814.314 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:34.718Z,1550334814.718 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:35.122Z,1550334815.122 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:35.526Z,1550334815.526 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:35.930Z,1550334815.930 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:36.334Z,1550334816.334 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:36.738Z,1550334816.738 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:37.142Z,1550334817.142 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:37.547Z,1550334817.547 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:37.950Z,1550334817.950 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:38.355Z,1550334818.355 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:38.758Z,1550334818.758 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:39.162Z,1550334819.162 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:39.566Z,1550334819.566 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:39.970Z,1550334819.970 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:40.374Z,1550334820.374 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:40.778Z,1550334820.778 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:40.799Z,1550334820.799 [CommandLine](IMPORTANT): got command get Radio_Surface.radio_surface_power 2019-02-16T16:33:40.799Z,1550334820.799 [CommandLine](IMPORTANT): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:33:41.182Z,1550334821.182 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:41.586Z,1550334821.586 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:41.990Z,1550334821.990 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:42.394Z,1550334822.394 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:42.798Z,1550334822.798 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:43.202Z,1550334823.202 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:43.606Z,1550334823.606 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:44.010Z,1550334824.010 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:44.414Z,1550334824.414 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:44.818Z,1550334824.818 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:45.222Z,1550334825.222 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:45.626Z,1550334825.626 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:46.030Z,1550334826.030 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:46.435Z,1550334826.435 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:46.838Z,1550334826.838 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:47.242Z,1550334827.242 [DataOverHttps](INFO): data state is TCP_CONNECT 2019-02-16T16:33:47.646Z,1550334827.646 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:33:48.050Z,1550334828.050 [DataOverHttps](INFO): data state is SSL_CONNECTING 2019-02-16T16:33:48.454Z,1550334828.454 [DataOverHttps](INFO): data state is SSL_CONNECTING 2019-02-16T16:33:48.858Z,1550334828.858 [DataOverHttps](INFO): data state is SSL_CONNECTING 2019-02-16T16:33:49.262Z,1550334829.262 [DataOverHttps](INFO): data state is SSL_CONNECTING 2019-02-16T16:33:52.936Z,1550334832.936 [DataOverHttps](INFO): data state is SSL_CONNECTING 2019-02-16T16:33:53.338Z,1550334833.338 [DataOverHttps](INFO): data state is SSL_CONNECTING 2019-02-16T16:33:53.340Z,1550334833.340 [DataOverHttps](IMPORTANT): dataWrite() sendFilename_: , busy: 1 2019-02-16T16:33:53.341Z,1550334833.341 [DataOverHttps](IMPORTANT): dataWriting 208 bytes: POST /TethysDash/dataLink HTTP/1.1 User-Agent: LRAUV Host: 134.89.2.23:443 Accept: */* Content-Type: application/x-www-form-urlencoded Content-Length: 42 imei=300234060751590&imeiPassword=Hde%603X 2019-02-16T16:33:53.342Z,1550334833.342 [DataOverHttps](IMPORTANT): dataWriting with SSL WROTE 208 bytes: POST /TethysDash/dataLink HTTP/1.1 User-Agent: LRAUV Host: 134.89.2.23:443 Accept: */* Content-Type: application/x-www-form-urlencoded Content-Length: 42 imei=300234060751590&imeiPassword=Hde%603X 2019-02-16T16:33:53.742Z,1550334833.742 [DataOverHttps](INFO): data state is DATA_READ 2019-02-16T16:33:53.743Z,1550334833.743 [DataOverHttps](INFO): dataread() @740 received: 211 2019-02-16T16:33:54.146Z,1550334834.146 [DataOverHttps](INFO): data state is DATA_READ 2019-02-16T16:33:54.147Z,1550334834.147 [DataOverHttps](INFO): dataread() @740 received: 24 2019-02-16T16:33:54.551Z,1550334834.551 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:54.954Z,1550334834.954 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:55.295Z,1550334835.295 [CommandLine](IMPORTANT): got command get Radio_Surface.radio_surface_power 2019-02-16T16:33:55.295Z,1550334835.295 [CommandLine](IMPORTANT): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:33:55.358Z,1550334835.358 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:55.762Z,1550334835.762 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:56.166Z,1550334836.166 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:56.570Z,1550334836.570 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:56.974Z,1550334836.974 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:57.378Z,1550334837.378 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:57.782Z,1550334837.782 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:58.186Z,1550334838.186 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:58.590Z,1550334838.590 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:58.994Z,1550334838.994 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:59.400Z,1550334839.400 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:33:59.802Z,1550334839.802 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:00.206Z,1550334840.206 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:00.610Z,1550334840.610 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:01.014Z,1550334841.014 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:01.418Z,1550334841.418 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:01.822Z,1550334841.822 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:02.226Z,1550334842.226 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:02.630Z,1550334842.630 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:03.034Z,1550334843.034 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:03.438Z,1550334843.438 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:03.842Z,1550334843.842 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:04.246Z,1550334844.246 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:04.650Z,1550334844.650 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:05.054Z,1550334845.054 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:05.458Z,1550334845.458 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:05.863Z,1550334845.863 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:06.266Z,1550334846.266 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:06.670Z,1550334846.670 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:07.074Z,1550334847.074 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:07.478Z,1550334847.478 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:07.882Z,1550334847.882 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:08.286Z,1550334848.286 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:08.690Z,1550334848.690 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:09.094Z,1550334849.094 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:09.498Z,1550334849.498 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:09.902Z,1550334849.902 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:10.306Z,1550334850.306 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:10.710Z,1550334850.710 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:11.114Z,1550334851.114 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:11.518Z,1550334851.518 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:11.922Z,1550334851.922 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:12.326Z,1550334852.326 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:12.730Z,1550334852.730 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:13.134Z,1550334853.134 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:13.538Z,1550334853.538 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:13.942Z,1550334853.942 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:14.346Z,1550334854.346 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:14.750Z,1550334854.750 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:15.154Z,1550334855.154 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:15.558Z,1550334855.558 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:15.962Z,1550334855.962 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:16.366Z,1550334856.366 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:16.770Z,1550334856.770 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:17.174Z,1550334857.174 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:17.578Z,1550334857.578 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:17.982Z,1550334857.982 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:18.386Z,1550334858.386 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:18.790Z,1550334858.790 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:19.195Z,1550334859.195 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:19.598Z,1550334859.598 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:20.002Z,1550334860.002 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:20.406Z,1550334860.406 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:20.810Z,1550334860.810 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:21.214Z,1550334861.214 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:21.618Z,1550334861.618 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:22.022Z,1550334862.022 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:22.426Z,1550334862.426 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:22.830Z,1550334862.830 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:23.234Z,1550334863.234 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:23.638Z,1550334863.638 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:24.042Z,1550334864.042 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:24.446Z,1550334864.446 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:24.850Z,1550334864.850 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:25.254Z,1550334865.254 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:25.658Z,1550334865.658 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:26.062Z,1550334866.062 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:26.466Z,1550334866.466 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:26.870Z,1550334866.870 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:27.274Z,1550334867.274 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:27.678Z,1550334867.678 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:28.082Z,1550334868.082 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:28.486Z,1550334868.486 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:28.892Z,1550334868.892 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:29.294Z,1550334869.294 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:29.698Z,1550334869.698 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:30.102Z,1550334870.102 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:30.506Z,1550334870.506 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:30.910Z,1550334870.910 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:31.314Z,1550334871.314 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:31.718Z,1550334871.718 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:32.124Z,1550334872.124 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:32.526Z,1550334872.526 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:32.930Z,1550334872.930 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:33.334Z,1550334873.334 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:33.738Z,1550334873.738 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:34.142Z,1550334874.142 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:34.546Z,1550334874.546 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:34.950Z,1550334874.950 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:35.354Z,1550334875.354 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:35.758Z,1550334875.758 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:36.162Z,1550334876.162 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:36.567Z,1550334876.567 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:36.970Z,1550334876.970 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:37.374Z,1550334877.374 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:37.779Z,1550334877.779 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:38.182Z,1550334878.182 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:38.586Z,1550334878.586 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:38.991Z,1550334878.991 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:39.394Z,1550334879.394 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:39.798Z,1550334879.798 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:40.203Z,1550334880.203 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:40.606Z,1550334880.606 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:41.010Z,1550334881.010 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:41.414Z,1550334881.414 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:41.818Z,1550334881.818 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:42.222Z,1550334882.222 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:42.626Z,1550334882.626 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:43.030Z,1550334883.030 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:43.434Z,1550334883.434 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:43.846Z,1550334883.846 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:44.243Z,1550334884.243 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:44.646Z,1550334884.646 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:45.053Z,1550334885.053 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:45.454Z,1550334885.454 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:45.858Z,1550334885.858 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:46.220Z,1550334886.220 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 3.000000 meter 2019-02-16T16:34:46.221Z,1550334886.221 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2019-02-16T16:34:46.262Z,1550334886.262 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:46.462Z,1550334886.462 [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-16T16:34:46.666Z,1550334886.666 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:47.070Z,1550334887.070 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:34:47.242Z,1550334887.242 [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-16T16:34:47.370Z,1550334887.370 [Radio_Surface](INFO): Powering down 2019-02-16T16:34:47.474Z,1550334887.474 [DataOverHttps](INFO): data state is TCP_CONNECT 2019-02-16T16:34:47.772Z,1550334887.772 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:34:47.878Z,1550334887.878 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:48.282Z,1550334888.282 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:48.686Z,1550334888.686 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:49.090Z,1550334889.090 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:49.495Z,1550334889.495 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:49.898Z,1550334889.898 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:50.302Z,1550334890.302 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:50.706Z,1550334890.706 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:51.110Z,1550334891.110 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:51.517Z,1550334891.517 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:51.919Z,1550334891.919 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:52.323Z,1550334892.323 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:52.726Z,1550334892.726 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:53.130Z,1550334893.130 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:53.351Z,1550334893.351 [CommandLine](IMPORTANT): got command maintain clear 2019-02-16T16:34:53.523Z,1550334893.523 [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-16T16:34:53.534Z,1550334893.534 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:53.940Z,1550334893.940 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:54.238Z,1550334894.238 [Radio_Surface](INFO): Powering up 2019-02-16T16:34:54.342Z,1550334894.342 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:54.746Z,1550334894.746 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:55.173Z,1550334895.173 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:55.574Z,1550334895.574 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:55.978Z,1550334895.978 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:56.382Z,1550334896.382 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:56.786Z,1550334896.786 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:57.190Z,1550334897.190 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:57.594Z,1550334897.594 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:57.998Z,1550334897.998 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:58.402Z,1550334898.402 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:58.806Z,1550334898.806 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:59.211Z,1550334899.211 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:34:59.544Z,1550334899.544 [Reporter](INFO): Radio_Surface.radio_surface_power 1 enum 2019-02-16T16:34:59.614Z,1550334899.614 [DataOverHttps](INFO): RADIO_SURFACE_POWER state change: 1 2019-02-16T16:34:59.614Z,1550334899.614 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:00.019Z,1550334900.019 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:00.422Z,1550334900.422 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:00.826Z,1550334900.826 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:01.230Z,1550334901.230 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:01.634Z,1550334901.634 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:02.038Z,1550334902.038 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:02.442Z,1550334902.442 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:02.846Z,1550334902.846 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:03.250Z,1550334903.250 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:03.654Z,1550334903.654 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:04.058Z,1550334904.058 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:04.462Z,1550334904.462 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:04.866Z,1550334904.866 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:05.270Z,1550334905.270 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:05.503Z,1550334905.503 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-02-16T16:35:05.675Z,1550334905.675 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:06.078Z,1550334906.078 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:06.322Z,1550334906.322 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:06.482Z,1550334906.482 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:06.886Z,1550334906.886 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:07.290Z,1550334907.290 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:07.694Z,1550334907.694 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:08.098Z,1550334908.098 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:08.502Z,1550334908.502 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:08.906Z,1550334908.906 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:09.310Z,1550334909.310 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:09.714Z,1550334909.714 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:10.118Z,1550334910.118 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:10.522Z,1550334910.522 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:10.766Z,1550334910.766 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:10.926Z,1550334910.926 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:11.331Z,1550334911.331 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:11.734Z,1550334911.734 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:12.138Z,1550334912.138 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:12.542Z,1550334912.542 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:12.946Z,1550334912.946 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:13.350Z,1550334913.350 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:13.754Z,1550334913.754 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:13.994Z,1550334913.994 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:14.158Z,1550334914.158 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:14.562Z,1550334914.562 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:14.966Z,1550334914.966 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:15.370Z,1550334915.370 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:15.774Z,1550334915.774 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:16.178Z,1550334916.178 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:16.582Z,1550334916.582 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:16.826Z,1550334916.826 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:16.986Z,1550334916.986 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:17.390Z,1550334917.390 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:17.615Z,1550334917.615 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 3.000000 meter 2019-02-16T16:35:17.616Z,1550334917.616 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2019-02-16T16:35:17.734Z,1550334917.734 [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-16T16:35:17.794Z,1550334917.794 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:17.794Z,1550334917.794 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-02-16T16:35:18.198Z,1550334918.198 [DataOverHttps](INFO): data state is TCP_CONNECT 2019-02-16T16:35:18.478Z,1550334918.478 [Radio_Surface](INFO): Powering down 2019-02-16T16:35:18.481Z,1550334918.481 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:35:18.602Z,1550334918.602 [DataOverHttps](INFO): RADIO_SURFACE_POWER state change: 0 2019-02-16T16:35:18.602Z,1550334918.602 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:19.006Z,1550334919.006 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:19.410Z,1550334919.410 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:19.814Z,1550334919.814 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:20.158Z,1550334920.158 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:20.218Z,1550334920.218 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:20.622Z,1550334920.622 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:21.026Z,1550334921.026 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:21.430Z,1550334921.430 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:21.835Z,1550334921.835 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:22.238Z,1550334922.238 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:22.642Z,1550334922.642 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:22.990Z,1550334922.990 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:23.048Z,1550334923.048 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:23.452Z,1550334923.452 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:23.873Z,1550334923.873 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:24.237Z,1550334924.237 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-02-16T16:35:24.237Z,1550334924.237 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-16T16:35:24.239Z,1550334924.239 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-02-16T16:35:24.239Z,1550334924.239 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-16T16:35:24.240Z,1550334924.240 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2019-02-16T16:35:24.240Z,1550334924.240 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-16T16:35:24.275Z,1550334924.275 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:24.318Z,1550334924.318 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-16T16:35:24.319Z,1550334924.319 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-16T16:35:24.319Z,1550334924.319 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-16T16:35:24.680Z,1550334924.680 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:24.706Z,1550334924.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-16T16:35:24.707Z,1550334924.707 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-02-16T16:35:24.707Z,1550334924.707 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-16T16:35:24.707Z,1550334924.707 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-02-16T16:35:24.708Z,1550334924.708 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-16T16:35:24.708Z,1550334924.708 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2019-02-16T16:35:24.971Z,1550334924.971 [CommandLine](IMPORTANT): got command maintain clear 2019-02-16T16:35:25.034Z,1550334925.034 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-16T16:35:25.082Z,1550334925.082 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:25.128Z,1550334925.128 [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-16T16:35:25.486Z,1550334925.486 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:25.814Z,1550334925.814 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:25.891Z,1550334925.891 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:26.154Z,1550334926.154 [Radio_Surface](INFO): Powering up 2019-02-16T16:35:26.294Z,1550334926.294 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:26.698Z,1550334926.698 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:27.102Z,1550334927.102 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:27.507Z,1550334927.507 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:27.910Z,1550334927.910 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:28.314Z,1550334928.314 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:28.382Z,1550334928.382 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-02-16T16:35:28.382Z,1550334928.382 [Rowe_600LCM] Communications Fault, FailCount= 3 2019-02-16T16:35:28.382Z,1550334928.382 [Rowe_600LCM](ERROR): Communications Fault 2019-02-16T16:35:28.718Z,1550334928.718 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:28.786Z,1550334928.786 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T16:35:28.838Z,1550334928.838 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-02-16T16:35:28.930Z,1550334928.930 [Rowe_600LCM](INFO): Powering down 2019-02-16T16:35:29.054Z,1550334929.054 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:29.122Z,1550334929.122 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:29.527Z,1550334929.527 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:29.930Z,1550334929.930 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:30.334Z,1550334930.334 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:30.355Z,1550334930.355 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-02-16T16:35:30.355Z,1550334930.355 [Rowe_600LCM] No Fault, FailCount= 3 2019-02-16T16:35:30.546Z,1550334930.546 [Rowe_600LCM](INFO): Initializing 2019-02-16T16:35:30.547Z,1550334930.547 [Rowe_600LCM](INFO): Powering up 2019-02-16T16:35:30.738Z,1550334930.738 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:31.142Z,1550334931.142 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:31.546Z,1550334931.546 [DataOverHttps](INFO): RADIO_SURFACE_POWER state change: 1 2019-02-16T16:35:31.546Z,1550334931.546 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:31.628Z,1550334931.628 [Reporter](INFO): Radio_Surface.radio_surface_power 1 enum 2019-02-16T16:35:31.878Z,1550334931.878 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:31.950Z,1550334931.950 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:32.354Z,1550334932.354 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:32.758Z,1550334932.758 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:33.162Z,1550334933.162 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:33.566Z,1550334933.566 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:33.970Z,1550334933.970 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:34.374Z,1550334934.374 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:34.673Z,1550334934.673 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T16:35:34.778Z,1550334934.778 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:34.798Z,1550334934.798 [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-16T16:35:35.106Z,1550334935.106 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:35.182Z,1550334935.182 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:35.586Z,1550334935.586 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:35.990Z,1550334935.990 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:36.394Z,1550334936.394 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:36.798Z,1550334936.798 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:37.202Z,1550334937.202 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:37.606Z,1550334937.606 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:37.934Z,1550334937.934 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:38.010Z,1550334938.010 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:38.414Z,1550334938.414 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:38.818Z,1550334938.818 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:39.222Z,1550334939.222 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:39.642Z,1550334939.642 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:40.046Z,1550334940.046 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:40.450Z,1550334940.450 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:40.766Z,1550334940.766 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:40.854Z,1550334940.854 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:41.258Z,1550334941.258 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:41.662Z,1550334941.662 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:42.066Z,1550334942.066 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:42.470Z,1550334942.470 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:42.778Z,1550334942.778 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:42.874Z,1550334942.874 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:43.278Z,1550334943.278 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:43.682Z,1550334943.682 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:44.086Z,1550334944.086 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:44.490Z,1550334944.490 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:44.894Z,1550334944.894 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:45.044Z,1550334945.044 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 3.000000 meter 2019-02-16T16:35:45.045Z,1550334945.045 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2019-02-16T16:35:45.270Z,1550334945.270 [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-16T16:35:45.298Z,1550334945.298 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:45.702Z,1550334945.702 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:46.010Z,1550334946.010 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:46.106Z,1550334946.106 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:46.354Z,1550334946.354 [Radio_Surface](INFO): Powering down 2019-02-16T16:35:46.510Z,1550334946.510 [DataOverHttps](INFO): RADIO_SURFACE_POWER state change: 0 2019-02-16T16:35:46.510Z,1550334946.510 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:46.536Z,1550334946.536 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:35:46.914Z,1550334946.914 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:47.318Z,1550334947.318 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:47.722Z,1550334947.722 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:48.127Z,1550334948.127 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:48.530Z,1550334948.530 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:48.530Z,1550334948.530 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-02-16T16:35:48.934Z,1550334948.934 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:48.942Z,1550334948.942 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:49.338Z,1550334949.338 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:49.742Z,1550334949.742 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:50.154Z,1550334950.154 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:50.559Z,1550334950.559 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:50.963Z,1550334950.963 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:51.283Z,1550334951.283 [CommandLine](IMPORTANT): got command maintain clear 2019-02-16T16:35:51.366Z,1550334951.366 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:51.504Z,1550334951.504 [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-16T16:35:51.770Z,1550334951.770 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:51.771Z,1550334951.771 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:52.175Z,1550334952.175 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:52.414Z,1550334952.414 [Radio_Surface](INFO): Powering up 2019-02-16T16:35:52.578Z,1550334952.578 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:52.983Z,1550334952.983 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:53.386Z,1550334953.386 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:53.790Z,1550334953.790 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:53.791Z,1550334953.791 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:54.195Z,1550334954.195 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:54.599Z,1550334954.599 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:55.003Z,1550334955.003 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:55.407Z,1550334955.407 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:55.810Z,1550334955.810 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:55.811Z,1550334955.811 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:56.215Z,1550334956.215 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:56.619Z,1550334956.619 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:57.023Z,1550334957.023 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:57.427Z,1550334957.427 [DataOverHttps](INFO): data state is DISCONNECTED 2019-02-16T16:35:57.831Z,1550334957.831 [DataOverHttps](INFO): RADIO_SURFACE_POWER state change: 1 2019-02-16T16:35:57.831Z,1550334957.831 [DataOverHttps](INFO): data state is TCP_CONNECT 2019-02-16T16:35:57.919Z,1550334957.919 [Reporter](INFO): Radio_Surface.radio_surface_power 1 enum 2019-02-16T16:35:58.235Z,1550334958.235 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:58.639Z,1550334958.639 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:59.042Z,1550334959.042 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:59.046Z,1550334959.046 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:35:59.447Z,1550334959.447 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:35:59.850Z,1550334959.850 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:00.254Z,1550334960.254 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:00.660Z,1550334960.660 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:01.063Z,1550334961.063 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:01.467Z,1550334961.467 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:01.870Z,1550334961.870 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:01.871Z,1550334961.871 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:02.274Z,1550334962.274 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:02.679Z,1550334962.679 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:03.082Z,1550334963.082 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:03.486Z,1550334963.486 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:03.890Z,1550334963.890 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:04.294Z,1550334964.294 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:04.698Z,1550334964.698 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:05.098Z,1550334965.098 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:05.103Z,1550334965.103 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:05.506Z,1550334965.506 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:05.910Z,1550334965.910 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:06.315Z,1550334966.315 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:06.719Z,1550334966.719 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:07.123Z,1550334967.123 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:07.528Z,1550334967.528 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:07.930Z,1550334967.930 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:07.931Z,1550334967.931 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:08.334Z,1550334968.334 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:08.739Z,1550334968.739 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:09.143Z,1550334969.143 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:09.546Z,1550334969.546 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:09.951Z,1550334969.951 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:10.354Z,1550334970.354 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:10.758Z,1550334970.758 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:11.158Z,1550334971.158 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:11.162Z,1550334971.162 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:11.566Z,1550334971.566 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:11.972Z,1550334971.972 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:12.374Z,1550334972.374 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:12.778Z,1550334972.778 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:13.183Z,1550334973.183 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:13.586Z,1550334973.586 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:13.986Z,1550334973.986 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:13.991Z,1550334973.991 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:14.394Z,1550334974.394 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:14.799Z,1550334974.799 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:15.202Z,1550334975.202 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:15.607Z,1550334975.607 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:16.011Z,1550334976.011 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:16.415Z,1550334976.415 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:16.820Z,1550334976.820 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:16.826Z,1550334976.826 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:17.222Z,1550334977.222 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:17.626Z,1550334977.626 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:18.031Z,1550334978.031 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:18.436Z,1550334978.436 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:18.838Z,1550334978.838 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:18.839Z,1550334978.839 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:19.242Z,1550334979.242 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:19.651Z,1550334979.651 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:20.050Z,1550334980.050 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:20.454Z,1550334980.454 [DataOverHttps](INFO): data state is TCP_CONNECTING 2019-02-16T16:36:20.473Z,1550334980.473 [CommandLine](IMPORTANT): got command configSet DataOverHttps.verbosity 0.000000 count 2019-02-16T16:36:22.070Z,1550334982.070 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:24.898Z,1550334984.898 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:28.130Z,1550334988.130 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:28.131Z,1550334988.131 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-02-16T16:36:30.962Z,1550334990.962 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:34.190Z,1550334994.190 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:37.018Z,1550334997.018 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:39.851Z,1550334999.851 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:41.862Z,1550335001.862 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:43.882Z,1550335003.882 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:47.118Z,1550335007.118 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:49.950Z,1550335009.950 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:53.182Z,1550335013.182 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:54.391Z,1550335014.391 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.243591 2019-02-16T16:36:56.006Z,1550335016.006 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:36:59.237Z,1550335019.237 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:02.066Z,1550335022.066 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:04.890Z,1550335024.890 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:06.918Z,1550335026.918 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:10.146Z,1550335030.146 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:12.970Z,1550335032.970 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:16.202Z,1550335036.202 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:19.032Z,1550335039.032 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:22.266Z,1550335042.266 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:25.094Z,1550335045.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-02-16T16:37:25.098Z,1550335045.098 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:27.926Z,1550335047.926 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:29.938Z,1550335049.938 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:31.046Z,1550335051.046 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-02-16T16:37:31.046Z,1550335051.046 [Rowe_600LCM] Communications Fault, FailCount= 4 2019-02-16T16:37:31.046Z,1550335051.046 [Rowe_600LCM](ERROR): Communications Fault 2019-02-16T16:37:31.205Z,1550335051.205 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-02-16T16:37:31.450Z,1550335051.450 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T16:37:31.582Z,1550335051.582 [Rowe_600LCM](INFO): Powering down 2019-02-16T16:37:31.970Z,1550335051.970 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:32.828Z,1550335052.828 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-02-16T16:37:32.828Z,1550335052.828 [Rowe_600LCM] No Fault, FailCount= 4 2019-02-16T16:37:33.198Z,1550335053.198 [Rowe_600LCM](INFO): Initializing 2019-02-16T16:37:33.199Z,1550335053.199 [Rowe_600LCM](INFO): Powering up 2019-02-16T16:37:35.202Z,1550335055.202 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:37.380Z,1550335057.380 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-16T16:37:37.433Z,1550335057.433 [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-16T16:37:38.026Z,1550335058.026 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:41.258Z,1550335061.258 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:44.086Z,1550335064.086 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:47.314Z,1550335067.314 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:50.143Z,1550335070.143 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:52.962Z,1550335072.962 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:54.994Z,1550335074.994 [NAL9602](DEBUG): Fix Requested 2019-02-16T16:37:55.799Z,1550335075.799 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-02-16T16:37:55.846Z,1550335075.846 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-16T16:32:55.8Z 2019-02-16T16:37:55.846Z,1550335075.846 [Default:CheckIn:Read_GPS] Stopped 2019-02-16T16:37:55.846Z,1550335075.846 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-02-16T16:37:56.251Z,1550335076.251 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-02-16T16:38:25.321Z,1550335105.321 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-02-16T16:38:25.321Z,1550335105.321 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-02-16T16:38:25.322Z,1550335105.322 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-02-16T16:38:25.323Z,1550335105.323 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-02-16T16:38:25.324Z,1550335105.324 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2019-02-16T16:38:25.324Z,1550335105.324 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-02-16T16:38:25.341Z,1550335105.341 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-02-16T16:38:25.342Z,1550335105.342 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-02-16T16:38:25.342Z,1550335105.342 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-02-16T16:38:25.758Z,1550335105.758 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-02-16T16:38:25.758Z,1550335105.758 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-02-16T16:38:25.759Z,1550335105.759 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-02-16T16:38:25.759Z,1550335105.759 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2019-02-16T16:38:25.759Z,1550335105.759 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-02-16T16:38:25.759Z,1550335105.759 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2019-02-16T16:38:26.091Z,1550335106.091 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2019-02-16T16:38:26.166Z,1550335106.166 [NAL9602](ERROR): received: +CSQ:0 OK 2019-02-16T16:38:26.166Z,1550335106.166 [NAL9602] Data Fault, FailCount= 1 2019-02-16T16:38:26.166Z,1550335106.166 [NAL9602](ERROR): Data Fault 2019-02-16T16:38:26.196Z,1550335106.196 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-16T16:38:26.209Z,1550335106.209 [CBIT](ERROR): Data Fault in component: NAL9602 2019-02-16T16:38:26.491Z,1550335106.491 [NAL9602](INFO): Powering down 2019-02-16T16:38:26.503Z,1550335106.503 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-02-16T16:38:27.425Z,1550335107.425 [CBIT](INFO): Clearing failed state for component NAL9602 2019-02-16T16:38:27.425Z,1550335107.425 [NAL9602] No Fault, FailCount= 1 2019-02-16T16:38:32.466Z,1550335112.466 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003048 2019-02-16T16:38:38.693Z,1550335118.693 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20190216T162912/Courier0000.lzma 2019-02-16T16:38:39.500Z,1550335119.500 [DataOverHttps](INFO): Moved sent file to Logs/20190216T162912/Courier0000.lzma.bak 2019-02-16T16:38:39.500Z,1550335119.500 [DataOverHttps](INFO): SBD MOMSN=9993336 2019-02-16T16:38:47.171Z,1550335127.171 [CommandLine](IMPORTANT): got command restart application 2019-02-16T16:38:48.177Z,1550335128.177 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T16:38:48.177Z,1550335128.177 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:48.226Z,1550335128.226 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-02-16T16:38:48.226Z,1550335128.226 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:48.227Z,1550335128.227 [CommandLine](INFO): Join timeout helper Thread ID is 1502 2019-02-16T16:38:48.227Z,1550335128.227 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-02-16T16:38:48.227Z,1550335128.227 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:48.228Z,1550335128.228 [NavChartDb](INFO): Join timeout helper Thread ID is 1503 2019-02-16T16:38:48.566Z,1550335128.566 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T16:38:48.566Z,1550335128.566 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:48.570Z,1550335128.570 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2019-02-16T16:38:48.570Z,1550335128.570 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:48.571Z,1550335128.571 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1504 2019-02-16T16:38:48.710Z,1550335128.710 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T16:38:48.710Z,1550335128.710 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-02-16T16:38:48.958Z,1550335128.958 [Rowe_600LCM](INFO): Powering down 2019-02-16T16:38:48.959Z,1550335128.959 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:48.974Z,1550335128.974 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-02-16T16:38:48.974Z,1550335128.974 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:48.975Z,1550335128.975 [Radio_Surface](INFO): Join timeout helper Thread ID is 1506 2019-02-16T16:38:49.366Z,1550335129.366 [Radio_Surface](INFO): Powering down 2019-02-16T16:38:49.368Z,1550335129.368 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T16:38:49.368Z,1550335129.368 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:49.370Z,1550335129.370 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-02-16T16:38:49.370Z,1550335129.370 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:49.371Z,1550335129.371 [DataOverHttps](INFO): Join timeout helper Thread ID is 1507 2019-02-16T16:38:49.615Z,1550335129.615 [Reporter](INFO): Radio_Surface.radio_surface_power 0 enum 2019-02-16T16:38:52.125Z,1550335132.125 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T16:38:52.130Z,1550335132.130 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:52.147Z,1550335132.147 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-02-16T16:38:52.147Z,1550335132.147 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:52.148Z,1550335132.148 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1508 2019-02-16T16:38:52.363Z,1550335132.363 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T16:38:52.363Z,1550335132.363 [WetLabsBB2FL](INFO): Powering down 2019-02-16T16:38:52.364Z,1550335132.364 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:52.371Z,1550335132.371 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-02-16T16:38:52.371Z,1550335132.371 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:52.371Z,1550335132.371 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1509 2019-02-16T16:38:53.238Z,1550335133.238 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T16:38:53.238Z,1550335133.238 [CTD_Seabird](INFO): Powering down 2019-02-16T16:38:53.250Z,1550335133.250 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.267Z,1550335133.267 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-02-16T16:38:53.267Z,1550335133.267 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.267Z,1550335133.267 [logger](INFO): Join timeout helper Thread ID is 1510 2019-02-16T16:38:53.282Z,1550335133.282 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T16:38:53.282Z,1550335133.282 [logger ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.287Z,1550335133.287 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-02-16T16:38:53.287Z,1550335133.287 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.287Z,1550335133.287 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-02-16T16:38:53.287Z,1550335133.287 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.287Z,1550335133.287 [controlThread](INFO): Join timeout helper Thread ID is 1511 2019-02-16T16:38:53.542Z,1550335133.542 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-02-16T16:38:53.542Z,1550335133.542 [controlThread](DEBUG): Uninitializing ControlThread 2019-02-16T16:38:53.543Z,1550335133.543 [Aanderaa_O2](INFO): Powering down 2019-02-16T16:38:53.545Z,1550335133.545 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-02-16T16:38:53.663Z,1550335133.663 [NAL9602](INFO): Powering down 2019-02-16T16:38:53.664Z,1550335133.664 [PNI_TCM](INFO): Powering down 2019-02-16T16:38:53.751Z,1550335133.751 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-02-16T16:38:53.752Z,1550335133.752 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-02-16T16:38:53.753Z,1550335133.753 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-02-16T16:38:53.753Z,1550335133.753 [MissionManager](INFO): Uninitializing Mission Default 2019-02-16T16:38:53.754Z,1550335133.754 [Default] Stopped 2019-02-16T16:38:53.754Z,1550335133.754 [Default](DEBUG): Aggregate::uninitialize Default 2019-02-16T16:38:53.754Z,1550335133.754 [Default:B.GoToSurface] Stopped 2019-02-16T16:38:53.754Z,1550335133.754 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-02-16T16:38:53.754Z,1550335133.754 [Default:CheckIn] Stopped 2019-02-16T16:38:53.754Z,1550335133.754 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-02-16T16:38:53.754Z,1550335133.754 [Default:CheckIn:Read_Iridium] Stopped 2019-02-16T16:38:53.757Z,1550335133.757 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-02-16T16:38:53.757Z,1550335133.757 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-02-16T16:38:53.758Z,1550335133.758 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-02-16T16:38:53.758Z,1550335133.758 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-02-16T16:38:53.758Z,1550335133.758 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-02-16T16:38:53.759Z,1550335133.759 [BuoyancyServo](INFO): Powering down 2019-02-16T16:38:53.770Z,1550335133.770 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-02-16T16:38:53.770Z,1550335133.770 [ElevatorServo](INFO): Powering down 2019-02-16T16:38:53.771Z,1550335133.771 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-16T16:38:53.771Z,1550335133.771 [MassServo](INFO): Powering down 2019-02-16T16:38:53.772Z,1550335133.772 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-02-16T16:38:53.772Z,1550335133.772 [RudderServo](INFO): Powering down 2019-02-16T16:38:53.773Z,1550335133.773 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-02-16T16:38:53.773Z,1550335133.773 [ThrusterServo](INFO): Powering down 2019-02-16T16:38:53.774Z,1550335133.774 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-02-16T16:38:53.774Z,1550335133.774 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-02-16T16:38:53.775Z,1550335133.775 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-02-16T16:38:53.775Z,1550335133.775 [CBIT](DEBUG): Powering off loads. 2019-02-16T16:38:53.786Z,1550335133.786 [CBIT](DEBUG): Disabling WDT. 2019-02-16T16:38:53.798Z,1550335133.798 [CBIT](DEBUG): Opening all GF detection circuits. 2019-02-16T16:38:53.799Z,1550335133.799 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.846Z,1550335133.846 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.851Z,1550335133.851 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.931Z,1550335133.931 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.936Z,1550335133.936 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.941Z,1550335133.941 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:53.991Z,1550335133.991 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-02-16T16:38:54.046Z,1550335134.046 [logger ThreadHandler](INFO): Thread cancelled.