2020-05-22T18:12:44.817Z,1590171164.817 [Supervisor](DEBUG): Initializing supervisor. 2020-05-22T18:12:44.820Z,1590171164.820 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-05-22T18:12:44.821Z,1590171164.821 [SyncHandler](INFO): Protected caller Thread ID is 806 2020-05-22T18:12:44.821Z,1590171164.821 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-05-22T18:12:44.822Z,1590171164.822 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-05-22T18:12:44.823Z,1590171164.823 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2020-05-22T18:12:44.826Z,1590171164.826 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-05-22T18:12:44.842Z,1590171164.842 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-05-22T18:12:44.843Z,1590171164.843 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-05-22T18:12:44.844Z,1590171164.844 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2020-05-22T18:12:44.845Z,1590171164.845 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-05-22T18:12:44.846Z,1590171164.846 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-05-22T18:12:44.846Z,1590171164.846 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2020-05-22T18:12:44.849Z,1590171164.849 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-05-22T18:12:44.849Z,1590171164.849 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-05-22T18:12:44.853Z,1590171164.853 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-05-22T18:12:44.952Z,1590171164.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-05-22T18:12:44.954Z,1590171164.954 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-05-22T18:12:45.642Z,1590171165.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-05-22T18:12:45.644Z,1590171165.644 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-05-22T18:12:45.860Z,1590171165.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-05-22T18:12:45.861Z,1590171165.861 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-05-22T18:12:45.965Z,1590171165.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-05-22T18:12:45.966Z,1590171165.966 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-05-22T18:12:46.073Z,1590171166.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-05-22T18:12:46.075Z,1590171166.075 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-05-22T18:12:46.158Z,1590171166.158 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-05-22T18:12:46.314Z,1590171166.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-05-22T18:12:46.316Z,1590171166.316 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-05-22T18:12:46.666Z,1590171166.666 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-05-22T18:12:46.668Z,1590171166.668 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-05-22T18:12:47.248Z,1590171167.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-05-22T18:12:47.250Z,1590171167.250 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-05-22T18:12:47.411Z,1590171167.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-05-22T18:12:47.412Z,1590171167.412 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-05-22T18:12:47.612Z,1590171167.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-05-22T18:12:47.613Z,1590171167.613 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-05-22T18:12:48.495Z,1590171168.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-05-22T18:12:48.496Z,1590171168.496 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-05-22T18:12:48.753Z,1590171168.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-05-22T18:12:48.755Z,1590171168.755 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-05-22T18:12:48.996Z,1590171168.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-05-22T18:12:48.998Z,1590171168.998 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-05-22T18:12:49.522Z,1590171169.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-05-22T18:12:49.523Z,1590171169.523 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-05-22T18:12:49.905Z,1590171169.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-05-22T18:12:49.907Z,1590171169.907 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-05-22T18:12:49.909Z,1590171169.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-05-22T18:12:49.994Z,1590171169.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-05-22T18:12:50.165Z,1590171170.165 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-05-22T18:12:50.278Z,1590171170.278 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-05-22T18:12:50.364Z,1590171170.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-05-22T18:12:50.459Z,1590171170.459 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-05-22T18:12:50.687Z,1590171170.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-05-22T18:12:50.993Z,1590171170.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-05-22T18:12:50.993Z,1590171170.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-05-22T18:12:51.089Z,1590171171.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-05-22T18:12:51.189Z,1590171171.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-05-22T18:12:51.338Z,1590171171.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-05-22T18:12:51.439Z,1590171171.439 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-05-22T18:12:51.456Z,1590171171.456 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-05-22T18:12:51.563Z,1590171171.563 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-05-22T18:12:51.565Z,1590171171.565 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-05-22T18:12:51.639Z,1590171171.639 [VerticalControl](DEBUG): Construct VerticalControl. 2020-05-22T18:12:51.776Z,1590171171.776 [VerticalControl] Loaded 2020-05-22T18:12:51.776Z,1590171171.776 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-05-22T18:12:51.777Z,1590171171.777 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-05-22T18:12:51.877Z,1590171171.877 [HorizontalControl] Loaded 2020-05-22T18:12:51.877Z,1590171171.877 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-05-22T18:12:51.878Z,1590171171.878 [SpeedControl](DEBUG): Construct SpeedControl. 2020-05-22T18:12:51.885Z,1590171171.885 [SpeedControl] Loaded 2020-05-22T18:12:51.885Z,1590171171.885 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-05-22T18:12:51.886Z,1590171171.886 [LoopControl](DEBUG): Construct LoopControl. 2020-05-22T18:12:51.887Z,1590171171.887 [LoopControl] Loaded 2020-05-22T18:12:51.887Z,1590171171.887 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-05-22T18:12:51.887Z,1590171171.887 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-05-22T18:12:51.888Z,1590171171.888 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-05-22T18:12:51.953Z,1590171171.953 [DepthRateCalculator] Loaded 2020-05-22T18:12:51.953Z,1590171171.953 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-05-22T18:12:51.960Z,1590171171.960 [PitchRateCalculator] Loaded 2020-05-22T18:12:51.960Z,1590171171.960 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-05-22T18:12:51.978Z,1590171171.978 [SpeedCalculator] Loaded 2020-05-22T18:12:51.979Z,1590171171.979 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-05-22T18:12:52.005Z,1590171172.005 [TempGradientCalculator] Loaded 2020-05-22T18:12:52.005Z,1590171172.005 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-05-22T18:12:52.011Z,1590171172.011 [YawRateCalculator] Loaded 2020-05-22T18:12:52.011Z,1590171172.011 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-05-22T18:12:52.049Z,1590171172.049 [ElevatorOffsetCalculator] Loaded 2020-05-22T18:12:52.049Z,1590171172.049 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-05-22T18:12:52.049Z,1590171172.049 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-05-22T18:12:52.051Z,1590171172.051 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-05-22T18:12:52.101Z,1590171172.101 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-05-22T18:12:52.102Z,1590171172.102 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-05-22T18:12:52.250Z,1590171172.250 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-05-22T18:12:52.250Z,1590171172.250 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-05-22T18:12:52.611Z,1590171172.611 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-05-22T18:12:52.611Z,1590171172.611 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-05-22T18:12:52.753Z,1590171172.753 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-05-22T18:12:52.754Z,1590171172.754 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-05-22T18:12:53.363Z,1590171173.363 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-05-22T18:12:53.370Z,1590171173.370 [AHRS_M2](INFO): created writer for : platform_orientation 2020-05-22T18:12:53.372Z,1590171173.372 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-05-22T18:12:53.379Z,1590171173.379 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-05-22T18:12:53.379Z,1590171173.379 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-05-22T18:12:53.386Z,1590171173.386 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-05-22T18:12:53.386Z,1590171173.386 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-05-22T18:12:53.394Z,1590171173.394 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-05-22T18:12:53.596Z,1590171173.596 [AHRS_M2] Loaded 2020-05-22T18:12:53.596Z,1590171173.596 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-05-22T18:12:53.745Z,1590171173.745 [DataOverHttps] Loaded 2020-05-22T18:12:53.746Z,1590171173.746 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-05-22T18:12:53.747Z,1590171173.747 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F34E0 2020-05-22T18:12:53.747Z,1590171173.747 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 891 2020-05-22T18:12:53.764Z,1590171173.764 [Depth_Keller] Loaded 2020-05-22T18:12:53.765Z,1590171173.765 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-05-22T18:12:54.067Z,1590171174.067 [NAL9602] Loaded 2020-05-22T18:12:54.067Z,1590171174.067 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-05-22T18:12:54.191Z,1590171174.191 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-05-22T18:12:54.192Z,1590171174.192 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-05-22T18:12:54.192Z,1590171174.192 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-05-22T18:12:54.193Z,1590171174.193 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-05-22T18:12:54.193Z,1590171174.193 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-05-22T18:12:54.194Z,1590171174.194 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-05-22T18:12:54.194Z,1590171174.194 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-05-22T18:12:54.195Z,1590171174.195 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-05-22T18:12:54.195Z,1590171174.195 [Onboard] Loaded 2020-05-22T18:12:54.195Z,1590171174.195 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-05-22T18:12:54.199Z,1590171174.199 [Radio_Surface] Loaded 2020-05-22T18:12:54.200Z,1590171174.200 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-05-22T18:12:54.201Z,1590171174.201 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A234E0 2020-05-22T18:12:54.201Z,1590171174.201 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 892 2020-05-22T18:12:54.267Z,1590171174.267 [RDI_Pathfinder] Loaded 2020-05-22T18:12:54.268Z,1590171174.268 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-05-22T18:12:54.699Z,1590171174.699 [DAT] Loaded 2020-05-22T18:12:54.699Z,1590171174.699 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-05-22T18:12:56.831Z,1590171176.831 [BPC1] Loaded 2020-05-22T18:12:56.832Z,1590171176.832 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-05-22T18:12:56.832Z,1590171176.832 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-05-22T18:12:56.833Z,1590171176.833 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-05-22T18:12:56.998Z,1590171176.998 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-05-22T18:12:56.999Z,1590171176.999 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-05-22T18:12:57.024Z,1590171177.024 [NavChart] Loaded 2020-05-22T18:12:57.024Z,1590171177.024 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-05-22T18:12:57.029Z,1590171177.029 [UniversalFixResidualReporter] Loaded 2020-05-22T18:12:57.030Z,1590171177.030 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-05-22T18:12:57.030Z,1590171177.030 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-05-22T18:12:57.031Z,1590171177.031 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-05-22T18:12:57.206Z,1590171177.206 [SBIT](DEBUG): Construct Startup Built In Test. 2020-05-22T18:12:57.220Z,1590171177.220 [SBIT] Loaded 2020-05-22T18:12:57.221Z,1590171177.221 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-05-22T18:12:57.222Z,1590171177.222 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-05-22T18:12:57.238Z,1590171177.238 [IBIT] Loaded 2020-05-22T18:12:57.238Z,1590171177.238 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-05-22T18:12:57.242Z,1590171177.242 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-05-22T18:12:57.425Z,1590171177.425 [CBIT] Loaded 2020-05-22T18:12:57.425Z,1590171177.425 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-05-22T18:12:57.426Z,1590171177.426 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-05-22T18:12:57.426Z,1590171177.426 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-05-22T18:12:57.566Z,1590171177.566 [BuoyancyServo] Loaded 2020-05-22T18:12:57.567Z,1590171177.567 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-05-22T18:12:57.581Z,1590171177.581 [ElevatorServo] Loaded 2020-05-22T18:12:57.582Z,1590171177.582 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-05-22T18:12:57.596Z,1590171177.596 [MassServo] Loaded 2020-05-22T18:12:57.596Z,1590171177.596 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-05-22T18:12:57.610Z,1590171177.610 [RudderServo] Loaded 2020-05-22T18:12:57.610Z,1590171177.610 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-05-22T18:12:57.624Z,1590171177.624 [ThrusterServo] Loaded 2020-05-22T18:12:57.624Z,1590171177.624 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-05-22T18:12:57.625Z,1590171177.625 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-05-22T18:12:57.625Z,1590171177.625 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-05-22T18:12:57.666Z,1590171177.666 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-05-22T18:12:57.667Z,1590171177.667 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-05-22T18:12:58.014Z,1590171178.014 [BackSeatDriver] Loaded 2020-05-22T18:12:58.014Z,1590171178.014 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-05-22T18:12:58.015Z,1590171178.015 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40C2C4E0 2020-05-22T18:12:58.016Z,1590171178.016 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 893 2020-05-22T18:12:58.029Z,1590171178.029 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-05-22T18:12:58.035Z,1590171178.035 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-05-22T18:12:58.036Z,1590171178.036 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-05-22T18:12:58.042Z,1590171178.042 [CTD_Seabird](INFO): created writer for : depth 2020-05-22T18:12:58.043Z,1590171178.043 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-05-22T18:12:58.049Z,1590171178.049 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-05-22T18:12:58.050Z,1590171178.050 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-05-22T18:12:58.057Z,1590171178.057 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-05-22T18:12:58.058Z,1590171178.058 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-05-22T18:12:58.064Z,1590171178.064 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-05-22T18:12:58.065Z,1590171178.065 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-05-22T18:12:58.072Z,1590171178.072 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-05-22T18:12:58.072Z,1590171178.072 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-05-22T18:12:58.079Z,1590171178.079 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-05-22T18:12:58.116Z,1590171178.116 [CTD_Seabird] Loaded 2020-05-22T18:12:58.117Z,1590171178.117 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-05-22T18:12:58.118Z,1590171178.118 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C5C4E0 2020-05-22T18:12:58.118Z,1590171178.118 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 894 2020-05-22T18:12:58.126Z,1590171178.126 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-05-22T18:12:58.127Z,1590171178.127 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-05-22T18:12:58.132Z,1590171178.132 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-05-22T18:12:58.132Z,1590171178.132 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-05-22T18:12:58.137Z,1590171178.137 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-05-22T18:12:58.137Z,1590171178.137 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-05-22T18:12:58.142Z,1590171178.142 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-05-22T18:12:58.142Z,1590171178.142 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-05-22T18:12:58.147Z,1590171178.147 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-05-22T18:12:58.147Z,1590171178.147 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-05-22T18:12:58.152Z,1590171178.152 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-05-22T18:12:58.152Z,1590171178.152 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-05-22T18:12:58.157Z,1590171178.157 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-05-22T18:12:58.157Z,1590171178.157 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-05-22T18:12:58.162Z,1590171178.162 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T18:12:58.168Z,1590171178.168 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T18:12:58.168Z,1590171178.168 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T18:12:58.169Z,1590171178.169 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T18:12:58.174Z,1590171178.174 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T18:12:58.174Z,1590171178.174 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T18:12:58.179Z,1590171178.179 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T18:12:58.179Z,1590171178.179 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T18:12:58.184Z,1590171178.184 [WetLabsBB2FL] Loaded 2020-05-22T18:12:58.185Z,1590171178.185 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-05-22T18:12:58.186Z,1590171178.186 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C8C4E0 2020-05-22T18:12:58.186Z,1590171178.186 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 895 2020-05-22T18:12:58.422Z,1590171178.422 [WetLabsUBAT..SerialDriver](INFO): Created logger 2020-05-22T18:12:58.425Z,1590171178.425 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2020-05-22T18:12:58.426Z,1590171178.426 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2020-05-22T18:12:58.431Z,1590171178.431 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2020-05-22T18:12:58.431Z,1590171178.431 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2020-05-22T18:12:58.432Z,1590171178.432 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2020-05-22T18:12:58.437Z,1590171178.437 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2020-05-22T18:12:58.437Z,1590171178.437 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2020-05-22T18:12:58.443Z,1590171178.443 [WetLabsUBAT] Loaded 2020-05-22T18:12:58.443Z,1590171178.443 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2020-05-22T18:12:58.445Z,1590171178.445 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40CBC4E0 2020-05-22T18:12:58.445Z,1590171178.445 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 896 2020-05-22T18:12:58.446Z,1590171178.446 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-05-22T18:12:58.451Z,1590171178.451 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-05-22T18:12:58.452Z,1590171178.452 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-05-22T18:12:58.462Z,1590171178.462 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-05-22T18:12:58.463Z,1590171178.463 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40CEC4E0 2020-05-22T18:12:58.463Z,1590171178.463 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 897 2020-05-22T18:12:58.469Z,1590171178.469 [Supervisor](INFO): Main Thread ID is 801 2020-05-22T18:12:58.469Z,1590171178.469 [Supervisor](DEBUG): Running supervisor. 2020-05-22T18:12:58.470Z,1590171178.470 [CommandLine ThreadHandler](INFO): Handler Thread ID is 898 2020-05-22T18:12:58.473Z,1590171178.473 [controlThread ThreadHandler](INFO): Handler Thread ID is 899 2020-05-22T18:12:58.474Z,1590171178.474 [controlThread](DEBUG): Initializing ControlThread 2020-05-22T18:12:58.474Z,1590171178.474 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-05-22T18:12:58.476Z,1590171178.476 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-05-22T18:12:58.477Z,1590171178.477 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-05-22T18:12:58.478Z,1590171178.478 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-05-22T18:12:58.478Z,1590171178.478 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-05-22T18:12:58.479Z,1590171178.479 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-05-22T18:12:58.479Z,1590171178.479 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-05-22T18:12:58.480Z,1590171178.480 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-05-22T18:12:58.480Z,1590171178.480 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-05-22T18:12:58.481Z,1590171178.481 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-05-22T18:12:58.488Z,1590171178.488 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-05-22T18:12:58.488Z,1590171178.488 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-05-22T18:12:58.489Z,1590171178.489 [SBIT](INFO): Initialize SBIT Component. 2020-05-22T18:12:58.489Z,1590171178.489 [SBIT](IMPORTANT): git: 2020-05-06_C-4-ge907442 2020-05-22T18:12:58.489Z,1590171178.489 [SBIT](INFO): git hash: e907442e8d324ef9e313fad2e4afa1f9562153e8 2020-05-22T18:12:58.490Z,1590171178.490 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-05-22T18:12:58.490Z,1590171178.490 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2020-05-22T18:12:58.491Z,1590171178.491 [SBIT](INFO): Beginning SBIT in 76.000000 seconds. 2020-05-22T18:12:58.492Z,1590171178.492 [IBIT](INFO): Initialize IBIT Component. 2020-05-22T18:12:58.493Z,1590171178.493 [CBIT](DEBUG): Initialize CBIT Component. 2020-05-22T18:12:58.494Z,1590171178.494 [logger ThreadHandler](INFO): Handler Thread ID is 900 2020-05-22T18:12:58.505Z,1590171178.505 [CBIT](DEBUG): Initialized mux pins. 2020-05-22T18:12:58.505Z,1590171178.505 [CBIT](DEBUG): Initializing the watchdog timer. 2020-05-22T18:12:58.513Z,1590171178.513 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 901 2020-05-22T18:12:58.514Z,1590171178.514 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-05-22T18:12:58.525Z,1590171178.525 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 902 2020-05-22T18:12:58.529Z,1590171178.529 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-05-22T18:12:58.529Z,1590171178.529 [CBIT](DEBUG): Initializing heartbeat. 2020-05-22T18:12:58.537Z,1590171178.537 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 903 2020-05-22T18:12:58.545Z,1590171178.545 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 904 2020-05-22T18:12:58.546Z,1590171178.546 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-05-22T18:12:58.550Z,1590171178.550 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 906 2020-05-22T18:12:58.552Z,1590171178.552 [WetLabsBB2FL](INFO): Powering down 2020-05-22T18:12:58.578Z,1590171178.578 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 907 2020-05-22T18:12:58.598Z,1590171178.598 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 908 2020-05-22T18:12:58.601Z,1590171178.601 [CBIT](DEBUG): Deactivating GF circuits. 2020-05-22T18:12:58.601Z,1590171178.601 [CBIT](DEBUG): Deactivating emergency mode. 2020-05-22T18:12:58.607Z,1590171178.607 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-05-22T18:12:58.607Z,1590171178.607 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-05-22T18:12:58.607Z,1590171178.607 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-05-22T18:12:58.607Z,1590171178.607 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-05-22T18:12:58.607Z,1590171178.607 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-05-22T18:12:58.608Z,1590171178.608 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-05-22T18:12:58.608Z,1590171178.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-05-22T18:12:58.608Z,1590171178.608 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-05-22T18:12:58.608Z,1590171178.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-05-22T18:12:58.608Z,1590171178.608 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-05-22T18:12:58.609Z,1590171178.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-05-22T18:12:58.609Z,1590171178.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-05-22T18:12:58.609Z,1590171178.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-05-22T18:12:58.609Z,1590171178.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-05-22T18:12:58.609Z,1590171178.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-05-22T18:12:58.610Z,1590171178.610 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-05-22T18:12:58.637Z,1590171178.637 [CBIT](DEBUG): Backplane powered. 2020-05-22T18:12:58.639Z,1590171178.639 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-05-22T18:12:58.664Z,1590171178.664 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-22T18:12:58.699Z,1590171178.699 [MissionManager](DEBUG): 2020-05-22T18:12:58.700Z,1590171178.700 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-05-22T18:12:58.785Z,1590171178.785 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-05-22T18:12:58.787Z,1590171178.787 [Default:A.Wait](DEBUG): Construct Wait. 2020-05-22T18:12:58.789Z,1590171178.789 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-22T18:12:58.835Z,1590171178.835 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-05-22T18:12:58.838Z,1590171178.838 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-05-22T18:12:58.865Z,1590171178.865 [Default:E.Execute](DEBUG): Construct Execute. 2020-05-22T18:12:58.885Z,1590171178.885 [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-22T18:12:58.889Z,1590171178.889 [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-22T18:12:58.906Z,1590171178.906 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-05-22T18:12:58.929Z,1590171178.929 [Radio_Surface](INFO): Powering up 2020-05-22T18:12:59.024Z,1590171179.024 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-05-22T18:12:59.068Z,1590171179.068 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-05-22T18:12:59.068Z,1590171179.068 [DAT](INFO): Powering up 2020-05-22T18:12:59.069Z,1590171179.069 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-05-22T18:12:59.081Z,1590171179.081 [DAT](DEBUG): Initializing DAT. 2020-05-22T18:12:59.137Z,1590171179.137 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-05-22T18:12:59.211Z,1590171179.211 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-05-22T18:12:59.225Z,1590171179.225 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-05-22T18:12:59.226Z,1590171179.226 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-05-22T18:12:59.233Z,1590171179.233 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-05-22T18:12:59.234Z,1590171179.234 [MassServo](DEBUG): Initializing EZServoServo. 2020-05-22T18:12:59.241Z,1590171179.241 [MassServo](DEBUG): Initializing MassServo. 2020-05-22T18:12:59.242Z,1590171179.242 [RudderServo](DEBUG): Initializing EZServoServo. 2020-05-22T18:12:59.249Z,1590171179.249 [RudderServo](DEBUG): Initializing RudderServo. 2020-05-22T18:12:59.250Z,1590171179.250 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-05-22T18:12:59.257Z,1590171179.257 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-05-22T18:12:59.405Z,1590171179.405 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-05-22T18:13:09.538Z,1590171189.538 [DAT](DEBUG): 2020-05-22T18:13:09.539Z,1590171189.539 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-05-22T18:13:10.767Z,1590171190.767 [DAT](DEBUG): MF Frequency Band 2020-05-22T18:13:10.768Z,1590171190.768 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21 2020-05-22T18:13:10.769Z,1590171190.769 [DAT](DEBUG): May 22 2020 18:09:10 2020-05-22T18:13:11.555Z,1590171191.555 [DAT](DEBUG): Features enabled [Bearing] 2020-05-22T18:13:11.566Z,1590171191.566 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-05-22T18:13:11.566Z,1590171191.566 [DAT](INFO): commRate: 800 2020-05-22T18:13:11.969Z,1590171191.969 [DAT](INFO): entering command mode 2020-05-22T18:13:12.378Z,1590171192.378 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-22T18:13:12.753Z,1590171192.753 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-22T18:13:13.205Z,1590171193.205 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-22T18:13:13.206Z,1590171193.206 [DAT](INFO): command mode acknowledged 2020-05-22T18:13:13.206Z,1590171193.206 [DAT](INFO): setting verbose to 3 2020-05-22T18:13:13.581Z,1590171193.581 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-05-22T18:13:13.582Z,1590171193.582 [DAT](INFO): set verbose to 3 2020-05-22T18:13:14.012Z,1590171194.012 [DAT](INFO): setting transmit power to 8 2020-05-22T18:13:14.369Z,1590171194.369 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-05-22T18:13:14.774Z,1590171194.774 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-05-22T18:13:14.775Z,1590171194.775 [DAT](INFO): set transmit power to 8 2020-05-22T18:13:15.177Z,1590171195.177 [DAT](INFO): setting local address to 9 2020-05-22T18:13:15.601Z,1590171195.601 [DAT](DEBUG): checking for local address setting acknowledgment 2020-05-22T18:13:15.986Z,1590171195.986 [DAT](DEBUG): checking for local address setting acknowledgment 2020-05-22T18:13:15.987Z,1590171195.987 [DAT](INFO): set local address to 9 2020-05-22T18:13:24.455Z,1590171204.455 [NAL9602](INFO): Powering up NAL9602 2020-05-22T18:13:35.363Z,1590171215.363 [NAL9602](INFO): NAL9602 initialized 2020-05-22T18:14:15.079Z,1590171255.079 [SBIT](IMPORTANT): Beginning Startup BIT 2020-05-22T18:14:15.092Z,1590171255.092 [CBIT](IMPORTANT): Beginning ground fault scan 2020-05-22T18:14:26.066Z,1590171266.066 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.000281 CHAN A1 (24V): 0.035586 CHAN A2 (12V): -0.007976 CHAN A3 (5V): 0.000463 CHAN B0 (3.3V): -0.000439 CHAN B1 (3.15aV): -0.000520 CHAN B2 (3.15bV): 0.000389 CHAN B3 (GND): 0.001233 OPEN: -0.004222 Full Scale Calc: 4.765 mA, -1.589 mA 2020-05-22T18:15:03.641Z,1590171303.641 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-22T18:15:08.909Z,1590171308.909 [SBIT](IMPORTANT): SBIT PASSED 2020-05-22T18:15:08.909Z,1590171308.909 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-05-22T18:15:08.910Z,1590171308.910 [SBIT](IMPORTANT): DataOverHttps.verbosity=0 count; 2020-05-22T18:15:08.910Z,1590171308.910 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2020-05-22T18:15:08.910Z,1590171308.910 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent; 2020-05-22T18:15:08.911Z,1590171308.911 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second; 2020-05-22T18:15:08.911Z,1590171308.911 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2020-05-22T18:15:08.911Z,1590171308.911 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=15 ampere_hour; 2020-05-22T18:15:08.911Z,1590171308.911 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt; 2020-05-22T18:15:08.911Z,1590171308.911 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=413.311030 cubic_centimeter; 2020-05-22T18:15:08.911Z,1590171308.911 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.023880 millimeter; 2020-05-22T18:15:08.911Z,1590171308.911 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool; 2020-05-22T18:15:09.295Z,1590171309.295 [MissionManager](IMPORTANT): Started mission Startup 2020-05-22T18:15:09.296Z,1590171309.296 [Startup] Running Loop=1 2020-05-22T18:15:09.296Z,1590171309.296 [Startup](DEBUG): Aggregate::initialize Startup 2020-05-22T18:15:09.296Z,1590171309.296 [Startup:A.GoToSurface] Running Loop=1 2020-05-22T18:15:09.296Z,1590171309.296 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-05-22T18:15:09.296Z,1590171309.296 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-05-22T18:15:09.301Z,1590171309.301 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-05-22T18:15:09.302Z,1590171309.302 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-05-22T18:15:09.302Z,1590171309.302 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-05-22T18:15:09.302Z,1590171309.302 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-05-22T18:15:09.304Z,1590171309.304 [Startup:StartupSatComms] Running Loop=1 2020-05-22T18:15:09.304Z,1590171309.304 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-05-22T18:15:09.304Z,1590171309.304 [Startup:StartupSatComms:A] Running Loop=1 2020-05-22T18:15:09.683Z,1590171309.683 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-05-22T18:15:13.760Z,1590171313.760 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-22T18:15:36.034Z,1590171336.034 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005924 2020-05-22T18:15:58.969Z,1590171358.969 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-05-22T18:15:58.969Z,1590171358.969 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-0