2020-08-19T14:17:01.993Z,1597846621.993 [Supervisor](DEBUG): Initializing supervisor. 2020-08-19T14:17:01.996Z,1597846621.996 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-08-19T14:17:01.996Z,1597846621.996 [SyncHandler](INFO): Protected caller Thread ID is 821 2020-08-19T14:17:01.997Z,1597846621.997 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-08-19T14:17:01.998Z,1597846621.998 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-08-19T14:17:01.998Z,1597846621.998 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 822 2020-08-19T14:17:02.001Z,1597846622.001 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-08-19T14:17:02.015Z,1597846622.015 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-08-19T14:17:02.016Z,1597846622.016 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-08-19T14:17:02.016Z,1597846622.016 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 823 2020-08-19T14:17:02.017Z,1597846622.017 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-08-19T14:17:02.018Z,1597846622.018 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-08-19T14:17:02.018Z,1597846622.018 [logger ThreadHandler](INFO): Protected caller Thread ID is 824 2020-08-19T14:17:02.020Z,1597846622.020 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-08-19T14:17:02.020Z,1597846622.020 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-08-19T14:17:02.025Z,1597846622.025 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-08-19T14:17:02.235Z,1597846622.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-08-19T14:17:02.237Z,1597846622.237 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-08-19T14:17:02.319Z,1597846622.319 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-08-19T14:17:02.761Z,1597846622.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-08-19T14:17:02.763Z,1597846622.763 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-08-19T14:17:03.103Z,1597846623.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-08-19T14:17:03.105Z,1597846623.105 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-08-19T14:17:03.202Z,1597846623.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-08-19T14:17:03.204Z,1597846623.204 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-08-19T14:17:03.509Z,1597846623.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-08-19T14:17:03.510Z,1597846623.510 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-08-19T14:17:03.708Z,1597846623.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-08-19T14:17:03.710Z,1597846623.710 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-08-19T14:17:04.192Z,1597846624.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-08-19T14:17:04.194Z,1597846624.194 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-08-19T14:17:04.298Z,1597846624.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-08-19T14:17:04.300Z,1597846624.300 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-08-19T14:17:04.401Z,1597846624.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-08-19T14:17:04.402Z,1597846624.402 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-08-19T14:17:05.072Z,1597846625.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-08-19T14:17:05.073Z,1597846625.073 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-08-19T14:17:05.515Z,1597846625.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-08-19T14:17:05.516Z,1597846625.516 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-08-19T14:17:06.231Z,1597846626.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-08-19T14:17:06.233Z,1597846626.233 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-08-19T14:17:06.381Z,1597846626.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-08-19T14:17:06.381Z,1597846626.381 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-08-19T14:17:06.533Z,1597846626.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-08-19T14:17:06.534Z,1597846626.534 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-08-19T14:17:06.748Z,1597846626.748 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-08-19T14:17:06.750Z,1597846626.750 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2020-08-19T14:17:06.752Z,1597846626.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2020-08-19T14:17:06.844Z,1597846626.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2020-08-19T14:17:06.929Z,1597846626.929 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2020-08-19T14:17:07.033Z,1597846627.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2020-08-19T14:17:07.117Z,1597846627.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2020-08-19T14:17:07.213Z,1597846627.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2020-08-19T14:17:07.316Z,1597846627.316 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2020-08-19T14:17:07.529Z,1597846627.529 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2020-08-19T14:17:07.607Z,1597846627.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2020-08-19T14:17:07.769Z,1597846627.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2020-08-19T14:17:07.919Z,1597846627.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2020-08-19T14:17:08.118Z,1597846628.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2020-08-19T14:17:08.359Z,1597846628.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-08-19T14:17:08.360Z,1597846628.360 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-08-19T14:17:08.372Z,1597846628.372 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-08-19T14:17:08.437Z,1597846628.437 [VerticalControl](DEBUG): Construct VerticalControl. 2020-08-19T14:17:08.551Z,1597846628.551 [VerticalControl] Loaded 2020-08-19T14:17:08.551Z,1597846628.551 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-08-19T14:17:08.552Z,1597846628.552 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-08-19T14:17:08.633Z,1597846628.633 [HorizontalControl] Loaded 2020-08-19T14:17:08.633Z,1597846628.633 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-08-19T14:17:08.634Z,1597846628.634 [SpeedControl](DEBUG): Construct SpeedControl. 2020-08-19T14:17:08.639Z,1597846628.639 [SpeedControl] Loaded 2020-08-19T14:17:08.640Z,1597846628.640 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-08-19T14:17:08.640Z,1597846628.640 [LoopControl](DEBUG): Construct LoopControl. 2020-08-19T14:17:08.641Z,1597846628.641 [LoopControl] Loaded 2020-08-19T14:17:08.641Z,1597846628.641 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-08-19T14:17:08.642Z,1597846628.642 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-08-19T14:17:08.643Z,1597846628.643 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-08-19T14:17:08.673Z,1597846628.673 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-08-19T14:17:08.674Z,1597846628.674 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-08-19T14:17:08.796Z,1597846628.796 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-08-19T14:17:08.796Z,1597846628.796 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-08-19T14:17:08.965Z,1597846628.965 [BuoyancyServo] Loaded 2020-08-19T14:17:08.965Z,1597846628.965 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-08-19T14:17:08.993Z,1597846628.993 [ElevatorServo] Loaded 2020-08-19T14:17:08.993Z,1597846628.993 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-08-19T14:17:09.021Z,1597846629.021 [MassServo] Loaded 2020-08-19T14:17:09.021Z,1597846629.021 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-08-19T14:17:09.048Z,1597846629.048 [RudderServo] Loaded 2020-08-19T14:17:09.049Z,1597846629.049 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-08-19T14:17:09.075Z,1597846629.075 [ThrusterServo] Loaded 2020-08-19T14:17:09.075Z,1597846629.075 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-08-19T14:17:09.076Z,1597846629.076 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-08-19T14:17:09.076Z,1597846629.076 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-08-19T14:17:09.216Z,1597846629.216 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-08-19T14:17:09.216Z,1597846629.216 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-08-19T14:17:09.236Z,1597846629.236 [NavChart] Loaded 2020-08-19T14:17:09.237Z,1597846629.237 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-08-19T14:17:09.241Z,1597846629.241 [UniversalFixResidualReporter] Loaded 2020-08-19T14:17:09.241Z,1597846629.241 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-08-19T14:17:09.242Z,1597846629.242 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-08-19T14:17:09.242Z,1597846629.242 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-08-19T14:17:09.364Z,1597846629.364 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-08-19T14:17:09.365Z,1597846629.365 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-08-19T14:17:09.899Z,1597846629.899 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-08-19T14:17:09.904Z,1597846629.904 [AHRS_M2](INFO): created writer for : platform_orientation 2020-08-19T14:17:09.906Z,1597846629.906 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-08-19T14:17:09.911Z,1597846629.911 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-08-19T14:17:09.911Z,1597846629.911 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-08-19T14:17:09.916Z,1597846629.916 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-08-19T14:17:09.917Z,1597846629.917 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-08-19T14:17:09.922Z,1597846629.922 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-08-19T14:17:10.099Z,1597846630.099 [AHRS_M2] Loaded 2020-08-19T14:17:10.099Z,1597846630.099 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-08-19T14:17:10.244Z,1597846630.244 [DataOverHttps] Loaded 2020-08-19T14:17:10.245Z,1597846630.245 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-08-19T14:17:10.246Z,1597846630.246 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4089B4E0 2020-08-19T14:17:10.246Z,1597846630.246 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 910 2020-08-19T14:17:10.276Z,1597846630.276 [Depth_Keller] Loaded 2020-08-19T14:17:10.277Z,1597846630.277 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-08-19T14:17:10.281Z,1597846630.281 [DropWeight] Loaded 2020-08-19T14:17:10.281Z,1597846630.281 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-08-19T14:17:10.393Z,1597846630.393 [NAL9602] Loaded 2020-08-19T14:17:10.393Z,1597846630.393 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-08-19T14:17:10.441Z,1597846630.441 [Onboard] Loaded 2020-08-19T14:17:10.441Z,1597846630.441 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-08-19T14:17:10.442Z,1597846630.442 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408CB4E0 2020-08-19T14:17:10.442Z,1597846630.442 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 911 2020-08-19T14:17:10.462Z,1597846630.462 [Radio_Surface] Loaded 2020-08-19T14:17:10.462Z,1597846630.462 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-08-19T14:17:10.463Z,1597846630.463 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408FB4E0 2020-08-19T14:17:10.463Z,1597846630.463 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 912 2020-08-19T14:17:12.427Z,1597846632.427 [BPC1] Loaded 2020-08-19T14:17:12.427Z,1597846632.427 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-08-19T14:17:12.428Z,1597846632.428 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-08-19T14:17:12.429Z,1597846632.429 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-08-19T14:17:12.483Z,1597846632.483 [DepthRateCalculator] Loaded 2020-08-19T14:17:12.484Z,1597846632.484 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-08-19T14:17:12.489Z,1597846632.489 [PitchRateCalculator] Loaded 2020-08-19T14:17:12.489Z,1597846632.489 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-08-19T14:17:12.501Z,1597846632.501 [SpeedCalculator] Loaded 2020-08-19T14:17:12.502Z,1597846632.502 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-08-19T14:17:12.523Z,1597846632.523 [TempGradientCalculator] Loaded 2020-08-19T14:17:12.523Z,1597846632.523 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-08-19T14:17:12.528Z,1597846632.528 [YawRateCalculator] Loaded 2020-08-19T14:17:12.528Z,1597846632.528 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-08-19T14:17:12.558Z,1597846632.558 [ElevatorOffsetCalculator] Loaded 2020-08-19T14:17:12.558Z,1597846632.558 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-08-19T14:17:12.559Z,1597846632.559 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-08-19T14:17:12.559Z,1597846632.559 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-08-19T14:17:12.848Z,1597846632.848 [CANONSampler] Loaded 2020-08-19T14:17:12.849Z,1597846632.849 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2020-08-19T14:17:12.877Z,1597846632.877 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-08-19T14:17:12.882Z,1597846632.882 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-08-19T14:17:12.882Z,1597846632.882 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-08-19T14:17:12.887Z,1597846632.887 [CTD_Seabird](INFO): created writer for : depth 2020-08-19T14:17:12.888Z,1597846632.888 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-08-19T14:17:12.892Z,1597846632.892 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-08-19T14:17:12.893Z,1597846632.893 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-08-19T14:17:12.898Z,1597846632.898 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-08-19T14:17:12.899Z,1597846632.899 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-08-19T14:17:12.904Z,1597846632.904 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-08-19T14:17:12.904Z,1597846632.904 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-08-19T14:17:12.910Z,1597846632.910 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-08-19T14:17:12.910Z,1597846632.910 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-08-19T14:17:12.915Z,1597846632.915 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-08-19T14:17:12.916Z,1597846632.916 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water 2020-08-19T14:17:12.921Z,1597846632.921 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water 2020-08-19T14:17:12.922Z,1597846632.922 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq 2020-08-19T14:17:12.922Z,1597846632.922 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq 2020-08-19T14:17:12.954Z,1597846632.954 [CTD_Seabird] Loaded 2020-08-19T14:17:12.954Z,1597846632.954 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-08-19T14:17:12.955Z,1597846632.955 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A074E0 2020-08-19T14:17:12.955Z,1597846632.955 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 913 2020-08-19T14:17:12.987Z,1597846632.987 [PAR_Licor] Loaded 2020-08-19T14:17:12.987Z,1597846632.987 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-08-19T14:17:13.009Z,1597846633.009 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-08-19T14:17:13.009Z,1597846633.009 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-08-19T14:17:13.013Z,1597846633.013 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-08-19T14:17:13.013Z,1597846633.013 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-08-19T14:17:13.017Z,1597846633.017 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-08-19T14:17:13.017Z,1597846633.017 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-08-19T14:17:13.021Z,1597846633.021 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-08-19T14:17:13.022Z,1597846633.022 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-08-19T14:17:13.026Z,1597846633.026 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-08-19T14:17:13.026Z,1597846633.026 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-08-19T14:17:13.030Z,1597846633.030 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-08-19T14:17:13.030Z,1597846633.030 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-08-19T14:17:13.034Z,1597846633.034 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-08-19T14:17:13.035Z,1597846633.035 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-08-19T14:17:13.039Z,1597846633.039 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-08-19T14:17:13.043Z,1597846633.043 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-08-19T14:17:13.044Z,1597846633.044 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-08-19T14:17:13.044Z,1597846633.044 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-08-19T14:17:13.048Z,1597846633.048 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-08-19T14:17:13.048Z,1597846633.048 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-08-19T14:17:13.053Z,1597846633.053 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-08-19T14:17:13.053Z,1597846633.053 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-08-19T14:17:13.057Z,1597846633.057 [WetLabsBB2FL] Loaded 2020-08-19T14:17:13.057Z,1597846633.057 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-08-19T14:17:13.058Z,1597846633.058 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A374E0 2020-08-19T14:17:13.059Z,1597846633.059 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 914 2020-08-19T14:17:13.060Z,1597846633.060 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-08-19T14:17:13.060Z,1597846633.060 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-08-19T14:17:13.101Z,1597846633.101 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-08-19T14:17:13.101Z,1597846633.101 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-08-19T14:17:13.432Z,1597846633.432 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-08-19T14:17:13.433Z,1597846633.433 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-08-19T14:17:13.592Z,1597846633.592 [SBIT](DEBUG): Construct Startup Built In Test. 2020-08-19T14:17:13.603Z,1597846633.603 [SBIT] Loaded 2020-08-19T14:17:13.604Z,1597846633.604 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-08-19T14:17:13.604Z,1597846633.604 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-08-19T14:17:13.617Z,1597846633.617 [IBIT] Loaded 2020-08-19T14:17:13.618Z,1597846633.618 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-08-19T14:17:13.621Z,1597846633.621 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-08-19T14:17:13.761Z,1597846633.761 [CBIT] Loaded 2020-08-19T14:17:13.761Z,1597846633.761 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-08-19T14:17:13.761Z,1597846633.761 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-08-19T14:17:13.762Z,1597846633.762 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-08-19T14:17:13.857Z,1597846633.857 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-08-19T14:17:13.861Z,1597846633.861 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-08-19T14:17:13.862Z,1597846633.862 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-08-19T14:17:13.869Z,1597846633.869 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-08-19T14:17:13.870Z,1597846633.870 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5D4E0 2020-08-19T14:17:13.871Z,1597846633.871 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 915 2020-08-19T14:17:13.875Z,1597846633.875 [Supervisor](INFO): Main Thread ID is 817 2020-08-19T14:17:13.875Z,1597846633.875 [Supervisor](DEBUG): Running supervisor. 2020-08-19T14:17:13.876Z,1597846633.876 [CommandLine ThreadHandler](INFO): Handler Thread ID is 916 2020-08-19T14:17:13.878Z,1597846633.878 [controlThread ThreadHandler](INFO): Handler Thread ID is 917 2020-08-19T14:17:13.879Z,1597846633.879 [controlThread](DEBUG): Initializing ControlThread 2020-08-19T14:17:13.880Z,1597846633.880 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-08-19T14:17:13.881Z,1597846633.881 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-08-19T14:17:13.882Z,1597846633.882 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-08-19T14:17:13.883Z,1597846633.883 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-08-19T14:17:13.884Z,1597846633.884 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-08-19T14:17:13.885Z,1597846633.885 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-08-19T14:17:13.889Z,1597846633.889 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-08-19T14:17:13.889Z,1597846633.889 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-08-19T14:17:13.889Z,1597846633.889 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-08-19T14:17:13.890Z,1597846633.890 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-08-19T14:17:13.890Z,1597846633.890 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-08-19T14:17:13.890Z,1597846633.890 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-08-19T14:17:13.895Z,1597846633.895 [SBIT](INFO): Initialize SBIT Component. 2020-08-19T14:17:13.896Z,1597846633.896 [SBIT](IMPORTANT): git: 2020-08-18a 2020-08-19T14:17:13.896Z,1597846633.896 [SBIT](INFO): git hash: 77519d12954ee93339c6cb522e5e7960b0ad92da 2020-08-19T14:17:13.896Z,1597846633.896 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-08-19T14:17:13.897Z,1597846633.897 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019 2020-08-19T14:17:13.898Z,1597846633.898 [SBIT](INFO): Beginning SBIT in 60.000000 seconds. 2020-08-19T14:17:13.899Z,1597846633.899 [IBIT](INFO): Initialize IBIT Component. 2020-08-19T14:17:13.900Z,1597846633.900 [CBIT](DEBUG): Initialize CBIT Component. 2020-08-19T14:17:13.901Z,1597846633.901 [logger ThreadHandler](INFO): Handler Thread ID is 918 2020-08-19T14:17:13.911Z,1597846633.911 [CBIT](DEBUG): Initialized mux pins. 2020-08-19T14:17:13.911Z,1597846633.911 [CBIT](DEBUG): Initializing the watchdog timer. 2020-08-19T14:17:13.919Z,1597846633.919 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 919 2020-08-19T14:17:13.920Z,1597846633.920 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-08-19T14:17:13.931Z,1597846633.931 [Onboard ThreadHandler](INFO): Handler Thread ID is 920 2020-08-19T14:17:13.936Z,1597846633.936 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-08-19T14:17:13.936Z,1597846633.936 [CBIT](DEBUG): Initializing heartbeat. 2020-08-19T14:17:13.955Z,1597846633.955 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 921 2020-08-19T14:17:13.967Z,1597846633.967 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 922 2020-08-19T14:17:13.968Z,1597846633.968 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-08-19T14:17:13.972Z,1597846633.972 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 924 2020-08-19T14:17:13.973Z,1597846633.973 [WetLabsBB2FL](INFO): Powering down 2020-08-19T14:17:14.007Z,1597846634.007 [CBIT](DEBUG): Deactivating GF circuits. 2020-08-19T14:17:14.007Z,1597846634.007 [CBIT](DEBUG): Deactivating emergency mode. 2020-08-19T14:17:14.043Z,1597846634.043 [CBIT](DEBUG): Backplane powered. 2020-08-19T14:17:14.044Z,1597846634.044 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-08-19T14:17:14.107Z,1597846634.107 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 925 2020-08-19T14:17:14.204Z,1597846634.204 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-08-19T14:17:14.204Z,1597846634.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-08-19T14:17:14.205Z,1597846634.205 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-08-19T14:17:14.205Z,1597846634.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-08-19T14:17:14.205Z,1597846634.205 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-08-19T14:17:14.205Z,1597846634.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-08-19T14:17:14.205Z,1597846634.205 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-08-19T14:17:14.205Z,1597846634.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-08-19T14:17:14.205Z,1597846634.205 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-08-19T14:17:14.206Z,1597846634.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-08-19T14:17:14.206Z,1597846634.206 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-08-19T14:17:14.206Z,1597846634.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-08-19T14:17:14.206Z,1597846634.206 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-08-19T14:17:14.206Z,1597846634.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-08-19T14:17:14.206Z,1597846634.206 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-08-19T14:17:14.207Z,1597846634.207 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-08-19T14:17:14.328Z,1597846634.328 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-08-19T14:17:14.359Z,1597846634.359 [Radio_Surface](INFO): Powering up 2020-08-19T14:17:14.383Z,1597846634.383 [MissionManager](DEBUG): 2020-08-19T14:17:14.446Z,1597846634.446 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-08-19T14:17:14.496Z,1597846634.496 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-08-19T14:17:14.497Z,1597846634.497 [Default:A.Wait](DEBUG): Construct Wait. 2020-08-19T14:17:14.499Z,1597846634.499 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-08-19T14:17:14.528Z,1597846634.528 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-08-19T14:17:14.539Z,1597846634.539 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-08-19T14:17:14.560Z,1597846634.560 [Default:E.Execute](DEBUG): Construct Execute. 2020-08-19T14:17:14.580Z,1597846634.580 [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-08-19T14:17:14.584Z,1597846634.584 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,BPC1,CANONSampler,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, 2020-08-19T14:17:14.589Z,1597846634.589 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-08-19T14:17:14.759Z,1597846634.759 [CANONSampler](INFO): Powering down 2020-08-19T14:17:14.792Z,1597846634.792 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-08-19T14:17:14.799Z,1597846634.799 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-08-19T14:17:14.800Z,1597846634.800 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-08-19T14:17:14.807Z,1597846634.807 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-08-19T14:17:14.808Z,1597846634.808 [MassServo](DEBUG): Initializing EZServoServo. 2020-08-19T14:17:14.815Z,1597846634.815 [MassServo](DEBUG): Initializing MassServo. 2020-08-19T14:17:14.816Z,1597846634.816 [RudderServo](DEBUG): Initializing EZServoServo. 2020-08-19T14:17:14.823Z,1597846634.823 [RudderServo](DEBUG): Initializing RudderServo. 2020-08-19T14:17:14.824Z,1597846634.824 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-08-19T14:17:14.831Z,1597846634.831 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-08-19T14:17:15.735Z,1597846635.735 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-08-19T14:17:15.735Z,1597846635.735 [RudderServo](FAULT): Rudder failed to initialize 2020-08-19T14:17:15.735Z,1597846635.735 [RudderServo] Communications Fault, FailCount= 1 2020-08-19T14:17:15.735Z,1597846635.735 [RudderServo](ERROR): Communications Fault 2020-08-19T14:17:15.846Z,1597846635.846 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-08-19T14:17:16.004Z,1597846636.004 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-08-19T14:17:16.004Z,1597846636.004 [RudderServo](INFO): Powering down 2020-08-19T14:17:16.683Z,1597846636.683 [RudderServo](DEBUG): Initializing EZServoServo. 2020-08-19T14:17:16.804Z,1597846636.804 [RudderServo](DEBUG): Initializing RudderServo. 2020-08-19T14:17:16.808Z,1597846636.808 [CBIT](INFO): Clearing failed state for component RudderServo 2020-08-19T14:17:16.808Z,1597846636.808 [RudderServo] No Fault, FailCount= 1 2020-08-19T14:17:40.500Z,1597846660.500 [NAL9602](INFO): Powering up NAL9602 2020-08-19T14:17:51.413Z,1597846671.413 [NAL9602](INFO): NAL9602 initialized 2020-08-19T14:18:14.454Z,1597846694.454 [SBIT](IMPORTANT): Beginning Startup BIT 2020-08-19T14:18:14.458Z,1597846694.458 [CBIT](IMPORTANT): Beginning ground fault scan 2020-08-19T14:18:25.396Z,1597846705.396 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.012979 CHAN A1 (24V): 4.781929 CHAN A2 (12V): -0.002080 CHAN A3 (5V): 4.753929 CHAN B0 (3.3V): 0.001635 CHAN B1 (3.15aV): 0.000016 CHAN B2 (3.15bV): -0.000796 CHAN B3 (GND): 0.000621 OPEN: -0.000318 Full Scale Calc: 4.765 mA, -1.589 mA 2020-08-19T14:19:08.598Z,1597846748.598 [SBIT](IMPORTANT): SBIT PASSED 2020-08-19T14:19:08.598Z,1597846748.598 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-08-19T14:19:08.599Z,1597846748.599 [SBIT](IMPORTANT): CBIT.gf24Offset=-17 microampere; 2020-08-19T14:19:08.600Z,1597846748.600 [SBIT](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool; 2020-08-19T14:19:08.600Z,1597846748.600 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter; 2020-08-19T14:19:08.600Z,1597846748.600 [SBIT](IMPORTANT): Express linearApproximation platform_average_power 1.000000 watt; 2020-08-19T14:19:08.600Z,1597846748.600 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=245.938302 cubic_centimeter; 2020-08-19T14:19:08.600Z,1597846748.600 [SBIT](IMPORTANT): VerticalControl.massDefault=-10.009265 millimeter; 2020-08-19T14:19:09.005Z,1597846749.005 [MissionManager](IMPORTANT): Started mission Startup 2020-08-19T14:19:09.005Z,1597846749.005 [Startup] Running Loop=1 2020-08-19T14:19:09.006Z,1597846749.006 [Startup](DEBUG): Aggregate::initialize Startup 2020-08-19T14:19:09.006Z,1597846749.006 [Startup:A.GoToSurface] Running Loop=1 2020-08-19T14:19:09.006Z,1597846749.006 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-08-19T14:19:09.006Z,1597846749.006 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-08-19T14:19:09.007Z,1597846749.007 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-08-19T14:19:09.007Z,1597846749.007 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-08-19T14:19:09.008Z,1597846749.008 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-08-19T14:19:09.008Z,1597846749.008 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-08-19T14:19:09.009Z,1597846749.009 [Startup:StartupSatComms] Running Loop=1 2020-08-19T14:19:09.009Z,1597846749.009 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-08-19T14:19:09.009Z,1597846749.009 [Startup:StartupSatComms:A] Running Loop=1 2020-08-19T14:19:09.398Z,1597846749.398 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-08-19T14:19:47.733Z,1597846787.733 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004859 2020-08-19T14:20:09.225Z,1597846809.225 [Startup:StartupSatComms:A](INFO): Timed out from 2020-08-19T14:19:09.0Z 2020-08-19T14:20:09.226Z,1597846809.226 [Sta