2019-01-22T23:38:49.639Z,1548200329.639 [Supervisor](DEBUG): Initializing supervisor.
2019-01-22T23:38:49.642Z,1548200329.642 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-01-22T23:38:49.643Z,1548200329.643 [SyncHandler](INFO): Protected caller Thread ID is 9309
2019-01-22T23:38:49.643Z,1548200329.643 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-01-22T23:38:49.644Z,1548200329.644 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-01-22T23:38:49.644Z,1548200329.644 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9310
2019-01-22T23:38:49.648Z,1548200329.648 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-01-22T23:38:49.660Z,1548200329.660 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-01-22T23:38:49.661Z,1548200329.661 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-01-22T23:38:49.662Z,1548200329.662 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9311
2019-01-22T23:38:49.663Z,1548200329.663 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-01-22T23:38:49.664Z,1548200329.664 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-01-22T23:38:49.664Z,1548200329.664 [logger ThreadHandler](INFO): Protected caller Thread ID is 9312
2019-01-22T23:38:49.666Z,1548200329.666 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-01-22T23:38:49.666Z,1548200329.666 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-01-22T23:38:49.668Z,1548200329.668 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-01-22T23:38:50.087Z,1548200330.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-01-22T23:38:50.088Z,1548200330.088 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-01-22T23:38:50.541Z,1548200330.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-01-22T23:38:50.542Z,1548200330.542 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-01-22T23:38:50.958Z,1548200330.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-01-22T23:38:50.959Z,1548200330.959 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-01-22T23:38:51.152Z,1548200331.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-01-22T23:38:51.152Z,1548200331.152 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-01-22T23:38:51.601Z,1548200331.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-01-22T23:38:51.602Z,1548200331.602 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-01-22T23:38:52.078Z,1548200332.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-01-22T23:38:52.079Z,1548200332.079 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-01-22T23:38:52.181Z,1548200332.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-01-22T23:38:52.182Z,1548200332.182 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-01-22T23:38:52.378Z,1548200332.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-01-22T23:38:52.379Z,1548200332.379 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-01-22T23:38:52.523Z,1548200332.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-01-22T23:38:52.524Z,1548200332.524 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-01-22T23:38:52.605Z,1548200332.605 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-01-22T23:38:52.851Z,1548200332.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-01-22T23:38:52.851Z,1548200332.851 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-01-22T23:38:52.981Z,1548200332.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-01-22T23:38:52.982Z,1548200332.982 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-01-22T23:38:53.173Z,1548200333.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-01-22T23:38:53.174Z,1548200333.174 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-01-22T23:38:53.457Z,1548200333.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-01-22T23:38:53.458Z,1548200333.458 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-01-22T23:38:53.780Z,1548200333.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-01-22T23:38:53.782Z,1548200333.782 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-01-22T23:38:53.783Z,1548200333.783 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-01-22T23:38:53.917Z,1548200333.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-01-22T23:38:54.143Z,1548200334.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-01-22T23:38:54.144Z,1548200334.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-01-22T23:38:54.314Z,1548200334.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-01-22T23:38:54.414Z,1548200334.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-01-22T23:38:54.511Z,1548200334.511 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-01-22T23:38:54.703Z,1548200334.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-01-22T23:38:54.788Z,1548200334.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-01-22T23:38:54.886Z,1548200334.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-01-22T23:38:54.979Z,1548200334.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-01-22T23:38:55.062Z,1548200335.062 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-01-22T23:38:55.170Z,1548200335.170 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-01-22T23:38:55.171Z,1548200335.171 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-01-22T23:38:55.175Z,1548200335.175 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-01-22T23:38:55.333Z,1548200335.333 [SBIT](DEBUG): Construct Startup Built In Test.
2019-01-22T23:38:55.362Z,1548200335.362 [SBIT] Loaded
2019-01-22T23:38:55.363Z,1548200335.363 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-01-22T23:38:55.363Z,1548200335.363 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-01-22T23:38:55.391Z,1548200335.391 [IBIT] Loaded
2019-01-22T23:38:55.391Z,1548200335.391 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-01-22T23:38:55.395Z,1548200335.395 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-01-22T23:38:55.781Z,1548200335.781 [CBIT] Loaded
2019-01-22T23:38:55.782Z,1548200335.782 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-01-22T23:38:55.782Z,1548200335.782 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-01-22T23:38:55.783Z,1548200335.783 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-01-22T23:38:55.902Z,1548200335.902 [BuoyancyServo] Loaded
2019-01-22T23:38:55.903Z,1548200335.903 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-01-22T23:38:55.917Z,1548200335.917 [ElevatorServo] Loaded
2019-01-22T23:38:55.918Z,1548200335.918 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-01-22T23:38:55.931Z,1548200335.931 [MassServo] Loaded
2019-01-22T23:38:55.932Z,1548200335.932 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-01-22T23:38:55.947Z,1548200335.947 [RudderServo] Loaded
2019-01-22T23:38:55.947Z,1548200335.947 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-01-22T23:38:55.962Z,1548200335.962 [ThrusterServo] Loaded
2019-01-22T23:38:55.962Z,1548200335.962 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-01-22T23:38:55.962Z,1548200335.962 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-01-22T23:38:55.963Z,1548200335.963 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-01-22T23:38:56.082Z,1548200336.082 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-01-22T23:38:56.083Z,1548200336.083 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-01-22T23:38:56.140Z,1548200336.140 [DeadReckonUsingSpeedCalculator] Loaded
2019-01-22T23:38:56.140Z,1548200336.140 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-01-22T23:38:56.157Z,1548200336.157 [NavChart] Loaded
2019-01-22T23:38:56.157Z,1548200336.157 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-01-22T23:38:56.161Z,1548200336.161 [UniversalFixResidualReporter] Loaded
2019-01-22T23:38:56.161Z,1548200336.161 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-01-22T23:38:56.162Z,1548200336.162 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-01-22T23:38:56.162Z,1548200336.162 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-01-22T23:38:56.185Z,1548200336.185 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-01-22T23:38:56.186Z,1548200336.186 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-01-22T23:38:56.461Z,1548200336.461 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-01-22T23:38:56.462Z,1548200336.462 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-01-22T23:38:57.093Z,1548200337.093 [AHRS_M2] Loaded
2019-01-22T23:38:57.093Z,1548200337.093 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-01-22T23:38:57.279Z,1548200337.279 [DataOverHttps] Loaded
2019-01-22T23:38:57.280Z,1548200337.280 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-01-22T23:38:57.293Z,1548200337.293 [Depth_Keller] Loaded
2019-01-22T23:38:57.293Z,1548200337.293 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-01-22T23:38:57.298Z,1548200337.298 [DropWeight] Loaded
2019-01-22T23:38:57.298Z,1548200337.298 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-01-22T23:38:57.387Z,1548200337.387 [NAL9602] Loaded
2019-01-22T23:38:57.388Z,1548200337.388 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-01-22T23:38:57.393Z,1548200337.393 [Onboard] Loaded
2019-01-22T23:38:57.393Z,1548200337.393 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-01-22T23:38:57.400Z,1548200337.400 [Radio_Surface] Loaded
2019-01-22T23:38:57.400Z,1548200337.400 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-01-22T23:38:57.401Z,1548200337.401 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408474E0
2019-01-22T23:38:57.402Z,1548200337.402 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9392
2019-01-22T23:38:57.429Z,1548200337.429 [RDI_Pathfinder] Loaded
2019-01-22T23:38:57.429Z,1548200337.429 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-01-22T23:38:57.451Z,1548200337.451 [RDI_PathfinderUp] Loaded
2019-01-22T23:38:57.451Z,1548200337.451 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-01-22T23:38:59.430Z,1548200339.430 [BPC1] Loaded
2019-01-22T23:38:59.430Z,1548200339.430 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-01-22T23:38:59.430Z,1548200339.430 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-01-22T23:38:59.431Z,1548200339.431 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-01-22T23:38:59.516Z,1548200339.516 [VerticalControl](DEBUG): Construct VerticalControl.
2019-01-22T23:38:59.603Z,1548200339.603 [VerticalControl] Loaded
2019-01-22T23:38:59.604Z,1548200339.604 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-01-22T23:38:59.604Z,1548200339.604 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-01-22T23:38:59.662Z,1548200339.662 [HorizontalControl] Loaded
2019-01-22T23:38:59.662Z,1548200339.662 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-01-22T23:38:59.663Z,1548200339.663 [SpeedControl](DEBUG): Construct SpeedControl.
2019-01-22T23:38:59.665Z,1548200339.665 [SpeedControl] Loaded
2019-01-22T23:38:59.665Z,1548200339.665 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-01-22T23:38:59.666Z,1548200339.666 [LoopControl](DEBUG): Construct LoopControl.
2019-01-22T23:38:59.666Z,1548200339.666 [LoopControl] Loaded
2019-01-22T23:38:59.667Z,1548200339.667 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-01-22T23:38:59.667Z,1548200339.667 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-01-22T23:38:59.668Z,1548200339.668 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-01-22T23:38:59.789Z,1548200339.789 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-01-22T23:38:59.790Z,1548200339.790 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-01-22T23:39:00.018Z,1548200340.018 [Aanderaa_O2] Loaded
2019-01-22T23:39:00.018Z,1548200340.018 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-01-22T23:39:00.102Z,1548200340.102 [CTD_NeilBrown] Loaded
2019-01-22T23:39:00.102Z,1548200340.102 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-01-22T23:39:00.103Z,1548200340.103 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409534E0
2019-01-22T23:39:00.104Z,1548200340.104 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9393
2019-01-22T23:39:00.148Z,1548200340.148 [WetLabsSeaOWL_UV_A] Loaded
2019-01-22T23:39:00.148Z,1548200340.148 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-01-22T23:39:00.149Z,1548200340.149 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409834E0
2019-01-22T23:39:00.150Z,1548200340.150 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 9394
2019-01-22T23:39:00.150Z,1548200340.150 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-01-22T23:39:00.151Z,1548200340.151 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-01-22T23:39:00.252Z,1548200340.252 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-01-22T23:39:00.253Z,1548200340.253 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-01-22T23:39:00.308Z,1548200340.308 [DepthRateCalculator] Loaded
2019-01-22T23:39:00.308Z,1548200340.308 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-01-22T23:39:00.313Z,1548200340.313 [PitchRateCalculator] Loaded
2019-01-22T23:39:00.314Z,1548200340.314 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-01-22T23:39:00.326Z,1548200340.326 [SpeedCalculator] Loaded
2019-01-22T23:39:00.326Z,1548200340.326 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-01-22T23:39:00.348Z,1548200340.348 [TempGradientCalculator] Loaded
2019-01-22T23:39:00.348Z,1548200340.348 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-01-22T23:39:00.353Z,1548200340.353 [YawRateCalculator] Loaded
2019-01-22T23:39:00.353Z,1548200340.353 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-01-22T23:39:00.383Z,1548200340.383 [ElevatorOffsetCalculator] Loaded
2019-01-22T23:39:00.383Z,1548200340.383 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-01-22T23:39:00.384Z,1548200340.384 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-01-22T23:39:00.385Z,1548200340.385 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-01-22T23:39:00.425Z,1548200340.425 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-01-22T23:39:00.428Z,1548200340.428 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-01-22T23:39:00.430Z,1548200340.430 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-01-22T23:39:00.436Z,1548200340.436 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-01-22T23:39:00.437Z,1548200340.437 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A3F4E0
2019-01-22T23:39:00.438Z,1548200340.438 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9395
2019-01-22T23:39:00.443Z,1548200340.443 [Supervisor](INFO): Main Thread ID is 9308
2019-01-22T23:39:00.443Z,1548200340.443 [Supervisor](DEBUG): Running supervisor.
2019-01-22T23:39:00.443Z,1548200340.443 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9396
2019-01-22T23:39:00.446Z,1548200340.446 [controlThread ThreadHandler](INFO): Handler Thread ID is 9397
2019-01-22T23:39:00.447Z,1548200340.447 [controlThread](DEBUG): Initializing ControlThread
2019-01-22T23:39:00.447Z,1548200340.447 [SBIT](INFO): Initialize SBIT Component.
2019-01-22T23:39:00.448Z,1548200340.448 [SBIT](IMPORTANT): git: 2019-01-14A-1-gbf16392
2019-01-22T23:39:00.448Z,1548200340.448 [SBIT](INFO): git hash: bf16392e469ce9bf3416e817fec750860d5875a3
2019-01-22T23:39:00.448Z,1548200340.448 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-01-22T23:39:00.448Z,1548200340.448 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-01-22T23:39:00.450Z,1548200340.450 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-01-22T23:39:00.450Z,1548200340.450 [IBIT](INFO): Initialize IBIT Component.
2019-01-22T23:39:00.451Z,1548200340.451 [CBIT](DEBUG): Initialize CBIT Component.
2019-01-22T23:39:00.452Z,1548200340.452 [logger ThreadHandler](INFO): Handler Thread ID is 9398
2019-01-22T23:39:00.465Z,1548200340.465 [CBIT](DEBUG): Initialized mux pins.
2019-01-22T23:39:00.465Z,1548200340.465 [CBIT](DEBUG): Initializing the watchdog timer.
2019-01-22T23:39:00.477Z,1548200340.477 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9399
2019-01-22T23:39:00.483Z,1548200340.483 [Radio_Surface](INFO): Powering up
2019-01-22T23:39:00.489Z,1548200340.489 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-01-22T23:39:00.489Z,1548200340.489 [CBIT](DEBUG): Initializing heartbeat.
2019-01-22T23:39:00.490Z,1548200340.490 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9400
2019-01-22T23:39:00.491Z,1548200340.491 [CTD_NeilBrown](INFO): Powering down
2019-01-22T23:39:00.517Z,1548200340.517 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 9401
2019-01-22T23:39:00.518Z,1548200340.518 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-01-22T23:39:00.549Z,1548200340.549 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9402
2019-01-22T23:39:00.556Z,1548200340.556 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-01-22T23:39:00.557Z,1548200340.557 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-01-22T23:39:00.557Z,1548200340.557 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-01-22T23:39:00.557Z,1548200340.557 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-01-22T23:39:00.557Z,1548200340.557 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-01-22T23:39:00.557Z,1548200340.557 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-01-22T23:39:00.558Z,1548200340.558 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-01-22T23:39:00.558Z,1548200340.558 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-01-22T23:39:00.558Z,1548200340.558 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-01-22T23:39:00.558Z,1548200340.558 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-01-22T23:39:00.558Z,1548200340.558 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-01-22T23:39:00.559Z,1548200340.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-01-22T23:39:00.559Z,1548200340.559 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-01-22T23:39:00.559Z,1548200340.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-01-22T23:39:00.559Z,1548200340.559 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-01-22T23:39:00.559Z,1548200340.559 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-01-22T23:39:00.561Z,1548200340.561 [CBIT](DEBUG): Deactivating GF circuits.
2019-01-22T23:39:00.561Z,1548200340.561 [CBIT](DEBUG): Deactivating emergency mode.
2019-01-22T23:39:00.598Z,1548200340.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-22T23:39:00.599Z,1548200340.599 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:39:00.599Z,1548200340.599 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:39:00.600Z,1548200340.600 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T23:39:00.600Z,1548200340.600 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:39:00.601Z,1548200340.601 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:39:00.601Z,1548200340.601 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-01-22T23:39:00.601Z,1548200340.601 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-01-22T23:39:00.608Z,1548200340.608 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-01-22T23:39:00.614Z,1548200340.614 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-01-22T23:39:00.614Z,1548200340.614 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-01-22T23:39:00.615Z,1548200340.615 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-01-22T23:39:00.615Z,1548200340.615 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-01-22T23:39:00.616Z,1548200340.616 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-01-22T23:39:00.616Z,1548200340.616 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-01-22T23:39:00.616Z,1548200340.616 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-01-22T23:39:00.625Z,1548200340.625 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-01-22T23:39:00.626Z,1548200340.626 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-01-22T23:39:00.627Z,1548200340.627 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-01-22T23:39:00.671Z,1548200340.671 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-22T23:39:00.689Z,1548200340.689 [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>


2019-01-22T23:39:00.690Z,1548200340.690 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-01-22T23:39:00.776Z,1548200340.776 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-01-22T23:39:00.777Z,1548200340.777 [Default:A.Wait](DEBUG): Construct Wait.
2019-01-22T23:39:00.779Z,1548200340.779 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-22T23:39:00.820Z,1548200340.820 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-01-22T23:39:00.822Z,1548200340.822 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-01-22T23:39:00.856Z,1548200340.856 [Default:E.Execute](DEBUG): Construct Execute.
2019-01-22T23:39:00.859Z,1548200340.859 [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 on</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>


2019-01-22T23:39:00.873Z,1548200340.873 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-01-22T23:39:00.897Z,1548200340.897 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-22T23:39:01.051Z,1548200341.051 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-01-22T23:39:01.065Z,1548200341.065 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-01-22T23:39:01.255Z,1548200341.255 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-22T23:39:01.486Z,1548200341.486 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-22T23:39:01.493Z,1548200341.493 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-22T23:39:01.507Z,1548200341.507 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-01-22T23:39:01.513Z,1548200341.513 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-01-22T23:39:01.527Z,1548200341.527 [MassServo](DEBUG): Initializing EZServoServo.
2019-01-22T23:39:01.533Z,1548200341.533 [MassServo](DEBUG): Initializing MassServo.
2019-01-22T23:39:01.554Z,1548200341.554 [RudderServo](DEBUG): Initializing EZServoServo.
2019-01-22T23:39:01.561Z,1548200341.561 [RudderServo](DEBUG): Initializing RudderServo.
2019-01-22T23:39:01.575Z,1548200341.575 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-01-22T23:39:01.581Z,1548200341.581 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-01-22T23:39:01.598Z,1548200341.598 [CommandLine](FAULT): Scheduling is paused
2019-01-22T23:39:01.598Z,1548200341.598 [CBIT](INFO): Critical error at 20190122T233900
2019-01-22T23:39:01.599Z,1548200341.599 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-01-22T23:39:01.937Z,1548200341.937 [RDI_PathfinderUp](INFO): Powering down
2019-01-22T23:39:02.333Z,1548200342.333 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-01-22T23:39:02.333Z,1548200342.333 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-01-22T23:39:02.333Z,1548200342.333 [BuoyancyServo] Communications Fault, FailCount= 1
2019-01-22T23:39:02.333Z,1548200342.333 [BuoyancyServo](ERROR): Communications Fault
2019-01-22T23:39:02.553Z,1548200342.553 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-01-22T23:39:11.701Z,1548200351.701 [Aanderaa_O2](INFO): Powering down
2019-01-22T23:39:11.946Z,1548200351.946 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004334
2019-01-22T23:39:13.210Z,1548200353.210 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-01-22T23:39:13.210Z,1548200353.210 [BuoyancyServo] No Fault, FailCount= 1
2019-01-22T23:39:13.592Z,1548200353.592 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-22T23:39:13.710Z,1548200353.710 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-22T23:39:14.365Z,1548200354.365 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-01-22T23:39:27.543Z,1548200367.543 [NAL9602](INFO): Powering up NAL9602
2019-01-22T23:39:29.174Z,1548200369.174 [SBIT](IMPORTANT): Beginning Startup BIT
2019-01-22T23:39:29.186Z,1548200369.186 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-22T23:39:43.447Z,1548200383.447 [NAL9602](INFO): NAL9602 initialized
2019-01-22T23:39:44.261Z,1548200384.261 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:39:45.862Z,1548200385.862 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.010262
CHAN A1 (24V): -0.196501
CHAN A2 (12V): -0.007103
CHAN A3 (5V): -0.002104
CHAN B0 (3.3V): 0.000428
CHAN B1 (3.15aV): 0.000125
CHAN B2 (3.15bV): 0.000049
CHAN B3 (GND): 0.001409
OPEN: 0.005036
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-22T23:39:59.341Z,1548200399.341 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8d,0ffe, 15.813, -0.014, 0.000
2019-01-22T23:40:23.319Z,1548200423.319 [SBIT](IMPORTANT): SBIT PASSED
2019-01-22T23:40:23.394Z,1548200423.394 [CommandLine](IMPORTANT): got command configSet list
2019-01-22T23:40:23.394Z,1548200423.394 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-01-22T23:40:23.395Z,1548200423.395 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool;

2019-01-22T23:40:23.727Z,1548200423.727 [MissionManager](IMPORTANT): Started mission Startup
2019-01-22T23:40:23.727Z,1548200423.727 [Startup] Running Loop=1
2019-01-22T23:40:23.727Z,1548200423.727 [Startup](DEBUG): Aggregate::initialize Startup
2019-01-22T23:40:23.727Z,1548200423.727 [Startup:A.GoToSurface] Running Loop=1
2019-01-22T23:40:23.727Z,1548200423.727 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-22T23:40:23.728Z,1548200423.728 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-22T23:40:23.733Z,1548200423.733 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-22T23:40:23.734Z,1548200423.734 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-22T23:40:23.734Z,1548200423.734 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-22T23:40:23.734Z,1548200423.734 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-22T23:40:23.745Z,1548200423.745 [Startup:StartupSatComms] Running Loop=1
2019-01-22T23:40:23.745Z,1548200423.745 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-01-22T23:40:23.746Z,1548200423.746 [Startup:StartupSatComms:A] Running Loop=1
2019-01-22T23:40:24.116Z,1548200424.116 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-01-22T23:41:26.126Z,1548200486.126 [Startup:StartupSatComms:A](INFO): Timed out from 2019-01-22T23:40:23.7Z
2019-01-22T23:41:26.126Z,1548200486.126 [Startup:StartupSatComms:A] Stopped
2019-01-22T23:41:26.126Z,1548200486.126 [Startup:StartupSatComms:B] Running Loop=1
2019-01-22T23:41:26.283Z,1548200486.283 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-01-22T23:41:32.627Z,1548200492.627 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20190121T092745/Courier0631.lzma
2019-01-22T23:41:33.377Z,1548200493.377 [DataOverHttps](INFO): Moved sent file to Logs/20190121T092745/Courier0631.lzma.bak
2019-01-22T23:41:33.378Z,1548200493.378 [DataOverHttps](INFO): SBD MOMSN=9594691
2019-01-22T23:41:44.156Z,1548200504.156 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20190122T233849/Courier0000.lzma
2019-01-22T23:41:44.930Z,1548200504.930 [DataOverHttps](INFO): Moved sent file to Logs/20190122T233849/Courier0000.lzma.bak
2019-01-22T23:41:44.931Z,1548200504.931 [DataOverHttps](INFO): SBD MOMSN=9594694
2019-01-22T23:41:55.492Z,1548200515.492 [DataOverHttps](INFO): Sending 814 bytes from file Logs/20190121T092745/Express0632.lzma
2019-01-22T23:41:56.249Z,1548200516.249 [DataOverHttps](INFO): Moved sent file to Logs/20190121T092745/Express0632.lzma.bak
2019-01-22T23:41:56.249Z,1548200516.249 [DataOverHttps](INFO): SBD MOMSN=9594698
2019-01-22T23:42:01.749Z,1548200521.749 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000038.018269
2019-01-22T23:42:01.749Z,1548200521.749 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-01-22T23:42:01.750Z,1548200521.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-22T23:42:01.751Z,1548200521.751 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000168.971426
2019-01-22T23:42:01.751Z,1548200521.751 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-01-22T23:42:01.751Z,1548200521.751 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T23:42:01.784Z,1548200521.784 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-22T23:42:01.784Z,1548200521.784 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T23:42:01.913Z,1548200521.913 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-22T23:42:01.913Z,1548200521.913 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-01-22T23:42:01.914Z,1548200521.914 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T23:42:01.914Z,1548200521.914 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-01-22T23:42:02.283Z,1548200522.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-22T23:42:02.283Z,1548200522.283 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:42:02.284Z,1548200522.284 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:42:02.284Z,1548200522.284 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T23:42:02.285Z,1548200522.285 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:42:02.285Z,1548200522.285 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:42:07.597Z,1548200527.597 [DataOverHttps](INFO): Sending 713 bytes from file Logs/20190122T233849/Express0001.lzma
2019-01-22T23:42:08.303Z,1548200528.303 [DataOverHttps](INFO): Moved sent file to Logs/20190122T233849/Express0001.lzma.bak
2019-01-22T23:42:08.303Z,1548200528.303 [DataOverHttps](INFO): SBD MOMSN=9594733
2019-01-22T23:42:10.465Z,1548200530.465 [Startup:StartupSatComms:B] Stopped
2019-01-22T23:42:10.466Z,1548200530.466 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-01-22T23:42:10.466Z,1548200530.466 [Startup:StartupSatComms] Stopped
2019-01-22T23:42:10.466Z,1548200530.466 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-01-22T23:42:10.467Z,1548200530.467 [Startup](INFO): Completed Startup
2019-01-22T23:42:10.467Z,1548200530.467 [MissionManager](INFO): Startup is completed.
2019-01-22T23:42:10.467Z,1548200530.467 [MissionManager](INFO): Uninitializing Mission Startup
2019-01-22T23:42:10.467Z,1548200530.467 [Startup] Stopped
2019-01-22T23:42:10.467Z,1548200530.467 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-01-22T23:42:10.467Z,1548200530.467 [Startup:A.GoToSurface] Stopped
2019-01-22T23:42:10.467Z,1548200530.467 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-22T23:42:10.594Z,1548200530.594 [MissionManager](IMPORTANT): Started mission Default
2019-01-22T23:42:10.594Z,1548200530.594 [Default] Running Loop=1
2019-01-22T23:42:10.594Z,1548200530.594 [Default](DEBUG): Aggregate::initialize Default
2019-01-22T23:42:10.594Z,1548200530.594 [Default:B.GoToSurface] Running Loop=1
2019-01-22T23:42:10.594Z,1548200530.594 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-22T23:42:10.594Z,1548200530.594 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-22T23:42:10.595Z,1548200530.595 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-22T23:42:10.595Z,1548200530.595 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-22T23:42:10.595Z,1548200530.595 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-22T23:42:10.596Z,1548200530.596 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-22T23:42:10.596Z,1548200530.596 [Default:A.Wait] Running Loop=1
2019-01-22T23:42:10.596Z,1548200530.596 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-01-22T23:42:23.903Z,1548200543.903 [Default:A.Wait](INFO): Done Waiting.
2019-01-22T23:42:23.904Z,1548200543.904 [Default:A.Wait] Stopped
2019-01-22T23:42:23.904Z,1548200543.904 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-01-22T23:42:24.316Z,1548200544.316 [Default:CheckIn] Running Loop=1
2019-01-22T23:42:24.317Z,1548200544.317 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-22T23:42:24.317Z,1548200544.317 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-22T23:42:24.694Z,1548200544.694 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-01-22T23:44:46.815Z,1548200686.815 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-01-22T23:44:47.629Z,1548200687.629 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:44:50.829Z,1548200690.829 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:44:53.638Z,1548200693.638 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:44:56.829Z,1548200696.829 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:44:59.633Z,1548200699.633 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:02.442Z,1548200702.442 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000075.936561
2019-01-22T23:45:02.442Z,1548200702.442 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-01-22T23:45:02.442Z,1548200702.442 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-22T23:45:02.444Z,1548200702.444 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000349.663801
2019-01-22T23:45:02.444Z,1548200702.444 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-01-22T23:45:02.444Z,1548200702.444 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T23:45:02.489Z,1548200702.489 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-22T23:45:02.489Z,1548200702.489 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T23:45:02.833Z,1548200702.833 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:02.889Z,1548200702.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-22T23:45:02.889Z,1548200702.889 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-01-22T23:45:02.890Z,1548200702.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T23:45:02.890Z,1548200702.890 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-01-22T23:45:03.278Z,1548200703.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-22T23:45:03.278Z,1548200703.279 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:45:03.279Z,1548200703.279 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:45:03.279Z,1548200703.279 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T23:45:03.280Z,1548200703.280 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:45:03.280Z,1548200703.280 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:45:05.637Z,1548200705.637 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:11.329Z,1548200711.329 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:13.728Z,1548200713.728 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:16.917Z,1548200716.917 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:19.721Z,1548200719.721 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:22.917Z,1548200722.917 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:25.724Z,1548200725.724 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:28.917Z,1548200728.917 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:31.717Z,1548200731.717 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:34.925Z,1548200734.925 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:37.717Z,1548200737.717 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:40.917Z,1548200740.917 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:43.717Z,1548200743.717 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:48.685Z,1548200748.685 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:51.885Z,1548200751.885 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:54.681Z,1548200754.681 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:45:57.881Z,1548200757.881 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:00.681Z,1548200760.681 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:03.885Z,1548200763.885 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:06.688Z,1548200766.688 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:09.885Z,1548200769.885 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:12.681Z,1548200772.681 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:15.881Z,1548200775.881 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:21.193Z,1548200781.193 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:23.593Z,1548200783.593 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:26.785Z,1548200786.785 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:27.632Z,1548200787.632 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-01-22T23:46:29.585Z,1548200789.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:32.793Z,1548200792.793 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:35.585Z,1548200795.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:38.785Z,1548200798.785 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:41.597Z,1548200801.597 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:44.785Z,1548200804.785 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:47.596Z,1548200807.596 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:50.785Z,1548200810.785 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:53.585Z,1548200813.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:46:58.841Z,1548200818.841 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:01.657Z,1548200821.657 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:04.845Z,1548200824.845 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:07.657Z,1548200827.657 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:10.845Z,1548200830.845 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:13.665Z,1548200833.665 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:16.845Z,1548200836.845 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:19.657Z,1548200839.657 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:22.842Z,1548200842.842 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:24.501Z,1548200844.501 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-01-22T23:42:24.3Z
2019-01-22T23:47:24.501Z,1548200844.501 [Default:CheckIn:Read_GPS] Stopped
2019-01-22T23:47:24.501Z,1548200844.501 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-01-22T23:47:24.890Z,1548200844.890 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-01-22T23:47:27.653Z,1548200847.653 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:31.780Z,1548200851.780 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20190122T233849/Courier0004.lzma
2019-01-22T23:47:32.539Z,1548200852.539 [DataOverHttps](INFO): Moved sent file to Logs/20190122T233849/Courier0004.lzma.bak
2019-01-22T23:47:32.539Z,1548200852.539 [DataOverHttps](INFO): SBD MOMSN=9594755
2019-01-22T23:47:39.833Z,1548200859.833 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:45.625Z,1548200865.625 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:45.766Z,1548200865.766 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20190122T233849/Express0005.lzma
2019-01-22T23:47:46.539Z,1548200866.539 [DataOverHttps](INFO): Moved sent file to Logs/20190122T233849/Express0005.lzma.bak
2019-01-22T23:47:46.539Z,1548200866.539 [DataOverHttps](INFO): SBD MOMSN=9594757
2019-01-22T23:47:48.678Z,1548200868.678 [Default:CheckIn:Read_Iridium] Stopped
2019-01-22T23:47:48.678Z,1548200868.678 [Default:CheckIn:C.Wait] Running Loop=1
2019-01-22T23:47:48.678Z,1548200868.678 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-01-22T23:47:52.589Z,1548200872.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:54.740Z,1548200874.740 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:57.549Z,1548200877.549 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:47:59.537Z,1548200879.537 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:01.541Z,1548200881.541 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:03.545Z,1548200883.545 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:03.586Z,1548200883.586 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000113.389081
2019-01-22T23:48:03.587Z,1548200883.587 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-01-22T23:48:03.587Z,1548200883.587 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-22T23:48:03.589Z,1548200883.589 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000530.808782
2019-01-22T23:48:03.589Z,1548200883.589 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-01-22T23:48:03.589Z,1548200883.589 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T23:48:03.605Z,1548200883.605 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-22T23:48:03.605Z,1548200883.605 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T23:48:04.005Z,1548200884.005 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-22T23:48:04.006Z,1548200884.006 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-01-22T23:48:04.006Z,1548200884.006 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T23:48:04.006Z,1548200884.006 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-01-22T23:48:04.373Z,1548200884.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-22T23:48:04.373Z,1548200884.373 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:48:04.374Z,1548200884.374 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:48:04.374Z,1548200884.374 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T23:48:04.374Z,1548200884.374 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:48:04.375Z,1548200884.375 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:48:05.537Z,1548200885.537 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:07.541Z,1548200887.541 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:10.737Z,1548200890.737 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:13.537Z,1548200893.537 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:15.541Z,1548200895.541 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:17.537Z,1548200897.537 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:19.541Z,1548200899.541 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:21.533Z,1548200901.533 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:23.537Z,1548200903.537 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:28.317Z,1548200908.317 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:30.833Z,1548200910.833 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:33.649Z,1548200913.649 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:36.833Z,1548200916.833 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:39.633Z,1548200919.633 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:42.829Z,1548200922.829 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:45.633Z,1548200925.633 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:48.825Z,1548200928.825 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:51.629Z,1548200931.629 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:54.825Z,1548200934.825 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:48:57.633Z,1548200937.633 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:03.581Z,1548200943.581 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:05.701Z,1548200945.701 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:08.497Z,1548200948.497 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:10.901Z,1548200950.901 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:13.705Z,1548200953.705 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:16.897Z,1548200956.897 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:19.706Z,1548200959.706 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:22.901Z,1548200962.901 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:25.697Z,1548200965.697 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:28.901Z,1548200968.901 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:31.697Z,1548200971.697 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:34.905Z,1548200974.905 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:39.796Z,1548200979.796 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:42.593Z,1548200982.593 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:49:45.779Z,1548200985.779 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-01-22T23:49:45.779Z,1548200985.779 [NAL9602] Data Fault, FailCount= 1
2019-01-22T23:49:45.779Z,1548200985.779 [NAL9602](ERROR): Data Fault
2019-01-22T23:49:45.836Z,1548200985.836 [CBIT](ERROR): Data Fault in component: NAL9602
2019-01-22T23:49:46.178Z,1548200986.178 [NAL9602](INFO): Powering down
2019-01-22T23:49:47.038Z,1548200987.038 [CBIT](INFO): Clearing failed state for component NAL9602
2019-01-22T23:49:47.038Z,1548200987.038 [NAL9602] No Fault, FailCount= 1
2019-01-22T23:50:16.238Z,1548201016.238 [NAL9602](INFO): Powering up NAL9602
2019-01-22T23:50:27.449Z,1548201027.449 [NAL9602](INFO): NAL9602 initialized
2019-01-22T23:50:28.273Z,1548201028.273 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:30.653Z,1548201030.653 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:33.850Z,1548201033.850 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:36.657Z,1548201036.657 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:38.678Z,1548201038.678 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for BIT error
2019-01-22T23:50:38.678Z,1548201038.678 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19012216430168,35.0, -0.1, 
2019-01-22T23:50:39.865Z,1548201039.865 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:42.669Z,1548201042.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:48.637Z,1548201048.637 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:50.769Z,1548201050.769 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:53.565Z,1548201053.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:55.565Z,1548201055.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:57.565Z,1548201057.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:50:59.565Z,1548201059.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:01.565Z,1548201061.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:03.565Z,1548201063.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:04.411Z,1548201064.411 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000150.539823
2019-01-22T23:51:04.411Z,1548201064.411 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-01-22T23:51:04.411Z,1548201064.411 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-22T23:51:04.413Z,1548201064.413 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000711.632704
2019-01-22T23:51:04.413Z,1548201064.413 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-01-22T23:51:04.413Z,1548201064.413 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T23:51:04.425Z,1548201064.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-22T23:51:04.426Z,1548201064.426 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T23:51:04.809Z,1548201064.809 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-22T23:51:04.809Z,1548201064.809 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-01-22T23:51:04.810Z,1548201064.810 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T23:51:04.810Z,1548201064.810 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-01-22T23:51:05.193Z,1548201065.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-22T23:51:05.193Z,1548201065.193 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:51:05.194Z,1548201065.194 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:51:05.194Z,1548201065.194 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T23:51:05.195Z,1548201065.195 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:51:05.195Z,1548201065.195 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:51:05.565Z,1548201065.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:07.565Z,1548201067.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:09.569Z,1548201069.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:11.565Z,1548201071.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:13.569Z,1548201073.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:15.561Z,1548201075.561 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:17.565Z,1548201077.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:19.573Z,1548201079.573 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:21.569Z,1548201081.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:26.865Z,1548201086.865 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:29.673Z,1548201089.673 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:32.873Z,1548201092.873 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:35.669Z,1548201095.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:38.865Z,1548201098.865 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:41.669Z,1548201101.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:44.873Z,1548201104.873 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:47.677Z,1548201107.677 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:50.865Z,1548201110.865 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:53.661Z,1548201113.661 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:51:59.385Z,1548201119.385 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:00.578Z,1548201120.578 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-01-22T23:52:01.769Z,1548201121.769 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:04.573Z,1548201124.573 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:06.573Z,1548201126.573 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:08.573Z,1548201128.573 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:10.573Z,1548201130.573 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:12.569Z,1548201132.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:14.609Z,1548201134.609 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:16.569Z,1548201136.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:18.573Z,1548201138.573 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:20.581Z,1548201140.581 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:22.585Z,1548201142.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:24.573Z,1548201144.573 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:26.577Z,1548201146.577 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:28.585Z,1548201148.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:30.569Z,1548201150.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:32.573Z,1548201152.573 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:37.869Z,1548201157.869 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:40.665Z,1548201160.665 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:43.869Z,1548201163.869 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:46.665Z,1548201166.665 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:49.079Z,1548201169.079 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-01-22T23:52:49.080Z,1548201169.080 [Default:CheckIn:C.Wait] Stopped
2019-01-22T23:52:49.080Z,1548201169.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-01-22T23:52:49.080Z,1548201169.080 [Default:CheckIn:D] Running Loop=1
2019-01-22T23:52:49.509Z,1548201169.509 [Default:CheckIn:D] Stopped
2019-01-22T23:52:49.509Z,1548201169.509 [Default:CheckIn:E] Running Loop=1
2019-01-22T23:52:49.865Z,1548201169.865 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:49.896Z,1548201169.896 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.648364 min 
2019-01-22T23:52:49.902Z,1548201169.902 [Default:CheckIn:E] Stopped
2019-01-22T23:52:49.902Z,1548201169.902 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-01-22T23:52:49.902Z,1548201169.902 [Default:CheckIn] Stopped
2019-01-22T23:52:49.902Z,1548201169.902 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-22T23:52:49.903Z,1548201169.903 [Default:CheckIn](INFO): Running loop #2
2019-01-22T23:52:49.903Z,1548201169.903 [Default:CheckIn] Running Loop=2
2019-01-22T23:52:49.903Z,1548201169.903 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-22T23:52:49.903Z,1548201169.903 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-22T23:52:52.665Z,1548201172.665 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:55.865Z,1548201175.865 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:52:58.665Z,1548201178.665 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:01.865Z,1548201181.865 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:04.669Z,1548201184.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:10.633Z,1548201190.633 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:12.729Z,1548201192.729 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:15.929Z,1548201195.929 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:18.741Z,1548201198.741 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:21.929Z,1548201201.929 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:24.733Z,1548201204.733 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:27.929Z,1548201207.929 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:30.741Z,1548201210.741 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:33.929Z,1548201213.929 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:36.725Z,1548201216.725 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:39.929Z,1548201219.929 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:45.241Z,1548201225.241 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:47.585Z,1548201227.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:49.593Z,1548201229.593 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:51.605Z,1548201231.605 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:53.581Z,1548201233.581 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:55.601Z,1548201235.601 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:57.585Z,1548201237.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:53:59.585Z,1548201239.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:01.593Z,1548201241.593 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:03.597Z,1548201243.597 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:05.196Z,1548201245.196 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000188.303731
2019-01-22T23:54:05.196Z,1548201245.196 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-01-22T23:54:05.197Z,1548201245.197 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-22T23:54:05.199Z,1548201245.199 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000892.418724
2019-01-22T23:54:05.199Z,1548201245.199 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-01-22T23:54:05.199Z,1548201245.199 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T23:54:05.235Z,1548201245.235 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-22T23:54:05.235Z,1548201245.235 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T23:54:05.585Z,1548201245.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:05.640Z,1548201245.640 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-22T23:54:05.640Z,1548201245.640 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-01-22T23:54:05.640Z,1548201245.640 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T23:54:05.641Z,1548201245.641 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-01-22T23:54:06.026Z,1548201246.026 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-22T23:54:06.027Z,1548201246.027 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:54:06.027Z,1548201246.027 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:54:06.027Z,1548201246.027 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T23:54:06.028Z,1548201246.028 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:54:06.028Z,1548201246.028 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:54:07.597Z,1548201247.597 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:09.593Z,1548201249.593 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:11.589Z,1548201251.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:13.589Z,1548201253.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:15.589Z,1548201255.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:20.349Z,1548201260.349 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:22.869Z,1548201262.869 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:25.669Z,1548201265.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:28.869Z,1548201268.869 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:31.669Z,1548201271.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:34.865Z,1548201274.865 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:37.669Z,1548201277.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:40.873Z,1548201280.873 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:43.669Z,1548201283.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:46.873Z,1548201286.873 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:49.669Z,1548201289.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:55.653Z,1548201295.653 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:54:57.789Z,1548201297.789 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:00.589Z,1548201300.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:02.601Z,1548201302.601 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:04.589Z,1548201304.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:06.593Z,1548201306.593 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:08.592Z,1548201308.592 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:10.593Z,1548201310.593 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:12.605Z,1548201312.605 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:14.589Z,1548201314.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:16.589Z,1548201316.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:18.593Z,1548201318.593 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:20.589Z,1548201320.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:22.594Z,1548201322.594 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:24.589Z,1548201324.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:26.589Z,1548201326.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:31.101Z,1548201331.101 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:33.885Z,1548201333.885 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:36.673Z,1548201336.673 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:39.877Z,1548201339.877 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:42.673Z,1548201342.673 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:44.691Z,1548201344.691 [RDI_Pathfinder](ERROR): Failed to parse:
:B  +0.00,        +0.00,        +0.00,   0.00,989.96
2019-01-22T23:55:45.877Z,1548201345.877 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:48.673Z,1548201348.673 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:51.873Z,1548201351.873 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:54.677Z,1548201354.677 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:55:57.873Z,1548201357.873 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:00.669Z,1548201360.669 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:06.409Z,1548201366.409 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:08.785Z,1548201368.785 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:11.585Z,1548201371.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:13.585Z,1548201373.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:15.589Z,1548201375.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:17.585Z,1548201377.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:19.585Z,1548201379.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:21.585Z,1548201381.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:23.589Z,1548201383.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:25.589Z,1548201385.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:27.585Z,1548201387.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:29.585Z,1548201389.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:31.585Z,1548201391.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:33.585Z,1548201393.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:35.585Z,1548201395.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:37.585Z,1548201397.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:42.369Z,1548201402.369 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:44.889Z,1548201404.889 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:47.697Z,1548201407.697 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:50.889Z,1548201410.889 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:53.693Z,1548201413.693 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:56.893Z,1548201416.893 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:56:59.693Z,1548201419.693 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:02.901Z,1548201422.901 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:05.689Z,1548201425.689 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:06.125Z,1548201426.125 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000225.572857
2019-01-22T23:57:06.125Z,1548201426.125 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-01-22T23:57:06.126Z,1548201426.126 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-22T23:57:06.127Z,1548201426.127 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001073.347440
2019-01-22T23:57:06.127Z,1548201426.127 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-01-22T23:57:06.127Z,1548201426.127 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-22T23:57:06.143Z,1548201426.143 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-22T23:57:06.144Z,1548201426.144 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-22T23:57:06.565Z,1548201426.565 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-22T23:57:06.565Z,1548201426.565 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-01-22T23:57:06.566Z,1548201426.566 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-22T23:57:06.566Z,1548201426.566 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-01-22T23:57:06.901Z,1548201426.901 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-22T23:57:06.901Z,1548201426.901 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:57:06.902Z,1548201426.902 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:57:06.902Z,1548201426.902 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-22T23:57:06.902Z,1548201426.902 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-22T23:57:06.903Z,1548201426.903 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-22T23:57:08.885Z,1548201428.885 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:11.689Z,1548201431.689 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:17.393Z,1548201437.393 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:19.785Z,1548201439.785 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:22.597Z,1548201442.597 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:24.589Z,1548201444.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:26.585Z,1548201446.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:28.589Z,1548201448.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:30.589Z,1548201450.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:32.581Z,1548201452.581 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:34.585Z,1548201454.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:36.585Z,1548201456.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:38.589Z,1548201458.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:40.589Z,1548201460.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:42.589Z,1548201462.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:44.585Z,1548201464.585 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:46.589Z,1548201466.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:48.589Z,1548201468.589 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:52.996Z,1548201472.996 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-01-22T23:52:49.9Z
2019-01-22T23:57:52.996Z,1548201472.996 [Default:CheckIn:Read_GPS] Stopped
2019-01-22T23:57:52.996Z,1548201472.996 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-01-22T23:57:53.101Z,1548201473.101 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:59.661Z,1548201479.661 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:57:59.778Z,1548201479.778 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190122T233849/Courier0007.lzma
2019-01-22T23:58:00.543Z,1548201480.543 [DataOverHttps](INFO): Moved sent file to Logs/20190122T233849/Courier0007.lzma.bak
2019-01-22T23:58:00.543Z,1548201480.543 [DataOverHttps](INFO): SBD MOMSN=9594777
2019-01-22T23:58:05.905Z,1548201485.905 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:11.618Z,1548201491.618 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:11.744Z,1548201491.744 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20190122T233849/Express0008.lzma
2019-01-22T23:58:12.507Z,1548201492.507 [DataOverHttps](INFO): Moved sent file to Logs/20190122T233849/Express0008.lzma.bak
2019-01-22T23:58:12.507Z,1548201492.507 [DataOverHttps](INFO): SBD MOMSN=9594779
2019-01-22T23:58:14.648Z,1548201494.648 [Default:CheckIn:Read_Iridium] Stopped
2019-01-22T23:58:14.649Z,1548201494.649 [Default:CheckIn:C.Wait] Running Loop=1
2019-01-22T23:58:14.649Z,1548201494.649 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-01-22T23:58:15.093Z,1548201495.093 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:20.805Z,1548201500.805 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:23.605Z,1548201503.605 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:25.605Z,1548201505.605 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:28.809Z,1548201508.809 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:31.601Z,1548201511.601 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:34.809Z,1548201514.809 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:37.601Z,1548201517.601 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:39.605Z,1548201519.605 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:42.813Z,1548201522.813 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:45.605Z,1548201525.605 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:47.601Z,1548201527.601 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:53.575Z,1548201533.575 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:55.685Z,1548201535.685 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:58:58.885Z,1548201538.885 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:01.685Z,1548201541.685 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:04.885Z,1548201544.885 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:07.685Z,1548201547.685 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:10.889Z,1548201550.889 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:13.685Z,1548201553.685 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:16.885Z,1548201556.885 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:19.693Z,1548201559.693 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:22.885Z,1548201562.885 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:25.686Z,1548201565.686 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:30.173Z,1548201570.173 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:32.565Z,1548201572.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:34.577Z,1548201574.577 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:36.569Z,1548201576.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:38.565Z,1548201578.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:40.565Z,1548201580.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:42.569Z,1548201582.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:44.565Z,1548201584.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:46.565Z,1548201586.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:48.565Z,1548201588.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:50.569Z,1548201590.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:52.565Z,1548201592.565 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:54.566Z,1548201594.566 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:56.569Z,1548201596.569 [NAL9602](DEBUG): Fix Requested
2019-01-22T23:59:58.561Z,1548201598.561 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:00.565Z,1548201600.565 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:05.370Z,1548201605.370 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:07.112Z,1548201607.112 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000262.912071
2019-01-23T00:00:07.112Z,1548201607.112 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-01-23T00:00:07.113Z,1548201607.113 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-23T00:00:07.114Z,1548201607.114 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001254.334645
2019-01-23T00:00:07.115Z,1548201607.115 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-01-23T00:00:07.115Z,1548201607.115 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-23T00:00:07.143Z,1548201607.143 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-23T00:00:07.143Z,1548201607.143 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-23T00:00:07.569Z,1548201607.569 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-23T00:00:07.569Z,1548201607.569 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-01-23T00:00:07.569Z,1548201607.569 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-23T00:00:07.569Z,1548201607.569 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-01-23T00:00:07.881Z,1548201607.881 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:07.909Z,1548201607.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-23T00:00:07.910Z,1548201607.910 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-23T00:00:07.910Z,1548201607.910 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-23T00:00:07.910Z,1548201607.910 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-23T00:00:07.911Z,1548201607.911 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-23T00:00:07.911Z,1548201607.911 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-23T00:00:10.685Z,1548201610.685 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:13.881Z,1548201613.881 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:16.701Z,1548201616.701 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:19.885Z,1548201619.885 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:22.693Z,1548201622.693 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:25.889Z,1548201625.889 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:00:28.682Z,1548201628.682 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-01-23T00:00:28.683Z,1548201628.683 [NAL9602] Data Fault, FailCount= 2
2019-01-23T00:00:28.683Z,1548201628.683 [NAL9602](ERROR): Data Fault
2019-01-23T00:00:28.754Z,1548201628.754 [CBIT](ERROR): Data Fault in component: NAL9602
2019-01-23T00:00:29.078Z,1548201629.078 [NAL9602](INFO): Powering down
2019-01-23T00:00:29.928Z,1548201629.928 [CBIT](INFO): Clearing failed state for component NAL9602
2019-01-23T00:00:29.928Z,1548201629.928 [NAL9602] No Fault, FailCount= 2
2019-01-23T00:00:59.163Z,1548201659.163 [NAL9602](INFO): Powering up NAL9602
2019-01-23T00:01:09.955Z,1548201669.955 [NAL9602](INFO): NAL9602 initialized
2019-01-23T00:01:10.769Z,1548201670.769 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:16.053Z,1548201676.053 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:18.849Z,1548201678.849 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:21.645Z,1548201681.645 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:24.849Z,1548201684.849 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:27.649Z,1548201687.649 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:30.845Z,1548201690.845 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:33.657Z,1548201693.657 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:36.858Z,1548201696.858 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:39.645Z,1548201699.645 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:42.849Z,1548201702.849 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:45.649Z,1548201705.649 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:50.545Z,1548201710.545 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:52.938Z,1548201712.938 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:55.741Z,1548201715.741 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:01:58.933Z,1548201718.933 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:01.749Z,1548201721.749 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:04.933Z,1548201724.933 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:07.749Z,1548201727.749 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:10.933Z,1548201730.933 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:13.733Z,1548201733.733 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:16.937Z,1548201736.937 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:19.789Z,1548201739.789 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:28.829Z,1548201748.829 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:31.585Z,1548201751.585 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:34.785Z,1548201754.785 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:37.589Z,1548201757.589 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:40.785Z,1548201760.785 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:43.593Z,1548201763.593 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:44.008Z,1548201764.008 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8c,0ffe,*14.429,*-0.101,*0.000
2019-01-23T00:02:46.785Z,1548201766.785 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:49.593Z,1548201769.593 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:52.785Z,1548201772.785 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:02:55.585Z,1548201775.585 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:01.593Z,1548201781.593 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:04.781Z,1548201784.781 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:07.581Z,1548201787.581 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:08.028Z,1548201788.028 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000300.172698
2019-01-23T00:03:08.028Z,1548201788.028 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-01-23T00:03:08.028Z,1548201788.028 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-23T00:03:08.030Z,1548201788.030 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001435.249930
2019-01-23T00:03:08.030Z,1548201788.030 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-01-23T00:03:08.030Z,1548201788.030 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-23T00:03:08.042Z,1548201788.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-23T00:03:08.043Z,1548201788.043 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-23T00:03:08.448Z,1548201788.448 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-23T00:03:08.448Z,1548201788.448 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-01-23T00:03:08.448Z,1548201788.448 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-23T00:03:08.449Z,1548201788.449 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-01-23T00:03:08.801Z,1548201788.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-23T00:03:08.802Z,1548201788.802 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-23T00:03:08.802Z,1548201788.802 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-23T00:03:08.802Z,1548201788.802 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-23T00:03:08.803Z,1548201788.803 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-23T00:03:08.803Z,1548201788.803 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-23T00:03:10.777Z,1548201790.777 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:13.581Z,1548201793.581 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:14.799Z,1548201794.799 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-01-23T00:03:14.800Z,1548201794.800 [Default:CheckIn:C.Wait] Stopped
2019-01-23T00:03:14.800Z,1548201794.800 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-01-23T00:03:14.800Z,1548201794.800 [Default:CheckIn:D] Running Loop=1
2019-01-23T00:03:15.227Z,1548201795.227 [Default:CheckIn:D] Stopped
2019-01-23T00:03:15.227Z,1548201795.227 [Default:CheckIn:E] Running Loop=1
2019-01-23T00:03:15.608Z,1548201795.608 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.077222 min 
2019-01-23T00:03:15.608Z,1548201795.608 [Default:CheckIn:E] Stopped
2019-01-23T00:03:15.608Z,1548201795.608 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-01-23T00:03:15.608Z,1548201795.608 [Default:CheckIn] Stopped
2019-01-23T00:03:15.625Z,1548201795.625 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-23T00:03:15.625Z,1548201795.625 [Default:CheckIn](INFO): Running loop #3
2019-01-23T00:03:15.625Z,1548201795.625 [Default:CheckIn] Running Loop=3
2019-01-23T00:03:15.625Z,1548201795.625 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-01-23T00:03:15.625Z,1548201795.625 [Default:CheckIn:Read_GPS] Running Loop=1
2019-01-23T00:03:16.781Z,1548201796.781 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:19.581Z,1548201799.581 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:22.781Z,1548201802.781 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:25.581Z,1548201805.581 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:28.785Z,1548201808.785 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:31.585Z,1548201811.585 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:36.085Z,1548201816.085 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:38.889Z,1548201818.889 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:41.677Z,1548201821.677 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:44.885Z,1548201824.885 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:47.685Z,1548201827.685 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:50.885Z,1548201830.885 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:53.677Z,1548201833.677 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:56.881Z,1548201836.881 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:03:59.681Z,1548201839.681 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:02.881Z,1548201842.881 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:05.677Z,1548201845.677 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:11.349Z,1548201851.349 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:13.745Z,1548201853.745 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:16.941Z,1548201856.941 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:19.741Z,1548201859.741 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:22.945Z,1548201862.945 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:25.745Z,1548201865.745 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:28.953Z,1548201868.953 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:31.753Z,1548201871.753 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:34.941Z,1548201874.941 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:37.745Z,1548201877.745 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:40.945Z,1548201880.945 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:46.254Z,1548201886.254 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:48.617Z,1548201888.617 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:51.825Z,1548201891.825 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:54.629Z,1548201894.629 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:04:57.821Z,1548201897.821 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:00.621Z,1548201900.621 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:03.821Z,1548201903.821 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:06.629Z,1548201906.629 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:09.829Z,1548201909.829 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:12.637Z,1548201912.637 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:15.822Z,1548201915.822 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:21.381Z,1548201921.381 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:23.505Z,1548201923.505 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:25.513Z,1548201925.513 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:27.509Z,1548201927.509 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:29.497Z,1548201929.497 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:31.501Z,1548201931.501 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:33.509Z,1548201933.509 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:35.509Z,1548201935.509 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:37.905Z,1548201937.905 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:40.701Z,1548201940.701 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:43.901Z,1548201943.901 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:46.701Z,1548201946.701 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:49.913Z,1548201949.913 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:52.701Z,1548201952.701 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:57.197Z,1548201957.197 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:05:59.605Z,1548201959.605 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:02.797Z,1548201962.797 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:05.601Z,1548201965.601 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:08.805Z,1548201968.805 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:08.827Z,1548201968.827 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1000337.322477
2019-01-23T00:06:08.827Z,1548201968.827 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-01-23T00:06:08.827Z,1548201968.827 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-23T00:06:08.838Z,1548201968.838 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = 1001616.049194
2019-01-23T00:06:08.838Z,1548201968.838 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-01-23T00:06:08.838Z,1548201968.838 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-23T00:06:08.859Z,1548201968.859 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-23T00:06:08.859Z,1548201968.859 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-23T00:06:09.290Z,1548201969.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-23T00:06:09.290Z,1548201969.290 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-01-23T00:06:09.291Z,1548201969.291 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-23T00:06:09.291Z,1548201969.291 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-01-23T00:06:09.632Z,1548201969.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-23T00:06:09.633Z,1548201969.633 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-23T00:06:09.633Z,1548201969.633 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-23T00:06:09.634Z,1548201969.634 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-23T00:06:09.634Z,1548201969.634 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-23T00:06:09.635Z,1548201969.635 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-23T00:06:11.597Z,1548201971.597 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:14.805Z,1548201974.805 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:17.601Z,1548201977.601 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:20.797Z,1548201980.797 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:23.621Z,1548201983.621 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:26.797Z,1548201986.797 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:32.109Z,1548201992.109 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:34.861Z,1548201994.861 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:37.657Z,1548201997.657 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:40.865Z,1548202000.865 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:43.661Z,1548202003.661 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:46.853Z,1548202006.853 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:49.661Z,1548202009.661 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:52.865Z,1548202012.865 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:55.657Z,1548202015.657 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:06:58.857Z,1548202018.857 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:01.653Z,1548202021.653 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:07.381Z,1548202027.381 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:09.728Z,1548202029.728 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:12.925Z,1548202032.925 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:15.729Z,1548202035.729 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:18.929Z,1548202038.929 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:21.721Z,1548202041.721 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:24.941Z,1548202044.941 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:27.721Z,1548202047.721 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:30.933Z,1548202050.933 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:33.725Z,1548202053.725 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:36.933Z,1548202056.933 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:42.483Z,1548202062.483 [NAL9602](DEBUG): Fix Requested
2019-01-23T00:07:42.618Z,1548202062.618 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235231.00,A,3648.15460,N,12147.28895,W,0.078,0.00,220119,,,A*7E
2019-01-23T00:07:42.626Z,1548202062.626 [NAL9602](INFO): GPS fix at 20190122T235231: (36.802577, -121.788149)
2019-01-23T00:07:42.680Z,1548202062.680 [Default:CheckIn:Read_GPS] Stopped
2019-01-23T00:07:42.680Z,1548202062.680 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-01-23T00:07:51.040Z,1548202071.040 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190122T233849/Courier0010.lzma
2019-01-23T00:07:51.832Z,1548202071.832 [DataOverHttps](INFO): Moved sent file to Logs/20190122T233849/Courier0010.lzma.bak
2019-01-23T00:07:51.832Z,1548202071.832 [DataOverHttps](INFO): SBD MOMSN=9594806
2019-01-23T00:08:04.131Z,1548202084.131 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20190122T233849/Express0011.lzma
2019-01-23T00:08:04.918Z,1548202084.918 [DataOverHttps](INFO): Moved sent file to Logs/20190122T233849/Express0011.lzma.bak
2019-01-23T00:08:04.918Z,1548202084.918 [DataOverHttps](INFO): SBD MOMSN=9594809
2019-01-23T00:08:07.110Z,1548202087.110 [Default:CheckIn:Read_Iridium] Stopped
2019-01-23T00:08:07.110Z,1548202087.110 [Default:CheckIn:C.Wait] Running Loop=1
2019-01-23T00:08:07.110Z,1548202087.110 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-01-23T00:08:14.874Z,1548202094.874 [NAL9602](INFO): Powering down
2019-01-23T00:08:47.368Z,1548202127.368 [DataOverHttps](IMPORTANT): SBD MTMSN=20190122T235337
2019-01-23T00:08:51.893Z,1548202131.893 [DataOverHttps](INFO): Received command:restart sys
2019-01-23T00:08:51.902Z,1548202131.902 [CommandLine](IMPORTANT): got command restart system
2019-01-23T00:08:54.248Z,1548202134.248 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-23T00:08:54.249Z,1548202134.249 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:54.405Z,1548202134.405 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-01-23T00:08:54.406Z,1548202134.406 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:54.407Z,1548202134.407 [CommandLine](INFO): Join timeout helper Thread ID is 9447
2019-01-23T00:08:54.413Z,1548202134.413 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-01-23T00:08:54.413Z,1548202134.413 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:54.414Z,1548202134.414 [NavChartDb](INFO): Join timeout helper Thread ID is 9448
2019-01-23T00:08:54.749Z,1548202134.749 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-23T00:08:54.749Z,1548202134.749 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:54.757Z,1548202134.757 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-01-23T00:08:54.757Z,1548202134.757 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:54.757Z,1548202134.757 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9449
2019-01-23T00:08:54.829Z,1548202134.829 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-23T00:08:54.829Z,1548202134.829 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-01-23T00:08:54.830Z,1548202134.830 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:54.856Z,1548202134.856 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-01-23T00:08:54.856Z,1548202134.856 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:54.857Z,1548202134.857 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9450
2019-01-23T00:08:55.041Z,1548202135.041 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-23T00:08:55.041Z,1548202135.041 [CTD_NeilBrown](INFO): Powering down
2019-01-23T00:08:55.055Z,1548202135.055 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.061Z,1548202135.061 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-01-23T00:08:55.061Z,1548202135.061 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.061Z,1548202135.061 [Radio_Surface](INFO): Join timeout helper Thread ID is 9451
2019-01-23T00:08:55.313Z,1548202135.313 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-23T00:08:55.313Z,1548202135.313 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.330Z,1548202135.330 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-01-23T00:08:55.330Z,1548202135.330 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.330Z,1548202135.330 [logger](INFO): Join timeout helper Thread ID is 9452
2019-01-23T00:08:55.349Z,1548202135.349 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-23T00:08:55.349Z,1548202135.349 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.370Z,1548202135.370 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-01-23T00:08:55.370Z,1548202135.370 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.370Z,1548202135.370 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-01-23T00:08:55.370Z,1548202135.370 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.370Z,1548202135.370 [controlThread](INFO): Join timeout helper Thread ID is 9453
2019-01-23T00:08:55.473Z,1548202135.473 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-23T00:08:55.473Z,1548202135.473 [controlThread](DEBUG): Uninitializing ControlThread
2019-01-23T00:08:55.474Z,1548202135.474 [AHRS_M2](INFO): Powering down
2019-01-23T00:08:55.546Z,1548202135.546 [NAL9602](INFO): Powering down
2019-01-23T00:08:55.617Z,1548202135.617 [RDI_Pathfinder](INFO): Powering down
2019-01-23T00:08:55.618Z,1548202135.618 [RDI_PathfinderUp](INFO): Powering down
2019-01-23T00:08:55.619Z,1548202135.619 [Aanderaa_O2](INFO): Powering down
2019-01-23T00:08:55.620Z,1548202135.620 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-01-23T00:08:55.621Z,1548202135.621 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-01-23T00:08:55.622Z,1548202135.622 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-01-23T00:08:55.622Z,1548202135.622 [MissionManager](INFO): Uninitializing Mission Default
2019-01-23T00:08:55.622Z,1548202135.622 [Default] Stopped
2019-01-23T00:08:55.622Z,1548202135.622 [Default](DEBUG): Aggregate::uninitialize Default
2019-01-23T00:08:55.622Z,1548202135.622 [Default:B.GoToSurface] Stopped
2019-01-23T00:08:55.623Z,1548202135.623 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-23T00:08:55.623Z,1548202135.623 [Default:CheckIn] Stopped
2019-01-23T00:08:55.623Z,1548202135.623 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-01-23T00:08:55.623Z,1548202135.623 [Default:CheckIn:C.Wait] Stopped
2019-01-23T00:08:55.623Z,1548202135.623 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-01-23T00:08:55.626Z,1548202135.626 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-01-23T00:08:55.626Z,1548202135.626 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-01-23T00:08:55.627Z,1548202135.627 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-01-23T00:08:55.627Z,1548202135.627 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-01-23T00:08:55.627Z,1548202135.627 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-01-23T00:08:55.627Z,1548202135.627 [BuoyancyServo](INFO): Powering down
2019-01-23T00:08:55.641Z,1548202135.641 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-01-23T00:08:55.641Z,1548202135.641 [ElevatorServo](INFO): Powering down
2019-01-23T00:08:55.642Z,1548202135.642 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-01-23T00:08:55.642Z,1548202135.642 [MassServo](INFO): Powering down
2019-01-23T00:08:55.643Z,1548202135.643 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-01-23T00:08:55.643Z,1548202135.643 [RudderServo](INFO): Powering down
2019-01-23T00:08:55.644Z,1548202135.644 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-01-23T00:08:55.644Z,1548202135.644 [ThrusterServo](INFO): Powering down
2019-01-23T00:08:55.645Z,1548202135.645 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-01-23T00:08:55.645Z,1548202135.645 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-01-23T00:08:55.645Z,1548202135.645 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-01-23T00:08:55.645Z,1548202135.645 [CBIT](DEBUG): Powering off loads.
2019-01-23T00:08:55.657Z,1548202135.657 [CBIT](DEBUG): Disabling WDT.
2019-01-23T00:08:55.669Z,1548202135.669 [CBIT](DEBUG): Opening all GF detection circuits.
2019-01-23T00:08:55.670Z,1548202135.670 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.781Z,1548202135.781 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.826Z,1548202135.826 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.829Z,1548202135.829 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.852Z,1548202135.852 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-23T00:08:55.923Z,1548202135.923 [logger ThreadHandler](INFO): Thread cancelled.