2020-02-25T22:11:45.782Z,1582668705.782 [Supervisor](DEBUG): Initializing supervisor. 2020-02-25T22:11:45.784Z,1582668705.784 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-02-25T22:11:45.785Z,1582668705.785 [SyncHandler](INFO): Protected caller Thread ID is 806 2020-02-25T22:11:45.785Z,1582668705.785 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-02-25T22:11:45.786Z,1582668705.786 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-02-25T22:11:45.787Z,1582668705.787 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2020-02-25T22:11:45.789Z,1582668705.789 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-02-25T22:11:45.801Z,1582668705.801 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-02-25T22:11:45.802Z,1582668705.802 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-02-25T22:11:45.802Z,1582668705.802 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2020-02-25T22:11:45.803Z,1582668705.803 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-02-25T22:11:45.804Z,1582668705.804 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-02-25T22:11:45.804Z,1582668705.804 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2020-02-25T22:11:45.806Z,1582668705.806 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-02-25T22:11:45.806Z,1582668705.806 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-02-25T22:11:45.811Z,1582668705.811 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-02-25T22:11:45.907Z,1582668705.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-02-25T22:11:45.909Z,1582668705.909 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-02-25T22:11:46.500Z,1582668706.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-02-25T22:11:46.502Z,1582668706.502 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-02-25T22:11:46.601Z,1582668706.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-02-25T22:11:46.603Z,1582668706.603 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-02-25T22:11:46.706Z,1582668706.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-02-25T22:11:46.707Z,1582668706.707 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-02-25T22:11:46.788Z,1582668706.788 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-02-25T22:11:46.929Z,1582668706.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-02-25T22:11:46.930Z,1582668706.930 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-02-25T22:11:47.225Z,1582668707.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-02-25T22:11:47.226Z,1582668707.226 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-02-25T22:11:47.683Z,1582668707.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-02-25T22:11:47.685Z,1582668707.685 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-02-25T22:11:47.829Z,1582668707.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-02-25T22:11:47.830Z,1582668707.830 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-02-25T22:11:48.026Z,1582668708.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-02-25T22:11:48.027Z,1582668708.027 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-02-25T22:11:48.500Z,1582668708.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-02-25T22:11:48.502Z,1582668708.502 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-02-25T22:11:48.725Z,1582668708.725 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-02-25T22:11:48.727Z,1582668708.727 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-02-25T22:11:48.927Z,1582668708.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-02-25T22:11:48.928Z,1582668708.928 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-02-25T22:11:49.351Z,1582668709.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-02-25T22:11:49.352Z,1582668709.352 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-02-25T22:11:50.471Z,1582668710.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-02-25T22:11:50.473Z,1582668710.473 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-02-25T22:11:50.476Z,1582668710.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-02-25T22:11:50.560Z,1582668710.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-02-25T22:11:50.725Z,1582668710.725 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-02-25T22:11:50.836Z,1582668710.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-02-25T22:11:50.922Z,1582668710.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-02-25T22:11:51.019Z,1582668711.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-02-25T22:11:51.233Z,1582668711.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-02-25T22:11:51.460Z,1582668711.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-02-25T22:11:51.460Z,1582668711.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-02-25T22:11:51.554Z,1582668711.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-02-25T22:11:51.652Z,1582668711.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-02-25T22:11:51.793Z,1582668711.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-02-25T22:11:51.892Z,1582668711.892 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2020-02-25T22:11:51.897Z,1582668711.897 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-02-25T22:11:51.992Z,1582668711.992 [VerticalControl](DEBUG): Construct VerticalControl. 2020-02-25T22:11:52.100Z,1582668712.100 [VerticalControl] Loaded 2020-02-25T22:11:52.101Z,1582668712.101 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-02-25T22:11:52.101Z,1582668712.101 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-02-25T22:11:52.179Z,1582668712.179 [HorizontalControl] Loaded 2020-02-25T22:11:52.180Z,1582668712.180 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-02-25T22:11:52.180Z,1582668712.180 [SpeedControl](DEBUG): Construct SpeedControl. 2020-02-25T22:11:52.186Z,1582668712.186 [SpeedControl] Loaded 2020-02-25T22:11:52.186Z,1582668712.186 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-02-25T22:11:52.187Z,1582668712.187 [LoopControl](DEBUG): Construct LoopControl. 2020-02-25T22:11:52.187Z,1582668712.187 [LoopControl] Loaded 2020-02-25T22:11:52.187Z,1582668712.187 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-02-25T22:11:52.188Z,1582668712.188 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-02-25T22:11:52.190Z,1582668712.190 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-02-25T22:11:52.244Z,1582668712.244 [DepthRateCalculator] Loaded 2020-02-25T22:11:52.244Z,1582668712.244 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-02-25T22:11:52.249Z,1582668712.249 [PitchRateCalculator] Loaded 2020-02-25T22:11:52.250Z,1582668712.250 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-02-25T22:11:52.265Z,1582668712.265 [SpeedCalculator] Loaded 2020-02-25T22:11:52.265Z,1582668712.265 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-02-25T22:11:52.285Z,1582668712.285 [TempGradientCalculator] Loaded 2020-02-25T22:11:52.286Z,1582668712.286 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-02-25T22:11:52.291Z,1582668712.291 [YawRateCalculator] Loaded 2020-02-25T22:11:52.291Z,1582668712.291 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-02-25T22:11:52.319Z,1582668712.319 [ElevatorOffsetCalculator] Loaded 2020-02-25T22:11:52.319Z,1582668712.319 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-02-25T22:11:52.320Z,1582668712.320 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-02-25T22:11:52.322Z,1582668712.322 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-02-25T22:11:52.371Z,1582668712.371 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-02-25T22:11:52.371Z,1582668712.371 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-02-25T22:11:52.495Z,1582668712.495 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-02-25T22:11:52.496Z,1582668712.496 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-02-25T22:11:52.913Z,1582668712.913 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-02-25T22:11:52.914Z,1582668712.914 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-02-25T22:11:53.040Z,1582668713.040 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-02-25T22:11:53.040Z,1582668713.040 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-02-25T22:11:53.576Z,1582668713.576 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-02-25T22:11:53.581Z,1582668713.581 [AHRS_M2](INFO): created writer for : platform_orientation 2020-02-25T22:11:53.583Z,1582668713.583 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-02-25T22:11:53.588Z,1582668713.588 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-02-25T22:11:53.588Z,1582668713.588 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-02-25T22:11:53.593Z,1582668713.593 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-02-25T22:11:53.594Z,1582668713.594 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-02-25T22:11:53.599Z,1582668713.599 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-02-25T22:11:53.773Z,1582668713.773 [AHRS_M2] Loaded 2020-02-25T22:11:53.774Z,1582668713.774 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-02-25T22:11:53.921Z,1582668713.921 [DataOverHttps] Loaded 2020-02-25T22:11:53.921Z,1582668713.921 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-02-25T22:11:53.923Z,1582668713.923 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0 2020-02-25T22:11:53.923Z,1582668713.923 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 888 2020-02-25T22:11:53.936Z,1582668713.936 [Depth_Keller] Loaded 2020-02-25T22:11:53.936Z,1582668713.936 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-02-25T22:11:53.941Z,1582668713.941 [DropWeight] Loaded 2020-02-25T22:11:53.941Z,1582668713.941 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-02-25T22:11:54.037Z,1582668714.037 [NAL9602] Loaded 2020-02-25T22:11:54.037Z,1582668714.037 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-02-25T22:11:54.073Z,1582668714.073 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-02-25T22:11:54.073Z,1582668714.073 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-02-25T22:11:54.074Z,1582668714.074 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-02-25T22:11:54.075Z,1582668714.075 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-02-25T22:11:54.075Z,1582668714.075 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-02-25T22:11:54.076Z,1582668714.076 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-02-25T22:11:54.076Z,1582668714.076 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-02-25T22:11:54.077Z,1582668714.077 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-02-25T22:11:54.077Z,1582668714.077 [Onboard] Loaded 2020-02-25T22:11:54.077Z,1582668714.077 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-02-25T22:11:54.080Z,1582668714.080 [Radio_Surface] Loaded 2020-02-25T22:11:54.081Z,1582668714.081 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-02-25T22:11:54.081Z,1582668714.081 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409174E0 2020-02-25T22:11:54.082Z,1582668714.082 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 889 2020-02-25T22:11:54.125Z,1582668714.125 [RDI_Pathfinder] Loaded 2020-02-25T22:11:54.125Z,1582668714.125 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-02-25T22:11:54.253Z,1582668714.253 [DAT] Loaded 2020-02-25T22:11:54.253Z,1582668714.253 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-02-25T22:11:56.180Z,1582668716.180 [BPC1] Loaded 2020-02-25T22:11:56.180Z,1582668716.180 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-02-25T22:11:56.180Z,1582668716.180 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-02-25T22:11:56.181Z,1582668716.181 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-02-25T22:11:56.313Z,1582668716.313 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-02-25T22:11:56.314Z,1582668716.314 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-02-25T22:11:56.334Z,1582668716.334 [NavChart] Loaded 2020-02-25T22:11:56.334Z,1582668716.334 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-02-25T22:11:56.338Z,1582668716.338 [UniversalFixResidualReporter] Loaded 2020-02-25T22:11:56.338Z,1582668716.338 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-02-25T22:11:56.339Z,1582668716.339 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-02-25T22:11:56.339Z,1582668716.339 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-02-25T22:11:56.503Z,1582668716.503 [SBIT](DEBUG): Construct Startup Built In Test. 2020-02-25T22:11:56.514Z,1582668716.514 [SBIT] Loaded 2020-02-25T22:11:56.514Z,1582668716.514 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-02-25T22:11:56.515Z,1582668716.515 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-02-25T22:11:56.527Z,1582668716.527 [IBIT] Loaded 2020-02-25T22:11:56.528Z,1582668716.528 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-02-25T22:11:56.531Z,1582668716.531 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-02-25T22:11:56.665Z,1582668716.665 [CBIT] Loaded 2020-02-25T22:11:56.666Z,1582668716.666 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-02-25T22:11:56.666Z,1582668716.666 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-02-25T22:11:56.667Z,1582668716.667 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-02-25T22:11:56.794Z,1582668716.794 [BuoyancyServo] Loaded 2020-02-25T22:11:56.794Z,1582668716.794 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-02-25T22:11:56.806Z,1582668716.806 [ElevatorServo] Loaded 2020-02-25T22:11:56.806Z,1582668716.806 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-02-25T22:11:56.816Z,1582668716.816 [MassServo] Loaded 2020-02-25T22:11:56.817Z,1582668716.817 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-02-25T22:11:56.828Z,1582668716.828 [RudderServo] Loaded 2020-02-25T22:11:56.828Z,1582668716.828 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-02-25T22:11:56.838Z,1582668716.838 [ThrusterServo] Loaded 2020-02-25T22:11:56.838Z,1582668716.838 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-02-25T22:11:56.839Z,1582668716.839 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-02-25T22:11:56.840Z,1582668716.840 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-02-25T22:11:56.864Z,1582668716.864 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-02-25T22:11:56.864Z,1582668716.864 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-02-25T22:11:57.157Z,1582668717.157 [BackSeatDriver] Loaded 2020-02-25T22:11:57.158Z,1582668717.158 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-02-25T22:11:57.159Z,1582668717.159 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40AAA4E0 2020-02-25T22:11:57.159Z,1582668717.159 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 890 2020-02-25T22:11:57.168Z,1582668717.168 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-02-25T22:11:57.174Z,1582668717.174 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-02-25T22:11:57.174Z,1582668717.174 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-02-25T22:11:57.179Z,1582668717.179 [CTD_Seabird](INFO): created writer for : depth 2020-02-25T22:11:57.179Z,1582668717.179 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-02-25T22:11:57.184Z,1582668717.184 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-02-25T22:11:57.185Z,1582668717.185 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-02-25T22:11:57.190Z,1582668717.190 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-02-25T22:11:57.191Z,1582668717.191 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-02-25T22:11:57.196Z,1582668717.196 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-02-25T22:11:57.196Z,1582668717.196 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-02-25T22:11:57.202Z,1582668717.202 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-02-25T22:11:57.202Z,1582668717.202 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-02-25T22:11:57.207Z,1582668717.207 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-02-25T22:11:57.233Z,1582668717.233 [CTD_Seabird] Loaded 2020-02-25T22:11:57.234Z,1582668717.234 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-02-25T22:11:57.235Z,1582668717.235 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADA4E0 2020-02-25T22:11:57.235Z,1582668717.235 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 891 2020-02-25T22:11:57.241Z,1582668717.241 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-02-25T22:11:57.242Z,1582668717.242 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-02-25T22:11:57.246Z,1582668717.246 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-02-25T22:11:57.246Z,1582668717.246 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-02-25T22:11:57.250Z,1582668717.250 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-02-25T22:11:57.250Z,1582668717.250 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-02-25T22:11:57.255Z,1582668717.255 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-02-25T22:11:57.255Z,1582668717.255 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-02-25T22:11:57.259Z,1582668717.259 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-02-25T22:11:57.259Z,1582668717.259 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-02-25T22:11:57.263Z,1582668717.263 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-02-25T22:11:57.264Z,1582668717.264 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-02-25T22:11:57.268Z,1582668717.268 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-02-25T22:11:57.268Z,1582668717.268 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-02-25T22:11:57.272Z,1582668717.272 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-02-25T22:11:57.276Z,1582668717.276 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-02-25T22:11:57.277Z,1582668717.277 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-02-25T22:11:57.277Z,1582668717.277 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-02-25T22:11:57.281Z,1582668717.281 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-02-25T22:11:57.281Z,1582668717.281 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-02-25T22:11:57.285Z,1582668717.285 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-02-25T22:11:57.286Z,1582668717.286 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-02-25T22:11:57.290Z,1582668717.290 [WetLabsBB2FL] Loaded 2020-02-25T22:11:57.290Z,1582668717.290 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-02-25T22:11:57.291Z,1582668717.291 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0A4E0 2020-02-25T22:11:57.291Z,1582668717.291 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 892 2020-02-25T22:11:57.519Z,1582668717.519 [WetLabsUBAT..SerialDriver](INFO): Created logger 2020-02-25T22:11:57.520Z,1582668717.520 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2020-02-25T22:11:57.520Z,1582668717.520 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2020-02-25T22:11:57.525Z,1582668717.525 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2020-02-25T22:11:57.525Z,1582668717.525 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2020-02-25T22:11:57.525Z,1582668717.525 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2020-02-25T22:11:57.529Z,1582668717.529 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2020-02-25T22:11:57.530Z,1582668717.530 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2020-02-25T22:11:57.534Z,1582668717.534 [WetLabsUBAT] Loaded 2020-02-25T22:11:57.534Z,1582668717.534 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2020-02-25T22:11:57.535Z,1582668717.535 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B3A4E0 2020-02-25T22:11:57.535Z,1582668717.535 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 893 2020-02-25T22:11:57.536Z,1582668717.536 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-02-25T22:11:57.539Z,1582668717.539 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-02-25T22:11:57.540Z,1582668717.540 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-02-25T22:11:57.547Z,1582668717.547 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-02-25T22:11:57.548Z,1582668717.548 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6A4E0 2020-02-25T22:11:57.548Z,1582668717.548 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 894 2020-02-25T22:11:57.552Z,1582668717.552 [Supervisor](INFO): Main Thread ID is 802 2020-02-25T22:11:57.553Z,1582668717.553 [Supervisor](DEBUG): Running supervisor. 2020-02-25T22:11:57.553Z,1582668717.553 [CommandLine ThreadHandler](INFO): Handler Thread ID is 895 2020-02-25T22:11:57.556Z,1582668717.556 [controlThread ThreadHandler](INFO): Handler Thread ID is 896 2020-02-25T22:11:57.556Z,1582668717.556 [controlThread](DEBUG): Initializing ControlThread 2020-02-25T22:11:57.557Z,1582668717.557 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-02-25T22:11:57.559Z,1582668717.559 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-02-25T22:11:57.559Z,1582668717.559 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-02-25T22:11:57.560Z,1582668717.560 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-02-25T22:11:57.560Z,1582668717.560 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-02-25T22:11:57.561Z,1582668717.561 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-02-25T22:11:57.561Z,1582668717.561 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-02-25T22:11:57.561Z,1582668717.561 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-02-25T22:11:57.562Z,1582668717.562 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-02-25T22:11:57.562Z,1582668717.562 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-02-25T22:11:57.569Z,1582668717.569 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-02-25T22:11:57.570Z,1582668717.570 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-02-25T22:11:57.570Z,1582668717.570 [SBIT](INFO): Initialize SBIT Component. 2020-02-25T22:11:57.571Z,1582668717.571 [SBIT](IMPORTANT): git: 2019-12-03-78-gb92fbb9 2020-02-25T22:11:57.571Z,1582668717.571 [SBIT](INFO): git hash: b92fbb99b4c8d1793352820b784fbd968d0634e4 2020-02-25T22:11:57.571Z,1582668717.571 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-02-25T22:11:57.571Z,1582668717.571 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2020-02-25T22:11:57.572Z,1582668717.572 [SBIT](INFO): Beginning SBIT in 45.000000 seconds. 2020-02-25T22:11:57.573Z,1582668717.573 [IBIT](INFO): Initialize IBIT Component. 2020-02-25T22:11:57.573Z,1582668717.573 [CBIT](DEBUG): Initialize CBIT Component. 2020-02-25T22:11:57.574Z,1582668717.574 [logger ThreadHandler](INFO): Handler Thread ID is 897 2020-02-25T22:11:57.586Z,1582668717.586 [CBIT](DEBUG): Initialized mux pins. 2020-02-25T22:11:57.587Z,1582668717.587 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2020-02-25T22:11:57.587Z,1582668717.587 [CBIT](DEBUG): Initializing the watchdog timer. 2020-02-25T22:11:57.595Z,1582668717.595 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 898 2020-02-25T22:11:57.596Z,1582668717.596 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-02-25T22:11:57.607Z,1582668717.607 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 899 2020-02-25T22:11:57.610Z,1582668717.610 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-02-25T22:11:57.611Z,1582668717.611 [CBIT](DEBUG): Initializing heartbeat. 2020-02-25T22:11:57.619Z,1582668717.619 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 900 2020-02-25T22:11:57.635Z,1582668717.635 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 901 2020-02-25T22:11:57.635Z,1582668717.635 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-02-25T22:11:57.640Z,1582668717.640 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 903 2020-02-25T22:11:57.641Z,1582668717.641 [WetLabsBB2FL](INFO): Powering down 2020-02-25T22:11:57.671Z,1582668717.671 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 904 2020-02-25T22:11:57.682Z,1582668717.682 [CBIT](DEBUG): Deactivating GF circuits. 2020-02-25T22:11:57.683Z,1582668717.683 [CBIT](DEBUG): Deactivating emergency mode. 2020-02-25T22:11:57.683Z,1582668717.683 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 905 2020-02-25T22:11:57.693Z,1582668717.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-02-25T22:11:57.693Z,1582668717.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-02-25T22:11:57.693Z,1582668717.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-02-25T22:11:57.693Z,1582668717.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-02-25T22:11:57.693Z,1582668717.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-02-25T22:11:57.694Z,1582668717.694 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-02-25T22:11:57.694Z,1582668717.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-02-25T22:11:57.694Z,1582668717.694 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-02-25T22:11:57.694Z,1582668717.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-02-25T22:11:57.694Z,1582668717.694 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-02-25T22:11:57.695Z,1582668717.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-02-25T22:11:57.695Z,1582668717.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-02-25T22:11:57.695Z,1582668717.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-02-25T22:11:57.695Z,1582668717.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-02-25T22:11:57.695Z,1582668717.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-02-25T22:11:57.695Z,1582668717.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-02-25T22:11:57.718Z,1582668717.718 [CBIT](DEBUG): Backplane powered. 2020-02-25T22:11:57.720Z,1582668717.720 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-02-25T22:11:57.745Z,1582668717.745 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-02-25T22:11:57.763Z,1582668717.763 [MissionManager](DEBUG): 2020-02-25T22:11:57.764Z,1582668717.764 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-02-25T22:11:57.847Z,1582668717.847 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-02-25T22:11:57.848Z,1582668717.848 [Default:A.Wait](DEBUG): Construct Wait. 2020-02-25T22:11:57.850Z,1582668717.850 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-02-25T22:11:57.873Z,1582668717.873 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-02-25T22:11:57.908Z,1582668717.908 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-02-25T22:11:57.913Z,1582668717.913 [Default:E.Execute](DEBUG): Construct Execute. 2020-02-25T22:11:57.932Z,1582668717.932 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2020-02-25T22:11:57.937Z,1582668717.937 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-02-25T22:11:57.941Z,1582668717.941 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-02-25T22:11:58.011Z,1582668718.011 [Radio_Surface](INFO): Powering up 2020-02-25T22:11:58.048Z,1582668718.048 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-02-25T22:11:58.066Z,1582668718.066 [RDI_Pathfinder](INFO): Start 2020-02-25T22:11:58.078Z,1582668718.078 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-02-25T22:11:58.078Z,1582668718.078 [DAT](INFO): Powering up 2020-02-25T22:11:58.078Z,1582668718.078 [DAT](DEBUG): Initializing DAT. 2020-02-25T22:11:58.143Z,1582668718.143 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-02-25T22:11:58.155Z,1582668718.155 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-02-25T22:11:58.235Z,1582668718.235 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-02-25T22:11:58.243Z,1582668718.243 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-02-25T22:11:58.244Z,1582668718.244 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-02-25T22:11:58.255Z,1582668718.255 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-02-25T22:11:58.256Z,1582668718.256 [MassServo](DEBUG): Initializing EZServoServo. 2020-02-25T22:11:58.267Z,1582668718.267 [MassServo](DEBUG): Initializing MassServo. 2020-02-25T22:11:58.268Z,1582668718.268 [RudderServo](DEBUG): Initializing EZServoServo. 2020-02-25T22:11:58.279Z,1582668718.279 [RudderServo](DEBUG): Initializing RudderServo. 2020-02-25T22:11:58.280Z,1582668718.280 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-02-25T22:11:58.291Z,1582668718.291 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-02-25T22:11:58.375Z,1582668718.375 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-02-25T22:11:58.375Z,1582668718.375 [DropWeight] Hardware Fault, FailCount= 1 2020-02-25T22:11:58.375Z,1582668718.375 [DropWeight](ERROR): Hardware Fault 2020-02-25T22:11:58.407Z,1582668718.407 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:11:58.415Z,1582668718.415 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-02-25T22:11:58.444Z,1582668718.444 [CommandLine](FAULT): Scheduling is paused 2020-02-25T22:11:58.445Z,1582668718.445 [CBIT](INFO): Critical error at 20200225T221158 2020-02-25T22:11:58.445Z,1582668718.445 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-02-25T22:11:58.448Z,1582668718.448 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-02-25T22:11:58.448Z,1582668718.448 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-02-25T22:11:58.782Z,1582668718.782 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:11:59.122Z,1582668719.122 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-02-25T22:11:59.123Z,1582668719.123 [RudderServo](FAULT): Rudder failed to initialize 2020-02-25T22:11:59.123Z,1582668719.123 [RudderServo] Communications Fault, FailCount= 1 2020-02-25T22:11:59.123Z,1582668719.123 [RudderServo](ERROR): Communications Fault 2020-02-25T22:11:59.280Z,1582668719.280 [CBIT](INFO): Critical error at 20200225T221158 2020-02-25T22:11:59.283Z,1582668719.283 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-02-25T22:11:59.311Z,1582668719.311 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:11:59.444Z,1582668719.444 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-02-25T22:11:59.444Z,1582668719.444 [RudderServo](INFO): Powering down 2020-02-25T22:11:59.709Z,1582668719.709 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:00.114Z,1582668720.114 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:00.126Z,1582668720.126 [RudderServo](DEBUG): Initializing EZServoServo. 2020-02-25T22:12:00.243Z,1582668720.243 [RudderServo](DEBUG): Initializing RudderServo. 2020-02-25T22:12:00.248Z,1582668720.248 [CBIT](INFO): Clearing failed state for component RudderServo 2020-02-25T22:12:00.248Z,1582668720.248 [RudderServo] No Fault, FailCount= 1 2020-02-25T22:12:00.518Z,1582668720.518 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:00.922Z,1582668720.922 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:01.393Z,1582668721.393 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:01.754Z,1582668721.754 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:02.169Z,1582668722.169 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:02.626Z,1582668722.626 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:02.978Z,1582668722.978 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:03.382Z,1582668723.382 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:03.786Z,1582668723.786 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:04.190Z,1582668724.190 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:04.594Z,1582668724.594 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:04.667Z,1582668724.667 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2020-02-25T22:12:04.998Z,1582668724.998 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:05.403Z,1582668725.403 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:05.806Z,1582668725.806 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:06.199Z,1582668726.199 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:06.606Z,1582668726.606 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:07.001Z,1582668727.001 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:07.403Z,1582668727.403 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:07.794Z,1582668727.794 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:08.198Z,1582668728.198 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:08.612Z,1582668728.612 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:08.613Z,1582668728.613 [DAT](DEBUG): 2020-02-25T22:12:09.006Z,1582668729.006 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:09.007Z,1582668729.007 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-02-25T22:12:09.409Z,1582668729.409 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:09.821Z,1582668729.821 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:09.822Z,1582668729.822 [DAT](DEBUG): MF Frequency Band 2020-02-25T22:12:10.218Z,1582668730.218 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:10.220Z,1582668730.220 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21 2020-02-25T22:12:10.220Z,1582668730.220 [DAT](DEBUG): Feb 25 2020 22:00:38 2020-02-25T22:12:10.623Z,1582668730.623 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:10.625Z,1582668730.625 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:12:11.026Z,1582668731.026 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:11.027Z,1582668731.027 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:11.028Z,1582668731.028 [DAT](DEBUG): Features enabled [Bearing] 2020-02-25T22:12:11.029Z,1582668731.029 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-02-25T22:12:11.030Z,1582668731.030 [DAT](INFO): commRate: 800 2020-02-25T22:12:11.445Z,1582668731.445 [DAT](INFO): entering command mode 2020-02-25T22:12:11.835Z,1582668731.835 [DAT](DEBUG): checking for command mode acknowledgment 2020-02-25T22:12:12.239Z,1582668732.239 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:12.240Z,1582668732.240 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:12.240Z,1582668732.240 [DAT](DEBUG): checking for command mode acknowledgment 2020-02-25T22:12:12.241Z,1582668732.241 [DAT](INFO): command mode acknowledged 2020-02-25T22:12:12.241Z,1582668732.241 [DAT](INFO): setting verbose to 3 2020-02-25T22:12:12.651Z,1582668732.651 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-02-25T22:12:12.652Z,1582668732.652 [DAT](INFO): set verbose to 3 2020-02-25T22:12:13.047Z,1582668733.047 [DAT](INFO): setting transmit power to 8 2020-02-25T22:12:13.458Z,1582668733.458 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:13.459Z,1582668733.459 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:13.460Z,1582668733.460 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-02-25T22:12:13.855Z,1582668733.855 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-02-25T22:12:13.856Z,1582668733.856 [DAT](INFO): set transmit power to 8 2020-02-25T22:12:14.258Z,1582668734.258 [DAT](INFO): setting local address to 9 2020-02-25T22:12:14.663Z,1582668734.663 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:14.665Z,1582668734.665 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:14.665Z,1582668734.665 [DAT](DEBUG): checking for local address setting acknowledgment 2020-02-25T22:12:15.067Z,1582668735.067 [DAT](DEBUG): checking for local address setting acknowledgment 2020-02-25T22:12:15.068Z,1582668735.068 [DAT](INFO): set local address to 9 2020-02-25T22:12:15.882Z,1582668735.882 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:15.883Z,1582668735.883 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:16.279Z,1582668736.279 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:16.281Z,1582668736.281 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:17.086Z,1582668737.086 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:17.087Z,1582668737.087 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:18.298Z,1582668738.298 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:18.299Z,1582668738.299 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:19.106Z,1582668739.106 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:19.108Z,1582668739.108 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:20.322Z,1582668740.322 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:12:20.322Z,1582668740.322 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-25T22:12:20.322Z,1582668740.322 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:12:20.351Z,1582668740.351 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:12:20.722Z,1582668740.722 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:12:20.788Z,1582668740.788 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:12:21.126Z,1582668741.126 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:12:21.530Z,1582668741.530 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:12:21.559Z,1582668741.559 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:12:21.559Z,1582668741.559 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-25T22:12:21.942Z,1582668741.942 [RDI_Pathfinder](INFO): Start 2020-02-25T22:12:22.334Z,1582668742.334 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:22.739Z,1582668742.739 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:23.146Z,1582668743.146 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:23.554Z,1582668743.554 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:23.964Z,1582668743.964 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:24.348Z,1582668744.348 [NAL9602](INFO): Powering up NAL9602 2020-02-25T22:12:24.365Z,1582668744.365 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:24.762Z,1582668744.762 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:25.169Z,1582668745.169 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:25.576Z,1582668745.576 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:25.970Z,1582668745.970 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:26.378Z,1582668746.378 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:26.786Z,1582668746.786 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:27.188Z,1582668747.188 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:27.586Z,1582668747.586 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:27.994Z,1582668747.994 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:28.401Z,1582668748.401 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:28.804Z,1582668748.804 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:29.206Z,1582668749.206 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:29.610Z,1582668749.610 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:30.014Z,1582668750.014 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:30.430Z,1582668750.430 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:30.826Z,1582668750.826 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:31.226Z,1582668751.226 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:31.630Z,1582668751.630 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:32.035Z,1582668752.035 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:32.434Z,1582668752.434 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:32.842Z,1582668752.842 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:33.248Z,1582668753.248 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:33.652Z,1582668753.652 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:34.054Z,1582668754.054 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:34.455Z,1582668754.455 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:34.458Z,1582668754.458 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:12:35.265Z,1582668755.265 [NAL9602](INFO): NAL9602 initialized 2020-02-25T22:12:35.682Z,1582668755.682 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:35.683Z,1582668755.683 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:36.893Z,1582668756.893 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:36.895Z,1582668756.895 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:38.105Z,1582668758.105 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:38.107Z,1582668758.107 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:38.930Z,1582668758.930 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:38.931Z,1582668758.931 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:39.347Z,1582668759.347 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:39.348Z,1582668759.348 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:40.527Z,1582668760.527 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:40.528Z,1582668760.528 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:41.339Z,1582668761.339 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:41.341Z,1582668761.341 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:42.546Z,1582668762.546 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:42.547Z,1582668762.547 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:43.357Z,1582668763.357 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:43.358Z,1582668763.358 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:12:43.398Z,1582668763.398 [SBIT](IMPORTANT): Beginning Startup BIT 2020-02-25T22:12:43.410Z,1582668763.410 [CBIT](IMPORTANT): Beginning ground fault scan 2020-02-25T22:12:44.171Z,1582668764.171 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:12:44.171Z,1582668764.171 [RDI_Pathfinder] Communications Fault, FailCount= 2 2020-02-25T22:12:44.171Z,1582668764.171 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:12:44.231Z,1582668764.231 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:12:44.569Z,1582668764.569 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:12:44.635Z,1582668764.635 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:12:44.962Z,1582668764.962 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:12:45.375Z,1582668765.375 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:12:45.831Z,1582668765.831 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:12:45.831Z,1582668765.831 [RDI_Pathfinder] No Fault, FailCount= 2 2020-02-25T22:12:45.863Z,1582668765.863 [RDI_Pathfinder](INFO): Start 2020-02-25T22:12:46.287Z,1582668766.287 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:46.663Z,1582668766.663 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:47.083Z,1582668767.083 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:47.481Z,1582668767.481 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:47.878Z,1582668767.878 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:48.282Z,1582668768.282 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:48.688Z,1582668768.688 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:49.097Z,1582668769.097 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:49.491Z,1582668769.491 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:49.898Z,1582668769.898 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:50.303Z,1582668770.303 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:50.712Z,1582668770.712 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:51.107Z,1582668771.107 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:51.510Z,1582668771.510 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:51.926Z,1582668771.926 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:52.329Z,1582668772.329 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:52.736Z,1582668772.736 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:53.134Z,1582668773.134 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:53.539Z,1582668773.539 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:53.944Z,1582668773.944 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:54.344Z,1582668774.344 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:54.419Z,1582668774.419 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.017708 CHAN A1 (24V): 0.035492 CHAN A2 (12V): -0.008233 CHAN A3 (5V): -0.002436 CHAN B0 (3.3V): -0.000428 CHAN B1 (3.15aV): -0.000202 CHAN B2 (3.15bV): -0.000222 CHAN B3 (GND): 0.002194 OPEN: 0.005318 Full Scale Calc: 4.765 mA, -1.589 mA 2020-02-25T22:12:54.758Z,1582668774.758 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:55.158Z,1582668775.158 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:55.564Z,1582668775.564 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:55.954Z,1582668775.954 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:56.358Z,1582668776.358 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:56.774Z,1582668776.774 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:57.183Z,1582668777.183 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:57.571Z,1582668777.571 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:57.974Z,1582668777.974 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:12:58.388Z,1582668778.388 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:58.391Z,1582668778.391 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:12:59.335Z,1582668779.335 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:12:59.336Z,1582668779.336 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:13:00.516Z,1582668780.516 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:13:00.517Z,1582668780.517 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:13:01.740Z,1582668781.740 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:13:01.741Z,1582668781.741 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:13:02.517Z,1582668782.517 [NAL9602](INFO): SBD MO Status=2, MOMSN=6582, MT Status=2, MTMSN=0 2020-02-25T22:13:02.517Z,1582668782.517 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-25T22:13:02.956Z,1582668782.956 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:13:02.957Z,1582668782.957 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:13:03.339Z,1582668783.339 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:13:03.340Z,1582668783.340 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:13:04.550Z,1582668784.550 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:13:04.551Z,1582668784.551 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:13:05.364Z,1582668785.364 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:13:05.365Z,1582668785.365 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:13:06.575Z,1582668786.575 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:13:06.576Z,1582668786.576 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:13:07.395Z,1582668787.395 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:13:07.396Z,1582668787.396 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:13:08.191Z,1582668788.191 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:13:08.191Z,1582668788.191 [RDI_Pathfinder] Communications Fault, FailCount= 3 2020-02-25T22:13:08.191Z,1582668788.191 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:13:08.272Z,1582668788.272 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:13:08.273Z,1582668788.273 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:13:08.596Z,1582668788.596 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:13:08.667Z,1582668788.667 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:13:08.738Z,1582668788.738 [CBIT](INFO): Critical error at 20200225T221308 2020-02-25T22:13:09.002Z,1582668789.002 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:13:09.398Z,1582668789.398 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:13:37.044Z,1582668817.044 [SBIT](IMPORTANT): SBIT PASSED 2020-02-25T22:13:37.045Z,1582668817.045 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2020-02-25T22:13:37.422Z,1582668817.422 [MissionManager](IMPORTANT): Started mission Startup 2020-02-25T22:13:37.423Z,1582668817.423 [Startup] Running Loop=1 2020-02-25T22:13:37.423Z,1582668817.423 [Startup](DEBUG): Aggregate::initialize Startup 2020-02-25T22:13:37.423Z,1582668817.423 [Startup:A.GoToSurface] Running Loop=1 2020-02-25T22:13:37.423Z,1582668817.423 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-02-25T22:13:37.424Z,1582668817.424 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-02-25T22:13:37.424Z,1582668817.424 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-02-25T22:13:37.424Z,1582668817.424 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-02-25T22:13:37.425Z,1582668817.425 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-02-25T22:13:37.425Z,1582668817.425 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-02-25T22:13:37.426Z,1582668817.426 [Startup:StartupSatComms] Running Loop=1 2020-02-25T22:13:37.427Z,1582668817.427 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-02-25T22:13:37.427Z,1582668817.427 [Startup:StartupSatComms:A] Running Loop=1 2020-02-25T22:13:37.825Z,1582668817.825 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-02-25T22:14:37.613Z,1582668877.613 [Startup:StartupSatComms:A](INFO): Timed out from 2020-02-25T22:13:37.4Z 2020-02-25T22:14:37.613Z,1582668877.613 [Startup:StartupSatComms:A] Stopped 2020-02-25T22:14:37.613Z,1582668877.613 [Startup:StartupSatComms:B] Running Loop=1 2020-02-25T22:14:38.036Z,1582668878.036 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-02-25T22:14:57.834Z,1582668897.834 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-02-25T22:14:57.834Z,1582668897.834 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-25T22:14:57.844Z,1582668897.844 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-25T22:14:58.252Z,1582668898.252 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-25T22:14:58.252Z,1582668898.252 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-02-25T22:15:25.757Z,1582668925.757 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.013231 2020-02-25T22:15:33.014Z,1582668933.014 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20200221T011149/Courier0007.lzma 2020-02-25T22:15:35.023Z,1582668935.023 [DataOverHttps](INFO): Moved sent file to Logs/20200221T011149/Courier0007.lzma.bak 2020-02-25T22:15:35.023Z,1582668935.023 [DataOverHttps](INFO): SBD MOMSN=12267280 2020-02-25T22:15:37.848Z,1582668937.848 [Startup:StartupSatComms:B](INFO): Timed out from 2020-02-25T22:14:37.6Z 2020-02-25T22:15:37.848Z,1582668937.848 [Startup:StartupSatComms:B] Stopped 2020-02-25T22:15:37.848Z,1582668937.848 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-02-25T22:15:37.848Z,1582668937.848 [Startup:StartupSatComms] Stopped 2020-02-25T22:15:37.848Z,1582668937.848 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-02-25T22:15:37.849Z,1582668937.849 [Startup](INFO): Completed Startup 2020-02-25T22:15:37.849Z,1582668937.849 [MissionManager](INFO): Startup is completed. 2020-02-25T22:15:37.849Z,1582668937.849 [MissionManager](INFO): Uninitializing Mission Startup 2020-02-25T22:15:37.849Z,1582668937.849 [Startup] Stopped 2020-02-25T22:15:37.849Z,1582668937.849 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-02-25T22:15:37.850Z,1582668937.850 [Startup:A.GoToSurface] Stopped 2020-02-25T22:15:37.850Z,1582668937.850 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-02-25T22:15:38.241Z,1582668938.241 [MissionManager](IMPORTANT): Started mission Default 2020-02-25T22:15:38.241Z,1582668938.241 [Default] Running Loop=1 2020-02-25T22:15:38.241Z,1582668938.241 [Default](DEBUG): Aggregate::initialize Default 2020-02-25T22:15:38.241Z,1582668938.241 [Default:B.GoToSurface] Running Loop=1 2020-02-25T22:15:38.241Z,1582668938.241 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-02-25T22:15:38.242Z,1582668938.242 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-02-25T22:15:38.242Z,1582668938.242 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-02-25T22:15:38.247Z,1582668938.247 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-02-25T22:15:38.247Z,1582668938.247 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-02-25T22:15:38.247Z,1582668938.247 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-02-25T22:15:38.247Z,1582668938.247 [Default:A.Wait] Running Loop=1 2020-02-25T22:15:38.248Z,1582668938.248 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-02-25T22:15:51.564Z,1582668951.564 [Default:A.Wait](INFO): Done Waiting. 2020-02-25T22:15:51.565Z,1582668951.565 [Default:A.Wait] Stopped 2020-02-25T22:15:51.565Z,1582668951.565 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-02-25T22:15:51.956Z,1582668951.956 [Default:CheckIn] Running Loop=1 2020-02-25T22:15:51.956Z,1582668951.956 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-25T22:15:51.956Z,1582668951.956 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-25T22:15:52.372Z,1582668952.372 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-02-25T22:15:53.531Z,1582668953.531 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20200225T220503/Courier0000.lzma 2020-02-25T22:15:56.251Z,1582668956.251 [DataOverHttps](INFO): Moved sent file to Logs/20200225T220503/Courier0000.lzma.bak 2020-02-25T22:15:56.251Z,1582668956.251 [DataOverHttps](INFO): SBD MOMSN=12267282 2020-02-25T22:16:08.553Z,1582668968.553 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:16:08.554Z,1582668968.554 [RDI_Pathfinder] No Fault, FailCount= 3 2020-02-25T22:16:08.919Z,1582668968.919 [RDI_Pathfinder](INFO): Start 2020-02-25T22:16:09.329Z,1582668969.329 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:09.726Z,1582668969.726 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:10.131Z,1582668970.131 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:10.542Z,1582668970.542 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:10.948Z,1582668970.948 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:11.359Z,1582668971.359 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:11.749Z,1582668971.749 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:12.159Z,1582668972.159 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:12.565Z,1582668972.565 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:12.958Z,1582668972.958 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:13.362Z,1582668973.362 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:13.770Z,1582668973.770 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:14.173Z,1582668974.173 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:14.574Z,1582668974.574 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:14.978Z,1582668974.978 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:15.382Z,1582668975.382 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:15.795Z,1582668975.795 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:16.173Z,1582668976.173 [NAL9602](INFO): SBD MO Status=2, MOMSN=6582, MT Status=2, MTMSN=0 2020-02-25T22:16:16.173Z,1582668976.173 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-25T22:16:16.188Z,1582668976.188 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:16.605Z,1582668976.605 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:17.003Z,1582668977.003 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:17.409Z,1582668977.409 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:17.806Z,1582668977.806 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:18.210Z,1582668978.210 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:18.618Z,1582668978.618 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:19.020Z,1582668979.020 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:19.455Z,1582668979.455 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:19.826Z,1582668979.826 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:20.235Z,1582668980.235 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:20.653Z,1582668980.653 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:21.055Z,1582668981.055 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:21.457Z,1582668981.457 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2020-02-25T22:16:21.457Z,1582668981.457 [RDI_Pathfinder] Data Fault, FailCount= 1 2020-02-25T22:16:21.457Z,1582668981.457 [RDI_Pathfinder](ERROR): Data Fault 2020-02-25T22:16:21.495Z,1582668981.495 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2020-02-25T22:16:21.851Z,1582668981.851 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:16:21.923Z,1582668981.923 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:16:22.257Z,1582668982.257 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:16:22.681Z,1582668982.681 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:16:22.716Z,1582668982.716 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:16:22.716Z,1582668982.716 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-25T22:16:23.058Z,1582668983.058 [RDI_Pathfinder](INFO): Start 2020-02-25T22:16:23.464Z,1582668983.464 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:23.869Z,1582668983.869 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:24.266Z,1582668984.266 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:24.682Z,1582668984.682 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:25.096Z,1582668985.096 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:25.495Z,1582668985.495 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:25.886Z,1582668985.886 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:26.290Z,1582668986.290 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:26.700Z,1582668986.700 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:27.105Z,1582668987.105 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:27.498Z,1582668987.498 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:27.902Z,1582668987.902 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:28.324Z,1582668988.324 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:28.731Z,1582668988.731 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:29.131Z,1582668989.131 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:29.530Z,1582668989.530 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:29.931Z,1582668989.931 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:30.337Z,1582668990.337 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:30.730Z,1582668990.730 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:31.138Z,1582668991.138 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:31.546Z,1582668991.546 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:31.954Z,1582668991.954 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:32.346Z,1582668992.346 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:32.765Z,1582668992.765 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:33.177Z,1582668993.177 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:33.575Z,1582668993.575 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:33.984Z,1582668993.984 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:34.372Z,1582668994.372 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:34.776Z,1582668994.776 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:35.181Z,1582668995.181 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:35.579Z,1582668995.579 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:35.581Z,1582668995.581 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:16:36.801Z,1582668996.801 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:36.803Z,1582668996.803 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:16:37.642Z,1582668997.642 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:37.643Z,1582668997.643 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:16:39.222Z,1582668999.222 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:39.224Z,1582668999.224 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:16:40.049Z,1582669000.049 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:40.050Z,1582669000.050 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:16:40.460Z,1582669000.460 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:40.462Z,1582669000.462 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:16:41.655Z,1582669001.655 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:41.657Z,1582669001.657 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:16:42.450Z,1582669002.450 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:42.452Z,1582669002.452 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:16:43.662Z,1582669003.662 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:43.663Z,1582669003.663 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:16:44.475Z,1582669004.475 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:44.476Z,1582669004.476 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:16:45.278Z,1582669005.278 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:16:45.278Z,1582669005.278 [RDI_Pathfinder] Communications Fault, FailCount= 2 2020-02-25T22:16:45.278Z,1582669005.278 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:16:45.337Z,1582669005.337 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:16:45.678Z,1582669005.678 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:16:45.747Z,1582669005.747 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:16:46.093Z,1582669006.093 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:16:46.504Z,1582669006.504 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:16:46.519Z,1582669006.519 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:16:46.519Z,1582669006.519 [RDI_Pathfinder] No Fault, FailCount= 2 2020-02-25T22:16:46.902Z,1582669006.902 [RDI_Pathfinder](INFO): Start 2020-02-25T22:16:47.298Z,1582669007.298 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:47.703Z,1582669007.703 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:48.107Z,1582669008.107 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:48.506Z,1582669008.506 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:48.911Z,1582669008.911 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:49.319Z,1582669009.319 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:49.735Z,1582669009.735 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:50.148Z,1582669010.148 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:50.526Z,1582669010.526 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:50.935Z,1582669010.935 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:51.342Z,1582669011.342 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:51.742Z,1582669011.742 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:52.150Z,1582669012.150 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:52.554Z,1582669012.554 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:52.952Z,1582669012.952 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:53.354Z,1582669013.354 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:53.759Z,1582669013.759 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:54.166Z,1582669014.166 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:54.573Z,1582669014.573 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:54.981Z,1582669014.981 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:55.374Z,1582669015.374 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:55.782Z,1582669015.782 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:56.190Z,1582669016.190 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:56.586Z,1582669016.586 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:56.994Z,1582669016.994 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:57.398Z,1582669017.398 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:57.801Z,1582669017.801 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:58.228Z,1582669018.228 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:58.628Z,1582669018.628 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:59.021Z,1582669019.021 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:16:59.420Z,1582669019.420 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:16:59.423Z,1582669019.423 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:17:00.627Z,1582669020.627 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:17:00.628Z,1582669020.628 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:17:01.842Z,1582669021.842 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:17:01.844Z,1582669021.844 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:17:03.081Z,1582669023.081 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:17:03.082Z,1582669023.082 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:17:03.881Z,1582669023.881 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:17:03.892Z,1582669023.892 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:17:04.293Z,1582669024.293 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:17:04.297Z,1582669024.297 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:17:05.478Z,1582669025.478 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:17:05.479Z,1582669025.479 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:17:06.301Z,1582669026.301 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:17:06.303Z,1582669026.303 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:17:07.517Z,1582669027.517 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:17:07.518Z,1582669027.518 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:17:08.324Z,1582669028.324 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:17:08.326Z,1582669028.326 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:17:09.122Z,1582669029.122 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:17:09.122Z,1582669029.122 [RDI_Pathfinder] Communications Fault, FailCount= 3 2020-02-25T22:17:09.122Z,1582669029.122 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:17:09.138Z,1582669029.138 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:17:09.139Z,1582669029.139 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:17:09.522Z,1582669029.522 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:17:09.591Z,1582669029.591 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:17:09.611Z,1582669029.611 [CBIT](INFO): Critical error at 20200225T221709 2020-02-25T22:17:09.919Z,1582669029.919 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:17:10.322Z,1582669030.322 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:17:38.200Z,1582669058.200 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-25T22:17:43.850Z,1582669063.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220631.00,A,3648.14853,N,12147.27033,W,0.078,0.00,250220,,,A*76 2020-02-25T22:17:43.853Z,1582669063.853 [NAL9602](INFO): GPS fix at 20200225T220631: (36.802475, -121.787839) 2020-02-25T22:17:43.876Z,1582669063.876 [Default:CheckIn:Read_GPS] Stopped 2020-02-25T22:17:43.876Z,1582669063.876 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-25T22:17:44.297Z,1582669064.297 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-02-25T22:17:53.964Z,1582669073.964 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20200225T220503/Courier0004.lzma 2020-02-25T22:17:55.968Z,1582669075.968 [DataOverHttps](INFO): Moved sent file to Logs/20200225T220503/Courier0004.lzma.bak 2020-02-25T22:17:55.968Z,1582669075.968 [DataOverHttps](INFO): SBD MOMSN=12267288 2020-02-25T22:18:14.554Z,1582669094.554 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20200225T221145/Courier0000.lzma 2020-02-25T22:18:16.564Z,1582669096.564 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Courier0000.lzma.bak 2020-02-25T22:18:16.564Z,1582669096.564 [DataOverHttps](INFO): SBD MOMSN=12267290 2020-02-25T22:18:16.588Z,1582669096.588 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-25T22:18:32.754Z,1582669112.754 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-02-25T22:18:32.757Z,1582669112.757 [BPC1](INFO): Received data from all battery sticks. 2020-02-25T22:18:33.546Z,1582669113.546 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20200225T221145/Courier0004.lzma 2020-02-25T22:18:36.404Z,1582669116.404 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Courier0004.lzma.bak 2020-02-25T22:18:36.404Z,1582669116.404 [DataOverHttps](INFO): SBD MOMSN=12267296 2020-02-25T22:18:52.574Z,1582669132.574 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20200221T011149/Express0008.lzma 2020-02-25T22:18:54.580Z,1582669134.580 [DataOverHttps](INFO): Moved sent file to Logs/20200221T011149/Express0008.lzma.bak 2020-02-25T22:18:54.580Z,1582669134.580 [DataOverHttps](INFO): SBD MOMSN=12267300 2020-02-25T22:19:11.393Z,1582669151.393 [DataOverHttps](INFO): Sending 916 bytes from file Logs/20200225T220503/Express0001.lzma 2020-02-25T22:19:13.412Z,1582669153.412 [DataOverHttps](INFO): Moved sent file to Logs/20200225T220503/Express0001.lzma.bak 2020-02-25T22:19:13.412Z,1582669153.412 [DataOverHttps](INFO): SBD MOMSN=12267302 2020-02-25T22:19:32.450Z,1582669172.450 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20200225T220503/Express0005.lzma 2020-02-25T22:19:34.456Z,1582669174.456 [DataOverHttps](INFO): Moved sent file to Logs/20200225T220503/Express0005.lzma.bak 2020-02-25T22:19:34.456Z,1582669174.456 [DataOverHttps](INFO): SBD MOMSN=12267326 2020-02-25T22:19:51.659Z,1582669191.659 [DataOverHttps](INFO): Sending 723 bytes from file Logs/20200225T221145/Express0001.lzma 2020-02-25T22:19:53.664Z,1582669193.664 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Express0001.lzma.bak 2020-02-25T22:19:53.664Z,1582669193.664 [DataOverHttps](INFO): SBD MOMSN=12267328 2020-02-25T22:20:09.356Z,1582669209.356 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:20:09.356Z,1582669209.356 [RDI_Pathfinder] No Fault, FailCount= 3 2020-02-25T22:20:09.711Z,1582669209.711 [RDI_Pathfinder](INFO): Start 2020-02-25T22:20:10.120Z,1582669210.120 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:10.517Z,1582669210.517 [DataOverHttps](INFO): Sending 354 bytes from file Logs/20200225T221145/Express0005.lzma 2020-02-25T22:20:10.523Z,1582669210.523 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:10.921Z,1582669210.921 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:11.327Z,1582669211.327 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:11.725Z,1582669211.725 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:12.127Z,1582669212.127 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:12.520Z,1582669212.520 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Express0005.lzma.bak 2020-02-25T22:20:12.520Z,1582669212.520 [DataOverHttps](INFO): SBD MOMSN=12267344 2020-02-25T22:20:12.551Z,1582669212.551 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:12.957Z,1582669212.957 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:13.358Z,1582669213.358 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:13.759Z,1582669213.759 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:14.158Z,1582669214.158 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:14.166Z,1582669214.166 [Default:CheckIn:Read_Iridium] Stopped 2020-02-25T22:20:14.166Z,1582669214.166 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-25T22:20:14.166Z,1582669214.166 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-25T22:20:14.567Z,1582669214.567 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:14.958Z,1582669214.958 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:15.361Z,1582669215.361 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:15.773Z,1582669215.773 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:16.170Z,1582669216.170 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:16.573Z,1582669216.573 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:16.977Z,1582669216.977 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:17.389Z,1582669217.389 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:17.785Z,1582669217.785 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:18.190Z,1582669218.190 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:18.602Z,1582669218.602 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:19.004Z,1582669219.004 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:19.399Z,1582669219.399 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:19.810Z,1582669219.810 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:20.222Z,1582669220.222 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:20.625Z,1582669220.625 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:21.021Z,1582669221.021 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:21.426Z,1582669221.426 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:21.825Z,1582669221.825 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:22.252Z,1582669222.252 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2020-02-25T22:20:22.252Z,1582669222.252 [RDI_Pathfinder] Data Fault, FailCount= 1 2020-02-25T22:20:22.252Z,1582669222.252 [RDI_Pathfinder](ERROR): Data Fault 2020-02-25T22:20:22.269Z,1582669222.269 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2020-02-25T22:20:22.637Z,1582669222.637 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:20:22.707Z,1582669222.707 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:20:23.035Z,1582669223.035 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:20:23.442Z,1582669223.442 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:20:23.459Z,1582669223.459 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:20:23.459Z,1582669223.459 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-25T22:20:23.852Z,1582669223.852 [RDI_Pathfinder](INFO): Start 2020-02-25T22:20:24.247Z,1582669224.247 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:24.658Z,1582669224.658 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:25.061Z,1582669225.061 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:25.474Z,1582669225.474 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:25.869Z,1582669225.869 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:26.273Z,1582669226.273 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:26.677Z,1582669226.677 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:27.085Z,1582669227.085 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:27.482Z,1582669227.482 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:27.883Z,1582669227.883 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:28.289Z,1582669228.289 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:28.700Z,1582669228.700 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:29.095Z,1582669229.095 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:29.506Z,1582669229.506 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:29.909Z,1582669229.909 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:30.317Z,1582669230.317 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:30.716Z,1582669230.716 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:31.120Z,1582669231.120 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:31.527Z,1582669231.527 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:31.934Z,1582669231.934 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:32.329Z,1582669232.329 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:32.733Z,1582669232.733 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:33.141Z,1582669233.141 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:33.548Z,1582669233.548 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:33.943Z,1582669233.943 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:34.348Z,1582669234.348 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:34.757Z,1582669234.757 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:35.164Z,1582669235.164 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:35.571Z,1582669235.571 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:35.969Z,1582669235.969 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:36.369Z,1582669236.369 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:36.372Z,1582669236.372 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:20:37.582Z,1582669237.582 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:37.583Z,1582669237.583 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:20:38.418Z,1582669238.418 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:38.421Z,1582669238.421 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:20:40.011Z,1582669240.011 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:40.013Z,1582669240.013 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:20:40.844Z,1582669240.844 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:40.846Z,1582669240.846 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:20:41.626Z,1582669241.626 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:41.628Z,1582669241.628 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:20:42.427Z,1582669242.427 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:42.429Z,1582669242.429 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:20:43.245Z,1582669243.245 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:43.247Z,1582669243.247 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:20:44.455Z,1582669244.455 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:44.456Z,1582669244.456 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:20:45.261Z,1582669245.261 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:20:45.263Z,1582669245.263 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:20:46.066Z,1582669246.066 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:20:46.066Z,1582669246.066 [RDI_Pathfinder] Communications Fault, FailCount= 2 2020-02-25T22:20:46.066Z,1582669246.066 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:20:46.083Z,1582669246.083 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:20:46.474Z,1582669246.474 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:20:46.543Z,1582669246.543 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:20:46.877Z,1582669246.877 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:20:47.275Z,1582669247.275 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:20:47.292Z,1582669247.292 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:20:47.292Z,1582669247.292 [RDI_Pathfinder] No Fault, FailCount= 2 2020-02-25T22:20:47.686Z,1582669247.686 [RDI_Pathfinder](INFO): Start 2020-02-25T22:20:48.097Z,1582669248.097 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:48.497Z,1582669248.497 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:48.902Z,1582669248.902 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:49.305Z,1582669249.305 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:49.713Z,1582669249.713 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:50.109Z,1582669250.109 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:50.513Z,1582669250.513 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:50.921Z,1582669250.921 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:51.318Z,1582669251.318 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:51.721Z,1582669251.721 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:52.133Z,1582669252.133 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:52.536Z,1582669252.536 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:52.934Z,1582669252.934 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:53.337Z,1582669253.337 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:53.742Z,1582669253.742 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:54.151Z,1582669254.151 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:54.549Z,1582669254.549 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:54.957Z,1582669254.957 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:55.361Z,1582669255.361 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:55.765Z,1582669255.765 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:56.176Z,1582669256.176 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:56.573Z,1582669256.573 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:56.977Z,1582669256.977 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:57.384Z,1582669257.384 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:57.781Z,1582669257.781 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:58.182Z,1582669258.182 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:58.594Z,1582669258.594 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:59.003Z,1582669259.003 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:59.395Z,1582669259.395 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:20:59.805Z,1582669259.805 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:21:00.206Z,1582669260.206 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:00.209Z,1582669260.209 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:21:01.421Z,1582669261.421 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:01.423Z,1582669261.423 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:21:02.255Z,1582669262.255 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:02.258Z,1582669262.258 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:21:03.841Z,1582669263.841 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:03.843Z,1582669263.843 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:21:04.675Z,1582669264.675 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:04.676Z,1582669264.676 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:21:05.081Z,1582669265.081 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:05.083Z,1582669265.083 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:21:06.274Z,1582669266.274 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:06.276Z,1582669266.276 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:21:07.082Z,1582669267.082 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:07.083Z,1582669267.083 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:21:08.297Z,1582669268.297 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:08.298Z,1582669268.298 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:21:09.094Z,1582669269.094 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:21:09.095Z,1582669269.095 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:21:09.902Z,1582669269.902 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:21:09.902Z,1582669269.902 [RDI_Pathfinder] Communications Fault, FailCount= 3 2020-02-25T22:21:09.902Z,1582669269.902 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:21:09.949Z,1582669269.949 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:21:09.949Z,1582669269.949 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:21:10.305Z,1582669270.305 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:21:10.375Z,1582669270.375 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:21:10.391Z,1582669270.391 [CBIT](INFO): Critical error at 20200225T222109 2020-02-25T22:21:10.714Z,1582669270.714 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:21:11.123Z,1582669271.123 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:22:50.303Z,1582669370.303 [CommandLine](INFO): End of History 2020-02-25T22:22:50.319Z,1582669370.319 [CommandLine](INFO): End of History 2020-02-25T22:22:50.321Z,1582669370.321 [CommandLine](INFO): End of History 2020-02-25T22:22:50.409Z,1582669370.409 [CommandLine](INFO): End of History 2020-02-25T22:22:50.411Z,1582669370.411 [CommandLine](INFO): End of History 2020-02-25T22:22:50.413Z,1582669370.413 [CommandLine](INFO): End of History 2020-02-25T22:22:50.415Z,1582669370.415 [CommandLine](INFO): End of History 2020-02-25T22:22:50.467Z,1582669370.467 [CommandLine](INFO): End of History 2020-02-25T22:22:50.469Z,1582669370.469 [CommandLine](INFO): End of History 2020-02-25T22:22:50.480Z,1582669370.480 [CommandLine](INFO): End of History 2020-02-25T22:22:50.482Z,1582669370.482 [CommandLine](INFO): End of History 2020-02-25T22:23:37.245Z,1582669417.245 [CommandLine](IMPORTANT): got command failComponent 2020-02-25T22:23:37.245Z,1582669417.245 [CommandLine](IMPORTANT): Failed components: 2020-02-25T22:23:37.245Z,1582669417.245 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-02-25T22:23:37.245Z,1582669417.245 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault 2020-02-25T22:23:41.056Z,1582669421.056 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder 2020-02-25T22:23:41.056Z,1582669421.056 [RDI_Pathfinder] No Fault, FailCount= 3 2020-02-25T22:23:41.057Z,1582669421.057 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault 2020-02-25T22:23:41.090Z,1582669421.090 [RDI_Pathfinder](INFO): Start 2020-02-25T22:23:41.473Z,1582669421.473 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:41.874Z,1582669421.874 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:42.278Z,1582669422.278 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:42.702Z,1582669422.702 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:43.091Z,1582669423.091 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:43.501Z,1582669423.501 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:43.893Z,1582669423.893 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:44.313Z,1582669424.313 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:44.702Z,1582669424.702 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:45.113Z,1582669425.113 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:45.509Z,1582669425.509 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:45.913Z,1582669425.913 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:46.323Z,1582669426.323 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:46.723Z,1582669426.723 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:47.129Z,1582669427.129 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:47.533Z,1582669427.533 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:47.937Z,1582669427.937 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:48.342Z,1582669428.342 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:48.739Z,1582669428.739 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:49.174Z,1582669429.174 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:49.556Z,1582669429.556 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:49.954Z,1582669429.954 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:50.357Z,1582669430.357 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:50.763Z,1582669430.763 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:51.171Z,1582669431.171 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:51.579Z,1582669431.579 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:51.974Z,1582669431.974 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:52.378Z,1582669432.378 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:52.788Z,1582669432.788 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:53.196Z,1582669433.196 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:23:53.614Z,1582669433.614 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2020-02-25T22:23:53.615Z,1582669433.615 [RDI_Pathfinder] Data Fault, FailCount= 4 2020-02-25T22:23:53.615Z,1582669433.615 [RDI_Pathfinder](ERROR): Data Fault 2020-02-25T22:23:53.680Z,1582669433.680 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2020-02-25T22:23:53.680Z,1582669433.680 [CBIT](CRITICAL): Data Fault in component: RDI_Pathfinder 2020-02-25T22:23:53.993Z,1582669433.993 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:23:54.063Z,1582669434.063 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:23:54.095Z,1582669434.095 [CBIT](INFO): Critical error at 20200225T222353 2020-02-25T22:23:54.407Z,1582669434.407 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:23:54.802Z,1582669434.802 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:24:41.028Z,1582669481.028 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder 2020-02-25T22:24:41.029Z,1582669481.029 [RDI_Pathfinder] No Fault, FailCount= 4 2020-02-25T22:24:41.029Z,1582669481.029 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault 2020-02-25T22:24:41.261Z,1582669481.261 [RDI_Pathfinder](INFO): Start 2020-02-25T22:24:41.673Z,1582669481.673 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:42.069Z,1582669482.069 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:42.471Z,1582669482.471 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:42.881Z,1582669482.881 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:43.288Z,1582669483.288 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:43.695Z,1582669483.695 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:44.094Z,1582669484.094 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:44.514Z,1582669484.514 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:44.902Z,1582669484.902 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:45.311Z,1582669485.311 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:45.711Z,1582669485.711 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:46.109Z,1582669486.109 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:46.524Z,1582669486.524 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:46.917Z,1582669486.917 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:47.319Z,1582669487.319 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:47.731Z,1582669487.731 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:48.143Z,1582669488.143 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:48.552Z,1582669488.552 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:48.946Z,1582669488.946 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:49.340Z,1582669489.340 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:49.785Z,1582669489.785 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:50.153Z,1582669490.153 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:50.558Z,1582669490.558 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:50.962Z,1582669490.962 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:51.362Z,1582669491.362 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:51.792Z,1582669491.792 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:52.170Z,1582669492.170 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:52.578Z,1582669492.578 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:52.978Z,1582669492.978 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:53.381Z,1582669493.381 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:24:53.801Z,1582669493.801 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:24:53.803Z,1582669493.803 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:24:55.001Z,1582669495.001 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:24:55.002Z,1582669495.002 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:24:56.214Z,1582669496.214 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:24:56.215Z,1582669496.215 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:24:57.826Z,1582669497.826 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:24:57.828Z,1582669497.828 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:24:58.642Z,1582669498.642 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:24:58.644Z,1582669498.644 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:24:59.441Z,1582669499.441 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:24:59.442Z,1582669499.442 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:25:00.654Z,1582669500.654 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:25:00.672Z,1582669500.672 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:25:01.462Z,1582669501.462 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:25:01.463Z,1582669501.463 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:25:02.674Z,1582669502.674 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:25:02.675Z,1582669502.675 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:25:03.490Z,1582669503.490 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:25:03.490Z,1582669503.490 [RDI_Pathfinder] Communications Fault, FailCount= 5 2020-02-25T22:25:03.490Z,1582669503.490 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:25:03.558Z,1582669503.558 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:25:03.567Z,1582669503.567 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:25:03.892Z,1582669503.892 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:25:03.963Z,1582669503.963 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:25:04.023Z,1582669504.023 [CBIT](INFO): Critical error at 20200225T222503 2020-02-25T22:25:04.290Z,1582669504.290 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:25:04.691Z,1582669504.691 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:25:14.824Z,1582669514.824 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-25T22:25:14.824Z,1582669514.824 [Default:CheckIn:C.Wait] Stopped 2020-02-25T22:25:14.824Z,1582669514.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-25T22:25:14.824Z,1582669514.824 [Default:CheckIn:D] Running Loop=1 2020-02-25T22:25:15.222Z,1582669515.222 [Default:CheckIn:D] Stopped 2020-02-25T22:25:15.222Z,1582669515.222 [Default:CheckIn:E] Running Loop=1 2020-02-25T22:25:15.614Z,1582669515.614 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.616340 min 2020-02-25T22:25:15.614Z,1582669515.614 [Default:CheckIn:E] Stopped 2020-02-25T22:25:15.614Z,1582669515.614 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-25T22:25:15.614Z,1582669515.614 [Default:CheckIn] Stopped 2020-02-25T22:25:15.614Z,1582669515.614 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-25T22:25:15.619Z,1582669515.619 [Default:CheckIn](INFO): Running loop #2 2020-02-25T22:25:15.619Z,1582669515.619 [Default:CheckIn] Running Loop=2 2020-02-25T22:25:15.619Z,1582669515.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-25T22:25:15.619Z,1582669515.619 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-25T22:25:17.609Z,1582669517.609 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221405.00,A,3648.14368,N,12147.26114,W,0.544,0.00,250220,,,A*7E 2020-02-25T22:25:17.611Z,1582669517.611 [NAL9602](INFO): GPS fix at 20200225T221405: (36.802395, -121.787686) 2020-02-25T22:25:17.645Z,1582669517.645 [Default:CheckIn:Read_GPS] Stopped 2020-02-25T22:25:17.645Z,1582669517.645 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-25T22:25:24.670Z,1582669524.670 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20200225T221145/Courier0007.lzma 2020-02-25T22:25:26.676Z,1582669526.676 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Courier0007.lzma.bak 2020-02-25T22:25:26.676Z,1582669526.676 [DataOverHttps](INFO): SBD MOMSN=12267349 2020-02-25T22:25:44.219Z,1582669544.219 [DataOverHttps](INFO): Sending 340 bytes from file Logs/20200225T221145/Express0008.lzma 2020-02-25T22:25:46.224Z,1582669546.224 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Express0008.lzma.bak 2020-02-25T22:25:46.224Z,1582669546.224 [DataOverHttps](INFO): SBD MOMSN=12267355 2020-02-25T22:25:47.986Z,1582669547.986 [Default:CheckIn:Read_Iridium] Stopped 2020-02-25T22:25:47.986Z,1582669547.986 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-25T22:25:47.986Z,1582669547.986 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-25T22:25:48.360Z,1582669548.360 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-02-25T22:25:48.435Z,1582669548.435 [NAL9602](FAULT): received: +CSQ:0 OK82, 2, 0, 0, 0 OK 2020-02-25T22:25:48.435Z,1582669548.435 [NAL9602] Data Fault, FailCount= 1 2020-02-25T22:25:48.435Z,1582669548.435 [NAL9602](ERROR): Data Fault 2020-02-25T22:25:48.499Z,1582669548.499 [CBIT](ERROR): Data Fault in component: NAL9602 2020-02-25T22:25:48.763Z,1582669548.763 [NAL9602](INFO): Powering down 2020-02-25T22:25:49.633Z,1582669549.633 [CBIT](INFO): Clearing failed state for component NAL9602 2020-02-25T22:25:49.633Z,1582669549.633 [NAL9602] No Fault, FailCount= 1 2020-02-25T22:26:19.068Z,1582669579.068 [NAL9602](INFO): Powering up NAL9602 2020-02-25T22:26:29.983Z,1582669589.983 [NAL9602](INFO): NAL9602 initialized 2020-02-25T22:26:58.692Z,1582669618.692 [CBIT](INFO): Clearing failed state for component DropWeight 2020-02-25T22:26:58.692Z,1582669618.692 [DropWeight] No Fault, FailCount= 1 2020-02-25T22:27:01.075Z,1582669621.075 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-25T22:27:35.826Z,1582669655.826 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2020-02-25T22:27:35.826Z,1582669655.826 [AHRS_M2] Data Fault, FailCount= 1 2020-02-25T22:27:35.827Z,1582669655.827 [AHRS_M2](ERROR): Data Fault 2020-02-25T22:27:35.941Z,1582669655.941 [CBIT](ERROR): Data Fault in component: AHRS_M2 2020-02-25T22:27:36.210Z,1582669656.210 [AHRS_M2](INFO): Powering down 2020-02-25T22:27:37.057Z,1582669657.057 [CBIT](INFO): Clearing failed state for component AHRS_M2 2020-02-25T22:27:37.057Z,1582669657.057 [AHRS_M2] No Fault, FailCount= 1 2020-02-25T22:27:37.422Z,1582669657.422 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-02-25T22:28:03.730Z,1582669683.730 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:28:03.730Z,1582669683.730 [RDI_Pathfinder] No Fault, FailCount= 5 2020-02-25T22:28:04.120Z,1582669684.120 [RDI_Pathfinder](INFO): Start 2020-02-25T22:28:04.562Z,1582669684.562 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:04.921Z,1582669684.921 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:05.334Z,1582669685.334 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:05.729Z,1582669685.729 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:06.140Z,1582669686.140 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:06.587Z,1582669686.587 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:06.945Z,1582669686.945 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:07.352Z,1582669687.352 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:07.761Z,1582669687.761 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:08.153Z,1582669688.153 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:08.555Z,1582669688.555 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:08.962Z,1582669688.962 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:09.429Z,1582669689.429 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:09.773Z,1582669689.773 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:10.178Z,1582669690.178 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:10.583Z,1582669690.583 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:10.991Z,1582669690.991 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:11.471Z,1582669691.471 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:11.790Z,1582669691.790 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:12.200Z,1582669692.200 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:12.598Z,1582669692.598 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:13.001Z,1582669693.001 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:13.406Z,1582669693.406 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:13.818Z,1582669693.818 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:14.230Z,1582669694.230 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:14.637Z,1582669694.637 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:15.034Z,1582669695.034 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:15.435Z,1582669695.435 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:15.840Z,1582669695.840 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:16.245Z,1582669696.245 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:16.637Z,1582669696.637 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2020-02-25T22:28:16.637Z,1582669696.637 [RDI_Pathfinder] Data Fault, FailCount= 1 2020-02-25T22:28:16.637Z,1582669696.637 [RDI_Pathfinder](ERROR): Data Fault 2020-02-25T22:28:16.655Z,1582669696.655 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2020-02-25T22:28:17.045Z,1582669697.045 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:28:17.115Z,1582669697.115 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:28:17.449Z,1582669697.449 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:28:17.849Z,1582669697.849 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:28:17.866Z,1582669697.866 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:28:17.866Z,1582669697.866 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-25T22:28:18.259Z,1582669698.259 [RDI_Pathfinder](INFO): Start 2020-02-25T22:28:18.668Z,1582669698.668 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:19.066Z,1582669699.066 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:19.465Z,1582669699.465 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:19.879Z,1582669699.879 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:20.273Z,1582669700.273 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:20.682Z,1582669700.682 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:21.083Z,1582669701.083 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:21.493Z,1582669701.493 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:21.887Z,1582669701.887 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:22.292Z,1582669702.292 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:22.701Z,1582669702.701 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:23.109Z,1582669703.109 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:23.509Z,1582669703.509 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:23.913Z,1582669703.913 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:24.313Z,1582669704.313 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:24.729Z,1582669704.729 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:25.125Z,1582669705.125 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:25.523Z,1582669705.523 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:25.933Z,1582669705.933 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:26.340Z,1582669706.340 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:26.742Z,1582669706.742 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:27.140Z,1582669707.140 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:27.545Z,1582669707.545 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:27.955Z,1582669707.955 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:28.357Z,1582669708.357 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:28.758Z,1582669708.758 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:29.161Z,1582669709.161 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:29.575Z,1582669709.575 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:29.969Z,1582669709.969 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:30.371Z,1582669710.371 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:40.479Z,1582669720.479 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:28:40.479Z,1582669720.479 [RDI_Pathfinder] Communications Fault, FailCount= 2 2020-02-25T22:28:40.479Z,1582669720.479 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:28:40.502Z,1582669720.502 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:28:40.878Z,1582669720.878 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:28:40.878Z,1582669720.878 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:28:41.279Z,1582669721.279 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:28:41.691Z,1582669721.691 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:28:41.708Z,1582669721.708 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:28:41.708Z,1582669721.708 [RDI_Pathfinder] No Fault, FailCount= 2 2020-02-25T22:28:42.095Z,1582669722.095 [RDI_Pathfinder](INFO): Start 2020-02-25T22:28:42.491Z,1582669722.491 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:42.895Z,1582669722.895 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:43.306Z,1582669723.306 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:43.717Z,1582669723.717 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:44.114Z,1582669724.114 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:44.517Z,1582669724.517 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:44.918Z,1582669724.918 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:45.335Z,1582669725.335 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:45.726Z,1582669725.726 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:46.127Z,1582669726.127 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:46.544Z,1582669726.544 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:46.943Z,1582669726.943 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:47.342Z,1582669727.342 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:47.746Z,1582669727.746 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:48.150Z,1582669728.150 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:48.560Z,1582669728.560 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:48.958Z,1582669728.958 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:49.456Z,1582669729.456 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:49.778Z,1582669729.778 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:50.182Z,1582669730.182 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:50.578Z,1582669730.578 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:50.981Z,1582669730.981 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:51.381Z,1582669731.381 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:51.794Z,1582669731.794 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:52.202Z,1582669732.202 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:52.594Z,1582669732.594 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:53.001Z,1582669733.001 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:53.405Z,1582669733.405 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:53.810Z,1582669733.810 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:28:54.209Z,1582669734.209 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:29:00.674Z,1582669740.674 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:29:00.675Z,1582669740.675 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:29:01.485Z,1582669741.485 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:29:01.486Z,1582669741.486 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:29:02.288Z,1582669742.288 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:29:02.290Z,1582669742.290 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:29:02.696Z,1582669742.696 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:29:02.698Z,1582669742.698 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:29:03.499Z,1582669743.499 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:29:03.500Z,1582669743.500 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:29:03.910Z,1582669743.910 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:29:03.911Z,1582669743.911 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:29:04.310Z,1582669744.310 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:29:04.310Z,1582669744.310 [RDI_Pathfinder] Communications Fault, FailCount= 3 2020-02-25T22:29:04.310Z,1582669744.310 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:29:04.327Z,1582669744.327 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:29:04.327Z,1582669744.327 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:29:04.732Z,1582669744.732 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:29:04.803Z,1582669744.803 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:29:04.831Z,1582669744.831 [CBIT](INFO): Critical error at 20200225T222904 2020-02-25T22:29:05.121Z,1582669745.121 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:29:05.519Z,1582669745.519 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:30:48.548Z,1582669848.548 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-25T22:30:48.548Z,1582669848.548 [Default:CheckIn:C.Wait] Stopped 2020-02-25T22:30:48.548Z,1582669848.548 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-25T22:30:48.548Z,1582669848.548 [Default:CheckIn:D] Running Loop=1 2020-02-25T22:30:48.976Z,1582669848.976 [Default:CheckIn:D] Stopped 2020-02-25T22:30:48.976Z,1582669848.976 [Default:CheckIn:E] Running Loop=1 2020-02-25T22:30:49.362Z,1582669849.362 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.178911 min 2020-02-25T22:30:49.362Z,1582669849.362 [Default:CheckIn:E] Stopped 2020-02-25T22:30:49.362Z,1582669849.362 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-25T22:30:49.362Z,1582669849.362 [Default:CheckIn] Stopped 2020-02-25T22:30:49.363Z,1582669849.363 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-25T22:30:49.363Z,1582669849.363 [Default:CheckIn](INFO): Running loop #3 2020-02-25T22:30:49.363Z,1582669849.363 [Default:CheckIn] Running Loop=3 2020-02-25T22:30:49.363Z,1582669849.363 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-25T22:30:49.363Z,1582669849.363 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-25T22:30:51.373Z,1582669851.373 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221938.00,A,3648.14709,N,12147.25855,W,0.330,334.19,250220,,,A*78 2020-02-25T22:30:51.375Z,1582669851.375 [NAL9602](INFO): GPS fix at 20200225T221938: (36.802451, -121.787643) 2020-02-25T22:30:51.398Z,1582669851.398 [Default:CheckIn:Read_GPS] Stopped 2020-02-25T22:30:51.398Z,1582669851.398 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-25T22:30:53.373Z,1582669853.373 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-25T22:30:59.210Z,1582669859.210 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200225T221145/Courier0010.lzma 2020-02-25T22:31:01.217Z,1582669861.217 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Courier0010.lzma.bak 2020-02-25T22:31:01.217Z,1582669861.217 [DataOverHttps](INFO): SBD MOMSN=12267432 2020-02-25T22:31:21.586Z,1582669881.586 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20200225T221145/Express0011.lzma 2020-02-25T22:31:23.592Z,1582669883.592 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Express0011.lzma.bak 2020-02-25T22:31:23.592Z,1582669883.592 [DataOverHttps](INFO): SBD MOMSN=12267436 2020-02-25T22:31:24.095Z,1582669884.095 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-25T22:31:25.341Z,1582669885.341 [Default:CheckIn:Read_Iridium] Stopped 2020-02-25T22:31:25.341Z,1582669885.341 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-25T22:31:25.342Z,1582669885.342 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-25T22:32:04.540Z,1582669924.540 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:32:04.540Z,1582669924.540 [RDI_Pathfinder] No Fault, FailCount= 3 2020-02-25T22:32:04.909Z,1582669924.909 [RDI_Pathfinder](INFO): Start 2020-02-25T22:32:05.315Z,1582669925.315 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:05.617Z,1582669925.617 [CommandLine](IMPORTANT): got command failComponent 2020-02-25T22:32:05.617Z,1582669925.617 [CommandLine](IMPORTANT): Failed components: 2020-02-25T22:32:05.617Z,1582669925.617 [CommandLine](IMPORTANT): No failed Components. 2020-02-25T22:32:05.724Z,1582669925.724 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:06.122Z,1582669926.122 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:06.526Z,1582669926.526 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:06.930Z,1582669926.930 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:07.337Z,1582669927.337 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:07.736Z,1582669927.736 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:08.145Z,1582669928.145 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:08.546Z,1582669928.546 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:08.958Z,1582669928.958 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:09.354Z,1582669929.354 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:09.755Z,1582669929.755 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:10.161Z,1582669930.161 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:10.574Z,1582669930.574 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:10.981Z,1582669930.981 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:11.377Z,1582669931.377 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:11.781Z,1582669931.781 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:12.186Z,1582669932.186 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:12.597Z,1582669932.597 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:12.993Z,1582669932.993 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:13.394Z,1582669933.394 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:13.808Z,1582669933.808 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:14.202Z,1582669934.202 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:14.605Z,1582669934.605 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:15.019Z,1582669935.019 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:15.413Z,1582669935.413 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:15.821Z,1582669935.821 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:16.225Z,1582669936.225 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:16.634Z,1582669936.634 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:17.043Z,1582669937.043 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:17.435Z,1582669937.435 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2020-02-25T22:32:17.435Z,1582669937.435 [RDI_Pathfinder] Data Fault, FailCount= 1 2020-02-25T22:32:17.435Z,1582669937.435 [RDI_Pathfinder](ERROR): Data Fault 2020-02-25T22:32:17.498Z,1582669937.498 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2020-02-25T22:32:17.843Z,1582669937.843 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:32:17.915Z,1582669937.915 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:32:18.247Z,1582669938.247 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:32:18.650Z,1582669938.650 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:32:18.683Z,1582669938.683 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:32:18.683Z,1582669938.683 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-25T22:32:19.050Z,1582669939.050 [RDI_Pathfinder](INFO): Start 2020-02-25T22:32:19.453Z,1582669939.453 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:19.862Z,1582669939.862 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:20.265Z,1582669940.265 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:20.662Z,1582669940.662 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:21.069Z,1582669941.069 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:21.480Z,1582669941.480 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:21.875Z,1582669941.875 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:22.286Z,1582669942.286 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:22.685Z,1582669942.685 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:23.095Z,1582669943.095 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:23.497Z,1582669943.497 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:23.901Z,1582669943.901 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:24.301Z,1582669944.301 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:24.715Z,1582669944.715 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:25.109Z,1582669945.109 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:25.511Z,1582669945.511 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:25.921Z,1582669945.921 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:26.330Z,1582669946.330 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:26.725Z,1582669946.725 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:27.128Z,1582669947.128 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:27.537Z,1582669947.537 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:27.943Z,1582669947.943 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:28.351Z,1582669948.351 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:28.746Z,1582669948.746 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:29.149Z,1582669949.149 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:29.563Z,1582669949.563 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:29.967Z,1582669949.967 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:30.364Z,1582669950.364 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:30.771Z,1582669950.771 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:31.176Z,1582669951.176 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:31.571Z,1582669951.571 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:31.573Z,1582669951.573 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:32:31.982Z,1582669951.982 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:31.984Z,1582669951.984 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:32:33.598Z,1582669953.598 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:33.599Z,1582669953.599 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:32:35.218Z,1582669955.218 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:35.219Z,1582669955.219 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:32:36.835Z,1582669956.835 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:36.836Z,1582669956.836 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:32:38.454Z,1582669958.454 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:38.456Z,1582669958.456 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:32:40.076Z,1582669960.076 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:40.078Z,1582669960.078 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:32:41.269Z,1582669961.269 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:32:41.270Z,1582669961.270 [RDI_Pathfinder] Communications Fault, FailCount= 2 2020-02-25T22:32:41.270Z,1582669961.270 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:32:41.294Z,1582669961.294 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:32:41.682Z,1582669961.682 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:32:41.747Z,1582669961.747 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:32:42.077Z,1582669962.077 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:32:42.482Z,1582669962.482 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:32:42.538Z,1582669962.538 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:32:42.538Z,1582669962.538 [RDI_Pathfinder] No Fault, FailCount= 2 2020-02-25T22:32:42.889Z,1582669962.889 [RDI_Pathfinder](INFO): Start 2020-02-25T22:32:43.296Z,1582669963.296 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:43.698Z,1582669963.698 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:44.102Z,1582669964.102 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:44.505Z,1582669964.505 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:44.911Z,1582669964.911 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:45.319Z,1582669965.319 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:45.714Z,1582669965.714 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:46.117Z,1582669966.117 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:46.529Z,1582669966.529 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:46.925Z,1582669966.925 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:47.327Z,1582669967.327 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:47.734Z,1582669967.734 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:48.144Z,1582669968.144 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:48.539Z,1582669968.539 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:48.950Z,1582669968.950 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:49.353Z,1582669969.353 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:49.765Z,1582669969.765 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:50.158Z,1582669970.158 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:50.562Z,1582669970.562 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:50.969Z,1582669970.969 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:51.381Z,1582669971.381 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:51.776Z,1582669971.776 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:52.181Z,1582669972.181 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:52.589Z,1582669972.589 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:52.994Z,1582669972.994 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:53.387Z,1582669973.387 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:53.792Z,1582669973.792 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:54.204Z,1582669974.204 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:54.607Z,1582669974.607 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:55.015Z,1582669975.015 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:32:55.410Z,1582669975.410 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:55.412Z,1582669975.412 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:32:55.821Z,1582669975.821 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:55.823Z,1582669975.823 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:32:57.435Z,1582669977.435 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:57.436Z,1582669977.436 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:32:59.045Z,1582669979.045 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:32:59.046Z,1582669979.046 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:33:00.662Z,1582669980.662 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:33:00.663Z,1582669980.663 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:33:02.281Z,1582669982.281 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:33:02.283Z,1582669982.283 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:33:03.902Z,1582669983.902 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:33:03.904Z,1582669983.904 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:33:05.105Z,1582669985.105 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:33:05.105Z,1582669985.105 [RDI_Pathfinder] Communications Fault, FailCount= 3 2020-02-25T22:33:05.105Z,1582669985.105 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:33:05.122Z,1582669985.122 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:33:05.123Z,1582669985.123 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:33:05.509Z,1582669985.509 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:33:05.579Z,1582669985.579 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:33:05.605Z,1582669985.605 [CBIT](INFO): Critical error at 20200225T223305 2020-02-25T22:33:05.914Z,1582669985.914 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:33:06.322Z,1582669986.322 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:33:40.141Z,1582670020.141 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder 2020-02-25T22:33:40.141Z,1582670020.141 [RDI_Pathfinder] No Fault, FailCount= 3 2020-02-25T22:33:40.141Z,1582670020.141 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault 2020-02-25T22:33:40.270Z,1582670020.270 [RDI_Pathfinder](INFO): Start 2020-02-25T22:33:40.697Z,1582670020.697 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:41.079Z,1582670021.079 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:41.476Z,1582670021.476 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:41.886Z,1582670021.886 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:42.283Z,1582670022.283 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:42.695Z,1582670022.695 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:43.105Z,1582670023.105 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:43.505Z,1582670023.505 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:43.916Z,1582670023.916 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:44.303Z,1582670024.303 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:44.719Z,1582670024.719 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:45.119Z,1582670025.119 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:45.515Z,1582670025.515 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:45.925Z,1582670025.925 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:46.329Z,1582670026.329 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:46.739Z,1582670026.739 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:47.145Z,1582670027.145 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:47.546Z,1582670027.546 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:47.942Z,1582670027.942 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:48.354Z,1582670028.354 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:48.757Z,1582670028.757 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:49.174Z,1582670029.174 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:49.564Z,1582670029.564 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:49.967Z,1582670029.967 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:50.366Z,1582670030.366 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:50.803Z,1582670030.803 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:51.174Z,1582670031.174 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:51.584Z,1582670031.584 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:51.990Z,1582670031.990 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:52.385Z,1582670032.385 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:33:52.806Z,1582670032.806 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:33:52.813Z,1582670032.813 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:33:54.405Z,1582670034.405 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:33:54.406Z,1582670034.406 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:33:55.211Z,1582670035.211 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:33:55.213Z,1582670035.213 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:33:56.853Z,1582670036.853 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:33:56.854Z,1582670036.854 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:33:58.065Z,1582670038.065 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:33:58.068Z,1582670038.068 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:33:59.670Z,1582670039.670 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:33:59.671Z,1582670039.671 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:34:01.282Z,1582670041.282 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:34:01.283Z,1582670041.283 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:34:02.486Z,1582670042.486 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:34:02.486Z,1582670042.486 [RDI_Pathfinder] Communications Fault, FailCount= 4 2020-02-25T22:34:02.486Z,1582670042.486 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:34:02.503Z,1582670042.503 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:34:02.504Z,1582670042.504 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:34:02.890Z,1582670042.890 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:34:02.955Z,1582670042.955 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:34:02.976Z,1582670042.976 [CBIT](INFO): Critical error at 20200225T223402 2020-02-25T22:34:03.291Z,1582670043.291 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:34:03.696Z,1582670043.696 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:36:25.924Z,1582670185.924 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-02-25T22:36:25.924Z,1582670185.924 [Default:CheckIn:C.Wait] Stopped 2020-02-25T22:36:25.924Z,1582670185.924 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-25T22:36:25.924Z,1582670185.924 [Default:CheckIn:D] Running Loop=1 2020-02-25T22:36:26.352Z,1582670186.352 [Default:CheckIn:D] Stopped 2020-02-25T22:36:26.352Z,1582670186.352 [Default:CheckIn:E] Running Loop=1 2020-02-25T22:36:26.722Z,1582670186.722 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.801843 min 2020-02-25T22:36:26.722Z,1582670186.722 [Default:CheckIn:E] Stopped 2020-02-25T22:36:26.722Z,1582670186.722 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-02-25T22:36:26.722Z,1582670186.722 [Default:CheckIn] Stopped 2020-02-25T22:36:26.722Z,1582670186.722 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-25T22:36:26.723Z,1582670186.723 [Default:CheckIn](INFO): Running loop #4 2020-02-25T22:36:26.723Z,1582670186.723 [Default:CheckIn] Running Loop=4 2020-02-25T22:36:26.723Z,1582670186.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-25T22:36:26.723Z,1582670186.723 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-25T22:36:28.730Z,1582670188.730 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222516.00,A,3648.14778,N,12147.26517,W,0.330,334.19,250220,,,A*75 2020-02-25T22:36:28.732Z,1582670188.732 [NAL9602](INFO): GPS fix at 20200225T222516: (36.802463, -121.787753) 2020-02-25T22:36:28.755Z,1582670188.755 [Default:CheckIn:Read_GPS] Stopped 2020-02-25T22:36:28.756Z,1582670188.756 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-25T22:36:35.680Z,1582670195.680 [DataOverHttps](INFO): Sending 124 bytes from file Logs/20200225T221145/Courier0013.lzma 2020-02-25T22:36:37.195Z,1582670197.195 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Courier0013.lzma.bak 2020-02-25T22:36:37.195Z,1582670197.195 [DataOverHttps](INFO): SBD MOMSN=12267442 2020-02-25T22:36:54.095Z,1582670214.095 [DataOverHttps](INFO): Sending 323 bytes from file Logs/20200225T221145/Express0014.lzma 2020-02-25T22:36:56.100Z,1582670216.100 [DataOverHttps](INFO): Moved sent file to Logs/20200225T221145/Express0014.lzma.bak 2020-02-25T22:36:56.100Z,1582670216.100 [DataOverHttps](INFO): SBD MOMSN=12267447 2020-02-25T22:36:57.845Z,1582670217.845 [Default:CheckIn:Read_Iridium] Stopped 2020-02-25T22:36:57.845Z,1582670217.845 [Default:CheckIn:C.Wait] Running Loop=1 2020-02-25T22:36:57.845Z,1582670217.845 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-02-25T22:37:02.701Z,1582670222.701 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:37:02.702Z,1582670222.702 [RDI_Pathfinder] No Fault, FailCount= 4 2020-02-25T22:37:03.105Z,1582670223.105 [RDI_Pathfinder](INFO): Start 2020-02-25T22:37:03.489Z,1582670223.489 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:03.896Z,1582670223.896 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:04.305Z,1582670224.305 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:04.704Z,1582670224.704 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:05.098Z,1582670225.098 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:05.507Z,1582670225.507 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:05.902Z,1582670225.902 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:06.318Z,1582670226.318 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:06.729Z,1582670226.729 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:07.122Z,1582670227.122 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:07.518Z,1582670227.518 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:07.926Z,1582670227.926 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:08.345Z,1582670228.345 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:08.752Z,1582670228.752 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:09.132Z,1582670229.132 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:09.542Z,1582670229.542 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:09.946Z,1582670229.946 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:10.367Z,1582670230.367 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:10.780Z,1582670230.780 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:11.162Z,1582670231.162 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:11.569Z,1582670231.569 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:11.970Z,1582670231.970 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:12.370Z,1582670232.370 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:12.784Z,1582670232.784 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:13.180Z,1582670233.180 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:13.580Z,1582670233.580 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:13.987Z,1582670233.987 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:14.392Z,1582670234.392 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:14.803Z,1582670234.803 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:15.223Z,1582670235.223 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:15.602Z,1582670235.602 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2020-02-25T22:37:15.603Z,1582670235.603 [RDI_Pathfinder] Data Fault, FailCount= 1 2020-02-25T22:37:15.603Z,1582670235.603 [RDI_Pathfinder](ERROR): Data Fault 2020-02-25T22:37:15.691Z,1582670235.691 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2020-02-25T22:37:16.036Z,1582670236.036 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:37:16.107Z,1582670236.107 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:37:16.426Z,1582670236.426 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:37:16.833Z,1582670236.833 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:37:16.862Z,1582670236.862 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:37:16.862Z,1582670236.862 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-25T22:37:17.242Z,1582670237.242 [RDI_Pathfinder](INFO): Start 2020-02-25T22:37:17.630Z,1582670237.630 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:18.050Z,1582670238.050 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:18.445Z,1582670238.445 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:18.840Z,1582670238.840 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:19.260Z,1582670239.260 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:19.650Z,1582670239.650 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:20.061Z,1582670240.061 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:20.462Z,1582670240.462 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:20.866Z,1582670240.866 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:21.266Z,1582670241.266 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:21.673Z,1582670241.673 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:22.078Z,1582670242.078 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:22.476Z,1582670242.476 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:22.886Z,1582670242.886 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:23.292Z,1582670243.292 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:23.688Z,1582670243.688 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:24.092Z,1582670244.092 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:24.499Z,1582670244.499 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:24.921Z,1582670244.921 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:25.303Z,1582670245.303 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:25.709Z,1582670245.709 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:26.120Z,1582670246.120 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:26.528Z,1582670246.528 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:26.922Z,1582670246.922 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:27.326Z,1582670247.326 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:27.743Z,1582670247.743 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:28.144Z,1582670248.144 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:28.542Z,1582670248.542 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:28.942Z,1582670248.942 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:29.355Z,1582670249.355 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:29.761Z,1582670249.761 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:29.763Z,1582670249.763 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:37:30.158Z,1582670250.158 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:30.160Z,1582670250.160 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:37:31.774Z,1582670251.774 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:31.776Z,1582670251.776 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:37:33.377Z,1582670253.377 [NAL9602](INFO): SBD MO Status=2, MOMSN=6582, MT Status=2, MTMSN=0 2020-02-25T22:37:33.377Z,1582670253.377 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-02-25T22:37:33.392Z,1582670253.392 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:33.394Z,1582670253.394 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:37:35.002Z,1582670255.002 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:35.004Z,1582670255.004 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:37:36.639Z,1582670256.639 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:36.640Z,1582670256.640 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:37:38.266Z,1582670258.266 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:38.268Z,1582670258.268 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:37:39.446Z,1582670259.446 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:37:39.465Z,1582670259.465 [RDI_Pathfinder] Communications Fault, FailCount= 2 2020-02-25T22:37:39.465Z,1582670259.465 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:37:39.483Z,1582670259.483 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:37:39.850Z,1582670259.850 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:37:39.919Z,1582670259.919 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:37:40.255Z,1582670260.255 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:37:40.662Z,1582670260.662 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:37:40.733Z,1582670260.733 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-25T22:37:40.733Z,1582670260.733 [RDI_Pathfinder] No Fault, FailCount= 2 2020-02-25T22:37:41.072Z,1582670261.072 [RDI_Pathfinder](INFO): Start 2020-02-25T22:37:41.466Z,1582670261.466 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:41.881Z,1582670261.881 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:42.279Z,1582670262.279 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:42.678Z,1582670262.678 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:43.079Z,1582670263.079 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:43.490Z,1582670263.490 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:43.877Z,1582670263.877 [NAL9602](INFO): SBD MO Status=0, MOMSN=6582, MT Status=0, MTMSN=0 2020-02-25T22:37:43.877Z,1582670263.877 [NAL9602](INFO): No messages in MT queue 2020-02-25T22:37:43.922Z,1582670263.922 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:44.291Z,1582670264.291 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:44.705Z,1582670264.705 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:45.117Z,1582670265.117 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:45.515Z,1582670265.515 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:45.919Z,1582670265.919 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:46.314Z,1582670266.314 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:46.717Z,1582670266.717 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:47.131Z,1582670267.131 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:47.525Z,1582670267.525 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:47.930Z,1582670267.930 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:48.337Z,1582670268.337 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:48.744Z,1582670268.744 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:49.139Z,1582670269.139 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:49.550Z,1582670269.550 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:49.948Z,1582670269.948 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:50.354Z,1582670270.354 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:50.806Z,1582670270.806 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:51.162Z,1582670271.162 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:51.563Z,1582670271.563 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:51.974Z,1582670271.974 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:52.381Z,1582670272.381 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:52.789Z,1582670272.789 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:53.189Z,1582670273.189 [RDI_Pathfinder](INFO): Starting 2020-02-25T22:37:53.590Z,1582670273.590 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:53.592Z,1582670273.592 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-25T22:37:53.996Z,1582670273.996 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:53.997Z,1582670273.997 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:37:55.616Z,1582670275.616 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:55.617Z,1582670275.617 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:37:57.230Z,1582670277.230 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:57.232Z,1582670277.232 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:37:58.847Z,1582670278.847 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:37:58.848Z,1582670278.848 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:38:00.464Z,1582670280.464 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:38:00.465Z,1582670280.465 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:38:02.080Z,1582670282.080 [RDI_Pathfinder](INFO): Message in queue 2020-02-25T22:38:02.082Z,1582670282.082 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-25T22:38:03.286Z,1582670283.286 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-25T22:38:03.286Z,1582670283.286 [RDI_Pathfinder] Communications Fault, FailCount= 3 2020-02-25T22:38:03.287Z,1582670283.287 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-25T22:38:03.304Z,1582670283.304 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:38:03.304Z,1582670283.304 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-25T22:38:03.692Z,1582670283.692 [RDI_Pathfinder](INFO): Stop 2020-02-25T22:38:03.763Z,1582670283.763 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:38:03.785Z,1582670283.785 [CBIT](INFO): Critical error at 20200225T223803 2020-02-25T22:38:04.094Z,1582670284.094 [RDI_Pathfinder](INFO): Stopping 2020-02-25T22:38:04.498Z,1582670284.498 [RDI_Pathfinder](INFO): Stopped 2020-02-25T22:38:14.579Z,1582670294.579 [NAL9602](INFO): Not Powering down - fast GPS 2020-02-25T22:40:40.021Z,1582670440.021 [CommandLine](IMPORTANT): got command restart application 2020-02-25T22:40:41.043Z,1582670441.043 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:41.043Z,1582670441.043 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:41.184Z,1582670441.184 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-02-25T22:40:41.184Z,1582670441.184 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:41.185Z,1582670441.185 [CommandLine](INFO): Join timeout helper Thread ID is 1128 2020-02-25T22:40:41.185Z,1582670441.185 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-02-25T22:40:41.186Z,1582670441.186 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:41.186Z,1582670441.186 [NavChartDb](INFO): Join timeout helper Thread ID is 1129 2020-02-25T22:40:41.579Z,1582670441.579 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:41.579Z,1582670441.579 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:41.590Z,1582670441.590 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2020-02-25T22:40:41.591Z,1582670441.591 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:41.591Z,1582670441.591 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1130 2020-02-25T22:40:41.807Z,1582670441.807 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:41.807Z,1582670441.807 [WetLabsUBAT](INFO): Powering down 2020-02-25T22:40:41.822Z,1582670441.822 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:41.839Z,1582670441.839 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-02-25T22:40:41.839Z,1582670441.839 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:41.839Z,1582670441.839 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1131 2020-02-25T22:40:42.219Z,1582670442.219 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:42.219Z,1582670442.219 [WetLabsBB2FL](INFO): Powering down 2020-02-25T22:40:42.220Z,1582670442.220 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:42.234Z,1582670442.234 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-02-25T22:40:42.234Z,1582670442.234 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:42.235Z,1582670442.235 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1132 2020-02-25T22:40:42.779Z,1582670442.779 [CTD_Seabird](INFO): Powering down 2020-02-25T22:40:42.790Z,1582670442.790 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:42.791Z,1582670442.791 [CTD_Seabird](INFO): Powering down 2020-02-25T22:40:42.802Z,1582670442.802 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:42.804Z,1582670442.804 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler 2020-02-25T22:40:42.804Z,1582670442.804 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:42.804Z,1582670442.804 [BackSeatDriver](INFO): Join timeout helper Thread ID is 1133 2020-02-25T22:40:43.031Z,1582670443.031 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:43.031Z,1582670443.031 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.047Z,1582670443.047 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-02-25T22:40:43.047Z,1582670443.047 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.048Z,1582670443.048 [Radio_Surface](INFO): Join timeout helper Thread ID is 1134 2020-02-25T22:40:43.363Z,1582670443.363 [Radio_Surface](INFO): Powering down 2020-02-25T22:40:43.363Z,1582670443.363 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:43.364Z,1582670443.364 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.379Z,1582670443.379 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-02-25T22:40:43.379Z,1582670443.379 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.380Z,1582670443.380 [DataOverHttps](INFO): Join timeout helper Thread ID is 1135 2020-02-25T22:40:43.387Z,1582670443.387 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:43.387Z,1582670443.387 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.403Z,1582670443.403 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-02-25T22:40:43.403Z,1582670443.403 [logger ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.404Z,1582670443.404 [logger](INFO): Join timeout helper Thread ID is 1136 2020-02-25T22:40:43.413Z,1582670443.413 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:43.413Z,1582670443.413 [logger ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.415Z,1582670443.415 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-02-25T22:40:43.415Z,1582670443.415 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.415Z,1582670443.415 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-02-25T22:40:43.415Z,1582670443.415 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.415Z,1582670443.415 [controlThread](INFO): Join timeout helper Thread ID is 1137 2020-02-25T22:40:43.659Z,1582670443.659 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-02-25T22:40:43.659Z,1582670443.659 [controlThread](DEBUG): Uninitializing ControlThread 2020-02-25T22:40:43.659Z,1582670443.659 [AHRS_M2](INFO): Powering down 2020-02-25T22:40:43.731Z,1582670443.731 [NAL9602](INFO): Powering down 2020-02-25T22:40:43.732Z,1582670443.732 [RDI_Pathfinder](INFO): Powering down 2020-02-25T22:40:43.733Z,1582670443.733 [DAT](INFO): Powering down 2020-02-25T22:40:43.851Z,1582670443.851 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-02-25T22:40:43.852Z,1582670443.852 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-02-25T22:40:43.852Z,1582670443.852 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-02-25T22:40:43.853Z,1582670443.853 [MissionManager](INFO): Uninitializing Mission Default 2020-02-25T22:40:43.853Z,1582670443.853 [Default] Stopped 2020-02-25T22:40:43.853Z,1582670443.853 [Default](DEBUG): Aggregate::uninitialize Default 2020-02-25T22:40:43.853Z,1582670443.853 [Default:B.GoToSurface] Stopped 2020-02-25T22:40:43.853Z,1582670443.853 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-02-25T22:40:43.853Z,1582670443.853 [Default:CheckIn] Stopped 2020-02-25T22:40:43.853Z,1582670443.853 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-02-25T22:40:43.853Z,1582670443.853 [Default:CheckIn:C.Wait] Stopped 2020-02-25T22:40:43.854Z,1582670443.854 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-02-25T22:40:43.856Z,1582670443.856 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-02-25T22:40:43.856Z,1582670443.856 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-02-25T22:40:43.856Z,1582670443.856 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-02-25T22:40:43.857Z,1582670443.857 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-02-25T22:40:43.857Z,1582670443.857 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-02-25T22:40:43.857Z,1582670443.857 [BuoyancyServo](INFO): Powering down 2020-02-25T22:40:43.871Z,1582670443.871 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-02-25T22:40:43.871Z,1582670443.871 [ElevatorServo](INFO): Powering down 2020-02-25T22:40:43.871Z,1582670443.871 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-02-25T22:40:43.872Z,1582670443.872 [MassServo](INFO): Powering down 2020-02-25T22:40:43.872Z,1582670443.872 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-02-25T22:40:43.872Z,1582670443.872 [RudderServo](INFO): Powering down 2020-02-25T22:40:43.873Z,1582670443.873 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-02-25T22:40:43.873Z,1582670443.873 [ThrusterServo](INFO): Powering down 2020-02-25T22:40:43.874Z,1582670443.874 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-02-25T22:40:43.874Z,1582670443.874 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-02-25T22:40:43.875Z,1582670443.875 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-02-25T22:40:43.875Z,1582670443.875 [CBIT](DEBUG): Powering off loads. 2020-02-25T22:40:43.886Z,1582670443.886 [CBIT](DEBUG): Disabling WDT. 2020-02-25T22:40:43.898Z,1582670443.898 [CBIT](DEBUG): Opening all GF detection circuits. 2020-02-25T22:40:43.899Z,1582670443.899 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:43.994Z,1582670443.994 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:44.001Z,1582670444.001 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:44.071Z,1582670444.071 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:44.072Z,1582670444.072 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open 2020-02-25T22:40:44.072Z,1582670444.072 [WetLabsUBAT](INFO): WetLabs destructor: done 2020-02-25T22:40:44.079Z,1582670444.079 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:44.082Z,1582670444.082 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:44.085Z,1582670444.085 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:44.094Z,1582670444.094 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-02-25T22:40:44.170Z,1582670444.170 [logger ThreadHandler](INFO): Thread cancelled.