2018-09-08T05:41:48.044Z,1536385308.044 [Supervisor](DEBUG): Initializing supervisor.
2018-09-08T05:41:48.047Z,1536385308.047 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-08T05:41:48.047Z,1536385308.047 [SyncHandler](INFO): Protected caller Thread ID is 953
2018-09-08T05:41:48.048Z,1536385308.048 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-08T05:41:48.049Z,1536385308.049 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-08T05:41:48.049Z,1536385308.049 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 954
2018-09-08T05:41:48.052Z,1536385308.052 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-08T05:41:48.064Z,1536385308.064 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-08T05:41:48.065Z,1536385308.065 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-08T05:41:48.065Z,1536385308.065 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 955
2018-09-08T05:41:48.066Z,1536385308.066 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-08T05:41:48.067Z,1536385308.067 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-08T05:41:48.067Z,1536385308.067 [logger ThreadHandler](INFO): Protected caller Thread ID is 956
2018-09-08T05:41:48.069Z,1536385308.069 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-08T05:41:48.070Z,1536385308.070 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-08T05:41:48.071Z,1536385308.071 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-08T05:41:48.417Z,1536385308.417 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-08T05:41:48.418Z,1536385308.418 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-08T05:41:48.619Z,1536385308.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-08T05:41:48.619Z,1536385308.619 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-08T05:41:49.089Z,1536385309.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-08T05:41:49.089Z,1536385309.089 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-08T05:41:49.190Z,1536385309.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-08T05:41:49.190Z,1536385309.190 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-08T05:41:49.398Z,1536385309.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-08T05:41:49.398Z,1536385309.398 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-08T05:41:49.537Z,1536385309.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-08T05:41:49.537Z,1536385309.537 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-08T05:41:49.883Z,1536385309.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-08T05:41:49.884Z,1536385309.884 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-08T05:41:49.966Z,1536385309.966 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-08T05:41:50.065Z,1536385310.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-08T05:41:50.065Z,1536385310.065 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-08T05:41:50.171Z,1536385310.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-08T05:41:50.172Z,1536385310.172 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-08T05:41:50.494Z,1536385310.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-08T05:41:50.494Z,1536385310.494 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-08T05:41:50.707Z,1536385310.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-08T05:41:50.707Z,1536385310.707 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-08T05:41:51.163Z,1536385311.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-08T05:41:51.163Z,1536385311.163 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-08T05:41:51.311Z,1536385311.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-08T05:41:51.312Z,1536385311.312 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-08T05:41:51.821Z,1536385311.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-08T05:41:51.823Z,1536385311.823 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-tethys/
2018-09-08T05:41:51.824Z,1536385311.824 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Control.cfg
2018-09-08T05:41:51.922Z,1536385311.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Navigation.cfg
2018-09-08T05:41:52.013Z,1536385312.013 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Simulator.cfg
2018-09-08T05:41:52.099Z,1536385312.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/logger.cfg
2018-09-08T05:41:52.180Z,1536385312.180 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Battery.cfg
2018-09-08T05:41:52.439Z,1536385312.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-08T05:41:52.439Z,1536385312.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Science.cfg
2018-09-08T05:41:52.604Z,1536385312.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/secure.cfg
2018-09-08T05:41:52.688Z,1536385312.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/workSite.cfg
2018-09-08T05:41:52.781Z,1536385312.781 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Servo.cfg
2018-09-08T05:41:52.879Z,1536385312.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/BIT.cfg
2018-09-08T05:41:52.976Z,1536385312.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/Sensor.cfg
2018-09-08T05:41:53.121Z,1536385313.121 [Supervisor](INFO): Opening Config file at: Config/lrauv-tethys/vehicle.cfg
2018-09-08T05:41:53.319Z,1536385313.319 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-tethys/root/
2018-09-08T05:41:53.320Z,1536385313.320 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-09-08T05:41:53.328Z,1536385313.328 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-08T05:41:53.456Z,1536385313.456 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-08T05:41:53.486Z,1536385313.486 [SBIT] Loaded
2018-09-08T05:41:53.487Z,1536385313.487 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-08T05:41:53.487Z,1536385313.487 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-08T05:41:53.515Z,1536385313.515 [IBIT] Loaded
2018-09-08T05:41:53.516Z,1536385313.516 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-08T05:41:53.519Z,1536385313.519 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-08T05:41:53.677Z,1536385313.677 [CBIT] Loaded
2018-09-08T05:41:53.677Z,1536385313.677 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-08T05:41:53.678Z,1536385313.678 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-08T05:41:53.678Z,1536385313.678 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-08T05:41:53.702Z,1536385313.702 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-08T05:41:53.703Z,1536385313.703 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-08T05:41:53.897Z,1536385313.897 [CTD_Seabird] Loaded
2018-09-08T05:41:53.897Z,1536385313.897 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-09-08T05:41:53.898Z,1536385313.898 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 405B74E0
2018-09-08T05:41:53.899Z,1536385313.899 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1038
2018-09-08T05:41:53.917Z,1536385313.917 [ESPComponent] Loaded
2018-09-08T05:41:53.917Z,1536385313.917 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-09-08T05:41:53.932Z,1536385313.932 [PAR_Licor] Loaded
2018-09-08T05:41:53.932Z,1536385313.932 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-09-08T05:41:53.982Z,1536385313.982 [WetLabsBB2FL] Loaded
2018-09-08T05:41:53.982Z,1536385313.982 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-09-08T05:41:53.983Z,1536385313.983 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405E74E0
2018-09-08T05:41:53.984Z,1536385313.984 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1039
2018-09-08T05:41:53.984Z,1536385313.984 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-08T05:41:53.985Z,1536385313.985 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-08T05:41:54.088Z,1536385314.088 [BuoyancyServo] Loaded
2018-09-08T05:41:54.088Z,1536385314.088 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-08T05:41:54.103Z,1536385314.103 [ElevatorServo] Loaded
2018-09-08T05:41:54.103Z,1536385314.103 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-09-08T05:41:54.118Z,1536385314.118 [MassServo] Loaded
2018-09-08T05:41:54.118Z,1536385314.118 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-09-08T05:41:54.133Z,1536385314.133 [RudderServo] Loaded
2018-09-08T05:41:54.134Z,1536385314.134 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-09-08T05:41:54.148Z,1536385314.148 [ThrusterServo] Loaded
2018-09-08T05:41:54.149Z,1536385314.149 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-09-08T05:41:54.149Z,1536385314.149 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-08T05:41:54.150Z,1536385314.150 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-08T05:41:54.388Z,1536385314.388 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-08T05:41:54.389Z,1536385314.389 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-08T05:41:54.681Z,1536385314.681 [AHRS_M2] Loaded
2018-09-08T05:41:54.681Z,1536385314.681 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-09-08T05:41:54.751Z,1536385314.751 [DataOverHttps] Loaded
2018-09-08T05:41:54.751Z,1536385314.751 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-08T05:41:54.764Z,1536385314.764 [Depth_Keller] Loaded
2018-09-08T05:41:54.764Z,1536385314.764 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-08T05:41:54.769Z,1536385314.769 [DropWeight] Loaded
2018-09-08T05:41:54.770Z,1536385314.770 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-08T05:41:54.861Z,1536385314.861 [NAL9602] Loaded
2018-09-08T05:41:54.862Z,1536385314.862 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-08T05:41:54.867Z,1536385314.867 [Onboard] Loaded
2018-09-08T05:41:54.867Z,1536385314.867 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-08T05:41:54.874Z,1536385314.874 [Radio_Surface] Loaded
2018-09-08T05:41:54.874Z,1536385314.874 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-08T05:41:54.875Z,1536385314.875 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-09-08T05:41:54.876Z,1536385314.876 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1040
2018-09-08T05:41:55.007Z,1536385315.007 [DAT] Loaded
2018-09-08T05:41:55.008Z,1536385315.008 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2018-09-08T05:41:56.824Z,1536385316.824 [BPC1] Loaded
2018-09-08T05:41:56.824Z,1536385316.824 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-08T05:41:56.825Z,1536385316.825 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-08T05:41:56.825Z,1536385316.825 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-08T05:41:56.838Z,1536385316.838 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-08T05:41:56.839Z,1536385316.839 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-08T05:41:56.903Z,1536385316.903 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-08T05:41:56.904Z,1536385316.904 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-08T05:41:57.004Z,1536385317.004 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-09-08T05:41:57.004Z,1536385317.004 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-09-08T05:41:57.059Z,1536385317.059 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-08T05:41:57.059Z,1536385317.059 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-08T05:41:57.076Z,1536385317.076 [NavChart] Loaded
2018-09-08T05:41:57.076Z,1536385317.076 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-08T05:41:57.080Z,1536385317.080 [UniversalFixResidualReporter] Loaded
2018-09-08T05:41:57.081Z,1536385317.081 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-08T05:41:57.081Z,1536385317.081 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-08T05:41:57.082Z,1536385317.082 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-08T05:41:57.184Z,1536385317.184 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-08T05:41:57.184Z,1536385317.184 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-08T05:41:57.252Z,1536385317.252 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-08T05:41:57.341Z,1536385317.341 [VerticalControl] Loaded
2018-09-08T05:41:57.342Z,1536385317.342 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-08T05:41:57.342Z,1536385317.342 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-08T05:41:57.401Z,1536385317.401 [HorizontalControl] Loaded
2018-09-08T05:41:57.401Z,1536385317.401 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-08T05:41:57.402Z,1536385317.402 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-08T05:41:57.404Z,1536385317.404 [SpeedControl] Loaded
2018-09-08T05:41:57.404Z,1536385317.404 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-08T05:41:57.405Z,1536385317.405 [LoopControl](DEBUG): Construct LoopControl.
2018-09-08T05:41:57.406Z,1536385317.406 [LoopControl] Loaded
2018-09-08T05:41:57.406Z,1536385317.406 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-08T05:41:57.406Z,1536385317.406 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-08T05:41:57.407Z,1536385317.407 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-08T05:41:57.447Z,1536385317.447 [DepthRateCalculator] Loaded
2018-09-08T05:41:57.448Z,1536385317.448 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-08T05:41:57.453Z,1536385317.453 [PitchRateCalculator] Loaded
2018-09-08T05:41:57.453Z,1536385317.453 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-08T05:41:57.465Z,1536385317.465 [SpeedCalculator] Loaded
2018-09-08T05:41:57.466Z,1536385317.466 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-08T05:41:57.488Z,1536385317.488 [TempGradientCalculator] Loaded
2018-09-08T05:41:57.488Z,1536385317.488 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-08T05:41:57.504Z,1536385317.504 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-09-08T05:41:57.504Z,1536385317.504 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-09-08T05:41:57.509Z,1536385317.509 [YawRateCalculator] Loaded
2018-09-08T05:41:57.510Z,1536385317.510 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-08T05:41:57.541Z,1536385317.541 [ElevatorOffsetCalculator] Loaded
2018-09-08T05:41:57.541Z,1536385317.541 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-08T05:41:57.541Z,1536385317.541 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-08T05:41:57.549Z,1536385317.549 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-08T05:41:57.550Z,1536385317.550 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-08T05:41:57.556Z,1536385317.556 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-08T05:41:57.557Z,1536385317.557 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A064E0
2018-09-08T05:41:57.558Z,1536385317.558 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1041
2018-09-08T05:41:57.562Z,1536385317.562 [Supervisor](INFO): Main Thread ID is 795
2018-09-08T05:41:57.562Z,1536385317.562 [Supervisor](DEBUG): Running supervisor.
2018-09-08T05:41:57.563Z,1536385317.563 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1042
2018-09-08T05:41:57.566Z,1536385317.566 [controlThread ThreadHandler](INFO): Handler Thread ID is 1043
2018-09-08T05:41:57.567Z,1536385317.567 [controlThread](DEBUG): Initializing ControlThread
2018-09-08T05:41:57.568Z,1536385317.568 [SBIT](INFO): Initialize SBIT Component.
2018-09-08T05:41:57.568Z,1536385317.568 [SBIT](IMPORTANT): git: 2018-09-06
2018-09-08T05:41:57.568Z,1536385317.568 [SBIT](INFO): git hash: 66a1d3cbc6e0267838eeaa8d8b2ab3dc44228fef
2018-09-08T05:41:57.569Z,1536385317.569 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-08T05:41:57.570Z,1536385317.570 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-09-08T05:41:57.571Z,1536385317.571 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-09-08T05:41:57.572Z,1536385317.572 [IBIT](INFO): Initialize IBIT Component.
2018-09-08T05:41:57.573Z,1536385317.573 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-08T05:41:57.573Z,1536385317.573 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-09-08T05:41:57.574Z,1536385317.574 [logger ThreadHandler](INFO): Handler Thread ID is 1044
2018-09-08T05:41:57.601Z,1536385317.601 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1045
2018-09-08T05:41:57.602Z,1536385317.602 [CTD_Seabird](INFO): Initializing
2018-09-08T05:41:57.602Z,1536385317.602 [CTD_Seabird](INFO): Checking LCM
2018-09-08T05:41:57.612Z,1536385317.612 [CTD_Seabird](INFO): LCM OK
2018-09-08T05:41:57.612Z,1536385317.612 [CTD_Seabird](INFO): Powering up
2018-09-08T05:41:57.624Z,1536385317.624 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1046
2018-09-08T05:41:57.637Z,1536385317.637 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1047
2018-09-08T05:41:57.644Z,1536385317.644 [Radio_Surface](INFO): Powering up
2018-09-08T05:41:57.646Z,1536385317.646 [WetLabsBB2FL](INFO): Powering down
2018-09-08T05:41:57.659Z,1536385317.659 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1048
2018-09-08T05:41:57.669Z,1536385317.669 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-08T05:41:57.669Z,1536385317.669 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-08T05:41:57.669Z,1536385317.669 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-08T05:41:57.669Z,1536385317.669 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-08T05:41:57.669Z,1536385317.669 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-08T05:41:57.670Z,1536385317.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-08T05:41:57.670Z,1536385317.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-08T05:41:57.670Z,1536385317.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-08T05:41:57.670Z,1536385317.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-08T05:41:57.670Z,1536385317.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-08T05:41:57.670Z,1536385317.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-08T05:41:57.671Z,1536385317.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-08T05:41:57.671Z,1536385317.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-08T05:41:57.671Z,1536385317.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-08T05:41:57.671Z,1536385317.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-08T05:41:57.671Z,1536385317.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-08T05:41:57.701Z,1536385317.701 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T05:41:57.702Z,1536385317.702 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:41:57.702Z,1536385317.702 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:41:57.703Z,1536385317.703 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T05:41:57.703Z,1536385317.703 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:41:57.703Z,1536385317.703 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:41:57.704Z,1536385317.704 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-08T05:41:57.704Z,1536385317.704 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-08T05:41:57.705Z,1536385317.705 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-08T05:41:57.707Z,1536385317.707 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-08T05:41:57.707Z,1536385317.707 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-08T05:41:57.708Z,1536385317.708 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-08T05:41:57.708Z,1536385317.708 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-08T05:41:57.709Z,1536385317.709 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-08T05:41:57.709Z,1536385317.709 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-08T05:41:57.710Z,1536385317.710 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-08T05:41:57.710Z,1536385317.710 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-09-08T05:41:57.711Z,1536385317.711 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-08T05:41:57.711Z,1536385317.711 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-08T05:41:57.712Z,1536385317.712 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-08T05:41:57.747Z,1536385317.747 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-08T05:41:57.782Z,1536385317.782 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-09-08T05:41:57.783Z,1536385317.783 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-08T05:41:57.867Z,1536385317.867 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-08T05:41:57.868Z,1536385317.868 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-08T05:41:57.870Z,1536385317.870 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-08T05:41:57.917Z,1536385317.917 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-08T05:41:57.920Z,1536385317.920 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-08T05:41:57.942Z,1536385317.942 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-08T05:41:57.945Z,1536385317.945 [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>


2018-09-08T05:41:57.965Z,1536385317.965 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-08T05:41:58.019Z,1536385318.019 [ESPComponent](INFO): powering down ESP
2018-09-08T05:41:58.450Z,1536385318.450 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-09-08T05:41:58.505Z,1536385318.505 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-08T05:41:58.534Z,1536385318.534 [Depth_Keller](ERROR): Pressure reading out of range: 2102.507080 decibar
2018-09-08T05:41:58.603Z,1536385318.603 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2018-09-08T05:41:58.603Z,1536385318.603 [DAT](INFO): Powering up
2018-09-08T05:41:58.603Z,1536385318.603 [DAT](DEBUG): Initializing DAT.
2018-09-08T05:41:58.665Z,1536385318.665 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2018-09-08T05:41:58.766Z,1536385318.766 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-08T05:41:58.872Z,1536385318.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:41:58.896Z,1536385318.896 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:41:59.025Z,1536385319.025 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-08T05:41:59.033Z,1536385319.033 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-08T05:41:59.056Z,1536385319.056 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-09-08T05:41:59.065Z,1536385319.065 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-09-08T05:41:59.080Z,1536385319.080 [MassServo](DEBUG): Initializing EZServoServo.
2018-09-08T05:41:59.085Z,1536385319.085 [MassServo](DEBUG): Initializing MassServo.
2018-09-08T05:41:59.099Z,1536385319.099 [RudderServo](DEBUG): Initializing EZServoServo.
2018-09-08T05:41:59.105Z,1536385319.105 [RudderServo](DEBUG): Initializing RudderServo.
2018-09-08T05:41:59.122Z,1536385319.122 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-08T05:41:59.129Z,1536385319.129 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-08T05:41:59.456Z,1536385319.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:41:59.458Z,1536385319.458 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:41:59.693Z,1536385319.693 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:41:59.694Z,1536385319.694 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:00.157Z,1536385320.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:00.158Z,1536385320.158 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:00.513Z,1536385320.513 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:00.533Z,1536385320.533 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:00.913Z,1536385320.913 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:00.929Z,1536385320.929 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:01.312Z,1536385321.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:01.329Z,1536385321.329 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:01.737Z,1536385321.737 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:01.753Z,1536385321.753 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-08T05:42:03.181Z,1536385323.181 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-08T05:42:03.312Z,1536385323.312 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-09-08T05:42:03.366Z,1536385323.366 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-09-08T05:42:03.760Z,1536385323.760 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-08T05:42:03.760Z,1536385323.760 [CTD_Seabird](INFO): Powering down
2018-09-08T05:42:13.425Z,1536385333.425 [DAT](INFO): setting local address to 6
2018-09-08T05:42:13.812Z,1536385333.812 [DAT](INFO): set local address to 6
2018-09-08T05:42:24.995Z,1536385344.995 [NAL9602](INFO): Powering up NAL9602
2018-09-08T05:42:26.261Z,1536385346.261 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-08T05:42:26.274Z,1536385346.274 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-08T05:42:35.935Z,1536385355.935 [NAL9602](INFO): NAL9602 initialized
2018-09-08T05:42:37.220Z,1536385357.220 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010995
CHAN A1 (24V): -0.026717
CHAN A2 (12V): -0.004316
CHAN A3 (5V): -0.003139
CHAN B0 (3.3V): -0.001448
CHAN B1 (3.15aV): -0.000937
CHAN B2 (3.15bV): -0.001167
CHAN B3 (GND): -0.000363
OPEN: 0.004088
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-08T05:42:54.540Z,1536385374.540 [NAL9602](INFO): SBD MO Status=0, MOMSN=48690, MT Status=0, MTMSN=0
2018-09-08T05:42:54.541Z,1536385374.541 [NAL9602](INFO): No messages in MT queue
2018-09-08T05:43:20.300Z,1536385400.300 [SBIT](IMPORTANT): SBIT PASSED
2018-09-08T05:43:20.347Z,1536385400.347 [CommandLine](IMPORTANT): got command configSet list
2018-09-08T05:43:20.347Z,1536385400.347 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-08T05:43:20.348Z,1536385400.348 [CommandLine](IMPORTANT): CBIT.gf24Offset=180 microampere;

2018-09-08T05:43:20.349Z,1536385400.349 [CommandLine](IMPORTANT): Express none ESPComponent.sample_number;

2018-09-08T05:43:20.349Z,1536385400.349 [CommandLine](IMPORTANT): Express none ESPComponent.sampling;

2018-09-08T05:43:20.349Z,1536385400.349 [CommandLine](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2018-09-08T05:43:20.349Z,1536385400.349 [CommandLine](IMPORTANT): Express linearApproximation latitude 0.005000 angular_degree;

2018-09-08T05:43:20.349Z,1536385400.349 [CommandLine](IMPORTANT): Express linearApproximation longitude 0.005000 angular_degree;

2018-09-08T05:43:20.349Z,1536385400.349 [CommandLine](IMPORTANT): Express linearApproximation sea_water_temperature 0.250000 kelvin;

2018-09-08T05:43:20.350Z,1536385400.350 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2018-09-08T05:43:20.695Z,1536385400.695 [MissionManager](IMPORTANT): Started mission Startup
2018-09-08T05:43:20.697Z,1536385400.697 [Startup] Running Loop=1
2018-09-08T05:43:20.698Z,1536385400.698 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-08T05:43:20.698Z,1536385400.698 [Startup:A.GoToSurface] Running Loop=1
2018-09-08T05:43:20.699Z,1536385400.699 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-08T05:43:20.700Z,1536385400.700 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-08T05:43:20.700Z,1536385400.700 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-08T05:43:20.701Z,1536385400.701 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-08T05:43:20.701Z,1536385400.701 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-08T05:43:20.701Z,1536385400.701 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-08T05:43:20.708Z,1536385400.708 [Startup:StartupSatComms] Running Loop=1
2018-09-08T05:43:20.708Z,1536385400.708 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-08T05:43:20.708Z,1536385400.708 [Startup:StartupSatComms:A] Running Loop=1
2018-09-08T05:43:21.106Z,1536385401.106 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-08T05:44:21.106Z,1536385461.106 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-08T05:43:20.7Z
2018-09-08T05:44:21.106Z,1536385461.106 [Startup:StartupSatComms:A] Stopped
2018-09-08T05:44:21.106Z,1536385461.106 [Startup:StartupSatComms:B] Running Loop=1
2018-09-08T05:44:21.514Z,1536385461.514 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-08T05:44:44.390Z,1536385484.390 [NAL9602](INFO): SBD MO Status=1, MOMSN=48691, MT Status=0, MTMSN=0
2018-09-08T05:44:44.441Z,1536385484.441 [NAL9602](INFO): Sent 25 bytes from file Logs/20180908T053145/Courier0007.lzma
2018-09-08T05:44:44.441Z,1536385484.441 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:44:44.443Z,1536385484.443 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T053145/Courier0007.lzma.parts/0000.sbd
2018-09-08T05:44:44.443Z,1536385484.443 [NAL9602](DEBUG): Completed sending Logs/20180908T053145/Courier0007.lzma
2018-09-08T05:44:54.900Z,1536385494.900 [NAL9602](INFO): SBD MO Status=1, MOMSN=48692, MT Status=0, MTMSN=0
2018-09-08T05:44:54.951Z,1536385494.951 [NAL9602](INFO): Sent 25 bytes from file Logs/20180908T053145/Courier0010.lzma
2018-09-08T05:44:54.951Z,1536385494.951 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:44:54.952Z,1536385494.952 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T053145/Courier0010.lzma.parts/0000.sbd
2018-09-08T05:44:54.953Z,1536385494.953 [NAL9602](DEBUG): Completed sending Logs/20180908T053145/Courier0010.lzma
2018-09-08T05:44:57.764Z,1536385497.764 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:44:57.764Z,1536385497.764 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-09-08T05:44:57.764Z,1536385497.764 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T05:44:57.770Z,1536385497.770 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:44:57.770Z,1536385497.770 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-09-08T05:44:57.770Z,1536385497.770 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T05:44:57.796Z,1536385497.796 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T05:44:57.796Z,1536385497.796 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T05:44:57.943Z,1536385497.943 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T05:44:57.943Z,1536385497.943 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-09-08T05:44:57.944Z,1536385497.944 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T05:44:57.944Z,1536385497.944 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-09-08T05:44:58.267Z,1536385498.267 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T05:44:58.268Z,1536385498.268 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:44:58.268Z,1536385498.268 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:44:58.268Z,1536385498.268 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T05:44:58.270Z,1536385498.270 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:44:58.270Z,1536385498.270 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:44:58.663Z,1536385498.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-09-08T05:44:59.067Z,1536385499.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2018-09-08T05:44:59.479Z,1536385499.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2018-09-08T05:44:59.883Z,1536385499.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2018-09-08T05:45:00.255Z,1536385500.255 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2018-09-08T05:45:00.651Z,1536385500.651 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2018-09-08T05:45:01.047Z,1536385501.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2018-09-08T05:45:01.455Z,1536385501.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2018-09-08T05:45:01.855Z,1536385501.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2018-09-08T05:45:02.255Z,1536385502.255 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-09-08T05:45:02.651Z,1536385502.651 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2018-09-08T05:45:03.056Z,1536385503.056 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2018-09-08T05:45:03.455Z,1536385503.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2018-09-08T05:45:03.855Z,1536385503.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2018-09-08T05:45:04.255Z,1536385504.255 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2018-09-08T05:45:04.655Z,1536385504.655 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2018-09-08T05:45:05.055Z,1536385505.055 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2018-09-08T05:45:05.432Z,1536385505.432 [NAL9602](INFO): SBD MO Status=1, MOMSN=48693, MT Status=0, MTMSN=0
2018-09-08T05:45:05.477Z,1536385505.477 [NAL9602](INFO): Sent 158 bytes from file Logs/20180908T053145/Express0001.lzma
2018-09-08T05:45:05.478Z,1536385505.478 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:45:05.479Z,1536385505.479 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T053145/Express0001.lzma.parts/0000.sbd
2018-09-08T05:45:05.479Z,1536385505.479 [NAL9602](DEBUG): Completed sending Logs/20180908T053145/Express0001.lzma
2018-09-08T05:45:05.536Z,1536385505.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.8 s old, using for 20.0 s.
2018-09-08T05:45:06.048Z,1536385506.048 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.3 s old, using for 20.0 s.
2018-09-08T05:45:08.291Z,1536385508.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2018-09-08T05:45:08.401Z,1536385508.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s.
2018-09-08T05:45:08.755Z,1536385508.755 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2018-09-08T05:45:09.156Z,1536385509.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2018-09-08T05:45:09.556Z,1536385509.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2018-09-08T05:45:09.963Z,1536385509.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.2 s old, using for 20.0 s.
2018-09-08T05:45:10.392Z,1536385510.392 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.6 s old, using for 20.0 s.
2018-09-08T05:45:10.763Z,1536385510.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s.
2018-09-08T05:45:11.163Z,1536385511.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s.
2018-09-08T05:45:11.563Z,1536385511.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s.
2018-09-08T05:45:11.971Z,1536385511.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.2 s old, using for 20.0 s.
2018-09-08T05:45:12.386Z,1536385512.386 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.6 s old, using for 20.0 s.
2018-09-08T05:45:12.763Z,1536385512.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2018-09-08T05:45:13.160Z,1536385513.160 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s.
2018-09-08T05:45:13.559Z,1536385513.559 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.8 s old, using for 20.0 s.
2018-09-08T05:45:13.960Z,1536385513.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.2 s old, using for 20.0 s.
2018-09-08T05:45:14.364Z,1536385514.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.6 s old, using for 20.0 s.
2018-09-08T05:45:14.763Z,1536385514.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.0 s old, using for 20.0 s.
2018-09-08T05:45:15.163Z,1536385515.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.4 s old, using for 20.0 s.
2018-09-08T05:45:15.560Z,1536385515.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.8 s old, using for 20.0 s.
2018-09-08T05:45:15.960Z,1536385515.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.2 s old, using for 20.0 s.
2018-09-08T05:45:16.359Z,1536385516.359 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.6 s old, using for 20.0 s.
2018-09-08T05:45:16.760Z,1536385516.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.0 s old, using for 20.0 s.
2018-09-08T05:45:17.163Z,1536385517.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.4 s old, using for 20.0 s.
2018-09-08T05:45:17.571Z,1536385517.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s.
2018-09-08T05:45:21.166Z,1536385521.166 [Startup:StartupSatComms:B](INFO): Timed out from 2018-09-08T05:44:21.1Z
2018-09-08T05:45:21.167Z,1536385521.167 [Startup:StartupSatComms:B] Stopped
2018-09-08T05:45:21.167Z,1536385521.167 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-08T05:45:21.167Z,1536385521.167 [Startup:StartupSatComms] Stopped
2018-09-08T05:45:21.167Z,1536385521.167 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-08T05:45:21.168Z,1536385521.168 [Startup](INFO): Completed Startup
2018-09-08T05:45:21.168Z,1536385521.168 [MissionManager](INFO): Startup is completed.
2018-09-08T05:45:21.168Z,1536385521.168 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-08T05:45:21.168Z,1536385521.168 [Startup] Stopped
2018-09-08T05:45:21.169Z,1536385521.169 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-08T05:45:21.169Z,1536385521.169 [Startup:A.GoToSurface] Stopped
2018-09-08T05:45:21.169Z,1536385521.169 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-08T05:45:21.572Z,1536385521.572 [MissionManager](IMPORTANT): Started mission Default
2018-09-08T05:45:21.573Z,1536385521.573 [Default] Running Loop=1
2018-09-08T05:45:21.573Z,1536385521.573 [Default](DEBUG): Aggregate::initialize Default
2018-09-08T05:45:21.573Z,1536385521.573 [Default:B.GoToSurface] Running Loop=1
2018-09-08T05:45:21.573Z,1536385521.573 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-08T05:45:21.573Z,1536385521.573 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-08T05:45:21.574Z,1536385521.574 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-08T05:45:21.574Z,1536385521.574 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-08T05:45:21.574Z,1536385521.574 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-08T05:45:21.575Z,1536385521.575 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-08T05:45:21.575Z,1536385521.575 [Default:A.Wait] Running Loop=1
2018-09-08T05:45:21.575Z,1536385521.575 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-08T05:45:27.932Z,1536385527.932 [NAL9602](INFO): SBD MO Status=1, MOMSN=48694, MT Status=0, MTMSN=0
2018-09-08T05:45:27.993Z,1536385527.993 [NAL9602](INFO): Sent 72 bytes from file Logs/20180908T053145/Express0005.lzma
2018-09-08T05:45:27.993Z,1536385527.993 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:45:27.995Z,1536385527.995 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T053145/Express0005.lzma.parts/0000.sbd
2018-09-08T05:45:27.995Z,1536385527.995 [NAL9602](DEBUG): Completed sending Logs/20180908T053145/Express0005.lzma
2018-09-08T05:45:34.815Z,1536385534.815 [Default:A.Wait](INFO): Done Waiting.
2018-09-08T05:45:34.815Z,1536385534.815 [Default:A.Wait] Stopped
2018-09-08T05:45:34.815Z,1536385534.815 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-08T05:45:35.190Z,1536385535.190 [Default:CheckIn] Running Loop=1
2018-09-08T05:45:35.190Z,1536385535.190 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-08T05:45:35.190Z,1536385535.190 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-08T05:45:35.567Z,1536385535.567 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-08T05:47:58.367Z,1536385678.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:47:58.367Z,1536385678.367 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-09-08T05:47:58.367Z,1536385678.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T05:47:58.369Z,1536385678.369 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:47:58.369Z,1536385678.369 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-09-08T05:47:58.369Z,1536385678.369 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T05:47:58.382Z,1536385678.382 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T05:47:58.382Z,1536385678.382 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T05:47:58.811Z,1536385678.811 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T05:47:58.812Z,1536385678.812 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-09-08T05:47:58.812Z,1536385678.812 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T05:47:58.812Z,1536385678.812 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-09-08T05:47:59.167Z,1536385679.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T05:47:59.167Z,1536385679.167 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:47:59.168Z,1536385679.168 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:47:59.168Z,1536385679.168 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T05:47:59.169Z,1536385679.169 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:47:59.169Z,1536385679.169 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:47:59.565Z,1536385679.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-09-08T05:47:59.968Z,1536385679.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-09-08T05:48:00.372Z,1536385680.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-09-08T05:48:00.778Z,1536385680.778 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-09-08T05:48:01.179Z,1536385681.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-09-08T05:48:01.583Z,1536385681.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-09-08T05:48:01.966Z,1536385681.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-09-08T05:48:02.366Z,1536385682.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-09-08T05:48:02.769Z,1536385682.769 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-09-08T05:48:03.170Z,1536385683.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-09-08T05:48:03.571Z,1536385683.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-09-08T05:48:03.980Z,1536385683.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-09-08T05:48:04.380Z,1536385684.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-09-08T05:48:04.779Z,1536385684.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-09-08T05:48:05.184Z,1536385685.184 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-09-08T05:48:05.587Z,1536385685.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-09-08T05:48:05.971Z,1536385685.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-09-08T05:48:06.375Z,1536385686.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-09-08T05:48:06.782Z,1536385686.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-09-08T05:48:07.187Z,1536385687.187 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-09-08T05:48:07.573Z,1536385687.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-09-08T05:48:07.974Z,1536385687.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-09-08T05:48:08.348Z,1536385688.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-09-08T05:48:08.772Z,1536385688.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-09-08T05:48:09.168Z,1536385689.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-09-08T05:48:09.549Z,1536385689.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-09-08T05:48:09.983Z,1536385689.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-09-08T05:48:10.382Z,1536385690.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-09-08T05:48:10.783Z,1536385690.783 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-09-08T05:48:11.155Z,1536385691.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-09-08T05:48:11.555Z,1536385691.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-09-08T05:48:11.957Z,1536385691.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-09-08T05:48:12.355Z,1536385692.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-09-08T05:48:12.786Z,1536385692.786 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-09-08T05:48:13.181Z,1536385693.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-09-08T05:48:13.588Z,1536385693.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-09-08T05:48:13.963Z,1536385693.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-09-08T05:48:14.355Z,1536385694.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-09-08T05:48:14.755Z,1536385694.755 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-09-08T05:48:15.157Z,1536385695.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-09-08T05:48:15.555Z,1536385695.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-09-08T05:48:15.987Z,1536385695.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-09-08T05:48:16.387Z,1536385696.387 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-09-08T05:48:16.785Z,1536385696.785 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-09-08T05:48:17.162Z,1536385697.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-09-08T05:48:17.582Z,1536385697.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-09-08T05:48:17.991Z,1536385697.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-09-08T05:49:13.207Z,1536385753.207 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004438
2018-09-08T05:50:35.242Z,1536385835.242 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-08T05:45:35.2Z
2018-09-08T05:50:35.242Z,1536385835.242 [Default:CheckIn:Read_GPS] Stopped
2018-09-08T05:50:35.242Z,1536385835.242 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-08T05:50:35.642Z,1536385835.642 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-08T05:50:48.385Z,1536385848.385 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20180908T054148/Courier0004.lzma
2018-09-08T05:50:51.990Z,1536385851.990 [DataOverHttps](INFO): Moved sent file to Logs/20180908T054148/Courier0004.lzma.bak
2018-09-08T05:50:51.990Z,1536385851.990 [DataOverHttps](IMPORTANT): SBD MOMSN=8494384, MTMSN=20180908T055041
2018-09-08T05:50:59.248Z,1536385859.248 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:50:59.248Z,1536385859.248 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-09-08T05:50:59.248Z,1536385859.248 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T05:50:59.250Z,1536385859.250 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:50:59.251Z,1536385859.251 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-09-08T05:50:59.251Z,1536385859.251 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T05:50:59.265Z,1536385859.265 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T05:50:59.265Z,1536385859.265 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T05:50:59.659Z,1536385859.659 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T05:50:59.659Z,1536385859.659 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-09-08T05:50:59.659Z,1536385859.659 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T05:50:59.659Z,1536385859.659 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-09-08T05:50:59.994Z,1536385859.994 [DataOverHttps](INFO): Received command:ibit
2018-09-08T05:51:00.050Z,1536385860.050 [CommandLine](IMPORTANT): got command ibit
2018-09-08T05:51:00.059Z,1536385860.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T05:51:00.059Z,1536385860.059 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:51:00.059Z,1536385860.059 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:51:00.060Z,1536385860.060 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T05:51:00.060Z,1536385860.060 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:51:00.061Z,1536385860.061 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:51:00.071Z,1536385860.071 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-09-08T05:51:00.071Z,1536385860.071 [IBIT](IMPORTANT): Beginning control surface checks.
2018-09-08T05:51:00.075Z,1536385860.075 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-08T05:51:00.614Z,1536385860.614 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.4 s old, using for 20.0 s.
2018-09-08T05:51:00.827Z,1536385860.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-09-08T05:51:00.910Z,1536385860.910 [Radio_Surface](INFO): Powering down
2018-09-08T05:51:01.227Z,1536385861.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-09-08T05:51:01.636Z,1536385861.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-09-08T05:51:02.035Z,1536385862.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-09-08T05:51:02.431Z,1536385862.431 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-09-08T05:51:02.827Z,1536385862.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-09-08T05:51:03.227Z,1536385863.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-09-08T05:51:03.622Z,1536385863.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-09-08T05:51:04.032Z,1536385864.032 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-09-08T05:51:04.435Z,1536385864.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-09-08T05:51:04.833Z,1536385864.833 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-09-08T05:51:05.222Z,1536385865.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-09-08T05:51:05.618Z,1536385865.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-09-08T05:51:06.018Z,1536385866.018 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-09-08T05:51:06.426Z,1536385866.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-09-08T05:51:06.833Z,1536385866.833 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-09-08T05:51:06.970Z,1536385866.970 [Radio_Surface](INFO): Powering up
2018-09-08T05:51:07.239Z,1536385867.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-09-08T05:51:07.630Z,1536385867.630 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-09-08T05:51:08.025Z,1536385868.025 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-09-08T05:51:08.431Z,1536385868.431 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-09-08T05:51:08.826Z,1536385868.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-09-08T05:51:09.226Z,1536385869.226 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-09-08T05:51:09.625Z,1536385869.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-09-08T05:51:10.043Z,1536385870.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-09-08T05:51:10.435Z,1536385870.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-09-08T05:51:10.839Z,1536385870.839 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-09-08T05:51:10.881Z,1536385870.881 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.123792
CHAN A1 (24V): -0.026923
CHAN A2 (12V): -0.004439
CHAN A3 (5V): -0.002299
CHAN B0 (3.3V): -0.000878
CHAN B1 (3.15aV): -0.001318
CHAN B2 (3.15bV): -0.001326
CHAN B3 (GND): -0.000748
OPEN: 0.004214
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-08T05:51:11.227Z,1536385871.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-09-08T05:51:11.611Z,1536385871.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-09-08T05:51:12.012Z,1536385872.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-09-08T05:51:12.411Z,1536385872.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-09-08T05:51:12.839Z,1536385872.839 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-09-08T05:51:13.235Z,1536385873.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-09-08T05:51:13.647Z,1536385873.647 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-09-08T05:51:14.011Z,1536385874.011 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-09-08T05:51:14.409Z,1536385874.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-09-08T05:51:14.835Z,1536385874.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-09-08T05:51:15.209Z,1536385875.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-09-08T05:51:15.615Z,1536385875.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-09-08T05:51:16.043Z,1536385876.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-09-08T05:51:16.447Z,1536385876.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-09-08T05:51:16.843Z,1536385876.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-09-08T05:51:17.239Z,1536385877.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-09-08T05:51:17.615Z,1536385877.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-09-08T05:51:18.014Z,1536385878.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-09-08T05:51:18.419Z,1536385878.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-09-08T05:51:18.839Z,1536385878.839 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-09-08T05:51:19.239Z,1536385879.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-09-08T05:51:22.786Z,1536385882.786 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-09-08T05:51:53.584Z,1536385913.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T05:52:24.375Z,1536385944.375 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T05:52:36.787Z,1536385956.787 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-08T05:52:36.787Z,1536385956.787 [NAL9602] Data Fault, FailCount= 1
2018-09-08T05:52:36.787Z,1536385956.787 [NAL9602](ERROR): Data Fault
2018-09-08T05:52:36.869Z,1536385956.869 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-08T05:52:37.187Z,1536385957.187 [NAL9602](INFO): Powering down
2018-09-08T05:52:38.052Z,1536385958.052 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-08T05:52:38.052Z,1536385958.052 [NAL9602] No Fault, FailCount= 1
2018-09-08T05:52:50.840Z,1536385970.840 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2018-09-08T05:52:51.235Z,1536385971.235 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 0.000000
2018-09-08T05:52:51.235Z,1536385971.235 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2018-09-08T05:52:51.235Z,1536385971.235 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2018-09-08T05:52:51.642Z,1536385971.642 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-09-08T05:52:51.642Z,1536385971.642 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-09-08T05:52:51.642Z,1536385971.642 [IBIT](IMPORTANT): Pressure:9.520661 PSI
2018-09-08T05:52:51.643Z,1536385971.643 [IBIT](IMPORTANT): Humidity:11.134171 %
2018-09-08T05:52:52.037Z,1536385972.037 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-09-08T05:52:52.037Z,1536385972.037 [IBIT](IMPORTANT): buoyancyNeutral: 325.000000 cc
2018-09-08T05:52:52.038Z,1536385972.038 [IBIT](IMPORTANT): massDefault: 0.200000 cm
2018-09-08T05:52:52.038Z,1536385972.038 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2018-09-08T05:52:52.038Z,1536385972.038 [IBIT](IMPORTANT): abortDepth: 225.000000 m
2018-09-08T05:52:52.038Z,1536385972.038 [IBIT](IMPORTANT): IBIT FAILED
2018-09-08T05:52:54.777Z,1536385974.777 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T05:53:07.578Z,1536385987.578 [NAL9602](INFO): Powering up NAL9602
2018-09-08T05:53:18.391Z,1536385998.391 [NAL9602](INFO): NAL9602 initialized
2018-09-08T05:53:25.577Z,1536386005.577 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T05:53:52.378Z,1536386032.378 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.388748
2018-09-08T05:54:00.426Z,1536386040.426 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:54:00.427Z,1536386040.427 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-09-08T05:54:00.427Z,1536386040.427 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T05:54:00.428Z,1536386040.428 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:54:00.429Z,1536386040.429 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-09-08T05:54:00.429Z,1536386040.429 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T05:54:00.466Z,1536386040.466 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T05:54:00.467Z,1536386040.467 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T05:54:00.975Z,1536386040.975 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T05:54:00.975Z,1536386040.975 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-09-08T05:54:00.976Z,1536386040.976 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T05:54:00.976Z,1536386040.976 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-09-08T05:54:03.235Z,1536386043.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T05:54:03.235Z,1536386043.235 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:54:03.236Z,1536386043.236 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:54:03.236Z,1536386043.236 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T05:54:03.237Z,1536386043.237 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:54:03.237Z,1536386043.237 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:54:03.360Z,1536386043.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2018-09-08T05:54:03.767Z,1536386043.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2018-09-08T05:54:04.145Z,1536386044.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2018-09-08T05:54:04.566Z,1536386044.566 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2018-09-08T05:54:04.951Z,1536386044.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-09-08T05:54:05.350Z,1536386045.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2018-09-08T05:54:05.751Z,1536386045.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2018-09-08T05:54:06.151Z,1536386046.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2018-09-08T05:54:06.552Z,1536386046.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2018-09-08T05:54:06.955Z,1536386046.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2018-09-08T05:54:07.348Z,1536386047.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2018-09-08T05:54:07.756Z,1536386047.756 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2018-09-08T05:54:08.155Z,1536386048.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2018-09-08T05:54:08.555Z,1536386048.555 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2018-09-08T05:54:08.956Z,1536386048.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2018-09-08T05:54:09.355Z,1536386049.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2018-09-08T05:54:09.755Z,1536386049.755 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2018-09-08T05:54:10.155Z,1536386050.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2018-09-08T05:54:10.552Z,1536386050.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2018-09-08T05:54:10.952Z,1536386050.952 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2018-09-08T05:54:11.359Z,1536386051.359 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2018-09-08T05:54:11.763Z,1536386051.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2018-09-08T05:54:12.152Z,1536386052.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-09-08T05:54:12.553Z,1536386052.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2018-09-08T05:54:12.963Z,1536386052.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2018-09-08T05:54:13.363Z,1536386053.363 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s.
2018-09-08T05:54:13.763Z,1536386053.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2018-09-08T05:54:14.136Z,1536386054.136 [NAL9602](INFO): SBD MO Status=1, MOMSN=48695, MT Status=0, MTMSN=0
2018-09-08T05:54:14.193Z,1536386054.193 [NAL9602](INFO): Sent 25 bytes from file Logs/20180908T054148/Courier0007.lzma
2018-09-08T05:54:14.193Z,1536386054.193 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:54:14.195Z,1536386054.195 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T054148/Courier0007.lzma.parts/0000.sbd
2018-09-08T05:54:14.195Z,1536386054.195 [NAL9602](DEBUG): Completed sending Logs/20180908T054148/Courier0007.lzma
2018-09-08T05:54:14.251Z,1536386054.251 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s.
2018-09-08T05:54:14.759Z,1536386054.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.3 s old, using for 20.0 s.
2018-09-08T05:54:16.988Z,1536386056.988 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.6 s old, using for 20.0 s.
2018-09-08T05:54:17.105Z,1536386057.105 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2018-09-08T05:54:17.466Z,1536386057.466 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.0 s old, using for 20.0 s.
2018-09-08T05:54:17.860Z,1536386057.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.4 s old, using for 20.0 s.
2018-09-08T05:54:18.260Z,1536386058.260 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.8 s old, using for 20.0 s.
2018-09-08T05:54:18.657Z,1536386058.657 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.2 s old, using for 20.0 s.
2018-09-08T05:54:19.061Z,1536386059.061 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.6 s old, using for 20.0 s.
2018-09-08T05:54:19.456Z,1536386059.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.0 s old, using for 20.0 s.
2018-09-08T05:54:19.860Z,1536386059.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.4 s old, using for 20.0 s.
2018-09-08T05:54:20.225Z,1536386060.225 [NAL9602](INFO): SBD MO Status=1, MOMSN=48696, MT Status=0, MTMSN=0
2018-09-08T05:54:20.277Z,1536386060.277 [NAL9602](INFO): Sent 141 bytes from file Logs/20180908T053145/Express0008.lzma
2018-09-08T05:54:20.277Z,1536386060.277 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:54:20.279Z,1536386060.279 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T053145/Express0008.lzma.parts/0000.sbd
2018-09-08T05:54:20.279Z,1536386060.279 [NAL9602](DEBUG): Completed sending Logs/20180908T053145/Express0008.lzma
2018-09-08T05:54:20.345Z,1536386060.345 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2018-09-08T05:54:23.154Z,1536386063.154 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T05:54:28.348Z,1536386068.348 [NAL9602](INFO): SBD MO Status=1, MOMSN=48697, MT Status=0, MTMSN=0
2018-09-08T05:54:28.409Z,1536386068.409 [NAL9602](INFO): Sent 39 bytes from file Logs/20180908T053145/Express0011.lzma
2018-09-08T05:54:28.409Z,1536386068.409 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:54:28.411Z,1536386068.411 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T053145/Express0011.lzma.parts/0000.sbd
2018-09-08T05:54:28.411Z,1536386068.411 [NAL9602](DEBUG): Completed sending Logs/20180908T053145/Express0011.lzma
2018-09-08T05:54:39.245Z,1536386079.245 [NAL9602](INFO): SBD MO Status=1, MOMSN=48698, MT Status=0, MTMSN=0
2018-09-08T05:54:39.294Z,1536386079.294 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T054148/Express0001.lzma
2018-09-08T05:54:39.295Z,1536386079.295 [NAL9602](INFO): Packets left to send: 2
2018-09-08T05:54:39.296Z,1536386079.296 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T054148/Express0001.lzma.parts/0002.sbd
2018-09-08T05:54:46.948Z,1536386086.948 [NAL9602](INFO): SBD MO Status=1, MOMSN=48699, MT Status=0, MTMSN=0
2018-09-08T05:54:46.998Z,1536386086.998 [NAL9602](INFO): Sent 332 bytes from file Logs/20180908T054148/Express0001.lzma
2018-09-08T05:54:46.999Z,1536386086.999 [NAL9602](INFO): Packets left to send: 1
2018-09-08T05:54:46.000Z,1536386087.000 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T054148/Express0001.lzma.parts/0001.sbd
2018-09-08T05:54:58.662Z,1536386098.662 [NAL9602](INFO): SBD MO Status=1, MOMSN=48700, MT Status=0, MTMSN=0
2018-09-08T05:54:58.713Z,1536386098.713 [NAL9602](INFO): Sent 119 bytes from file Logs/20180908T054148/Express0001.lzma
2018-09-08T05:54:58.713Z,1536386098.713 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:54:58.714Z,1536386098.714 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T054148/Express0001.lzma.parts/0000.sbd
2018-09-08T05:54:58.715Z,1536386098.715 [NAL9602](DEBUG): Completed sending Logs/20180908T054148/Express0001.lzma
2018-09-08T05:55:06.347Z,1536386106.347 [NAL9602](INFO): SBD MO Status=1, MOMSN=48701, MT Status=0, MTMSN=0
2018-09-08T05:55:06.397Z,1536386106.397 [NAL9602](INFO): Sent 72 bytes from file Logs/20180908T054148/Express0005.lzma
2018-09-08T05:55:06.397Z,1536386106.397 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:55:06.399Z,1536386106.399 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T054148/Express0005.lzma.parts/0000.sbd
2018-09-08T05:55:06.399Z,1536386106.399 [NAL9602](DEBUG): Completed sending Logs/20180908T054148/Express0005.lzma
2018-09-08T05:55:15.268Z,1536386115.268 [NAL9602](INFO): SBD MO Status=1, MOMSN=48702, MT Status=0, MTMSN=0
2018-09-08T05:55:15.317Z,1536386115.317 [NAL9602](INFO): Sent 224 bytes from file Logs/20180908T054148/Express0008.lzma
2018-09-08T05:55:15.317Z,1536386115.317 [NAL9602](INFO): Packets left to send: 0
2018-09-08T05:55:15.322Z,1536386115.322 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180908T054148/Express0008.lzma.parts/0000.sbd
2018-09-08T05:55:15.322Z,1536386115.322 [NAL9602](DEBUG): Completed sending Logs/20180908T054148/Express0008.lzma
2018-09-08T05:55:23.246Z,1536386123.246 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T05:55:30.067Z,1536386130.067 [NAL9602](INFO): SBD MO Status=0, MOMSN=48703, MT Status=0, MTMSN=0
2018-09-08T05:55:30.169Z,1536386130.169 [Default:CheckIn:Read_Iridium] Stopped
2018-09-08T05:55:30.169Z,1536386130.169 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-08T05:55:30.169Z,1536386130.169 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-08T05:56:23.645Z,1536386183.645 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T05:57:03.299Z,1536386223.299 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:57:03.300Z,1536386223.300 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2018-09-08T05:57:03.300Z,1536386223.300 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T05:57:03.302Z,1536386223.302 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T05:57:03.302Z,1536386223.302 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-09-08T05:57:03.302Z,1536386223.302 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T05:57:03.375Z,1536386223.375 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T05:57:03.375Z,1536386223.375 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T05:57:03.704Z,1536386223.704 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T05:57:03.704Z,1536386223.704 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2018-09-08T05:57:03.705Z,1536386223.705 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T05:57:03.705Z,1536386223.705 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-09-08T05:57:04.094Z,1536386224.094 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T05:57:04.095Z,1536386224.095 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:57:04.095Z,1536386224.095 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:57:04.095Z,1536386224.095 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T05:57:04.096Z,1536386224.096 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T05:57:04.096Z,1536386224.096 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T05:57:04.490Z,1536386224.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-09-08T05:57:04.891Z,1536386224.891 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-09-08T05:57:05.292Z,1536386225.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-09-08T05:57:05.711Z,1536386225.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-09-08T05:57:06.101Z,1536386226.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-09-08T05:57:06.511Z,1536386226.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-09-08T05:57:06.894Z,1536386226.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-09-08T05:57:07.292Z,1536386227.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-09-08T05:57:07.674Z,1536386227.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-09-08T05:57:08.079Z,1536386228.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-09-08T05:57:08.507Z,1536386228.507 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-09-08T05:57:08.911Z,1536386228.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-09-08T05:57:09.303Z,1536386229.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-09-08T05:57:09.703Z,1536386229.703 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-09-08T05:57:10.079Z,1536386230.079 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-09-08T05:57:10.480Z,1536386230.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-09-08T05:57:10.883Z,1536386230.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-09-08T05:57:11.279Z,1536386231.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-09-08T05:57:11.685Z,1536386231.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-09-08T05:57:12.109Z,1536386232.109 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-09-08T05:57:12.507Z,1536386232.507 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-09-08T05:57:12.879Z,1536386232.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-09-08T05:57:13.276Z,1536386233.276 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-09-08T05:57:13.685Z,1536386233.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-09-08T05:57:14.083Z,1536386234.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-09-08T05:57:14.514Z,1536386234.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-09-08T05:57:14.911Z,1536386234.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-09-08T05:57:15.311Z,1536386235.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-09-08T05:57:15.676Z,1536386235.676 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-09-08T05:57:16.080Z,1536386236.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-09-08T05:57:16.479Z,1536386236.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-09-08T05:57:16.886Z,1536386236.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-09-08T05:57:17.279Z,1536386237.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-09-08T05:57:17.689Z,1536386237.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-09-08T05:57:18.113Z,1536386238.113 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-09-08T05:57:18.511Z,1536386238.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-09-08T05:57:18.878Z,1536386238.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-09-08T05:57:19.284Z,1536386239.284 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-09-08T05:57:19.682Z,1536386239.682 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-09-08T05:57:20.083Z,1536386240.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-09-08T05:57:20.512Z,1536386240.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-09-08T05:57:20.911Z,1536386240.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-09-08T05:57:21.313Z,1536386241.313 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-09-08T05:57:21.684Z,1536386241.684 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-09-08T05:57:22.084Z,1536386242.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-09-08T05:57:22.483Z,1536386242.483 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-09-08T05:57:22.891Z,1536386242.891 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-09-08T05:57:23.287Z,1536386243.287 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-09-08T05:57:24.047Z,1536386244.047 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T05:58:24.045Z,1536386304.045 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T05:59:24.445Z,1536386364.445 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T06:00:04.495Z,1536386404.495 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T06:00:04.495Z,1536386404.495 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2018-09-08T06:00:04.495Z,1536386404.495 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T06:00:04.497Z,1536386404.497 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T06:00:04.497Z,1536386404.497 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-09-08T06:00:04.497Z,1536386404.497 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T06:00:04.535Z,1536386404.535 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T06:00:04.535Z,1536386404.535 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T06:00:04.905Z,1536386404.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T06:00:04.905Z,1536386404.905 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2018-09-08T06:00:04.906Z,1536386404.906 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T06:00:04.906Z,1536386404.906 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-09-08T06:00:05.290Z,1536386405.290 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T06:00:05.291Z,1536386405.291 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T06:00:05.291Z,1536386405.291 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T06:00:05.292Z,1536386405.292 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T06:00:05.292Z,1536386405.292 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T06:00:05.292Z,1536386405.292 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T06:00:05.697Z,1536386405.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-09-08T06:00:06.092Z,1536386406.092 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-09-08T06:00:06.499Z,1536386406.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-09-08T06:00:06.899Z,1536386406.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-09-08T06:00:07.296Z,1536386407.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-09-08T06:00:07.690Z,1536386407.690 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-09-08T06:00:08.095Z,1536386408.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-09-08T06:00:08.492Z,1536386408.492 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-09-08T06:00:08.899Z,1536386408.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-09-08T06:00:09.291Z,1536386409.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-09-08T06:00:09.696Z,1536386409.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-09-08T06:00:10.099Z,1536386410.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-09-08T06:00:10.499Z,1536386410.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-09-08T06:00:10.892Z,1536386410.892 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-09-08T06:00:11.295Z,1536386411.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-09-08T06:00:11.693Z,1536386411.693 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-09-08T06:00:12.092Z,1536386412.092 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-09-08T06:00:12.503Z,1536386412.503 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-09-08T06:00:12.899Z,1536386412.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-09-08T06:00:13.296Z,1536386413.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-09-08T06:00:13.695Z,1536386413.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-09-08T06:00:14.095Z,1536386414.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-09-08T06:00:14.493Z,1536386414.493 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-09-08T06:00:14.893Z,1536386414.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-09-08T06:00:15.295Z,1536386415.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-09-08T06:00:15.696Z,1536386415.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-09-08T06:00:16.099Z,1536386416.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-09-08T06:00:16.499Z,1536386416.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-09-08T06:00:16.892Z,1536386416.892 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-09-08T06:00:17.295Z,1536386417.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-09-08T06:00:17.699Z,1536386417.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-09-08T06:00:18.085Z,1536386418.085 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-09-08T06:00:18.499Z,1536386418.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-09-08T06:00:18.897Z,1536386418.897 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-09-08T06:00:19.296Z,1536386419.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-09-08T06:00:19.695Z,1536386419.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-09-08T06:00:20.091Z,1536386420.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-09-08T06:00:20.492Z,1536386420.492 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-09-08T06:00:20.893Z,1536386420.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-09-08T06:00:21.295Z,1536386421.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-09-08T06:00:21.696Z,1536386421.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-09-08T06:00:22.103Z,1536386422.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-09-08T06:00:22.499Z,1536386422.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-09-08T06:00:22.889Z,1536386422.889 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-09-08T06:00:23.295Z,1536386423.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-09-08T06:00:23.699Z,1536386423.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-09-08T06:00:24.097Z,1536386424.097 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-09-08T06:00:24.449Z,1536386424.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-08T06:00:30.502Z,1536386430.502 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-09-08T06:00:30.502Z,1536386430.502 [Default:CheckIn:C.Wait] Stopped
2018-09-08T06:00:30.502Z,1536386430.502 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-08T06:00:30.502Z,1536386430.502 [Default:CheckIn:D] Running Loop=1
2018-09-08T06:00:30.912Z,1536386430.912 [Default:CheckIn:D] Stopped
2018-09-08T06:00:30.912Z,1536386430.912 [Default:CheckIn:E] Running Loop=1
2018-09-08T06:00:31.310Z,1536386431.310 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.155621 min 
2018-09-08T06:00:31.312Z,1536386431.312 [Default:CheckIn:E] Stopped
2018-09-08T06:00:31.312Z,1536386431.312 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-09-08T06:00:31.312Z,1536386431.312 [Default:CheckIn] Stopped
2018-09-08T06:00:31.313Z,1536386431.313 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-08T06:00:31.313Z,1536386431.313 [Default:CheckIn](INFO): Running loop #2
2018-09-08T06:00:31.313Z,1536386431.313 [Default:CheckIn] Running Loop=2
2018-09-08T06:00:31.313Z,1536386431.313 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-08T06:00:31.313Z,1536386431.313 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-08T06:01:15.266Z,1536386475.266 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.011276
2018-09-08T06:02:09.245Z,1536386529.245 [DataOverHttps](IMPORTANT): SBD MTMSN=20180908T060200
2018-09-08T06:02:53.616Z,1536386573.616 [DataOverHttps](INFO): Received command:set NAL9602.time_Fix 1536386494 second
2018-09-08T06:02:54.642Z,1536386574.642 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1536386432.000000 second
2018-09-08T06:02:54.695Z,1536386574.695 [Default:CheckIn:Read_GPS] Stopped
2018-09-08T06:02:54.695Z,1536386574.695 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-08T06:03:07.120Z,1536386587.120 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T06:03:07.120Z,1536386587.120 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2018-09-08T06:03:07.120Z,1536386587.120 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-08T06:03:07.122Z,1536386587.122 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-08T06:03:07.122Z,1536386587.122 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-09-08T06:03:07.122Z,1536386587.122 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-08T06:03:07.135Z,1536386587.135 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-08T06:03:07.135Z,1536386587.135 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-08T06:03:07.281Z,1536386587.281 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-08T06:03:07.281Z,1536386587.281 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2018-09-08T06:03:07.282Z,1536386587.282 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-08T06:03:07.282Z,1536386587.282 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-09-08T06:03:07.607Z,1536386587.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-08T06:03:07.607Z,1536386587.607 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T06:03:07.607Z,1536386587.607 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T06:03:07.608Z,1536386587.608 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-08T06:03:07.608Z,1536386587.608 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-08T06:03:07.609Z,1536386587.609 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-08T06:03:08.002Z,1536386588.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-09-08T06:03:08.399Z,1536386588.399 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2018-09-08T06:03:08.810Z,1536386588.810 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2018-09-08T06:03:09.158Z,1536386589.158 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20180908T054148/Courier0010.lzma
2018-09-08T06:03:09.233Z,1536386589.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2018-09-08T06:03:09.607Z,1536386589.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2018-09-08T06:03:09.999Z,1536386589.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2018-09-08T06:03:10.404Z,1536386590.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2018-09-08T06:03:10.802Z,1536386590.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2018-09-08T06:03:11.200Z,1536386591.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2018-09-08T06:03:11.605Z,1536386591.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-09-08T06:03:12.011Z,1536386592.011 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2018-09-08T06:03:12.410Z,1536386592.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2018-09-08T06:03:12.799Z,1536386592.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2018-09-08T06:03:13.198Z,1536386593.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2018-09-08T06:03:13.601Z,1536386593.601 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2018-09-08T06:03:14.004Z,1536386594.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2018-09-08T06:03:14.401Z,1536386594.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2018-09-08T06:03:14.807Z,1536386594.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2018-09-08T06:03:15.211Z,1536386595.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2018-09-08T06:03:15.607Z,1536386595.607 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2018-09-08T06:03:15.000Z,1536386596.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2018-09-08T06:03:16.394Z,1536386596.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2018-09-08T06:03:16.798Z,1536386596.798 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2018-09-08T06:03:17.202Z,1536386597.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2018-09-08T06:03:17.611Z,1536386597.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2018-09-08T06:03:17.958Z,1536386597.958 [DataOverHttps](INFO): Moved sent file to Logs/20180908T054148/Courier0010.lzma.bak
2018-09-08T06:03:17.959Z,1536386597.959 [DataOverHttps](INFO): SBD MOMSN=8494530
2018-09-08T06:03:18.019Z,1536386598.019 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2018-09-08T06:03:18.464Z,1536386598.464 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2018-09-08T06:03:18.799Z,1536386598.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-09-08T06:03:19.197Z,1536386599.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2018-09-08T06:03:19.603Z,1536386599.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2018-09-08T06:03:20.002Z,1536386600.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s.
2018-09-08T06:03:20.395Z,1536386600.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2018-09-08T06:03:20.763Z,1536386600.763 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-08T06:03:20.763Z,1536386600.763 [NAL9602] Data Fault, FailCount= 2
2018-09-08T06:03:20.763Z,1536386600.763 [NAL9602](ERROR): Data Fault
2018-09-08T06:03:20.812Z,1536386600.812 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2018-09-08T06:03:20.834Z,1536386600.834 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-08T06:03:21.163Z,1536386601.163 [NAL9602](INFO): Powering down
2018-09-08T06:03:21.206Z,1536386601.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2018-09-08T06:03:21.597Z,1536386601.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2018-09-08T06:03:22.003Z,1536386602.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2018-09-08T06:03:22.018Z,1536386602.018 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-08T06:03:22.018Z,1536386602.018 [NAL9602] No Fault, FailCount= 2
2018-09-08T06:03:22.398Z,1536386602.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2018-09-08T06:03:22.800Z,1536386602.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-09-08T06:03:23.207Z,1536386603.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-09-08T06:03:23.599Z,1536386603.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2018-09-08T06:03:23.992Z,1536386603.992 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2018-09-08T06:03:24.407Z,1536386604.407 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2018-09-08T06:03:24.801Z,1536386604.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2018-09-08T06:03:25.199Z,1536386605.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2018-09-08T06:03:25.584Z,1536386605.584 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2018-09-08T06:03:25.986Z,1536386605.986 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2018-09-08T06:03:26.380Z,1536386606.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2018-09-08T06:03:26.807Z,1536386606.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2018-09-08T06:03:48.742Z,1536386628.742 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-09-08T06:03:51.559Z,1536386631.559 [NAL9602](INFO): Powering up NAL9602
2018-09-08T06:04:05.457Z,1536386645.457 [NAL9602](INFO): NAL9602 initialized
2018-09-08T06:04:19.522Z,1536386659.522 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-09-08T06:04:39.514Z,1536386679.514 [DataOverHttps](IMPORTANT): SBD MTMSN=20180908T060430
2018-09-08T06:04:48.688Z,1536386688.688 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180908T054148/Courier0013.lzma
2018-09-08T06:04:48.690Z,1536386688.690 [DataOverHttps](INFO): Received command:maintain clear
2018-09-08T06:04:48.777Z,1536386688.777 [CommandLine](IMPORTANT): got command maintain clear
2018-09-08T06:04:57.487Z,1536386697.487 [DataOverHttps](INFO): Moved sent file to Logs/20180908T054148/Courier0013.lzma.bak
2018-09-08T06:04:57.487Z,1536386697.487 [DataOverHttps](INFO): SBD MOMSN=8494534
2018-09-08T06:05:17.487Z,1536386717.487 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180908T054148/Courier0016.lzma
2018-09-08T06:05:25.879Z,1536386725.879 [DataOverHttps](INFO): Moved sent file to Logs/20180908T054148/Courier0016.lzma.bak
2018-09-08T06:05:25.879Z,1536386725.879 [DataOverHttps](INFO): SBD MOMSN=8494537
2018-09-08T06:05:42.270Z,1536386742.270 [DataOverHttps](IMPORTANT): SBD MTMSN=20180908T060533
2018-09-08T06:05:51.462Z,1536386751.462 [DataOverHttps](INFO): Sending 700 bytes from file Logs/20180908T054148/Express0011.lzma
2018-09-08T06:05:51.466Z,1536386751.466 [DataOverHttps](INFO): Received command:restart sys
2018-09-08T06:05:51.502Z,1536386751.502 [CommandLine](IMPORTANT): got command restart system
2018-09-08T06:05:53.741Z,1536386753.741 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-09-08T06:05:53.742Z,1536386753.742 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T06:05:53.742Z,1536386753.742 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:53.789Z,1536386753.789 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-08T06:05:53.790Z,1536386753.790 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:53.790Z,1536386753.790 [CommandLine](INFO): Join timeout helper Thread ID is 1091
2018-09-08T06:05:53.791Z,1536386753.791 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-08T06:05:53.791Z,1536386753.791 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:53.791Z,1536386753.791 [NavChartDb](INFO): Join timeout helper Thread ID is 1092
2018-09-08T06:05:53.805Z,1536386753.805 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T06:05:53.805Z,1536386753.805 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:53.813Z,1536386753.813 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-08T06:05:53.813Z,1536386753.813 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:53.813Z,1536386753.813 [Radio_Surface](INFO): Join timeout helper Thread ID is 1093
2018-09-08T06:05:54.081Z,1536386754.081 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T06:05:54.081Z,1536386754.081 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:54.093Z,1536386754.093 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-09-08T06:05:54.093Z,1536386754.093 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:54.093Z,1536386754.093 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1094
2018-09-08T06:05:54.293Z,1536386754.293 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T06:05:54.293Z,1536386754.293 [WetLabsBB2FL](INFO): Powering down
2018-09-08T06:05:54.294Z,1536386754.294 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:54.295Z,1536386754.295 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-09-08T06:05:54.295Z,1536386754.295 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:54.296Z,1536386754.296 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1095
2018-09-08T06:05:54.585Z,1536386754.585 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T06:05:54.748Z,1536386754.748 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-08T06:05:54.748Z,1536386754.748 [CTD_Seabird](INFO): Powering down
2018-09-08T06:05:54.749Z,1536386754.749 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:54.759Z,1536386754.759 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-08T06:05:54.759Z,1536386754.759 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:54.759Z,1536386754.759 [logger](INFO): Join timeout helper Thread ID is 1097
2018-09-08T06:05:54.790Z,1536386754.790 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T06:05:54.791Z,1536386754.791 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:54.806Z,1536386754.806 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-08T06:05:54.806Z,1536386754.806 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:54.806Z,1536386754.806 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-08T06:05:54.806Z,1536386754.806 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:54.806Z,1536386754.806 [controlThread](INFO): Join timeout helper Thread ID is 1098
2018-09-08T06:05:55.045Z,1536386755.045 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-08T06:05:55.045Z,1536386755.045 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-08T06:05:55.047Z,1536386755.047 [AHRS_M2](INFO): Powering down
2018-09-08T06:05:55.124Z,1536386755.124 [NAL9602](INFO): Powering down
2018-09-08T06:05:55.125Z,1536386755.125 [DAT](INFO): Powering down
2018-09-08T06:05:55.246Z,1536386755.246 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-08T06:05:55.247Z,1536386755.247 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-08T06:05:55.248Z,1536386755.248 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-08T06:05:55.248Z,1536386755.248 [MissionManager](INFO): Uninitializing Mission Default
2018-09-08T06:05:55.249Z,1536386755.249 [Default] Stopped
2018-09-08T06:05:55.249Z,1536386755.249 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-08T06:05:55.249Z,1536386755.249 [Default:B.GoToSurface] Stopped
2018-09-08T06:05:55.249Z,1536386755.249 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-08T06:05:55.249Z,1536386755.249 [Default:CheckIn] Stopped
2018-09-08T06:05:55.249Z,1536386755.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-08T06:05:55.249Z,1536386755.249 [Default:CheckIn:Read_Iridium] Stopped
2018-09-08T06:05:55.253Z,1536386755.253 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-08T06:05:55.253Z,1536386755.253 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-08T06:05:55.254Z,1536386755.254 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-08T06:05:55.254Z,1536386755.254 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-08T06:05:55.254Z,1536386755.254 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-08T06:05:55.255Z,1536386755.255 [BuoyancyServo](INFO): Powering down
2018-09-08T06:05:55.269Z,1536386755.269 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-09-08T06:05:55.269Z,1536386755.269 [ElevatorServo](INFO): Powering down
2018-09-08T06:05:55.270Z,1536386755.270 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-09-08T06:05:55.270Z,1536386755.270 [MassServo](INFO): Powering down
2018-09-08T06:05:55.271Z,1536386755.271 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-09-08T06:05:55.271Z,1536386755.271 [RudderServo](INFO): Powering down
2018-09-08T06:05:55.272Z,1536386755.272 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-08T06:05:55.272Z,1536386755.272 [ThrusterServo](INFO): Powering down
2018-09-08T06:05:55.274Z,1536386755.274 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-08T06:05:55.274Z,1536386755.274 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-08T06:05:55.274Z,1536386755.274 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-08T06:05:55.275Z,1536386755.275 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:55.312Z,1536386755.312 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:55.318Z,1536386755.318 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:55.403Z,1536386755.403 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:55.465Z,1536386755.465 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-08T06:05:55.526Z,1536386755.526 [logger ThreadHandler](INFO): Thread cancelled.