2018-07-25T16:44:38.032Z,1532537078.032 [Supervisor](DEBUG): Initializing supervisor.
2018-07-25T16:44:38.036Z,1532537078.036 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-07-25T16:44:38.037Z,1532537078.037 [SyncHandler](INFO): Protected caller Thread ID is 1025
2018-07-25T16:44:38.037Z,1532537078.037 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-07-25T16:44:38.038Z,1532537078.038 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-07-25T16:44:38.038Z,1532537078.038 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1026
2018-07-25T16:44:38.042Z,1532537078.042 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-07-25T16:44:38.054Z,1532537078.054 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-07-25T16:44:38.055Z,1532537078.055 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-07-25T16:44:38.056Z,1532537078.056 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1027
2018-07-25T16:44:38.056Z,1532537078.056 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-07-25T16:44:38.057Z,1532537078.057 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-07-25T16:44:38.058Z,1532537078.058 [logger ThreadHandler](INFO): Protected caller Thread ID is 1028
2018-07-25T16:44:38.060Z,1532537078.060 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-07-25T16:44:38.060Z,1532537078.060 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-07-25T16:44:38.062Z,1532537078.062 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-07-25T16:44:38.276Z,1532537078.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-07-25T16:44:38.277Z,1532537078.277 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-07-25T16:44:38.419Z,1532537078.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-07-25T16:44:38.419Z,1532537078.419 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-07-25T16:44:38.765Z,1532537078.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-07-25T16:44:38.766Z,1532537078.766 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-07-25T16:44:38.904Z,1532537078.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-07-25T16:44:38.905Z,1532537078.905 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-07-25T16:44:38.994Z,1532537078.994 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-07-25T16:44:39.196Z,1532537079.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-07-25T16:44:39.197Z,1532537079.197 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-07-25T16:44:39.295Z,1532537079.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-07-25T16:44:39.295Z,1532537079.295 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-07-25T16:44:39.648Z,1532537079.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-07-25T16:44:39.649Z,1532537079.649 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-07-25T16:44:40.081Z,1532537080.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-07-25T16:44:40.082Z,1532537080.082 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-07-25T16:44:40.386Z,1532537080.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-07-25T16:44:40.387Z,1532537080.387 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-07-25T16:44:40.904Z,1532537080.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-07-25T16:44:40.905Z,1532537080.905 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-07-25T16:44:41.173Z,1532537081.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-07-25T16:44:41.173Z,1532537081.173 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-07-25T16:44:41.271Z,1532537081.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-07-25T16:44:41.272Z,1532537081.272 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-07-25T16:44:41.755Z,1532537081.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-07-25T16:44:41.755Z,1532537081.755 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-07-25T16:44:41.862Z,1532537081.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-07-25T16:44:41.864Z,1532537081.864 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-07-25T16:44:41.865Z,1532537081.865 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-07-25T16:44:42.096Z,1532537082.096 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-07-25T16:44:42.096Z,1532537082.096 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-07-25T16:44:42.194Z,1532537082.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-07-25T16:44:42.288Z,1532537082.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-07-25T16:44:42.548Z,1532537082.548 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-07-25T16:44:42.634Z,1532537082.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-07-25T16:44:42.766Z,1532537082.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-07-25T16:44:42.932Z,1532537082.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-07-25T16:44:43.042Z,1532537083.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-07-25T16:44:43.127Z,1532537083.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-07-25T16:44:43.211Z,1532537083.211 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-07-25T16:44:43.398Z,1532537083.398 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-07-25T16:44:43.398Z,1532537083.398 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-07-25T16:44:43.402Z,1532537083.402 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-07-25T16:44:44.199Z,1532537084.199 [SBIT](DEBUG): Construct Startup Built In Test.
2018-07-25T16:44:44.229Z,1532537084.229 [SBIT] Loaded
2018-07-25T16:44:44.229Z,1532537084.229 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-07-25T16:44:44.230Z,1532537084.230 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-07-25T16:44:44.257Z,1532537084.257 [IBIT] Loaded
2018-07-25T16:44:44.257Z,1532537084.257 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-07-25T16:44:44.261Z,1532537084.261 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-07-25T16:44:44.414Z,1532537084.414 [CBIT] Loaded
2018-07-25T16:44:44.415Z,1532537084.415 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-07-25T16:44:44.415Z,1532537084.415 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-07-25T16:44:44.416Z,1532537084.416 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-07-25T16:44:44.503Z,1532537084.503 [VerticalControl](DEBUG): Construct VerticalControl.
2018-07-25T16:44:44.600Z,1532537084.600 [VerticalControl] Loaded
2018-07-25T16:44:44.601Z,1532537084.601 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-07-25T16:44:44.601Z,1532537084.601 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-07-25T16:44:44.662Z,1532537084.662 [HorizontalControl] Loaded
2018-07-25T16:44:44.662Z,1532537084.662 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-07-25T16:44:44.663Z,1532537084.663 [SpeedControl](DEBUG): Construct SpeedControl.
2018-07-25T16:44:44.669Z,1532537084.669 [SpeedControl] Loaded
2018-07-25T16:44:44.669Z,1532537084.669 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-07-25T16:44:44.670Z,1532537084.670 [LoopControl](DEBUG): Construct LoopControl.
2018-07-25T16:44:44.670Z,1532537084.670 [LoopControl] Loaded
2018-07-25T16:44:44.670Z,1532537084.670 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-07-25T16:44:44.671Z,1532537084.671 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-07-25T16:44:44.671Z,1532537084.671 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-07-25T16:44:44.755Z,1532537084.755 [DepthRateCalculator] Loaded
2018-07-25T16:44:44.755Z,1532537084.755 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-07-25T16:44:44.761Z,1532537084.761 [PitchRateCalculator] Loaded
2018-07-25T16:44:44.761Z,1532537084.761 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-07-25T16:44:44.777Z,1532537084.777 [SpeedCalculator] Loaded
2018-07-25T16:44:44.777Z,1532537084.777 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-07-25T16:44:44.798Z,1532537084.798 [TempGradientCalculator] Loaded
2018-07-25T16:44:44.798Z,1532537084.798 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-07-25T16:44:44.814Z,1532537084.814 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-07-25T16:44:44.814Z,1532537084.814 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-07-25T16:44:44.819Z,1532537084.819 [YawRateCalculator] Loaded
2018-07-25T16:44:44.819Z,1532537084.819 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-07-25T16:44:44.848Z,1532537084.848 [ElevatorOffsetCalculator] Loaded
2018-07-25T16:44:44.848Z,1532537084.848 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-07-25T16:44:44.848Z,1532537084.848 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-07-25T16:44:44.849Z,1532537084.849 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-07-25T16:44:49.129Z,1532537089.129 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-07-25T16:44:49.129Z,1532537089.129 [StratificationFrontDetector](DEBUG): (re)initializing
2018-07-25T16:44:49.130Z,1532537089.130 [StratificationFrontDetector] Loaded
2018-07-25T16:44:49.130Z,1532537089.130 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-07-25T16:44:49.131Z,1532537089.131 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-07-25T16:44:49.131Z,1532537089.131 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-07-25T16:44:49.411Z,1532537089.411 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-07-25T16:44:49.411Z,1532537089.411 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-07-25T16:44:49.523Z,1532537089.523 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-07-25T16:44:49.523Z,1532537089.523 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-07-25T16:44:49.577Z,1532537089.577 [DeadReckonUsingSpeedCalculator] Loaded
2018-07-25T16:44:49.578Z,1532537089.578 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-07-25T16:44:49.594Z,1532537089.594 [NavChart] Loaded
2018-07-25T16:44:49.595Z,1532537089.595 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-07-25T16:44:49.599Z,1532537089.599 [UniversalFixResidualReporter] Loaded
2018-07-25T16:44:49.599Z,1532537089.599 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-07-25T16:44:49.599Z,1532537089.599 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-07-25T16:44:49.600Z,1532537089.600 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-07-25T16:44:49.624Z,1532537089.624 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-07-25T16:44:49.624Z,1532537089.624 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-07-25T16:44:49.824Z,1532537089.824 [Aanderaa_O2] Loaded
2018-07-25T16:44:49.824Z,1532537089.824 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-07-25T16:44:49.874Z,1532537089.874 [WetLabsBB2FL] Loaded
2018-07-25T16:44:49.874Z,1532537089.874 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-07-25T16:44:49.875Z,1532537089.875 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406D24E0
2018-07-25T16:44:49.875Z,1532537089.875 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1107
2018-07-25T16:44:49.876Z,1532537089.876 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-07-25T16:44:49.877Z,1532537089.877 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-07-25T16:44:50.230Z,1532537090.230 [AHRS_M2] Loaded
2018-07-25T16:44:50.230Z,1532537090.230 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-07-25T16:44:50.302Z,1532537090.302 [DataOverHttps] Loaded
2018-07-25T16:44:50.303Z,1532537090.303 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-07-25T16:44:50.316Z,1532537090.316 [Depth_Keller] Loaded
2018-07-25T16:44:50.316Z,1532537090.316 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-07-25T16:44:50.321Z,1532537090.321 [DropWeight] Loaded
2018-07-25T16:44:50.321Z,1532537090.321 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-07-25T16:44:50.411Z,1532537090.411 [NAL9602] Loaded
2018-07-25T16:44:50.411Z,1532537090.411 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-07-25T16:44:50.416Z,1532537090.416 [Onboard] Loaded
2018-07-25T16:44:50.416Z,1532537090.416 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-07-25T16:44:50.423Z,1532537090.423 [Radio_Surface] Loaded
2018-07-25T16:44:50.424Z,1532537090.424 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-07-25T16:44:50.425Z,1532537090.425 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409284E0
2018-07-25T16:44:50.425Z,1532537090.425 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1108
2018-07-25T16:44:50.454Z,1532537090.454 [RDI_Pathfinder] Loaded
2018-07-25T16:44:50.454Z,1532537090.454 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-07-25T16:44:50.460Z,1532537090.460 [SCPI] Loaded
2018-07-25T16:44:50.461Z,1532537090.461 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-07-25T16:44:52.251Z,1532537092.251 [BPC1] Loaded
2018-07-25T16:44:52.251Z,1532537092.251 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-07-25T16:44:52.252Z,1532537092.252 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-07-25T16:44:52.252Z,1532537092.252 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-07-25T16:44:52.371Z,1532537092.371 [BuoyancyServo] Loaded
2018-07-25T16:44:52.371Z,1532537092.371 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-07-25T16:44:52.383Z,1532537092.383 [ElevatorServo] Loaded
2018-07-25T16:44:52.383Z,1532537092.383 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-07-25T16:44:52.394Z,1532537092.394 [MassServo] Loaded
2018-07-25T16:44:52.394Z,1532537092.394 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-07-25T16:44:52.405Z,1532537092.405 [RudderServo] Loaded
2018-07-25T16:44:52.406Z,1532537092.406 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-07-25T16:44:52.416Z,1532537092.416 [ThrusterServo] Loaded
2018-07-25T16:44:52.417Z,1532537092.417 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-07-25T16:44:52.417Z,1532537092.417 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-07-25T16:44:52.418Z,1532537092.418 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-07-25T16:44:52.543Z,1532537092.543 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-07-25T16:44:52.544Z,1532537092.544 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-07-25T16:44:52.581Z,1532537092.581 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-07-25T16:44:52.585Z,1532537092.585 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-07-25T16:44:52.586Z,1532537092.586 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-07-25T16:44:52.592Z,1532537092.592 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-07-25T16:44:52.594Z,1532537092.594 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D14E0
2018-07-25T16:44:52.594Z,1532537092.594 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1109
2018-07-25T16:44:52.599Z,1532537092.599 [Supervisor](INFO): Main Thread ID is 796
2018-07-25T16:44:52.599Z,1532537092.599 [Supervisor](DEBUG): Running supervisor.
2018-07-25T16:44:52.599Z,1532537092.599 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1110
2018-07-25T16:44:52.602Z,1532537092.602 [controlThread ThreadHandler](INFO): Handler Thread ID is 1111
2018-07-25T16:44:52.602Z,1532537092.602 [controlThread](DEBUG): Initializing ControlThread
2018-07-25T16:44:52.603Z,1532537092.603 [SBIT](INFO): Initialize SBIT Component.
2018-07-25T16:44:52.604Z,1532537092.604 [SBIT](IMPORTANT): git: 2018-07-25
2018-07-25T16:44:52.604Z,1532537092.604 [SBIT](INFO): git hash: 2912d14d75d7acdc47d6eebe250915c9e75a6268
2018-07-25T16:44:52.604Z,1532537092.604 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-07-25T16:44:52.606Z,1532537092.606 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-07-25T16:44:52.607Z,1532537092.607 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-07-25T16:44:52.608Z,1532537092.608 [IBIT](INFO): Initialize IBIT Component.
2018-07-25T16:44:52.609Z,1532537092.609 [CBIT](DEBUG): Initialize CBIT Component.
2018-07-25T16:44:52.609Z,1532537092.609 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-07-25T16:44:52.610Z,1532537092.610 [logger ThreadHandler](INFO): Handler Thread ID is 1112
2018-07-25T16:44:52.637Z,1532537092.637 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1113
2018-07-25T16:44:52.657Z,1532537092.657 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1114
2018-07-25T16:44:52.663Z,1532537092.663 [Radio_Surface](INFO): Powering up
2018-07-25T16:44:52.681Z,1532537092.681 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1115
2018-07-25T16:44:52.687Z,1532537092.687 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-07-25T16:44:52.687Z,1532537092.687 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-07-25T16:44:52.688Z,1532537092.688 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-07-25T16:44:52.688Z,1532537092.688 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-07-25T16:44:52.688Z,1532537092.688 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-07-25T16:44:52.688Z,1532537092.688 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-07-25T16:44:52.688Z,1532537092.688 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-07-25T16:44:52.689Z,1532537092.689 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-07-25T16:44:52.689Z,1532537092.689 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-07-25T16:44:52.689Z,1532537092.689 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-07-25T16:44:52.689Z,1532537092.689 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-07-25T16:44:52.690Z,1532537092.690 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-07-25T16:44:52.690Z,1532537092.690 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-07-25T16:44:52.690Z,1532537092.690 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-07-25T16:44:52.690Z,1532537092.690 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-07-25T16:44:52.690Z,1532537092.690 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-07-25T16:44:52.717Z,1532537092.717 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-07-25T16:44:52.719Z,1532537092.719 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-07-25T16:44:52.720Z,1532537092.720 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-07-25T16:44:52.720Z,1532537092.720 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-07-25T16:44:52.720Z,1532537092.720 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-25T16:44:52.721Z,1532537092.721 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-07-25T16:44:52.721Z,1532537092.721 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-07-25T16:44:52.722Z,1532537092.722 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-07-25T16:44:52.722Z,1532537092.722 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-07-25T16:44:52.723Z,1532537092.723 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-07-25T16:44:52.723Z,1532537092.723 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-07-25T16:44:52.724Z,1532537092.724 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-25T16:44:52.725Z,1532537092.725 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:44:52.725Z,1532537092.725 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:44:52.726Z,1532537092.726 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-25T16:44:52.726Z,1532537092.726 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:44:52.727Z,1532537092.727 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:44:52.727Z,1532537092.727 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-07-25T16:44:52.727Z,1532537092.727 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-07-25T16:44:52.734Z,1532537092.734 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-07-25T16:44:52.770Z,1532537092.770 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-25T16:44:52.810Z,1532537092.810 [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-07-25T16:44:52.811Z,1532537092.811 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-07-25T16:44:52.886Z,1532537092.886 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-07-25T16:44:52.887Z,1532537092.887 [Default:A.Wait](DEBUG): Construct Wait.
2018-07-25T16:44:52.909Z,1532537092.909 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-25T16:44:52.958Z,1532537092.958 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-07-25T16:44:52.961Z,1532537092.961 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-07-25T16:44:52.983Z,1532537092.983 [Default:E.Execute](DEBUG): Construct Execute.
2018-07-25T16:44:52.986Z,1532537092.986 [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-07-25T16:44:53.003Z,1532537093.003 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-07-25T16:44:53.052Z,1532537093.052 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-25T16:44:53.109Z,1532537093.109 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-07-25T16:44:53.557Z,1532537093.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:53.577Z,1532537093.577 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:53.688Z,1532537093.688 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-25T16:44:53.693Z,1532537093.693 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-25T16:44:53.712Z,1532537093.712 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-07-25T16:44:53.717Z,1532537093.717 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-07-25T16:44:53.739Z,1532537093.739 [MassServo](DEBUG): Initializing EZServoServo.
2018-07-25T16:44:53.745Z,1532537093.745 [MassServo](DEBUG): Initializing MassServo.
2018-07-25T16:44:53.759Z,1532537093.759 [RudderServo](DEBUG): Initializing EZServoServo.
2018-07-25T16:44:53.765Z,1532537093.765 [RudderServo](DEBUG): Initializing RudderServo.
2018-07-25T16:44:53.781Z,1532537093.781 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-25T16:44:53.789Z,1532537093.789 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-25T16:44:53.838Z,1532537093.838 [CommandLine](FAULT): Scheduling is paused
2018-07-25T16:44:53.838Z,1532537093.838 [CBIT](INFO): Critical error at 20180725T164452
2018-07-25T16:44:53.839Z,1532537093.839 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-07-25T16:44:54.014Z,1532537094.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:54.015Z,1532537094.015 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:54.327Z,1532537094.327 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:54.329Z,1532537094.329 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:54.677Z,1532537094.677 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-07-25T16:44:54.677Z,1532537094.677 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-07-25T16:44:54.677Z,1532537094.677 [BuoyancyServo] Communications Fault, FailCount= 1
2018-07-25T16:44:54.677Z,1532537094.677 [BuoyancyServo](ERROR): Communications Fault
2018-07-25T16:44:54.896Z,1532537094.896 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-25T16:44:54.945Z,1532537094.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:54.946Z,1532537094.946 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:54.953Z,1532537094.953 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-25T16:44:54.969Z,1532537094.969 [BuoyancyServo](INFO): Powering down
2018-07-25T16:44:55.373Z,1532537095.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:55.374Z,1532537095.374 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:55.805Z,1532537095.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:55.806Z,1532537095.806 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:56.225Z,1532537096.225 [Aanderaa_O2](INFO): Powering down
2018-07-25T16:44:56.369Z,1532537096.369 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:56.370Z,1532537096.370 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:56.557Z,1532537096.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:56.558Z,1532537096.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:56.989Z,1532537096.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:56.990Z,1532537096.990 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:57.114Z,1532537097.114 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-25T16:44:57.114Z,1532537097.114 [BuoyancyServo] No Fault, FailCount= 1
2018-07-25T16:44:57.405Z,1532537097.405 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-25T16:44:57.433Z,1532537097.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:57.434Z,1532537097.434 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:57.449Z,1532537097.449 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-25T16:44:57.570Z,1532537097.570 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-25T16:44:58.113Z,1532537098.113 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-25T16:44:58.141Z,1532537098.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:58.142Z,1532537098.142 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:58.741Z,1532537098.741 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-25T16:44:58.771Z,1532537098.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:58.772Z,1532537098.772 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:59.141Z,1532537099.141 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-25T16:44:59.170Z,1532537099.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:59.171Z,1532537099.171 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:59.545Z,1532537099.545 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2018-07-25T16:44:59.574Z,1532537099.574 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:59.575Z,1532537099.575 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:59.814Z,1532537099.814 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:44:59.815Z,1532537099.815 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:00.150Z,1532537100.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:00.151Z,1532537100.151 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:00.546Z,1532537100.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:00.547Z,1532537100.547 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:00.922Z,1532537100.922 [AHRS_M2](FAULT): Failed to initialize within timeout.
2018-07-25T16:45:00.922Z,1532537100.922 [AHRS_M2] Communications Fault, FailCount= 1
2018-07-25T16:45:00.922Z,1532537100.922 [AHRS_M2](ERROR): Communications Fault
2018-07-25T16:45:00.954Z,1532537100.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:00.955Z,1532537100.955 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:01.122Z,1532537101.122 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-07-25T16:45:01.294Z,1532537101.294 [AHRS_M2](INFO): Powering down
2018-07-25T16:45:01.438Z,1532537101.438 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:01.439Z,1532537101.439 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:01.722Z,1532537101.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:01.723Z,1532537101.723 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:02.126Z,1532537102.126 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:02.127Z,1532537102.127 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:02.522Z,1532537102.522 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:02.523Z,1532537102.523 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:02.922Z,1532537102.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:02.923Z,1532537102.923 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:03.326Z,1532537103.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:03.327Z,1532537103.327 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:03.722Z,1532537103.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:03.723Z,1532537103.723 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:04.122Z,1532537104.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:04.123Z,1532537104.123 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:04.151Z,1532537104.151 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-07-25T16:45:04.151Z,1532537104.151 [AHRS_M2] No Fault, FailCount= 1
2018-07-25T16:45:04.495Z,1532537104.495 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-25T16:45:04.542Z,1532537104.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:04.543Z,1532537104.543 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:04.922Z,1532537104.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:04.923Z,1532537104.923 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:05.322Z,1532537105.322 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:05.323Z,1532537105.323 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:05.726Z,1532537105.726 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:05.727Z,1532537105.727 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:06.122Z,1532537106.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:06.123Z,1532537106.123 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:06.550Z,1532537106.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:06.551Z,1532537106.551 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:06.945Z,1532537106.945 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-25T16:45:06.954Z,1532537106.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:06.955Z,1532537106.955 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:07.374Z,1532537107.374 [RDI_Pathfinder](ERROR): Failed to parse:
All rights reserved.
2018-07-25T16:45:07.382Z,1532537107.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:07.383Z,1532537107.383 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:07.804Z,1532537107.804 [RDI_Pathfinder](ERROR): Failed to parse:
Current time is: 18/07/25,16:46:05.99
2018-07-25T16:45:07.850Z,1532537107.850 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:07.851Z,1532537107.851 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:08.144Z,1532537108.144 [RDI_Pathfinder](ERROR): Failed to parse:
>
2018-07-25T16:45:08.154Z,1532537108.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:08.155Z,1532537108.155 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:08.603Z,1532537108.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:08.604Z,1532537108.604 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:08.997Z,1532537108.997 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:08.999Z,1532537108.999 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:09.350Z,1532537109.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:09.351Z,1532537109.351 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:09.797Z,1532537109.797 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:09.798Z,1532537109.798 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:10.154Z,1532537110.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:10.155Z,1532537110.155 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:10.526Z,1532537110.526 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:10.527Z,1532537110.527 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:10.950Z,1532537110.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:10.951Z,1532537110.951 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:11.354Z,1532537111.354 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:11.355Z,1532537111.355 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-25T16:45:20.515Z,1532537120.515 [NAL9602](INFO): Powering up NAL9602
2018-07-25T16:45:21.357Z,1532537121.357 [SBIT](IMPORTANT): Beginning Startup BIT
2018-07-25T16:45:21.361Z,1532537121.361 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-25T16:45:31.435Z,1532537131.435 [NAL9602](INFO): NAL9602 initialized
2018-07-25T16:45:32.351Z,1532537132.351 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009556
CHAN A1 (24V): -0.013041
CHAN A2 (12V): -0.007277
CHAN A3 (5V): -0.002304
CHAN B0 (3.3V): 0.000405
CHAN B1 (3.15aV): 0.000102
CHAN B2 (3.15bV): 0.000251
CHAN B3 (GND): 0.002462
OPEN: 0.005359
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-25T16:45:58.850Z,1532537158.850 [NAL9602](INFO): SBD MO Status=2, MOMSN=368, MT Status=2, MTMSN=0
2018-07-25T16:45:58.850Z,1532537158.850 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-25T16:46:15.459Z,1532537175.459 [SBIT](IMPORTANT): SBIT PASSED
2018-07-25T16:46:15.553Z,1532537175.553 [CommandLine](IMPORTANT): got command configSet list
2018-07-25T16:46:15.554Z,1532537175.554 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-07-25T16:46:15.555Z,1532537175.555 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=300 cubic_centimeter;

2018-07-25T16:46:15.555Z,1532537175.555 [CommandLine](IMPORTANT): VerticalControl.massDefault=2 millimeter;

2018-07-25T16:46:15.830Z,1532537175.830 [MissionManager](IMPORTANT): Started mission Startup
2018-07-25T16:46:15.830Z,1532537175.830 [Startup] Running Loop=1
2018-07-25T16:46:15.830Z,1532537175.830 [Startup](DEBUG): Aggregate::initialize Startup
2018-07-25T16:46:15.830Z,1532537175.830 [Startup:A.GoToSurface] Running Loop=1
2018-07-25T16:46:15.830Z,1532537175.830 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-25T16:46:15.831Z,1532537175.831 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-25T16:46:15.832Z,1532537175.832 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-25T16:46:15.832Z,1532537175.832 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-25T16:46:15.832Z,1532537175.832 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-25T16:46:15.837Z,1532537175.837 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-25T16:46:15.847Z,1532537175.847 [Startup:StartupSatComms] Running Loop=1
2018-07-25T16:46:15.847Z,1532537175.847 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-07-25T16:46:15.848Z,1532537175.848 [Startup:StartupSatComms:A] Running Loop=1
2018-07-25T16:46:16.246Z,1532537176.246 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-07-25T16:47:16.245Z,1532537236.245 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-25T16:46:15.8Z
2018-07-25T16:47:16.246Z,1532537236.246 [Startup:StartupSatComms:A] Stopped
2018-07-25T16:47:16.246Z,1532537236.246 [Startup:StartupSatComms:B] Running Loop=1
2018-07-25T16:47:16.620Z,1532537236.620 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-25T16:47:53.043Z,1532537273.043 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:47:53.043Z,1532537273.043 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-07-25T16:47:53.044Z,1532537273.044 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-25T16:47:53.046Z,1532537273.046 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:47:53.046Z,1532537273.046 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-07-25T16:47:53.046Z,1532537273.046 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-25T16:47:53.083Z,1532537273.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-25T16:47:53.083Z,1532537273.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-25T16:47:53.445Z,1532537273.445 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-25T16:47:53.445Z,1532537273.445 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-07-25T16:47:53.446Z,1532537273.446 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-25T16:47:53.446Z,1532537273.446 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-07-25T16:47:53.827Z,1532537273.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-25T16:47:53.828Z,1532537273.828 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:47:53.828Z,1532537273.828 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:47:53.828Z,1532537273.828 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-25T16:47:53.829Z,1532537273.829 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:47:53.829Z,1532537273.829 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:47:54.236Z,1532537274.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-25T16:47:54.634Z,1532537274.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-25T16:47:55.023Z,1532537275.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-25T16:47:55.447Z,1532537275.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-25T16:47:55.824Z,1532537275.824 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-25T16:47:56.236Z,1532537276.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-25T16:47:56.627Z,1532537276.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-25T16:47:57.047Z,1532537277.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-25T16:47:57.440Z,1532537277.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-25T16:47:57.835Z,1532537277.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-25T16:47:58.223Z,1532537278.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-25T16:47:58.612Z,1532537278.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-25T16:47:59.039Z,1532537279.039 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-25T16:47:59.411Z,1532537279.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-25T16:47:59.835Z,1532537279.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-25T16:48:00.231Z,1532537280.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-25T16:48:00.623Z,1532537280.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-25T16:48:01.038Z,1532537281.038 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-25T16:48:01.444Z,1532537281.444 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-25T16:48:01.845Z,1532537281.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-25T16:48:02.227Z,1532537282.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-25T16:48:02.639Z,1532537282.639 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-25T16:48:03.052Z,1532537283.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-25T16:48:03.408Z,1532537283.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-25T16:48:03.812Z,1532537283.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-25T16:48:04.239Z,1532537284.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-25T16:48:04.623Z,1532537284.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-25T16:48:05.016Z,1532537285.016 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-25T16:48:05.431Z,1532537285.431 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-25T16:48:05.831Z,1532537285.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-25T16:48:06.243Z,1532537286.243 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-25T16:48:06.634Z,1532537286.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-25T16:48:07.043Z,1532537287.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-25T16:48:07.426Z,1532537287.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-25T16:48:07.852Z,1532537287.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-25T16:48:08.212Z,1532537288.212 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-25T16:48:08.612Z,1532537288.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-07-25T16:48:09.019Z,1532537289.019 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-07-25T16:48:09.423Z,1532537289.423 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-07-25T16:48:09.827Z,1532537289.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-07-25T16:48:10.224Z,1532537290.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-07-25T16:48:10.647Z,1532537290.647 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-07-25T16:48:11.042Z,1532537291.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-07-25T16:48:11.419Z,1532537291.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-25T16:48:11.832Z,1532537291.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-25T16:48:12.223Z,1532537292.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-25T16:48:12.621Z,1532537292.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-25T16:48:13.038Z,1532537293.038 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-07-25T16:48:18.241Z,1532537298.241 [Startup:StartupSatComms:B](INFO): Timed out from 2018-07-25T16:47:16.2Z
2018-07-25T16:48:18.241Z,1532537298.241 [Startup:StartupSatComms:B] Stopped
2018-07-25T16:48:18.241Z,1532537298.241 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-07-25T16:48:18.241Z,1532537298.241 [Startup:StartupSatComms] Stopped
2018-07-25T16:48:18.241Z,1532537298.241 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-07-25T16:48:18.242Z,1532537298.242 [Startup](INFO): Completed Startup
2018-07-25T16:48:18.243Z,1532537298.243 [MissionManager](INFO): Startup is completed.
2018-07-25T16:48:18.243Z,1532537298.243 [MissionManager](INFO): Uninitializing Mission Startup
2018-07-25T16:48:18.243Z,1532537298.243 [Startup] Stopped
2018-07-25T16:48:18.243Z,1532537298.243 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-07-25T16:48:18.243Z,1532537298.243 [Startup:A.GoToSurface] Stopped
2018-07-25T16:48:18.243Z,1532537298.243 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-25T16:48:18.382Z,1532537298.382 [MissionManager](IMPORTANT): Started mission Default
2018-07-25T16:48:18.382Z,1532537298.382 [Default] Running Loop=1
2018-07-25T16:48:18.382Z,1532537298.382 [Default](DEBUG): Aggregate::initialize Default
2018-07-25T16:48:18.382Z,1532537298.382 [Default:B.GoToSurface] Running Loop=1
2018-07-25T16:48:18.382Z,1532537298.382 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-25T16:48:18.383Z,1532537298.383 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-25T16:48:18.383Z,1532537298.383 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-25T16:48:18.383Z,1532537298.383 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-25T16:48:18.384Z,1532537298.384 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-25T16:48:18.389Z,1532537298.389 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-25T16:48:18.389Z,1532537298.389 [Default:A.Wait] Running Loop=1
2018-07-25T16:48:18.389Z,1532537298.389 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-25T16:48:19.075Z,1532537299.075 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003933
2018-07-25T16:48:31.524Z,1532537311.524 [Default:A.Wait](INFO): Done Waiting.
2018-07-25T16:48:31.524Z,1532537311.524 [Default:A.Wait] Stopped
2018-07-25T16:48:31.524Z,1532537311.524 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T16:48:31.946Z,1532537311.946 [Default:CheckIn] Running Loop=1
2018-07-25T16:48:31.946Z,1532537311.946 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T16:48:31.947Z,1532537311.947 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T16:48:32.334Z,1532537312.334 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-25T16:50:34.863Z,1532537434.863 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-25T16:50:54.146Z,1532537454.146 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:50:54.146Z,1532537454.146 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-07-25T16:50:54.146Z,1532537454.146 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-25T16:50:54.148Z,1532537454.148 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:50:54.148Z,1532537454.148 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-07-25T16:50:54.148Z,1532537454.148 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-25T16:50:54.161Z,1532537454.161 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-25T16:50:54.162Z,1532537454.162 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-25T16:50:54.518Z,1532537454.518 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-25T16:50:54.518Z,1532537454.518 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-07-25T16:50:54.518Z,1532537454.518 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-25T16:50:54.519Z,1532537454.519 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-07-25T16:50:54.903Z,1532537454.903 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-25T16:50:54.903Z,1532537454.903 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:50:54.903Z,1532537454.903 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:50:54.904Z,1532537454.904 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-25T16:50:54.904Z,1532537454.904 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:50:54.909Z,1532537454.909 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:50:55.323Z,1532537455.323 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-25T16:50:58.506Z,1532537458.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-25T16:50:58.632Z,1532537458.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-07-25T16:50:59.011Z,1532537459.011 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2018-07-25T16:50:59.398Z,1532537459.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2018-07-25T16:50:59.808Z,1532537459.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2018-07-25T16:51:00.175Z,1532537460.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-25T16:51:00.579Z,1532537460.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-25T16:51:01.022Z,1532537461.022 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2018-07-25T16:51:01.408Z,1532537461.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2018-07-25T16:51:01.819Z,1532537461.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2018-07-25T16:51:02.187Z,1532537462.187 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-25T16:51:02.582Z,1532537462.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-25T16:51:02.987Z,1532537462.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-25T16:51:03.379Z,1532537463.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-25T16:51:03.782Z,1532537463.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-25T16:51:04.187Z,1532537464.187 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-25T16:51:04.583Z,1532537464.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-25T16:51:04.987Z,1532537464.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-25T16:51:05.424Z,1532537465.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2018-07-25T16:51:05.768Z,1532537465.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-25T16:51:06.200Z,1532537466.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2018-07-25T16:51:06.571Z,1532537466.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-25T16:51:07.004Z,1532537467.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s.
2018-07-25T16:51:07.389Z,1532537467.389 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-25T16:51:07.804Z,1532537467.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2018-07-25T16:51:08.171Z,1532537468.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-25T16:51:08.575Z,1532537468.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-25T16:51:09.007Z,1532537469.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2018-07-25T16:51:09.375Z,1532537469.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-25T16:51:09.798Z,1532537469.798 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-07-25T16:51:10.192Z,1532537470.192 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-07-25T16:51:10.595Z,1532537470.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-07-25T16:51:10.990Z,1532537470.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-07-25T16:51:11.386Z,1532537471.386 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-07-25T16:51:11.802Z,1532537471.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2018-07-25T16:51:12.177Z,1532537472.177 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-07-25T16:51:12.587Z,1532537472.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-25T16:51:12.996Z,1532537472.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2018-07-25T16:51:13.395Z,1532537473.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-25T16:51:13.793Z,1532537473.793 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-25T16:53:32.023Z,1532537612.023 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-25T16:48:31.9Z
2018-07-25T16:53:32.023Z,1532537612.023 [Default:CheckIn:Read_GPS] Stopped
2018-07-25T16:53:32.023Z,1532537612.023 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-25T16:53:32.418Z,1532537612.418 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-25T16:53:36.468Z,1532537616.468 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180725T160816/Courier0013.lzma
2018-07-25T16:53:37.247Z,1532537617.247 [DataOverHttps](INFO): Moved sent file to Logs/20180725T160816/Courier0013.lzma.bak
2018-07-25T16:53:37.247Z,1532537617.247 [DataOverHttps](INFO): SBD MOMSN=8375845
2018-07-25T16:53:46.695Z,1532537626.695 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20180725T164438/Courier0000.lzma
2018-07-25T16:53:47.482Z,1532537627.482 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0000.lzma.bak
2018-07-25T16:53:47.482Z,1532537627.482 [DataOverHttps](INFO): SBD MOMSN=8375847
2018-07-25T16:53:56.402Z,1532537636.402 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:53:56.402Z,1532537636.402 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-07-25T16:53:56.402Z,1532537636.402 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-25T16:53:56.404Z,1532537636.404 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:53:56.404Z,1532537636.404 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-07-25T16:53:56.404Z,1532537636.404 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-25T16:53:56.418Z,1532537636.418 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-25T16:53:56.418Z,1532537636.418 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-25T16:53:56.456Z,1532537636.456 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180725T164438/Courier0004.lzma
2018-07-25T16:53:56.572Z,1532537636.572 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-25T16:53:56.572Z,1532537636.572 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-07-25T16:53:56.572Z,1532537636.572 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-25T16:53:56.572Z,1532537636.572 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-07-25T16:53:56.906Z,1532537636.906 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-25T16:53:56.907Z,1532537636.907 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:53:56.907Z,1532537636.907 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:53:56.907Z,1532537636.907 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-25T16:53:56.908Z,1532537636.908 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:53:56.908Z,1532537636.908 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:53:57.230Z,1532537637.230 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0004.lzma.bak
2018-07-25T16:53:57.230Z,1532537637.230 [DataOverHttps](INFO): SBD MOMSN=8375851
2018-07-25T16:53:57.283Z,1532537637.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-07-25T16:53:57.676Z,1532537637.676 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2018-07-25T16:53:58.091Z,1532537638.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2018-07-25T16:54:01.781Z,1532537641.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.4 s old, using for 20.0 s.
2018-07-25T16:54:01.947Z,1532537641.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2018-07-25T16:54:02.307Z,1532537642.307 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2018-07-25T16:54:02.731Z,1532537642.731 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2018-07-25T16:54:03.139Z,1532537643.139 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s.
2018-07-25T16:54:03.504Z,1532537643.504 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s.
2018-07-25T16:54:03.997Z,1532537643.997 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-25T16:54:04.303Z,1532537644.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.9 s old, using for 20.0 s.
2018-07-25T16:54:04.711Z,1532537644.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.3 s old, using for 20.0 s.
2018-07-25T16:54:08.101Z,1532537648.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-07-25T16:54:08.203Z,1532537648.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2018-07-25T16:54:08.529Z,1532537648.529 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20180725T160816/Express0014.lzma
2018-07-25T16:54:08.575Z,1532537648.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.2 s old, using for 20.0 s.
2018-07-25T16:54:08.999Z,1532537648.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.6 s old, using for 20.0 s.
2018-07-25T16:54:09.326Z,1532537649.326 [DataOverHttps](INFO): Moved sent file to Logs/20180725T160816/Express0014.lzma.bak
2018-07-25T16:54:09.327Z,1532537649.327 [DataOverHttps](INFO): SBD MOMSN=8375853
2018-07-25T16:54:09.380Z,1532537649.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s.
2018-07-25T16:54:09.819Z,1532537649.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s.
2018-07-25T16:54:10.174Z,1532537650.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s.
2018-07-25T16:54:13.374Z,1532537653.374 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.0 s old, using for 20.0 s.
2018-07-25T16:54:13.491Z,1532537653.491 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2018-07-25T16:54:13.871Z,1532537653.871 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2018-07-25T16:54:14.290Z,1532537654.290 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.9 s old, using for 20.0 s.
2018-07-25T16:54:14.667Z,1532537654.667 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.3 s old, using for 20.0 s.
2018-07-25T16:54:15.098Z,1532537655.098 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.7 s old, using for 20.0 s.
2018-07-25T16:54:15.481Z,1532537655.481 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.1 s old, using for 20.0 s.
2018-07-25T16:54:18.742Z,1532537658.742 [DataOverHttps](INFO): Sending 726 bytes from file Logs/20180725T164438/Express0001.lzma
2018-07-25T16:54:19.506Z,1532537659.506 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0001.lzma.bak
2018-07-25T16:54:19.506Z,1532537659.506 [DataOverHttps](INFO): SBD MOMSN=8375858
2018-07-25T16:54:28.513Z,1532537668.513 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180725T164438/Express0005.lzma
2018-07-25T16:54:29.302Z,1532537669.302 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0005.lzma.bak
2018-07-25T16:54:29.303Z,1532537669.303 [DataOverHttps](INFO): SBD MOMSN=8375875
2018-07-25T16:54:29.780Z,1532537669.780 [Default:CheckIn:Read_Iridium] Stopped
2018-07-25T16:54:29.780Z,1532537669.780 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-25T16:54:29.780Z,1532537669.780 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-25T16:55:35.875Z,1532537735.875 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-25T16:55:35.875Z,1532537735.875 [NAL9602] Data Fault, FailCount= 1
2018-07-25T16:55:35.875Z,1532537735.875 [NAL9602](ERROR): Data Fault
2018-07-25T16:55:35.955Z,1532537735.955 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-25T16:55:36.275Z,1532537736.275 [NAL9602](INFO): Powering down
2018-07-25T16:55:37.155Z,1532537737.155 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-25T16:55:37.155Z,1532537737.155 [NAL9602] No Fault, FailCount= 1
2018-07-25T16:56:06.279Z,1532537766.279 [NAL9602](INFO): Powering up NAL9602
2018-07-25T16:56:17.475Z,1532537777.475 [NAL9602](INFO): NAL9602 initialized
2018-07-25T16:56:43.191Z,1532537803.191 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-07-25T16:56:43.192Z,1532537803.192 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-3768,V
2018-07-25T16:56:43.997Z,1532537803.997 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T16:56:44.406Z,1532537804.406 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87, 14.684, -0.055, 0.000
2018-07-25T16:56:44.798Z,1532537804.798 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T16:56:45.176Z,1532537805.176 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T16:56:45.578Z,1532537805.578 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T16:56:45.994Z,1532537805.994 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88,*14.684,*-0.055,*0.000
2018-07-25T16:56:46.374Z,1532537806.374 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T16:56:46.811Z,1532537806.811 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T16:56:47.194Z,1532537807.194 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T16:56:47.601Z,1532537807.601 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87,*14.671,*-0.057,*0.000
2018-07-25T16:56:47.984Z,1532537807.984 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T16:56:48.378Z,1532537808.378 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T16:56:48.785Z,1532537808.785 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T16:56:49.190Z,1532537809.190 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88,*14.684,*-0.053,*0.000
2018-07-25T16:56:49.579Z,1532537809.579 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T16:56:49.995Z,1532537809.995 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T16:56:50.403Z,1532537810.403 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T16:56:50.798Z,1532537810.798 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.684, -0.053, 0.000
2018-07-25T16:56:51.191Z,1532537811.191 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T16:56:51.591Z,1532537811.591 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T16:56:51.995Z,1532537811.995 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T16:56:52.385Z,1532537812.385 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-07-25T16:56:52.386Z,1532537812.386 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-07-25T16:56:52.386Z,1532537812.386 [RDI_Pathfinder](ERROR): Communications Fault
2018-07-25T16:56:52.409Z,1532537812.409 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-07-25T16:56:52.869Z,1532537812.869 [RDI_Pathfinder](INFO): Powering down
2018-07-25T16:56:53.649Z,1532537813.649 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-25T16:56:53.649Z,1532537813.649 [RDI_Pathfinder] No Fault, FailCount= 1
2018-07-25T16:56:57.200Z,1532537817.200 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:56:57.200Z,1532537817.200 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-07-25T16:56:57.200Z,1532537817.200 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-25T16:56:57.206Z,1532537817.206 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:56:57.206Z,1532537817.206 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-07-25T16:56:57.206Z,1532537817.206 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-25T16:56:57.234Z,1532537817.234 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-25T16:56:57.234Z,1532537817.234 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-25T16:56:57.618Z,1532537817.618 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-25T16:56:57.618Z,1532537817.618 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-07-25T16:56:57.618Z,1532537817.618 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-25T16:56:57.619Z,1532537817.619 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-07-25T16:56:58.003Z,1532537818.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-25T16:56:58.003Z,1532537818.003 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:56:58.003Z,1532537818.003 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:56:58.004Z,1532537818.004 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-25T16:56:58.004Z,1532537818.004 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:56:58.005Z,1532537818.005 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:56:58.399Z,1532537818.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-25T16:56:58.816Z,1532537818.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-25T16:56:59.207Z,1532537819.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-25T16:56:59.613Z,1532537819.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-25T16:56:59.983Z,1532537819.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-25T16:57:00.397Z,1532537820.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-25T16:57:00.805Z,1532537820.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-25T16:57:01.199Z,1532537821.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-25T16:57:01.597Z,1532537821.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-25T16:57:01.000Z,1532537822.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-25T16:57:02.407Z,1532537822.407 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-25T16:57:02.799Z,1532537822.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-25T16:57:03.191Z,1532537823.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-25T16:57:03.597Z,1532537823.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-25T16:57:03.997Z,1532537823.997 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-25T16:57:04.401Z,1532537824.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-25T16:57:04.819Z,1532537824.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-25T16:57:05.207Z,1532537825.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-25T16:57:05.615Z,1532537825.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-25T16:57:05.999Z,1532537825.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-25T16:57:06.409Z,1532537826.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-25T16:57:06.795Z,1532537826.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-25T16:57:07.201Z,1532537827.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-25T16:57:07.592Z,1532537827.592 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-25T16:57:07.601Z,1532537827.601 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-25T16:57:07.990Z,1532537827.990 [RDI_Pathfinder](ERROR): Failed to parse:
All rights reserved.
2018-07-25T16:57:07.000Z,1532537828.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-25T16:57:08.394Z,1532537828.394 [RDI_Pathfinder](ERROR): Failed to parse:
Current time is: 18/07/25,16:58:06.47
2018-07-25T16:57:08.416Z,1532537828.416 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-25T16:57:08.783Z,1532537828.783 [RDI_Pathfinder](ERROR): Failed to parse:
>
2018-07-25T16:57:08.791Z,1532537828.791 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-25T16:57:09.238Z,1532537829.238 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-25T16:57:09.595Z,1532537829.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-25T16:57:10.003Z,1532537830.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-25T16:57:10.412Z,1532537830.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-25T16:57:10.826Z,1532537830.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-25T16:57:11.253Z,1532537831.253 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2018-07-25T16:57:11.608Z,1532537831.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-25T16:57:11.996Z,1532537831.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-25T16:57:12.419Z,1532537832.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-25T16:57:12.795Z,1532537832.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-07-25T16:57:13.199Z,1532537833.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-07-25T16:57:13.614Z,1532537833.614 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-07-25T16:57:13.995Z,1532537833.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-07-25T16:57:14.436Z,1532537834.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-07-25T16:57:14.799Z,1532537834.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-07-25T16:57:15.195Z,1532537835.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-07-25T16:57:15.640Z,1532537835.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-25T16:57:15.995Z,1532537835.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-25T16:57:16.407Z,1532537836.407 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-25T16:57:16.820Z,1532537836.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-25T16:59:30.204Z,1532537970.204 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-25T16:59:30.204Z,1532537970.204 [Default:CheckIn:C.Wait] Stopped
2018-07-25T16:59:30.221Z,1532537970.221 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T16:59:30.221Z,1532537970.221 [Default:CheckIn:D] Running Loop=1
2018-07-25T16:59:30.606Z,1532537970.606 [Default:CheckIn:D] Stopped
2018-07-25T16:59:30.606Z,1532537970.606 [Default:CheckIn:E] Running Loop=1
2018-07-25T16:59:33.755Z,1532537973.755 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.203683 min 
2018-07-25T16:59:33.757Z,1532537973.757 [Default:CheckIn:E] Stopped
2018-07-25T16:59:33.758Z,1532537973.758 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-25T16:59:33.758Z,1532537973.758 [Default:CheckIn] Stopped
2018-07-25T16:59:33.758Z,1532537973.758 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-25T16:59:33.758Z,1532537973.758 [Default:CheckIn](INFO): Running loop #2
2018-07-25T16:59:33.758Z,1532537973.758 [Default:CheckIn] Running Loop=2
2018-07-25T16:59:33.758Z,1532537973.758 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T16:59:33.758Z,1532537973.758 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T16:59:58.291Z,1532537998.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:59:58.291Z,1532537998.291 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2018-07-25T16:59:58.291Z,1532537998.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-25T16:59:58.293Z,1532537998.293 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T16:59:58.293Z,1532537998.293 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-07-25T16:59:58.293Z,1532537998.293 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-25T16:59:58.306Z,1532537998.306 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-25T16:59:58.306Z,1532537998.306 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-25T16:59:58.680Z,1532537998.680 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-25T16:59:58.680Z,1532537998.680 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2018-07-25T16:59:58.686Z,1532537998.686 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-25T16:59:58.686Z,1532537998.686 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-07-25T16:59:59.059Z,1532537999.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-25T16:59:59.059Z,1532537999.059 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:59:59.059Z,1532537999.059 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:59:59.060Z,1532537999.060 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-25T16:59:59.060Z,1532537999.060 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T16:59:59.065Z,1532537999.065 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T16:59:59.481Z,1532537999.481 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-25T16:59:59.871Z,1532537999.871 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-25T17:00:00.278Z,1532538000.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-25T17:00:00.683Z,1532538000.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-25T17:00:01.079Z,1532538001.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-25T17:00:01.488Z,1532538001.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-25T17:00:01.867Z,1532538001.867 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-25T17:00:02.285Z,1532538002.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-25T17:00:02.677Z,1532538002.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-25T17:00:03.075Z,1532538003.075 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-25T17:00:03.496Z,1532538003.496 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-25T17:00:03.883Z,1532538003.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-25T17:00:04.287Z,1532538004.287 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-25T17:00:04.666Z,1532538004.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-25T17:00:05.067Z,1532538005.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-25T17:00:05.468Z,1532538005.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-25T17:00:05.887Z,1532538005.887 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-25T17:00:06.264Z,1532538006.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-25T17:00:06.701Z,1532538006.701 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-25T17:00:07.083Z,1532538007.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-25T17:00:07.496Z,1532538007.496 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-25T17:00:07.875Z,1532538007.875 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-25T17:00:08.278Z,1532538008.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-25T17:00:08.659Z,1532538008.659 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-25T17:00:09.057Z,1532538009.057 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-25T17:00:09.495Z,1532538009.495 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-25T17:00:09.880Z,1532538009.880 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-25T17:00:10.285Z,1532538010.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-25T17:00:10.659Z,1532538010.659 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-25T17:00:11.060Z,1532538011.060 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-25T17:00:11.487Z,1532538011.487 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-25T17:00:11.873Z,1532538011.873 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-25T17:00:12.282Z,1532538012.282 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-25T17:00:12.683Z,1532538012.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-25T17:00:13.079Z,1532538013.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-25T17:00:13.490Z,1532538013.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-25T17:00:13.879Z,1532538013.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-07-25T17:00:14.256Z,1532538014.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-07-25T17:00:14.680Z,1532538014.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-07-25T17:00:15.059Z,1532538015.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-07-25T17:00:15.483Z,1532538015.483 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-07-25T17:00:15.899Z,1532538015.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-07-25T17:00:16.285Z,1532538016.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-07-25T17:00:16.689Z,1532538016.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-25T17:00:17.068Z,1532538017.068 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-25T17:00:17.479Z,1532538017.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-25T17:00:17.863Z,1532538017.863 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-25T17:00:18.279Z,1532538018.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-07-25T17:02:00.321Z,1532538120.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-25T17:02:59.254Z,1532538179.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T17:02:59.254Z,1532538179.254 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2018-07-25T17:02:59.254Z,1532538179.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-25T17:02:59.256Z,1532538179.256 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-25T17:02:59.256Z,1532538179.256 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-07-25T17:02:59.256Z,1532538179.256 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-25T17:02:59.286Z,1532538179.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-25T17:02:59.286Z,1532538179.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-25T17:02:59.643Z,1532538179.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-25T17:02:59.643Z,1532538179.643 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2018-07-25T17:02:59.643Z,1532538179.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-25T17:02:59.643Z,1532538179.643 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-07-25T17:03:00.034Z,1532538180.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-25T17:03:00.034Z,1532538180.034 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T17:03:00.035Z,1532538180.035 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T17:03:00.035Z,1532538180.035 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-25T17:03:00.035Z,1532538180.035 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-25T17:03:00.036Z,1532538180.036 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-25T17:03:00.430Z,1532538180.430 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-25T17:03:00.835Z,1532538180.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-25T17:03:01.269Z,1532538181.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-25T17:03:01.631Z,1532538181.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-25T17:03:02.031Z,1532538182.031 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-25T17:03:02.427Z,1532538182.427 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-25T17:03:02.819Z,1532538182.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-25T17:03:03.225Z,1532538183.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-25T17:03:03.645Z,1532538183.645 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-25T17:03:04.032Z,1532538184.032 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-25T17:03:04.479Z,1532538184.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-25T17:03:04.831Z,1532538184.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-25T17:03:05.212Z,1532538185.212 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-25T17:03:05.634Z,1532538185.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-25T17:03:06.020Z,1532538186.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-25T17:03:06.420Z,1532538186.420 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-25T17:03:06.828Z,1532538186.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-25T17:03:07.236Z,1532538187.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-25T17:03:07.634Z,1532538187.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-25T17:03:08.014Z,1532538188.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-25T17:03:08.439Z,1532538188.439 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-25T17:03:08.827Z,1532538188.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-25T17:03:09.252Z,1532538189.252 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-25T17:03:09.631Z,1532538189.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-25T17:03:10.033Z,1532538190.033 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-25T17:03:10.440Z,1532538190.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-25T17:03:10.804Z,1532538190.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-25T17:03:11.243Z,1532538191.243 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-25T17:03:11.611Z,1532538191.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-25T17:03:12.034Z,1532538192.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-25T17:03:12.434Z,1532538192.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-25T17:03:12.830Z,1532538192.830 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-25T17:03:13.246Z,1532538193.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-25T17:03:13.639Z,1532538193.639 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-25T17:03:14.027Z,1532538194.027 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-25T17:03:14.435Z,1532538194.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-25T17:03:14.827Z,1532538194.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-07-25T17:03:15.221Z,1532538195.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-07-25T17:03:15.661Z,1532538195.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-07-25T17:03:16.035Z,1532538196.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-07-25T17:03:16.445Z,1532538196.445 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-07-25T17:03:16.843Z,1532538196.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-07-25T17:03:17.232Z,1532538197.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-07-25T17:03:17.622Z,1532538197.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-25T17:03:18.023Z,1532538198.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-25T17:03:18.429Z,1532538198.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-25T17:03:18.827Z,1532538198.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-25T17:03:19.207Z,1532538199.207 [NAL9602](INFO): GPS fix at 20180725T170318: (36.802858, -121.787965)
2018-07-25T17:03:19.278Z,1532538199.278 [Default:CheckIn:Read_GPS] Stopped
2018-07-25T17:03:19.278Z,1532538199.278 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-25T17:03:26.137Z,1532538206.137 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180725T164438/Courier0007.lzma
2018-07-25T17:03:26.904Z,1532538206.904 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0007.lzma.bak
2018-07-25T17:03:26.904Z,1532538206.904 [DataOverHttps](IMPORTANT): SBD MOMSN=8375880, MTMSN=20180725T170326
2018-07-25T17:03:35.001Z,1532538215.001 [DataOverHttps](INFO): Received command:ibit
2018-07-25T17:03:35.045Z,1532538215.045 [CommandLine](IMPORTANT): got command ibit
2018-07-25T17:03:35.090Z,1532538215.090 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-07-25T17:03:35.090Z,1532538215.090 [IBIT](IMPORTANT): Beginning control surface checks.
2018-07-25T17:03:35.093Z,1532538215.093 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-25T17:03:36.607Z,1532538216.607 [NAL9602](INFO): GPS fix at 20180725T170335: (36.802919, -121.788005)
2018-07-25T17:03:40.313Z,1532538220.313 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20180725T164438/Express0008.lzma
2018-07-25T17:03:41.112Z,1532538221.112 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0008.lzma.bak
2018-07-25T17:03:41.112Z,1532538221.112 [DataOverHttps](INFO): SBD MOMSN=8375884
2018-07-25T17:03:41.939Z,1532538221.939 [NAL9602](INFO): GPS fix at 20180725T170341: (36.802921, -121.788007)
2018-07-25T17:03:48.130Z,1532538228.130 [NAL9602](INFO): GPS fix at 20180725T170347: (36.802922, -121.788009)
2018-07-25T17:03:50.943Z,1532538230.943 [NAL9602](INFO): GPS fix at 20180725T170350: (36.802923, -121.788010)
2018-07-25T17:03:51.434Z,1532538231.434 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.018882
CHAN A1 (24V): -0.022166
CHAN A2 (12V): -0.007586
CHAN A3 (5V): -0.001980
CHAN B0 (3.3V): 0.000314
CHAN B1 (3.15aV): 0.000031
CHAN B2 (3.15bV): 0.000092
CHAN B3 (GND): 0.002330
OPEN: 0.005323
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-25T17:03:55.262Z,1532538235.262 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180725T164438/Courier0010.lzma
2018-07-25T17:03:55.659Z,1532538235.659 [NAL9602](INFO): GPS fix at 20180725T170354: (36.802923, -121.788010)
2018-07-25T17:03:56.052Z,1532538236.052 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0010.lzma.bak
2018-07-25T17:03:56.052Z,1532538236.052 [DataOverHttps](INFO): SBD MOMSN=8375888
2018-07-25T17:03:58.062Z,1532538238.062 [NAL9602](INFO): GPS fix at 20180725T170357: (36.802923, -121.788011)
2018-07-25T17:04:03.850Z,1532538243.850 [NAL9602](INFO): GPS fix at 20180725T170403: (36.802922, -121.788011)
2018-07-25T17:04:08.840Z,1532538248.840 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20180725T164438/Express0011.lzma
2018-07-25T17:04:08.861Z,1532538248.861 [NAL9602](INFO): GPS fix at 20180725T170408: (36.802917, -121.788015)
2018-07-25T17:04:09.624Z,1532538249.624 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0011.lzma.bak
2018-07-25T17:04:09.624Z,1532538249.624 [DataOverHttps](INFO): SBD MOMSN=8375890
2018-07-25T17:04:14.002Z,1532538254.002 [NAL9602](INFO): GPS fix at 20180725T170413: (36.802915, -121.788018)
2018-07-25T17:04:19.726Z,1532538259.726 [NAL9602](INFO): GPS fix at 20180725T170418: (36.802925, -121.788015)
2018-07-25T17:04:24.650Z,1532538264.650 [NAL9602](INFO): GPS fix at 20180725T170423: (36.802931, -121.787959)
2018-07-25T17:04:29.614Z,1532538269.614 [NAL9602](INFO): GPS fix at 20180725T170428: (36.802909, -121.787884)
2018-07-25T17:04:34.527Z,1532538274.527 [NAL9602](INFO): GPS fix at 20180725T170433: (36.802878, -121.787837)
2018-07-25T17:04:39.854Z,1532538279.854 [NAL9602](INFO): GPS fix at 20180725T170438: (36.802816, -121.787791)
2018-07-25T17:04:44.823Z,1532538284.823 [NAL9602](INFO): GPS fix at 20180725T170443: (36.802813, -121.787763)
2018-07-25T17:04:49.867Z,1532538289.867 [NAL9602](INFO): GPS fix at 20180725T170448: (36.802812, -121.787755)
2018-07-25T17:04:54.825Z,1532538294.825 [NAL9602](INFO): GPS fix at 20180725T170453: (36.802816, -121.787749)
2018-07-25T17:04:59.883Z,1532538299.883 [NAL9602](INFO): GPS fix at 20180725T170458: (36.802820, -121.787746)
2018-07-25T17:05:04.983Z,1532538304.983 [NAL9602](INFO): GPS fix at 20180725T170504: (36.802823, -121.787723)
2018-07-25T17:05:09.920Z,1532538309.920 [NAL9602](INFO): GPS fix at 20180725T170508: (36.802817, -121.787668)
2018-07-25T17:05:15.031Z,1532538315.031 [NAL9602](INFO): GPS fix at 20180725T170514: (36.802788, -121.787588)
2018-07-25T17:05:19.992Z,1532538319.992 [NAL9602](INFO): GPS fix at 20180725T170519: (36.802756, -121.787521)
2018-07-25T17:05:25.091Z,1532538325.091 [NAL9602](INFO): GPS fix at 20180725T170524: (36.802697, -121.787484)
2018-07-25T17:05:25.961Z,1532538325.961 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2018-07-25T17:05:29.356Z,1532538329.356 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2018-07-25T17:05:29.485Z,1532538329.485 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-07-25T17:05:29.486Z,1532538329.486 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-07-25T17:05:29.486Z,1532538329.486 [IBIT](IMPORTANT): Pressure:10.347019 PSI
2018-07-25T17:05:29.486Z,1532538329.486 [IBIT](IMPORTANT): Humidity:21.071909 %
2018-07-25T17:05:29.872Z,1532538329.872 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-07-25T17:05:29.873Z,1532538329.873 [IBIT](IMPORTANT): buoyancyNeutral: 300.000000 cc
2018-07-25T17:05:29.873Z,1532538329.873 [IBIT](IMPORTANT): massDefault: 0.200000 cm
2018-07-25T17:05:29.874Z,1532538329.874 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2018-07-25T17:05:29.874Z,1532538329.874 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2018-07-25T17:05:29.874Z,1532538329.874 [IBIT](IMPORTANT): IBIT FAILED
2018-07-25T17:05:30.237Z,1532538330.237 [NAL9602](INFO): GPS fix at 20180725T170529: (36.802626, -121.787518)
2018-07-25T17:05:30.299Z,1532538330.299 [Default:CheckIn:Read_Iridium] Stopped
2018-07-25T17:05:30.300Z,1532538330.300 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-25T17:05:30.300Z,1532538330.300 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-25T17:05:53.960Z,1532538353.960 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 31 sticks.
2018-07-25T17:05:53.960Z,1532538353.960 [BPC1] Data Fault, FailCount= 1
2018-07-25T17:05:53.960Z,1532538353.960 [BPC1](ERROR): Data Fault
2018-07-25T17:05:54.028Z,1532538354.028 [CBIT](ERROR): Data Fault in component: BPC1
2018-07-25T17:05:55.228Z,1532538355.228 [CBIT](INFO): Clearing failed state for component BPC1
2018-07-25T17:05:55.228Z,1532538355.228 [BPC1] No Fault, FailCount= 1
2018-07-25T17:06:04.738Z,1532538364.738 [NAL9602](INFO): Powering down
2018-07-25T17:06:35.668Z,1532538395.668 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:06:36.074Z,1532538396.074 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:06:36.475Z,1532538396.475 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87,*14.664,*-0.046,*0.000
2018-07-25T17:06:36.858Z,1532538396.858 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:06:37.267Z,1532538397.267 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:06:37.660Z,1532538397.660 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:06:38.062Z,1532538398.062 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88,*14.671,*-0.056,*0.000
2018-07-25T17:06:38.471Z,1532538398.471 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:06:38.874Z,1532538398.874 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:06:39.264Z,1532538399.264 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:06:39.681Z,1532538399.681 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87,*14.671,*-0.056,*0.000
2018-07-25T17:06:40.058Z,1532538400.058 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:06:40.467Z,1532538400.467 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:06:40.868Z,1532538400.868 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:06:41.285Z,1532538401.285 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.671, -0.056, 0.000
2018-07-25T17:06:41.662Z,1532538401.662 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:06:42.077Z,1532538402.077 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:06:42.469Z,1532538402.469 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:06:42.880Z,1532538402.880 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87, 14.671, -0.056, 0.000
2018-07-25T17:06:43.294Z,1532538403.294 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:06:43.678Z,1532538403.678 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:06:44.082Z,1532538404.082 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:06:44.458Z,1532538404.458 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-07-25T17:06:44.459Z,1532538404.459 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-07-25T17:06:44.459Z,1532538404.459 [RDI_Pathfinder](ERROR): Communications Fault
2018-07-25T17:06:44.536Z,1532538404.536 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-07-25T17:06:44.937Z,1532538404.937 [RDI_Pathfinder](INFO): Powering down
2018-07-25T17:06:45.715Z,1532538405.715 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-25T17:06:45.715Z,1532538405.715 [RDI_Pathfinder] No Fault, FailCount= 1
2018-07-25T17:06:59.660Z,1532538419.660 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-25T17:07:00.066Z,1532538420.066 [RDI_Pathfinder](ERROR): Failed to parse:
All rights reserved.
2018-07-25T17:07:00.473Z,1532538420.473 [RDI_Pathfinder](ERROR): Failed to parse:
Current time is: 18/07/25,17:07:58.55
2018-07-25T17:07:00.868Z,1532538420.868 [RDI_Pathfinder](ERROR): Failed to parse:
>
2018-07-25T17:07:50.682Z,1532538470.682 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-07-25T17:07:50.683Z,1532538470.683 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00 +0.00,   0.00, 51.00
2018-07-25T17:07:51.475Z,1532538471.475 [RDI_Pathfinder](ERROR): Failed to parse:

2018-07-25T17:10:30.970Z,1532538630.970 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-25T17:10:30.970Z,1532538630.970 [Default:CheckIn:C.Wait] Stopped
2018-07-25T17:10:30.970Z,1532538630.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T17:10:30.970Z,1532538630.970 [Default:CheckIn:D] Running Loop=1
2018-07-25T17:10:31.379Z,1532538631.379 [Default:CheckIn:D] Stopped
2018-07-25T17:10:31.379Z,1532538631.379 [Default:CheckIn:E] Running Loop=1
2018-07-25T17:10:31.790Z,1532538631.790 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.216616 min 
2018-07-25T17:10:31.790Z,1532538631.790 [Default:CheckIn:E] Stopped
2018-07-25T17:10:31.790Z,1532538631.790 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-25T17:10:31.790Z,1532538631.790 [Default:CheckIn] Stopped
2018-07-25T17:10:31.790Z,1532538631.790 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-25T17:10:31.790Z,1532538631.790 [Default:CheckIn](INFO): Running loop #3
2018-07-25T17:10:31.791Z,1532538631.791 [Default:CheckIn] Running Loop=3
2018-07-25T17:10:31.791Z,1532538631.791 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T17:10:31.791Z,1532538631.791 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T17:10:35.070Z,1532538635.070 [NAL9602](INFO): Powering up
2018-07-25T17:10:46.222Z,1532538646.222 [NAL9602](INFO): NAL9602 initialized
2018-07-25T17:11:08.632Z,1532538668.632 [NAL9602](INFO): SBD MO Status=0, MOMSN=368, MT Status=0, MTMSN=0
2018-07-25T17:11:08.632Z,1532538668.632 [NAL9602](INFO): No messages in MT queue
2018-07-25T17:11:19.426Z,1532538679.426 [NAL9602](INFO): GPS fix at 20180725T171118: (36.802744, -121.786946)
2018-07-25T17:11:19.496Z,1532538679.496 [Default:CheckIn:Read_GPS] Stopped
2018-07-25T17:11:19.496Z,1532538679.496 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-25T17:11:24.755Z,1532538684.755 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180725T164438/Courier0013.lzma
2018-07-25T17:11:25.552Z,1532538685.552 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0013.lzma.bak
2018-07-25T17:11:25.552Z,1532538685.552 [DataOverHttps](INFO): SBD MOMSN=8375897
2018-07-25T17:11:34.571Z,1532538694.571 [DataOverHttps](INFO): Sending 691 bytes from file Logs/20180725T164438/Express0014.lzma
2018-07-25T17:11:35.359Z,1532538695.359 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0014.lzma.bak
2018-07-25T17:11:35.360Z,1532538695.360 [DataOverHttps](INFO): SBD MOMSN=8375900
2018-07-25T17:11:35.804Z,1532538695.804 [Default:CheckIn:Read_Iridium] Stopped
2018-07-25T17:11:35.804Z,1532538695.804 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-25T17:11:35.805Z,1532538695.805 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-25T17:11:54.318Z,1532538714.318 [NAL9602](INFO): Powering down
2018-07-25T17:16:36.578Z,1532538996.578 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-25T17:16:36.578Z,1532538996.578 [Default:CheckIn:C.Wait] Stopped
2018-07-25T17:16:36.578Z,1532538996.578 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T17:16:36.578Z,1532538996.578 [Default:CheckIn:D] Running Loop=1
2018-07-25T17:16:36.969Z,1532538996.969 [Default:CheckIn:D] Stopped
2018-07-25T17:16:36.970Z,1532538996.970 [Default:CheckIn:E] Running Loop=1
2018-07-25T17:16:40.187Z,1532539000.187 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.309788 min 
2018-07-25T17:16:40.187Z,1532539000.187 [Default:CheckIn:E] Stopped
2018-07-25T17:16:40.187Z,1532539000.187 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-25T17:16:40.187Z,1532539000.187 [Default:CheckIn] Stopped
2018-07-25T17:16:40.187Z,1532539000.187 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-25T17:16:40.188Z,1532539000.188 [Default:CheckIn](INFO): Running loop #4
2018-07-25T17:16:40.188Z,1532539000.188 [Default:CheckIn] Running Loop=4
2018-07-25T17:16:40.188Z,1532539000.188 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T17:16:40.188Z,1532539000.188 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T17:16:40.642Z,1532539000.642 [NAL9602](INFO): Powering up
2018-07-25T17:16:51.838Z,1532539011.838 [NAL9602](INFO): NAL9602 initialized
2018-07-25T17:17:21.440Z,1532539041.440 [NAL9602](INFO): SBD MO Status=0, MOMSN=369, MT Status=0, MTMSN=0
2018-07-25T17:17:21.440Z,1532539041.440 [NAL9602](INFO): No messages in MT queue
2018-07-25T17:17:22.639Z,1532539042.639 [NAL9602](INFO): GPS fix at 20180725T171721: (36.802724, -121.786972)
2018-07-25T17:17:22.698Z,1532539042.698 [Default:CheckIn:Read_GPS] Stopped
2018-07-25T17:17:22.698Z,1532539042.698 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-25T17:17:27.234Z,1532539047.234 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20180725T164438/Courier0016.lzma
2018-07-25T17:17:28.014Z,1532539048.014 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0016.lzma.bak
2018-07-25T17:17:28.015Z,1532539048.015 [DataOverHttps](INFO): SBD MOMSN=8375919
2018-07-25T17:17:38.659Z,1532539058.659 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20180725T164438/Express0017.lzma
2018-07-25T17:17:39.428Z,1532539059.428 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0017.lzma.bak
2018-07-25T17:17:39.428Z,1532539059.428 [DataOverHttps](INFO): SBD MOMSN=8375922
2018-07-25T17:17:39.879Z,1532539059.879 [Default:CheckIn:Read_Iridium] Stopped
2018-07-25T17:17:39.879Z,1532539059.879 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-25T17:17:39.879Z,1532539059.879 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-25T17:17:57.210Z,1532539077.210 [NAL9602](INFO): Powering down
2018-07-25T17:22:40.535Z,1532539360.535 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-25T17:22:40.535Z,1532539360.535 [Default:CheckIn:C.Wait] Stopped
2018-07-25T17:22:40.535Z,1532539360.535 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T17:22:40.536Z,1532539360.536 [Default:CheckIn:D] Running Loop=1
2018-07-25T17:22:40.917Z,1532539360.917 [Default:CheckIn:D] Stopped
2018-07-25T17:22:40.917Z,1532539360.917 [Default:CheckIn:E] Running Loop=1
2018-07-25T17:22:44.101Z,1532539364.101 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.375574 min 
2018-07-25T17:22:44.101Z,1532539364.101 [Default:CheckIn:E] Stopped
2018-07-25T17:22:44.102Z,1532539364.102 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-25T17:22:44.102Z,1532539364.102 [Default:CheckIn] Stopped
2018-07-25T17:22:44.102Z,1532539364.102 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-25T17:22:44.102Z,1532539364.102 [Default:CheckIn](INFO): Running loop #5
2018-07-25T17:22:44.102Z,1532539364.102 [Default:CheckIn] Running Loop=5
2018-07-25T17:22:44.102Z,1532539364.102 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T17:22:44.102Z,1532539364.102 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T17:22:44.538Z,1532539364.538 [NAL9602](INFO): Powering up
2018-07-25T17:22:55.774Z,1532539375.774 [NAL9602](INFO): NAL9602 initialized
2018-07-25T17:23:29.344Z,1532539409.344 [NAL9602](INFO): SBD MO Status=2, MOMSN=370, MT Status=2, MTMSN=0
2018-07-25T17:23:29.344Z,1532539409.344 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-25T17:23:48.300Z,1532539428.300 [NAL9602](INFO): SBD MO Status=0, MOMSN=370, MT Status=0, MTMSN=0
2018-07-25T17:23:48.300Z,1532539428.300 [NAL9602](INFO): No messages in MT queue
2018-07-25T17:23:49.503Z,1532539429.503 [NAL9602](INFO): GPS fix at 20180725T172348: (36.802737, -121.786953)
2018-07-25T17:23:49.589Z,1532539429.589 [Default:CheckIn:Read_GPS] Stopped
2018-07-25T17:23:49.590Z,1532539429.590 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-25T17:23:54.018Z,1532539434.018 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180725T164438/Courier0019.lzma
2018-07-25T17:23:54.792Z,1532539434.792 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0019.lzma.bak
2018-07-25T17:23:54.792Z,1532539434.792 [DataOverHttps](INFO): SBD MOMSN=8375927
2018-07-25T17:24:04.201Z,1532539444.201 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180725T164438/Express0020.lzma
2018-07-25T17:24:04.980Z,1532539444.980 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0020.lzma.bak
2018-07-25T17:24:04.980Z,1532539444.980 [DataOverHttps](INFO): SBD MOMSN=8375930
2018-07-25T17:24:05.443Z,1532539445.443 [Default:CheckIn:Read_Iridium] Stopped
2018-07-25T17:24:05.443Z,1532539445.443 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-25T17:24:05.443Z,1532539445.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-25T17:24:24.086Z,1532539464.086 [NAL9602](INFO): Powering down
2018-07-25T17:25:10.249Z,1532539510.249 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e88, 14.651, -0.052, 0.000
2018-07-25T17:25:10.653Z,1532539510.653 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:25:11.456Z,1532539511.456 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:25:11.852Z,1532539511.852 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:25:12.264Z,1532539512.264 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.651, -0.052, 0.000
2018-07-25T17:25:12.654Z,1532539512.654 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:25:13.054Z,1532539513.054 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:25:13.464Z,1532539513.464 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:25:13.859Z,1532539513.859 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87, 14.651, -0.052, 0.000
2018-07-25T17:25:14.266Z,1532539514.266 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:25:14.647Z,1532539514.647 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:25:15.051Z,1532539515.051 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:25:15.451Z,1532539515.451 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87, 14.651, -0.052, 0.000
2018-07-25T17:25:15.845Z,1532539515.845 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:25:16.256Z,1532539516.256 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:25:16.702Z,1532539516.702 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:25:17.060Z,1532539517.060 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87, 14.651, -0.052, 0.000
2018-07-25T17:25:17.453Z,1532539517.453 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:25:17.866Z,1532539517.866 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:25:18.261Z,1532539518.261 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:25:18.675Z,1532539518.675 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87, 14.651, -0.052, 0.000
2018-07-25T17:25:19.061Z,1532539519.061 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:25:19.440Z,1532539519.440 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-07-25T17:25:19.449Z,1532539519.449 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-07-25T17:25:19.449Z,1532539519.449 [RDI_Pathfinder](ERROR): Communications Fault
2018-07-25T17:25:19.509Z,1532539519.509 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-07-25T17:25:19.909Z,1532539519.909 [RDI_Pathfinder](INFO): Powering down
2018-07-25T17:25:20.683Z,1532539520.683 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-25T17:25:20.683Z,1532539520.683 [RDI_Pathfinder] No Fault, FailCount= 1
2018-07-25T17:25:34.642Z,1532539534.642 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-25T17:25:35.049Z,1532539535.049 [RDI_Pathfinder](ERROR): Failed to parse:
All rights reserved.
2018-07-25T17:25:35.452Z,1532539535.452 [RDI_Pathfinder](ERROR): Failed to parse:
Current time is: 18/07/25,17:26:33.54
2018-07-25T17:25:35.852Z,1532539535.852 [RDI_Pathfinder](ERROR): Failed to parse:
>
2018-07-25T17:29:06.187Z,1532539746.187 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-25T17:29:06.188Z,1532539746.188 [Default:CheckIn:C.Wait] Stopped
2018-07-25T17:29:06.188Z,1532539746.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T17:29:06.188Z,1532539746.188 [Default:CheckIn:D] Running Loop=1
2018-07-25T17:29:06.574Z,1532539746.574 [Default:CheckIn:D] Stopped
2018-07-25T17:29:06.574Z,1532539746.574 [Default:CheckIn:E] Running Loop=1
2018-07-25T17:29:09.751Z,1532539749.751 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.803202 min 
2018-07-25T17:29:09.751Z,1532539749.751 [Default:CheckIn:E] Stopped
2018-07-25T17:29:09.751Z,1532539749.751 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-25T17:29:09.752Z,1532539749.752 [Default:CheckIn] Stopped
2018-07-25T17:29:09.752Z,1532539749.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-25T17:29:09.752Z,1532539749.752 [Default:CheckIn](INFO): Running loop #6
2018-07-25T17:29:09.752Z,1532539749.752 [Default:CheckIn] Running Loop=6
2018-07-25T17:29:09.752Z,1532539749.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T17:29:09.752Z,1532539749.752 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T17:29:10.202Z,1532539750.202 [NAL9602](INFO): Powering up
2018-07-25T17:29:21.406Z,1532539761.406 [NAL9602](INFO): NAL9602 initialized
2018-07-25T17:29:35.400Z,1532539775.400 [NAL9602](INFO): SBD MO Status=0, MOMSN=371, MT Status=0, MTMSN=0
2018-07-25T17:29:35.400Z,1532539775.400 [NAL9602](INFO): No messages in MT queue
2018-07-25T17:29:54.203Z,1532539794.203 [NAL9602](INFO): GPS fix at 20180725T172953: (36.803098, -121.786251)
2018-07-25T17:29:54.267Z,1532539794.267 [Default:CheckIn:Read_GPS] Stopped
2018-07-25T17:29:54.267Z,1532539794.267 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-25T17:29:59.510Z,1532539799.510 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180725T164438/Courier0022.lzma
2018-07-25T17:30:00.326Z,1532539800.326 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0022.lzma.bak
2018-07-25T17:30:00.326Z,1532539800.326 [DataOverHttps](INFO): SBD MOMSN=8375935
2018-07-25T17:30:09.290Z,1532539809.290 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20180725T164438/Express0023.lzma
2018-07-25T17:30:10.076Z,1532539810.076 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0023.lzma.bak
2018-07-25T17:30:10.076Z,1532539810.076 [DataOverHttps](INFO): SBD MOMSN=8375938
2018-07-25T17:30:10.544Z,1532539810.544 [Default:CheckIn:Read_Iridium] Stopped
2018-07-25T17:30:10.544Z,1532539810.544 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-25T17:30:10.544Z,1532539810.544 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-25T17:30:28.774Z,1532539828.774 [NAL9602](INFO): Powering down
2018-07-25T17:31:16.563Z,1532539876.563 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.644, -0.059, 0.000
2018-07-25T17:31:16.952Z,1532539876.952 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:31:17.363Z,1532539877.363 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:31:17.743Z,1532539877.743 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:31:18.157Z,1532539878.157 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.644, -0.059, 0.000
2018-07-25T17:31:18.563Z,1532539878.563 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:31:18.978Z,1532539878.978 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:31:19.380Z,1532539879.380 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:31:19.764Z,1532539879.764 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.644, -0.059, 0.000
2018-07-25T17:31:20.152Z,1532539880.152 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:31:20.571Z,1532539880.571 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:31:20.994Z,1532539880.994 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:31:21.357Z,1532539881.357 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.644, -0.059, 0.000
2018-07-25T17:31:21.758Z,1532539881.758 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:31:22.173Z,1532539882.173 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:31:22.556Z,1532539882.556 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:31:22.966Z,1532539882.966 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.644, -0.059, 0.000
2018-07-25T17:31:23.345Z,1532539883.345 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:31:23.750Z,1532539883.750 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:31:24.147Z,1532539884.147 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:31:24.572Z,1532539884.572 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87, 14.644, -0.059, 0.000
2018-07-25T17:31:24.945Z,1532539884.945 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:31:25.340Z,1532539885.340 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-07-25T17:31:25.340Z,1532539885.340 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-07-25T17:31:25.340Z,1532539885.340 [RDI_Pathfinder](ERROR): Communications Fault
2018-07-25T17:31:25.410Z,1532539885.410 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-07-25T17:31:25.821Z,1532539885.821 [RDI_Pathfinder](INFO): Powering down
2018-07-25T17:31:26.605Z,1532539886.605 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-25T17:31:26.606Z,1532539886.606 [RDI_Pathfinder] No Fault, FailCount= 1
2018-07-25T17:31:40.554Z,1532539900.554 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-25T17:31:40.944Z,1532539900.944 [RDI_Pathfinder](ERROR): Failed to parse:
All rights reserved.
2018-07-25T17:31:41.350Z,1532539901.350 [RDI_Pathfinder](ERROR): Failed to parse:
Current time is: 18/07/25,17:32:39.46
2018-07-25T17:31:41.778Z,1532539901.778 [RDI_Pathfinder](ERROR): Failed to parse:
>
2018-07-25T17:32:15.800Z,1532539935.800 [DataOverHttps](IMPORTANT): SBD MTMSN=20180725T173215
2018-07-25T17:32:20.270Z,1532539940.270 [DataOverHttps](INFO): Received command:ibit
2018-07-25T17:32:20.286Z,1532539940.286 [CommandLine](IMPORTANT): got command ibit
2018-07-25T17:32:20.377Z,1532539940.377 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-07-25T17:32:20.377Z,1532539940.377 [IBIT](IMPORTANT): Beginning control surface checks.
2018-07-25T17:32:20.380Z,1532539940.380 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-25T17:32:20.670Z,1532539940.670 [NAL9602](INFO): Powering up
2018-07-25T17:32:31.203Z,1532539951.203 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.115935
CHAN A1 (24V): -0.022065
CHAN A2 (12V): -0.007141
CHAN A3 (5V): -0.001856
CHAN B0 (3.3V): -0.000024
CHAN B1 (3.15aV): -0.000043
CHAN B2 (3.15bV): 0.000363
CHAN B3 (GND): 0.002221
OPEN: 0.005082
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-25T17:32:31.486Z,1532539951.486 [NAL9602](INFO): NAL9602 initialized
2018-07-25T17:32:47.488Z,1532539967.488 [NAL9602](INFO): SBD MO Status=0, MOMSN=372, MT Status=0, MTMSN=0
2018-07-25T17:32:47.488Z,1532539967.488 [NAL9602](INFO): No messages in MT queue
2018-07-25T17:33:03.101Z,1532539983.101 [NAL9602](INFO): GPS fix at 20180725T173302: (36.807540, -121.785133)
2018-07-25T17:33:05.890Z,1532539985.890 [NAL9602](INFO): GPS fix at 20180725T173305: (36.807614, -121.785137)
2018-07-25T17:33:06.374Z,1532539986.374 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.807613 Longitude: -121.785141
2018-07-25T17:33:06.742Z,1532539986.742 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 0.000000
2018-07-25T17:33:06.742Z,1532539986.742 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2018-07-25T17:33:06.743Z,1532539986.743 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2018-07-25T17:33:07.176Z,1532539987.176 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-07-25T17:33:07.176Z,1532539987.176 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-07-25T17:33:07.181Z,1532539987.181 [IBIT](IMPORTANT): Pressure:10.393780 PSI
2018-07-25T17:33:07.182Z,1532539987.182 [IBIT](IMPORTANT): Humidity:20.009768 %
2018-07-25T17:33:07.548Z,1532539987.548 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-07-25T17:33:07.549Z,1532539987.549 [IBIT](IMPORTANT): buoyancyNeutral: 300.000000 cc
2018-07-25T17:33:07.549Z,1532539987.549 [IBIT](IMPORTANT): massDefault: 0.200000 cm
2018-07-25T17:33:07.549Z,1532539987.549 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2018-07-25T17:33:07.550Z,1532539987.550 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2018-07-25T17:33:07.550Z,1532539987.550 [IBIT](IMPORTANT): IBIT PASSED
2018-07-25T17:33:38.450Z,1532540018.450 [NAL9602](INFO): Powering down
2018-07-25T17:35:11.082Z,1532540111.082 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-25T17:35:11.082Z,1532540111.082 [Default:CheckIn:C.Wait] Stopped
2018-07-25T17:35:11.082Z,1532540111.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T17:35:11.082Z,1532540111.082 [Default:CheckIn:D] Running Loop=1
2018-07-25T17:35:11.507Z,1532540111.507 [Default:CheckIn:D] Stopped
2018-07-25T17:35:11.507Z,1532540111.507 [Default:CheckIn:E] Running Loop=1
2018-07-25T17:35:11.902Z,1532540111.902 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.885417 min 
2018-07-25T17:35:11.902Z,1532540111.902 [Default:CheckIn:E] Stopped
2018-07-25T17:35:11.902Z,1532540111.902 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-25T17:35:11.902Z,1532540111.902 [Default:CheckIn] Stopped
2018-07-25T17:35:11.902Z,1532540111.902 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-25T17:35:11.903Z,1532540111.903 [Default:CheckIn](INFO): Running loop #7
2018-07-25T17:35:11.903Z,1532540111.903 [Default:CheckIn] Running Loop=7
2018-07-25T17:35:11.903Z,1532540111.903 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T17:35:11.903Z,1532540111.903 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T17:35:12.642Z,1532540112.642 [NAL9602](INFO): Powering up
2018-07-25T17:35:23.646Z,1532540123.646 [NAL9602](INFO): NAL9602 initialized
2018-07-25T17:35:46.052Z,1532540146.052 [NAL9602](INFO): SBD MO Status=2, MOMSN=373, MT Status=2, MTMSN=0
2018-07-25T17:35:46.052Z,1532540146.052 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-25T17:35:54.848Z,1532540154.848 [NAL9602](INFO): SBD MO Status=0, MOMSN=373, MT Status=0, MTMSN=0
2018-07-25T17:35:54.848Z,1532540154.848 [NAL9602](INFO): No messages in MT queue
2018-07-25T17:36:56.983Z,1532540216.983 [NAL9602](INFO): GPS fix at 20180725T173656: (36.805292, -121.795680)
2018-07-25T17:36:57.050Z,1532540217.050 [Default:CheckIn:Read_GPS] Stopped
2018-07-25T17:36:57.050Z,1532540217.050 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-25T17:37:01.953Z,1532540221.953 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180725T164438/Courier0025.lzma
2018-07-25T17:37:02.732Z,1532540222.732 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0025.lzma.bak
2018-07-25T17:37:02.732Z,1532540222.732 [DataOverHttps](INFO): SBD MOMSN=8375948
2018-07-25T17:37:12.942Z,1532540232.942 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180725T164438/Courier0028.lzma
2018-07-25T17:37:13.720Z,1532540233.720 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0028.lzma.bak
2018-07-25T17:37:13.720Z,1532540233.720 [DataOverHttps](INFO): SBD MOMSN=8375951
2018-07-25T17:37:23.785Z,1532540243.785 [DataOverHttps](INFO): Sending 745 bytes from file Logs/20180725T164438/Express0026.lzma
2018-07-25T17:37:24.547Z,1532540244.547 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0026.lzma.bak
2018-07-25T17:37:24.547Z,1532540244.547 [DataOverHttps](INFO): SBD MOMSN=8375953
2018-07-25T17:37:35.449Z,1532540255.449 [NAL9602](INFO): Powering down
2018-07-25T17:37:35.612Z,1532540255.612 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180725T164438/Express0029.lzma
2018-07-25T17:37:36.392Z,1532540256.392 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0029.lzma.bak
2018-07-25T17:37:36.392Z,1532540256.392 [DataOverHttps](INFO): SBD MOMSN=8375978
2018-07-25T17:37:36.852Z,1532540256.852 [Default:CheckIn:Read_Iridium] Stopped
2018-07-25T17:37:36.852Z,1532540256.852 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-25T17:37:36.852Z,1532540256.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-25T17:38:45.477Z,1532540325.477 [RDI_Pathfinder](ERROR): Failed to parse:

2018-07-25T17:38:57.635Z,1532540337.635 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_voltage
2018-07-25T17:38:57.636Z,1532540337.636 [CommandLine](IMPORTANT): BPC1.platform_battery_voltage 15.809096 _
2018-07-25T17:40:15.560Z,1532540415.560 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-07-25T17:40:15.560Z,1532540415.560 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18072517412452,35.0, -0.1,   0.0,14
2018-07-25T17:40:16.365Z,1532540416.365 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2018-07-25T17:40:16.761Z,1532540416.761 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,514.99
2018-07-25T17:40:17.157Z,1532540417.157 [RDI_Pathfinder](ERROR): Failed to parse:

2018-07-25T17:40:44.719Z,1532540444.719 [CommandLine](IMPORTANT): got command get BPC1.platform_battery_charge
2018-07-25T17:40:44.720Z,1532540444.720 [CommandLine](IMPORTANT): BPC1.platform_battery_charge 326.548993 Ah
2018-07-25T17:41:23.407Z,1532540483.407 [CommandLine](IMPORTANT): got command failComponent
2018-07-25T17:41:23.408Z,1532540483.408 [CommandLine](IMPORTANT): Failed components:
2018-07-25T17:41:23.408Z,1532540483.408 [CommandLine](IMPORTANT): No failed Components.
2018-07-25T17:42:27.360Z,1532540547.360 [RDI_Pathfinder](ERROR): Unknown system health message
2018-07-25T17:42:27.360Z,1532540547.360 [RDI_Pathfinder] Hardware Fault, FailCount= 1
2018-07-25T17:42:27.360Z,1532540547.360 [RDI_Pathfinder](ERROR): Hardware Fault
2018-07-25T17:42:27.433Z,1532540547.433 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder
2018-07-25T17:42:27.797Z,1532540547.797 [RDI_Pathfinder](INFO): Powering down
2018-07-25T17:42:28.601Z,1532540548.601 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-25T17:42:28.601Z,1532540548.601 [RDI_Pathfinder] No Fault, FailCount= 0
2018-07-25T17:42:37.361Z,1532540557.361 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-25T17:42:37.361Z,1532540557.361 [Default:CheckIn:C.Wait] Stopped
2018-07-25T17:42:37.361Z,1532540557.361 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T17:42:37.362Z,1532540557.362 [Default:CheckIn:D] Running Loop=1
2018-07-25T17:42:37.779Z,1532540557.779 [Default:CheckIn:D] Stopped
2018-07-25T17:42:37.779Z,1532540557.779 [Default:CheckIn:E] Running Loop=1
2018-07-25T17:42:41.066Z,1532540561.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.323283 min 
2018-07-25T17:42:41.066Z,1532540561.066 [Default:CheckIn:E] Stopped
2018-07-25T17:42:41.066Z,1532540561.066 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-25T17:42:41.066Z,1532540561.066 [Default:CheckIn] Stopped
2018-07-25T17:42:41.067Z,1532540561.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-25T17:42:41.067Z,1532540561.067 [Default:CheckIn](INFO): Running loop #8
2018-07-25T17:42:41.067Z,1532540561.067 [Default:CheckIn] Running Loop=8
2018-07-25T17:42:41.067Z,1532540561.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T17:42:41.067Z,1532540561.067 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T17:42:41.506Z,1532540561.506 [NAL9602](INFO): Powering up
2018-07-25T17:42:42.348Z,1532540562.348 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-25T17:42:42.727Z,1532540562.727 [RDI_Pathfinder](ERROR): Failed to parse:
All rights reserved.
2018-07-25T17:42:43.121Z,1532540563.121 [RDI_Pathfinder](ERROR): Failed to parse:
Current time is: 18/07/25,17:43:41.45
2018-07-25T17:42:43.519Z,1532540563.519 [RDI_Pathfinder](ERROR): Failed to parse:
>
2018-07-25T17:42:52.299Z,1532540572.299 [NAL9602](INFO): NAL9602 initialized
2018-07-25T17:43:07.500Z,1532540587.500 [NAL9602](INFO): SBD MO Status=0, MOMSN=374, MT Status=0, MTMSN=0
2018-07-25T17:43:07.500Z,1532540587.500 [NAL9602](INFO): No messages in MT queue
2018-07-25T17:44:42.852Z,1532540682.852 [DataOverHttps](IMPORTANT): SBD MTMSN=20180725T174442
2018-07-25T17:44:47.161Z,1532540687.161 [DataOverHttps](INFO): Received command:load Science/sci2.xml;set sci2.MissionTimeout 20 minute;set sci2.NeedCommsTime 10 minute;set sci2.Lat1 36.750 degree;set sci2.Lon1 -122.022 degree;run 
2018-07-25T17:44:47.185Z,1532540687.185 [CommandLine](IMPORTANT): got command load ./Missions/Science/sci2.xml
2018-07-25T17:44:47.185Z,1532540687.185 [MissionManager](INFO): Loading Mission: ./Missions/Science/sci2.xml
2018-07-25T17:44:47.349Z,1532540687.349 [MissionManager](INFO): DefineArg sci2.MissionTimeout = 2.000000 h
2018-07-25T17:44:47.352Z,1532540687.352 [MissionManager](INFO): DefineArg sci2.NeedCommsTime = 60.000000 min
2018-07-25T17:44:47.355Z,1532540687.355 [MissionManager](INFO): DefineArg sci2.Repeat = 1.000000 count
2018-07-25T17:44:47.358Z,1532540687.358 [MissionManager](INFO): DefineArg sci2.Lat1 = nan arcdeg
2018-07-25T17:44:47.360Z,1532540687.360 [MissionManager](INFO): DefineArg sci2.Lon1 = nan arcdeg
2018-07-25T17:44:47.363Z,1532540687.363 [MissionManager](INFO): DefineArg sci2.Lat2 = nan arcdeg
2018-07-25T17:44:47.366Z,1532540687.366 [MissionManager](INFO): DefineArg sci2.Lon2 = nan arcdeg
2018-07-25T17:44:47.372Z,1532540687.372 [MissionManager](INFO): DefineArg sci2.Lat3 = nan arcdeg
2018-07-25T17:44:47.375Z,1532540687.375 [MissionManager](INFO): DefineArg sci2.Lon3 = nan arcdeg
2018-07-25T17:44:47.378Z,1532540687.378 [MissionManager](INFO): DefineArg sci2.Lat4 = nan arcdeg
2018-07-25T17:44:47.381Z,1532540687.381 [MissionManager](INFO): DefineArg sci2.Lon4 = nan arcdeg
2018-07-25T17:44:47.384Z,1532540687.384 [MissionManager](INFO): DefineArg sci2.Lat5 = nan arcdeg
2018-07-25T17:44:47.387Z,1532540687.387 [MissionManager](INFO): DefineArg sci2.Lon5 = nan arcdeg
2018-07-25T17:44:47.431Z,1532540687.431 [MissionManager](INFO): DefineArg sci2.Lat6 = nan arcdeg
2018-07-25T17:44:47.434Z,1532540687.434 [MissionManager](INFO): DefineArg sci2.Lon6 = nan arcdeg
2018-07-25T17:44:47.437Z,1532540687.437 [MissionManager](INFO): DefineArg sci2.Lat7 = nan arcdeg
2018-07-25T17:44:47.439Z,1532540687.439 [MissionManager](INFO): DefineArg sci2.Lon7 = nan arcdeg
2018-07-25T17:44:47.448Z,1532540687.448 [MissionManager](INFO): DefineArg sci2.Speed = 1.000000 m/s
2018-07-25T17:44:47.451Z,1532540687.451 [MissionManager](INFO): DefineArg sci2.YoYoMinDepth = 2.000000 m
2018-07-25T17:44:47.454Z,1532540687.454 [MissionManager](INFO): DefineArg sci2.YoYoMaxDepth = 200.000000 m
2018-07-25T17:44:47.473Z,1532540687.473 [MissionManager](INFO): DefineArg sci2.YoYoMinAltitude = 9.000000 m
2018-07-25T17:44:47.476Z,1532540687.476 [MissionManager](INFO): DefineArg sci2.YoYoUpPitch = 20.000000 arcdeg
2018-07-25T17:44:47.479Z,1532540687.479 [MissionManager](INFO): DefineArg sci2.YoYoDownPitch = -20.000000 arcdeg
2018-07-25T17:44:47.483Z,1532540687.483 [MissionManager](INFO): DefineArg sci2.BuoyancyNeutral = 300.000014 cc
2018-07-25T17:44:47.498Z,1532540687.498 [MissionManager](INFO): DefineArg sci2.MassDefault = 0.200000 cm
2018-07-25T17:44:47.501Z,1532540687.501 [MissionManager](INFO): DefineArg sci2.MinAltitude = 5.000000 m
2018-07-25T17:44:47.504Z,1532540687.504 [MissionManager](INFO): DefineArg sci2.MaxDepth = 205.000000 m
2018-07-25T17:44:47.507Z,1532540687.507 [MissionManager](INFO): DefineArg sci2.MinOffshore = 2.000000 km
2018-07-25T17:44:47.507Z,1532540687.507 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-07-25T17:44:47.924Z,1532540687.924 [MissionManager](INFO): DefineArg sci2:Science.SampleISUS = 0 bool
2018-07-25T17:44:47.932Z,1532540687.932 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-07-25T17:44:47.944Z,1532540687.944 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-07-25T17:44:47.955Z,1532540687.955 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectChlActive = 0 bool
2018-07-25T17:44:47.958Z,1532540687.958 [MissionManager](INFO): DefineArg sci2:Science.HighestChlPeakReportActive = 0 bool
2018-07-25T17:44:47.961Z,1532540687.961 [MissionManager](INFO): DefineArg sci2:Science.PatchTracking = 0 bool
2018-07-25T17:44:47.981Z,1532540687.981 [MissionManager](INFO): DefineArg sci2:Science.FilterWidthHorizontal = 3.000000 count
2018-07-25T17:44:47.984Z,1532540687.984 [MissionManager](INFO): DefineArg sci2:Science.NumProfilesSlidingwindow = 100.000000 count
2018-07-25T17:44:47.991Z,1532540687.991 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectNO3Active = 0 bool
2018-07-25T17:44:47.994Z,1532540687.994 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectOilActive = 0 bool
2018-07-25T17:44:47.996Z,1532540687.996 [MissionManager](INFO): DefineArg sci2:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-07-25T17:44:47.000Z,1532540688.000 [MissionManager](INFO): DefineArg sci2:Science.EnabledAanderaaO2 = 1.000000 bool
2018-07-25T17:44:48.031Z,1532540688.031 [MissionManager](INFO): DefineArg sci2:Science.EnabledNeilBrown = 0.000000 bool
2018-07-25T17:44:48.034Z,1532540688.034 [MissionManager](INFO): DefineArg sci2:Science.EnabledSeabird = 0.000000 bool
2018-07-25T17:44:48.055Z,1532540688.055 [MissionManager](INFO): DefineArg sci2:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-07-25T17:44:48.059Z,1532540688.059 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsBB2FL = 1.000000 bool
2018-07-25T17:44:48.062Z,1532540688.062 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2018-07-25T17:44:48.078Z,1532540688.078 [MissionManager](INFO): DefineArg sci2:Science.EnabledISUS = 0.000000 bool
2018-07-25T17:44:48.081Z,1532540688.081 [MissionManager](INFO): DefineArg sci2:Science.LowPassWindowLength = 20.000000 count
2018-07-25T17:44:48.084Z,1532540688.084 [MissionManager](INFO): DefineArg sci2:Science.PeakChlShallowBound = nan m
2018-07-25T17:44:48.087Z,1532540688.087 [MissionManager](INFO): DefineArg sci2:Science.PeakChlDeepBound = nan m
2018-07-25T17:44:48.118Z,1532540688.118 [MissionManager](INFO): DefineOutput sci2:Science.PeakChl = 0.000000 ug/l
2018-07-25T17:44:48.121Z,1532540688.121 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlDepth = 0.000000 m
2018-07-25T17:44:48.124Z,1532540688.124 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlTemperature = 0.000000 degC
2018-07-25T17:44:48.127Z,1532540688.127 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLatitude = nan arcdeg
2018-07-25T17:44:48.151Z,1532540688.151 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLongitude = nan arcdeg
2018-07-25T17:44:48.154Z,1532540688.154 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3 = 0.000000 umol/l
2018-07-25T17:44:48.157Z,1532540688.157 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Depth = 0.000000 m
2018-07-25T17:44:48.160Z,1532540688.160 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Latitude = nan arcdeg
2018-07-25T17:44:48.181Z,1532540688.181 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Longitude = nan arcdeg
2018-07-25T17:44:48.216Z,1532540688.216 [MissionManager](INFO): DefineOutput sci2:Science.PatchChl = 0.000000 m
2018-07-25T17:44:48.236Z,1532540688.236 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDepth = 0.000000 m
2018-07-25T17:44:48.239Z,1532540688.239 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLatitude = nan arcdeg
2018-07-25T17:44:48.242Z,1532540688.242 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLongitude = nan arcdeg
2018-07-25T17:44:48.268Z,1532540688.268 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDistance = nan m
2018-07-25T17:44:48.271Z,1532540688.271 [MissionManager](INFO): DefineOutput sci2:Science.PeakOil = 0.000000 kg/m3
2018-07-25T17:44:48.274Z,1532540688.274 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilDepth = 0.000000 m
2018-07-25T17:44:48.295Z,1532540688.295 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLatitude = nan arcdeg
2018-07-25T17:44:48.299Z,1532540688.299 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLongitude = nan arcdeg
2018-07-25T17:44:48.327Z,1532540688.327 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-25T17:44:48.372Z,1532540688.372 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-07-25T17:44:48.411Z,1532540688.411 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-25T17:44:48.432Z,1532540688.432 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-25T17:44:48.470Z,1532540688.470 [MissionManager](INFO): RedefineArg sci2:Science.SampleISUS = value:1 bool
2018-07-25T17:44:48.472Z,1532540688.472 [MissionManager](INFO): RedefineArg sci2:Science.PeakDetectChlActive = value:1 bool
2018-07-25T17:44:48.473Z,1532540688.473 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-07-25T17:44:48.591Z,1532540688.591 [MissionManager](INFO): DefineArg sci2:NeedComms.DiveInterval = 3.000000 h
2018-07-25T17:44:48.600Z,1532540688.600 [MissionManager](INFO): DefineArg sci2:NeedComms.WaitForPitchUp = 10.000000 min
2018-07-25T17:44:48.603Z,1532540688.603 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-07-25T17:44:48.610Z,1532540688.610 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceDepthRate = nan m/s
2018-07-25T17:44:48.634Z,1532540688.634 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-07-25T17:44:48.647Z,1532540688.647 [MissionManager](INFO): DefineArg sci2:NeedComms.GPSTimeout = 7.000000 min
2018-07-25T17:44:48.650Z,1532540688.650 [MissionManager](INFO): DefineArg sci2:NeedComms.CommsTimeout = 30.000000 min
2018-07-25T17:44:48.675Z,1532540688.675 [sci2:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-25T17:44:48.708Z,1532540688.708 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-07-25T17:44:48.772Z,1532540688.772 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinAltitude = 5.000000 m
2018-07-25T17:44:48.777Z,1532540688.777 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MaxDepth = 200.000000 m
2018-07-25T17:44:48.780Z,1532540688.780 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-07-25T17:44:48.797Z,1532540688.797 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-07-25T17:44:48.828Z,1532540688.828 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-07-25T17:44:48.849Z,1532540688.849 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-07-25T17:44:48.872Z,1532540688.872 [sci2:I.Pitch](DEBUG): Construct.
2018-07-25T17:44:48.908Z,1532540688.908 [sci2:J.Buoyancy](DEBUG): Construct Buoyancy.
2018-07-25T17:44:48.911Z,1532540688.911 [sci2:K.SetSpeed](DEBUG): Construct.
2018-07-25T17:44:48.914Z,1532540688.914 [sci2:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-07-25T17:44:48.980Z,1532540688.980 [sci2:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-07-25T17:44:48.988Z,1532540688.988 [sci2:N.YoYo](DEBUG): Construct YoYo.
2018-07-25T17:44:49.036Z,1532540689.036 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-25T17:44:49.056Z,1532540689.056 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-25T17:44:49.084Z,1532540689.084 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-25T17:44:49.107Z,1532540689.107 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-25T17:44:49.122Z,1532540689.122 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-25T17:44:49.146Z,1532540689.146 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-25T17:44:49.186Z,1532540689.186 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-25T17:44:49.267Z,1532540689.267 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Control="Tethys/Control"
         xmlns:Guidance="Tethys/Guidance"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd"
       Id="sci2">

    <Description>
        Vehicle yo-yo's to the specified waypoints, with science turned on.
    </Description>
    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>2</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>60</Value></DefineArg>

    <DefineArg Name="Repeat"><Description>
        Number of times the vehicle should try to cycle through waypoints..
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="Lat1"><Description>
        Latitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon1"><Description>
        Longitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat2"><Description>
        Latitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon2"><Description>
        Longitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat3"><Description>
        Latitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon3"><Description>
        Longitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat4"><Description>
        Latitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon4"><Description>
        Longitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat5"><Description>
        Latitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon5"><Description>
        Longitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat6"><Description>
        Latitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon6"><Description>
        Longitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat7"><Description>
        Latitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon7"><Description>
        Longitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

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

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

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

    <DefineArg Name="YoYoUpPitch"><Description>
        Vehicle up pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Vehicle down pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>-20</Value></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position while performing the YoYo behavior. Defaults
        to buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc
        for active buoyancy
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission. Set to NaN mm for active
        mass position
    </Description><Control:VerticalControl.massDefault/></DefineArg>

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

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

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

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

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

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></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>

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

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Arg Name="BuoyancyNeutral"/></Setting>
    </Guidance:Buoyancy>

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

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

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

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:YoYo.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="Lap">
        <Sequence Repeat="Repeat"/>

        <Aggregate Id="Wpt1">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat1"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon1"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt2">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat2"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon2"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt3">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat3"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon3"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt4">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat4"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon4"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt5">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat5"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon5"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt6">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat6"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon6"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt7">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat7"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon7"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>
    </Aggregate>
</Mission>

2018-07-25T17:44:49.267Z,1532540689.267 [CommandLine](IMPORTANT): Loaded ./Missions/Science/sci2.xml
2018-07-25T17:44:54.174Z,1532540694.174 [CommandLine](IMPORTANT): got command set sci2.MissionTimeout 20.000000 minute
2018-07-25T17:44:54.175Z,1532540694.175 [CommandLine](IMPORTANT): got command set sci2.NeedCommsTime 10.000000 minute
2018-07-25T17:44:54.175Z,1532540694.175 [CommandLine](IMPORTANT): got command set sci2.Lat1 36.750000 degree
2018-07-25T17:44:54.176Z,1532540694.176 [CommandLine](IMPORTANT): got command set sci2.Lon1 -122.022003 degree
2018-07-25T17:44:54.177Z,1532540694.177 [CommandLine](IMPORTANT): got command run 
2018-07-25T17:44:54.180Z,1532540694.180 [CommandLine](IMPORTANT): Running 
2018-07-25T17:44:54.352Z,1532540694.352 [Default] Stopped
2018-07-25T17:44:54.369Z,1532540694.369 [Default](DEBUG): Aggregate::uninitialize Default
2018-07-25T17:44:54.369Z,1532540694.369 [Default:B.GoToSurface] Stopped
2018-07-25T17:44:54.369Z,1532540694.369 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-25T17:44:54.369Z,1532540694.369 [Default:CheckIn] Stopped
2018-07-25T17:44:54.369Z,1532540694.369 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-25T17:44:54.370Z,1532540694.370 [Default:CheckIn:Read_GPS] Stopped
2018-07-25T17:44:54.370Z,1532540694.370 [MissionManager](IMPORTANT): Started mission sci2
2018-07-25T17:44:54.370Z,1532540694.370 [sci2] Running Loop=1
2018-07-25T17:44:54.370Z,1532540694.370 [sci2](DEBUG): Aggregate::initialize sci2
2018-07-25T17:44:54.370Z,1532540694.370 [sci2:Science] Running Loop=1
2018-07-25T17:44:54.370Z,1532540694.370 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science
2018-07-25T17:44:54.370Z,1532540694.370 [sci2:Science:A] Running Loop=1
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:B] Running Loop=1
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:C] Running Loop=1
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:D] Running Loop=1
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:E] Running Loop=1
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:F] Running Loop=1
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:G] Running Loop=1
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:Read_Oil] Running Loop=1
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:PeakDetectChl] Running Loop=1
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl
2018-07-25T17:44:54.371Z,1532540694.371 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-07-25T17:44:54.372Z,1532540694.372 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-25T17:44:54.374Z,1532540694.374 [sci2:Science:HighestChlPeakReport] Running Loop=1
2018-07-25T17:44:54.374Z,1532540694.374 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport
2018-07-25T17:44:54.374Z,1532540694.374 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-07-25T17:44:54.374Z,1532540694.374 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-07-25T17:44:54.375Z,1532540694.375 [sci2:Science:PeakDetectNO3] Running Loop=1
2018-07-25T17:44:54.375Z,1532540694.375 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3
2018-07-25T17:44:54.375Z,1532540694.375 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-07-25T17:44:54.375Z,1532540694.375 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-25T17:44:54.377Z,1532540694.377 [sci2:Science:PeakDetectOil] Running Loop=1
2018-07-25T17:44:54.377Z,1532540694.377 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil
2018-07-25T17:44:54.377Z,1532540694.377 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-07-25T17:44:54.377Z,1532540694.377 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-25T17:44:54.379Z,1532540694.379 [sci2:StandardEnvelopes] Running Loop=1
2018-07-25T17:44:54.380Z,1532540694.379 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes
2018-07-25T17:44:54.380Z,1532540694.380 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-07-25T17:44:54.380Z,1532540694.380 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-07-25T17:44:54.380Z,1532540694.380 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-07-25T17:44:54.380Z,1532540694.380 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-07-25T17:44:54.380Z,1532540694.380 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-07-25T17:44:54.380Z,1532540694.380 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-07-25T17:44:54.380Z,1532540694.380 [sci2:I.Pitch] Running Loop=1
2018-07-25T17:44:54.389Z,1532540694.389 [sci2:I.Pitch](DEBUG): Initialize.
2018-07-25T17:44:54.389Z,1532540694.389 [sci2:J.Buoyancy] Running Loop=1
2018-07-25T17:44:54.389Z,1532540694.389 [sci2:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-07-25T17:44:54.389Z,1532540694.389 [sci2:K.SetSpeed] Running Loop=1
2018-07-25T17:44:54.389Z,1532540694.389 [sci2:K.SetSpeed](DEBUG): Initialize.
2018-07-25T17:44:54.390Z,1532540694.390 [sci2:L.DepthEnvelope] Running Loop=1
2018-07-25T17:44:54.390Z,1532540694.390 [sci2:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-07-25T17:44:54.390Z,1532540694.390 [sci2:M.AltitudeEnvelope] Running Loop=1
2018-07-25T17:44:54.390Z,1532540694.390 [sci2:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-07-25T17:44:54.390Z,1532540694.390 [sci2:N.YoYo] Running Loop=1
2018-07-25T17:44:54.390Z,1532540694.390 [sci2:N.YoYo](DEBUG): Initialize YoYoComponent.
2018-07-25T17:44:54.391Z,1532540694.391 [sci2:C] Running Loop=1
2018-07-25T17:44:54.391Z,1532540694.391 [sci2:N.YoYo] Running Loop=1
2018-07-25T17:44:54.405Z,1532540694.405 [sci2:M.AltitudeEnvelope] Running Loop=1
2018-07-25T17:44:54.414Z,1532540694.414 [sci2:L.DepthEnvelope] Running Loop=1
2018-07-25T17:44:54.424Z,1532540694.424 [sci2:K.SetSpeed] Running Loop=1
2018-07-25T17:44:54.438Z,1532540694.438 [sci2:J.Buoyancy] Running Loop=1
2018-07-25T17:44:54.444Z,1532540694.444 [sci2:I.Pitch] Running Loop=1
2018-07-25T17:44:54.466Z,1532540694.466 [sci2:StandardEnvelopes] Running Loop=1
2018-07-25T17:44:54.466Z,1532540694.466 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-07-25T17:44:54.491Z,1532540694.491 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-07-25T17:44:54.500Z,1532540694.500 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-07-25T17:44:54.515Z,1532540694.515 [sci2:C] Stopped
2018-07-25T17:44:54.516Z,1532540694.516 [sci2:E] Running Loop=1
2018-07-25T17:44:54.516Z,1532540694.516 [sci2:Science] Running Loop=1
2018-07-25T17:44:54.530Z,1532540694.530 [sci2:Science:PeakDetectOil] Stopped
2018-07-25T17:44:54.530Z,1532540694.530 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:PeakDetectNO3] Stopped
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:HighestChlPeakReport] Stopped
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-07-25T17:44:54.531Z,1532540694.531 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-07-25T17:44:54.532Z,1532540694.532 [sci2:Science:PeakDetectChl] Running Loop=1
2018-07-25T17:44:54.545Z,1532540694.545 [sci2:Science:Read_Oil] Running Loop=1
2018-07-25T17:44:54.546Z,1532540694.546 [sci2:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water
2018-07-25T17:44:54.547Z,1532540694.547 [sci2:Science:G] Running Loop=1
2018-07-25T17:44:54.548Z,1532540694.548 [sci2:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-07-25T17:44:54.550Z,1532540694.550 [sci2:Science:F] Stopped
2018-07-25T17:44:54.550Z,1532540694.550 [sci2:Science:E] Stopped
2018-07-25T17:44:54.550Z,1532540694.550 [sci2:Science:D] Stopped
2018-07-25T17:44:54.550Z,1532540694.550 [sci2:Science:C] Stopped
2018-07-25T17:44:54.550Z,1532540694.550 [sci2:Science:B] Stopped
2018-07-25T17:44:54.550Z,1532540694.550 [sci2:Science:A] Running Loop=1
2018-07-25T17:44:54.551Z,1532540694.551 [sci2:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2018-07-25T17:44:54.735Z,1532540694.735 [sci2:E] Stopped
2018-07-25T17:44:54.735Z,1532540694.735 [sci2:F] Running Loop=1
2018-07-25T17:44:55.064Z,1532540695.064 [DataOverHttps](IMPORTANT): SBD MTMSN=20180725T174448
2018-07-25T17:44:55.188Z,1532540695.188 [sci2:F] Stopped
2018-07-25T17:44:55.188Z,1532540695.188 [sci2:G] Running Loop=1
2018-07-25T17:44:55.533Z,1532540695.533 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2018-07-25T17:44:55.533Z,1532540695.533 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2018-07-25T17:44:55.533Z,1532540695.533 [ThrusterServo] Communications Fault, FailCount= 1
2018-07-25T17:44:55.533Z,1532540695.533 [ThrusterServo](ERROR): Communications Fault
2018-07-25T17:44:55.537Z,1532540695.537 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-07-25T17:44:55.682Z,1532540695.682 [sci2:G] Stopped
2018-07-25T17:44:55.682Z,1532540695.682 [sci2:StartingMission] Running Loop=1
2018-07-25T17:44:55.682Z,1532540695.682 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission
2018-07-25T17:44:55.810Z,1532540695.810 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-07-25T17:44:55.810Z,1532540695.810 [ThrusterServo](INFO): Powering down
2018-07-25T17:44:56.029Z,1532540696.029 [sci2:NeedComms] Running Loop=1
2018-07-25T17:44:56.029Z,1532540696.029 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2018-07-25T17:44:56.029Z,1532540696.029 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2018-07-25T17:44:56.029Z,1532540696.029 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-25T17:44:56.030Z,1532540696.030 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-07-25T17:44:56.030Z,1532540696.030 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-07-25T17:44:56.031Z,1532540696.031 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-07-25T17:44:56.031Z,1532540696.031 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-25T17:44:56.031Z,1532540696.031 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-25T17:44:56.032Z,1532540696.032 [sci2:NeedComms:A] Running Loop=1
2018-07-25T17:44:56.040Z,1532540696.040 [sci2:NeedComms:A](INFO): last time_fix was: 1532540216.000000 second since 1970/01/01T00:00:00Z 
2018-07-25T17:44:56.057Z,1532540696.057 [sci2:NeedComms:A] Stopped
2018-07-25T17:44:59.381Z,1532540699.381 [sci2:NeedComms:C] Running Loop=1
2018-07-25T17:44:59.729Z,1532540699.729 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-07-25T17:44:59.729Z,1532540699.729 [ThrusterServo] No Fault, FailCount= 1
2018-07-25T17:44:59.749Z,1532540699.749 [DataOverHttps](INFO): Received command:stop
2018-07-25T17:44:59.786Z,1532540699.786 [CommandLine](IMPORTANT): got command stop
2018-07-25T17:44:59.787Z,1532540699.787 [CommandLine](IMPORTANT): Scheduling is paused
2018-07-25T17:44:59.787Z,1532540699.787 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2018-07-25T17:44:59.849Z,1532540699.849 [sci2:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-25T17:44:59.854Z,1532540699.854 [MissionManager](INFO): MissionManager is completed.
2018-07-25T17:44:59.854Z,1532540699.854 [MissionManager](INFO): Uninitializing Mission sci2
2018-07-25T17:44:59.854Z,1532540699.854 [sci2] Stopped
2018-07-25T17:44:59.854Z,1532540699.854 [sci2](DEBUG): Aggregate::uninitialize sci2
2018-07-25T17:44:59.854Z,1532540699.854 [sci2:Science] Stopped
2018-07-25T17:44:59.854Z,1532540699.854 [sci2:Science](DEBUG): Aggregate::uninitialize sci2:Science
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:Science:A] Stopped
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:Science:G] Stopped
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:Science:Read_Oil] Stopped
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:Science:PeakDetectChl] Stopped
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:NeedComms] Stopped
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:NeedComms:B.GoToSurface] Stopped
2018-07-25T17:44:59.855Z,1532540699.855 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:NeedComms:C] Stopped
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StandardEnvelopes] Stopped
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2:StandardEnvelopes
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StartingMission] Stopped
2018-07-25T17:44:59.856Z,1532540699.856 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission
2018-07-25T17:44:59.866Z,1532540699.866 [sci2:I.Pitch] Stopped
2018-07-25T17:44:59.866Z,1532540699.866 [sci2:J.Buoyancy] Stopped
2018-07-25T17:44:59.866Z,1532540699.866 [sci2:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-07-25T17:44:59.867Z,1532540699.867 [sci2:K.SetSpeed] Stopped
2018-07-25T17:44:59.867Z,1532540699.867 [sci2:K.SetSpeed](DEBUG): Uninitialize.
2018-07-25T17:44:59.867Z,1532540699.867 [sci2:L.DepthEnvelope] Stopped
2018-07-25T17:44:59.867Z,1532540699.867 [sci2:L.DepthEnvelope](DEBUG): Uninitialize.
2018-07-25T17:44:59.867Z,1532540699.867 [sci2:M.AltitudeEnvelope] Stopped
2018-07-25T17:44:59.867Z,1532540699.867 [sci2:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-07-25T17:44:59.867Z,1532540699.867 [sci2:N.YoYo] Stopped
2018-07-25T17:44:59.867Z,1532540699.867 [sci2:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2018-07-25T17:45:00.218Z,1532540700.218 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-25T17:45:00.220Z,1532540700.220 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-25T17:45:00.314Z,1532540700.314 [MissionManager](IMPORTANT): Started mission Default
2018-07-25T17:45:00.315Z,1532540700.315 [Default] Running Loop=1
2018-07-25T17:45:00.315Z,1532540700.315 [Default](DEBUG): Aggregate::initialize Default
2018-07-25T17:45:00.315Z,1532540700.315 [Default:B.GoToSurface] Running Loop=1
2018-07-25T17:45:00.315Z,1532540700.315 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-25T17:45:00.315Z,1532540700.315 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-25T17:45:00.316Z,1532540700.316 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-25T17:45:00.316Z,1532540700.316 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-25T17:45:00.316Z,1532540700.316 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-25T17:45:00.322Z,1532540700.322 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-25T17:45:00.322Z,1532540700.322 [Default:A.Wait] Running Loop=1
2018-07-25T17:45:00.322Z,1532540700.322 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-25T17:45:00.753Z,1532540700.753 [Aanderaa_O2](INFO): Powering down
2018-07-25T17:45:00.812Z,1532540700.812 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:45:01.065Z,1532540701.065 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:45:01.508Z,1532540701.508 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87,*14.597,*-0.069,*0.000
2018-07-25T17:45:01.867Z,1532540701.867 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:45:02.276Z,1532540702.276 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:45:02.675Z,1532540702.675 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:45:03.078Z,1532540703.078 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88,*14.604,*-0.060,*0.000
2018-07-25T17:45:03.476Z,1532540703.476 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:45:03.882Z,1532540703.882 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:45:04.284Z,1532540704.284 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:45:04.672Z,1532540704.672 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88,*14.604,*-0.064,*0.000
2018-07-25T17:45:05.063Z,1532540705.063 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:45:05.473Z,1532540705.473 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:45:05.872Z,1532540705.872 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:45:06.285Z,1532540706.285 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87, 14.604, -0.064, 0.000
2018-07-25T17:45:06.682Z,1532540706.682 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:45:07.083Z,1532540707.083 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:45:07.480Z,1532540707.480 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:45:07.887Z,1532540707.887 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.604, -0.064, 0.000
2018-07-25T17:45:08.275Z,1532540708.275 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:45:08.686Z,1532540708.686 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:45:09.068Z,1532540709.068 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:45:09.462Z,1532540709.462 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-07-25T17:45:09.463Z,1532540709.463 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-07-25T17:45:09.463Z,1532540709.463 [RDI_Pathfinder](ERROR): Communications Fault
2018-07-25T17:45:09.541Z,1532540709.541 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-07-25T17:45:09.941Z,1532540709.941 [RDI_Pathfinder](INFO): Powering down
2018-07-25T17:45:10.712Z,1532540710.712 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-25T17:45:10.712Z,1532540710.712 [RDI_Pathfinder] No Fault, FailCount= 1
2018-07-25T17:45:13.493Z,1532540713.493 [Default:A.Wait](INFO): Done Waiting.
2018-07-25T17:45:13.494Z,1532540713.494 [Default:A.Wait] Stopped
2018-07-25T17:45:13.494Z,1532540713.494 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T17:45:13.888Z,1532540713.888 [Default:CheckIn] Running Loop=1
2018-07-25T17:45:13.888Z,1532540713.888 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T17:45:13.888Z,1532540713.888 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T17:45:24.690Z,1532540724.690 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-25T17:45:25.081Z,1532540725.081 [RDI_Pathfinder](ERROR): Failed to parse:
All rights reserved.
2018-07-25T17:45:25.495Z,1532540725.495 [RDI_Pathfinder](ERROR): Failed to parse:
Current time is: 18/07/25,17:46:23.59
2018-07-25T17:45:25.872Z,1532540725.872 [RDI_Pathfinder](ERROR): Failed to parse:
>
2018-07-25T17:46:21.222Z,1532540781.222 [NAL9602](INFO): GPS fix at 20180725T174621: (36.809955, -121.829971)
2018-07-25T17:46:21.254Z,1532540781.254 [Default:CheckIn:Read_GPS] Stopped
2018-07-25T17:46:21.254Z,1532540781.254 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-25T17:46:26.712Z,1532540786.712 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180725T164438/Courier0031.lzma
2018-07-25T17:46:27.492Z,1532540787.492 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0031.lzma.bak
2018-07-25T17:46:27.492Z,1532540787.492 [DataOverHttps](INFO): SBD MOMSN=8375986
2018-07-25T17:46:45.022Z,1532540805.022 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180725T164438/Courier0034.lzma
2018-07-25T17:46:45.812Z,1532540805.812 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Courier0034.lzma.bak
2018-07-25T17:46:45.812Z,1532540805.812 [DataOverHttps](INFO): SBD MOMSN=8375989
2018-07-25T17:46:53.554Z,1532540813.554 [NAL9602](INFO): Powering down
2018-07-25T17:47:54.339Z,1532540874.339 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-25T17:48:04.503Z,1532540884.503 [DataOverHttps](INFO): Sending 590 bytes from file Logs/20180725T164438/Express0032.lzma
2018-07-25T17:48:04.535Z,1532540884.535 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:48:04.925Z,1532540884.925 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:48:05.339Z,1532540885.339 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88,*14.631,*-0.059,*0.000
2018-07-25T17:48:05.727Z,1532540885.727 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:48:06.126Z,1532540886.126 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:48:06.534Z,1532540886.534 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:48:06.934Z,1532540886.934 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e87, 14.631, -0.059, 0.000
2018-07-25T17:48:07.332Z,1532540887.332 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:48:07.696Z,1532540887.696 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0032.lzma.bak
2018-07-25T17:48:07.696Z,1532540887.696 [DataOverHttps](INFO): SBD MOMSN=8375991
2018-07-25T17:48:07.735Z,1532540887.735 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:48:08.128Z,1532540888.128 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:48:08.527Z,1532540888.527 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e88, 14.631, -0.059, 0.000
2018-07-25T17:48:08.925Z,1532540888.925 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:48:09.353Z,1532540889.353 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-07-25T17:48:09.722Z,1532540889.722 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-07-25T17:48:10.134Z,1532540890.134 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e87,0e87, 14.631, -0.059, 0.000
2018-07-25T17:48:10.521Z,1532540890.521 [RDI_Pathfinder](ERROR): Failed to parse::SA,   +0.00,   +0.00,  0.00
2018-07-25T17:48:13.758Z,1532540893.758 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-07-25T17:48:13.758Z,1532540893.758 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-07-25T17:48:13.758Z,1532540893.758 [RDI_Pathfinder](ERROR): Communications Fault
2018-07-25T17:48:13.806Z,1532540893.806 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-07-25T17:48:13.933Z,1532540893.933 [RDI_Pathfinder](INFO): Powering down
2018-07-25T17:48:14.687Z,1532540894.687 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-25T17:48:14.687Z,1532540894.687 [RDI_Pathfinder] No Fault, FailCount= 1
2018-07-25T17:48:24.654Z,1532540904.654 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180725T164438/Express0035.lzma
2018-07-25T17:48:26.252Z,1532540906.252 [DataOverHttps](INFO): Moved sent file to Logs/20180725T164438/Express0035.lzma.bak
2018-07-25T17:48:26.252Z,1532540906.252 [DataOverHttps](INFO): SBD MOMSN=8376017
2018-07-25T17:48:26.713Z,1532540906.713 [Default:CheckIn:Read_Iridium] Stopped
2018-07-25T17:48:26.714Z,1532540906.714 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-25T17:48:26.714Z,1532540906.714 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-25T17:48:28.302Z,1532540908.302 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-25T17:48:28.678Z,1532540908.678 [RDI_Pathfinder](ERROR): Failed to parse:
All rights reserved.
2018-07-25T17:48:29.075Z,1532540909.075 [RDI_Pathfinder](ERROR): Failed to parse:
Current time is: 18/07/25,17:49:27.56
2018-07-25T17:48:29.471Z,1532540909.471 [RDI_Pathfinder](ERROR): Failed to parse:
>
2018-07-25T17:50:00.282Z,1532541000.282 [Radio_Surface](INFO): Powering down
2018-07-25T17:50:00.312Z,1532541000.312 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (1 active estimators).
2018-07-25T17:50:06.342Z,1532541006.342 [Radio_Surface](INFO): Powering up
2018-07-25T17:50:39.616Z,1532541039.616 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-25T17:50:40.010Z,1532541040.010 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-25T17:50:40.415Z,1532541040.415 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-25T17:50:45.622Z,1532541045.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-25T17:50:46.023Z,1532541046.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-25T17:50:46.417Z,1532541046.417 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-25T17:50:46.826Z,1532541046.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-25T17:50:47.199Z,1532541047.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-25T17:50:48.833Z,1532541048.833 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-25T17:50:49.207Z,1532541049.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-25T17:50:52.827Z,1532541052.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-25T17:50:53.217Z,1532541053.217 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-25T17:50:57.178Z,1532541057.178 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-25T17:51:57.571Z,1532541117.571 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-25T17:51:58.812Z,1532541118.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-25T17:51:59.211Z,1532541119.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-25T17:52:24.817Z,1532541144.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-25T17:52:25.211Z,1532541145.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-25T17:52:38.371Z,1532541158.371 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.114594
2018-07-25T17:53:27.220Z,1532541207.220 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-25T17:53:27.220Z,1532541207.220 [Default:CheckIn:C.Wait] Stopped
2018-07-25T17:53:27.220Z,1532541207.220 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-25T17:53:27.221Z,1532541207.221 [Default:CheckIn:D] Running Loop=1
2018-07-25T17:53:27.626Z,1532541207.626 [Default:CheckIn:D] Stopped
2018-07-25T17:53:27.626Z,1532541207.626 [Default:CheckIn:E] Running Loop=1
2018-07-25T17:53:28.025Z,1532541208.025 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.455184 min 
2018-07-25T17:53:28.025Z,1532541208.025 [Default:CheckIn:E] Stopped
2018-07-25T17:53:28.025Z,1532541208.025 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-25T17:53:28.025Z,1532541208.025 [Default:CheckIn] Stopped
2018-07-25T17:53:28.026Z,1532541208.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-25T17:53:28.026Z,1532541208.026 [Default:CheckIn](INFO): Running loop #2
2018-07-25T17:53:28.026Z,1532541208.026 [Default:CheckIn] Running Loop=2
2018-07-25T17:53:28.026Z,1532541208.026 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-25T17:53:28.026Z,1532541208.026 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-25T17:53:31.574Z,1532541211.574 [NAL9602](INFO): Powering up
2018-07-25T17:53:32.518Z,1532541212.518 [DataOverHttps](IMPORTANT): SBD MTMSN=20180725T175332
2018-07-25T17:53:32.521Z,1532541212.521 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003084
2018-07-25T17:53:42.721Z,1532541222.721 [DataOverHttps](INFO): Received command:restart logs