2018-05-23T19:52:59.277Z,1527105179.277 [Supervisor](DEBUG): Initializing supervisor.
2018-05-23T19:52:59.279Z,1527105179.279 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-23T19:52:59.280Z,1527105179.280 [SyncHandler](INFO): Protected caller Thread ID is 5167
2018-05-23T19:52:59.280Z,1527105179.280 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-23T19:52:59.281Z,1527105179.281 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-23T19:52:59.281Z,1527105179.281 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5168
2018-05-23T19:52:59.284Z,1527105179.284 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-23T19:52:59.295Z,1527105179.295 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-23T19:52:59.296Z,1527105179.296 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-23T19:52:59.296Z,1527105179.296 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5169
2018-05-23T19:52:59.297Z,1527105179.297 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-23T19:52:59.298Z,1527105179.298 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-23T19:52:59.298Z,1527105179.298 [logger ThreadHandler](INFO): Protected caller Thread ID is 5170
2018-05-23T19:52:59.300Z,1527105179.300 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-23T19:52:59.300Z,1527105179.300 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-23T19:52:59.302Z,1527105179.302 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-23T19:52:59.622Z,1527105179.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-23T19:52:59.622Z,1527105179.622 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-23T19:52:59.819Z,1527105179.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-23T19:52:59.819Z,1527105179.819 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-23T19:53:00.099Z,1527105180.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-23T19:53:00.099Z,1527105180.099 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-23T19:53:00.236Z,1527105180.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-23T19:53:00.236Z,1527105180.236 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-23T19:53:00.333Z,1527105180.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-23T19:53:00.333Z,1527105180.333 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-23T19:53:00.786Z,1527105180.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-23T19:53:00.786Z,1527105180.786 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-23T19:53:01.161Z,1527105181.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-23T19:53:01.162Z,1527105181.162 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-23T19:53:01.595Z,1527105181.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-23T19:53:01.595Z,1527105181.595 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-23T19:53:01.699Z,1527105181.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-23T19:53:01.699Z,1527105181.699 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-23T19:53:01.795Z,1527105181.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-23T19:53:01.796Z,1527105181.796 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-23T19:53:01.997Z,1527105181.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-23T19:53:01.998Z,1527105181.998 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-23T19:53:02.203Z,1527105182.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-23T19:53:02.204Z,1527105182.204 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-23T19:53:02.544Z,1527105182.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-23T19:53:02.545Z,1527105182.545 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-23T19:53:02.683Z,1527105182.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-23T19:53:02.684Z,1527105182.684 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-23T19:53:02.765Z,1527105182.765 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-05-23T19:53:02.766Z,1527105182.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-05-23T19:53:02.867Z,1527105182.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-05-23T19:53:02.958Z,1527105182.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-05-23T19:53:03.051Z,1527105183.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-05-23T19:53:03.135Z,1527105183.135 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-05-23T19:53:03.282Z,1527105183.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-05-23T19:53:03.463Z,1527105183.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-05-23T19:53:03.712Z,1527105183.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-05-23T19:53:04.455Z,1527105184.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-23T19:53:04.455Z,1527105184.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-05-23T19:53:04.592Z,1527105184.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-05-23T19:53:04.681Z,1527105184.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-05-23T19:53:04.780Z,1527105184.780 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-05-23T19:53:04.903Z,1527105184.903 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-05-23T19:53:04.912Z,1527105184.912 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-23T19:53:05.139Z,1527105185.139 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-23T19:53:05.140Z,1527105185.140 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-23T19:53:05.179Z,1527105185.179 [DepthRateCalculator] Loaded
2018-05-23T19:53:05.180Z,1527105185.180 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-23T19:53:05.185Z,1527105185.185 [PitchRateCalculator] Loaded
2018-05-23T19:53:05.186Z,1527105185.186 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-23T19:53:05.201Z,1527105185.201 [SpeedCalculator] Loaded
2018-05-23T19:53:05.201Z,1527105185.201 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-23T19:53:05.221Z,1527105185.221 [TempGradientCalculator] Loaded
2018-05-23T19:53:05.222Z,1527105185.222 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-23T19:53:05.236Z,1527105185.236 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-23T19:53:05.237Z,1527105185.237 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-23T19:53:05.242Z,1527105185.242 [YawRateCalculator] Loaded
2018-05-23T19:53:05.243Z,1527105185.243 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-23T19:53:05.281Z,1527105185.281 [ElevatorOffsetCalculator] Loaded
2018-05-23T19:53:05.281Z,1527105185.281 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-23T19:53:05.281Z,1527105185.281 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-23T19:53:05.282Z,1527105185.282 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-23T19:53:05.361Z,1527105185.361 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-23T19:53:05.361Z,1527105185.361 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-23T19:53:05.361Z,1527105185.361 [StratificationFrontDetector] Loaded
2018-05-23T19:53:05.361Z,1527105185.361 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-23T19:53:05.362Z,1527105185.362 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-23T19:53:05.362Z,1527105185.362 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-23T19:53:05.471Z,1527105185.471 [BuoyancyServo] Loaded
2018-05-23T19:53:05.471Z,1527105185.471 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-23T19:53:05.485Z,1527105185.485 [ElevatorServo] Loaded
2018-05-23T19:53:05.486Z,1527105185.486 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-23T19:53:05.500Z,1527105185.500 [MassServo] Loaded
2018-05-23T19:53:05.500Z,1527105185.500 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-23T19:53:05.515Z,1527105185.515 [RudderServo] Loaded
2018-05-23T19:53:05.515Z,1527105185.515 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-23T19:53:05.529Z,1527105185.529 [ThrusterServo] Loaded
2018-05-23T19:53:05.529Z,1527105185.529 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-23T19:53:05.529Z,1527105185.529 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-23T19:53:05.530Z,1527105185.530 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-23T19:53:05.543Z,1527105185.543 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-23T19:53:05.543Z,1527105185.543 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-23T19:53:05.637Z,1527105185.637 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-23T19:53:05.638Z,1527105185.638 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-23T19:53:05.909Z,1527105185.909 [DataOverHttps] Loaded
2018-05-23T19:53:05.909Z,1527105185.909 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-23T19:53:05.923Z,1527105185.923 [Depth_Keller] Loaded
2018-05-23T19:53:05.923Z,1527105185.923 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-23T19:53:05.927Z,1527105185.927 [DropWeight] Loaded
2018-05-23T19:53:05.928Z,1527105185.928 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-05-23T19:53:06.021Z,1527105186.021 [NAL9602] Loaded
2018-05-23T19:53:06.022Z,1527105186.022 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-23T19:53:06.037Z,1527105186.037 [Onboard] Loaded
2018-05-23T19:53:06.037Z,1527105186.037 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-23T19:53:06.047Z,1527105186.047 [Radio_Surface] Loaded
2018-05-23T19:53:06.047Z,1527105186.047 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-23T19:53:06.048Z,1527105186.048 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2018-05-23T19:53:06.049Z,1527105186.049 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5252
2018-05-23T19:53:06.095Z,1527105186.095 [PNI_TCM] Loaded
2018-05-23T19:53:06.095Z,1527105186.095 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-05-23T19:53:07.952Z,1527105187.952 [BPC1] Loaded
2018-05-23T19:53:07.952Z,1527105187.952 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-23T19:53:07.953Z,1527105187.953 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-23T19:53:07.953Z,1527105187.953 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-23T19:53:08.076Z,1527105188.076 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-23T19:53:08.097Z,1527105188.097 [SBIT] Loaded
2018-05-23T19:53:08.097Z,1527105188.097 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-23T19:53:08.098Z,1527105188.098 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-23T19:53:08.109Z,1527105188.109 [IBIT] Loaded
2018-05-23T19:53:08.110Z,1527105188.110 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-23T19:53:08.113Z,1527105188.113 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-23T19:53:08.256Z,1527105188.256 [CBIT] Loaded
2018-05-23T19:53:08.257Z,1527105188.257 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-23T19:53:08.257Z,1527105188.257 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-23T19:53:08.258Z,1527105188.258 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-23T19:53:08.392Z,1527105188.392 [Aanderaa_O2] Loaded
2018-05-23T19:53:08.392Z,1527105188.392 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-05-23T19:53:08.403Z,1527105188.403 [CANONSampler] Loaded
2018-05-23T19:53:08.404Z,1527105188.404 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2018-05-23T19:53:08.420Z,1527105188.420 [ESPComponent] Loaded
2018-05-23T19:53:08.420Z,1527105188.420 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-05-23T19:53:08.433Z,1527105188.433 [PAR_Licor] Loaded
2018-05-23T19:53:08.434Z,1527105188.434 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-23T19:53:08.481Z,1527105188.481 [WetLabsBB2FL] Loaded
2018-05-23T19:53:08.481Z,1527105188.481 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-05-23T19:53:08.482Z,1527105188.482 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409444E0
2018-05-23T19:53:08.482Z,1527105188.482 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5253
2018-05-23T19:53:08.483Z,1527105188.483 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-23T19:53:08.484Z,1527105188.484 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-23T19:53:08.674Z,1527105188.674 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-23T19:53:08.922Z,1527105188.922 [VerticalControl] Loaded
2018-05-23T19:53:08.970Z,1527105188.970 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-23T19:53:08.971Z,1527105188.971 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-23T19:53:09.045Z,1527105189.045 [HorizontalControl] Loaded
2018-05-23T19:53:09.045Z,1527105189.045 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-23T19:53:09.046Z,1527105189.046 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-23T19:53:09.047Z,1527105189.047 [SpeedControl] Loaded
2018-05-23T19:53:09.048Z,1527105189.048 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-23T19:53:09.048Z,1527105189.048 [LoopControl](DEBUG): Construct LoopControl.
2018-05-23T19:53:09.049Z,1527105189.049 [LoopControl] Loaded
2018-05-23T19:53:09.049Z,1527105189.049 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-23T19:53:09.050Z,1527105189.050 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-23T19:53:09.050Z,1527105189.050 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-23T19:53:09.098Z,1527105189.098 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-23T19:53:09.098Z,1527105189.098 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-23T19:53:09.186Z,1527105189.186 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-23T19:53:09.186Z,1527105189.186 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-23T19:53:09.239Z,1527105189.239 [DeadReckonWithRespectToSeafloor] Loaded
2018-05-23T19:53:09.239Z,1527105189.239 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-05-23T19:53:09.255Z,1527105189.255 [NavChart] Loaded
2018-05-23T19:53:09.255Z,1527105189.255 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-23T19:53:09.259Z,1527105189.259 [UniversalFixResidualReporter] Loaded
2018-05-23T19:53:09.259Z,1527105189.259 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-23T19:53:09.260Z,1527105189.260 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-23T19:53:09.263Z,1527105189.263 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-23T19:53:09.264Z,1527105189.264 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-23T19:53:09.271Z,1527105189.271 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-23T19:53:09.272Z,1527105189.272 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0
2018-05-23T19:53:09.272Z,1527105189.272 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5254
2018-05-23T19:53:09.276Z,1527105189.276 [Supervisor](INFO): Main Thread ID is 797
2018-05-23T19:53:09.277Z,1527105189.277 [Supervisor](DEBUG): Running supervisor.
2018-05-23T19:53:09.277Z,1527105189.277 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5255
2018-05-23T19:53:09.279Z,1527105189.279 [controlThread ThreadHandler](INFO): Handler Thread ID is 5256
2018-05-23T19:53:09.280Z,1527105189.280 [controlThread](DEBUG): Initializing ControlThread
2018-05-23T19:53:09.281Z,1527105189.281 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-23T19:53:09.281Z,1527105189.281 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-23T19:53:09.282Z,1527105189.282 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-23T19:53:09.282Z,1527105189.282 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-23T19:53:09.283Z,1527105189.283 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-23T19:53:09.283Z,1527105189.283 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-23T19:53:09.284Z,1527105189.284 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-23T19:53:09.289Z,1527105189.289 [SBIT](INFO): Initialize SBIT Component.
2018-05-23T19:53:09.289Z,1527105189.289 [SBIT](IMPORTANT): git: 2018-05-17
2018-05-23T19:53:09.289Z,1527105189.289 [SBIT](INFO): git hash: 418d027d9554c4aed44cef59bd1b73332cce0e52
2018-05-23T19:53:09.290Z,1527105189.290 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-23T19:53:09.291Z,1527105189.291 [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-23T19:53:09.292Z,1527105189.292 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-05-23T19:53:09.292Z,1527105189.292 [IBIT](INFO): Initialize IBIT Component.
2018-05-23T19:53:09.293Z,1527105189.293 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-23T19:53:09.293Z,1527105189.293 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-05-23T19:53:09.295Z,1527105189.295 [logger ThreadHandler](INFO): Handler Thread ID is 5257
2018-05-23T19:53:09.315Z,1527105189.315 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5258
2018-05-23T19:53:09.321Z,1527105189.321 [Radio_Surface](INFO): Powering up
2018-05-23T19:53:09.327Z,1527105189.327 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5259
2018-05-23T19:53:09.328Z,1527105189.328 [WetLabsBB2FL](INFO): Powering down
2018-05-23T19:53:09.359Z,1527105189.359 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5260
2018-05-23T19:53:09.362Z,1527105189.362 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-23T19:53:09.362Z,1527105189.362 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-23T19:53:09.363Z,1527105189.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-23T19:53:09.363Z,1527105189.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-23T19:53:09.363Z,1527105189.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-23T19:53:09.363Z,1527105189.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-23T19:53:09.363Z,1527105189.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-23T19:53:09.364Z,1527105189.364 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-23T19:53:09.364Z,1527105189.364 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-23T19:53:09.364Z,1527105189.364 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-23T19:53:09.364Z,1527105189.364 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-23T19:53:09.364Z,1527105189.364 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-23T19:53:09.365Z,1527105189.365 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-23T19:53:09.365Z,1527105189.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-23T19:53:09.365Z,1527105189.365 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-23T19:53:09.365Z,1527105189.365 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-23T19:53:09.403Z,1527105189.403 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-23T19:53:09.404Z,1527105189.404 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-23T19:53:09.405Z,1527105189.405 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-23T19:53:09.405Z,1527105189.405 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-23T19:53:09.406Z,1527105189.406 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-23T19:53:09.407Z,1527105189.407 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T19:53:09.407Z,1527105189.407 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T19:53:09.408Z,1527105189.408 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T19:53:09.408Z,1527105189.408 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T19:53:09.408Z,1527105189.408 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T19:53:09.409Z,1527105189.409 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-23T19:53:09.409Z,1527105189.409 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-23T19:53:09.410Z,1527105189.410 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-23T19:53:09.433Z,1527105189.433 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-23T19:53:09.468Z,1527105189.468 [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-23T19:53:09.469Z,1527105189.469 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-23T19:53:09.546Z,1527105189.546 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-23T19:53:09.547Z,1527105189.547 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-23T19:53:09.548Z,1527105189.548 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-23T19:53:09.595Z,1527105189.595 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-23T19:53:09.598Z,1527105189.598 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-23T19:53:09.627Z,1527105189.627 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-23T19:53:09.631Z,1527105189.631 [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-23T19:53:09.643Z,1527105189.643 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,CANONSampler,ESPComponent,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-23T19:53:09.660Z,1527105189.660 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-23T19:53:10.000Z,1527105190.000 [CANONSampler](INFO): Powering down
2018-05-23T19:53:10.283Z,1527105190.283 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:10.290Z,1527105190.290 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:10.408Z,1527105190.408 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-23T19:53:10.415Z,1527105190.415 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-23T19:53:10.434Z,1527105190.434 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-23T19:53:10.446Z,1527105190.446 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-23T19:53:10.458Z,1527105190.458 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-23T19:53:10.467Z,1527105190.467 [MassServo](DEBUG): Initializing MassServo.
2018-05-23T19:53:10.484Z,1527105190.484 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-23T19:53:10.490Z,1527105190.490 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-23T19:53:10.496Z,1527105190.496 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-23T19:53:10.502Z,1527105190.502 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-23T19:53:10.765Z,1527105190.765 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:10.766Z,1527105190.766 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:11.046Z,1527105191.046 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:11.047Z,1527105191.047 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:11.527Z,1527105191.527 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:11.528Z,1527105191.528 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:11.926Z,1527105191.926 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:11.927Z,1527105191.927 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:12.350Z,1527105192.350 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:12.351Z,1527105192.351 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:12.793Z,1527105192.793 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:12.794Z,1527105192.794 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:13.234Z,1527105193.234 [Aanderaa_O2](INFO): Powering down
2018-05-23T19:53:13.311Z,1527105193.311 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:13.312Z,1527105193.312 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T19:53:32.850Z,1527105212.850 [SBIT](IMPORTANT): Beginning Startup BIT
2018-05-23T19:53:32.862Z,1527105212.862 [CBIT](IMPORTANT): Beginning ground fault scan
2018-05-23T19:53:36.112Z,1527105216.112 [NAL9602](INFO): Powering up NAL9602
2018-05-23T19:53:43.812Z,1527105223.812 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.015568
CHAN A1 (24V): -0.019662
CHAN A2 (12V): -0.002004
CHAN A3 (5V): -0.001622
CHAN B0 (3.3V): -0.000298
CHAN B1 (3.15aV): 0.000267
CHAN B2 (3.15bV): 0.000004
CHAN B3 (GND): 0.000261
OPEN: -0.000189
Full Scale Calc: 4.765 mA, -1.589 mA
2018-05-23T19:53:47.312Z,1527105227.312 [NAL9602](INFO): NAL9602 initialized
2018-05-23T19:54:26.905Z,1527105266.905 [SBIT](IMPORTANT): SBIT PASSED
2018-05-23T19:54:26.946Z,1527105266.946 [CommandLine](IMPORTANT): got command configSet list
2018-05-23T19:54:26.946Z,1527105266.946 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-05-23T19:54:26.947Z,1527105266.947 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2018-05-23T19:54:26.947Z,1527105266.947 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-05-23T19:54:26.948Z,1527105266.948 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2018-05-23T19:54:26.948Z,1527105266.948 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2018-05-23T19:54:26.948Z,1527105266.948 [CommandLine](IMPORTANT): Normal linearApproximation CTD_NeilBrown.bin_mean_sea_water_salinity 0.020000 practical_salinity_unit;

2018-05-23T19:54:26.948Z,1527105266.948 [CommandLine](IMPORTANT): Normal linearApproximation CTD_NeilBrown.bin_mean_sea_water_temperature 0.150000 kelvin;

2018-05-23T19:54:26.948Z,1527105266.948 [CommandLine](IMPORTANT): Normal linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.020000 practical_salinity_unit;

2018-05-23T19:54:26.948Z,1527105266.948 [CommandLine](IMPORTANT): Normal linearApproximation CTD_Seabird.bin_median_sea_water_temperature 0.150000 kelvin;

2018-05-23T19:54:26.948Z,1527105266.948 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter;

2018-05-23T19:54:26.948Z,1527105266.948 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-05-23T19:54:27.315Z,1527105267.315 [MissionManager](IMPORTANT): Started mission Startup
2018-05-23T19:54:27.315Z,1527105267.315 [Startup] Running Loop=1
2018-05-23T19:54:27.315Z,1527105267.315 [Startup](DEBUG): Aggregate::initialize Startup
2018-05-23T19:54:27.315Z,1527105267.315 [Startup:A.GoToSurface] Running Loop=1
2018-05-23T19:54:27.315Z,1527105267.315 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-23T19:54:27.316Z,1527105267.316 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-23T19:54:27.316Z,1527105267.316 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-23T19:54:27.317Z,1527105267.317 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-23T19:54:27.317Z,1527105267.317 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-23T19:54:27.318Z,1527105267.318 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-23T19:54:27.323Z,1527105267.323 [Startup:StartupSatComms] Running Loop=1
2018-05-23T19:54:27.323Z,1527105267.323 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-05-23T19:54:27.323Z,1527105267.323 [Startup:StartupSatComms:A] Running Loop=1
2018-05-23T19:54:27.715Z,1527105267.715 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-05-23T19:55:27.707Z,1527105327.707 [Startup:StartupSatComms:A](INFO): Timed out from 2018-05-23T19:54:27.3Z
2018-05-23T19:55:27.707Z,1527105327.707 [Startup:StartupSatComms:A] Stopped
2018-05-23T19:55:27.707Z,1527105327.707 [Startup:StartupSatComms:B] Running Loop=1
2018-05-23T19:55:28.092Z,1527105328.092 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-23T19:55:38.449Z,1527105338.449 [NAL9602](INFO): SBD MO Status=0, MOMSN=6028, MT Status=0, MTMSN=0
2018-05-23T19:55:38.450Z,1527105338.450 [NAL9602](INFO): No messages in MT queue
2018-05-23T19:55:39.653Z,1527105339.653 [NAL9602](INFO): GPS fix at 20180523T195536: (36.802255, -121.786987)
2018-05-23T19:55:56.362Z,1527105356.362 [NAL9602](INFO): SBD MO Status=1, MOMSN=6029, MT Status=0, MTMSN=0
2018-05-23T19:55:56.431Z,1527105356.431 [NAL9602](INFO): Sent 25 bytes from file Logs/20180523T175334/Courier0048.lzma
2018-05-23T19:55:56.431Z,1527105356.431 [NAL9602](INFO): Packets left to send: 0
2018-05-23T19:55:56.432Z,1527105356.432 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T175334/Courier0048.lzma.parts/0000.sbd
2018-05-23T19:55:56.433Z,1527105356.433 [NAL9602](DEBUG): Completed sending Logs/20180523T175334/Courier0048.lzma
2018-05-23T19:56:09.621Z,1527105369.621 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T19:56:09.621Z,1527105369.621 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T19:56:09.621Z,1527105369.621 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T19:56:09.674Z,1527105369.674 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T19:56:10.019Z,1527105370.019 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T19:56:10.019Z,1527105370.019 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T19:56:10.396Z,1527105370.396 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T19:56:10.397Z,1527105370.397 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T19:56:10.397Z,1527105370.397 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T19:56:11.542Z,1527105371.542 [NAL9602](INFO): SBD MO Status=2, MOMSN=6030, MT Status=2, MTMSN=0
2018-05-23T19:56:11.542Z,1527105371.542 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T19:56:27.833Z,1527105387.833 [Startup:StartupSatComms:B](INFO): Timed out from 2018-05-23T19:55:27.7Z
2018-05-23T19:56:27.833Z,1527105387.833 [Startup:StartupSatComms:B] Stopped
2018-05-23T19:56:27.834Z,1527105387.834 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-05-23T19:56:27.834Z,1527105387.834 [Startup:StartupSatComms] Stopped
2018-05-23T19:56:27.834Z,1527105387.834 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-05-23T19:56:27.859Z,1527105387.859 [Startup](INFO): Completed Startup
2018-05-23T19:56:27.859Z,1527105387.859 [MissionManager](INFO): Startup is completed.
2018-05-23T19:56:27.859Z,1527105387.859 [MissionManager](INFO): Uninitializing Mission Startup
2018-05-23T19:56:27.859Z,1527105387.859 [Startup] Stopped
2018-05-23T19:56:27.859Z,1527105387.859 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-05-23T19:56:27.859Z,1527105387.859 [Startup:A.GoToSurface] Stopped
2018-05-23T19:56:27.860Z,1527105387.860 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-23T19:56:28.232Z,1527105388.232 [MissionManager](IMPORTANT): Started mission Default
2018-05-23T19:56:28.233Z,1527105388.233 [Default] Running Loop=1
2018-05-23T19:56:28.233Z,1527105388.233 [Default](DEBUG): Aggregate::initialize Default
2018-05-23T19:56:28.233Z,1527105388.233 [Default:B.GoToSurface] Running Loop=1
2018-05-23T19:56:28.233Z,1527105388.233 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-23T19:56:28.233Z,1527105388.233 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-23T19:56:28.234Z,1527105388.234 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-23T19:56:28.234Z,1527105388.234 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-23T19:56:28.234Z,1527105388.234 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-23T19:56:28.235Z,1527105388.235 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-23T19:56:28.235Z,1527105388.235 [Default:A.Wait] Running Loop=1
2018-05-23T19:56:28.235Z,1527105388.235 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-05-23T19:56:31.789Z,1527105391.789 [NAL9602](INFO): SBD MO Status=1, MOMSN=6030, MT Status=0, MTMSN=0
2018-05-23T19:56:31.843Z,1527105391.843 [NAL9602](INFO): Sent 306 bytes from file Logs/20180523T175334/Express0049.lzma
2018-05-23T19:56:31.843Z,1527105391.843 [NAL9602](INFO): Packets left to send: 0
2018-05-23T19:56:31.844Z,1527105391.844 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T175334/Express0049.lzma.parts/0000.sbd
2018-05-23T19:56:31.844Z,1527105391.844 [NAL9602](DEBUG): Completed sending Logs/20180523T175334/Express0049.lzma
2018-05-23T19:56:41.430Z,1527105401.430 [Default:A.Wait](INFO): Done Waiting.
2018-05-23T19:56:41.431Z,1527105401.431 [Default:A.Wait] Stopped
2018-05-23T19:56:41.431Z,1527105401.431 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T19:56:41.827Z,1527105401.827 [Default:CheckIn] Running Loop=1
2018-05-23T19:56:41.827Z,1527105401.827 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T19:56:41.827Z,1527105401.827 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T19:56:42.236Z,1527105402.236 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-23T19:56:43.787Z,1527105403.787 [NAL9602](INFO): GPS fix at 20180523T195641: (36.802170, -121.787032)
2018-05-23T19:56:43.864Z,1527105403.864 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T19:56:43.864Z,1527105403.864 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T19:56:44.259Z,1527105404.259 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-23T19:57:07.497Z,1527105427.497 [NAL9602](INFO): SBD MO Status=1, MOMSN=6031, MT Status=0, MTMSN=0
2018-05-23T19:57:07.550Z,1527105427.550 [NAL9602](INFO): Sent 212 bytes from file Logs/20180523T195259/Courier0004.lzma
2018-05-23T19:57:07.551Z,1527105427.551 [NAL9602](INFO): Packets left to send: 0
2018-05-23T19:57:07.552Z,1527105427.552 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T195259/Courier0004.lzma.parts/0000.sbd
2018-05-23T19:57:07.552Z,1527105427.552 [NAL9602](DEBUG): Completed sending Logs/20180523T195259/Courier0004.lzma
2018-05-23T19:57:15.487Z,1527105435.487 [DataOverHttps](IMPORTANT): SBD MTMSN=20180523T195713
2018-05-23T19:57:15.492Z,1527105435.492 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004661
2018-05-23T19:57:19.772Z,1527105439.772 [DataOverHttps](INFO): Sending 809 bytes from file Logs/20180523T195259/Express0001.lzma
2018-05-23T19:57:19.782Z,1527105439.782 [DataOverHttps](INFO): Received command:get platform_buoyancy_position
2018-05-23T19:57:20.532Z,1527105440.532 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2018-05-23T19:57:20.533Z,1527105440.533 [CommandLine](IMPORTANT): platform_buoyancy_position 952.311268 cc
2018-05-23T19:57:21.047Z,1527105441.047 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0001.lzma.bak
2018-05-23T19:57:21.048Z,1527105441.048 [DataOverHttps](INFO): SBD MOMSN=8290990
2018-05-23T19:57:25.027Z,1527105445.027 [NAL9602](INFO): SBD MO Status=1, MOMSN=6032, MT Status=0, MTMSN=0
2018-05-23T19:57:25.074Z,1527105445.074 [NAL9602](INFO): Sent 332 bytes from file Logs/20180523T195259/Express0001.lzma
2018-05-23T19:57:25.075Z,1527105445.075 [NAL9602](INFO): Packets left to send: 2
2018-05-23T19:57:25.076Z,1527105445.076 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T195259/Express0001.lzma.parts/0002.sbd
2018-05-23T19:57:25.161Z,1527105445.161 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T19:57:25.161Z,1527105445.161 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T19:57:25.161Z,1527105445.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T19:57:30.014Z,1527105450.014 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T195259/Courier0007.lzma
2018-05-23T19:57:30.803Z,1527105450.803 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0007.lzma.bak
2018-05-23T19:57:30.804Z,1527105450.804 [DataOverHttps](INFO): SBD MOMSN=8291014
2018-05-23T19:57:55.611Z,1527105475.611 [NAL9602](INFO): Powering down
2018-05-23T19:59:10.446Z,1527105550.446 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T19:59:10.446Z,1527105550.446 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T19:59:10.446Z,1527105550.446 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T19:59:10.471Z,1527105550.471 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T19:59:10.851Z,1527105550.851 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T19:59:10.851Z,1527105550.851 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T19:59:11.246Z,1527105551.246 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T19:59:11.246Z,1527105551.246 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T19:59:11.247Z,1527105551.247 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:02:11.455Z,1527105731.455 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:02:11.455Z,1527105731.455 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T20:02:11.455Z,1527105731.455 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:02:11.467Z,1527105731.467 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:02:11.874Z,1527105731.874 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:02:11.874Z,1527105731.874 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T20:02:12.263Z,1527105732.263 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:02:12.263Z,1527105732.263 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:02:12.263Z,1527105732.263 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:02:25.465Z,1527105745.465 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T20:02:25.465Z,1527105745.465 [Default:CheckIn:C.Wait] Stopped
2018-05-23T20:02:25.465Z,1527105745.465 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T20:02:25.465Z,1527105745.465 [Default:CheckIn:D] Running Loop=1
2018-05-23T20:02:25.871Z,1527105745.871 [Default:CheckIn:D] Stopped
2018-05-23T20:02:25.871Z,1527105745.871 [Default:CheckIn:E] Running Loop=1
2018-05-23T20:02:26.264Z,1527105746.264 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.960540 min 
2018-05-23T20:02:26.266Z,1527105746.266 [Default:CheckIn:E] Stopped
2018-05-23T20:02:26.266Z,1527105746.266 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T20:02:26.266Z,1527105746.266 [Default:CheckIn] Stopped
2018-05-23T20:02:26.267Z,1527105746.267 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T20:02:26.267Z,1527105746.267 [Default:CheckIn](INFO): Running loop #2
2018-05-23T20:02:26.267Z,1527105746.267 [Default:CheckIn] Running Loop=2
2018-05-23T20:02:26.267Z,1527105746.267 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T20:02:26.267Z,1527105746.267 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T20:02:27.007Z,1527105747.007 [NAL9602](INFO): Powering up
2018-05-23T20:02:37.880Z,1527105757.880 [NAL9602](INFO): NAL9602 initialized
2018-05-23T20:03:09.592Z,1527105789.592 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T20:04:15.720Z,1527105855.720 [NAL9602](INFO): SBD MO Status=0, MOMSN=6033, MT Status=0, MTMSN=0
2018-05-23T20:04:15.720Z,1527105855.720 [NAL9602](INFO): No messages in MT queue
2018-05-23T20:04:16.923Z,1527105856.923 [NAL9602](INFO): GPS fix at 20180523T200414: (36.802131, -121.787066)
2018-05-23T20:04:16.986Z,1527105856.986 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T20:04:16.986Z,1527105856.986 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T20:04:21.734Z,1527105861.734 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180523T195259/Courier0010.lzma
2018-05-23T20:04:22.531Z,1527105862.531 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0010.lzma.bak
2018-05-23T20:04:22.532Z,1527105862.532 [DataOverHttps](INFO): SBD MOMSN=8291030
2018-05-23T20:04:31.770Z,1527105871.770 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20180523T195259/Express0005.lzma
2018-05-23T20:04:32.567Z,1527105872.567 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0005.lzma.bak
2018-05-23T20:04:32.568Z,1527105872.568 [DataOverHttps](INFO): SBD MOMSN=8291033
2018-05-23T20:04:42.098Z,1527105882.098 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20180523T195259/Express0008.lzma
2018-05-23T20:04:42.895Z,1527105882.895 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0008.lzma.bak
2018-05-23T20:04:42.896Z,1527105882.896 [DataOverHttps](INFO): SBD MOMSN=8291036
2018-05-23T20:04:51.888Z,1527105891.888 [NAL9602](INFO): Powering down
2018-05-23T20:04:52.010Z,1527105892.010 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20180523T195259/Express0011.lzma
2018-05-23T20:04:52.799Z,1527105892.799 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0011.lzma.bak
2018-05-23T20:04:52.800Z,1527105892.800 [DataOverHttps](INFO): SBD MOMSN=8291041
2018-05-23T20:04:53.690Z,1527105893.690 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T20:04:53.690Z,1527105893.690 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T20:04:53.694Z,1527105893.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T20:05:12.628Z,1527105912.628 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:05:12.628Z,1527105912.628 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T20:05:12.628Z,1527105912.628 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:05:12.641Z,1527105912.641 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:05:13.054Z,1527105913.054 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:05:13.054Z,1527105913.054 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T20:05:13.434Z,1527105913.434 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:05:13.435Z,1527105913.435 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:05:13.435Z,1527105913.435 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:08:13.855Z,1527106093.855 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:08:13.855Z,1527106093.855 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T20:08:13.855Z,1527106093.855 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:08:13.867Z,1527106093.867 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:08:14.262Z,1527106094.262 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:08:14.262Z,1527106094.262 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T20:08:14.631Z,1527106094.631 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:08:14.631Z,1527106094.631 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:08:14.631Z,1527106094.631 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:09:53.937Z,1527106193.937 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T20:09:53.938Z,1527106193.938 [Default:CheckIn:C.Wait] Stopped
2018-05-23T20:09:53.938Z,1527106193.938 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T20:09:53.938Z,1527106193.938 [Default:CheckIn:D] Running Loop=1
2018-05-23T20:09:54.317Z,1527106194.317 [Default:CheckIn:D] Stopped
2018-05-23T20:09:54.317Z,1527106194.317 [Default:CheckIn:E] Running Loop=1
2018-05-23T20:09:54.696Z,1527106194.696 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.434740 min 
2018-05-23T20:09:54.697Z,1527106194.697 [Default:CheckIn:E] Stopped
2018-05-23T20:09:54.697Z,1527106194.697 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T20:09:54.697Z,1527106194.697 [Default:CheckIn] Stopped
2018-05-23T20:09:54.697Z,1527106194.697 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T20:09:54.697Z,1527106194.697 [Default:CheckIn](INFO): Running loop #3
2018-05-23T20:09:54.697Z,1527106194.697 [Default:CheckIn] Running Loop=3
2018-05-23T20:09:54.697Z,1527106194.697 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T20:09:54.698Z,1527106194.698 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T20:09:58.071Z,1527106198.071 [NAL9602](INFO): Powering up
2018-05-23T20:10:09.240Z,1527106209.240 [NAL9602](INFO): NAL9602 initialized
2018-05-23T20:10:43.241Z,1527106243.241 [NAL9602](INFO): SBD MO Status=0, MOMSN=6034, MT Status=0, MTMSN=0
2018-05-23T20:10:43.241Z,1527106243.241 [NAL9602](INFO): No messages in MT queue
2018-05-23T20:10:52.843Z,1527106252.843 [NAL9602](INFO): GPS fix at 20180523T201050: (36.802206, -121.787019)
2018-05-23T20:10:52.922Z,1527106252.922 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T20:10:52.923Z,1527106252.923 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T20:10:57.670Z,1527106257.670 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180523T195259/Courier0013.lzma
2018-05-23T20:10:58.467Z,1527106258.467 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0013.lzma.bak
2018-05-23T20:10:58.468Z,1527106258.468 [DataOverHttps](INFO): SBD MOMSN=8291045
2018-05-23T20:11:08.134Z,1527106268.134 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T195259/Express0014.lzma
2018-05-23T20:11:08.932Z,1527106268.932 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0014.lzma.bak
2018-05-23T20:11:08.932Z,1527106268.932 [DataOverHttps](INFO): SBD MOMSN=8291048
2018-05-23T20:11:09.775Z,1527106269.775 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T20:11:09.775Z,1527106269.775 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T20:11:09.776Z,1527106269.776 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T20:11:14.656Z,1527106274.656 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:11:14.657Z,1527106274.657 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T20:11:14.657Z,1527106274.657 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:11:14.669Z,1527106274.669 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:11:15.074Z,1527106275.074 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:11:15.074Z,1527106275.074 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T20:11:15.450Z,1527106275.450 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:11:15.451Z,1527106275.451 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:11:15.451Z,1527106275.451 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:11:27.411Z,1527106287.411 [NAL9602](INFO): Powering down
2018-05-23T20:13:09.888Z,1527106389.888 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T20:14:10.662Z,1527106450.662 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 31 sticks.
2018-05-23T20:14:10.663Z,1527106450.663 [BPC1] Data Fault, FailCount= 1
2018-05-23T20:14:10.663Z,1527106450.663 [BPC1](ERROR): Data Fault
2018-05-23T20:14:10.687Z,1527106450.687 [CBIT](ERROR): Data Fault in component: BPC1
2018-05-23T20:14:14.409Z,1527106454.409 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-23T20:14:14.409Z,1527106454.409 [BPC1] No Fault, FailCount= 1
2018-05-23T20:14:15.597Z,1527106455.597 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:14:15.597Z,1527106455.597 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T20:14:15.597Z,1527106455.597 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:14:15.609Z,1527106455.609 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:14:16.010Z,1527106456.010 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:14:16.010Z,1527106456.010 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T20:14:16.419Z,1527106456.419 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:14:16.419Z,1527106456.419 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:14:16.419Z,1527106456.419 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:16:10.189Z,1527106570.189 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T20:16:10.190Z,1527106570.190 [Default:CheckIn:C.Wait] Stopped
2018-05-23T20:16:10.190Z,1527106570.190 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T20:16:10.190Z,1527106570.190 [Default:CheckIn:D] Running Loop=1
2018-05-23T20:16:10.598Z,1527106570.598 [Default:CheckIn:D] Stopped
2018-05-23T20:16:10.598Z,1527106570.598 [Default:CheckIn:E] Running Loop=1
2018-05-23T20:16:11.003Z,1527106571.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.706089 min 
2018-05-23T20:16:11.003Z,1527106571.003 [Default:CheckIn:E] Stopped
2018-05-23T20:16:11.003Z,1527106571.003 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T20:16:11.004Z,1527106571.004 [Default:CheckIn] Stopped
2018-05-23T20:16:11.004Z,1527106571.004 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T20:16:11.004Z,1527106571.004 [Default:CheckIn](INFO): Running loop #4
2018-05-23T20:16:11.004Z,1527106571.004 [Default:CheckIn] Running Loop=4
2018-05-23T20:16:11.004Z,1527106571.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T20:16:11.004Z,1527106571.004 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T20:16:14.167Z,1527106574.167 [NAL9602](INFO): Powering up
2018-05-23T20:16:25.320Z,1527106585.320 [NAL9602](INFO): NAL9602 initialized
2018-05-23T20:16:49.321Z,1527106609.321 [NAL9602](INFO): SBD MO Status=0, MOMSN=6035, MT Status=0, MTMSN=0
2018-05-23T20:16:49.321Z,1527106609.321 [NAL9602](INFO): No messages in MT queue
2018-05-23T20:17:07.723Z,1527106627.723 [NAL9602](INFO): GPS fix at 20180523T201705: (36.802202, -121.787010)
2018-05-23T20:17:07.776Z,1527106627.776 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T20:17:07.776Z,1527106627.776 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T20:17:12.546Z,1527106632.546 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20180523T195259/Courier0016.lzma
2018-05-23T20:17:13.343Z,1527106633.343 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0016.lzma.bak
2018-05-23T20:17:13.344Z,1527106633.344 [DataOverHttps](INFO): SBD MOMSN=8291053
2018-05-23T20:17:17.748Z,1527106637.748 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:17:17.748Z,1527106637.748 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T20:17:17.748Z,1527106637.748 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:17:17.761Z,1527106637.761 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:17:17.907Z,1527106637.907 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:17:17.907Z,1527106637.907 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T20:17:18.256Z,1527106638.256 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:17:18.257Z,1527106638.257 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:17:18.257Z,1527106638.257 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:17:23.006Z,1527106643.006 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20180523T195259/Express0017.lzma
2018-05-23T20:17:23.803Z,1527106643.803 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0017.lzma.bak
2018-05-23T20:17:23.804Z,1527106643.804 [DataOverHttps](INFO): SBD MOMSN=8291056
2018-05-23T20:17:24.646Z,1527106644.646 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T20:17:24.647Z,1527106644.647 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T20:17:24.647Z,1527106644.647 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T20:17:42.299Z,1527106662.299 [NAL9602](INFO): Powering down
2018-05-23T20:20:18.315Z,1527106818.315 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:20:18.315Z,1527106818.315 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T20:20:18.315Z,1527106818.315 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:20:18.353Z,1527106818.353 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:20:18.729Z,1527106818.729 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:20:18.729Z,1527106818.729 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T20:20:19.115Z,1527106819.115 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:20:19.115Z,1527106819.115 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:20:19.115Z,1527106819.115 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:22:25.100Z,1527106945.100 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T20:22:25.100Z,1527106945.100 [Default:CheckIn:C.Wait] Stopped
2018-05-23T20:22:25.100Z,1527106945.100 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T20:22:25.100Z,1527106945.100 [Default:CheckIn:D] Running Loop=1
2018-05-23T20:22:25.494Z,1527106945.494 [Default:CheckIn:D] Stopped
2018-05-23T20:22:25.494Z,1527106945.494 [Default:CheckIn:E] Running Loop=1
2018-05-23T20:22:25.876Z,1527106945.876 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.954354 min 
2018-05-23T20:22:25.876Z,1527106945.876 [Default:CheckIn:E] Stopped
2018-05-23T20:22:25.876Z,1527106945.876 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T20:22:25.876Z,1527106945.876 [Default:CheckIn] Stopped
2018-05-23T20:22:25.877Z,1527106945.877 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T20:22:25.877Z,1527106945.877 [Default:CheckIn](INFO): Running loop #5
2018-05-23T20:22:25.877Z,1527106945.877 [Default:CheckIn] Running Loop=5
2018-05-23T20:22:25.877Z,1527106945.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T20:22:25.877Z,1527106945.877 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T20:22:26.639Z,1527106946.639 [NAL9602](INFO): Powering up
2018-05-23T20:22:37.444Z,1527106957.444 [NAL9602](INFO): NAL9602 initialized
2018-05-23T20:23:09.919Z,1527106989.919 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T20:23:19.491Z,1527106999.491 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:23:19.492Z,1527106999.492 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T20:23:19.492Z,1527106999.492 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:23:19.517Z,1527106999.517 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:23:19.896Z,1527106999.896 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:23:19.896Z,1527106999.896 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T20:23:20.287Z,1527107000.287 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:23:20.287Z,1527107000.287 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:23:20.288Z,1527107000.288 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:23:34.293Z,1527107014.293 [NAL9602](INFO): SBD MO Status=0, MOMSN=6036, MT Status=0, MTMSN=0
2018-05-23T20:23:34.293Z,1527107014.293 [NAL9602](INFO): No messages in MT queue
2018-05-23T20:23:35.495Z,1527107015.495 [NAL9602](INFO): GPS fix at 20180523T202332: (36.802217, -121.786997)
2018-05-23T20:23:35.559Z,1527107015.559 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T20:23:35.559Z,1527107015.559 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T20:23:40.554Z,1527107020.554 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180523T195259/Courier0019.lzma
2018-05-23T20:23:41.352Z,1527107021.352 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0019.lzma.bak
2018-05-23T20:23:41.352Z,1527107021.352 [DataOverHttps](INFO): SBD MOMSN=8291061
2018-05-23T20:23:50.970Z,1527107030.970 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T195259/Express0020.lzma
2018-05-23T20:23:51.767Z,1527107031.767 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0020.lzma.bak
2018-05-23T20:23:51.768Z,1527107031.768 [DataOverHttps](INFO): SBD MOMSN=8291064
2018-05-23T20:23:52.613Z,1527107032.613 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T20:23:52.613Z,1527107032.613 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T20:23:52.613Z,1527107032.613 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T20:24:07.059Z,1527107047.059 [NAL9602](INFO): Powering down
2018-05-23T20:26:20.678Z,1527107180.678 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:26:20.678Z,1527107180.678 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T20:26:20.678Z,1527107180.678 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:26:20.699Z,1527107180.699 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:26:21.102Z,1527107181.102 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:26:21.102Z,1527107181.102 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T20:26:21.483Z,1527107181.483 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:26:21.483Z,1527107181.483 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:26:21.483Z,1527107181.483 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:28:53.088Z,1527107333.088 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T20:28:53.088Z,1527107333.088 [Default:CheckIn:C.Wait] Stopped
2018-05-23T20:28:53.088Z,1527107333.088 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T20:28:53.088Z,1527107333.088 [Default:CheckIn:D] Running Loop=1
2018-05-23T20:28:53.484Z,1527107333.484 [Default:CheckIn:D] Stopped
2018-05-23T20:28:53.484Z,1527107333.484 [Default:CheckIn:E] Running Loop=1
2018-05-23T20:28:53.901Z,1527107333.901 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.420846 min 
2018-05-23T20:28:53.901Z,1527107333.901 [Default:CheckIn:E] Stopped
2018-05-23T20:28:53.901Z,1527107333.901 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T20:28:53.902Z,1527107333.902 [Default:CheckIn] Stopped
2018-05-23T20:28:53.902Z,1527107333.902 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T20:28:53.902Z,1527107333.902 [Default:CheckIn](INFO): Running loop #6
2018-05-23T20:28:53.902Z,1527107333.902 [Default:CheckIn] Running Loop=6
2018-05-23T20:28:53.902Z,1527107333.902 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T20:28:53.902Z,1527107333.902 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T20:28:57.343Z,1527107337.343 [NAL9602](INFO): Powering up
2018-05-23T20:29:08.224Z,1527107348.224 [NAL9602](INFO): NAL9602 initialized
2018-05-23T20:29:21.866Z,1527107361.866 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:29:21.866Z,1527107361.866 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T20:29:21.866Z,1527107361.866 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:29:21.879Z,1527107361.879 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:29:22.300Z,1527107362.300 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:29:22.300Z,1527107362.300 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T20:29:22.678Z,1527107362.678 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:29:22.679Z,1527107362.679 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:29:22.679Z,1527107362.679 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:31:35.069Z,1527107495.069 [NAL9602](INFO): SBD MO Status=2, MOMSN=6037, MT Status=2, MTMSN=0
2018-05-23T20:31:35.069Z,1527107495.069 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T20:31:46.269Z,1527107506.269 [NAL9602](INFO): SBD MO Status=0, MOMSN=6037, MT Status=0, MTMSN=0
2018-05-23T20:31:46.269Z,1527107506.269 [NAL9602](INFO): No messages in MT queue
2018-05-23T20:31:47.471Z,1527107507.471 [NAL9602](INFO): GPS fix at 20180523T203144: (36.802184, -121.787027)
2018-05-23T20:31:47.534Z,1527107507.534 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T20:31:47.534Z,1527107507.534 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T20:31:51.903Z,1527107511.903 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180523T195259/Courier0022.lzma
2018-05-23T20:31:52.704Z,1527107512.704 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0022.lzma.bak
2018-05-23T20:31:52.704Z,1527107512.704 [DataOverHttps](INFO): SBD MOMSN=8291068
2018-05-23T20:32:02.344Z,1527107522.344 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T195259/Express0023.lzma
2018-05-23T20:32:03.139Z,1527107523.139 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0023.lzma.bak
2018-05-23T20:32:03.140Z,1527107523.140 [DataOverHttps](INFO): SBD MOMSN=8291071
2018-05-23T20:32:03.965Z,1527107523.965 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T20:32:03.965Z,1527107523.965 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T20:32:03.965Z,1527107523.965 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T20:32:18.983Z,1527107538.983 [NAL9602](INFO): Powering down
2018-05-23T20:32:23.040Z,1527107543.040 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:32:23.040Z,1527107543.040 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T20:32:23.040Z,1527107543.040 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:32:23.053Z,1527107543.053 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:32:23.459Z,1527107543.459 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:32:23.459Z,1527107543.459 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T20:32:23.815Z,1527107543.815 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:32:23.816Z,1527107543.816 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:32:23.816Z,1527107543.816 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:33:10.220Z,1527107590.220 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T20:35:23.863Z,1527107723.863 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:35:23.864Z,1527107723.864 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T20:35:23.864Z,1527107723.864 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:35:23.900Z,1527107723.900 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:35:24.300Z,1527107724.300 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:35:24.300Z,1527107724.300 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T20:35:24.669Z,1527107724.669 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:35:24.669Z,1527107724.669 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:35:24.669Z,1527107724.669 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:37:04.448Z,1527107824.448 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T20:37:04.449Z,1527107824.449 [Default:CheckIn:C.Wait] Stopped
2018-05-23T20:37:04.449Z,1527107824.449 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T20:37:04.449Z,1527107824.449 [Default:CheckIn:D] Running Loop=1
2018-05-23T20:37:04.855Z,1527107824.855 [Default:CheckIn:D] Stopped
2018-05-23T20:37:04.855Z,1527107824.855 [Default:CheckIn:E] Running Loop=1
2018-05-23T20:37:05.253Z,1527107825.253 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.610368 min 
2018-05-23T20:37:05.254Z,1527107825.254 [Default:CheckIn:E] Stopped
2018-05-23T20:37:05.254Z,1527107825.254 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T20:37:05.254Z,1527107825.254 [Default:CheckIn] Stopped
2018-05-23T20:37:05.254Z,1527107825.254 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T20:37:05.255Z,1527107825.255 [Default:CheckIn](INFO): Running loop #7
2018-05-23T20:37:05.255Z,1527107825.255 [Default:CheckIn] Running Loop=7
2018-05-23T20:37:05.255Z,1527107825.255 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T20:37:05.255Z,1527107825.255 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T20:37:08.702Z,1527107828.702 [NAL9602](INFO): Powering up
2018-05-23T20:37:19.583Z,1527107839.583 [NAL9602](INFO): NAL9602 initialized
2018-05-23T20:37:55.585Z,1527107875.585 [NAL9602](INFO): SBD MO Status=2, MOMSN=6038, MT Status=2, MTMSN=0
2018-05-23T20:37:55.585Z,1527107875.585 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T20:38:15.177Z,1527107895.177 [NAL9602](INFO): SBD MO Status=2, MOMSN=6038, MT Status=2, MTMSN=0
2018-05-23T20:38:15.177Z,1527107895.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T20:38:24.828Z,1527107904.828 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:38:24.828Z,1527107904.828 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T20:38:24.828Z,1527107904.828 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:38:24.841Z,1527107904.841 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:38:25.250Z,1527107905.250 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:38:25.251Z,1527107905.251 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T20:38:25.646Z,1527107905.646 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:38:25.647Z,1527107905.647 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:38:25.647Z,1527107905.647 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:41:24.005Z,1527108084.005 [NAL9602](INFO): SBD MO Status=2, MOMSN=6038, MT Status=2, MTMSN=0
2018-05-23T20:41:24.005Z,1527108084.005 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T20:41:25.662Z,1527108085.662 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:41:25.662Z,1527108085.662 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-05-23T20:41:25.662Z,1527108085.662 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:41:25.687Z,1527108085.687 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:41:26.063Z,1527108086.063 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:41:26.063Z,1527108086.063 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-05-23T20:41:26.460Z,1527108086.460 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:41:26.460Z,1527108086.460 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:41:26.460Z,1527108086.460 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:41:41.205Z,1527108101.205 [NAL9602](INFO): SBD MO Status=2, MOMSN=6038, MT Status=2, MTMSN=0
2018-05-23T20:41:41.205Z,1527108101.205 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T20:41:56.405Z,1527108116.405 [NAL9602](INFO): SBD MO Status=2, MOMSN=6038, MT Status=2, MTMSN=0
2018-05-23T20:41:56.405Z,1527108116.405 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T20:42:05.261Z,1527108125.261 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-23T20:37:05.3Z
2018-05-23T20:42:05.261Z,1527108125.261 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T20:42:05.261Z,1527108125.261 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T20:42:09.642Z,1527108129.642 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T195259/Courier0025.lzma
2018-05-23T20:42:10.443Z,1527108130.443 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0025.lzma.bak
2018-05-23T20:42:10.444Z,1527108130.444 [DataOverHttps](INFO): SBD MOMSN=8291075
2018-05-23T20:42:19.362Z,1527108139.362 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T195259/Express0026.lzma
2018-05-23T20:42:20.128Z,1527108140.128 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0026.lzma.bak
2018-05-23T20:42:20.128Z,1527108140.128 [DataOverHttps](INFO): SBD MOMSN=8291077
2018-05-23T20:42:20.968Z,1527108140.968 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T20:42:20.968Z,1527108140.968 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T20:42:20.968Z,1527108140.968 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T20:42:30.777Z,1527108150.777 [NAL9602](INFO): SBD MO Status=0, MOMSN=6038, MT Status=0, MTMSN=0
2018-05-23T20:42:30.777Z,1527108150.777 [NAL9602](INFO): No messages in MT queue
2018-05-23T20:43:01.575Z,1527108181.575 [NAL9602](INFO): Powering down
2018-05-23T20:43:10.451Z,1527108190.451 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T20:44:26.508Z,1527108266.508 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:44:26.508Z,1527108266.508 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2018-05-23T20:44:26.508Z,1527108266.508 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:44:26.521Z,1527108266.521 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:44:26.925Z,1527108266.925 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:44:26.925Z,1527108266.925 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2018-05-23T20:44:27.309Z,1527108267.309 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:44:27.309Z,1527108267.309 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:44:27.310Z,1527108267.310 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:47:21.445Z,1527108441.445 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T20:47:21.445Z,1527108441.445 [Default:CheckIn:C.Wait] Stopped
2018-05-23T20:47:21.445Z,1527108441.445 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T20:47:21.445Z,1527108441.445 [Default:CheckIn:D] Running Loop=1
2018-05-23T20:47:21.868Z,1527108441.868 [Default:CheckIn:D] Stopped
2018-05-23T20:47:21.868Z,1527108441.868 [Default:CheckIn:E] Running Loop=1
2018-05-23T20:47:22.252Z,1527108442.252 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.893925 min 
2018-05-23T20:47:22.252Z,1527108442.252 [Default:CheckIn:E] Stopped
2018-05-23T20:47:22.252Z,1527108442.252 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T20:47:22.252Z,1527108442.252 [Default:CheckIn] Stopped
2018-05-23T20:47:22.253Z,1527108442.253 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T20:47:22.253Z,1527108442.253 [Default:CheckIn](INFO): Running loop #8
2018-05-23T20:47:22.253Z,1527108442.253 [Default:CheckIn] Running Loop=8
2018-05-23T20:47:22.253Z,1527108442.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T20:47:22.253Z,1527108442.253 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T20:47:25.710Z,1527108445.710 [NAL9602](INFO): Powering up
2018-05-23T20:47:27.455Z,1527108447.455 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:47:27.455Z,1527108447.455 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2018-05-23T20:47:27.455Z,1527108447.455 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:47:27.467Z,1527108447.467 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:47:27.867Z,1527108447.867 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:47:27.867Z,1527108447.867 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2018-05-23T20:47:28.254Z,1527108448.254 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:47:28.255Z,1527108448.255 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:47:28.255Z,1527108448.255 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:47:36.603Z,1527108456.603 [NAL9602](INFO): NAL9602 initialized
2018-05-23T20:47:37.404Z,1527108457.404 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-23T20:47:37.404Z,1527108457.404 [NAL9602] Data Fault, FailCount= 1
2018-05-23T20:47:37.404Z,1527108457.404 [NAL9602](ERROR): Data Fault
2018-05-23T20:47:37.474Z,1527108457.474 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-23T20:47:37.803Z,1527108457.803 [NAL9602](INFO): Powering down
2018-05-23T20:47:38.668Z,1527108458.668 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-23T20:47:38.668Z,1527108458.668 [NAL9602] No Fault, FailCount= 1
2018-05-23T20:48:08.203Z,1527108488.203 [NAL9602](INFO): Powering up NAL9602
2018-05-23T20:48:19.403Z,1527108499.403 [NAL9602](INFO): NAL9602 initialized
2018-05-23T20:49:38.061Z,1527108578.061 [NAL9602](INFO): SBD MO Status=0, MOMSN=6039, MT Status=0, MTMSN=0
2018-05-23T20:49:38.061Z,1527108578.061 [NAL9602](INFO): No messages in MT queue
2018-05-23T20:50:28.510Z,1527108628.510 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:50:28.510Z,1527108628.510 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2018-05-23T20:50:28.510Z,1527108628.510 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:50:28.522Z,1527108628.522 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:50:28.935Z,1527108628.935 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:50:28.935Z,1527108628.935 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2018-05-23T20:50:29.302Z,1527108629.302 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:50:29.303Z,1527108629.303 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:50:29.303Z,1527108629.303 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:51:45.763Z,1527108705.763 [NAL9602](INFO): GPS fix at 20180523T205144: (36.802154, -121.787068)
2018-05-23T20:51:45.825Z,1527108705.825 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T20:51:45.825Z,1527108705.825 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T20:51:50.290Z,1527108710.290 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180523T195259/Courier0028.lzma
2018-05-23T20:51:51.087Z,1527108711.087 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0028.lzma.bak
2018-05-23T20:51:51.088Z,1527108711.088 [DataOverHttps](INFO): SBD MOMSN=8291082
2018-05-23T20:52:00.414Z,1527108720.414 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20180523T195259/Express0029.lzma
2018-05-23T20:52:01.211Z,1527108721.211 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0029.lzma.bak
2018-05-23T20:52:01.212Z,1527108721.212 [DataOverHttps](INFO): SBD MOMSN=8291085
2018-05-23T20:52:02.063Z,1527108722.063 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T20:52:02.063Z,1527108722.063 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T20:52:02.063Z,1527108722.063 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T20:52:20.647Z,1527108740.647 [NAL9602](INFO): Powering down
2018-05-23T20:53:10.695Z,1527108790.695 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T20:53:29.468Z,1527108809.468 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:53:29.468Z,1527108809.468 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T20:53:29.468Z,1527108809.468 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:53:29.481Z,1527108809.481 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:53:29.895Z,1527108809.895 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:53:29.895Z,1527108809.895 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T20:53:30.264Z,1527108810.264 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:53:30.264Z,1527108810.264 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:53:30.265Z,1527108810.265 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:56:30.274Z,1527108990.274 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:56:30.274Z,1527108990.274 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T20:56:30.274Z,1527108990.274 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:56:30.287Z,1527108990.287 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:56:30.693Z,1527108990.693 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:56:30.693Z,1527108990.693 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T20:56:31.087Z,1527108991.087 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:56:31.087Z,1527108991.087 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:56:31.087Z,1527108991.087 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T20:57:02.283Z,1527109022.283 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T20:57:02.283Z,1527109022.283 [Default:CheckIn:C.Wait] Stopped
2018-05-23T20:57:02.283Z,1527109022.283 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T20:57:02.283Z,1527109022.283 [Default:CheckIn:D] Running Loop=1
2018-05-23T20:57:02.681Z,1527109022.681 [Default:CheckIn:D] Stopped
2018-05-23T20:57:02.681Z,1527109022.681 [Default:CheckIn:E] Running Loop=1
2018-05-23T20:57:03.084Z,1527109023.084 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.574129 min 
2018-05-23T20:57:03.084Z,1527109023.084 [Default:CheckIn:E] Stopped
2018-05-23T20:57:03.084Z,1527109023.085 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T20:57:03.085Z,1527109023.085 [Default:CheckIn] Stopped
2018-05-23T20:57:03.085Z,1527109023.085 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T20:57:03.085Z,1527109023.085 [Default:CheckIn](INFO): Running loop #9
2018-05-23T20:57:03.085Z,1527109023.085 [Default:CheckIn] Running Loop=9
2018-05-23T20:57:03.085Z,1527109023.085 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T20:57:03.085Z,1527109023.085 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T20:57:03.831Z,1527109023.831 [NAL9602](INFO): Powering up
2018-05-23T20:57:15.004Z,1527109035.004 [NAL9602](INFO): NAL9602 initialized
2018-05-23T20:58:12.177Z,1527109092.177 [NAL9602](INFO): SBD MO Status=2, MOMSN=6040, MT Status=2, MTMSN=0
2018-05-23T20:58:12.177Z,1527109092.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T20:58:44.577Z,1527109124.577 [NAL9602](INFO): SBD MO Status=0, MOMSN=6040, MT Status=0, MTMSN=0
2018-05-23T20:58:44.577Z,1527109124.577 [NAL9602](INFO): No messages in MT queue
2018-05-23T20:59:31.439Z,1527109171.439 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T20:59:31.439Z,1527109171.439 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T20:59:31.439Z,1527109171.439 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T20:59:31.471Z,1527109171.471 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T20:59:31.851Z,1527109171.851 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T20:59:31.851Z,1527109171.851 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T20:59:32.246Z,1527109172.246 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T20:59:32.246Z,1527109172.246 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T20:59:32.246Z,1527109172.246 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:00:22.679Z,1527109222.679 [NAL9602](INFO): GPS fix at 20180523T210020: (36.802081, -121.787098)
2018-05-23T21:00:22.750Z,1527109222.750 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T21:00:22.750Z,1527109222.750 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T21:00:27.119Z,1527109227.119 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180523T195259/Courier0031.lzma
2018-05-23T21:00:27.875Z,1527109227.875 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0031.lzma.bak
2018-05-23T21:00:27.876Z,1527109227.876 [DataOverHttps](INFO): SBD MOMSN=8291090
2018-05-23T21:00:37.142Z,1527109237.142 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T195259/Express0032.lzma
2018-05-23T21:00:37.939Z,1527109237.939 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0032.lzma.bak
2018-05-23T21:00:37.940Z,1527109237.940 [DataOverHttps](INFO): SBD MOMSN=8291093
2018-05-23T21:00:38.798Z,1527109238.798 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T21:00:38.798Z,1527109238.798 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T21:00:38.798Z,1527109238.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T21:00:57.383Z,1527109257.383 [NAL9602](INFO): Powering down
2018-05-23T21:02:32.605Z,1527109352.605 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:02:32.605Z,1527109352.605 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T21:02:32.605Z,1527109352.605 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:02:32.617Z,1527109352.617 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:02:33.031Z,1527109353.031 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:02:33.031Z,1527109353.031 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T21:02:33.409Z,1527109353.409 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:02:33.410Z,1527109353.410 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:02:33.411Z,1527109353.411 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:03:10.987Z,1527109390.987 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T21:05:33.468Z,1527109533.468 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:05:33.468Z,1527109533.468 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T21:05:33.468Z,1527109533.468 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:05:33.481Z,1527109533.481 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:05:33.881Z,1527109533.881 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:05:33.881Z,1527109533.881 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T21:05:34.270Z,1527109534.270 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:05:34.271Z,1527109534.271 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:05:34.271Z,1527109534.271 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:05:39.063Z,1527109539.063 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T21:05:39.063Z,1527109539.063 [Default:CheckIn:C.Wait] Stopped
2018-05-23T21:05:39.063Z,1527109539.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T21:05:39.063Z,1527109539.063 [Default:CheckIn:D] Running Loop=1
2018-05-23T21:05:39.472Z,1527109539.472 [Default:CheckIn:D] Stopped
2018-05-23T21:05:39.472Z,1527109539.472 [Default:CheckIn:E] Running Loop=1
2018-05-23T21:05:39.861Z,1527109539.861 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.187313 min 
2018-05-23T21:05:39.861Z,1527109539.861 [Default:CheckIn:E] Stopped
2018-05-23T21:05:39.862Z,1527109539.862 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T21:05:39.862Z,1527109539.862 [Default:CheckIn] Stopped
2018-05-23T21:05:39.862Z,1527109539.862 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T21:05:39.862Z,1527109539.862 [Default:CheckIn](INFO): Running loop #10
2018-05-23T21:05:39.862Z,1527109539.862 [Default:CheckIn] Running Loop=10
2018-05-23T21:05:39.862Z,1527109539.862 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T21:05:39.863Z,1527109539.863 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T21:05:43.319Z,1527109543.319 [NAL9602](INFO): Powering up
2018-05-23T21:05:54.188Z,1527109554.188 [NAL9602](INFO): NAL9602 initialized
2018-05-23T21:06:11.389Z,1527109571.389 [NAL9602](INFO): SBD MO Status=0, MOMSN=6041, MT Status=0, MTMSN=0
2018-05-23T21:06:11.389Z,1527109571.389 [NAL9602](INFO): No messages in MT queue
2018-05-23T21:08:17.635Z,1527109697.635 [NAL9602](INFO): GPS fix at 20180523T210815: (36.802234, -121.786933)
2018-05-23T21:08:17.700Z,1527109697.700 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T21:08:17.700Z,1527109697.700 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T21:08:22.446Z,1527109702.446 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180523T195259/Courier0034.lzma
2018-05-23T21:08:23.243Z,1527109703.243 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0034.lzma.bak
2018-05-23T21:08:23.244Z,1527109703.244 [DataOverHttps](INFO): SBD MOMSN=8291097
2018-05-23T21:08:32.562Z,1527109712.562 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T195259/Express0035.lzma
2018-05-23T21:08:33.360Z,1527109713.360 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0035.lzma.bak
2018-05-23T21:08:33.360Z,1527109713.360 [DataOverHttps](INFO): SBD MOMSN=8291100
2018-05-23T21:08:34.206Z,1527109714.206 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T21:08:34.206Z,1527109714.206 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T21:08:34.206Z,1527109714.206 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T21:08:34.351Z,1527109714.351 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:08:34.352Z,1527109714.352 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T21:08:34.352Z,1527109714.352 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:08:34.364Z,1527109714.364 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:08:37.578Z,1527109717.578 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:08:37.578Z,1527109717.578 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T21:08:37.791Z,1527109717.791 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:08:37.791Z,1527109717.791 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:08:37.792Z,1527109717.792 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:08:52.403Z,1527109732.403 [NAL9602](INFO): Powering down
2018-05-23T21:11:38.222Z,1527109898.222 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:11:38.222Z,1527109898.222 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T21:11:38.222Z,1527109898.222 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:11:38.235Z,1527109898.235 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:11:38.387Z,1527109898.387 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:11:38.387Z,1527109898.387 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T21:11:38.709Z,1527109898.709 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:11:38.734Z,1527109898.734 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:11:38.734Z,1527109898.734 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:13:11.075Z,1527109991.075 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T21:13:34.661Z,1527110014.661 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T21:13:34.661Z,1527110014.661 [Default:CheckIn:C.Wait] Stopped
2018-05-23T21:13:34.661Z,1527110014.661 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T21:13:34.661Z,1527110014.661 [Default:CheckIn:D] Running Loop=1
2018-05-23T21:13:35.063Z,1527110015.063 [Default:CheckIn:D] Stopped
2018-05-23T21:13:35.063Z,1527110015.063 [Default:CheckIn:E] Running Loop=1
2018-05-23T21:13:35.462Z,1527110015.462 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.113835 min 
2018-05-23T21:13:35.462Z,1527110015.462 [Default:CheckIn:E] Stopped
2018-05-23T21:13:35.462Z,1527110015.462 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T21:13:35.462Z,1527110015.462 [Default:CheckIn] Stopped
2018-05-23T21:13:35.462Z,1527110015.462 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T21:13:35.463Z,1527110015.463 [Default:CheckIn](INFO): Running loop #11
2018-05-23T21:13:35.463Z,1527110015.463 [Default:CheckIn] Running Loop=11
2018-05-23T21:13:35.463Z,1527110015.463 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T21:13:35.463Z,1527110015.463 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T21:13:38.647Z,1527110018.647 [NAL9602](INFO): Powering up
2018-05-23T21:13:49.796Z,1527110029.796 [NAL9602](INFO): NAL9602 initialized
2018-05-23T21:14:31.797Z,1527110071.797 [NAL9602](INFO): SBD MO Status=2, MOMSN=6042, MT Status=2, MTMSN=0
2018-05-23T21:14:31.797Z,1527110071.797 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T21:14:39.070Z,1527110079.070 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:14:39.070Z,1527110079.070 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T21:14:39.070Z,1527110079.070 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:14:39.107Z,1527110079.107 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:14:39.225Z,1527110079.225 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:14:39.225Z,1527110079.225 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T21:14:39.581Z,1527110079.581 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:14:39.582Z,1527110079.582 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:14:39.582Z,1527110079.582 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:14:57.121Z,1527110097.121 [NAL9602](INFO): SBD MO Status=0, MOMSN=6042, MT Status=0, MTMSN=0
2018-05-23T21:14:57.121Z,1527110097.121 [NAL9602](INFO): No messages in MT queue
2018-05-23T21:15:06.727Z,1527110106.727 [NAL9602](INFO): GPS fix at 20180523T211504: (36.802151, -121.787026)
2018-05-23T21:15:06.783Z,1527110106.783 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T21:15:06.783Z,1527110106.783 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T21:15:11.180Z,1527110111.180 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180523T195259/Courier0037.lzma
2018-05-23T21:15:11.979Z,1527110111.979 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0037.lzma.bak
2018-05-23T21:15:11.980Z,1527110111.980 [DataOverHttps](INFO): SBD MOMSN=8291103
2018-05-23T21:15:21.678Z,1527110121.678 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T195259/Express0038.lzma
2018-05-23T21:15:22.476Z,1527110122.476 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0038.lzma.bak
2018-05-23T21:15:22.476Z,1527110122.476 [DataOverHttps](INFO): SBD MOMSN=8291106
2018-05-23T21:15:23.318Z,1527110123.318 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T21:15:23.318Z,1527110123.318 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T21:15:23.318Z,1527110123.318 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T21:15:41.499Z,1527110141.499 [NAL9602](INFO): Powering down
2018-05-23T21:17:39.899Z,1527110259.899 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:17:39.899Z,1527110259.899 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T21:17:39.899Z,1527110259.899 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:17:39.914Z,1527110259.914 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:17:40.318Z,1527110260.318 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:17:40.318Z,1527110260.318 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T21:17:40.711Z,1527110260.711 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:17:40.712Z,1527110260.712 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:17:40.712Z,1527110260.712 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:20:23.580Z,1527110423.580 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T21:20:23.581Z,1527110423.581 [Default:CheckIn:C.Wait] Stopped
2018-05-23T21:20:23.581Z,1527110423.581 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T21:20:23.581Z,1527110423.581 [Default:CheckIn:D] Running Loop=1
2018-05-23T21:20:23.982Z,1527110423.982 [Default:CheckIn:D] Stopped
2018-05-23T21:20:23.982Z,1527110423.982 [Default:CheckIn:E] Running Loop=1
2018-05-23T21:20:24.389Z,1527110424.389 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.929150 min 
2018-05-23T21:20:24.389Z,1527110424.389 [Default:CheckIn:E] Stopped
2018-05-23T21:20:24.389Z,1527110424.389 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T21:20:24.389Z,1527110424.389 [Default:CheckIn] Stopped
2018-05-23T21:20:24.389Z,1527110424.389 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T21:20:24.389Z,1527110424.389 [Default:CheckIn](INFO): Running loop #12
2018-05-23T21:20:24.389Z,1527110424.389 [Default:CheckIn] Running Loop=12
2018-05-23T21:20:24.390Z,1527110424.390 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T21:20:24.390Z,1527110424.390 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T21:20:27.833Z,1527110427.833 [NAL9602](INFO): Powering up
2018-05-23T21:20:38.704Z,1527110438.704 [NAL9602](INFO): NAL9602 initialized
2018-05-23T21:20:40.762Z,1527110440.762 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:20:40.762Z,1527110440.762 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T21:20:40.763Z,1527110440.763 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:20:40.796Z,1527110440.796 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:20:41.169Z,1527110441.169 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:20:41.169Z,1527110441.169 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T21:20:41.579Z,1527110441.579 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:20:41.579Z,1527110441.579 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:20:41.580Z,1527110441.580 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:23:11.282Z,1527110591.282 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T21:23:41.841Z,1527110621.841 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:23:41.841Z,1527110621.841 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T21:23:41.842Z,1527110621.842 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:23:41.871Z,1527110621.871 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:23:42.263Z,1527110622.263 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:23:42.263Z,1527110622.263 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T21:23:42.650Z,1527110622.650 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:23:42.651Z,1527110622.651 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:23:42.651Z,1527110622.651 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:24:08.989Z,1527110648.989 [NAL9602](INFO): SBD MO Status=0, MOMSN=6043, MT Status=0, MTMSN=0
2018-05-23T21:24:08.989Z,1527110648.989 [NAL9602](INFO): No messages in MT queue
2018-05-23T21:24:10.191Z,1527110650.191 [NAL9602](INFO): GPS fix at 20180523T212407: (36.802166, -121.787001)
2018-05-23T21:24:10.274Z,1527110650.274 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T21:24:10.274Z,1527110650.274 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T21:24:15.050Z,1527110655.050 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180523T195259/Courier0040.lzma
2018-05-23T21:24:15.847Z,1527110655.847 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0040.lzma.bak
2018-05-23T21:24:15.848Z,1527110655.848 [DataOverHttps](INFO): SBD MOMSN=8291110
2018-05-23T21:24:24.717Z,1527110664.717 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T195259/Express0041.lzma
2018-05-23T21:24:25.485Z,1527110665.485 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0041.lzma.bak
2018-05-23T21:24:25.485Z,1527110665.485 [DataOverHttps](INFO): SBD MOMSN=8291113
2018-05-23T21:24:26.325Z,1527110666.325 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T21:24:26.325Z,1527110666.325 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T21:24:26.325Z,1527110666.325 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T21:24:44.951Z,1527110684.951 [NAL9602](INFO): Powering down
2018-05-23T21:26:42.967Z,1527110802.967 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:26:42.967Z,1527110802.967 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T21:26:42.967Z,1527110802.967 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:26:42.980Z,1527110802.980 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:26:43.363Z,1527110803.363 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:26:43.363Z,1527110803.363 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T21:26:43.752Z,1527110803.752 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:26:43.753Z,1527110803.753 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:26:43.753Z,1527110803.753 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:29:26.781Z,1527110966.781 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T21:29:26.782Z,1527110966.782 [Default:CheckIn:C.Wait] Stopped
2018-05-23T21:29:26.782Z,1527110966.782 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T21:29:26.782Z,1527110966.782 [Default:CheckIn:D] Running Loop=1
2018-05-23T21:29:27.183Z,1527110967.183 [Default:CheckIn:D] Stopped
2018-05-23T21:29:27.183Z,1527110967.183 [Default:CheckIn:E] Running Loop=1
2018-05-23T21:29:27.587Z,1527110967.587 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.982495 min 
2018-05-23T21:29:27.588Z,1527110967.588 [Default:CheckIn:E] Stopped
2018-05-23T21:29:27.588Z,1527110967.588 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T21:29:27.588Z,1527110967.588 [Default:CheckIn] Stopped
2018-05-23T21:29:27.588Z,1527110967.588 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T21:29:27.588Z,1527110967.588 [Default:CheckIn](INFO): Running loop #13
2018-05-23T21:29:27.588Z,1527110967.588 [Default:CheckIn] Running Loop=13
2018-05-23T21:29:27.588Z,1527110967.588 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T21:29:27.588Z,1527110967.588 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T21:29:31.031Z,1527110971.031 [NAL9602](INFO): Powering up
2018-05-23T21:29:41.904Z,1527110981.904 [NAL9602](INFO): NAL9602 initialized
2018-05-23T21:29:43.961Z,1527110983.961 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:29:43.961Z,1527110983.961 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T21:29:43.961Z,1527110983.961 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:29:43.973Z,1527110983.973 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:29:44.370Z,1527110984.370 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:29:44.371Z,1527110984.371 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T21:29:44.771Z,1527110984.771 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:29:44.772Z,1527110984.772 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:29:44.772Z,1527110984.772 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:32:20.365Z,1527111140.365 [NAL9602](INFO): SBD MO Status=0, MOMSN=6044, MT Status=0, MTMSN=0
2018-05-23T21:32:20.365Z,1527111140.365 [NAL9602](INFO): No messages in MT queue
2018-05-23T21:32:21.576Z,1527111141.576 [NAL9602](INFO): GPS fix at 20180523T213218: (36.802156, -121.787059)
2018-05-23T21:32:21.632Z,1527111141.632 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T21:32:21.632Z,1527111141.632 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T21:32:26.122Z,1527111146.122 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180523T195259/Courier0043.lzma
2018-05-23T21:32:26.912Z,1527111146.912 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0043.lzma.bak
2018-05-23T21:32:26.912Z,1527111146.912 [DataOverHttps](INFO): SBD MOMSN=8291117
2018-05-23T21:32:36.218Z,1527111156.218 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T195259/Express0044.lzma
2018-05-23T21:32:37.016Z,1527111157.016 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0044.lzma.bak
2018-05-23T21:32:37.016Z,1527111157.016 [DataOverHttps](INFO): SBD MOMSN=8291120
2018-05-23T21:32:37.858Z,1527111157.858 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T21:32:37.858Z,1527111157.858 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T21:32:37.858Z,1527111157.858 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T21:32:44.916Z,1527111164.916 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:32:44.916Z,1527111164.916 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T21:32:44.916Z,1527111164.916 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:32:44.928Z,1527111164.928 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:32:45.314Z,1527111165.314 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:32:45.314Z,1527111165.314 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T21:32:45.698Z,1527111165.698 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:32:45.699Z,1527111165.699 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:32:45.699Z,1527111165.699 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:32:56.443Z,1527111176.443 [NAL9602](INFO): Powering down
2018-05-23T21:33:11.312Z,1527111191.312 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T21:35:45.892Z,1527111345.892 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:35:45.892Z,1527111345.892 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T21:35:45.892Z,1527111345.892 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:35:45.926Z,1527111345.926 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:35:46.299Z,1527111346.299 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:35:46.299Z,1527111346.299 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T21:35:46.680Z,1527111346.680 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:35:46.681Z,1527111346.681 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:35:46.681Z,1527111346.681 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:37:38.260Z,1527111458.260 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T21:37:38.260Z,1527111458.260 [Default:CheckIn:C.Wait] Stopped
2018-05-23T21:37:38.260Z,1527111458.260 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T21:37:38.260Z,1527111458.260 [Default:CheckIn:D] Running Loop=1
2018-05-23T21:37:38.663Z,1527111458.663 [Default:CheckIn:D] Stopped
2018-05-23T21:37:38.663Z,1527111458.663 [Default:CheckIn:E] Running Loop=1
2018-05-23T21:37:39.062Z,1527111459.062 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.173828 min 
2018-05-23T21:37:39.062Z,1527111459.062 [Default:CheckIn:E] Stopped
2018-05-23T21:37:39.062Z,1527111459.062 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T21:37:39.062Z,1527111459.062 [Default:CheckIn] Stopped
2018-05-23T21:37:39.062Z,1527111459.062 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T21:37:39.062Z,1527111459.062 [Default:CheckIn](INFO): Running loop #14
2018-05-23T21:37:39.062Z,1527111459.062 [Default:CheckIn] Running Loop=14
2018-05-23T21:37:39.063Z,1527111459.063 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T21:37:39.063Z,1527111459.063 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T21:37:42.511Z,1527111462.511 [NAL9602](INFO): Powering up
2018-05-23T21:37:53.416Z,1527111473.416 [NAL9602](INFO): NAL9602 initialized
2018-05-23T21:38:46.736Z,1527111526.736 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:38:46.736Z,1527111526.736 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T21:38:46.736Z,1527111526.736 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:38:46.749Z,1527111526.749 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:38:47.151Z,1527111527.151 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:38:47.151Z,1527111527.151 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T21:38:47.532Z,1527111527.532 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:38:47.533Z,1527111527.533 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:38:47.533Z,1527111527.533 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:40:30.065Z,1527111630.065 [NAL9602](INFO): SBD MO Status=0, MOMSN=6045, MT Status=0, MTMSN=0
2018-05-23T21:40:30.065Z,1527111630.065 [NAL9602](INFO): No messages in MT queue
2018-05-23T21:41:02.871Z,1527111662.871 [NAL9602](INFO): GPS fix at 20180523T214100: (36.802114, -121.787063)
2018-05-23T21:41:02.957Z,1527111662.957 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T21:41:02.957Z,1527111662.957 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T21:41:07.299Z,1527111667.299 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180523T195259/Courier0046.lzma
2018-05-23T21:41:08.088Z,1527111668.088 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0046.lzma.bak
2018-05-23T21:41:08.088Z,1527111668.088 [DataOverHttps](INFO): SBD MOMSN=8291124
2018-05-23T21:41:17.790Z,1527111677.790 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20180523T195259/Express0047.lzma
2018-05-23T21:41:18.588Z,1527111678.588 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0047.lzma.bak
2018-05-23T21:41:18.588Z,1527111678.588 [DataOverHttps](INFO): SBD MOMSN=8291127
2018-05-23T21:41:19.422Z,1527111679.422 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T21:41:19.422Z,1527111679.422 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T21:41:19.422Z,1527111679.422 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T21:41:37.729Z,1527111697.729 [NAL9602](INFO): Powering down
2018-05-23T21:41:47.752Z,1527111707.752 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:41:47.752Z,1527111707.752 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T21:41:47.752Z,1527111707.752 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:41:47.765Z,1527111707.765 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:41:48.171Z,1527111708.171 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:41:48.171Z,1527111708.171 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T21:41:48.545Z,1527111708.545 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:41:48.546Z,1527111708.546 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:41:48.546Z,1527111708.546 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:43:11.581Z,1527111791.581 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T21:44:48.823Z,1527111888.823 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:44:48.823Z,1527111888.823 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T21:44:48.823Z,1527111888.823 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:44:48.836Z,1527111888.836 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:44:49.229Z,1527111889.229 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:44:49.229Z,1527111889.229 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T21:44:49.626Z,1527111889.626 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:44:49.627Z,1527111889.627 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:44:49.627Z,1527111889.627 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:46:19.793Z,1527111979.793 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T21:46:19.793Z,1527111979.793 [Default:CheckIn:C.Wait] Stopped
2018-05-23T21:46:19.793Z,1527111979.793 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T21:46:19.793Z,1527111979.793 [Default:CheckIn:D] Running Loop=1
2018-05-23T21:46:20.195Z,1527111980.195 [Default:CheckIn:D] Stopped
2018-05-23T21:46:20.196Z,1527111980.196 [Default:CheckIn:E] Running Loop=1
2018-05-23T21:46:20.602Z,1527111980.602 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.866048 min 
2018-05-23T21:46:20.602Z,1527111980.602 [Default:CheckIn:E] Stopped
2018-05-23T21:46:20.603Z,1527111980.603 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T21:46:20.603Z,1527111980.603 [Default:CheckIn] Stopped
2018-05-23T21:46:20.603Z,1527111980.603 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T21:46:20.603Z,1527111980.603 [Default:CheckIn](INFO): Running loop #15
2018-05-23T21:46:20.603Z,1527111980.603 [Default:CheckIn] Running Loop=15
2018-05-23T21:46:20.603Z,1527111980.603 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T21:46:20.603Z,1527111980.603 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T21:46:25.285Z,1527111985.285 [NAL9602](INFO): Powering up
2018-05-23T21:46:36.172Z,1527111996.172 [NAL9602](INFO): NAL9602 initialized
2018-05-23T21:47:49.963Z,1527112069.963 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:47:49.963Z,1527112069.963 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T21:47:49.963Z,1527112069.963 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:47:49.976Z,1527112069.976 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:47:50.394Z,1527112070.394 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:47:50.394Z,1527112070.394 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T21:47:50.752Z,1527112070.752 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:47:50.752Z,1527112070.752 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:47:50.752Z,1527112070.752 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:49:06.149Z,1527112146.149 [NAL9602](INFO): SBD MO Status=0, MOMSN=6046, MT Status=0, MTMSN=0
2018-05-23T21:49:06.149Z,1527112146.149 [NAL9602](INFO): No messages in MT queue
2018-05-23T21:49:07.351Z,1527112147.351 [NAL9602](INFO): GPS fix at 20180523T214904: (36.802181, -121.786995)
2018-05-23T21:49:07.423Z,1527112147.423 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T21:49:07.423Z,1527112147.423 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T21:49:11.889Z,1527112151.889 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180523T195259/Courier0049.lzma
2018-05-23T21:49:12.677Z,1527112152.677 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0049.lzma.bak
2018-05-23T21:49:12.677Z,1527112152.677 [DataOverHttps](INFO): SBD MOMSN=8291131
2018-05-23T21:49:21.975Z,1527112161.975 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20180523T195259/Express0050.lzma
2018-05-23T21:49:22.756Z,1527112162.756 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0050.lzma.bak
2018-05-23T21:49:22.756Z,1527112162.756 [DataOverHttps](INFO): SBD MOMSN=8291134
2018-05-23T21:49:23.613Z,1527112163.613 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T21:49:23.613Z,1527112163.613 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T21:49:23.614Z,1527112163.614 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T21:49:38.605Z,1527112178.605 [NAL9602](INFO): Powering down
2018-05-23T21:50:51.117Z,1527112251.117 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:50:51.117Z,1527112251.117 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T21:50:51.117Z,1527112251.117 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:50:51.139Z,1527112251.139 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:50:51.528Z,1527112251.528 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:50:51.528Z,1527112251.528 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T21:50:51.918Z,1527112251.918 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:50:51.919Z,1527112251.919 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:50:51.919Z,1527112251.919 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:51:26.813Z,1527112286.813 [CommandLine](IMPORTANT): got command report mod SpeedControl.speedCmd
2018-05-23T21:51:26.815Z,1527112286.815 [CommandLine](IMPORTANT): got command report mod VerticalControl.depthCmd
2018-05-23T21:51:26.816Z,1527112286.816 [CommandLine](IMPORTANT): got command report mod VerticalControl.depthDeadband
2018-05-23T21:51:28.316Z,1527112288.316 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2018-05-23T21:51:28.317Z,1527112288.317 [Reporter](INFO): VerticalControl.depthCmd no_value
2018-05-23T21:51:28.318Z,1527112288.318 [Reporter](INFO): VerticalControl.depthDeadband 0.010000 m
2018-05-23T21:51:36.101Z,1527112296.101 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/ballast_and_trim.xml
2018-05-23T21:51:36.101Z,1527112296.101 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/ballast_and_trim.xml
2018-05-23T21:51:36.212Z,1527112296.212 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 90.000000 min
2018-05-23T21:51:36.215Z,1527112296.215 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2018-05-23T21:51:36.217Z,1527112296.217 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 35.000000 m
2018-05-23T21:51:36.220Z,1527112296.220 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband1 = 0.100000 m
2018-05-23T21:51:36.242Z,1527112296.242 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2018-05-23T21:51:36.245Z,1527112296.245 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband2 = nan m
2018-05-23T21:51:36.248Z,1527112296.248 [MissionManager](INFO): DefineArg ballast_and_trim.SpeedToTargetDepth = 1.000000 m/s
2018-05-23T21:51:36.251Z,1527112296.251 [MissionManager](INFO): DefineArg ballast_and_trim.TargetDepthTimeout = 45.000000 min
2018-05-23T21:51:36.255Z,1527112296.255 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 25.000000 min
2018-05-23T21:51:36.303Z,1527112296.303 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2018-05-23T21:51:36.306Z,1527112296.306 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2018-05-23T21:51:36.309Z,1527112296.309 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2018-05-23T21:51:36.312Z,1527112296.312 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2018-05-23T21:51:36.340Z,1527112296.340 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2018-05-23T21:51:36.381Z,1527112296.381 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 %
2018-05-23T21:51:36.388Z,1527112296.388 [MissionManager](INFO): DefineArg ballast_and_trim.MinAltitude = 5.000000 m
2018-05-23T21:51:36.420Z,1527112296.420 [MissionManager](INFO): DefineArg ballast_and_trim.MaxDepth = 52.000000 m
2018-05-23T21:51:36.431Z,1527112296.431 [MissionManager](INFO): DefineArg ballast_and_trim.MinOffshore = 2.000000 km
2018-05-23T21:51:36.439Z,1527112296.439 [MissionManager](INFO): DefineArg ballast_and_trim.CheckDepths = 1.000000 bool
2018-05-23T21:51:36.439Z,1527112296.439 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-05-23T21:51:36.853Z,1527112296.853 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUS = 0 bool
2018-05-23T21:51:36.864Z,1527112296.864 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-05-23T21:51:36.873Z,1527112296.873 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-05-23T21:51:36.888Z,1527112296.888 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2018-05-23T21:51:36.895Z,1527112296.895 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2018-05-23T21:51:36.897Z,1527112296.897 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2018-05-23T21:51:36.902Z,1527112296.902 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2018-05-23T21:51:36.913Z,1527112296.913 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2018-05-23T21:51:36.916Z,1527112296.916 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2018-05-23T21:51:36.932Z,1527112296.932 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2018-05-23T21:51:36.938Z,1527112296.938 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-05-23T21:51:36.946Z,1527112296.946 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 1.000000 bool
2018-05-23T21:51:36.950Z,1527112296.950 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0.000000 bool
2018-05-23T21:51:36.961Z,1527112296.961 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 0.000000 bool
2018-05-23T21:51:36.975Z,1527112296.975 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-05-23T21:51:36.983Z,1527112296.983 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1.000000 bool
2018-05-23T21:51:36.990Z,1527112296.990 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2018-05-23T21:51:36.999Z,1527112296.999 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledISUS = 0.000000 bool
2018-05-23T21:51:37.002Z,1527112297.002 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2018-05-23T21:51:37.027Z,1527112297.027 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlShallowBound = nan m
2018-05-23T21:51:37.030Z,1527112297.030 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlDeepBound = nan m
2018-05-23T21:51:37.033Z,1527112297.033 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2018-05-23T21:51:37.042Z,1527112297.042 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2018-05-23T21:51:37.045Z,1527112297.045 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2018-05-23T21:51:37.048Z,1527112297.048 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2018-05-23T21:51:37.063Z,1527112297.063 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2018-05-23T21:51:37.066Z,1527112297.066 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2018-05-23T21:51:37.069Z,1527112297.069 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2018-05-23T21:51:37.073Z,1527112297.073 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2018-05-23T21:51:37.088Z,1527112297.088 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2018-05-23T21:51:37.091Z,1527112297.091 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 m
2018-05-23T21:51:37.094Z,1527112297.094 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2018-05-23T21:51:37.097Z,1527112297.097 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2018-05-23T21:51:37.113Z,1527112297.113 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2018-05-23T21:51:37.116Z,1527112297.116 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan arcdeg
2018-05-23T21:51:37.119Z,1527112297.119 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2018-05-23T21:51:37.122Z,1527112297.122 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2018-05-23T21:51:37.169Z,1527112297.169 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2018-05-23T21:51:37.172Z,1527112297.172 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2018-05-23T21:51:37.241Z,1527112297.241 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-05-23T21:51:37.284Z,1527112297.284 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-05-23T21:51:37.329Z,1527112297.329 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-05-23T21:51:37.355Z,1527112297.355 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-05-23T21:51:37.375Z,1527112297.375 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-05-23T21:51:37.491Z,1527112297.491 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.DiveInterval = 3.000000 h
2018-05-23T21:51:37.494Z,1527112297.494 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.WaitForPitchUp = 10.000000 min
2018-05-23T21:51:37.497Z,1527112297.497 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-05-23T21:51:37.567Z,1527112297.567 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceDepthRate = nan m/s
2018-05-23T21:51:37.570Z,1527112297.570 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-05-23T21:51:37.604Z,1527112297.604 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.GPSTimeout = 7.000000 min
2018-05-23T21:51:37.611Z,1527112297.611 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.CommsTimeout = 30.000000 min
2018-05-23T21:51:37.625Z,1527112297.625 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-23T21:51:37.685Z,1527112297.685 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-05-23T21:51:37.746Z,1527112297.746 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinAltitude = 5.000000 m
2018-05-23T21:51:37.749Z,1527112297.749 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MaxDepth = 200.000000 m
2018-05-23T21:51:37.753Z,1527112297.753 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-05-23T21:51:37.754Z,1527112297.754 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-05-23T21:51:37.761Z,1527112297.761 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-05-23T21:51:37.812Z,1527112297.812 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-05-23T21:51:37.883Z,1527112297.883 [ballast_and_trim:CheckTargetDepths:CheckDepth1:B.Execute](DEBUG): Construct Execute.
2018-05-23T21:51:37.887Z,1527112297.887 [ballast_and_trim:CheckTargetDepths:CheckDepth2:B.Execute](DEBUG): Construct Execute.
2018-05-23T21:51:37.894Z,1527112297.894 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2018-05-23T21:51:38.141Z,1527112298.141 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertDepth = 25.000000 m
2018-05-23T21:51:38.144Z,1527112298.144 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2018-05-23T21:51:38.155Z,1527112298.155 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s
2018-05-23T21:51:38.166Z,1527112298.166 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min
2018-05-23T21:51:38.169Z,1527112298.169 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min
2018-05-23T21:51:38.181Z,1527112298.181 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m
2018-05-23T21:51:38.188Z,1527112298.188 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSettleTime = 10.000000 min
2018-05-23T21:51:38.199Z,1527112298.199 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.EstTimeout = 45.000000 min
2018-05-23T21:51:38.207Z,1527112298.207 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.MinEstTime = 10.000000 min
2018-05-23T21:51:38.210Z,1527112298.210 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2018-05-23T21:51:38.217Z,1527112298.217 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2018-05-23T21:51:38.221Z,1527112298.221 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.EstConfidence = 99.900000 %
2018-05-23T21:51:38.259Z,1527112298.259 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct.
2018-05-23T21:51:38.277Z,1527112298.277 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-05-23T21:51:38.279Z,1527112298.279 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Construct.
2018-05-23T21:51:38.308Z,1527112298.308 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-05-23T21:51:38.313Z,1527112298.313 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct.
2018-05-23T21:51:38.316Z,1527112298.316 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct.
2018-05-23T21:51:38.370Z,1527112298.370 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait.
2018-05-23T21:51:38.373Z,1527112298.373 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2018-05-23T21:51:38.444Z,1527112298.444 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth1
2018-05-23T21:51:38.469Z,1527112298.469 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband1
2018-05-23T21:51:38.471Z,1527112298.471 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSpeedToTargetDepth = reader:ballast_and_trim.SpeedToTargetDepth
2018-05-23T21:51:38.473Z,1527112298.473 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertTargetDepthTimeout = reader:ballast_and_trim.TargetDepthTimeout
2018-05-23T21:51:38.475Z,1527112298.475 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2018-05-23T21:51:38.477Z,1527112298.477 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2018-05-23T21:51:38.494Z,1527112298.494 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2018-05-23T21:51:38.496Z,1527112298.496 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2018-05-23T21:51:38.498Z,1527112298.498 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2018-05-23T21:51:38.500Z,1527112298.500 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2018-05-23T21:51:38.502Z,1527112298.502 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2018-05-23T21:51:38.503Z,1527112298.503 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2018-05-23T21:51:38.643Z,1527112298.643 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertDepth = 25.000000 m
2018-05-23T21:51:38.646Z,1527112298.646 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2018-05-23T21:51:38.649Z,1527112298.649 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s
2018-05-23T21:51:38.652Z,1527112298.652 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min
2018-05-23T21:51:38.667Z,1527112298.667 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min
2018-05-23T21:51:38.670Z,1527112298.670 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m
2018-05-23T21:51:38.674Z,1527112298.674 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSettleTime = 10.000000 min
2018-05-23T21:51:38.678Z,1527112298.678 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.EstTimeout = 45.000000 min
2018-05-23T21:51:38.698Z,1527112298.698 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.MinEstTime = 10.000000 min
2018-05-23T21:51:38.701Z,1527112298.701 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2018-05-23T21:51:38.704Z,1527112298.704 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2018-05-23T21:51:38.738Z,1527112298.738 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.EstConfidence = 99.900000 %
2018-05-23T21:51:38.774Z,1527112298.774 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct.
2018-05-23T21:51:38.812Z,1527112298.812 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-05-23T21:51:38.813Z,1527112298.813 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Construct.
2018-05-23T21:51:38.833Z,1527112298.833 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-05-23T21:51:38.861Z,1527112298.861 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct.
2018-05-23T21:51:38.864Z,1527112298.864 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct.
2018-05-23T21:51:38.883Z,1527112298.883 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait.
2018-05-23T21:51:38.891Z,1527112298.891 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2018-05-23T21:51:38.907Z,1527112298.907 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth2
2018-05-23T21:51:38.909Z,1527112298.909 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband2
2018-05-23T21:51:38.911Z,1527112298.911 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSpeedToTargetDepth = reader:ballast_and_trim.SpeedToTargetDepth
2018-05-23T21:51:38.913Z,1527112298.913 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2018-05-23T21:51:38.915Z,1527112298.915 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertTargetDepthTimeout = reader:ballast_and_trim.TargetDepthTimeout
2018-05-23T21:51:38.917Z,1527112298.917 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2018-05-23T21:51:38.927Z,1527112298.927 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2018-05-23T21:51:38.928Z,1527112298.928 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2018-05-23T21:51:38.947Z,1527112298.947 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2018-05-23T21:51:38.949Z,1527112298.949 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2018-05-23T21:51:38.951Z,1527112298.951 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2018-05-23T21:51:39.039Z,1527112299.039 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Units="Tethys/Units"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd"
       Id="ballast_and_trim">

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

    <DefineArg Name="SkipComms"><Description>
        Skip communications at start of mission.
    </Description><False/></DefineArg>

    <DefineArg Name="Depth1"><Units:meter/><Value>35</Value></DefineArg>

    <DefineArg Name="DepthDeadband1"><Units:meter/><Value>0.1</Value></DefineArg>

    <DefineArg Name="Depth2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="DepthDeadband2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="SpeedToTargetDepth"><Description>
        Enables vehicle thruster at the commanded speed to reach target depth (set to 0 m/s to drift).
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>
    
    <DefineArg Name="TargetDepthTimeout"><Description>
        Maximum wait time for the vehicle to reach the targeted depth.
    </Description><Units:minute/><Value>45</Value></DefineArg>
    
    <DefineArg Name="MaxWaitOnSurface"><Description>
        Maximum wait time for the vehicle to go under the surface (i.e., reach
        the surface threshold depth).
    </Description><Units:minute/><Value>25</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before starting ballast and
        trim.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="EstimationTimeout"><Description>
        Timeout for ballast and trim estimation.
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="MinEstimationTime"><Description>
        Minimum time to run ballast and trim estimation before determining
        convergence (must be shorter than timeout).
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="MassEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:millimeter/><Value>0.25</Value></DefineArg>

    <DefineArg Name="BuoyEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:cubic_centimeter/><Value>15</Value></DefineArg>

    <DefineArg Name="EstimationConfidence"><Description>
        Confidence level that the estimator has reached the desired error range
        (larger values take longer to converge).
    </Description><Units:percent/><Value>99.9</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Units:meter/><Value>52</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Units:kilometer/><Value>2</Value></DefineArg>
    
    <DefineArg Name="CheckDepths"><Units:bool/><Value>1</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

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

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

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

    <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>

    <Aggregate Id="CheckTargetDepths">
        
        <Sequence/>
        
        <Break>
            <Not><Arg Name="CheckDepths"/></Not>
        </Break>
        
        <Aggregate Id="CheckDepth1">
            <Sequence/>
            
            <Break>
                <Not>
                    <Arg Name="Depth1"/>
                    <Gt><Arg Name="MaxDepth"/></Gt>
                </Not>
            </Break>
            
            <Syslog Severity="Important">Aborting ballast and trim mission. Target depth Depth1 exceeds the mission's MaxDepth setting (review mission parameters).</Syslog>
            <Guidance:Execute>
                <Sequence/>
                <Setting><Guidance:Execute.command/><String>stop</String></Setting>
            </Guidance:Execute>
        </Aggregate>
        
        <Aggregate Id="CheckDepth2">
            <Sequence/>
            
            <Break>
                <Not>
                    <Arg Name="Depth2"/>
                    <Gt><Arg Name="MaxDepth"/></Gt>
                </Not>
            </Break>
            
            <Syslog Severity="Important">Aborting ballast and trim mission. Target depth Depth2 exceeds the mission's MaxDepth setting (review mission parameters).</Syslog>
            <Guidance:Execute>
                <Sequence/>
                <Setting><Guidance:Execute.command/><String>stop</String></Setting>
            </Guidance:Execute>
        </Aggregate>
        
        <Assign><Sequence/><Arg Name="CheckDepths"/><Units:bool/><Value>0</Value></Assign>
        
    </Aggregate>


    <Aggregate Id="StartingMission">

        <Sequence/>

        <Break><Arg Name="SkipComms"/>
        </Break>

<!-- DO NOT REMOVE the syslog entry below. The MissionManager seems to ignore the value of SkipComms, does not evaluate the Break statement properly, and always calls NeedComms. Adding the syslog call somehow fixes it. -->

        <Syslog Severity="Info">Checking for additional instructions before submerging.</Syslog>

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

    </Aggregate>
    

    <Aggregate Id="RunBallastAndTrim">

        <Sequence/>

        <Aggregate Id="RunDepth1">

            <Sequence/>

            <Break>
                <IsNaN>
                    <Arg Name="Depth1"/>
                </IsNaN>
                <Or><IsNaN>
                    <Arg Name="DepthDeadband1"/>
                </IsNaN></Or>
            </Break>

            <Insert Filename="Insert/BallastAndTrim.xml">
                <RedefineArg Name="InsertDepth"><Arg Name="Depth1"/></RedefineArg>
                <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband1"/></RedefineArg>
                <RedefineArg Name="InsertSpeedToTargetDepth"><Arg Name="SpeedToTargetDepth"/></RedefineArg>
                <RedefineArg Name="InsertTargetDepthTimeout"><Arg Name="TargetDepthTimeout"/></RedefineArg>
                <RedefineArg Name="InsertSurfaceTimeout"><Arg Name="MaxWaitOnSurface"/></RedefineArg>
                <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
                <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
                <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
                <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
                <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
                <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
            </Insert>

        </Aggregate>

        <Aggregate Id="RunDepth2">

            <Sequence/>

            <Break>
                <IsNaN>
                    <Arg Name="Depth2"/>
                </IsNaN>
                <Or><IsNaN>
                    <Arg Name="DepthDeadband2"/>
                </IsNaN></Or>
            </Break>

            <Insert Filename="Insert/BallastAndTrim.xml">
                <RedefineArg Name="InsertDepth"><Arg Name="Depth2"/></RedefineArg>
                <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband2"/></RedefineArg>
                <RedefineArg Name="InsertSpeedToTargetDepth"><Arg Name="SpeedToTargetDepth"/></RedefineArg>
                <RedefineArg Name="InsertSurfaceTimeout"><Arg Name="MaxWaitOnSurface"/></RedefineArg>
                <RedefineArg Name="InsertTargetDepthTimeout"><Arg Name="TargetDepthTimeout"/></RedefineArg>
                <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
                <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
                <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
                <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
                <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
                <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
            </Insert>

        </Aggregate>

    </Aggregate>

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

</Mission>


2018-05-23T21:51:39.039Z,1527112299.039 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/ballast_and_trim.xml
2018-05-23T21:51:44.104Z,1527112304.104 [CommandLine](IMPORTANT): got command run
2018-05-23T21:51:44.108Z,1527112304.108 [CommandLine](IMPORTANT): Running 
2018-05-23T21:51:44.376Z,1527112304.376 [Default] Stopped
2018-05-23T21:51:44.377Z,1527112304.377 [Default](DEBUG): Aggregate::uninitialize Default
2018-05-23T21:51:44.377Z,1527112304.377 [Default:B.GoToSurface] Stopped
2018-05-23T21:51:44.377Z,1527112304.377 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-23T21:51:44.377Z,1527112304.377 [Default:CheckIn] Stopped
2018-05-23T21:51:44.377Z,1527112304.377 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T21:51:44.377Z,1527112304.377 [Default:CheckIn:C.Wait] Stopped
2018-05-23T21:51:44.377Z,1527112304.377 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T21:51:44.377Z,1527112304.377 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim] Running Loop=1
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science] Running Loop=1
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science:A] Running Loop=1
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science:B] Running Loop=1
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science:C] Running Loop=1
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science:D] Running Loop=1
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science:E] Running Loop=1
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science:F] Running Loop=1
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science:G] Running Loop=1
2018-05-23T21:51:44.378Z,1527112304.378 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2018-05-23T21:51:44.379Z,1527112304.379 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2018-05-23T21:51:44.379Z,1527112304.379 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2018-05-23T21:51:44.379Z,1527112304.379 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-05-23T21:51:44.379Z,1527112304.379 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-23T21:51:44.381Z,1527112304.381 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2018-05-23T21:51:44.381Z,1527112304.381 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2018-05-23T21:51:44.381Z,1527112304.381 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-05-23T21:51:44.381Z,1527112304.381 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-05-23T21:51:44.382Z,1527112304.382 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2018-05-23T21:51:44.382Z,1527112304.382 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2018-05-23T21:51:44.382Z,1527112304.382 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-05-23T21:51:44.382Z,1527112304.382 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-23T21:51:44.384Z,1527112304.384 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2018-05-23T21:51:44.384Z,1527112304.384 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2018-05-23T21:51:44.384Z,1527112304.384 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-05-23T21:51:44.384Z,1527112304.384 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-23T21:51:44.386Z,1527112304.386 [ballast_and_trim:StandardEnvelopes] Running Loop=1
2018-05-23T21:51:44.386Z,1527112304.386 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::initialize ballast_and_trim:StandardEnvelopes
2018-05-23T21:51:44.386Z,1527112304.386 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-05-23T21:51:44.386Z,1527112304.386 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-05-23T21:51:44.386Z,1527112304.386 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-05-23T21:51:44.386Z,1527112304.386 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-05-23T21:51:44.387Z,1527112304.387 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-05-23T21:51:44.387Z,1527112304.387 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-05-23T21:51:44.387Z,1527112304.387 [ballast_and_trim:C] Running Loop=1
2018-05-23T21:51:44.388Z,1527112304.388 [ballast_and_trim:StandardEnvelopes] Running Loop=1
2018-05-23T21:51:44.388Z,1527112304.388 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-05-23T21:51:44.432Z,1527112304.432 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-05-23T21:51:44.443Z,1527112304.443 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-05-23T21:51:44.450Z,1527112304.450 [ballast_and_trim:C] Stopped
2018-05-23T21:51:44.468Z,1527112304.468 [ballast_and_trim:E] Running Loop=1
2018-05-23T21:51:44.468Z,1527112304.468 [ballast_and_trim:Science] Running Loop=1
2018-05-23T21:51:44.468Z,1527112304.468 [ballast_and_trim:Science:PeakDetectOil] Stopped
2018-05-23T21:51:44.468Z,1527112304.468 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:PeakDetectChl] Stopped
2018-05-23T21:51:44.469Z,1527112304.469 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2018-05-23T21:51:44.470Z,1527112304.470 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-05-23T21:51:44.470Z,1527112304.470 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-05-23T21:51:44.470Z,1527112304.470 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2018-05-23T21:51:44.471Z,1527112304.471 [ballast_and_trim:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water
2018-05-23T21:51:44.472Z,1527112304.472 [ballast_and_trim:Science:G] Running Loop=1
2018-05-23T21:51:44.473Z,1527112304.473 [ballast_and_trim:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-05-23T21:51:44.474Z,1527112304.474 [ballast_and_trim:Science:F] Stopped
2018-05-23T21:51:44.474Z,1527112304.474 [ballast_and_trim:Science:E] Stopped
2018-05-23T21:51:44.474Z,1527112304.474 [ballast_and_trim:Science:D] Stopped
2018-05-23T21:51:44.474Z,1527112304.474 [ballast_and_trim:Science:C] Stopped
2018-05-23T21:51:44.474Z,1527112304.474 [ballast_and_trim:Science:B] Stopped
2018-05-23T21:51:44.474Z,1527112304.474 [ballast_and_trim:Science:A] Running Loop=1
2018-05-23T21:51:44.475Z,1527112304.475 [ballast_and_trim:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2018-05-23T21:51:44.519Z,1527112304.519 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2018-05-23T21:51:44.519Z,1527112304.519 [WetLabsBB2FL](INFO): Powering up
2018-05-23T21:51:44.522Z,1527112304.522 [Reporter](INFO): SpeedControl.speedCmd 1.000000 m/s
2018-05-23T21:51:44.808Z,1527112304.808 [ballast_and_trim:E] Stopped
2018-05-23T21:51:44.808Z,1527112304.808 [ballast_and_trim:F] Running Loop=1
2018-05-23T21:51:45.207Z,1527112305.207 [ballast_and_trim:F] Stopped
2018-05-23T21:51:45.207Z,1527112305.207 [ballast_and_trim:G] Running Loop=1
2018-05-23T21:51:45.614Z,1527112305.614 [ballast_and_trim:G] Stopped
2018-05-23T21:51:45.614Z,1527112305.614 [ballast_and_trim:CheckTargetDepths] Running Loop=1
2018-05-23T21:51:45.614Z,1527112305.614 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths
2018-05-23T21:51:45.614Z,1527112305.614 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Running Loop=1
2018-05-23T21:51:45.615Z,1527112305.615 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth1
2018-05-23T21:51:45.615Z,1527112305.615 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Running Loop=1
2018-05-23T21:51:46.003Z,1527112306.003 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Stopped
2018-05-23T21:51:46.003Z,1527112306.003 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth1
2018-05-23T21:51:46.004Z,1527112306.004 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Stopped
2018-05-23T21:51:46.004Z,1527112306.004 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Running Loop=1
2018-05-23T21:51:46.004Z,1527112306.004 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth2
2018-05-23T21:51:46.005Z,1527112306.005 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Running Loop=1
2018-05-23T21:51:46.380Z,1527112306.380 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Stopped
2018-05-23T21:51:46.380Z,1527112306.380 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth2
2018-05-23T21:51:46.380Z,1527112306.380 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Stopped
2018-05-23T21:51:46.380Z,1527112306.380 [ballast_and_trim:CheckTargetDepths:C] Running Loop=1
2018-05-23T21:51:46.781Z,1527112306.781 [ballast_and_trim:CheckTargetDepths:C] Stopped
2018-05-23T21:51:46.781Z,1527112306.781 [ballast_and_trim:CheckTargetDepths](INFO): Completed ballast_and_trim:CheckTargetDepths
2018-05-23T21:51:46.781Z,1527112306.781 [ballast_and_trim:CheckTargetDepths] Stopped
2018-05-23T21:51:46.781Z,1527112306.781 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths
2018-05-23T21:51:46.782Z,1527112306.782 [ballast_and_trim:StartingMission] Running Loop=1
2018-05-23T21:51:46.782Z,1527112306.782 [ballast_and_trim:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission
2018-05-23T21:51:46.782Z,1527112306.782 [ballast_and_trim:StartingMission:A] Running Loop=1
2018-05-23T21:51:47.367Z,1527112307.367 [ballast_and_trim:StartingMission:A](INFO): Checking for additional instructions before submerging.
2018-05-23T21:51:47.368Z,1527112307.368 [ballast_and_trim:StartingMission:A] Stopped
2018-05-23T21:51:47.368Z,1527112307.368 [ballast_and_trim:StartingMission:StartingMission] Running Loop=1
2018-05-23T21:51:47.368Z,1527112307.368 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission:StartingMission
2018-05-23T21:51:47.803Z,1527112307.803 [ballast_and_trim:NeedComms] Running Loop=1
2018-05-23T21:51:47.803Z,1527112307.803 [ballast_and_trim:NeedComms](DEBUG): Aggregate::initialize ballast_and_trim:NeedComms
2018-05-23T21:51:47.803Z,1527112307.803 [ballast_and_trim:NeedComms:B.GoToSurface] Running Loop=1
2018-05-23T21:51:47.803Z,1527112307.803 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-23T21:51:47.804Z,1527112307.804 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-05-23T21:51:47.804Z,1527112307.804 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-05-23T21:51:47.805Z,1527112307.805 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-05-23T21:51:47.805Z,1527112307.805 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-23T21:51:47.805Z,1527112307.805 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-23T21:51:47.806Z,1527112307.806 [ballast_and_trim:NeedComms:A] Running Loop=1
2018-05-23T21:51:47.820Z,1527112307.820 [ballast_and_trim:NeedComms:A](INFO): last time_fix was: 1527112144.000000 second since 1970/01/01T00:00:00Z 
2018-05-23T21:51:47.821Z,1527112307.821 [ballast_and_trim:NeedComms:A] Stopped
2018-05-23T21:51:47.999Z,1527112307.999 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2018-05-23T21:51:48.192Z,1527112308.192 [ballast_and_trim:NeedComms:C] Running Loop=1
2018-05-23T21:51:48.689Z,1527112308.689 [ballast_and_trim:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-23T21:51:48.999Z,1527112308.999 [NAL9602](INFO): Powering up
2018-05-23T21:51:53.437Z,1527112313.437 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		175.136		58.513		17.401		35.303		35.303		43.913		8.610		310.7		546.0		183.2	




2018-05-23T21:51:53.437Z,1527112313.437 [Aanderaa_O2](DEBUG): Error parsing device response
2018-05-23T21:51:54.621Z,1527112314.621 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		174.795		58.402		17.403		35.326		35.326		43.937		8.611		310.8		545.4		183.2	


2018-05-23T21:51:54.621Z,1527112314.621 [Aanderaa_O2](DEBUG): Error parsing device response
2018-05-23T21:51:56.632Z,1527112316.632 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		174.656		58.360		17.407		35.334		35.334		43.943		8.608		310.6		544.4		183.1	


2018-05-23T21:51:56.632Z,1527112316.632 [Aanderaa_O2](DEBUG): Error parsing device response
2018-05-23T21:51:59.800Z,1527112319.800 [NAL9602](INFO): NAL9602 initialized
2018-05-23T21:52:18.597Z,1527112338.597 [NAL9602](INFO): SBD MO Status=2, MOMSN=6047, MT Status=2, MTMSN=0
2018-05-23T21:52:18.597Z,1527112338.597 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T21:52:28.924Z,1527112348.924 [NAL9602](INFO): SBD MO Status=0, MOMSN=6047, MT Status=0, MTMSN=0
2018-05-23T21:52:28.924Z,1527112348.924 [NAL9602](INFO): No messages in MT queue
2018-05-23T21:53:11.985Z,1527112391.985 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T21:53:52.068Z,1527112432.068 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:53:52.068Z,1527112432.068 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T21:53:52.068Z,1527112432.068 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:53:52.092Z,1527112432.092 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:53:52.543Z,1527112432.543 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:53:52.543Z,1527112432.543 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T21:53:52.887Z,1527112432.887 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:53:52.887Z,1527112432.887 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:53:52.888Z,1527112432.888 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:54:05.861Z,1527112445.861 [CommandLine](IMPORTANT): got command failComponent
2018-05-23T21:54:05.861Z,1527112445.861 [CommandLine](IMPORTANT): Failed components:
2018-05-23T21:54:05.861Z,1527112445.861 [CommandLine](IMPORTANT): No failed Components.
2018-05-23T21:55:01.341Z,1527112501.341 [CommandLine](IMPORTANT): got command show stack
2018-05-23T21:55:01.342Z,1527112501.342 [CommandLine](IMPORTANT): Behavior Stack:

2018-05-23T21:55:01.343Z,1527112501.343 [ballast_and_trim:Science](IMPORTANT): Priority 0: ballast_and_trim:Science:A
2018-05-23T21:55:01.343Z,1527112501.343 [ballast_and_trim:Science](IMPORTANT): Priority 1: ballast_and_trim:Science:G
2018-05-23T21:55:01.343Z,1527112501.343 [ballast_and_trim:Science](IMPORTANT): Priority 2: ballast_and_trim:Science:Read_Oil
2018-05-23T21:55:01.344Z,1527112501.344 [ballast_and_trim:NeedComms](IMPORTANT): Priority 3: ballast_and_trim:NeedComms:B.GoToSurface
2018-05-23T21:55:01.344Z,1527112501.344 [ballast_and_trim:NeedComms](IMPORTANT): Priority 4: ballast_and_trim:NeedComms:C
2018-05-23T21:55:01.344Z,1527112501.344 [ballast_and_trim:StandardEnvelopes](IMPORTANT): Priority 5: ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope
2018-05-23T21:55:01.345Z,1527112501.345 [ballast_and_trim:StandardEnvelopes](IMPORTANT): Priority 6: ballast_and_trim:StandardEnvelopes:B.DepthEnvelope
2018-05-23T21:55:01.345Z,1527112501.345 [ballast_and_trim:StandardEnvelopes](IMPORTANT): Priority 7: ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope
2018-05-23T21:56:11.818Z,1527112571.818 [CommandLine](IMPORTANT): got command show stack
2018-05-23T21:56:11.818Z,1527112571.818 [CommandLine](IMPORTANT): Behavior Stack:

2018-05-23T21:56:11.819Z,1527112571.819 [ballast_and_trim:Science](IMPORTANT): Priority 0: ballast_and_trim:Science:A
2018-05-23T21:56:11.820Z,1527112571.820 [ballast_and_trim:Science](IMPORTANT): Priority 1: ballast_and_trim:Science:G
2018-05-23T21:56:11.820Z,1527112571.820 [ballast_and_trim:Science](IMPORTANT): Priority 2: ballast_and_trim:Science:Read_Oil
2018-05-23T21:56:11.820Z,1527112571.820 [ballast_and_trim:NeedComms](IMPORTANT): Priority 3: ballast_and_trim:NeedComms:B.GoToSurface
2018-05-23T21:56:11.821Z,1527112571.821 [ballast_and_trim:NeedComms](IMPORTANT): Priority 4: ballast_and_trim:NeedComms:C
2018-05-23T21:56:11.821Z,1527112571.821 [ballast_and_trim:StandardEnvelopes](IMPORTANT): Priority 5: ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope
2018-05-23T21:56:11.821Z,1527112571.821 [ballast_and_trim:StandardEnvelopes](IMPORTANT): Priority 6: ballast_and_trim:StandardEnvelopes:B.DepthEnvelope
2018-05-23T21:56:11.822Z,1527112571.822 [ballast_and_trim:StandardEnvelopes](IMPORTANT): Priority 7: ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope
2018-05-23T21:56:22.242Z,1527112582.242 [CommandLine](INFO): End of History
2018-05-23T21:56:22.282Z,1527112582.282 [CommandLine](INFO): End of History
2018-05-23T21:56:22.306Z,1527112582.306 [CommandLine](INFO): End of History
2018-05-23T21:56:22.437Z,1527112582.437 [CommandLine](INFO): End of History
2018-05-23T21:56:35.643Z,1527112595.643 [NAL9602](INFO): GPS fix at 20180523T215634: (36.802257, -121.787031)
2018-05-23T21:56:35.743Z,1527112595.743 [ballast_and_trim:NeedComms:C] Stopped
2018-05-23T21:56:35.743Z,1527112595.743 [ballast_and_trim:NeedComms:D] Running Loop=1
2018-05-23T21:56:36.180Z,1527112596.180 [ballast_and_trim:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-23T21:56:40.518Z,1527112600.518 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180523T195259/Courier0052.lzma
2018-05-23T21:56:41.304Z,1527112601.304 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0052.lzma.bak
2018-05-23T21:56:41.304Z,1527112601.304 [DataOverHttps](INFO): SBD MOMSN=8291139
2018-05-23T21:56:50.983Z,1527112610.983 [DataOverHttps](INFO): Sending 794 bytes from file Logs/20180523T195259/Express0053.lzma
2018-05-23T21:56:51.776Z,1527112611.776 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0053.lzma.bak
2018-05-23T21:56:51.776Z,1527112611.776 [DataOverHttps](INFO): SBD MOMSN=8291142
2018-05-23T21:56:52.680Z,1527112612.680 [ballast_and_trim:NeedComms:D] Stopped
2018-05-23T21:56:52.681Z,1527112612.681 [ballast_and_trim:NeedComms:E] Running Loop=1
2018-05-23T21:56:52.891Z,1527112612.891 [ballast_and_trim:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-23T21:56:56.208Z,1527112616.208 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:56:56.208Z,1527112616.208 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T21:56:56.208Z,1527112616.208 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:56:56.273Z,1527112616.273 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:56:56.442Z,1527112616.442 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:56:56.442Z,1527112616.442 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T21:56:56.759Z,1527112616.759 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:56:56.760Z,1527112616.760 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:56:56.760Z,1527112616.760 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T21:56:57.099Z,1527112617.099 [NAL9602](INFO): GPS fix at 20180523T215655: (36.802238, -121.787024)
2018-05-23T21:56:57.200Z,1527112617.200 [ballast_and_trim:NeedComms:E] Stopped
2018-05-23T21:56:57.200Z,1527112617.200 [ballast_and_trim:NeedComms](INFO): Completed ballast_and_trim:NeedComms
2018-05-23T21:56:57.200Z,1527112617.200 [ballast_and_trim:NeedComms] Stopped
2018-05-23T21:56:57.201Z,1527112617.201 [ballast_and_trim:NeedComms](DEBUG): Aggregate::uninitialize ballast_and_trim:NeedComms
2018-05-23T21:56:57.201Z,1527112617.201 [ballast_and_trim:NeedComms:B.GoToSurface] Stopped
2018-05-23T21:56:57.201Z,1527112617.201 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-23T21:56:57.558Z,1527112617.558 [ballast_and_trim:StartingMission:StartingMission](INFO): Completed ballast_and_trim:StartingMission:StartingMission
2018-05-23T21:56:57.559Z,1527112617.559 [ballast_and_trim:StartingMission:StartingMission] Stopped
2018-05-23T21:56:57.559Z,1527112617.559 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission:StartingMission
2018-05-23T21:56:57.559Z,1527112617.559 [ballast_and_trim:StartingMission](INFO): Completed ballast_and_trim:StartingMission
2018-05-23T21:56:57.559Z,1527112617.559 [ballast_and_trim:StartingMission] Stopped
2018-05-23T21:56:57.559Z,1527112617.559 [ballast_and_trim:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission
2018-05-23T21:56:57.559Z,1527112617.559 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2018-05-23T21:56:57.559Z,1527112617.559 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2018-05-23T21:56:57.559Z,1527112617.559 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Running Loop=1
2018-05-23T21:56:57.560Z,1527112617.560 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1
2018-05-23T21:56:57.560Z,1527112617.560 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim] Running Loop=1
2018-05-23T21:56:57.560Z,1527112617.560 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim
2018-05-23T21:56:57.560Z,1527112617.560 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:A] Running Loop=1
2018-05-23T21:56:57.560Z,1527112617.560 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:B] Running Loop=1
2018-05-23T21:56:57.560Z,1527112617.560 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:C] Running Loop=1
2018-05-23T21:56:57.560Z,1527112617.560 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:D] Running Loop=1
2018-05-23T21:56:57.604Z,1527112617.604 [Reporter](INFO): SpeedControl.speedCmd 1.000000 m/s
2018-05-23T21:56:57.985Z,1527112617.985 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:D](INFO): Going to target depth. Speed set to 1.000000 m/s 
2018-05-23T21:56:57.995Z,1527112617.995 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:D] Stopped
2018-05-23T21:56:57.995Z,1527112617.995 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth] Running Loop=1
2018-05-23T21:56:57.995Z,1527112617.995 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth
2018-05-23T21:56:57.995Z,1527112617.995 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1
2018-05-23T21:56:57.995Z,1527112617.995 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Initialize.
2018-05-23T21:56:57.996Z,1527112617.996 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1
2018-05-23T21:56:57.996Z,1527112617.996 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout
2018-05-23T21:56:57.996Z,1527112617.996 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch] Running Loop=1
2018-05-23T21:56:57.996Z,1527112617.996 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Initialize.
2018-05-23T21:56:57.996Z,1527112617.996 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:C] Running Loop=1
2018-05-23T21:56:57.998Z,1527112617.998 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:B] Running Loop=1
2018-05-23T21:56:57.999Z,1527112617.999 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:A] Running Loop=1
2018-05-23T21:56:58.356Z,1527112618.356 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1
2018-05-23T21:56:58.356Z,1527112618.356 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1
2018-05-23T21:56:58.492Z,1527112618.492 [Reporter](INFO): VerticalControl.depthCmd 35.000000 m
2018-05-23T21:57:29.235Z,1527112649.235 [NAL9602](INFO): Powering down
2018-05-23T21:57:29.973Z,1527112649.973 [CommandLine](IMPORTANT): got command stop
2018-05-23T21:57:29.973Z,1527112649.973 [CommandLine](IMPORTANT): Scheduling is paused
2018-05-23T21:57:29.973Z,1527112649.973 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2018-05-23T21:57:30.136Z,1527112650.136 [MissionManager](INFO): MissionManager is completed.
2018-05-23T21:57:30.136Z,1527112650.136 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2018-05-23T21:57:30.136Z,1527112650.136 [ballast_and_trim] Stopped
2018-05-23T21:57:30.136Z,1527112650.136 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2018-05-23T21:57:30.136Z,1527112650.136 [ballast_and_trim:Science] Stopped
2018-05-23T21:57:30.136Z,1527112650.136 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2018-05-23T21:57:30.136Z,1527112650.136 [ballast_and_trim:Science:A] Stopped
2018-05-23T21:57:30.136Z,1527112650.136 [ballast_and_trim:Science:G] Stopped
2018-05-23T21:57:30.136Z,1527112650.136 [ballast_and_trim:Science:Read_Oil] Stopped
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:StandardEnvelopes] Stopped
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::uninitialize ballast_and_trim:StandardEnvelopes
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:RunBallastAndTrim] Stopped
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2018-05-23T21:57:30.137Z,1527112650.137 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Stopped
2018-05-23T21:57:30.138Z,1527112650.138 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1
2018-05-23T21:57:30.138Z,1527112650.138 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim] Stopped
2018-05-23T21:57:30.138Z,1527112650.138 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim
2018-05-23T21:57:30.138Z,1527112650.138 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:A] Stopped
2018-05-23T21:57:30.138Z,1527112650.138 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:B] Stopped
2018-05-23T21:57:30.138Z,1527112650.138 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:C] Stopped
2018-05-23T21:57:30.138Z,1527112650.138 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth] Stopped
2018-05-23T21:57:30.138Z,1527112650.138 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth
2018-05-23T21:57:30.139Z,1527112650.139 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Stopped
2018-05-23T21:57:30.139Z,1527112650.139 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2018-05-23T21:57:30.139Z,1527112650.139 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Stopped
2018-05-23T21:57:30.139Z,1527112650.139 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout
2018-05-23T21:57:30.139Z,1527112650.139 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch] Stopped
2018-05-23T21:57:30.317Z,1527112650.317 [Reporter](INFO): VerticalControl.depthCmd no_value
2018-05-23T21:57:30.326Z,1527112650.326 [WetLabsBB2FL](INFO): Powering down
2018-05-23T21:57:30.499Z,1527112650.499 [MissionManager](IMPORTANT): Started mission Default
2018-05-23T21:57:30.499Z,1527112650.499 [Default] Running Loop=1
2018-05-23T21:57:30.499Z,1527112650.499 [Default](DEBUG): Aggregate::initialize Default
2018-05-23T21:57:30.499Z,1527112650.499 [Default:B.GoToSurface] Running Loop=1
2018-05-23T21:57:30.499Z,1527112650.499 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-23T21:57:30.500Z,1527112650.500 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-23T21:57:30.500Z,1527112650.500 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-23T21:57:30.500Z,1527112650.500 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-23T21:57:30.500Z,1527112650.500 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-23T21:57:30.501Z,1527112650.501 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-23T21:57:30.501Z,1527112650.501 [Default:A.Wait] Running Loop=1
2018-05-23T21:57:30.501Z,1527112650.501 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-05-23T21:57:30.632Z,1527112650.632 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2018-05-23T21:57:30.970Z,1527112650.970 [Aanderaa_O2](INFO): Powering down
2018-05-23T21:57:43.676Z,1527112663.676 [Default:A.Wait](INFO): Done Waiting.
2018-05-23T21:57:43.676Z,1527112663.676 [Default:A.Wait] Stopped
2018-05-23T21:57:43.676Z,1527112663.676 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T21:57:44.089Z,1527112664.089 [Default:CheckIn] Running Loop=1
2018-05-23T21:57:44.089Z,1527112664.089 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T21:57:44.089Z,1527112664.089 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T21:57:44.831Z,1527112664.831 [NAL9602](INFO): Powering up
2018-05-23T21:57:56.873Z,1527112676.873 [NAL9602](INFO): NAL9602 initialized
2018-05-23T21:58:49.265Z,1527112729.265 [NAL9602](INFO): SBD MO Status=2, MOMSN=6048, MT Status=2, MTMSN=0
2018-05-23T21:58:49.265Z,1527112729.265 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T21:59:09.065Z,1527112749.065 [NAL9602](INFO): SBD MO Status=2, MOMSN=6048, MT Status=2, MTMSN=0
2018-05-23T21:59:09.065Z,1527112749.065 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T21:59:57.112Z,1527112797.112 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T21:59:57.112Z,1527112797.112 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T21:59:57.112Z,1527112797.112 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T21:59:57.159Z,1527112797.159 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T21:59:57.543Z,1527112797.543 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T21:59:57.543Z,1527112797.543 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T21:59:57.923Z,1527112797.923 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T21:59:57.924Z,1527112797.924 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T21:59:57.924Z,1527112797.924 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:00:02.675Z,1527112802.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=6048, MT Status=0, MTMSN=0
2018-05-23T22:00:02.675Z,1527112802.675 [NAL9602](INFO): No messages in MT queue
2018-05-23T22:00:27.871Z,1527112827.871 [NAL9602](INFO): GPS fix at 20180523T220025: (36.802113, -121.787014)
2018-05-23T22:00:27.943Z,1527112827.943 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T22:00:27.944Z,1527112827.944 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T22:00:32.750Z,1527112832.750 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180523T195259/Courier0055.lzma
2018-05-23T22:00:33.548Z,1527112833.548 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0055.lzma.bak
2018-05-23T22:00:33.548Z,1527112833.548 [DataOverHttps](INFO): SBD MOMSN=8291175
2018-05-23T22:00:43.255Z,1527112843.255 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20180523T195259/Express0056.lzma
2018-05-23T22:00:43.940Z,1527112843.940 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0056.lzma.bak
2018-05-23T22:00:43.940Z,1527112843.940 [DataOverHttps](INFO): SBD MOMSN=8291178
2018-05-23T22:00:44.796Z,1527112844.796 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T22:00:44.796Z,1527112844.796 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T22:00:44.796Z,1527112844.796 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T22:01:02.631Z,1527112862.631 [NAL9602](INFO): Powering down
2018-05-23T22:02:58.248Z,1527112978.248 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:02:58.248Z,1527112978.248 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T22:02:58.249Z,1527112978.249 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:02:58.261Z,1527112978.261 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:02:58.659Z,1527112978.659 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:02:58.659Z,1527112978.659 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T22:02:59.049Z,1527112979.049 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:02:59.050Z,1527112979.050 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:02:59.050Z,1527112979.050 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:03:12.249Z,1527112992.249 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T22:05:45.081Z,1527113145.081 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T22:05:45.081Z,1527113145.081 [Default:CheckIn:C.Wait] Stopped
2018-05-23T22:05:45.081Z,1527113145.081 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T22:05:45.082Z,1527113145.082 [Default:CheckIn:D] Running Loop=1
2018-05-23T22:05:45.489Z,1527113145.489 [Default:CheckIn:D] Stopped
2018-05-23T22:05:45.489Z,1527113145.489 [Default:CheckIn:E] Running Loop=1
2018-05-23T22:05:45.886Z,1527113145.886 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.249834 min 
2018-05-23T22:05:45.887Z,1527113145.887 [Default:CheckIn:E] Stopped
2018-05-23T22:05:45.887Z,1527113145.887 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T22:05:45.887Z,1527113145.887 [Default:CheckIn] Stopped
2018-05-23T22:05:45.887Z,1527113145.887 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T22:05:45.887Z,1527113145.887 [Default:CheckIn](INFO): Running loop #2
2018-05-23T22:05:45.887Z,1527113145.887 [Default:CheckIn] Running Loop=2
2018-05-23T22:05:45.887Z,1527113145.887 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T22:05:45.887Z,1527113145.887 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T22:05:46.635Z,1527113146.635 [NAL9602](INFO): Powering up
2018-05-23T22:05:57.816Z,1527113157.816 [NAL9602](INFO): NAL9602 initialized
2018-05-23T22:05:59.093Z,1527113159.093 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:05:59.093Z,1527113159.093 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T22:05:59.093Z,1527113159.093 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:05:59.106Z,1527113159.106 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:05:59.511Z,1527113159.511 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:05:59.511Z,1527113159.511 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T22:05:59.874Z,1527113159.874 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:05:59.875Z,1527113159.875 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:05:59.875Z,1527113159.875 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:06:22.225Z,1527113182.225 [NAL9602](INFO): SBD MO Status=2, MOMSN=6049, MT Status=2, MTMSN=0
2018-05-23T22:06:22.225Z,1527113182.225 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T22:06:39.413Z,1527113199.413 [NAL9602](INFO): SBD MO Status=2, MOMSN=6049, MT Status=2, MTMSN=0
2018-05-23T22:06:39.413Z,1527113199.413 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T22:06:50.139Z,1527113210.139 [NAL9602](INFO): SBD MO Status=0, MOMSN=6049, MT Status=0, MTMSN=0
2018-05-23T22:06:50.139Z,1527113210.139 [NAL9602](INFO): No messages in MT queue
2018-05-23T22:06:51.035Z,1527113211.035 [NAL9602](INFO): GPS fix at 20180523T220648: (36.802176, -121.787035)
2018-05-23T22:06:51.104Z,1527113211.104 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T22:06:51.104Z,1527113211.104 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T22:06:55.846Z,1527113215.846 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180523T195259/Courier0058.lzma
2018-05-23T22:06:56.644Z,1527113216.644 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Courier0058.lzma.bak
2018-05-23T22:06:56.644Z,1527113216.644 [DataOverHttps](INFO): SBD MOMSN=8291184
2018-05-23T22:07:06.066Z,1527113226.066 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20180523T195259/Express0059.lzma
2018-05-23T22:07:06.864Z,1527113226.864 [DataOverHttps](INFO): Moved sent file to Logs/20180523T195259/Express0059.lzma.bak
2018-05-23T22:07:06.864Z,1527113226.864 [DataOverHttps](INFO): SBD MOMSN=8291187
2018-05-23T22:07:07.717Z,1527113227.717 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T22:07:07.717Z,1527113227.717 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T22:07:07.717Z,1527113227.717 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T22:07:25.883Z,1527113245.883 [NAL9602](INFO): Powering down
2018-05-23T22:08:59.931Z,1527113339.931 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:08:59.931Z,1527113339.931 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T22:08:59.931Z,1527113339.931 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:08:59.944Z,1527113339.944 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:09:00.342Z,1527113340.342 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:09:00.342Z,1527113340.342 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T22:09:00.733Z,1527113340.733 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:09:00.734Z,1527113340.734 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:09:00.738Z,1527113340.738 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:12:00.836Z,1527113520.836 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:12:00.836Z,1527113520.836 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T22:12:00.836Z,1527113520.836 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:12:00.848Z,1527113520.848 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:12:01.270Z,1527113521.270 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:12:01.270Z,1527113521.270 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T22:12:01.640Z,1527113521.640 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:12:01.640Z,1527113521.640 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:12:01.640Z,1527113521.640 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:12:08.042Z,1527113528.042 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T22:12:08.042Z,1527113528.042 [Default:CheckIn:C.Wait] Stopped
2018-05-23T22:12:08.042Z,1527113528.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T22:12:08.043Z,1527113528.043 [Default:CheckIn:D] Running Loop=1
2018-05-23T22:12:08.428Z,1527113528.428 [Default:CheckIn:D] Stopped
2018-05-23T22:12:08.428Z,1527113528.428 [Default:CheckIn:E] Running Loop=1
2018-05-23T22:12:08.849Z,1527113528.849 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.632145 min 
2018-05-23T22:12:08.849Z,1527113528.849 [Default:CheckIn:E] Stopped
2018-05-23T22:12:08.849Z,1527113528.849 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T22:12:08.849Z,1527113528.849 [Default:CheckIn] Stopped
2018-05-23T22:12:08.849Z,1527113528.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T22:12:08.849Z,1527113528.849 [Default:CheckIn](INFO): Running loop #3
2018-05-23T22:12:08.849Z,1527113528.849 [Default:CheckIn] Running Loop=3
2018-05-23T22:12:08.850Z,1527113528.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T22:12:08.850Z,1527113528.850 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T22:12:12.007Z,1527113532.007 [NAL9602](INFO): Powering up
2018-05-23T22:12:23.179Z,1527113543.179 [NAL9602](INFO): NAL9602 initialized
2018-05-23T22:13:12.372Z,1527113592.372 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T22:15:01.696Z,1527113701.696 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:15:01.696Z,1527113701.696 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T22:15:01.696Z,1527113701.696 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:15:01.721Z,1527113701.721 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:15:02.122Z,1527113702.122 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:15:02.122Z,1527113702.122 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T22:15:02.502Z,1527113702.502 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:15:02.510Z,1527113702.510 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:15:02.511Z,1527113702.511 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:16:07.245Z,1527113767.245 [NAL9602](INFO): SBD MO Status=2, MOMSN=6050, MT Status=2, MTMSN=0
2018-05-23T22:16:07.245Z,1527113767.245 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T22:16:13.819Z,1527113773.819 [DataOverHttps](IMPORTANT): SBD MTMSN=20180523T221612