2019-08-15T15:54:56.530Z,1565884496.530 [Supervisor](DEBUG): Initializing supervisor. 2019-08-15T15:54:56.533Z,1565884496.533 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-08-15T15:54:56.533Z,1565884496.533 [SyncHandler](INFO): Protected caller Thread ID is 7947 2019-08-15T15:54:56.534Z,1565884496.534 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-08-15T15:54:56.534Z,1565884496.534 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-08-15T15:54:56.535Z,1565884496.535 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7948 2019-08-15T15:54:56.537Z,1565884496.537 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-08-15T15:54:56.549Z,1565884496.549 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-08-15T15:54:56.550Z,1565884496.550 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-08-15T15:54:56.550Z,1565884496.550 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7949 2019-08-15T15:54:56.551Z,1565884496.551 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-08-15T15:54:56.552Z,1565884496.552 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-08-15T15:54:56.552Z,1565884496.552 [logger ThreadHandler](INFO): Protected caller Thread ID is 7950 2019-08-15T15:54:56.554Z,1565884496.554 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-08-15T15:54:56.555Z,1565884496.555 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-08-15T15:54:56.556Z,1565884496.556 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-08-15T15:54:56.653Z,1565884496.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-08-15T15:54:56.653Z,1565884496.653 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-08-15T15:54:56.854Z,1565884496.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-08-15T15:54:56.855Z,1565884496.855 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-08-15T15:54:56.992Z,1565884496.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-08-15T15:54:56.992Z,1565884496.992 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-08-15T15:54:57.547Z,1565884497.547 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-08-15T15:54:57.548Z,1565884497.548 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-08-15T15:54:57.985Z,1565884497.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-08-15T15:54:57.986Z,1565884497.986 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-08-15T15:54:58.454Z,1565884498.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-08-15T15:54:58.455Z,1565884498.455 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-08-15T15:54:58.759Z,1565884498.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-08-15T15:54:58.760Z,1565884498.760 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-08-15T15:54:59.122Z,1565884499.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-08-15T15:54:59.123Z,1565884499.123 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-08-15T15:54:59.515Z,1565884499.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-08-15T15:54:59.516Z,1565884499.516 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-08-15T15:54:59.665Z,1565884499.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-08-15T15:54:59.666Z,1565884499.666 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-08-15T15:54:59.771Z,1565884499.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-08-15T15:54:59.772Z,1565884499.772 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-08-15T15:54:59.854Z,1565884499.854 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-08-15T15:54:59.958Z,1565884499.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-08-15T15:54:59.958Z,1565884499.958 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-08-15T15:55:00.153Z,1565884500.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-08-15T15:55:00.154Z,1565884500.154 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-08-15T15:55:00.360Z,1565884500.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-08-15T15:55:00.363Z,1565884500.363 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-08-15T15:55:00.363Z,1565884500.363 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-08-15T15:55:00.449Z,1565884500.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-08-15T15:55:00.677Z,1565884500.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-08-15T15:55:00.678Z,1565884500.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-08-15T15:55:00.776Z,1565884500.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-08-15T15:55:00.948Z,1565884500.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-08-15T15:55:01.161Z,1565884501.161 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-08-15T15:55:01.250Z,1565884501.250 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-08-15T15:55:01.942Z,1565884501.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-08-15T15:55:02.159Z,1565884502.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-08-15T15:55:02.380Z,1565884502.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-08-15T15:55:02.489Z,1565884502.489 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-08-15T15:55:02.590Z,1565884502.590 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2019-08-15T15:55:02.591Z,1565884502.591 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-08-15T15:55:02.685Z,1565884502.685 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-08-15T15:55:02.685Z,1565884502.685 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-08-15T15:55:02.786Z,1565884502.786 [BuoyancyServo] Loaded 2019-08-15T15:55:02.786Z,1565884502.786 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-08-15T15:55:02.801Z,1565884502.801 [ElevatorServo] Loaded 2019-08-15T15:55:02.801Z,1565884502.801 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-08-15T15:55:02.815Z,1565884502.815 [MassServo] Loaded 2019-08-15T15:55:02.815Z,1565884502.815 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-08-15T15:55:02.830Z,1565884502.830 [RudderServo] Loaded 2019-08-15T15:55:02.830Z,1565884502.830 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-08-15T15:55:02.844Z,1565884502.844 [ThrusterServo] Loaded 2019-08-15T15:55:02.844Z,1565884502.844 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-08-15T15:55:02.845Z,1565884502.845 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-08-15T15:55:02.845Z,1565884502.845 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-08-15T15:55:02.971Z,1565884502.971 [SBIT](DEBUG): Construct Startup Built In Test. 2019-08-15T15:55:02.000Z,1565884503.000 [SBIT] Loaded 2019-08-15T15:55:02.000Z,1565884503.000 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-08-15T15:55:03.000Z,1565884503.000 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-08-15T15:55:03.027Z,1565884503.027 [IBIT] Loaded 2019-08-15T15:55:03.027Z,1565884503.027 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-08-15T15:55:03.030Z,1565884503.030 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-08-15T15:55:03.405Z,1565884503.405 [CBIT] Loaded 2019-08-15T15:55:03.405Z,1565884503.405 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-08-15T15:55:03.405Z,1565884503.405 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-08-15T15:55:03.406Z,1565884503.406 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-08-15T15:55:03.579Z,1565884503.579 [Aanderaa_O2] Loaded 2019-08-15T15:55:03.580Z,1565884503.580 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-08-15T15:55:03.589Z,1565884503.589 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-08-15T15:55:03.594Z,1565884503.594 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-08-15T15:55:03.595Z,1565884503.595 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-08-15T15:55:03.600Z,1565884503.600 [CTD_Seabird](INFO): created writer for : depth 2019-08-15T15:55:03.601Z,1565884503.601 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-08-15T15:55:03.606Z,1565884503.606 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-08-15T15:55:03.606Z,1565884503.606 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-08-15T15:55:03.611Z,1565884503.611 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-08-15T15:55:03.612Z,1565884503.612 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-08-15T15:55:03.617Z,1565884503.617 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-08-15T15:55:03.617Z,1565884503.617 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-08-15T15:55:03.622Z,1565884503.622 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-08-15T15:55:03.623Z,1565884503.623 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-08-15T15:55:03.628Z,1565884503.628 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-08-15T15:55:03.653Z,1565884503.653 [CTD_Seabird] Loaded 2019-08-15T15:55:03.653Z,1565884503.653 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-08-15T15:55:03.654Z,1565884503.654 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066B4E0 2019-08-15T15:55:03.655Z,1565884503.655 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8029 2019-08-15T15:55:03.684Z,1565884503.684 [ESPComponent] Loaded 2019-08-15T15:55:03.684Z,1565884503.684 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-08-15T15:55:03.698Z,1565884503.698 [PAR_Licor] Loaded 2019-08-15T15:55:03.698Z,1565884503.698 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-08-15T15:55:03.744Z,1565884503.744 [WetLabsBB2FL] Loaded 2019-08-15T15:55:03.744Z,1565884503.744 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-08-15T15:55:03.745Z,1565884503.745 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069B4E0 2019-08-15T15:55:03.746Z,1565884503.746 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8030 2019-08-15T15:55:03.746Z,1565884503.746 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-08-15T15:55:03.747Z,1565884503.747 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-08-15T15:55:03.786Z,1565884503.786 [DepthRateCalculator] Loaded 2019-08-15T15:55:03.787Z,1565884503.787 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-08-15T15:55:03.792Z,1565884503.792 [PitchRateCalculator] Loaded 2019-08-15T15:55:03.792Z,1565884503.792 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-08-15T15:55:03.808Z,1565884503.808 [SpeedCalculator] Loaded 2019-08-15T15:55:03.808Z,1565884503.808 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-08-15T15:55:03.828Z,1565884503.828 [TempGradientCalculator] Loaded 2019-08-15T15:55:03.828Z,1565884503.828 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-08-15T15:55:03.834Z,1565884503.834 [YawRateCalculator] Loaded 2019-08-15T15:55:03.834Z,1565884503.834 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-08-15T15:55:03.866Z,1565884503.866 [ElevatorOffsetCalculator] Loaded 2019-08-15T15:55:03.866Z,1565884503.866 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-08-15T15:55:03.866Z,1565884503.866 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-08-15T15:55:03.867Z,1565884503.867 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-08-15T15:55:03.897Z,1565884503.897 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-08-15T15:55:03.898Z,1565884503.898 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-08-15T15:55:03.997Z,1565884503.997 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-08-15T15:55:03.997Z,1565884503.997 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-08-15T15:55:04.018Z,1565884504.018 [NavChart] Loaded 2019-08-15T15:55:04.018Z,1565884504.018 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-08-15T15:55:04.022Z,1565884504.022 [UniversalFixResidualReporter] Loaded 2019-08-15T15:55:04.022Z,1565884504.022 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-08-15T15:55:04.023Z,1565884504.023 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-08-15T15:55:04.023Z,1565884504.023 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-08-15T15:55:04.357Z,1565884504.357 [AHRS_M2] Loaded 2019-08-15T15:55:04.357Z,1565884504.357 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-08-15T15:55:04.432Z,1565884504.432 [DataOverHttps] Loaded 2019-08-15T15:55:04.432Z,1565884504.432 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-08-15T15:55:04.433Z,1565884504.433 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409744E0 2019-08-15T15:55:04.434Z,1565884504.434 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8031 2019-08-15T15:55:04.446Z,1565884504.446 [Depth_Keller] Loaded 2019-08-15T15:55:04.447Z,1565884504.447 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-08-15T15:55:04.451Z,1565884504.451 [DropWeight] Loaded 2019-08-15T15:55:04.451Z,1565884504.451 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-08-15T15:55:04.578Z,1565884504.578 [DVL_micro] Loaded 2019-08-15T15:55:04.578Z,1565884504.578 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2019-08-15T15:55:04.665Z,1565884504.665 [NAL9602] Loaded 2019-08-15T15:55:04.665Z,1565884504.665 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-08-15T15:55:04.671Z,1565884504.671 [Onboard] Loaded 2019-08-15T15:55:04.671Z,1565884504.671 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-08-15T15:55:04.674Z,1565884504.674 [Radio_Surface] Loaded 2019-08-15T15:55:04.675Z,1565884504.675 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-08-15T15:55:04.675Z,1565884504.675 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0 2019-08-15T15:55:04.676Z,1565884504.676 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8032 2019-08-15T15:55:04.800Z,1565884504.800 [DAT] Loaded 2019-08-15T15:55:04.800Z,1565884504.800 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-08-15T15:55:06.052Z,1565884506.052 [BPC1] Loaded 2019-08-15T15:55:06.052Z,1565884506.052 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-08-15T15:55:06.052Z,1565884506.052 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-08-15T15:55:06.053Z,1565884506.053 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-08-15T15:55:06.120Z,1565884506.120 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-08-15T15:55:06.120Z,1565884506.120 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-08-15T15:55:06.183Z,1565884506.183 [VerticalControl](DEBUG): Construct VerticalControl. 2019-08-15T15:55:06.264Z,1565884506.264 [VerticalControl] Loaded 2019-08-15T15:55:06.264Z,1565884506.264 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-08-15T15:55:06.265Z,1565884506.265 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-08-15T15:55:06.321Z,1565884506.321 [HorizontalControl] Loaded 2019-08-15T15:55:06.321Z,1565884506.321 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-08-15T15:55:06.322Z,1565884506.322 [SpeedControl](DEBUG): Construct SpeedControl. 2019-08-15T15:55:06.323Z,1565884506.323 [SpeedControl] Loaded 2019-08-15T15:55:06.324Z,1565884506.324 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-08-15T15:55:06.324Z,1565884506.324 [LoopControl](DEBUG): Construct LoopControl. 2019-08-15T15:55:06.325Z,1565884506.325 [LoopControl] Loaded 2019-08-15T15:55:06.325Z,1565884506.325 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-08-15T15:55:06.326Z,1565884506.326 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-08-15T15:55:06.326Z,1565884506.326 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-08-15T15:55:06.339Z,1565884506.339 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-08-15T15:55:06.339Z,1565884506.339 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-08-15T15:55:06.612Z,1565884506.612 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-08-15T15:55:06.616Z,1565884506.616 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-08-15T15:55:06.617Z,1565884506.617 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-08-15T15:55:06.623Z,1565884506.623 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-08-15T15:55:06.624Z,1565884506.624 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0 2019-08-15T15:55:06.625Z,1565884506.625 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8033 2019-08-15T15:55:06.629Z,1565884506.629 [Supervisor](INFO): Main Thread ID is 7946 2019-08-15T15:55:06.629Z,1565884506.629 [Supervisor](DEBUG): Running supervisor. 2019-08-15T15:55:06.630Z,1565884506.630 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8034 2019-08-15T15:55:06.632Z,1565884506.632 [controlThread ThreadHandler](INFO): Handler Thread ID is 8035 2019-08-15T15:55:06.633Z,1565884506.633 [controlThread](DEBUG): Initializing ControlThread 2019-08-15T15:55:06.634Z,1565884506.634 [SBIT](INFO): Initialize SBIT Component. 2019-08-15T15:55:06.635Z,1565884506.635 [SBIT](IMPORTANT): git: 2019-08-13 2019-08-15T15:55:06.635Z,1565884506.635 [SBIT](INFO): git hash: 6b080875362fb71081cecd990260e30fa66d1cc0 2019-08-15T15:55:06.635Z,1565884506.635 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-08-15T15:55:06.637Z,1565884506.637 [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-08-15T15:55:06.638Z,1565884506.638 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2019-08-15T15:55:06.638Z,1565884506.638 [IBIT](INFO): Initialize IBIT Component. 2019-08-15T15:55:06.639Z,1565884506.639 [CBIT](DEBUG): Initialize CBIT Component. 2019-08-15T15:55:06.640Z,1565884506.640 [logger ThreadHandler](INFO): Handler Thread ID is 8036 2019-08-15T15:55:06.653Z,1565884506.653 [CBIT](DEBUG): Initialized mux pins. 2019-08-15T15:55:06.653Z,1565884506.653 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2019-08-15T15:55:06.653Z,1565884506.653 [CBIT](DEBUG): Initializing the watchdog timer. 2019-08-15T15:55:06.666Z,1565884506.666 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8037 2019-08-15T15:55:06.667Z,1565884506.667 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-08-15T15:55:06.670Z,1565884506.670 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-08-15T15:55:06.671Z,1565884506.671 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8039 2019-08-15T15:55:06.672Z,1565884506.672 [WetLabsBB2FL](INFO): Powering down 2019-08-15T15:55:06.677Z,1565884506.677 [CBIT](CRITICAL): Watchdog Timer failed to initialize. 2019-08-15T15:55:06.677Z,1565884506.677 [CBIT] Hardware Fault, FailCount= 1 2019-08-15T15:55:06.677Z,1565884506.677 [CBIT](ERROR): Hardware Fault 2019-08-15T15:55:06.678Z,1565884506.678 [CBIT](DEBUG): Initializing heartbeat. 2019-08-15T15:55:06.702Z,1565884506.702 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8040 2019-08-15T15:55:06.703Z,1565884506.703 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-08-15T15:55:06.714Z,1565884506.714 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8041 2019-08-15T15:55:06.726Z,1565884506.726 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8042 2019-08-15T15:55:06.729Z,1565884506.729 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-08-15T15:55:06.729Z,1565884506.729 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-08-15T15:55:06.729Z,1565884506.729 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-08-15T15:55:06.730Z,1565884506.730 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-08-15T15:55:06.730Z,1565884506.730 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-08-15T15:55:06.730Z,1565884506.730 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-08-15T15:55:06.730Z,1565884506.730 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-08-15T15:55:06.730Z,1565884506.730 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-08-15T15:55:06.731Z,1565884506.731 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-08-15T15:55:06.731Z,1565884506.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-08-15T15:55:06.731Z,1565884506.731 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-08-15T15:55:06.731Z,1565884506.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-08-15T15:55:06.731Z,1565884506.731 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-08-15T15:55:06.731Z,1565884506.731 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-08-15T15:55:06.732Z,1565884506.732 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-08-15T15:55:06.732Z,1565884506.732 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-08-15T15:55:06.749Z,1565884506.749 [CBIT](DEBUG): Deactivating GF circuits. 2019-08-15T15:55:06.749Z,1565884506.749 [CBIT](DEBUG): Deactivating emergency mode. 2019-08-15T15:55:06.785Z,1565884506.785 [CBIT](DEBUG): Backplane powered. 2019-08-15T15:55:06.790Z,1565884506.790 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-08-15T15:55:06.790Z,1565884506.790 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-08-15T15:55:06.790Z,1565884506.790 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-08-15T15:55:06.791Z,1565884506.791 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-08-15T15:55:06.791Z,1565884506.791 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-08-15T15:55:06.791Z,1565884506.791 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-08-15T15:55:06.793Z,1565884506.793 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-08-15T15:55:06.793Z,1565884506.793 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-08-15T15:55:06.804Z,1565884506.804 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-08-15T15:55:06.814Z,1565884506.814 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-08-15T15:55:06.815Z,1565884506.815 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-08-15T15:55:06.815Z,1565884506.815 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-08-15T15:55:06.816Z,1565884506.816 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-08-15T15:55:06.850Z,1565884506.850 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-08-15T15:55:06.877Z,1565884506.877 [MissionManager](DEBUG): 2019-08-15T15:55:06.890Z,1565884506.890 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-08-15T15:55:06.955Z,1565884506.955 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-08-15T15:55:06.956Z,1565884506.956 [Default:A.Wait](DEBUG): Construct Wait. 2019-08-15T15:55:06.963Z,1565884506.963 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-08-15T15:55:06.993Z,1565884506.993 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-08-15T15:55:06.996Z,1565884506.996 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-08-15T15:55:07.042Z,1565884507.042 [Default:E.Execute](DEBUG): Construct Execute. 2019-08-15T15:55:07.057Z,1565884507.057 [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-08-15T15:55:07.062Z,1565884507.062 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,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-08-15T15:55:07.088Z,1565884507.088 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-08-15T15:55:07.117Z,1565884507.117 [Radio_Surface](INFO): Powering up 2019-08-15T15:55:07.262Z,1565884507.262 [DVL_micro](INFO): Initializing 2019-08-15T15:55:07.336Z,1565884507.336 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-08-15T15:55:07.354Z,1565884507.354 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-08-15T15:55:07.354Z,1565884507.354 [DAT](INFO): Powering up 2019-08-15T15:55:07.354Z,1565884507.354 [DAT](DEBUG): Initializing DAT. 2019-08-15T15:55:07.448Z,1565884507.448 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-08-15T15:55:07.458Z,1565884507.458 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-08-15T15:55:07.459Z,1565884507.459 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-08-15T15:55:07.470Z,1565884507.470 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-08-15T15:55:07.471Z,1565884507.471 [MassServo](DEBUG): Initializing EZServoServo. 2019-08-15T15:55:07.478Z,1565884507.478 [MassServo](DEBUG): Initializing MassServo. 2019-08-15T15:55:07.479Z,1565884507.479 [RudderServo](DEBUG): Initializing EZServoServo. 2019-08-15T15:55:07.486Z,1565884507.486 [RudderServo](DEBUG): Initializing RudderServo. 2019-08-15T15:55:07.487Z,1565884507.487 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-08-15T15:55:07.494Z,1565884507.494 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-08-15T15:55:07.495Z,1565884507.495 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-08-15T15:55:07.495Z,1565884507.495 [CBIT](DEBUG): Powering off loads. 2019-08-15T15:55:07.509Z,1565884507.509 [CBIT](DEBUG): Disabling WDT. 2019-08-15T15:55:07.521Z,1565884507.521 [CBIT](DEBUG): Opening all GF detection circuits. 2019-08-15T15:55:08.301Z,1565884508.301 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-08-15T15:55:08.301Z,1565884508.301 [RudderServo](FAULT): Rudder failed to initialize 2019-08-15T15:55:08.302Z,1565884508.302 [RudderServo] Communications Fault, FailCount= 1 2019-08-15T15:55:08.302Z,1565884508.302 [RudderServo](ERROR): Communications Fault 2019-08-15T15:55:08.625Z,1565884508.625 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2019-08-15T15:55:08.949Z,1565884508.949 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2019-08-15T15:55:08.949Z,1565884508.949 [ThrusterServo](FAULT): Thruster failed to initialize 2019-08-15T15:55:08.949Z,1565884508.949 [ThrusterServo] Communications Fault, FailCount= 1 2019-08-15T15:55:08.950Z,1565884508.950 [ThrusterServo](ERROR): Communications Fault 2019-08-15T15:55:09.353Z,1565884509.353 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2019-08-15T15:55:09.353Z,1565884509.353 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2019-08-15T15:55:09.353Z,1565884509.353 [BuoyancyServo] Communications Fault, FailCount= 1 2019-08-15T15:55:09.354Z,1565884509.354 [BuoyancyServo](ERROR): Communications Fault 2019-08-15T15:55:09.665Z,1565884509.665 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2019-08-15T15:55:09.665Z,1565884509.665 [ElevatorServo](FAULT): Elevator failed to initialize 2019-08-15T15:55:09.665Z,1565884509.665 [ElevatorServo] Communications Fault, FailCount= 1 2019-08-15T15:55:09.666Z,1565884509.666 [ElevatorServo](ERROR): Communications Fault 2019-08-15T15:55:09.667Z,1565884509.667 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-08-15T15:55:09.667Z,1565884509.667 [RudderServo](INFO): Powering down 2019-08-15T15:55:09.744Z,1565884509.744 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-08-15T15:55:09.744Z,1565884509.744 [ThrusterServo](INFO): Powering down 2019-08-15T15:55:09.895Z,1565884509.895 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-08-15T15:55:09.895Z,1565884509.895 [ElevatorServo](INFO): Powering down 2019-08-15T15:55:09.977Z,1565884509.977 [RudderServo](FAULT): LCB fault: Current Limiter Activated. 2019-08-15T15:55:09.977Z,1565884509.977 [RudderServo] Hardware Fault, FailCount= 1 2019-08-15T15:55:09.977Z,1565884509.977 [RudderServo](ERROR): Hardware Fault 2019-08-15T15:55:10.557Z,1565884510.557 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:10.607Z,1565884510.607 [ElevatorServo](FAULT): LCB fault: Current Limiter Activated. 2019-08-15T15:55:10.607Z,1565884510.607 [ElevatorServo] Hardware Fault, FailCount= 1 2019-08-15T15:55:10.607Z,1565884510.607 [ElevatorServo](ERROR): Hardware Fault 2019-08-15T15:55:10.608Z,1565884510.608 [RudderServo](DEBUG): Initializing EZServoServo. 2019-08-15T15:55:10.726Z,1565884510.726 [RudderServo](DEBUG): Initializing RudderServo. 2019-08-15T15:55:11.177Z,1565884511.177 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:11.229Z,1565884511.229 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-08-15T15:55:11.350Z,1565884511.350 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-08-15T15:55:11.653Z,1565884511.653 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-08-15T15:55:11.653Z,1565884511.653 [MassServo] Communications Fault, FailCount= 1 2019-08-15T15:55:11.653Z,1565884511.653 [MassServo](ERROR): Communications Fault 2019-08-15T15:55:11.977Z,1565884511.977 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:12.058Z,1565884512.058 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-08-15T15:55:12.058Z,1565884512.058 [MassServo](INFO): Powering down 2019-08-15T15:55:12.485Z,1565884512.485 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:12.545Z,1565884512.545 [MassServo](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2019-08-15T15:55:12.545Z,1565884512.545 [MassServo] Hardware Fault, FailCount= 1 2019-08-15T15:55:12.545Z,1565884512.545 [MassServo](ERROR): Hardware Fault 2019-08-15T15:55:12.929Z,1565884512.929 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:12.995Z,1565884512.995 [MassServo](DEBUG): Initializing EZServoServo. 2019-08-15T15:55:13.114Z,1565884513.114 [MassServo](DEBUG): Initializing MassServo. 2019-08-15T15:55:13.445Z,1565884513.445 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:13.901Z,1565884513.901 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:14.365Z,1565884514.365 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:14.809Z,1565884514.809 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:15.257Z,1565884515.257 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:15.709Z,1565884515.709 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:16.149Z,1565884516.149 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:16.597Z,1565884516.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:17.045Z,1565884517.045 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:17.125Z,1565884517.125 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-08-15T15:55:17.125Z,1565884517.125 [Aanderaa_O2] Communications Fault, FailCount= 1 2019-08-15T15:55:17.126Z,1565884517.126 [Aanderaa_O2](ERROR): Communications Fault 2019-08-15T15:55:17.497Z,1565884517.497 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:17.673Z,1565884517.673 [Aanderaa_O2](INFO): Powering down 2019-08-15T15:55:18.053Z,1565884518.053 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:18.059Z,1565884518.059 [DVL_micro](ERROR): No DVL communication! Re-initializing 2019-08-15T15:55:18.059Z,1565884518.059 [DVL_micro] Communications Fault, FailCount= 1 2019-08-15T15:55:18.059Z,1565884518.059 [DVL_micro](ERROR): Communications Fault 2019-08-15T15:55:18.114Z,1565884518.114 [Aanderaa_O2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2019-08-15T15:55:18.114Z,1565884518.114 [Aanderaa_O2] Hardware Fault, FailCount= 1 2019-08-15T15:55:18.115Z,1565884518.115 [Aanderaa_O2](ERROR): Hardware Fault 2019-08-15T15:55:18.493Z,1565884518.493 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:18.569Z,1565884518.569 [DVL_micro](INFO): uninitialize:Powering down 2019-08-15T15:55:19.057Z,1565884519.057 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:19.489Z,1565884519.489 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:19.717Z,1565884519.717 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-08-15T15:55:19.718Z,1565884519.718 [CTD_Seabird] Communications Fault, FailCount= 1 2019-08-15T15:55:19.718Z,1565884519.718 [CTD_Seabird](ERROR): Communications Fault 2019-08-15T15:55:19.937Z,1565884519.937 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2019-08-15T15:55:19.937Z,1565884519.937 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-08-15T15:55:19.937Z,1565884519.937 [AHRS_M2] Communications Fault, FailCount= 1 2019-08-15T15:55:19.938Z,1565884519.938 [AHRS_M2](ERROR): Communications Fault 2019-08-15T15:55:20.050Z,1565884520.050 [AHRS_M2](INFO): Powering down 2019-08-15T15:55:20.122Z,1565884520.122 [CTD_Seabird](INFO): Powering down 2019-08-15T15:55:20.678Z,1565884520.678 [CTD_Seabird](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2019-08-15T15:55:20.678Z,1565884520.678 [CTD_Seabird] Hardware Fault, FailCount= 1 2019-08-15T15:55:20.678Z,1565884520.678 [CTD_Seabird](ERROR): Hardware Fault 2019-08-15T15:55:31.353Z,1565884531.353 [SBIT](IMPORTANT): Beginning Startup BIT 2019-08-15T15:55:34.959Z,1565884534.959 [NAL9602](INFO): Powering up NAL9602 2019-08-15T15:55:44.694Z,1565884544.694 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-08-15T15:55:44.694Z,1565884544.694 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-08-15T15:55:44.694Z,1565884544.694 [SBIT](ERROR): Could not read massPosReader_. 2019-08-15T15:55:55.560Z,1565884555.560 [NAL9602](ERROR): NAL9602 initialization error. 2019-08-15T15:55:55.560Z,1565884555.560 [NAL9602] Communications Fault, FailCount= 1 2019-08-15T15:55:55.560Z,1565884555.560 [NAL9602](ERROR): Communications Fault 2019-08-15T15:55:55.960Z,1565884555.960 [NAL9602](INFO): Powering down 2019-08-15T15:56:07.701Z,1565884567.701 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2019-08-15T15:56:07.701Z,1565884567.701 [DAT] Communications Fault, FailCount= 1 2019-08-15T15:56:07.701Z,1565884567.701 [DAT](ERROR): Communications Fault 2019-08-15T15:56:08.104Z,1565884568.104 [DAT](INFO): Powering down 2019-08-15T15:56:08.510Z,1565884568.510 [DAT](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2019-08-15T15:56:08.510Z,1565884568.510 [DAT] Hardware Fault, FailCount= 1 2019-08-15T15:56:08.510Z,1565884568.510 [DAT](ERROR): Hardware Fault 2019-08-15T15:56:11.342Z,1565884571.342 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-08-15T15:56:11.342Z,1565884571.342 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-08-15T15:56:11.342Z,1565884571.342 [SBIT](ERROR): Could not read massPosReader_. 2019-08-15T15:56:11.342Z,1565884571.342 [SBIT](FAULT): Control surface position failure. 2019-08-15T15:56:12.961Z,1565884572.961 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:56:23.926Z,1565884583.926 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:56:25.078Z,1565884585.078 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-08-15T15:56:25.078Z,1565884585.078 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-08-15T15:56:25.078Z,1565884585.078 [SBIT](ERROR): Could not read massPosReader_. 2019-08-15T15:56:25.078Z,1565884585.078 [SBIT](FAULT): Control surface position failure. 2019-08-15T15:56:25.478Z,1565884585.478 [SBIT](CRITICAL): SBIT FAILED 2019-08-15T15:56:25.554Z,1565884585.554 [CommandLine](IMPORTANT): got command configSet list 2019-08-15T15:56:25.555Z,1565884585.555 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-08-15T15:56:25.555Z,1565884585.555 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2019-08-15T15:56:25.556Z,1565884585.556 [CommandLine](IMPORTANT): BuoyancyServo.fastPumpDepth=5 meter; 2019-08-15T15:56:25.556Z,1565884585.556 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-08-15T15:56:25.556Z,1565884585.556 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=1 bool; 2019-08-15T15:56:25.556Z,1565884585.556 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=310 cubic_centimeter; 2019-08-15T15:56:25.556Z,1565884585.556 [CommandLine](IMPORTANT): VerticalControl.elevDeadband=0.1 degree; 2019-08-15T15:56:25.556Z,1565884585.556 [CommandLine](IMPORTANT): VerticalControl.kiDepthBuoy=0.3 reciprocal_second; 2019-08-15T15:56:25.556Z,1565884585.556 [CommandLine](IMPORTANT): VerticalControl.massDefault=16 millimeter; 2019-08-15T15:56:25.889Z,1565884585.889 [MissionManager](IMPORTANT): Started mission Startup 2019-08-15T15:56:25.890Z,1565884585.890 [Startup] Running Loop=1 2019-08-15T15:56:25.890Z,1565884585.890 [Startup](DEBUG): Aggregate::initialize Startup 2019-08-15T15:56:25.890Z,1565884585.890 [Startup:A.GoToSurface] Running Loop=1 2019-08-15T15:56:25.890Z,1565884585.890 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-08-15T15:56:25.891Z,1565884585.891 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-08-15T15:56:25.891Z,1565884585.891 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-08-15T15:56:25.891Z,1565884585.891 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-08-15T15:56:25.892Z,1565884585.892 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-08-15T15:56:25.892Z,1565884585.892 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-08-15T15:56:25.894Z,1565884585.894 [Startup:StartupSatComms] Running Loop=1 2019-08-15T15:56:25.894Z,1565884585.894 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-08-15T15:56:25.894Z,1565884585.894 [Startup:StartupSatComms:A] Running Loop=1 2019-08-15T15:56:26.300Z,1565884586.300 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-08-15T15:56:32.409Z,1565884592.409 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:56:34.833Z,1565884594.833 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:56:43.726Z,1565884603.726 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:56:45.737Z,1565884605.737 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:56:54.628Z,1565884614.628 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:56:56.240Z,1565884616.240 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:05.946Z,1565884625.946 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:07.152Z,1565884627.152 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:07.157Z,1565884627.157 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-08-15T15:57:07.157Z,1565884627.157 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-08-15T15:57:07.157Z,1565884627.157 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-08-15T15:57:16.442Z,1565884636.442 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:18.062Z,1565884638.062 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:26.085Z,1565884646.085 [Startup:StartupSatComms:A](INFO): Timed out from 2019-08-15T15:56:25.9Z 2019-08-15T15:57:26.085Z,1565884646.085 [Startup:StartupSatComms:A] Stopped 2019-08-15T15:57:26.085Z,1565884646.085 [Startup:StartupSatComms:B] Running Loop=1 2019-08-15T15:57:26.489Z,1565884646.489 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-08-15T15:57:27.355Z,1565884647.355 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:28.968Z,1565884648.968 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:38.262Z,1565884658.262 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:39.474Z,1565884659.474 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:49.630Z,1565884669.630 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:57:49.902Z,1565884669.902 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2019-08-15T15:57:49.904Z,1565884669.904 [BPC1](ERROR): BPC1A failed to parse battery stick message. 2019-08-15T15:58:00.481Z,1565884680.481 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:00.886Z,1565884680.886 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:04.054Z,1565884684.054 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:07.286Z,1565884687.286 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:10.518Z,1565884690.518 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:11.793Z,1565884691.793 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:13.750Z,1565884693.750 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:16.982Z,1565884696.982 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:20.214Z,1565884700.214 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:22.701Z,1565884702.701 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:23.045Z,1565884703.045 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:26.277Z,1565884706.277 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:26.281Z,1565884706.281 [Startup:StartupSatComms:B](INFO): Timed out from 2019-08-15T15:57:26.1Z 2019-08-15T15:58:26.282Z,1565884706.282 [Startup:StartupSatComms:B] Stopped 2019-08-15T15:58:26.282Z,1565884706.282 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-08-15T15:58:26.282Z,1565884706.282 [Startup:StartupSatComms] Stopped 2019-08-15T15:58:26.282Z,1565884706.282 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-08-15T15:58:26.283Z,1565884706.283 [Startup](INFO): Completed Startup 2019-08-15T15:58:26.283Z,1565884706.283 [MissionManager](INFO): Startup is completed. 2019-08-15T15:58:26.283Z,1565884706.283 [MissionManager](INFO): Uninitializing Mission Startup 2019-08-15T15:58:26.283Z,1565884706.283 [Startup] Stopped 2019-08-15T15:58:26.283Z,1565884706.283 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-08-15T15:58:26.283Z,1565884706.283 [Startup:A.GoToSurface] Stopped 2019-08-15T15:58:26.283Z,1565884706.283 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-08-15T15:58:26.693Z,1565884706.693 [MissionManager](IMPORTANT): Started mission Default 2019-08-15T15:58:26.694Z,1565884706.694 [Default] Running Loop=1 2019-08-15T15:58:26.694Z,1565884706.694 [Default](DEBUG): Aggregate::initialize Default 2019-08-15T15:58:26.694Z,1565884706.694 [Default:B.GoToSurface] Running Loop=1 2019-08-15T15:58:26.694Z,1565884706.694 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-08-15T15:58:26.694Z,1565884706.694 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-08-15T15:58:26.694Z,1565884706.694 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-08-15T15:58:26.695Z,1565884706.695 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-08-15T15:58:26.695Z,1565884706.695 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-08-15T15:58:26.695Z,1565884706.695 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-08-15T15:58:26.696Z,1565884706.696 [Default:A.Wait] Running Loop=1 2019-08-15T15:58:26.696Z,1565884706.696 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-08-15T15:58:29.509Z,1565884709.509 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:32.739Z,1565884712.739 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:33.615Z,1565884713.615 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:35.969Z,1565884715.969 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:39.201Z,1565884719.201 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:40.026Z,1565884720.026 [Default:A.Wait](INFO): Done Waiting. 2019-08-15T15:58:40.026Z,1565884720.026 [Default:A.Wait] Stopped 2019-08-15T15:58:40.026Z,1565884720.026 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-08-15T15:58:40.423Z,1565884720.423 [Default:CheckIn] Running Loop=1 2019-08-15T15:58:40.423Z,1565884720.423 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-08-15T15:58:40.423Z,1565884720.423 [Default:CheckIn:Read_GPS] Running Loop=1 2019-08-15T15:58:40.818Z,1565884720.818 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-08-15T15:58:42.434Z,1565884722.434 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:44.519Z,1565884724.519 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:45.665Z,1565884725.665 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:47.286Z,1565884727.286 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:48.897Z,1565884728.897 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:50.110Z,1565884730.110 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:52.129Z,1565884732.129 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:53.343Z,1565884733.343 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:55.361Z,1565884735.361 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:56.574Z,1565884736.574 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:58.593Z,1565884738.593 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:58:59.415Z,1565884739.415 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:01.422Z,1565884741.422 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:01.838Z,1565884741.838 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:04.654Z,1565884744.654 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:04.667Z,1565884744.667 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:07.485Z,1565884747.485 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:07.891Z,1565884747.891 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:10.311Z,1565884750.311 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:11.122Z,1565884751.122 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:13.137Z,1565884753.137 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:13.947Z,1565884753.947 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:15.967Z,1565884755.967 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:17.177Z,1565884757.177 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:18.390Z,1565884758.390 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:20.409Z,1565884760.409 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:21.622Z,1565884761.622 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:23.641Z,1565884763.641 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-08-15T15:59:24.058Z,1565884764.058 [BPC1](ERROR