2018-07-12T23:57:03.313Z,1531439823.313 [Supervisor](DEBUG): Initializing supervisor. 2018-07-12T23:57:03.316Z,1531439823.316 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-07-12T23:57:03.316Z,1531439823.316 [SyncHandler](INFO): Protected caller Thread ID is 800 2018-07-12T23:57:03.317Z,1531439823.317 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-07-12T23:57:03.318Z,1531439823.318 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-07-12T23:57:03.318Z,1531439823.318 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 801 2018-07-12T23:57:03.321Z,1531439823.321 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-07-12T23:57:03.332Z,1531439823.332 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-07-12T23:57:03.333Z,1531439823.333 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-07-12T23:57:03.334Z,1531439823.334 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802 2018-07-12T23:57:03.335Z,1531439823.335 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-07-12T23:57:03.335Z,1531439823.335 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-07-12T23:57:03.336Z,1531439823.336 [logger ThreadHandler](INFO): Protected caller Thread ID is 803 2018-07-12T23:57:03.338Z,1531439823.338 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-07-12T23:57:03.338Z,1531439823.338 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-07-12T23:57:03.342Z,1531439823.342 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-07-12T23:57:03.541Z,1531439823.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-07-12T23:57:03.543Z,1531439823.543 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-07-12T23:57:03.687Z,1531439823.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-07-12T23:57:03.689Z,1531439823.689 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-07-12T23:57:04.041Z,1531439824.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-07-12T23:57:04.041Z,1531439824.041 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-07-12T23:57:04.181Z,1531439824.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-07-12T23:57:04.183Z,1531439824.183 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-07-12T23:57:04.264Z,1531439824.264 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-07-12T23:57:04.469Z,1531439824.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-07-12T23:57:04.470Z,1531439824.470 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-07-12T23:57:04.569Z,1531439824.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-07-12T23:57:04.570Z,1531439824.570 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-07-12T23:57:04.934Z,1531439824.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-07-12T23:57:04.935Z,1531439824.935 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-07-12T23:57:05.377Z,1531439825.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-07-12T23:57:05.378Z,1531439825.378 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-07-12T23:57:05.688Z,1531439825.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-07-12T23:57:05.689Z,1531439825.689 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-07-12T23:57:06.197Z,1531439826.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-07-12T23:57:06.198Z,1531439826.198 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-07-12T23:57:06.408Z,1531439826.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-07-12T23:57:06.409Z,1531439826.409 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-07-12T23:57:06.508Z,1531439826.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-07-12T23:57:06.509Z,1531439826.509 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-07-12T23:57:07.019Z,1531439827.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-07-12T23:57:07.020Z,1531439827.020 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-07-12T23:57:07.351Z,1531439827.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-07-12T23:57:07.353Z,1531439827.353 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-07-12T23:57:07.380Z,1531439827.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-07-12T23:57:07.612Z,1531439827.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-07-12T23:57:07.614Z,1531439827.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-07-12T23:57:07.716Z,1531439827.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-07-12T23:57:07.811Z,1531439827.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-07-12T23:57:07.915Z,1531439827.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-07-12T23:57:08.003Z,1531439828.003 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-07-12T23:57:08.138Z,1531439828.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-07-12T23:57:08.308Z,1531439828.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-07-12T23:57:08.420Z,1531439828.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-07-12T23:57:08.505Z,1531439828.505 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-07-12T23:57:08.590Z,1531439828.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-07-12T23:57:08.784Z,1531439828.784 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-07-12T23:57:08.790Z,1531439828.790 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-07-12T23:57:08.948Z,1531439828.948 [SBIT](DEBUG): Construct Startup Built In Test. 2018-07-12T23:57:08.978Z,1531439828.978 [SBIT] Loaded 2018-07-12T23:57:08.979Z,1531439828.979 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-07-12T23:57:08.979Z,1531439828.979 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-07-12T23:57:09.007Z,1531439829.007 [IBIT] Loaded 2018-07-12T23:57:09.008Z,1531439829.008 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-07-12T23:57:09.011Z,1531439829.011 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-07-12T23:57:09.166Z,1531439829.166 [CBIT] Loaded 2018-07-12T23:57:09.167Z,1531439829.167 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-07-12T23:57:09.167Z,1531439829.167 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-07-12T23:57:09.168Z,1531439829.168 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-07-12T23:57:09.256Z,1531439829.256 [VerticalControl](DEBUG): Construct VerticalControl. 2018-07-12T23:57:09.356Z,1531439829.356 [VerticalControl] Loaded 2018-07-12T23:57:09.357Z,1531439829.357 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-07-12T23:57:09.357Z,1531439829.357 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-07-12T23:57:09.419Z,1531439829.419 [HorizontalControl] Loaded 2018-07-12T23:57:09.419Z,1531439829.419 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-07-12T23:57:09.419Z,1531439829.419 [SpeedControl](DEBUG): Construct SpeedControl. 2018-07-12T23:57:09.425Z,1531439829.425 [SpeedControl] Loaded 2018-07-12T23:57:09.425Z,1531439829.425 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-07-12T23:57:09.426Z,1531439829.426 [LoopControl](DEBUG): Construct LoopControl. 2018-07-12T23:57:09.427Z,1531439829.427 [LoopControl] Loaded 2018-07-12T23:57:09.427Z,1531439829.427 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-07-12T23:57:09.427Z,1531439829.427 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-07-12T23:57:09.428Z,1531439829.428 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-07-12T23:57:09.484Z,1531439829.484 [DepthRateCalculator] Loaded 2018-07-12T23:57:09.484Z,1531439829.484 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-07-12T23:57:09.490Z,1531439829.490 [PitchRateCalculator] Loaded 2018-07-12T23:57:09.490Z,1531439829.490 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-07-12T23:57:09.506Z,1531439829.506 [SpeedCalculator] Loaded 2018-07-12T23:57:09.506Z,1531439829.506 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-07-12T23:57:09.528Z,1531439829.528 [TempGradientCalculator] Loaded 2018-07-12T23:57:09.528Z,1531439829.528 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-07-12T23:57:09.543Z,1531439829.543 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-07-12T23:57:09.544Z,1531439829.544 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-07-12T23:57:09.549Z,1531439829.549 [YawRateCalculator] Loaded 2018-07-12T23:57:09.549Z,1531439829.549 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-07-12T23:57:09.578Z,1531439829.578 [ElevatorOffsetCalculator] Loaded 2018-07-12T23:57:09.579Z,1531439829.579 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-07-12T23:57:09.579Z,1531439829.579 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-07-12T23:57:09.580Z,1531439829.580 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-07-12T23:57:09.704Z,1531439829.704 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-07-12T23:57:09.704Z,1531439829.704 [StratificationFrontDetector](DEBUG): (re)initializing 2018-07-12T23:57:09.704Z,1531439829.704 [StratificationFrontDetector] Loaded 2018-07-12T23:57:09.704Z,1531439829.704 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-07-12T23:57:09.705Z,1531439829.705 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-07-12T23:57:09.707Z,1531439829.707 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-07-12T23:57:09.992Z,1531439829.992 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-07-12T23:57:09.992Z,1531439829.992 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-07-12T23:57:10.107Z,1531439830.107 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-07-12T23:57:10.107Z,1531439830.107 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-07-12T23:57:10.162Z,1531439830.162 [DeadReckonUsingSpeedCalculator] Loaded 2018-07-12T23:57:10.162Z,1531439830.162 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-07-12T23:57:10.179Z,1531439830.179 [NavChart] Loaded 2018-07-12T23:57:10.179Z,1531439830.179 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-07-12T23:57:10.183Z,1531439830.183 [UniversalFixResidualReporter] Loaded 2018-07-12T23:57:10.183Z,1531439830.183 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-07-12T23:57:10.184Z,1531439830.184 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-07-12T23:57:10.184Z,1531439830.184 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-07-12T23:57:10.209Z,1531439830.209 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-07-12T23:57:10.210Z,1531439830.210 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-07-12T23:57:10.412Z,1531439830.412 [Aanderaa_O2] Loaded 2018-07-12T23:57:10.412Z,1531439830.412 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-07-12T23:57:10.492Z,1531439830.492 [CTD_NeilBrown] Loaded 2018-07-12T23:57:10.492Z,1531439830.492 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2018-07-12T23:57:10.493Z,1531439830.493 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406D24E0 2018-07-12T23:57:10.494Z,1531439830.494 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 882 2018-07-12T23:57:10.508Z,1531439830.508 [PAR_Licor] Loaded 2018-07-12T23:57:10.508Z,1531439830.508 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-07-12T23:57:10.558Z,1531439830.558 [WetLabsBB2FL] Loaded 2018-07-12T23:57:10.558Z,1531439830.558 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-07-12T23:57:10.559Z,1531439830.559 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407024E0 2018-07-12T23:57:10.560Z,1531439830.560 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 883 2018-07-12T23:57:10.604Z,1531439830.604 [WetLabsSeaOWL_UV_A] Loaded 2018-07-12T23:57:10.604Z,1531439830.604 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2018-07-12T23:57:10.605Z,1531439830.605 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407324E0 2018-07-12T23:57:10.606Z,1531439830.606 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 884 2018-07-12T23:57:10.606Z,1531439830.606 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-07-12T23:57:10.607Z,1531439830.607 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-07-12T23:57:11.214Z,1531439831.214 [AHRS_M2] Loaded 2018-07-12T23:57:11.214Z,1531439831.214 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-07-12T23:57:11.351Z,1531439831.351 [DataOverHttps] Loaded 2018-07-12T23:57:11.352Z,1531439831.352 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-07-12T23:57:11.365Z,1531439831.365 [Depth_Keller] Loaded 2018-07-12T23:57:11.365Z,1531439831.365 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-07-12T23:57:11.370Z,1531439831.370 [DropWeight] Loaded 2018-07-12T23:57:11.370Z,1531439831.370 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-07-12T23:57:11.462Z,1531439831.462 [NAL9602] Loaded 2018-07-12T23:57:11.462Z,1531439831.462 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-07-12T23:57:11.468Z,1531439831.468 [Onboard] Loaded 2018-07-12T23:57:11.468Z,1531439831.468 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-07-12T23:57:11.475Z,1531439831.475 [Radio_Surface] Loaded 2018-07-12T23:57:11.475Z,1531439831.475 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-07-12T23:57:11.476Z,1531439831.476 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409884E0 2018-07-12T23:57:11.477Z,1531439831.477 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 885 2018-07-12T23:57:11.505Z,1531439831.505 [RDI_Pathfinder] Loaded 2018-07-12T23:57:11.505Z,1531439831.505 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-07-12T23:57:11.512Z,1531439831.512 [SCPI] Loaded 2018-07-12T23:57:11.512Z,1531439831.512 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-07-12T23:57:13.517Z,1531439833.517 [BPC1] Loaded 2018-07-12T23:57:13.518Z,1531439833.518 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-07-12T23:57:13.518Z,1531439833.518 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-07-12T23:57:13.519Z,1531439833.519 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-07-12T23:57:13.640Z,1531439833.640 [BuoyancyServo] Loaded 2018-07-12T23:57:13.640Z,1531439833.640 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-07-12T23:57:13.652Z,1531439833.652 [ElevatorServo] Loaded 2018-07-12T23:57:13.652Z,1531439833.652 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-07-12T23:57:13.663Z,1531439833.663 [MassServo] Loaded 2018-07-12T23:57:13.664Z,1531439833.664 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-07-12T23:57:13.676Z,1531439833.676 [RudderServo] Loaded 2018-07-12T23:57:13.676Z,1531439833.676 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-07-12T23:57:13.687Z,1531439833.687 [ThrusterServo] Loaded 2018-07-12T23:57:13.687Z,1531439833.687 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-07-12T23:57:13.688Z,1531439833.688 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-07-12T23:57:13.688Z,1531439833.688 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-07-12T23:57:13.816Z,1531439833.816 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-07-12T23:57:13.817Z,1531439833.817 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-07-12T23:57:13.854Z,1531439833.854 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-07-12T23:57:13.858Z,1531439833.858 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-07-12T23:57:13.859Z,1531439833.859 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-07-12T23:57:13.866Z,1531439833.866 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-07-12T23:57:13.867Z,1531439833.867 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A314E0 2018-07-12T23:57:13.867Z,1531439833.867 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 886 2018-07-12T23:57:13.872Z,1531439833.872 [Supervisor](INFO): Main Thread ID is 796 2018-07-12T23:57:13.872Z,1531439833.872 [Supervisor](DEBUG): Running supervisor. 2018-07-12T23:57:13.873Z,1531439833.873 [CommandLine ThreadHandler](INFO): Handler Thread ID is 887 2018-07-12T23:57:13.875Z,1531439833.875 [controlThread ThreadHandler](INFO): Handler Thread ID is 888 2018-07-12T23:57:13.876Z,1531439833.876 [controlThread](DEBUG): Initializing ControlThread 2018-07-12T23:57:13.876Z,1531439833.876 [SBIT](INFO): Initialize SBIT Component. 2018-07-12T23:57:13.877Z,1531439833.877 [SBIT](IMPORTANT): git: 2018-06-03-17-gbdf8474 2018-07-12T23:57:13.877Z,1531439833.877 [SBIT](INFO): git hash: bdf847407d9faffe74fd7e88a9e899f3511e6e11 2018-07-12T23:57:13.878Z,1531439833.878 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-07-12T23:57:13.879Z,1531439833.879 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-07-12T23:57:13.880Z,1531439833.880 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2018-07-12T23:57:13.881Z,1531439833.881 [IBIT](INFO): Initialize IBIT Component. 2018-07-12T23:57:13.882Z,1531439833.882 [CBIT](DEBUG): Initialize CBIT Component. 2018-07-12T23:57:13.882Z,1531439833.882 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-07-12T23:57:13.882Z,1531439833.882 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-07-12T23:57:13.883Z,1531439833.883 [logger ThreadHandler](INFO): Handler Thread ID is 889 2018-07-12T23:57:13.909Z,1531439833.909 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 890 2018-07-12T23:57:13.910Z,1531439833.910 [CTD_NeilBrown](INFO): Powering down 2018-07-12T23:57:13.943Z,1531439833.943 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 891 2018-07-12T23:57:13.965Z,1531439833.965 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 892 2018-07-12T23:57:13.966Z,1531439833.966 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-07-12T23:57:13.989Z,1531439833.989 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-07-12T23:57:13.991Z,1531439833.991 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-07-12T23:57:13.991Z,1531439833.991 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-07-12T23:57:13.992Z,1531439833.992 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-07-12T23:57:13.992Z,1531439833.992 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-07-12T23:57:13.992Z,1531439833.992 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-07-12T23:57:13.993Z,1531439833.993 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-07-12T23:57:13.993Z,1531439833.993 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-07-12T23:57:13.994Z,1531439833.994 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-07-12T23:57:13.995Z,1531439833.995 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-07-12T23:57:13.995Z,1531439833.995 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-07-12T23:57:13.996Z,1531439833.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-07-12T23:57:13.997Z,1531439833.997 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-07-12T23:57:13.997Z,1531439833.997 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-07-12T23:57:13.998Z,1531439833.998 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-07-12T23:57:13.998Z,1531439833.998 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-07-12T23:57:13.999Z,1531439833.999 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-07-12T23:57:13.999Z,1531439833.999 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-07-12T23:57:13.999Z,1531439833.999 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-07-12T23:57:14.014Z,1531439834.014 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-07-12T23:57:14.026Z,1531439834.026 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 893 2018-07-12T23:57:14.033Z,1531439834.033 [Radio_Surface](INFO): Powering up 2018-07-12T23:57:14.037Z,1531439834.037 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 894 2018-07-12T23:57:14.045Z,1531439834.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-07-12T23:57:14.045Z,1531439834.045 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-07-12T23:57:14.045Z,1531439834.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-07-12T23:57:14.046Z,1531439834.046 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-07-12T23:57:14.046Z,1531439834.046 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-07-12T23:57:14.046Z,1531439834.046 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-07-12T23:57:14.046Z,1531439834.046 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-07-12T23:57:14.047Z,1531439834.047 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-07-12T23:57:14.047Z,1531439834.047 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-07-12T23:57:14.047Z,1531439834.047 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-07-12T23:57:14.047Z,1531439834.047 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-07-12T23:57:14.047Z,1531439834.047 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-07-12T23:57:14.048Z,1531439834.048 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-07-12T23:57:14.048Z,1531439834.048 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-07-12T23:57:14.048Z,1531439834.048 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-07-12T23:57:14.048Z,1531439834.048 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-07-12T23:57:14.086Z,1531439834.086 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-07-12T23:57:14.106Z,1531439834.106 [MissionManager](DEBUG): 2018-07-12T23:57:14.107Z,1531439834.107 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-07-12T23:57:14.206Z,1531439834.206 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-07-12T23:57:14.207Z,1531439834.207 [Default:A.Wait](DEBUG): Construct Wait. 2018-07-12T23:57:14.228Z,1531439834.228 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-07-12T23:57:14.248Z,1531439834.248 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-07-12T23:57:14.283Z,1531439834.283 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-07-12T23:57:14.305Z,1531439834.305 [Default:E.Execute](DEBUG): Construct Execute. 2018-07-12T23:57:14.309Z,1531439834.309 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-07-12T23:57:14.319Z,1531439834.319 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-07-12T23:57:14.421Z,1531439834.421 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-07-12T23:57:14.543Z,1531439834.543 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-07-12T23:57:15.065Z,1531439835.065 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:15.089Z,1531439835.089 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:15.212Z,1531439835.212 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:57:15.217Z,1531439835.217 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-12T23:57:15.243Z,1531439835.243 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:57:15.249Z,1531439835.249 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-07-12T23:57:15.263Z,1531439835.263 [MassServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:57:15.269Z,1531439835.269 [MassServo](DEBUG): Initializing MassServo. 2018-07-12T23:57:15.283Z,1531439835.283 [RudderServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:57:15.289Z,1531439835.289 [RudderServo](DEBUG): Initializing RudderServo. 2018-07-12T23:57:15.307Z,1531439835.307 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:57:15.313Z,1531439835.313 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-07-12T23:57:15.370Z,1531439835.370 [CommandLine](FAULT): Scheduling is paused 2018-07-12T23:57:15.370Z,1531439835.370 [CBIT](INFO): Critical error at 20180712T235713 2018-07-12T23:57:15.370Z,1531439835.370 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-07-12T23:57:15.545Z,1531439835.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:15.546Z,1531439835.546 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:15.818Z,1531439835.818 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-12T23:57:15.818Z,1531439835.818 [Aanderaa_O2] Data Fault, FailCount= 1 2018-07-12T23:57:15.818Z,1531439835.818 [Aanderaa_O2](ERROR): Data Fault 2018-07-12T23:57:16.149Z,1531439836.149 [AHRS_M2](ERROR): Stop data-stream UART error: serial timeout 2018-07-12T23:57:16.197Z,1531439836.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:16.198Z,1531439836.198 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:16.489Z,1531439836.489 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-12T23:57:16.617Z,1531439836.617 [Aanderaa_O2](INFO): Powering down 2018-07-12T23:57:16.753Z,1531439836.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:16.755Z,1531439836.755 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:16.949Z,1531439836.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:16.950Z,1531439836.950 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:17.381Z,1531439837.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:17.382Z,1531439837.382 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:17.408Z,1531439837.408 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-12T23:57:17.408Z,1531439837.408 [Aanderaa_O2] No Fault, FailCount= 1 2018-07-12T23:57:17.750Z,1531439837.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:17.751Z,1531439837.751 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:18.182Z,1531439838.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:18.186Z,1531439838.186 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:18.573Z,1531439838.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:18.574Z,1531439838.574 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:18.614Z,1531439838.614 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-12T23:57:18.614Z,1531439838.614 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-12T23:57:18.614Z,1531439838.614 [BuoyancyServo] Hardware Fault, FailCount= 1 2018-07-12T23:57:18.614Z,1531439838.614 [BuoyancyServo](ERROR): Hardware Fault 2018-07-12T23:57:18.714Z,1531439838.714 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-12T23:57:18.977Z,1531439838.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:18.978Z,1531439838.978 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:18.999Z,1531439838.999 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-12T23:57:18.999Z,1531439838.999 [BuoyancyServo](INFO): Powering down 2018-07-12T23:57:19.409Z,1531439839.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:19.411Z,1531439839.411 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:19.787Z,1531439839.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:19.788Z,1531439839.788 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:20.181Z,1531439840.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:20.183Z,1531439840.183 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:20.566Z,1531439840.566 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:20.567Z,1531439840.567 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:20.938Z,1531439840.938 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:20.939Z,1531439840.939 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:21.334Z,1531439841.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:21.335Z,1531439841.335 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:21.390Z,1531439841.390 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-12T23:57:21.390Z,1531439841.390 [BuoyancyServo] No Fault, FailCount= 1 2018-07-12T23:57:21.786Z,1531439841.786 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:21.787Z,1531439841.787 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:21.817Z,1531439841.817 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:57:21.938Z,1531439841.938 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-12T23:57:22.170Z,1531439842.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:22.171Z,1531439842.171 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:22.594Z,1531439842.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:22.595Z,1531439842.595 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:22.996Z,1531439842.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:23.005Z,1531439843.005 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:23.378Z,1531439843.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:23.379Z,1531439843.379 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:23.758Z,1531439843.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:23.760Z,1531439843.760 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:24.201Z,1531439844.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:24.203Z,1531439844.203 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:24.547Z,1531439844.547 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:24.548Z,1531439844.548 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:24.935Z,1531439844.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:24.936Z,1531439844.936 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:24.985Z,1531439844.985 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-12T23:57:24.985Z,1531439844.985 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-12T23:57:24.985Z,1531439844.985 [BuoyancyServo] Hardware Fault, FailCount= 2 2018-07-12T23:57:24.985Z,1531439844.985 [BuoyancyServo](ERROR): Hardware Fault 2018-07-12T23:57:25.018Z,1531439845.018 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-12T23:57:25.334Z,1531439845.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:25.335Z,1531439845.335 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:25.357Z,1531439845.357 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-12T23:57:25.358Z,1531439845.358 [BuoyancyServo](INFO): Powering down 2018-07-12T23:57:25.734Z,1531439845.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:25.735Z,1531439845.735 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:26.138Z,1531439846.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:26.139Z,1531439846.139 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:26.534Z,1531439846.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:26.535Z,1531439846.535 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:26.934Z,1531439846.934 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:26.935Z,1531439846.935 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:27.346Z,1531439847.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:27.347Z,1531439847.347 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:27.418Z,1531439847.418 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-12T23:57:27.418Z,1531439847.418 [BuoyancyServo] No Fault, FailCount= 2 2018-07-12T23:57:27.758Z,1531439847.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:27.759Z,1531439847.759 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-12T23:57:27.785Z,1531439847.785 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:57:27.906Z,1531439847.906 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-12T23:57:28.099Z,1531439848.099 [Aanderaa_O2](FAULT): Timed out starting 2018-07-12T23:57:28.099Z,1531439848.099 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-07-12T23:57:28.099Z,1531439848.099 [Aanderaa_O2](ERROR): Communications Fault 2018-07-12T23:57:28.242Z,1531439848.242 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-07-12T23:57:28.617Z,1531439848.617 [Aanderaa_O2](INFO): Powering down 2018-07-12T23:57:29.495Z,1531439849.495 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-12T23:57:29.495Z,1531439849.495 [Aanderaa_O2] No Fault, FailCount= 2 2018-07-12T23:57:31.299Z,1531439851.299 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-12T23:57:31.299Z,1531439851.299 [Aanderaa_O2] Data Fault, FailCount= 3 2018-07-12T23:57:31.299Z,1531439851.299 [Aanderaa_O2](ERROR): Data Fault 2018-07-12T23:57:31.477Z,1531439851.477 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-12T23:57:31.477Z,1531439851.477 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-12T23:57:31.478Z,1531439851.478 [BuoyancyServo] Hardware Fault, FailCount= 3 2018-07-12T23:57:31.478Z,1531439851.478 [BuoyancyServo](ERROR): Hardware Fault 2018-07-12T23:57:31.510Z,1531439851.510 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-12T23:57:31.510Z,1531439851.510 [CBIT](FAULT): Data Fault in component: Aanderaa_O2 2018-07-12T23:57:31.511Z,1531439851.511 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-12T23:57:31.817Z,1531439851.817 [Aanderaa_O2](INFO): Powering down 2018-07-12T23:57:31.887Z,1531439851.887 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-12T23:57:31.888Z,1531439851.888 [BuoyancyServo](INFO): Powering down 2018-07-12T23:57:33.822Z,1531439853.822 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-12T23:57:33.822Z,1531439853.822 [BuoyancyServo] No Fault, FailCount= 3 2018-07-12T23:57:34.179Z,1531439854.179 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:57:34.298Z,1531439854.298 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-12T23:57:37.402Z,1531439857.402 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-12T23:57:37.403Z,1531439857.403 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-12T23:57:37.403Z,1531439857.403 [BuoyancyServo] Hardware Fault, FailCount= 4 2018-07-12T23:57:37.403Z,1531439857.403 [BuoyancyServo](ERROR): Hardware Fault 2018-07-12T23:57:37.450Z,1531439857.450 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-12T23:57:37.829Z,1531439857.829 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-12T23:57:37.830Z,1531439857.830 [BuoyancyServo](INFO): Powering down 2018-07-12T23:57:39.832Z,1531439859.832 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-12T23:57:39.832Z,1531439859.832 [BuoyancyServo] No Fault, FailCount= 4 2018-07-12T23:57:40.173Z,1531439860.173 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:57:40.295Z,1531439860.295 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-12T23:57:41.727Z,1531439861.727 [NAL9602](INFO): Powering up NAL9602 2018-07-12T23:57:43.458Z,1531439863.458 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-12T23:57:43.458Z,1531439863.458 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-12T23:57:43.458Z,1531439863.458 [BuoyancyServo] Hardware Fault, FailCount= 5 2018-07-12T23:57:43.458Z,1531439863.458 [BuoyancyServo](ERROR): Hardware Fault 2018-07-12T23:57:43.502Z,1531439863.502 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-12T23:57:43.503Z,1531439863.503 [CBIT](CRITICAL): Hardware Fault in component: BuoyancyServo 2018-07-12T23:57:43.786Z,1531439863.786 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-12T23:57:43.787Z,1531439863.787 [BuoyancyServo](INFO): Powering down 2018-07-12T23:57:43.860Z,1531439863.860 [CBIT](INFO): Critical error at 20180712T235743 2018-07-12T23:57:52.911Z,1531439872.911 [NAL9602](INFO): NAL9602 initialized 2018-07-12T23:58:10.984Z,1531439890.984 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s. 2018-07-12T23:58:11.391Z,1531439891.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s. 2018-07-12T23:58:11.760Z,1531439891.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2018-07-12T23:58:12.163Z,1531439892.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2018-07-12T23:58:12.597Z,1531439892.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2018-07-12T23:58:12.972Z,1531439892.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2018-07-12T23:58:13.414Z,1531439893.414 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2018-07-12T23:58:13.828Z,1531439893.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s. 2018-07-12T23:58:14.151Z,1531439894.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2018-07-12T23:58:14.567Z,1531439894.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2018-07-12T23:58:15.012Z,1531439895.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2018-07-12T23:58:15.424Z,1531439895.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s. 2018-07-12T23:58:15.771Z,1531439895.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2018-07-12T23:58:16.172Z,1531439896.172 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2018-07-12T23:58:16.582Z,1531439896.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2018-07-12T23:58:16.973Z,1531439896.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2018-07-12T23:58:17.432Z,1531439897.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s. 2018-07-12T23:58:17.477Z,1531439897.477 [SBIT](IMPORTANT): Beginning Startup BIT 2018-07-12T23:58:17.482Z,1531439897.482 [CBIT](IMPORTANT): Beginning ground fault scan 2018-07-12T23:58:17.780Z,1531439897.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2018-07-12T23:58:18.183Z,1531439898.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2018-07-12T23:58:18.599Z,1531439898.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2018-07-12T23:58:18.952Z,1531439898.952 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s. 2018-07-12T23:58:19.394Z,1531439899.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2018-07-12T23:58:19.953Z,1531439899.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.4 s old, using for 20.0 s. 2018-07-12T23:58:20.363Z,1531439900.363 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.8 s old, using for 20.0 s. 2018-07-12T23:58:20.754Z,1531439900.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s. 2018-07-12T23:58:21.182Z,1531439901.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s. 2018-07-12T23:58:21.553Z,1531439901.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s. 2018-07-12T23:58:21.952Z,1531439901.952 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s. 2018-07-12T23:58:22.399Z,1531439902.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s. 2018-07-12T23:58:22.744Z,1531439902.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.2 s old, using for 20.0 s. 2018-07-12T23:58:23.151Z,1531439903.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.6 s old, using for 20.0 s. 2018-07-12T23:58:23.560Z,1531439903.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s. 2018-07-12T23:58:23.947Z,1531439903.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s. 2018-07-12T23:58:24.373Z,1531439904.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s. 2018-07-12T23:58:24.757Z,1531439904.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.2 s old, using for 20.0 s. 2018-07-12T23:58:25.133Z,1531439905.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s. 2018-07-12T23:58:25.554Z,1531439905.554 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s. 2018-07-12T23:58:25.914Z,1531439905.914 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s. 2018-07-12T23:58:26.385Z,1531439906.385 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.8 s old, using for 20.0 s. 2018-07-12T23:58:26.720Z,1531439906.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s. 2018-07-12T23:58:27.159Z,1531439907.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.6 s old, using for 20.0 s. 2018-07-12T23:58:27.567Z,1531439907.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.0 s old, using for 20.0 s. 2018-07-12T23:58:27.959Z,1531439907.959 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.4 s old, using for 20.0 s. 2018-07-12T23:58:28.374Z,1531439908.374 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.8 s old, using for 20.0 s. 2018-07-12T23:58:28.446Z,1531439908.446 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.013806 CHAN A1 (24V): -0.018440 CHAN A2 (12V): -0.007488 CHAN A3 (5V): -0.003638 CHAN B0 (3.3V): -0.000575 CHAN B1 (3.15aV): -0.000304 CHAN B2 (3.15bV): -0.000455 CHAN B3 (GND): 0.001048 OPEN: 0.009547 Full Scale Calc: 4.765 mA, -1.589 mA 2018-07-12T23:58:28.720Z,1531439908.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s. 2018-07-12T23:58:29.120Z,1531439909.120 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s. 2018-07-12T23:58:29.543Z,1531439909.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.0 s old, using for 20.0 s. 2018-07-12T23:58:29.929Z,1531439909.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.4 s old, using for 20.0 s. 2018-07-12T23:58:30.371Z,1531439910.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s. 2018-07-12T23:59:11.504Z,1531439951.504 [SBIT](IMPORTANT): SBIT PASSED 2018-07-12T23:59:11.557Z,1531439951.557 [CommandLine](IMPORTANT): got command configSet list 2018-07-12T23:59:11.557Z,1531439951.557 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-07-12T23:59:11.561Z,1531439951.561 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2018-07-12T23:59:11.561Z,1531439951.561 [CommandLine](IMPORTANT): BuoyancyServo.limitHi=9575 count; 2018-07-12T23:59:11.561Z,1531439951.561 [CommandLine](IMPORTANT): BuoyancyServo.limitLo=1200 count; 2018-07-12T23:59:11.561Z,1531439951.561 [CommandLine](IMPORTANT): BuoyancyServo.offsetVolume=200 cubic_centimeter; 2018-07-12T23:59:11.562Z,1531439951.562 [CommandLine](IMPORTANT): VerticalControl.buoyancyDefault=1070 cubic_centimeter; 2018-07-12T23:59:11.562Z,1531439951.562 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitHiCC=1075 cubic_centimeter; 2018-07-12T23:59:11.562Z,1531439951.562 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC=100 cubic_centimeter; 2018-07-12T23:59:11.562Z,1531439951.562 [CommandLine](IMPORTANT): VerticalControl.massDefault=1 millimeter; 2018-07-12T23:59:11.867Z,1531439951.867 [MissionManager](IMPORTANT): Started mission Startup 2018-07-12T23:59:11.868Z,1531439951.868 [Startup] Running Loop=1 2018-07-12T23:59:11.868Z,1531439951.868 [Startup](DEBUG): Aggregate::initialize Startup 2018-07-12T23:59:11.868Z,1531439951.868 [Startup:A.GoToSurface] Running Loop=1 2018-07-12T23:59:11.868Z,1531439951.868 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-07-12T23:59:11.869Z,1531439951.869 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-07-12T23:59:11.869Z,1531439951.869 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-07-12T23:59:11.870Z,1531439951.870 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-07-12T23:59:11.870Z,1531439951.870 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-07-12T23:59:11.870Z,1531439951.870 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-07-12T23:59:11.876Z,1531439951.876 [Startup:StartupSatComms] Running Loop=1 2018-07-12T23:59:11.876Z,1531439951.876 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-07-12T23:59:11.877Z,1531439951.877 [Startup:StartupSatComms:A] Running Loop=1 2018-07-12T23:59:12.261Z,1531439952.261 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-07-12T23:59:43.507Z,1531439983.507 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-12T23:59:43.508Z,1531439983.508 [BuoyancyServo] No Fault, FailCount= 5 2018-07-12T23:59:43.897Z,1531439983.897 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:59:44.018Z,1531439984.018 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-12T23:59:47.097Z,1531439987.097 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-12T23:59:47.098Z,1531439987.098 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-12T23:59:47.098Z,1531439987.098 [BuoyancyServo] Hardware Fault, FailCount= 1 2018-07-12T23:59:47.098Z,1531439987.098 [BuoyancyServo](ERROR): Hardware Fault 2018-07-12T23:59:47.104Z,1531439987.104 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-12T23:59:47.481Z,1531439987.481 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-12T23:59:47.482Z,1531439987.482 [BuoyancyServo](INFO): Powering down 2018-07-12T23:59:49.494Z,1531439989.494 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-12T23:59:49.494Z,1531439989.494 [BuoyancyServo] No Fault, FailCount= 1 2018-07-12T23:59:49.868Z,1531439989.868 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:59:49.986Z,1531439989.986 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-12T23:59:53.085Z,1531439993.085 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-12T23:59:53.085Z,1531439993.085 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-12T23:59:53.085Z,1531439993.085 [BuoyancyServo] Hardware Fault, FailCount= 2 2018-07-12T23:59:53.086Z,1531439993.086 [BuoyancyServo](ERROR): Hardware Fault 2018-07-12T23:59:53.092Z,1531439993.092 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-12T23:59:53.486Z,1531439993.486 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-12T23:59:53.487Z,1531439993.487 [BuoyancyServo](INFO): Powering down 2018-07-12T23:59:55.490Z,1531439995.490 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-12T23:59:55.490Z,1531439995.490 [BuoyancyServo] No Fault, FailCount= 2 2018-07-12T23:59:55.897Z,1531439995.897 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-12T23:59:56.018Z,1531439996.018 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-12T23:59:59.089Z,1531439999.089 [BuoyancyServo](FAULT): Reading outside of valid range:9521.000000 2018-07-12T23:59:59.090Z,1531439999.090 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-12T23:59:59.090Z,1531439999.090 [BuoyancyServo] Hardware Fault, FailCount= 3 2018-07-12T23:59:59.090Z,1531439999.090 [BuoyancyServo](ERROR): Hardware Fault 2018-07-12T23:59:59.096Z,1531439999.096 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-12T23:59:59.501Z,1531439999.501 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-12T23:59:59.501Z,1531439999.501 [BuoyancyServo](INFO): Powering down 2018-07-13T00:00:01.500Z,1531440001.500 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:00:01.500Z,1531440001.500 [Aanderaa_O2] No Fault, FailCount= 3 2018-07-13T00:00:01.501Z,1531440001.501 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:00:01.501Z,1531440001.501 [BuoyancyServo] No Fault, FailCount= 3 2018-07-13T00:00:01.884Z,1531440001.884 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:00:02.002Z,1531440002.002 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:00:03.403Z,1531440003.403 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:00:03.403Z,1531440003.403 [Aanderaa_O2] Data Fault, FailCount= 1 2018-07-13T00:00:03.403Z,1531440003.403 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:00:03.507Z,1531440003.507 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:00:03.921Z,1531440003.921 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:00:04.726Z,1531440004.726 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:00:04.726Z,1531440004.726 [Aanderaa_O2] No Fault, FailCount= 1 2018-07-13T00:00:05.078Z,1531440005.078 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-13T00:00:05.078Z,1531440005.078 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:00:05.078Z,1531440005.078 [BuoyancyServo] Hardware Fault, FailCount= 4 2018-07-13T00:00:05.078Z,1531440005.078 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:00:05.084Z,1531440005.084 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:00:05.492Z,1531440005.492 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:00:05.497Z,1531440005.497 [BuoyancyServo](INFO): Powering down 2018-07-13T00:00:06.603Z,1531440006.603 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:00:06.603Z,1531440006.603 [Aanderaa_O2] Data Fault, FailCount= 2 2018-07-13T00:00:06.603Z,1531440006.603 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:00:06.744Z,1531440006.744 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:00:07.123Z,1531440007.123 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:00:07.503Z,1531440007.503 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:00:07.503Z,1531440007.503 [BuoyancyServo] No Fault, FailCount= 4 2018-07-13T00:00:07.870Z,1531440007.870 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:00:07.986Z,1531440007.986 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:00:07.992Z,1531440007.992 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:00:07.992Z,1531440007.992 [Aanderaa_O2] No Fault, FailCount= 2 2018-07-13T00:00:09.803Z,1531440009.803 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:00:09.803Z,1531440009.803 [Aanderaa_O2] Data Fault, FailCount= 3 2018-07-13T00:00:09.803Z,1531440009.803 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:00:09.899Z,1531440009.899 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:00:09.900Z,1531440009.900 [CBIT](FAULT): Data Fault in component: Aanderaa_O2 2018-07-13T00:00:10.322Z,1531440010.322 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:00:11.097Z,1531440011.097 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-13T00:00:11.098Z,1531440011.098 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:00:11.098Z,1531440011.098 [BuoyancyServo] Hardware Fault, FailCount= 5 2018-07-13T00:00:11.098Z,1531440011.098 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:00:11.104Z,1531440011.104 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:00:11.104Z,1531440011.104 [CBIT](CRITICAL): Hardware Fault in component: BuoyancyServo 2018-07-13T00:00:11.485Z,1531440011.485 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:00:11.486Z,1531440011.486 [BuoyancyServo](INFO): Powering down 2018-07-13T00:00:11.508Z,1531440011.508 [CBIT](INFO): Critical error at 20180713T000011 2018-07-13T00:00:12.263Z,1531440012.263 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-12T23:59:11.9Z 2018-07-13T00:00:12.263Z,1531440012.263 [Startup:StartupSatComms:A] Stopped 2018-07-13T00:00:12.263Z,1531440012.263 [Startup:StartupSatComms:B] Running Loop=1 2018-07-13T00:00:12.695Z,1531440012.695 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-07-13T00:00:14.268Z,1531440014.268 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-13T00:00:14.268Z,1531440014.268 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2018-07-13T00:00:14.268Z,1531440014.268 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-07-13T00:00:14.270Z,1531440014.270 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-13T00:00:14.270Z,1531440014.270 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-07-13T00:00:14.270Z,1531440014.270 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-07-13T00:00:14.283Z,1531440014.283 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-07-13T00:00:14.283Z,1531440014.283 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-07-13T00:00:14.713Z,1531440014.713 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-07-13T00:00:14.713Z,1531440014.713 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2018-07-13T00:00:14.713Z,1531440014.713 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-07-13T00:00:14.714Z,1531440014.714 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-07-13T00:00:15.048Z,1531440015.048 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-07-13T00:00:15.048Z,1531440015.048 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-07-13T00:00:15.069Z,1531440015.069 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-07-13T00:00:15.069Z,1531440015.069 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-07-13T00:00:15.070Z,1531440015.070 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-07-13T00:00:15.070Z,1531440015.070 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-07-13T00:00:46.386Z,1531440046.386 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004121 2018-07-13T00:00:50.907Z,1531440050.907 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180712T231035/Courier0007.lzma 2018-07-13T00:00:51.700Z,1531440051.700 [DataOverHttps](INFO): Moved sent file to Logs/20180712T231035/Courier0007.lzma.bak 2018-07-13T00:00:51.700Z,1531440051.700 [DataOverHttps](INFO): SBD MOMSN=8366883 2018-07-13T00:01:01.245Z,1531440061.245 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180712T235413/Courier0000.lzma 2018-07-13T00:01:02.026Z,1531440062.026 [DataOverHttps](INFO): Moved sent file to Logs/20180712T235413/Courier0000.lzma.bak 2018-07-13T00:01:02.026Z,1531440062.026 [DataOverHttps](INFO): SBD MOMSN=8366885 2018-07-13T00:01:11.468Z,1531440071.468 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20180712T235703/Courier0000.lzma 2018-07-13T00:01:12.254Z,1531440072.254 [DataOverHttps](INFO): Moved sent file to Logs/20180712T235703/Courier0000.lzma.bak 2018-07-13T00:01:12.255Z,1531440072.255 [DataOverHttps](INFO): SBD MOMSN=8366888 2018-07-13T00:01:12.351Z,1531440072.351 [Startup:StartupSatComms:B](INFO): Timed out from 2018-07-13T00:00:12.3Z 2018-07-13T00:01:12.351Z,1531440072.351 [Startup:StartupSatComms:B] Stopped 2018-07-13T00:01:12.352Z,1531440072.352 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-07-13T00:01:12.352Z,1531440072.352 [Startup:StartupSatComms] Stopped 2018-07-13T00:01:12.352Z,1531440072.352 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-07-13T00:01:12.354Z,1531440072.354 [Startup](INFO): Completed Startup 2018-07-13T00:01:12.354Z,1531440072.354 [MissionManager](INFO): Startup is completed. 2018-07-13T00:01:12.355Z,1531440072.355 [MissionManager](INFO): Uninitializing Mission Startup 2018-07-13T00:01:12.355Z,1531440072.355 [Startup] Stopped 2018-07-13T00:01:12.355Z,1531440072.355 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-07-13T00:01:12.356Z,1531440072.356 [Startup:A.GoToSurface] Stopped 2018-07-13T00:01:12.356Z,1531440072.356 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-07-13T00:01:12.734Z,1531440072.734 [MissionManager](IMPORTANT): Started mission Default 2018-07-13T00:01:12.734Z,1531440072.734 [Default] Running Loop=1 2018-07-13T00:01:12.734Z,1531440072.734 [Default](DEBUG): Aggregate::initialize Default 2018-07-13T00:01:12.734Z,1531440072.734 [Default:B.GoToSurface] Running Loop=1 2018-07-13T00:01:12.734Z,1531440072.734 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-07-13T00:01:12.734Z,1531440072.734 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-07-13T00:01:12.735Z,1531440072.735 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-07-13T00:01:12.735Z,1531440072.735 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-07-13T00:01:12.735Z,1531440072.735 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-07-13T00:01:12.736Z,1531440072.736 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-07-13T00:01:12.736Z,1531440072.736 [Default:A.Wait] Running Loop=1 2018-07-13T00:01:12.736Z,1531440072.736 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-07-13T00:01:13.064Z,1531440073.064 [NAL9602](INFO): SBD MO Status=2, MOMSN=32, MT Status=2, MTMSN=0 2018-07-13T00:01:13.064Z,1531440073.064 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-13T00:01:26.039Z,1531440086.039 [Default:A.Wait](INFO): Done Waiting. 2018-07-13T00:01:26.040Z,1531440086.040 [Default:A.Wait] Stopped 2018-07-13T00:01:26.040Z,1531440086.040 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-07-13T00:01:26.451Z,1531440086.451 [Default:CheckIn] Running Loop=1 2018-07-13T00:01:26.451Z,1531440086.451 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-13T00:01:26.451Z,1531440086.451 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-13T00:01:26.849Z,1531440086.849 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-07-13T00:02:11.266Z,1531440131.266 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:02:11.266Z,1531440131.266 [BuoyancyServo] No Fault, FailCount= 5 2018-07-13T00:02:11.661Z,1531440131.661 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:02:11.782Z,1531440131.782 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:02:17.485Z,1531440137.485 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-13T00:02:17.485Z,1531440137.485 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:02:17.485Z,1531440137.485 [BuoyancyServo] Hardware Fault, FailCount= 1 2018-07-13T00:02:17.486Z,1531440137.486 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:02:17.500Z,1531440137.500 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:02:17.835Z,1531440137.835 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:02:17.836Z,1531440137.836 [BuoyancyServo](INFO): Powering down 2018-07-13T00:02:19.835Z,1531440139.835 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:02:19.835Z,1531440139.835 [BuoyancyServo] No Fault, FailCount= 1 2018-07-13T00:02:20.232Z,1531440140.232 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:02:20.352Z,1531440140.352 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:02:23.477Z,1531440143.477 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-13T00:02:23.477Z,1531440143.477 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:02:23.477Z,1531440143.477 [BuoyancyServo] Hardware Fault, FailCount= 2 2018-07-13T00:02:23.477Z,1531440143.477 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:02:23.483Z,1531440143.483 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:02:23.804Z,1531440143.804 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:02:23.805Z,1531440143.805 [BuoyancyServo](INFO): Powering down 2018-07-13T00:02:25.840Z,1531440145.840 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:02:25.840Z,1531440145.840 [BuoyancyServo] No Fault, FailCount= 2 2018-07-13T00:02:26.240Z,1531440146.240 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:02:26.366Z,1531440146.366 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:02:29.489Z,1531440149.489 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-13T00:02:29.489Z,1531440149.489 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:02:29.489Z,1531440149.489 [BuoyancyServo] Hardware Fault, FailCount= 3 2018-07-13T00:02:29.490Z,1531440149.490 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:02:29.504Z,1531440149.504 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:02:29.830Z,1531440149.830 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:02:29.831Z,1531440149.831 [BuoyancyServo](INFO): Powering down 2018-07-13T00:02:31.828Z,1531440151.828 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:02:31.828Z,1531440151.828 [BuoyancyServo] No Fault, FailCount= 3 2018-07-13T00:02:32.239Z,1531440152.239 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:02:32.359Z,1531440152.359 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:02:35.477Z,1531440155.477 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-13T00:02:35.477Z,1531440155.477 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:02:35.477Z,1531440155.477 [BuoyancyServo] Hardware Fault, FailCount= 4 2018-07-13T00:02:35.478Z,1531440155.478 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:02:35.484Z,1531440155.484 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:02:35.840Z,1531440155.840 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:02:35.845Z,1531440155.845 [BuoyancyServo](INFO): Powering down 2018-07-13T00:02:37.834Z,1531440157.834 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:02:37.834Z,1531440157.834 [BuoyancyServo] No Fault, FailCount= 4 2018-07-13T00:02:38.229Z,1531440158.229 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:02:38.351Z,1531440158.351 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:02:39.839Z,1531440159.839 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:02:39.839Z,1531440159.839 [Aanderaa_O2] No Fault, FailCount= 3 2018-07-13T00:02:41.498Z,1531440161.498 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-13T00:02:41.498Z,1531440161.498 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:02:41.498Z,1531440161.498 [BuoyancyServo] Hardware Fault, FailCount= 5 2018-07-13T00:02:41.498Z,1531440161.498 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:02:41.505Z,1531440161.505 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:02:41.506Z,1531440161.506 [CBIT](CRITICAL): Hardware Fault in component: BuoyancyServo 2018-07-13T00:02:41.759Z,1531440161.759 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:02:41.759Z,1531440161.759 [Aanderaa_O2] Data Fault, FailCount= 1 2018-07-13T00:02:41.759Z,1531440161.759 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:02:41.844Z,1531440161.844 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:02:41.849Z,1531440161.849 [BuoyancyServo](INFO): Powering down 2018-07-13T00:02:41.865Z,1531440161.865 [CBIT](INFO): Critical error at 20180713T000241 2018-07-13T00:02:41.867Z,1531440161.867 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:02:42.281Z,1531440162.281 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:02:43.052Z,1531440163.052 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:02:43.052Z,1531440163.052 [Aanderaa_O2] No Fault, FailCount= 1 2018-07-13T00:02:44.959Z,1531440164.959 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:02:44.959Z,1531440164.959 [Aanderaa_O2] Data Fault, FailCount= 2 2018-07-13T00:02:44.959Z,1531440164.959 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:02:45.041Z,1531440165.041 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:02:45.481Z,1531440165.481 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:02:46.257Z,1531440166.257 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:02:46.257Z,1531440166.257 [Aanderaa_O2] No Fault, FailCount= 2 2018-07-13T00:02:48.159Z,1531440168.159 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:02:48.159Z,1531440168.159 [Aanderaa_O2] Data Fault, FailCount= 3 2018-07-13T00:02:48.159Z,1531440168.159 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:02:48.258Z,1531440168.258 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:02:48.258Z,1531440168.258 [CBIT](FAULT): Data Fault in component: Aanderaa_O2 2018-07-13T00:02:48.677Z,1531440168.677 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:02:55.775Z,1531440175.775 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-07-13T00:03:00.580Z,1531440180.580 [NAL9602](INFO): GPS fix at 20180713T000242: (36.802723, -121.788150) 2018-07-13T00:03:00.661Z,1531440180.661 [Default:CheckIn:Read_GPS] Stopped 2018-07-13T00:03:00.661Z,1531440180.661 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-07-13T00:03:01.060Z,1531440181.060 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-07-13T00:03:07.178Z,1531440187.178 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20180712T235703/Courier0004.lzma 2018-07-13T00:03:07.939Z,1531440187.939 [DataOverHttps](INFO): Moved sent file to Logs/20180712T235703/Courier0004.lzma.bak 2018-07-13T00:03:07.940Z,1531440187.940 [DataOverHttps](INFO): SBD MOMSN=8366894 2018-07-13T00:03:18.951Z,1531440198.951 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180712T231035/Express0008.lzma 2018-07-13T00:03:19.748Z,1531440199.748 [DataOverHttps](INFO): Moved sent file to Logs/20180712T231035/Express0008.lzma.bak 2018-07-13T00:03:19.748Z,1531440199.748 [DataOverHttps](INFO): SBD MOMSN=8366898 2018-07-13T00:03:31.706Z,1531440211.706 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180712T235413/Express0001.lzma 2018-07-13T00:03:32.492Z,1531440212.492 [DataOverHttps](INFO): Moved sent file to Logs/20180712T235413/Express0001.lzma.bak 2018-07-13T00:03:32.492Z,1531440212.492 [DataOverHttps](INFO): SBD MOMSN=8366900 2018-07-13T00:03:38.602Z,1531440218.602 [NAL9602](INFO): Powering down 2018-07-13T00:03:44.610Z,1531440224.610 [DataOverHttps](INFO): Sending 1026 bytes from file Logs/20180712T235703/Express0001.lzma 2018-07-13T00:03:45.395Z,1531440225.395 [DataOverHttps](INFO): Moved sent file to Logs/20180712T235703/Express0001.lzma.bak 2018-07-13T00:03:45.396Z,1531440225.396 [DataOverHttps](INFO): SBD MOMSN=8366902 2018-07-13T00:03:55.625Z,1531440235.625 [DataOverHttps](INFO): Sending 440 bytes from file Logs/20180712T235703/Express0005.lzma 2018-07-13T00:03:56.375Z,1531440236.375 [DataOverHttps](INFO): Moved sent file to Logs/20180712T235703/Express0005.lzma.bak 2018-07-13T00:03:56.376Z,1531440236.376 [DataOverHttps](INFO): SBD MOMSN=8366947 2018-07-13T00:03:56.874Z,1531440236.874 [Default:CheckIn:Read_Iridium] Stopped 2018-07-13T00:03:56.874Z,1531440236.874 [Default:CheckIn:C.Wait] Running Loop=1 2018-07-13T00:03:56.874Z,1531440236.874 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-07-13T00:04:05.392Z,1531440245.392 [CommandLine](IMPORTANT): got command gfscan 2018-07-13T00:04:05.549Z,1531440245.549 [CBIT](IMPORTANT): Beginning ground fault scan 2018-07-13T00:04:16.370Z,1531440256.370 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.013353 CHAN A1 (24V): -0.017893 CHAN A2 (12V): -0.007468 CHAN A3 (5V): -0.003142 CHAN B0 (3.3V): -0.000663 CHAN B1 (3.15aV): 0.000125 CHAN B2 (3.15bV): 0.000158 CHAN B3 (GND): 0.001177 OPEN: 0.009640 Full Scale Calc: 4.765 mA, -1.589 mA 2018-07-13T00:04:41.532Z,1531440281.532 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:04:41.532Z,1531440281.532 [BuoyancyServo] No Fault, FailCount= 5 2018-07-13T00:04:41.920Z,1531440281.920 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:04:42.034Z,1531440282.034 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:04:45.189Z,1531440285.189 [BuoyancyServo](FAULT): Reading outside of valid range:9521.000000 2018-07-13T00:04:45.189Z,1531440285.189 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:04:45.189Z,1531440285.189 [BuoyancyServo] Hardware Fault, FailCount= 1 2018-07-13T00:04:45.189Z,1531440285.189 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:04:45.196Z,1531440285.196 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:04:45.536Z,1531440285.536 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:04:45.536Z,1531440285.536 [BuoyancyServo](INFO): Powering down 2018-07-13T00:04:47.557Z,1531440287.557 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:04:47.557Z,1531440287.557 [BuoyancyServo] No Fault, FailCount= 1 2018-07-13T00:04:47.912Z,1531440287.912 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:04:48.026Z,1531440288.026 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:04:51.153Z,1531440291.153 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-13T00:04:51.153Z,1531440291.153 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:04:51.154Z,1531440291.154 [BuoyancyServo] Hardware Fault, FailCount= 2 2018-07-13T00:04:51.154Z,1531440291.154 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:04:51.160Z,1531440291.160 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:04:51.544Z,1531440291.544 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:04:51.544Z,1531440291.544 [BuoyancyServo](INFO): Powering down 2018-07-13T00:04:53.540Z,1531440293.540 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:04:53.540Z,1531440293.540 [BuoyancyServo] No Fault, FailCount= 2 2018-07-13T00:04:53.912Z,1531440293.912 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:04:54.030Z,1531440294.030 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:04:57.157Z,1531440297.157 [BuoyancyServo](FAULT): Reading outside of valid range:9521.000000 2018-07-13T00:04:57.158Z,1531440297.158 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:04:57.158Z,1531440297.158 [BuoyancyServo] Hardware Fault, FailCount= 3 2018-07-13T00:04:57.158Z,1531440297.158 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:04:57.164Z,1531440297.164 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:04:57.573Z,1531440297.573 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:04:57.574Z,1531440297.574 [BuoyancyServo](INFO): Powering down 2018-07-13T00:05:01.123Z,1531440301.123 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:05:01.123Z,1531440301.123 [BuoyancyServo] No Fault, FailCount= 3 2018-07-13T00:05:01.466Z,1531440301.466 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:05:01.582Z,1531440301.582 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:05:04.681Z,1531440304.681 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-13T00:05:04.681Z,1531440304.681 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:05:04.681Z,1531440304.681 [BuoyancyServo] Hardware Fault, FailCount= 4 2018-07-13T00:05:04.682Z,1531440304.682 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:05:04.688Z,1531440304.688 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:05:05.061Z,1531440305.061 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:05:05.062Z,1531440305.062 [BuoyancyServo](INFO): Powering down 2018-07-13T00:05:07.061Z,1531440307.061 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:05:07.061Z,1531440307.061 [BuoyancyServo] No Fault, FailCount= 4 2018-07-13T00:05:07.468Z,1531440307.468 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:05:07.586Z,1531440307.586 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:05:10.698Z,1531440310.698 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-13T00:05:10.698Z,1531440310.698 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:05:10.698Z,1531440310.698 [BuoyancyServo] Hardware Fault, FailCount= 5 2018-07-13T00:05:10.698Z,1531440310.698 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:05:10.704Z,1531440310.704 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:05:10.705Z,1531440310.705 [CBIT](CRITICAL): Hardware Fault in component: BuoyancyServo 2018-07-13T00:05:11.070Z,1531440311.070 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:05:11.071Z,1531440311.071 [BuoyancyServo](INFO): Powering down 2018-07-13T00:05:11.092Z,1531440311.092 [CBIT](INFO): Critical error at 20180713T000510 2018-07-13T00:05:18.263Z,1531440318.263 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:05:18.263Z,1531440318.263 [Aanderaa_O2] No Fault, FailCount= 3 2018-07-13T00:05:20.179Z,1531440320.179 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:05:20.179Z,1531440320.179 [Aanderaa_O2] Data Fault, FailCount= 1 2018-07-13T00:05:20.179Z,1531440320.179 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:05:20.279Z,1531440320.279 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:05:20.697Z,1531440320.697 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:05:21.484Z,1531440321.484 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:05:21.484Z,1531440321.484 [Aanderaa_O2] No Fault, FailCount= 1 2018-07-13T00:05:23.379Z,1531440323.379 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:05:23.379Z,1531440323.379 [Aanderaa_O2] Data Fault, FailCount= 2 2018-07-13T00:05:23.379Z,1531440323.379 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:05:23.480Z,1531440323.480 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:05:23.893Z,1531440323.893 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:05:24.688Z,1531440324.688 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:05:24.688Z,1531440324.688 [Aanderaa_O2] No Fault, FailCount= 2 2018-07-13T00:05:26.579Z,1531440326.579 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:05:26.579Z,1531440326.579 [Aanderaa_O2] Data Fault, FailCount= 3 2018-07-13T00:05:26.579Z,1531440326.579 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:05:26.697Z,1531440326.697 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:05:26.697Z,1531440326.697 [CBIT](FAULT): Data Fault in component: Aanderaa_O2 2018-07-13T00:05:27.097Z,1531440327.097 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:07:10.872Z,1531440430.872 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:07:10.872Z,1531440430.872 [BuoyancyServo] No Fault, FailCount= 5 2018-07-13T00:07:11.274Z,1531440431.274 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:07:11.390Z,1531440431.390 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:07:14.517Z,1531440434.517 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-13T00:07:14.518Z,1531440434.518 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:07:14.518Z,1531440434.518 [BuoyancyServo] Hardware Fault, FailCount= 1 2018-07-13T00:07:14.518Z,1531440434.518 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:07:14.526Z,1531440434.526 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:07:14.909Z,1531440434.909 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:07:14.910Z,1531440434.910 [BuoyancyServo](INFO): Powering down 2018-07-13T00:07:16.905Z,1531440436.905 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:07:16.905Z,1531440436.905 [BuoyancyServo] No Fault, FailCount= 1 2018-07-13T00:07:17.278Z,1531440437.278 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:07:17.394Z,1531440437.394 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:07:20.537Z,1531440440.537 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-13T00:07:20.537Z,1531440440.537 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:07:20.537Z,1531440440.537 [BuoyancyServo] Hardware Fault, FailCount= 2 2018-07-13T00:07:20.537Z,1531440440.537 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:07:20.544Z,1531440440.544 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:07:20.902Z,1531440440.902 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:07:20.903Z,1531440440.903 [BuoyancyServo](INFO): Powering down 2018-07-13T00:07:22.937Z,1531440442.937 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:07:22.937Z,1531440442.937 [BuoyancyServo] No Fault, FailCount= 2 2018-07-13T00:07:23.361Z,1531440443.361 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:07:23.482Z,1531440443.482 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:07:26.589Z,1531440446.589 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-13T00:07:26.589Z,1531440446.589 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:07:26.589Z,1531440446.589 [BuoyancyServo] Hardware Fault, FailCount= 3 2018-07-13T00:07:26.589Z,1531440446.589 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:07:26.595Z,1531440446.595 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:07:26.914Z,1531440446.914 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:07:26.915Z,1531440446.915 [BuoyancyServo](INFO): Powering down 2018-07-13T00:07:28.900Z,1531440448.900 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:07:28.903Z,1531440448.903 [BuoyancyServo] No Fault, FailCount= 3 2018-07-13T00:07:29.300Z,1531440449.300 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:07:29.426Z,1531440449.426 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:07:32.529Z,1531440452.529 [BuoyancyServo](FAULT): Reading outside of valid range:9519.000000 2018-07-13T00:07:32.529Z,1531440452.529 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:07:32.529Z,1531440452.529 [BuoyancyServo] Hardware Fault, FailCount= 4 2018-07-13T00:07:32.530Z,1531440452.530 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:07:32.535Z,1531440452.535 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:07:32.890Z,1531440452.890 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:07:32.891Z,1531440452.891 [BuoyancyServo](INFO): Powering down 2018-07-13T00:07:34.916Z,1531440454.916 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:07:34.916Z,1531440454.916 [BuoyancyServo] No Fault, FailCount= 4 2018-07-13T00:07:35.267Z,1531440455.267 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:07:35.382Z,1531440455.382 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:07:38.514Z,1531440458.514 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000 2018-07-13T00:07:38.515Z,1531440458.515 [BuoyancyServo](FAULT): Buoyancy engine reporting null position 2018-07-13T00:07:38.515Z,1531440458.515 [BuoyancyServo] Hardware Fault, FailCount= 5 2018-07-13T00:07:38.515Z,1531440458.515 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:07:38.521Z,1531440458.521 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:07:38.522Z,1531440458.522 [CBIT](CRITICAL): Hardware Fault in component: BuoyancyServo 2018-07-13T00:07:38.880Z,1531440458.880 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:07:38.902Z,1531440458.902 [BuoyancyServo](INFO): Powering down 2018-07-13T00:07:38.924Z,1531440458.924 [CBIT](INFO): Critical error at 20180713T000738 2018-07-13T00:07:45.497Z,1531440465.497 [CommandLine](IMPORTANT): got command configSet Aanderaa_O2.loadAtStartup 0.000000 bool persist 2018-07-13T00:07:45.498Z,1531440465.498 [CommandLine](IMPORTANT): configSet Aanderaa_O2.loadAtStartup requires a restart to take effect. 2018-07-13T00:07:56.896Z,1531440476.896 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:07:56.913Z,1531440476.913 [Aanderaa_O2] No Fault, FailCount= 3 2018-07-13T00:08:01.621Z,1531440481.621 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:08:01.622Z,1531440481.622 [Aanderaa_O2] Data Fault, FailCount= 1 2018-07-13T00:08:01.622Z,1531440481.622 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:08:01.798Z,1531440481.798 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:08:02.141Z,1531440482.141 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:08:02.950Z,1531440482.950 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:08:02.950Z,1531440482.950 [Aanderaa_O2] No Fault, FailCount= 1 2018-07-13T00:08:04.827Z,1531440484.827 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:08:04.827Z,1531440484.827 [Aanderaa_O2] Data Fault, FailCount= 2 2018-07-13T00:08:04.827Z,1531440484.827 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:08:04.935Z,1531440484.935 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:08:05.346Z,1531440485.346 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:08:06.131Z,1531440486.131 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:08:06.131Z,1531440486.131 [Aanderaa_O2] No Fault, FailCount= 2 2018-07-13T00:08:08.023Z,1531440488.023 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:08:08.023Z,1531440488.023 [Aanderaa_O2] Data Fault, FailCount= 3 2018-07-13T00:08:08.023Z,1531440488.023 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:08:08.115Z,1531440488.115 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:08:08.115Z,1531440488.115 [CBIT](FAULT): Data Fault in component: Aanderaa_O2 2018-07-13T00:08:08.545Z,1531440488.545 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:08:31.584Z,1531440511.584 [CommandLine](IMPORTANT): got command get BuoyancyServo.limitHi 2018-07-13T00:08:31.585Z,1531440511.585 [CommandLine](IMPORTANT): BuoyancyServo.limitHi 9500 count 2018-07-13T00:08:56.866Z,1531440536.866 [CommandLine](IMPORTANT): got command configSet BuoyancyServo.limitHi 9600.000000 count persist 2018-07-13T00:08:57.283Z,1531440537.283 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-07-13T00:08:57.283Z,1531440537.283 [Default:CheckIn:C.Wait] Stopped 2018-07-13T00:08:57.284Z,1531440537.284 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-07-13T00:08:57.284Z,1531440537.284 [Default:CheckIn:D] Running Loop=1 2018-07-13T00:08:57.703Z,1531440537.703 [Default:CheckIn:D] Stopped 2018-07-13T00:08:57.703Z,1531440537.703 [Default:CheckIn:E] Running Loop=1 2018-07-13T00:08:58.088Z,1531440538.088 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.749458 min 2018-07-13T00:08:58.106Z,1531440538.106 [Default:CheckIn:E] Stopped 2018-07-13T00:08:58.107Z,1531440538.107 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-07-13T00:08:58.107Z,1531440538.107 [Default:CheckIn] Stopped 2018-07-13T00:08:58.107Z,1531440538.107 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-13T00:08:58.107Z,1531440538.107 [Default:CheckIn](INFO): Running loop #2 2018-07-13T00:08:58.107Z,1531440538.107 [Default:CheckIn] Running Loop=2 2018-07-13T00:08:58.107Z,1531440538.107 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-13T00:08:58.107Z,1531440538.107 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-13T00:09:01.431Z,1531440541.431 [NAL9602](INFO): Powering up 2018-07-13T00:09:12.622Z,1531440552.622 [NAL9602](INFO): NAL9602 initialized 2018-07-13T00:09:23.425Z,1531440563.425 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position 2018-07-13T00:09:23.515Z,1531440563.515 [Reporter](INFO): platform_buoyancy_position no_value 2018-07-13T00:09:38.686Z,1531440578.686 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:09:38.686Z,1531440578.686 [BuoyancyServo] No Fault, FailCount= 5 2018-07-13T00:09:39.088Z,1531440579.088 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:09:39.203Z,1531440579.203 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:09:40.704Z,1531440580.704 [CommandLine](IMPORTANT): got command failComponent hardware BuoyancyServo 2018-07-13T00:09:40.707Z,1531440580.707 [BuoyancyServo] Hardware Fault, FailCount= 1 2018-07-13T00:09:40.707Z,1531440580.707 [BuoyancyServo](ERROR): Hardware Fault 2018-07-13T00:09:40.707Z,1531440580.707 [CommandLine](IMPORTANT): BuoyancyServo failureMode is Hardware Fault 2018-07-13T00:09:40.713Z,1531440580.713 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2018-07-13T00:09:41.084Z,1531440581.084 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:09:41.089Z,1531440581.089 [BuoyancyServo](INFO): Powering down 2018-07-13T00:09:43.074Z,1531440583.074 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-13T00:09:43.075Z,1531440583.075 [BuoyancyServo] No Fault, FailCount= 1 2018-07-13T00:09:43.482Z,1531440583.482 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-13T00:09:43.602Z,1531440583.602 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-13T00:09:44.620Z,1531440584.620 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-07-13T00:09:44.693Z,1531440584.693 [NAL9602](FAULT): received: +CSQ:0 OK, 2, 0, 0, 0 OK 2018-07-13T00:09:44.693Z,1531440584.693 [NAL9602] Data Fault, FailCount= 1 2018-07-13T00:09:44.693Z,1531440584.693 [NAL9602](ERROR): Data Fault 2018-07-13T00:09:44.817Z,1531440584.817 [CBIT](ERROR): Data Fault in component: NAL9602 2018-07-13T00:09:45.031Z,1531440585.031 [NAL9602](INFO): Powering down 2018-07-13T00:09:45.891Z,1531440585.891 [CBIT](INFO): Clearing failed state for component NAL9602 2018-07-13T00:09:45.891Z,1531440585.891 [NAL9602] No Fault, FailCount= 1 2018-07-13T00:09:49.944Z,1531440589.944 [Reporter](INFO): platform_buoyancy_position 1179.949730 cc 2018-07-13T00:09:50.388Z,1531440590.388 [Reporter](INFO): platform_buoyancy_position 1180.084073 cc 2018-07-13T00:09:50.736Z,1531440590.736 [Reporter](INFO): platform_buoyancy_position 1177.935279 cc 2018-07-13T00:09:51.134Z,1531440591.134 [Reporter](INFO): platform_buoyancy_position 1177.666592 cc 2018-07-13T00:09:51.518Z,1531440591.518 [Reporter](INFO): platform_buoyancy_position 1175.517798 cc 2018-07-13T00:09:51.910Z,1531440591.910 [Reporter](INFO): platform_buoyancy_position 1162.759378 cc 2018-07-13T00:09:52.311Z,1531440592.311 [Reporter](INFO): platform_buoyancy_position 1158.461790 cc 2018-07-13T00:09:52.721Z,1531440592.721 [Reporter](INFO): platform_buoyancy_position 1154.164202 cc 2018-07-13T00:09:53.114Z,1531440593.114 [Reporter](INFO): platform_buoyancy_position 1149.866614 cc 2018-07-13T00:09:53.513Z,1531440593.513 [Reporter](INFO): platform_buoyancy_position 1143.688685 cc 2018-07-13T00:09:53.929Z,1531440593.929 [Reporter](INFO): platform_buoyancy_position 1139.122527 cc 2018-07-13T00:09:54.310Z,1531440594.310 [Reporter](INFO): platform_buoyancy_position 1134.824939 cc 2018-07-13T00:09:54.804Z,1531440594.804 [Reporter](INFO): platform_buoyancy_position 1128.512784 cc 2018-07-13T00:09:55.112Z,1531440595.112 [Reporter](INFO): platform_buoyancy_position 1124.080969 cc 2018-07-13T00:09:55.516Z,1531440595.516 [Reporter](INFO): platform_buoyancy_position 1117.768930 cc 2018-07-13T00:09:55.910Z,1531440595.910 [Reporter](INFO): platform_buoyancy_position 1113.471342 cc 2018-07-13T00:09:56.333Z,1531440596.333 [Reporter](INFO): platform_buoyancy_position 1108.770724 cc 2018-07-13T00:09:56.732Z,1531440596.732 [Reporter](INFO): platform_buoyancy_position 1102.593029 cc 2018-07-13T00:09:57.092Z,1531440597.092 [Reporter](INFO): platform_buoyancy_position 1098.295441 cc 2018-07-13T00:09:57.536Z,1531440597.536 [Reporter](INFO): platform_buoyancy_position 1091.848942 cc 2018-07-13T00:09:57.920Z,1531440597.920 [Reporter](INFO): platform_buoyancy_position 1087.551354 cc 2018-07-13T00:09:58.331Z,1531440598.331 [Reporter](INFO): platform_buoyancy_position 1081.507769 cc 2018-07-13T00:10:01.574Z,1531440601.574 [Reporter](INFO): platform_buoyancy_position 1039.606170 cc 2018-07-13T00:10:01.736Z,1531440601.736 [Reporter](INFO): platform_buoyancy_position 1036.383095 cc 2018-07-13T00:10:02.122Z,1531440602.122 [Reporter](INFO): platform_buoyancy_position 1031.682594 cc 2018-07-13T00:10:02.516Z,1531440602.516 [Reporter](INFO): platform_buoyancy_position 1027.250662 cc 2018-07-13T00:10:02.916Z,1531440602.916 [Reporter](INFO): platform_buoyancy_position 1021.072851 cc 2018-07-13T00:10:03.304Z,1531440603.304 [Reporter](INFO): platform_buoyancy_position 1016.506692 cc 2018-07-13T00:10:03.707Z,1531440603.707 [Reporter](INFO): platform_buoyancy_position 1010.328881 cc 2018-07-13T00:10:04.091Z,1531440604.091 [Reporter](INFO): platform_buoyancy_position 1006.031292 cc 2018-07-13T00:10:04.524Z,1531440604.524 [Reporter](INFO): platform_buoyancy_position 999.584794 cc 2018-07-13T00:10:04.906Z,1531440604.906 [Reporter](INFO): platform_buoyancy_position 995.287206 cc 2018-07-13T00:10:05.304Z,1531440605.304 [Reporter](INFO): platform_buoyancy_position 990.721048 cc 2018-07-13T00:10:05.698Z,1531440605.698 [Reporter](INFO): platform_buoyancy_position 984.408893 cc 2018-07-13T00:10:06.150Z,1531440606.150 [Reporter](INFO): platform_buoyancy_position 980.111305 cc 2018-07-13T00:10:06.508Z,1531440606.508 [Reporter](INFO): platform_buoyancy_position 973.933609 cc 2018-07-13T00:10:06.892Z,1531440606.892 [Reporter](INFO): platform_buoyancy_position 969.501736 cc 2018-07-13T00:10:10.564Z,1531440610.564 [Reporter](INFO): platform_buoyancy_position 956.608914 cc 2018-07-13T00:10:15.199Z,1531440615.199 [NAL9602](INFO): Powering up NAL9602 2018-07-13T00:10:24.542Z,1531440624.542 [CommandLine](IMPORTANT): got command stop 2018-07-13T00:10:24.542Z,1531440624.542 [CommandLine](IMPORTANT): Scheduling is paused 2018-07-13T00:10:26.408Z,1531440626.408 [NAL9602](INFO): NAL9602 initialized 2018-07-13T00:10:38.072Z,1531440638.072 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:10:38.072Z,1531440638.072 [Aanderaa_O2] No Fault, FailCount= 3 2018-07-13T00:10:39.983Z,1531440639.983 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:10:39.983Z,1531440639.983 [Aanderaa_O2] Data Fault, FailCount= 1 2018-07-13T00:10:39.983Z,1531440639.983 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:10:40.126Z,1531440640.126 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:10:40.501Z,1531440640.501 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:10:41.270Z,1531440641.270 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:10:41.270Z,1531440641.270 [Aanderaa_O2] No Fault, FailCount= 1 2018-07-13T00:10:43.183Z,1531440643.183 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 651 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 12 2018-07-13T00:10:43.183Z,1531440643.183 [Aanderaa_O2] Data Fault, FailCount= 2 2018-07-13T00:10:43.183Z,1531440643.183 [Aanderaa_O2](ERROR): Data Fault 2018-07-13T00:10:43.301Z,1531440643.301 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2018-07-13T00:10:43.701Z,1531440643.701 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:10:43.798Z,1531440643.798 [CommandLine](IMPORTANT): got command restart application 2018-07-13T00:10:44.516Z,1531440644.516 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-07-13T00:10:44.516Z,1531440644.516 [Aanderaa_O2] No Fault, FailCount= 2 2018-07-13T00:10:44.808Z,1531440644.808 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-13T00:10:44.808Z,1531440644.808 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:44.969Z,1531440644.969 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-07-13T00:10:44.969Z,1531440644.969 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:44.970Z,1531440644.970 [CommandLine](INFO): Join timeout helper Thread ID is 974 2018-07-13T00:10:44.970Z,1531440644.970 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-07-13T00:10:44.970Z,1531440644.970 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:44.970Z,1531440644.970 [NavChartDb](INFO): Join timeout helper Thread ID is 975 2018-07-13T00:10:45.285Z,1531440645.285 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-13T00:10:45.285Z,1531440645.285 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:45.297Z,1531440645.297 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-07-13T00:10:45.298Z,1531440645.298 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:45.299Z,1531440645.299 [Radio_Surface](INFO): Join timeout helper Thread ID is 976 2018-07-13T00:10:45.389Z,1531440645.389 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-13T00:10:45.389Z,1531440645.389 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:45.405Z,1531440645.405 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2018-07-13T00:10:45.405Z,1531440645.405 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:45.405Z,1531440645.405 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 977 2018-07-13T00:10:45.681Z,1531440645.681 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-13T00:10:45.681Z,1531440645.681 [WetLabsSeaOWL_UV_A](INFO): Powering down 2018-07-13T00:10:45.682Z,1531440645.682 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:45.685Z,1531440645.685 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-07-13T00:10:45.685Z,1531440645.685 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:45.685Z,1531440645.685 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 978 2018-07-13T00:10:45.861Z,1531440645.861 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-13T00:10:45.861Z,1531440645.861 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:45.866Z,1531440645.866 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2018-07-13T00:10:45.866Z,1531440645.866 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:45.866Z,1531440645.866 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 979 2018-07-13T00:10:46.025Z,1531440646.025 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-13T00:10:46.026Z,1531440646.026 [CTD_NeilBrown](INFO): Powering down 2018-07-13T00:10:46.026Z,1531440646.026 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.042Z,1531440646.042 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-07-13T00:10:46.042Z,1531440646.042 [logger ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.042Z,1531440646.042 [logger](INFO): Join timeout helper Thread ID is 980 2018-07-13T00:10:46.042Z,1531440646.042 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-13T00:10:46.043Z,1531440646.043 [logger ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.044Z,1531440646.044 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-07-13T00:10:46.044Z,1531440646.044 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.044Z,1531440646.044 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-07-13T00:10:46.044Z,1531440646.044 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.045Z,1531440646.045 [controlThread](INFO): Join timeout helper Thread ID is 981 2018-07-13T00:10:46.065Z,1531440646.065 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-07-13T00:10:46.065Z,1531440646.065 [controlThread](DEBUG): Uninitializing ControlThread 2018-07-13T00:10:46.185Z,1531440646.185 [Aanderaa_O2](INFO): Powering down 2018-07-13T00:10:46.186Z,1531440646.186 [AHRS_M2](INFO): Powering down 2018-07-13T00:10:46.258Z,1531440646.258 [NAL9602](INFO): Powering down 2018-07-13T00:10:46.329Z,1531440646.329 [RDI_Pathfinder](INFO): Powering down 2018-07-13T00:10:46.331Z,1531440646.331 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-07-13T00:10:46.332Z,1531440646.332 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-07-13T00:10:46.333Z,1531440646.333 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-07-13T00:10:46.333Z,1531440646.333 [MissionManager](INFO): Uninitializing Mission Default 2018-07-13T00:10:46.333Z,1531440646.333 [Default] Stopped 2018-07-13T00:10:46.334Z,1531440646.334 [Default](DEBUG): Aggregate::uninitialize Default 2018-07-13T00:10:46.334Z,1531440646.334 [Default:B.GoToSurface] Stopped 2018-07-13T00:10:46.334Z,1531440646.334 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-07-13T00:10:46.334Z,1531440646.334 [Default:CheckIn] Stopped 2018-07-13T00:10:46.334Z,1531440646.334 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-13T00:10:46.334Z,1531440646.334 [Default:CheckIn:Read_GPS] Stopped 2018-07-13T00:10:46.338Z,1531440646.338 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-07-13T00:10:46.338Z,1531440646.338 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-07-13T00:10:46.338Z,1531440646.338 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-07-13T00:10:46.338Z,1531440646.338 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-07-13T00:10:46.339Z,1531440646.339 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-13T00:10:46.339Z,1531440646.339 [BuoyancyServo](INFO): Powering down 2018-07-13T00:10:46.353Z,1531440646.353 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-07-13T00:10:46.353Z,1531440646.353 [ElevatorServo](INFO): Powering down 2018-07-13T00:10:46.354Z,1531440646.354 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-07-13T00:10:46.354Z,1531440646.354 [MassServo](INFO): Powering down 2018-07-13T00:10:46.355Z,1531440646.355 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-07-13T00:10:46.355Z,1531440646.355 [RudderServo](INFO): Powering down 2018-07-13T00:10:46.356Z,1531440646.356 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-07-13T00:10:46.356Z,1531440646.356 [ThrusterServo](INFO): Powering down 2018-07-13T00:10:46.357Z,1531440646.357 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-07-13T00:10:46.358Z,1531440646.358 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-07-13T00:10:46.358Z,1531440646.358 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-07-13T00:10:46.359Z,1531440646.359 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.409Z,1531440646.409 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.412Z,1531440646.412 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.415Z,1531440646.415 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.472Z,1531440646.472 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.514Z,1531440646.514 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-07-13T00:10:46.574Z,1531440646.574 [logger ThreadHandler](INFO): Thread cancelled.