2018-11-06T18:43:55.123Z,1541529835.123 [Supervisor](DEBUG): Initializing supervisor. 2018-11-06T18:43:55.125Z,1541529835.125 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-06T18:43:55.126Z,1541529835.126 [SyncHandler](INFO): Protected caller Thread ID is 6614 2018-11-06T18:43:55.126Z,1541529835.126 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-11-06T18:43:55.127Z,1541529835.127 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-11-06T18:43:55.128Z,1541529835.128 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6615 2018-11-06T18:43:55.130Z,1541529835.130 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-11-06T18:43:55.142Z,1541529835.142 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-11-06T18:43:55.143Z,1541529835.143 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-11-06T18:43:55.143Z,1541529835.143 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6616 2018-11-06T18:43:55.144Z,1541529835.144 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-11-06T18:43:55.145Z,1541529835.145 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-11-06T18:43:55.145Z,1541529835.145 [logger ThreadHandler](INFO): Protected caller Thread ID is 6617 2018-11-06T18:43:55.147Z,1541529835.147 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-11-06T18:43:55.148Z,1541529835.148 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-11-06T18:43:55.149Z,1541529835.149 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-11-06T18:43:55.488Z,1541529835.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-11-06T18:43:55.489Z,1541529835.489 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-11-06T18:43:55.694Z,1541529835.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-11-06T18:43:55.695Z,1541529835.695 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-11-06T18:43:56.009Z,1541529836.009 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-11-06T18:43:56.010Z,1541529836.010 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-11-06T18:43:56.156Z,1541529836.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-11-06T18:43:56.157Z,1541529836.157 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-11-06T18:43:56.255Z,1541529836.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-11-06T18:43:56.256Z,1541529836.256 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-11-06T18:43:56.734Z,1541529836.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-11-06T18:43:56.735Z,1541529836.735 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-11-06T18:43:57.209Z,1541529837.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-11-06T18:43:57.210Z,1541529837.210 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-11-06T18:43:57.905Z,1541529837.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-11-06T18:43:57.906Z,1541529837.906 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-11-06T18:43:58.082Z,1541529838.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-11-06T18:43:58.083Z,1541529838.083 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-11-06T18:43:58.232Z,1541529838.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-11-06T18:43:58.232Z,1541529838.232 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-11-06T18:43:58.455Z,1541529838.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-11-06T18:43:58.455Z,1541529838.455 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-11-06T18:43:58.664Z,1541529838.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-11-06T18:43:58.664Z,1541529838.664 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-11-06T18:43:59.024Z,1541529839.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-11-06T18:43:59.025Z,1541529839.025 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-11-06T18:43:59.172Z,1541529839.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-11-06T18:43:59.172Z,1541529839.172 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-11-06T18:43:59.254Z,1541529839.254 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2018-11-06T18:43:59.255Z,1541529839.255 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2018-11-06T18:43:59.358Z,1541529839.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2018-11-06T18:43:59.478Z,1541529839.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2018-11-06T18:43:59.573Z,1541529839.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2018-11-06T18:43:59.658Z,1541529839.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2018-11-06T18:43:59.827Z,1541529839.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2018-11-06T18:44:00.011Z,1541529840.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2018-11-06T18:44:00.086Z,1541529840.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2018-11-06T18:44:00.318Z,1541529840.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-11-06T18:44:00.319Z,1541529840.319 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2018-11-06T18:44:00.402Z,1541529840.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2018-11-06T18:44:00.493Z,1541529840.493 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2018-11-06T18:44:00.595Z,1541529840.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2018-11-06T18:44:00.723Z,1541529840.723 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-11-06T18:44:00.733Z,1541529840.733 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-11-06T18:44:00.968Z,1541529840.968 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-11-06T18:44:00.968Z,1541529840.968 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-11-06T18:44:01.097Z,1541529841.097 [DepthRateCalculator] Loaded 2018-11-06T18:44:01.097Z,1541529841.097 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-11-06T18:44:01.115Z,1541529841.115 [PitchRateCalculator] Loaded 2018-11-06T18:44:01.115Z,1541529841.115 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-11-06T18:44:01.149Z,1541529841.149 [SpeedCalculator] Loaded 2018-11-06T18:44:01.169Z,1541529841.169 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-11-06T18:44:01.277Z,1541529841.277 [TempGradientCalculator] Loaded 2018-11-06T18:44:01.277Z,1541529841.277 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-11-06T18:44:01.311Z,1541529841.311 [YawRateCalculator] Loaded 2018-11-06T18:44:01.311Z,1541529841.311 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-11-06T18:44:01.447Z,1541529841.447 [ElevatorOffsetCalculator] Loaded 2018-11-06T18:44:01.447Z,1541529841.447 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-11-06T18:44:01.448Z,1541529841.448 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-11-06T18:44:01.448Z,1541529841.448 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-11-06T18:44:01.513Z,1541529841.513 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-11-06T18:44:01.513Z,1541529841.513 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-11-06T18:44:01.627Z,1541529841.627 [BuoyancyServo] Loaded 2018-11-06T18:44:01.627Z,1541529841.627 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-11-06T18:44:01.642Z,1541529841.642 [ElevatorServo] Loaded 2018-11-06T18:44:01.642Z,1541529841.642 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-11-06T18:44:01.657Z,1541529841.657 [MassServo] Loaded 2018-11-06T18:44:01.657Z,1541529841.657 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-11-06T18:44:01.672Z,1541529841.672 [RudderServo] Loaded 2018-11-06T18:44:01.672Z,1541529841.672 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-11-06T18:44:01.687Z,1541529841.687 [ThrusterServo] Loaded 2018-11-06T18:44:01.687Z,1541529841.687 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-11-06T18:44:01.687Z,1541529841.687 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-11-06T18:44:01.688Z,1541529841.688 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-11-06T18:44:01.701Z,1541529841.701 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-11-06T18:44:01.702Z,1541529841.702 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-11-06T18:44:01.799Z,1541529841.799 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-11-06T18:44:01.800Z,1541529841.800 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-11-06T18:44:02.086Z,1541529842.086 [DataOverHttps] Loaded 2018-11-06T18:44:02.087Z,1541529842.087 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-11-06T18:44:02.100Z,1541529842.100 [Depth_Keller] Loaded 2018-11-06T18:44:02.100Z,1541529842.100 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-11-06T18:44:02.105Z,1541529842.105 [DropWeight] Loaded 2018-11-06T18:44:02.105Z,1541529842.105 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-11-06T18:44:02.203Z,1541529842.203 [NAL9602] Loaded 2018-11-06T18:44:02.203Z,1541529842.203 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-11-06T18:44:02.219Z,1541529842.219 [Onboard] Loaded 2018-11-06T18:44:02.219Z,1541529842.219 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-11-06T18:44:02.230Z,1541529842.230 [Radio_Surface] Loaded 2018-11-06T18:44:02.230Z,1541529842.230 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-11-06T18:44:02.231Z,1541529842.231 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088F4E0 2018-11-06T18:44:02.232Z,1541529842.232 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6699 2018-11-06T18:44:02.278Z,1541529842.278 [PNI_TCM] Loaded 2018-11-06T18:44:02.278Z,1541529842.278 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-11-06T18:44:02.339Z,1541529842.339 [Rowe_600LCM] Loaded 2018-11-06T18:44:02.339Z,1541529842.339 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-11-06T18:44:02.340Z,1541529842.340 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0 2018-11-06T18:44:02.340Z,1541529842.340 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6700 2018-11-06T18:44:02.341Z,1541529842.341 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-11-06T18:44:02.341Z,1541529842.341 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-11-06T18:44:02.467Z,1541529842.467 [SBIT](DEBUG): Construct Startup Built In Test. 2018-11-06T18:44:02.489Z,1541529842.489 [SBIT] Loaded 2018-11-06T18:44:02.489Z,1541529842.489 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-11-06T18:44:02.490Z,1541529842.490 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-11-06T18:44:02.502Z,1541529842.502 [IBIT] Loaded 2018-11-06T18:44:02.502Z,1541529842.502 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-11-06T18:44:02.505Z,1541529842.505 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-11-06T18:44:02.653Z,1541529842.653 [CBIT] Loaded 2018-11-06T18:44:02.653Z,1541529842.653 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-11-06T18:44:02.654Z,1541529842.654 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-11-06T18:44:02.654Z,1541529842.654 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-11-06T18:44:02.808Z,1541529842.808 [Aanderaa_O2] Loaded 2018-11-06T18:44:02.808Z,1541529842.808 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-11-06T18:44:02.885Z,1541529842.885 [CTD_NeilBrown] Loaded 2018-11-06T18:44:02.885Z,1541529842.885 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2018-11-06T18:44:02.886Z,1541529842.886 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409894E0 2018-11-06T18:44:02.887Z,1541529842.887 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6701 2018-11-06T18:44:02.901Z,1541529842.901 [PAR_Licor] Loaded 2018-11-06T18:44:02.901Z,1541529842.901 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-11-06T18:44:02.950Z,1541529842.950 [WetLabsBB2FL] Loaded 2018-11-06T18:44:02.950Z,1541529842.950 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-11-06T18:44:02.951Z,1541529842.951 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0 2018-11-06T18:44:02.951Z,1541529842.951 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6702 2018-11-06T18:44:02.952Z,1541529842.952 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-11-06T18:44:02.953Z,1541529842.953 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-11-06T18:44:03.216Z,1541529843.216 [VerticalControl](DEBUG): Construct VerticalControl. 2018-11-06T18:44:03.575Z,1541529843.575 [VerticalControl] Loaded 2018-11-06T18:44:03.576Z,1541529843.576 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-11-06T18:44:03.576Z,1541529843.576 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-11-06T18:44:03.647Z,1541529843.647 [HorizontalControl] Loaded 2018-11-06T18:44:03.648Z,1541529843.648 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-11-06T18:44:03.648Z,1541529843.648 [SpeedControl](DEBUG): Construct SpeedControl. 2018-11-06T18:44:03.650Z,1541529843.650 [SpeedControl] Loaded 2018-11-06T18:44:03.650Z,1541529843.650 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-11-06T18:44:03.651Z,1541529843.651 [LoopControl](DEBUG): Construct LoopControl. 2018-11-06T18:44:03.652Z,1541529843.652 [LoopControl] Loaded 2018-11-06T18:44:03.652Z,1541529843.652 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-11-06T18:44:03.652Z,1541529843.652 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-11-06T18:44:03.653Z,1541529843.653 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-11-06T18:44:03.892Z,1541529843.892 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-11-06T18:44:03.893Z,1541529843.893 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-11-06T18:44:03.982Z,1541529843.982 [DeadReckonUsingSpeedCalculator] Loaded 2018-11-06T18:44:03.982Z,1541529843.982 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-11-06T18:44:03.998Z,1541529843.998 [NavChart] Loaded 2018-11-06T18:44:03.999Z,1541529843.999 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-11-06T18:44:04.003Z,1541529844.003 [UniversalFixResidualReporter] Loaded 2018-11-06T18:44:04.003Z,1541529844.003 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-11-06T18:44:04.003Z,1541529844.003 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-11-06T18:44:04.007Z,1541529844.007 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-11-06T18:44:04.008Z,1541529844.008 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-11-06T18:44:04.015Z,1541529844.015 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-11-06T18:44:04.016Z,1541529844.016 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0 2018-11-06T18:44:04.016Z,1541529844.016 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6703 2018-11-06T18:44:04.021Z,1541529844.021 [Supervisor](INFO): Main Thread ID is 6410 2018-11-06T18:44:04.021Z,1541529844.021 [Supervisor](DEBUG): Running supervisor. 2018-11-06T18:44:04.022Z,1541529844.022 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6704 2018-11-06T18:44:04.024Z,1541529844.024 [controlThread ThreadHandler](INFO): Handler Thread ID is 6705 2018-11-06T18:44:04.025Z,1541529844.025 [controlThread](DEBUG): Initializing ControlThread 2018-11-06T18:44:04.026Z,1541529844.026 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-06T18:44:04.026Z,1541529844.026 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-11-06T18:44:04.026Z,1541529844.026 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-11-06T18:44:04.027Z,1541529844.027 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-11-06T18:44:04.027Z,1541529844.027 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-11-06T18:44:04.028Z,1541529844.028 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-11-06T18:44:04.035Z,1541529844.035 [SBIT](INFO): Initialize SBIT Component. 2018-11-06T18:44:04.036Z,1541529844.036 [SBIT](IMPORTANT): git: 2018-10-09 2018-11-06T18:44:04.036Z,1541529844.036 [SBIT](INFO): git hash: e647870a3e94d94bcb418e3013bcadd95d09d9aa 2018-11-06T18:44:04.036Z,1541529844.036 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-11-06T18:44:04.036Z,1541529844.036 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-11-06T18:44:04.038Z,1541529844.038 [SBIT](INFO): Beginning SBIT in 36.000000 seconds. 2018-11-06T18:44:04.038Z,1541529844.038 [IBIT](INFO): Initialize IBIT Component. 2018-11-06T18:44:04.039Z,1541529844.039 [CBIT](DEBUG): Initialize CBIT Component. 2018-11-06T18:44:04.039Z,1541529844.039 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-11-06T18:44:04.040Z,1541529844.040 [logger ThreadHandler](INFO): Handler Thread ID is 6706 2018-11-06T18:44:04.057Z,1541529844.057 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6707 2018-11-06T18:44:04.063Z,1541529844.063 [Radio_Surface](INFO): Powering up 2018-11-06T18:44:04.069Z,1541529844.069 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6708 2018-11-06T18:44:04.070Z,1541529844.070 [Rowe_600LCM](INFO): Initializing 2018-11-06T18:44:04.070Z,1541529844.070 [Rowe_600LCM](INFO): Checking LCM 2018-11-06T18:44:04.080Z,1541529844.080 [Rowe_600LCM](INFO): LCM OK 2018-11-06T18:44:04.080Z,1541529844.080 [Rowe_600LCM](INFO): Powering up 2018-11-06T18:44:04.085Z,1541529844.085 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6709 2018-11-06T18:44:04.086Z,1541529844.086 [CTD_NeilBrown](INFO): Powering down 2018-11-06T18:44:04.113Z,1541529844.113 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6710 2018-11-06T18:44:04.115Z,1541529844.115 [WetLabsBB2FL](INFO): Powering down 2018-11-06T18:44:04.141Z,1541529844.141 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6711 2018-11-06T18:44:04.144Z,1541529844.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-11-06T18:44:04.149Z,1541529844.149 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-11-06T18:44:04.151Z,1541529844.151 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-11-06T18:44:04.152Z,1541529844.152 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-11-06T18:44:04.152Z,1541529844.152 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-11-06T18:44:04.153Z,1541529844.153 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-06T18:44:04.154Z,1541529844.154 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-06T18:44:04.154Z,1541529844.154 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-06T18:44:04.155Z,1541529844.155 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-11-06T18:44:04.155Z,1541529844.155 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-11-06T18:44:04.156Z,1541529844.156 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-11-06T18:44:04.157Z,1541529844.157 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-11-06T18:44:04.157Z,1541529844.157 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-11-06T18:44:04.157Z,1541529844.157 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-11-06T18:44:04.158Z,1541529844.158 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-11-06T18:44:04.158Z,1541529844.158 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-11-06T18:44:04.158Z,1541529844.158 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-11-06T18:44:04.158Z,1541529844.158 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-11-06T18:44:04.158Z,1541529844.158 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-11-06T18:44:04.159Z,1541529844.159 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-11-06T18:44:04.159Z,1541529844.159 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-11-06T18:44:04.159Z,1541529844.159 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-11-06T18:44:04.159Z,1541529844.159 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-11-06T18:44:04.159Z,1541529844.159 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-11-06T18:44:04.160Z,1541529844.160 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-06T18:44:04.160Z,1541529844.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-11-06T18:44:04.180Z,1541529844.180 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-06T18:44:04.208Z,1541529844.208 [MissionManager](DEBUG): 2018-11-06T18:44:04.208Z,1541529844.208 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-11-06T18:44:04.304Z,1541529844.304 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-11-06T18:44:04.305Z,1541529844.305 [Default:A.Wait](DEBUG): Construct Wait. 2018-11-06T18:44:04.307Z,1541529844.307 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-06T18:44:04.345Z,1541529844.345 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-11-06T18:44:04.348Z,1541529844.348 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-11-06T18:44:04.369Z,1541529844.369 [Default:E.Execute](DEBUG): Construct Execute. 2018-11-06T18:44:04.389Z,1541529844.389 [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 2018-11-06T18:44:04.394Z,1541529844.394 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-11-06T18:44:04.419Z,1541529844.419 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-11-06T18:44:04.442Z,1541529844.442 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2018-11-06T18:44:04.605Z,1541529844.605 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-06T18:44:04.689Z,1541529844.689 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-06T18:44:04.810Z,1541529844.810 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-06T18:44:04.817Z,1541529844.817 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-06T18:44:04.832Z,1541529844.832 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-06T18:44:04.841Z,1541529844.841 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-06T18:44:04.867Z,1541529844.867 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-06T18:44:04.873Z,1541529844.873 [MassServo](DEBUG): Initializing MassServo. 2018-11-06T18:44:04.880Z,1541529844.880 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-06T18:44:04.890Z,1541529844.890 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-06T18:44:04.903Z,1541529844.903 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-11-06T18:44:04.909Z,1541529844.909 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-11-06T18:44:05.219Z,1541529845.219 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-06T18:44:05.461Z,1541529845.461 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-06T18:44:05.946Z,1541529845.946 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-06T18:44:06.335Z,1541529846.335 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-06T18:44:06.737Z,1541529846.737 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-06T18:44:07.132Z,1541529847.132 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-06T18:44:07.532Z,1541529847.532 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-11-06T18:44:08.041Z,1541529848.041 [Aanderaa_O2](INFO): Powering down 2018-11-06T18:44:08.475Z,1541529848.475 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-11-06T18:44:08.612Z,1541529848.612 [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 & 2018-11-06T18:44:08.648Z,1541529848.648 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-11-06T18:44:08.649Z,1541529848.649 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-11-06T18:44:08.650Z,1541529848.650 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-11-06T18:44:32.467Z,1541529872.467 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:44:40.552Z,1541529880.552 [SBIT](IMPORTANT): Beginning Startup BIT 2018-11-06T18:44:40.565Z,1541529880.565 [CBIT](IMPORTANT): Beginning ground fault scan 2018-11-06T18:44:52.045Z,1541529892.045 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.008108 CHAN A1 (24V): -0.011681 CHAN A2 (12V): -0.001947 CHAN A3 (5V): -0.001815 CHAN B0 (3.3V): 0.000069 CHAN B1 (3.15aV): -0.000337 CHAN B2 (3.15bV): -0.000126 CHAN B3 (GND): -0.000389 OPEN: -0.000536 Full Scale Calc: 4.765 mA, -1.589 mA 2018-11-06T18:44:53.828Z,1541529893.828 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:44:53.828Z,1541529893.828 [NAL9602] Communications Fault, FailCount= 1 2018-11-06T18:44:53.828Z,1541529893.828 [NAL9602](ERROR): Communications Fault 2018-11-06T18:44:54.055Z,1541529894.055 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:44:54.223Z,1541529894.223 [NAL9602](INFO): Powering down 2018-11-06T18:44:55.129Z,1541529895.129 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:44:55.129Z,1541529895.129 [NAL9602] No Fault, FailCount= 1 2018-11-06T18:45:24.239Z,1541529924.239 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:45:34.304Z,1541529934.304 [SBIT](IMPORTANT): SBIT PASSED 2018-11-06T18:45:34.385Z,1541529934.385 [CommandLine](IMPORTANT): got command configSet list 2018-11-06T18:45:34.386Z,1541529934.386 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-11-06T18:45:34.387Z,1541529934.387 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=28 count; 2018-11-06T18:45:34.387Z,1541529934.387 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute; 2018-11-06T18:45:34.387Z,1541529934.387 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool; 2018-11-06T18:45:34.387Z,1541529934.387 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool; 2018-11-06T18:45:34.387Z,1541529934.387 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index; 2018-11-06T18:45:34.387Z,1541529934.387 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2018-11-06T18:45:34.387Z,1541529934.387 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool; 2018-11-06T18:45:34.388Z,1541529934.388 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter; 2018-11-06T18:45:34.388Z,1541529934.388 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter; 2018-11-06T18:45:34.677Z,1541529934.677 [MissionManager](IMPORTANT): Started mission Startup 2018-11-06T18:45:34.678Z,1541529934.678 [Startup] Running Loop=1 2018-11-06T18:45:34.678Z,1541529934.678 [Startup](DEBUG): Aggregate::initialize Startup 2018-11-06T18:45:34.678Z,1541529934.678 [Startup:A.GoToSurface] Running Loop=1 2018-11-06T18:45:34.678Z,1541529934.678 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-06T18:45:34.679Z,1541529934.679 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-06T18:45:34.679Z,1541529934.679 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-06T18:45:34.680Z,1541529934.680 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-06T18:45:34.680Z,1541529934.680 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-06T18:45:34.680Z,1541529934.680 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-06T18:45:34.687Z,1541529934.687 [Startup:StartupSatComms] Running Loop=1 2018-11-06T18:45:34.687Z,1541529934.687 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-11-06T18:45:34.687Z,1541529934.687 [Startup:StartupSatComms:A] Running Loop=1 2018-11-06T18:45:35.107Z,1541529935.107 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-11-06T18:45:45.435Z,1541529945.435 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:45:45.435Z,1541529945.435 [NAL9602] Communications Fault, FailCount= 2 2018-11-06T18:45:45.435Z,1541529945.435 [NAL9602](ERROR): Communications Fault 2018-11-06T18:45:45.524Z,1541529945.524 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:45:45.843Z,1541529945.843 [NAL9602](INFO): Powering down 2018-11-06T18:45:46.699Z,1541529946.699 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:45:46.699Z,1541529946.699 [NAL9602] No Fault, FailCount= 2 2018-11-06T18:46:16.239Z,1541529976.239 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:46:34.694Z,1541529994.694 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-06T18:45:34.7Z 2018-11-06T18:46:34.694Z,1541529994.694 [Startup:StartupSatComms:A] Stopped 2018-11-06T18:46:34.694Z,1541529994.694 [Startup:StartupSatComms:B] Running Loop=1 2018-11-06T18:46:35.091Z,1541529995.091 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-06T18:46:37.435Z,1541529997.435 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:46:37.435Z,1541529997.435 [NAL9602] Communications Fault, FailCount= 3 2018-11-06T18:46:37.435Z,1541529997.435 [NAL9602](ERROR): Communications Fault 2018-11-06T18:46:37.523Z,1541529997.523 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:46:37.842Z,1541529997.842 [NAL9602](INFO): Powering down 2018-11-06T18:46:38.687Z,1541529998.687 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:46:38.687Z,1541529998.687 [NAL9602] No Fault, FailCount= 3 2018-11-06T18:47:04.284Z,1541530024.284 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-11-06T18:47:04.284Z,1541530024.284 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-11-06T18:47:04.284Z,1541530024.284 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-06T18:47:04.341Z,1541530024.341 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-06T18:47:04.688Z,1541530024.688 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-06T18:47:04.689Z,1541530024.689 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-11-06T18:47:05.086Z,1541530025.086 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-06T18:47:05.087Z,1541530025.087 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-06T18:47:05.087Z,1541530025.087 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-06T18:47:07.850Z,1541530027.850 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:47:29.039Z,1541530049.039 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:47:29.039Z,1541530049.039 [NAL9602] Communications Fault, FailCount= 4 2018-11-06T18:47:29.039Z,1541530049.039 [NAL9602](ERROR): Communications Fault 2018-11-06T18:47:29.128Z,1541530049.128 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:47:29.435Z,1541530049.435 [NAL9602](INFO): Powering down 2018-11-06T18:47:30.310Z,1541530050.310 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:47:30.310Z,1541530050.310 [NAL9602] No Fault, FailCount= 4 2018-11-06T18:47:35.076Z,1541530055.076 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-06T18:46:34.7Z 2018-11-06T18:47:35.076Z,1541530055.076 [Startup:StartupSatComms:B] Stopped 2018-11-06T18:47:35.076Z,1541530055.076 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-11-06T18:47:35.077Z,1541530055.077 [Startup:StartupSatComms] Stopped 2018-11-06T18:47:35.077Z,1541530055.077 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-11-06T18:47:35.078Z,1541530055.078 [Startup](INFO): Completed Startup 2018-11-06T18:47:35.078Z,1541530055.078 [MissionManager](INFO): Startup is completed. 2018-11-06T18:47:35.078Z,1541530055.078 [MissionManager](INFO): Uninitializing Mission Startup 2018-11-06T18:47:35.078Z,1541530055.078 [Startup] Stopped 2018-11-06T18:47:35.078Z,1541530055.078 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-11-06T18:47:35.079Z,1541530055.079 [Startup:A.GoToSurface] Stopped 2018-11-06T18:47:35.079Z,1541530055.079 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-06T18:47:35.476Z,1541530055.476 [MissionManager](IMPORTANT): Started mission Default 2018-11-06T18:47:35.477Z,1541530055.477 [Default] Running Loop=1 2018-11-06T18:47:35.477Z,1541530055.477 [Default](DEBUG): Aggregate::initialize Default 2018-11-06T18:47:35.477Z,1541530055.477 [Default:B.GoToSurface] Running Loop=1 2018-11-06T18:47:35.477Z,1541530055.477 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-06T18:47:35.477Z,1541530055.477 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-06T18:47:35.478Z,1541530055.478 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-06T18:47:35.478Z,1541530055.478 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-06T18:47:35.478Z,1541530055.478 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-06T18:47:35.479Z,1541530055.479 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-06T18:47:35.479Z,1541530055.479 [Default:A.Wait] Running Loop=1 2018-11-06T18:47:35.479Z,1541530055.479 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-11-06T18:47:48.700Z,1541530068.700 [Default:A.Wait](INFO): Done Waiting. 2018-11-06T18:47:48.700Z,1541530068.700 [Default:A.Wait] Stopped 2018-11-06T18:47:48.700Z,1541530068.700 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-11-06T18:47:49.077Z,1541530069.077 [Default:CheckIn] Running Loop=1 2018-11-06T18:47:49.077Z,1541530069.077 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-11-06T18:47:49.077Z,1541530069.077 [Default:CheckIn:Read_GPS] Running Loop=1 2018-11-06T18:47:49.502Z,1541530069.502 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-11-06T18:47:59.844Z,1541530079.844 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:48:10.164Z,1541530090.164 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004629 2018-11-06T18:48:20.971Z,1541530100.971 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:48:20.971Z,1541530100.971 [NAL9602] Communications Fault, FailCount= 5 2018-11-06T18:48:20.971Z,1541530100.971 [NAL9602](ERROR): Communications Fault 2018-11-06T18:48:21.034Z,1541530101.034 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:48:21.034Z,1541530101.034 [CBIT](CRITICAL): Communications Fault in component: NAL9602 2018-11-06T18:48:21.362Z,1541530101.362 [NAL9602](INFO): Powering down 2018-11-06T18:48:21.441Z,1541530101.441 [CommandLine](FAULT): Scheduling is paused 2018-11-06T18:48:21.441Z,1541530101.441 [CBIT](INFO): Critical error at 20181106T184821 2018-11-06T18:48:21.442Z,1541530101.442 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-11-06T18:49:10.356Z,1541530150.356 [DataOverHttps](IMPORTANT): SBD MTMSN=20181106T184844 2018-11-06T18:49:15.126Z,1541530155.126 [DataOverHttps](INFO): Received command:load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MinEstimationTime 20 minute;run 2018-11-06T18:49:15.157Z,1541530155.157 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.xml 2018-11-06T18:49:15.157Z,1541530155.157 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_ballast_and_trim.xml 2018-11-06T18:49:15.240Z,1541530155.240 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min 2018-11-06T18:49:15.242Z,1541530155.242 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool 2018-11-06T18:49:15.246Z,1541530155.246 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m 2018-11-06T18:49:15.249Z,1541530155.249 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband1 = 0.100000 m 2018-11-06T18:49:15.252Z,1541530155.252 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m 2018-11-06T18:49:15.255Z,1541530155.255 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband2 = nan m 2018-11-06T18:49:15.258Z,1541530155.258 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m 2018-11-06T18:49:15.260Z,1541530155.260 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min 2018-11-06T18:49:15.263Z,1541530155.263 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min 2018-11-06T18:49:15.266Z,1541530155.266 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min 2018-11-06T18:49:15.269Z,1541530155.269 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min 2018-11-06T18:49:15.272Z,1541530155.272 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm 2018-11-06T18:49:15.275Z,1541530155.275 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc 2018-11-06T18:49:15.278Z,1541530155.278 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 % 2018-11-06T18:49:15.281Z,1541530155.281 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min 2018-11-06T18:49:15.281Z,1541530155.281 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2018-11-06T18:49:15.635Z,1541530155.635 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUS = 0 bool 2018-11-06T18:49:15.638Z,1541530155.638 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSmaxPitch = 60.000000 arcdeg 2018-11-06T18:49:15.641Z,1541530155.641 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSminPitch = -60.000000 arcdeg 2018-11-06T18:49:15.644Z,1541530155.644 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool 2018-11-06T18:49:15.647Z,1541530155.647 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min 2018-11-06T18:49:15.649Z,1541530155.649 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool 2018-11-06T18:49:15.652Z,1541530155.652 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool 2018-11-06T18:49:15.655Z,1541530155.655 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count 2018-11-06T18:49:15.658Z,1541530155.658 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count 2018-11-06T18:49:15.693Z,1541530155.693 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 % 2018-11-06T18:49:15.696Z,1541530155.696 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool 2018-11-06T18:49:15.699Z,1541530155.699 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool 2018-11-06T18:49:15.702Z,1541530155.702 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool 2018-11-06T18:49:15.704Z,1541530155.704 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2018-11-06T18:49:15.722Z,1541530155.722 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 1.000000 bool 2018-11-06T18:49:15.738Z,1541530155.738 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 1.000000 bool 2018-11-06T18:49:15.741Z,1541530155.741 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 0.000000 bool 2018-11-06T18:49:15.745Z,1541530155.745 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledTurbulence_NPS = 0.000000 bool 2018-11-06T18:49:15.748Z,1541530155.748 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1.000000 bool 2018-11-06T18:49:15.775Z,1541530155.775 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool 2018-11-06T18:49:15.779Z,1541530155.779 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0.000000 bool 2018-11-06T18:49:15.799Z,1541530155.799 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledISUS = 0.000000 bool 2018-11-06T18:49:15.835Z,1541530155.835 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count 2018-11-06T18:49:15.855Z,1541530155.855 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlShallowBound = nan m 2018-11-06T18:49:15.863Z,1541530155.863 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlDeepBound = nan m 2018-11-06T18:49:15.867Z,1541530155.867 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m 2018-11-06T18:49:15.888Z,1541530155.888 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m 2018-11-06T18:49:15.903Z,1541530155.903 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l 2018-11-06T18:49:15.916Z,1541530155.916 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m 2018-11-06T18:49:15.939Z,1541530155.939 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC 2018-11-06T18:49:15.959Z,1541530155.959 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg 2018-11-06T18:49:15.975Z,1541530155.975 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg 2018-11-06T18:49:15.997Z,1541530155.997 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l 2018-11-06T18:49:16.023Z,1541530156.023 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m 2018-11-06T18:49:16.032Z,1541530156.032 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg 2018-11-06T18:49:16.035Z,1541530156.035 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg 2018-11-06T18:49:16.046Z,1541530156.046 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 m 2018-11-06T18:49:16.050Z,1541530156.050 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m 2018-11-06T18:49:16.053Z,1541530156.053 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg 2018-11-06T18:49:16.056Z,1541530156.056 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg 2018-11-06T18:49:16.059Z,1541530156.059 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m 2018-11-06T18:49:16.078Z,1541530156.078 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3 2018-11-06T18:49:16.081Z,1541530156.081 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m 2018-11-06T18:49:16.084Z,1541530156.084 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg 2018-11-06T18:49:16.088Z,1541530156.088 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg 2018-11-06T18:49:16.113Z,1541530156.113 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb 2018-11-06T18:49:16.116Z,1541530156.116 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m 2018-11-06T18:49:16.119Z,1541530156.119 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg 2018-11-06T18:49:16.131Z,1541530156.131 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg 2018-11-06T18:49:16.134Z,1541530156.134 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m 2018-11-06T18:49:16.173Z,1541530156.173 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2018-11-06T18:49:16.209Z,1541530156.209 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2018-11-06T18:49:16.228Z,1541530156.228 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2018-11-06T18:49:16.250Z,1541530156.250 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2018-11-06T18:49:16.273Z,1541530156.273 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2018-11-06T18:49:16.289Z,1541530156.289 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml 2018-11-06T18:49:16.448Z,1541530156.448 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepth = 25.000000 m 2018-11-06T18:49:16.463Z,1541530156.463 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2018-11-06T18:49:16.466Z,1541530156.466 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s 2018-11-06T18:49:16.469Z,1541530156.469 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min 2018-11-06T18:49:16.472Z,1541530156.472 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min 2018-11-06T18:49:16.492Z,1541530156.492 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m 2018-11-06T18:49:16.495Z,1541530156.495 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSettleTime = 10.000000 min 2018-11-06T18:49:16.498Z,1541530156.498 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstTimeout = 45.000000 min 2018-11-06T18:49:16.515Z,1541530156.515 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MinEstTime = 10.000000 min 2018-11-06T18:49:16.519Z,1541530156.519 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2018-11-06T18:49:16.523Z,1541530156.523 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2018-11-06T18:49:16.526Z,1541530156.526 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstConfidence = 99.900000 % 2018-11-06T18:49:16.543Z,1541530156.543 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct. 2018-11-06T18:49:16.566Z,1541530156.566 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute. 2018-11-06T18:49:16.568Z,1541530156.568 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Construct. 2018-11-06T18:49:16.584Z,1541530156.584 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2018-11-06T18:49:16.598Z,1541530156.598 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct. 2018-11-06T18:49:16.605Z,1541530156.605 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct. 2018-11-06T18:49:16.624Z,1541530156.624 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait. 2018-11-06T18:49:16.631Z,1541530156.631 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2018-11-06T18:49:16.681Z,1541530156.681 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth1 2018-11-06T18:49:16.687Z,1541530156.687 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband1 2018-11-06T18:49:16.690Z,1541530156.690 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceThreshold = reader:ballast_and_trim.SurfaceThreshold 2018-11-06T18:49:16.692Z,1541530156.692 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface 2018-11-06T18:49:16.702Z,1541530156.702 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime 2018-11-06T18:49:16.704Z,1541530156.704 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout 2018-11-06T18:49:16.714Z,1541530156.714 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime 2018-11-06T18:49:16.716Z,1541530156.716 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound 2018-11-06T18:49:16.718Z,1541530156.718 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound 2018-11-06T18:49:16.719Z,1541530156.719 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence 2018-11-06T18:49:16.726Z,1541530156.726 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml 2018-11-06T18:49:16.906Z,1541530156.906 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepth = 25.000000 m 2018-11-06T18:49:16.908Z,1541530156.908 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2018-11-06T18:49:16.924Z,1541530156.924 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s 2018-11-06T18:49:16.927Z,1541530156.927 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min 2018-11-06T18:49:16.930Z,1541530156.930 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min 2018-11-06T18:49:16.953Z,1541530156.953 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m 2018-11-06T18:49:16.956Z,1541530156.956 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSettleTime = 10.000000 min 2018-11-06T18:49:16.959Z,1541530156.959 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstTimeout = 45.000000 min 2018-11-06T18:49:16.963Z,1541530156.963 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MinEstTime = 10.000000 min 2018-11-06T18:49:16.968Z,1541530156.968 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2018-11-06T18:49:16.971Z,1541530156.971 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2018-11-06T18:49:16.975Z,1541530156.975 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstConfidence = 99.900000 % 2018-11-06T18:49:17.011Z,1541530157.011 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct. 2018-11-06T18:49:17.036Z,1541530157.036 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute. 2018-11-06T18:49:17.038Z,1541530157.038 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Construct. 2018-11-06T18:49:17.057Z,1541530157.057 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2018-11-06T18:49:17.062Z,1541530157.062 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct. 2018-11-06T18:49:17.078Z,1541530157.078 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct. 2018-11-06T18:49:17.085Z,1541530157.085 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait. 2018-11-06T18:49:17.101Z,1541530157.101 [ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2018-11-06T18:49:17.107Z,1541530157.107 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth2 2018-11-06T18:49:17.109Z,1541530157.109 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband2 2018-11-06T18:49:17.111Z,1541530157.111 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceThreshold = reader:ballast_and_trim.SurfaceThreshold 2018-11-06T18:49:17.125Z,1541530157.125 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface 2018-11-06T18:49:17.127Z,1541530157.127 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime 2018-11-06T18:49:17.129Z,1541530157.129 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout 2018-11-06T18:49:17.130Z,1541530157.130 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime 2018-11-06T18:49:17.132Z,1541530157.132 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound 2018-11-06T18:49:17.134Z,1541530157.134 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound 2018-11-06T18:49:17.136Z,1541530157.136 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:Depth2:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence 2018-11-06T18:49:17.159Z,1541530157.159 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy. 2018-11-06T18:49:17.162Z,1541530157.162 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait. 2018-11-06T18:49:17.232Z,1541530157.232 [MissionManager](DEBUG): Maximum duration of mission 120 Skip communications at start of mission. 7 0.1 NaN NaN Surface threshold depth. 2.5 Maximum wait time for the vehicle to go under the surface (i.e., reach the surface threshold depth). 20 How long to wait after reaching target depth before starting ballast and trim. 10 Timeout for ballast and trim estimation. 45 Minimum time to run ballast and trim estimation before determining convergence (must be shorter than timeout). 10 Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). 0.25 Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). 15 Confidence level that the estimator has reached the desired error range (larger values take longer to converge). 99.9 5 Float to surface instead of driving. 2018-11-06T18:49:17.233Z,1541530157.233 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.xml 2018-11-06T18:49:20.382Z,1541530160.382 [CommandLine](IMPORTANT): got command set ballast_and_trim.MinEstimationTime 20.000000 minute 2018-11-06T18:49:20.382Z,1541530160.382 [CommandLine](IMPORTANT): got command run 2018-11-06T18:49:20.386Z,1541530160.386 [CommandLine](IMPORTANT): Running 2018-11-06T18:49:20.469Z,1541530160.469 [Default] Stopped 2018-11-06T18:49:20.470Z,1541530160.470 [Default](DEBUG): Aggregate::uninitialize Default 2018-11-06T18:49:20.470Z,1541530160.470 [Default:B.GoToSurface] Stopped 2018-11-06T18:49:20.470Z,1541530160.470 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-06T18:49:20.470Z,1541530160.470 [Default:CheckIn] Stopped 2018-11-06T18:49:20.470Z,1541530160.470 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-11-06T18:49:20.470Z,1541530160.470 [Default:CheckIn:Read_GPS] Stopped 2018-11-06T18:49:20.470Z,1541530160.470 [MissionManager](IMPORTANT): Started mission ballast_and_trim 2018-11-06T18:49:20.471Z,1541530160.471 [ballast_and_trim] Running Loop=1 2018-11-06T18:49:20.471Z,1541530160.471 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim 2018-11-06T18:49:20.471Z,1541530160.471 [ballast_and_trim:Science] Running Loop=1 2018-11-06T18:49:20.471Z,1541530160.471 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science 2018-11-06T18:49:20.471Z,1541530160.471 [ballast_and_trim:Science:A] Running Loop=1 2018-11-06T18:49:20.471Z,1541530160.471 [ballast_and_trim:Science:B] Running Loop=1 2018-11-06T18:49:20.471Z,1541530160.471 [ballast_and_trim:Science:C] Running Loop=1 2018-11-06T18:49:20.471Z,1541530160.471 [ballast_and_trim:Science:D] Running Loop=1 2018-11-06T18:49:20.472Z,1541530160.472 [ballast_and_trim:Science:E] Running Loop=1 2018-11-06T18:49:20.472Z,1541530160.472 [ballast_and_trim:Science:F] Running Loop=1 2018-11-06T18:49:20.472Z,1541530160.472 [ballast_and_trim:Science:G] Running Loop=1 2018-11-06T18:49:20.472Z,1541530160.472 [ballast_and_trim:Science:H] Running Loop=1 2018-11-06T18:49:20.472Z,1541530160.472 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2018-11-06T18:49:20.472Z,1541530160.472 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1 2018-11-06T18:49:20.472Z,1541530160.472 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl 2018-11-06T18:49:20.472Z,1541530160.472 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2018-11-06T18:49:20.472Z,1541530160.472 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2018-11-06T18:49:20.474Z,1541530160.474 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1 2018-11-06T18:49:20.475Z,1541530160.475 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport 2018-11-06T18:49:20.475Z,1541530160.475 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2018-11-06T18:49:20.475Z,1541530160.475 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2018-11-06T18:49:20.475Z,1541530160.475 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1 2018-11-06T18:49:20.475Z,1541530160.475 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3 2018-11-06T18:49:20.476Z,1541530160.476 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2018-11-06T18:49:20.476Z,1541530160.476 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2018-11-06T18:49:20.478Z,1541530160.478 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1 2018-11-06T18:49:20.478Z,1541530160.478 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil 2018-11-06T18:49:20.478Z,1541530160.478 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2018-11-06T18:49:20.478Z,1541530160.478 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2018-11-06T18:49:20.480Z,1541530160.480 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1 2018-11-06T18:49:20.480Z,1541530160.480 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM 2018-11-06T18:49:20.480Z,1541530160.480 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2018-11-06T18:49:20.480Z,1541530160.480 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2018-11-06T18:49:20.483Z,1541530160.483 [ballast_and_trim:RunBallastAndTrim] Running Loop=1 2018-11-06T18:49:20.483Z,1541530160.483 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim 2018-11-06T18:49:20.483Z,1541530160.483 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1 2018-11-06T18:49:20.483Z,1541530160.483 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1 2018-11-06T18:49:20.483Z,1541530160.483 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim] Running Loop=1 2018-11-06T18:49:20.483Z,1541530160.483 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim 2018-11-06T18:49:20.483Z,1541530160.483 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:A] Running Loop=1 2018-11-06T18:49:20.483Z,1541530160.483 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:B] Running Loop=1 2018-11-06T18:49:20.484Z,1541530160.484 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:C] Running Loop=1 2018-11-06T18:49:20.484Z,1541530160.484 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:D] Running Loop=1 2018-11-06T18:49:20.537Z,1541530160.537 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:D](INFO): Going to target depth. Speed set to 0.000000 m/s 2018-11-06T18:49:20.539Z,1541530160.539 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:D] Stopped 2018-11-06T18:49:20.539Z,1541530160.539 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth] Running Loop=1 2018-11-06T18:49:20.539Z,1541530160.539 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth 2018-11-06T18:49:20.539Z,1541530160.539 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1 2018-11-06T18:49:20.539Z,1541530160.539 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Initialize. 2018-11-06T18:49:20.539Z,1541530160.539 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1 2018-11-06T18:49:20.540Z,1541530160.540 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout 2018-11-06T18:49:20.540Z,1541530160.540 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch] Running Loop=1 2018-11-06T18:49:20.540Z,1541530160.540 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Initialize. 2018-11-06T18:49:20.541Z,1541530160.541 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:C] Running Loop=1 2018-11-06T18:49:20.542Z,1541530160.542 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:B] Running Loop=1 2018-11-06T18:49:20.544Z,1541530160.544 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:A] Running Loop=1 2018-11-06T18:49:20.546Z,1541530160.546 [ballast_and_trim:Science] Running Loop=1 2018-11-06T18:49:20.547Z,1541530160.547 [ballast_and_trim:Science:PeakDetectFDOM] Stopped 2018-11-06T18:49:20.547Z,1541530160.547 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM 2018-11-06T18:49:20.547Z,1541530160.547 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2018-11-06T18:49:20.547Z,1541530160.547 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2018-11-06T18:49:20.547Z,1541530160.547 [ballast_and_trim:Science:PeakDetectOil] Stopped 2018-11-06T18:49:20.547Z,1541530160.547 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil 2018-11-06T18:49:20.547Z,1541530160.547 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:PeakDetectNO3] Stopped 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:HighestChlPeakReport] Stopped 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2018-11-06T18:49:20.548Z,1541530160.548 [ballast_and_trim:Science:PeakDetectChl] Stopped 2018-11-06T18:49:20.549Z,1541530160.549 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl 2018-11-06T18:49:20.549Z,1541530160.549 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2018-11-06T18:49:20.549Z,1541530160.549 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2018-11-06T18:49:20.549Z,1541530160.549 [ballast_and_trim:Science:Read_Oil] Stopped 2018-11-06T18:49:20.549Z,1541530160.549 [ballast_and_trim:Science:H] Stopped 2018-11-06T18:49:20.549Z,1541530160.549 [ballast_and_trim:Science:G] Running Loop=1 2018-11-06T18:49:20.550Z,1541530160.550 [ballast_and_trim:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2018-11-06T18:49:20.551Z,1541530160.551 [ballast_and_trim:Science:F] Stopped 2018-11-06T18:49:20.551Z,1541530160.551 [ballast_and_trim:Science:E] Stopped 2018-11-06T18:49:20.551Z,1541530160.551 [ballast_and_trim:Science:D] Stopped 2018-11-06T18:49:20.551Z,1541530160.551 [ballast_and_trim:Science:C] Running Loop=1 2018-11-06T18:49:20.552Z,1541530160.552 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2018-11-06T18:49:20.553Z,1541530160.553 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2018-11-06T18:49:20.554Z,1541530160.554 [ballast_and_trim:Science:B] Stopped 2018-11-06T18:49:20.554Z,1541530160.554 [ballast_and_trim:Science:A] Running Loop=1 2018-11-06T18:49:20.555Z,1541530160.555 [ballast_and_trim:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water 2018-11-06T18:49:20.706Z,1541530160.706 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1 2018-11-06T18:49:20.706Z,1541530160.706 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1 2018-11-06T18:49:20.725Z,1541530160.725 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2018-11-06T18:49:20.733Z,1541530160.733 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2018-11-06T18:49:20.801Z,1541530160.801 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2 2018-11-06T18:49:20.801Z,1541530160.801 [WetLabsBB2FL](INFO): Powering up 2018-11-06T18:49:20.869Z,1541530160.869 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:21.277Z,1541530161.277 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:21.685Z,1541530161.685 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:22.093Z,1541530162.093 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:27.917Z,1541530167.917 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:28.248Z,1541530168.248 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 219.974 76.336 19.255 31.658 31.658 40.360 8.702 262.6 518.9 122.0 2018-11-06T18:49:28.248Z,1541530168.248 [Aanderaa_O2](DEBUG): Error parsing device response 2018-11-06T18:49:28.337Z,1541530168.337 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:29.467Z,1541530169.467 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 219.640  76.220 19.255 31.676 31.676 40.379 8.703 262.6 518.2 122.0 2018-11-06T18:49:29.467Z,1541530169.467 [Aanderaa_O2](DEBUG): Error parsing device response 2018-11-06T18:49:30.286Z,1541530170.286 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 219.592 76.203 19.255 31.679 31.679 40.379 8.700 262.6 517.6 122.0 2018-11-06T18:49:30.286Z,1541530170.286 [Aanderaa_O2](DEBUG): Error parsing device response 2018-11-06T18:49:31.153Z,1541530171.153 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:31.460Z,1541530171.460 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 219.505 76.173 19.255 31.684 31.684 40.387 8.703 262.6 517.0 122.0 2018-11-06T18:49:31.460Z,1541530171.460 [Aanderaa_O2](DEBUG): Error parsing device response 2018-11-06T18:49:32.294Z,1541530172.294 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 219.645 76.221 19.254 31.676 31.676 40.375 8.699 262.5 516.7 122.0 2018-11-06T18:49:32.294Z,1541530172.294 [Aanderaa_O2](DEBUG): Error parsing device response 2018-11-06T18:49:34.660Z,1541530174.660 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 219.854 76.294 19.254 31.665 31.665 40.370 8.705 262.6 515.9 122.0 2018-11-06T18:49:34.660Z,1541530174.660 [Aanderaa_O2](DEBUG): Error parsing device response 2018-11-06T18:49:41.302Z,1541530181.302 [CommandLine](IMPORTANT): got command report touch platform_buoyancy_position 2018-11-06T18:49:41.364Z,1541530181.364 [Reporter](INFO): platform_buoyancy_position 806.058524 cc 2018-11-06T18:49:41.779Z,1541530181.779 [Reporter](INFO): platform_buoyancy_position 803.909730 cc 2018-11-06T18:49:42.192Z,1541530182.192 [Reporter](INFO): platform_buoyancy_position 800.149341 cc 2018-11-06T18:49:42.556Z,1541530182.556 [Reporter](INFO): platform_buoyancy_position 797.463290 cc 2018-11-06T18:49:42.965Z,1541530182.965 [Reporter](INFO): platform_buoyancy_position 795.448839 cc 2018-11-06T18:49:43.360Z,1541530183.360 [Reporter](INFO): platform_buoyancy_position 792.762788 cc 2018-11-06T18:49:43.724Z,1541530183.724 [Reporter](INFO): platform_buoyancy_position 789.002457 cc 2018-11-06T18:49:44.129Z,1541530184.129 [Reporter](INFO): platform_buoyancy_position 786.719378 cc 2018-11-06T18:49:44.519Z,1541530184.519 [Reporter](INFO): platform_buoyancy_position 782.556017 cc 2018-11-06T18:49:44.936Z,1541530184.936 [Reporter](INFO): platform_buoyancy_position 778.124144 cc 2018-11-06T18:49:45.364Z,1541530185.364 [Reporter](INFO): platform_buoyancy_position 774.363696 cc 2018-11-06T18:49:45.716Z,1541530185.716 [Reporter](INFO): platform_buoyancy_position 771.677762 cc 2018-11-06T18:49:46.180Z,1541530186.180 [Reporter](INFO): platform_buoyancy_position 767.380116 cc 2018-11-06T18:49:46.587Z,1541530186.587 [Reporter](INFO): platform_buoyancy_position 763.216871 cc 2018-11-06T18:49:46.907Z,1541530186.907 [Reporter](INFO): platform_buoyancy_position 758.919225 cc 2018-11-06T18:49:47.340Z,1541530187.340 [Reporter](INFO): platform_buoyancy_position 756.501802 cc 2018-11-06T18:49:47.485Z,1541530187.485 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:47.760Z,1541530187.760 [Reporter](INFO): platform_buoyancy_position 752.338558 cc 2018-11-06T18:49:48.172Z,1541530188.172 [Reporter](INFO): platform_buoyancy_position 748.175255 cc 2018-11-06T18:49:48.551Z,1541530188.551 [Reporter](INFO): platform_buoyancy_position 746.026402 cc 2018-11-06T18:49:48.917Z,1541530188.917 [Reporter](INFO): platform_buoyancy_position 743.743323 cc 2018-11-06T18:49:49.372Z,1541530189.372 [Reporter](INFO): platform_buoyancy_position 739.445735 cc 2018-11-06T18:49:49.778Z,1541530189.778 [Reporter](INFO): platform_buoyancy_position 735.282432 cc 2018-11-06T18:49:50.172Z,1541530190.172 [Reporter](INFO): platform_buoyancy_position 732.999353 cc 2018-11-06T18:49:50.566Z,1541530190.566 [Reporter](INFO): platform_buoyancy_position 729.104620 cc 2018-11-06T18:49:50.729Z,1541530190.729 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:50.952Z,1541530190.952 [Reporter](INFO): platform_buoyancy_position 726.687256 cc 2018-11-06T18:49:51.149Z,1541530191.149 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:51.361Z,1541530191.361 [Reporter](INFO): platform_buoyancy_position 724.404119 cc 2018-11-06T18:49:51.585Z,1541530191.585 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:51.724Z,1541530191.724 [Reporter](INFO): platform_buoyancy_position 722.121040 cc 2018-11-06T18:49:52.160Z,1541530192.160 [Reporter](INFO): platform_buoyancy_position 717.957737 cc 2018-11-06T18:49:52.552Z,1541530192.552 [Reporter](INFO): platform_buoyancy_position 713.794434 cc 2018-11-06T18:49:52.916Z,1541530192.916 [Reporter](INFO): platform_buoyancy_position 711.511355 cc 2018-11-06T18:49:52.925Z,1541530192.925 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:53.332Z,1541530193.332 [Reporter](INFO): platform_buoyancy_position 707.347994 cc 2018-11-06T18:49:53.726Z,1541530193.726 [Reporter](INFO): platform_buoyancy_position 703.184749 cc 2018-11-06T18:49:53.769Z,1541530193.769 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:53.980Z,1541530193.980 [CommandLine](IMPORTANT): got command get BPC1.loadAtStartup 2018-11-06T18:49:53.981Z,1541530193.981 [CommandLine](IMPORTANT): BPC1.loadAtStartup 0 bool 2018-11-06T18:49:54.208Z,1541530194.208 [Reporter](INFO): platform_buoyancy_position 698.618533 cc 2018-11-06T18:49:54.544Z,1541530194.544 [Reporter](INFO): platform_buoyancy_position 694.320945 cc 2018-11-06T18:49:54.950Z,1541530194.950 [Reporter](INFO): platform_buoyancy_position 690.023298 cc 2018-11-06T18:49:55.368Z,1541530195.368 [Reporter](INFO): platform_buoyancy_position 687.203021 cc 2018-11-06T18:49:55.549Z,1541530195.549 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:55.713Z,1541530195.713 [Reporter](INFO): platform_buoyancy_position 683.711260 cc 2018-11-06T18:49:56.139Z,1541530196.139 [Reporter](INFO): platform_buoyancy_position 681.428122 cc 2018-11-06T18:49:56.568Z,1541530196.568 [Reporter](INFO): platform_buoyancy_position 677.130534 cc 2018-11-06T18:49:56.963Z,1541530196.963 [Reporter](INFO): platform_buoyancy_position 674.981740 cc 2018-11-06T18:49:57.325Z,1541530197.325 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:57.764Z,1541530197.764 [Reporter](INFO): platform_buoyancy_position 670.684152 cc 2018-11-06T18:49:58.167Z,1541530198.167 [Reporter](INFO): platform_buoyancy_position 664.237712 cc 2018-11-06T18:49:58.185Z,1541530198.185 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:49:58.372Z,1541530198.372 [Reporter](INFO): platform_buoyancy_position 663.297600 cc 2018-11-06T18:49:58.807Z,1541530198.807 [Reporter](INFO): platform_buoyancy_position 660.208752 cc 2018-11-06T18:49:59.215Z,1541530199.215 [Reporter](INFO): platform_buoyancy_position 657.791330 cc 2018-11-06T18:49:59.509Z,1541530199.509 [Reporter](INFO): platform_buoyancy_position 655.239623 cc 2018-11-06T18:50:00.108Z,1541530200.108 [Reporter](INFO): platform_buoyancy_position 649.196096 cc 2018-11-06T18:50:00.368Z,1541530200.368 [Reporter](INFO): platform_buoyancy_position 647.047302 cc 2018-11-06T18:50:00.726Z,1541530200.726 [Reporter](INFO): platform_buoyancy_position 644.092739 cc 2018-11-06T18:50:01.185Z,1541530201.185 [Reporter](INFO): platform_buoyancy_position 640.600978 cc 2018-11-06T18:50:01.508Z,1541530201.508 [Reporter](INFO): platform_buoyancy_position 638.586411 cc 2018-11-06T18:50:02.096Z,1541530202.096 [Reporter](INFO): platform_buoyancy_position 632.140029 cc 2018-11-06T18:50:02.376Z,1541530202.376 [Reporter](INFO): platform_buoyancy_position 629.856950 cc 2018-11-06T18:50:02.736Z,1541530202.736 [Reporter](INFO): platform_buoyancy_position 627.708156 cc 2018-11-06T18:50:03.152Z,1541530203.152 [Reporter](INFO): platform_buoyancy_position 623.410509 cc 2018-11-06T18:50:03.516Z,1541530203.516 [Reporter](INFO): platform_buoyancy_position 621.261715 cc 2018-11-06T18:50:04.116Z,1541530204.116 [Reporter](INFO): platform_buoyancy_position 615.083904 cc 2018-11-06T18:50:04.378Z,1541530204.378 [Reporter](INFO): platform_buoyancy_position 612.666481 cc 2018-11-06T18:50:04.740Z,1541530204.740 [Reporter](INFO): platform_buoyancy_position 610.383402 cc 2018-11-06T18:50:05.136Z,1541530205.136 [Reporter](INFO): platform_buoyancy_position 606.354442 cc 2018-11-06T18:50:05.439Z,1541530205.439 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-11-06T18:50:05.439Z,1541530205.439 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-11-06T18:50:05.439Z,1541530205.439 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-06T18:50:05.491Z,1541530205.491 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-06T18:50:05.492Z,1541530205.492 [Reporter](INFO): platform_buoyancy_position 604.071363 cc 2018-11-06T18:50:06.115Z,1541530206.115 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-06T18:50:06.115Z,1541530206.115 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-11-06T18:50:06.116Z,1541530206.116 [Reporter](INFO): platform_buoyancy_position 597.759208 cc 2018-11-06T18:50:06.262Z,1541530206.262 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-06T18:50:06.262Z,1541530206.262 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-06T18:50:06.263Z,1541530206.263 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-06T18:50:06.374Z,1541530206.374 [Reporter](INFO): platform_buoyancy_position 595.476129 cc 2018-11-06T18:50:06.762Z,1541530206.762 [Reporter](INFO): platform_buoyancy_position 591.447169 cc 2018-11-06T18:50:07.136Z,1541530207.136 [Reporter](INFO): platform_buoyancy_position 589.029747 cc 2018-11-06T18:50:07.524Z,1541530207.524 [Reporter](INFO): platform_buoyancy_position 586.880895 cc 2018-11-06T18:50:08.112Z,1541530208.112 [Reporter](INFO): platform_buoyancy_position 580.568856 cc 2018-11-06T18:50:08.363Z,1541530208.363 [Reporter](INFO): platform_buoyancy_position 578.285719 cc 2018-11-06T18:50:08.732Z,1541530208.732 [Reporter](INFO): platform_buoyancy_position 573.988073 cc 2018-11-06T18:50:09.160Z,1541530209.160 [Reporter](INFO): platform_buoyancy_position 569.959113 cc 2018-11-06T18:50:09.500Z,1541530209.500 [Reporter](INFO): platform_buoyancy_position 567.676034 cc 2018-11-06T18:50:09.677Z,1541530209.677 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:10.148Z,1541530210.148 [Reporter](INFO): platform_buoyancy_position 563.109817 cc 2018-11-06T18:50:10.348Z,1541530210.348 [Reporter](INFO): platform_buoyancy_position 560.961023 cc 2018-11-06T18:50:10.782Z,1541530210.782 [Reporter](INFO): platform_buoyancy_position 556.797720 cc 2018-11-06T18:50:11.176Z,1541530211.176 [Reporter](INFO): platform_buoyancy_position 552.500132 cc 2018-11-06T18:50:11.516Z,1541530211.516 [Reporter](INFO): platform_buoyancy_position 550.351338 cc 2018-11-06T18:50:12.933Z,1541530212.933 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:15.831Z,1541530215.831 [Reporter](INFO): platform_buoyancy_position 496.765657 cc 2018-11-06T18:50:16.143Z,1541530216.143 [Reporter](INFO): platform_buoyancy_position 492.602354 cc 2018-11-06T18:50:16.362Z,1541530216.362 [Reporter](INFO): platform_buoyancy_position 490.453502 cc 2018-11-06T18:50:16.812Z,1541530216.812 [Reporter](INFO): platform_buoyancy_position 487.096026 cc 2018-11-06T18:50:17.228Z,1541530217.228 [Reporter](INFO): platform_buoyancy_position 483.738550 cc 2018-11-06T18:50:17.320Z,1541530217.320 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:17.588Z,1541530217.588 [Reporter](INFO): platform_buoyancy_position 479.575217 cc 2018-11-06T18:50:18.120Z,1541530218.120 [Reporter](INFO): platform_buoyancy_position 475.277629 cc 2018-11-06T18:50:18.424Z,1541530218.424 [Reporter](INFO): platform_buoyancy_position 472.860265 cc 2018-11-06T18:50:18.816Z,1541530218.816 [Reporter](INFO): platform_buoyancy_position 468.831277 cc 2018-11-06T18:50:19.228Z,1541530219.228 [Reporter](INFO): platform_buoyancy_position 466.548139 cc 2018-11-06T18:50:19.580Z,1541530219.580 [Reporter](INFO): platform_buoyancy_position 462.384865 cc 2018-11-06T18:50:19.941Z,1541530219.941 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:20.143Z,1541530220.143 [Reporter](INFO): platform_buoyancy_position 458.087248 cc 2018-11-06T18:50:20.357Z,1541530220.357 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:20.408Z,1541530220.408 [Reporter](INFO): platform_buoyancy_position 455.938454 cc 2018-11-06T18:50:20.814Z,1541530220.814 [Reporter](INFO): platform_buoyancy_position 451.640837 cc 2018-11-06T18:50:21.215Z,1541530221.215 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:50:21.215Z,1541530221.215 [NAL9602] No Fault, FailCount= 5 2018-11-06T18:50:21.217Z,1541530221.217 [Reporter](INFO): platform_buoyancy_position 449.357758 cc 2018-11-06T18:50:21.447Z,1541530221.447 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:50:21.628Z,1541530221.628 [Reporter](INFO): platform_buoyancy_position 446.940336 cc 2018-11-06T18:50:22.144Z,1541530222.144 [Reporter](INFO): platform_buoyancy_position 442.911347 cc 2018-11-06T18:50:22.383Z,1541530222.383 [Reporter](INFO): platform_buoyancy_position 440.896809 cc 2018-11-06T18:50:22.812Z,1541530222.812 [Reporter](INFO): platform_buoyancy_position 438.479474 cc 2018-11-06T18:50:23.199Z,1541530223.199 [Reporter](INFO): platform_buoyancy_position 434.316142 cc 2018-11-06T18:50:23.619Z,1541530223.619 [Reporter](INFO): platform_buoyancy_position 432.167319 cc 2018-11-06T18:50:24.128Z,1541530224.128 [Reporter](INFO): platform_buoyancy_position 427.869731 cc 2018-11-06T18:50:24.337Z,1541530224.337 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:24.400Z,1541530224.400 [Reporter](INFO): platform_buoyancy_position 425.855251 cc 2018-11-06T18:50:24.823Z,1541530224.823 [Reporter](INFO): platform_buoyancy_position 423.706457 cc 2018-11-06T18:50:25.187Z,1541530225.187 [Reporter](INFO): platform_buoyancy_position 419.408840 cc 2018-11-06T18:50:25.588Z,1541530225.588 [Reporter](INFO): platform_buoyancy_position 417.125761 cc 2018-11-06T18:50:26.108Z,1541530226.108 [Reporter](INFO): platform_buoyancy_position 412.828143 cc 2018-11-06T18:50:26.376Z,1541530226.376 [Reporter](INFO): platform_buoyancy_position 411.619432 cc 2018-11-06T18:50:26.812Z,1541530226.812 [Reporter](INFO): platform_buoyancy_position 408.530555 cc 2018-11-06T18:50:27.286Z,1541530227.286 [Reporter](INFO): platform_buoyancy_position 404.367252 cc 2018-11-06T18:50:28.004Z,1541530228.004 [Reporter](INFO): platform_buoyancy_position 402.218458 cc 2018-11-06T18:50:28.277Z,1541530228.277 [Reporter](INFO): platform_buoyancy_position 396.443531 cc 2018-11-06T18:50:28.644Z,1541530228.644 [Reporter](INFO): platform_buoyancy_position 393.488939 cc 2018-11-06T18:50:29.048Z,1541530229.048 [Reporter](INFO): platform_buoyancy_position 389.325665 cc 2018-11-06T18:50:29.459Z,1541530229.459 [Reporter](INFO): platform_buoyancy_position 387.042528 cc 2018-11-06T18:50:29.753Z,1541530229.753 [Reporter](INFO): platform_buoyancy_position 384.222221 cc 2018-11-06T18:50:30.172Z,1541530230.172 [Reporter](INFO): platform_buoyancy_position 380.730431 cc 2018-11-06T18:50:30.604Z,1541530230.604 [Reporter](INFO): platform_buoyancy_position 376.567128 cc 2018-11-06T18:50:30.964Z,1541530230.964 [Reporter](INFO): platform_buoyancy_position 374.284020 cc 2018-11-06T18:50:31.393Z,1541530231.393 [Reporter](INFO): platform_buoyancy_position 369.986461 cc 2018-11-06T18:50:31.776Z,1541530231.776 [Reporter](INFO): platform_buoyancy_position 365.688844 cc 2018-11-06T18:50:32.180Z,1541530232.180 [Reporter](INFO): platform_buoyancy_position 361.256942 cc 2018-11-06T18:50:32.548Z,1541530232.548 [Reporter](INFO): platform_buoyancy_position 356.959354 cc 2018-11-06T18:50:32.956Z,1541530232.956 [Reporter](INFO): platform_buoyancy_position 350.781513 cc 2018-11-06T18:50:33.380Z,1541530233.380 [Reporter](INFO): platform_buoyancy_position 346.349640 cc 2018-11-06T18:50:33.553Z,1541530233.553 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:33.776Z,1541530233.776 [Reporter](INFO): platform_buoyancy_position 341.783452 cc 2018-11-06T18:50:34.142Z,1541530234.142 [Reporter](INFO): platform_buoyancy_position 335.739926 cc 2018-11-06T18:50:34.556Z,1541530234.556 [Reporter](INFO): platform_buoyancy_position 331.308052 cc 2018-11-06T18:50:34.956Z,1541530234.956 [Reporter](INFO): platform_buoyancy_position 324.861641 cc 2018-11-06T18:50:35.340Z,1541530235.340 [Reporter](INFO): platform_buoyancy_position 320.429739 cc 2018-11-06T18:50:35.768Z,1541530235.768 [Reporter](INFO): platform_buoyancy_position 315.729238 cc 2018-11-06T18:50:36.192Z,1541530236.192 [Reporter](INFO): platform_buoyancy_position 309.820025 cc 2018-11-06T18:50:36.568Z,1541530236.568 [Reporter](INFO): platform_buoyancy_position 305.522437 cc 2018-11-06T18:50:36.944Z,1541530236.944 [Reporter](INFO): platform_buoyancy_position 299.076026 cc 2018-11-06T18:50:37.113Z,1541530237.113 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:37.356Z,1541530237.356 [Reporter](INFO): platform_buoyancy_position 294.644124 cc 2018-11-06T18:50:37.772Z,1541530237.772 [Reporter](INFO): platform_buoyancy_position 288.197713 cc 2018-11-06T18:50:38.196Z,1541530238.196 [Reporter](INFO): platform_buoyancy_position 283.362955 cc 2018-11-06T18:50:38.572Z,1541530238.572 [Reporter](INFO): platform_buoyancy_position 277.588057 cc 2018-11-06T18:50:38.964Z,1541530238.964 [Reporter](INFO): platform_buoyancy_position 273.156125 cc 2018-11-06T18:50:39.360Z,1541530239.360 [Reporter](INFO): platform_buoyancy_position 266.844028 cc 2018-11-06T18:50:39.768Z,1541530239.768 [Reporter](INFO): platform_buoyancy_position 262.412126 cc 2018-11-06T18:50:40.180Z,1541530240.180 [Reporter](INFO): platform_buoyancy_position 256.234343 cc 2018-11-06T18:50:40.572Z,1541530240.572 [Reporter](INFO): platform_buoyancy_position 251.668127 cc 2018-11-06T18:50:40.952Z,1541530240.952 [Reporter](INFO): platform_buoyancy_position 247.101940 cc 2018-11-06T18:50:41.348Z,1541530241.348 [Reporter](INFO): platform_buoyancy_position 241.058442 cc 2018-11-06T18:50:41.495Z,1541530241.495 [Radio_Surface](INFO): Powering down 2018-11-06T18:50:41.764Z,1541530241.764 [Reporter](INFO): platform_buoyancy_position 236.357941 cc 2018-11-06T18:50:42.165Z,1541530242.165 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:42.196Z,1541530242.196 [Reporter](INFO): platform_buoyancy_position 230.180114 cc 2018-11-06T18:50:42.423Z,1541530242.423 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:50:42.423Z,1541530242.423 [NAL9602] Communications Fault, FailCount= 1 2018-11-06T18:50:42.423Z,1541530242.423 [NAL9602](ERROR): Communications Fault 2018-11-06T18:50:42.607Z,1541530242.607 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:50:42.610Z,1541530242.610 [Reporter](INFO): platform_buoyancy_position 223.733732 cc 2018-11-06T18:50:42.831Z,1541530242.831 [NAL9602](INFO): Powering down 2018-11-06T18:50:42.980Z,1541530242.980 [Reporter](INFO): platform_buoyancy_position 219.570429 cc 2018-11-06T18:50:43.348Z,1541530243.348 [Reporter](INFO): platform_buoyancy_position 215.138512 cc 2018-11-06T18:50:43.767Z,1541530243.767 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:50:43.767Z,1541530243.767 [NAL9602] No Fault, FailCount= 1 2018-11-06T18:50:43.768Z,1541530243.768 [Reporter](INFO): platform_buoyancy_position 208.826415 cc 2018-11-06T18:50:44.180Z,1541530244.180 [Reporter](INFO): platform_buoyancy_position 204.528842 cc 2018-11-06T18:50:44.573Z,1541530244.573 [Reporter](INFO): platform_buoyancy_position 199.559727 cc 2018-11-06T18:50:44.960Z,1541530244.960 [Reporter](INFO): platform_buoyancy_position 193.784814 cc 2018-11-06T18:50:45.370Z,1541530245.370 [Reporter](INFO): platform_buoyancy_position 189.352941 cc 2018-11-06T18:50:45.693Z,1541530245.693 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:50:46.135Z,1541530246.135 [Reporter](INFO): platform_buoyancy_position 183.443728 cc 2018-11-06T18:50:46.327Z,1541530246.327 [Reporter](INFO): platform_buoyancy_position 176.460118 cc 2018-11-06T18:50:46.787Z,1541530246.787 [Reporter](INFO): platform_buoyancy_position 170.013736 cc 2018-11-06T18:50:47.120Z,1541530247.120 [Reporter](INFO): platform_buoyancy_position 165.850433 cc 2018-11-06T18:50:47.547Z,1541530247.547 [Reporter](INFO): platform_buoyancy_position 161.418517 cc 2018-11-06T18:50:48.118Z,1541530248.118 [Reporter](INFO): platform_buoyancy_position 152.823297 cc 2018-11-06T18:50:48.374Z,1541530248.374 [Reporter](INFO): platform_buoyancy_position 148.660009 cc 2018-11-06T18:50:48.832Z,1541530248.832 [Reporter](INFO): platform_buoyancy_position 142.347897 cc 2018-11-06T18:50:49.116Z,1541530249.116 [Reporter](INFO): platform_buoyancy_position 139.930518 cc 2018-11-06T18:50:49.535Z,1541530249.535 [Reporter](INFO): platform_buoyancy_position 135.498631 cc 2018-11-06T18:50:50.479Z,1541530250.479 [Reporter](INFO): platform_buoyancy_position 127.172025 cc 2018-11-06T18:50:50.680Z,1541530250.680 [Reporter](INFO): platform_buoyancy_position 120.725606 cc 2018-11-06T18:50:51.097Z,1541530251.097 [Reporter](INFO): platform_buoyancy_position 115.756506 cc 2018-11-06T18:50:51.451Z,1541530251.451 [Reporter](INFO): platform_buoyancy_position 109.847315 cc 2018-11-06T18:50:52.030Z,1541530252.030 [Reporter](INFO): platform_buoyancy_position 103.400918 cc 2018-11-06T18:50:52.243Z,1541530252.243 [Reporter](INFO): platform_buoyancy_position 99.103308 cc 2018-11-06T18:50:52.660Z,1541530252.660 [Reporter](INFO): platform_buoyancy_position 94.805720 cc 2018-11-06T18:50:53.060Z,1541530253.060 [Reporter](INFO): platform_buoyancy_position 88.493616 cc 2018-11-06T18:50:53.475Z,1541530253.475 [Reporter](INFO): platform_buoyancy_position 84.061714 cc 2018-11-06T18:50:54.006Z,1541530254.006 [Reporter](INFO): platform_buoyancy_position 77.212411 cc 2018-11-06T18:50:54.227Z,1541530254.227 [Reporter](INFO): platform_buoyancy_position 73.317708 cc 2018-11-06T18:50:54.660Z,1541530254.660 [Reporter](INFO): platform_buoyancy_position 67.005611 cc 2018-11-06T18:50:55.012Z,1541530255.012 [Reporter](INFO): platform_buoyancy_position 62.708015 cc 2018-11-06T18:50:55.420Z,1541530255.420 [Reporter](INFO): platform_buoyancy_position 58.276117 cc 2018-11-06T18:50:55.876Z,1541530255.876 [Reporter](INFO): platform_buoyancy_position 51.964013 cc 2018-11-06T18:50:56.204Z,1541530256.204 [Reporter](INFO): platform_buoyancy_position 47.532110 cc 2018-11-06T18:50:56.634Z,1541530256.634 [Reporter](INFO): platform_buoyancy_position 41.220010 cc 2018-11-06T18:50:57.058Z,1541530257.058 [Reporter](INFO): platform_buoyancy_position 38.936912 cc 2018-11-06T18:50:57.101Z,1541530257.101 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:01.081Z,1541530261.081 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:02.341Z,1541530262.341 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:12.293Z,1541530272.293 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:12.891Z,1541530272.891 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:51:21.505Z,1541530281.505 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:24.349Z,1541530284.349 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:33.353Z,1541530293.353 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:33.511Z,1541530293.511 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:51:33.511Z,1541530293.511 [NAL9602] Communications Fault, FailCount= 2 2018-11-06T18:51:33.511Z,1541530293.511 [NAL9602](ERROR): Communications Fault 2018-11-06T18:51:33.615Z,1541530293.615 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:51:33.943Z,1541530293.943 [NAL9602](INFO): Powering down 2018-11-06T18:51:34.717Z,1541530294.717 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:34.859Z,1541530294.859 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:51:34.859Z,1541530294.859 [NAL9602] No Fault, FailCount= 2 2018-11-06T18:51:36.905Z,1541530296.905 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:41.594Z,1541530301.594 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-11-06T18:51:56.925Z,1541530316.925 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:57.549Z,1541530317.549 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:51:58.650Z,1541530318.650 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Stopped 2018-11-06T18:51:58.650Z,1541530318.650 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout 2018-11-06T18:52:04.167Z,1541530324.167 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:52:04.581Z,1541530324.581 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:08.133Z,1541530328.133 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:25.079Z,1541530345.079 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:52:25.079Z,1541530345.079 [NAL9602] Communications Fault, FailCount= 3 2018-11-06T18:52:25.079Z,1541530345.079 [NAL9602](ERROR): Communications Fault 2018-11-06T18:52:25.180Z,1541530345.180 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:52:25.483Z,1541530345.483 [NAL9602](INFO): Powering down 2018-11-06T18:52:25.530Z,1541530345.530 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:26.433Z,1541530346.433 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:52:26.433Z,1541530346.433 [NAL9602] No Fault, FailCount= 3 2018-11-06T18:52:32.757Z,1541530352.757 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:33.185Z,1541530353.185 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:35.185Z,1541530355.185 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:37.157Z,1541530357.157 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:38.729Z,1541530358.729 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:41.477Z,1541530361.477 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-11-06T18:52:42.501Z,1541530362.501 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:43.129Z,1541530363.129 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:48.157Z,1541530368.157 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:51.505Z,1541530371.505 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:55.487Z,1541530375.487 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:52:56.321Z,1541530376.321 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:52:59.365Z,1541530379.365 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:03.346Z,1541530383.346 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:06.185Z,1541530386.185 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:06.352Z,1541530386.352 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-11-06T18:53:06.352Z,1541530386.352 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-11-06T18:53:06.352Z,1541530386.352 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-06T18:53:06.396Z,1541530386.396 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-06T18:53:06.771Z,1541530386.771 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-06T18:53:06.772Z,1541530386.772 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-11-06T18:53:07.124Z,1541530387.124 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-06T18:53:07.129Z,1541530387.129 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-06T18:53:07.130Z,1541530387.130 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-06T18:53:10.457Z,1541530390.457 [Reporter](INFO): platform_buoyancy_position 33.027703 cc 2018-11-06T18:53:10.848Z,1541530390.848 [Reporter](INFO): platform_buoyancy_position 32.490501 cc 2018-11-06T18:53:11.236Z,1541530391.236 [Reporter](INFO): platform_buoyancy_position 32.893411 cc 2018-11-06T18:53:11.670Z,1541530391.670 [Reporter](INFO): platform_buoyancy_position 32.624808 cc 2018-11-06T18:53:12.132Z,1541530392.132 [Reporter](INFO): platform_buoyancy_position 32.624808 cc 2018-11-06T18:53:12.420Z,1541530392.420 [Reporter](INFO): platform_buoyancy_position 32.624808 cc 2018-11-06T18:53:12.844Z,1541530392.844 [Reporter](INFO): platform_buoyancy_position 34.102104 cc 2018-11-06T18:53:13.251Z,1541530393.251 [Reporter](INFO): platform_buoyancy_position 34.639306 cc 2018-11-06T18:53:13.576Z,1541530393.576 [Reporter](INFO): platform_buoyancy_position 34.639306 cc 2018-11-06T18:53:14.133Z,1541530394.133 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:14.161Z,1541530394.161 [Reporter](INFO): platform_buoyancy_position 36.788108 cc 2018-11-06T18:53:14.495Z,1541530394.495 [Reporter](INFO): platform_buoyancy_position 36.788108 cc 2018-11-06T18:53:14.871Z,1541530394.871 [Reporter](INFO): platform_buoyancy_position 36.788108 cc 2018-11-06T18:53:15.664Z,1541530395.664 [Reporter](INFO): platform_buoyancy_position 38.936912 cc 2018-11-06T18:53:15.900Z,1541530395.900 [Reporter](INFO): platform_buoyancy_position 39.071205 cc 2018-11-06T18:53:16.087Z,1541530396.087 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:53:16.087Z,1541530396.087 [NAL9602] Communications Fault, FailCount= 4 2018-11-06T18:53:16.087Z,1541530396.087 [NAL9602](ERROR): Communications Fault 2018-11-06T18:53:16.268Z,1541530396.268 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:53:16.279Z,1541530396.279 [Reporter](INFO): platform_buoyancy_position 40.414208 cc 2018-11-06T18:53:16.504Z,1541530396.504 [NAL9602](INFO): Powering down 2018-11-06T18:53:16.655Z,1541530396.655 [Reporter](INFO): platform_buoyancy_position 41.085699 cc 2018-11-06T18:53:16.878Z,1541530396.878 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.234344 2018-11-06T18:53:17.076Z,1541530397.076 [Reporter](INFO): platform_buoyancy_position 41.354302 cc 2018-11-06T18:53:17.181Z,1541530397.181 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:17.424Z,1541530397.424 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:53:17.424Z,1541530397.424 [NAL9602] No Fault, FailCount= 4 2018-11-06T18:53:17.425Z,1541530397.425 [Reporter](INFO): platform_buoyancy_position 43.368811 cc 2018-11-06T18:53:17.799Z,1541530397.799 [Reporter](INFO): platform_buoyancy_position 45.383309 cc 2018-11-06T18:53:18.164Z,1541530398.164 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:C.Pitch] Stopped 2018-11-06T18:53:18.169Z,1541530398.169 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth 2018-11-06T18:53:18.169Z,1541530398.169 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth] Stopped 2018-11-06T18:53:18.169Z,1541530398.169 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth 2018-11-06T18:53:18.169Z,1541530398.169 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Stopped 2018-11-06T18:53:18.169Z,1541530398.169 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2018-11-06T18:53:18.169Z,1541530398.169 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2018-11-06T18:53:18.170Z,1541530398.170 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth 2018-11-06T18:53:18.170Z,1541530398.170 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:A] Running Loop=1 2018-11-06T18:53:18.170Z,1541530398.170 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed] Running Loop=1 2018-11-06T18:53:18.170Z,1541530398.170 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Initialize. 2018-11-06T18:53:18.170Z,1541530398.170 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch] Running Loop=1 2018-11-06T18:53:18.170Z,1541530398.170 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Initialize. 2018-11-06T18:53:18.170Z,1541530398.170 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:D] Running Loop=1 2018-11-06T18:53:18.275Z,1541530398.275 [Reporter](INFO): platform_buoyancy_position 45.517601 cc 2018-11-06T18:53:18.603Z,1541530398.603 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:D](INFO): Waiting for the vehicle to settle. Depth = 7.024257 m 2018-11-06T18:53:18.604Z,1541530398.604 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:D] Stopped 2018-11-06T18:53:18.604Z,1541530398.604 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait] Running Loop=1 2018-11-06T18:53:18.609Z,1541530398.609 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Initialize Wait Component. 2018-11-06T18:53:18.609Z,1541530398.609 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch] Running Loop=1 2018-11-06T18:53:18.621Z,1541530398.621 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed] Running Loop=1 2018-11-06T18:53:18.634Z,1541530398.634 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:A] Running Loop=1 2018-11-06T18:53:18.759Z,1541530398.759 [Reporter](INFO): platform_buoyancy_position 47.532110 cc 2018-11-06T18:53:19.024Z,1541530399.024 [Reporter](INFO): platform_buoyancy_position 47.532110 cc 2018-11-06T18:53:19.169Z,1541530399.169 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:19.464Z,1541530399.464 [Reporter](INFO): platform_buoyancy_position 49.680901 cc 2018-11-06T18:53:19.870Z,1541530399.870 [Reporter](INFO): platform_buoyancy_position 49.680901 cc 2018-11-06T18:53:20.200Z,1541530400.200 [Reporter](INFO): platform_buoyancy_position 49.949500 cc 2018-11-06T18:53:20.608Z,1541530400.608 [Reporter](INFO): platform_buoyancy_position 51.829706 cc 2018-11-06T18:53:21.008Z,1541530401.008 [Reporter](INFO): platform_buoyancy_position 51.964013 cc 2018-11-06T18:53:21.491Z,1541530401.491 [Reporter](INFO): platform_buoyancy_position 53.978507 cc 2018-11-06T18:53:21.851Z,1541530401.851 [Reporter](INFO): platform_buoyancy_position 54.112799 cc 2018-11-06T18:53:22.248Z,1541530402.248 [Reporter](INFO): platform_buoyancy_position 56.127312 cc 2018-11-06T18:53:22.521Z,1541530402.521 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:22.643Z,1541530402.643 [Reporter](INFO): platform_buoyancy_position 56.127312 cc 2018-11-06T18:53:23.055Z,1541530403.055 [Reporter](INFO): platform_buoyancy_position 56.395911 cc 2018-11-06T18:53:23.475Z,1541530403.475 [Reporter](INFO): platform_buoyancy_position 58.276117 cc 2018-11-06T18:53:23.826Z,1541530403.826 [Reporter](INFO): platform_buoyancy_position 58.276117 cc 2018-11-06T18:53:24.001Z,1541530404.001 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:24.240Z,1541530404.240 [Reporter](INFO): platform_buoyancy_position 58.544701 cc 2018-11-06T18:53:24.648Z,1541530404.648 [Reporter](INFO): platform_buoyancy_position 60.424903 cc 2018-11-06T18:53:25.004Z,1541530405.004 [Reporter](INFO): platform_buoyancy_position 60.559210 cc 2018-11-06T18:53:25.411Z,1541530405.411 [Reporter](INFO): platform_buoyancy_position 62.573708 cc 2018-11-06T18:53:25.781Z,1541530405.781 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:25.865Z,1541530405.865 [Reporter](INFO): platform_buoyancy_position 62.708015 cc 2018-11-06T18:53:26.268Z,1541530406.268 [Reporter](INFO): platform_buoyancy_position 64.722510 cc 2018-11-06T18:53:26.513Z,1541530406.513 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:26.610Z,1541530406.610 [Reporter](INFO): platform_buoyancy_position 64.722510 cc 2018-11-06T18:53:27.051Z,1541530407.051 [Reporter](INFO): platform_buoyancy_position 64.991109 cc 2018-11-06T18:53:27.357Z,1541530407.357 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:27.439Z,1541530407.439 [Reporter](INFO): platform_buoyancy_position 66.871318 cc 2018-11-06T18:53:27.866Z,1541530407.866 [Reporter](INFO): platform_buoyancy_position 67.005611 cc 2018-11-06T18:53:28.228Z,1541530408.228 [Reporter](INFO): platform_buoyancy_position 68.617213 cc 2018-11-06T18:53:28.634Z,1541530408.634 [Reporter](INFO): platform_buoyancy_position 69.154412 cc 2018-11-06T18:53:28.705Z,1541530408.705 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:28.988Z,1541530408.988 [Reporter](INFO): platform_buoyancy_position 69.691610 cc 2018-11-06T18:53:29.400Z,1541530409.400 [Reporter](INFO): platform_buoyancy_position 71.168906 cc 2018-11-06T18:53:29.838Z,1541530409.838 [Reporter](INFO): platform_buoyancy_position 73.317708 cc 2018-11-06T18:53:30.220Z,1541530410.220 [Reporter](INFO): platform_buoyancy_position 73.452000 cc 2018-11-06T18:53:30.644Z,1541530410.644 [Reporter](INFO): platform_buoyancy_position 75.466516 cc 2018-11-06T18:53:30.996Z,1541530410.996 [Reporter](INFO): platform_buoyancy_position 75.735115 cc 2018-11-06T18:53:31.440Z,1541530411.440 [Reporter](INFO): platform_buoyancy_position 77.615303 cc 2018-11-06T18:53:31.839Z,1541530411.839 [Reporter](INFO): platform_buoyancy_position 77.883902 cc 2018-11-06T18:53:32.256Z,1541530412.256 [Reporter](INFO): platform_buoyancy_position 79.764104 cc 2018-11-06T18:53:32.695Z,1541530412.695 [Reporter](INFO): platform_buoyancy_position 81.778606 cc 2018-11-06T18:53:32.993Z,1541530412.993 [Reporter](INFO): platform_buoyancy_position 81.912913 cc 2018-11-06T18:53:33.432Z,1541530413.432 [Reporter](INFO): platform_buoyancy_position 82.047205 cc 2018-11-06T18:53:33.868Z,1541530413.868 [Reporter](INFO): platform_buoyancy_position 82.047205 cc 2018-11-06T18:53:34.216Z,1541530414.216 [Reporter](INFO): platform_buoyancy_position 84.061714 cc 2018-11-06T18:53:34.619Z,1541530414.619 [Reporter](INFO): platform_buoyancy_position 84.196006 cc 2018-11-06T18:53:35.051Z,1541530415.051 [Reporter](INFO): platform_buoyancy_position 86.210501 cc 2018-11-06T18:53:35.505Z,1541530415.505 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:35.779Z,1541530415.779 [Reporter](INFO): platform_buoyancy_position 86.210501 cc 2018-11-06T18:53:36.163Z,1541530416.163 [Reporter](INFO): platform_buoyancy_position 88.493616 cc 2018-11-06T18:53:36.411Z,1541530416.411 [Reporter](INFO): platform_buoyancy_position 90.508111 cc 2018-11-06T18:53:36.788Z,1541530416.788 [Reporter](INFO): platform_buoyancy_position 90.508111 cc 2018-11-06T18:53:37.163Z,1541530417.163 [Reporter](INFO): platform_buoyancy_position 92.656912 cc 2018-11-06T18:53:37.552Z,1541530417.552 [Reporter](INFO): platform_buoyancy_position 92.791204 cc 2018-11-06T18:53:38.108Z,1541530418.108 [Reporter](INFO): platform_buoyancy_position 94.805720 cc 2018-11-06T18:53:38.324Z,1541530418.324 [Reporter](INFO): platform_buoyancy_position 94.805720 cc 2018-11-06T18:53:38.784Z,1541530418.784 [Reporter](INFO): platform_buoyancy_position 94.940013 cc 2018-11-06T18:53:39.171Z,1541530419.171 [Reporter](INFO): platform_buoyancy_position 96.954507 cc 2018-11-06T18:53:39.536Z,1541530419.536 [Reporter](INFO): platform_buoyancy_position 97.088814 cc 2018-11-06T18:53:40.168Z,1541530420.168 [Reporter](INFO): platform_buoyancy_position 98.969016 cc 2018-11-06T18:53:40.332Z,1541530420.332 [Reporter](INFO): platform_buoyancy_position 99.103308 cc 2018-11-06T18:53:40.739Z,1541530420.739 [Reporter](INFO): platform_buoyancy_position 98.700417 cc 2018-11-06T18:53:41.172Z,1541530421.172 [Reporter](INFO): platform_buoyancy_position 99.237615 cc 2018-11-06T18:53:41.547Z,1541530421.547 [Reporter](INFO): platform_buoyancy_position 99.237615 cc 2018-11-06T18:53:42.156Z,1541530422.156 [Reporter](INFO): platform_buoyancy_position 101.386409 cc 2018-11-06T18:53:42.366Z,1541530422.366 [Reporter](INFO): platform_buoyancy_position 101.386409 cc 2018-11-06T18:53:42.767Z,1541530422.767 [Reporter](INFO): platform_buoyancy_position 103.535211 cc 2018-11-06T18:53:43.191Z,1541530423.191 [Reporter](INFO): platform_buoyancy_position 103.535211 cc 2018-11-06T18:53:43.504Z,1541530423.504 [Reporter](INFO): platform_buoyancy_position 103.938117 cc 2018-11-06T18:53:43.811Z,1541530423.811 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:44.152Z,1541530424.152 [Reporter](INFO): platform_buoyancy_position 105.684012 cc 2018-11-06T18:53:44.362Z,1541530424.362 [Reporter](INFO): platform_buoyancy_position 105.818304 cc 2018-11-06T18:53:44.725Z,1541530424.725 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:44.761Z,1541530424.761 [Reporter](INFO): platform_buoyancy_position 107.698506 cc 2018-11-06T18:53:45.180Z,1541530425.180 [Reporter](INFO): platform_buoyancy_position 107.698506 cc 2018-11-06T18:53:45.496Z,1541530425.496 [Reporter](INFO): platform_buoyancy_position 109.847315 cc 2018-11-06T18:53:46.178Z,1541530426.178 [Reporter](INFO): platform_buoyancy_position 109.981607 cc 2018-11-06T18:53:46.757Z,1541530426.757 [Reporter](INFO): platform_buoyancy_position 109.981607 cc 2018-11-06T18:53:46.771Z,1541530426.771 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:53:47.051Z,1541530427.051 [Reporter](INFO): platform_buoyancy_position 112.130409 cc 2018-11-06T18:53:47.379Z,1541530427.379 [Reporter](INFO): platform_buoyancy_position 112.130409 cc 2018-11-06T18:53:47.779Z,1541530427.779 [Reporter](INFO): platform_buoyancy_position 114.144903 cc 2018-11-06T18:53:48.227Z,1541530428.227 [Reporter](INFO): platform_buoyancy_position 114.279210 cc 2018-11-06T18:53:48.513Z,1541530428.513 [Reporter](INFO): platform_buoyancy_position 114.413502 cc 2018-11-06T18:53:48.908Z,1541530428.908 [Reporter](INFO): platform_buoyancy_position 116.293711 cc 2018-11-06T18:53:49.336Z,1541530429.336 [Reporter](INFO): platform_buoyancy_position 116.428018 cc 2018-11-06T18:53:49.337Z,1541530429.337 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:49.752Z,1541530429.752 [Reporter](INFO): platform_buoyancy_position 118.442513 cc 2018-11-06T18:53:50.172Z,1541530430.172 [Reporter](INFO): platform_buoyancy_position 118.442513 cc 2018-11-06T18:53:50.488Z,1541530430.488 [Reporter](INFO): platform_buoyancy_position 119.919809 cc 2018-11-06T18:53:50.947Z,1541530430.947 [Reporter](INFO): platform_buoyancy_position 120.591314 cc 2018-11-06T18:53:51.356Z,1541530431.356 [Reporter](INFO): platform_buoyancy_position 120.994213 cc 2018-11-06T18:53:51.712Z,1541530431.712 [Reporter](INFO): platform_buoyancy_position 122.740123 cc 2018-11-06T18:53:52.208Z,1541530432.208 [Reporter](INFO): platform_buoyancy_position 123.008722 cc 2018-11-06T18:53:52.544Z,1541530432.544 [Reporter](INFO): platform_buoyancy_position 125.023216 cc 2018-11-06T18:53:52.908Z,1541530432.908 [Reporter](INFO): platform_buoyancy_position 124.888902 cc 2018-11-06T18:53:53.308Z,1541530433.308 [Reporter](INFO): platform_buoyancy_position 127.037711 cc 2018-11-06T18:53:53.317Z,1541530433.317 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:53:53.757Z,1541530433.757 [Reporter](INFO): platform_buoyancy_position 127.172025 cc 2018-11-06T18:53:54.204Z,1541530434.204 [Reporter](INFO): platform_buoyancy_position 129.186519 cc 2018-11-06T18:53:54.477Z,1541530434.477 [Reporter](INFO): platform_buoyancy_position 129.186519 cc 2018-11-06T18:53:54.896Z,1541530434.896 [Reporter](INFO): platform_buoyancy_position 129.186519 cc 2018-11-06T18:53:55.320Z,1541530435.320 [Reporter](INFO): platform_buoyancy_position 131.335328 cc 2018-11-06T18:53:55.690Z,1541530435.690 [Reporter](INFO): platform_buoyancy_position 131.335328 cc 2018-11-06T18:53:56.265Z,1541530436.265 [Reporter](INFO): platform_buoyancy_position 133.618407 cc 2018-11-06T18:53:56.938Z,1541530436.938 [Reporter](INFO): platform_buoyancy_position 133.618407 cc 2018-11-06T18:53:57.196Z,1541530437.196 [Reporter](INFO): platform_buoyancy_position 135.767215 cc 2018-11-06T18:53:57.597Z,1541530437.597 [Reporter](INFO): platform_buoyancy_position 136.304414 cc 2018-11-06T18:53:58.143Z,1541530438.143 [Reporter](INFO): platform_buoyancy_position 137.781710 cc 2018-11-06T18:53:58.347Z,1541530438.347 [Reporter](INFO): platform_buoyancy_position 137.781710 cc 2018-11-06T18:53:58.701Z,1541530438.701 [Reporter](INFO): platform_buoyancy_position 138.587522 cc 2018-11-06T18:53:59.137Z,1541530439.137 [Reporter](INFO): platform_buoyancy_position 139.930518 cc 2018-11-06T18:53:59.496Z,1541530439.496 [Reporter](INFO): platform_buoyancy_position 140.064818 cc 2018-11-06T18:54:00.032Z,1541530440.032 [Reporter](INFO): platform_buoyancy_position 141.945013 cc 2018-11-06T18:54:00.312Z,1541530440.312 [Reporter](INFO): platform_buoyancy_position 142.079312 cc 2018-11-06T18:54:00.720Z,1541530440.720 [Reporter](INFO): platform_buoyancy_position 142.347897 cc 2018-11-06T18:54:00.969Z,1541530440.969 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:54:01.143Z,1541530441.143 [Reporter](INFO): platform_buoyancy_position 144.228121 cc 2018-11-06T18:54:01.460Z,1541530441.460 [Reporter](INFO): platform_buoyancy_position 144.362421 cc 2018-11-06T18:54:02.060Z,1541530442.060 [Reporter](INFO): platform_buoyancy_position 146.376929 cc 2018-11-06T18:54:02.125Z,1541530442.125 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:54:02.340Z,1541530442.340 [Reporter](INFO): platform_buoyancy_position 146.376929 cc 2018-11-06T18:54:02.672Z,1541530442.672 [Reporter](INFO): platform_buoyancy_position 146.376929 cc 2018-11-06T18:54:03.139Z,1541530443.139 [Reporter](INFO): platform_buoyancy_position 148.525723 cc 2018-11-06T18:54:03.385Z,1541530443.385 [CTD_NeilBrown](DEBUG): serial timeout 2018-11-06T18:54:03.563Z,1541530443.563 [Reporter](INFO): platform_buoyancy_position 148.660009 cc 2018-11-06T18:54:04.100Z,1541530444.100 [Reporter](INFO): platform_buoyancy_position 150.674532 cc 2018-11-06T18:54:04.352Z,1541530444.352 [Reporter](INFO): platform_buoyancy_position 150.674532 cc 2018-11-06T18:54:04.704Z,1541530444.704 [Reporter](INFO): platform_buoyancy_position 150.808803 cc 2018-11-06T18:54:05.116Z,1541530445.116 [Reporter](INFO): platform_buoyancy_position 152.689026 cc 2018-11-06T18:54:05.476Z,1541530445.476 [Reporter](INFO): platform_buoyancy_position 152.957611 cc 2018-11-06T18:54:06.048Z,1541530446.048 [Reporter](INFO): platform_buoyancy_position 154.300622 cc 2018-11-06T18:54:06.352Z,1541530446.352 [Reporter](INFO): platform_buoyancy_position 154.972106 cc 2018-11-06T18:54:06.677Z,1541530446.677 [Reporter](INFO): platform_buoyancy_position 155.106420 cc 2018-11-06T18:54:07.108Z,1541530447.108 [Reporter](INFO): platform_buoyancy_position 156.986600 cc 2018-11-06T18:54:07.518Z,1541530447.518 [Reporter](INFO): platform_buoyancy_position 157.120914 cc 2018-11-06T18:54:07.755Z,1541530447.755 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:54:07.755Z,1541530447.755 [NAL9602] Communications Fault, FailCount= 5 2018-11-06T18:54:07.755Z,1541530447.755 [NAL9602](ERROR): Communications Fault 2018-11-06T18:54:08.059Z,1541530448.059 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:54:08.059Z,1541530448.059 [CBIT](CRITICAL): Communications Fault in component: NAL9602 2018-11-06T18:54:08.061Z,1541530448.061 [Reporter](INFO): platform_buoyancy_position 159.269708 cc 2018-11-06T18:54:08.159Z,1541530448.159 [NAL9602](INFO): Powering down 2018-11-06T18:54:08.357Z,1541530448.357 [CBIT](INFO): Critical error at 20181106T185408 2018-11-06T18:54:08.357Z,1541530448.357 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-11-06T18:54:08.361Z,1541530448.361 [Reporter](INFO): platform_buoyancy_position 159.269708 cc 2018-11-06T18:54:08.672Z,1541530448.672 [MissionManager](INFO): MissionManager is completed. 2018-11-06T18:54:08.672Z,1541530448.672 [MissionManager](INFO): Uninitializing Mission ballast_and_trim 2018-11-06T18:54:08.672Z,1541530448.672 [ballast_and_trim] Stopped 2018-11-06T18:54:08.673Z,1541530448.673 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim 2018-11-06T18:54:08.693Z,1541530448.693 [ballast_and_trim:Science] Stopped 2018-11-06T18:54:08.693Z,1541530448.693 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science 2018-11-06T18:54:08.693Z,1541530448.693 [ballast_and_trim:Science:A] Stopped 2018-11-06T18:54:08.693Z,1541530448.693 [ballast_and_trim:Science:C] Stopped 2018-11-06T18:54:08.693Z,1541530448.693 [ballast_and_trim:Science:G] Stopped 2018-11-06T18:54:08.693Z,1541530448.693 [ballast_and_trim:RunBallastAndTrim] Stopped 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim] Stopped 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:A] Stopped 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:B] Stopped 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:C] Stopped 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2018-11-06T18:54:08.694Z,1541530448.694 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth 2018-11-06T18:54:08.695Z,1541530448.695 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:A] Stopped 2018-11-06T18:54:08.695Z,1541530448.695 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed] Stopped 2018-11-06T18:54:08.695Z,1541530448.695 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Uninitialize. 2018-11-06T18:54:08.695Z,1541530448.695 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch] Stopped 2018-11-06T18:54:08.695Z,1541530448.695 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait] Stopped 2018-11-06T18:54:08.695Z,1541530448.695 [ballast_and_trim:RunBallastAndTrim:Depth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Uninitialize Wait Component. 2018-11-06T18:54:08.833Z,1541530448.833 [WetLabsBB2FL](INFO): Powering down 2018-11-06T18:54:08.908Z,1541530448.908 [Reporter](INFO): platform_buoyancy_position 159.404022 cc 2018-11-06T18:54:08.998Z,1541530448.998 [MissionManager](IMPORTANT): Started mission Default 2018-11-06T18:54:08.999Z,1541530448.999 [Default] Running Loop=1 2018-11-06T18:54:08.999Z,1541530448.999 [Default](DEBUG): Aggregate::initialize Default 2018-11-06T18:54:08.999Z,1541530448.999 [Default:B.GoToSurface] Running Loop=1 2018-11-06T18:54:08.999Z,1541530448.999 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-11-06T18:54:08.999Z,1541530448.999 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-11-06T18:54:08.000Z,1541530449.000 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-11-06T18:54:08.000Z,1541530449.000 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-11-06T18:54:09.000Z,1541530449.000 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-11-06T18:54:09.005Z,1541530449.005 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-11-06T18:54:09.005Z,1541530449.005 [Default:A.Wait] Running Loop=1 2018-11-06T18:54:09.006Z,1541530449.006 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-11-06T18:54:09.088Z,1541530449.088 [Reporter](INFO): platform_buoyancy_position 160.747004 cc 2018-11-06T18:54:09.501Z,1541530449.501 [Aanderaa_O2](INFO): Powering down 2018-11-06T18:54:09.526Z,1541530449.526 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -35.00 mm (1 active estimators). 2018-11-06T18:54:09.660Z,1541530449.660 [Reporter](INFO): platform_buoyancy_position 161.418517 cc 2018-11-06T18:54:09.991Z,1541530449.991 [Reporter](INFO): platform_buoyancy_position 161.687130 cc 2018-11-06T18:54:10.336Z,1541530450.336 [Reporter](INFO): platform_buoyancy_position 163.567325 cc 2018-11-06T18:54:11.091Z,1541530451.091 [Reporter](INFO): platform_buoyancy_position 163.970210 cc 2018-11-06T18:54:11.294Z,1541530451.294 [Reporter](INFO): platform_buoyancy_position 165.850433 cc 2018-11-06T18:54:11.698Z,1541530451.698 [Reporter](INFO): platform_buoyancy_position 167.864928 cc 2018-11-06T18:54:12.163Z,1541530452.163 [Reporter](INFO): platform_buoyancy_position 169.879422 cc 2018-11-06T18:54:12.454Z,1541530452.454 [Reporter](INFO): platform_buoyancy_position 170.148007 cc 2018-11-06T18:54:12.850Z,1541530452.850 [Reporter](INFO): platform_buoyancy_position 172.162530 cc 2018-11-06T18:54:13.302Z,1541530453.302 [Reporter](INFO): platform_buoyancy_position 172.296815 cc 2018-11-06T18:54:13.738Z,1541530453.738 [Reporter](INFO): platform_buoyancy_position 174.311310 cc 2018-11-06T18:54:14.238Z,1541530454.238 [Reporter](INFO): platform_buoyancy_position 176.460118 cc 2018-11-06T18:54:14.502Z,1541530454.502 [Reporter](INFO): platform_buoyancy_position 176.594418 cc 2018-11-06T18:54:14.878Z,1541530454.878 [Reporter](INFO): platform_buoyancy_position 178.608912 cc 2018-11-06T18:54:15.278Z,1541530455.278 [Reporter](INFO): platform_buoyancy_position 178.743227 cc 2018-11-06T18:54:15.650Z,1541530455.650 [Reporter](INFO): platform_buoyancy_position 180.757721 cc 2018-11-06T18:54:16.243Z,1541530456.243 [Reporter](INFO): platform_buoyancy_position 182.906530 cc 2018-11-06T18:54:16.525Z,1541530456.525 [Reporter](INFO): platform_buoyancy_position 183.175114 cc 2018-11-06T18:54:16.946Z,1541530456.946 [Reporter](INFO): platform_buoyancy_position 185.055324 cc 2018-11-06T18:54:17.274Z,1541530457.274 [Reporter](INFO): platform_buoyancy_position 185.189638 cc 2018-11-06T18:54:17.658Z,1541530457.658 [Reporter](INFO): platform_buoyancy_position 187.204132 cc 2018-11-06T18:54:18.166Z,1541530458.166 [Reporter](INFO): platform_buoyancy_position 189.352941 cc 2018-11-06T18:54:18.466Z,1541530458.466 [Reporter](INFO): platform_buoyancy_position 189.352941 cc 2018-11-06T18:54:18.898Z,1541530458.898 [Reporter](INFO): platform_buoyancy_position 191.636020 cc 2018-11-06T18:54:19.282Z,1541530459.282 [Reporter](INFO): platform_buoyancy_position 193.650514 cc 2018-11-06T18:54:19.694Z,1541530459.694 [Reporter](INFO): platform_buoyancy_position 193.650514 cc 2018-11-06T18:54:20.130Z,1541530460.130 [Reporter](INFO): platform_buoyancy_position 195.799323 cc 2018-11-06T18:54:20.566Z,1541530460.566 [Reporter](INFO): platform_buoyancy_position 195.933622 cc 2018-11-06T18:54:20.926Z,1541530460.926 [Reporter](INFO): platform_buoyancy_position 197.948117 cc 2018-11-06T18:54:21.354Z,1541530461.354 [Reporter](INFO): platform_buoyancy_position 198.485315 cc 2018-11-06T18:54:21.698Z,1541530461.698 [Reporter](INFO): platform_buoyancy_position 200.096925 cc 2018-11-06T18:54:22.041Z,1541530462.041 [Default:A.Wait](INFO): Done Waiting. 2018-11-06T18:54:22.042Z,1541530462.042 [Default:A.Wait] Stopped 2018-11-06T18:54:22.042Z,1541530462.042 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-11-06T18:54:22.154Z,1541530462.154 [Reporter](INFO): platform_buoyancy_position 202.245734 cc 2018-11-06T18:54:22.490Z,1541530462.490 [Reporter](INFO): platform_buoyancy_position 202.380033 cc 2018-11-06T18:54:22.890Z,1541530462.890 [Reporter](INFO): platform_buoyancy_position 204.528842 cc 2018-11-06T18:54:23.366Z,1541530463.366 [Reporter](INFO): platform_buoyancy_position 206.543336 cc 2018-11-06T18:54:23.714Z,1541530463.714 [Reporter](INFO): platform_buoyancy_position 206.543336 cc 2018-11-06T18:54:24.231Z,1541530464.231 [Reporter](INFO): platform_buoyancy_position 208.692145 cc 2018-11-06T18:54:24.542Z,1541530464.542 [Reporter](INFO): platform_buoyancy_position 208.960730 cc 2018-11-06T18:54:24.946Z,1541530464.946 [Reporter](INFO): platform_buoyancy_position 210.840910 cc 2018-11-06T18:54:25.302Z,1541530465.302 [Reporter](INFO): platform_buoyancy_position 212.989718 cc 2018-11-06T18:54:25.666Z,1541530465.666 [Reporter](INFO): platform_buoyancy_position 213.124018 cc 2018-11-06T18:54:26.150Z,1541530466.150 [Reporter](INFO): platform_buoyancy_position 215.138512 cc 2018-11-06T18:54:26.500Z,1541530466.500 [Reporter](INFO): platform_buoyancy_position 215.407141 cc 2018-11-06T18:54:26.894Z,1541530466.894 [Reporter](INFO): platform_buoyancy_position 217.287321 cc 2018-11-06T18:54:27.294Z,1541530467.294 [Reporter](INFO): platform_buoyancy_position 219.436130 cc 2018-11-06T18:54:27.658Z,1541530467.658 [Reporter](INFO): platform_buoyancy_position 219.570429 cc 2018-11-06T18:54:28.154Z,1541530468.154 [Reporter](INFO): platform_buoyancy_position 221.719238 cc 2018-11-06T18:54:28.490Z,1541530468.490 [Reporter](INFO): platform_buoyancy_position 223.733732 cc 2018-11-06T18:54:28.898Z,1541530468.898 [Reporter](INFO): platform_buoyancy_position 223.733732 cc 2018-11-06T18:54:29.310Z,1541530469.310 [Reporter](INFO): platform_buoyancy_position 225.882541 cc 2018-11-06T18:54:29.702Z,1541530469.702 [Reporter](INFO): platform_buoyancy_position 225.882541 cc 2018-11-06T18:54:30.154Z,1541530470.154 [Reporter](INFO): platform_buoyancy_position 228.031335 cc 2018-11-06T18:54:30.490Z,1541530470.490 [Reporter](INFO): platform_buoyancy_position 230.180114 cc 2018-11-06T18:54:30.906Z,1541530470.906 [Reporter](INFO): platform_buoyancy_position 230.314428 cc 2018-11-06T18:54:31.338Z,1541530471.338 [Reporter](INFO): platform_buoyancy_position 232.328923 cc 2018-11-06T18:54:31.718Z,1541530471.718 [Reporter](INFO): platform_buoyancy_position 232.463222 cc 2018-11-06T18:54:32.162Z,1541530472.162 [Reporter](INFO): platform_buoyancy_position 234.477717 cc 2018-11-06T18:54:32.498Z,1541530472.498 [Reporter](INFO): platform_buoyancy_position 236.626525 cc 2018-11-06T18:54:32.878Z,1541530472.878 [Reporter](INFO): platform_buoyancy_position 236.626525 cc 2018-11-06T18:54:33.271Z,1541530473.271 [Reporter](INFO): platform_buoyancy_position 238.775334 cc 2018-11-06T18:54:33.722Z,1541530473.722 [Reporter](INFO): platform_buoyancy_position 238.909633 cc 2018-11-06T18:54:34.146Z,1541530474.146 [Reporter](INFO): platform_buoyancy_position 241.058442 cc 2018-11-06T18:54:34.562Z,1541530474.562 [Reporter](INFO): platform_buoyancy_position 243.072936 cc 2018-11-06T18:54:34.914Z,1541530474.914 [Reporter](INFO): platform_buoyancy_position 243.207222 cc 2018-11-06T18:54:35.358Z,1541530475.358 [Reporter](INFO): platform_buoyancy_position 245.356030 cc 2018-11-06T18:54:35.690Z,1541530475.690 [Reporter](INFO): platform_buoyancy_position 245.490315 cc 2018-11-06T18:54:36.174Z,1541530476.174 [Reporter](INFO): platform_buoyancy_position 247.370539 cc 2018-11-06T18:54:36.491Z,1541530476.491 [Reporter](INFO): platform_buoyancy_position 249.519304 cc 2018-11-06T18:54:36.906Z,1541530476.906 [Reporter](INFO): platform_buoyancy_position 249.653618 cc 2018-11-06T18:54:37.358Z,1541530477.358 [Reporter](INFO): platform_buoyancy_position 251.802441 cc 2018-11-06T18:54:37.690Z,1541530477.690 [Reporter](INFO): platform_buoyancy_position 252.473925 cc 2018-11-06T18:54:38.226Z,1541530478.226 [Reporter](INFO): platform_buoyancy_position 253.951235 cc 2018-11-06T18:54:38.522Z,1541530478.522 [Reporter](INFO): platform_buoyancy_position 255.965715 cc 2018-11-06T18:54:38.974Z,1541530478.974 [Reporter](INFO): platform_buoyancy_position 256.234343 cc 2018-11-06T18:54:39.310Z,1541530479.310 [Reporter](INFO): platform_buoyancy_position 258.248852 cc 2018-11-06T18:54:39.766Z,1541530479.766 [Reporter](INFO): platform_buoyancy_position 260.263332 cc 2018-11-06T18:54:40.238Z,1541530480.238 [Reporter](INFO): platform_buoyancy_position 260.666216 cc 2018-11-06T18:54:40.538Z,1541530480.538 [Reporter](INFO): platform_buoyancy_position 262.546411 cc 2018-11-06T18:54:40.942Z,1541530480.942 [Reporter](INFO): platform_buoyancy_position 262.680725 cc 2018-11-06T18:54:41.262Z,1541530481.262 [Reporter](INFO): platform_buoyancy_position 264.560949 cc 2018-11-06T18:54:41.666Z,1541530481.666 [Reporter](INFO): platform_buoyancy_position 266.709714 cc 2018-11-06T18:54:42.158Z,1541530482.158 [Reporter](INFO): platform_buoyancy_position 266.844028 cc 2018-11-06T18:54:42.298Z,1541530482.298 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-11-06T18:54:42.523Z,1541530482.523 [Reporter](INFO): platform_buoyancy_position 268.992822 cc 2018-11-06T18:54:42.974Z,1541530482.974 [Reporter](INFO): platform_buoyancy_position 271.007331 cc 2018-11-06T18:54:43.306Z,1541530483.306 [Reporter](INFO): platform_buoyancy_position 271.007331 cc 2018-11-06T18:54:43.770Z,1541530483.770 [Reporter](INFO): platform_buoyancy_position 273.156125 cc 2018-11-06T18:54:44.179Z,1541530484.179 [Reporter](INFO): platform_buoyancy_position 273.424754 cc 2018-11-06T18:54:44.530Z,1541530484.530 [Reporter](INFO): platform_buoyancy_position 275.304919 cc 2018-11-06T18:54:44.902Z,1541530484.902 [Reporter](INFO): platform_buoyancy_position 277.453742 cc 2018-11-06T18:54:45.286Z,1541530485.286 [Reporter](INFO): platform_buoyancy_position 277.588057 cc 2018-11-06T18:54:45.674Z,1541530485.674 [Reporter](INFO): platform_buoyancy_position 279.602536 cc 2018-11-06T18:54:46.239Z,1541530486.239 [Reporter](INFO): platform_buoyancy_position 280.005421 cc 2018-11-06T18:54:46.534Z,1541530486.534 [Reporter](INFO): platform_buoyancy_position 281.751330 cc 2018-11-06T18:54:46.922Z,1541530486.922 [Reporter](INFO): platform_buoyancy_position 283.900154 cc 2018-11-06T18:54:47.282Z,1541530487.282 [Reporter](INFO): platform_buoyancy_position 283.900154 cc 2018-11-06T18:54:47.694Z,1541530487.694 [Reporter](INFO): platform_buoyancy_position 286.048918 cc 2018-11-06T18:54:48.163Z,1541530488.163 [Reporter](INFO): platform_buoyancy_position 288.197713 cc 2018-11-06T18:54:48.502Z,1541530488.502 [Reporter](INFO): platform_buoyancy_position 288.197713 cc 2018-11-06T18:54:48.987Z,1541530488.987 [Reporter](INFO): platform_buoyancy_position 290.480821 cc 2018-11-06T18:54:49.330Z,1541530489.330 [Reporter](INFO): platform_buoyancy_position 290.346536 cc 2018-11-06T18:54:49.774Z,1541530489.774 [Reporter](INFO): platform_buoyancy_position 292.495330 cc 2018-11-06T18:54:50.158Z,1541530490.158 [Reporter](INFO): platform_buoyancy_position 293.569727 cc 2018-11-06T18:54:50.514Z,1541530490.514 [Reporter](INFO): platform_buoyancy_position 294.644124 cc 2018-11-06T18:54:50.878Z,1541530490.878 [Reporter](INFO): platform_buoyancy_position 296.927232 cc 2018-11-06T18:54:51.270Z,1541530491.270 [Reporter](INFO): platform_buoyancy_position 296.927232 cc 2018-11-06T18:54:51.751Z,1541530491.751 [Reporter](INFO): platform_buoyancy_position 298.941741 cc 2018-11-06T18:54:52.195Z,1541530492.195 [Reporter](INFO): platform_buoyancy_position 301.090535 cc 2018-11-06T18:54:52.582Z,1541530492.582 [Reporter](INFO): platform_buoyancy_position 301.090535 cc 2018-11-06T18:54:52.898Z,1541530492.898 [Reporter](INFO): platform_buoyancy_position 303.239329 cc 2018-11-06T18:54:53.295Z,1541530493.295 [Reporter](INFO): platform_buoyancy_position 303.373614 cc 2018-11-06T18:54:53.670Z,1541530493.670 [Reporter](INFO): platform_buoyancy_position 305.522437 cc 2018-11-06T18:54:54.158Z,1541530494.158 [Reporter](INFO): platform_buoyancy_position 307.536917 cc 2018-11-06T18:54:54.503Z,1541530494.503 [Reporter](INFO): platform_buoyancy_position 307.671231 cc 2018-11-06T18:54:54.886Z,1541530494.886 [Reporter](INFO): platform_buoyancy_position 309.685740 cc 2018-11-06T18:54:55.306Z,1541530495.306 [Reporter](INFO): platform_buoyancy_position 309.820025 cc 2018-11-06T18:54:55.698Z,1541530495.698 [Reporter](INFO): platform_buoyancy_position 311.968848 cc 2018-11-06T18:54:56.142Z,1541530496.142 [Reporter](INFO): platform_buoyancy_position 313.983328 cc 2018-11-06T18:54:56.494Z,1541530496.494 [Reporter](INFO): platform_buoyancy_position 314.117642 cc 2018-11-06T18:54:56.894Z,1541530496.894 [Reporter](INFO): platform_buoyancy_position 315.997837 cc 2018-11-06T18:54:57.282Z,1541530497.282 [Reporter](INFO): platform_buoyancy_position 316.266436 cc 2018-11-06T18:54:57.686Z,1541530497.686 [Reporter](INFO): platform_buoyancy_position 318.415230 cc 2018-11-06T18:54:58.158Z,1541530498.158 [Reporter](INFO): platform_buoyancy_position 320.564024 cc 2018-11-06T18:54:58.486Z,1541530498.486 [Reporter](INFO): platform_buoyancy_position 320.429739 cc 2018-11-06T18:54:58.894Z,1541530498.894 [Reporter](INFO): platform_buoyancy_position 322.578533 cc 2018-11-06T18:54:59.270Z,1541530499.270 [Reporter](INFO): platform_buoyancy_position 322.981447 cc 2018-11-06T18:54:59.686Z,1541530499.686 [Reporter](INFO): platform_buoyancy_position 324.727327 cc 2018-11-06T18:55:00.146Z,1541530500.146 [Reporter](INFO): platform_buoyancy_position 326.876121 cc 2018-11-06T18:55:00.490Z,1541530500.490 [Reporter](INFO): platform_buoyancy_position 327.010435 cc 2018-11-06T18:55:00.894Z,1541530500.894 [Reporter](INFO): platform_buoyancy_position 329.159229 cc 2018-11-06T18:55:01.290Z,1541530501.290 [Reporter](INFO): platform_buoyancy_position 331.173738 cc 2018-11-06T18:55:01.672Z,1541530501.672 [Reporter](INFO): platform_buoyancy_position 331.308052 cc 2018-11-06T18:55:02.155Z,1541530502.155 [Reporter](INFO): platform_buoyancy_position 333.456846 cc 2018-11-06T18:55:02.507Z,1541530502.507 [Reporter](INFO): platform_buoyancy_position 333.456846 cc 2018-11-06T18:55:02.886Z,1541530502.886 [Reporter](INFO): platform_buoyancy_position 335.605611 cc 2018-11-06T18:55:03.278Z,1541530503.278 [Reporter](INFO): platform_buoyancy_position 337.485835 cc 2018-11-06T18:55:03.694Z,1541530503.694 [Reporter](INFO): platform_buoyancy_position 337.888720 cc 2018-11-06T18:55:04.155Z,1541530504.155 [Reporter](INFO): platform_buoyancy_position 339.903228 cc 2018-11-06T18:55:04.439Z,1541530504.439 [Reporter](INFO): platform_buoyancy_position 340.171857 cc 2018-11-06T18:55:04.834Z,1541530504.834 [Reporter](INFO): platform_buoyancy_position 342.052022 cc 2018-11-06T18:55:05.248Z,1541530505.248 [Reporter](INFO): platform_buoyancy_position 344.066531 cc 2018-11-06T18:55:05.652Z,1541530505.652 [Reporter](INFO): platform_buoyancy_position 344.200846 cc 2018-11-06T18:55:06.104Z,1541530506.104 [Reporter](INFO): platform_buoyancy_position 346.349640 cc 2018-11-06T18:55:06.446Z,1541530506.446 [Reporter](INFO): platform_buoyancy_position 348.364149 cc 2018-11-06T18:55:06.815Z,1541530506.815 [Reporter](INFO): platform_buoyancy_position 348.498434 cc 2018-11-06T18:55:07.214Z,1541530507.214 [Reporter](INFO): platform_buoyancy_position 350.512943 cc 2018-11-06T18:55:07.618Z,1541530507.618 [Reporter](INFO): platform_buoyancy_position 350.647257 cc 2018-11-06T18:55:08.082Z,1541530508.082 [Reporter](INFO): platform_buoyancy_position 352.661737 cc 2018-11-06T18:55:08.418Z,1541530508.418 [Reporter](INFO): platform_buoyancy_position 354.676245 cc 2018-11-06T18:55:08.830Z,1541530508.830 [Reporter](INFO): platform_buoyancy_position 354.810560 cc 2018-11-06T18:55:09.246Z,1541530509.246 [Reporter](INFO): platform_buoyancy_position 357.093639 cc 2018-11-06T18:55:09.627Z,1541530509.627 [Reporter](INFO): platform_buoyancy_position 357.093639 cc 2018-11-06T18:55:10.090Z,1541530510.090 [Reporter](INFO): platform_buoyancy_position 359.242433 cc 2018-11-06T18:55:10.434Z,1541530510.434 [Reporter](INFO): platform_buoyancy_position 361.256942 cc 2018-11-06T18:55:10.830Z,1541530510.830 [Reporter](INFO): platform_buoyancy_position 361.391227 cc 2018-11-06T18:55:11.218Z,1541530511.218 [Reporter](INFO): platform_buoyancy_position 363.405736 cc 2018-11-06T18:55:11.614Z,1541530511.614 [Reporter](INFO): platform_buoyancy_position 365.688844 cc 2018-11-06T18:55:12.094Z,1541530512.094 [Reporter](INFO): platform_buoyancy_position 365.688844 cc 2018-11-06T18:55:12.426Z,1541530512.426 [Reporter](INFO): platform_buoyancy_position 367.703353 cc 2018-11-06T18:55:12.830Z,1541530512.830 [Reporter](INFO): platform_buoyancy_position 367.971923 cc 2018-11-06T18:55:13.230Z,1541530513.230 [Reporter](INFO): platform_buoyancy_position 369.852147 cc 2018-11-06T18:55:13.651Z,1541530513.651 [Reporter](INFO): platform_buoyancy_position 370.120717 cc 2018-11-06T18:55:14.082Z,1541530514.082 [Reporter](INFO): platform_buoyancy_position 372.135255 cc 2018-11-06T18:55:14.454Z,1541530514.454 [Reporter](INFO): platform_buoyancy_position 374.284020 cc 2018-11-06T18:55:14.810Z,1541530514.810 [Reporter](INFO): platform_buoyancy_position 374.284020 cc 2018-11-06T18:55:15.214Z,1541530515.214 [Reporter](INFO): platform_buoyancy_position 376.432843 cc 2018-11-06T18:55:15.623Z,1541530515.623 [Reporter](INFO): platform_buoyancy_position 378.447323 cc 2018-11-06T18:55:16.094Z,1541530516.094 [Reporter](INFO): platform_buoyancy_position 379.118836 cc 2018-11-06T18:55:16.439Z,1541530516.439 [Reporter](INFO): platform_buoyancy_position 380.596117 cc 2018-11-06T18:55:16.843Z,1541530516.843 [Reporter](INFO): platform_buoyancy_position 382.744940 cc 2018-11-06T18:55:17.263Z,1541530517.263 [Reporter](INFO): platform_buoyancy_position 382.744940 cc 2018-11-06T18:55:17.614Z,1541530517.614 [Reporter](INFO): platform_buoyancy_position 384.893734 cc 2018-11-06T18:55:18.110Z,1541530518.110 [Reporter](INFO): platform_buoyancy_position 385.162362 cc 2018-11-06T18:55:18.462Z,1541530518.462 [Reporter](INFO): platform_buoyancy_position 387.176842 cc 2018-11-06T18:55:18.826Z,1541530518.826 [Reporter](INFO): platform_buoyancy_position 387.445441 cc 2018-11-06T18:55:19.222Z,1541530519.222 [Reporter](INFO): platform_buoyancy_position 389.459921 cc 2018-11-06T18:55:19.639Z,1541530519.639 [Reporter](INFO): platform_buoyancy_position 391.340145 cc 2018-11-06T18:55:20.110Z,1541530520.110 [Reporter](INFO): platform_buoyancy_position 391.743029 cc 2018-11-06T18:55:20.435Z,1541530520.435 [Reporter](INFO): platform_buoyancy_position 393.488939 cc 2018-11-06T18:55:20.818Z,1541530520.818 [Reporter](INFO): platform_buoyancy_position 395.637733 cc 2018-11-06T18:55:21.210Z,1541530521.210 [Reporter](INFO): platform_buoyancy_position 395.637733 cc 2018-11-06T18:55:21.614Z,1541530521.614 [Reporter](INFO): platform_buoyancy_position 397.786527 cc 2018-11-06T18:55:22.067Z,1541530522.067 [Reporter](INFO): platform_buoyancy_position 399.532437 cc 2018-11-06T18:55:22.434Z,1541530522.434 [Reporter](INFO): platform_buoyancy_position 399.935321 cc 2018-11-06T18:55:22.851Z,1541530522.851 [Reporter](INFO): platform_buoyancy_position 402.084144 cc 2018-11-06T18:55:23.251Z,1541530523.251 [Reporter](INFO): platform_buoyancy_position 402.218458 cc 2018-11-06T18:55:23.622Z,1541530523.622 [Reporter](INFO): platform_buoyancy_position 404.232938 cc 2018-11-06T18:55:24.114Z,1541530524.114 [Reporter](INFO): platform_buoyancy_position 406.381732 cc 2018-11-06T18:55:24.414Z,1541530524.414 [Reporter](INFO): platform_buoyancy_position 406.516046 cc 2018-11-06T18:55:24.814Z,1541530524.814 [Reporter](INFO): platform_buoyancy_position 408.530555 cc 2018-11-06T18:55:25.222Z,1541530525.222 [Reporter](INFO): platform_buoyancy_position 408.933440 cc 2018-11-06T18:55:25.623Z,1541530525.623 [Reporter](INFO): platform_buoyancy_position 410.679349 cc 2018-11-06T18:55:26.154Z,1541530526.154 [Reporter](INFO): platform_buoyancy_position 412.828143 cc 2018-11-06T18:55:26.443Z,1541530526.443 [Reporter](INFO): platform_buoyancy_position 412.962429 cc 2018-11-06T18:55:26.855Z,1541530526.855 [Reporter](INFO): platform_buoyancy_position 414.976967 cc 2018-11-06T18:55:27.218Z,1541530527.218 [Reporter](INFO): platform_buoyancy_position 415.111223 cc 2018-11-06T18:55:27.610Z,1541530527.610 [Reporter](INFO): platform_buoyancy_position 417.260046 cc 2018-11-06T18:55:28.110Z,1541530528.110 [Reporter](INFO): platform_buoyancy_position 419.274555 cc 2018-11-06T18:55:28.422Z,1541530528.422 [Reporter](INFO): platform_buoyancy_position 419.408840 cc 2018-11-06T18:55:28.822Z,1541530528.822 [Reporter](INFO): platform_buoyancy_position 421.423378 cc 2018-11-06T18:55:29.222Z,1541530529.222 [Reporter](INFO): platform_buoyancy_position 422.094832 cc 2018-11-06T18:55:29.630Z,1541530529.630 [Reporter](INFO): platform_buoyancy_position 423.572114 cc 2018-11-06T18:55:30.102Z,1541530530.102 [Reporter](INFO): platform_buoyancy_position 425.855251 cc 2018-11-06T18:55:30.426Z,1541530530.426 [Reporter](INFO): platform_buoyancy_position 425.855251 cc 2018-11-06T18:55:30.848Z,1541530530.848 [Reporter](INFO): platform_buoyancy_position 427.869731 cc 2018-11-06T18:55:31.254Z,1541530531.254 [Reporter](INFO): platform_buoyancy_position 430.018525 cc 2018-11-06T18:55:31.627Z,1541530531.627 [Reporter](INFO): platform_buoyancy_position 430.018525 cc 2018-11-06T18:55:32.110Z,1541530532.110 [Reporter](INFO): platform_buoyancy_position 432.301662 cc 2018-11-06T18:55:32.415Z,1541530532.415 [Reporter](INFO): platform_buoyancy_position 432.301662 cc 2018-11-06T18:55:32.858Z,1541530532.858 [Reporter](INFO): platform_buoyancy_position 434.450456 cc 2018-11-06T18:55:33.226Z,1541530533.226 [Reporter](INFO): platform_buoyancy_position 436.464936 cc 2018-11-06T18:55:33.627Z,1541530533.627 [Reporter](INFO): platform_buoyancy_position 436.464936 cc 2018-11-06T18:55:34.106Z,1541530534.106 [Reporter](INFO): platform_buoyancy_position 438.613730 cc 2018-11-06T18:55:34.434Z,1541530534.434 [Reporter](INFO): platform_buoyancy_position 438.748015 cc 2018-11-06T18:55:34.843Z,1541530534.843 [Reporter](INFO): platform_buoyancy_position 440.896809 cc 2018-11-06T18:55:35.246Z,1541530535.246 [Reporter](INFO): platform_buoyancy_position 442.911347 cc 2018-11-06T18:55:35.614Z,1541530535.614 [Reporter](INFO): platform_buoyancy_position 442.911347 cc 2018-11-06T18:55:36.110Z,1541530536.110 [Reporter](INFO): platform_buoyancy_position 445.060141 cc 2018-11-06T18:55:36.434Z,1541530536.434 [Reporter](INFO): platform_buoyancy_position 445.463054 cc 2018-11-06T18:55:36.854Z,1541530536.854 [Reporter](INFO): platform_buoyancy_position 447.208964 cc 2018-11-06T18:55:37.259Z,1541530537.259 [Reporter](INFO): platform_buoyancy_position 449.357758 cc 2018-11-06T18:55:37.606Z,1541530537.606 [Reporter](INFO): platform_buoyancy_position 449.492043 cc 2018-11-06T18:55:38.098Z,1541530538.098 [Reporter](INFO): platform_buoyancy_position 451.640837 cc 2018-11-06T18:55:38.426Z,1541530538.426 [Reporter](INFO): platform_buoyancy_position 451.506552 cc 2018-11-06T18:55:38.830Z,1541530538.830 [Reporter](INFO): platform_buoyancy_position 453.655375 cc 2018-11-06T18:55:39.262Z,1541530539.262 [Reporter](INFO): platform_buoyancy_position 455.804169 cc 2018-11-06T18:55:39.651Z,1541530539.651 [Reporter](INFO): platform_buoyancy_position 455.938454 cc 2018-11-06T18:55:40.110Z,1541530540.110 [Reporter](INFO): platform_buoyancy_position 458.087248 cc 2018-11-06T18:55:40.430Z,1541530540.430 [Reporter](INFO): platform_buoyancy_position 460.101728 cc 2018-11-06T18:55:40.854Z,1541530540.854 [Reporter](INFO): platform_buoyancy_position 460.236042 cc 2018-11-06T18:55:41.226Z,1541530541.226 [Reporter](INFO): platform_buoyancy_position 462.250522 cc 2018-11-06T18:55:41.626Z,1541530541.626 [Reporter](INFO): platform_buoyancy_position 462.519121 cc 2018-11-06T18:55:42.094Z,1541530542.094 [Reporter](INFO): platform_buoyancy_position 464.399316 cc 2018-11-06T18:55:42.415Z,1541530542.415 [Reporter](INFO): platform_buoyancy_position 466.682453 cc 2018-11-06T18:55:42.699Z,1541530542.699 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-11-06T18:55:42.843Z,1541530542.843 [Reporter](INFO): platform_buoyancy_position 466.682453 cc 2018-11-06T18:55:43.230Z,1541530543.230 [Reporter](INFO): platform_buoyancy_position 468.696933 cc 2018-11-06T18:55:43.614Z,1541530543.614 [Reporter](INFO): platform_buoyancy_position 468.696933 cc 2018-11-06T18:55:44.094Z,1541530544.094 [Reporter](INFO): platform_buoyancy_position 470.845727 cc 2018-11-06T18:55:44.446Z,1541530544.446 [Reporter](INFO): platform_buoyancy_position 472.994550 cc 2018-11-06T18:55:44.850Z,1541530544.850 [Reporter](INFO): platform_buoyancy_position 473.128865 cc 2018-11-06T18:55:45.243Z,1541530545.243 [Reporter](INFO): platform_buoyancy_position 475.143344 cc 2018-11-06T18:55:45.614Z,1541530545.614 [Reporter](INFO): platform_buoyancy_position 477.426423 cc 2018-11-06T18:55:46.086Z,1541530546.086 [Reporter](INFO): platform_buoyancy_position 477.426423 cc 2018-11-06T18:55:46.430Z,1541530546.430 [Reporter](INFO): platform_buoyancy_position 479.440961 cc 2018-11-06T18:55:46.834Z,1541530546.834 [Reporter](INFO): platform_buoyancy_position 480.381073 cc 2018-11-06T18:55:47.234Z,1541530547.234 [Reporter](INFO): platform_buoyancy_position 481.589756 cc 2018-11-06T18:55:47.662Z,1541530547.662 [Reporter](INFO): platform_buoyancy_position 483.738550 cc 2018-11-06T18:55:48.086Z,1541530548.086 [Reporter](INFO): platform_buoyancy_position 483.872835 cc 2018-11-06T18:55:48.450Z,1541530548.450 [Reporter](INFO): platform_buoyancy_position 486.021629 cc 2018-11-06T18:55:48.818Z,1541530548.818 [Reporter](INFO): platform_buoyancy_position 486.693141 cc 2018-11-06T18:55:49.230Z,1541530549.230 [Reporter](INFO): platform_buoyancy_position 488.036167 cc 2018-11-06T18:55:49.626Z,1541530549.626 [Reporter](INFO): platform_buoyancy_position 490.184990 cc 2018-11-06T18:55:50.106Z,1541530550.106 [Reporter](INFO): platform_buoyancy_position 490.319217 cc 2018-11-06T18:55:50.439Z,1541530550.439 [Reporter](INFO): platform_buoyancy_position 492.333784 cc 2018-11-06T18:55:50.839Z,1541530550.839 [Reporter](INFO): platform_buoyancy_position 494.348234 cc 2018-11-06T18:55:51.218Z,1541530551.218 [Reporter](INFO): platform_buoyancy_position 494.616863 cc 2018-11-06T18:55:51.556Z,1541530551.556 [Default:CheckIn] Running Loop=1 2018-11-06T18:55:51.556Z,1541530551.556 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-11-06T18:55:51.556Z,1541530551.556 [Default:CheckIn:Read_GPS] Running Loop=1 2018-11-06T18:55:51.627Z,1541530551.627 [Reporter](INFO): platform_buoyancy_position 496.765657 cc 2018-11-06T18:55:51.638Z,1541530551.638 [Radio_Surface](INFO): Powering up 2018-11-06T18:55:52.074Z,1541530552.074 [Reporter](INFO): platform_buoyancy_position 498.780108 cc 2018-11-06T18:55:52.416Z,1541530552.416 [Reporter](INFO): platform_buoyancy_position 498.780108 cc 2018-11-06T18:55:52.931Z,1541530552.931 [Reporter](INFO): platform_buoyancy_position 500.928902 cc 2018-11-06T18:55:53.643Z,1541530553.643 [Reporter](INFO): platform_buoyancy_position 501.197530 cc 2018-11-06T18:55:53.851Z,1541530553.851 [Reporter](INFO): platform_buoyancy_position 503.212039 cc 2018-11-06T18:55:54.243Z,1541530554.243 [Reporter](INFO): platform_buoyancy_position 505.226548 cc 2018-11-06T18:55:54.635Z,1541530554.635 [Reporter](INFO): platform_buoyancy_position 505.226548 cc 2018-11-06T18:55:55.015Z,1541530555.015 [Reporter](INFO): platform_buoyancy_position 507.375342 cc 2018-11-06T18:55:55.419Z,1541530555.419 [Reporter](INFO): platform_buoyancy_position 508.986996 cc 2018-11-06T18:55:55.910Z,1541530555.910 [Reporter](INFO): platform_buoyancy_position 509.792764 cc 2018-11-06T18:55:56.191Z,1541530556.191 [Reporter](INFO): platform_buoyancy_position 511.672930 cc 2018-11-06T18:55:56.603Z,1541530556.603 [Reporter](INFO): platform_buoyancy_position 511.807215 cc 2018-11-06T18:55:57.011Z,1541530557.011 [Reporter](INFO): platform_buoyancy_position 513.821724 cc 2018-11-06T18:55:57.415Z,1541530557.415 [Reporter](INFO): platform_buoyancy_position 516.104803 cc 2018-11-06T18:55:57.815Z,1541530557.815 [Reporter](INFO): platform_buoyancy_position 515.970518 cc 2018-11-06T18:55:58.201Z,1541530558.201 [Reporter](INFO): platform_buoyancy_position 518.119312 cc 2018-11-06T18:55:58.615Z,1541530558.615 [Reporter](INFO): platform_buoyancy_position 518.656510 cc 2018-11-06T18:55:59.008Z,1541530559.008 [Reporter](INFO): platform_buoyancy_position 520.402391 cc 2018-11-06T18:55:59.403Z,1541530559.403 [Reporter](INFO): platform_buoyancy_position 522.416958 cc 2018-11-06T18:55:59.797Z,1541530559.797 [Reporter](INFO): platform_buoyancy_position 522.551185 cc 2018-11-06T18:56:00.203Z,1541530560.203 [Reporter](INFO): platform_buoyancy_position 524.565752 cc 2018-11-06T18:56:00.534Z,1541530560.534 [Radio_Surface](INFO): Powering down 2018-11-06T18:56:00.631Z,1541530560.631 [Reporter](INFO): platform_buoyancy_position 525.102892 cc 2018-11-06T18:56:01.047Z,1541530561.047 [Reporter](INFO): platform_buoyancy_position 526.714546 cc 2018-11-06T18:56:01.522Z,1541530561.522 [Reporter](INFO): platform_buoyancy_position 528.997625 cc 2018-11-06T18:56:01.908Z,1541530561.908 [Reporter](INFO): platform_buoyancy_position 528.997625 cc 2018-11-06T18:56:02.246Z,1541530562.246 [Reporter](INFO): platform_buoyancy_position 531.146419 cc 2018-11-06T18:56:02.654Z,1541530562.654 [Reporter](INFO): platform_buoyancy_position 533.160928 cc 2018-11-06T18:56:03.062Z,1541530563.062 [Reporter](INFO): platform_buoyancy_position 533.295213 cc 2018-11-06T18:56:03.478Z,1541530563.478 [Reporter](INFO): platform_buoyancy_position 535.309664 cc 2018-11-06T18:56:03.872Z,1541530563.872 [Reporter](INFO): platform_buoyancy_position 536.249834 cc 2018-11-06T18:56:04.346Z,1541530564.346 [Reporter](INFO): platform_buoyancy_position 537.458516 cc 2018-11-06T18:56:04.662Z,1541530564.662 [Reporter](INFO): platform_buoyancy_position 539.607310 cc 2018-11-06T18:56:05.050Z,1541530565.050 [Reporter](INFO): platform_buoyancy_position 539.607310 cc 2018-11-06T18:56:05.526Z,1541530565.526 [Reporter](INFO): platform_buoyancy_position 541.756104 cc 2018-11-06T18:56:05.846Z,1541530565.846 [Reporter](INFO): platform_buoyancy_position 541.890447 cc 2018-11-06T18:56:06.299Z,1541530566.299 [Reporter](INFO): platform_buoyancy_position 544.039242 cc 2018-11-06T18:56:06.646Z,1541530566.646 [Reporter](INFO): platform_buoyancy_position 546.188036 cc 2018-11-06T18:56:07.106Z,1541530567.106 [Reporter](INFO): platform_buoyancy_position 546.053692 cc 2018-11-06T18:56:07.301Z,1541530567.301 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-11-06T18:56:07.301Z,1541530567.301 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-11-06T18:56:07.301Z,1541530567.301 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-06T18:56:07.426Z,1541530567.426 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-06T18:56:07.427Z,1541530567.427 [Reporter](INFO): platform_buoyancy_position 548.202486 cc 2018-11-06T18:56:07.851Z,1541530567.851 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:56:07.852Z,1541530567.852 [NAL9602] No Fault, FailCount= 5 2018-11-06T18:56:07.853Z,1541530567.853 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-06T18:56:07.853Z,1541530567.853 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-11-06T18:56:07.856Z,1541530567.856 [Reporter](INFO): platform_buoyancy_position 550.351338 cc 2018-11-06T18:56:08.067Z,1541530568.067 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:56:08.121Z,1541530568.121 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-06T18:56:08.122Z,1541530568.122 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-06T18:56:08.122Z,1541530568.122 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-06T18:56:08.258Z,1541530568.258 [Reporter](INFO): platform_buoyancy_position 550.351338 cc 2018-11-06T18:56:08.566Z,1541530568.566 [Reporter](INFO): platform_buoyancy_position 552.365847 cc 2018-11-06T18:56:08.990Z,1541530568.990 [Reporter](INFO): platform_buoyancy_position 552.634418 cc 2018-11-06T18:56:09.366Z,1541530569.366 [Reporter](INFO): platform_buoyancy_position 554.648926 cc 2018-11-06T18:56:09.774Z,1541530569.774 [Reporter](INFO): platform_buoyancy_position 555.454695 cc 2018-11-06T18:56:10.186Z,1541530570.186 [Reporter](INFO): platform_buoyancy_position 556.932006 cc 2018-11-06T18:56:10.594Z,1541530570.594 [Reporter](INFO): platform_buoyancy_position 558.946515 cc 2018-11-06T18:56:11.037Z,1541530571.037 [Reporter](INFO): platform_buoyancy_position 559.080800 cc 2018-11-06T18:56:11.390Z,1541530571.390 [Reporter](INFO): platform_buoyancy_position 561.095309 cc 2018-11-06T18:56:11.792Z,1541530571.792 [Reporter](INFO): platform_buoyancy_position 563.244161 cc 2018-11-06T18:56:12.182Z,1541530572.182 [Reporter](INFO): platform_buoyancy_position 563.378446 cc 2018-11-06T18:56:12.570Z,1541530572.570 [Reporter](INFO): platform_buoyancy_position 565.392955 cc 2018-11-06T18:56:12.970Z,1541530572.970 [Reporter](INFO): platform_buoyancy_position 565.795810 cc 2018-11-06T18:56:13.379Z,1541530573.379 [Reporter](INFO): platform_buoyancy_position 567.541749 cc 2018-11-06T18:56:13.779Z,1541530573.779 [Reporter](INFO): platform_buoyancy_position 569.690543 cc 2018-11-06T18:56:14.178Z,1541530574.178 [Reporter](INFO): platform_buoyancy_position 569.824828 cc 2018-11-06T18:56:14.574Z,1541530574.574 [Reporter](INFO): platform_buoyancy_position 571.973622 cc 2018-11-06T18:56:14.978Z,1541530574.978 [Reporter](INFO): platform_buoyancy_position 573.316647 cc 2018-11-06T18:56:15.366Z,1541530575.366 [Reporter](INFO): platform_buoyancy_position 573.988073 cc 2018-11-06T18:56:15.774Z,1541530575.774 [Reporter](INFO): platform_buoyancy_position 576.271268 cc 2018-11-06T18:56:16.174Z,1541530576.174 [Reporter](INFO): platform_buoyancy_position 576.271268 cc 2018-11-06T18:56:16.588Z,1541530576.588 [Reporter](INFO): platform_buoyancy_position 578.285719 cc 2018-11-06T18:56:16.974Z,1541530576.974 [Reporter](INFO): platform_buoyancy_position 580.434513 cc 2018-11-06T18:56:17.395Z,1541530577.395 [Reporter](INFO): platform_buoyancy_position 580.434513 cc 2018-11-06T18:56:17.804Z,1541530577.804 [Reporter](INFO): platform_buoyancy_position 582.583307 cc 2018-11-06T18:56:18.178Z,1541530578.178 [Reporter](INFO): platform_buoyancy_position 582.717650 cc 2018-11-06T18:56:18.590Z,1541530578.590 [Reporter](INFO): platform_buoyancy_position 584.866386 cc 2018-11-06T18:56:18.974Z,1541530578.974 [Reporter](INFO): platform_buoyancy_position 585.537928 cc 2018-11-06T18:56:19.394Z,1541530579.394 [Reporter](INFO): platform_buoyancy_position 587.015180 cc 2018-11-06T18:56:19.786Z,1541530579.786 [Reporter](INFO): platform_buoyancy_position 589.029747 cc 2018-11-06T18:56:20.198Z,1541530580.198 [Reporter](INFO): platform_buoyancy_position 589.163974 cc 2018-11-06T18:56:20.578Z,1541530580.578 [Reporter](INFO): platform_buoyancy_position 591.178541 cc 2018-11-06T18:56:20.978Z,1541530580.978 [Reporter](INFO): platform_buoyancy_position 591.312826 cc 2018-11-06T18:56:21.366Z,1541530581.366 [Reporter](INFO): platform_buoyancy_position 593.461620 cc 2018-11-06T18:56:21.774Z,1541530581.774 [Reporter](INFO): platform_buoyancy_position 595.476129 cc 2018-11-06T18:56:22.190Z,1541530582.190 [Reporter](INFO): platform_buoyancy_position 595.744757 cc 2018-11-06T18:56:22.591Z,1541530582.591 [Reporter](INFO): platform_buoyancy_position 597.624923 cc 2018-11-06T18:56:22.978Z,1541530582.978 [Reporter](INFO): platform_buoyancy_position 599.773717 cc 2018-11-06T18:56:23.374Z,1541530583.374 [Reporter](INFO): platform_buoyancy_position 599.773717 cc 2018-11-06T18:56:23.774Z,1541530583.774 [Reporter](INFO): platform_buoyancy_position 601.922569 cc 2018-11-06T18:56:24.198Z,1541530584.198 [Reporter](INFO): platform_buoyancy_position 602.056796 cc 2018-11-06T18:56:24.586Z,1541530584.586 [Reporter](INFO): platform_buoyancy_position 604.071363 cc 2018-11-06T18:56:24.970Z,1541530584.970 [Reporter](INFO): platform_buoyancy_position 606.220157 cc 2018-11-06T18:56:25.394Z,1541530585.394 [Reporter](INFO): platform_buoyancy_position 606.354442 cc 2018-11-06T18:56:25.799Z,1541530585.799 [Reporter](INFO): platform_buoyancy_position 608.368893 cc 2018-11-06T18:56:26.191Z,1541530586.191 [Reporter](INFO): platform_buoyancy_position 610.517687 cc 2018-11-06T18:56:26.578Z,1541530586.578 [Reporter](INFO): platform_buoyancy_position 610.652030 cc 2018-11-06T18:56:26.990Z,1541530586.990 [Reporter](INFO): platform_buoyancy_position 612.666481 cc 2018-11-06T18:56:27.382Z,1541530587.382 [Reporter](INFO): platform_buoyancy_position 612.935110 cc 2018-11-06T18:56:27.785Z,1541530587.785 [Reporter](INFO): platform_buoyancy_position 614.815333 cc 2018-11-06T18:56:28.178Z,1541530588.178 [Reporter](INFO): platform_buoyancy_position 616.964127 cc 2018-11-06T18:56:28.582Z,1541530588.582 [Reporter](INFO): platform_buoyancy_position 617.098471 cc 2018-11-06T18:56:28.986Z,1541530588.986 [Reporter](INFO): platform_buoyancy_position 619.112921 cc 2018-11-06T18:56:29.259Z,1541530589.259 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:56:29.259Z,1541530589.259 [NAL9602] Communications Fault, FailCount= 1 2018-11-06T18:56:29.259Z,1541530589.259 [NAL9602](ERROR): Communications Fault 2018-11-06T18:56:29.409Z,1541530589.409 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:56:29.411Z,1541530589.411 [Reporter](INFO): platform_buoyancy_position 619.247265 cc 2018-11-06T18:56:29.655Z,1541530589.655 [NAL9602](INFO): Powering down 2018-11-06T18:56:29.774Z,1541530589.774 [Reporter](INFO): platform_buoyancy_position 621.261715 cc 2018-11-06T18:56:30.214Z,1541530590.214 [Reporter](INFO): platform_buoyancy_position 623.410509 cc 2018-11-06T18:56:30.585Z,1541530590.585 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:56:30.585Z,1541530590.585 [NAL9602] No Fault, FailCount= 1 2018-11-06T18:56:30.587Z,1541530590.587 [Reporter](INFO): platform_buoyancy_position 623.544795 cc 2018-11-06T18:56:30.986Z,1541530590.986 [Reporter](INFO): platform_buoyancy_position 625.559303 cc 2018-11-06T18:56:31.386Z,1541530591.386 [Reporter](INFO): platform_buoyancy_position 625.693589 cc 2018-11-06T18:56:31.779Z,1541530591.779 [Reporter](INFO): platform_buoyancy_position 627.842383 cc 2018-11-06T18:56:32.194Z,1541530592.194 [Reporter](INFO): platform_buoyancy_position 629.856950 cc 2018-11-06T18:56:32.566Z,1541530592.566 [Reporter](INFO): platform_buoyancy_position 629.991235 cc 2018-11-06T18:56:33.002Z,1541530593.002 [Reporter](INFO): platform_buoyancy_position 632.005744 cc 2018-11-06T18:56:33.382Z,1541530593.382 [Reporter](INFO): platform_buoyancy_position 633.080141 cc 2018-11-06T18:56:33.778Z,1541530593.778 [Reporter](INFO): platform_buoyancy_position 634.288823 cc 2018-11-06T18:56:34.178Z,1541530594.178 [Reporter](INFO): platform_buoyancy_position 636.303332 cc 2018-11-06T18:56:34.574Z,1541530594.574 [Reporter](INFO): platform_buoyancy_position 636.437617 cc 2018-11-06T18:56:34.994Z,1541530594.994 [Reporter](INFO): platform_buoyancy_position 638.452126 cc 2018-11-06T18:56:35.406Z,1541530595.406 [Reporter](INFO): platform_buoyancy_position 640.735205 cc 2018-11-06T18:56:35.770Z,1541530595.770 [Reporter](INFO): platform_buoyancy_position 640.735205 cc 2018-11-06T18:56:36.186Z,1541530596.186 [Reporter](INFO): platform_buoyancy_position 642.749772 cc 2018-11-06T18:56:36.590Z,1541530596.590 [Reporter](INFO): platform_buoyancy_position 642.884057 cc 2018-11-06T18:56:36.982Z,1541530596.982 [Reporter](INFO): platform_buoyancy_position 645.032851 cc 2018-11-06T18:56:37.386Z,1541530597.386 [Reporter](INFO): platform_buoyancy_position 647.047302 cc 2018-11-06T18:56:37.805Z,1541530597.805 [Reporter](INFO): platform_buoyancy_position 647.181645 cc 2018-11-06T18:56:38.190Z,1541530598.190 [Reporter](INFO): platform_buoyancy_position 649.196096 cc 2018-11-06T18:56:38.558Z,1541530598.558 [Reporter](INFO): platform_buoyancy_position 649.464724 cc 2018-11-06T18:56:39.018Z,1541530599.018 [Reporter](INFO): platform_buoyancy_position 651.344890 cc 2018-11-06T18:56:39.366Z,1541530599.366 [Reporter](INFO): platform_buoyancy_position 653.493742 cc 2018-11-06T18:56:39.774Z,1541530599.774 [Reporter](INFO): platform_buoyancy_position 653.628027 cc 2018-11-06T18:56:40.174Z,1541530600.174 [Reporter](INFO): platform_buoyancy_position 655.642536 cc 2018-11-06T18:56:40.582Z,1541530600.582 [Reporter](INFO): platform_buoyancy_position 657.657045 cc 2018-11-06T18:56:40.978Z,1541530600.978 [Reporter](INFO): platform_buoyancy_position 657.925673 cc 2018-11-06T18:56:41.402Z,1541530601.402 [Reporter](INFO): platform_buoyancy_position 659.940124 cc 2018-11-06T18:56:41.782Z,1541530601.782 [Reporter](INFO): platform_buoyancy_position 660.074409 cc 2018-11-06T18:56:42.178Z,1541530602.178 [Reporter](INFO): platform_buoyancy_position 662.088918 cc 2018-11-06T18:56:42.450Z,1541530602.450 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-11-06T18:56:42.587Z,1541530602.587 [Reporter](INFO): platform_buoyancy_position 664.237712 cc 2018-11-06T18:56:42.966Z,1541530602.966 [Reporter](INFO): platform_buoyancy_position 664.237712 cc 2018-11-06T18:56:43.370Z,1541530603.370 [Reporter](INFO): platform_buoyancy_position 666.386564 cc 2018-11-06T18:56:43.780Z,1541530603.780 [Reporter](INFO): platform_buoyancy_position 666.386564 cc 2018-11-06T18:56:44.192Z,1541530604.192 [Reporter](INFO): platform_buoyancy_position 668.535358 cc 2018-11-06T18:56:44.582Z,1541530604.582 [Reporter](INFO): platform_buoyancy_position 670.818437 cc 2018-11-06T18:56:44.995Z,1541530604.995 [Reporter](INFO): platform_buoyancy_position 670.818437 cc 2018-11-06T18:56:45.370Z,1541530605.370 [Reporter](INFO): platform_buoyancy_position 672.967231 cc 2018-11-06T18:56:45.787Z,1541530605.787 [Reporter](INFO): platform_buoyancy_position 672.967231 cc 2018-11-06T18:56:46.170Z,1541530606.170 [Reporter](INFO): platform_buoyancy_position 674.981740 cc 2018-11-06T18:56:46.578Z,1541530606.578 [Reporter](INFO): platform_buoyancy_position 677.130534 cc 2018-11-06T18:56:46.987Z,1541530606.987 [Reporter](INFO): platform_buoyancy_position 677.264819 cc 2018-11-06T18:56:47.378Z,1541530607.378 [Reporter](INFO): platform_buoyancy_position 679.413613 cc 2018-11-06T18:56:47.792Z,1541530607.792 [Reporter](INFO): platform_buoyancy_position 679.413613 cc 2018-11-06T18:56:48.187Z,1541530608.187 [Reporter](INFO): platform_buoyancy_position 681.428122 cc 2018-11-06T18:56:48.582Z,1541530608.582 [Reporter](INFO): platform_buoyancy_position 683.711260 cc 2018-11-06T18:56:48.970Z,1541530608.970 [Reporter](INFO): platform_buoyancy_position 683.711260 cc 2018-11-06T18:56:49.366Z,1541530609.366 [Reporter](INFO): platform_buoyancy_position 685.860054 cc 2018-11-06T18:56:49.803Z,1541530609.803 [Reporter](INFO): platform_buoyancy_position 687.874504 cc 2018-11-06T18:56:50.178Z,1541530610.178 [Reporter](INFO): platform_buoyancy_position 688.143133 cc 2018-11-06T18:56:50.587Z,1541530610.587 [Reporter](INFO): platform_buoyancy_position 690.157642 cc 2018-11-06T18:56:50.983Z,1541530610.983 [Reporter](INFO): platform_buoyancy_position 690.157642 cc 2018-11-06T18:56:51.386Z,1541530611.386 [Reporter](INFO): platform_buoyancy_position 692.172092 cc 2018-11-06T18:56:51.803Z,1541530611.803 [Reporter](INFO): platform_buoyancy_position 694.320945 cc 2018-11-06T18:56:52.206Z,1541530612.206 [Reporter](INFO): platform_buoyancy_position 694.455288 cc 2018-11-06T18:56:52.578Z,1541530612.578 [Reporter](INFO): platform_buoyancy_position 696.469739 cc 2018-11-06T18:56:52.670Z,1541530612.670 [Radio_Surface](INFO): Powering up 2018-11-06T18:56:52.937Z,1541530612.937 [Reporter](INFO): platform_buoyancy_position 696.604024 cc 2018-11-06T18:56:53.340Z,1541530613.340 [Reporter](INFO): platform_buoyancy_position 698.752818 cc 2018-11-06T18:56:53.880Z,1541530613.880 [Reporter](INFO): platform_buoyancy_position 700.633042 cc 2018-11-06T18:56:54.591Z,1541530614.591 [Reporter](INFO): platform_buoyancy_position 700.901612 cc 2018-11-06T18:56:54.783Z,1541530614.783 [Reporter](INFO): platform_buoyancy_position 702.916121 cc 2018-11-06T18:56:55.180Z,1541530615.180 [Reporter](INFO): platform_buoyancy_position 705.064915 cc 2018-11-06T18:56:55.563Z,1541530615.563 [Reporter](INFO): platform_buoyancy_position 705.199200 cc 2018-11-06T18:56:56.083Z,1541530616.083 [Reporter](INFO): platform_buoyancy_position 707.347994 cc 2018-11-06T18:56:56.365Z,1541530616.365 [Reporter](INFO): platform_buoyancy_position 709.093933 cc 2018-11-06T18:56:56.819Z,1541530616.819 [Reporter](INFO): platform_buoyancy_position 709.496846 cc 2018-11-06T18:56:57.135Z,1541530617.135 [Reporter](INFO): platform_buoyancy_position 711.511355 cc 2018-11-06T18:56:57.535Z,1541530617.535 [Reporter](INFO): platform_buoyancy_position 711.511355 cc 2018-11-06T18:56:58.051Z,1541530618.051 [Reporter](INFO): platform_buoyancy_position 713.794434 cc 2018-11-06T18:56:58.355Z,1541530618.355 [Reporter](INFO): platform_buoyancy_position 715.808943 cc 2018-11-06T18:56:58.748Z,1541530618.748 [Reporter](INFO): platform_buoyancy_position 715.943228 cc 2018-11-06T18:56:59.159Z,1541530619.159 [Reporter](INFO): platform_buoyancy_position 717.957737 cc 2018-11-06T18:56:59.557Z,1541530619.557 [Reporter](INFO): platform_buoyancy_position 718.092022 cc 2018-11-06T18:56:59.815Z,1541530619.815 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:57:00.000Z,1541530620.000 [Reporter](INFO): platform_buoyancy_position 720.106531 cc 2018-11-06T18:57:00.359Z,1541530620.359 [Reporter](INFO): platform_buoyancy_position 722.255325 cc 2018-11-06T18:57:00.747Z,1541530620.747 [Reporter](INFO): platform_buoyancy_position 722.389668 cc 2018-11-06T18:57:01.155Z,1541530621.155 [Reporter](INFO): platform_buoyancy_position 724.538462 cc 2018-11-06T18:57:01.551Z,1541530621.551 [Reporter](INFO): platform_buoyancy_position 726.552913 cc 2018-11-06T18:57:02.075Z,1541530622.075 [Reporter](INFO): platform_buoyancy_position 728.836050 cc 2018-11-06T18:57:02.354Z,1541530622.354 [Reporter](INFO): platform_buoyancy_position 728.836050 cc 2018-11-06T18:57:02.760Z,1541530622.760 [Reporter](INFO): platform_buoyancy_position 728.836050 cc 2018-11-06T18:57:03.139Z,1541530623.139 [Reporter](INFO): platform_buoyancy_position 730.850501 cc 2018-11-06T18:57:03.543Z,1541530623.543 [Reporter](INFO): platform_buoyancy_position 731.119129 cc 2018-11-06T18:57:04.059Z,1541530624.059 [Reporter](INFO): platform_buoyancy_position 733.133580 cc 2018-11-06T18:57:04.367Z,1541530624.367 [Reporter](INFO): platform_buoyancy_position 735.148147 cc 2018-11-06T18:57:04.739Z,1541530624.739 [Reporter](INFO): platform_buoyancy_position 735.282432 cc 2018-11-06T18:57:05.139Z,1541530625.139 [Reporter](INFO): platform_buoyancy_position 737.431226 cc 2018-11-06T18:57:05.543Z,1541530625.543 [Reporter](INFO): platform_buoyancy_position 739.445735 cc 2018-11-06T18:57:06.079Z,1541530626.079 [Reporter](INFO): platform_buoyancy_position 739.714364 cc 2018-11-06T18:57:06.344Z,1541530626.344 [Reporter](INFO): platform_buoyancy_position 741.594529 cc 2018-11-06T18:57:06.747Z,1541530626.747 [Reporter](INFO): platform_buoyancy_position 741.728814 cc 2018-11-06T18:57:07.151Z,1541530627.151 [Reporter](INFO): platform_buoyancy_position 743.877608 cc 2018-11-06T18:57:07.535Z,1541530627.535 [Reporter](INFO): platform_buoyancy_position 745.892175 cc 2018-11-06T18:57:08.029Z,1541530628.029 [Reporter](INFO): platform_buoyancy_position 746.160687 cc 2018-11-06T18:57:08.356Z,1541530628.356 [Reporter](INFO): platform_buoyancy_position 748.040969 cc 2018-11-06T18:57:08.766Z,1541530628.766 [Reporter](INFO): platform_buoyancy_position 748.175255 cc 2018-11-06T18:57:09.163Z,1541530629.163 [Reporter](INFO): platform_buoyancy_position 750.324049 cc 2018-11-06T18:57:09.531Z,1541530629.531 [Reporter](INFO): platform_buoyancy_position 752.338558 cc 2018-11-06T18:57:10.079Z,1541530630.079 [Reporter](INFO): platform_buoyancy_position 752.472843 cc 2018-11-06T18:57:10.377Z,1541530630.377 [Reporter](INFO): platform_buoyancy_position 754.487352 cc 2018-11-06T18:57:10.744Z,1541530630.744 [Reporter](INFO): platform_buoyancy_position 755.561690 cc 2018-11-06T18:57:11.147Z,1541530631.147 [Reporter](INFO): platform_buoyancy_position 756.770431 cc 2018-11-06T18:57:11.551Z,1541530631.551 [Reporter](INFO): platform_buoyancy_position 758.784940 cc 2018-11-06T18:57:12.055Z,1541530632.055 [Reporter](INFO): platform_buoyancy_position 760.799448 cc 2018-11-06T18:57:12.358Z,1541530632.358 [Reporter](INFO): platform_buoyancy_position 761.068077 cc 2018-11-06T18:57:12.755Z,1541530632.755 [Reporter](INFO): platform_buoyancy_position 763.082528 cc 2018-11-06T18:57:13.147Z,1541530633.147 [Reporter](INFO): platform_buoyancy_position 763.082528 cc 2018-11-06T18:57:13.560Z,1541530633.560 [Reporter](INFO): platform_buoyancy_position 765.231322 cc 2018-11-06T18:57:14.075Z,1541530634.075 [Reporter](INFO): platform_buoyancy_position 767.380116 cc 2018-11-06T18:57:14.343Z,1541530634.343 [Reporter](INFO): platform_buoyancy_position 767.514459 cc 2018-11-06T18:57:14.747Z,1541530634.747 [Reporter](INFO): platform_buoyancy_position 769.528910 cc 2018-11-06T18:57:15.143Z,1541530635.143 [Reporter](INFO): platform_buoyancy_position 769.663195 cc 2018-11-06T18:57:15.559Z,1541530635.559 [Reporter](INFO): platform_buoyancy_position 771.543477 cc 2018-11-06T18:57:16.012Z,1541530636.012 [Reporter](INFO): platform_buoyancy_position 773.826556 cc 2018-11-06T18:57:16.352Z,1541530636.352 [Reporter](INFO): platform_buoyancy_position 773.826556 cc 2018-11-06T18:57:16.765Z,1541530636.765 [Reporter](INFO): platform_buoyancy_position 775.975350 cc 2018-11-06T18:57:17.158Z,1541530637.158 [Reporter](INFO): platform_buoyancy_position 775.975350 cc 2018-11-06T18:57:17.571Z,1541530637.571 [Reporter](INFO): platform_buoyancy_position 778.124144 cc 2018-11-06T18:57:18.039Z,1541530638.039 [Reporter](INFO): platform_buoyancy_position 780.272938 cc 2018-11-06T18:57:18.347Z,1541530638.347 [Reporter](INFO): platform_buoyancy_position 780.272938 cc 2018-11-06T18:57:18.759Z,1541530638.759 [Reporter](INFO): platform_buoyancy_position 782.421732 cc 2018-11-06T18:57:19.147Z,1541530639.147 [Reporter](INFO): platform_buoyancy_position 782.690360 cc 2018-11-06T18:57:19.555Z,1541530639.555 [Reporter](INFO): platform_buoyancy_position 784.704811 cc 2018-11-06T18:57:20.027Z,1541530640.027 [Reporter](INFO): platform_buoyancy_position 786.719378 cc 2018-11-06T18:57:20.358Z,1541530640.358 [Reporter](INFO): platform_buoyancy_position 786.853663 cc 2018-11-06T18:57:20.611Z,1541530640.611 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:57:20.611Z,1541530640.611 [NAL9602] Communications Fault, FailCount= 2 2018-11-06T18:57:20.611Z,1541530640.611 [NAL9602](ERROR): Communications Fault 2018-11-06T18:57:20.766Z,1541530640.766 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:57:20.767Z,1541530640.767 [Reporter](INFO): platform_buoyancy_position 788.868172 cc 2018-11-06T18:57:21.010Z,1541530641.010 [NAL9602](INFO): Powering down 2018-11-06T18:57:21.160Z,1541530641.160 [Reporter](INFO): platform_buoyancy_position 789.405312 cc 2018-11-06T18:57:21.531Z,1541530641.531 [Reporter](INFO): platform_buoyancy_position 791.151251 cc 2018-11-06T18:57:22.062Z,1541530642.062 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:57:22.062Z,1541530642.062 [NAL9602] No Fault, FailCount= 2 2018-11-06T18:57:22.063Z,1541530642.063 [Reporter](INFO): platform_buoyancy_position 793.300045 cc 2018-11-06T18:57:22.339Z,1541530642.339 [Reporter](INFO): platform_buoyancy_position 793.165702 cc 2018-11-06T18:57:22.755Z,1541530642.755 [Reporter](INFO): platform_buoyancy_position 795.314496 cc 2018-11-06T18:57:23.167Z,1541530643.167 [Reporter](INFO): platform_buoyancy_position 797.463290 cc 2018-11-06T18:57:23.557Z,1541530643.557 [Reporter](INFO): platform_buoyancy_position 797.597633 cc 2018-11-06T18:57:24.071Z,1541530644.071 [Reporter](INFO): platform_buoyancy_position 799.746485 cc 2018-11-06T18:57:24.362Z,1541530644.362 [Reporter](INFO): platform_buoyancy_position 799.880712 cc 2018-11-06T18:57:24.747Z,1541530644.747 [Reporter](INFO): platform_buoyancy_position 801.760936 cc 2018-11-06T18:57:25.144Z,1541530645.144 [Reporter](INFO): platform_buoyancy_position 802.298135 cc 2018-11-06T18:57:25.543Z,1541530645.543 [Reporter](INFO): platform_buoyancy_position 804.044073 cc 2018-11-06T18:57:26.116Z,1541530646.116 [Reporter](INFO): platform_buoyancy_position 806.058524 cc 2018-11-06T18:57:26.339Z,1541530646.339 [Reporter](INFO): platform_buoyancy_position 806.192809 cc 2018-11-06T18:57:26.759Z,1541530646.759 [Reporter](INFO): platform_buoyancy_position 808.207318 cc 2018-11-06T18:57:27.143Z,1541530647.143 [Reporter](INFO): platform_buoyancy_position 810.490397 cc 2018-11-06T18:57:27.547Z,1541530647.547 [Reporter](INFO): platform_buoyancy_position 810.490397 cc 2018-11-06T18:57:28.044Z,1541530648.044 [Reporter](INFO): platform_buoyancy_position 812.639249 cc 2018-11-06T18:57:28.363Z,1541530648.363 [Reporter](INFO): platform_buoyancy_position 812.773535 cc 2018-11-06T18:57:28.755Z,1541530648.755 [Reporter](INFO): platform_buoyancy_position 814.653758 cc 2018-11-06T18:57:29.124Z,1541530649.124 [Reporter](INFO): platform_buoyancy_position 816.802552 cc 2018-11-06T18:57:29.539Z,1541530649.539 [Reporter](INFO): platform_buoyancy_position 816.936838 cc 2018-11-06T18:57:30.060Z,1541530650.060 [Reporter](INFO): platform_buoyancy_position 819.085632 cc 2018-11-06T18:57:30.351Z,1541530650.351 [Reporter](INFO): platform_buoyancy_position 819.219975 cc 2018-11-06T18:57:30.747Z,1541530650.747 [Reporter](INFO): platform_buoyancy_position 821.100140 cc 2018-11-06T18:57:31.159Z,1541530651.159 [Reporter](INFO): platform_buoyancy_position 823.248934 cc 2018-11-06T18:57:31.558Z,1541530651.558 [Reporter](INFO): platform_buoyancy_position 823.383220 cc 2018-11-06T18:57:32.036Z,1541530652.036 [Reporter](INFO): platform_buoyancy_position 825.397787 cc 2018-11-06T18:57:32.351Z,1541530652.351 [Reporter](INFO): platform_buoyancy_position 826.069212 cc 2018-11-06T18:57:32.743Z,1541530652.743 [Reporter](INFO): platform_buoyancy_position 827.680866 cc 2018-11-06T18:57:33.127Z,1541530653.127 [Reporter](INFO): platform_buoyancy_position 829.695316 cc 2018-11-06T18:57:33.531Z,1541530653.531 [Reporter](INFO): platform_buoyancy_position 829.695316 cc 2018-11-06T18:57:34.040Z,1541530654.040 [Reporter](INFO): platform_buoyancy_position 831.844111 cc 2018-11-06T18:57:34.345Z,1541530654.345 [Reporter](INFO): platform_buoyancy_position 832.515652 cc 2018-11-06T18:57:34.758Z,1541530654.758 [Reporter](INFO): platform_buoyancy_position 834.127248 cc 2018-11-06T18:57:35.154Z,1541530655.154 [Reporter](INFO): platform_buoyancy_position 836.276042 cc 2018-11-06T18:57:35.564Z,1541530655.564 [Reporter](INFO): platform_buoyancy_position 836.410327 cc 2018-11-06T18:57:36.025Z,1541530656.025 [Reporter](INFO): platform_buoyancy_position 838.424894 cc 2018-11-06T18:57:36.327Z,1541530656.327 [Reporter](INFO): platform_buoyancy_position 840.439345 cc 2018-11-06T18:57:36.790Z,1541530656.790 [Reporter](INFO): platform_buoyancy_position 840.439345 cc 2018-11-06T18:57:37.147Z,1541530657.147 [Reporter](INFO): platform_buoyancy_position 842.588139 cc 2018-11-06T18:57:37.566Z,1541530657.566 [Reporter](INFO): platform_buoyancy_position 842.856767 cc 2018-11-06T18:57:38.095Z,1541530658.095 [Reporter](INFO): platform_buoyancy_position 844.871218 cc 2018-11-06T18:57:38.331Z,1541530658.331 [Reporter](INFO): platform_buoyancy_position 846.885727 cc 2018-11-06T18:57:38.735Z,1541530658.735 [Reporter](INFO): platform_buoyancy_position 846.885727 cc 2018-11-06T18:57:39.139Z,1541530659.139 [Reporter](INFO): platform_buoyancy_position 849.034521 cc 2018-11-06T18:57:39.544Z,1541530659.544 [Reporter](INFO): platform_buoyancy_position 849.437434 cc 2018-11-06T18:57:40.051Z,1541530660.051 [Reporter](INFO): platform_buoyancy_position 851.183373 cc 2018-11-06T18:57:40.339Z,1541530660.339 [Reporter](INFO): platform_buoyancy_position 853.332167 cc 2018-11-06T18:57:40.752Z,1541530660.752 [Reporter](INFO): platform_buoyancy_position 853.466452 cc 2018-11-06T18:57:41.163Z,1541530661.163 [Reporter](INFO): platform_buoyancy_position 855.615246 cc 2018-11-06T18:57:41.515Z,1541530661.515 [Reporter](INFO): platform_buoyancy_position 856.689643 cc 2018-11-06T18:57:42.059Z,1541530662.059 [Reporter](INFO): platform_buoyancy_position 857.898325 cc 2018-11-06T18:57:42.360Z,1541530662.360 [Reporter](INFO): platform_buoyancy_position 859.778549 cc 2018-11-06T18:57:42.599Z,1541530662.599 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-11-06T18:57:42.763Z,1541530662.763 [Reporter](INFO): platform_buoyancy_position 859.912834 cc 2018-11-06T18:57:43.155Z,1541530663.155 [Reporter](INFO): platform_buoyancy_position 861.927343 cc 2018-11-06T18:57:43.543Z,1541530663.543 [Reporter](INFO): platform_buoyancy_position 862.464542 cc 2018-11-06T18:57:44.040Z,1541530664.040 [Reporter](INFO): platform_buoyancy_position 864.076195 cc 2018-11-06T18:57:44.339Z,1541530664.339 [Reporter](INFO): platform_buoyancy_position 866.224931 cc 2018-11-06T18:57:44.689Z,1541530664.689 [Reporter](INFO): platform_buoyancy_position 866.224931 cc 2018-11-06T18:57:45.088Z,1541530665.088 [Reporter](INFO): platform_buoyancy_position 868.373725 cc 2018-11-06T18:57:45.488Z,1541530665.488 [Reporter](INFO): platform_buoyancy_position 868.642353 cc 2018-11-06T18:57:45.938Z,1541530665.938 [Reporter](INFO): platform_buoyancy_position 870.656862 cc 2018-11-06T18:57:46.296Z,1541530666.296 [Reporter](INFO): platform_buoyancy_position 872.671313 cc 2018-11-06T18:57:46.708Z,1541530666.708 [Reporter](INFO): platform_buoyancy_position 873.074227 cc 2018-11-06T18:57:47.115Z,1541530667.115 [Reporter](INFO): platform_buoyancy_position 874.954450 cc 2018-11-06T18:57:47.515Z,1541530667.515 [Reporter](INFO): platform_buoyancy_position 876.968959 cc 2018-11-06T18:57:48.012Z,1541530668.012 [Reporter](INFO): platform_buoyancy_position 877.371815 cc 2018-11-06T18:57:48.296Z,1541530668.296 [Reporter](INFO): platform_buoyancy_position 879.252097 cc 2018-11-06T18:57:48.692Z,1541530668.692 [Reporter](INFO): platform_buoyancy_position 880.326435 cc 2018-11-06T18:57:49.092Z,1541530669.092 [Reporter](INFO): platform_buoyancy_position 881.266547 cc 2018-11-06T18:57:49.500Z,1541530669.500 [Reporter](INFO): platform_buoyancy_position 883.415341 cc 2018-11-06T18:57:50.024Z,1541530670.024 [Reporter](INFO): platform_buoyancy_position 885.429850 cc 2018-11-06T18:57:50.304Z,1541530670.304 [Reporter](INFO): platform_buoyancy_position 885.564135 cc 2018-11-06T18:57:50.720Z,1541530670.720 [Reporter](INFO): platform_buoyancy_position 887.712929 cc 2018-11-06T18:57:51.088Z,1541530671.088 [Reporter](INFO): platform_buoyancy_position 887.847214 cc 2018-11-06T18:57:51.403Z,1541530671.403 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:57:51.504Z,1541530671.504 [Reporter](INFO): platform_buoyancy_position 889.861782 cc 2018-11-06T18:57:51.968Z,1541530671.968 [Reporter](INFO): platform_buoyancy_position 890.398922 cc 2018-11-06T18:57:52.296Z,1541530672.296 [Reporter](INFO): platform_buoyancy_position 892.010576 cc 2018-11-06T18:57:52.693Z,1541530672.693 [Reporter](INFO): platform_buoyancy_position 894.159370 cc 2018-11-06T18:57:53.111Z,1541530673.111 [Reporter](INFO): platform_buoyancy_position 894.159370 cc 2018-11-06T18:57:53.504Z,1541530673.504 [Reporter](INFO): platform_buoyancy_position 896.308164 cc 2018-11-06T18:57:54.052Z,1541530674.052 [Reporter](INFO): platform_buoyancy_position 898.456958 cc 2018-11-06T18:57:54.288Z,1541530674.288 [Reporter](INFO): platform_buoyancy_position 898.591243 cc 2018-11-06T18:57:54.692Z,1541530674.692 [Reporter](INFO): platform_buoyancy_position 900.605752 cc 2018-11-06T18:57:55.084Z,1541530675.084 [Reporter](INFO): platform_buoyancy_position 900.740037 cc 2018-11-06T18:57:55.499Z,1541530675.499 [Reporter](INFO): platform_buoyancy_position 902.888831 cc 2018-11-06T18:57:56.036Z,1541530676.036 [Reporter](INFO): platform_buoyancy_position 904.903340 cc 2018-11-06T18:57:56.300Z,1541530676.300 [Reporter](INFO): platform_buoyancy_position 905.037683 cc 2018-11-06T18:57:56.708Z,1541530676.708 [Reporter](INFO): platform_buoyancy_position 907.052134 cc 2018-11-06T18:57:57.096Z,1541530677.096 [Reporter](INFO): platform_buoyancy_position 907.186477 cc 2018-11-06T18:57:57.484Z,1541530677.484 [Reporter](INFO): platform_buoyancy_position 909.200928 cc 2018-11-06T18:57:58.023Z,1541530678.023 [Reporter](INFO): platform_buoyancy_position 911.349722 cc 2018-11-06T18:57:58.304Z,1541530678.304 [Reporter](INFO): platform_buoyancy_position 911.349722 cc 2018-11-06T18:57:58.704Z,1541530678.704 [Reporter](INFO): platform_buoyancy_position 913.498516 cc 2018-11-06T18:57:59.096Z,1541530679.096 [Reporter](INFO): platform_buoyancy_position 913.632859 cc 2018-11-06T18:57:59.508Z,1541530679.508 [Reporter](INFO): platform_buoyancy_position 915.781653 cc 2018-11-06T18:57:59.972Z,1541530679.972 [Reporter](INFO): platform_buoyancy_position 917.796162 cc 2018-11-06T18:58:00.288Z,1541530680.288 [Reporter](INFO): platform_buoyancy_position 917.930447 cc 2018-11-06T18:58:00.704Z,1541530680.704 [Reporter](INFO): platform_buoyancy_position 919.944956 cc 2018-11-06T18:58:01.100Z,1541530681.100 [Reporter](INFO): platform_buoyancy_position 920.079241 cc 2018-11-06T18:58:01.500Z,1541530681.500 [Reporter](INFO): platform_buoyancy_position 922.093750 cc 2018-11-06T18:58:02.036Z,1541530682.036 [Reporter](INFO): platform_buoyancy_position 924.242544 cc 2018-11-06T18:58:02.328Z,1541530682.328 [Reporter](INFO): platform_buoyancy_position 924.511172 cc 2018-11-06T18:58:02.722Z,1541530682.722 [Reporter](INFO): platform_buoyancy_position 926.391338 cc 2018-11-06T18:58:03.104Z,1541530683.104 [Reporter](INFO): platform_buoyancy_position 928.540190 cc 2018-11-06T18:58:03.492Z,1541530683.492 [Reporter](INFO): platform_buoyancy_position 928.674417 cc 2018-11-06T18:58:04.018Z,1541530684.018 [Reporter](INFO): platform_buoyancy_position 930.688984 cc 2018-11-06T18:58:04.292Z,1541530684.292 [Reporter](INFO): platform_buoyancy_position 930.957496 cc 2018-11-06T18:58:04.708Z,1541530684.708 [Reporter](INFO): platform_buoyancy_position 932.837778 cc 2018-11-06T18:58:05.104Z,1541530685.104 [Reporter](INFO): platform_buoyancy_position 934.852287 cc 2018-11-06T18:58:05.504Z,1541530685.504 [Reporter](INFO): platform_buoyancy_position 935.120857 cc 2018-11-06T18:58:05.982Z,1541530685.982 [Reporter](INFO): platform_buoyancy_position 937.135366 cc 2018-11-06T18:58:06.292Z,1541530686.292 [Reporter](INFO): platform_buoyancy_position 937.269651 cc 2018-11-06T18:58:06.700Z,1541530686.700 [Reporter](INFO): platform_buoyancy_position 939.418445 cc 2018-11-06T18:58:07.104Z,1541530687.104 [Reporter](INFO): platform_buoyancy_position 941.433012 cc 2018-11-06T18:58:07.504Z,1541530687.504 [Reporter](INFO): platform_buoyancy_position 941.433012 cc 2018-11-06T18:58:07.978Z,1541530687.978 [Reporter](INFO): platform_buoyancy_position 943.716092 cc 2018-11-06T18:58:08.320Z,1541530688.320 [Reporter](INFO): platform_buoyancy_position 943.716092 cc 2018-11-06T18:58:08.678Z,1541530688.678 [Reporter](INFO): platform_buoyancy_position 945.730601 cc 2018-11-06T18:58:12.603Z,1541530692.603 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:58:12.603Z,1541530692.603 [NAL9602] Communications Fault, FailCount= 3 2018-11-06T18:58:12.603Z,1541530692.603 [NAL9602](ERROR): Communications Fault 2018-11-06T18:58:12.686Z,1541530692.686 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:58:13.003Z,1541530693.003 [NAL9602](INFO): Powering down 2018-11-06T18:58:14.019Z,1541530694.019 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:58:14.020Z,1541530694.020 [NAL9602] No Fault, FailCount= 3 2018-11-06T18:58:42.998Z,1541530722.998 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-11-06T18:58:43.007Z,1541530723.007 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:59:03.828Z,1541530743.828 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:59:03.828Z,1541530743.828 [NAL9602] Communications Fault, FailCount= 4 2018-11-06T18:59:03.828Z,1541530743.828 [NAL9602](ERROR): Communications Fault 2018-11-06T18:59:04.030Z,1541530744.030 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:59:04.203Z,1541530744.203 [NAL9602](INFO): Powering down 2018-11-06T18:59:05.099Z,1541530745.099 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T18:59:05.099Z,1541530745.099 [NAL9602] No Fault, FailCount= 4 2018-11-06T18:59:08.244Z,1541530748.244 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-11-06T18:59:08.244Z,1541530748.244 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-11-06T18:59:08.244Z,1541530748.244 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-06T18:59:08.257Z,1541530748.257 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-06T18:59:08.673Z,1541530748.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-06T18:59:08.673Z,1541530748.673 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2018-11-06T18:59:09.043Z,1541530749.043 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-06T18:59:09.043Z,1541530749.043 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-06T18:59:09.043Z,1541530749.043 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-06T18:59:34.211Z,1541530774.211 [NAL9602](INFO): Powering up NAL9602 2018-11-06T18:59:43.803Z,1541530783.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-11-06T18:59:55.403Z,1541530795.403 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T18:59:55.403Z,1541530795.403 [NAL9602] Communications Fault, FailCount= 5 2018-11-06T18:59:55.403Z,1541530795.403 [NAL9602](ERROR): Communications Fault 2018-11-06T18:59:55.492Z,1541530795.492 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T18:59:55.492Z,1541530795.492 [CBIT](CRITICAL): Communications Fault in component: NAL9602 2018-11-06T18:59:55.811Z,1541530795.811 [NAL9602](INFO): Powering down 2018-11-06T18:59:55.960Z,1541530795.960 [CBIT](INFO): Critical error at 20181106T185955 2018-11-06T19:00:27.770Z,1541530827.770 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003125 2018-11-06T19:00:51.882Z,1541530851.882 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T18:55:51.6Z 2018-11-06T19:00:51.882Z,1541530851.882 [Default:CheckIn:Read_GPS] Stopped 2018-11-06T19:00:51.884Z,1541530851.884 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-11-06T19:00:52.231Z,1541530852.231 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-11-06T19:00:56.998Z,1541530856.998 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20181106T172703/Courier0022.lzma 2018-11-06T19:00:57.799Z,1541530857.799 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Courier0022.lzma.bak 2018-11-06T19:00:57.799Z,1541530857.799 [DataOverHttps](INFO): SBD MOMSN=8796721 2018-11-06T19:01:07.606Z,1541530867.606 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20181106T184355/Courier0004.lzma 2018-11-06T19:01:08.404Z,1541530868.404 [DataOverHttps](INFO): Moved sent file to Logs/20181106T184355/Courier0004.lzma.bak 2018-11-06T19:01:08.404Z,1541530868.404 [DataOverHttps](INFO): SBD MOMSN=8796725 2018-11-06T19:01:19.702Z,1541530879.702 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181106T184355/Courier0007.lzma 2018-11-06T19:01:20.472Z,1541530880.472 [DataOverHttps](INFO): Moved sent file to Logs/20181106T184355/Courier0007.lzma.bak 2018-11-06T19:01:20.472Z,1541530880.472 [DataOverHttps](INFO): SBD MOMSN=8796731 2018-11-06T19:01:32.262Z,1541530892.262 [CommandLine](IMPORTANT): got command failComponent 2018-11-06T19:01:32.262Z,1541530892.262 [CommandLine](IMPORTANT): Failed components: 2018-11-06T19:01:32.262Z,1541530892.262 [CommandLine](IMPORTANT): NAL9602: Communications Fault 2018-11-06T19:01:32.516Z,1541530892.516 [DataOverHttps](INFO): Sending 663 bytes from file Logs/20181106T172703/Express0023.lzma 2018-11-06T19:01:33.312Z,1541530893.312 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Express0023.lzma.bak 2018-11-06T19:01:33.312Z,1541530893.312 [DataOverHttps](INFO): SBD MOMSN=8796733 2018-11-06T19:01:43.517Z,1541530903.517 [DataOverHttps](INFO): Sending 957 bytes from file Logs/20181106T184355/Express0001.lzma 2018-11-06T19:01:44.312Z,1541530904.312 [DataOverHttps](INFO): Moved sent file to Logs/20181106T184355/Express0001.lzma.bak 2018-11-06T19:01:44.312Z,1541530904.312 [DataOverHttps](INFO): SBD MOMSN=8796749 2018-11-06T19:01:54.690Z,1541530914.690 [DataOverHttps](INFO): Sending 1061 bytes from file Logs/20181106T184355/Express0005.lzma 2018-11-06T19:01:55.484Z,1541530915.484 [DataOverHttps](INFO): Moved sent file to Logs/20181106T184355/Express0005.lzma.bak 2018-11-06T19:01:55.484Z,1541530915.484 [DataOverHttps](INFO): SBD MOMSN=8796771 2018-11-06T19:01:55.589Z,1541530915.589 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T19:01:55.589Z,1541530915.589 [NAL9602] No Fault, FailCount= 5 2018-11-06T19:01:56.200Z,1541530916.200 [NAL9602](INFO): Powering up NAL9602 2018-11-06T19:02:05.510Z,1541530925.510 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181106T184355/Express0008.lzma 2018-11-06T19:02:06.308Z,1541530926.308 [DataOverHttps](INFO): Moved sent file to Logs/20181106T184355/Express0008.lzma.bak 2018-11-06T19:02:06.308Z,1541530926.308 [DataOverHttps](INFO): SBD MOMSN=8796786 2018-11-06T19:02:06.978Z,1541530926.978 [Default:CheckIn:Read_Iridium] Stopped 2018-11-06T19:02:06.978Z,1541530926.978 [Default:CheckIn:C.Wait] Running Loop=1 2018-11-06T19:02:06.978Z,1541530926.978 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-11-06T19:02:10.660Z,1541530930.660 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-11-06T19:02:10.660Z,1541530930.660 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2018-11-06T19:02:10.660Z,1541530930.660 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-11-06T19:02:10.673Z,1541530930.673 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-11-06T19:02:10.838Z,1541530930.838 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-11-06T19:02:10.838Z,1541530930.838 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2018-11-06T19:02:11.148Z,1541530931.148 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-06T19:02:11.149Z,1541530931.149 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-06T19:02:11.149Z,1541530931.149 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-06T19:02:17.095Z,1541530937.095 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-06T19:02:17.095Z,1541530937.095 [NAL9602] Communications Fault, FailCount= 1 2018-11-06T19:02:17.095Z,1541530937.095 [NAL9602](ERROR): Communications Fault 2018-11-06T19:02:17.195Z,1541530937.195 [CBIT](ERROR): Communications Fault in component: NAL9602 2018-11-06T19:02:17.487Z,1541530937.487 [NAL9602](INFO): Powering down 2018-11-06T19:02:18.352Z,1541530938.352 [CBIT](INFO): Clearing failed state for component NAL9602 2018-11-06T19:02:18.352Z,1541530938.352 [NAL9602] No Fault, FailCount= 1 2018-11-06T19:02:47.891Z,1541530967.891 [NAL9602](INFO): Powering up NAL9602 2018-11-06T19:02:59.725Z,1541530979.725 [CommandLine](IMPORTANT): got command restart system 2018-11-06T19:03:02.633Z,1541530982.633 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-06T19:03:02.633Z,1541530982.633 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:02.805Z,1541530982.805 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-11-06T19:03:02.805Z,1541530982.805 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:02.806Z,1541530982.806 [CommandLine](INFO): Join timeout helper Thread ID is 6770 2018-11-06T19:03:02.806Z,1541530982.806 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-11-06T19:03:02.806Z,1541530982.806 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:02.807Z,1541530982.807 [NavChartDb](INFO): Join timeout helper Thread ID is 6771 2018-11-06T19:03:02.837Z,1541530982.837 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-06T19:03:02.837Z,1541530982.837 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:02.845Z,1541530982.845 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-11-06T19:03:02.845Z,1541530982.845 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:02.845Z,1541530982.845 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6772 2018-11-06T19:03:03.329Z,1541530983.329 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-06T19:03:03.329Z,1541530983.329 [WetLabsBB2FL](INFO): Powering down 2018-11-06T19:03:03.330Z,1541530983.330 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:03.349Z,1541530983.349 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2018-11-06T19:03:03.349Z,1541530983.349 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:03.349Z,1541530983.349 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6773 2018-11-06T19:03:03.409Z,1541530983.409 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-06T19:03:03.409Z,1541530983.409 [CTD_NeilBrown](INFO): Powering down 2018-11-06T19:03:03.410Z,1541530983.410 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:03.417Z,1541530983.417 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2018-11-06T19:03:03.417Z,1541530983.417 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:03.417Z,1541530983.417 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6774 2018-11-06T19:03:04.025Z,1541530984.025 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-06T19:03:04.025Z,1541530984.025 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-11-06T19:03:04.166Z,1541530984.166 [Rowe_600LCM](INFO): Powering down 2018-11-06T19:03:04.167Z,1541530984.167 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.170Z,1541530984.170 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-11-06T19:03:04.170Z,1541530984.170 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.171Z,1541530984.171 [Radio_Surface](INFO): Join timeout helper Thread ID is 6776 2018-11-06T19:03:04.193Z,1541530984.193 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-06T19:03:04.193Z,1541530984.193 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.210Z,1541530984.210 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-11-06T19:03:04.210Z,1541530984.210 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.210Z,1541530984.210 [logger](INFO): Join timeout helper Thread ID is 6777 2018-11-06T19:03:04.225Z,1541530984.225 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-06T19:03:04.225Z,1541530984.225 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.230Z,1541530984.230 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-11-06T19:03:04.230Z,1541530984.230 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.230Z,1541530984.230 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-11-06T19:03:04.230Z,1541530984.230 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.230Z,1541530984.230 [controlThread](INFO): Join timeout helper Thread ID is 6778 2018-11-06T19:03:04.277Z,1541530984.277 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-06T19:03:04.277Z,1541530984.277 [controlThread](DEBUG): Uninitializing ControlThread 2018-11-06T19:03:04.278Z,1541530984.278 [NAL9602](INFO): Powering down 2018-11-06T19:03:04.280Z,1541530984.280 [PNI_TCM](INFO): Powering down 2018-11-06T19:03:04.369Z,1541530984.369 [Aanderaa_O2](INFO): Powering down 2018-11-06T19:03:04.371Z,1541530984.371 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-06T19:03:04.372Z,1541530984.372 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-11-06T19:03:04.372Z,1541530984.372 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-11-06T19:03:04.373Z,1541530984.373 [MissionManager](INFO): Uninitializing Mission Default 2018-11-06T19:03:04.373Z,1541530984.373 [Default] Stopped 2018-11-06T19:03:04.374Z,1541530984.374 [Default](DEBUG): Aggregate::uninitialize Default 2018-11-06T19:03:04.374Z,1541530984.374 [Default:B.GoToSurface] Stopped 2018-11-06T19:03:04.374Z,1541530984.374 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-11-06T19:03:04.374Z,1541530984.374 [Default:CheckIn] Stopped 2018-11-06T19:03:04.374Z,1541530984.374 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-11-06T19:03:04.374Z,1541530984.374 [Default:CheckIn:C.Wait] Stopped 2018-11-06T19:03:04.374Z,1541530984.374 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-11-06T19:03:04.379Z,1541530984.379 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-11-06T19:03:04.379Z,1541530984.379 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-11-06T19:03:04.379Z,1541530984.379 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-11-06T19:03:04.380Z,1541530984.380 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-11-06T19:03:04.380Z,1541530984.380 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-06T19:03:04.380Z,1541530984.380 [BuoyancyServo](INFO): Powering down 2018-11-06T19:03:04.393Z,1541530984.393 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-06T19:03:04.393Z,1541530984.393 [ElevatorServo](INFO): Powering down 2018-11-06T19:03:04.394Z,1541530984.394 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-06T19:03:04.395Z,1541530984.395 [MassServo](INFO): Powering down 2018-11-06T19:03:04.396Z,1541530984.396 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-06T19:03:04.396Z,1541530984.396 [RudderServo](INFO): Powering down 2018-11-06T19:03:04.397Z,1541530984.397 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-11-06T19:03:04.397Z,1541530984.397 [ThrusterServo](INFO): Powering down 2018-11-06T19:03:04.398Z,1541530984.398 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-11-06T19:03:04.398Z,1541530984.398 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-11-06T19:03:04.398Z,1541530984.398 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-11-06T19:03:04.399Z,1541530984.399 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.458Z,1541530984.458 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.466Z,1541530984.466 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.504Z,1541530984.504 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.507Z,1541530984.507 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.534Z,1541530984.534 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-06T19:03:04.588Z,1541530984.588 [logger ThreadHandler](INFO): Thread cancelled.