2018-05-23T22:17:57.604Z,1527113877.604 [Supervisor](DEBUG): Initializing supervisor.
2018-05-23T22:17:57.607Z,1527113877.607 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-23T22:17:57.607Z,1527113877.607 [SyncHandler](INFO): Protected caller Thread ID is 5532
2018-05-23T22:17:57.608Z,1527113877.608 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-23T22:17:57.609Z,1527113877.609 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-23T22:17:57.609Z,1527113877.609 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5533
2018-05-23T22:17:57.612Z,1527113877.612 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-23T22:17:57.623Z,1527113877.623 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-23T22:17:57.624Z,1527113877.624 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-23T22:17:57.624Z,1527113877.624 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5534
2018-05-23T22:17:57.625Z,1527113877.625 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-23T22:17:57.626Z,1527113877.626 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-23T22:17:57.626Z,1527113877.626 [logger ThreadHandler](INFO): Protected caller Thread ID is 5535
2018-05-23T22:17:57.628Z,1527113877.628 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-23T22:17:57.629Z,1527113877.629 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-23T22:17:57.630Z,1527113877.630 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-23T22:17:57.939Z,1527113877.939 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-23T22:17:57.940Z,1527113877.940 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-23T22:17:58.130Z,1527113878.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-23T22:17:58.130Z,1527113878.130 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-23T22:17:58.402Z,1527113878.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-23T22:17:58.403Z,1527113878.403 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-23T22:17:58.537Z,1527113878.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-23T22:17:58.537Z,1527113878.537 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-23T22:17:58.633Z,1527113878.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-23T22:17:58.634Z,1527113878.634 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-23T22:17:59.075Z,1527113879.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-23T22:17:59.076Z,1527113879.076 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-23T22:17:59.441Z,1527113879.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-23T22:17:59.441Z,1527113879.441 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-23T22:17:59.863Z,1527113879.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-23T22:17:59.863Z,1527113879.863 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-23T22:17:59.967Z,1527113879.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-23T22:17:59.968Z,1527113879.968 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-23T22:18:00.063Z,1527113880.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-23T22:18:00.064Z,1527113880.064 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-23T22:18:00.262Z,1527113880.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-23T22:18:00.262Z,1527113880.262 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-23T22:18:00.462Z,1527113880.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-23T22:18:00.463Z,1527113880.463 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-23T22:18:00.795Z,1527113880.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-23T22:18:00.795Z,1527113880.795 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-23T22:18:00.975Z,1527113880.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-23T22:18:00.976Z,1527113880.976 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-23T22:18:01.218Z,1527113881.218 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-05-23T22:18:01.219Z,1527113881.219 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-05-23T22:18:01.322Z,1527113881.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-05-23T22:18:01.514Z,1527113881.514 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-05-23T22:18:01.642Z,1527113881.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-05-23T22:18:01.799Z,1527113881.799 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-05-23T22:18:01.958Z,1527113881.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-05-23T22:18:02.133Z,1527113882.133 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-05-23T22:18:02.210Z,1527113882.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-05-23T22:18:02.435Z,1527113882.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-23T22:18:02.435Z,1527113882.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-05-23T22:18:02.518Z,1527113882.518 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-05-23T22:18:02.606Z,1527113882.606 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-05-23T22:18:02.703Z,1527113882.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-05-23T22:18:02.824Z,1527113882.824 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-05-23T22:18:02.833Z,1527113882.833 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-23T22:18:03.058Z,1527113883.058 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-23T22:18:03.058Z,1527113883.058 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-23T22:18:03.098Z,1527113883.098 [DepthRateCalculator] Loaded
2018-05-23T22:18:03.098Z,1527113883.098 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-23T22:18:03.104Z,1527113883.104 [PitchRateCalculator] Loaded
2018-05-23T22:18:03.104Z,1527113883.104 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-23T22:18:03.119Z,1527113883.119 [SpeedCalculator] Loaded
2018-05-23T22:18:03.120Z,1527113883.120 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-23T22:18:03.140Z,1527113883.140 [TempGradientCalculator] Loaded
2018-05-23T22:18:03.140Z,1527113883.140 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-23T22:18:03.155Z,1527113883.155 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-23T22:18:03.155Z,1527113883.155 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-23T22:18:03.161Z,1527113883.161 [YawRateCalculator] Loaded
2018-05-23T22:18:03.161Z,1527113883.161 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-23T22:18:03.200Z,1527113883.200 [ElevatorOffsetCalculator] Loaded
2018-05-23T22:18:03.200Z,1527113883.200 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-23T22:18:03.201Z,1527113883.201 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-23T22:18:03.201Z,1527113883.201 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-23T22:18:03.280Z,1527113883.280 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-23T22:18:03.280Z,1527113883.280 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-23T22:18:03.281Z,1527113883.281 [StratificationFrontDetector] Loaded
2018-05-23T22:18:03.281Z,1527113883.281 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-23T22:18:03.281Z,1527113883.281 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-23T22:18:03.282Z,1527113883.282 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-23T22:18:03.391Z,1527113883.391 [BuoyancyServo] Loaded
2018-05-23T22:18:03.391Z,1527113883.391 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-23T22:18:03.406Z,1527113883.406 [ElevatorServo] Loaded
2018-05-23T22:18:03.406Z,1527113883.406 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-23T22:18:03.420Z,1527113883.420 [MassServo] Loaded
2018-05-23T22:18:03.420Z,1527113883.420 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-23T22:18:03.435Z,1527113883.435 [RudderServo] Loaded
2018-05-23T22:18:03.435Z,1527113883.435 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-23T22:18:03.449Z,1527113883.449 [ThrusterServo] Loaded
2018-05-23T22:18:03.449Z,1527113883.449 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-23T22:18:03.450Z,1527113883.450 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-23T22:18:03.450Z,1527113883.450 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-23T22:18:03.463Z,1527113883.463 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-23T22:18:03.464Z,1527113883.464 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-23T22:18:03.558Z,1527113883.558 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-23T22:18:03.559Z,1527113883.559 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-23T22:18:03.828Z,1527113883.828 [DataOverHttps] Loaded
2018-05-23T22:18:03.828Z,1527113883.828 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-23T22:18:03.842Z,1527113883.842 [Depth_Keller] Loaded
2018-05-23T22:18:03.842Z,1527113883.842 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-23T22:18:03.846Z,1527113883.846 [DropWeight] Loaded
2018-05-23T22:18:03.847Z,1527113883.847 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-05-23T22:18:03.940Z,1527113883.940 [NAL9602] Loaded
2018-05-23T22:18:03.940Z,1527113883.940 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-23T22:18:03.955Z,1527113883.955 [Onboard] Loaded
2018-05-23T22:18:03.956Z,1527113883.956 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-23T22:18:03.965Z,1527113883.965 [Radio_Surface] Loaded
2018-05-23T22:18:03.966Z,1527113883.966 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-23T22:18:03.967Z,1527113883.967 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2018-05-23T22:18:03.967Z,1527113883.967 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5617
2018-05-23T22:18:04.013Z,1527113884.013 [PNI_TCM] Loaded
2018-05-23T22:18:04.013Z,1527113884.013 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-05-23T22:18:05.707Z,1527113885.707 [BPC1] Loaded
2018-05-23T22:18:05.708Z,1527113885.708 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-23T22:18:05.708Z,1527113885.708 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-23T22:18:05.709Z,1527113885.709 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-23T22:18:05.831Z,1527113885.831 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-23T22:18:05.852Z,1527113885.852 [SBIT] Loaded
2018-05-23T22:18:05.852Z,1527113885.852 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-23T22:18:05.853Z,1527113885.853 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-23T22:18:05.864Z,1527113885.864 [IBIT] Loaded
2018-05-23T22:18:05.865Z,1527113885.865 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-23T22:18:05.868Z,1527113885.868 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-23T22:18:06.013Z,1527113886.013 [CBIT] Loaded
2018-05-23T22:18:06.013Z,1527113886.013 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-23T22:18:06.013Z,1527113886.013 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-23T22:18:06.014Z,1527113886.014 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-23T22:18:06.359Z,1527113886.359 [Aanderaa_O2] Loaded
2018-05-23T22:18:06.359Z,1527113886.359 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-05-23T22:18:06.371Z,1527113886.371 [CANONSampler] Loaded
2018-05-23T22:18:06.371Z,1527113886.371 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2018-05-23T22:18:06.446Z,1527113886.446 [CTD_NeilBrown] Loaded
2018-05-23T22:18:06.446Z,1527113886.446 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-05-23T22:18:06.447Z,1527113886.447 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409444E0
2018-05-23T22:18:06.448Z,1527113886.448 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5618
2018-05-23T22:18:06.617Z,1527113886.617 [CTD_Seabird] Loaded
2018-05-23T22:18:06.618Z,1527113886.618 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-23T22:18:06.619Z,1527113886.619 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409744E0
2018-05-23T22:18:06.619Z,1527113886.619 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5619
2018-05-23T22:18:06.635Z,1527113886.635 [ESPComponent] Loaded
2018-05-23T22:18:06.635Z,1527113886.635 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-05-23T22:18:06.654Z,1527113886.654 [PAR_Licor] Loaded
2018-05-23T22:18:06.654Z,1527113886.654 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-23T22:18:06.701Z,1527113886.701 [WetLabsBB2FL] Loaded
2018-05-23T22:18:06.701Z,1527113886.701 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-05-23T22:18:06.702Z,1527113886.702 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0
2018-05-23T22:18:06.702Z,1527113886.702 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5620
2018-05-23T22:18:06.703Z,1527113886.703 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-23T22:18:06.703Z,1527113886.703 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-23T22:18:06.780Z,1527113886.780 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-23T22:18:06.860Z,1527113886.860 [VerticalControl] Loaded
2018-05-23T22:18:06.861Z,1527113886.861 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-23T22:18:06.861Z,1527113886.861 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-23T22:18:06.917Z,1527113886.917 [HorizontalControl] Loaded
2018-05-23T22:18:06.917Z,1527113886.917 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-23T22:18:06.918Z,1527113886.918 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-23T22:18:06.919Z,1527113886.919 [SpeedControl] Loaded
2018-05-23T22:18:06.920Z,1527113886.920 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-23T22:18:06.920Z,1527113886.920 [LoopControl](DEBUG): Construct LoopControl.
2018-05-23T22:18:06.921Z,1527113886.921 [LoopControl] Loaded
2018-05-23T22:18:06.921Z,1527113886.921 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-23T22:18:06.922Z,1527113886.922 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-23T22:18:06.922Z,1527113886.922 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-23T22:18:06.946Z,1527113886.946 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-23T22:18:06.947Z,1527113886.947 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-23T22:18:07.034Z,1527113887.034 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-23T22:18:07.035Z,1527113887.035 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-23T22:18:07.088Z,1527113887.088 [DeadReckonWithRespectToSeafloor] Loaded
2018-05-23T22:18:07.088Z,1527113887.088 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-05-23T22:18:07.104Z,1527113887.104 [NavChart] Loaded
2018-05-23T22:18:07.104Z,1527113887.104 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-23T22:18:07.108Z,1527113887.108 [UniversalFixResidualReporter] Loaded
2018-05-23T22:18:07.108Z,1527113887.108 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-23T22:18:07.109Z,1527113887.109 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-23T22:18:07.112Z,1527113887.112 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-23T22:18:07.113Z,1527113887.113 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-23T22:18:07.120Z,1527113887.120 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-23T22:18:07.121Z,1527113887.121 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A304E0
2018-05-23T22:18:07.121Z,1527113887.121 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5621
2018-05-23T22:18:07.126Z,1527113887.126 [Supervisor](INFO): Main Thread ID is 797
2018-05-23T22:18:07.126Z,1527113887.126 [Supervisor](DEBUG): Running supervisor.
2018-05-23T22:18:07.127Z,1527113887.127 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5622
2018-05-23T22:18:07.129Z,1527113887.129 [controlThread ThreadHandler](INFO): Handler Thread ID is 5623
2018-05-23T22:18:07.130Z,1527113887.130 [controlThread](DEBUG): Initializing ControlThread
2018-05-23T22:18:07.131Z,1527113887.131 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-23T22:18:07.131Z,1527113887.131 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-23T22:18:07.131Z,1527113887.131 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-23T22:18:07.132Z,1527113887.132 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-23T22:18:07.132Z,1527113887.132 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-23T22:18:07.133Z,1527113887.133 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-23T22:18:07.133Z,1527113887.133 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-23T22:18:07.141Z,1527113887.141 [SBIT](INFO): Initialize SBIT Component.
2018-05-23T22:18:07.141Z,1527113887.141 [SBIT](IMPORTANT): git: 2018-05-17
2018-05-23T22:18:07.142Z,1527113887.142 [SBIT](INFO): git hash: 418d027d9554c4aed44cef59bd1b73332cce0e52
2018-05-23T22:18:07.142Z,1527113887.142 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-23T22:18:07.143Z,1527113887.143 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-05-23T22:18:07.144Z,1527113887.144 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2018-05-23T22:18:07.145Z,1527113887.145 [IBIT](INFO): Initialize IBIT Component.
2018-05-23T22:18:07.146Z,1527113887.146 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-23T22:18:07.146Z,1527113887.146 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-05-23T22:18:07.147Z,1527113887.147 [logger ThreadHandler](INFO): Handler Thread ID is 5624
2018-05-23T22:18:07.167Z,1527113887.167 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5625
2018-05-23T22:18:07.173Z,1527113887.173 [Radio_Surface](INFO): Powering up
2018-05-23T22:18:07.179Z,1527113887.179 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5626
2018-05-23T22:18:07.180Z,1527113887.180 [CTD_NeilBrown](INFO): Powering down
2018-05-23T22:18:07.203Z,1527113887.203 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5627
2018-05-23T22:18:07.203Z,1527113887.203 [CTD_Seabird](INFO): Initializing
2018-05-23T22:18:07.204Z,1527113887.204 [CTD_Seabird](INFO): Checking LCM
2018-05-23T22:18:07.222Z,1527113887.222 [CTD_Seabird](INFO): LCM OK
2018-05-23T22:18:07.222Z,1527113887.222 [CTD_Seabird](INFO): Powering up
2018-05-23T22:18:07.231Z,1527113887.231 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5628
2018-05-23T22:18:07.232Z,1527113887.232 [WetLabsBB2FL](INFO): Powering down
2018-05-23T22:18:07.259Z,1527113887.259 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-23T22:18:07.260Z,1527113887.260 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-23T22:18:07.261Z,1527113887.261 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-23T22:18:07.261Z,1527113887.261 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-23T22:18:07.262Z,1527113887.262 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-23T22:18:07.263Z,1527113887.263 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:18:07.263Z,1527113887.263 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:18:07.264Z,1527113887.264 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:18:07.264Z,1527113887.264 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:18:07.264Z,1527113887.264 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:18:07.265Z,1527113887.265 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-23T22:18:07.265Z,1527113887.265 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-23T22:18:07.266Z,1527113887.266 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-23T22:18:07.271Z,1527113887.271 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5629
2018-05-23T22:18:07.274Z,1527113887.274 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-23T22:18:07.274Z,1527113887.274 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-23T22:18:07.275Z,1527113887.275 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-23T22:18:07.275Z,1527113887.275 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-23T22:18:07.275Z,1527113887.275 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-23T22:18:07.275Z,1527113887.275 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-23T22:18:07.275Z,1527113887.275 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-23T22:18:07.275Z,1527113887.275 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-23T22:18:07.276Z,1527113887.276 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-23T22:18:07.276Z,1527113887.276 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-23T22:18:07.276Z,1527113887.276 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-23T22:18:07.276Z,1527113887.276 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-23T22:18:07.277Z,1527113887.277 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-23T22:18:07.277Z,1527113887.277 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-23T22:18:07.277Z,1527113887.277 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-23T22:18:07.277Z,1527113887.277 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-23T22:18:07.321Z,1527113887.321 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-23T22:18:07.348Z,1527113887.348 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-05-23T22:18:07.349Z,1527113887.349 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-23T22:18:07.429Z,1527113887.429 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-23T22:18:07.446Z,1527113887.446 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-23T22:18:07.448Z,1527113887.448 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-23T22:18:07.471Z,1527113887.471 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-23T22:18:07.473Z,1527113887.473 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-23T22:18:07.503Z,1527113887.503 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-23T22:18:07.514Z,1527113887.514 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-05-23T22:18:07.519Z,1527113887.519 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,CANONSampler,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-23T22:18:07.559Z,1527113887.559 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-23T22:18:07.584Z,1527113887.584 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-05-23T22:18:07.784Z,1527113887.784 [CANONSampler](INFO): Powering down
2018-05-23T22:18:07.980Z,1527113887.980 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-05-23T22:18:08.079Z,1527113888.079 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:08.094Z,1527113888.094 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:08.211Z,1527113888.211 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-23T22:18:08.218Z,1527113888.218 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-23T22:18:08.241Z,1527113888.241 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-23T22:18:08.246Z,1527113888.246 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-23T22:18:08.253Z,1527113888.253 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-23T22:18:08.258Z,1527113888.258 [MassServo](DEBUG): Initializing MassServo.
2018-05-23T22:18:08.264Z,1527113888.264 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-23T22:18:08.270Z,1527113888.270 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-23T22:18:08.292Z,1527113888.292 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-23T22:18:08.299Z,1527113888.299 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-23T22:18:08.650Z,1527113888.650 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:08.651Z,1527113888.651 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:08.817Z,1527113888.817 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:08.818Z,1527113888.818 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:09.343Z,1527113889.343 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:09.344Z,1527113889.344 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:09.747Z,1527113889.747 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:09.747Z,1527113889.747 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:10.142Z,1527113890.142 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:10.146Z,1527113890.146 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:10.526Z,1527113890.526 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:10.527Z,1527113890.527 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:11.030Z,1527113891.030 [Aanderaa_O2](INFO): Powering down
2018-05-23T22:18:11.130Z,1527113891.130 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:11.131Z,1527113891.131 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-05-23T22:18:12.786Z,1527113892.786 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-23T22:18:12.869Z,1527113892.869 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-05-23T22:18:12.927Z,1527113892.927 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-23T22:18:13.305Z,1527113893.305 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-23T22:18:13.305Z,1527113893.305 [CTD_Seabird](INFO): Powering down
2018-05-23T22:18:14.593Z,1527113894.593 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.395847 seconds old.
2018-05-23T22:18:14.594Z,1527113894.594 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 0.396226 seconds old.
2018-05-23T22:18:14.997Z,1527113894.997 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.799840 seconds old.
2018-05-23T22:18:15.006Z,1527113895.006 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 0.800232 seconds old.
2018-05-23T22:18:15.393Z,1527113895.393 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.195945 seconds old.
2018-05-23T22:18:15.402Z,1527113895.402 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 1.204522 seconds old.
2018-05-23T22:18:15.798Z,1527113895.798 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.600679 seconds old.
2018-05-23T22:18:15.799Z,1527113895.799 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 1.601140 seconds old.
2018-05-23T22:18:16.197Z,1527113896.197 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.000040 seconds old.
2018-05-23T22:18:16.199Z,1527113896.199 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 2.000600 seconds old.
2018-05-23T22:18:16.580Z,1527113896.580 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.383128 seconds old.
2018-05-23T22:18:16.581Z,1527113896.581 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 2.383505 seconds old.
2018-05-23T22:18:17.005Z,1527113897.005 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.808146 seconds old.
2018-05-23T22:18:17.007Z,1527113897.007 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 2.808765 seconds old.
2018-05-23T22:18:17.382Z,1527113897.382 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.184421 seconds old.
2018-05-23T22:18:17.391Z,1527113897.391 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 3.193006 seconds old.
2018-05-23T22:18:17.797Z,1527113897.797 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.599275 seconds old.
2018-05-23T22:18:17.798Z,1527113897.798 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 3.599655 seconds old.
2018-05-23T22:18:18.243Z,1527113898.243 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.045818 seconds old.
2018-05-23T22:18:18.244Z,1527113898.244 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 4.046205 seconds old.
2018-05-23T22:18:18.597Z,1527113898.597 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.399330 seconds old.
2018-05-23T22:18:18.598Z,1527113898.598 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 4.399711 seconds old.
2018-05-23T22:18:18.997Z,1527113898.997 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.799404 seconds old.
2018-05-23T22:18:18.998Z,1527113898.998 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 4.799770 seconds old.
2018-05-23T22:18:19.440Z,1527113899.440 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.242797 seconds old.
2018-05-23T22:18:19.441Z,1527113899.441 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 5.243168 seconds old.
2018-05-23T22:18:19.795Z,1527113899.795 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.598171 seconds old.
2018-05-23T22:18:19.796Z,1527113899.796 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 5.598550 seconds old.
2018-05-23T22:18:20.196Z,1527113900.196 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.998611 seconds old.
2018-05-23T22:18:20.197Z,1527113900.197 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 5.998981 seconds old.
2018-05-23T22:18:20.600Z,1527113900.600 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.403029 seconds old.
2018-05-23T22:18:20.601Z,1527113900.601 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 6.403410 seconds old.
2018-05-23T22:18:20.996Z,1527113900.996 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.798868 seconds old.
2018-05-23T22:18:20.997Z,1527113900.997 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 6.799249 seconds old.
2018-05-23T22:18:21.405Z,1527113901.405 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.207399 seconds old.
2018-05-23T22:18:21.406Z,1527113901.406 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 7.207766 seconds old.
2018-05-23T22:18:21.831Z,1527113901.831 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.633647 seconds old.
2018-05-23T22:18:21.832Z,1527113901.832 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 7.634027 seconds old.
2018-05-23T22:18:22.197Z,1527113902.197 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 7.999370 seconds old.
2018-05-23T22:18:22.198Z,1527113902.198 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 7.999749 seconds old.
2018-05-23T22:18:22.597Z,1527113902.597 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.399472 seconds old.
2018-05-23T22:18:22.598Z,1527113902.598 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 8.399853 seconds old.
2018-05-23T22:18:23.068Z,1527113903.068 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 8.870437 seconds old.
2018-05-23T22:18:23.069Z,1527113903.069 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 8.870965 seconds old.
2018-05-23T22:18:23.408Z,1527113903.408 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.210447 seconds old.
2018-05-23T22:18:23.409Z,1527113903.409 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 9.210829 seconds old.
2018-05-23T22:18:23.793Z,1527113903.793 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.595682 seconds old.
2018-05-23T22:18:23.794Z,1527113903.794 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 9.596081 seconds old.
2018-05-23T22:18:24.197Z,1527113904.197 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 9.999380 seconds old.
2018-05-23T22:18:24.198Z,1527113904.198 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 9.999763 seconds old.
2018-05-23T22:18:24.597Z,1527113904.597 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.399489 seconds old.
2018-05-23T22:18:24.598Z,1527113904.598 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 10.399923 seconds old.
2018-05-23T22:18:24.997Z,1527113904.997 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 10.799556 seconds old.
2018-05-23T22:18:25.006Z,1527113905.006 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 10.799969 seconds old.
2018-05-23T22:18:25.419Z,1527113905.419 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.221648 seconds old.
2018-05-23T22:18:25.420Z,1527113905.420 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 11.222070 seconds old.
2018-05-23T22:18:25.843Z,1527113905.843 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 11.645868 seconds old.
2018-05-23T22:18:25.844Z,1527113905.844 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 11.646314 seconds old.
2018-05-23T22:18:26.212Z,1527113906.212 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.014697 seconds old.
2018-05-23T22:18:26.213Z,1527113906.213 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 12.015126 seconds old.
2018-05-23T22:18:26.635Z,1527113906.635 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.437729 seconds old.
2018-05-23T22:18:26.636Z,1527113906.636 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 12.438160 seconds old.
2018-05-23T22:18:26.997Z,1527113906.997 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 12.799324 seconds old.
2018-05-23T22:18:26.998Z,1527113906.998 [DeadReckonWithRespectToSeafloor](DEBUG): Most recent orientation data is 12.799745 seconds old.
2018-05-23T22:18:30.668Z,1527113910.668 [SBIT](IMPORTANT): Beginning Startup BIT
2018-05-23T22:18:30.680Z,1527113910.680 [CBIT](IMPORTANT): Beginning ground fault scan
2018-05-23T22:18:34.244Z,1527113914.244 [NAL9602](INFO): Powering up NAL9602
2018-05-23T22:18:41.539Z,1527113921.539 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.015035
CHAN A1 (24V): -0.018146
CHAN A2 (12V): -0.002310
CHAN A3 (5V): -0.001683
CHAN B0 (3.3V): -0.000205
CHAN B1 (3.15aV): -0.000034
CHAN B2 (3.15bV): -0.000529
CHAN B3 (GND): 0.000435
OPEN: -0.000398
Full Scale Calc: 4.765 mA, -1.589 mA
2018-05-23T22:18:45.444Z,1527113925.444 [NAL9602](INFO): NAL9602 initialized
2018-05-23T22:19:24.666Z,1527113964.666 [SBIT](IMPORTANT): SBIT PASSED
2018-05-23T22:19:24.695Z,1527113964.695 [CommandLine](IMPORTANT): got command configSet list
2018-05-23T22:19:24.696Z,1527113964.696 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-05-23T22:19:24.697Z,1527113964.697 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2018-05-23T22:19:24.697Z,1527113964.697 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2018-05-23T22:19:24.697Z,1527113964.697 [CommandLine](IMPORTANT): Normal linearApproximation CTD_NeilBrown.bin_mean_sea_water_salinity 0.020000 practical_salinity_unit;

2018-05-23T22:19:24.697Z,1527113964.697 [CommandLine](IMPORTANT): Normal linearApproximation CTD_NeilBrown.bin_mean_sea_water_temperature 0.150000 kelvin;

2018-05-23T22:19:24.697Z,1527113964.697 [CommandLine](IMPORTANT): Normal linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.020000 practical_salinity_unit;

2018-05-23T22:19:24.697Z,1527113964.697 [CommandLine](IMPORTANT): Normal linearApproximation CTD_Seabird.bin_median_sea_water_temperature 0.150000 kelvin;

2018-05-23T22:19:24.697Z,1527113964.697 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter;

2018-05-23T22:19:24.697Z,1527113964.697 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-05-23T22:19:25.043Z,1527113965.043 [MissionManager](IMPORTANT): Started mission Startup
2018-05-23T22:19:25.043Z,1527113965.043 [Startup] Running Loop=1
2018-05-23T22:19:25.043Z,1527113965.043 [Startup](DEBUG): Aggregate::initialize Startup
2018-05-23T22:19:25.043Z,1527113965.043 [Startup:A.GoToSurface] Running Loop=1
2018-05-23T22:19:25.043Z,1527113965.043 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-23T22:19:25.044Z,1527113965.044 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-23T22:19:25.044Z,1527113965.044 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-23T22:19:25.045Z,1527113965.045 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-23T22:19:25.045Z,1527113965.045 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-23T22:19:25.045Z,1527113965.045 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-23T22:19:25.071Z,1527113965.071 [Startup:StartupSatComms] Running Loop=1
2018-05-23T22:19:25.072Z,1527113965.072 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-05-23T22:19:25.072Z,1527113965.072 [Startup:StartupSatComms:A] Running Loop=1
2018-05-23T22:19:25.439Z,1527113965.439 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-05-23T22:19:38.597Z,1527113978.597 [NAL9602](INFO): SBD MO Status=2, MOMSN=6051, MT Status=2, MTMSN=0
2018-05-23T22:19:38.598Z,1527113978.598 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T22:20:25.448Z,1527114025.448 [Startup:StartupSatComms:A](INFO): Timed out from 2018-05-23T22:19:25.1Z
2018-05-23T22:20:25.448Z,1527114025.448 [Startup:StartupSatComms:A] Stopped
2018-05-23T22:20:25.448Z,1527114025.448 [Startup:StartupSatComms:B] Running Loop=1
2018-05-23T22:20:25.852Z,1527114025.852 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-23T22:21:07.436Z,1527114067.436 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:21:07.436Z,1527114067.436 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-05-23T22:21:07.436Z,1527114067.436 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-23T22:21:07.438Z,1527114067.438 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:21:07.438Z,1527114067.438 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T22:21:07.438Z,1527114067.438 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:21:07.450Z,1527114067.450 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-23T22:21:07.470Z,1527114067.470 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:21:07.849Z,1527114067.849 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-23T22:21:07.850Z,1527114067.850 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-05-23T22:21:07.850Z,1527114067.850 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:21:07.850Z,1527114067.850 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T22:21:08.234Z,1527114068.234 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-23T22:21:08.235Z,1527114068.235 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:21:08.235Z,1527114068.235 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:21:08.235Z,1527114068.235 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:21:08.236Z,1527114068.236 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:21:08.236Z,1527114068.236 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:21:25.839Z,1527114085.839 [Startup:StartupSatComms:B](INFO): Timed out from 2018-05-23T22:20:25.4Z
2018-05-23T22:21:25.840Z,1527114085.840 [Startup:StartupSatComms:B] Stopped
2018-05-23T22:21:25.840Z,1527114085.840 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-05-23T22:21:25.840Z,1527114085.840 [Startup:StartupSatComms] Stopped
2018-05-23T22:21:25.840Z,1527114085.840 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-05-23T22:21:25.841Z,1527114085.841 [Startup](INFO): Completed Startup
2018-05-23T22:21:25.841Z,1527114085.841 [MissionManager](INFO): Startup is completed.
2018-05-23T22:21:25.841Z,1527114085.841 [MissionManager](INFO): Uninitializing Mission Startup
2018-05-23T22:21:25.841Z,1527114085.841 [Startup] Stopped
2018-05-23T22:21:25.841Z,1527114085.841 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-05-23T22:21:25.841Z,1527114085.841 [Startup:A.GoToSurface] Stopped
2018-05-23T22:21:25.841Z,1527114085.841 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-23T22:21:26.239Z,1527114086.239 [MissionManager](IMPORTANT): Started mission Default
2018-05-23T22:21:26.239Z,1527114086.239 [Default] Running Loop=1
2018-05-23T22:21:26.239Z,1527114086.239 [Default](DEBUG): Aggregate::initialize Default
2018-05-23T22:21:26.239Z,1527114086.239 [Default:B.GoToSurface] Running Loop=1
2018-05-23T22:21:26.239Z,1527114086.239 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-23T22:21:26.239Z,1527114086.239 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-23T22:21:26.240Z,1527114086.240 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-23T22:21:26.240Z,1527114086.240 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-23T22:21:26.240Z,1527114086.240 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-23T22:21:26.241Z,1527114086.241 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-23T22:21:26.241Z,1527114086.241 [Default:A.Wait] Running Loop=1
2018-05-23T22:21:26.241Z,1527114086.241 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-05-23T22:21:39.439Z,1527114099.439 [Default:A.Wait](INFO): Done Waiting.
2018-05-23T22:21:39.439Z,1527114099.439 [Default:A.Wait] Stopped
2018-05-23T22:21:39.439Z,1527114099.439 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T22:21:39.838Z,1527114099.838 [Default:CheckIn] Running Loop=1
2018-05-23T22:21:39.838Z,1527114099.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T22:21:39.838Z,1527114099.838 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T22:21:40.237Z,1527114100.237 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-23T22:22:12.603Z,1527114132.603 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004169
2018-05-23T22:23:49.015Z,1527114229.015 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-05-23T22:23:50.220Z,1527114230.220 [NAL9602](INFO): GPS fix at 20180523T222347: (36.802878, -121.788002)
2018-05-23T22:23:50.275Z,1527114230.275 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T22:23:50.275Z,1527114230.275 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T22:23:50.688Z,1527114230.688 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-23T22:23:56.453Z,1527114236.453 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T221617/Courier0006.lzma
2018-05-23T22:23:57.255Z,1527114237.255 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221617/Courier0006.lzma.bak
2018-05-23T22:23:57.255Z,1527114237.255 [DataOverHttps](INFO): SBD MOMSN=8291211
2018-05-23T22:24:07.451Z,1527114247.451 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T221617/Courier0009.lzma
2018-05-23T22:24:08.236Z,1527114248.236 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221617/Courier0009.lzma.bak
2018-05-23T22:24:08.236Z,1527114248.236 [DataOverHttps](INFO): SBD MOMSN=8291213
2018-05-23T22:24:08.312Z,1527114248.312 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:24:08.312Z,1527114248.312 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T22:24:08.312Z,1527114248.312 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:24:08.324Z,1527114248.324 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:24:08.919Z,1527114248.919 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:24:08.919Z,1527114248.919 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T22:24:09.081Z,1527114249.081 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:24:09.081Z,1527114249.081 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:24:09.081Z,1527114249.081 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:24:18.061Z,1527114258.061 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180523T221757/Courier0000.lzma
2018-05-23T22:24:18.847Z,1527114258.847 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0000.lzma.bak
2018-05-23T22:24:18.848Z,1527114258.848 [DataOverHttps](INFO): SBD MOMSN=8291215
2018-05-23T22:24:28.578Z,1527114268.578 [NAL9602](INFO): Powering down
2018-05-23T22:24:28.685Z,1527114268.685 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180523T221757/Courier0004.lzma
2018-05-23T22:24:29.455Z,1527114269.455 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0004.lzma.bak
2018-05-23T22:24:29.456Z,1527114269.456 [DataOverHttps](INFO): SBD MOMSN=8291218
2018-05-23T22:24:39.174Z,1527114279.174 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T221617/Express0004.lzma
2018-05-23T22:24:39.967Z,1527114279.967 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221617/Express0004.lzma.bak
2018-05-23T22:24:39.968Z,1527114279.968 [DataOverHttps](INFO): SBD MOMSN=8291221
2018-05-23T22:24:49.014Z,1527114289.014 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20180523T221617/Express0007.lzma
2018-05-23T22:24:49.811Z,1527114289.811 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221617/Express0007.lzma.bak
2018-05-23T22:24:49.812Z,1527114289.812 [DataOverHttps](INFO): SBD MOMSN=8291223
2018-05-23T22:24:59.254Z,1527114299.254 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180523T221617/Express0010.lzma
2018-05-23T22:25:00.051Z,1527114300.051 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221617/Express0010.lzma.bak
2018-05-23T22:25:00.052Z,1527114300.052 [DataOverHttps](INFO): SBD MOMSN=8291227
2018-05-23T22:25:09.115Z,1527114309.115 [DataOverHttps](INFO): Sending 801 bytes from file Logs/20180523T221757/Express0001.lzma
2018-05-23T22:25:09.899Z,1527114309.899 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0001.lzma.bak
2018-05-23T22:25:09.900Z,1527114309.900 [DataOverHttps](INFO): SBD MOMSN=8291229
2018-05-23T22:25:19.393Z,1527114319.393 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20180523T221757/Express0005.lzma
2018-05-23T22:25:20.187Z,1527114320.187 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0005.lzma.bak
2018-05-23T22:25:20.188Z,1527114320.188 [DataOverHttps](INFO): SBD MOMSN=8291249
2018-05-23T22:25:21.062Z,1527114321.062 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T22:25:21.062Z,1527114321.062 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T22:25:21.062Z,1527114321.062 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T22:27:09.252Z,1527114429.252 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:27:09.252Z,1527114429.252 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T22:27:09.252Z,1527114429.252 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:27:09.265Z,1527114429.265 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:27:09.662Z,1527114429.662 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:27:09.662Z,1527114429.662 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T22:27:10.050Z,1527114430.050 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:27:10.051Z,1527114430.051 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:27:10.051Z,1527114430.051 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:28:07.433Z,1527114487.433 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T22:30:10.379Z,1527114610.379 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:30:10.379Z,1527114610.379 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T22:30:10.379Z,1527114610.379 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:30:10.437Z,1527114610.437 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:30:10.789Z,1527114610.789 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:30:10.789Z,1527114610.789 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T22:30:11.179Z,1527114611.179 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:30:11.179Z,1527114611.179 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:30:11.180Z,1527114611.180 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:30:21.581Z,1527114621.581 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T22:30:21.581Z,1527114621.581 [Default:CheckIn:C.Wait] Stopped
2018-05-23T22:30:21.581Z,1527114621.581 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T22:30:21.581Z,1527114621.581 [Default:CheckIn:D] Running Loop=1
2018-05-23T22:30:21.988Z,1527114621.988 [Default:CheckIn:D] Stopped
2018-05-23T22:30:21.988Z,1527114621.988 [Default:CheckIn:E] Running Loop=1
2018-05-23T22:30:22.385Z,1527114622.385 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.929112 min 
2018-05-23T22:30:22.387Z,1527114622.387 [Default:CheckIn:E] Stopped
2018-05-23T22:30:22.387Z,1527114622.387 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T22:30:22.387Z,1527114622.387 [Default:CheckIn] Stopped
2018-05-23T22:30:22.387Z,1527114622.387 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T22:30:22.387Z,1527114622.387 [Default:CheckIn](INFO): Running loop #2
2018-05-23T22:30:22.387Z,1527114622.387 [Default:CheckIn] Running Loop=2
2018-05-23T22:30:22.387Z,1527114622.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T22:30:22.387Z,1527114622.388 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T22:30:27.439Z,1527114627.439 [NAL9602](INFO): Powering up
2018-05-23T22:30:38.556Z,1527114638.556 [NAL9602](INFO): NAL9602 initialized
2018-05-23T22:33:11.507Z,1527114791.507 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:33:11.507Z,1527114791.507 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-05-23T22:33:11.507Z,1527114791.507 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:33:11.520Z,1527114791.520 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:33:11.925Z,1527114791.925 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:33:11.925Z,1527114791.925 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-05-23T22:33:12.317Z,1527114792.317 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:33:12.318Z,1527114792.318 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:33:12.318Z,1527114792.318 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:34:27.636Z,1527114867.636 [NAL9602](INFO): SBD MO Status=2, MOMSN=6051, MT Status=2, MTMSN=0
2018-05-23T22:34:27.636Z,1527114867.636 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T22:34:52.917Z,1527114892.917 [NAL9602](INFO): SBD MO Status=2, MOMSN=6051, MT Status=2, MTMSN=0
2018-05-23T22:34:52.918Z,1527114892.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T22:35:18.917Z,1527114918.917 [NAL9602](INFO): SBD MO Status=2, MOMSN=6051, MT Status=2, MTMSN=0
2018-05-23T22:35:18.918Z,1527114918.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T22:35:22.559Z,1527114922.559 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-23T22:30:22.4Z
2018-05-23T22:35:22.559Z,1527114922.559 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T22:35:22.559Z,1527114922.559 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T22:35:26.593Z,1527114926.593 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T221757/Courier0007.lzma
2018-05-23T22:35:27.384Z,1527114927.384 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0007.lzma.bak
2018-05-23T22:35:27.384Z,1527114927.384 [DataOverHttps](INFO): SBD MOMSN=8291253
2018-05-23T22:35:36.770Z,1527114936.770 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20180523T221757/Express0008.lzma
2018-05-23T22:35:37.567Z,1527114937.567 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0008.lzma.bak
2018-05-23T22:35:37.568Z,1527114937.568 [DataOverHttps](INFO): SBD MOMSN=8291255
2018-05-23T22:35:38.423Z,1527114938.423 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T22:35:38.423Z,1527114938.423 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T22:35:38.423Z,1527114938.423 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T22:35:42.181Z,1527114942.181 [NAL9602](INFO): SBD MO Status=2, MOMSN=6051, MT Status=2, MTMSN=0
2018-05-23T22:35:42.181Z,1527114942.181 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T22:35:42.580Z,1527114942.580 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-05-23T22:36:12.621Z,1527114972.621 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:36:12.621Z,1527114972.621 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2018-05-23T22:36:12.621Z,1527114972.621 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:36:12.635Z,1527114972.635 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:36:13.036Z,1527114973.036 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:36:13.036Z,1527114973.036 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2018-05-23T22:36:13.387Z,1527114973.387 [NAL9602](INFO): Powering down
2018-05-23T22:36:13.455Z,1527114973.455 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:36:13.455Z,1527114973.455 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:36:13.456Z,1527114973.456 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:38:07.486Z,1527115087.486 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T22:39:13.855Z,1527115153.855 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:39:13.855Z,1527115153.855 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2018-05-23T22:39:13.855Z,1527115153.855 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:39:13.896Z,1527115153.896 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:39:14.265Z,1527115154.265 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:39:14.265Z,1527115154.265 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2018-05-23T22:39:14.650Z,1527115154.650 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:39:14.650Z,1527115154.650 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:39:14.651Z,1527115154.651 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:40:38.641Z,1527115238.641 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T22:40:38.642Z,1527115238.642 [Default:CheckIn:C.Wait] Stopped
2018-05-23T22:40:38.642Z,1527115238.642 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T22:40:38.642Z,1527115238.642 [Default:CheckIn:D] Running Loop=1
2018-05-23T22:40:39.077Z,1527115239.077 [Default:CheckIn:D] Stopped
2018-05-23T22:40:39.077Z,1527115239.077 [Default:CheckIn:E] Running Loop=1
2018-05-23T22:40:39.449Z,1527115239.449 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.213969 min 
2018-05-23T22:40:39.449Z,1527115239.449 [Default:CheckIn:E] Stopped
2018-05-23T22:40:39.449Z,1527115239.449 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T22:40:39.449Z,1527115239.449 [Default:CheckIn] Stopped
2018-05-23T22:40:39.449Z,1527115239.449 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T22:40:39.449Z,1527115239.449 [Default:CheckIn](INFO): Running loop #3
2018-05-23T22:40:39.449Z,1527115239.449 [Default:CheckIn] Running Loop=3
2018-05-23T22:40:39.450Z,1527115239.450 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T22:40:39.450Z,1527115239.450 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T22:40:42.897Z,1527115242.897 [NAL9602](INFO): Powering up
2018-05-23T22:40:54.100Z,1527115254.100 [NAL9602](INFO): NAL9602 initialized
2018-05-23T22:40:54.905Z,1527115254.905 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-23T22:40:54.905Z,1527115254.905 [NAL9602] Data Fault, FailCount= 1
2018-05-23T22:40:54.905Z,1527115254.905 [NAL9602](ERROR): Data Fault
2018-05-23T22:40:54.987Z,1527115254.987 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-23T22:40:55.299Z,1527115255.299 [NAL9602](INFO): Powering down
2018-05-23T22:40:56.166Z,1527115256.166 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-23T22:40:56.166Z,1527115256.166 [NAL9602] No Fault, FailCount= 1
2018-05-23T22:41:25.303Z,1527115285.303 [NAL9602](INFO): Powering up NAL9602
2018-05-23T22:41:36.500Z,1527115296.500 [NAL9602](INFO): NAL9602 initialized
2018-05-23T22:42:14.965Z,1527115334.965 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:42:14.965Z,1527115334.965 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2018-05-23T22:42:14.965Z,1527115334.965 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:42:14.978Z,1527115334.978 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:42:15.376Z,1527115335.376 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:42:15.376Z,1527115335.376 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2018-05-23T22:42:15.764Z,1527115335.764 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:42:15.764Z,1527115335.764 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:42:15.765Z,1527115335.765 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:44:04.017Z,1527115444.017 [NAL9602](INFO): SBD MO Status=0, MOMSN=6051, MT Status=0, MTMSN=0
2018-05-23T22:44:04.017Z,1527115444.017 [NAL9602](INFO): No messages in MT queue
2018-05-23T22:44:05.219Z,1527115445.219 [NAL9602](INFO): GPS fix at 20180523T224403: (36.802868, -121.787938)
2018-05-23T22:44:05.293Z,1527115445.293 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T22:44:05.293Z,1527115445.293 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T22:44:10.094Z,1527115450.094 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180523T221757/Courier0010.lzma
2018-05-23T22:44:10.891Z,1527115450.891 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0010.lzma.bak
2018-05-23T22:44:10.892Z,1527115450.892 [DataOverHttps](INFO): SBD MOMSN=8291258
2018-05-23T22:44:20.241Z,1527115460.241 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20180523T221757/Express0011.lzma
2018-05-23T22:44:21.039Z,1527115461.039 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0011.lzma.bak
2018-05-23T22:44:21.040Z,1527115461.040 [DataOverHttps](INFO): SBD MOMSN=8291261
2018-05-23T22:44:21.889Z,1527115461.889 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T22:44:21.889Z,1527115461.889 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T22:44:21.889Z,1527115461.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T22:44:39.647Z,1527115479.647 [NAL9602](INFO): Powering down
2018-05-23T22:45:16.100Z,1527115516.100 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:45:16.100Z,1527115516.100 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T22:45:16.100Z,1527115516.100 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:45:16.136Z,1527115516.136 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:45:16.506Z,1527115516.506 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:45:16.507Z,1527115516.507 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T22:45:16.895Z,1527115516.895 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:45:16.895Z,1527115516.895 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:45:16.896Z,1527115516.896 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:48:07.884Z,1527115687.884 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T22:48:17.045Z,1527115697.045 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:48:17.045Z,1527115697.045 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T22:48:17.045Z,1527115697.045 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:48:17.057Z,1527115697.057 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:48:17.456Z,1527115697.456 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:48:17.456Z,1527115697.456 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T22:48:17.845Z,1527115697.845 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:48:17.845Z,1527115697.845 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:48:17.845Z,1527115697.845 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:49:22.143Z,1527115762.143 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T22:49:22.143Z,1527115762.143 [Default:CheckIn:C.Wait] Stopped
2018-05-23T22:49:22.143Z,1527115762.143 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T22:49:22.143Z,1527115762.143 [Default:CheckIn:D] Running Loop=1
2018-05-23T22:49:22.549Z,1527115762.549 [Default:CheckIn:D] Stopped
2018-05-23T22:49:22.549Z,1527115762.549 [Default:CheckIn:E] Running Loop=1
2018-05-23T22:49:22.978Z,1527115762.978 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.938495 min 
2018-05-23T22:49:22.978Z,1527115762.978 [Default:CheckIn:E] Stopped
2018-05-23T22:49:22.979Z,1527115762.979 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T22:49:22.979Z,1527115762.979 [Default:CheckIn] Stopped
2018-05-23T22:49:22.979Z,1527115762.979 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T22:49:22.979Z,1527115762.979 [Default:CheckIn](INFO): Running loop #4
2018-05-23T22:49:22.979Z,1527115762.979 [Default:CheckIn] Running Loop=4
2018-05-23T22:49:22.979Z,1527115762.979 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T22:49:22.979Z,1527115762.979 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T22:49:26.413Z,1527115766.413 [NAL9602](INFO): Powering up
2018-05-23T22:49:37.296Z,1527115777.296 [NAL9602](INFO): NAL9602 initialized
2018-05-23T22:49:52.497Z,1527115792.497 [NAL9602](INFO): SBD MO Status=0, MOMSN=6052, MT Status=0, MTMSN=0
2018-05-23T22:49:52.497Z,1527115792.497 [NAL9602](INFO): No messages in MT queue
2018-05-23T22:51:17.982Z,1527115877.982 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:51:17.982Z,1527115877.982 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T22:51:17.982Z,1527115877.982 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:51:17.995Z,1527115877.995 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:51:18.385Z,1527115878.385 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:51:18.385Z,1527115878.385 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T22:51:18.780Z,1527115878.780 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:51:18.781Z,1527115878.781 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:51:18.781Z,1527115878.781 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:54:19.148Z,1527116059.148 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:54:19.148Z,1527116059.148 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-05-23T22:54:19.148Z,1527116059.148 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:54:19.161Z,1527116059.161 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:54:19.553Z,1527116059.553 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:54:19.554Z,1527116059.554 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-05-23T22:54:19.940Z,1527116059.940 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:54:19.941Z,1527116059.941 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:54:19.941Z,1527116059.941 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:54:23.138Z,1527116063.138 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-23T22:49:22.0Z
2018-05-23T22:54:23.138Z,1527116063.138 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T22:54:23.138Z,1527116063.138 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T22:54:28.606Z,1527116068.606 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T221757/Courier0013.lzma
2018-05-23T22:54:29.399Z,1527116069.399 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0013.lzma.bak
2018-05-23T22:54:29.400Z,1527116069.400 [DataOverHttps](INFO): SBD MOMSN=8291268
2018-05-23T22:54:39.222Z,1527116079.222 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20180523T221757/Express0014.lzma
2018-05-23T22:54:40.019Z,1527116080.019 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0014.lzma.bak
2018-05-23T22:54:40.020Z,1527116080.020 [DataOverHttps](INFO): SBD MOMSN=8291270
2018-05-23T22:54:40.922Z,1527116080.922 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T22:54:40.922Z,1527116080.922 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T22:54:40.922Z,1527116080.922 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T22:54:54.635Z,1527116094.635 [NAL9602](INFO): Powering down
2018-05-23T22:56:56.530Z,1527116216.530 [CommandLine](INFO): End of History
2018-05-23T22:57:20.052Z,1527116240.052 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T22:57:20.052Z,1527116240.052 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2018-05-23T22:57:20.053Z,1527116240.053 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T22:57:20.065Z,1527116240.065 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T22:57:20.462Z,1527116240.462 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T22:57:20.462Z,1527116240.462 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2018-05-23T22:57:20.856Z,1527116240.856 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T22:57:20.856Z,1527116240.856 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T22:57:20.856Z,1527116240.856 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T22:58:08.083Z,1527116288.083 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T22:59:41.387Z,1527116381.387 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T22:59:41.387Z,1527116381.387 [Default:CheckIn:C.Wait] Stopped
2018-05-23T22:59:41.387Z,1527116381.387 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T22:59:41.387Z,1527116381.387 [Default:CheckIn:D] Running Loop=1
2018-05-23T22:59:41.789Z,1527116381.789 [Default:CheckIn:D] Stopped
2018-05-23T22:59:41.789Z,1527116381.789 [Default:CheckIn:E] Running Loop=1
2018-05-23T22:59:45.084Z,1527116385.084 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.259159 min 
2018-05-23T22:59:45.084Z,1527116385.084 [Default:CheckIn:E] Stopped
2018-05-23T22:59:45.084Z,1527116385.084 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T22:59:45.085Z,1527116385.085 [Default:CheckIn] Stopped
2018-05-23T22:59:45.085Z,1527116385.085 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T22:59:45.085Z,1527116385.085 [Default:CheckIn](INFO): Running loop #5
2018-05-23T22:59:45.085Z,1527116385.085 [Default:CheckIn] Running Loop=5
2018-05-23T22:59:45.085Z,1527116385.085 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T22:59:45.085Z,1527116385.085 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T22:59:45.507Z,1527116385.507 [NAL9602](INFO): Powering up
2018-05-23T22:59:56.708Z,1527116396.708 [NAL9602](INFO): NAL9602 initialized
2018-05-23T22:59:57.512Z,1527116397.512 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-23T22:59:57.512Z,1527116397.512 [NAL9602] Data Fault, FailCount= 1
2018-05-23T22:59:57.512Z,1527116397.512 [NAL9602](ERROR): Data Fault
2018-05-23T22:59:57.602Z,1527116397.602 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-23T22:59:57.907Z,1527116397.907 [NAL9602](INFO): Powering down
2018-05-23T22:59:58.779Z,1527116398.779 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-23T22:59:58.779Z,1527116398.779 [NAL9602] No Fault, FailCount= 1
2018-05-23T23:00:21.148Z,1527116421.148 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:00:21.148Z,1527116421.148 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2018-05-23T23:00:21.148Z,1527116421.148 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:00:21.161Z,1527116421.161 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:00:21.570Z,1527116421.570 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:00:21.578Z,1527116421.578 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2018-05-23T23:00:21.950Z,1527116421.950 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:00:21.950Z,1527116421.950 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:00:21.951Z,1527116421.951 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:00:28.308Z,1527116428.308 [NAL9602](INFO): Powering up NAL9602
2018-05-23T23:00:39.507Z,1527116439.507 [NAL9602](INFO): NAL9602 initialized
2018-05-23T23:03:00.209Z,1527116580.209 [NAL9602](INFO): SBD MO Status=2, MOMSN=6053, MT Status=2, MTMSN=0
2018-05-23T23:03:00.209Z,1527116580.209 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T23:03:06.609Z,1527116586.609 [NAL9602](INFO): SBD MO Status=0, MOMSN=6053, MT Status=0, MTMSN=0
2018-05-23T23:03:06.609Z,1527116586.609 [NAL9602](INFO): No messages in MT queue
2018-05-23T23:03:07.811Z,1527116587.811 [NAL9602](INFO): GPS fix at 20180523T230305: (36.802793, -121.787997)
2018-05-23T23:03:07.872Z,1527116587.872 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T23:03:07.873Z,1527116587.873 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T23:03:12.278Z,1527116592.278 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180523T221757/Courier0016.lzma
2018-05-23T23:03:13.047Z,1527116593.047 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0016.lzma.bak
2018-05-23T23:03:13.048Z,1527116593.048 [DataOverHttps](INFO): SBD MOMSN=8291273
2018-05-23T23:03:22.325Z,1527116602.325 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:03:22.325Z,1527116602.325 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T23:03:22.325Z,1527116602.325 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:03:22.363Z,1527116602.363 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:03:22.389Z,1527116602.389 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20180523T221757/Express0017.lzma
2018-05-23T23:03:22.487Z,1527116602.487 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:03:22.487Z,1527116602.487 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T23:03:22.821Z,1527116602.821 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:03:22.821Z,1527116602.821 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:03:22.822Z,1527116602.822 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:03:23.172Z,1527116603.172 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0017.lzma.bak
2018-05-23T23:03:23.173Z,1527116603.173 [DataOverHttps](INFO): SBD MOMSN=8291276
2018-05-23T23:03:24.048Z,1527116604.048 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T23:03:24.048Z,1527116604.048 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T23:03:24.048Z,1527116604.048 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T23:03:42.639Z,1527116622.639 [NAL9602](INFO): Powering down
2018-05-23T23:06:22.932Z,1527116782.932 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:06:22.932Z,1527116782.932 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T23:06:22.933Z,1527116782.933 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:06:22.945Z,1527116782.945 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:06:23.386Z,1527116783.386 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:06:23.386Z,1527116783.386 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T23:06:23.745Z,1527116783.745 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:06:23.745Z,1527116783.745 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:06:23.746Z,1527116783.746 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:08:08.224Z,1527116888.224 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T23:08:24.214Z,1527116904.214 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T23:08:24.214Z,1527116904.214 [Default:CheckIn:C.Wait] Stopped
2018-05-23T23:08:24.215Z,1527116904.215 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T23:08:24.215Z,1527116904.215 [Default:CheckIn:D] Running Loop=1
2018-05-23T23:08:24.662Z,1527116904.662 [Default:CheckIn:D] Stopped
2018-05-23T23:08:24.662Z,1527116904.662 [Default:CheckIn:E] Running Loop=1
2018-05-23T23:08:25.036Z,1527116905.036 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.973714 min 
2018-05-23T23:08:25.036Z,1527116905.036 [Default:CheckIn:E] Stopped
2018-05-23T23:08:25.036Z,1527116905.036 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T23:08:25.036Z,1527116905.036 [Default:CheckIn] Stopped
2018-05-23T23:08:25.036Z,1527116905.036 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T23:08:25.036Z,1527116905.036 [Default:CheckIn](INFO): Running loop #6
2018-05-23T23:08:25.036Z,1527116905.036 [Default:CheckIn] Running Loop=6
2018-05-23T23:08:25.037Z,1527116905.037 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T23:08:25.037Z,1527116905.037 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T23:08:28.508Z,1527116908.508 [NAL9602](INFO): Powering up
2018-05-23T23:08:39.400Z,1527116919.400 [NAL9602](INFO): NAL9602 initialized
2018-05-23T23:08:52.601Z,1527116932.601 [NAL9602](INFO): SBD MO Status=0, MOMSN=6054, MT Status=0, MTMSN=0
2018-05-23T23:08:52.601Z,1527116932.601 [NAL9602](INFO): No messages in MT queue
2018-05-23T23:09:23.857Z,1527116963.857 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:09:23.857Z,1527116963.857 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T23:09:23.858Z,1527116963.858 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:09:23.888Z,1527116963.888 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:09:24.271Z,1527116964.271 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:09:24.272Z,1527116964.272 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T23:09:24.651Z,1527116964.651 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:09:24.652Z,1527116964.652 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:09:24.652Z,1527116964.652 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:12:24.763Z,1527117144.763 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:12:24.763Z,1527117144.763 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-05-23T23:12:24.763Z,1527117144.763 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:12:24.816Z,1527117144.816 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:12:25.159Z,1527117145.159 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:12:25.160Z,1527117145.160 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-05-23T23:12:25.569Z,1527117145.569 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:12:25.570Z,1527117145.570 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:12:25.570Z,1527117145.570 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:13:25.307Z,1527117205.307 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-23T23:08:25.0Z
2018-05-23T23:13:25.308Z,1527117205.308 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T23:13:25.308Z,1527117205.308 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T23:13:29.846Z,1527117209.846 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T221757/Courier0019.lzma
2018-05-23T23:13:30.647Z,1527117210.647 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0019.lzma.bak
2018-05-23T23:13:30.648Z,1527117210.648 [DataOverHttps](INFO): SBD MOMSN=8291282
2018-05-23T23:13:40.075Z,1527117220.075 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20180523T221757/Express0020.lzma
2018-05-23T23:13:40.875Z,1527117220.875 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0020.lzma.bak
2018-05-23T23:13:40.876Z,1527117220.876 [DataOverHttps](INFO): SBD MOMSN=8291284
2018-05-23T23:13:41.733Z,1527117221.733 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T23:13:41.733Z,1527117221.733 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T23:13:41.733Z,1527117221.733 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T23:13:59.951Z,1527117239.951 [NAL9602](INFO): Powering down
2018-05-23T23:15:25.707Z,1527117325.707 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:15:25.707Z,1527117325.707 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2018-05-23T23:15:25.707Z,1527117325.707 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:15:25.720Z,1527117325.720 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:15:26.123Z,1527117326.123 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:15:26.123Z,1527117326.123 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2018-05-23T23:15:26.513Z,1527117326.513 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:15:26.513Z,1527117326.513 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:15:26.514Z,1527117326.514 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:18:08.404Z,1527117488.404 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T23:18:26.797Z,1527117506.797 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:18:26.797Z,1527117506.797 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2018-05-23T23:18:26.797Z,1527117506.797 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:18:26.810Z,1527117506.810 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:18:27.206Z,1527117507.206 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:18:27.206Z,1527117507.206 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2018-05-23T23:18:27.601Z,1527117507.601 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:18:27.602Z,1527117507.602 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:18:27.602Z,1527117507.602 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:18:41.993Z,1527117521.993 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T23:18:41.993Z,1527117521.993 [Default:CheckIn:C.Wait] Stopped
2018-05-23T23:18:41.993Z,1527117521.993 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T23:18:41.993Z,1527117521.993 [Default:CheckIn:D] Running Loop=1
2018-05-23T23:18:42.394Z,1527117522.394 [Default:CheckIn:D] Stopped
2018-05-23T23:18:42.394Z,1527117522.394 [Default:CheckIn:E] Running Loop=1
2018-05-23T23:18:42.793Z,1527117522.793 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.269246 min 
2018-05-23T23:18:42.793Z,1527117522.793 [Default:CheckIn:E] Stopped
2018-05-23T23:18:42.793Z,1527117522.793 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T23:18:42.793Z,1527117522.793 [Default:CheckIn] Stopped
2018-05-23T23:18:42.794Z,1527117522.794 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T23:18:42.794Z,1527117522.794 [Default:CheckIn](INFO): Running loop #7
2018-05-23T23:18:42.794Z,1527117522.794 [Default:CheckIn] Running Loop=7
2018-05-23T23:18:42.794Z,1527117522.794 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T23:18:42.794Z,1527117522.794 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T23:18:45.972Z,1527117525.972 [NAL9602](INFO): Powering up
2018-05-23T23:18:57.164Z,1527117537.164 [NAL9602](INFO): NAL9602 initialized
2018-05-23T23:18:57.960Z,1527117537.960 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-23T23:18:57.960Z,1527117537.960 [NAL9602] Data Fault, FailCount= 1
2018-05-23T23:18:57.960Z,1527117537.960 [NAL9602](ERROR): Data Fault
2018-05-23T23:18:58.056Z,1527117538.056 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-23T23:18:58.363Z,1527117538.363 [NAL9602](INFO): Powering down
2018-05-23T23:18:59.214Z,1527117539.214 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-23T23:18:59.214Z,1527117539.214 [NAL9602] No Fault, FailCount= 1
2018-05-23T23:19:28.759Z,1527117568.759 [NAL9602](INFO): Powering up NAL9602
2018-05-23T23:19:39.960Z,1527117579.960 [NAL9602](INFO): NAL9602 initialized
2018-05-23T23:21:27.771Z,1527117687.771 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:21:27.771Z,1527117687.771 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2018-05-23T23:21:27.771Z,1527117687.771 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:21:27.800Z,1527117687.800 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:21:28.149Z,1527117688.149 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:21:28.150Z,1527117688.150 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2018-05-23T23:21:28.542Z,1527117688.542 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:21:28.543Z,1527117688.543 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:21:28.543Z,1527117688.543 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:21:32.889Z,1527117692.889 [NAL9602](INFO): SBD MO Status=0, MOMSN=6055, MT Status=0, MTMSN=0
2018-05-23T23:21:32.889Z,1527117692.889 [NAL9602](INFO): No messages in MT queue
2018-05-23T23:21:34.091Z,1527117694.091 [NAL9602](INFO): GPS fix at 20180523T232132: (36.802485, -121.788134)
2018-05-23T23:21:34.164Z,1527117694.164 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T23:21:34.164Z,1527117694.164 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T23:21:38.614Z,1527117698.614 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180523T221757/Courier0022.lzma
2018-05-23T23:21:39.407Z,1527117699.407 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0022.lzma.bak
2018-05-23T23:21:39.408Z,1527117699.408 [DataOverHttps](INFO): SBD MOMSN=8291287
2018-05-23T23:21:48.745Z,1527117708.745 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20180523T221757/Express0023.lzma
2018-05-23T23:21:49.536Z,1527117709.536 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0023.lzma.bak
2018-05-23T23:21:49.536Z,1527117709.536 [DataOverHttps](INFO): SBD MOMSN=8291290
2018-05-23T23:21:50.397Z,1527117710.397 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T23:21:50.397Z,1527117710.397 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T23:21:50.397Z,1527117710.397 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T23:22:08.567Z,1527117728.567 [NAL9602](INFO): Powering down
2018-05-23T23:24:28.791Z,1527117868.791 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:24:28.791Z,1527117868.791 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T23:24:28.791Z,1527117868.791 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:24:28.847Z,1527117868.847 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:24:29.200Z,1527117869.200 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:24:29.200Z,1527117869.200 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T23:24:29.595Z,1527117869.595 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:24:29.595Z,1527117869.595 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:24:29.596Z,1527117869.596 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:26:50.866Z,1527118010.866 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T23:26:50.866Z,1527118010.866 [Default:CheckIn:C.Wait] Stopped
2018-05-23T23:26:50.866Z,1527118010.866 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T23:26:50.867Z,1527118010.867 [Default:CheckIn:D] Running Loop=1
2018-05-23T23:26:51.269Z,1527118011.269 [Default:CheckIn:D] Stopped
2018-05-23T23:26:51.269Z,1527118011.269 [Default:CheckIn:E] Running Loop=1
2018-05-23T23:26:51.669Z,1527118011.669 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.417175 min 
2018-05-23T23:26:51.669Z,1527118011.669 [Default:CheckIn:E] Stopped
2018-05-23T23:26:51.669Z,1527118011.669 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T23:26:51.670Z,1527118011.670 [Default:CheckIn] Stopped
2018-05-23T23:26:51.670Z,1527118011.670 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T23:26:51.670Z,1527118011.670 [Default:CheckIn](INFO): Running loop #8
2018-05-23T23:26:51.670Z,1527118011.670 [Default:CheckIn] Running Loop=8
2018-05-23T23:26:51.670Z,1527118011.670 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T23:26:51.670Z,1527118011.670 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T23:26:54.831Z,1527118014.831 [NAL9602](INFO): Powering up
2018-05-23T23:27:05.000Z,1527118026.000 [NAL9602](INFO): NAL9602 initialized
2018-05-23T23:27:29.650Z,1527118049.650 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:27:29.651Z,1527118049.651 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T23:27:29.651Z,1527118049.651 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:27:29.679Z,1527118049.679 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:27:30.064Z,1527118050.064 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:27:30.064Z,1527118050.064 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T23:27:30.453Z,1527118050.453 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:27:30.454Z,1527118050.454 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:27:30.454Z,1527118050.454 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:28:08.480Z,1527118088.480 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T23:29:56.311Z,1527118196.311 [DataOverHttps](IMPORTANT): SBD MTMSN=20180523T232954
2018-05-23T23:30:00.366Z,1527118200.366 [DataOverHttps](INFO): Received command:get NAL9602.numSatellites
2018-05-23T23:30:00.986Z,1527118200.986 [CommandLine](IMPORTANT): got command get NAL9602.numSatellites
2018-05-23T23:30:00.987Z,1527118200.987 [CommandLine](IMPORTANT): NAL9602.numSatellites 0 count
2018-05-23T23:30:30.759Z,1527118230.759 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:30:30.759Z,1527118230.759 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T23:30:30.759Z,1527118230.759 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:30:30.772Z,1527118230.772 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:30:31.179Z,1527118231.179 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:30:31.179Z,1527118231.179 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T23:30:31.570Z,1527118231.570 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:30:31.570Z,1527118231.570 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:30:31.571Z,1527118231.571 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:30:52.317Z,1527118252.317 [NAL9602](INFO): SBD MO Status=2, MOMSN=6056, MT Status=2, MTMSN=0
2018-05-23T23:30:52.317Z,1527118252.317 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-05-23T23:31:09.197Z,1527118269.197 [NAL9602](INFO): SBD MO Status=0, MOMSN=6056, MT Status=0, MTMSN=0
2018-05-23T23:31:09.197Z,1527118269.197 [NAL9602](INFO): No messages in MT queue
2018-05-23T23:31:10.399Z,1527118270.399 [NAL9602](INFO): GPS fix at 20180523T233107: (36.802908, -121.788057)
2018-05-23T23:31:10.470Z,1527118270.470 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T23:31:10.470Z,1527118270.470 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-23T23:31:15.218Z,1527118275.218 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180523T221757/Courier0025.lzma
2018-05-23T23:31:16.007Z,1527118276.007 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0025.lzma.bak
2018-05-23T23:31:16.008Z,1527118276.008 [DataOverHttps](INFO): SBD MOMSN=8291297
2018-05-23T23:31:25.201Z,1527118285.201 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T221757/Courier0028.lzma
2018-05-23T23:31:25.991Z,1527118285.991 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Courier0028.lzma.bak
2018-05-23T23:31:25.992Z,1527118285.992 [DataOverHttps](INFO): SBD MOMSN=8291300
2018-05-23T23:31:35.709Z,1527118295.709 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20180523T221757/Express0026.lzma
2018-05-23T23:31:36.501Z,1527118296.501 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0026.lzma.bak
2018-05-23T23:31:36.501Z,1527118296.501 [DataOverHttps](INFO): SBD MOMSN=8291302
2018-05-23T23:31:42.191Z,1527118302.191 [NAL9602](INFO): Powering down
2018-05-23T23:31:45.570Z,1527118305.570 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180523T221757/Express0029.lzma
2018-05-23T23:31:46.360Z,1527118306.360 [DataOverHttps](INFO): Moved sent file to Logs/20180523T221757/Express0029.lzma.bak
2018-05-23T23:31:46.360Z,1527118306.360 [DataOverHttps](INFO): SBD MOMSN=8291308
2018-05-23T23:31:47.225Z,1527118307.225 [Default:CheckIn:Read_Iridium] Stopped
2018-05-23T23:31:47.225Z,1527118307.225 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-23T23:31:47.225Z,1527118307.225 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-23T23:33:31.597Z,1527118411.597 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:33:31.597Z,1527118411.597 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-05-23T23:33:31.597Z,1527118411.597 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:33:31.609Z,1527118411.609 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:33:32.013Z,1527118412.013 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:33:32.013Z,1527118412.013 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-05-23T23:33:32.403Z,1527118412.403 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:33:32.404Z,1527118412.404 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:33:32.404Z,1527118412.404 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:36:32.487Z,1527118592.487 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:36:32.487Z,1527118592.487 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-05-23T23:36:32.487Z,1527118592.487 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:36:32.503Z,1527118592.503 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:36:32.907Z,1527118592.907 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:36:32.907Z,1527118592.907 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-05-23T23:36:33.292Z,1527118593.292 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:36:33.292Z,1527118593.292 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:36:33.293Z,1527118593.293 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:36:47.681Z,1527118607.681 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-23T23:36:47.681Z,1527118607.681 [Default:CheckIn:C.Wait] Stopped
2018-05-23T23:36:47.681Z,1527118607.681 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-23T23:36:47.681Z,1527118607.681 [Default:CheckIn:D] Running Loop=1
2018-05-23T23:36:48.089Z,1527118608.089 [Default:CheckIn:D] Stopped
2018-05-23T23:36:48.089Z,1527118608.089 [Default:CheckIn:E] Running Loop=1
2018-05-23T23:36:48.483Z,1527118608.483 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.364168 min 
2018-05-23T23:36:48.483Z,1527118608.483 [Default:CheckIn:E] Stopped
2018-05-23T23:36:48.483Z,1527118608.483 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-23T23:36:48.483Z,1527118608.483 [Default:CheckIn] Stopped
2018-05-23T23:36:48.483Z,1527118608.483 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T23:36:48.484Z,1527118608.484 [Default:CheckIn](INFO): Running loop #9
2018-05-23T23:36:48.484Z,1527118608.484 [Default:CheckIn] Running Loop=9
2018-05-23T23:36:48.484Z,1527118608.484 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-23T23:36:48.484Z,1527118608.484 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-23T23:36:52.123Z,1527118612.123 [NAL9602](INFO): Powering up
2018-05-23T23:37:03.039Z,1527118623.039 [NAL9602](INFO): NAL9602 initialized
2018-05-23T23:38:08.740Z,1527118688.740 [CBIT](DEBUG): EFC running - data check-sum false
2018-05-23T23:39:33.662Z,1527118773.662 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-23T23:39:33.662Z,1527118773.662 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-05-23T23:39:33.662Z,1527118773.662 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-05-23T23:39:33.674Z,1527118773.674 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-05-23T23:39:34.073Z,1527118774.073 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-05-23T23:39:34.073Z,1527118774.073 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-05-23T23:39:34.462Z,1527118774.462 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-05-23T23:39:34.462Z,1527118774.462 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-05-23T23:39:34.463Z,1527118774.463 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-05-23T23:40:03.408Z,1527118803.408 [CommandLine](IMPORTANT): got command configSet NAL9602.loadAtStartup 0.000000 bool persist
2018-05-23T23:40:03.409Z,1527118803.409 [CommandLine](IMPORTANT): configSet NAL9602.loadAtStartup requires a restart to take effect.
2018-05-23T23:40:06.632Z,1527118806.632 [CommandLine](IMPORTANT): got command configSet NAL9602.loadAtStartup 0.000000 bool persist
2018-05-23T23:40:06.633Z,1527118806.633 [CommandLine](IMPORTANT): configSet NAL9602.loadAtStartup requires a restart to take effect.
2018-05-23T23:40:11.271Z,1527118811.271 [CommandLine](IMPORTANT): got command restart application
2018-05-23T23:40:12.274Z,1527118812.274 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-05-23T23:40:12.277Z,1527118812.277 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-23T23:40:12.278Z,1527118812.278 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:12.430Z,1527118812.430 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-05-23T23:40:12.430Z,1527118812.430 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:12.431Z,1527118812.431 [CommandLine](INFO): Join timeout helper Thread ID is 5901
2018-05-23T23:40:12.431Z,1527118812.431 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-05-23T23:40:12.432Z,1527118812.432 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:12.432Z,1527118812.432 [NavChartDb](INFO): Join timeout helper Thread ID is 5902
2018-05-23T23:40:12.658Z,1527118812.658 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-23T23:40:12.659Z,1527118812.659 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:12.674Z,1527118812.674 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-05-23T23:40:12.674Z,1527118812.674 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:12.675Z,1527118812.675 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5903
2018-05-23T23:40:12.834Z,1527118812.834 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-23T23:40:12.835Z,1527118812.835 [WetLabsBB2FL](INFO): Powering down
2018-05-23T23:40:12.835Z,1527118812.835 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:12.838Z,1527118812.838 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-05-23T23:40:12.838Z,1527118812.838 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:12.839Z,1527118812.839 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5904
2018-05-23T23:40:13.234Z,1527118813.234 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-23T23:40:13.326Z,1527118813.326 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-23T23:40:13.326Z,1527118813.326 [CTD_Seabird](INFO): Powering down
2018-05-23T23:40:13.327Z,1527118813.327 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:13.328Z,1527118813.328 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-05-23T23:40:13.328Z,1527118813.328 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:13.329Z,1527118813.329 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5906
2018-05-23T23:40:13.499Z,1527118813.499 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-23T23:40:13.499Z,1527118813.499 [CTD_NeilBrown](INFO): Powering down
2018-05-23T23:40:13.500Z,1527118813.500 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:13.507Z,1527118813.507 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-05-23T23:40:13.508Z,1527118813.508 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:13.508Z,1527118813.508 [Radio_Surface](INFO): Join timeout helper Thread ID is 5907
2018-05-23T23:40:13.587Z,1527118813.587 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-23T23:40:13.587Z,1527118813.587 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:13.595Z,1527118813.595 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-05-23T23:40:13.595Z,1527118813.595 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:13.596Z,1527118813.596 [logger](INFO): Join timeout helper Thread ID is 5908
2018-05-23T23:40:13.618Z,1527118813.618 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-23T23:40:13.618Z,1527118813.618 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:13.631Z,1527118813.631 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-05-23T23:40:13.631Z,1527118813.631 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:13.631Z,1527118813.631 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-05-23T23:40:13.631Z,1527118813.631 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:13.632Z,1527118813.632 [controlThread](INFO): Join timeout helper Thread ID is 5909
2018-05-23T23:40:13.886Z,1527118813.886 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-23T23:40:13.887Z,1527118813.887 [controlThread](DEBUG): Uninitializing ControlThread
2018-05-23T23:40:13.887Z,1527118813.887 [NAL9602](INFO): Powering down
2018-05-23T23:40:13.888Z,1527118813.888 [PNI_TCM](INFO): Powering down
2018-05-23T23:40:13.975Z,1527118813.975 [Aanderaa_O2](INFO): Powering down
2018-05-23T23:40:13.977Z,1527118813.977 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-23T23:40:13.978Z,1527118813.978 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-05-23T23:40:13.979Z,1527118813.979 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-05-23T23:40:13.980Z,1527118813.980 [MissionManager](INFO): Uninitializing Mission Default
2018-05-23T23:40:13.980Z,1527118813.980 [Default] Stopped
2018-05-23T23:40:13.980Z,1527118813.980 [Default](DEBUG): Aggregate::uninitialize Default
2018-05-23T23:40:13.980Z,1527118813.980 [Default:B.GoToSurface] Stopped
2018-05-23T23:40:13.980Z,1527118813.980 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-23T23:40:13.980Z,1527118813.980 [Default:CheckIn] Stopped
2018-05-23T23:40:13.981Z,1527118813.981 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-23T23:40:13.981Z,1527118813.981 [Default:CheckIn:Read_GPS] Stopped
2018-05-23T23:40:13.984Z,1527118813.984 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-05-23T23:40:13.984Z,1527118813.984 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-05-23T23:40:13.984Z,1527118813.984 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-05-23T23:40:13.985Z,1527118813.985 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-05-23T23:40:13.985Z,1527118813.985 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-23T23:40:13.985Z,1527118813.985 [BuoyancyServo](INFO): Powering down
2018-05-23T23:40:13.998Z,1527118813.998 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-05-23T23:40:13.999Z,1527118813.999 [ElevatorServo](INFO): Powering down
2018-05-23T23:40:13.999Z,1527118813.999 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-05-23T23:40:13.000Z,1527118814.000 [MassServo](INFO): Powering down
2018-05-23T23:40:14.000Z,1527118814.000 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-05-23T23:40:14.000Z,1527118814.000 [RudderServo](INFO): Powering down
2018-05-23T23:40:14.001Z,1527118814.001 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-23T23:40:14.001Z,1527118814.001 [ThrusterServo](INFO): Powering down
2018-05-23T23:40:14.002Z,1527118814.002 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-05-23T23:40:14.003Z,1527118814.003 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-05-23T23:40:14.003Z,1527118814.003 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-05-23T23:40:14.004Z,1527118814.004 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:14.121Z,1527118814.121 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:14.158Z,1527118814.158 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:14.162Z,1527118814.162 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:14.165Z,1527118814.165 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:14.193Z,1527118814.193 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-23T23:40:14.265Z,1527118814.265 [logger ThreadHandler](INFO): Thread cancelled.