2018-05-23T17:32:51.807Z,1527096771.807 [Supervisor](DEBUG): Initializing supervisor. 2018-05-23T17:32:51.809Z,1527096771.809 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-05-23T17:32:51.810Z,1527096771.810 [SyncHandler](INFO): Protected caller Thread ID is 4874 2018-05-23T17:32:51.810Z,1527096771.810 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-05-23T17:32:51.811Z,1527096771.811 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-05-23T17:32:51.811Z,1527096771.811 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4875 2018-05-23T17:32:51.814Z,1527096771.814 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-05-23T17:32:51.825Z,1527096771.825 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-05-23T17:32:51.826Z,1527096771.826 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-05-23T17:32:51.826Z,1527096771.826 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4876 2018-05-23T17:32:51.827Z,1527096771.827 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-05-23T17:32:51.828Z,1527096771.828 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-05-23T17:32:51.828Z,1527096771.828 [logger ThreadHandler](INFO): Protected caller Thread ID is 4877 2018-05-23T17:32:51.830Z,1527096771.830 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-05-23T17:32:51.830Z,1527096771.830 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-05-23T17:32:51.833Z,1527096771.833 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-05-23T17:32:52.208Z,1527096772.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-05-23T17:32:52.209Z,1527096772.209 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-05-23T17:32:52.400Z,1527096772.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-05-23T17:32:52.400Z,1527096772.400 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-05-23T17:32:52.676Z,1527096772.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-05-23T17:32:52.676Z,1527096772.676 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-05-23T17:32:52.811Z,1527096772.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-05-23T17:32:52.812Z,1527096772.812 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-05-23T17:32:52.909Z,1527096772.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-05-23T17:32:52.909Z,1527096772.909 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-05-23T17:32:53.941Z,1527096773.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-05-23T17:32:53.944Z,1527096773.944 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-05-23T17:32:54.312Z,1527096774.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-05-23T17:32:54.313Z,1527096774.313 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-05-23T17:32:54.736Z,1527096774.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-05-23T17:32:54.737Z,1527096774.737 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-05-23T17:32:54.840Z,1527096774.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-05-23T17:32:54.840Z,1527096774.840 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-05-23T17:32:54.937Z,1527096774.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-05-23T17:32:54.937Z,1527096774.937 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-05-23T17:32:55.135Z,1527096775.135 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-05-23T17:32:55.136Z,1527096775.136 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-05-23T17:32:55.338Z,1527096775.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-05-23T17:32:55.339Z,1527096775.339 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-05-23T17:32:55.671Z,1527096775.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-05-23T17:32:55.672Z,1527096775.672 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-05-23T17:32:55.809Z,1527096775.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-05-23T17:32:55.809Z,1527096775.809 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-05-23T17:32:55.892Z,1527096775.892 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2018-05-23T17:32:55.895Z,1527096775.895 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2018-05-23T17:32:55.997Z,1527096775.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2018-05-23T17:32:56.088Z,1527096776.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2018-05-23T17:32:56.182Z,1527096776.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2018-05-23T17:32:56.266Z,1527096776.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2018-05-23T17:32:56.411Z,1527096776.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2018-05-23T17:32:56.579Z,1527096776.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2018-05-23T17:32:56.655Z,1527096776.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2018-05-23T17:32:56.879Z,1527096776.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-05-23T17:32:56.879Z,1527096776.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2018-05-23T17:32:56.962Z,1527096776.962 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2018-05-23T17:32:57.051Z,1527096777.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2018-05-23T17:32:57.150Z,1527096777.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2018-05-23T17:32:57.271Z,1527096777.271 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-05-23T17:32:57.281Z,1527096777.281 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-05-23T17:32:57.542Z,1527096777.542 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-05-23T17:32:57.543Z,1527096777.543 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-05-23T17:32:57.593Z,1527096777.593 [DepthRateCalculator] Loaded 2018-05-23T17:32:57.593Z,1527096777.593 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-05-23T17:32:57.599Z,1527096777.599 [PitchRateCalculator] Loaded 2018-05-23T17:32:57.599Z,1527096777.599 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-05-23T17:32:57.614Z,1527096777.614 [SpeedCalculator] Loaded 2018-05-23T17:32:57.615Z,1527096777.615 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-05-23T17:32:57.635Z,1527096777.635 [TempGradientCalculator] Loaded 2018-05-23T17:32:57.635Z,1527096777.635 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-05-23T17:32:57.650Z,1527096777.650 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-05-23T17:32:57.650Z,1527096777.650 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-05-23T17:32:57.656Z,1527096777.656 [YawRateCalculator] Loaded 2018-05-23T17:32:57.656Z,1527096777.656 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-05-23T17:32:57.694Z,1527096777.694 [ElevatorOffsetCalculator] Loaded 2018-05-23T17:32:57.694Z,1527096777.694 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-05-23T17:32:57.694Z,1527096777.694 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-05-23T17:32:57.695Z,1527096777.695 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-05-23T17:32:57.806Z,1527096777.806 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-05-23T17:32:57.806Z,1527096777.806 [StratificationFrontDetector](DEBUG): (re)initializing 2018-05-23T17:32:57.806Z,1527096777.806 [StratificationFrontDetector] Loaded 2018-05-23T17:32:57.806Z,1527096777.806 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-05-23T17:32:57.807Z,1527096777.807 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-05-23T17:32:57.807Z,1527096777.807 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-05-23T17:32:57.931Z,1527096777.931 [BuoyancyServo] Loaded 2018-05-23T17:32:57.931Z,1527096777.931 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-05-23T17:32:57.946Z,1527096777.946 [ElevatorServo] Loaded 2018-05-23T17:32:57.946Z,1527096777.946 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-05-23T17:32:57.960Z,1527096777.960 [MassServo] Loaded 2018-05-23T17:32:57.960Z,1527096777.960 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-05-23T17:32:57.975Z,1527096777.975 [RudderServo] Loaded 2018-05-23T17:32:57.975Z,1527096777.975 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-05-23T17:32:57.989Z,1527096777.989 [ThrusterServo] Loaded 2018-05-23T17:32:57.989Z,1527096777.989 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-05-23T17:32:57.989Z,1527096777.989 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-05-23T17:32:57.990Z,1527096777.990 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-05-23T17:32:58.011Z,1527096778.011 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-05-23T17:32:58.011Z,1527096778.011 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-05-23T17:32:58.127Z,1527096778.127 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-05-23T17:32:58.128Z,1527096778.128 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-05-23T17:32:58.488Z,1527096778.488 [DataOverHttps] Loaded 2018-05-23T17:32:58.488Z,1527096778.488 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-05-23T17:32:58.501Z,1527096778.501 [Depth_Keller] Loaded 2018-05-23T17:32:58.502Z,1527096778.502 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-05-23T17:32:58.506Z,1527096778.506 [DropWeight] Loaded 2018-05-23T17:32:58.506Z,1527096778.506 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-05-23T17:32:58.600Z,1527096778.600 [NAL9602] Loaded 2018-05-23T17:32:58.600Z,1527096778.600 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-05-23T17:32:58.615Z,1527096778.615 [Onboard] Loaded 2018-05-23T17:32:58.615Z,1527096778.615 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-05-23T17:32:58.625Z,1527096778.625 [Radio_Surface] Loaded 2018-05-23T17:32:58.625Z,1527096778.625 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-05-23T17:32:58.626Z,1527096778.626 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408844E0 2018-05-23T17:32:58.627Z,1527096778.627 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4959 2018-05-23T17:32:58.672Z,1527096778.672 [PNI_TCM] Loaded 2018-05-23T17:32:58.672Z,1527096778.672 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-05-23T17:33:00.861Z,1527096780.861 [BPC1] Loaded 2018-05-23T17:33:00.861Z,1527096780.861 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-05-23T17:33:00.862Z,1527096780.862 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-05-23T17:33:00.863Z,1527096780.863 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-05-23T17:33:01.007Z,1527096781.007 [SBIT](DEBUG): Construct Startup Built In Test. 2018-05-23T17:33:01.028Z,1527096781.028 [SBIT] Loaded 2018-05-23T17:33:01.029Z,1527096781.029 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-05-23T17:33:01.029Z,1527096781.029 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-05-23T17:33:01.041Z,1527096781.041 [IBIT] Loaded 2018-05-23T17:33:01.041Z,1527096781.041 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-05-23T17:33:01.044Z,1527096781.044 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-05-23T17:33:01.187Z,1527096781.187 [CBIT] Loaded 2018-05-23T17:33:01.187Z,1527096781.187 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-05-23T17:33:01.188Z,1527096781.188 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-05-23T17:33:01.188Z,1527096781.188 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-05-23T17:33:01.359Z,1527096781.359 [Aanderaa_O2] Loaded 2018-05-23T17:33:01.359Z,1527096781.359 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-05-23T17:33:01.370Z,1527096781.370 [CANONSampler] Loaded 2018-05-23T17:33:01.371Z,1527096781.371 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2018-05-23T17:33:01.416Z,1527096781.416 [CTD_Seabird] Loaded 2018-05-23T17:33:01.416Z,1527096781.416 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-05-23T17:33:01.417Z,1527096781.417 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409444E0 2018-05-23T17:33:01.418Z,1527096781.418 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4960 2018-05-23T17:33:01.433Z,1527096781.433 [ESPComponent] Loaded 2018-05-23T17:33:01.434Z,1527096781.434 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-05-23T17:33:01.447Z,1527096781.447 [PAR_Licor] Loaded 2018-05-23T17:33:01.448Z,1527096781.448 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-05-23T17:33:01.494Z,1527096781.494 [WetLabsBB2FL] Loaded 2018-05-23T17:33:01.495Z,1527096781.495 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-05-23T17:33:01.495Z,1527096781.495 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409744E0 2018-05-23T17:33:01.496Z,1527096781.496 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4961 2018-05-23T17:33:01.496Z,1527096781.496 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-05-23T17:33:01.497Z,1527096781.497 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-05-23T17:33:01.577Z,1527096781.577 [VerticalControl](DEBUG): Construct VerticalControl. 2018-05-23T17:33:01.657Z,1527096781.657 [VerticalControl] Loaded 2018-05-23T17:33:01.657Z,1527096781.657 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-05-23T17:33:01.658Z,1527096781.658 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-05-23T17:33:01.712Z,1527096781.712 [HorizontalControl] Loaded 2018-05-23T17:33:01.713Z,1527096781.713 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-05-23T17:33:01.713Z,1527096781.713 [SpeedControl](DEBUG): Construct SpeedControl. 2018-05-23T17:33:01.715Z,1527096781.715 [SpeedControl] Loaded 2018-05-23T17:33:01.715Z,1527096781.715 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-05-23T17:33:01.716Z,1527096781.716 [LoopControl](DEBUG): Construct LoopControl. 2018-05-23T17:33:01.716Z,1527096781.716 [LoopControl] Loaded 2018-05-23T17:33:01.717Z,1527096781.717 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-05-23T17:33:01.717Z,1527096781.717 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-05-23T17:33:01.718Z,1527096781.718 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-05-23T17:33:01.751Z,1527096781.751 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-05-23T17:33:01.751Z,1527096781.751 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-05-23T17:33:01.849Z,1527096781.849 [DeadReckonUsingSpeedCalculator] Loaded 2018-05-23T17:33:01.850Z,1527096781.850 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-05-23T17:33:01.903Z,1527096781.903 [DeadReckonWithRespectToSeafloor] Loaded 2018-05-23T17:33:01.903Z,1527096781.903 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2018-05-23T17:33:01.919Z,1527096781.919 [NavChart] Loaded 2018-05-23T17:33:01.919Z,1527096781.919 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-05-23T17:33:01.923Z,1527096781.923 [UniversalFixResidualReporter] Loaded 2018-05-23T17:33:01.923Z,1527096781.923 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-05-23T17:33:01.923Z,1527096781.923 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-05-23T17:33:01.927Z,1527096781.927 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-05-23T17:33:01.928Z,1527096781.928 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-05-23T17:33:01.934Z,1527096781.934 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-05-23T17:33:01.935Z,1527096781.935 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A004E0 2018-05-23T17:33:01.935Z,1527096781.935 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4962 2018-05-23T17:33:01.940Z,1527096781.940 [Supervisor](INFO): Main Thread ID is 797 2018-05-23T17:33:01.940Z,1527096781.940 [Supervisor](DEBUG): Running supervisor. 2018-05-23T17:33:01.941Z,1527096781.941 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4963 2018-05-23T17:33:01.943Z,1527096781.943 [controlThread ThreadHandler](INFO): Handler Thread ID is 4964 2018-05-23T17:33:01.943Z,1527096781.943 [controlThread](DEBUG): Initializing ControlThread 2018-05-23T17:33:01.944Z,1527096781.944 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-05-23T17:33:01.945Z,1527096781.945 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-05-23T17:33:01.945Z,1527096781.945 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-05-23T17:33:01.945Z,1527096781.945 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-05-23T17:33:01.946Z,1527096781.946 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-05-23T17:33:01.947Z,1527096781.947 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-05-23T17:33:01.947Z,1527096781.947 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-05-23T17:33:01.953Z,1527096781.953 [SBIT](INFO): Initialize SBIT Component. 2018-05-23T17:33:01.953Z,1527096781.953 [SBIT](IMPORTANT): git: 2018-05-17 2018-05-23T17:33:01.953Z,1527096781.953 [SBIT](INFO): git hash: 418d027d9554c4aed44cef59bd1b73332cce0e52 2018-05-23T17:33:01.954Z,1527096781.954 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-05-23T17:33:01.955Z,1527096781.955 [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-23T17:33:01.956Z,1527096781.956 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2018-05-23T17:33:01.956Z,1527096781.956 [IBIT](INFO): Initialize IBIT Component. 2018-05-23T17:33:01.957Z,1527096781.957 [CBIT](DEBUG): Initialize CBIT Component. 2018-05-23T17:33:01.957Z,1527096781.957 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-05-23T17:33:01.959Z,1527096781.959 [logger ThreadHandler](INFO): Handler Thread ID is 4965 2018-05-23T17:33:01.979Z,1527096781.979 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4966 2018-05-23T17:33:01.985Z,1527096781.985 [Radio_Surface](INFO): Powering up 2018-05-23T17:33:01.991Z,1527096781.991 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4967 2018-05-23T17:33:01.991Z,1527096781.991 [CTD_Seabird](INFO): Initializing 2018-05-23T17:33:01.992Z,1527096781.992 [CTD_Seabird](INFO): Checking LCM 2018-05-23T17:33:02.001Z,1527096782.001 [CTD_Seabird](INFO): LCM OK 2018-05-23T17:33:02.001Z,1527096782.001 [CTD_Seabird](INFO): Powering up 2018-05-23T17:33:02.007Z,1527096782.007 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4968 2018-05-23T17:33:02.008Z,1527096782.008 [WetLabsBB2FL](INFO): Powering down 2018-05-23T17:33:02.035Z,1527096782.035 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4969 2018-05-23T17:33:02.042Z,1527096782.042 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-05-23T17:33:02.043Z,1527096782.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-05-23T17:33:02.043Z,1527096782.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-05-23T17:33:02.043Z,1527096782.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-05-23T17:33:02.043Z,1527096782.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-05-23T17:33:02.043Z,1527096782.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-05-23T17:33:02.044Z,1527096782.044 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-05-23T17:33:02.044Z,1527096782.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-05-23T17:33:02.044Z,1527096782.044 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-05-23T17:33:02.044Z,1527096782.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-05-23T17:33:02.044Z,1527096782.044 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-05-23T17:33:02.044Z,1527096782.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-05-23T17:33:02.045Z,1527096782.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-05-23T17:33:02.045Z,1527096782.045 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-05-23T17:33:02.045Z,1527096782.045 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-05-23T17:33:02.045Z,1527096782.045 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-05-23T17:33:02.067Z,1527096782.067 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-05-23T17:33:02.068Z,1527096782.068 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-05-23T17:33:02.069Z,1527096782.069 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-05-23T17:33:02.069Z,1527096782.069 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-05-23T17:33:02.070Z,1527096782.070 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-05-23T17:33:02.071Z,1527096782.071 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-05-23T17:33:02.071Z,1527096782.071 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-05-23T17:33:02.072Z,1527096782.072 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-05-23T17:33:02.072Z,1527096782.072 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-05-23T17:33:02.072Z,1527096782.072 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-05-23T17:33:02.073Z,1527096782.073 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-05-23T17:33:02.073Z,1527096782.073 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-05-23T17:33:02.074Z,1527096782.074 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-05-23T17:33:02.093Z,1527096782.093 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-05-23T17:33:02.124Z,1527096782.124 [MissionManager](DEBUG): 2018-05-23T17:33:02.125Z,1527096782.125 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-05-23T17:33:02.204Z,1527096782.204 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-05-23T17:33:02.206Z,1527096782.206 [Default:A.Wait](DEBUG): Construct Wait. 2018-05-23T17:33:02.207Z,1527096782.207 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-05-23T17:33:02.247Z,1527096782.247 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-05-23T17:33:02.249Z,1527096782.249 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-05-23T17:33:02.271Z,1527096782.271 [Default:E.Execute](DEBUG): Construct Execute. 2018-05-23T17:33:02.274Z,1527096782.274 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-05-23T17:33:02.293Z,1527096782.293 [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-23T17:33:02.339Z,1527096782.339 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-05-23T17:33:02.348Z,1527096782.348 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2018-05-23T17:33:02.584Z,1527096782.584 [CANONSampler](INFO): Powering down 2018-05-23T17:33:02.694Z,1527096782.694 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-05-23T17:33:02.819Z,1527096782.819 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:02.830Z,1527096782.830 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:02.923Z,1527096782.923 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-05-23T17:33:02.930Z,1527096782.930 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-05-23T17:33:02.953Z,1527096782.953 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-05-23T17:33:02.958Z,1527096782.958 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-05-23T17:33:02.965Z,1527096782.965 [MassServo](DEBUG): Initializing EZServoServo. 2018-05-23T17:33:02.970Z,1527096782.970 [MassServo](DEBUG): Initializing MassServo. 2018-05-23T17:33:02.976Z,1527096782.976 [RudderServo](DEBUG): Initializing EZServoServo. 2018-05-23T17:33:02.983Z,1527096782.983 [RudderServo](DEBUG): Initializing RudderServo. 2018-05-23T17:33:02.988Z,1527096782.988 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-05-23T17:33:02.995Z,1527096782.995 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-05-23T17:33:03.307Z,1527096783.307 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:03.307Z,1527096783.307 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:03.527Z,1527096783.527 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:03.528Z,1527096783.528 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:03.866Z,1527096783.866 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-05-23T17:33:03.866Z,1527096783.866 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-05-23T17:33:03.867Z,1527096783.867 [BuoyancyServo] Communications Fault, FailCount= 1 2018-05-23T17:33:03.867Z,1527096783.867 [BuoyancyServo](ERROR): Communications Fault 2018-05-23T17:33:04.074Z,1527096784.074 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-05-23T17:33:04.155Z,1527096784.155 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:04.155Z,1527096784.155 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:04.168Z,1527096784.168 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-05-23T17:33:04.169Z,1527096784.169 [BuoyancyServo](INFO): Powering down 2018-05-23T17:33:04.534Z,1527096784.534 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:04.535Z,1527096784.535 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:04.954Z,1527096784.954 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:04.955Z,1527096784.955 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:05.354Z,1527096785.354 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:05.355Z,1527096785.355 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:05.838Z,1527096785.838 [Aanderaa_O2](INFO): Powering down 2018-05-23T17:33:05.939Z,1527096785.939 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:05.940Z,1527096785.940 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-05-23T17:33:06.247Z,1527096786.247 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-05-23T17:33:06.247Z,1527096786.247 [BuoyancyServo] No Fault, FailCount= 1 2018-05-23T17:33:06.587Z,1527096786.587 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-05-23T17:33:06.707Z,1527096786.707 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-05-23T17:33:07.566Z,1527096787.566 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-05-23T17:33:07.693Z,1527096787.693 [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-23T17:33:07.769Z,1527096787.769 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-05-23T17:33:08.110Z,1527096788.110 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-05-23T17:33:08.110Z,1527096788.110 [CTD_Seabird](INFO): Powering down 2018-05-23T17:33:25.424Z,1527096805.424 [SBIT](IMPORTANT): Beginning Startup BIT 2018-05-23T17:33:25.436Z,1527096805.436 [CBIT](IMPORTANT): Beginning ground fault scan 2018-05-23T17:33:28.676Z,1527096808.676 [NAL9602](INFO): Powering up NAL9602 2018-05-23T17:33:36.806Z,1527096816.806 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.015018 CHAN A1 (24V): -0.017828 CHAN A2 (12V): -0.002227 CHAN A3 (5V): -0.001868 CHAN B0 (3.3V): -0.000140 CHAN B1 (3.15aV): -0.000122 CHAN B2 (3.15bV): 0.000236 CHAN B3 (GND): -0.000428 OPEN: -0.000538 Full Scale Calc: 4.765 mA, -1.589 mA 2018-05-23T17:33:39.488Z,1527096819.488 [NAL9602](INFO): NAL9602 initialized 2018-05-23T17:34:04.149Z,1527096844.149 [NAL9602](INFO): SBD MO Status=0, MOMSN=5998, MT Status=0, MTMSN=0 2018-05-23T17:34:04.150Z,1527096844.150 [NAL9602](INFO): No messages in MT queue 2018-05-23T17:34:19.563Z,1527096859.563 [SBIT](IMPORTANT): SBIT PASSED 2018-05-23T17:34:19.603Z,1527096859.603 [CommandLine](IMPORTANT): got command configSet list 2018-05-23T17:34:19.604Z,1527096859.604 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-05-23T17:34:19.605Z,1527096859.605 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2018-05-23T17:34:19.605Z,1527096859.605 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2018-05-23T17:34:19.605Z,1527096859.605 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2018-05-23T17:34:19.605Z,1527096859.605 [CommandLine](IMPORTANT): Normal linearApproximation CTD_NeilBrown.bin_mean_sea_water_salinity 0.020000 practical_salinity_unit; 2018-05-23T17:34:19.605Z,1527096859.605 [CommandLine](IMPORTANT): Normal linearApproximation CTD_NeilBrown.bin_mean_sea_water_temperature 0.150000 kelvin; 2018-05-23T17:34:19.605Z,1527096859.605 [CommandLine](IMPORTANT): Normal linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.020000 practical_salinity_unit; 2018-05-23T17:34:19.605Z,1527096859.605 [CommandLine](IMPORTANT): Normal linearApproximation CTD_Seabird.bin_median_sea_water_temperature 0.150000 kelvin; 2018-05-23T17:34:19.605Z,1527096859.605 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter; 2018-05-23T17:34:19.606Z,1527096859.606 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool; 2018-05-23T17:34:19.939Z,1527096859.939 [MissionManager](IMPORTANT): Started mission Startup 2018-05-23T17:34:19.939Z,1527096859.939 [Startup] Running Loop=1 2018-05-23T17:34:19.939Z,1527096859.939 [Startup](DEBUG): Aggregate::initialize Startup 2018-05-23T17:34:19.940Z,1527096859.940 [Startup:A.GoToSurface] Running Loop=1 2018-05-23T17:34:19.940Z,1527096859.940 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-05-23T17:34:19.940Z,1527096859.940 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-05-23T17:34:19.941Z,1527096859.941 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-05-23T17:34:19.941Z,1527096859.941 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-05-23T17:34:19.941Z,1527096859.941 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-05-23T17:34:19.942Z,1527096859.942 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-05-23T17:34:19.960Z,1527096859.960 [Startup:StartupSatComms] Running Loop=1 2018-05-23T17:34:19.960Z,1527096859.960 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-05-23T17:34:19.960Z,1527096859.960 [Startup:StartupSatComms:A] Running Loop=1 2018-05-23T17:34:20.340Z,1527096860.340 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-05-23T17:34:35.085Z,1527096875.085 [NAL9602](INFO): GPS fix at 20180523T173432: (36.802245, -121.786890) 2018-05-23T17:34:35.164Z,1527096875.164 [Startup:StartupSatComms:A] Stopped 2018-05-23T17:34:35.164Z,1527096875.164 [Startup:StartupSatComms:B] Running Loop=1 2018-05-23T17:34:35.556Z,1527096875.556 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-05-23T17:34:48.829Z,1527096888.829 [NAL9602](INFO): SBD MO Status=1, MOMSN=5999, MT Status=0, MTMSN=0 2018-05-23T17:34:48.879Z,1527096888.879 [NAL9602](INFO): Sent 25 bytes from file Logs/20180523T165048/Courier0015.lzma 2018-05-23T17:34:48.879Z,1527096888.879 [NAL9602](INFO): Packets left to send: 0 2018-05-23T17:34:48.880Z,1527096888.880 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T165048/Courier0015.lzma.parts/0000.sbd 2018-05-23T17:34:48.881Z,1527096888.881 [NAL9602](DEBUG): Completed sending Logs/20180523T165048/Courier0015.lzma 2018-05-23T17:34:57.365Z,1527096897.365 [NAL9602](INFO): SBD MO Status=1, MOMSN=6000, MT Status=0, MTMSN=0 2018-05-23T17:34:57.414Z,1527096897.414 [NAL9602](INFO): Sent 212 bytes from file Logs/20180523T173251/Courier0000.lzma 2018-05-23T17:34:57.415Z,1527096897.415 [NAL9602](INFO): Packets left to send: 0 2018-05-23T17:34:57.416Z,1527096897.416 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T173251/Courier0000.lzma.parts/0000.sbd 2018-05-23T17:34:57.416Z,1527096897.416 [NAL9602](DEBUG): Completed sending Logs/20180523T173251/Courier0000.lzma 2018-05-23T17:35:07.025Z,1527096907.025 [NAL9602](INFO): SBD MO Status=1, MOMSN=6001, MT Status=0, MTMSN=0 2018-05-23T17:35:07.078Z,1527096907.078 [NAL9602](INFO): Sent 332 bytes from file Logs/20180523T165048/Express0016.lzma 2018-05-23T17:35:07.079Z,1527096907.079 [NAL9602](INFO): Packets left to send: 2 2018-05-23T17:35:07.080Z,1527096907.080 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T165048/Express0016.lzma.parts/0002.sbd 2018-05-23T17:35:17.717Z,1527096917.717 [NAL9602](INFO): SBD MO Status=1, MOMSN=6002, MT Status=0, MTMSN=0 2018-05-23T17:35:17.771Z,1527096917.771 [NAL9602](INFO): Sent 332 bytes from file Logs/20180523T165048/Express0016.lzma 2018-05-23T17:35:17.771Z,1527096917.771 [NAL9602](INFO): Packets left to send: 1 2018-05-23T17:35:17.772Z,1527096917.772 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T165048/Express0016.lzma.parts/0001.sbd 2018-05-23T17:35:28.353Z,1527096928.353 [NAL9602](INFO): SBD MO Status=1, MOMSN=6003, MT Status=0, MTMSN=0 2018-05-23T17:35:28.402Z,1527096928.402 [NAL9602](INFO): Sent 121 bytes from file Logs/20180523T165048/Express0016.lzma 2018-05-23T17:35:28.403Z,1527096928.403 [NAL9602](INFO): Packets left to send: 0 2018-05-23T17:35:28.404Z,1527096928.404 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T165048/Express0016.lzma.parts/0000.sbd 2018-05-23T17:35:28.404Z,1527096928.404 [NAL9602](DEBUG): Completed sending Logs/20180523T165048/Express0016.lzma 2018-05-23T17:35:35.465Z,1527096935.465 [Startup:StartupSatComms:B](INFO): Timed out from 2018-05-23T17:34:35.2Z 2018-05-23T17:35:35.465Z,1527096935.465 [Startup:StartupSatComms:B] Stopped 2018-05-23T17:35:35.465Z,1527096935.465 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-05-23T17:35:35.465Z,1527096935.465 [Startup:StartupSatComms] Stopped 2018-05-23T17:35:35.465Z,1527096935.465 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-05-23T17:35:35.466Z,1527096935.466 [Startup](INFO): Completed Startup 2018-05-23T17:35:35.466Z,1527096935.466 [MissionManager](INFO): Startup is completed. 2018-05-23T17:35:35.467Z,1527096935.467 [MissionManager](INFO): Uninitializing Mission Startup 2018-05-23T17:35:35.467Z,1527096935.467 [Startup] Stopped 2018-05-23T17:35:35.467Z,1527096935.467 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-05-23T17:35:35.467Z,1527096935.467 [Startup:A.GoToSurface] Stopped 2018-05-23T17:35:35.467Z,1527096935.467 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-05-23T17:35:35.879Z,1527096935.879 [MissionManager](IMPORTANT): Started mission Default 2018-05-23T17:35:35.879Z,1527096935.879 [Default] Running Loop=1 2018-05-23T17:35:35.879Z,1527096935.879 [Default](DEBUG): Aggregate::initialize Default 2018-05-23T17:35:35.879Z,1527096935.879 [Default:B.GoToSurface] Running Loop=1 2018-05-23T17:35:35.879Z,1527096935.879 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-05-23T17:35:35.879Z,1527096935.879 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-05-23T17:35:35.880Z,1527096935.880 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-05-23T17:35:35.880Z,1527096935.880 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-05-23T17:35:35.880Z,1527096935.880 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-05-23T17:35:35.881Z,1527096935.881 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-05-23T17:35:35.881Z,1527096935.881 [Default:A.Wait] Running Loop=1 2018-05-23T17:35:35.881Z,1527096935.881 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-05-23T17:35:37.421Z,1527096937.421 [NAL9602](INFO): SBD MO Status=1, MOMSN=6004, MT Status=0, MTMSN=0 2018-05-23T17:35:37.470Z,1527096937.470 [NAL9602](INFO): Sent 332 bytes from file Logs/20180523T173251/Express0001.lzma 2018-05-23T17:35:37.471Z,1527096937.471 [NAL9602](INFO): Packets left to send: 2 2018-05-23T17:35:37.472Z,1527096937.472 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T173251/Express0001.lzma.parts/0002.sbd 2018-05-23T17:35:49.068Z,1527096949.068 [Default:A.Wait](INFO): Done Waiting. 2018-05-23T17:35:49.069Z,1527096949.069 [Default:A.Wait] Stopped 2018-05-23T17:35:49.069Z,1527096949.069 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-05-23T17:35:49.473Z,1527096949.473 [Default:CheckIn] Running Loop=1 2018-05-23T17:35:49.474Z,1527096949.474 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-05-23T17:35:49.474Z,1527096949.474 [Default:CheckIn:Read_GPS] Running Loop=1 2018-05-23T17:35:49.868Z,1527096949.868 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-05-23T17:35:51.423Z,1527096951.423 [NAL9602](INFO): GPS fix at 20180523T173548: (36.802169, -121.787017) 2018-05-23T17:35:51.491Z,1527096951.491 [Default:CheckIn:Read_GPS] Stopped 2018-05-23T17:35:51.491Z,1527096951.491 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-05-23T17:35:51.889Z,1527096951.889 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-05-23T17:36:02.283Z,1527096962.283 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-05-23T17:36:02.283Z,1527096962.283 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-05-23T17:36:02.283Z,1527096962.283 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-05-23T17:36:02.337Z,1527096962.337 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-05-23T17:36:02.435Z,1527096962.435 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-05-23T17:36:02.435Z,1527096962.435 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-05-23T17:36:02.819Z,1527096962.819 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-05-23T17:36:02.819Z,1527096962.819 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-05-23T17:36:02.819Z,1527096962.819 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-05-23T17:36:08.765Z,1527096968.765 [NAL9602](INFO): SBD MO Status=1, MOMSN=6005, MT Status=0, MTMSN=0 2018-05-23T17:36:08.819Z,1527096968.819 [NAL9602](INFO): Sent 229 bytes from file Logs/20180523T173251/Courier0004.lzma 2018-05-23T17:36:08.819Z,1527096968.819 [NAL9602](INFO): Packets left to send: 0 2018-05-23T17:36:08.820Z,1527096968.820 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T173251/Courier0004.lzma.parts/0000.sbd 2018-05-23T17:36:08.820Z,1527096968.820 [NAL9602](DEBUG): Completed sending Logs/20180523T173251/Courier0004.lzma 2018-05-23T17:36:17.401Z,1527096977.401 [NAL9602](INFO): SBD MO Status=1, MOMSN=6006, MT Status=0, MTMSN=0 2018-05-23T17:36:17.451Z,1527096977.451 [NAL9602](INFO): Sent 332 bytes from file Logs/20180523T173251/Express0001.lzma 2018-05-23T17:36:17.451Z,1527096977.451 [NAL9602](INFO): Packets left to send: 1 2018-05-23T17:36:17.452Z,1527096977.452 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T173251/Express0001.lzma.parts/0001.sbd 2018-05-23T17:36:26.025Z,1527096986.025 [NAL9602](INFO): SBD MO Status=1, MOMSN=6007, MT Status=0, MTMSN=0 2018-05-23T17:36:26.074Z,1527096986.074 [NAL9602](INFO): Sent 250 bytes from file Logs/20180523T173251/Express0001.lzma 2018-05-23T17:36:26.075Z,1527096986.075 [NAL9602](INFO): Packets left to send: 0 2018-05-23T17:36:26.076Z,1527096986.076 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T173251/Express0001.lzma.parts/0000.sbd 2018-05-23T17:36:26.077Z,1527096986.077 [NAL9602](DEBUG): Completed sending Logs/20180523T173251/Express0001.lzma 2018-05-23T17:36:33.053Z,1527096993.053 [NAL9602](INFO): SBD MO Status=1, MOMSN=6008, MT Status=0, MTMSN=0 2018-05-23T17:36:33.099Z,1527096993.099 [NAL9602](INFO): Sent 94 bytes from file Logs/20180523T173251/Express0005.lzma 2018-05-23T17:36:33.099Z,1527096993.099 [NAL9602](INFO): Packets left to send: 0 2018-05-23T17:36:33.100Z,1527096993.100 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180523T173251/Express0005.lzma.parts/0000.sbd 2018-05-23T17:36:33.100Z,1527096993.100 [NAL9602](DEBUG): Completed sending Logs/20180523T173251/Express0005.lzma 2018-05-23T17:36:41.525Z,1527097001.525 [NAL9602](INFO): SBD MO Status=0, MOMSN=6009, MT Status=0, MTMSN=0 2018-05-23T17:36:41.629Z,1527097001.629 [Default:CheckIn:Read_Iridium] Stopped 2018-05-23T17:36:41.629Z,1527097001.629 [Default:CheckIn:C.Wait] Running Loop=1 2018-05-23T17:36:41.629Z,1527097001.629 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-05-23T17:37:07.924Z,1527097027.924 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004466 2018-05-23T17:37:12.323Z,1527097032.323 [NAL9602](INFO): Powering down 2018-05-23T17:39:03.227Z,1527097143.227 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-05-23T17:39:03.227Z,1527097143.227 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-05-23T17:39:03.227Z,1527097143.227 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-05-23T17:39:03.248Z,1527097143.248 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-05-23T17:39:03.614Z,1527097143.614 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-05-23T17:39:03.614Z,1527097143.614 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-05-23T17:39:04.021Z,1527097144.021 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-05-23T17:39:04.021Z,1527097144.021 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-05-23T17:39:04.021Z,1527097144.021 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-05-23T17:41:42.115Z,1527097302.115 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-05-23T17:41:42.115Z,1527097302.115 [Default:CheckIn:C.Wait] Stopped 2018-05-23T17:41:42.116Z,1527097302.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-05-23T17:41:42.116Z,1527097302.116 [Default:CheckIn:D] Running Loop=1 2018-05-23T17:41:42.514Z,1527097302.514 [Default:CheckIn:D] Stopped 2018-05-23T17:41:42.514Z,1527097302.514 [Default:CheckIn:E] Running Loop=1 2018-05-23T17:41:42.904Z,1527097302.904 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.110547 min 2018-05-23T17:41:42.906Z,1527097302.906 [Default:CheckIn:E] Stopped 2018-05-23T17:41:42.910Z,1527097302.910 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-05-23T17:41:42.910Z,1527097302.910 [Default:CheckIn] Stopped 2018-05-23T17:41:42.910Z,1527097302.911 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-05-23T17:41:42.911Z,1527097302.911 [Default:CheckIn](INFO): Running loop #2 2018-05-23T17:41:42.911Z,1527097302.911 [Default:CheckIn] Running Loop=2 2018-05-23T17:41:42.911Z,1527097302.911 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-05-23T17:41:42.911Z,1527097302.911 [Default:CheckIn:Read_GPS] Running Loop=1 2018-05-23T17:41:43.655Z,1527097303.655 [NAL9602](INFO): Powering up 2018-05-23T17:41:54.856Z,1527097314.856 [NAL9602](INFO): NAL9602 initialized 2018-05-23T17:41:57.327Z,1527097317.327 [CommandLine](INFO): End of History 2018-05-23T17:41:57.399Z,1527097317.399 [CommandLine](INFO): End of History 2018-05-23T17:41:57.443Z,1527097317.443 [CommandLine](INFO): End of History 2018-05-23T17:41:57.497Z,1527097317.497 [CommandLine](INFO): End of History 2018-05-23T17:41:57.522Z,1527097317.522 [CommandLine](INFO): End of History 2018-05-23T17:41:57.566Z,1527097317.566 [CommandLine](INFO): End of History 2018-05-23T17:41:57.609Z,1527097317.609 [CommandLine](INFO): End of History 2018-05-23T17:41:57.633Z,1527097317.633 [CommandLine](INFO): End of History 2018-05-23T17:42:04.111Z,1527097324.111 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-05-23T17:42:04.111Z,1527097324.111 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2018-05-23T17:42:04.111Z,1527097324.111 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-05-23T17:42:04.124Z,1527097324.124 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-05-23T17:42:04.553Z,1527097324.553 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-05-23T17:42:04.553Z,1527097324.553 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2018-05-23T17:42:04.916Z,1527097324.916 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-05-23T17:42:04.916Z,1527097324.916 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-05-23T17:42:04.917Z,1527097324.917 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-05-23T17:42:34.669Z,1527097354.669 [NAL9602](INFO): SBD MO Status=0, MOMSN=6010, MT Status=0, MTMSN=0 2018-05-23T17:42:34.669Z,1527097354.669 [NAL9602](INFO): No messages in MT queue 2018-05-23T17:43:02.320Z,1527097382.320 [CBIT](DEBUG): EFC running - data check-sum false 2018-05-23T17:45:05.111Z,1527097505.111 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-05-23T17:45:05.111Z,1527097505.111 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2018-05-23T17:45:05.111Z,1527097505.111 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-05-23T17:45:05.152Z,1527097505.152 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-05-23T17:45:05.526Z,1527097505.526 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-05-23T17:45:05.526Z,1527097505.526 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2018-05-23T17:45:08.759Z,1527097508.759 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-05-23T17:45:08.759Z,1527097508.759 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-05-23T17:45:08.760Z,1527097508.760 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-05-23T17:45:28.792Z,1527097528.792 [NAL9602](INFO): GPS fix at 20180523T174527: (36.802217, -121.787037) 2018-05-23T17:45:28.851Z,1527097528.851 [Default:CheckIn:Read_GPS] Stopped 2018-05-23T17:45:28.851Z,1527097528.851 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-05-23T17:45:33.303Z,1527097533.303 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180523T173251/Courier0007.lzma 2018-05-23T17:45:34.047Z,1527097534.047 [DataOverHttps](INFO): Moved sent file to Logs/20180523T173251/Courier0007.lzma.bak 2018-05-23T17:45:34.048Z,1527097534.048 [DataOverHttps](INFO): SBD MOMSN=8290812 2018-05-23T17:45:43.033Z,1527097543.033 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20180523T173251/Express0008.lzma 2018-05-23T17:45:43.831Z,1527097543.831 [DataOverHttps](INFO): Moved sent file to Logs/20180523T173251/Express0008.lzma.bak 2018-05-23T17:45:43.832Z,1527097543.832 [DataOverHttps](INFO): SBD MOMSN=8290815 2018-05-23T17:45:44.666Z,1527097544.666 [Default:CheckIn:Read_Iridium] Stopped 2018-05-23T17:45:44.666Z,1527097544.666 [Default:CheckIn:C.Wait] Running Loop=1 2018-05-23T17:45:44.666Z,1527097544.666 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-05-23T17:46:03.651Z,1527097563.651 [NAL9602](INFO): Powering down 2018-05-23T17:46:06.417Z,1527097566.417 [CommandLine](INFO): End of History 2018-05-23T17:48:08.978Z,1527097688.978 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-05-23T17:48:08.978Z,1527097688.978 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-05-23T17:48:08.978Z,1527097688.978 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-05-23T17:48:09.007Z,1527097689.007 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-05-23T17:48:09.397Z,1527097689.397 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-05-23T17:48:09.397Z,1527097689.397 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-05-23T17:48:09.775Z,1527097689.775 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-05-23T17:48:09.775Z,1527097689.775 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-05-23T17:48:09.775Z,1527097689.775 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-05-23T17:50:45.007Z,1527097845.007 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-05-23T17:50:45.007Z,1527097845.007 [Default:CheckIn:C.Wait] Stopped 2018-05-23T17:50:45.007Z,1527097845.007 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-05-23T17:50:45.007Z,1527097845.007 [Default:CheckIn:D] Running Loop=1 2018-05-23T17:50:45.413Z,1527097845.413 [Default:CheckIn:D] Stopped 2018-05-23T17:50:45.413Z,1527097845.413 [Default:CheckIn:E] Running Loop=1 2018-05-23T17:50:45.806Z,1527097845.806 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.158900 min 2018-05-23T17:50:45.807Z,1527097845.807 [Default:CheckIn:E] Stopped 2018-05-23T17:50:45.807Z,1527097845.807 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-05-23T17:50:45.807Z,1527097845.807 [Default:CheckIn] Stopped 2018-05-23T17:50:45.807Z,1527097845.807 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-05-23T17:50:45.807Z,1527097845.807 [Default:CheckIn](INFO): Running loop #3 2018-05-23T17:50:45.807Z,1527097845.807 [Default:CheckIn] Running Loop=3 2018-05-23T17:50:45.807Z,1527097845.807 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-05-23T17:50:45.807Z,1527097845.807 [Default:CheckIn:Read_GPS] Running Loop=1 2018-05-23T17:50:49.010Z,1527097849.010 [NAL9602](INFO): Powering up 2018-05-23T17:51:00.175Z,1527097860.175 [NAL9602](INFO): NAL9602 initialized 2018-05-23T17:51:09.820Z,1527097869.820 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-05-23T17:51:09.820Z,1527097869.820 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-05-23T17:51:09.820Z,1527097869.820 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-05-23T17:51:09.833Z,1527097869.833 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-05-23T17:51:10.230Z,1527097870.230 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-05-23T17:51:10.230Z,1527097870.230 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-05-23T17:51:10.635Z,1527097870.635 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-05-23T17:51:10.636Z,1527097870.636 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-05-23T17:51:10.636Z,1527097870.636 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-05-23T17:52:49.909Z,1527097969.909 [NAL9602](INFO): SBD MO Status=2, MOMSN=6011, MT Status=2, MTMSN=0 2018-05-23T17:52:49.909Z,1527097969.909 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-05-23T17:52:59.909Z,1527097979.909 [NAL9602](INFO): SBD MO Status=0, MOMSN=6011, MT Status=0, MTMSN=0 2018-05-23T17:52:59.909Z,1527097979.909 [NAL9602](INFO): No messages in MT queue 2018-05-23T17:53:02.367Z,1527097982.367 [CBIT](DEBUG): EFC running - data check-sum false 2018-05-23T17:53:24.707Z,1527098004.707 [NAL9602](INFO): GPS fix at 20180523T175321: (36.802146, -121.787027) 2018-05-23T17:53:24.789Z,1527098004.789 [Default:CheckIn:Read_GPS] Stopped 2018-05-23T17:53:24.789Z,1527098004.789 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-05-23T17:53:29.176Z,1527098009.176 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180523T173251/Courier0010.lzma 2018-05-23T17:53:29.976Z,1527098009.976 [DataOverHttps](INFO): Moved sent file to Logs/20180523T173251/Courier0010.lzma.bak 2018-05-23T17:53:29.976Z,1527098009.976 [DataOverHttps](IMPORTANT): SBD MOMSN=8290819, MTMSN=20180523T175328 2018-05-23T17:53:34.446Z,1527098014.446 [DataOverHttps](INFO): Received command:restart logs