2019-05-08T23:17:51.908Z,1557357471.908 [Supervisor](DEBUG): Initializing supervisor. 2019-05-08T23:17:51.911Z,1557357471.911 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-05-08T23:17:51.911Z,1557357471.911 [SyncHandler](INFO): Protected caller Thread ID is 807 2019-05-08T23:17:51.912Z,1557357471.912 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-05-08T23:17:51.913Z,1557357471.913 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-05-08T23:17:51.913Z,1557357471.913 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808 2019-05-08T23:17:51.916Z,1557357471.916 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-05-08T23:17:51.927Z,1557357471.927 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-05-08T23:17:51.928Z,1557357471.928 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-05-08T23:17:51.929Z,1557357471.929 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809 2019-05-08T23:17:51.929Z,1557357471.929 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-05-08T23:17:51.930Z,1557357471.930 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-05-08T23:17:51.931Z,1557357471.931 [logger ThreadHandler](INFO): Protected caller Thread ID is 810 2019-05-08T23:17:51.933Z,1557357471.933 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-05-08T23:17:51.933Z,1557357471.933 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-05-08T23:17:51.936Z,1557357471.936 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-05-08T23:17:52.032Z,1557357472.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-05-08T23:17:52.033Z,1557357472.033 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-05-08T23:17:52.234Z,1557357472.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-05-08T23:17:52.234Z,1557357472.234 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-05-08T23:17:52.370Z,1557357472.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-05-08T23:17:52.373Z,1557357472.373 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-05-08T23:17:52.908Z,1557357472.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-05-08T23:17:52.909Z,1557357472.909 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-05-08T23:17:53.363Z,1557357473.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-05-08T23:17:53.364Z,1557357473.364 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-05-08T23:17:53.831Z,1557357473.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-05-08T23:17:53.832Z,1557357473.832 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-05-08T23:17:54.138Z,1557357474.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-05-08T23:17:54.140Z,1557357474.140 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-05-08T23:17:54.502Z,1557357474.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-05-08T23:17:54.502Z,1557357474.502 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-05-08T23:17:54.958Z,1557357474.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-05-08T23:17:54.959Z,1557357474.959 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-05-08T23:17:55.110Z,1557357475.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-05-08T23:17:55.112Z,1557357475.112 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-05-08T23:17:55.216Z,1557357475.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-05-08T23:17:55.218Z,1557357475.218 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-05-08T23:17:55.304Z,1557357475.304 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-05-08T23:17:55.731Z,1557357475.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-05-08T23:17:55.732Z,1557357475.732 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-05-08T23:17:55.927Z,1557357475.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-05-08T23:17:55.928Z,1557357475.928 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-05-08T23:17:56.134Z,1557357476.134 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-05-08T23:17:56.136Z,1557357476.136 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-05-08T23:17:56.138Z,1557357476.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-05-08T23:17:56.222Z,1557357476.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-05-08T23:17:56.447Z,1557357476.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-05-08T23:17:56.448Z,1557357476.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-05-08T23:17:56.543Z,1557357476.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-05-08T23:17:56.702Z,1557357476.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-05-08T23:17:56.905Z,1557357476.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-05-08T23:17:56.991Z,1557357476.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-05-08T23:17:57.089Z,1557357477.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-05-08T23:17:57.181Z,1557357477.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-05-08T23:17:57.334Z,1557357477.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-05-08T23:17:57.439Z,1557357477.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-05-08T23:17:57.537Z,1557357477.537 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2019-05-08T23:17:57.537Z,1557357477.537 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2019-05-08T23:17:57.540Z,1557357477.540 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-05-08T23:17:57.667Z,1557357477.667 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-05-08T23:17:57.667Z,1557357477.667 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-05-08T23:17:57.821Z,1557357477.821 [BuoyancyServo] Loaded 2019-05-08T23:17:57.821Z,1557357477.821 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-05-08T23:17:57.836Z,1557357477.836 [ElevatorServo] Loaded 2019-05-08T23:17:57.837Z,1557357477.837 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-05-08T23:17:57.851Z,1557357477.851 [MassServo] Loaded 2019-05-08T23:17:57.851Z,1557357477.851 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-05-08T23:17:57.866Z,1557357477.866 [RudderServo] Loaded 2019-05-08T23:17:57.866Z,1557357477.866 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-05-08T23:17:57.880Z,1557357477.880 [ThrusterServo] Loaded 2019-05-08T23:17:57.881Z,1557357477.881 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-05-08T23:17:57.881Z,1557357477.881 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-05-08T23:17:57.883Z,1557357477.883 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-05-08T23:17:58.039Z,1557357478.039 [SBIT](DEBUG): Construct Startup Built In Test. 2019-05-08T23:17:58.068Z,1557357478.068 [SBIT] Loaded 2019-05-08T23:17:58.069Z,1557357478.069 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-05-08T23:17:58.069Z,1557357478.069 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-05-08T23:17:58.096Z,1557357478.096 [IBIT] Loaded 2019-05-08T23:17:58.097Z,1557357478.097 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-05-08T23:17:58.100Z,1557357478.100 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-05-08T23:17:58.481Z,1557357478.481 [CBIT] Loaded 2019-05-08T23:17:58.481Z,1557357478.481 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-05-08T23:17:58.481Z,1557357478.481 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-05-08T23:17:58.482Z,1557357478.482 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-05-08T23:17:58.735Z,1557357478.735 [Aanderaa_O2] Loaded 2019-05-08T23:17:58.735Z,1557357478.735 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-05-08T23:17:58.744Z,1557357478.744 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-05-08T23:17:58.750Z,1557357478.750 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-05-08T23:17:58.751Z,1557357478.751 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-05-08T23:17:58.756Z,1557357478.756 [CTD_Seabird](INFO): created writer for : depth 2019-05-08T23:17:58.756Z,1557357478.756 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-05-08T23:17:58.762Z,1557357478.761 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-05-08T23:17:58.762Z,1557357478.762 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-05-08T23:17:58.767Z,1557357478.767 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-05-08T23:17:58.768Z,1557357478.768 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-05-08T23:17:58.773Z,1557357478.773 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-05-08T23:17:58.774Z,1557357478.774 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-05-08T23:17:58.779Z,1557357478.779 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-05-08T23:17:58.779Z,1557357478.779 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-05-08T23:17:58.784Z,1557357478.784 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-05-08T23:17:58.810Z,1557357478.810 [CTD_Seabird] Loaded 2019-05-08T23:17:58.810Z,1557357478.810 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-05-08T23:17:58.811Z,1557357478.811 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406624E0 2019-05-08T23:17:58.812Z,1557357478.812 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 890 2019-05-08T23:17:58.837Z,1557357478.837 [ESPComponent] Loaded 2019-05-08T23:17:58.837Z,1557357478.837 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-05-08T23:17:58.851Z,1557357478.851 [PAR_Licor] Loaded 2019-05-08T23:17:58.852Z,1557357478.852 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-05-08T23:17:58.898Z,1557357478.898 [WetLabsBB2FL] Loaded 2019-05-08T23:17:58.899Z,1557357478.899 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-05-08T23:17:58.900Z,1557357478.900 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406924E0 2019-05-08T23:17:58.900Z,1557357478.900 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 891 2019-05-08T23:17:58.901Z,1557357478.901 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-05-08T23:17:58.903Z,1557357478.903 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-05-08T23:17:58.956Z,1557357478.956 [DepthRateCalculator] Loaded 2019-05-08T23:17:58.957Z,1557357478.957 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-05-08T23:17:58.962Z,1557357478.962 [PitchRateCalculator] Loaded 2019-05-08T23:17:58.963Z,1557357478.963 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-05-08T23:17:58.978Z,1557357478.978 [SpeedCalculator] Loaded 2019-05-08T23:17:58.979Z,1557357478.979 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-05-08T23:17:58.999Z,1557357478.999 [TempGradientCalculator] Loaded 2019-05-08T23:17:58.000Z,1557357479.000 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-05-08T23:17:59.005Z,1557357479.005 [YawRateCalculator] Loaded 2019-05-08T23:17:59.006Z,1557357479.006 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-05-08T23:17:59.038Z,1557357479.038 [ElevatorOffsetCalculator] Loaded 2019-05-08T23:17:59.038Z,1557357479.038 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-05-08T23:17:59.038Z,1557357479.038 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-05-08T23:17:59.039Z,1557357479.039 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-05-08T23:17:59.080Z,1557357479.080 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-05-08T23:17:59.080Z,1557357479.080 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-05-08T23:17:59.199Z,1557357479.199 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-05-08T23:17:59.199Z,1557357479.199 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-05-08T23:17:59.220Z,1557357479.220 [NavChart] Loaded 2019-05-08T23:17:59.220Z,1557357479.220 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-05-08T23:17:59.225Z,1557357479.225 [UniversalFixResidualReporter] Loaded 2019-05-08T23:17:59.225Z,1557357479.225 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-05-08T23:17:59.225Z,1557357479.225 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-05-08T23:17:59.226Z,1557357479.226 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-05-08T23:17:59.747Z,1557357479.747 [AcousticModem_Benthos_ATM900] Loaded 2019-05-08T23:17:59.748Z,1557357479.748 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2019-05-08T23:17:59.888Z,1557357479.888 [DataOverHttps] Loaded 2019-05-08T23:17:59.889Z,1557357479.889 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-05-08T23:17:59.890Z,1557357479.890 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409694E0 2019-05-08T23:17:59.890Z,1557357479.890 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 892 2019-05-08T23:17:59.903Z,1557357479.903 [Depth_Keller] Loaded 2019-05-08T23:17:59.903Z,1557357479.903 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-05-08T23:17:59.908Z,1557357479.908 [DropWeight] Loaded 2019-05-08T23:17:59.908Z,1557357479.908 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-05-08T23:17:59.997Z,1557357479.997 [NAL9602] Loaded 2019-05-08T23:17:59.997Z,1557357479.997 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-05-08T23:18:00.002Z,1557357480.002 [Onboard] Loaded 2019-05-08T23:18:00.002Z,1557357480.002 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-05-08T23:18:00.006Z,1557357480.006 [Radio_Surface] Loaded 2019-05-08T23:18:00.006Z,1557357480.006 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-05-08T23:18:00.007Z,1557357480.007 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409994E0 2019-05-08T23:18:00.007Z,1557357480.007 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 893 2019-05-08T23:18:00.160Z,1557357480.160 [PNI_TCM] Loaded 2019-05-08T23:18:00.160Z,1557357480.160 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2019-05-08T23:18:00.220Z,1557357480.220 [Rowe_600LCM] Loaded 2019-05-08T23:18:00.220Z,1557357480.220 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2019-05-08T23:18:00.221Z,1557357480.221 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409C94E0 2019-05-08T23:18:00.221Z,1557357480.221 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 894 2019-05-08T23:18:01.923Z,1557357481.923 [BPC1] Loaded 2019-05-08T23:18:01.923Z,1557357481.923 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-05-08T23:18:01.923Z,1557357481.923 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-05-08T23:18:01.924Z,1557357481.924 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-05-08T23:18:02.035Z,1557357482.035 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-05-08T23:18:02.036Z,1557357482.036 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-05-08T23:18:02.120Z,1557357482.120 [VerticalControl](DEBUG): Construct VerticalControl. 2019-05-08T23:18:02.203Z,1557357482.203 [VerticalControl] Loaded 2019-05-08T23:18:02.203Z,1557357482.203 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-05-08T23:18:02.204Z,1557357482.204 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-05-08T23:18:02.261Z,1557357482.261 [HorizontalControl] Loaded 2019-05-08T23:18:02.261Z,1557357482.261 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-05-08T23:18:02.261Z,1557357482.261 [SpeedControl](DEBUG): Construct SpeedControl. 2019-05-08T23:18:02.263Z,1557357482.263 [SpeedControl] Loaded 2019-05-08T23:18:02.264Z,1557357482.264 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-05-08T23:18:02.264Z,1557357482.264 [LoopControl](DEBUG): Construct LoopControl. 2019-05-08T23:18:02.265Z,1557357482.265 [LoopControl] Loaded 2019-05-08T23:18:02.265Z,1557357482.265 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-05-08T23:18:02.265Z,1557357482.265 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-05-08T23:18:02.266Z,1557357482.266 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-05-08T23:18:02.290Z,1557357482.290 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-05-08T23:18:02.292Z,1557357482.292 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-05-08T23:18:02.624Z,1557357482.624 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-05-08T23:18:02.628Z,1557357482.628 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-05-08T23:18:02.629Z,1557357482.629 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-05-08T23:18:02.636Z,1557357482.636 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-05-08T23:18:02.637Z,1557357482.637 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0 2019-05-08T23:18:02.637Z,1557357482.637 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 895 2019-05-08T23:18:02.642Z,1557357482.642 [Supervisor](INFO): Main Thread ID is 803 2019-05-08T23:18:02.642Z,1557357482.642 [Supervisor](DEBUG): Running supervisor. 2019-05-08T23:18:02.642Z,1557357482.642 [CommandLine ThreadHandler](INFO): Handler Thread ID is 896 2019-05-08T23:18:02.645Z,1557357482.645 [controlThread ThreadHandler](INFO): Handler Thread ID is 897 2019-05-08T23:18:02.645Z,1557357482.645 [controlThread](DEBUG): Initializing ControlThread 2019-05-08T23:18:02.647Z,1557357482.647 [SBIT](INFO): Initialize SBIT Component. 2019-05-08T23:18:02.648Z,1557357482.648 [SBIT](IMPORTANT): git: 2019-05-01-6-g08bb213 2019-05-08T23:18:02.648Z,1557357482.648 [SBIT](INFO): git hash: 08bb2137fac00bb96d649b4144ef516361bafd54 2019-05-08T23:18:02.648Z,1557357482.648 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-05-08T23:18:02.649Z,1557357482.649 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-05-08T23:18:02.651Z,1557357482.651 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2019-05-08T23:18:02.651Z,1557357482.651 [IBIT](INFO): Initialize IBIT Component. 2019-05-08T23:18:02.652Z,1557357482.652 [CBIT](DEBUG): Initialize CBIT Component. 2019-05-08T23:18:02.653Z,1557357482.653 [logger ThreadHandler](INFO): Handler Thread ID is 898 2019-05-08T23:18:02.663Z,1557357482.663 [CBIT](DEBUG): Initialized mux pins. 2019-05-08T23:18:02.663Z,1557357482.663 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2019-05-08T23:18:02.663Z,1557357482.663 [CBIT](DEBUG): Initializing the watchdog timer. 2019-05-08T23:18:02.671Z,1557357482.671 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 899 2019-05-08T23:18:02.672Z,1557357482.672 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-05-08T23:18:02.676Z,1557357482.676 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-05-08T23:18:02.677Z,1557357482.677 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 901 2019-05-08T23:18:02.678Z,1557357482.678 [WetLabsBB2FL](INFO): Powering down 2019-05-08T23:18:02.687Z,1557357482.687 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2019-05-08T23:18:02.687Z,1557357482.687 [CBIT](DEBUG): Initializing heartbeat. 2019-05-08T23:18:02.715Z,1557357482.715 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 902 2019-05-08T23:18:02.716Z,1557357482.716 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-05-08T23:18:02.735Z,1557357482.735 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 903 2019-05-08T23:18:02.755Z,1557357482.755 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 904 2019-05-08T23:18:02.759Z,1557357482.759 [CBIT](DEBUG): Deactivating GF circuits. 2019-05-08T23:18:02.759Z,1557357482.759 [CBIT](DEBUG): Deactivating emergency mode. 2019-05-08T23:18:02.760Z,1557357482.760 [Rowe_600LCM](INFO): Initializing 2019-05-08T23:18:02.795Z,1557357482.795 [CBIT](DEBUG): Backplane powered. 2019-05-08T23:18:02.799Z,1557357482.799 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-05-08T23:18:02.800Z,1557357482.800 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-05-08T23:18:02.800Z,1557357482.800 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-05-08T23:18:02.800Z,1557357482.800 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-05-08T23:18:02.801Z,1557357482.801 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-05-08T23:18:02.801Z,1557357482.801 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-05-08T23:18:02.802Z,1557357482.802 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-05-08T23:18:02.803Z,1557357482.803 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-05-08T23:18:02.817Z,1557357482.817 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-05-08T23:18:02.871Z,1557357482.871 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-05-08T23:18:02.872Z,1557357482.872 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 905 2019-05-08T23:18:02.880Z,1557357482.880 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-05-08T23:18:02.880Z,1557357482.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-05-08T23:18:02.880Z,1557357482.880 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-05-08T23:18:02.880Z,1557357482.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-05-08T23:18:02.880Z,1557357482.880 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-05-08T23:18:02.881Z,1557357482.881 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-05-08T23:18:02.881Z,1557357482.881 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-05-08T23:18:02.881Z,1557357482.881 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-05-08T23:18:02.881Z,1557357482.881 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-05-08T23:18:02.881Z,1557357482.881 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-05-08T23:18:02.882Z,1557357482.882 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-05-08T23:18:02.882Z,1557357482.882 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-05-08T23:18:02.882Z,1557357482.882 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-05-08T23:18:02.882Z,1557357482.882 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-05-08T23:18:02.882Z,1557357482.882 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-05-08T23:18:02.882Z,1557357482.882 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-05-08T23:18:02.895Z,1557357482.895 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-05-08T23:18:02.895Z,1557357482.895 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-05-08T23:18:02.896Z,1557357482.896 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-05-08T23:18:03.017Z,1557357483.017 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-08T23:18:03.135Z,1557357483.135 [MissionManager](DEBUG): 2019-05-08T23:18:03.136Z,1557357483.136 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-05-08T23:18:03.139Z,1557357483.139 [Radio_Surface](INFO): Powering up 2019-05-08T23:18:03.202Z,1557357483.202 [Rowe_600LCM](INFO): Powering up 2019-05-08T23:18:03.262Z,1557357483.262 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-05-08T23:18:03.264Z,1557357483.264 [Default:A.Wait](DEBUG): Construct Wait. 2019-05-08T23:18:03.265Z,1557357483.265 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-08T23:18:03.305Z,1557357483.305 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-05-08T23:18:03.323Z,1557357483.323 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-05-08T23:18:03.329Z,1557357483.329 [Default:E.Execute](DEBUG): Construct Execute. 2019-05-08T23:18:03.360Z,1557357483.360 [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-05-08T23:18:03.365Z,1557357483.365 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,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-05-08T23:18:03.384Z,1557357483.384 [ESPComponent](INFO): powering down ESP 2019-05-08T23:18:03.677Z,1557357483.677 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-05-08T23:18:03.677Z,1557357483.677 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-05-08T23:18:03.700Z,1557357483.700 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar 2019-05-08T23:18:03.767Z,1557357483.767 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-05-08T23:18:03.915Z,1557357483.915 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-05-08T23:18:03.923Z,1557357483.923 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-05-08T23:18:03.924Z,1557357483.924 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-05-08T23:18:03.931Z,1557357483.931 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-05-08T23:18:03.932Z,1557357483.932 [MassServo](DEBUG): Initializing EZServoServo. 2019-05-08T23:18:03.939Z,1557357483.939 [MassServo](DEBUG): Initializing MassServo. 2019-05-08T23:18:03.940Z,1557357483.940 [RudderServo](DEBUG): Initializing EZServoServo. 2019-05-08T23:18:03.948Z,1557357483.948 [RudderServo](DEBUG): Initializing RudderServo. 2019-05-08T23:18:03.948Z,1557357483.948 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-05-08T23:18:03.955Z,1557357483.955 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-05-08T23:18:03.958Z,1557357483.958 [CommandLine](FAULT): Scheduling is paused 2019-05-08T23:18:03.958Z,1557357483.958 [CBIT](INFO): Critical error at 20190508T231802 2019-05-08T23:18:03.958Z,1557357483.958 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-05-08T23:18:04.054Z,1557357484.054 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-05-08T23:18:04.054Z,1557357484.054 [DropWeight] Hardware Fault, FailCount= 1 2019-05-08T23:18:04.054Z,1557357484.054 [DropWeight](ERROR): Hardware Fault 2019-05-08T23:18:04.109Z,1557357484.109 [CBIT](INFO): Critical error at 20190508T231804 2019-05-08T23:18:04.112Z,1557357484.112 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-05-08T23:18:04.113Z,1557357484.113 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-05-08T23:18:04.421Z,1557357484.421 [CBIT](INFO): Critical error at 20190508T231804 2019-05-08T23:18:07.045Z,1557357487.045 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-05-08T23:18:07.132Z,1557357487.132 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./auv-shared/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-05-08T23:18:10.858Z,1557357490.858 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2019-05-08T23:18:10.858Z,1557357490.858 [PNI_TCM] Data Fault, FailCount= 1 2019-05-08T23:18:10.858Z,1557357490.858 [PNI_TCM](ERROR): Data Fault 2019-05-08T23:18:10.931Z,1557357490.931 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-05-08T23:18:11.286Z,1557357491.286 [PNI_TCM](INFO): Powering down 2019-05-08T23:18:12.539Z,1557357492.539 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-05-08T23:18:12.539Z,1557357492.539 [PNI_TCM] No Fault, FailCount= 1 2019-05-08T23:18:12.943Z,1557357492.943 [CBIT](CRITICAL): Environmental Failure. Press:14.697024 PSI. Humidity:38%. Temp:26 C. ABORTING MISSION 2019-05-08T23:18:13.349Z,1557357493.349 [CBIT](INFO): Critical error at 20190508T231812 2019-05-08T23:18:13.663Z,1557357493.663 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-05-08T23:18:13.664Z,1557357493.664 [Aanderaa_O2] Communications Fault, FailCount= 1 2019-05-08T23:18:13.664Z,1557357493.664 [Aanderaa_O2](ERROR): Communications Fault 2019-05-08T23:18:13.764Z,1557357493.764 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-05-08T23:18:14.088Z,1557357494.088 [Aanderaa_O2](INFO): Powering down 2019-05-08T23:18:14.971Z,1557357494.971 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-05-08T23:18:14.971Z,1557357494.971 [Aanderaa_O2] No Fault, FailCount= 1 2019-05-08T23:18:15.723Z,1557357495.723 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-05-08T23:18:15.723Z,1557357495.723 [CTD_Seabird] Communications Fault, FailCount= 1 2019-05-08T23:18:15.723Z,1557357495.723 [CTD_Seabird](ERROR): Communications Fault 2019-05-08T23:18:15.864Z,1557357495.864 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-05-08T23:18:16.127Z,1557357496.127 [CTD_Seabird](INFO): Powering down 2019-05-08T23:18:16.976Z,1557357496.976 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-05-08T23:18:16.976Z,1557357496.976 [CTD_Seabird] No Fault, FailCount= 1 2019-05-08T23:18:17.375Z,1557357497.375 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-05-08T23:18:17.376Z,1557357497.376 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-05-08T23:18:20.151Z,1557357500.151 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2019-05-08T23:18:20.151Z,1557357500.151 [PNI_TCM] Data Fault, FailCount= 2 2019-05-08T23:18:20.151Z,1557357500.151 [PNI_TCM](ERROR): Data Fault 2019-05-08T23:18:20.228Z,1557357500.228 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-05-08T23:18:20.554Z,1557357500.554 [PNI_TCM](INFO): Powering down 2019-05-08T23:18:21.833Z,1557357501.833 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-05-08T23:18:21.833Z,1557357501.833 [PNI_TCM] No Fault, FailCount= 2 2019-05-08T23:18:23.768Z,1557357503.768 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-05-08T23:18:23.768Z,1557357503.768 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1 2019-05-08T23:18:23.768Z,1557357503.768 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-05-08T23:18:23.827Z,1557357503.827 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-05-08T23:18:24.170Z,1557357504.170 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-05-08T23:18:25.048Z,1557357505.048 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-05-08T23:18:25.048Z,1557357505.048 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1 2019-05-08T23:18:25.379Z,1557357505.379 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-05-08T23:18:25.379Z,1557357505.379 [Aanderaa_O2] Communications Fault, FailCount= 2 2019-05-08T23:18:25.379Z,1557357505.379 [Aanderaa_O2](ERROR): Communications Fault 2019-05-08T23:18:25.460Z,1557357505.460 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-05-08T23:18:25.784Z,1557357505.784 [Aanderaa_O2](INFO): Powering down 2019-05-08T23:18:26.658Z,1557357506.658 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-05-08T23:18:26.658Z,1557357506.658 [Aanderaa_O2] No Fault, FailCount= 2 2019-05-08T23:18:27.040Z,1557357507.040 [SBIT](IMPORTANT): Beginning Startup BIT 2019-05-08T23:18:27.048Z,1557357507.048 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-08T23:18:27.416Z,1557357507.416 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-05-08T23:18:27.416Z,1557357507.416 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-05-08T23:18:29.451Z,1557357509.451 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2019-05-08T23:18:29.451Z,1557357509.451 [PNI_TCM] Data Fault, FailCount= 3 2019-05-08T23:18:29.451Z,1557357509.451 [PNI_TCM](ERROR): Data Fault 2019-05-08T23:18:29.880Z,1557357509.880 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-05-08T23:18:30.024Z,1557357510.024 [NAL9602](INFO): Powering up NAL9602 2019-05-08T23:18:30.040Z,1557357510.040 [PNI_TCM](INFO): Powering down 2019-05-08T23:18:30.427Z,1557357510.427 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-05-08T23:18:30.427Z,1557357510.427 [CTD_Seabird] Communications Fault, FailCount= 2 2019-05-08T23:18:30.427Z,1557357510.427 [CTD_Seabird](ERROR): Communications Fault 2019-05-08T23:18:30.500Z,1557357510.500 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-05-08T23:18:30.831Z,1557357510.831 [CTD_Seabird](INFO): Powering down 2019-05-08T23:18:31.320Z,1557357511.320 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-05-08T23:18:31.320Z,1557357511.320 [PNI_TCM] No Fault, FailCount= 3 2019-05-08T23:18:31.787Z,1557357511.787 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-05-08T23:18:31.788Z,1557357511.788 [CTD_Seabird] No Fault, FailCount= 2 2019-05-08T23:18:32.055Z,1557357512.055 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-05-08T23:18:32.056Z,1557357512.056 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-05-08T23:18:37.256Z,1557357517.256 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-05-08T23:18:37.256Z,1557357517.256 [Aanderaa_O2] Communications Fault, FailCount= 3 2019-05-08T23:18:37.256Z,1557357517.256 [Aanderaa_O2](ERROR): Communications Fault 2019-05-08T23:18:37.372Z,1557357517.372 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-05-08T23:18:37.373Z,1557357517.373 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2019-05-08T23:18:37.660Z,1557357517.660 [Aanderaa_O2](INFO): Powering down 2019-05-08T23:18:38.180Z,1557357518.180 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.006236 CHAN A1 (24V): -0.130269 CHAN A2 (12V): -0.005072 CHAN A3 (5V): -0.002498 CHAN B0 (3.3V): -0.000908 CHAN B1 (3.15aV): -0.001473 CHAN B2 (3.15bV): -0.000652 CHAN B3 (GND): -0.000171 OPEN: -0.000835 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-08T23:18:38.895Z,1557357518.895 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2019-05-08T23:18:38.895Z,1557357518.895 [PNI_TCM] Data Fault, FailCount= 4 2019-05-08T23:18:38.895Z,1557357518.895 [PNI_TCM](ERROR): Data Fault 2019-05-08T23:18:39.017Z,1557357519.017 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-05-08T23:18:39.299Z,1557357519.299 [PNI_TCM](INFO): Powering down 2019-05-08T23:18:40.569Z,1557357520.569 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-05-08T23:18:40.569Z,1557357520.569 [PNI_TCM] No Fault, FailCount= 4 2019-05-08T23:18:40.900Z,1557357520.900 [NAL9602](INFO): NAL9602 initialized 2019-05-08T23:18:41.719Z,1557357521.719 [NAL9602](DEBUG): Fix Requested 2019-05-08T23:18:45.107Z,1557357525.107 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-05-08T23:18:45.107Z,1557357525.107 [CTD_Seabird] Communications Fault, FailCount= 3 2019-05-08T23:18:45.107Z,1557357525.107 [CTD_Seabird](ERROR): Communications Fault 2019-05-08T23:18:45.224Z,1557357525.224 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-05-08T23:18:45.224Z,1557357525.224 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2019-05-08T23:18:45.511Z,1557357525.511 [CTD_Seabird](INFO): Powering down 2019-05-08T23:18:47.500Z,1557357527.500 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-05-08T23:18:47.500Z,1557357527.500 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2 2019-05-08T23:18:47.500Z,1557357527.500 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-05-08T23:18:47.736Z,1557357527.736 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-05-08T23:18:47.903Z,1557357527.903 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-05-08T23:18:48.347Z,1557357528.347 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2019-05-08T23:18:48.347Z,1557357528.347 [PNI_TCM] Data Fault, FailCount= 5 2019-05-08T23:18:48.348Z,1557357528.348 [PNI_TCM](ERROR): Data Fault 2019-05-08T23:18:48.414Z,1557357528.414 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-05-08T23:18:48.414Z,1557357528.414 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2019-05-08T23:18:48.762Z,1557357528.762 [PNI_TCM](INFO): Powering down 2019-05-08T23:18:48.842Z,1557357528.842 [CBIT](INFO): Critical error at 20190508T231848 2019-05-08T23:18:48.845Z,1557357528.845 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-05-08T23:18:48.845Z,1557357528.845 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2 2019-05-08T23:18:51.155Z,1557357531.155 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-05-08T23:18:51.156Z,1557357531.156 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-05-08T23:19:11.484Z,1557357551.484 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-05-08T23:19:11.484Z,1557357551.484 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3 2019-05-08T23:19:11.484Z,1557357551.484 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-05-08T23:19:11.736Z,1557357551.736 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-05-08T23:19:11.888Z,1557357551.888 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-05-08T23:19:12.805Z,1557357552.805 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-05-08T23:19:12.805Z,1557357552.805 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3 2019-05-08T23:19:15.119Z,1557357555.119 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-05-08T23:19:15.120Z,1557357555.120 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-05-08T23:19:20.440Z,1557357560.440 [SBIT](IMPORTANT): SBIT PASSED 2019-05-08T23:19:20.521Z,1557357560.521 [CommandLine](IMPORTANT): got command configSet list 2019-05-08T23:19:20.521Z,1557357560.521 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-05-08T23:19:20.523Z,1557357560.523 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-05-08T23:19:20.523Z,1557357560.523 [CommandLine](IMPORTANT): PAR_Licor.adcCal=8.765e-06 microampere_per_count; 2019-05-08T23:19:20.524Z,1557357560.524 [CommandLine](IMPORTANT): PAR_Licor.darkCount=0 count; 2019-05-08T23:19:20.524Z,1557357560.524 [CommandLine](IMPORTANT): PAR_Licor.multiplier=190.3 micromole_per_second_per_square_meter_per_microampere; 2019-05-08T23:19:20.524Z,1557357560.524 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool; 2019-05-08T23:19:20.824Z,1557357560.824 [MissionManager](IMPORTANT): Started mission Startup 2019-05-08T23:19:20.825Z,1557357560.825 [Startup] Running Loop=1 2019-05-08T23:19:20.825Z,1557357560.825 [Startup](DEBUG): Aggregate::initialize Startup 2019-05-08T23:19:20.825Z,1557357560.825 [Startup:A.GoToSurface] Running Loop=1 2019-05-08T23:19:20.825Z,1557357560.825 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-05-08T23:19:20.826Z,1557357560.826 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-05-08T23:19:20.826Z,1557357560.826 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-05-08T23:19:20.826Z,1557357560.826 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-05-08T23:19:20.835Z,1557357560.835 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-05-08T23:19:20.836Z,1557357560.836 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-05-08T23:19:20.859Z,1557357560.859 [Startup:StartupSatComms] Running Loop=1 2019-05-08T23:19:20.859Z,1557357560.859 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-05-08T23:19:20.860Z,1557357560.860 [Startup:StartupSatComms:A] Running Loop=1 2019-05-08T23:19:21.240Z,1557357561.240 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-05-08T23:19:35.340Z,1557357575.340 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-05-08T23:19:35.340Z,1557357575.340 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4 2019-05-08T23:19:35.340Z,1557357575.340 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-05-08T23:19:35.429Z,1557357575.429 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-05-08T23:19:35.723Z,1557357575.723 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-05-08T23:19:36.617Z,1557357576.617 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-05-08T23:19:36.617Z,1557357576.617 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4 2019-05-08T23:19:39.008Z,1557357579.008 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-05-08T23:19:39.008Z,1557357579.008 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-05-08T23:19:59.220Z,1557357599.220 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-05-08T23:19:59.220Z,1557357599.220 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5 2019-05-08T23:19:59.220Z,1557357599.220 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-05-08T23:19:59.319Z,1557357599.319 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-05-08T23:19:59.623Z,1557357599.623 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-05-08T23:20:00.530Z,1557357600.530 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-05-08T23:20:00.530Z,1557357600.530 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5 2019-05-08T23:20:02.856Z,1557357602.856 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-05-08T23:20:02.856Z,1557357602.856 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-05-08T23:20:02.930Z,1557357602.930 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-05-08T23:20:02.930Z,1557357602.930 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-05-08T23:20:02.931Z,1557357602.931 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-08T23:20:02.975Z,1557357602.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-08T23:20:03.251Z,1557357603.251 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2019-05-08T23:20:03.251Z,1557357603.251 [Rowe_600LCM] Communications Fault, FailCount= 1 2019-05-08T23:20:03.251Z,1557357603.251 [Rowe_600LCM](ERROR): Communications Fault 2019-05-08T23:20:03.353Z,1557357603.353 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-08T23:20:03.353Z,1557357603.353 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-05-08T23:20:03.354Z,1557357603.354 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2019-05-08T23:20:03.669Z,1557357603.669 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2019-05-08T23:20:03.972Z,1557357603.972 [Rowe_600LCM](INFO): Powering down 2019-05-08T23:20:05.353Z,1557357605.353 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2019-05-08T23:20:05.353Z,1557357605.353 [Rowe_600LCM] No Fault, FailCount= 1 2019-05-08T23:20:05.587Z,1557357605.587 [Rowe_600LCM](INFO): Initializing 2019-05-08T23:20:05.587Z,1557357605.587 [Rowe_600LCM](INFO): Powering up 2019-05-08T23:20:09.833Z,1557357609.833 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-05-08T23:20:09.930Z,1557357609.930 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./auv-shared/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-05-08T23:20:21.079Z,1557357621.079 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-08T23:19:20.9Z 2019-05-08T23:20:21.079Z,1557357621.079 [Startup:StartupSatComms:A] Stopped 2019-05-08T23:20:21.079Z,1557357621.079 [Startup:StartupSatComms:B] Running Loop=1 2019-05-08T23:20:21.479Z,1557357621.479 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-05-08T23:20:23.136Z,1557357623.136 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-05-08T23:20:23.136Z,1557357623.136 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6 2019-05-08T23:20:23.136Z,1557357623.136 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-05-08T23:20:23.232Z,1557357623.232 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-05-08T23:20:23.539Z,1557357623.539 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-05-08T23:20:24.396Z,1557357624.396 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-05-08T23:20:24.396Z,1557357624.396 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6 2019-05-08T23:20:26.772Z,1557357626.772 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-05-08T23:20:26.772Z,1557357626.772 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-05-08T23:20:41.685Z,1557357641.685 [CommandLine](IMPORTANT): got command restart system 2019-05-08T23:20:42.457Z,1557357642.457 [DataOverHttps]