2020-02-21T01:11:49.734Z,1582247509.734 [Supervisor](DEBUG): Initializing supervisor. 2020-02-21T01:11:49.737Z,1582247509.737 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-02-21T01:11:49.737Z,1582247509.737 [SyncHandler](INFO): Protected caller Thread ID is 1988 2020-02-21T01:11:49.738Z,1582247509.738 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-02-21T01:11:49.738Z,1582247509.738 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-02-21T01:11:49.739Z,1582247509.739 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1989 2020-02-21T01:11:49.741Z,1582247509.741 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-02-21T01:11:49.753Z,1582247509.753 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-02-21T01:11:49.754Z,1582247509.754 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-02-21T01:11:49.754Z,1582247509.754 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1990 2020-02-21T01:11:49.755Z,1582247509.755 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-02-21T01:11:49.756Z,1582247509.756 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-02-21T01:11:49.756Z,1582247509.756 [logger ThreadHandler](INFO): Protected caller Thread ID is 1991 2020-02-21T01:11:49.758Z,1582247509.758 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-02-21T01:11:49.759Z,1582247509.759 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-02-21T01:11:49.760Z,1582247509.760 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-02-21T01:11:49.854Z,1582247509.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-02-21T01:11:49.855Z,1582247509.855 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-02-21T01:11:50.441Z,1582247510.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-02-21T01:11:50.442Z,1582247510.442 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-02-21T01:11:50.641Z,1582247510.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-02-21T01:11:50.642Z,1582247510.642 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-02-21T01:11:50.743Z,1582247510.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-02-21T01:11:50.744Z,1582247510.744 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-02-21T01:11:50.823Z,1582247510.823 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-02-21T01:11:50.961Z,1582247510.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-02-21T01:11:50.962Z,1582247510.962 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-02-21T01:11:51.249Z,1582247511.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-02-21T01:11:51.249Z,1582247511.249 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-02-21T01:11:51.694Z,1582247511.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-02-21T01:11:51.695Z,1582247511.695 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-02-21T01:11:51.837Z,1582247511.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-02-21T01:11:51.837Z,1582247511.837 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-02-21T01:11:52.026Z,1582247512.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-02-21T01:11:52.026Z,1582247512.026 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-02-21T01:11:52.485Z,1582247512.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-02-21T01:11:52.486Z,1582247512.486 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-02-21T01:11:52.698Z,1582247512.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-02-21T01:11:52.699Z,1582247512.699 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-02-21T01:11:52.895Z,1582247512.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-02-21T01:11:52.896Z,1582247512.896 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-02-21T01:11:53.297Z,1582247513.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-02-21T01:11:53.298Z,1582247513.298 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-02-21T01:11:53.620Z,1582247513.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-02-21T01:11:53.622Z,1582247513.622 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-02-21T01:11:53.622Z,1582247513.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-02-21T01:11:53.705Z,1582247513.705 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-02-21T01:11:53.866Z,1582247513.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-02-21T01:11:53.975Z,1582247513.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-02-21T01:11:54.059Z,1582247514.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-02-21T01:11:54.153Z,1582247514.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-02-21T01:11:54.360Z,1582247514.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-02-21T01:11:54.585Z,1582247514.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-02-21T01:11:54.586Z,1582247514.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-02-21T01:11:54.678Z,1582247514.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-02-21T01:11:54.774Z,1582247514.774 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-02-21T01:11:54.912Z,1582247514.912 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-02-21T01:11:55.010Z,1582247515.010 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-02-21T01:11:55.025Z,1582247515.025 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-02-21T01:11:55.073Z,1582247515.073 [VerticalControl](DEBUG): Construct VerticalControl. 2020-02-21T01:11:55.181Z,1582247515.181 [VerticalControl] Loaded 2020-02-21T01:11:55.182Z,1582247515.182 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-02-21T01:11:55.182Z,1582247515.182 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-02-21T01:11:55.261Z,1582247515.261 [HorizontalControl] Loaded 2020-02-21T01:11:55.261Z,1582247515.261 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-02-21T01:11:55.262Z,1582247515.262 [SpeedControl](DEBUG): Construct SpeedControl. 2020-02-21T01:11:55.267Z,1582247515.267 [SpeedControl] Loaded 2020-02-21T01:11:55.267Z,1582247515.267 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-02-21T01:11:55.268Z,1582247515.268 [LoopControl](DEBUG): Construct LoopControl. 2020-02-21T01:11:55.269Z,1582247515.269 [LoopControl] Loaded 2020-02-21T01:11:55.269Z,1582247515.269 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-02-21T01:11:55.269Z,1582247515.269 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-02-21T01:11:55.270Z,1582247515.270 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-02-21T01:11:55.310Z,1582247515.310 [DepthRateCalculator] Loaded 2020-02-21T01:11:55.310Z,1582247515.310 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-02-21T01:11:55.315Z,1582247515.315 [PitchRateCalculator] Loaded 2020-02-21T01:11:55.315Z,1582247515.315 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-02-21T01:11:55.330Z,1582247515.330 [SpeedCalculator] Loaded 2020-02-21T01:11:55.331Z,1582247515.331 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-02-21T01:11:55.351Z,1582247515.351 [TempGradientCalculator] Loaded 2020-02-21T01:11:55.351Z,1582247515.351 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-02-21T01:11:55.356Z,1582247515.356 [YawRateCalculator] Loaded 2020-02-21T01:11:55.356Z,1582247515.356 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-02-21T01:11:55.384Z,1582247515.384 [ElevatorOffsetCalculator] Loaded 2020-02-21T01:11:55.385Z,1582247515.385 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-02-21T01:11:55.385Z,1582247515.385 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-02-21T01:11:55.386Z,1582247515.386 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-02-21T01:11:55.417Z,1582247515.417 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-02-21T01:11:55.418Z,1582247515.418 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-02-21T01:11:55.499Z,1582247515.499 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-02-21T01:11:55.499Z,1582247515.499 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-02-21T01:11:56.345Z,1582247516.345 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-02-21T01:11:56.345Z,1582247516.345 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-02-21T01:11:56.443Z,1582247516.443 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-02-21T01:11:56.443Z,1582247516.443 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-02-21T01:11:56.763Z,1582247516.763 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-02-21T01:11:56.768Z,1582247516.768 [AHRS_M2](INFO): created writer for : platform_orientation 2020-02-21T01:11:56.770Z,1582247516.770 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-02-21T01:11:56.775Z,1582247516.775 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-02-21T01:11:56.775Z,1582247516.775 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-02-21T01:11:56.780Z,1582247516.780 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-02-21T01:11:56.781Z,1582247516.781 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-02-21T01:11:56.786Z,1582247516.786 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-02-21T01:11:56.855Z,1582247516.855 [AHRS_M2] Loaded 2020-02-21T01:11:56.855Z,1582247516.855 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-02-21T01:11:56.938Z,1582247516.938 [DataOverHttps] Loaded 2020-02-21T01:11:56.939Z,1582247516.939 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-02-21T01:11:56.940Z,1582247516.940 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408DD4E0 2020-02-21T01:11:56.940Z,1582247516.940 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2070 2020-02-21T01:11:56.953Z,1582247516.953 [Depth_Keller] Loaded 2020-02-21T01:11:56.953Z,1582247516.953 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-02-21T01:11:56.958Z,1582247516.958 [DropWeight] Loaded 2020-02-21T01:11:56.958Z,1582247516.958 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-02-21T01:11:57.054Z,1582247517.054 [NAL9602] Loaded 2020-02-21T01:11:57.054Z,1582247517.054 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-02-21T01:11:57.090Z,1582247517.090 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-02-21T01:11:57.091Z,1582247517.091 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-02-21T01:11:57.091Z,1582247517.091 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-02-21T01:11:57.092Z,1582247517.092 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-02-21T01:11:57.093Z,1582247517.093 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-02-21T01:11:57.093Z,1582247517.093 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-02-21T01:11:57.094Z,1582247517.094 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-02-21T01:11:57.094Z,1582247517.094 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-02-21T01:11:57.094Z,1582247517.094 [Onboard] Loaded 2020-02-21T01:11:57.094Z,1582247517.094 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-02-21T01:11:57.098Z,1582247517.098 [Radio_Surface] Loaded 2020-02-21T01:11:57.098Z,1582247517.098 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-02-21T01:11:57.099Z,1582247517.099 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0 2020-02-21T01:11:57.099Z,1582247517.099 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2071 2020-02-21T01:11:57.142Z,1582247517.142 [RDI_Pathfinder] Loaded 2020-02-21T01:11:57.143Z,1582247517.143 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-02-21T01:11:57.272Z,1582247517.272 [DAT] Loaded 2020-02-21T01:11:57.272Z,1582247517.272 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-02-21T01:11:58.734Z,1582247518.734 [BPC1] Loaded 2020-02-21T01:11:58.735Z,1582247518.735 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-02-21T01:11:58.735Z,1582247518.735 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-02-21T01:11:58.735Z,1582247518.735 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-02-21T01:11:58.846Z,1582247518.846 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-02-21T01:11:58.846Z,1582247518.846 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-02-21T01:11:58.866Z,1582247518.866 [NavChart] Loaded 2020-02-21T01:11:58.866Z,1582247518.866 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-02-21T01:11:58.870Z,1582247518.870 [UniversalFixResidualReporter] Loaded 2020-02-21T01:11:58.870Z,1582247518.870 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-02-21T01:11:58.871Z,1582247518.871 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-02-21T01:11:58.871Z,1582247518.871 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-02-21T01:11:59.008Z,1582247519.008 [SBIT](DEBUG): Construct Startup Built In Test. 2020-02-21T01:11:59.018Z,1582247519.018 [SBIT] Loaded 2020-02-21T01:11:59.019Z,1582247519.019 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-02-21T01:11:59.019Z,1582247519.019 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-02-21T01:11:59.032Z,1582247519.032 [IBIT] Loaded 2020-02-21T01:11:59.032Z,1582247519.032 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-02-21T01:11:59.035Z,1582247519.035 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-02-21T01:11:59.170Z,1582247519.170 [CBIT] Loaded 2020-02-21T01:11:59.170Z,1582247519.170 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-02-21T01:11:59.170Z,1582247519.170 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-02-21T01:11:59.171Z,1582247519.171 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-02-21T01:11:59.273Z,1582247519.273 [BuoyancyServo] Loaded 2020-02-21T01:11:59.273Z,1582247519.273 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-02-21T01:11:59.285Z,1582247519.285 [ElevatorServo] Loaded 2020-02-21T01:11:59.285Z,1582247519.285 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-02-21T01:11:59.296Z,1582247519.296 [MassServo] Loaded 2020-02-21T01:11:59.296Z,1582247519.296 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-02-21T01:11:59.307Z,1582247519.307 [RudderServo] Loaded 2020-02-21T01:11:59.307Z,1582247519.307 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-02-21T01:11:59.317Z,1582247519.317 [ThrusterServo] Loaded 2020-02-21T01:11:59.317Z,1582247519.317 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-02-21T01:11:59.318Z,1582247519.318 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-02-21T01:11:59.318Z,1582247519.318 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-02-21T01:11:59.331Z,1582247519.331 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-02-21T01:11:59.331Z,1582247519.331 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-02-21T01:11:59.542Z,1582247519.542 [BackSeatDriver] Loaded 2020-02-21T01:11:59.543Z,1582247519.543 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-02-21T01:11:59.544Z,1582247519.544 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40A9C4E0 2020-02-21T01:11:59.544Z,1582247519.544 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 2072 2020-02-21T01:11:59.553Z,1582247519.553 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-02-21T01:11:59.558Z,1582247519.558 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-02-21T01:11:59.559Z,1582247519.559 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-02-21T01:11:59.564Z,1582247519.564 [CTD_Seabird](INFO): created writer for : depth 2020-02-21T01:11:59.564Z,1582247519.564 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-02-21T01:11:59.569Z,1582247519.569 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-02-21T01:11:59.570Z,1582247519.570 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-02-21T01:11:59.575Z,1582247519.575 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-02-21T01:11:59.575Z,1582247519.575 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-02-21T01:11:59.580Z,1582247519.580 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-02-21T01:11:59.581Z,1582247519.581 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-02-21T01:11:59.587Z,1582247519.587 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-02-21T01:11:59.587Z,1582247519.587 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-02-21T01:11:59.592Z,1582247519.592 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-02-21T01:11:59.619Z,1582247519.619 [CTD_Seabird] Loaded 2020-02-21T01:11:59.619Z,1582247519.619 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-02-21T01:11:59.620Z,1582247519.620 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ACC4E0 2020-02-21T01:11:59.620Z,1582247519.620 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2073 2020-02-21T01:11:59.650Z,1582247519.650 [ESPComponent] Loaded 2020-02-21T01:11:59.650Z,1582247519.650 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-02-21T01:11:59.657Z,1582247519.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-02-21T01:11:59.657Z,1582247519.657 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-02-21T01:11:59.661Z,1582247519.661 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-02-21T01:11:59.661Z,1582247519.661 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-02-21T01:11:59.665Z,1582247519.665 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-02-21T01:11:59.665Z,1582247519.665 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-02-21T01:11:59.670Z,1582247519.670 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-02-21T01:11:59.670Z,1582247519.670 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-02-21T01:11:59.674Z,1582247519.674 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-02-21T01:11:59.674Z,1582247519.674 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-02-21T01:11:59.678Z,1582247519.678 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-02-21T01:11:59.679Z,1582247519.679 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-02-21T01:11:59.683Z,1582247519.683 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-02-21T01:11:59.683Z,1582247519.683 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-02-21T01:11:59.687Z,1582247519.687 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-02-21T01:11:59.691Z,1582247519.691 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-02-21T01:11:59.692Z,1582247519.692 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-02-21T01:11:59.692Z,1582247519.692 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-02-21T01:11:59.696Z,1582247519.696 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-02-21T01:11:59.696Z,1582247519.696 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-02-21T01:11:59.700Z,1582247519.700 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-02-21T01:11:59.700Z,1582247519.700 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-02-21T01:11:59.705Z,1582247519.705 [WetLabsBB2FL] Loaded 2020-02-21T01:11:59.705Z,1582247519.705 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-02-21T01:11:59.706Z,1582247519.706 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AFC4E0 2020-02-21T01:11:59.706Z,1582247519.706 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2074 2020-02-21T01:11:59.720Z,1582247519.720 [WetLabsUBAT..SerialDriver](INFO): Created logger 2020-02-21T01:11:59.720Z,1582247519.720 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2020-02-21T01:11:59.721Z,1582247519.721 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2020-02-21T01:11:59.726Z,1582247519.726 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2020-02-21T01:11:59.726Z,1582247519.726 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2020-02-21T01:11:59.726Z,1582247519.726 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2020-02-21T01:11:59.730Z,1582247519.730 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2020-02-21T01:11:59.730Z,1582247519.730 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2020-02-21T01:11:59.735Z,1582247519.735 [WetLabsUBAT] Loaded 2020-02-21T01:11:59.735Z,1582247519.735 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2020-02-21T01:11:59.736Z,1582247519.736 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B2C4E0 2020-02-21T01:11:59.736Z,1582247519.736 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 2075 2020-02-21T01:11:59.737Z,1582247519.737 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-02-21T01:11:59.740Z,1582247519.740 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-02-21T01:11:59.741Z,1582247519.741 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-02-21T01:11:59.747Z,1582247519.747 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-02-21T01:11:59.748Z,1582247519.748 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5C4E0 2020-02-21T01:11:59.749Z,1582247519.749 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2076 2020-02-21T01:11:59.753Z,1582247519.753 [Supervisor](INFO): Main Thread ID is 803 2020-02-21T01:11:59.753Z,1582247519.753 [Supervisor](DEBUG): Running supervisor. 2020-02-21T01:11:59.754Z,1582247519.754 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2077 2020-02-21T01:11:59.757Z,1582247519.757 [controlThread ThreadHandler](INFO): Handler Thread ID is 2078 2020-02-21T01:11:59.757Z,1582247519.757 [controlThread](DEBUG): Initializing ControlThread 2020-02-21T01:11:59.758Z,1582247519.758 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-02-21T01:11:59.760Z,1582247519.760 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-02-21T01:11:59.760Z,1582247519.760 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-02-21T01:11:59.761Z,1582247519.761 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-02-21T01:11:59.761Z,1582247519.761 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-02-21T01:11:59.761Z,1582247519.761 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-02-21T01:11:59.761Z,1582247519.761 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-02-21T01:11:59.762Z,1582247519.762 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-02-21T01:11:59.762Z,1582247519.762 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-02-21T01:11:59.762Z,1582247519.762 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-02-21T01:11:59.768Z,1582247519.768 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-02-21T01:11:59.768Z,1582247519.768 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-02-21T01:11:59.768Z,1582247519.768 [SBIT](INFO): Initialize SBIT Component. 2020-02-21T01:11:59.769Z,1582247519.769 [SBIT](IMPORTANT): git: 2019-12-03-78-gb92fbb9 2020-02-21T01:11:59.769Z,1582247519.769 [SBIT](INFO): git hash: b92fbb99b4c8d1793352820b784fbd968d0634e4 2020-02-21T01:11:59.770Z,1582247519.770 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-02-21T01:11:59.770Z,1582247519.770 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2020-02-21T01:11:59.771Z,1582247519.771 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2020-02-21T01:11:59.771Z,1582247519.771 [IBIT](INFO): Initialize IBIT Component. 2020-02-21T01:11:59.772Z,1582247519.772 [CBIT](DEBUG): Initialize CBIT Component. 2020-02-21T01:11:59.773Z,1582247519.773 [logger ThreadHandler](INFO): Handler Thread ID is 2079 2020-02-21T01:11:59.785Z,1582247519.785 [CBIT](DEBUG): Initialized mux pins. 2020-02-21T01:11:59.785Z,1582247519.785 [CBIT](DEBUG): Initializing the watchdog timer. 2020-02-21T01:11:59.793Z,1582247519.793 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2080 2020-02-21T01:11:59.794Z,1582247519.794 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-02-21T01:11:59.805Z,1582247519.805 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2081 2020-02-21T01:11:59.809Z,1582247519.809 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-02-21T01:11:59.809Z,1582247519.809 [CBIT](DEBUG): Initializing heartbeat. 2020-02-21T01:11:59.817Z,1582247519.817 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 2082 2020-02-21T01:11:59.837Z,1582247519.837 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2083 2020-02-21T01:11:59.838Z,1582247519.838 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-02-21T01:11:59.842Z,1582247519.842 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2085 2020-02-21T01:11:59.843Z,1582247519.843 [WetLabsBB2FL](INFO): Powering down 2020-02-21T01:11:59.873Z,1582247519.873 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 2086 2020-02-21T01:11:59.881Z,1582247519.881 [CBIT](DEBUG): Deactivating GF circuits. 2020-02-21T01:11:59.881Z,1582247519.881 [CBIT](DEBUG): Deactivating emergency mode. 2020-02-21T01:11:59.893Z,1582247519.893 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2087 2020-02-21T01:11:59.896Z,1582247519.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-02-21T01:11:59.896Z,1582247519.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-02-21T01:11:59.896Z,1582247519.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-02-21T01:11:59.897Z,1582247519.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-02-21T01:11:59.897Z,1582247519.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-02-21T01:11:59.897Z,1582247519.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-02-21T01:11:59.897Z,1582247519.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-02-21T01:11:59.897Z,1582247519.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-02-21T01:11:59.897Z,1582247519.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-02-21T01:11:59.898Z,1582247519.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-02-21T01:11:59.898Z,1582247519.898 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-02-21T01:11:59.898Z,1582247519.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-02-21T01:11:59.898Z,1582247519.898 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-02-21T01:11:59.898Z,1582247519.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-02-21T01:11:59.898Z,1582247519.898 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-02-21T01:11:59.898Z,1582247519.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-02-21T01:11:59.917Z,1582247519.917 [CBIT](DEBUG): Backplane powered. 2020-02-21T01:11:59.919Z,1582247519.919 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-02-21T01:11:59.928Z,1582247519.928 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-02-21T01:11:59.940Z,1582247519.940 [MissionManager](DEBUG): 2020-02-21T01:11:59.940Z,1582247519.940 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-02-21T01:12:00.015Z,1582247520.015 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-02-21T01:12:00.033Z,1582247520.033 [Default:A.Wait](DEBUG): Construct Wait. 2020-02-21T01:12:00.034Z,1582247520.034 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-02-21T01:12:00.057Z,1582247520.057 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-02-21T01:12:00.059Z,1582247520.059 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-02-21T01:12:00.080Z,1582247520.080 [Default:E.Execute](DEBUG): Construct Execute. 2020-02-21T01:12:00.084Z,1582247520.084 [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-21T01:12:00.097Z,1582247520.097 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,ESPComponent,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-21T01:12:00.109Z,1582247520.109 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-02-21T01:12:00.162Z,1582247520.162 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-02-21T01:12:00.177Z,1582247520.177 [RDI_Pathfinder](INFO): Start 2020-02-21T01:12:00.188Z,1582247520.188 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-02-21T01:12:00.193Z,1582247520.193 [DAT](INFO): Powering up 2020-02-21T01:12:00.193Z,1582247520.193 [DAT](DEBUG): Initializing DAT. 2020-02-21T01:12:00.209Z,1582247520.209 [Radio_Surface](INFO): Powering up 2020-02-21T01:12:00.245Z,1582247520.245 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-02-21T01:12:00.254Z,1582247520.254 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-02-21T01:12:00.296Z,1582247520.296 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-02-21T01:12:00.305Z,1582247520.305 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-02-21T01:12:00.307Z,1582247520.307 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-02-21T01:12:00.317Z,1582247520.317 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-02-21T01:12:00.318Z,1582247520.318 [MassServo](DEBUG): Initializing EZServoServo. 2020-02-21T01:12:00.329Z,1582247520.329 [MassServo](DEBUG): Initializing MassServo. 2020-02-21T01:12:00.330Z,1582247520.330 [RudderServo](DEBUG): Initializing EZServoServo. 2020-02-21T01:12:00.341Z,1582247520.341 [RudderServo](DEBUG): Initializing RudderServo. 2020-02-21T01:12:00.342Z,1582247520.342 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-02-21T01:12:00.359Z,1582247520.359 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-02-21T01:12:00.530Z,1582247520.530 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-02-21T01:12:00.530Z,1582247520.530 [DropWeight] Hardware Fault, FailCount= 1 2020-02-21T01:12:00.530Z,1582247520.530 [DropWeight](ERROR): Hardware Fault 2020-02-21T01:12:00.545Z,1582247520.545 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:00.549Z,1582247520.549 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-02-21T01:12:00.568Z,1582247520.568 [CommandLine](FAULT): Scheduling is paused 2020-02-21T01:12:00.568Z,1582247520.568 [CBIT](INFO): Critical error at 20200221T011200 2020-02-21T01:12:00.569Z,1582247520.569 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-02-21T01:12:00.571Z,1582247520.571 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-02-21T01:12:00.572Z,1582247520.572 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-02-21T01:12:00.929Z,1582247520.929 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:01.269Z,1582247521.269 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-02-21T01:12:01.269Z,1582247521.269 [RudderServo](FAULT): Rudder failed to initialize 2020-02-21T01:12:01.269Z,1582247521.269 [RudderServo] Communications Fault, FailCount= 1 2020-02-21T01:12:01.269Z,1582247521.269 [RudderServo](ERROR): Communications Fault 2020-02-21T01:12:01.378Z,1582247521.378 [CBIT](INFO): Critical error at 20200221T011200 2020-02-21T01:12:01.381Z,1582247521.381 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-02-21T01:12:01.409Z,1582247521.409 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:01.542Z,1582247521.542 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-02-21T01:12:01.542Z,1582247521.542 [RudderServo](INFO): Powering down 2020-02-21T01:12:01.809Z,1582247521.809 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:02.212Z,1582247522.212 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:02.224Z,1582247522.224 [RudderServo](DEBUG): Initializing EZServoServo. 2020-02-21T01:12:02.342Z,1582247522.342 [RudderServo](DEBUG): Initializing RudderServo. 2020-02-21T01:12:02.346Z,1582247522.346 [CBIT](INFO): Clearing failed state for component RudderServo 2020-02-21T01:12:02.346Z,1582247522.346 [RudderServo] No Fault, FailCount= 1 2020-02-21T01:12:02.616Z,1582247522.616 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:03.020Z,1582247523.020 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:03.492Z,1582247523.492 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:03.853Z,1582247523.853 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:04.256Z,1582247524.256 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:04.725Z,1582247524.725 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:05.076Z,1582247525.076 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:05.481Z,1582247525.481 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:05.885Z,1582247525.885 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:06.289Z,1582247526.289 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:06.701Z,1582247526.701 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:07.097Z,1582247527.097 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:07.501Z,1582247527.501 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:07.917Z,1582247527.917 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:08.296Z,1582247528.296 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:08.698Z,1582247528.698 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:09.088Z,1582247529.088 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:09.515Z,1582247529.515 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:09.893Z,1582247529.893 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:10.296Z,1582247530.296 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:10.734Z,1582247530.734 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:10.734Z,1582247530.734 [DAT](DEBUG): 2020-02-21T01:12:10.735Z,1582247530.735 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-02-21T01:12:11.104Z,1582247531.104 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:11.504Z,1582247531.504 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:11.912Z,1582247531.912 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:11.913Z,1582247531.913 [DAT](DEBUG): MF Frequency Band 2020-02-21T01:12:11.914Z,1582247531.914 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21 2020-02-21T01:12:11.915Z,1582247531.915 [DAT](DEBUG): Feb 21 2020 01:01:22 2020-02-21T01:12:12.316Z,1582247532.316 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:12.721Z,1582247532.721 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:12.724Z,1582247532.724 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-21T01:12:12.726Z,1582247532.726 [DAT](DEBUG): Features enabled [Bearing] 2020-02-21T01:12:12.727Z,1582247532.727 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-02-21T01:12:12.727Z,1582247532.727 [DAT](INFO): commRate: 800 2020-02-21T01:12:13.135Z,1582247533.135 [DAT](INFO): entering command mode 2020-02-21T01:12:13.546Z,1582247533.546 [DAT](DEBUG): checking for command mode acknowledgment 2020-02-21T01:12:13.932Z,1582247533.932 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:13.933Z,1582247533.933 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:13.934Z,1582247533.934 [DAT](DEBUG): checking for command mode acknowledgment 2020-02-21T01:12:13.934Z,1582247533.934 [DAT](INFO): command mode acknowledged 2020-02-21T01:12:13.935Z,1582247533.935 [DAT](INFO): setting verbose to 3 2020-02-21T01:12:14.337Z,1582247534.337 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-02-21T01:12:14.338Z,1582247534.338 [DAT](INFO): set verbose to 3 2020-02-21T01:12:14.750Z,1582247534.750 [DAT](INFO): setting transmit power to 8 2020-02-21T01:12:15.152Z,1582247535.152 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:15.154Z,1582247535.154 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:15.155Z,1582247535.155 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-02-21T01:12:15.548Z,1582247535.548 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-02-21T01:12:15.549Z,1582247535.549 [DAT](INFO): set transmit power to 8 2020-02-21T01:12:15.953Z,1582247535.953 [DAT](INFO): setting local address to 9 2020-02-21T01:12:16.352Z,1582247536.352 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:16.354Z,1582247536.354 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:16.354Z,1582247536.354 [DAT](DEBUG): checking for local address setting acknowledgment 2020-02-21T01:12:16.762Z,1582247536.762 [DAT](DEBUG): checking for local address setting acknowledgment 2020-02-21T01:12:16.762Z,1582247536.762 [DAT](INFO): set local address to 9 2020-02-21T01:12:17.565Z,1582247537.565 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:17.566Z,1582247537.566 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:17.973Z,1582247537.973 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:17.974Z,1582247537.974 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:18.776Z,1582247538.776 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:18.777Z,1582247538.777 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:19.989Z,1582247539.989 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:19.990Z,1582247539.990 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:20.801Z,1582247540.801 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:20.803Z,1582247540.803 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:22.009Z,1582247542.009 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:22.010Z,1582247542.010 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:22.417Z,1582247542.417 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-21T01:12:22.417Z,1582247542.417 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-02-21T01:12:22.417Z,1582247542.417 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-21T01:12:22.445Z,1582247542.445 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-21T01:12:22.822Z,1582247542.822 [RDI_Pathfinder](INFO): Stop 2020-02-21T01:12:22.893Z,1582247542.893 [RDI_Pathfinder](INFO): Powering down 2020-02-21T01:12:23.221Z,1582247543.221 [RDI_Pathfinder](INFO): Stopping 2020-02-21T01:12:23.624Z,1582247543.624 [RDI_Pathfinder](INFO): Stopped 2020-02-21T01:12:23.680Z,1582247543.680 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-21T01:12:23.680Z,1582247543.680 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-21T01:12:24.034Z,1582247544.034 [RDI_Pathfinder](INFO): Start 2020-02-21T01:12:24.438Z,1582247544.438 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:24.836Z,1582247544.836 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:25.240Z,1582247545.240 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:25.652Z,1582247545.652 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:26.057Z,1582247546.057 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:26.467Z,1582247546.467 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:26.860Z,1582247546.860 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:27.250Z,1582247547.250 [NAL9602](INFO): Powering up NAL9602 2020-02-21T01:12:27.266Z,1582247547.266 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:27.670Z,1582247547.670 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:28.076Z,1582247548.076 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:28.472Z,1582247548.472 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:28.880Z,1582247548.880 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:29.285Z,1582247549.285 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:29.690Z,1582247549.690 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:30.088Z,1582247550.088 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:30.492Z,1582247550.492 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:30.901Z,1582247550.901 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:31.307Z,1582247551.307 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:31.704Z,1582247551.704 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:32.109Z,1582247552.109 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:32.517Z,1582247552.517 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:32.923Z,1582247552.923 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:33.320Z,1582247553.320 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:33.724Z,1582247553.724 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:34.132Z,1582247554.132 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:34.532Z,1582247554.532 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:34.937Z,1582247554.937 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:35.342Z,1582247555.342 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:35.748Z,1582247555.748 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:36.156Z,1582247556.156 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:36.552Z,1582247556.552 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:36.555Z,1582247556.555 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-21T01:12:37.782Z,1582247557.782 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:37.783Z,1582247557.783 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:38.158Z,1582247558.158 [NAL9602](INFO): NAL9602 initialized 2020-02-21T01:12:39.004Z,1582247559.004 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:39.006Z,1582247559.006 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:39.415Z,1582247559.415 [SBIT](IMPORTANT): Beginning Startup BIT 2020-02-21T01:12:39.423Z,1582247559.423 [CBIT](IMPORTANT): Beginning ground fault scan 2020-02-21T01:12:40.202Z,1582247560.202 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:40.204Z,1582247560.204 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:41.030Z,1582247561.030 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:41.031Z,1582247561.031 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:41.444Z,1582247561.444 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:41.446Z,1582247561.446 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:42.742Z,1582247562.742 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:42.743Z,1582247562.743 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:43.545Z,1582247563.545 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:43.547Z,1582247563.547 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:44.762Z,1582247564.762 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:44.763Z,1582247564.763 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:45.561Z,1582247565.561 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:12:45.562Z,1582247565.562 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:12:46.386Z,1582247566.386 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-21T01:12:46.386Z,1582247566.386 [RDI_Pathfinder] Communications Fault, FailCount= 2 2020-02-21T01:12:46.386Z,1582247566.386 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-21T01:12:46.466Z,1582247566.466 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-21T01:12:46.783Z,1582247566.783 [RDI_Pathfinder](INFO): Stop 2020-02-21T01:12:46.853Z,1582247566.853 [RDI_Pathfinder](INFO): Powering down 2020-02-21T01:12:47.191Z,1582247567.191 [RDI_Pathfinder](INFO): Stopping 2020-02-21T01:12:47.602Z,1582247567.602 [RDI_Pathfinder](INFO): Stopped 2020-02-21T01:12:47.678Z,1582247567.678 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-21T01:12:47.678Z,1582247567.678 [RDI_Pathfinder] No Fault, FailCount= 2 2020-02-21T01:12:47.984Z,1582247567.984 [RDI_Pathfinder](INFO): Start 2020-02-21T01:12:48.393Z,1582247568.393 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:48.797Z,1582247568.797 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:49.203Z,1582247569.203 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:49.610Z,1582247569.610 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:50.010Z,1582247570.010 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:50.412Z,1582247570.412 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:50.490Z,1582247570.490 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.016374 CHAN A1 (24V): -0.000255 CHAN A2 (12V): -0.007607 CHAN A3 (5V): -0.000960 CHAN B0 (3.3V): 0.000756 CHAN B1 (3.15aV): -0.000261 CHAN B2 (3.15bV): 0.000001 CHAN B3 (GND): 0.002027 OPEN: 0.005314 Full Scale Calc: 4.765 mA, -1.589 mA 2020-02-21T01:12:50.818Z,1582247570.818 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:51.252Z,1582247571.252 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:51.641Z,1582247571.641 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:52.055Z,1582247572.055 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:52.445Z,1582247572.445 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:52.839Z,1582247572.839 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:53.247Z,1582247573.247 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:53.641Z,1582247573.641 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:54.053Z,1582247574.053 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:54.456Z,1582247574.456 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:55.033Z,1582247575.033 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:55.424Z,1582247575.424 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:55.832Z,1582247575.832 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:56.251Z,1582247576.251 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:56.655Z,1582247576.655 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:57.057Z,1582247577.057 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:57.463Z,1582247577.463 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:57.849Z,1582247577.849 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:58.258Z,1582247578.258 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:58.663Z,1582247578.663 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:59.057Z,1582247579.057 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:59.464Z,1582247579.464 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:12:59.871Z,1582247579.871 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:13:00.278Z,1582247580.279 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:13:00.673Z,1582247580.673 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:00.676Z,1582247580.676 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-21T01:13:01.504Z,1582247581.504 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:01.505Z,1582247581.505 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:13:02.697Z,1582247582.697 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:02.698Z,1582247582.698 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:13:03.920Z,1582247583.921 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:03.922Z,1582247583.922 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:13:05.147Z,1582247585.147 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:05.148Z,1582247585.148 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:13:05.546Z,1582247585.546 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:05.547Z,1582247585.547 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:13:06.357Z,1582247586.357 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:06.359Z,1582247586.359 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:13:07.545Z,1582247587.545 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:07.546Z,1582247587.546 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:13:08.370Z,1582247588.370 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:08.372Z,1582247588.372 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:13:09.588Z,1582247589.588 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:13:09.589Z,1582247589.589 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:13:10.397Z,1582247590.397 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-21T01:13:10.398Z,1582247590.398 [RDI_Pathfinder] Communications Fault, FailCount= 3 2020-02-21T01:13:10.398Z,1582247590.398 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-21T01:13:10.490Z,1582247590.490 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-21T01:13:10.491Z,1582247590.491 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-21T01:13:10.787Z,1582247590.787 [RDI_Pathfinder](INFO): Stop 2020-02-21T01:13:10.853Z,1582247590.853 [RDI_Pathfinder](INFO): Powering down 2020-02-21T01:13:10.987Z,1582247590.987 [CBIT](INFO): Critical error at 20200221T011310 2020-02-21T01:13:11.181Z,1582247591.181 [RDI_Pathfinder](INFO): Stopping 2020-02-21T01:13:11.590Z,1582247591.590 [RDI_Pathfinder](INFO): Stopped 2020-02-21T01:13:32.742Z,1582247612.742 [SBIT](IMPORTANT): SBIT PASSED 2020-02-21T01:13:32.743Z,1582247612.743 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-02-21T01:13:32.743Z,1582247612.743 [SBIT](IMPORTANT): BR_Ping1D.loadAtStartup=0 bool; 2020-02-21T01:13:32.744Z,1582247612.744 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere; 2020-02-21T01:13:32.744Z,1582247612.744 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum; 2020-02-21T01:13:32.744Z,1582247612.744 [SBIT](IMPORTANT): DAT.transponderAddress=4 enum; 2020-02-21T01:13:32.744Z,1582247612.744 [SBIT](IMPORTANT): DAT.verbosity=3 count; 2020-02-21T01:13:32.744Z,1582247612.744 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2020-02-21T01:13:32.744Z,1582247612.744 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute; 2020-02-21T01:13:32.744Z,1582247612.744 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2020-02-21T01:13:32.744Z,1582247612.744 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_temperature 0.025000 kelvin; 2020-02-21T01:13:32.744Z,1582247612.744 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 50.000000 liter_per_second; 2020-02-21T01:13:32.745Z,1582247612.745 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 15.000000 meter; 2020-02-21T01:13:32.745Z,1582247612.745 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2020-02-21T01:13:32.745Z,1582247612.745 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour; 2020-02-21T01:13:32.745Z,1582247612.745 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt; 2020-02-21T01:13:32.745Z,1582247612.745 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=225 cubic_centimeter; 2020-02-21T01:13:32.745Z,1582247612.745 [SBIT](IMPORTANT): VerticalControl.massDefault=5 millimeter; 2020-02-21T01:13:32.745Z,1582247612.745 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool; 2020-02-21T01:13:33.196Z,1582247613.196 [MissionManager](IMPORTANT): Started mission Startup 2020-02-21T01:13:33.196Z,1582247613.196 [Startup] Running Loop=1 2020-02-21T01:13:33.196Z,1582247613.196 [Startup](DEBUG): Aggregate::initialize Startup 2020-02-21T01:13:33.196Z,1582247613.196 [Startup:A.GoToSurface] Running Loop=1 2020-02-21T01:13:33.196Z,1582247613.196 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-02-21T01:13:33.198Z,1582247613.198 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-02-21T01:13:33.198Z,1582247613.198 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-02-21T01:13:33.198Z,1582247613.198 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-02-21T01:13:33.199Z,1582247613.199 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-02-21T01:13:33.199Z,1582247613.199 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-02-21T01:13:33.200Z,1582247613.200 [Startup:StartupSatComms] Running Loop=1 2020-02-21T01:13:33.200Z,1582247613.200 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-02-21T01:13:33.201Z,1582247613.201 [Startup:StartupSatComms:A] Running Loop=1 2020-02-21T01:13:33.550Z,1582247613.550 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-02-21T01:14:33.320Z,1582247673.320 [Startup:StartupSatComms:A](INFO): Timed out from 2020-02-21T01:13:33.2Z 2020-02-21T01:14:33.320Z,1582247673.320 [Startup:StartupSatComms:A] Stopped 2020-02-21T01:14:33.320Z,1582247673.320 [Startup:StartupSatComms:B] Running Loop=1 2020-02-21T01:14:33.730Z,1582247673.730 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-02-21T01:14:59.984Z,1582247699.984 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-02-21T01:14:59.984Z,1582247699.984 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-21T01:14:59.994Z,1582247699.994 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-21T01:15:00.397Z,1582247700.397 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-21T01:15:00.397Z,1582247700.397 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-02-21T01:15:23.931Z,1582247723.931 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004818 2020-02-21T01:15:31.157Z,1582247731.157 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20200221T004624/Courier0010.lzma 2020-02-21T01:15:33.163Z,1582247733.163 [DataOverHttps](INFO): Moved sent file to Logs/20200221T004624/Courier0010.lzma.bak 2020-02-21T01:15:33.163Z,1582247733.163 [DataOverHttps](INFO): SBD MOMSN=12255905 2020-02-21T01:15:33.512Z,1582247733.512 [Startup:StartupSatComms:B](INFO): Timed out from 2020-02-21T01:14:33.3Z 2020-02-21T01:15:33.512Z,1582247733.512 [Startup:StartupSatComms:B] Stopped 2020-02-21T01:15:33.513Z,1582247733.513 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-02-21T01:15:33.513Z,1582247733.513 [Startup:StartupSatComms] Stopped 2020-02-21T01:15:33.513Z,1582247733.513 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-02-21T01:15:33.514Z,1582247733.514 [Startup](INFO): Completed Startup 2020-02-21T01:15:33.514Z,1582247733.514 [MissionManager](INFO): Startup is completed. 2020-02-21T01:15:33.514Z,1582247733.514 [MissionManager](INFO): Uninitializing Mission Startup 2020-02-21T01:15:33.514Z,1582247733.514 [Startup] Stopped 2020-02-21T01:15:33.514Z,1582247733.514 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-02-21T01:15:33.514Z,1582247733.514 [Startup:A.GoToSurface] Stopped 2020-02-21T01:15:33.514Z,1582247733.514 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-02-21T01:15:34.004Z,1582247734.004 [MissionManager](IMPORTANT): Started mission Default 2020-02-21T01:15:34.005Z,1582247734.005 [Default] Running Loop=1 2020-02-21T01:15:34.005Z,1582247734.005 [Default](DEBUG): Aggregate::initialize Default 2020-02-21T01:15:34.005Z,1582247734.005 [Default:B.GoToSurface] Running Loop=1 2020-02-21T01:15:34.005Z,1582247734.005 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-02-21T01:15:34.005Z,1582247734.005 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-02-21T01:15:34.006Z,1582247734.006 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-02-21T01:15:34.006Z,1582247734.006 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-02-21T01:15:34.006Z,1582247734.006 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-02-21T01:15:34.007Z,1582247734.007 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-02-21T01:15:34.007Z,1582247734.007 [Default:A.Wait] Running Loop=1 2020-02-21T01:15:34.007Z,1582247734.007 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-02-21T01:15:47.373Z,1582247747.373 [Default:A.Wait](INFO): Done Waiting. 2020-02-21T01:15:47.374Z,1582247747.374 [Default:A.Wait] Stopped 2020-02-21T01:15:47.374Z,1582247747.374 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-02-21T01:15:47.720Z,1582247747.720 [Default:CheckIn] Running Loop=1 2020-02-21T01:15:47.721Z,1582247747.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-02-21T01:15:47.721Z,1582247747.721 [Default:CheckIn:Read_GPS] Running Loop=1 2020-02-21T01:15:48.123Z,1582247748.123 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-02-21T01:15:48.587Z,1582247748.587 [CommandLine](IMPORTANT): got command failComponent 2020-02-21T01:15:48.587Z,1582247748.587 [CommandLine](IMPORTANT): Failed components: 2020-02-21T01:15:48.588Z,1582247748.588 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-02-21T01:15:48.588Z,1582247748.588 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault 2020-02-21T01:15:53.075Z,1582247753.075 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder 2020-02-21T01:15:53.075Z,1582247753.075 [RDI_Pathfinder] No Fault, FailCount= 3 2020-02-21T01:15:53.076Z,1582247753.076 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault 2020-02-21T01:15:53.360Z,1582247753.360 [RDI_Pathfinder](INFO): Start 2020-02-21T01:15:53.760Z,1582247753.760 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:54.166Z,1582247754.166 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:54.564Z,1582247754.564 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:54.983Z,1582247754.983 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:55.391Z,1582247755.391 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:55.795Z,1582247755.795 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:56.198Z,1582247756.198 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:56.588Z,1582247756.588 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:56.999Z,1582247756.999 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:57.402Z,1582247757.402 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:57.801Z,1582247757.801 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:58.204Z,1582247758.204 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:58.610Z,1582247758.610 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:59.014Z,1582247759.014 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:59.413Z,1582247759.413 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:15:59.820Z,1582247759.820 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:00.239Z,1582247760.239 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:00.641Z,1582247760.641 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:01.050Z,1582247761.050 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:01.447Z,1582247761.447 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:01.846Z,1582247761.846 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:02.240Z,1582247762.240 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:02.649Z,1582247762.649 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:03.056Z,1582247763.056 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:03.459Z,1582247763.459 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:03.886Z,1582247763.886 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:04.261Z,1582247764.261 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:04.669Z,1582247764.669 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:05.072Z,1582247765.072 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:05.494Z,1582247765.494 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:05.909Z,1582247765.909 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2020-02-21T01:16:05.909Z,1582247765.909 [RDI_Pathfinder] Data Fault, FailCount= 4 2020-02-21T01:16:05.909Z,1582247765.909 [RDI_Pathfinder](ERROR): Data Fault 2020-02-21T01:16:05.930Z,1582247765.930 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2020-02-21T01:16:05.931Z,1582247765.931 [CBIT](CRITICAL): Data Fault in component: RDI_Pathfinder 2020-02-21T01:16:06.295Z,1582247766.295 [RDI_Pathfinder](INFO): Stop 2020-02-21T01:16:06.362Z,1582247766.362 [RDI_Pathfinder](INFO): Powering down 2020-02-21T01:16:06.386Z,1582247766.386 [CBIT](INFO): Critical error at 20200221T011605 2020-02-21T01:16:06.707Z,1582247766.707 [RDI_Pathfinder](INFO): Stopping 2020-02-21T01:16:07.098Z,1582247767.098 [RDI_Pathfinder](INFO): Stopped 2020-02-21T01:16:17.072Z,1582247777.072 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder 2020-02-21T01:16:17.072Z,1582247777.072 [RDI_Pathfinder] No Fault, FailCount= 4 2020-02-21T01:16:17.072Z,1582247777.072 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault 2020-02-21T01:16:17.213Z,1582247777.213 [RDI_Pathfinder](INFO): Start 2020-02-21T01:16:17.600Z,1582247777.600 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:18.006Z,1582247778.006 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:18.408Z,1582247778.408 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:18.808Z,1582247778.808 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:19.212Z,1582247779.212 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:19.619Z,1582247779.619 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:20.024Z,1582247780.024 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:20.439Z,1582247780.439 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:20.828Z,1582247780.828 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:21.251Z,1582247781.251 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:21.648Z,1582247781.648 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:22.054Z,1582247782.054 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:22.451Z,1582247782.451 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:22.849Z,1582247782.849 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:23.258Z,1582247783.258 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:23.653Z,1582247783.653 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:24.060Z,1582247784.060 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:24.470Z,1582247784.470 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:24.868Z,1582247784.868 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:25.269Z,1582247785.269 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:25.676Z,1582247785.676 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:26.086Z,1582247786.086 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:26.500Z,1582247786.500 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:26.911Z,1582247786.911 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:27.307Z,1582247787.307 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:27.726Z,1582247787.726 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:28.114Z,1582247788.114 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:28.505Z,1582247788.505 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:28.905Z,1582247788.905 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:29.318Z,1582247789.318 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:16:29.733Z,1582247789.733 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:29.736Z,1582247789.736 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-21T01:16:30.934Z,1582247790.934 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:30.935Z,1582247790.935 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:16:32.151Z,1582247792.151 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:32.152Z,1582247792.152 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:16:33.353Z,1582247793.353 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:33.354Z,1582247793.354 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:16:34.568Z,1582247794.568 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:34.570Z,1582247794.570 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:16:34.969Z,1582247794.969 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:34.971Z,1582247794.971 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:16:35.785Z,1582247795.785 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:35.786Z,1582247795.786 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:16:37.003Z,1582247797.003 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:37.004Z,1582247797.004 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:16:37.810Z,1582247797.810 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:37.811Z,1582247797.811 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:16:39.013Z,1582247799.013 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:16:39.015Z,1582247799.015 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:16:39.423Z,1582247799.423 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-21T01:16:39.423Z,1582247799.423 [RDI_Pathfinder] Communications Fault, FailCount= 5 2020-02-21T01:16:39.423Z,1582247799.423 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-21T01:16:39.440Z,1582247799.440 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-21T01:16:39.440Z,1582247799.440 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-21T01:16:39.817Z,1582247799.817 [RDI_Pathfinder](INFO): Stop 2020-02-21T01:16:39.885Z,1582247799.885 [RDI_Pathfinder](INFO): Powering down 2020-02-21T01:16:39.900Z,1582247799.900 [CBIT](INFO): Critical error at 20200221T011639 2020-02-21T01:16:40.221Z,1582247800.221 [RDI_Pathfinder](INFO): Stopping 2020-02-21T01:16:40.632Z,1582247800.632 [RDI_Pathfinder](INFO): Stopped 2020-02-21T01:17:41.610Z,1582247861.610 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-02-21T01:18:01.037Z,1582247881.037 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-02-21T01:18:01.037Z,1582247881.037 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-21T01:18:01.047Z,1582247881.047 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-21T01:18:01.452Z,1582247881.452 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-21T01:18:01.452Z,1582247881.452 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-02-21T01:19:29.518Z,1582247969.518 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-02-21T01:19:29.521Z,1582247969.521 [BPC1](INFO): Received data from all battery sticks. 2020-02-21T01:19:39.630Z,1582247979.630 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-21T01:19:39.630Z,1582247979.630 [RDI_Pathfinder] No Fault, FailCount= 5 2020-02-21T01:19:40.007Z,1582247980.007 [RDI_Pathfinder](INFO): Start 2020-02-21T01:19:40.423Z,1582247980.423 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:40.821Z,1582247980.821 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:41.212Z,1582247981.212 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:41.614Z,1582247981.614 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:42.021Z,1582247982.021 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:42.433Z,1582247982.433 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:42.840Z,1582247982.840 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:43.258Z,1582247983.258 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:43.651Z,1582247983.651 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:44.052Z,1582247984.052 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:44.444Z,1582247984.444 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:44.845Z,1582247984.845 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:45.253Z,1582247985.253 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:45.683Z,1582247985.683 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:46.096Z,1582247986.096 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:46.483Z,1582247986.483 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:46.879Z,1582247986.879 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:47.274Z,1582247987.274 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:47.683Z,1582247987.683 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:48.096Z,1582247988.096 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:48.484Z,1582247988.484 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:48.899Z,1582247988.899 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:49.307Z,1582247989.307 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:49.718Z,1582247989.718 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:50.096Z,1582247990.096 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:50.505Z,1582247990.505 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:50.915Z,1582247990.915 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:51.308Z,1582247991.308 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:51.731Z,1582247991.731 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:52.143Z,1582247992.143 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:52.538Z,1582247992.538 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2020-02-21T01:19:52.538Z,1582247992.538 [RDI_Pathfinder] Data Fault, FailCount= 1 2020-02-21T01:19:52.538Z,1582247992.538 [RDI_Pathfinder](ERROR): Data Fault 2020-02-21T01:19:52.558Z,1582247992.558 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2020-02-21T01:19:52.924Z,1582247992.924 [RDI_Pathfinder](INFO): Stop 2020-02-21T01:19:52.994Z,1582247992.994 [RDI_Pathfinder](INFO): Powering down 2020-02-21T01:19:53.328Z,1582247993.328 [RDI_Pathfinder](INFO): Stopping 2020-02-21T01:19:53.737Z,1582247993.737 [RDI_Pathfinder](INFO): Stopped 2020-02-21T01:19:53.753Z,1582247993.753 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-21T01:19:53.753Z,1582247993.753 [RDI_Pathfinder] No Fault, FailCount= 1 2020-02-21T01:19:54.152Z,1582247994.152 [RDI_Pathfinder](INFO): Start 2020-02-21T01:19:54.567Z,1582247994.567 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:54.959Z,1582247994.959 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:55.357Z,1582247995.357 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:55.765Z,1582247995.765 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:56.180Z,1582247996.180 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:56.568Z,1582247996.568 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:56.987Z,1582247996.987 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:57.385Z,1582247997.385 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:57.794Z,1582247997.794 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:58.180Z,1582247998.180 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:58.584Z,1582247998.584 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:58.990Z,1582247998.990 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:59.393Z,1582247999.393 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:19:59.792Z,1582247999.792 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:00.211Z,1582248000.211 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:00.619Z,1582248000.619 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:01.026Z,1582248001.026 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:01.412Z,1582248001.412 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:01.821Z,1582248001.821 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:02.224Z,1582248002.224 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:02.620Z,1582248002.620 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:03.039Z,1582248003.039 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:03.451Z,1582248003.451 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:03.849Z,1582248003.849 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:04.236Z,1582248004.236 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:04.640Z,1582248004.640 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:05.050Z,1582248005.050 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:05.463Z,1582248005.463 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:05.871Z,1582248005.871 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:06.271Z,1582248006.271 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:06.679Z,1582248006.679 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:06.682Z,1582248006.682 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-21T01:20:07.881Z,1582248007.881 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:07.882Z,1582248007.882 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:09.110Z,1582248009.110 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:09.111Z,1582248009.111 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:10.303Z,1582248010.303 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:10.304Z,1582248010.304 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:11.138Z,1582248011.138 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:11.140Z,1582248011.140 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:11.544Z,1582248011.544 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:11.549Z,1582248011.549 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:12.724Z,1582248012.724 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:12.726Z,1582248012.726 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:13.549Z,1582248013.549 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:13.550Z,1582248013.550 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:14.760Z,1582248014.760 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:14.761Z,1582248014.761 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:15.561Z,1582248015.561 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:15.562Z,1582248015.562 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:16.364Z,1582248016.364 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-21T01:20:16.364Z,1582248016.364 [RDI_Pathfinder] Communications Fault, FailCount= 2 2020-02-21T01:20:16.364Z,1582248016.364 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-21T01:20:16.380Z,1582248016.380 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-21T01:20:16.775Z,1582248016.775 [RDI_Pathfinder](INFO): Stop 2020-02-21T01:20:16.845Z,1582248016.845 [RDI_Pathfinder](INFO): Powering down 2020-02-21T01:20:17.185Z,1582248017.185 [RDI_Pathfinder](INFO): Stopping 2020-02-21T01:20:17.589Z,1582248017.589 [RDI_Pathfinder](INFO): Stopped 2020-02-21T01:20:17.604Z,1582248017.604 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-02-21T01:20:17.604Z,1582248017.604 [RDI_Pathfinder] No Fault, FailCount= 2 2020-02-21T01:20:17.973Z,1582248017.973 [RDI_Pathfinder](INFO): Start 2020-02-21T01:20:18.381Z,1582248018.381 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:18.791Z,1582248018.791 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:19.189Z,1582248019.189 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:19.589Z,1582248019.589 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:20.011Z,1582248020.011 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:20.414Z,1582248020.414 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:20.821Z,1582248020.821 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:21.204Z,1582248021.204 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:21.613Z,1582248021.613 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:22.023Z,1582248022.023 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:22.416Z,1582248022.416 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:22.831Z,1582248022.831 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:23.243Z,1582248023.243 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:23.645Z,1582248023.645 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:24.033Z,1582248024.033 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:24.437Z,1582248024.437 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:24.845Z,1582248024.845 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:25.253Z,1582248025.253 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:25.648Z,1582248025.648 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:26.067Z,1582248026.067 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:26.483Z,1582248026.483 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:26.877Z,1582248026.877 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:27.268Z,1582248027.268 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:27.673Z,1582248027.673 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:28.078Z,1582248028.078 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:28.486Z,1582248028.486 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:28.895Z,1582248028.895 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:29.326Z,1582248029.326 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:29.715Z,1582248029.715 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:30.100Z,1582248030.100 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:20:30.500Z,1582248030.500 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:30.515Z,1582248030.515 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600 2020-02-21T01:20:31.728Z,1582248031.728 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:31.729Z,1582248031.729 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:32.552Z,1582248032.552 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:32.554Z,1582248032.554 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:34.138Z,1582248034.138 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:34.139Z,1582248034.139 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:34.972Z,1582248034.972 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:34.973Z,1582248034.973 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:35.375Z,1582248035.375 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:35.377Z,1582248035.377 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:36.568Z,1582248036.568 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:36.569Z,1582248036.569 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:37.369Z,1582248037.369 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:37.370Z,1582248037.370 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:38.598Z,1582248038.598 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:38.599Z,1582248038.599 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:39.393Z,1582248039.393 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:20:39.395Z,1582248039.395 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:20:40.197Z,1582248040.197 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-02-21T01:20:40.197Z,1582248040.197 [RDI_Pathfinder] Communications Fault, FailCount= 3 2020-02-21T01:20:40.198Z,1582248040.198 [RDI_Pathfinder](ERROR): Communications Fault 2020-02-21T01:20:40.240Z,1582248040.240 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-02-21T01:20:40.240Z,1582248040.240 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-02-21T01:20:40.601Z,1582248040.601 [RDI_Pathfinder](INFO): Stop 2020-02-21T01:20:40.669Z,1582248040.669 [RDI_Pathfinder](INFO): Powering down 2020-02-21T01:20:40.691Z,1582248040.691 [CBIT](INFO): Critical error at 20200221T012040 2020-02-21T01:20:41.019Z,1582248041.019 [RDI_Pathfinder](INFO): Stopping 2020-02-21T01:20:41.421Z,1582248041.421 [RDI_Pathfinder](INFO): Stopped 2020-02-21T01:20:47.910Z,1582248047.910 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-02-21T01:15:47.7Z 2020-02-21T01:20:47.910Z,1582248047.910 [Default:CheckIn:Read_GPS] Stopped 2020-02-21T01:20:47.910Z,1582248047.910 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-02-21T01:20:48.296Z,1582248048.296 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-02-21T01:20:59.524Z,1582248059.524 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200221T011149/Courier0000.lzma 2020-02-21T01:21:01.530Z,1582248061.530 [DataOverHttps](INFO): Moved sent file to Logs/20200221T011149/Courier0000.lzma.bak 2020-02-21T01:21:01.530Z,1582248061.530 [DataOverHttps](INFO): SBD MOMSN=12255929 2020-02-21T01:21:02.061Z,1582248062.061 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-02-21T01:21:02.061Z,1582248062.061 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-02-21T01:21:02.071Z,1582248062.071 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-02-21T01:21:02.482Z,1582248062.482 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-02-21T01:21:02.482Z,1582248062.482 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-02-21T01:21:18.173Z,1582248078.173 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20200221T011149/Courier0004.lzma 2020-02-21T01:21:20.178Z,1582248080.178 [DataOverHttps](INFO): Moved sent file to Logs/20200221T011149/Courier0004.lzma.bak 2020-02-21T01:21:20.178Z,1582248080.178 [DataOverHttps](INFO): SBD MOMSN=12255935 2020-02-21T01:21:36.565Z,1582248096.565 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20200221T004624/Express0011.lzma 2020-02-21T01:21:36.887Z,1582248096.887 [CommandLine](IMPORTANT): got command failComponent 2020-02-21T01:21:36.888Z,1582248096.888 [CommandLine](IMPORTANT): Failed components: 2020-02-21T01:21:36.888Z,1582248096.888 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-02-21T01:21:36.888Z,1582248096.888 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault 2020-02-21T01:21:38.571Z,1582248098.571 [DataOverHttps](INFO): Moved sent file to Logs/20200221T004624/Express0011.lzma.bak 2020-02-21T01:21:38.571Z,1582248098.571 [DataOverHttps](INFO): SBD MOMSN=12255940 2020-02-21T01:21:50.171Z,1582248110.171 [CommandLine](IMPORTANT): got command failComponent 2020-02-21T01:21:50.171Z,1582248110.171 [CommandLine](IMPORTANT): Failed components: 2020-02-21T01:21:50.171Z,1582248110.171 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-02-21T01:21:50.171Z,1582248110.171 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault 2020-02-21T01:21:54.557Z,1582248114.557 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder 2020-02-21T01:21:54.559Z,1582248114.559 [RDI_Pathfinder] No Fault, FailCount= 3 2020-02-21T01:21:54.561Z,1582248114.561 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault 2020-02-21T01:21:54.563Z,1582248114.563 [RDI_Pathfinder](INFO): Start 2020-02-21T01:21:54.957Z,1582248114.957 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:55.350Z,1582248115.350 [DataOverHttps](INFO): Sending 1094 bytes from file Logs/20200221T011149/Express0001.lzma 2020-02-21T01:21:55.385Z,1582248115.385 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:55.771Z,1582248115.771 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:56.183Z,1582248116.183 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:56.588Z,1582248116.588 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:56.972Z,1582248116.972 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:57.354Z,1582248117.354 [DataOverHttps](INFO): Moved sent file to Logs/20200221T011149/Express0001.lzma.bak 2020-02-21T01:21:57.354Z,1582248117.354 [DataOverHttps](INFO): SBD MOMSN=12255946 2020-02-21T01:21:57.386Z,1582248117.386 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:57.796Z,1582248117.796 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:58.207Z,1582248118.207 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:58.593Z,1582248118.593 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:59.011Z,1582248119.011 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:59.427Z,1582248119.427 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:21:59.821Z,1582248119.821 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:00.235Z,1582248120.235 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:00.613Z,1582248120.613 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:01.025Z,1582248121.025 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:01.431Z,1582248121.431 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:01.842Z,1582248121.842 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:02.243Z,1582248122.243 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:02.659Z,1582248122.659 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:03.044Z,1582248123.044 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:03.461Z,1582248123.461 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:03.849Z,1582248123.849 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:04.254Z,1582248124.254 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:04.659Z,1582248124.659 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:05.076Z,1582248125.076 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:05.492Z,1582248125.492 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:05.879Z,1582248125.879 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:06.291Z,1582248126.291 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:06.676Z,1582248126.676 [RDI_Pathfinder](INFO): Starting 2020-02-21T01:22:07.082Z,1582248127.082 [RDI_Pathfinder](INFO): Message in queue 2020-02-21T01:22:07.091Z,1582248127.091 [RDI_Pathfinder](ERROR): Failed to parse: 2020-02-21T01:22:07.484Z,1582248127.484 [RDI_Pathfinder](INF