2018-02-01T17:22:01.166Z,1517505721.166 [Supervisor](DEBUG): Initializing supervisor. 2018-02-01T17:22:01.169Z,1517505721.169 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-02-01T17:22:01.170Z,1517505721.170 [SyncHandler](INFO): Protected caller Thread ID is 6231 2018-02-01T17:22:01.170Z,1517505721.170 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-02-01T17:22:01.171Z,1517505721.171 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-02-01T17:22:01.172Z,1517505721.172 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6232 2018-02-01T17:22:01.175Z,1517505721.175 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-02-01T17:22:01.186Z,1517505721.186 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-02-01T17:22:01.187Z,1517505721.187 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-02-01T17:22:01.187Z,1517505721.187 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6233 2018-02-01T17:22:01.188Z,1517505721.188 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-02-01T17:22:01.189Z,1517505721.189 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-02-01T17:22:01.189Z,1517505721.189 [logger ThreadHandler](INFO): Protected caller Thread ID is 6234 2018-02-01T17:22:01.191Z,1517505721.191 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-02-01T17:22:01.192Z,1517505721.192 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-02-01T17:22:01.193Z,1517505721.193 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-02-01T17:22:01.485Z,1517505721.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-02-01T17:22:01.486Z,1517505721.486 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-02-01T17:22:01.667Z,1517505721.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-02-01T17:22:01.668Z,1517505721.668 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-02-01T17:22:01.925Z,1517505721.925 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-02-01T17:22:01.925Z,1517505721.925 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-02-01T17:22:02.058Z,1517505722.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-02-01T17:22:02.059Z,1517505722.059 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-02-01T17:22:02.153Z,1517505722.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-02-01T17:22:02.154Z,1517505722.154 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-02-01T17:22:02.572Z,1517505722.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-02-01T17:22:02.573Z,1517505722.573 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-02-01T17:22:02.887Z,1517505722.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-02-01T17:22:02.888Z,1517505722.888 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-02-01T17:22:03.289Z,1517505723.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-02-01T17:22:03.290Z,1517505723.290 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-02-01T17:22:03.389Z,1517505723.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-02-01T17:22:03.390Z,1517505723.390 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-02-01T17:22:03.483Z,1517505723.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-02-01T17:22:03.484Z,1517505723.484 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-02-01T17:22:03.674Z,1517505723.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-02-01T17:22:03.675Z,1517505723.675 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-02-01T17:22:03.862Z,1517505723.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-02-01T17:22:03.863Z,1517505723.863 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-02-01T17:22:04.172Z,1517505724.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-02-01T17:22:04.173Z,1517505724.173 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-02-01T17:22:04.305Z,1517505724.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-02-01T17:22:04.306Z,1517505724.306 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-02-01T17:22:04.386Z,1517505724.386 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2018-02-01T17:22:04.387Z,1517505724.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2018-02-01T17:22:04.484Z,1517505724.484 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2018-02-01T17:22:04.572Z,1517505724.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2018-02-01T17:22:04.663Z,1517505724.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2018-02-01T17:22:04.745Z,1517505724.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2018-02-01T17:22:05.077Z,1517505725.077 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2018-02-01T17:22:05.500Z,1517505725.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2018-02-01T17:22:05.595Z,1517505725.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2018-02-01T17:22:06.013Z,1517505726.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-02-01T17:22:06.014Z,1517505726.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2018-02-01T17:22:06.095Z,1517505726.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2018-02-01T17:22:06.182Z,1517505726.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2018-02-01T17:22:06.279Z,1517505726.279 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2018-02-01T17:22:06.399Z,1517505726.399 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2018-02-01T17:22:06.400Z,1517505726.400 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-02-01T17:22:06.404Z,1517505726.404 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-02-01T17:22:06.635Z,1517505726.635 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-02-01T17:22:06.635Z,1517505726.635 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-02-01T17:22:06.675Z,1517505726.675 [DepthRateCalculator] Loaded 2018-02-01T17:22:06.675Z,1517505726.675 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-02-01T17:22:06.681Z,1517505726.681 [PitchRateCalculator] Loaded 2018-02-01T17:22:06.681Z,1517505726.681 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-02-01T17:22:06.697Z,1517505726.697 [SpeedCalculator] Loaded 2018-02-01T17:22:06.697Z,1517505726.697 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-02-01T17:22:06.717Z,1517505726.717 [TempGradientCalculator] Loaded 2018-02-01T17:22:06.717Z,1517505726.717 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-02-01T17:22:06.732Z,1517505726.732 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-02-01T17:22:06.733Z,1517505726.733 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-02-01T17:22:06.738Z,1517505726.738 [YawRateCalculator] Loaded 2018-02-01T17:22:06.738Z,1517505726.738 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-02-01T17:22:06.739Z,1517505726.739 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-02-01T17:22:06.739Z,1517505726.739 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-02-01T17:22:06.818Z,1517505726.818 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-02-01T17:22:06.818Z,1517505726.818 [StratificationFrontDetector](DEBUG): (re)initializing 2018-02-01T17:22:06.818Z,1517505726.818 [StratificationFrontDetector] Loaded 2018-02-01T17:22:06.818Z,1517505726.818 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-02-01T17:22:06.819Z,1517505726.819 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-02-01T17:22:06.819Z,1517505726.819 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-02-01T17:22:06.929Z,1517505726.929 [BuoyancyServo] Loaded 2018-02-01T17:22:06.929Z,1517505726.929 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-02-01T17:22:06.943Z,1517505726.943 [ElevatorServo] Loaded 2018-02-01T17:22:06.944Z,1517505726.944 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-02-01T17:22:06.958Z,1517505726.958 [MassServo] Loaded 2018-02-01T17:22:06.958Z,1517505726.958 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-02-01T17:22:06.972Z,1517505726.972 [RudderServo] Loaded 2018-02-01T17:22:06.972Z,1517505726.972 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-02-01T17:22:06.986Z,1517505726.986 [ThrusterServo] Loaded 2018-02-01T17:22:06.986Z,1517505726.986 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-02-01T17:22:06.986Z,1517505726.986 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-02-01T17:22:06.987Z,1517505726.987 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-02-01T17:22:06.000Z,1517505727.000 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-02-01T17:22:07.001Z,1517505727.001 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-02-01T17:22:07.093Z,1517505727.093 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-02-01T17:22:07.093Z,1517505727.093 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-02-01T17:22:07.355Z,1517505727.355 [DataOverHttps] Loaded 2018-02-01T17:22:07.355Z,1517505727.355 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-02-01T17:22:07.368Z,1517505727.368 [Depth_Keller] Loaded 2018-02-01T17:22:07.369Z,1517505727.369 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-02-01T17:22:07.373Z,1517505727.373 [DropWeight] Loaded 2018-02-01T17:22:07.373Z,1517505727.373 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-02-01T17:22:07.467Z,1517505727.467 [NAL9602] Loaded 2018-02-01T17:22:07.467Z,1517505727.467 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-02-01T17:22:07.482Z,1517505727.482 [Onboard] Loaded 2018-02-01T17:22:07.482Z,1517505727.482 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-02-01T17:22:07.492Z,1517505727.492 [Radio_Surface] Loaded 2018-02-01T17:22:07.492Z,1517505727.492 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-02-01T17:22:07.493Z,1517505727.493 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0 2018-02-01T17:22:07.494Z,1517505727.494 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6316 2018-02-01T17:22:07.539Z,1517505727.539 [PNI_TCM] Loaded 2018-02-01T17:22:07.539Z,1517505727.539 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-02-01T17:22:09.200Z,1517505729.200 [BPC1] Loaded 2018-02-01T17:22:09.201Z,1517505729.201 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-02-01T17:22:09.201Z,1517505729.201 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-02-01T17:22:09.202Z,1517505729.202 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-02-01T17:22:09.325Z,1517505729.325 [SBIT](DEBUG): Construct Startup Built In Test. 2018-02-01T17:22:09.349Z,1517505729.349 [SBIT] Loaded 2018-02-01T17:22:09.350Z,1517505729.350 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-02-01T17:22:09.350Z,1517505729.350 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-02-01T17:22:09.361Z,1517505729.361 [IBIT] Loaded 2018-02-01T17:22:09.362Z,1517505729.362 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-02-01T17:22:09.365Z,1517505729.365 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-02-01T17:22:09.510Z,1517505729.510 [CBIT] Loaded 2018-02-01T17:22:09.510Z,1517505729.510 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-02-01T17:22:09.510Z,1517505729.510 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-02-01T17:22:09.511Z,1517505729.511 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-02-01T17:22:09.648Z,1517505729.648 [Aanderaa_O2] Loaded 2018-02-01T17:22:09.648Z,1517505729.648 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-02-01T17:22:09.660Z,1517505729.660 [CANONSampler] Loaded 2018-02-01T17:22:09.660Z,1517505729.660 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2018-02-01T17:22:09.735Z,1517505729.735 [CTD_NeilBrown] Loaded 2018-02-01T17:22:09.735Z,1517505729.735 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2018-02-01T17:22:09.737Z,1517505729.737 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409364E0 2018-02-01T17:22:09.737Z,1517505729.737 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6317 2018-02-01T17:22:09.781Z,1517505729.781 [CTD_Seabird] Loaded 2018-02-01T17:22:09.781Z,1517505729.781 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-02-01T17:22:09.782Z,1517505729.782 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409664E0 2018-02-01T17:22:09.782Z,1517505729.782 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6318 2018-02-01T17:22:09.797Z,1517505729.797 [PAR_Licor] Loaded 2018-02-01T17:22:09.797Z,1517505729.797 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-02-01T17:22:09.844Z,1517505729.844 [WetLabsBB2FL] Loaded 2018-02-01T17:22:09.845Z,1517505729.845 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-02-01T17:22:09.846Z,1517505729.846 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409964E0 2018-02-01T17:22:09.846Z,1517505729.846 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6319 2018-02-01T17:22:09.847Z,1517505729.847 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-02-01T17:22:09.847Z,1517505729.847 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-02-01T17:22:09.912Z,1517505729.912 [VerticalControl](DEBUG): Construct VerticalControl. 2018-02-01T17:22:09.995Z,1517505729.995 [VerticalControl] Loaded 2018-02-01T17:22:09.995Z,1517505729.995 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-02-01T17:22:09.996Z,1517505729.996 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-02-01T17:22:10.051Z,1517505730.051 [HorizontalControl] Loaded 2018-02-01T17:22:10.051Z,1517505730.051 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-02-01T17:22:10.052Z,1517505730.052 [SpeedControl](DEBUG): Construct SpeedControl. 2018-02-01T17:22:10.053Z,1517505730.053 [SpeedControl] Loaded 2018-02-01T17:22:10.054Z,1517505730.054 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-02-01T17:22:10.054Z,1517505730.054 [LoopControl](DEBUG): Construct LoopControl. 2018-02-01T17:22:10.055Z,1517505730.055 [LoopControl] Loaded 2018-02-01T17:22:10.055Z,1517505730.055 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-02-01T17:22:10.056Z,1517505730.056 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-02-01T17:22:10.056Z,1517505730.056 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-02-01T17:22:10.080Z,1517505730.080 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-02-01T17:22:10.080Z,1517505730.080 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-02-01T17:22:10.310Z,1517505730.310 [DeadReckonUsingSpeedCalculator] Loaded 2018-02-01T17:22:10.311Z,1517505730.311 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-02-01T17:22:10.413Z,1517505730.413 [DeadReckonWithRespectToSeafloor] Loaded 2018-02-01T17:22:10.413Z,1517505730.413 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2018-02-01T17:22:10.429Z,1517505730.429 [NavChart] Loaded 2018-02-01T17:22:10.429Z,1517505730.429 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-02-01T17:22:10.433Z,1517505730.433 [UniversalFixResidualReporter] Loaded 2018-02-01T17:22:10.433Z,1517505730.433 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-02-01T17:22:10.434Z,1517505730.434 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-02-01T17:22:10.437Z,1517505730.437 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-02-01T17:22:10.438Z,1517505730.438 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-02-01T17:22:10.444Z,1517505730.444 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-02-01T17:22:10.446Z,1517505730.446 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A214E0 2018-02-01T17:22:10.446Z,1517505730.446 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6320 2018-02-01T17:22:10.451Z,1517505730.451 [Supervisor](INFO): Main Thread ID is 6079 2018-02-01T17:22:10.451Z,1517505730.451 [Supervisor](DEBUG): Running supervisor. 2018-02-01T17:22:10.451Z,1517505730.451 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6321 2018-02-01T17:22:10.454Z,1517505730.454 [controlThread ThreadHandler](INFO): Handler Thread ID is 6322 2018-02-01T17:22:10.454Z,1517505730.454 [controlThread](DEBUG): Initializing ControlThread 2018-02-01T17:22:10.455Z,1517505730.455 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-02-01T17:22:10.456Z,1517505730.456 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-02-01T17:22:10.456Z,1517505730.456 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-02-01T17:22:10.456Z,1517505730.456 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-02-01T17:22:10.457Z,1517505730.457 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-02-01T17:22:10.458Z,1517505730.458 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-02-01T17:22:10.463Z,1517505730.463 [SBIT](INFO): Initialize SBIT Component. 2018-02-01T17:22:10.464Z,1517505730.464 [SBIT](IMPORTANT): git: 2018-01-24-12-g35243d8 2018-02-01T17:22:10.464Z,1517505730.464 [SBIT](INFO): git hash: 35243d8075f2fa9bd3aa575030d1586c25df9f26 2018-02-01T17:22:10.464Z,1517505730.464 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-02-01T17:22:10.465Z,1517505730.465 [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-02-01T17:22:10.466Z,1517505730.466 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2018-02-01T17:22:10.467Z,1517505730.467 [IBIT](INFO): Initialize IBIT Component. 2018-02-01T17:22:10.467Z,1517505730.467 [CBIT](DEBUG): Initialize CBIT Component. 2018-02-01T17:22:10.468Z,1517505730.468 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-02-01T17:22:10.469Z,1517505730.469 [logger ThreadHandler](INFO): Handler Thread ID is 6323 2018-02-01T17:22:10.485Z,1517505730.485 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6324 2018-02-01T17:22:10.491Z,1517505730.491 [Radio_Surface](INFO): Powering up 2018-02-01T17:22:10.497Z,1517505730.497 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6325 2018-02-01T17:22:10.498Z,1517505730.498 [CTD_NeilBrown](INFO): Powering down 2018-02-01T17:22:10.521Z,1517505730.521 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6326 2018-02-01T17:22:10.522Z,1517505730.522 [CTD_Seabird](INFO): Initializing 2018-02-01T17:22:10.522Z,1517505730.522 [CTD_Seabird](INFO): Checking LCM 2018-02-01T17:22:10.531Z,1517505730.531 [CTD_Seabird](INFO): LCM OK 2018-02-01T17:22:10.531Z,1517505730.531 [CTD_Seabird](INFO): Powering up 2018-02-01T17:22:10.541Z,1517505730.541 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6327 2018-02-01T17:22:10.542Z,1517505730.542 [WetLabsBB2FL](INFO): Powering down 2018-02-01T17:22:10.565Z,1517505730.565 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6328 2018-02-01T17:22:10.568Z,1517505730.568 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-02-01T17:22:10.569Z,1517505730.569 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-02-01T17:22:10.569Z,1517505730.569 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-02-01T17:22:10.569Z,1517505730.569 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-02-01T17:22:10.569Z,1517505730.569 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-02-01T17:22:10.570Z,1517505730.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-02-01T17:22:10.570Z,1517505730.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-02-01T17:22:10.570Z,1517505730.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-02-01T17:22:10.570Z,1517505730.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-02-01T17:22:10.570Z,1517505730.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-02-01T17:22:10.571Z,1517505730.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-02-01T17:22:10.571Z,1517505730.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-02-01T17:22:10.571Z,1517505730.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-02-01T17:22:10.571Z,1517505730.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-02-01T17:22:10.571Z,1517505730.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-02-01T17:22:10.572Z,1517505730.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-02-01T17:22:10.585Z,1517505730.585 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-02-01T17:22:10.587Z,1517505730.587 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-02-01T17:22:10.587Z,1517505730.587 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-02-01T17:22:10.588Z,1517505730.588 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-02-01T17:22:10.588Z,1517505730.588 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-02-01T17:22:10.593Z,1517505730.593 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:22:10.594Z,1517505730.594 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:22:10.594Z,1517505730.594 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-02-01T17:22:10.595Z,1517505730.595 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:22:10.595Z,1517505730.595 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:22:10.595Z,1517505730.595 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-02-01T17:22:10.596Z,1517505730.596 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-02-01T17:22:10.605Z,1517505730.605 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-02-01T17:22:10.631Z,1517505730.631 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-02-01T17:22:10.648Z,1517505730.648 [MissionManager](DEBUG): 2018-02-01T17:22:10.669Z,1517505730.669 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-02-01T17:22:10.724Z,1517505730.724 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-02-01T17:22:10.742Z,1517505730.742 [Default:A.Wait](DEBUG): Construct Wait. 2018-02-01T17:22:10.743Z,1517505730.743 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-02-01T17:22:10.772Z,1517505730.772 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-02-01T17:22:10.775Z,1517505730.775 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-02-01T17:22:10.796Z,1517505730.796 [Default:E.Execute](DEBUG): Construct Execute. 2018-02-01T17:22:10.800Z,1517505730.800 [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-02-01T17:22:10.808Z,1517505730.808 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-02-01T17:22:10.825Z,1517505730.825 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-02-01T17:22:10.838Z,1517505730.838 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2018-02-01T17:22:11.093Z,1517505731.093 [CANONSampler](INFO): Powering down 2018-02-01T17:22:11.174Z,1517505731.174 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-02-01T17:22:11.263Z,1517505731.263 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:11.289Z,1517505731.289 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:11.401Z,1517505731.401 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-02-01T17:22:11.409Z,1517505731.409 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-02-01T17:22:11.423Z,1517505731.423 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-02-01T17:22:11.429Z,1517505731.429 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-02-01T17:22:11.451Z,1517505731.451 [MassServo](DEBUG): Initializing EZServoServo. 2018-02-01T17:22:11.457Z,1517505731.457 [MassServo](DEBUG): Initializing MassServo. 2018-02-01T17:22:11.467Z,1517505731.467 [RudderServo](DEBUG): Initializing EZServoServo. 2018-02-01T17:22:11.473Z,1517505731.473 [RudderServo](DEBUG): Initializing RudderServo. 2018-02-01T17:22:11.479Z,1517505731.479 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-02-01T17:22:11.485Z,1517505731.485 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-02-01T17:22:11.703Z,1517505731.703 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-02-01T17:22:11.703Z,1517505731.703 [DropWeight] Hardware Fault, FailCount= 1 2018-02-01T17:22:11.703Z,1517505731.703 [DropWeight](ERROR): Hardware Fault 2018-02-01T17:22:11.761Z,1517505731.761 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:11.762Z,1517505731.762 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:11.779Z,1517505731.779 [CommandLine](FAULT): Scheduling is paused 2018-02-01T17:22:11.779Z,1517505731.779 [CBIT](INFO): Critical error at 20180201T172211 2018-02-01T17:22:11.780Z,1517505731.780 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-02-01T17:22:11.782Z,1517505731.782 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-02-01T17:22:11.783Z,1517505731.783 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-02-01T17:22:12.015Z,1517505732.015 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:12.016Z,1517505732.016 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:12.337Z,1517505732.337 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-02-01T17:22:12.337Z,1517505732.337 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-02-01T17:22:12.338Z,1517505732.338 [BuoyancyServo] Communications Fault, FailCount= 1 2018-02-01T17:22:12.338Z,1517505732.338 [BuoyancyServo](ERROR): Communications Fault 2018-02-01T17:22:12.534Z,1517505732.534 [CBIT](INFO): Critical error at 20180201T172211 2018-02-01T17:22:12.537Z,1517505732.537 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-02-01T17:22:12.598Z,1517505732.598 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:12.599Z,1517505732.599 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:12.604Z,1517505732.604 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-02-01T17:22:12.609Z,1517505732.609 [BuoyancyServo](INFO): Powering down 2018-02-01T17:22:13.004Z,1517505733.004 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:13.005Z,1517505733.005 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:13.400Z,1517505733.400 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:13.401Z,1517505733.401 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:13.797Z,1517505733.797 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:13.805Z,1517505733.805 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:14.297Z,1517505734.297 [Aanderaa_O2](INFO): Powering down 2018-02-01T17:22:14.404Z,1517505734.404 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:14.405Z,1517505734.405 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-02-01T17:22:14.682Z,1517505734.682 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-02-01T17:22:14.682Z,1517505734.682 [BuoyancyServo] No Fault, FailCount= 1 2018-02-01T17:22:15.045Z,1517505735.045 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-02-01T17:22:15.166Z,1517505735.166 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-02-01T17:22:16.521Z,1517505736.521 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-02-01T17:22:16.648Z,1517505736.648 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-02-01T17:22:16.706Z,1517505736.706 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-02-01T17:22:17.151Z,1517505737.151 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-02-01T17:22:17.151Z,1517505737.151 [CTD_Seabird](INFO): Powering down 2018-02-01T17:22:33.853Z,1517505753.853 [SBIT](IMPORTANT): Beginning Startup BIT 2018-02-01T17:22:33.865Z,1517505753.865 [CBIT](IMPORTANT): Beginning ground fault scan 2018-02-01T17:22:37.486Z,1517505757.486 [NAL9602](INFO): Powering up NAL9602 2018-02-01T17:22:45.567Z,1517505765.567 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 4.657929 CHAN A1 (24V): -0.005404 CHAN A2 (12V): -0.002220 CHAN A3 (5V): -0.001498 CHAN B0 (3.3V): 4.766929 CHAN B1 (3.15aV): -0.000096 CHAN B2 (3.15bV): -0.000092 CHAN B3 (GND): 0.000403 OPEN: -0.000601 Full Scale Calc: 4.765 mA, -1.589 mA 2018-02-01T17:22:48.686Z,1517505768.686 [NAL9602](INFO): NAL9602 initialized 2018-02-01T17:23:27.881Z,1517505807.881 [SBIT](IMPORTANT): SBIT PASSED 2018-02-01T17:23:28.294Z,1517505808.294 [MissionManager](IMPORTANT): Started mission Startup 2018-02-01T17:23:28.294Z,1517505808.294 [Startup] Running Loop=1 2018-02-01T17:23:28.295Z,1517505808.295 [Startup](DEBUG): Aggregate::initialize Startup 2018-02-01T17:23:28.295Z,1517505808.295 [Startup:A.GoToSurface] Running Loop=1 2018-02-01T17:23:28.295Z,1517505808.295 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-02-01T17:23:28.295Z,1517505808.295 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-02-01T17:23:28.296Z,1517505808.296 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-02-01T17:23:28.296Z,1517505808.296 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-02-01T17:23:28.301Z,1517505808.301 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2018-02-01T17:23:28.301Z,1517505808.301 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-02-01T17:23:28.311Z,1517505808.311 [Startup:StartupSatComms] Running Loop=1 2018-02-01T17:23:28.311Z,1517505808.311 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-02-01T17:23:28.311Z,1517505808.311 [Startup:StartupSatComms:A] Running Loop=1 2018-02-01T17:23:28.711Z,1517505808.711 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-02-01T17:24:28.669Z,1517505868.669 [Startup:StartupSatComms:A](INFO): Timed out from 2018-02-01T17:23:28.3Z 2018-02-01T17:24:28.669Z,1517505868.669 [Startup:StartupSatComms:A] Stopped 2018-02-01T17:24:28.669Z,1517505868.669 [Startup:StartupSatComms:B] Running Loop=1 2018-02-01T17:24:29.101Z,1517505869.101 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-02-01T17:25:10.679Z,1517505910.679 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:25:10.679Z,1517505910.679 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-02-01T17:25:10.679Z,1517505910.679 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-02-01T17:25:10.681Z,1517505910.681 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:25:10.681Z,1517505910.681 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-02-01T17:25:10.682Z,1517505910.682 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-02-01T17:25:10.693Z,1517505910.693 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-02-01T17:25:10.693Z,1517505910.693 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-02-01T17:25:11.081Z,1517505911.081 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-02-01T17:25:11.081Z,1517505911.081 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-02-01T17:25:11.082Z,1517505911.082 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-02-01T17:25:11.082Z,1517505911.082 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-02-01T17:25:11.494Z,1517505911.494 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-02-01T17:25:11.495Z,1517505911.495 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:25:11.495Z,1517505911.495 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:25:11.496Z,1517505911.496 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-02-01T17:25:11.496Z,1517505911.496 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:25:11.496Z,1517505911.496 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:25:15.504Z,1517505915.504 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003281 2018-02-01T17:25:25.042Z,1517505925.042 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180201T170743/Courier0008.lzma 2018-02-01T17:25:25.830Z,1517505925.830 [DataOverHttps](INFO): Moved sent file to Logs/20180201T170743/Courier0008.lzma.bak 2018-02-01T17:25:25.830Z,1517505925.830 [DataOverHttps](INFO): SBD MOMSN=7781328 2018-02-01T17:25:29.869Z,1517505929.869 [Startup:StartupSatComms:B](INFO): Timed out from 2018-02-01T17:24:28.7Z 2018-02-01T17:25:29.869Z,1517505929.869 [Startup:StartupSatComms:B] Stopped 2018-02-01T17:25:29.869Z,1517505929.869 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-02-01T17:25:29.869Z,1517505929.869 [Startup:StartupSatComms] Stopped 2018-02-01T17:25:29.869Z,1517505929.869 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-02-01T17:25:29.870Z,1517505929.870 [Startup](INFO): Completed Startup 2018-02-01T17:25:29.870Z,1517505929.870 [MissionManager](INFO): Startup is completed. 2018-02-01T17:25:29.870Z,1517505929.870 [MissionManager](INFO): Uninitializing Mission Startup 2018-02-01T17:25:29.870Z,1517505929.870 [Startup] Stopped 2018-02-01T17:25:29.871Z,1517505929.871 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-02-01T17:25:29.871Z,1517505929.871 [Startup:A.GoToSurface] Stopped 2018-02-01T17:25:29.871Z,1517505929.871 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-02-01T17:25:30.004Z,1517505930.004 [MissionManager](IMPORTANT): Started mission Default 2018-02-01T17:25:30.004Z,1517505930.004 [Default] Running Loop=1 2018-02-01T17:25:30.004Z,1517505930.004 [Default](DEBUG): Aggregate::initialize Default 2018-02-01T17:25:30.004Z,1517505930.004 [Default:B.GoToSurface] Running Loop=1 2018-02-01T17:25:30.004Z,1517505930.004 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-02-01T17:25:30.004Z,1517505930.004 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-02-01T17:25:30.009Z,1517505930.009 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-02-01T17:25:30.009Z,1517505930.009 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-02-01T17:25:30.010Z,1517505930.010 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2018-02-01T17:25:30.010Z,1517505930.010 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-02-01T17:25:30.010Z,1517505930.010 [Default:A.Wait] Running Loop=1 2018-02-01T17:25:30.010Z,1517505930.010 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-02-01T17:25:34.754Z,1517505934.754 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20180201T172201/Courier0000.lzma 2018-02-01T17:25:35.517Z,1517505935.517 [DataOverHttps](INFO): Moved sent file to Logs/20180201T172201/Courier0000.lzma.bak 2018-02-01T17:25:35.517Z,1517505935.517 [DataOverHttps](INFO): SBD MOMSN=7781330 2018-02-01T17:25:43.184Z,1517505943.184 [Default:A.Wait](INFO): Done Waiting. 2018-02-01T17:25:43.205Z,1517505943.205 [Default:A.Wait] Stopped 2018-02-01T17:25:43.205Z,1517505943.205 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-02-01T17:25:43.566Z,1517505943.566 [Default:CheckIn] Running Loop=1 2018-02-01T17:25:43.566Z,1517505943.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-02-01T17:25:43.567Z,1517505943.567 [Default:CheckIn:Read_GPS] Running Loop=1 2018-02-01T17:25:43.972Z,1517505943.972 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-02-01T17:27:52.022Z,1517506072.022 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-02-01T17:28:11.696Z,1517506091.696 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:28:11.696Z,1517506091.696 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-02-01T17:28:11.696Z,1517506091.696 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-02-01T17:28:11.702Z,1517506091.702 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:28:11.702Z,1517506091.702 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-02-01T17:28:11.702Z,1517506091.702 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-02-01T17:28:11.735Z,1517506091.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-02-01T17:28:11.735Z,1517506091.735 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-02-01T17:28:12.110Z,1517506092.110 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-02-01T17:28:12.111Z,1517506092.111 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-02-01T17:28:12.111Z,1517506092.111 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-02-01T17:28:12.111Z,1517506092.111 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-02-01T17:28:12.479Z,1517506092.479 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-02-01T17:28:12.480Z,1517506092.480 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:28:12.480Z,1517506092.480 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:28:12.481Z,1517506092.481 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-02-01T17:28:12.481Z,1517506092.481 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:28:12.481Z,1517506092.481 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:30:43.741Z,1517506243.741 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-02-01T17:25:43.6Z 2018-02-01T17:30:43.741Z,1517506243.741 [Default:CheckIn:Read_GPS] Stopped 2018-02-01T17:30:43.741Z,1517506243.741 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-02-01T17:30:44.136Z,1517506244.136 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-02-01T17:30:48.136Z,1517506248.136 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180201T172201/Courier0004.lzma 2018-02-01T17:30:48.937Z,1517506248.937 [DataOverHttps](INFO): Moved sent file to Logs/20180201T172201/Courier0004.lzma.bak 2018-02-01T17:30:48.938Z,1517506248.938 [DataOverHttps](INFO): SBD MOMSN=7781335 2018-02-01T17:30:57.888Z,1517506257.888 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20180201T170743/Express0009.lzma 2018-02-01T17:30:58.689Z,1517506258.689 [DataOverHttps](INFO): Moved sent file to Logs/20180201T170743/Express0009.lzma.bak 2018-02-01T17:30:58.690Z,1517506258.690 [DataOverHttps](INFO): SBD MOMSN=7781337 2018-02-01T17:31:12.444Z,1517506272.444 [DataOverHttps](INFO): Sending 606 bytes from file Logs/20180201T172201/Express0001.lzma 2018-02-01T17:31:12.571Z,1517506272.571 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:31:12.571Z,1517506272.571 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2018-02-01T17:31:12.571Z,1517506272.571 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-02-01T17:31:12.573Z,1517506272.573 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:31:12.573Z,1517506272.573 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2018-02-01T17:31:12.573Z,1517506272.573 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-02-01T17:31:12.585Z,1517506272.585 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-02-01T17:31:12.586Z,1517506272.586 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-02-01T17:31:12.908Z,1517506272.908 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-02-01T17:31:12.908Z,1517506272.908 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2018-02-01T17:31:12.929Z,1517506272.929 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-02-01T17:31:12.929Z,1517506272.929 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2018-02-01T17:31:13.241Z,1517506273.241 [DataOverHttps](INFO): Moved sent file to Logs/20180201T172201/Express0001.lzma.bak 2018-02-01T17:31:13.241Z,1517506273.241 [DataOverHttps](INFO): SBD MOMSN=7781348 2018-02-01T17:31:13.307Z,1517506273.307 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-02-01T17:31:13.308Z,1517506273.308 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:31:13.308Z,1517506273.308 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:31:13.309Z,1517506273.309 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-02-01T17:31:13.309Z,1517506273.309 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:31:13.309Z,1517506273.309 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:31:22.231Z,1517506282.231 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180201T172201/Express0005.lzma 2018-02-01T17:31:23.013Z,1517506283.013 [DataOverHttps](INFO): Moved sent file to Logs/20180201T172201/Express0005.lzma.bak 2018-02-01T17:31:23.014Z,1517506283.014 [DataOverHttps](INFO): SBD MOMSN=7781393 2018-02-01T17:31:23.577Z,1517506283.577 [Default:CheckIn:Read_Iridium] Stopped 2018-02-01T17:31:23.577Z,1517506283.577 [Default:CheckIn:C.Wait] Running Loop=1 2018-02-01T17:31:23.577Z,1517506283.577 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-02-01T17:32:10.771Z,1517506330.771 [CBIT](DEBUG): EFC running - data check-sum false 2018-02-01T17:32:50.062Z,1517506370.062 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-02-01T17:32:50.062Z,1517506370.062 [NAL9602] Data Fault, FailCount= 1 2018-02-01T17:32:50.062Z,1517506370.062 [NAL9602](ERROR): Data Fault 2018-02-01T17:32:50.123Z,1517506370.123 [CBIT](ERROR): Data Fault in component: NAL9602 2018-02-01T17:32:50.478Z,1517506370.478 [NAL9602](INFO): Powering down 2018-02-01T17:32:51.312Z,1517506371.312 [CBIT](INFO): Clearing failed state for component NAL9602 2018-02-01T17:32:51.312Z,1517506371.312 [NAL9602] No Fault, FailCount= 1 2018-02-01T17:33:20.862Z,1517506400.862 [NAL9602](INFO): Powering up NAL9602 2018-02-01T17:33:31.694Z,1517506411.694 [NAL9602](INFO): NAL9602 initialized 2018-02-01T17:34:13.335Z,1517506453.335 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:34:13.336Z,1517506453.336 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2018-02-01T17:34:13.336Z,1517506453.336 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-02-01T17:34:13.337Z,1517506453.337 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:34:13.338Z,1517506453.338 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2018-02-01T17:34:13.338Z,1517506453.338 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-02-01T17:34:13.349Z,1517506453.349 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-02-01T17:34:13.350Z,1517506453.350 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-02-01T17:34:13.738Z,1517506453.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-02-01T17:34:13.738Z,1517506453.738 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2018-02-01T17:34:13.739Z,1517506453.739 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-02-01T17:34:13.739Z,1517506453.739 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2018-02-01T17:34:14.135Z,1517506454.135 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-02-01T17:34:14.135Z,1517506454.135 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:34:14.135Z,1517506454.135 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:34:14.136Z,1517506454.136 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-02-01T17:34:14.136Z,1517506454.136 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-02-01T17:34:14.136Z,1517506454.136 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-02-01T17:36:23.913Z,1517506583.913 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-02-01T17:36:23.913Z,1517506583.913 [Default:CheckIn:C.Wait] Stopped 2018-02-01T17:36:23.913Z,1517506583.913 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-02-01T17:36:23.913Z,1517506583.913 [Default:CheckIn:D] Running Loop=1 2018-02-01T17:36:24.311Z,1517506584.311 [Default:CheckIn:D] Stopped 2018-02-01T17:36:24.311Z,1517506584.311 [Default:CheckIn:E] Running Loop=1 2018-02-01T17:36:24.703Z,1517506584.703 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.905089 min 2018-02-01T17:36:24.705Z,1517506584.705 [Default:CheckIn:E] Stopped 2018-02-01T17:36:24.705Z,1517506584.705 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-02-01T17:36:24.705Z,1517506584.705 [Default:CheckIn] Stopped 2018-02-01T17:36:24.706Z,1517506584.706 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-02-01T17:36:24.706Z,1517506584.706 [Default:CheckIn](INFO): Running loop #2 2018-02-01T17:36:24.706Z,1517506584.706 [Default:CheckIn] Running Loop=2 2018-02-01T17:36:24.706Z,1517506584.706 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-02-01T17:36:24.706Z,1517506584.706 [Default:CheckIn:Read_GPS] Running Loop=1 2018-02-01T17:37:11.883Z,1517506631.883 [CBIT](INFO): Clearing failed state for component DropWeight 2018-02-01T17:37:11.883Z,1517506631.883 [DropWeight] No Fault, FailCount= 1 2018-02-01T17:37:14.264Z,1517506634.264 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:37:14.264Z,1517506634.264 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2018-02-01T17:37:14.264Z,1517506634.264 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-02-01T17:37:14.266Z,1517506634.266 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-02-01T17:37:14.266Z,1517506634.266 [DeadReckonWithResp