2018-05-17T20:57:26.323Z,1526590646.323 [Supervisor](DEBUG): Initializing supervisor.
2018-05-17T20:57:26.326Z,1526590646.326 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-17T20:57:26.327Z,1526590646.327 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-05-17T20:57:26.327Z,1526590646.327 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-17T20:57:26.328Z,1526590646.328 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-17T20:57:26.328Z,1526590646.328 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-05-17T20:57:26.331Z,1526590646.331 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-17T20:57:26.343Z,1526590646.343 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-17T20:57:26.344Z,1526590646.344 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-17T20:57:26.344Z,1526590646.344 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-05-17T20:57:26.345Z,1526590646.345 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-17T20:57:26.346Z,1526590646.346 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-17T20:57:26.346Z,1526590646.346 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-05-17T20:57:26.348Z,1526590646.348 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-17T20:57:26.348Z,1526590646.348 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-17T20:57:26.352Z,1526590646.352 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-17T20:57:26.704Z,1526590646.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-17T20:57:26.705Z,1526590646.705 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-17T20:57:26.922Z,1526590646.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-17T20:57:26.923Z,1526590646.923 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-17T20:57:27.225Z,1526590647.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-17T20:57:27.227Z,1526590647.227 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-17T20:57:27.370Z,1526590647.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-17T20:57:27.371Z,1526590647.371 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-17T20:57:27.471Z,1526590647.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-17T20:57:27.471Z,1526590647.471 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-17T20:57:27.958Z,1526590647.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-17T20:57:27.960Z,1526590647.960 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-17T20:57:28.362Z,1526590648.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-17T20:57:28.364Z,1526590648.364 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-17T20:57:28.834Z,1526590648.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-17T20:57:28.836Z,1526590648.836 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-17T20:57:28.944Z,1526590648.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-17T20:57:28.945Z,1526590648.945 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-17T20:57:29.044Z,1526590649.044 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-17T20:57:29.045Z,1526590649.045 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-17T20:57:29.258Z,1526590649.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-17T20:57:29.258Z,1526590649.258 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-17T20:57:29.475Z,1526590649.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-17T20:57:29.476Z,1526590649.476 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-17T20:57:29.835Z,1526590649.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-17T20:57:29.836Z,1526590649.836 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-17T20:57:30.460Z,1526590650.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-17T20:57:30.461Z,1526590650.461 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-17T20:57:30.545Z,1526590650.545 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-05-17T20:57:30.548Z,1526590650.548 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-05-17T20:57:30.656Z,1526590650.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-05-17T20:57:30.750Z,1526590650.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-05-17T20:57:30.846Z,1526590650.846 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-05-17T20:57:30.934Z,1526590650.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-05-17T20:57:31.091Z,1526590651.091 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-05-17T20:57:31.277Z,1526590651.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-05-17T20:57:31.354Z,1526590651.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-05-17T20:57:31.587Z,1526590651.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-17T20:57:31.588Z,1526590651.588 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-05-17T20:57:31.672Z,1526590651.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-05-17T20:57:31.764Z,1526590651.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-05-17T20:57:31.867Z,1526590651.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-05-17T20:57:31.996Z,1526590651.996 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-05-17T20:57:32.003Z,1526590652.003 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-17T20:57:32.282Z,1526590652.282 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-17T20:57:32.283Z,1526590652.283 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-17T20:57:32.339Z,1526590652.339 [DepthRateCalculator] Loaded
2018-05-17T20:57:32.339Z,1526590652.339 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-17T20:57:32.345Z,1526590652.345 [PitchRateCalculator] Loaded
2018-05-17T20:57:32.345Z,1526590652.345 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-17T20:57:32.361Z,1526590652.361 [SpeedCalculator] Loaded
2018-05-17T20:57:32.361Z,1526590652.361 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-17T20:57:32.382Z,1526590652.382 [TempGradientCalculator] Loaded
2018-05-17T20:57:32.382Z,1526590652.382 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-17T20:57:32.398Z,1526590652.398 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-17T20:57:32.398Z,1526590652.398 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-17T20:57:32.404Z,1526590652.404 [YawRateCalculator] Loaded
2018-05-17T20:57:32.404Z,1526590652.404 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-17T20:57:32.444Z,1526590652.444 [ElevatorOffsetCalculator] Loaded
2018-05-17T20:57:32.444Z,1526590652.444 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-17T20:57:32.445Z,1526590652.445 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-17T20:57:32.445Z,1526590652.445 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-17T20:57:32.565Z,1526590652.565 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-17T20:57:32.565Z,1526590652.565 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-17T20:57:32.566Z,1526590652.566 [StratificationFrontDetector] Loaded
2018-05-17T20:57:32.566Z,1526590652.566 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-17T20:57:32.567Z,1526590652.567 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-17T20:57:32.567Z,1526590652.567 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-17T20:57:32.700Z,1526590652.700 [BuoyancyServo] Loaded
2018-05-17T20:57:32.700Z,1526590652.700 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-17T20:57:32.715Z,1526590652.715 [ElevatorServo] Loaded
2018-05-17T20:57:32.715Z,1526590652.715 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-17T20:57:32.730Z,1526590652.730 [MassServo] Loaded
2018-05-17T20:57:32.730Z,1526590652.730 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-17T20:57:32.745Z,1526590652.745 [RudderServo] Loaded
2018-05-17T20:57:32.745Z,1526590652.745 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-17T20:57:32.760Z,1526590652.760 [ThrusterServo] Loaded
2018-05-17T20:57:32.760Z,1526590652.760 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-17T20:57:32.760Z,1526590652.760 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-17T20:57:32.761Z,1526590652.761 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-17T20:57:32.785Z,1526590652.785 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-17T20:57:32.786Z,1526590652.786 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-17T20:57:32.912Z,1526590652.912 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-17T20:57:32.912Z,1526590652.912 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-17T20:57:33.468Z,1526590653.468 [DataOverHttps] Loaded
2018-05-17T20:57:33.468Z,1526590653.468 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-17T20:57:33.482Z,1526590653.482 [Depth_Keller] Loaded
2018-05-17T20:57:33.482Z,1526590653.482 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-17T20:57:33.487Z,1526590653.487 [DropWeight] Loaded
2018-05-17T20:57:33.487Z,1526590653.487 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-05-17T20:57:33.584Z,1526590653.584 [NAL9602] Loaded
2018-05-17T20:57:33.585Z,1526590653.585 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-17T20:57:33.600Z,1526590653.600 [Onboard] Loaded
2018-05-17T20:57:33.600Z,1526590653.600 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-17T20:57:33.610Z,1526590653.610 [Radio_Surface] Loaded
2018-05-17T20:57:33.611Z,1526590653.611 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-17T20:57:33.612Z,1526590653.612 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2018-05-17T20:57:33.612Z,1526590653.612 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 884
2018-05-17T20:57:33.765Z,1526590653.765 [PNI_TCM] Loaded
2018-05-17T20:57:33.766Z,1526590653.766 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-05-17T20:57:35.922Z,1526590655.922 [BPC1] Loaded
2018-05-17T20:57:35.922Z,1526590655.922 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-17T20:57:35.923Z,1526590655.923 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-17T20:57:35.924Z,1526590655.924 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-17T20:57:36.079Z,1526590656.079 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-17T20:57:36.100Z,1526590656.100 [SBIT] Loaded
2018-05-17T20:57:36.101Z,1526590656.101 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-17T20:57:36.101Z,1526590656.101 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-17T20:57:36.113Z,1526590656.113 [IBIT] Loaded
2018-05-17T20:57:36.113Z,1526590656.113 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-17T20:57:36.117Z,1526590656.117 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-17T20:57:36.266Z,1526590656.266 [CBIT] Loaded
2018-05-17T20:57:36.266Z,1526590656.266 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-17T20:57:36.267Z,1526590656.267 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-17T20:57:36.267Z,1526590656.267 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-17T20:57:36.451Z,1526590656.451 [Aanderaa_O2] Loaded
2018-05-17T20:57:36.452Z,1526590656.452 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-05-17T20:57:36.464Z,1526590656.464 [CANONSampler] Loaded
2018-05-17T20:57:36.464Z,1526590656.464 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2018-05-17T20:57:36.540Z,1526590656.540 [CTD_NeilBrown] Loaded
2018-05-17T20:57:36.540Z,1526590656.540 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-05-17T20:57:36.541Z,1526590656.541 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409444E0
2018-05-17T20:57:36.542Z,1526590656.542 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 885
2018-05-17T20:57:36.587Z,1526590656.587 [CTD_Seabird] Loaded
2018-05-17T20:57:36.588Z,1526590656.588 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-17T20:57:36.589Z,1526590656.589 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409744E0
2018-05-17T20:57:36.589Z,1526590656.589 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 886
2018-05-17T20:57:36.603Z,1526590656.603 [PAR_Licor] Loaded
2018-05-17T20:57:36.604Z,1526590656.604 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-17T20:57:36.652Z,1526590656.652 [WetLabsBB2FL] Loaded
2018-05-17T20:57:36.653Z,1526590656.653 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-05-17T20:57:36.654Z,1526590656.654 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0
2018-05-17T20:57:36.654Z,1526590656.654 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 887
2018-05-17T20:57:36.655Z,1526590656.655 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-17T20:57:36.655Z,1526590656.655 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-17T20:57:36.742Z,1526590656.742 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-17T20:57:36.826Z,1526590656.826 [VerticalControl] Loaded
2018-05-17T20:57:36.826Z,1526590656.826 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-17T20:57:36.827Z,1526590656.827 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-17T20:57:36.884Z,1526590656.884 [HorizontalControl] Loaded
2018-05-17T20:57:36.884Z,1526590656.884 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-17T20:57:36.885Z,1526590656.885 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-17T20:57:36.886Z,1526590656.886 [SpeedControl] Loaded
2018-05-17T20:57:36.886Z,1526590656.886 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-17T20:57:36.888Z,1526590656.888 [LoopControl](DEBUG): Construct LoopControl.
2018-05-17T20:57:36.888Z,1526590656.888 [LoopControl] Loaded
2018-05-17T20:57:36.889Z,1526590656.889 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-17T20:57:36.889Z,1526590656.889 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-17T20:57:36.890Z,1526590656.890 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-17T20:57:36.927Z,1526590656.927 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-17T20:57:36.927Z,1526590656.927 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-17T20:57:37.034Z,1526590657.034 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-17T20:57:37.034Z,1526590657.034 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-17T20:57:37.089Z,1526590657.089 [DeadReckonWithRespectToSeafloor] Loaded
2018-05-17T20:57:37.089Z,1526590657.089 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-05-17T20:57:37.106Z,1526590657.106 [NavChart] Loaded
2018-05-17T20:57:37.106Z,1526590657.106 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-17T20:57:37.110Z,1526590657.110 [UniversalFixResidualReporter] Loaded
2018-05-17T20:57:37.110Z,1526590657.110 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-17T20:57:37.111Z,1526590657.111 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-17T20:57:37.114Z,1526590657.114 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-17T20:57:37.115Z,1526590657.115 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-17T20:57:37.122Z,1526590657.122 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-17T20:57:37.123Z,1526590657.123 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A304E0
2018-05-17T20:57:37.123Z,1526590657.123 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 888
2018-05-17T20:57:37.128Z,1526590657.128 [Supervisor](INFO): Main Thread ID is 795
2018-05-17T20:57:37.128Z,1526590657.128 [Supervisor](DEBUG): Running supervisor.
2018-05-17T20:57:37.129Z,1526590657.129 [CommandLine ThreadHandler](INFO): Handler Thread ID is 889
2018-05-17T20:57:37.131Z,1526590657.131 [controlThread ThreadHandler](INFO): Handler Thread ID is 890
2018-05-17T20:57:37.132Z,1526590657.132 [controlThread](DEBUG): Initializing ControlThread
2018-05-17T20:57:37.132Z,1526590657.132 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-17T20:57:37.133Z,1526590657.133 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-17T20:57:37.133Z,1526590657.133 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-17T20:57:37.133Z,1526590657.133 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-17T20:57:37.134Z,1526590657.134 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-17T20:57:37.135Z,1526590657.135 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-17T20:57:37.135Z,1526590657.135 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-17T20:57:37.141Z,1526590657.141 [SBIT](INFO): Initialize SBIT Component.
2018-05-17T20:57:37.142Z,1526590657.142 [SBIT](IMPORTANT): git: 2018-05-17
2018-05-17T20:57:37.142Z,1526590657.142 [SBIT](INFO): git hash: 418d027d9554c4aed44cef59bd1b73332cce0e52
2018-05-17T20:57:37.142Z,1526590657.142 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-17T20:57:37.143Z,1526590657.143 [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-05-17T20:57:37.144Z,1526590657.144 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-05-17T20:57:37.145Z,1526590657.145 [IBIT](INFO): Initialize IBIT Component.
2018-05-17T20:57:37.146Z,1526590657.146 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-17T20:57:37.146Z,1526590657.146 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-05-17T20:57:37.147Z,1526590657.147 [logger ThreadHandler](INFO): Handler Thread ID is 891
2018-05-17T20:57:37.167Z,1526590657.167 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 892
2018-05-17T20:57:37.173Z,1526590657.173 [Radio_Surface](INFO): Powering up
2018-05-17T20:57:37.179Z,1526590657.179 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 893
2018-05-17T20:57:37.180Z,1526590657.180 [CTD_NeilBrown](INFO): Powering down
2018-05-17T20:57:37.204Z,1526590657.204 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 894
2018-05-17T20:57:37.207Z,1526590657.207 [CTD_Seabird](INFO): Initializing
2018-05-17T20:57:37.208Z,1526590657.208 [CTD_Seabird](INFO): Checking LCM
2018-05-17T20:57:37.255Z,1526590657.255 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-17T20:57:37.257Z,1526590657.257 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-17T20:57:37.258Z,1526590657.258 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-17T20:57:37.258Z,1526590657.258 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-17T20:57:37.258Z,1526590657.258 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-17T20:57:37.259Z,1526590657.259 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T20:57:37.260Z,1526590657.260 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T20:57:37.260Z,1526590657.260 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T20:57:37.261Z,1526590657.261 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T20:57:37.261Z,1526590657.261 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T20:57:37.261Z,1526590657.261 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-17T20:57:37.262Z,1526590657.262 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-17T20:57:37.263Z,1526590657.263 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-17T20:57:37.341Z,1526590657.341 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 895
2018-05-17T20:57:37.342Z,1526590657.342 [WetLabsBB2FL](INFO): Powering down
2018-05-17T20:57:37.434Z,1526590657.434 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 896
2018-05-17T20:57:37.453Z,1526590657.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-17T20:57:37.454Z,1526590657.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-17T20:57:37.454Z,1526590657.454 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-17T20:57:37.454Z,1526590657.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-17T20:57:37.454Z,1526590657.454 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-17T20:57:37.454Z,1526590657.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-17T20:57:37.455Z,1526590657.455 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-17T20:57:37.455Z,1526590657.455 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-17T20:57:37.455Z,1526590657.455 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-17T20:57:37.455Z,1526590657.455 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-17T20:57:37.456Z,1526590657.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-17T20:57:37.456Z,1526590657.456 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-17T20:57:37.456Z,1526590657.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-17T20:57:37.456Z,1526590657.456 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-17T20:57:37.456Z,1526590657.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-17T20:57:37.457Z,1526590657.457 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-17T20:57:37.479Z,1526590657.479 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-17T20:57:37.576Z,1526590657.576 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2018-05-17T20:57:37.577Z,1526590657.577 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-17T20:57:37.664Z,1526590657.664 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-17T20:57:37.665Z,1526590657.665 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-17T20:57:37.679Z,1526590657.679 [CTD_Seabird](INFO): LCM OK
2018-05-17T20:57:37.679Z,1526590657.679 [CTD_Seabird](INFO): Powering up
2018-05-17T20:57:37.683Z,1526590657.683 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-17T20:57:37.706Z,1526590657.706 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-17T20:57:37.724Z,1526590657.724 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-17T20:57:37.730Z,1526590657.730 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-17T20:57:37.734Z,1526590657.734 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2018-05-17T20:57:37.751Z,1526590657.751 [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,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-17T20:57:37.759Z,1526590657.759 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-17T20:57:37.783Z,1526590657.783 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-05-17T20:57:38.030Z,1526590658.030 [CANONSampler](INFO): Powering down
2018-05-17T20:57:38.263Z,1526590658.263 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-05-17T20:57:38.377Z,1526590658.377 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:38.487Z,1526590658.487 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:38.599Z,1526590658.599 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-17T20:57:38.607Z,1526590658.607 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-17T20:57:38.621Z,1526590658.621 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-17T20:57:38.627Z,1526590658.627 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-17T20:57:38.641Z,1526590658.641 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-17T20:57:38.647Z,1526590658.647 [MassServo](DEBUG): Initializing MassServo.
2018-05-17T20:57:38.653Z,1526590658.653 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-17T20:57:38.659Z,1526590658.659 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-17T20:57:38.673Z,1526590658.673 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-17T20:57:38.679Z,1526590658.679 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-17T20:57:38.956Z,1526590658.956 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:38.957Z,1526590658.957 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:39.215Z,1526590659.215 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:39.216Z,1526590659.216 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:39.547Z,1526590659.547 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-05-17T20:57:39.547Z,1526590659.547 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-05-17T20:57:39.547Z,1526590659.547 [BuoyancyServo] Communications Fault, FailCount= 1
2018-05-17T20:57:39.547Z,1526590659.547 [BuoyancyServo](ERROR): Communications Fault
2018-05-17T20:57:39.755Z,1526590659.755 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-05-17T20:57:39.798Z,1526590659.798 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:39.798Z,1526590659.798 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:39.820Z,1526590659.820 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-17T20:57:39.820Z,1526590659.820 [BuoyancyServo](INFO): Powering down
2018-05-17T20:57:40.210Z,1526590660.210 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:40.211Z,1526590660.211 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:40.619Z,1526590660.619 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:40.627Z,1526590660.627 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:41.019Z,1526590661.019 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:41.020Z,1526590661.020 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:41.515Z,1526590661.515 [Aanderaa_O2](INFO): Powering down
2018-05-17T20:57:41.614Z,1526590661.614 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:41.619Z,1526590661.619 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-17T20:57:41.928Z,1526590661.928 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-05-17T20:57:41.928Z,1526590661.928 [BuoyancyServo] No Fault, FailCount= 1
2018-05-17T20:57:42.239Z,1526590662.239 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-17T20:57:42.360Z,1526590662.360 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-17T20:57:43.092Z,1526590663.092 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-17T20:57:43.262Z,1526590663.262 [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-05-17T20:57:43.423Z,1526590663.423 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-17T20:57:43.757Z,1526590663.757 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-17T20:57:43.757Z,1526590663.757 [CTD_Seabird](INFO): Powering down
2018-05-17T20:58:00.675Z,1526590680.675 [SBIT](IMPORTANT): Beginning Startup BIT
2018-05-17T20:58:00.679Z,1526590680.679 [CBIT](IMPORTANT): Beginning ground fault scan
2018-05-17T20:58:03.901Z,1526590683.901 [NAL9602](INFO): Powering up NAL9602
2018-05-17T20:58:11.588Z,1526590691.588 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.010165
CHAN A1 (24V): 4.657929
CHAN A2 (12V): -0.002526
CHAN A3 (5V): -0.000919
CHAN B0 (3.3V): 0.000091
CHAN B1 (3.15aV): -0.000120
CHAN B2 (3.15bV): -0.000326
CHAN B3 (GND): -0.000012
OPEN: -0.000676
Full Scale Calc: 4.765 mA, -1.589 mA
2018-05-17T20:58:15.085Z,1526590695.085 [NAL9602](INFO): NAL9602 initialized
2018-05-17T20:58:50.611Z,1526590730.611 [NAL9602](INFO): SBD MO Status=0, MOMSN=5628, MT Status=0, MTMSN=0
2018-05-17T20:58:50.611Z,1526590730.611 [NAL9602](INFO): No messages in MT queue
2018-05-17T20:58:54.678Z,1526590734.678 [SBIT](IMPORTANT): SBIT PASSED
2018-05-17T20:58:54.759Z,1526590734.759 [CommandLine](IMPORTANT): got command configSet list
2018-05-17T20:58:54.760Z,1526590734.760 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-05-17T20:58:54.760Z,1526590734.760 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-05-17T20:58:55.084Z,1526590735.084 [MissionManager](IMPORTANT): Started mission Startup
2018-05-17T20:58:55.084Z,1526590735.084 [Startup] Running Loop=1
2018-05-17T20:58:55.084Z,1526590735.084 [Startup](DEBUG): Aggregate::initialize Startup
2018-05-17T20:58:55.084Z,1526590735.084 [Startup:A.GoToSurface] Running Loop=1
2018-05-17T20:58:55.084Z,1526590735.084 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-17T20:58:55.085Z,1526590735.085 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-17T20:58:55.085Z,1526590735.085 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-17T20:58:55.086Z,1526590735.086 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-17T20:58:55.086Z,1526590735.086 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-17T20:58:55.086Z,1526590735.086 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-17T20:58:55.092Z,1526590735.092 [Startup:StartupSatComms] Running Loop=1
2018-05-17T20:58:55.093Z,1526590735.093 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-05-17T20:58:55.093Z,1526590735.093 [Startup:StartupSatComms:A] Running Loop=1
2018-05-17T20:58:55.476Z,1526590735.476 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-05-17T20:59:55.468Z,1526590795.468 [Startup:StartupSatComms:A](INFO): Timed out from 2018-05-17T20:58:55.1Z
2018-05-17T20:59:55.468Z,1526590795.468 [Startup:StartupSatComms:A] Stopped
2018-05-17T20:59:55.468Z,1526590795.468 [Startup:StartupSatComms:B] Running Loop=1
2018-05-17T20:59:55.858Z,1526590795.858 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-17T21:00:37.470Z,1526590837.470 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:00:37.470Z,1526590837.470 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-05-17T21:00:37.470Z,1526590837.470 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-17T21:00:37.473Z,1526590837.473 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:00:37.473Z,1526590837.473 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-17T21:00:37.473Z,1526590837.473 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:00:37.501Z,1526590837.501 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-17T21:00:37.502Z,1526590837.502 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:00:37.910Z,1526590837.910 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-17T21:00:37.910Z,1526590837.910 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-05-17T21:00:37.915Z,1526590837.915 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:00:37.915Z,1526590837.915 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-17T21:00:38.265Z,1526590838.265 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-17T21:00:38.266Z,1526590838.266 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:00:38.266Z,1526590838.266 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:00:38.266Z,1526590838.266 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:00:38.267Z,1526590838.267 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:00:38.267Z,1526590838.267 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:00:55.470Z,1526590855.470 [Startup:StartupSatComms:B](INFO): Timed out from 2018-05-17T20:59:55.5Z
2018-05-17T21:00:55.470Z,1526590855.470 [Startup:StartupSatComms:B] Stopped
2018-05-17T21:00:55.470Z,1526590855.470 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-05-17T21:00:55.470Z,1526590855.470 [Startup:StartupSatComms] Stopped
2018-05-17T21:00:55.470Z,1526590855.470 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-05-17T21:00:55.471Z,1526590855.471 [Startup](INFO): Completed Startup
2018-05-17T21:00:55.472Z,1526590855.472 [MissionManager](INFO): Startup is completed.
2018-05-17T21:00:55.472Z,1526590855.472 [MissionManager](INFO): Uninitializing Mission Startup
2018-05-17T21:00:55.472Z,1526590855.472 [Startup] Stopped
2018-05-17T21:00:55.472Z,1526590855.472 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-05-17T21:00:55.472Z,1526590855.472 [Startup:A.GoToSurface] Stopped
2018-05-17T21:00:55.472Z,1526590855.472 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-17T21:00:55.865Z,1526590855.865 [MissionManager](IMPORTANT): Started mission Default
2018-05-17T21:00:55.866Z,1526590855.866 [Default] Running Loop=1
2018-05-17T21:00:55.866Z,1526590855.866 [Default](DEBUG): Aggregate::initialize Default
2018-05-17T21:00:55.866Z,1526590855.866 [Default:B.GoToSurface] Running Loop=1
2018-05-17T21:00:55.866Z,1526590855.866 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-17T21:00:55.866Z,1526590855.866 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-17T21:00:55.867Z,1526590855.867 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-17T21:00:55.867Z,1526590855.867 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-17T21:00:55.867Z,1526590855.867 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-17T21:00:55.868Z,1526590855.868 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-17T21:00:55.868Z,1526590855.868 [Default:A.Wait] Running Loop=1
2018-05-17T21:00:55.868Z,1526590855.868 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-05-17T21:01:03.417Z,1526590863.417 [NAL9602](INFO): GPS fix at 20180517T210101: (36.802892, -121.787967)
2018-05-17T21:01:11.085Z,1526590871.085 [Default:A.Wait](INFO): Done Waiting.
2018-05-17T21:01:11.086Z,1526590871.086 [Default:A.Wait] Stopped
2018-05-17T21:01:11.086Z,1526590871.086 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-05-17T21:01:11.226Z,1526590871.226 [Default:CheckIn] Running Loop=1
2018-05-17T21:01:11.226Z,1526590871.226 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-17T21:01:11.227Z,1526590871.227 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-17T21:01:11.591Z,1526590871.591 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-17T21:01:11.937Z,1526590871.937 [DataOverHttps](IMPORTANT): SBD MTMSN=20180517T210111
2018-05-17T21:01:11.942Z,1526590871.942 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005209
2018-05-17T21:01:16.142Z,1526590876.142 [NAL9602](INFO): GPS fix at 20180517T210112: (36.802866, -121.787978)
2018-05-17T21:01:16.205Z,1526590876.205 [Default:CheckIn:Read_GPS] Stopped
2018-05-17T21:01:16.205Z,1526590876.205 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-17T21:01:16.260Z,1526590876.260 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" 3jjoz 1 2
2018-05-17T21:01:16.313Z,1526590876.313 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" 3jjoz 1 2.000000
2018-05-17T21:01:16.313Z,1526590876.313 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=3jjoz
2018-05-17T21:01:16.314Z,1526590876.314 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='3jjoz'): "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" ASAP
2018-05-17T21:01:16.386Z,1526590876.386 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-17T21:01:17.112Z,1526590877.112 [DataOverHttps](IMPORTANT): SBD MTMSN=20180517T210116
2018-05-17T21:01:21.734Z,1526590881.734 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180517T203542/Courier0010.lzma
2018-05-17T21:01:21.740Z,1526590881.740 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 30 meter;run " 3jjoz 2 2
2018-05-17T21:01:21.824Z,1526590881.824 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 30 meter;run " 3jjoz 2 2.000000
2018-05-17T21:01:21.825Z,1526590881.825 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=3jjoz
2018-05-17T21:01:21.826Z,1526590881.826 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='3jjoz'): "set profile_station.YoYoMaxDepth 20 meter;set profile_station.MaxDepth 30 meter;run " ASAP
2018-05-17T21:01:21.992Z,1526590881.992 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2018-05-17T21:01:21.992Z,1526590881.992 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2018-05-17T21:01:22.071Z,1526590882.071 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2018-05-17T21:01:22.074Z,1526590882.074 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2018-05-17T21:01:22.077Z,1526590882.077 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2018-05-17T21:01:22.080Z,1526590882.080 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2018-05-17T21:01:22.082Z,1526590882.082 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2018-05-17T21:01:22.169Z,1526590882.169 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2018-05-17T21:01:22.172Z,1526590882.172 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2018-05-17T21:01:22.191Z,1526590882.191 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2018-05-17T21:01:22.194Z,1526590882.194 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2018-05-17T21:01:22.197Z,1526590882.197 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2018-05-17T21:01:22.224Z,1526590882.224 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2018-05-17T21:01:22.227Z,1526590882.227 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2018-05-17T21:01:22.230Z,1526590882.230 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2018-05-17T21:01:22.253Z,1526590882.253 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2018-05-17T21:01:22.256Z,1526590882.256 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2018-05-17T21:01:22.258Z,1526590882.258 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2018-05-17T21:01:22.275Z,1526590882.275 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-05-17T21:01:22.592Z,1526590882.592 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2018-05-17T21:01:22.595Z,1526590882.595 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-05-17T21:01:22.598Z,1526590882.598 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-05-17T21:01:22.601Z,1526590882.601 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2018-05-17T21:01:22.606Z,1526590882.606 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2018-05-17T21:01:22.696Z,1526590882.696 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2018-05-17T21:01:22.705Z,1526590882.705 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2018-05-17T21:01:22.725Z,1526590882.725 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2018-05-17T21:01:22.752Z,1526590882.752 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2018-05-17T21:01:22.754Z,1526590882.754 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2018-05-17T21:01:22.769Z,1526590882.769 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-05-17T21:01:22.791Z,1526590882.791 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 1.000000 bool
2018-05-17T21:01:22.794Z,1526590882.794 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 1.000000 bool
2018-05-17T21:01:22.797Z,1526590882.797 [DataOverHttps](INFO): Moved sent file to Logs/20180517T203542/Courier0010.lzma.bak
2018-05-17T21:01:22.797Z,1526590882.797 [DataOverHttps](INFO): SBD MOMSN=8283425
2018-05-17T21:01:22.806Z,1526590882.806 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2018-05-17T21:01:22.836Z,1526590882.836 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-05-17T21:01:22.842Z,1526590882.842 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2018-05-17T21:01:22.861Z,1526590882.861 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2018-05-17T21:01:22.865Z,1526590882.865 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2018-05-17T21:01:22.884Z,1526590882.884 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2018-05-17T21:01:22.887Z,1526590882.887 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2018-05-17T21:01:22.890Z,1526590882.890 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2018-05-17T21:01:22.893Z,1526590882.893 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2018-05-17T21:01:22.901Z,1526590882.901 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2018-05-17T21:01:22.904Z,1526590882.904 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2018-05-17T21:01:22.931Z,1526590882.931 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2018-05-17T21:01:22.934Z,1526590882.934 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2018-05-17T21:01:22.937Z,1526590882.937 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2018-05-17T21:01:22.961Z,1526590882.961 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2018-05-17T21:01:22.965Z,1526590882.965 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2018-05-17T21:01:22.968Z,1526590882.968 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2018-05-17T21:01:22.971Z,1526590882.971 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2018-05-17T21:01:22.974Z,1526590882.974 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2018-05-17T21:01:22.997Z,1526590882.997 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2018-05-17T21:01:23.000Z,1526590883.000 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2018-05-17T21:01:23.020Z,1526590883.020 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan arcdeg
2018-05-17T21:01:23.023Z,1526590883.023 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2018-05-17T21:01:23.026Z,1526590883.026 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2018-05-17T21:01:23.029Z,1526590883.029 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2018-05-17T21:01:23.044Z,1526590883.044 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2018-05-17T21:01:23.068Z,1526590883.068 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-05-17T21:01:23.112Z,1526590883.112 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-05-17T21:01:23.134Z,1526590883.134 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-05-17T21:01:23.160Z,1526590883.160 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-05-17T21:01:23.186Z,1526590883.186 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2018-05-17T21:01:23.208Z,1526590883.208 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2018-05-17T21:01:23.211Z,1526590883.211 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-05-17T21:01:23.330Z,1526590883.330 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2018-05-17T21:01:23.333Z,1526590883.333 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2018-05-17T21:01:23.344Z,1526590883.344 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-05-17T21:01:23.347Z,1526590883.347 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2018-05-17T21:01:23.350Z,1526590883.350 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-05-17T21:01:23.353Z,1526590883.353 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2018-05-17T21:01:23.371Z,1526590883.371 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2018-05-17T21:01:23.387Z,1526590883.387 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-17T21:01:23.440Z,1526590883.440 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-05-17T21:01:23.489Z,1526590883.489 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2018-05-17T21:01:23.492Z,1526590883.492 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2018-05-17T21:01:23.519Z,1526590883.519 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-05-17T21:01:23.520Z,1526590883.520 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-05-17T21:01:23.540Z,1526590883.540 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-05-17T21:01:23.549Z,1526590883.549 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-05-17T21:01:23.569Z,1526590883.569 [profile_station:I.Pitch](DEBUG): Construct.
2018-05-17T21:01:23.584Z,1526590883.584 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2018-05-17T21:01:23.623Z,1526590883.623 [profile_station:K.SetSpeed](DEBUG): Construct.
2018-05-17T21:01:23.626Z,1526590883.626 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-05-17T21:01:23.664Z,1526590883.664 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-05-17T21:01:23.688Z,1526590883.688 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2018-05-17T21:01:23.714Z,1526590883.714 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2018-05-17T21:01:23.784Z,1526590883.784 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

    <Description>
        This mission yoyos in a circle around a specified location.
    </Description>

<!-- Mission-wide settings -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>4</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:minute/><Value>60</Value></DefineArg>

<!-- You probably need to change these. -->

    <DefineArg Name="Lat"><Description>
        The latitude of the center of the circle.
    </Description><Units:degree/><Value>36.806966</Value></DefineArg>

    <DefineArg Name="Lon"><Description>
        The longitude of the center of the circle.
    </Description><Units:degree/><Value>-121.824326</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius to circle at
    </Description><Units:meter/><Value>300</Value></DefineArg>

<!-- You probably do not need to change these. -->

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="YoYoPitch"><Description>
        Pitch (plus and minus) for yo-yo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

<!-- You are even less likely to need to change these. -->

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>100</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

<!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

<!-- Most missions should use the NeedComms aggregate. -->

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

<!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

<!-- Check for additional instructions first. -->

    <Call Id="StartingMission" RefId="NeedComms"/>

<!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
    </Guidance:Buoyancy>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

        <Assign><Parallel/><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

        <Guidance:Circle>
            <Sequence/>
            <Setting><Guidance:Circle.latitude/><Arg Name="Lat"/></Setting>
            <Setting><Guidance:Circle.longitude/><Arg Name="Lon"/></Setting>
            <Setting><Guidance:Circle.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
            <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
        </Guidance:Circle>

    </Aggregate>

</Mission>


2018-05-17T21:01:23.784Z,1526590883.784 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2018-05-17T21:01:35.358Z,1526590895.358 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 2.000000 hour
2018-05-17T21:01:35.371Z,1526590895.371 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 20.000000 minute
2018-05-17T21:01:35.372Z,1526590895.372 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.797001 degree
2018-05-17T21:01:35.373Z,1526590895.373 [CommandLine](IMPORTANT): got command set profile_station.Lon -121.847000 degree
2018-05-17T21:01:38.815Z,1526590898.815 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180517T205726/Courier0004.lzma
2018-05-17T21:01:39.604Z,1526590899.604 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0004.lzma.bak
2018-05-17T21:01:39.604Z,1526590899.604 [DataOverHttps](INFO): SBD MOMSN=8283428
2018-05-17T21:01:40.623Z,1526590900.623 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 20.000000 meter
2018-05-17T21:01:40.624Z,1526590900.624 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 30.000000 meter
2018-05-17T21:01:40.624Z,1526590900.624 [CommandLine](IMPORTANT): got command run 
2018-05-17T21:01:40.627Z,1526590900.627 [CommandLine](IMPORTANT): Running 
2018-05-17T21:01:43.880Z,1526590903.880 [Default] Stopped
2018-05-17T21:01:43.880Z,1526590903.880 [Default](DEBUG): Aggregate::uninitialize Default
2018-05-17T21:01:43.880Z,1526590903.880 [Default:B.GoToSurface] Stopped
2018-05-17T21:01:43.881Z,1526590903.881 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-17T21:01:43.881Z,1526590903.881 [Default:CheckIn] Stopped
2018-05-17T21:01:43.881Z,1526590903.881 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-17T21:01:43.881Z,1526590903.881 [Default:CheckIn:Read_Iridium] Stopped
2018-05-17T21:01:43.881Z,1526590903.881 [MissionManager](IMPORTANT): Started mission profile_station
2018-05-17T21:01:43.881Z,1526590903.881 [profile_station] Running Loop=1
2018-05-17T21:01:43.881Z,1526590903.881 [profile_station](DEBUG): Aggregate::initialize profile_station
2018-05-17T21:01:43.881Z,1526590903.881 [profile_station:Science] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:A] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:B] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:C] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:D] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:E] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:F] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:G] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:Read_Oil] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:PeakDetectChl] Running Loop=1
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2018-05-17T21:01:43.882Z,1526590903.882 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-05-17T21:01:43.923Z,1526590903.923 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-17T21:01:43.925Z,1526590903.925 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2018-05-17T21:01:43.925Z,1526590903.925 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2018-05-17T21:01:43.925Z,1526590903.925 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-05-17T21:01:43.925Z,1526590903.925 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-05-17T21:01:43.925Z,1526590903.925 [profile_station:Science:PeakDetectNO3] Running Loop=1
2018-05-17T21:01:43.925Z,1526590903.925 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2018-05-17T21:01:43.926Z,1526590903.926 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-05-17T21:01:43.926Z,1526590903.926 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-17T21:01:43.928Z,1526590903.928 [profile_station:Science:PeakDetectOil] Running Loop=1
2018-05-17T21:01:43.928Z,1526590903.928 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2018-05-17T21:01:43.928Z,1526590903.928 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-05-17T21:01:43.928Z,1526590903.928 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-17T21:01:43.930Z,1526590903.930 [profile_station:StandardEnvelopes] Running Loop=1
2018-05-17T21:01:43.930Z,1526590903.930 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2018-05-17T21:01:43.930Z,1526590903.930 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-05-17T21:01:43.930Z,1526590903.930 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-05-17T21:01:43.930Z,1526590903.930 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-05-17T21:01:43.930Z,1526590903.930 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-05-17T21:01:43.931Z,1526590903.931 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-05-17T21:01:43.931Z,1526590903.931 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-05-17T21:01:43.931Z,1526590903.931 [profile_station:I.Pitch] Running Loop=1
2018-05-17T21:01:43.931Z,1526590903.931 [profile_station:I.Pitch](DEBUG): Initialize.
2018-05-17T21:01:43.931Z,1526590903.931 [profile_station:J.Buoyancy] Running Loop=1
2018-05-17T21:01:43.931Z,1526590903.931 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-05-17T21:01:43.931Z,1526590903.931 [profile_station:K.SetSpeed] Running Loop=1
2018-05-17T21:01:43.931Z,1526590903.931 [profile_station:K.SetSpeed](DEBUG): Initialize.
2018-05-17T21:01:43.932Z,1526590903.931 [profile_station:L.DepthEnvelope] Running Loop=1
2018-05-17T21:01:43.932Z,1526590903.932 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-05-17T21:01:43.932Z,1526590903.932 [profile_station:M.AltitudeEnvelope] Running Loop=1
2018-05-17T21:01:43.932Z,1526590903.932 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-05-17T21:01:43.932Z,1526590903.932 [profile_station:N.YoYo] Running Loop=1
2018-05-17T21:01:43.932Z,1526590903.932 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2018-05-17T21:01:43.933Z,1526590903.933 [profile_station:C] Running Loop=1
2018-05-17T21:01:43.933Z,1526590903.933 [profile_station:N.YoYo] Running Loop=1
2018-05-17T21:01:43.938Z,1526590903.938 [profile_station:M.AltitudeEnvelope] Running Loop=1
2018-05-17T21:01:43.956Z,1526590903.956 [profile_station:L.DepthEnvelope] Running Loop=1
2018-05-17T21:01:43.961Z,1526590903.961 [profile_station:K.SetSpeed] Running Loop=1
2018-05-17T21:01:43.986Z,1526590903.986 [profile_station:J.Buoyancy] Running Loop=1
2018-05-17T21:01:43.991Z,1526590903.991 [profile_station:I.Pitch] Running Loop=1
2018-05-17T21:01:44.007Z,1526590904.007 [profile_station:StandardEnvelopes] Running Loop=1
2018-05-17T21:01:44.007Z,1526590904.007 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-05-17T21:01:44.032Z,1526590904.032 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-05-17T21:01:44.036Z,1526590904.036 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-05-17T21:01:44.059Z,1526590904.059 [profile_station:C] Stopped
2018-05-17T21:01:44.059Z,1526590904.059 [profile_station:E] Running Loop=1
2018-05-17T21:01:44.059Z,1526590904.059 [profile_station:Science] Running Loop=1
2018-05-17T21:01:44.062Z,1526590904.062 [profile_station:Science:PeakDetectOil] Stopped
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:PeakDetectNO3] Stopped
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:HighestChlPeakReport] Stopped
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2018-05-17T21:01:44.063Z,1526590904.063 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-05-17T21:01:44.064Z,1526590904.064 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-05-17T21:01:44.064Z,1526590904.064 [profile_station:Science:PeakDetectChl] Running Loop=1
2018-05-17T21:01:44.068Z,1526590904.068 [profile_station:Science:Read_Oil] Running Loop=1
2018-05-17T21:01:44.069Z,1526590904.069 [profile_station:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water
2018-05-17T21:01:44.070Z,1526590904.070 [profile_station:Science:G] Running Loop=1
2018-05-17T21:01:44.071Z,1526590904.071 [profile_station:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-05-17T21:01:44.072Z,1526590904.072 [profile_station:Science:F] Stopped
2018-05-17T21:01:44.073Z,1526590904.073 [profile_station:Science:E] Stopped
2018-05-17T21:01:44.073Z,1526590904.073 [profile_station:Science:D] Running Loop=1
2018-05-17T21:01:44.073Z,1526590904.073 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2018-05-17T21:01:44.074Z,1526590904.074 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2018-05-17T21:01:44.075Z,1526590904.075 [profile_station:Science:C] Running Loop=1
2018-05-17T21:01:44.076Z,1526590904.076 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2018-05-17T21:01:44.077Z,1526590904.077 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2018-05-17T21:01:44.078Z,1526590904.078 [profile_station:Science:B] Stopped
2018-05-17T21:01:44.078Z,1526590904.078 [profile_station:Science:A] Running Loop=1
2018-05-17T21:01:44.079Z,1526590904.079 [profile_station:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2018-05-17T21:01:44.226Z,1526590904.226 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2018-05-17T21:01:44.228Z,1526590904.228 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2018-05-17T21:01:44.228Z,1526590904.228 [WetLabsBB2FL](INFO): Powering up
2018-05-17T21:01:44.235Z,1526590904.235 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-05-17T21:01:44.284Z,1526590904.284 [profile_station:E] Stopped
2018-05-17T21:01:44.284Z,1526590904.284 [profile_station:F] Running Loop=1
2018-05-17T21:01:44.316Z,1526590904.316 [CTD_Seabird](INFO): Initializing
2018-05-17T21:01:44.316Z,1526590904.316 [CTD_Seabird](INFO): Checking LCM
2018-05-17T21:01:44.316Z,1526590904.316 [CTD_Seabird](INFO): LCM OK
2018-05-17T21:01:44.316Z,1526590904.316 [CTD_Seabird](INFO): Powering up
2018-05-17T21:01:44.371Z,1526590904.371 [CTD_NeilBrown](DEBUG): serial timeout
2018-05-17T21:01:44.663Z,1526590904.663 [profile_station:F] Stopped
2018-05-17T21:01:44.663Z,1526590904.663 [profile_station:G] Running Loop=1
2018-05-17T21:01:44.783Z,1526590904.783 [CTD_NeilBrown](DEBUG): serial timeout
2018-05-17T21:01:44.999Z,1526590904.999 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2018-05-17T21:01:44.999Z,1526590904.999 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2018-05-17T21:01:44.999Z,1526590904.999 [ThrusterServo] Communications Fault, FailCount= 1
2018-05-17T21:01:44.999Z,1526590904.999 [ThrusterServo](ERROR): Communications Fault
2018-05-17T21:01:45.003Z,1526590905.003 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-05-17T21:01:45.120Z,1526590905.120 [profile_station:G] Stopped
2018-05-17T21:01:45.120Z,1526590905.120 [profile_station:StartingMission] Running Loop=1
2018-05-17T21:01:45.120Z,1526590905.120 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2018-05-17T21:01:45.191Z,1526590905.191 [CTD_NeilBrown](DEBUG): serial timeout
2018-05-17T21:01:45.348Z,1526590905.348 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-17T21:01:45.348Z,1526590905.348 [ThrusterServo](INFO): Powering down
2018-05-17T21:01:45.498Z,1526590905.498 [profile_station:NeedComms] Running Loop=1
2018-05-17T21:01:45.498Z,1526590905.498 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2018-05-17T21:01:45.498Z,1526590905.498 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2018-05-17T21:01:45.498Z,1526590905.498 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-17T21:01:45.498Z,1526590905.498 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-05-17T21:01:45.503Z,1526590905.503 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-05-17T21:01:45.503Z,1526590905.503 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-05-17T21:01:45.504Z,1526590905.504 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-17T21:01:45.504Z,1526590905.504 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-17T21:01:45.504Z,1526590905.504 [profile_station:NeedComms:A] Running Loop=1
2018-05-17T21:01:45.514Z,1526590905.514 [profile_station:NeedComms:A](INFO): last time_fix was: 1526590872.000000 second since 1970/01/01T00:00:00Z 
2018-05-17T21:01:45.516Z,1526590905.516 [profile_station:NeedComms:A] Stopped
2018-05-17T21:01:45.603Z,1526590905.603 [CTD_NeilBrown](DEBUG): serial timeout
2018-05-17T21:01:45.712Z,1526590905.712 [CTD_NeilBrown](ERROR): Salinity reading out of range: 191.925095 psu
2018-05-17T21:01:45.722Z,1526590905.722 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:45.936Z,1526590905.936 [CTD_NeilBrown](ERROR): Salinity reading out of range: 171.260101 psu
2018-05-17T21:01:45.941Z,1526590905.941 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:46.049Z,1526590906.049 [profile_station:NeedComms:C] Running Loop=1
2018-05-17T21:01:46.144Z,1526590906.144 [CTD_NeilBrown](ERROR): Salinity reading out of range: 182.441696 psu
2018-05-17T21:01:46.149Z,1526590906.149 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:46.363Z,1526590906.363 [CTD_NeilBrown](ERROR): Salinity reading out of range: 194.131805 psu
2018-05-17T21:01:46.368Z,1526590906.368 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:46.434Z,1526590906.434 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-05-17T21:01:46.435Z,1526590906.435 [ThrusterServo] No Fault, FailCount= 1
2018-05-17T21:01:46.575Z,1526590906.575 [CTD_NeilBrown](ERROR): Salinity reading out of range: 168.498306 psu
2018-05-17T21:01:46.580Z,1526590906.580 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:46.780Z,1526590906.780 [CTD_NeilBrown](ERROR): Salinity reading out of range: 179.404800 psu
2018-05-17T21:01:46.784Z,1526590906.784 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:46.847Z,1526590906.847 [CTD_NeilBrown](DEBUG): serial timeout
2018-05-17T21:01:47.003Z,1526590907.003 [CTD_NeilBrown](ERROR): Salinity reading out of range: 168.954407 psu
2018-05-17T21:01:47.018Z,1526590907.018 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:47.212Z,1526590907.212 [CTD_NeilBrown](ERROR): Salinity reading out of range: 163.172104 psu
2018-05-17T21:01:47.216Z,1526590907.216 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:47.510Z,1526590907.510 [CTD_NeilBrown](ERROR): Salinity reading out of range: 171.483704 psu
2018-05-17T21:01:47.513Z,1526590907.513 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:47.575Z,1526590907.575 [CTD_NeilBrown](DEBUG): serial timeout
2018-05-17T21:01:47.728Z,1526590907.728 [CTD_NeilBrown](ERROR): Salinity reading out of range: 173.073502 psu
2018-05-17T21:01:47.733Z,1526590907.733 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:47.940Z,1526590907.940 [CTD_NeilBrown](ERROR): Salinity reading out of range: 132.535904 psu
2018-05-17T21:01:47.944Z,1526590907.944 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:48.152Z,1526590908.152 [CTD_NeilBrown](ERROR): Salinity reading out of range: 160.425293 psu
2018-05-17T21:01:48.156Z,1526590908.156 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:48.375Z,1526590908.375 [CTD_NeilBrown](ERROR): Salinity reading out of range: 103.265900 psu
2018-05-17T21:01:48.378Z,1526590908.378 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:48.587Z,1526590908.587 [CTD_NeilBrown](ERROR): Salinity reading out of range: 174.421707 psu
2018-05-17T21:01:48.592Z,1526590908.592 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:48.804Z,1526590908.804 [CTD_NeilBrown](ERROR): Salinity reading out of range: 181.876801 psu
2018-05-17T21:01:48.828Z,1526590908.828 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:49.011Z,1526590909.011 [CTD_NeilBrown](ERROR): Salinity reading out of range: 185.157196 psu
2018-05-17T21:01:49.016Z,1526590909.016 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:49.315Z,1526590909.315 [CTD_NeilBrown](ERROR): Salinity reading out of range: 198.744904 psu
2018-05-17T21:01:49.322Z,1526590909.322 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:49.535Z,1526590909.535 [CTD_NeilBrown](ERROR): Salinity reading out of range: 134.799805 psu
2018-05-17T21:01:49.541Z,1526590909.541 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:49.738Z,1526590909.738 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-17T21:01:49.782Z,1526590909.782 [CTD_NeilBrown](ERROR): Salinity reading out of range: 183.979507 psu
2018-05-17T21:01:49.795Z,1526590909.795 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:49.927Z,1526590909.927 [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-05-17T21:01:49.929Z,1526590909.929 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-17T21:01:49.955Z,1526590909.955 [CTD_NeilBrown](ERROR): Salinity reading out of range: 158.657303 psu
2018-05-17T21:01:49.976Z,1526590909.976 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:50.171Z,1526590910.171 [CTD_NeilBrown](ERROR): Salinity reading out of range: 203.632507 psu
2018-05-17T21:01:50.177Z,1526590910.177 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:50.384Z,1526590910.384 [CTD_NeilBrown](ERROR): Salinity reading out of range: 212.663406 psu
2018-05-17T21:01:50.388Z,1526590910.388 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:50.596Z,1526590910.596 [CTD_NeilBrown](ERROR): Salinity reading out of range: 177.229095 psu
2018-05-17T21:01:50.606Z,1526590910.606 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:50.695Z,1526590910.695 [CTD_NeilBrown](DEBUG): serial timeout
2018-05-17T21:01:50.814Z,1526590910.814 [CTD_NeilBrown](ERROR): Salinity reading out of range: 168.945999 psu
2018-05-17T21:01:50.821Z,1526590910.821 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:50.835Z,1526590910.835 [NAL9602](INFO): Powering down
2018-05-17T21:01:50.959Z,1526590910.959 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-17T21:01:51.111Z,1526590911.111 [CTD_NeilBrown](ERROR): Salinity reading out of range: 181.318100 psu
2018-05-17T21:01:51.118Z,1526590911.118 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:51.118Z,1526590911.118 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-17T21:01:51.124Z,1526590911.124 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-17T21:01:51.150Z,1526590911.150 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180517T205726/Courier0007.lzma
2018-05-17T21:01:51.172Z,1526590911.172 [NAL9602](INFO): Powering up
2018-05-17T21:01:51.335Z,1526590911.335 [CTD_NeilBrown](ERROR): Salinity reading out of range: 196.345001 psu
2018-05-17T21:01:51.342Z,1526590911.342 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:51.560Z,1526590911.560 [CTD_NeilBrown](ERROR): Salinity reading out of range: 180.454407 psu
2018-05-17T21:01:51.565Z,1526590911.565 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:51.760Z,1526590911.760 [CTD_NeilBrown](ERROR): Salinity reading out of range: 189.329697 psu
2018-05-17T21:01:51.766Z,1526590911.766 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:51.967Z,1526590911.967 [CTD_NeilBrown](ERROR): Salinity reading out of range: 163.971100 psu
2018-05-17T21:01:51.973Z,1526590911.973 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:52.071Z,1526590912.071 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-05-17T21:01:52.135Z,1526590912.135 [ThrusterServo](FAULT): Thruster failed to initialize
2018-05-17T21:01:52.135Z,1526590912.135 [ThrusterServo] Communications Fault, FailCount= 2
2018-05-17T21:01:52.135Z,1526590912.135 [ThrusterServo](ERROR): Communications Fault
2018-05-17T21:01:52.139Z,1526590912.139 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-05-17T21:01:52.149Z,1526590912.149 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0007.lzma.bak
2018-05-17T21:01:52.150Z,1526590912.150 [DataOverHttps](IMPORTANT): SBD MOMSN=8283433, MTMSN=20180517T210151
2018-05-17T21:01:52.184Z,1526590912.184 [CTD_NeilBrown](ERROR): Salinity reading out of range: 176.674500 psu
2018-05-17T21:01:52.188Z,1526590912.188 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:52.254Z,1526590912.254 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		229.085		78.453		18.602		31.463		31.463		40.170		8.707		439.6		644.1		143.5	



2018-05-17T21:01:52.255Z,1526590912.255 [Aanderaa_O2](DEBUG): Error parsing device response
2018-05-17T21:01:52.398Z,1526590912.398 [CTD_NeilBrown](ERROR): Salinity reading out of range: 206.153900 psu
2018-05-17T21:01:52.404Z,1526590912.404 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:52.441Z,1526590912.441 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-17T21:01:52.441Z,1526590912.441 [ThrusterServo](INFO): Powering down
2018-05-17T21:01:52.611Z,1526590912.611 [CTD_NeilBrown](ERROR): Salinity reading out of range: 182.617599 psu
2018-05-17T21:01:52.627Z,1526590912.627 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:52.915Z,1526590912.915 [CTD_NeilBrown](ERROR): Salinity reading out of range: 164.277496 psu
2018-05-17T21:01:52.922Z,1526590912.922 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:53.005Z,1526590913.005 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		229.246		78.507		18.602		31.455		31.455		40.159		8.704		439.5		643.3		143.6	



2018-05-17T21:01:53.005Z,1526590913.005 [Aanderaa_O2](DEBUG): Error parsing device response
2018-05-17T21:01:53.132Z,1526590913.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: 168.179504 psu
2018-05-17T21:01:53.136Z,1526590913.136 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:53.162Z,1526590913.162 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-05-17T21:01:53.163Z,1526590913.163 [ThrusterServo] No Fault, FailCount= 2
2018-05-17T21:01:53.340Z,1526590913.340 [CTD_NeilBrown](ERROR): Salinity reading out of range: 180.029099 psu
2018-05-17T21:01:53.356Z,1526590913.356 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:53.556Z,1526590913.556 [CTD_NeilBrown](ERROR): Salinity reading out of range: 297.786285 psu
2018-05-17T21:01:53.560Z,1526590913.560 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:53.777Z,1526590913.777 [CTD_NeilBrown](ERROR): Salinity reading out of range: 310.354614 psu
2018-05-17T21:01:53.780Z,1526590913.780 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:53.987Z,1526590913.987 [CTD_NeilBrown](ERROR): Salinity reading out of range: 180.284805 psu
2018-05-17T21:01:53.992Z,1526590913.992 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:54.199Z,1526590914.199 [CTD_NeilBrown](ERROR): Salinity reading out of range: 159.557602 psu
2018-05-17T21:01:54.205Z,1526590914.205 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:54.411Z,1526590914.411 [CTD_NeilBrown](ERROR): Salinity reading out of range: 166.109299 psu
2018-05-17T21:01:54.414Z,1526590914.414 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:54.716Z,1526590914.716 [CTD_NeilBrown](ERROR): Salinity reading out of range: 199.190994 psu
2018-05-17T21:01:54.720Z,1526590914.720 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:54.931Z,1526590914.931 [CTD_NeilBrown](ERROR): Salinity reading out of range: 200.607101 psu
2018-05-17T21:01:54.934Z,1526590914.934 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:55.151Z,1526590915.151 [CTD_NeilBrown](ERROR): Salinity reading out of range: 263.197510 psu
2018-05-17T21:01:55.156Z,1526590915.156 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:55.364Z,1526590915.364 [CTD_NeilBrown](ERROR): Salinity reading out of range: 330.526489 psu
2018-05-17T21:01:55.368Z,1526590915.368 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:55.575Z,1526590915.575 [CTD_NeilBrown](ERROR): Salinity reading out of range: 176.141602 psu
2018-05-17T21:01:55.581Z,1526590915.581 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:55.791Z,1526590915.791 [CTD_NeilBrown](ERROR): Salinity reading out of range: 168.162704 psu
2018-05-17T21:01:55.794Z,1526590915.794 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:56.003Z,1526590916.003 [CTD_NeilBrown](ERROR): Salinity reading out of range: 166.421799 psu
2018-05-17T21:01:56.017Z,1526590916.017 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:56.212Z,1526590916.212 [CTD_NeilBrown](ERROR): Salinity reading out of range: 172.549103 psu
2018-05-17T21:01:56.216Z,1526590916.216 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:56.519Z,1526590916.519 [CTD_NeilBrown](ERROR): Salinity reading out of range: 173.322098 psu
2018-05-17T21:01:56.526Z,1526590916.526 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:56.728Z,1526590916.728 [CTD_NeilBrown](ERROR): Salinity reading out of range: 169.797501 psu
2018-05-17T21:01:56.732Z,1526590916.732 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:56.944Z,1526590916.944 [CTD_NeilBrown](ERROR): Salinity reading out of range: 205.145706 psu
2018-05-17T21:01:56.948Z,1526590916.948 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:57.156Z,1526590917.156 [CTD_NeilBrown](ERROR): Salinity reading out of range: 181.579605 psu
2018-05-17T21:01:57.176Z,1526590917.176 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:57.180Z,1526590917.180 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		229.067		78.445		18.601		31.464		31.464		40.166		8.701		439.6		642.6		143.6	

2018-05-17T21:01:57.180Z,1526590917.180 [Aanderaa_O2](DEBUG): Error parsing device response
2018-05-17T21:01:57.181Z,1526590917.181 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		229.118		78.461		18.600		31.462		31.462		40.161		8.700		439.5		641.5		143.6	

2018-05-17T21:01:57.181Z,1526590917.181 [Aanderaa_O2](DEBUG): Error parsing device response
2018-05-17T21:01:57.261Z,1526590917.261 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-17T21:01:57.268Z,1526590917.268 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-17T21:01:57.296Z,1526590917.296 [DataOverHttps](INFO): Received command:stop
2018-05-17T21:01:57.368Z,1526590917.368 [CommandLine](IMPORTANT): got command stop
2018-05-17T21:01:57.368Z,1526590917.368 [CommandLine](IMPORTANT): Scheduling is paused
2018-05-17T21:01:57.368Z,1526590917.368 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2018-05-17T21:01:57.372Z,1526590917.372 [CTD_NeilBrown](ERROR): Salinity reading out of range: 181.414505 psu
2018-05-17T21:01:57.376Z,1526590917.376 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:57.413Z,1526590917.413 [MissionManager](INFO): MissionManager is completed.
2018-05-17T21:01:57.414Z,1526590917.414 [MissionManager](INFO): Uninitializing Mission profile_station
2018-05-17T21:01:57.414Z,1526590917.414 [profile_station] Stopped
2018-05-17T21:01:57.414Z,1526590917.414 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2018-05-17T21:01:57.419Z,1526590917.419 [profile_station:Science] Stopped
2018-05-17T21:01:57.420Z,1526590917.420 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2018-05-17T21:01:57.420Z,1526590917.420 [profile_station:Science:A] Stopped
2018-05-17T21:01:57.420Z,1526590917.420 [profile_station:Science:C] Stopped
2018-05-17T21:01:57.420Z,1526590917.420 [profile_station:Science:D] Stopped
2018-05-17T21:01:57.420Z,1526590917.420 [profile_station:Science:G] Stopped
2018-05-17T21:01:57.421Z,1526590917.421 [profile_station:Science:Read_Oil] Stopped
2018-05-17T21:01:57.421Z,1526590917.421 [profile_station:Science:PeakDetectChl] Stopped
2018-05-17T21:01:57.421Z,1526590917.421 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2018-05-17T21:01:57.421Z,1526590917.421 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-05-17T21:01:57.421Z,1526590917.421 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-05-17T21:01:57.422Z,1526590917.422 [profile_station:NeedComms] Stopped
2018-05-17T21:01:57.422Z,1526590917.422 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2018-05-17T21:01:57.422Z,1526590917.422 [profile_station:NeedComms:B.GoToSurface] Stopped
2018-05-17T21:01:57.422Z,1526590917.422 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-17T21:01:57.427Z,1526590917.427 [profile_station:NeedComms:C] Stopped
2018-05-17T21:01:57.427Z,1526590917.427 [profile_station:StandardEnvelopes] Stopped
2018-05-17T21:01:57.427Z,1526590917.427 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2018-05-17T21:01:57.427Z,1526590917.427 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-05-17T21:01:57.427Z,1526590917.427 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-05-17T21:01:57.427Z,1526590917.427 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-05-17T21:01:57.427Z,1526590917.427 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-05-17T21:01:57.427Z,1526590917.427 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:StartingMission] Stopped
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:I.Pitch] Stopped
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:J.Buoyancy] Stopped
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:K.SetSpeed] Stopped
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:L.DepthEnvelope] Stopped
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:M.AltitudeEnvelope] Stopped
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-05-17T21:01:57.428Z,1526590917.428 [profile_station:N.YoYo] Stopped
2018-05-17T21:01:57.429Z,1526590917.429 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2018-05-17T21:01:57.532Z,1526590917.532 [WetLabsBB2FL](INFO): Powering down
2018-05-17T21:01:57.584Z,1526590917.584 [CTD_NeilBrown](ERROR): Salinity reading out of range: 177.923294 psu
2018-05-17T21:01:57.589Z,1526590917.589 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-05-17T21:01:57.799Z,1526590917.799 [MissionManager](IMPORTANT): Started mission Default
2018-05-17T21:01:57.799Z,1526590917.799 [Default] Running Loop=1
2018-05-17T21:01:57.800Z,1526590917.800 [Default](DEBUG): Aggregate::initialize Default
2018-05-17T21:01:57.800Z,1526590917.800 [Default:B.GoToSurface] Running Loop=1
2018-05-17T21:01:57.800Z,1526590917.800 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-17T21:01:57.800Z,1526590917.800 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-17T21:01:57.800Z,1526590917.800 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-17T21:01:57.801Z,1526590917.801 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-17T21:01:57.801Z,1526590917.801 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-17T21:01:57.801Z,1526590917.801 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-17T21:01:57.802Z,1526590917.802 [Default:A.Wait] Running Loop=1
2018-05-17T21:01:57.802Z,1526590917.802 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-05-17T21:01:57.975Z,1526590917.975 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-17T21:01:57.975Z,1526590917.975 [CTD_Seabird](INFO): Powering down
2018-05-17T21:01:58.143Z,1526590918.143 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-05-17T21:01:58.207Z,1526590918.207 [ThrusterServo](FAULT): Thruster failed to initialize
2018-05-17T21:01:58.207Z,1526590918.207 [ThrusterServo] Communications Fault, FailCount= 3
2018-05-17T21:01:58.207Z,1526590918.207 [ThrusterServo](ERROR): Communications Fault
2018-05-17T21:01:58.211Z,1526590918.211 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-05-17T21:01:58.383Z,1526590918.383 [Aanderaa_O2](INFO): Powering down
2018-05-17T21:01:58.436Z,1526590918.436 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-17T21:01:58.436Z,1526590918.436 [ThrusterServo](INFO): Powering down
2018-05-17T21:01:59.282Z,1526590919.282 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-05-17T21:01:59.283Z,1526590919.283 [ThrusterServo] No Fault, FailCount= 3
2018-05-17T21:01:59.520Z,1526590919.520 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-17T21:01:59.527Z,1526590919.527 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-17T21:02:02.228Z,1526590922.228 [NAL9602](INFO): NAL9602 initialized
2018-05-17T21:02:11.088Z,1526590931.088 [Default:A.Wait](INFO): Done Waiting.
2018-05-17T21:02:11.088Z,1526590931.088 [Default:A.Wait] Stopped
2018-05-17T21:02:11.088Z,1526590931.088 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-05-17T21:02:11.482Z,1526590931.482 [Default:CheckIn] Running Loop=1
2018-05-17T21:02:11.482Z,1526590931.482 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-17T21:02:11.487Z,1526590931.487 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-17T21:03:38.304Z,1526591018.304 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:03:38.304Z,1526591018.304 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-17T21:03:38.304Z,1526591018.304 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:03:38.317Z,1526591018.317 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:03:38.722Z,1526591018.722 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:03:38.722Z,1526591018.722 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-17T21:03:39.111Z,1526591019.111 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:03:39.112Z,1526591019.112 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:03:39.112Z,1526591019.112 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:04:42.398Z,1526591082.398 [NAL9602](INFO): SBD MO Status=0, MOMSN=5629, MT Status=0, MTMSN=0
2018-05-17T21:04:42.398Z,1526591082.398 [NAL9602](INFO): No messages in MT queue
2018-05-17T21:04:43.596Z,1526591083.596 [NAL9602](INFO): GPS fix at 20180517T210442: (36.802988, -121.787969)
2018-05-17T21:04:43.663Z,1526591083.663 [Default:CheckIn:Read_GPS] Stopped
2018-05-17T21:04:43.664Z,1526591083.664 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-17T21:04:48.057Z,1526591088.057 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180517T205726/Courier0010.lzma
2018-05-17T21:04:48.856Z,1526591088.856 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0010.lzma.bak
2018-05-17T21:04:48.856Z,1526591088.856 [DataOverHttps](INFO): SBD MOMSN=8283436
2018-05-17T21:04:58.115Z,1526591098.115 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180517T205726/Courier0013.lzma
2018-05-17T21:04:58.900Z,1526591098.900 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0013.lzma.bak
2018-05-17T21:04:58.900Z,1526591098.900 [DataOverHttps](INFO): SBD MOMSN=8283438
2018-05-17T21:05:08.430Z,1526591108.430 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180517T205726/Courier0016.lzma
2018-05-17T21:05:09.228Z,1526591109.228 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0016.lzma.bak
2018-05-17T21:05:09.228Z,1526591109.228 [DataOverHttps](INFO): SBD MOMSN=8283441
2018-05-17T21:05:20.197Z,1526591120.197 [NAL9602](INFO): Powering down
2018-05-17T21:05:20.325Z,1526591120.325 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20180517T203542/Express0011.lzma
2018-05-17T21:05:21.120Z,1526591121.120 [DataOverHttps](INFO): Moved sent file to Logs/20180517T203542/Express0011.lzma.bak
2018-05-17T21:05:21.120Z,1526591121.120 [DataOverHttps](INFO): SBD MOMSN=8283443
2018-05-17T21:05:30.724Z,1526591130.724 [DataOverHttps](INFO): Sending 641 bytes from file Logs/20180517T205726/Express0001.lzma
2018-05-17T21:05:31.520Z,1526591131.520 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0001.lzma.bak
2018-05-17T21:05:31.520Z,1526591131.520 [DataOverHttps](INFO): SBD MOMSN=8283448
2018-05-17T21:05:41.959Z,1526591141.959 [DataOverHttps](INFO): Sending 473 bytes from file Logs/20180517T205726/Express0005.lzma
2018-05-17T21:05:42.756Z,1526591142.756 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0005.lzma.bak
2018-05-17T21:05:42.756Z,1526591142.756 [DataOverHttps](INFO): SBD MOMSN=8283462
2018-05-17T21:05:52.198Z,1526591152.198 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180517T205726/Express0008.lzma
2018-05-17T21:05:52.996Z,1526591152.996 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0008.lzma.bak
2018-05-17T21:05:52.996Z,1526591152.996 [DataOverHttps](INFO): SBD MOMSN=8283469
2018-05-17T21:06:02.227Z,1526591162.227 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20180517T205726/Express0011.lzma
2018-05-17T21:06:03.012Z,1526591163.012 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0011.lzma.bak
2018-05-17T21:06:03.012Z,1526591163.012 [DataOverHttps](INFO): SBD MOMSN=8283471
2018-05-17T21:06:12.543Z,1526591172.543 [DataOverHttps](INFO): Sending 623 bytes from file Logs/20180517T205726/Express0014.lzma
2018-05-17T21:06:13.312Z,1526591173.312 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0014.lzma.bak
2018-05-17T21:06:13.312Z,1526591173.312 [DataOverHttps](INFO): SBD MOMSN=8283479
2018-05-17T21:06:22.754Z,1526591182.754 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180517T205726/Express0017.lzma
2018-05-17T21:06:23.552Z,1526591183.552 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0017.lzma.bak
2018-05-17T21:06:23.552Z,1526591183.552 [DataOverHttps](INFO): SBD MOMSN=8283499
2018-05-17T21:06:24.011Z,1526591184.011 [Default:CheckIn:Read_Iridium] Stopped
2018-05-17T21:06:24.011Z,1526591184.011 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-17T21:06:24.011Z,1526591184.011 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-17T21:06:39.333Z,1526591199.333 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:06:39.333Z,1526591199.333 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-17T21:06:39.333Z,1526591199.333 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:06:39.346Z,1526591199.346 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:06:39.739Z,1526591199.739 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:06:39.739Z,1526591199.739 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-17T21:06:40.126Z,1526591200.126 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:06:40.127Z,1526591200.127 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:06:40.127Z,1526591200.127 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:07:37.219Z,1526591257.219 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-17T21:09:40.467Z,1526591380.467 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:09:40.468Z,1526591380.468 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-17T21:09:40.468Z,1526591380.468 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:09:40.493Z,1526591380.493 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:09:40.868Z,1526591380.868 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:09:40.868Z,1526591380.868 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-17T21:09:41.259Z,1526591381.259 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:09:41.260Z,1526591381.260 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:09:41.260Z,1526591381.260 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:11:08.670Z,1526591468.670 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.409564 seconds old.
2018-05-17T21:11:08.672Z,1526591468.672 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 0.410064 seconds old.
2018-05-17T21:11:09.459Z,1526591469.459 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.394198 seconds old.
2018-05-17T21:11:09.461Z,1526591469.461 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 0.394563 seconds old.
2018-05-17T21:11:09.868Z,1526591469.868 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.802545 seconds old.
2018-05-17T21:11:09.870Z,1526591469.870 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 0.802876 seconds old.
2018-05-17T21:11:24.661Z,1526591484.661 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-17T21:11:24.661Z,1526591484.661 [Default:CheckIn:C.Wait] Stopped
2018-05-17T21:11:24.661Z,1526591484.661 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-17T21:11:24.662Z,1526591484.662 [Default:CheckIn:D] Running Loop=1
2018-05-17T21:11:25.073Z,1526591485.073 [Default:CheckIn:D] Stopped
2018-05-17T21:11:25.073Z,1526591485.073 [Default:CheckIn:E] Running Loop=1
2018-05-17T21:11:25.456Z,1526591485.456 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.454522 min 
2018-05-17T21:11:25.458Z,1526591485.458 [Default:CheckIn:E] Stopped
2018-05-17T21:11:25.459Z,1526591485.459 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-17T21:11:25.459Z,1526591485.459 [Default:CheckIn] Stopped
2018-05-17T21:11:25.459Z,1526591485.459 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-17T21:11:25.459Z,1526591485.459 [Default:CheckIn](INFO): Running loop #2
2018-05-17T21:11:25.459Z,1526591485.459 [Default:CheckIn] Running Loop=2
2018-05-17T21:11:25.459Z,1526591485.459 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-17T21:11:25.459Z,1526591485.459 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-17T21:11:28.636Z,1526591488.636 [NAL9602](INFO): Powering up
2018-05-17T21:11:39.792Z,1526591499.792 [NAL9602](INFO): NAL9602 initialized
2018-05-17T21:12:05.789Z,1526591525.789 [NAL9602](INFO): SBD MO Status=2, MOMSN=5630, MT Status=2, MTMSN=0
2018-05-17T21:12:05.790Z,1526591525.790 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-17T21:12:23.389Z,1526591543.389 [NAL9602](INFO): SBD MO Status=2, MOMSN=5630, MT Status=2, MTMSN=0
2018-05-17T21:12:23.389Z,1526591543.389 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-17T21:12:41.489Z,1526591561.489 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:12:41.490Z,1526591561.490 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-17T21:12:41.490Z,1526591561.490 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:12:41.534Z,1526591561.534 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:12:41.934Z,1526591561.934 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:12:41.934Z,1526591561.934 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-17T21:12:42.282Z,1526591562.282 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:12:42.283Z,1526591562.283 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:12:42.283Z,1526591562.283 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:13:41.070Z,1526591621.070 [NAL9602](INFO): SBD MO Status=0, MOMSN=5630, MT Status=0, MTMSN=0
2018-05-17T21:13:41.070Z,1526591621.070 [NAL9602](INFO): No messages in MT queue
2018-05-17T21:13:42.272Z,1526591622.272 [NAL9602](INFO): GPS fix at 20180517T211341: (36.802339, -121.786771)
2018-05-17T21:13:42.381Z,1526591622.381 [Default:CheckIn:Read_GPS] Stopped
2018-05-17T21:13:42.381Z,1526591622.381 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-17T21:13:46.738Z,1526591626.738 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180517T205726/Courier0019.lzma
2018-05-17T21:13:47.540Z,1526591627.540 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0019.lzma.bak
2018-05-17T21:13:47.540Z,1526591627.540 [DataOverHttps](INFO): SBD MOMSN=8283501
2018-05-17T21:13:56.383Z,1526591636.383 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20180517T205726/Express0020.lzma
2018-05-17T21:13:57.184Z,1526591637.184 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0020.lzma.bak
2018-05-17T21:13:57.184Z,1526591637.184 [DataOverHttps](INFO): SBD MOMSN=8283505
2018-05-17T21:13:57.641Z,1526591637.641 [Default:CheckIn:Read_Iridium] Stopped
2018-05-17T21:13:57.641Z,1526591637.641 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-17T21:13:57.641Z,1526591637.641 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-17T21:14:17.012Z,1526591657.012 [NAL9602](INFO): Powering down
2018-05-17T21:15:42.680Z,1526591742.680 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:15:42.680Z,1526591742.680 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-17T21:15:42.680Z,1526591742.680 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:15:42.693Z,1526591742.693 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:15:43.092Z,1526591743.092 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:15:43.092Z,1526591743.092 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-17T21:15:43.472Z,1526591743.472 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:15:43.472Z,1526591743.472 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:15:43.473Z,1526591743.473 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:17:37.624Z,1526591857.624 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-17T21:18:38.403Z,1526591918.403 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 31 sticks.
2018-05-17T21:18:38.403Z,1526591918.403 [BPC1] Data Fault, FailCount= 1
2018-05-17T21:18:38.403Z,1526591918.403 [BPC1](ERROR): Data Fault
2018-05-17T21:18:38.467Z,1526591918.467 [CBIT](ERROR): Data Fault in component: BPC1
2018-05-17T21:18:39.634Z,1526591919.634 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-17T21:18:39.634Z,1526591919.634 [BPC1] No Fault, FailCount= 1
2018-05-17T21:18:43.603Z,1526591923.603 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:18:43.603Z,1526591923.603 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-17T21:18:43.603Z,1526591923.603 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:18:43.628Z,1526591923.628 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:18:44.052Z,1526591924.052 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:18:44.052Z,1526591924.052 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-17T21:18:44.405Z,1526591924.405 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:18:44.405Z,1526591924.405 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:18:44.405Z,1526591924.405 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:18:58.414Z,1526591938.414 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-17T21:18:58.414Z,1526591938.414 [Default:CheckIn:C.Wait] Stopped
2018-05-17T21:18:58.414Z,1526591938.414 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-17T21:18:58.414Z,1526591938.414 [Default:CheckIn:D] Running Loop=1
2018-05-17T21:19:01.532Z,1526591941.532 [Default:CheckIn:D] Stopped
2018-05-17T21:19:01.532Z,1526591941.532 [Default:CheckIn:E] Running Loop=1
2018-05-17T21:19:01.670Z,1526591941.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.062200 min 
2018-05-17T21:19:01.670Z,1526591941.670 [Default:CheckIn:E] Stopped
2018-05-17T21:19:01.670Z,1526591941.670 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-17T21:19:01.670Z,1526591941.670 [Default:CheckIn] Stopped
2018-05-17T21:19:01.670Z,1526591941.670 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-17T21:19:01.671Z,1526591941.671 [Default:CheckIn](INFO): Running loop #3
2018-05-17T21:19:01.671Z,1526591941.671 [Default:CheckIn] Running Loop=3
2018-05-17T21:19:01.671Z,1526591941.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-17T21:19:01.671Z,1526591941.671 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-17T21:19:02.384Z,1526591942.384 [NAL9602](INFO): Powering up
2018-05-17T21:19:13.580Z,1526591953.580 [NAL9602](INFO): NAL9602 initialized
2018-05-17T21:19:37.182Z,1526591977.182 [NAL9602](INFO): SBD MO Status=0, MOMSN=5631, MT Status=0, MTMSN=0
2018-05-17T21:19:37.182Z,1526591977.182 [NAL9602](INFO): No messages in MT queue
2018-05-17T21:20:02.059Z,1526592002.059 [NAL9602](INFO): GPS fix at 20180517T212000: (36.802304, -121.786785)
2018-05-17T21:20:02.096Z,1526592002.096 [Default:CheckIn:Read_GPS] Stopped
2018-05-17T21:20:02.097Z,1526592002.097 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-17T21:20:06.949Z,1526592006.949 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180517T205726/Courier0022.lzma
2018-05-17T21:20:07.740Z,1526592007.740 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0022.lzma.bak
2018-05-17T21:20:07.740Z,1526592007.740 [DataOverHttps](INFO): SBD MOMSN=8283509
2018-05-17T21:20:16.946Z,1526592016.946 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20180517T205726/Express0023.lzma
2018-05-17T21:20:17.744Z,1526592017.744 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0023.lzma.bak
2018-05-17T21:20:17.744Z,1526592017.744 [DataOverHttps](INFO): SBD MOMSN=8283512
2018-05-17T21:20:18.214Z,1526592018.214 [Default:CheckIn:Read_Iridium] Stopped
2018-05-17T21:20:18.214Z,1526592018.214 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-17T21:20:18.214Z,1526592018.214 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-17T21:20:33.992Z,1526592033.992 [NAL9602](INFO): Powering down
2018-05-17T21:21:44.422Z,1526592104.422 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:21:44.422Z,1526592104.422 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-17T21:21:44.423Z,1526592104.423 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:21:44.435Z,1526592104.435 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:21:44.859Z,1526592104.859 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:21:44.859Z,1526592104.859 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-17T21:21:45.231Z,1526592105.231 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:21:45.231Z,1526592105.231 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:21:45.231Z,1526592105.231 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:24:45.435Z,1526592285.435 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:24:45.435Z,1526592285.435 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-17T21:24:45.435Z,1526592285.435 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:24:45.461Z,1526592285.461 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:24:45.864Z,1526592285.864 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:24:45.864Z,1526592285.864 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-17T21:24:46.254Z,1526592286.254 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:24:46.254Z,1526592286.254 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:24:46.255Z,1526592286.255 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:25:18.647Z,1526592318.647 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-17T21:25:18.647Z,1526592318.647 [Default:CheckIn:C.Wait] Stopped
2018-05-17T21:25:18.647Z,1526592318.647 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-17T21:25:18.648Z,1526592318.648 [Default:CheckIn:D] Running Loop=1
2018-05-17T21:25:19.037Z,1526592319.037 [Default:CheckIn:D] Stopped
2018-05-17T21:25:19.037Z,1526592319.037 [Default:CheckIn:E] Running Loop=1
2018-05-17T21:25:19.454Z,1526592319.454 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.353963 min 
2018-05-17T21:25:19.454Z,1526592319.454 [Default:CheckIn:E] Stopped
2018-05-17T21:25:19.454Z,1526592319.454 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-17T21:25:19.454Z,1526592319.454 [Default:CheckIn] Stopped
2018-05-17T21:25:19.454Z,1526592319.454 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-17T21:25:19.455Z,1526592319.455 [Default:CheckIn](INFO): Running loop #4
2018-05-17T21:25:19.455Z,1526592319.455 [Default:CheckIn] Running Loop=4
2018-05-17T21:25:19.455Z,1526592319.455 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-17T21:25:19.455Z,1526592319.455 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-17T21:25:22.906Z,1526592322.906 [NAL9602](INFO): Powering up
2018-05-17T21:25:33.780Z,1526592333.780 [NAL9602](INFO): NAL9602 initialized
2018-05-17T21:26:05.786Z,1526592365.786 [NAL9602](INFO): SBD MO Status=0, MOMSN=5632, MT Status=0, MTMSN=0
2018-05-17T21:26:05.786Z,1526592365.786 [NAL9602](INFO): No messages in MT queue
2018-05-17T21:26:33.608Z,1526592393.608 [NAL9602](INFO): GPS fix at 20180517T212633: (36.805245, -121.785473)
2018-05-17T21:26:33.672Z,1526592393.672 [Default:CheckIn:Read_GPS] Stopped
2018-05-17T21:26:33.673Z,1526592393.673 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-17T21:26:38.431Z,1526592398.431 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20180517T205726/Courier0025.lzma
2018-05-17T21:26:39.228Z,1526592399.228 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0025.lzma.bak
2018-05-17T21:26:39.228Z,1526592399.228 [DataOverHttps](INFO): SBD MOMSN=8283517
2018-05-17T21:26:48.030Z,1526592408.030 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20180517T205726/Express0026.lzma
2018-05-17T21:26:48.808Z,1526592408.808 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0026.lzma.bak
2018-05-17T21:26:48.808Z,1526592408.808 [DataOverHttps](INFO): SBD MOMSN=8283521
2018-05-17T21:26:49.270Z,1526592409.270 [Default:CheckIn:Read_Iridium] Stopped
2018-05-17T21:26:49.270Z,1526592409.270 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-17T21:26:49.270Z,1526592409.270 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-17T21:27:08.228Z,1526592428.228 [NAL9602](INFO): Powering down
2018-05-17T21:27:37.903Z,1526592457.903 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-17T21:27:46.276Z,1526592466.276 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:27:46.276Z,1526592466.276 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-17T21:27:46.276Z,1526592466.276 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:27:46.314Z,1526592466.314 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:27:46.714Z,1526592466.714 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:27:46.714Z,1526592466.714 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-17T21:27:47.071Z,1526592467.071 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:27:47.072Z,1526592467.072 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:27:47.072Z,1526592467.072 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:29:54.092Z,1526592594.092 [DataOverHttps](IMPORTANT): SBD MTMSN=20180517T212953
2018-05-17T21:29:58.200Z,1526592598.200 [DataOverHttps](INFO): Received command:ibit
2018-05-17T21:29:58.276Z,1526592598.276 [CommandLine](IMPORTANT): got command ibit
2018-05-17T21:29:58.307Z,1526592598.307 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-05-17T21:29:58.307Z,1526592598.307 [IBIT](IMPORTANT): Beginning control surface checks.
2018-05-17T21:29:58.310Z,1526592598.310 [CBIT](IMPORTANT): Beginning ground fault scan
2018-05-17T21:29:58.604Z,1526592598.604 [NAL9602](INFO): Powering up
2018-05-17T21:30:09.100Z,1526592609.100 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.124842
CHAN A1 (24V): 4.657929
CHAN A2 (12V): -0.002224
CHAN A3 (5V): -0.001324
CHAN B0 (3.3V): 0.000300
CHAN B1 (3.15aV): 0.000087
CHAN B2 (3.15bV): -0.000199
CHAN B3 (GND): 0.000611
OPEN: -0.000597
Full Scale Calc: 4.765 mA, -1.589 mA
2018-05-17T21:30:09.417Z,1526592609.417 [NAL9602](INFO): NAL9602 initialized
2018-05-17T21:30:47.472Z,1526592647.472 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:30:47.472Z,1526592647.472 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-17T21:30:47.472Z,1526592647.472 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:30:47.484Z,1526592647.484 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:30:47.886Z,1526592647.886 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:30:47.886Z,1526592647.886 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-17T21:30:48.274Z,1526592648.274 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:30:48.275Z,1526592648.275 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:30:48.275Z,1526592648.275 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:31:24.049Z,1526592684.049 [NAL9602](INFO): SBD MO Status=2, MOMSN=5633, MT Status=2, MTMSN=0
2018-05-17T21:31:24.050Z,1526592684.050 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-17T21:31:34.845Z,1526592694.845 [NAL9602](INFO): SBD MO Status=0, MOMSN=5633, MT Status=0, MTMSN=0
2018-05-17T21:31:34.846Z,1526592694.846 [NAL9602](INFO): No messages in MT queue
2018-05-17T21:31:48.900Z,1526592708.900 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2018-05-17T21:31:49.304Z,1526592709.304 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 0.000000
2018-05-17T21:31:49.305Z,1526592709.305 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2018-05-17T21:31:49.305Z,1526592709.305 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2018-05-17T21:31:49.704Z,1526592709.704 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-05-17T21:31:49.704Z,1526592709.704 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-05-17T21:31:49.705Z,1526592709.705 [IBIT](IMPORTANT): Pressure:9.316158 PSI
2018-05-17T21:31:49.705Z,1526592709.705 [IBIT](IMPORTANT): Humidity:14.167990 %
2018-05-17T21:31:50.108Z,1526592710.108 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-05-17T21:31:50.109Z,1526592710.109 [IBIT](IMPORTANT): buoyancyNeutral: 315.000000 cc
2018-05-17T21:31:50.109Z,1526592710.109 [IBIT](IMPORTANT): massDefault: -0.075000 cm
2018-05-17T21:31:50.109Z,1526592710.109 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2018-05-17T21:31:50.110Z,1526592710.110 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2018-05-17T21:31:50.110Z,1526592710.110 [IBIT](IMPORTANT): IBIT FAILED
2018-05-17T21:31:50.503Z,1526592710.503 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-17T21:31:50.504Z,1526592710.504 [Default:CheckIn:C.Wait] Stopped
2018-05-17T21:31:50.504Z,1526592710.504 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-17T21:31:50.504Z,1526592710.504 [Default:CheckIn:D] Running Loop=1
2018-05-17T21:31:50.905Z,1526592710.905 [Default:CheckIn:D] Stopped
2018-05-17T21:31:50.905Z,1526592710.905 [Default:CheckIn:E] Running Loop=1
2018-05-17T21:31:51.301Z,1526592711.301 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.885097 min 
2018-05-17T21:31:51.301Z,1526592711.301 [Default:CheckIn:E] Stopped
2018-05-17T21:31:51.301Z,1526592711.301 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-17T21:31:51.301Z,1526592711.301 [Default:CheckIn] Stopped
2018-05-17T21:31:51.301Z,1526592711.301 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-17T21:31:51.302Z,1526592711.302 [Default:CheckIn](INFO): Running loop #5
2018-05-17T21:31:51.302Z,1526592711.302 [Default:CheckIn] Running Loop=5
2018-05-17T21:31:51.302Z,1526592711.302 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-17T21:31:51.302Z,1526592711.302 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-17T21:33:48.493Z,1526592828.493 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:33:48.493Z,1526592828.493 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-17T21:33:48.493Z,1526592828.493 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:33:48.505Z,1526592828.505 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:33:48.915Z,1526592828.915 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:33:48.915Z,1526592828.915 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-17T21:33:49.299Z,1526592829.299 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:33:49.300Z,1526592829.300 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:33:49.300Z,1526592829.300 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:33:49.640Z,1526592829.640 [NAL9602](INFO): GPS fix at 20180517T213348: (36.805700, -121.794339)
2018-05-17T21:33:49.710Z,1526592829.710 [Default:CheckIn:Read_GPS] Stopped
2018-05-17T21:33:49.711Z,1526592829.711 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-17T21:33:54.080Z,1526592834.080 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180517T205726/Courier0028.lzma
2018-05-17T21:33:54.864Z,1526592834.864 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0028.lzma.bak
2018-05-17T21:33:54.864Z,1526592834.864 [DataOverHttps](INFO): SBD MOMSN=8283528
2018-05-17T21:34:04.059Z,1526592844.059 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180517T205726/Courier0031.lzma
2018-05-17T21:34:04.852Z,1526592844.852 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Courier0031.lzma.bak
2018-05-17T21:34:04.852Z,1526592844.852 [DataOverHttps](INFO): SBD MOMSN=8283531
2018-05-17T21:34:14.733Z,1526592854.733 [DataOverHttps](INFO): Sending 723 bytes from file Logs/20180517T205726/Express0029.lzma
2018-05-17T21:34:15.528Z,1526592855.528 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0029.lzma.bak
2018-05-17T21:34:15.528Z,1526592855.528 [DataOverHttps](INFO): SBD MOMSN=8283533
2018-05-17T21:34:25.946Z,1526592865.946 [NAL9602](INFO): Powering down
2018-05-17T21:34:26.051Z,1526592866.051 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180517T205726/Express0032.lzma
2018-05-17T21:34:26.852Z,1526592866.852 [DataOverHttps](INFO): Moved sent file to Logs/20180517T205726/Express0032.lzma.bak
2018-05-17T21:34:26.852Z,1526592866.852 [DataOverHttps](INFO): SBD MOMSN=8283556
2018-05-17T21:34:27.357Z,1526592867.357 [Default:CheckIn:Read_Iridium] Stopped
2018-05-17T21:34:27.357Z,1526592867.357 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-17T21:34:27.357Z,1526592867.357 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-17T21:36:49.618Z,1526593009.618 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:36:49.618Z,1526593009.618 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-17T21:36:49.618Z,1526593009.618 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:36:49.650Z,1526593009.650 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:36:50.011Z,1526593010.011 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:36:50.011Z,1526593010.011 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-17T21:36:50.400Z,1526593010.400 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:36:50.400Z,1526593010.400 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:36:50.401Z,1526593010.401 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:37:38.041Z,1526593058.041 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-17T21:39:27.722Z,1526593167.722 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-17T21:39:27.722Z,1526593167.722 [Default:CheckIn:C.Wait] Stopped
2018-05-17T21:39:27.722Z,1526593167.722 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-17T21:39:27.722Z,1526593167.722 [Default:CheckIn:D] Running Loop=1
2018-05-17T21:39:28.127Z,1526593168.127 [Default:CheckIn:D] Stopped
2018-05-17T21:39:28.128Z,1526593168.128 [Default:CheckIn:E] Running Loop=1
2018-05-17T21:39:31.270Z,1526593171.270 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.505465 min 
2018-05-17T21:39:31.270Z,1526593171.270 [Default:CheckIn:E] Stopped
2018-05-17T21:39:31.270Z,1526593171.270 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-17T21:39:31.270Z,1526593171.270 [Default:CheckIn] Stopped
2018-05-17T21:39:31.271Z,1526593171.271 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-17T21:39:31.271Z,1526593171.271 [Default:CheckIn](INFO): Running loop #6
2018-05-17T21:39:31.271Z,1526593171.271 [Default:CheckIn] Running Loop=6
2018-05-17T21:39:31.271Z,1526593171.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-17T21:39:31.271Z,1526593171.271 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-17T21:39:31.696Z,1526593171.696 [NAL9602](INFO): Powering up
2018-05-17T21:39:42.896Z,1526593182.896 [NAL9602](INFO): NAL9602 initialized
2018-05-17T21:39:50.537Z,1526593190.537 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:39:50.537Z,1526593190.537 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-17T21:39:50.537Z,1526593190.537 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:39:50.550Z,1526593190.550 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:39:50.953Z,1526593190.953 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:39:50.953Z,1526593190.953 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-17T21:39:51.346Z,1526593191.346 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:39:51.346Z,1526593191.346 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:39:51.346Z,1526593191.346 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:40:02.096Z,1526593202.096 [NAL9602](INFO): SBD MO Status=0, MOMSN=5634, MT Status=0, MTMSN=0
2018-05-17T21:40:02.096Z,1526593202.096 [NAL9602](INFO): No messages in MT queue
2018-05-17T21:42:51.485Z,1526593371.485 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-17T21:42:51.485Z,1526593371.485 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-17T21:42:51.485Z,1526593371.485 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-17T21:42:51.498Z,1526593371.498 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-17T21:42:51.890Z,1526593371.890 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-17T21:42:51.890Z,1526593371.890 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-17T21:42:52.278Z,1526593372.278 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-17T21:42:52.279Z,1526593372.279 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-17T21:42:52.279Z,1526593372.279 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-17T21:42:57.823Z,1526593377.823 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-05-17T21:43:42.064Z,1526593422.064 [DataOverHttps](IMPORTANT): SBD MTMSN=20180517T214340
2018-05-17T21:43:51.258Z,1526593431.258 [DataOverHttps](INFO): Received command:restart logs