2020-05-22T19:03:05.917Z,1590174185.917 [Supervisor](DEBUG): Initializing supervisor. 2020-05-22T19:03:05.920Z,1590174185.920 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-05-22T19:03:05.921Z,1590174185.921 [SyncHandler](INFO): Protected caller Thread ID is 806 2020-05-22T19:03:05.921Z,1590174185.921 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-05-22T19:03:05.923Z,1590174185.923 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-05-22T19:03:05.923Z,1590174185.923 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2020-05-22T19:03:05.926Z,1590174185.926 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-05-22T19:03:05.943Z,1590174185.943 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-05-22T19:03:05.944Z,1590174185.944 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-05-22T19:03:05.945Z,1590174185.945 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2020-05-22T19:03:05.946Z,1590174185.946 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-05-22T19:03:05.947Z,1590174185.947 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-05-22T19:03:05.947Z,1590174185.947 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2020-05-22T19:03:05.950Z,1590174185.950 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-05-22T19:03:05.950Z,1590174185.950 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-05-22T19:03:05.954Z,1590174185.954 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-05-22T19:03:06.053Z,1590174186.053 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-05-22T19:03:06.055Z,1590174186.055 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-05-22T19:03:06.750Z,1590174186.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-05-22T19:03:06.752Z,1590174186.752 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-05-22T19:03:06.979Z,1590174186.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-05-22T19:03:06.980Z,1590174186.980 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-05-22T19:03:07.085Z,1590174187.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-05-22T19:03:07.086Z,1590174187.086 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-05-22T19:03:07.193Z,1590174187.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-05-22T19:03:07.195Z,1590174187.195 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-05-22T19:03:07.277Z,1590174187.277 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-05-22T19:03:07.431Z,1590174187.431 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-05-22T19:03:07.433Z,1590174187.433 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-05-22T19:03:07.775Z,1590174187.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-05-22T19:03:07.777Z,1590174187.777 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-05-22T19:03:08.362Z,1590174188.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-05-22T19:03:08.364Z,1590174188.364 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-05-22T19:03:08.528Z,1590174188.528 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-05-22T19:03:08.528Z,1590174188.528 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-05-22T19:03:08.729Z,1590174188.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-05-22T19:03:08.731Z,1590174188.731 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-05-22T19:03:09.501Z,1590174189.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-05-22T19:03:09.501Z,1590174189.501 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-05-22T19:03:10.190Z,1590174190.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-05-22T19:03:10.277Z,1590174190.277 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-05-22T19:03:10.522Z,1590174190.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-05-22T19:03:10.524Z,1590174190.524 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-05-22T19:03:11.052Z,1590174191.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-05-22T19:03:11.053Z,1590174191.053 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-05-22T19:03:11.437Z,1590174191.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-05-22T19:03:11.439Z,1590174191.439 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-05-22T19:03:11.441Z,1590174191.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-05-22T19:03:11.526Z,1590174191.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-05-22T19:03:11.698Z,1590174191.698 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-05-22T19:03:11.812Z,1590174191.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-05-22T19:03:11.898Z,1590174191.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-05-22T19:03:11.994Z,1590174191.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-05-22T19:03:12.222Z,1590174192.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-05-22T19:03:12.531Z,1590174192.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-05-22T19:03:12.532Z,1590174192.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-05-22T19:03:12.627Z,1590174192.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-05-22T19:03:12.728Z,1590174192.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-05-22T19:03:12.877Z,1590174192.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-05-22T19:03:12.978Z,1590174192.978 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-05-22T19:03:12.995Z,1590174192.995 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-05-22T19:03:13.109Z,1590174193.109 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-05-22T19:03:13.109Z,1590174193.109 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-05-22T19:03:13.186Z,1590174193.186 [VerticalControl](DEBUG): Construct VerticalControl. 2020-05-22T19:03:13.333Z,1590174193.333 [VerticalControl] Loaded 2020-05-22T19:03:13.333Z,1590174193.333 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-05-22T19:03:13.334Z,1590174193.334 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-05-22T19:03:13.438Z,1590174193.438 [HorizontalControl] Loaded 2020-05-22T19:03:13.438Z,1590174193.438 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-05-22T19:03:13.439Z,1590174193.439 [SpeedControl](DEBUG): Construct SpeedControl. 2020-05-22T19:03:13.446Z,1590174193.446 [SpeedControl] Loaded 2020-05-22T19:03:13.447Z,1590174193.447 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-05-22T19:03:13.447Z,1590174193.447 [LoopControl](DEBUG): Construct LoopControl. 2020-05-22T19:03:13.448Z,1590174193.448 [LoopControl] Loaded 2020-05-22T19:03:13.448Z,1590174193.448 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-05-22T19:03:13.449Z,1590174193.449 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-05-22T19:03:13.450Z,1590174193.450 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-05-22T19:03:13.517Z,1590174193.517 [DepthRateCalculator] Loaded 2020-05-22T19:03:13.518Z,1590174193.518 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-05-22T19:03:13.524Z,1590174193.524 [PitchRateCalculator] Loaded 2020-05-22T19:03:13.524Z,1590174193.524 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-05-22T19:03:13.544Z,1590174193.544 [SpeedCalculator] Loaded 2020-05-22T19:03:13.544Z,1590174193.544 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-05-22T19:03:13.571Z,1590174193.571 [TempGradientCalculator] Loaded 2020-05-22T19:03:13.572Z,1590174193.572 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-05-22T19:03:13.578Z,1590174193.578 [YawRateCalculator] Loaded 2020-05-22T19:03:13.578Z,1590174193.578 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-05-22T19:03:13.617Z,1590174193.617 [ElevatorOffsetCalculator] Loaded 2020-05-22T19:03:13.618Z,1590174193.618 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-05-22T19:03:13.618Z,1590174193.618 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-05-22T19:03:13.620Z,1590174193.620 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-05-22T19:03:13.672Z,1590174193.672 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-05-22T19:03:13.672Z,1590174193.672 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-05-22T19:03:13.825Z,1590174193.825 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-05-22T19:03:13.826Z,1590174193.826 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-05-22T19:03:14.213Z,1590174194.213 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-05-22T19:03:14.214Z,1590174194.214 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-05-22T19:03:14.364Z,1590174194.364 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-05-22T19:03:14.365Z,1590174194.365 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-05-22T19:03:15.631Z,1590174195.631 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-05-22T19:03:15.638Z,1590174195.638 [AHRS_M2](INFO): created writer for : platform_orientation 2020-05-22T19:03:15.640Z,1590174195.640 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-05-22T19:03:15.647Z,1590174195.647 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-05-22T19:03:15.648Z,1590174195.648 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-05-22T19:03:15.655Z,1590174195.655 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-05-22T19:03:15.655Z,1590174195.655 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-05-22T19:03:15.663Z,1590174195.663 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-05-22T19:03:15.871Z,1590174195.871 [AHRS_M2] Loaded 2020-05-22T19:03:15.872Z,1590174195.872 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-05-22T19:03:16.022Z,1590174196.022 [DataOverHttps] Loaded 2020-05-22T19:03:16.022Z,1590174196.022 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-05-22T19:03:16.023Z,1590174196.023 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F34E0 2020-05-22T19:03:16.024Z,1590174196.024 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 891 2020-05-22T19:03:16.042Z,1590174196.042 [Depth_Keller] Loaded 2020-05-22T19:03:16.042Z,1590174196.042 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-05-22T19:03:16.169Z,1590174196.169 [NAL9602] Loaded 2020-05-22T19:03:16.169Z,1590174196.169 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-05-22T19:03:16.217Z,1590174196.217 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-05-22T19:03:16.218Z,1590174196.218 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-05-22T19:03:16.218Z,1590174196.218 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-05-22T19:03:16.219Z,1590174196.219 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-05-22T19:03:16.220Z,1590174196.220 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-05-22T19:03:16.220Z,1590174196.220 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-05-22T19:03:16.221Z,1590174196.221 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-05-22T19:03:16.221Z,1590174196.221 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-05-22T19:03:16.221Z,1590174196.221 [Onboard] Loaded 2020-05-22T19:03:16.222Z,1590174196.222 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-05-22T19:03:16.226Z,1590174196.226 [Radio_Surface] Loaded 2020-05-22T19:03:16.226Z,1590174196.226 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-05-22T19:03:16.227Z,1590174196.227 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A234E0 2020-05-22T19:03:16.228Z,1590174196.228 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 892 2020-05-22T19:03:16.284Z,1590174196.284 [RDI_Pathfinder] Loaded 2020-05-22T19:03:16.284Z,1590174196.284 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-05-22T19:03:16.466Z,1590174196.466 [DAT] Loaded 2020-05-22T19:03:16.466Z,1590174196.466 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-05-22T19:03:18.615Z,1590174198.615 [BPC1] Loaded 2020-05-22T19:03:18.616Z,1590174198.616 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-05-22T19:03:18.616Z,1590174198.616 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-05-22T19:03:18.617Z,1590174198.617 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-05-22T19:03:18.794Z,1590174198.794 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-05-22T19:03:18.795Z,1590174198.795 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-05-22T19:03:18.822Z,1590174198.822 [NavChart] Loaded 2020-05-22T19:03:18.822Z,1590174198.822 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-05-22T19:03:18.827Z,1590174198.827 [UniversalFixResidualReporter] Loaded 2020-05-22T19:03:18.827Z,1590174198.827 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-05-22T19:03:18.828Z,1590174198.828 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-05-22T19:03:18.830Z,1590174198.830 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-05-22T19:03:19.018Z,1590174199.018 [SBIT](DEBUG): Construct Startup Built In Test. 2020-05-22T19:03:19.033Z,1590174199.033 [SBIT] Loaded 2020-05-22T19:03:19.033Z,1590174199.033 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-05-22T19:03:19.034Z,1590174199.034 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-05-22T19:03:19.051Z,1590174199.051 [IBIT] Loaded 2020-05-22T19:03:19.051Z,1590174199.051 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-05-22T19:03:19.056Z,1590174199.056 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-05-22T19:03:19.246Z,1590174199.246 [CBIT] Loaded 2020-05-22T19:03:19.246Z,1590174199.246 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-05-22T19:03:19.247Z,1590174199.247 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-05-22T19:03:19.247Z,1590174199.247 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-05-22T19:03:19.396Z,1590174199.396 [BuoyancyServo] Loaded 2020-05-22T19:03:19.396Z,1590174199.396 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-05-22T19:03:19.411Z,1590174199.411 [ElevatorServo] Loaded 2020-05-22T19:03:19.412Z,1590174199.412 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-05-22T19:03:19.426Z,1590174199.426 [MassServo] Loaded 2020-05-22T19:03:19.427Z,1590174199.427 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-05-22T19:03:19.441Z,1590174199.441 [RudderServo] Loaded 2020-05-22T19:03:19.441Z,1590174199.441 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-05-22T19:03:19.455Z,1590174199.455 [ThrusterServo] Loaded 2020-05-22T19:03:19.456Z,1590174199.456 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-05-22T19:03:19.456Z,1590174199.456 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-05-22T19:03:19.457Z,1590174199.457 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-05-22T19:03:19.498Z,1590174199.498 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-05-22T19:03:19.499Z,1590174199.499 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-05-22T19:03:19.863Z,1590174199.863 [BackSeatDriver] Loaded 2020-05-22T19:03:19.864Z,1590174199.864 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-05-22T19:03:19.865Z,1590174199.865 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40C2C4E0 2020-05-22T19:03:19.865Z,1590174199.865 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 893 2020-05-22T19:03:19.878Z,1590174199.878 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-05-22T19:03:19.885Z,1590174199.885 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-05-22T19:03:19.886Z,1590174199.886 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-05-22T19:03:19.892Z,1590174199.892 [CTD_Seabird](INFO): created writer for : depth 2020-05-22T19:03:19.893Z,1590174199.893 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-05-22T19:03:19.900Z,1590174199.900 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-05-22T19:03:19.900Z,1590174199.900 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-05-22T19:03:19.908Z,1590174199.908 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-05-22T19:03:19.908Z,1590174199.908 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-05-22T19:03:19.915Z,1590174199.915 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-05-22T19:03:19.916Z,1590174199.916 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-05-22T19:03:19.923Z,1590174199.923 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-05-22T19:03:19.924Z,1590174199.924 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-05-22T19:03:19.931Z,1590174199.931 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-05-22T19:03:19.969Z,1590174199.969 [CTD_Seabird] Loaded 2020-05-22T19:03:19.970Z,1590174199.970 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-05-22T19:03:19.971Z,1590174199.971 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C5C4E0 2020-05-22T19:03:19.971Z,1590174199.971 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 894 2020-05-22T19:03:19.980Z,1590174199.980 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-05-22T19:03:19.980Z,1590174199.980 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-05-22T19:03:19.986Z,1590174199.986 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-05-22T19:03:19.986Z,1590174199.986 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-05-22T19:03:19.991Z,1590174199.991 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-05-22T19:03:19.991Z,1590174199.991 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-05-22T19:03:19.996Z,1590174199.996 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-05-22T19:03:19.996Z,1590174199.996 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-05-22T19:03:20.002Z,1590174200.002 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-05-22T19:03:20.002Z,1590174200.002 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-05-22T19:03:20.007Z,1590174200.007 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-05-22T19:03:20.007Z,1590174200.007 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-05-22T19:03:20.012Z,1590174200.012 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-05-22T19:03:20.013Z,1590174200.013 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-05-22T19:03:20.018Z,1590174200.018 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T19:03:20.024Z,1590174200.024 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T19:03:20.024Z,1590174200.024 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T19:03:20.025Z,1590174200.025 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T19:03:20.030Z,1590174200.030 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T19:03:20.030Z,1590174200.030 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T19:03:20.036Z,1590174200.036 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T19:03:20.036Z,1590174200.036 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T19:03:20.041Z,1590174200.041 [WetLabsBB2FL] Loaded 2020-05-22T19:03:20.042Z,1590174200.042 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-05-22T19:03:20.043Z,1590174200.043 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C8C4E0 2020-05-22T19:03:20.043Z,1590174200.043 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 895 2020-05-22T19:03:20.279Z,1590174200.279 [WetLabsUBAT..SerialDriver](INFO): Created logger 2020-05-22T19:03:20.282Z,1590174200.282 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2020-05-22T19:03:20.283Z,1590174200.283 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2020-05-22T19:03:20.288Z,1590174200.288 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2020-05-22T19:03:20.288Z,1590174200.288 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2020-05-22T19:03:20.289Z,1590174200.289 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2020-05-22T19:03:20.294Z,1590174200.294 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2020-05-22T19:03:20.294Z,1590174200.294 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2020-05-22T19:03:20.301Z,1590174200.301 [WetLabsUBAT] Loaded 2020-05-22T19:03:20.301Z,1590174200.301 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2020-05-22T19:03:20.302Z,1590174200.302 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40CBC4E0 2020-05-22T19:03:20.303Z,1590174200.303 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 896 2020-05-22T19:03:20.304Z,1590174200.304 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-05-22T19:03:20.309Z,1590174200.309 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-05-22T19:03:20.310Z,1590174200.310 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-05-22T19:03:20.320Z,1590174200.320 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-05-22T19:03:20.321Z,1590174200.321 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40CEC4E0 2020-05-22T19:03:20.322Z,1590174200.322 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 897 2020-05-22T19:03:20.327Z,1590174200.327 [Supervisor](INFO): Main Thread ID is 802 2020-05-22T19:03:20.328Z,1590174200.328 [Supervisor](DEBUG): Running supervisor. 2020-05-22T19:03:20.328Z,1590174200.328 [CommandLine ThreadHandler](INFO): Handler Thread ID is 898 2020-05-22T19:03:20.331Z,1590174200.331 [controlThread ThreadHandler](INFO): Handler Thread ID is 899 2020-05-22T19:03:20.332Z,1590174200.332 [controlThread](DEBUG): Initializing ControlThread 2020-05-22T19:03:20.333Z,1590174200.333 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-05-22T19:03:20.335Z,1590174200.335 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-05-22T19:03:20.336Z,1590174200.336 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-05-22T19:03:20.336Z,1590174200.336 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-05-22T19:03:20.337Z,1590174200.337 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-05-22T19:03:20.337Z,1590174200.337 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-05-22T19:03:20.338Z,1590174200.338 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-05-22T19:03:20.338Z,1590174200.338 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-05-22T19:03:20.339Z,1590174200.339 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-05-22T19:03:20.339Z,1590174200.339 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-05-22T19:03:20.343Z,1590174200.343 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-05-22T19:03:20.344Z,1590174200.344 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-05-22T19:03:20.344Z,1590174200.344 [SBIT](INFO): Initialize SBIT Component. 2020-05-22T19:03:20.345Z,1590174200.345 [SBIT](IMPORTANT): git: 2020-05-06_C-4-ge907442 2020-05-22T19:03:20.345Z,1590174200.345 [SBIT](INFO): git hash: e907442e8d324ef9e313fad2e4afa1f9562153e8 2020-05-22T19:03:20.345Z,1590174200.345 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-05-22T19:03:20.346Z,1590174200.346 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2020-05-22T19:03:20.347Z,1590174200.347 [SBIT](INFO): Beginning SBIT in 76.000000 seconds. 2020-05-22T19:03:20.347Z,1590174200.347 [IBIT](INFO): Initialize IBIT Component. 2020-05-22T19:03:20.349Z,1590174200.349 [CBIT](DEBUG): Initialize CBIT Component. 2020-05-22T19:03:20.350Z,1590174200.350 [logger ThreadHandler](INFO): Handler Thread ID is 900 2020-05-22T19:03:20.361Z,1590174200.361 [CBIT](DEBUG): Initialized mux pins. 2020-05-22T19:03:20.361Z,1590174200.361 [CBIT](DEBUG): Initializing the watchdog timer. 2020-05-22T19:03:20.369Z,1590174200.369 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 901 2020-05-22T19:03:20.370Z,1590174200.370 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-05-22T19:03:20.381Z,1590174200.381 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 902 2020-05-22T19:03:20.385Z,1590174200.385 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2020-05-22T19:03:20.385Z,1590174200.385 [CBIT](DEBUG): Initializing heartbeat. 2020-05-22T19:03:20.393Z,1590174200.393 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 903 2020-05-22T19:03:20.413Z,1590174200.413 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 904 2020-05-22T19:03:20.414Z,1590174200.414 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-05-22T19:03:20.418Z,1590174200.418 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 906 2020-05-22T19:03:20.419Z,1590174200.419 [WetLabsBB2FL](INFO): Powering down 2020-05-22T19:03:20.442Z,1590174200.442 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 907 2020-05-22T19:03:20.457Z,1590174200.457 [CBIT](DEBUG): Deactivating GF circuits. 2020-05-22T19:03:20.457Z,1590174200.457 [CBIT](DEBUG): Deactivating emergency mode. 2020-05-22T19:03:20.469Z,1590174200.469 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 908 2020-05-22T19:03:20.478Z,1590174200.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-05-22T19:03:20.478Z,1590174200.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-05-22T19:03:20.478Z,1590174200.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-05-22T19:03:20.479Z,1590174200.479 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-05-22T19:03:20.479Z,1590174200.479 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-05-22T19:03:20.479Z,1590174200.479 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-05-22T19:03:20.479Z,1590174200.479 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-05-22T19:03:20.479Z,1590174200.479 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-05-22T19:03:20.479Z,1590174200.479 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-05-22T19:03:20.480Z,1590174200.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-05-22T19:03:20.480Z,1590174200.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-05-22T19:03:20.480Z,1590174200.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-05-22T19:03:20.480Z,1590174200.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-05-22T19:03:20.480Z,1590174200.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-05-22T19:03:20.481Z,1590174200.481 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-05-22T19:03:20.481Z,1590174200.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-05-22T19:03:20.493Z,1590174200.493 [CBIT](DEBUG): Backplane powered. 2020-05-22T19:03:20.495Z,1590174200.495 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-05-22T19:03:20.521Z,1590174200.521 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-22T19:03:20.550Z,1590174200.550 [MissionManager](DEBUG): 2020-05-22T19:03:20.551Z,1590174200.551 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-05-22T19:03:20.629Z,1590174200.629 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-05-22T19:03:20.630Z,1590174200.630 [Default:A.Wait](DEBUG): Construct Wait. 2020-05-22T19:03:20.632Z,1590174200.632 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-22T19:03:20.678Z,1590174200.678 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-05-22T19:03:20.682Z,1590174200.682 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-05-22T19:03:20.709Z,1590174200.709 [Default:E.Execute](DEBUG): Construct Execute. 2020-05-22T19:03:20.712Z,1590174200.712 [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-05-22T19:03:20.733Z,1590174200.733 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,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-05-22T19:03:20.741Z,1590174200.741 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-05-22T19:03:20.785Z,1590174200.785 [Radio_Surface](INFO): Powering up 2020-05-22T19:03:20.862Z,1590174200.862 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-05-22T19:03:20.894Z,1590174200.894 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-05-22T19:03:20.894Z,1590174200.894 [DAT](INFO): Powering up 2020-05-22T19:03:20.894Z,1590174200.894 [DAT](DEBUG): Initializing DAT. 2020-05-22T19:03:20.930Z,1590174200.930 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-05-22T19:03:20.953Z,1590174200.953 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-05-22T19:03:21.038Z,1590174201.038 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-05-22T19:03:21.049Z,1590174201.049 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-05-22T19:03:21.051Z,1590174201.051 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-05-22T19:03:21.061Z,1590174201.061 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-05-22T19:03:21.062Z,1590174201.062 [MassServo](DEBUG): Initializing EZServoServo. 2020-05-22T19:03:21.073Z,1590174201.073 [MassServo](DEBUG): Initializing MassServo. 2020-05-22T19:03:21.074Z,1590174201.074 [RudderServo](DEBUG): Initializing EZServoServo. 2020-05-22T19:03:21.085Z,1590174201.085 [RudderServo](DEBUG): Initializing RudderServo. 2020-05-22T19:03:21.086Z,1590174201.086 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-05-22T19:03:21.097Z,1590174201.097 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-05-22T19:03:21.100Z,1590174201.100 [CommandLine](FAULT): Scheduling is paused 2020-05-22T19:03:21.100Z,1590174201.100 [CBIT](INFO): Critical error at 20200522T190320 2020-05-22T19:03:21.101Z,1590174201.101 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-05-22T19:03:21.217Z,1590174201.217 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-05-22T19:03:31.281Z,1590174211.281 [DAT](DEBUG): 2020-05-22T19:03:31.282Z,1590174211.282 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-05-22T19:03:32.478Z,1590174212.478 [DAT](DEBUG): MF Frequency Band 2020-05-22T19:03:32.479Z,1590174212.479 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21 2020-05-22T19:03:32.480Z,1590174212.480 [DAT](DEBUG): May 22 2020 18:59:32 2020-05-22T19:03:33.290Z,1590174213.290 [DAT](DEBUG): Features enabled [Bearing] 2020-05-22T19:03:33.292Z,1590174213.292 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-05-22T19:03:33.293Z,1590174213.293 [DAT](INFO): commRate: 800 2020-05-22T19:03:33.729Z,1590174213.729 [DAT](INFO): entering command mode 2020-05-22T19:03:34.094Z,1590174214.094 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-22T19:03:34.509Z,1590174214.509 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-22T19:03:34.933Z,1590174214.933 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-22T19:03:34.933Z,1590174214.933 [DAT](INFO): command mode acknowledged 2020-05-22T19:03:34.934Z,1590174214.934 [DAT](INFO): setting verbose to 3 2020-05-22T19:03:35.306Z,1590174215.306 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-05-22T19:03:35.307Z,1590174215.307 [DAT](INFO): set verbose to 3 2020-05-22T19:03:35.721Z,1590174215.721 [DAT](INFO): setting transmit power to 8 2020-05-22T19:03:36.144Z,1590174216.144 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-05-22T19:03:36.518Z,1590174216.518 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-05-22T19:03:36.519Z,1590174216.519 [DAT](INFO): set transmit power t