2019-12-13T17:36:39.014Z,1576258599.014 [Supervisor](DEBUG): Initializing supervisor. 2019-12-13T17:36:39.017Z,1576258599.017 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-12-13T17:36:39.018Z,1576258599.018 [SyncHandler](INFO): Protected caller Thread ID is 806 2019-12-13T17:36:39.018Z,1576258599.018 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-12-13T17:36:39.019Z,1576258599.019 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-12-13T17:36:39.019Z,1576258599.019 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2019-12-13T17:36:39.022Z,1576258599.022 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-12-13T17:36:39.034Z,1576258599.034 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-12-13T17:36:39.035Z,1576258599.035 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-12-13T17:36:39.035Z,1576258599.035 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2019-12-13T17:36:39.036Z,1576258599.036 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-12-13T17:36:39.037Z,1576258599.037 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-12-13T17:36:39.037Z,1576258599.037 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2019-12-13T17:36:39.039Z,1576258599.039 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-12-13T17:36:39.040Z,1576258599.040 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-12-13T17:36:39.044Z,1576258599.044 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-12-13T17:36:39.478Z,1576258599.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-12-13T17:36:39.480Z,1576258599.480 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-12-13T17:36:39.621Z,1576258599.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-12-13T17:36:39.622Z,1576258599.622 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-12-13T17:36:39.703Z,1576258599.703 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-12-13T17:36:39.801Z,1576258599.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-12-13T17:36:39.803Z,1576258599.803 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-12-13T17:36:39.897Z,1576258599.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-12-13T17:36:39.898Z,1576258599.898 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-12-13T17:36:40.285Z,1576258600.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-12-13T17:36:40.287Z,1576258600.287 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-12-13T17:36:40.390Z,1576258600.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-12-13T17:36:40.393Z,1576258600.393 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-12-13T17:36:40.865Z,1576258600.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-12-13T17:36:40.867Z,1576258600.867 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-12-13T17:36:41.057Z,1576258601.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-12-13T17:36:41.059Z,1576258601.059 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-12-13T17:36:41.360Z,1576258601.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-12-13T17:36:41.362Z,1576258601.362 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-12-13T17:36:41.576Z,1576258601.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-12-13T17:36:41.578Z,1576258601.578 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-12-13T17:36:41.719Z,1576258601.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-12-13T17:36:41.720Z,1576258601.720 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-12-13T17:36:42.375Z,1576258602.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-12-13T17:36:42.638Z,1576258602.638 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-12-13T17:36:43.025Z,1576258603.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-12-13T17:36:43.027Z,1576258603.027 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-12-13T17:36:43.335Z,1576258603.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-12-13T17:36:43.337Z,1576258603.337 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2019-12-13T17:36:43.340Z,1576258603.340 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2019-12-13T17:36:43.544Z,1576258603.544 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2019-12-13T17:36:43.628Z,1576258603.628 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2019-12-13T17:36:43.786Z,1576258603.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2019-12-13T17:36:43.872Z,1576258603.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2019-12-13T17:36:44.104Z,1576258604.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-12-13T17:36:44.105Z,1576258604.105 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2019-12-13T17:36:44.212Z,1576258604.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2019-12-13T17:36:44.323Z,1576258604.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2019-12-13T17:36:44.418Z,1576258604.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2019-12-13T17:36:44.567Z,1576258604.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2019-12-13T17:36:44.667Z,1576258604.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2019-12-13T17:36:44.767Z,1576258604.767 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2019-12-13T17:36:44.768Z,1576258604.768 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-12-13T17:36:44.782Z,1576258604.782 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-12-13T17:36:44.806Z,1576258604.806 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-12-13T17:36:44.807Z,1576258604.807 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-12-13T17:36:44.870Z,1576258604.870 [VerticalControl](DEBUG): Construct VerticalControl. 2019-12-13T17:36:44.984Z,1576258604.984 [VerticalControl] Loaded 2019-12-13T17:36:44.984Z,1576258604.984 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-12-13T17:36:44.985Z,1576258604.985 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-12-13T17:36:45.067Z,1576258605.067 [HorizontalControl] Loaded 2019-12-13T17:36:45.067Z,1576258605.067 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-12-13T17:36:45.068Z,1576258605.068 [SpeedControl](DEBUG): Construct SpeedControl. 2019-12-13T17:36:45.074Z,1576258605.074 [SpeedControl] Loaded 2019-12-13T17:36:45.074Z,1576258605.074 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-12-13T17:36:45.075Z,1576258605.075 [LoopControl](DEBUG): Construct LoopControl. 2019-12-13T17:36:45.075Z,1576258605.075 [LoopControl] Loaded 2019-12-13T17:36:45.075Z,1576258605.075 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-12-13T17:36:45.076Z,1576258605.076 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-12-13T17:36:45.078Z,1576258605.078 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-12-13T17:36:45.227Z,1576258605.227 [BuoyancyServo] Loaded 2019-12-13T17:36:45.227Z,1576258605.227 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-12-13T17:36:45.238Z,1576258605.238 [ElevatorServo] Loaded 2019-12-13T17:36:45.239Z,1576258605.239 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-12-13T17:36:45.249Z,1576258605.249 [MassServo] Loaded 2019-12-13T17:36:45.250Z,1576258605.250 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-12-13T17:36:45.261Z,1576258605.261 [RudderServo] Loaded 2019-12-13T17:36:45.261Z,1576258605.261 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-12-13T17:36:45.272Z,1576258605.272 [ThrusterServo] Loaded 2019-12-13T17:36:45.273Z,1576258605.273 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-12-13T17:36:45.273Z,1576258605.273 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-12-13T17:36:45.274Z,1576258605.274 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-12-13T17:36:45.329Z,1576258605.329 [DepthRateCalculator] Loaded 2019-12-13T17:36:45.330Z,1576258605.330 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-12-13T17:36:45.335Z,1576258605.335 [PitchRateCalculator] Loaded 2019-12-13T17:36:45.335Z,1576258605.335 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-12-13T17:36:45.350Z,1576258605.350 [SpeedCalculator] Loaded 2019-12-13T17:36:45.351Z,1576258605.351 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-12-13T17:36:45.372Z,1576258605.372 [TempGradientCalculator] Loaded 2019-12-13T17:36:45.372Z,1576258605.372 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-12-13T17:36:45.377Z,1576258605.377 [YawRateCalculator] Loaded 2019-12-13T17:36:45.378Z,1576258605.378 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-12-13T17:36:45.407Z,1576258605.407 [ElevatorOffsetCalculator] Loaded 2019-12-13T17:36:45.407Z,1576258605.407 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-12-13T17:36:45.408Z,1576258605.408 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-12-13T17:36:45.408Z,1576258605.408 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-12-13T17:36:45.528Z,1576258605.528 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-12-13T17:36:45.528Z,1576258605.528 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-12-13T17:36:45.550Z,1576258605.550 [NavChart] Loaded 2019-12-13T17:36:45.550Z,1576258605.550 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-12-13T17:36:45.554Z,1576258605.554 [UniversalFixResidualReporter] Loaded 2019-12-13T17:36:45.554Z,1576258605.554 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-12-13T17:36:45.555Z,1576258605.555 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-12-13T17:36:45.555Z,1576258605.555 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-12-13T17:36:46.072Z,1576258606.072 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2019-12-13T17:36:46.077Z,1576258606.077 [AHRS_M2](INFO): created writer for : platform_orientation 2019-12-13T17:36:46.079Z,1576258606.079 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2019-12-13T17:36:46.084Z,1576258606.084 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2019-12-13T17:36:46.084Z,1576258606.084 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2019-12-13T17:36:46.089Z,1576258606.089 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2019-12-13T17:36:46.090Z,1576258606.090 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2019-12-13T17:36:46.095Z,1576258606.095 [AHRS_M2](INFO): created writer for : platform_roll_angle 2019-12-13T17:36:46.274Z,1576258606.274 [AHRS_M2] Loaded 2019-12-13T17:36:46.274Z,1576258606.274 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-12-13T17:36:46.421Z,1576258606.421 [DataOverHttps] Loaded 2019-12-13T17:36:46.421Z,1576258606.421 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-12-13T17:36:46.423Z,1576258606.423 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408164E0 2019-12-13T17:36:46.423Z,1576258606.423 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 890 2019-12-13T17:36:46.437Z,1576258606.437 [Depth_Keller] Loaded 2019-12-13T17:36:46.437Z,1576258606.437 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-12-13T17:36:46.442Z,1576258606.442 [DropWeight] Loaded 2019-12-13T17:36:46.442Z,1576258606.442 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-12-13T17:36:46.488Z,1576258606.488 [DVL_micro] Loaded 2019-12-13T17:36:46.488Z,1576258606.488 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2019-12-13T17:36:46.587Z,1576258606.587 [NAL9602] Loaded 2019-12-13T17:36:46.587Z,1576258606.587 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-12-13T17:36:46.625Z,1576258606.625 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2019-12-13T17:36:46.625Z,1576258606.625 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2019-12-13T17:36:46.626Z,1576258606.626 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2019-12-13T17:36:46.627Z,1576258606.627 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2019-12-13T17:36:46.627Z,1576258606.627 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2019-12-13T17:36:46.628Z,1576258606.628 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2019-12-13T17:36:46.628Z,1576258606.628 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2019-12-13T17:36:46.629Z,1576258606.629 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2019-12-13T17:36:46.629Z,1576258606.629 [Onboard] Loaded 2019-12-13T17:36:46.629Z,1576258606.629 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-12-13T17:36:46.633Z,1576258606.633 [Radio_Surface] Loaded 2019-12-13T17:36:46.633Z,1576258606.633 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-12-13T17:36:46.634Z,1576258606.634 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408464E0 2019-12-13T17:36:46.634Z,1576258606.634 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 891 2019-12-13T17:36:48.343Z,1576258608.343 [BPC1] Loaded 2019-12-13T17:36:48.343Z,1576258608.343 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-12-13T17:36:48.345Z,1576258608.345 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-12-13T17:36:48.383Z,1576258608.383 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-12-13T17:36:48.745Z,1576258608.745 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-12-13T17:36:48.745Z,1576258608.745 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-12-13T17:36:48.869Z,1576258608.869 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-12-13T17:36:48.870Z,1576258608.870 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-12-13T17:36:48.919Z,1576258608.919 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-12-13T17:36:48.919Z,1576258608.919 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-12-13T17:36:49.193Z,1576258609.193 [Aanderaa_O2] Loaded 2019-12-13T17:36:49.193Z,1576258609.193 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-12-13T17:36:49.203Z,1576258609.203 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-12-13T17:36:49.208Z,1576258609.208 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-12-13T17:36:49.209Z,1576258609.209 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-12-13T17:36:49.214Z,1576258609.214 [CTD_Seabird](INFO): created writer for : depth 2019-12-13T17:36:49.214Z,1576258609.214 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-12-13T17:36:49.219Z,1576258609.219 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-12-13T17:36:49.220Z,1576258609.220 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-12-13T17:36:49.225Z,1576258609.225 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-12-13T17:36:49.226Z,1576258609.226 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-12-13T17:36:49.231Z,1576258609.231 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-12-13T17:36:49.232Z,1576258609.232 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-12-13T17:36:49.237Z,1576258609.237 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-12-13T17:36:49.238Z,1576258609.238 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-12-13T17:36:49.243Z,1576258609.243 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-12-13T17:36:49.270Z,1576258609.270 [CTD_Seabird] Loaded 2019-12-13T17:36:49.270Z,1576258609.270 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-12-13T17:36:49.272Z,1576258609.272 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E04E0 2019-12-13T17:36:49.272Z,1576258609.272 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 892 2019-12-13T17:36:49.303Z,1576258609.303 [ESPComponent] Loaded 2019-12-13T17:36:49.303Z,1576258609.303 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-12-13T17:36:49.318Z,1576258609.318 [PAR_Licor] Loaded 2019-12-13T17:36:49.318Z,1576258609.318 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-12-13T17:36:49.324Z,1576258609.324 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2019-12-13T17:36:49.324Z,1576258609.324 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2019-12-13T17:36:49.329Z,1576258609.329 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2019-12-13T17:36:49.329Z,1576258609.329 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2019-12-13T17:36:49.333Z,1576258609.333 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2019-12-13T17:36:49.333Z,1576258609.333 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2019-12-13T17:36:49.337Z,1576258609.337 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2019-12-13T17:36:49.338Z,1576258609.338 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2019-12-13T17:36:49.342Z,1576258609.342 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2019-12-13T17:36:49.342Z,1576258609.342 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2019-12-13T17:36:49.347Z,1576258609.347 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2019-12-13T17:36:49.347Z,1576258609.347 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2019-12-13T17:36:49.351Z,1576258609.351 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2019-12-13T17:36:49.351Z,1576258609.351 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2019-12-13T17:36:49.355Z,1576258609.355 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2019-12-13T17:36:49.360Z,1576258609.360 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2019-12-13T17:36:49.360Z,1576258609.360 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-12-13T17:36:49.361Z,1576258609.361 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-12-13T17:36:49.365Z,1576258609.365 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-12-13T17:36:49.365Z,1576258609.365 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-12-13T17:36:49.369Z,1576258609.369 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-12-13T17:36:49.370Z,1576258609.370 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-12-13T17:36:49.374Z,1576258609.374 [WetLabsBB2FL] Loaded 2019-12-13T17:36:49.374Z,1576258609.374 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-12-13T17:36:49.376Z,1576258609.376 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A104E0 2019-12-13T17:36:49.376Z,1576258609.376 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 893 2019-12-13T17:36:49.377Z,1576258609.377 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-12-13T17:36:49.377Z,1576258609.377 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-12-13T17:36:49.734Z,1576258609.734 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-12-13T17:36:49.734Z,1576258609.734 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-12-13T17:36:49.898Z,1576258609.898 [SBIT](DEBUG): Construct Startup Built In Test. 2019-12-13T17:36:49.909Z,1576258609.909 [SBIT] Loaded 2019-12-13T17:36:49.909Z,1576258609.909 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-12-13T17:36:49.910Z,1576258609.910 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-12-13T17:36:49.923Z,1576258609.923 [IBIT] Loaded 2019-12-13T17:36:49.923Z,1576258609.923 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-12-13T17:36:49.926Z,1576258609.926 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-12-13T17:36:50.066Z,1576258610.066 [CBIT] Loaded 2019-12-13T17:36:50.066Z,1576258610.066 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-12-13T17:36:50.067Z,1576258610.067 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-12-13T17:36:50.070Z,1576258610.070 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-12-13T17:36:50.071Z,1576258610.071 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-12-13T17:36:50.078Z,1576258610.078 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-12-13T17:36:50.079Z,1576258610.079 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF94E0 2019-12-13T17:36:50.079Z,1576258610.079 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 894 2019-12-13T17:36:50.084Z,1576258610.084 [Supervisor](INFO): Main Thread ID is 802 2019-12-13T17:36:50.084Z,1576258610.084 [Supervisor](DEBUG): Running supervisor. 2019-12-13T17:36:50.085Z,1576258610.085 [CommandLine ThreadHandler](INFO): Handler Thread ID is 895 2019-12-13T17:36:50.087Z,1576258610.087 [controlThread ThreadHandler](INFO): Handler Thread ID is 896 2019-12-13T17:36:50.088Z,1576258610.088 [controlThread](DEBUG): Initializing ControlThread 2019-12-13T17:36:50.088Z,1576258610.088 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-12-13T17:36:50.090Z,1576258610.090 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-12-13T17:36:50.091Z,1576258610.091 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-12-13T17:36:50.091Z,1576258610.091 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-12-13T17:36:50.092Z,1576258610.092 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-12-13T17:36:50.093Z,1576258610.093 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-12-13T17:36:50.093Z,1576258610.093 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-12-13T17:36:50.093Z,1576258610.093 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-12-13T17:36:50.094Z,1576258610.094 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-12-13T17:36:50.094Z,1576258610.094 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-12-13T17:36:50.095Z,1576258610.095 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-12-13T17:36:50.095Z,1576258610.095 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-12-13T17:36:50.101Z,1576258610.101 [SBIT](INFO): Initialize SBIT Component. 2019-12-13T17:36:50.102Z,1576258610.102 [SBIT](IMPORTANT): git: 2019-12-13 2019-12-13T17:36:50.102Z,1576258610.102 [SBIT](INFO): git hash: acb51ddeff3b98c650dbf9534f1cbf95eba4c7ce 2019-12-13T17:36:50.102Z,1576258610.102 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-12-13T17:36:50.103Z,1576258610.103 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-12-13T17:36:50.104Z,1576258610.104 [SBIT](INFO): Beginning SBIT in 34.000000 seconds. 2019-12-13T17:36:50.105Z,1576258610.105 [IBIT](INFO): Initialize IBIT Component. 2019-12-13T17:36:50.106Z,1576258610.106 [CBIT](DEBUG): Initialize CBIT Component. 2019-12-13T17:36:50.107Z,1576258610.107 [logger ThreadHandler](INFO): Handler Thread ID is 897 2019-12-13T17:36:50.117Z,1576258610.117 [CBIT](DEBUG): Initialized mux pins. 2019-12-13T17:36:50.117Z,1576258610.117 [CBIT](DEBUG): Initializing the watchdog timer. 2019-12-13T17:36:50.125Z,1576258610.125 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 898 2019-12-13T17:36:50.126Z,1576258610.126 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-12-13T17:36:50.137Z,1576258610.137 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 899 2019-12-13T17:36:50.141Z,1576258610.141 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-12-13T17:36:50.141Z,1576258610.141 [CBIT](DEBUG): Initializing heartbeat. 2019-12-13T17:36:50.149Z,1576258610.149 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 900 2019-12-13T17:36:50.150Z,1576258610.150 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-12-13T17:36:50.153Z,1576258610.153 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-12-13T17:36:50.154Z,1576258610.154 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 902 2019-12-13T17:36:50.155Z,1576258610.155 [WetLabsBB2FL](INFO): Powering down 2019-12-13T17:36:50.199Z,1576258610.199 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 903 2019-12-13T17:36:50.207Z,1576258610.207 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-12-13T17:36:50.207Z,1576258610.207 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-12-13T17:36:50.207Z,1576258610.207 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-12-13T17:36:50.207Z,1576258610.207 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-12-13T17:36:50.207Z,1576258610.207 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-12-13T17:36:50.208Z,1576258610.208 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-12-13T17:36:50.208Z,1576258610.208 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-12-13T17:36:50.208Z,1576258610.208 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-12-13T17:36:50.208Z,1576258610.208 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-12-13T17:36:50.208Z,1576258610.208 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-12-13T17:36:50.209Z,1576258610.209 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-12-13T17:36:50.209Z,1576258610.209 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-12-13T17:36:50.209Z,1576258610.209 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-12-13T17:36:50.209Z,1576258610.209 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-12-13T17:36:50.210Z,1576258610.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-12-13T17:36:50.210Z,1576258610.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-12-13T17:36:50.213Z,1576258610.213 [CBIT](DEBUG): Deactivating GF circuits. 2019-12-13T17:36:50.213Z,1576258610.213 [CBIT](DEBUG): Deactivating emergency mode. 2019-12-13T17:36:50.249Z,1576258610.249 [CBIT](DEBUG): Backplane powered. 2019-12-13T17:36:50.250Z,1576258610.250 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-12-13T17:36:50.541Z,1576258610.541 [Radio_Surface](INFO): Powering up 2019-12-13T17:36:50.557Z,1576258610.557 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-12-13T17:36:50.591Z,1576258610.591 [MissionManager](DEBUG): 2019-12-13T17:36:50.592Z,1576258610.592 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-12-13T17:36:51.093Z,1576258611.093 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-12-13T17:36:51.094Z,1576258611.094 [Default:A.Wait](DEBUG): Construct Wait. 2019-12-13T17:36:51.096Z,1576258611.096 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-12-13T17:36:51.115Z,1576258611.115 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-12-13T17:36:51.146Z,1576258611.146 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-12-13T17:36:51.151Z,1576258611.151 [Default:E.Execute](DEBUG): Construct Execute. 2019-12-13T17:36:51.170Z,1576258611.170 [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 2019-12-13T17:36:51.175Z,1576258611.175 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-12-13T17:36:51.180Z,1576258611.180 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-13T17:36:51.306Z,1576258611.306 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2019-12-13T17:36:51.359Z,1576258611.359 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-12-13T17:36:51.417Z,1576258611.417 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-12-13T17:36:51.425Z,1576258611.425 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-12-13T17:36:51.426Z,1576258611.426 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-12-13T17:36:51.433Z,1576258611.433 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-12-13T17:36:51.435Z,1576258611.435 [MassServo](DEBUG): Initializing EZServoServo. 2019-12-13T17:36:51.441Z,1576258611.441 [MassServo](DEBUG): Initializing MassServo. 2019-12-13T17:36:51.442Z,1576258611.442 [RudderServo](DEBUG): Initializing EZServoServo. 2019-12-13T17:36:51.449Z,1576258611.449 [RudderSe