2019-11-19T20:34:19.785Z,1574195659.785 [Supervisor](DEBUG): Initializing supervisor. 2019-11-19T20:34:19.797Z,1574195659.797 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-11-19T20:34:19.798Z,1574195659.798 [SyncHandler](INFO): Protected caller Thread ID is 1996 2019-11-19T20:34:19.798Z,1574195659.798 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-11-19T20:34:19.809Z,1574195659.809 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-11-19T20:34:19.809Z,1574195659.809 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1997 2019-11-19T20:34:19.836Z,1574195659.836 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-11-19T20:34:19.861Z,1574195659.861 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-11-19T20:34:19.873Z,1574195659.873 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-11-19T20:34:19.873Z,1574195659.873 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1998 2019-11-19T20:34:19.874Z,1574195659.874 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-11-19T20:34:19.892Z,1574195659.892 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-11-19T20:34:19.892Z,1574195659.892 [logger ThreadHandler](INFO): Protected caller Thread ID is 1999 2019-11-19T20:34:19.894Z,1574195659.894 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-11-19T20:34:19.895Z,1574195659.895 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-11-19T20:34:19.899Z,1574195659.899 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-11-19T20:34:20.060Z,1574195660.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-11-19T20:34:20.062Z,1574195660.062 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-11-19T20:34:20.643Z,1574195660.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-11-19T20:34:20.645Z,1574195660.645 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-11-19T20:34:20.747Z,1574195660.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-11-19T20:34:20.749Z,1574195660.749 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-11-19T20:34:20.852Z,1574195660.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-11-19T20:34:20.854Z,1574195660.854 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-11-19T20:34:20.997Z,1574195660.997 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-11-19T20:34:21.256Z,1574195661.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-11-19T20:34:21.256Z,1574195661.256 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-11-19T20:34:21.826Z,1574195661.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-11-19T20:34:21.827Z,1574195661.827 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-11-19T20:34:22.710Z,1574195662.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-11-19T20:34:22.711Z,1574195662.711 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-11-19T20:34:22.976Z,1574195662.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-11-19T20:34:22.977Z,1574195662.977 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-11-19T20:34:23.388Z,1574195663.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-11-19T20:34:23.389Z,1574195663.389 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-11-19T20:34:24.731Z,1574195664.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-11-19T20:34:24.733Z,1574195664.733 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-11-19T20:34:25.138Z,1574195665.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-11-19T20:34:25.140Z,1574195665.140 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-11-19T20:34:25.520Z,1574195665.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-11-19T20:34:25.522Z,1574195665.522 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-11-19T20:34:26.311Z,1574195666.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-11-19T20:34:26.313Z,1574195666.313 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-11-19T20:34:26.950Z,1574195666.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-11-19T20:34:26.952Z,1574195666.952 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2019-11-19T20:34:26.955Z,1574195666.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2019-11-19T20:34:27.100Z,1574195667.100 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2019-11-19T20:34:27.330Z,1574195667.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2019-11-19T20:34:27.439Z,1574195667.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2019-11-19T20:34:27.527Z,1574195667.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2019-11-19T20:34:27.624Z,1574195667.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2019-11-19T20:34:27.818Z,1574195667.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2019-11-19T20:34:28.042Z,1574195668.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-11-19T20:34:28.043Z,1574195668.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2019-11-19T20:34:28.139Z,1574195668.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2019-11-19T20:34:28.239Z,1574195668.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2019-11-19T20:34:28.379Z,1574195668.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2019-11-19T20:34:28.613Z,1574195668.613 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2019-11-19T20:34:28.614Z,1574195668.614 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-11-19T20:34:28.791Z,1574195668.791 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-11-19T20:34:28.938Z,1574195668.938 [VerticalControl](DEBUG): Construct VerticalControl. 2019-11-19T20:34:29.217Z,1574195669.217 [VerticalControl] Loaded 2019-11-19T20:34:29.218Z,1574195669.218 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-11-19T20:34:29.218Z,1574195669.218 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-11-19T20:34:29.285Z,1574195669.285 [HorizontalControl] Loaded 2019-11-19T20:34:29.285Z,1574195669.285 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-11-19T20:34:29.286Z,1574195669.286 [SpeedControl](DEBUG): Construct SpeedControl. 2019-11-19T20:34:29.291Z,1574195669.291 [SpeedControl] Loaded 2019-11-19T20:34:29.291Z,1574195669.291 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-11-19T20:34:29.292Z,1574195669.292 [LoopControl](DEBUG): Construct LoopControl. 2019-11-19T20:34:29.292Z,1574195669.292 [LoopControl] Loaded 2019-11-19T20:34:29.293Z,1574195669.293 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-11-19T20:34:29.293Z,1574195669.293 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-11-19T20:34:29.505Z,1574195669.505 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-11-19T20:34:29.560Z,1574195669.560 [DepthRateCalculator] Loaded 2019-11-19T20:34:29.560Z,1574195669.560 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-11-19T20:34:29.565Z,1574195669.565 [PitchRateCalculator] Loaded 2019-11-19T20:34:29.566Z,1574195669.566 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-11-19T20:34:29.580Z,1574195669.580 [SpeedCalculator] Loaded 2019-11-19T20:34:29.581Z,1574195669.581 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-11-19T20:34:29.601Z,1574195669.601 [TempGradientCalculator] Loaded 2019-11-19T20:34:29.601Z,1574195669.601 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-11-19T20:34:29.606Z,1574195669.606 [YawRateCalculator] Loaded 2019-11-19T20:34:29.606Z,1574195669.606 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-11-19T20:34:29.635Z,1574195669.635 [ElevatorOffsetCalculator] Loaded 2019-11-19T20:34:29.635Z,1574195669.635 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-11-19T20:34:29.635Z,1574195669.635 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-11-19T20:34:29.637Z,1574195669.637 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-11-19T20:34:29.686Z,1574195669.686 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-11-19T20:34:29.686Z,1574195669.686 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-11-19T20:34:29.797Z,1574195669.797 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-11-19T20:34:29.798Z,1574195669.798 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-11-19T20:34:30.120Z,1574195670.120 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-11-19T20:34:30.120Z,1574195670.120 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-11-19T20:34:30.242Z,1574195670.242 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-11-19T20:34:30.243Z,1574195670.243 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-11-19T20:34:30.692Z,1574195670.692 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2019-11-19T20:34:30.697Z,1574195670.697 [AHRS_M2](INFO): created writer for : platform_orientation 2019-11-19T20:34:30.699Z,1574195670.699 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2019-11-19T20:34:30.704Z,1574195670.704 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2019-11-19T20:34:30.704Z,1574195670.704 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2019-11-19T20:34:30.709Z,1574195670.709 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2019-11-19T20:34:30.709Z,1574195670.709 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2019-11-19T20:34:30.715Z,1574195670.715 [AHRS_M2](INFO): created writer for : platform_roll_angle 2019-11-19T20:34:30.890Z,1574195670.890 [AHRS_M2] Loaded 2019-11-19T20:34:30.891Z,1574195670.891 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-11-19T20:34:31.159Z,1574195671.159 [DataOverHttps] Loaded 2019-11-19T20:34:31.159Z,1574195671.159 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-11-19T20:34:31.161Z,1574195671.161 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0 2019-11-19T20:34:31.161Z,1574195671.161 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2078 2019-11-19T20:34:31.174Z,1574195671.174 [Depth_Keller] Loaded 2019-11-19T20:34:31.174Z,1574195671.174 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-11-19T20:34:31.179Z,1574195671.179 [DropWeight] Loaded 2019-11-19T20:34:31.179Z,1574195671.179 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-11-19T20:34:31.272Z,1574195671.272 [NAL9602] Loaded 2019-11-19T20:34:31.272Z,1574195671.272 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-11-19T20:34:31.287Z,1574195671.287 [Onboard] Loaded 2019-11-19T20:34:31.288Z,1574195671.288 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-11-19T20:34:31.291Z,1574195671.291 [Radio_Surface] Loaded 2019-11-19T20:34:31.291Z,1574195671.291 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-11-19T20:34:31.292Z,1574195671.292 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0 2019-11-19T20:34:31.293Z,1574195671.293 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2079 2019-11-19T20:34:31.335Z,1574195671.335 [RDI_Pathfinder] Loaded 2019-11-19T20:34:31.335Z,1574195671.335 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-11-19T20:34:31.455Z,1574195671.455 [DAT] Loaded 2019-11-19T20:34:31.455Z,1574195671.455 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-11-19T20:34:32.900Z,1574195672.900 [BPC1] Loaded 2019-11-19T20:34:32.900Z,1574195672.900 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-11-19T20:34:32.900Z,1574195672.900 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-11-19T20:34:32.901Z,1574195672.901 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-11-19T20:34:33.018Z,1574195673.018 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-11-19T20:34:33.019Z,1574195673.019 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-11-19T20:34:33.038Z,1574195673.038 [NavChart] Loaded 2019-11-19T20:34:33.039Z,1574195673.039 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-11-19T20:34:33.043Z,1574195673.043 [UniversalFixResidualReporter] Loaded 2019-11-19T20:34:33.043Z,1574195673.043 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-11-19T20:34:33.043Z,1574195673.043 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-11-19T20:34:33.045Z,1574195673.045 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-11-19T20:34:33.199Z,1574195673.199 [SBIT](DEBUG): Construct Startup Built In Test. 2019-11-19T20:34:33.209Z,1574195673.209 [SBIT] Loaded 2019-11-19T20:34:33.210Z,1574195673.210 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-11-19T20:34:33.210Z,1574195673.210 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-11-19T20:34:33.222Z,1574195673.222 [IBIT] Loaded 2019-11-19T20:34:33.223Z,1574195673.223 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-11-19T20:34:33.226Z,1574195673.226 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-11-19T20:34:33.358Z,1574195673.358 [CBIT] Loaded 2019-11-19T20:34:33.358Z,1574195673.358 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-11-19T20:34:33.358Z,1574195673.358 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-11-19T20:34:33.359Z,1574195673.359 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-11-19T20:34:33.481Z,1574195673.481 [BuoyancyServo] Loaded 2019-11-19T20:34:33.482Z,1574195673.482 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-11-19T20:34:33.492Z,1574195673.492 [ElevatorServo] Loaded 2019-11-19T20:34:33.493Z,1574195673.493 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-11-19T20:34:33.503Z,1574195673.503 [MassServo] Loaded 2019-11-19T20:34:33.504Z,1574195673.504 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-11-19T20:34:33.514Z,1574195673.514 [RudderServo] Loaded 2019-11-19T20:34:33.514Z,1574195673.514 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-11-19T20:34:33.525Z,1574195673.525 [ThrusterServo] Loaded 2019-11-19T20:34:33.525Z,1574195673.525 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-11-19T20:34:33.525Z,1574195673.525 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-11-19T20:34:33.526Z,1574195673.526 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-11-19T20:34:33.586Z,1574195673.586 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-11-19T20:34:33.587Z,1574195673.587 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-11-19T20:34:33.887Z,1574195673.887 [BackSeatDriver] Loaded 2019-11-19T20:34:33.888Z,1574195673.888 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2019-11-19T20:34:33.889Z,1574195673.889 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40A654E0 2019-11-19T20:34:33.889Z,1574195673.889 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 2080 2019-11-19T20:34:33.899Z,1574195673.899 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-11-19T20:34:33.904Z,1574195673.904 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-11-19T20:34:33.904Z,1574195673.904 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-11-19T20:34:33.909Z,1574195673.909 [CTD_Seabird](INFO): created writer for : depth 2019-11-19T20:34:33.909Z,1574195673.909 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-11-19T20:34:33.914Z,1574195673.914 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-11-19T20:34:33.915Z,1574195673.915 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-11-19T20:34:33.920Z,1574195673.920 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-11-19T20:34:33.921Z,1574195673.921 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-11-19T20:34:33.925Z,1574195673.925 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-11-19T20:34:33.926Z,1574195673.926 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-11-19T20:34:33.931Z,1574195673.931 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-11-19T20:34:33.932Z,1574195673.932 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-11-19T20:34:33.937Z,1574195673.937 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-11-19T20:34:33.963Z,1574195673.963 [CTD_Seabird] Loaded 2019-11-19T20:34:33.963Z,1574195673.963 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-11-19T20:34:33.964Z,1574195673.964 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A954E0 2019-11-19T20:34:33.965Z,1574195673.965 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2081 2019-11-19T20:34:33.971Z,1574195673.971 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2019-11-19T20:34:33.971Z,1574195673.971 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2019-11-19T20:34:33.975Z,1574195673.975 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2019-11-19T20:34:33.976Z,1574195673.976 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2019-11-19T20:34:33.980Z,1574195673.980 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2019-11-19T20:34:33.980Z,1574195673.980 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2019-11-19T20:34:33.984Z,1574195673.984 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2019-11-19T20:34:33.984Z,1574195673.984 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2019-11-19T20:34:33.988Z,1574195673.988 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2019-11-19T20:34:33.988Z,1574195673.988 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2019-11-19T20:34:33.993Z,1574195673.993 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2019-11-19T20:34:33.993Z,1574195673.993 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2019-11-19T20:34:33.997Z,1574195673.997 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2019-11-19T20:34:33.997Z,1574195673.997 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2019-11-19T20:34:34.001Z,1574195674.001 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2019-11-19T20:34:34.005Z,1574195674.005 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2019-11-19T20:34:34.006Z,1574195674.006 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-11-19T20:34:34.006Z,1574195674.006 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-11-19T20:34:34.010Z,1574195674.010 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-11-19T20:34:34.010Z,1574195674.010 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-11-19T20:34:34.014Z,1574195674.014 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-11-19T20:34:34.015Z,1574195674.015 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-11-19T20:34:34.019Z,1574195674.019 [WetLabsBB2FL] Loaded 2019-11-19T20:34:34.019Z,1574195674.019 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-11-19T20:34:34.020Z,1574195674.020 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0 2019-11-19T20:34:34.020Z,1574195674.020 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2082 2019-11-19T20:34:34.260Z,1574195674.260 [WetLabsUBAT..SerialDriver](INFO): Created logger 2019-11-19T20:34:34.261Z,1574195674.261 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2019-11-19T20:34:34.261Z,1574195674.261 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2019-11-19T20:34:34.266Z,1574195674.266 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2019-11-19T20:34:34.266Z,1574195674.266 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2019-11-19T20:34:34.267Z,1574195674.267 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2019-11-19T20:34:34.271Z,1574195674.271 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2019-11-19T20:34:34.271Z,1574195674.271 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2019-11-19T20:34:34.275Z,1574195674.275 [WetLabsUBAT] Loaded 2019-11-19T20:34:34.275Z,1574195674.275 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2019-11-19T20:34:34.276Z,1574195674.276 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0 2019-11-19T20:34:34.277Z,1574195674.277 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 2083 2019-11-19T20:34:34.277Z,1574195674.277 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-11-19T20:34:34.281Z,1574195674.281 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-11-19T20:34:34.282Z,1574195674.282 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-11-19T20:34:34.288Z,1574195674.288 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-11-19T20:34:34.289Z,1574195674.289 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B254E0 2019-11-19T20:34:34.289Z,1574195674.289 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2084 2019-11-19T20:34:34.294Z,1574195674.294 [Supervisor](INFO): Main Thread ID is 1994 2019-11-19T20:34:34.294Z,1574195674.294 [Supervisor](DEBUG): Running supervisor. 2019-11-19T20:34:34.295Z,1574195674.295 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2085 2019-11-19T20:34:34.300Z,1574195674.300 [controlThread ThreadHandler](INFO): Handler Thread ID is 2086 2019-11-19T20:34:34.301Z,1574195674.301 [controlThread](DEBUG): Initializing ControlThread 2019-11-19T20:34:34.302Z,1574195674.302 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-11-19T20:34:34.303Z,1574195674.303 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-11-19T20:34:34.304Z,1574195674.304 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-11-19T20:34:34.304Z,1574195674.304 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-11-19T20:34:34.305Z,1574195674.305 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-11-19T20:34:34.305Z,1574195674.305 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-11-19T20:34:34.305Z,1574195674.305 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-11-19T20:34:34.305Z,1574195674.305 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-11-19T20:34:34.306Z,1574195674.306 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-11-19T20:34:34.306Z,1574195674.306 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-11-19T20:34:34.312Z,1574195674.312 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-11-19T20:34:34.312Z,1574195674.312 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-11-19T20:34:34.312Z,1574195674.312 [SBIT](INFO): Initialize SBIT Component. 2019-11-19T20:34:34.313Z,1574195674.313 [SBIT](IMPORTANT): git: 2019-11-11 2019-11-19T20:34:34.313Z,1574195674.313 [SBIT](INFO): git hash: d323e2e841967a23cb7fd5209d5be46b38c2f2ac 2019-11-19T20:34:34.314Z,1574195674.314 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-11-19T20:34:34.314Z,1574195674.314 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-11-19T20:34:34.315Z,1574195674.315 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2019-11-19T20:34:34.315Z,1574195674.315 [IBIT](INFO): Initialize IBIT Component. 2019-11-19T20:34:34.316Z,1574195674.316 [CBIT](DEBUG): Initialize CBIT Component. 2019-11-19T20:34:34.317Z,1574195674.317 [logger ThreadHandler](INFO): Handler Thread ID is 2087 2019-11-19T20:34:34.329Z,1574195674.329 [CBIT](DEBUG): Initialized mux pins. 2019-11-19T20:34:34.329Z,1574195674.329 [CBIT](DEBUG): Initializing the watchdog timer. 2019-11-19T20:34:34.330Z,1574195674.330 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2088 2019-11-19T20:34:34.331Z,1574195674.331 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-11-19T20:34:34.337Z,1574195674.337 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2089 2019-11-19T20:34:34.349Z,1574195674.349 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 2090 2019-11-19T20:34:34.353Z,1574195674.353 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-11-19T20:34:34.353Z,1574195674.353 [CBIT](DEBUG): Initializing heartbeat. 2019-11-19T20:34:34.361Z,1574195674.361 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2091 2019-11-19T20:34:34.362Z,1574195674.362 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-11-19T20:34:34.365Z,1574195674.365 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-11-19T20:34:34.367Z,1574195674.367 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2093 2019-11-19T20:34:34.368Z,1574195674.368 [WetLabsBB2FL](INFO): Powering down 2019-11-19T20:34:34.401Z,1574195674.401 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 2094 2019-11-19T20:34:34.413Z,1574195674.413 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2095 2019-11-19T20:34:34.423Z,1574195674.423 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-11-19T20:34:34.423Z,1574195674.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-11-19T20:34:34.423Z,1574195674.423 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-11-19T20:34:34.423Z,1574195674.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-11-19T20:34:34.424Z,1574195674.424 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-11-19T20:34:34.424Z,1574195674.424 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-11-19T20:34:34.424Z,1574195674.424 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-11-19T20:34:34.424Z,1574195674.424 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-11-19T20:34:34.424Z,1574195674.424 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-11-19T20:34:34.425Z,1574195674.425 [CBIT](DEBUG): Deactivating GF circuits. 2019-11-19T20:34:34.425Z,1574195674.425 [CBIT](DEBUG): Deactivating emergency mode. 2019-11-19T20:34:34.425Z,1574195674.425 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-11-19T20:34:34.425Z,1574195674.425 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-11-19T20:34:34.425Z,1574195674.425 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-11-19T20:34:34.426Z,1574195674.426 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-11-19T20:34:34.426Z,1574195674.426 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-11-19T20:34:34.426Z,1574195674.426 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-11-19T20:34:34.426Z,1574195674.426 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-11-19T20:34:34.461Z,1574195674.461 [CBIT](DEBUG): Backplane powered. 2019-11-19T20:34:34.463Z,1574195674.463 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-11-19T20:34:34.490Z,1574195674.490 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-11-19T20:34:34.529Z,1574195674.529 [MissionManager](DEBUG): 2019-11-19T20:34:34.530Z,1574195674.530 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-11-19T20:34:34.611Z,1574195674.611 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-11-19T20:34:34.612Z,1574195674.612 [Default:A.Wait](DEBUG): Construct Wait. 2019-11-19T20:34:34.614Z,1574195674.614 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-11-19T20:34:34.662Z,1574195674.662 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-11-19T20:34:34.664Z,1574195674.664 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-11-19T20:34:34.681Z,1574195674.681 [Default:E.Execute](DEBUG): Construct Execute. 2019-11-19T20:34:34.684Z,1574195674.684 [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-11-19T20:34:34.697Z,1574195674.697 [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-11-19T20:34:34.709Z,1574195674.709 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-11-19T20:34:34.741Z,1574195674.741 [Radio_Surface](INFO): Powering up 2019-11-19T20:34:34.924Z,1574195674.924 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-11-19T20:34:34.924Z,1574195674.924 [DAT](INFO): Powering up 2019-11-19T20:34:34.924Z,1574195674.924 [DAT](DEBUG): Initializing DAT. 2019-11-19T20:34:35.005Z,1574195675.005 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-11-19T20:34:35.030Z,1574195675.030 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-11-19T20:34:35.041Z,1574195675.041 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-11-19T20:34:35.043Z,1574195675.043 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-11-19T20:34:35.053Z,1574195675.053 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-11-19T20:34:35.054Z,1574195675.054 [MassServo](DEBUG): Initializing EZServoServo. 2019-11-19T20:34:35.065Z,1574195675.065 [MassServo](DEBUG): Initializing MassServo. 2019-11-19T20:34:35.066Z,1574195675.066 [RudderServo](DEBUG): Initializing EZServoServo. 2019-11-19T20:34:35.077Z,1574195675.077 [RudderServo](DEBUG): Initializing RudderServo. 2019-11-19T20:34:35.078Z,1574195675.078 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-11-19T20:34:35.089Z,1574195675.089 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-11-19T20:34:35.169Z,1574195675.169 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2019-11-19T20:34:35.177Z,1574195675.177 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-11-19T20:34:35.177Z,1574195675.177 [DropWeight] Hardware Fault, FailCount= 1 2019-11-19T20:34:35.177Z,1574195675.177 [DropWeight](ERROR): Hardware Fault 2019-11-19T20:34:35.228Z,1574195675.228 [CommandLine](FAULT): Scheduling is paused 2019-11-19T20:34:35.237Z,1574195675.237 [CBIT](INFO): Critical error at 20191119T203435 2019-11-19T20:34:35.238Z,1574195675.238 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-11-19T20:34:35.240Z,1574195675.240 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-11-19T20:34:35.241Z,1574195675.241 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-11-19T20:34:35.550Z,1574195675.550 [CBIT](INFO): Critical error at 20191119T203435 2019-11-19T20:34:41.394Z,1574195681.394 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2019-11-19T20:34:49.333Z,1574195689.333 [DAT](INFO): setting local address to 4 2019-11-19T20:34:49.371Z,1574195689.371 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004746 2019-11-19T20:34:49.743Z,1574195689.743 [DAT](INFO): set local address to 4 2019-11-19T20:34:57.420Z,1574195697.420 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-11-19T20:34:57.421Z,1574195697.421 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-11-19T20:34:57.421Z,1574195697.421 [RDI_Pathfinder](ERROR): Communications Fault 2019-11-19T20:34:57.462Z,1574195697.462 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-11-19T20:34:57.889Z,1574195697.889 [RDI_Pathfinder](INFO): Powering down 2019-11-19T20:34:58.649Z,1574195698.649 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-11-19T20:34:58.650Z,1574195698.650 [RDI_Pathfinder] No Fault, FailCount= 1 2019-11-19T20:35:01.438Z,1574195701.438 [NAL9602](INFO): Powering up NAL9602 2019-11-19T20:35:07.049Z,1574195707.049 [CommandLine](IMPORTANT): got command failComponent 2019-11-19T20:35:07.049Z,1574195707.049 [CommandLine](IMPORTANT): Failed components: 2019-11-19T20:35:07.049Z,1574195707.049 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2019-11-19T20:35:12.350Z,1574195712.350 [NAL9602](INFO): NAL9602 initialized 2019-11-19T20:35:12.368Z,1574195712.368 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder 2019-11-19T20:35:12.369Z,1574195712.369 [RDI_Pathfinder] Hardware Fault, FailCount= 2 2019-11-19T20:35:12.369Z,1574195712.369 [RDI_Pathfinder](ERROR): Hardware Fault 2019-11-19T20:35:12.370Z,1574195712.370 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault 2019-11-19T20:35:12.438Z,1574195712.438 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder 2019-11-19T20:35:12.857Z,1574195712.857 [RDI_Pathfinder](INFO): Powering down 2019-11-19T20:35:13.128Z,1574195713.128 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder 2019-11-19T20:35:13.130Z,1574195713.130 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault 2019-11-19T20:35:13.472Z,1574195713.472 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder 2019-11-19T20:35:13.475Z,1574195713.475 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault 2019-11-19T20:35:13.645Z,1574195713.645 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-11-19T20:35:13.646Z,1574195713.646 [RDI_Pathfinder] No Fault, FailCount= 2 2019-11-19T20:35:13.715Z,1574195713.715 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder 2019-11-19T20:35:13.715Z,1574195713.715 [RDI_Pathfinder] Hardware Fault, FailCount= 3 2019-11-19T20:35:13.715Z,1574195713.715 [RDI_Pathfinder](ERROR): Hardware Fault 2019-11-19T20:35:13.715Z,1574195713.715 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault 2019-11-19T20:35:13.932Z,1574195713.932 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder 2019-11-19T20:35:13.932Z,1574195713.932 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault 2019-11-19T20:35:14.022Z,1574195714.022 [SBIT](IMPORTANT): Beginning Startup BIT 2019-11-19T20:35:14.026Z,1574195714.026 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder 2019-11-19T20:35:14.026Z,1574195714.026 [CBIT](CRITICAL): Hardware Fault in component: RDI_Pathfinder 2019-11-19T20:35:14.027Z,1574195714.027 [CBIT](IMPORTANT): Beginning ground fault scan 2019-11-19T20:35:14.123Z,1574195714.123 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder 2019-11-19T20:35:14.123Z,1574195714.123 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault 2019-11-19T20:35:14.273Z,1574195714.273 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder 2019-11-19T20:35:14.289Z,1574195714.289 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault 2019-11-19T20:35:14.484Z,1574195714.484 [CBIT](INFO): Critical error at 20191119T203514 2019-11-19T20:35:15.376Z,1574195715.376 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder 2019-11-19T20:35:15.377Z,1574195715.377 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault 2019-11-19T20:35:16.456Z,1574195716.456 [CommandLine](IMPORTANT): got command 2019-11-19T20:35:16.456Z,1574195716.456 [CommandLine](FAULT): Incomplete syntax. Try: help 2019-11-19T20:35:17.424Z,1574195717.424 [CommandLine](IMPORTANT): got command failComponent 2019-11-19T20:35:17.424Z,1574195717.424 [CommandLine](IMPORTANT): Failed components: 2019-11-19T20:35:17.425Z,1574195717.425 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2019-11-19T20:35:17.425Z,1574195717.425 [CommandLine](IMPORTANT): RDI_Pathfinder: Hardware Fault 2019-11-19T20:35:24.752Z,1574195724.752 [CommandLine](IMPORTANT): got command show variable thrusterServo 2019-11-19T20:35:24.829Z,1574195724.829 [CommandLine](IMPORTANT): ThrusterServo.loadAtStartup (bool) 2019-11-19T20:35:24.830Z,1574195724.830 [CommandLine](IMPORTANT): ThrusterServo.simulateHardware (bool) 2019-11-19T20:35:24.830Z,1574195724.830 [CommandLine](IMPORTANT): ThrusterServo.accel (none) 2019-11-19T20:35:24.831Z,1574195724.831 [CommandLine](IMPORTANT): ThrusterServo.currLimit (percent) 2019-11-19T20:35:24.831Z,1574195724.831 [CommandLine](IMPORTANT): ThrusterServo.overloadTimeout (millisecond) 2019-11-19T20:35:24.831Z,1574195724.831 [CommandLine](IMPORTANT): ThrusterServo.pidW (count) 2019-11-19T20:35:24.832Z,1574195724.832 [CommandLine](IMPORTANT): ThrusterServo.pidX (count) 2019-11-19T20:35:24.832Z,1574195724.832 [CommandLine](IMPORTANT): ThrusterServo.pidY (count) 2019-11-19T20:35:24.832Z,1574195724.832 [CommandLine](IMPORTANT): ThrusterServo.powerOnTimeout (second) 2019-11-19T20:35:24.889Z,1574195724.889 [CommandLine](IMPORTANT): ThrusterServo.allowableBadVelocity (count) 2019-11-19T20:35:24.890Z,1574195724.890 [CommandLine](IMPORTANT): ThrusterServo.deviation (count) 2019-11-19T20:35:24.890Z,1574195724.890 [CommandLine](IMPORTANT): ThrusterServo.encoderTks (count_per_second) 2019-11-19T20:35:24.890Z,1574195724.890 [CommandLine](IMPORTANT): ThrusterServo.tksPerRev (count_per_revolution) 2019-11-19T20:35:25.060Z,1574195725.060 [CommandLine](IMPORTANT): ThrusterServo.loadControl (none) 2019-11-19T20:35:25.060Z,1574195725.060 [CommandLine](IMPORTANT): ThrusterServo.uart (none) 2019-11-19T20:35:25.061Z,1574195725.061 [CommandLine](IMPORTANT): ThrusterServo.baud (bit_per_second) 2019-11-19T20:35:25.118Z,1574195725.118 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006979 CHAN A1 (24V): 0.006057 CHAN A2 (12V): -0.004676 CHAN A3 (5V): -0.000623 CHAN B0 (3.3V): 0.000747 CHAN B1 (3.15aV): 0.000355 CHAN B2 (3.15bV): 0.000343 CHAN B3 (GND): 0.001938 OPEN: 0.006754 Full Scale Calc: 4.765 mA, -1.589 mA 2019-11-19T20:35:25.434Z,1574195725.434 [CommandLine](IMPORTANT): ThrusterServo.platform_propeller_rotation_rate (radian_per_second) 2019-11-19T20:35:25.474Z,1574195725.474 [CommandLine](IMPORTANT): ThrusterServo.component_voltage (volt) 2019-11-19T20:35:25.475Z,1574195725.475 [CommandLine](IMPORTANT): ThrusterServo.component_avgVoltage (volt) 2019-11-19T20:35:25.475Z,1574195725.475 [CommandLine](IMPORTANT): ThrusterServo.component_current (milliampere) 2019-11-19T20:35:25.475Z,1574195725.475 [CommandLine](IMPORTANT): ThrusterServo.component_avgCurrent (milliampere) 2019-11-19T20:35:36.187Z,1574195736.187 [CommandLine](IMPORTANT): got command report mod ThrusterServo.component_avgCurrent 2019-11-19T20:35:36.543Z,1574195736.543 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA 2019-11-19T20:35:45.033Z,1574195745.033 [CommandLine](IMPORTANT): got command maintain control SpeedControl.speedCmd 150.000000 revolution_per_minute 2019-11-19T20:35:45.034Z,1574195745.034 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.speedCmd" handled in the control thread. 2019-11-19T20:35:45.504Z,1574195745.504 [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,Maintain_SpeedControl.speedCmd,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-11-19T20:35:48.234Z,1574195748.234 [Reporter](INFO): ThrusterServo.component_avgCurrent 58.720001 mA 2019-11-19T20:35:50.668Z,1574195750.668 [Reporter](INFO): ThrusterServo.component_avgCurrent 212.860001 mA 2019-11-19T20:35:53.077Z,1574195753.077 [ThrusterServo](FAULT): Overload Error 2019-11-19T20:35:53.077Z,1574195753.077 [ThrusterServo] Hardware Fault, FailCount= 1 2019-11-19T20:35:53.077Z,1574195753.077 [ThrusterServo](ERROR): Hardware Fault 2019-11-19T20:35:53.093Z,1574195753.093 [CBIT](ERROR): Hardware Fault in component: ThrusterServo 2019-11-19T20:35:53.094Z,1574195753.094 [Reporter](INFO): ThrusterServo.component_avgCurrent no_value 2019-11-19T20:35:53.454Z,1574195753.454 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-11-19T20:35:53.454Z,1574195753.454 [ThrusterServo](INFO): Powering down 2019-11-19T20:35:54.259Z,1574195754.259 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-11-19T20:35:54.374Z,1574195754.374 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-11-19T20:35:54.383Z,1574195754.383 [CBIT](INFO): Clearing failed state for component ThrusterServo 2019-11-19T20:35:54.383Z,1574195754.383 [ThrusterServo] No Fault, FailCount= 1 2019-11-19T20:35:56.752Z,1574195756.752 [NAL9602](INFO): SBD MO Status=2, MOMSN=5166, MT Status=2, MTMSN=0 2019-11-19T20:35:56.752Z,1574195756.752 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-19T20:35:56.957Z,1574195756.957 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA 2019-11-19T20:35:59.235Z,1574195759.235 [CommandLine](IMPORTANT): got command maintain control SpeedControl.speedCmd 100.000000 revolution_per_minute 2019-11-19T20:35:59.515Z,1574195759.515 [Reporter](INFO): ThrusterServo.component_avgCurrent 84.410004 mA 2019-11-19T20:36:01.729Z,1574195761.729 [Reporter](INFO): ThrusterServo.component_avgCurrent 704.640015 mA 2019-11-19T20:36:02.877Z,1574195762.877 [ThrusterServo](FAULT): Overload Error 2019-11-19T20:36:02.877Z,1574195762.877 [ThrusterServo] Hardware Fault, FailCount= 2 2019-11-19T20:36:02.877Z,1574195762.877 [ThrusterServo](ERROR): Hardware Fault 2019-11-19T20:36:02.888Z,1574195762.888 [CBIT](ERROR): Hardware Fault in component: ThrusterServo 2019-11-19T20:36:02.890Z,1574195762.890 [Reporter](INFO): ThrusterServo.component_avgCurrent no_value 2019-11-19T20:36:03.250Z,1574195763.250 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-11-19T20:36:03.251Z,1574195763.251 [ThrusterServo](INFO): Powering down 2019-11-19T20:36:04.055Z,1574195764.055 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-11-19T20:36:04.170Z,1574195764.170 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-11-19T20:36:04.174Z,1574195764.174 [CBIT](INFO): Clearing failed state for component ThrusterServo 2019-11-19T20:36:04.174Z,1574195764.174 [ThrusterServo] No Fault, FailCount= 2 2019-11-19T20:36:06.905Z,1574195766.905 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA 2019-11-19T20:36:07.325Z,1574195767.325 [SBIT](IMPORTANT): SBIT PASSED 2019-11-19T20:36:07.381Z,1574195767.381 [CommandLine](IMPORTANT): got command configSet list 2019-11-19T20:36:07.381Z,1574195767.381 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-11-19T20:36:07.382Z,1574195767.382 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere; 2019-11-19T20:36:07.382Z,1574195767.382 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=198 cubic_centimeter; 2019-11-19T20:36:07.383Z,1574195767.383 [CommandLine](IMPORTANT): VerticalControl.massDefault=5 millimeter; 2019-11-19T20:36:07.702Z,1574195767.702 [MissionManager](IMPORTANT): Started mission Startup 2019-11-19T20:36:07.702Z,1574195767.702 [Startup] Running Loop=1 2019-11-19T20:36:07.703Z,1574195767.703 [Startup](DEBUG): Aggregate::initialize Startup 2019-11-19T20:36:07.703Z,1574195767.703 [Startup:A.GoToSurface] Running Loop=1 2019-11-19T20:36:07.703Z,1574195767.703 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-11-19T20:36:07.703Z,1574195767.703 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-11-19T20:36:07.704Z,1574195767.704 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-11-19T20:36:07.704Z,1574195767.704 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-11-19T20:36:07.705Z,1574195767.705 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-11-19T20:36:07.705Z,1574195767.705 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-11-19T20:36:07.706Z,1574195767.706 [Startup:StartupSatComms] Running Loop=1 2019-11-19T20:36:07.707Z,1574195767.707 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-11-19T20:36:07.707Z,1574195767.707 [Startup:StartupSatComms:A] Running Loop=1 2019-11-19T20:36:08.089Z,1574195768.089 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-11-19T20:36:16.634Z,1574195776.634 [CommandLine](IMPORTANT): got command maintain control SpeedControl.speedCmd 50.000000 revolution_per_minute 2019-11-19T20:36:20.746Z,1574195780.746 [CommandLine](IMPORTANT): got command failComponent 2019-11-19T20:36:20.746Z,1574195780.746 [CommandLine](IMPORTANT): Failed components: 2019-11-19T20:36:20.746Z,1574195780.746 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2019-11-19T20:36:20.746Z,1574195780.746 [CommandLine](IMPORTANT): RDI_Pathfinder: Hardware Fault 2019-11-19T20:36:25.098Z,1574195785.098 [CommandLine](IMPORTANT): got command report 2019-11-19T20:36:25.099Z,1574195785.099 [Reporter](INFO): ThrusterServo.component_avgCurrent reporting changes 2019-11-19T20:36:32.707Z,1574195792.707 [CommandLine](IMPORTANT): got command maintain control SpeedControl.speedCmd 60.000000 revolution_per_minute 2019-11-19T20:36:33.519Z,1574195793.519 [NAL9602](INFO): SBD MO Status=2, MOMSN=5166, MT Status=2, MTMSN=0 2019-11-19T20:36:33.520Z,1574195793.520 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-19T20:36:44.729Z,1574195804.729 [CommandLine](IMPORTANT): got command failComponent 2019-11-19T20:36:44.729Z,1574195804.729 [CommandLine](IMPORTANT): Failed components: 2019-11-19T20:36:44.730Z,1574195804.730 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2019-11-19T20:36:44.730Z,1574195804.730 [CommandLine](IMPORTANT): RDI_Pathfinder: Hardware Fault 2019-11-19T20:36:47.657Z,1574195807.657 [CommandLine](IMPORTANT): got command maintain 2019-11-19T20:36:47.658Z,1574195807.658 [Maintain_SpeedControl.speedCmd](INFO): SpeedControl.speedCmd maintaining 60.000000 rpm 2019-11-19T20:37:01.496Z,1574195821.496 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 100.000000 revolution_per_minute 2019-11-19T20:37:01.498Z,1574195821.498 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2019-11-19T20:37:01.854Z,1574195821.854 [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,Maintain_SpeedControl.speedCmd,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-11-19T20:37:05.345Z,1574195825.345 [CommandLine](IMPORTANT): got command report 2019-11-19T20:37:05.346Z,1574195825.346 [Reporter](INFO): ThrusterServo.component_avgCurrent reporting changes 2019-11-19T20:37:05.516Z,1574195825.516 [Reporter](INFO): ThrusterServo.component_avgCurrent 113.770004 mA 2019-11-19T20:37:07.884Z,1574195827.884 [Startup:StartupSatComms:A](INFO): Timed out from 2019-11-19T20:36:07.7Z 2019-11-19T20:37:07.884Z,1574195827.884 [Startup:StartupSatComms:A] Stopped 2019-11-19T20:37:07.884Z,1574195827.884 [Startup:StartupSatComms:B] Running Loop=1 2019-11-19T20:37:07.934Z,1574195827.934 [Reporter](INFO): ThrusterServo.component_avgCurrent 132.119995 mA 2019-11-19T20:37:08.287Z,1574195828.287 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-11-19T20:37:10.362Z,1574195830.362 [Reporter](INFO): ThrusterServo.component_avgCurrent 143.130005 mA 2019-11-19T20:37:12.810Z,1574195832.810 [Reporter](INFO): ThrusterServo.component_avgCurrent 150.470001 mA 2019-11-19T20:37:15.208Z,1574195835.208 [Reporter](INFO): ThrusterServo.component_avgCurrent 146.800003 mA 2019-11-19T20:37:15.543Z,1574195835.543 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191114T182338/Courier0144.lzma 2019-11-19T20:37:17.547Z,1574195837.547 [DataOverHttps](INFO): Moved sent file to Logs/20191114T182338/Courier0144.lzma.bak 2019-11-19T20:37:17.547Z,1574195837.547 [DataOverHttps](INFO): SBD MOMSN=12073129 2019-11-19T20:37:17.671Z,1574195837.671 [Reporter](INFO): ThrusterServo.component_avgCurrent 135.790009 mA 2019-11-19T20:37:20.058Z,1574195840.058 [Reporter](INFO): ThrusterServo.component_avgCurrent 150.470001 mA 2019-11-19T20:37:22.475Z,1574195842.475 [Reporter](INFO): ThrusterServo.component_avgCurrent 132.119995 mA 2019-11-19T20:37:24.902Z,1574195844.902 [Reporter](INFO): ThrusterServo.component_avgCurrent 146.800003 mA 2019-11-19T20:37:27.320Z,1574195847.320 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449997 mA 2019-11-19T20:37:29.874Z,1574195849.874 [Reporter](INFO): ThrusterServo.component_avgCurrent 139.460007 mA 2019-11-19T20:37:32.228Z,1574195852.228 [Reporter](INFO): ThrusterServo.component_avgCurrent 128.449997 mA 2019-11-19T20:37:34.558Z,1574195854.558 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-11-19T20:37:34.558Z,1574195854.558 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-11-19T20:37:34.618Z,1574195854.618 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-11-19T20:37:34.620Z,1574195854.620 [Reporter](INFO): ThrusterServo.component_avgCurrent 139.460007 mA 2019-11-19T20:37:34.817Z,1574195854.817 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20191119T203419/Courier0000.lzma 2019-11-19T20:37:35.001Z,1574195855.001 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-11-19T20:37:35.001Z,1574195855.001 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-11-19T20:37:36.822Z,1574195856.822 [DataOverHttps](INFO): Moved sent file to Logs/20191119T203419/Courier0000.lzma.bak 2019-11-19T20:37:36.822Z,1574195856.822 [DataOverHttps](INFO): SBD MOMSN=12073131 2019-11-19T20:37:39.453Z,1574195859.453 [Reporter](INFO): ThrusterServo.component_avgCurrent 135.790009 mA 2019-11-19T20:37:40.579Z,1574195860.579 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 150.000000 revolution_per_minute 2019-11-19T20:37:41.866Z,1574195861.866 [Reporter](INFO): ThrusterServo.component_avgCurrent 132.119995 mA 2019-11-19T20:37:43.902Z,1574195863.902 [Reporter](INFO): ThrusterServo.component_avgCurrent 205.520004 mA 2019-11-19T20:37:46.302Z,1574195866.302 [Reporter](INFO): ThrusterServo.component_avgCurrent 245.889999 mA 2019-11-19T20:37:47.856Z,1574195867.856 [NAL9602](INFO): SBD MO Status=2, MOMSN=5166, MT Status=2, MTMSN=0 2019-11-19T20:37:47.856Z,1574195867.856 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-19T20:37:48.726Z,1574195868.726 [Reporter](INFO): ThrusterServo.component_avgCurrent 249.559998 mA 2019-11-19T20:37:50.336Z,1574195870.336 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 175.000000 revolution_per_minute 2019-11-19T20:37:51.154Z,1574195871.154 [Reporter](INFO): ThrusterServo.component_avgCurrent 256.899994 mA 2019-11-19T20:37:53.594Z,1574195873.594 [Reporter](INFO): ThrusterServo.component_avgCurrent 322.960022 mA 2019-11-19T20:37:53.965Z,1574195873.965 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20191114T182338/Express0145.lzma 2019-11-19T20:37:55.971Z,1574195875.971 [DataOverHttps](INFO): Moved sent file to Logs/20191114T182338/Express0145.lzma.bak 2019-11-19T20:37:55.971Z,1574195875.971 [DataOverHttps](INFO): SBD MOMSN=12073137 2019-11-19T20:37:56.104Z,1574195876.104 [Reporter](INFO): ThrusterServo.component_avgCurrent 311.950012 mA 2019-11-19T20:37:58.426Z,1574195878.426 [Reporter](INFO): ThrusterServo.component_avgCurrent 322.960022 mA 2019-11-19T20:38:01.088Z,1574195881.088 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 200.000000 revolution_per_minute 2019-11-19T20:38:01.184Z,1574195881.184 [NAL9602](INFO): SBD MO Status=2, MOMSN=5166, MT Status=2, MTMSN=0 2019-11-19T20:38:01.184Z,1574195881.184 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-19T20:38:03.270Z,1574195883.270 [Reporter](INFO): ThrusterServo.component_avgCurrent 333.970001 mA 2019-11-19T20:38:05.723Z,1574195885.723 [Reporter](INFO): ThrusterServo.component_avgCurrent 396.360016 mA 2019-11-19T20:38:08.082Z,1574195888.082 [Startup:StartupSatComms:B](INFO): Timed out from 2019-11-19T20:37:07.9Z 2019-11-19T20:38:08.082Z,1574195888.082 [Startup:StartupSatComms:B] Stopped 2019-11-19T20:38:08.083Z,1574195888.083 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-11-19T20:38:08.083Z,1574195888.083 [Startup:StartupSatComms] Stopped 2019-11-19T20:38:08.083Z,1574195888.083 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-11-19T20:38:08.084Z,1574195888.084 [Startup](INFO): Completed Startup 2019-11-19T20:38:08.084Z,1574195888.084 [MissionManager](INFO): Startup is completed. 2019-11-19T20:38:08.084Z,1574195888.084 [MissionManager](INFO): Uninitializing Mission Startup 2019-11-19T20:38:08.084Z,1574195888.084 [Startup] Stopped 2019-11-19T20:38:08.084Z,1574195888.084 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-11-19T20:38:08.084Z,1574195888.084 [Startup:A.GoToSurface] Stopped 2019-11-19T20:38:08.084Z,1574195888.084 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-11-19T20:38:08.150Z,1574195888.150 [Reporter](INFO): ThrusterServo.component_avgCurrent 414.710022 mA 2019-11-19T20:38:08.478Z,1574195888.478 [MissionManager](IMPORTANT): Started mission Default 2019-11-19T20:38:08.478Z,1574195888.478 [Default] Running Loop=1 2019-11-19T20:38:08.478Z,1574195888.478 [Default](DEBUG): Aggregate::initialize Default 2019-11-19T20:38:08.478Z,1574195888.478 [Default:B.GoToSurface] Running Loop=1 2019-11-19T20:38:08.478Z,1574195888.478 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-11-19T20:38:08.479Z,1574195888.479 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-11-19T20:38:08.479Z,1574195888.479 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-11-19T20:38:08.479Z,1574195888.479 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-11-19T20:38:08.480Z,1574195888.480 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-11-19T20:38:08.480Z,1574195888.480 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-11-19T20:38:08.480Z,1574195888.480 [Default:A.Wait] Running Loop=1 2019-11-19T20:38:08.480Z,1574195888.480 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-11-19T20:38:10.286Z,1574195890.286 [Reporter](INFO): ThrusterServo.component_avgCurrent 418.380005 mA 2019-11-19T20:38:12.552Z,1574195892.552 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 2225.000000 revolution_per_minute 2019-11-19T20:38:12.615Z,1574195892.615 [Reporter](INFO): ThrusterServo.component_avgCurrent 359.660004 mA 2019-11-19T20:38:12.907Z,1574195892.907 [NAL9602](INFO): SBD MO Status=2, MOMSN=5166, MT Status=2, MTMSN=0 2019-11-19T20:38:12.908Z,1574195892.908 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-11-19T20:38:13.777Z,1574195893.777 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-11-19T20:38:13.777Z,1574195893.777 [RDI_Pathfinder] No Fault, FailCount= 3 2019-11-19T20:38:14.092Z,1574195894.092 [DataOverHttps](INFO): Sending 1480 bytes from file Logs/20191119T203419/Express0001.lzma 2019-11-19T20:38:14.978Z,1574195894.978 [Reporter](INFO): ThrusterServo.component_avgCurrent 422.050018 mA 2019-11-19T20:38:16.094Z,1574195896.094 [DataOverHttps](INFO): Moved sent file to Logs/20191119T203419/Express0001.lzma.bak 2019-11-19T20:38:16.094Z,1574195896.094 [DataOverHttps](INFO): SBD MOMSN=12073143 2019-11-19T20:38:16.866Z,1574195896.866 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 225.000000 revolution_per_minute 2019-11-19T20:38:17.041Z,1574195897.041 [ThrusterServo](FAULT): Overload Error 2019-11-19T20:38:17.041Z,1574195897.041 [ThrusterServo] Hardware Fault, FailCount= 1 2019-11-19T20:38:17.041Z,1574195897.041 [ThrusterServo](ERROR): Hardware Fault 2019-11-19T20:38:17.077Z,1574195897.077 [CBIT](ERROR): Hardware Fault in component: ThrusterServo 2019-11-19T20:38:17.078Z,1574195897.078 [Reporter](INFO): ThrusterServo.component_avgCurrent no_value 2019-11-19T20:38:17.434Z,1574195897.434 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-11-19T20:38:17.434Z,1574195897.434 [ThrusterServo](INFO): Powering down 2019-11-19T20:38:18.181Z,1574195898.181 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-11-19T20:38:18.298Z,1574195898.298 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-11-19T20:38:18.302Z,1574195898.302 [CBIT](INFO): Clearing failed state for component ThrusterServo 2019-11-19T20:38:18.302Z,1574195898.302 [ThrusterServo] No Fault, FailCount= 1 2019-11-19T20:38:18.664Z,1574195898.664 [CommandLine](IMPORTANT): got command failComponent 2019-11-19T20:38:18.664Z,1574195898.664 [CommandLine](IMPORTANT): Failed components: 2019-11-19T20:38:18.664Z,1574195898.664 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2019-11-19T20:38:21.025Z,1574195901.025 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA 2019-11-19T20:38:21.954Z,1574195901.954 [Default:A.Wait](INFO): Done Waiting. 2019-11-19T20:38:21.954Z,1574195901.954 [Default:A.Wait] Stopped 2019-11-19T20:38:21.954Z,1574195901.954 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-11-19T20:38:22.338Z,1574195902.338 [Default:CheckIn] Running Loop=1 2019-11-19T20:38:22.338Z,1574195902.338 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-11-19T20:38:22.338Z,1574195902.338 [Default:CheckIn:Read_GPS] Running Loop=1 2019-11-19T20:38:22.655Z,1574195902.655 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-11-19T20:38:23.882Z,1574195903.882 [Reporter](INFO): ThrusterServo.component_avgCurrent 91.750000 mA 2019-11-19T20:38:26.298Z,1574195906.298 [Reporter](INFO): ThrusterServo.component_avgCurrent 458.750000 mA 2019-11-19T20:38:26.653Z,1574195906.653 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2019-11-19T20:38:26.653Z,1574195906.653 [RDI_Pathfinder] Data Fault, FailCount= 1 2019-11-19T20:38:26.653Z,1574195906.653 [RDI_Pathfinder](ERROR): Data Fault 2019-11-19T20:38:26.705Z,1574195906.705 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2019-11-19T20:38:27.121Z,1574195907.121 [RDI_Pathfinder](INFO): Powering down 2019-11-19T20:38:27.933Z,1574195907.933 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-11-19T20:38:27.933Z,1574195907.933 [RDI_Pathfinder] No Fault, FailCount= 1 2019-11-19T20:38:28.734Z,1574195908.734 [Reporter](INFO): ThrusterServo.component_avgCurrent 469.760010 mA 2019-11-19T20:38:31.154Z,1574195911.154 [Reporter](INFO): ThrusterServo.component_avgCurrent 598.210022 mA 2019-11-19T20:38:33.583Z,1574195913.583 [Reporter](INFO): ThrusterServo.component_avgCurrent 532.150024 mA 2019-11-19T20:38:35.441Z,1574195915.441 [CommandLine](IMPORTANT): got command failComponent hardware RDI_Pathfinder 2019-11-19T20:38:35.442Z,1574195915.442 [RDI_Pathfinder] Hardware Fault, FailCount= 2 2019-11-19T20:38:35.442Z,1574195915.442 [RDI_Pathfinder](ERROR): Hardware Fault 2019-11-19T20:38:35.442Z,1574195915.442 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is Hardware Fault 2019-11-19T20:38:35.621Z,1574195915.621 [RDI_Pathfinder](INFO): Powering down 2019-11-19T20:38:35.705Z,1574195915.705 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder 2019-11-19T20:38:36.006Z,1574195916.006 [Reporter](INFO): ThrusterServo.component_avgCurrent 528.479980 mA 2019-11-19T20:38:36.413Z,1574195916.413 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-11-19T20:38:36.413Z,1574195916.413 [RDI_Pathfinder] No Fault, FailCount= 2 2019-11-19T20:38:38.438Z,1574195918.438 [Reporter](INFO): ThrusterServo.component_avgCurrent 561.510010 mA 2019-11-19T20:38:40.842Z,1574195920.842 [Reporter](INFO): ThrusterServo.component_avgCurrent 513.799988 mA 2019-11-19T20:38:41.409Z,1574195921.409 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB4 2019-11-19T20:38:43.282Z,1574195923.282 [Reporter](INFO): ThrusterServo.component_avgCurrent 462.420013 mA 2019-11-19T20:38:45.712Z,1574195925.712 [Reporter](INFO): ThrusterServo.component_avgCurrent 480.770020 mA 2019-11-19T20:38:46.032Z,1574195926.032 [NAL9602](INFO): SBD MO Status=0, MOMSN=5166, MT Status=0, MTMSN=0 2019-11-19T20:38:46.032Z,1574195926.032 [NAL9602](INFO): No messages in MT queue 2019-11-19T20:38:47.248Z,1574195927.248 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203853.00,A,3648.16558,N,12147.28122,W,0.194,0.00,191119,,,A*73 2019-11-19T20:38:47.277Z,1574195927.277 [NAL9602](INFO): GPS fix at 20191119T203853: (36.802760, -121.788020) 2019-11-19T20:38:47.308Z,1574195927.308 [Default:CheckIn:Read_GPS] Stopped 2019-11-19T20:38:47.308Z,1574195927.308 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-11-19T20:38:47.694Z,1574195927.694 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-11-19T20:38:48.118Z,1574195928.118 [Reporter](INFO): ThrusterServo.component_avgCurrent 469.760010 mA 2019-11-19T20:38:50.602Z,1574195930.602 [Reporter](INFO): ThrusterServo.component_avgCurrent 491.779999 mA 2019-11-19T20:38:53.002Z,1574195933.002 [Reporter](INFO): ThrusterServo.component_avgCurrent 528.479980 mA 2019-11-19T20:38:55.401Z,1574195935.401 [Reporter](INFO): ThrusterServo.component_avgCurrent 557.840027 mA 2019-11-19T20:38:57.765Z,1574195937.765 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20191119T203419/Courier0004.lzma 2019-11-19T20:38:57.830Z,1574195937.830 [Reporter](INFO): ThrusterServo.component_avgCurrent 513.799988 mA 2019-11-19T20:38:58.972Z,1574195938.972 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-11-19T20:38:58.972Z,1574195938.972 [RDI_Pathfinder] Communications Fault, FailCount= 3 2019-11-19T20:38:58.972Z,1574195938.972 [RDI_Pathfinder](ERROR): Communications Fault 2019-11-19T20:38:59.030Z,1574195939.030 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-11-19T20:38:59.030Z,1574195939.030 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2019-11-19T20:38:59.453Z,1574195939.453 [RDI_Pathfinder](INFO): Powering down 2019-11-19T20:38:59.523Z,1574195939.523 [CBIT](INFO): Critical error at 20191119T203859 2019-11-19T20:38:59.770Z,1574195939.770 [DataOverHttps](INFO): Moved sent file to Logs/20191119T203419/Courier0004.lzma.bak 2019-11-19T20:38:59.770Z,1574195939.770 [DataOverHttps](INFO): SBD MOMSN=12073227 2019-11-19T20:39:00.262Z,1574195940.262 [Reporter](INFO): ThrusterServo.component_avgCurrent 458.750000 mA 2019-11-19T20:39:02.678Z,1574195942.678 [Reporter](INFO): ThrusterServo.component_avgCurrent 524.809998 mA 2019-11-19T20:39:04.860Z,1574195944.860 [Reporter](INFO): ThrusterServo.component_avgCurrent 473.430023 mA 2019-11-19T20:39:07.148Z,1574195947.148 [Reporter](INFO): ThrusterServo.component_avgCurrent 510.129974 mA 2019-11-19T20:39:09.538Z,1574195949.538 [Reporter](INFO): ThrusterServo.component_avgCurrent 517.470032 mA 2019-11-19T20:39:11.974Z,1574195951.974 [Reporter](INFO): ThrusterServo.component_avgCurrent 488.110016 mA 2019-11-19T20:39:14.475Z,1574195954.475 [Reporter](INFO): ThrusterServo.component_avgCurrent 550.500000 mA 2019-11-19T20:39:16.466Z,1574195956.466 [Reporter](INFO): ThrusterServo.component_avgCurrent 477.100006 mA 2019-11-19T20:39:18.886Z,1574195958.886 [Reporter](INFO): ThrusterServo.component_avgCurrent 502.790039 mA 2019-11-19T20:39:19.600Z,1574195959.600 [NAL9602](INFO): Not Powering down - fast GPS 2019-11-19T20:39:20.554Z,1574195960.554 [DataOverHttps](INFO): Sending 527 bytes from file Logs/20191119T203419/Express0005.lzma 2019-11-19T20:39:21.299Z,1574195961.299 [Reporter](INFO): ThrusterServo.component_avgCurrent 473.430023 mA 2019-11-19T20:39:22.558Z,1574195962.558 [DataOverHttps](INFO): Moved sent file to Logs/20191119T203419/Express0005.lzma.bak 2019-11-19T20:39:22.558Z,1574195962.558 [DataOverHttps](INFO): SBD MOMSN=12073230 2019-11-19T20:39:23.730Z,1574195963.730 [Reporter](INFO): ThrusterServo.component_avgCurrent 480.770020 mA 2019-11-19T20:39:24.056Z,1574195964.056 [Default:CheckIn:Read_Iridium] Stopped 2019-11-19T20:39:24.056Z,1574195964.056 [Default:CheckIn:C.Wait] Running Loop=1 2019-11-19T20:39:24.056Z,1574195964.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-11-19T20:39:26.129Z,1574195966.129 [Reporter](INFO): ThrusterServo.component_avgCurrent 521.140015 mA 2019-11-19T20:39:28.694Z,1574195968.694 [Reporter](INFO): ThrusterServo.component_avgCurrent 539.489990 mA 2019-11-19T20:39:30.051Z,1574195970.051 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 250.000000 revolution_per_minute 2019-11-19T20:39:30.970Z,1574195970.970 [Reporter](INFO): ThrusterServo.component_avgCurrent 524.809998 mA 2019-11-19T20:39:33.422Z,1574195973.422 [Reporter](INFO): ThrusterServo.component_avgCurrent 539.489990 mA 2019-11-19T20:39:35.818Z,1574195975.818 [Reporter](INFO): ThrusterServo.component_avgCurrent 649.590027 mA 2019-11-19T20:39:38.258Z,1574195978.258 [Reporter](INFO): ThrusterServo.component_avgCurrent 638.580017 mA 2019-11-19T20:39:40.669Z,1574195980.669 [Reporter](INFO): ThrusterServo.component_avgCurrent 671.609985 mA 2019-11-19T20:39:43.099Z,1574195983.099 [Reporter](INFO): ThrusterServo.component_avgCurrent 653.260010 mA 2019-11-19T20:39:43.289Z,1574195983.289 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 275.000000 revolution_per_minute 2019-11-19T20:39:45.506Z,1574195985.506 [Reporter](INFO): ThrusterServo.component_avgCurrent 678.950012 mA 2019-11-19T20:39:47.942Z,1574195987.942 [Reporter](INFO): ThrusterServo.component_avgCurrent 844.100037 mA 2019-11-19T20:39:50.366Z,1574195990.366 [Reporter](INFO): ThrusterServo.component_avgCurrent 818.410034 mA 2019-11-19T20:39:52.804Z,1574195992.804 [Reporter](INFO): ThrusterServo.component_avgCurrent 902.820007 mA 2019-11-19T20:39:54.742Z,1574195994.742 [CommandLine](IMPORTANT): got command get RDI_Pathfinder.loadControl 2019-11-19T20:39:54.742Z,1574195994.742 [CommandLine](IMPORTANT): RDI_Pathfinder.loadControl /dev/loadB4 2019-11-19T20:39:55.223Z,1574195995.223 [Reporter](INFO): ThrusterServo.component_avgCurrent 851.440002 mA 2019-11-19T20:39:57.634Z,1574195997.634 [Reporter](INFO): ThrusterServo.component_avgCurrent 844.100037 mA 2019-11-19T20:40:00.075Z,1574196000.075 [Reporter](INFO): ThrusterServo.component_avgCurrent 880.800049 mA 2019-11-19T20:40:02.518Z,1574196002.518 [Reporter](INFO): ThrusterServo.component_avgCurrent 873.460022 mA 2019-11-19T20:40:02.591Z,1574196002.591 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB4 2019-11-19T20:40:04.930Z,1574196004.930 [Reporter](INFO): ThrusterServo.component_avgCurrent 921.170044 mA 2019-11-19T20:40:07.334Z,1574196007.334 [Reporter](INFO): ThrusterServo.component_avgCurrent 796.390015 mA 2019-11-19T20:40:09.794Z,1574196009.794 [Reporter](INFO): ThrusterServo.component_avgCurrent 858.780029 mA 2019-11-19T20:40:11.898Z,1574196011.898 [Reporter](INFO): ThrusterServo.component_avgCurrent 888.140015 mA 2019-11-19T20:40:14.199Z,1574196014.199 [Reporter](INFO): ThrusterServo.component_avgCurrent 811.070007 mA 2019-11-19T20:40:15.827Z,1574196015.827 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 280.000000 revolution_per_minute 2019-11-19T20:40:16.610Z,1574196016.610 [Reporter](INFO): ThrusterServo.component_avgCurrent 844.100037 mA 2019-11-19T20:40:19.059Z,1574196019.059 [Reporter](INFO): ThrusterServo.component_avgCurrent 858.780029 mA 2019-11-19T20:40:21.458Z,1574196021.458 [Reporter](INFO): ThrusterServo.component_avgCurrent 873.460022 mA 2019-11-19T20:40:23.911Z,1574196023.911 [Reporter](INFO): ThrusterServo.component_avgCurrent 946.860046 mA 2019-11-19T20:40:26.355Z,1574196026.355 [Reporter](INFO): ThrusterServo.component_avgCurrent 803.730042 mA 2019-11-19T20:40:28.750Z,1574196028.750 [Reporter](INFO): ThrusterServo.component_avgCurrent 935.850037 mA 2019-11-19T20:40:31.170Z,1574196031.170 [Reporter](INFO): ThrusterServo.component_avgCurrent 869.790039 mA 2019-11-19T20:40:33.578Z,1574196033.578 [Reporter](INFO): ThrusterServo.component_avgCurrent 946.860046 mA 2019-11-19T20:40:36.078Z,1574196036.078 [Reporter](INFO): ThrusterServo.component_avgCurrent 880.800049 mA 2019-11-19T20:40:38.576Z,1574196038.576 [Reporter](INFO): ThrusterServo.component_avgCurrent 877.130005 mA 2019-11-19T20:40:43.282Z,1574196043.282 [Reporter](INFO): ThrusterServo.component_avgCurrent 858.780029 mA 2019-11-19T20:40:45.734Z,1574196045.734 [Reporter](INFO): ThrusterServo.component_avgCurrent 917.500000 mA 2019-11-19T20:40:48.130Z,1574196048.130 [Reporter](INFO): ThrusterServo.component_avgCurrent 836.760010 mA 2019-11-19T20:40:50.571Z,1574196050.571 [Reporter](INFO): ThrusterServo.component_avgCurrent 935.850037 mA 2019-11-19T20:40:52.995Z,1574196052.995 [Reporter](INFO): ThrusterServo.component_avgCurrent 829.420044 mA 2019-11-19T20:40:55.416Z,1574196055.416 [Reporter](INFO): ThrusterServo.component_avgCurrent 891.809998 mA 2019-11-19T20:40:57.830Z,1574196057.830 [Reporter](INFO): ThrusterServo.component_avgCurrent 851.440002 mA 2019-11-19T20:41:00.270Z,1574196060.270 [Reporter](INFO): ThrusterServo.component_avgCurrent 833.090027 mA 2019-11-19T20:41:02.682Z,1574196062.682 [Reporter](INFO): ThrusterServo.component_avgCurrent 939.520020 mA 2019-11-19T20:41:05.158Z,1574196065.158 [Reporter](INFO): ThrusterServo.component_avgCurrent 906.489990 mA 2019-11-19T20:41:07.530Z,1574196067.530 [Reporter](INFO): ThrusterServo.component_avgCurrent 943.190002 mA 2019-11-19T20:41:09.956Z,1574196069.956 [Reporter](INFO): ThrusterServo.component_avgCurrent 990.900024 mA 2019-11-19T20:41:12.382Z,1574196072.382 [Reporter](INFO): ThrusterServo.component_avgCurrent 877.130005 mA 2019-11-19T20:41:14.798Z,1574196074.798 [Reporter](INFO): ThrusterServo.component_avgCurrent 972.550049 mA 2019-11-19T20:41:17.259Z,1574196077.259 [Reporter](INFO): ThrusterServo.component_avgCurrent 862.450012 mA 2019-11-19T20:41:19.654Z,1574196079.654 [Reporter](INFO): ThrusterServo.component_avgCurrent 1001.910095 mA 2019-11-19T20:41:22.058Z,1574196082.058 [Reporter](INFO): ThrusterServo.component_avgCurrent 825.750000 mA 2019-11-19T20:41:24.490Z,1574196084.490 [Reporter](INFO): ThrusterServo.component_avgCurrent 961.540039 mA 2019-11-19T20:41:26.935Z,1574196086.935 [Reporter](INFO): ThrusterServo.component_avgCurrent 939.520020 mA 2019-11-19T20:41:29.330Z,1574196089.330 [Reporter](INFO): ThrusterServo.component_avgCurrent 935.850037 mA 2019-11-19T20:41:31.834Z,1574196091.834 [Reporter](INFO): ThrusterServo.component_avgCurrent 902.820007 mA 2019-11-19T20:41:34.182Z,1574196094.182 [Reporter](INFO): ThrusterServo.component_avgCurrent 939.520020 mA 2019-11-19T20:41:36.618Z,1574196096.618 [Reporter](INFO): ThrusterServo.component_avgCurrent 946.860046 mA 2019-11-19T20:41:39.030Z,1574196099.030 [Reporter](INFO): ThrusterServo.component_avgCurrent 792.720032 mA 2019-11-19T20:41:41.466Z,1574196101.466 [Reporter](INFO): ThrusterServo.component_avgCurrent 957.869995 mA 2019-11-19T20:41:43.886Z,1574196103.886 [Reporter](INFO): ThrusterServo.component_avgCurrent 932.179993 mA 2019-11-19T20:41:46.326Z,1574196106.326 [Reporter](INFO): ThrusterServo.component_avgCurrent 994.570007 mA 2019-11-19T20:41:48.718Z,1574196108.718 [Reporter](INFO): ThrusterServo.component_avgCurrent 792.720032 mA 2019-11-19T20:41:51.175Z,1574196111.175 [Reporter](INFO): ThrusterServo.component_avgCurrent 950.530029 mA 2019-11-19T20:41:53.583Z,1574196113.583 [Reporter](INFO): ThrusterServo.component_avgCurrent 932.179993 mA 2019-11-19T20:41:56.014Z,1574196116.014 [Reporter](INFO): ThrusterServo.component_avgCurrent 873.460022 mA 2019-11-19T20:41:58.505Z,1574196118.505 [Reporter](INFO): ThrusterServo.component_avgCurrent 884.470032 mA 2019-11-19T20:41:59.249Z,1574196119.249 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-11-19T20:41:59.249Z,1574196119.249 [RDI_Pathfinder] No Fault, FailCount= 3 2019-11-19T20:42:00.866Z,1574196120.866 [Reporter](INFO): ThrusterServo.component_avgCurrent 895.480042 mA 2019-11-19T20:42:03.290Z,1574196123.290 [Reporter](INFO): ThrusterServo.component_avgCurrent 906.489990 mA 2019-11-19T20:42:05.699Z,1574196125.699 [Reporter](INFO): ThrusterServo.component_avgCurrent 829.420044 mA 2019-11-19T20:42:08.122Z,1574196128.122 [Reporter](INFO): ThrusterServo.component_avgCurrent 902.820007 mA 2019-11-19T20:42:09.688Z,1574196129.688 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-11-19T20:42:09.692Z,1574196129.692 [BPC1](INFO): Received data from all battery sticks. 2019-11-19T20:42:10.562Z,1574196130.562 [Reporter](INFO): ThrusterServo.component_avgCurrent 921.170044 mA 2019-11-19T20:42:12.121Z,1574196132.121 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2019-11-19T20:42:12.121Z,1574196132.121 [RDI_Pathfinder] Data Fault, FailCount= 1 2019-11-19T20:42:12.121Z,1574196132.121 [RDI_Pathfinder](ERROR): Data Fault 2019-11-19T20:42:12.173Z,1574196132.173 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2019-11-19T20:42:12.585Z,1574196132.585 [RDI_Pathfinder](INFO): Powering down 2019-11-19T20:42:12.990Z,1574196132.990 [Reporter](INFO): ThrusterServo.component_avgCurrent 873.460022 mA 2019-11-19T20:42:13.373Z,1574196133.373 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-11-19T20:42:13.373Z,1574196133.373 [RDI_Pathfinder] No Fault, FailCount= 1 2019-11-19T20:42:15.410Z,1574196135.410 [Reporter](INFO): ThrusterServo.component_avgCurrent 877.130005 mA 2019-11-19T20:42:17.810Z,1574196137.810 [Reporter](INFO): ThrusterServo.component_avgCurrent 924.840027 mA 2019-11-19T20:42:20.254Z,1574196140.254 [Reporter](INFO): ThrusterServo.component_avgCurrent 957.869995 mA 2019-11-19T20:42:22.674Z,1574196142.674 [Reporter](INFO): ThrusterServo.component_avgCurrent 935.850037 mA 2019-11-19T20:42:25.118Z,1574196145.118 [Reporter](INFO): ThrusterServo.component_avgCurrent 910.160034 mA 2019-11-19T20:42:27.526Z,1574196147.526 [Reporter](INFO): ThrusterServo.component_avgCurrent 921.170044 mA 2019-11-19T20:42:29.962Z,1574196149.962 [Reporter](INFO): ThrusterServo.component_avgCurrent 990.900024 mA 2019-11-19T20:42:32.355Z,1574196152.355 [Reporter](INFO): ThrusterServo.component_avgCurrent 946.860046 mA 2019-11-19T20:42:34.788Z,1574196154.788 [Reporter](INFO): ThrusterServo.component_avgCurrent 957.869995 mA 2019-11-19T20:42:35.947Z,1574196155.947 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-11-19T20:42:35.948Z,1574196155.948 [RDI_Pathfinder] Communications Fault, FailCount= 2 2019-11-19T20:42:35.948Z,1574196155.948 [RDI_Pathfinder](ERROR): Communications Fault 2019-11-19T20:42:36.001Z,1574196156.001 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-11-19T20:42:36.409Z,1574196156.409 [RDI_Pathfinder](INFO): Powering down 2019-11-19T20:42:37.201Z,1574196157.201 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-11-19T20:42:37.201Z,1574196157.201 [RDI_Pathfinder] No Fault, FailCount= 2 2019-11-19T20:42:37.203Z,1574196157.203 [Reporter](INFO): ThrusterServo.component_avgCurrent 847.770020 mA 2019-11-19T20:42:39.650Z,1574196159.650 [Reporter](INFO): ThrusterServo.component_avgCurrent 902.820007 mA 2019-11-19T20:42:42.054Z,1574196162.054 [Reporter](INFO): ThrusterServo.component_avgCurrent 906.489990 mA 2019-11-19T20:42:44.499Z,1574196164.499 [Reporter](INFO): ThrusterServo.component_avgCurrent 939.520020 mA 2019-11-19T20:42:46.902Z,1574196166.902 [Reporter](INFO): ThrusterServo.component_avgCurrent 972.550049 mA 2019-11-19T20:42:49.342Z,1574196169.342 [Reporter](INFO): ThrusterServo.component_avgCurrent 895.480042 mA 2019-11-19T20:42:51.766Z,1574196171.766 [Reporter](INFO): ThrusterServo.component_avgCurrent 880.800049 mA 2019-11-19T20:42:54.178Z,1574196174.178 [Reporter](INFO): ThrusterServo.component_avgCurrent 946.860046 mA 2019-11-19T20:42:56.602Z,1574196176.602 [Reporter](INFO): ThrusterServo.component_avgCurrent 888.140015 mA 2019-11-19T20:42:59.038Z,1574196179.038 [Reporter](INFO): ThrusterServo.component_avgCurrent 961.540039 mA 2019-11-19T20:42:59.776Z,1574196179.776 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-11-19T20:42:59.776Z,1574196179.776 [RDI_Pathfinder] Communications Fault, FailCount= 3 2019-11-19T20:42:59.776Z,1574196179.776 [RDI_Pathfinder](ERROR): Communications Fault 2019-11-19T20:42:59.830Z,1574196179.830 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-11-19T20:42:59.830Z,1574196179.830 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2019-11-19T20:43:00.257Z,1574196180.257 [RDI_Pathfinder](INFO): Powering down 2019-11-19T20:43:00.347Z,1574196180.347 [CBIT](INFO): Critical error at 20191119T204259 2019-11-19T20:43:01.460Z,1574196181.460 [Reporter](INFO): ThrusterServo.component_avgCurrent 833.090027 mA 2019-11-19T20:43:03.874Z,1574196183.874 [Reporter](INFO): ThrusterServo.component_avgCurrent 917.500000 mA 2019-11-19T20:43:06.314Z,1574196186.314 [Reporter](INFO): ThrusterServo.component_avgCurrent 877.130005 mA 2019-11-19T20:43:08.734Z,1574196188.734 [Reporter](INFO): ThrusterServo.component_avgCurrent 921.170044 mA 2019-11-19T20:43:11.154Z,1574196191.154 [Reporter](INFO): ThrusterServo.component_avgCurrent 902.820007 mA 2019-11-19T20:43:13.574Z,1574196193.574 [Reporter](INFO): ThrusterServo.component_avgCurrent 987.230042 mA 2019-11-19T20:43:16.010Z,1574196196.010 [Reporter](INFO): ThrusterServo.component_avgCurrent 818.410034 mA 2019-11-19T20:43:18.422Z,1574196198.422 [Reporter](INFO): ThrusterServo.component_avgCurrent 939.520020 mA 2019-11-19T20:43:20.858Z,1574196200.858 [Reporter](INFO): ThrusterServo.component_avgCurrent 877.130005 mA 2019-11-19T20:43:23.262Z,1574196203.262 [Reporter](INFO): ThrusterServo.component_avgCurrent 866.119995 mA 2019-11-19T20:43:25.703Z,1574196205.703 [Reporter](INFO): ThrusterServo.component_avgCurrent 910.160034 mA 2019-11-19T20:43:28.114Z,1574196208.114 [Reporter](INFO): ThrusterServo.component_avgCurrent 888.140015 mA 2019-11-19T20:43:30.546Z,1574196210.546 [Reporter](INFO): ThrusterServo.component_avgCurrent 910.160034 mA 2019-11-19T20:43:32.966Z,1574196212.966 [Reporter](INFO): ThrusterServo.component_avgCurrent 840.429993 mA 2019-11-19T20:43:35.397Z,1574196215.397 [Reporter](INFO): ThrusterServo.component_avgCurrent 902.820007 mA 2019-11-19T20:43:37.850Z,1574196217.850 [Reporter](INFO): ThrusterServo.component_avgCurrent 917.500000 mA 2019-11-19T20:43:40.251Z,1574196220.251 [Reporter](INFO): ThrusterServo.component_avgCurrent 895.480042 mA 2019-11-19T20:43:42.662Z,1574196222.662 [Reporter](INFO): ThrusterServo.component_avgCurrent 836.760010 mA 2019-11-19T20:43:45.082Z,1574196225.082 [Reporter](INFO): ThrusterServo.component_avgCurrent 983.559998 mA 2019-11-19T20:43:47.526Z,1574196227.526 [Reporter](INFO): ThrusterServo.component_avgCurrent 957.869995 mA 2019-11-19T20:43:49.918Z,1574196229.918 [Reporter](INFO): ThrusterServo.component_avgCurrent 895.480042 mA 2019-11-19T20:43:52.382Z,1574196232.382 [Reporter](INFO): ThrusterServo.component_avgCurrent 855.110046 mA 2019-11-19T20:43:54.830Z,1574196234.830 [Reporter](INFO): ThrusterServo.component_avgCurrent 917.500000 mA 2019-11-19T20:43:57.222Z,1574196237.222 [Reporter](INFO): ThrusterServo.component_avgCurrent 899.150024 mA 2019-11-19T20:43:59.630Z,1574196239.630 [Reporter](INFO): ThrusterServo.component_avgCurrent 950.530029 mA 2019-11-19T20:44:02.054Z,1574196242.054 [Reporter](INFO): ThrusterServo.component_avgCurrent 891.809998 mA 2019-11-19T20:44:04.482Z,1574196244.482 [Reporter](INFO): ThrusterServo.component_avgCurrent 939.520020 mA 2019-11-19T20:44:06.914Z,1574196246.914 [Reporter](INFO): ThrusterServo.component_avgCurrent 899.150024 mA 2019-11-19T20:44:09.322Z,1574196249.322 [Reporter](INFO): ThrusterServo.component_avgCurrent 844.100037 mA 2019-11-19T20:44:11.812Z,1574196251.812 [Reporter](INFO): ThrusterServo.component_avgCurrent 961.540039 mA 2019-11-19T20:44:14.198Z,1574196254.198 [Reporter](INFO): ThrusterServo.component_avgCurrent 855.110046 mA 2019-11-19T20:44:16.604Z,1574196256.604 [Reporter](INFO): ThrusterServo.component_avgCurrent 880.800049 mA 2019-11-19T20:44:19.018Z,1574196259.018 [Reporter](INFO): ThrusterServo.component_avgCurrent 844.100037 mA 2019-11-19T20:44:21.454Z,1574196261.454 [Reporter](INFO): ThrusterServo.component_avgCurrent 803.730042 mA 2019-11-19T20:44:23.878Z,1574196263.878 [Reporter](INFO): ThrusterServo.component_avgCurrent 869.790039 mA 2019-11-19T20:44:24.416Z,1574196264.416 [CommandLine](IMPORTANT): got command maintain clear 2019-11-19T20:44:24.624Z,1574196264.624 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-11-19T20:44:24.624Z,1574196264.624 [Default:CheckIn:C.Wait] Stopped 2019-11-19T20:44:24.625Z,1574196264.625 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-11-19T20:44:24.625Z,1574196264.625 [Default:CheckIn:D] Running Loop=1 2019-11-19T20:44:24.666Z,1574196264.666 [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-11-19T20:44:25.034Z,1574196265.034 [Default:CheckIn:D] Stopped 2019-11-19T20:44:25.034Z,1574196265.034 [Default:CheckIn:E] Running Loop=1 2019-11-19T20:44:25.448Z,1574196265.448 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.275934 min 2019-11-19T20:44:25.448Z,1574196265.448 [Default:CheckIn:E] Stopped 2019-11-19T20:44:25.448Z,1574196265.448 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-11-19T20:44:25.448Z,1574196265.448 [Default:CheckIn] Stopped 2019-11-19T20:44:25.448Z,1574196265.448 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-11-19T20:44:25.449Z,1574196265.449 [Default:CheckIn](INFO): Running loop #2 2019-11-19T20:44:25.449Z,1574196265.449 [Default:CheckIn] Running Loop=2 2019-11-19T20:44:25.449Z,1574196265.449 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-11-19T20:44:25.449Z,1574196265.449 [Default:CheckIn:Read_GPS] Running Loop=1 2019-11-19T20:44:25.574Z,1574196265.574 [Reporter](INFO): ThrusterServo.component_avgCurrent 0.000000 mA 2019-11-19T20:44:27.435Z,1574196267.435 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204434.00,A,3648.16628,N,12147.28069,W,0.078,0.00,191119,,,A*70 2019-11-19T20:44:27.442Z,1574196267.442 [NAL9602](INFO): GPS fix at 20191119T204434: (36.802771, -121.788011) 2019-11-19T20:44:27.465Z,1574196267.465 [Default:CheckIn:Read_GPS] Stopped 2019-11-19T20:44:27.465Z,1574196267.465 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-11-19T20:44:28.383Z,1574196268.383 [CommandLine](IMPORTANT): got command report clear 2019-11-19T20:44:35.149Z,1574196275.149 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20191119T203419/Courier0007.lzma 2019-11-19T20:44:37.154Z,1574196277.154 [DataOverHttps](INFO): Moved sent file to Logs/20191119T203419/Courier0007.lzma.bak 2019-11-19T20:44:37.155Z,1574196277.155 [DataOverHttps](INFO): SBD MOMSN=12073247 2019-11-19T20:44:54.133Z,1574196294.133 [DataOverHttps](INFO): Sending 365 bytes from file Logs/20191119T203419/Express0008.lzma 2019-11-19T20:44:56.138Z,1574196296.138 [DataOverHttps](INFO): Moved sent file to Logs/20191119T203419/Express0008.lzma.bak 2019-11-19T20:44:56.139Z,1574196296.139 [DataOverHttps](INFO): SBD MOMSN=12073252 2019-11-19T20:44:57.355Z,1574196297.355 [Default:CheckIn:Read_Iridium] Stopped 2019-11-19T20:44:57.356Z,1574196297.356 [Default:CheckIn:C.Wait] Running Loop=1 2019-11-19T20:44:57.356Z,1574196297.356 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-11-19T20:45:06.018Z,1574196306.018 [CommandLine](IMPORTANT): got command quit 2019-11-19T20:45:07.025Z,1574196307.025 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:07.026Z,1574196307.026 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.073Z,1574196307.073 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-11-19T20:45:07.073Z,1574196307.073 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.074Z,1574196307.074 [CommandLine](INFO): Join timeout helper Thread ID is 2139 2019-11-19T20:45:07.086Z,1574196307.086 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-11-19T20:45:07.086Z,1574196307.086 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.087Z,1574196307.087 [NavChartDb](INFO): Join timeout helper Thread ID is 2140 2019-11-19T20:45:07.257Z,1574196307.257 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:07.257Z,1574196307.257 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.273Z,1574196307.273 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2019-11-19T20:45:07.273Z,1574196307.273 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.273Z,1574196307.273 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 2141 2019-11-19T20:45:07.533Z,1574196307.533 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:07.533Z,1574196307.533 [WetLabsUBAT](INFO): Powering down 2019-11-19T20:45:07.550Z,1574196307.550 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.565Z,1574196307.565 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-11-19T20:45:07.565Z,1574196307.565 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.565Z,1574196307.565 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2142 2019-11-19T20:45:07.701Z,1574196307.701 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:07.701Z,1574196307.701 [WetLabsBB2FL](INFO): Powering down 2019-11-19T20:45:07.702Z,1574196307.702 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.709Z,1574196307.709 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-11-19T20:45:07.709Z,1574196307.709 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.709Z,1574196307.709 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2143 2019-11-19T20:45:07.969Z,1574196307.969 [CTD_Seabird](INFO): Powering down 2019-11-19T20:45:07.981Z,1574196307.981 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:07.981Z,1574196307.981 [CTD_Seabird](INFO): Powering down 2019-11-19T20:45:07.997Z,1574196307.997 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.998Z,1574196307.998 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler 2019-11-19T20:45:07.998Z,1574196307.998 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:07.999Z,1574196307.999 [BackSeatDriver](INFO): Join timeout helper Thread ID is 2144 2019-11-19T20:45:08.029Z,1574196308.029 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:08.029Z,1574196308.029 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:08.038Z,1574196308.038 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-11-19T20:45:08.038Z,1574196308.038 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:08.038Z,1574196308.038 [Radio_Surface](INFO): Join timeout helper Thread ID is 2145 2019-11-19T20:45:08.349Z,1574196308.349 [Radio_Surface](INFO): Powering down 2019-11-19T20:45:08.350Z,1574196308.350 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:08.350Z,1574196308.350 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:08.365Z,1574196308.365 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-11-19T20:45:08.365Z,1574196308.365 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:08.365Z,1574196308.365 [DataOverHttps](INFO): Join timeout helper Thread ID is 2146 2019-11-19T20:45:08.865Z,1574196308.865 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:08.865Z,1574196308.865 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:08.881Z,1574196308.881 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-11-19T20:45:08.881Z,1574196308.881 [logger ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:08.881Z,1574196308.881 [logger](INFO): Join timeout helper Thread ID is 2147 2019-11-19T20:45:08.881Z,1574196308.881 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:08.882Z,1574196308.882 [logger ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:08.883Z,1574196308.883 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-11-19T20:45:08.883Z,1574196308.883 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:08.883Z,1574196308.883 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-11-19T20:45:08.883Z,1574196308.883 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:08.884Z,1574196308.884 [controlThread](INFO): Join timeout helper Thread ID is 2148 2019-11-19T20:45:09.025Z,1574196309.025 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-11-19T20:45:09.025Z,1574196309.025 [controlThread](DEBUG): Uninitializing ControlThread 2019-11-19T20:45:09.025Z,1574196309.025 [AHRS_M2](INFO): Powering down 2019-11-19T20:45:09.097Z,1574196309.097 [NAL9602](INFO): Powering down 2019-11-19T20:45:09.099Z,1574196309.099 [RDI_Pathfinder](INFO): Powering down 2019-11-19T20:45:09.100Z,1574196309.100 [DAT](INFO): Powering down 2019-11-19T20:45:09.217Z,1574196309.217 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-11-19T20:45:09.218Z,1574196309.218 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-11-19T20:45:09.219Z,1574196309.219 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-11-19T20:45:09.219Z,1574196309.219 [MissionManager](INFO): Uninitializing Mission Default 2019-11-19T20:45:09.219Z,1574196309.219 [Default] Stopped 2019-11-19T20:45:09.220Z,1574196309.220 [Default](DEBUG): Aggregate::uninitialize Default 2019-11-19T20:45:09.220Z,1574196309.220 [Default:B.GoToSurface] Stopped 2019-11-19T20:45:09.220Z,1574196309.220 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-11-19T20:45:09.220Z,1574196309.220 [Default:CheckIn] Stopped 2019-11-19T20:45:09.220Z,1574196309.220 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-11-19T20:45:09.220Z,1574196309.220 [Default:CheckIn:C.Wait] Stopped 2019-11-19T20:45:09.220Z,1574196309.220 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-11-19T20:45:09.223Z,1574196309.223 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-11-19T20:45:09.223Z,1574196309.223 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-11-19T20:45:09.223Z,1574196309.223 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-11-19T20:45:09.223Z,1574196309.223 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-11-19T20:45:09.224Z,1574196309.224 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-11-19T20:45:09.224Z,1574196309.224 [BuoyancyServo](INFO): Powering down 2019-11-19T20:45:09.237Z,1574196309.237 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-11-19T20:45:09.237Z,1574196309.237 [ElevatorServo](INFO): Powering down 2019-11-19T20:45:09.238Z,1574196309.238 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-11-19T20:45:09.238Z,1574196309.238 [MassServo](INFO): Powering down 2019-11-19T20:45:09.239Z,1574196309.239 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-11-19T20:45:09.239Z,1574196309.239 [RudderServo](INFO): Powering down 2019-11-19T20:45:09.239Z,1574196309.239 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-11-19T20:45:09.240Z,1574196309.240 [ThrusterServo](INFO): Powering down 2019-11-19T20:45:09.240Z,1574196309.240 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-11-19T20:45:09.241Z,1574196309.241 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-11-19T20:45:09.241Z,1574196309.241 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-11-19T20:45:09.241Z,1574196309.241 [CBIT](DEBUG): Powering off loads. 2019-11-19T20:45:09.253Z,1574196309.253 [CBIT](DEBUG): Disabling WDT. 2019-11-19T20:45:09.265Z,1574196309.265 [CBIT](DEBUG): Opening all GF detection circuits. 2019-11-19T20:45:09.266Z,1574196309.266 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:09.357Z,1574196309.357 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:09.363Z,1574196309.363 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:09.417Z,1574196309.417 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:09.418Z,1574196309.418 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open 2019-11-19T20:45:09.418Z,1574196309.418 [WetLabsUBAT](INFO): WetLabs destructor: done 2019-11-19T20:45:09.425Z,1574196309.425 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:09.427Z,1574196309.427 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:09.430Z,1574196309.430 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:09.439Z,1574196309.439 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-11-19T20:45:09.511Z,1574196309.511 [logger ThreadHandler](INFO): Thread cancelled.