2019-10-03T07:50:23.066Z,1570089023.066 [Supervisor](DEBUG): Initializing supervisor. 2019-10-03T07:50:23.069Z,1570089023.069 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-10-03T07:50:23.070Z,1570089023.070 [SyncHandler](INFO): Protected caller Thread ID is 806 2019-10-03T07:50:23.070Z,1570089023.070 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-10-03T07:50:23.071Z,1570089023.071 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-10-03T07:50:23.071Z,1570089023.071 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2019-10-03T07:50:23.074Z,1570089023.074 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-10-03T07:50:23.084Z,1570089023.084 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-10-03T07:50:23.085Z,1570089023.085 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-10-03T07:50:23.086Z,1570089023.086 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2019-10-03T07:50:23.087Z,1570089023.087 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-10-03T07:50:23.087Z,1570089023.087 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-10-03T07:50:23.088Z,1570089023.088 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2019-10-03T07:50:23.090Z,1570089023.090 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-10-03T07:50:23.090Z,1570089023.090 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-10-03T07:50:23.094Z,1570089023.094 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-10-03T07:50:23.189Z,1570089023.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-10-03T07:50:23.191Z,1570089023.191 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-10-03T07:50:23.755Z,1570089023.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-10-03T07:50:23.757Z,1570089023.757 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-10-03T07:50:23.854Z,1570089023.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-10-03T07:50:23.855Z,1570089023.855 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-10-03T07:50:23.956Z,1570089023.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-10-03T07:50:23.958Z,1570089023.958 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-10-03T07:50:24.039Z,1570089024.039 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-10-03T07:50:24.175Z,1570089024.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-10-03T07:50:24.175Z,1570089024.175 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-10-03T07:50:24.469Z,1570089024.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-10-03T07:50:24.471Z,1570089024.471 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-10-03T07:50:24.920Z,1570089024.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-10-03T07:50:24.921Z,1570089024.921 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-10-03T07:50:25.064Z,1570089025.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-10-03T07:50:25.065Z,1570089025.065 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-10-03T07:50:25.299Z,1570089025.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-10-03T07:50:25.301Z,1570089025.301 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-10-03T07:50:25.750Z,1570089025.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-10-03T07:50:25.751Z,1570089025.751 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-10-03T07:50:25.959Z,1570089025.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-10-03T07:50:25.960Z,1570089025.960 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-10-03T07:50:26.163Z,1570089026.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-10-03T07:50:26.164Z,1570089026.164 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-10-03T07:50:26.564Z,1570089026.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-10-03T07:50:26.893Z,1570089026.893 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-10-03T07:50:27.497Z,1570089027.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-10-03T07:50:27.499Z,1570089027.499 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2019-10-03T07:50:27.502Z,1570089027.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2019-10-03T07:50:27.585Z,1570089027.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2019-10-03T07:50:27.738Z,1570089027.738 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2019-10-03T07:50:27.845Z,1570089027.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2019-10-03T07:50:27.930Z,1570089027.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2019-10-03T07:50:28.023Z,1570089028.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2019-10-03T07:50:28.216Z,1570089028.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2019-10-03T07:50:28.440Z,1570089028.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-10-03T07:50:28.441Z,1570089028.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2019-10-03T07:50:28.534Z,1570089028.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2019-10-03T07:50:28.632Z,1570089028.632 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2019-10-03T07:50:28.772Z,1570089028.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2019-10-03T07:50:28.871Z,1570089028.871 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2019-10-03T07:50:28.875Z,1570089028.875 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-10-03T07:50:28.943Z,1570089028.943 [VerticalControl](DEBUG): Construct VerticalControl. 2019-10-03T07:50:29.055Z,1570089029.055 [VerticalControl] Loaded 2019-10-03T07:50:29.056Z,1570089029.056 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-10-03T07:50:29.056Z,1570089029.056 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-10-03T07:50:29.125Z,1570089029.125 [HorizontalControl] Loaded 2019-10-03T07:50:29.125Z,1570089029.125 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-10-03T07:50:29.126Z,1570089029.126 [SpeedControl](DEBUG): Construct SpeedControl. 2019-10-03T07:50:29.131Z,1570089029.131 [SpeedControl] Loaded 2019-10-03T07:50:29.132Z,1570089029.132 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-10-03T07:50:29.132Z,1570089029.132 [LoopControl](DEBUG): Construct LoopControl. 2019-10-03T07:50:29.133Z,1570089029.133 [LoopControl] Loaded 2019-10-03T07:50:29.133Z,1570089029.133 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-10-03T07:50:29.134Z,1570089029.134 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-10-03T07:50:29.134Z,1570089029.134 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-10-03T07:50:29.189Z,1570089029.189 [DepthRateCalculator] Loaded 2019-10-03T07:50:29.189Z,1570089029.189 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-10-03T07:50:29.195Z,1570089029.195 [PitchRateCalculator] Loaded 2019-10-03T07:50:29.195Z,1570089029.195 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-10-03T07:50:29.210Z,1570089029.210 [SpeedCalculator] Loaded 2019-10-03T07:50:29.211Z,1570089029.211 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-10-03T07:50:29.232Z,1570089029.232 [TempGradientCalculator] Loaded 2019-10-03T07:50:29.232Z,1570089029.232 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-10-03T07:50:29.237Z,1570089029.237 [YawRateCalculator] Loaded 2019-10-03T07:50:29.238Z,1570089029.238 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-10-03T07:50:29.267Z,1570089029.267 [ElevatorOffsetCalculator] Loaded 2019-10-03T07:50:29.267Z,1570089029.267 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-10-03T07:50:29.268Z,1570089029.268 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-10-03T07:50:29.269Z,1570089029.269 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-10-03T07:50:29.319Z,1570089029.319 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-10-03T07:50:29.319Z,1570089029.319 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-10-03T07:50:29.431Z,1570089029.431 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-10-03T07:50:29.432Z,1570089029.432 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-10-03T07:50:29.773Z,1570089029.773 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-10-03T07:50:29.774Z,1570089029.774 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-10-03T07:50:29.900Z,1570089029.900 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-10-03T07:50:29.900Z,1570089029.900 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-10-03T07:50:30.383Z,1570089030.383 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2019-10-03T07:50:30.387Z,1570089030.387 [AHRS_M2](INFO): created writer for : platform_orientation 2019-10-03T07:50:30.389Z,1570089030.389 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2019-10-03T07:50:30.394Z,1570089030.394 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2019-10-03T07:50:30.395Z,1570089030.395 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2019-10-03T07:50:30.400Z,1570089030.400 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2019-10-03T07:50:30.400Z,1570089030.400 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2019-10-03T07:50:30.406Z,1570089030.406 [AHRS_M2](INFO): created writer for : platform_roll_angle 2019-10-03T07:50:30.582Z,1570089030.582 [AHRS_M2] Loaded 2019-10-03T07:50:30.583Z,1570089030.583 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-10-03T07:50:30.722Z,1570089030.722 [DataOverHttps] Loaded 2019-10-03T07:50:30.722Z,1570089030.722 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-10-03T07:50:30.724Z,1570089030.724 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0 2019-10-03T07:50:30.724Z,1570089030.724 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 889 2019-10-03T07:50:30.737Z,1570089030.737 [Depth_Keller] Loaded 2019-10-03T07:50:30.738Z,1570089030.738 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-10-03T07:50:30.743Z,1570089030.743 [DropWeight] Loaded 2019-10-03T07:50:30.743Z,1570089030.743 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-10-03T07:50:30.840Z,1570089030.840 [NAL9602] Loaded 2019-10-03T07:50:30.840Z,1570089030.840 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-10-03T07:50:30.856Z,1570089030.856 [Onboard] Loaded 2019-10-03T07:50:30.856Z,1570089030.856 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-10-03T07:50:30.859Z,1570089030.859 [Radio_Surface] Loaded 2019-10-03T07:50:30.859Z,1570089030.859 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-10-03T07:50:30.860Z,1570089030.860 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0 2019-10-03T07:50:30.861Z,1570089030.861 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 890 2019-10-03T07:50:30.905Z,1570089030.905 [RDI_Pathfinder] Loaded 2019-10-03T07:50:30.906Z,1570089030.906 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-10-03T07:50:31.031Z,1570089031.031 [DAT] Loaded 2019-10-03T07:50:31.031Z,1570089031.031 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-10-03T07:50:33.328Z,1570089033.328 [BPC1] Loaded 2019-10-03T07:50:33.329Z,1570089033.329 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-10-03T07:50:33.329Z,1570089033.329 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-10-03T07:50:33.330Z,1570089033.330 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-10-03T07:50:33.450Z,1570089033.450 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-10-03T07:50:33.450Z,1570089033.450 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-10-03T07:50:33.471Z,1570089033.471 [NavChart] Loaded 2019-10-03T07:50:33.471Z,1570089033.471 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-10-03T07:50:33.475Z,1570089033.475 [UniversalFixResidualReporter] Loaded 2019-10-03T07:50:33.475Z,1570089033.475 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-10-03T07:50:33.476Z,1570089033.476 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-10-03T07:50:33.476Z,1570089033.476 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-10-03T07:50:33.638Z,1570089033.638 [SBIT](DEBUG): Construct Startup Built In Test. 2019-10-03T07:50:33.649Z,1570089033.649 [SBIT] Loaded 2019-10-03T07:50:33.650Z,1570089033.650 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-10-03T07:50:33.650Z,1570089033.650 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-10-03T07:50:33.662Z,1570089033.662 [IBIT] Loaded 2019-10-03T07:50:33.662Z,1570089033.662 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-10-03T07:50:33.665Z,1570089033.665 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-10-03T07:50:33.803Z,1570089033.803 [CBIT] Loaded 2019-10-03T07:50:33.803Z,1570089033.803 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-10-03T07:50:33.803Z,1570089033.803 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-10-03T07:50:33.804Z,1570089033.804 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-10-03T07:50:33.931Z,1570089033.931 [BuoyancyServo] Loaded 2019-10-03T07:50:33.932Z,1570089033.932 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-10-03T07:50:33.943Z,1570089033.943 [ElevatorServo] Loaded 2019-10-03T07:50:33.943Z,1570089033.943 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-10-03T07:50:33.954Z,1570089033.954 [MassServo] Loaded 2019-10-03T07:50:33.955Z,1570089033.955 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-10-03T07:50:33.965Z,1570089033.965 [RudderServo] Loaded 2019-10-03T07:50:33.966Z,1570089033.966 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-10-03T07:50:33.976Z,1570089033.976 [ThrusterServo] Loaded 2019-10-03T07:50:33.977Z,1570089033.977 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-10-03T07:50:33.977Z,1570089033.977 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-10-03T07:50:33.978Z,1570089033.978 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-10-03T07:50:34.002Z,1570089034.002 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-10-03T07:50:34.002Z,1570089034.002 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-10-03T07:50:34.289Z,1570089034.289 [BackSeatDriver] Loaded 2019-10-03T07:50:34.289Z,1570089034.289 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2019-10-03T07:50:34.290Z,1570089034.290 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40A654E0 2019-10-03T07:50:34.290Z,1570089034.290 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 891 2019-10-03T07:50:34.300Z,1570089034.300 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-10-03T07:50:34.305Z,1570089034.305 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-10-03T07:50:34.305Z,1570089034.305 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-10-03T07:50:34.310Z,1570089034.310 [CTD_Seabird](INFO): created writer for : depth 2019-10-03T07:50:34.311Z,1570089034.311 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-10-03T07:50:34.316Z,1570089034.316 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-10-03T07:50:34.317Z,1570089034.317 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-10-03T07:50:34.322Z,1570089034.322 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-10-03T07:50:34.323Z,1570089034.323 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-10-03T07:50:34.328Z,1570089034.328 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-10-03T07:50:34.329Z,1570089034.329 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-10-03T07:50:34.334Z,1570089034.334 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-10-03T07:50:34.334Z,1570089034.334 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-10-03T07:50:34.340Z,1570089034.340 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-10-03T07:50:34.367Z,1570089034.367 [CTD_Seabird] Loaded 2019-10-03T07:50:34.367Z,1570089034.367 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-10-03T07:50:34.368Z,1570089034.368 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A954E0 2019-10-03T07:50:34.369Z,1570089034.369 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 892 2019-10-03T07:50:34.375Z,1570089034.375 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2019-10-03T07:50:34.375Z,1570089034.375 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2019-10-03T07:50:34.380Z,1570089034.380 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2019-10-03T07:50:34.380Z,1570089034.380 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2019-10-03T07:50:34.384Z,1570089034.384 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2019-10-03T07:50:34.384Z,1570089034.384 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2019-10-03T07:50:34.389Z,1570089034.389 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2019-10-03T07:50:34.389Z,1570089034.389 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2019-10-03T07:50:34.394Z,1570089034.394 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2019-10-03T07:50:34.394Z,1570089034.394 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2019-10-03T07:50:34.398Z,1570089034.398 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2019-10-03T07:50:34.398Z,1570089034.398 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2019-10-03T07:50:34.403Z,1570089034.403 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2019-10-03T07:50:34.403Z,1570089034.403 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2019-10-03T07:50:34.407Z,1570089034.407 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T07:50:34.411Z,1570089034.411 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T07:50:34.412Z,1570089034.412 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T07:50:34.412Z,1570089034.412 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T07:50:34.416Z,1570089034.416 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T07:50:34.416Z,1570089034.416 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T07:50:34.421Z,1570089034.421 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T07:50:34.421Z,1570089034.421 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T07:50:34.425Z,1570089034.425 [WetLabsBB2FL] Loaded 2019-10-03T07:50:34.426Z,1570089034.426 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-10-03T07:50:34.427Z,1570089034.427 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0 2019-10-03T07:50:34.427Z,1570089034.427 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 893 2019-10-03T07:50:34.662Z,1570089034.662 [WetLabsUBAT..SerialDriver](INFO): Created logger 2019-10-03T07:50:34.662Z,1570089034.662 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2019-10-03T07:50:34.663Z,1570089034.663 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2019-10-03T07:50:34.668Z,1570089034.668 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2019-10-03T07:50:34.668Z,1570089034.668 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2019-10-03T07:50:34.668Z,1570089034.668 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2019-10-03T07:50:34.673Z,1570089034.673 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2019-10-03T07:50:34.673Z,1570089034.673 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2019-10-03T07:50:34.677Z,1570089034.677 [WetLabsUBAT] Loaded 2019-10-03T07:50:34.678Z,1570089034.678 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2019-10-03T07:50:34.679Z,1570089034.679 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0 2019-10-03T07:50:34.679Z,1570089034.679 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 894 2019-10-03T07:50:34.679Z,1570089034.679 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-10-03T07:50:34.683Z,1570089034.683 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-10-03T07:50:34.684Z,1570089034.684 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-10-03T07:50:34.690Z,1570089034.690 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-10-03T07:50:34.691Z,1570089034.691 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B254E0 2019-10-03T07:50:34.692Z,1570089034.692 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 895 2019-10-03T07:50:34.696Z,1570089034.696 [Supervisor](INFO): Main Thread ID is 802 2019-10-03T07:50:34.696Z,1570089034.696 [Supervisor](DEBUG): Running supervisor. 2019-10-03T07:50:34.697Z,1570089034.697 [CommandLine ThreadHandler](INFO): Handler Thread ID is 896 2019-10-03T07:50:34.700Z,1570089034.700 [controlThread ThreadHandler](INFO): Handler Thread ID is 897 2019-10-03T07:50:34.700Z,1570089034.700 [controlThread](DEBUG): Initializing ControlThread 2019-10-03T07:50:34.701Z,1570089034.701 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-10-03T07:50:34.702Z,1570089034.702 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-10-03T07:50:34.703Z,1570089034.703 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-10-03T07:50:34.703Z,1570089034.703 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-10-03T07:50:34.704Z,1570089034.704 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-10-03T07:50:34.704Z,1570089034.704 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-10-03T07:50:34.704Z,1570089034.704 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-10-03T07:50:34.705Z,1570089034.705 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-10-03T07:50:34.705Z,1570089034.705 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-10-03T07:50:34.705Z,1570089034.705 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-10-03T07:50:34.712Z,1570089034.712 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-10-03T07:50:34.712Z,1570089034.712 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-10-03T07:50:34.712Z,1570089034.712 [SBIT](INFO): Initialize SBIT Component. 2019-10-03T07:50:34.713Z,1570089034.713 [SBIT](IMPORTANT): git: 2019-10-02_A-22-gc0737ec 2019-10-03T07:50:34.713Z,1570089034.713 [SBIT](INFO): git hash: c0737ec0000e1c9d4bf2eea0c11ce458bb27ad8a 2019-10-03T07:50:34.713Z,1570089034.713 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-10-03T07:50:34.714Z,1570089034.714 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-10-03T07:50:34.715Z,1570089034.715 [SBIT](INFO): Beginning SBIT in 44.000000 seconds. 2019-10-03T07:50:34.715Z,1570089034.715 [IBIT](INFO): Initialize IBIT Component. 2019-10-03T07:50:34.716Z,1570089034.716 [CBIT](DEBUG): Initialize CBIT Component. 2019-10-03T07:50:34.717Z,1570089034.717 [logger ThreadHandler](INFO): Handler Thread ID is 898 2019-10-03T07:50:34.729Z,1570089034.729 [CBIT](DEBUG): Initialized mux pins. 2019-10-03T07:50:34.729Z,1570089034.729 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2019-10-03T07:50:34.729Z,1570089034.729 [CBIT](DEBUG): Initializing the watchdog timer. 2019-10-03T07:50:34.737Z,1570089034.737 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 899 2019-10-03T07:50:34.738Z,1570089034.738 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-10-03T07:50:34.749Z,1570089034.749 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 900 2019-10-03T07:50:34.753Z,1570089034.753 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-10-03T07:50:34.753Z,1570089034.753 [CBIT](DEBUG): Initializing heartbeat. 2019-10-03T07:50:34.761Z,1570089034.761 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 901 2019-10-03T07:50:34.777Z,1570089034.777 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 902 2019-10-03T07:50:34.778Z,1570089034.778 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-10-03T07:50:34.781Z,1570089034.781 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-10-03T07:50:34.782Z,1570089034.782 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 904 2019-10-03T07:50:34.783Z,1570089034.783 [WetLabsBB2FL](INFO): Powering down 2019-10-03T07:50:34.813Z,1570089034.813 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 905 2019-10-03T07:50:34.825Z,1570089034.825 [CBIT](DEBUG): Deactivating GF circuits. 2019-10-03T07:50:34.825Z,1570089034.825 [CBIT](DEBUG): Deactivating emergency mode. 2019-10-03T07:50:34.826Z,1570089034.826 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 906 2019-10-03T07:50:34.835Z,1570089034.835 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-10-03T07:50:34.835Z,1570089034.835 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-10-03T07:50:34.835Z,1570089034.835 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-10-03T07:50:34.836Z,1570089034.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-10-03T07:50:34.836Z,1570089034.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-10-03T07:50:34.836Z,1570089034.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-10-03T07:50:34.836Z,1570089034.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-10-03T07:50:34.836Z,1570089034.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-10-03T07:50:34.837Z,1570089034.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-10-03T07:50:34.837Z,1570089034.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-10-03T07:50:34.837Z,1570089034.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-10-03T07:50:34.837Z,1570089034.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-10-03T07:50:34.838Z,1570089034.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-10-03T07:50:34.838Z,1570089034.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-10-03T07:50:34.838Z,1570089034.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-10-03T07:50:34.838Z,1570089034.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-10-03T07:50:34.861Z,1570089034.861 [CBIT](DEBUG): Backplane powered. 2019-10-03T07:50:34.863Z,1570089034.863 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-10-03T07:50:34.902Z,1570089034.902 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-03T07:50:34.913Z,1570089034.913 [MissionManager](DEBUG): 2019-10-03T07:50:34.913Z,1570089034.913 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-10-03T07:50:34.966Z,1570089034.966 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-10-03T07:50:34.967Z,1570089034.967 [Default:A.Wait](DEBUG): Construct Wait. 2019-10-03T07:50:34.981Z,1570089034.981 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-03T07:50:35.008Z,1570089035.008 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-10-03T07:50:35.010Z,1570089035.010 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-10-03T07:50:35.032Z,1570089035.032 [Default:E.Execute](DEBUG): Construct Execute. 2019-10-03T07:50:35.043Z,1570089035.043 [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-10-03T07:50:35.047Z,1570089035.047 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,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, 2019-10-03T07:50:35.057Z,1570089035.057 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-10-03T07:50:35.153Z,1570089035.153 [Radio_Surface](INFO): Powering up 2019-10-03T07:50:35.161Z,1570089035.161 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2019-10-03T07:50:35.196Z,1570089035.196 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-10-03T07:50:35.196Z,1570089035.196 [DAT](INFO): Powering up 2019-10-03T07:50:35.196Z,1570089035.196 [DAT](DEBUG): Initializing DAT. 2019-10-03T07:50:35.197Z,1570089035.197 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-10-03T07:50:35.289Z,1570089035.289 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-10-03T07:50:35.343Z,1570089035.343 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-10-03T07:50:35.349Z,1570089035.349 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-10-03T07:50:35.350Z,1570089035.350 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-10-03T07:50:35.357Z,1570089035.357 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-10-03T07:50:35.358Z,1570089035.358 [MassServo](DEBUG): Initializing EZServoServo. 2019-10-03T07:50:35.365Z,1570089035.365 [MassServo](DEBUG): Initializing MassServo. 2019-10-03T07:50:35.366Z,1570089035.366 [RudderServo](DEBUG): Initializing EZServoServo. 2019-10-03T07:50:35.373Z,1570089035.373 [RudderServo](DEBUG): Initializing RudderServo. 2019-10-03T07:50:35.374Z,1570089035.374 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-10-03T07:50:35.381Z,1570089035.381 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-10-03T07:50:36.201Z,1570089036.201 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-10-03T07:50:36.201Z,1570089036.201 [RudderServo](FAULT): Rudder failed to initialize 2019-10-03T07:50:36.201Z,1570089036.201 [RudderServo] Communications Fault, FailCount= 1 2019-10-03T07:50:36.201Z,1570089036.201 [RudderServo](ERROR): Communications Fault 2019-10-03T07:50:36.372Z,1570089036.372 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-10-03T07:50:36.554Z,1570089036.554 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-10-03T07:50:36.554Z,1570089036.554 [RudderServo](INFO): Powering down 2019-10-03T07:50:37.216Z,1570089037.216 [RudderServo](DEBUG): Initializing EZServoServo. 2019-10-03T07:50:37.334Z,1570089037.334 [RudderServo](DEBUG): Initializing RudderServo. 2019-10-03T07:50:37.338Z,1570089037.338 [CBIT](INFO): Clearing failed state for component RudderServo 2019-10-03T07:50:37.338Z,1570089037.338 [RudderServo] No Fault, FailCount= 1 2019-10-03T07:50:41.809Z,1570089041.809 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2019-10-03T07:50:48.954Z,1570089048.954 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-10-03T07:50:49.370Z,1570089049.370 [DAT](INFO): setting local address to 4 2019-10-03T07:50:49.815Z,1570089049.815 [DAT](INFO): set local address to 4 2019-10-03T07:51:01.068Z,1570089061.068 [NAL9602](INFO): Powering up NAL9602 2019-10-03T07:51:11.962Z,1570089071.962 [NAL9602](INFO): NAL9602 initialized 2019-10-03T07:51:12.789Z,1570089072.789 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:51:19.263Z,1570089079.263 [SBIT](IMPORTANT): Beginning Startup BIT 2019-10-03T07:51:19.270Z,1570089079.270 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-03T07:51:30.345Z,1570089090.345 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.008968 CHAN A1 (24V): 0.087201 CHAN A2 (12V): -0.007874 CHAN A3 (5V): -0.001736 CHAN B0 (3.3V): 0.000293 CHAN B1 (3.15aV): 0.000136 CHAN B2 (3.15bV): -0.000298 CHAN B3 (GND): 0.001516 OPEN: -0.003175 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-03T07:52:01.116Z,1570089121.116 [NAL9602](INFO): SBD MO Status=2, MOMSN=1893, MT Status=2, MTMSN=0 2019-10-03T07:52:01.116Z,1570089121.116 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-03T07:52:12.573Z,1570089132.573 [SBIT](IMPORTANT): SBIT PASSED 2019-10-03T07:52:12.618Z,1570089132.618 [CommandLine](IMPORTANT): got command configSet list 2019-10-03T07:52:12.618Z,1570089132.618 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-10-03T07:52:12.623Z,1570089132.623 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2019-10-03T07:52:12.623Z,1570089132.623 [CommandLine](IMPORTANT): BackSeatDriver.verbosity=0 count; 2019-10-03T07:52:12.623Z,1570089132.623 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere; 2019-10-03T07:52:12.623Z,1570089132.623 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-10-03T07:52:12.623Z,1570089132.623 [CommandLine](IMPORTANT): Express none RDI_Pathfinder.height_above_sea_floor; 2019-10-03T07:52:12.623Z,1570089132.623 [CommandLine](IMPORTANT): Express linearApproximation Tracking.range_to_contact 10.000000 meter; 2019-10-03T07:52:12.623Z,1570089132.623 [CommandLine](IMPORTANT): Express none WetLabsUBAT.biolum_potential; 2019-10-03T07:52:12.623Z,1570089132.623 [CommandLine](IMPORTANT): Express none WetLabsUBAT.flow_rate; 2019-10-03T07:52:12.623Z,1570089132.623 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter; 2019-10-03T07:52:12.624Z,1570089132.624 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsUBAT.biolum_potential 1000000.000000 none; 2019-10-03T07:52:12.624Z,1570089132.624 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2019-10-03T07:52:12.624Z,1570089132.624 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool; 2019-10-03T07:52:12.624Z,1570089132.624 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=80 cubic_centimeter; 2019-10-03T07:52:12.624Z,1570089132.624 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter; 2019-10-03T07:52:12.973Z,1570089132.973 [MissionManager](IMPORTANT): Started mission Startup 2019-10-03T07:52:12.974Z,1570089132.974 [Startup] Running Loop=1 2019-10-03T07:52:12.974Z,1570089132.974 [Startup](DEBUG): Aggregate::initialize Startup 2019-10-03T07:52:12.974Z,1570089132.974 [Startup:A.GoToSurface] Running Loop=1 2019-10-03T07:52:12.974Z,1570089132.974 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-03T07:52:12.975Z,1570089132.975 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-03T07:52:12.975Z,1570089132.975 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-03T07:52:12.975Z,1570089132.975 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-03T07:52:12.976Z,1570089132.976 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-03T07:52:12.976Z,1570089132.976 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-03T07:52:12.978Z,1570089132.978 [Startup:StartupSatComms] Running Loop=1 2019-10-03T07:52:12.978Z,1570089132.978 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-10-03T07:52:12.978Z,1570089132.978 [Startup:StartupSatComms:A] Running Loop=1 2019-10-03T07:52:13.336Z,1570089133.336 [NAL9602](INFO): SBD MO Status=0, MOMSN=1893, MT Status=0, MTMSN=0 2019-10-03T07:52:13.336Z,1570089133.336 [NAL9602](INFO): No messages in MT queue 2019-10-03T07:52:13.376Z,1570089133.376 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-10-03T07:52:14.165Z,1570089134.165 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:16.577Z,1570089136.577 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:19.407Z,1570089139.407 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:21.829Z,1570089141.829 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:24.657Z,1570089144.657 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:24.767Z,1570089144.767 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-10-03T07:52:27.485Z,1570089147.485 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:30.734Z,1570089150.734 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:33.553Z,1570089153.553 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:36.785Z,1570089156.785 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:39.605Z,1570089159.605 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:42.433Z,1570089162.433 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:45.665Z,1570089165.665 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:48.495Z,1570089168.495 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:51.389Z,1570089171.389 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-10-03T07:52:51.749Z,1570089171.749 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:54.557Z,1570089174.557 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:57.785Z,1570089177.785 [NAL9602](DEBUG): Fix Requested 2019-10-03T07:52:58.180Z,1570089178.180 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,075136.00,A,3648.18353,N,12147.27738,W,0.136,0.00,031019,,,A*79 2019-10-03T07:52:58.187Z,1570089178.187 [NAL9602](INFO): GPS fix at 20191003T075136: (36.803059, -121.787956) 2019-10-03T07:52:58.220Z,1570089178.220 [Startup:StartupSatComms:A] Stopped 2019-10-03T07:52:58.220Z,1570089178.220 [Startup:StartupSatComms:B] Running Loop=1 2019-10-03T07:52:58.656Z,1570089178.656 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-10-03T07:53:05.956Z,1570089185.956 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-10-03T07:53:21.931Z,1570089201.931 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005089 2019-10-03T07:53:29.796Z,1570089209.796 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191003T001023/Courier0097.lzma 2019-10-03T07:53:30.489Z,1570089210.489 [NAL9602](INFO): SBD MO Status=2, MOMSN=1894, MT Status=2, MTMSN=0 2019-10-03T07:53:30.490Z,1570089210.490 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-03T07:53:31.803Z,1570089211.803 [DataOverHttps](INFO): Moved sent file to Logs/20191003T001023/Courier0097.lzma.bak 2019-10-03T07:53:31.803Z,1570089211.803 [DataOverHttps](INFO): SBD MOMSN=11843102 2019-10-03T07:53:49.541Z,1570089229.541 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20191003T075023/Courier0000.lzma 2019-10-03T07:53:51.546Z,1570089231.546 [DataOverHttps](INFO): Moved sent file to Logs/20191003T075023/Courier0000.lzma.bak 2019-10-03T07:53:51.546Z,1570089231.546 [DataOverHttps](INFO): SBD MOMSN=11843153 2019-10-03T07:53:58.398Z,1570089238.398 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-03T07:52:58.2Z 2019-10-03T07:53:58.398Z,1570089238.398 [Startup:StartupSatComms:B] Stopped 2019-10-03T07:53:58.398Z,1570089238.398 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-10-03T07:53:58.398Z,1570089238.398 [Startup:StartupSatComms] Stopped 2019-10-03T07:53:58.398Z,1570089238.398 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-10-03T07:53:58.399Z,1570089238.399 [Startup](INFO): Completed Startup 2019-10-03T07:53:58.399Z,1570089238.399 [MissionManager](INFO): Startup is completed. 2019-10-03T07:53:58.399Z,1570089238.399 [MissionManager](INFO): Uninitializing Mission Startup 2019-10-03T07:53:58.399Z,1570089238.399 [Startup] Stopped 2019-10-03T07:53:58.399Z,1570089238.399 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-10-03T07:53:58.400Z,1570089238.400 [Startup:A.GoToSurface] Stopped 2019-10-03T07:53:58.400Z,1570089238.400 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-10-03T07:53:58.792Z,1570089238.792 [MissionManager](IMPORTANT): Started mission Default 2019-10-03T07:53:58.792Z,1570089238.792 [Default] Running Loop=1 2019-10-03T07:53:58.793Z,1570089238.793 [Default](DEBUG): Aggregate::initialize Default 2019-10-03T07:53:58.793Z,1570089238.793 [Default:B.GoToSurface] Running Loop=1 2019-10-03T07:53:58.793Z,1570089238.793 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-03T07:53:58.793Z,1570089238.793 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-03T07:53:58.793Z,1570089238.793 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-03T07:53:58.794Z,1570089238.794 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-03T07:53:58.794Z,1570089238.794 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-03T07:53:58.794Z,1570089238.794 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-03T07:53:58.794Z,1570089238.794 [Default:A.Wait] Running Loop=1 2019-10-03T07:53:58.794Z,1570089238.794 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-10-03T07:54:01.186Z,1570089241.186 [NAL9602](INFO): Not Powering down - fa