2019-05-20T19:12:17.419Z,1558379537.419 [Supervisor](DEBUG): Initializing supervisor. 2019-05-20T19:12:17.422Z,1558379537.422 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-05-20T19:12:17.423Z,1558379537.423 [SyncHandler](INFO): Protected caller Thread ID is 4745 2019-05-20T19:12:17.424Z,1558379537.424 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-05-20T19:12:17.425Z,1558379537.425 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-05-20T19:12:17.425Z,1558379537.425 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4746 2019-05-20T19:12:17.428Z,1558379537.428 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-05-20T19:12:17.440Z,1558379537.440 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-05-20T19:12:17.441Z,1558379537.441 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-05-20T19:12:17.441Z,1558379537.441 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4747 2019-05-20T19:12:17.442Z,1558379537.442 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-05-20T19:12:17.443Z,1558379537.443 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-05-20T19:12:17.444Z,1558379537.444 [logger ThreadHandler](INFO): Protected caller Thread ID is 4748 2019-05-20T19:12:17.446Z,1558379537.446 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-05-20T19:12:17.446Z,1558379537.446 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-05-20T19:12:17.448Z,1558379537.448 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-05-20T19:12:17.713Z,1558379537.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-05-20T19:12:17.713Z,1558379537.713 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-05-20T19:12:17.793Z,1558379537.793 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-05-20T19:12:18.227Z,1558379538.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-05-20T19:12:18.228Z,1558379538.228 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-05-20T19:12:18.569Z,1558379538.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-05-20T19:12:18.569Z,1558379538.569 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-05-20T19:12:18.915Z,1558379538.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-05-20T19:12:18.915Z,1558379538.915 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-05-20T19:12:19.306Z,1558379539.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-05-20T19:12:19.306Z,1558379539.306 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-05-20T19:12:19.500Z,1558379539.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-05-20T19:12:19.501Z,1558379539.501 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-05-20T19:12:19.952Z,1558379539.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-05-20T19:12:19.953Z,1558379539.953 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-05-20T19:12:20.057Z,1558379540.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-05-20T19:12:20.058Z,1558379540.058 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-05-20T19:12:20.158Z,1558379540.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-05-20T19:12:20.158Z,1558379540.158 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-05-20T19:12:20.731Z,1558379540.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-05-20T19:12:20.732Z,1558379540.732 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-05-20T19:12:21.118Z,1558379541.118 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-05-20T19:12:21.119Z,1558379541.119 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-05-20T19:12:21.313Z,1558379541.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-05-20T19:12:21.314Z,1558379541.314 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-05-20T19:12:21.458Z,1558379541.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-05-20T19:12:21.459Z,1558379541.459 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-05-20T19:12:21.607Z,1558379541.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-05-20T19:12:21.609Z,1558379541.609 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2019-05-20T19:12:21.610Z,1558379541.610 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2019-05-20T19:12:21.701Z,1558379541.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2019-05-20T19:12:21.786Z,1558379541.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2019-05-20T19:12:21.888Z,1558379541.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2019-05-20T19:12:21.971Z,1558379541.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2019-05-20T19:12:22.066Z,1558379542.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2019-05-20T19:12:22.165Z,1558379542.165 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2019-05-20T19:12:22.372Z,1558379542.372 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2019-05-20T19:12:22.449Z,1558379542.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2019-05-20T19:12:22.619Z,1558379542.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2019-05-20T19:12:22.754Z,1558379542.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2019-05-20T19:12:22.874Z,1558379542.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2019-05-20T19:12:23.101Z,1558379543.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-05-20T19:12:23.103Z,1558379543.103 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2019-05-20T19:12:23.103Z,1558379543.103 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-05-20T19:12:23.110Z,1558379543.110 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-05-20T19:12:23.196Z,1558379543.196 [VerticalControl](DEBUG): Construct VerticalControl. 2019-05-20T19:12:23.311Z,1558379543.311 [VerticalControl] Loaded 2019-05-20T19:12:23.311Z,1558379543.311 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-05-20T19:12:23.312Z,1558379543.312 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-05-20T19:12:23.382Z,1558379543.382 [HorizontalControl] Loaded 2019-05-20T19:12:23.383Z,1558379543.383 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-05-20T19:12:23.383Z,1558379543.383 [SpeedControl](DEBUG): Construct SpeedControl. 2019-05-20T19:12:23.389Z,1558379543.389 [SpeedControl] Loaded 2019-05-20T19:12:23.389Z,1558379543.389 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-05-20T19:12:23.390Z,1558379543.390 [LoopControl](DEBUG): Construct LoopControl. 2019-05-20T19:12:23.390Z,1558379543.390 [LoopControl] Loaded 2019-05-20T19:12:23.391Z,1558379543.391 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-05-20T19:12:23.391Z,1558379543.391 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-05-20T19:12:23.392Z,1558379543.392 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-05-20T19:12:23.415Z,1558379543.415 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-05-20T19:12:23.415Z,1558379543.415 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-05-20T19:12:23.540Z,1558379543.540 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-05-20T19:12:23.541Z,1558379543.541 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-05-20T19:12:23.691Z,1558379543.691 [BuoyancyServo] Loaded 2019-05-20T19:12:23.691Z,1558379543.691 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-05-20T19:12:23.739Z,1558379543.739 [ElevatorServo] Loaded 2019-05-20T19:12:23.740Z,1558379543.740 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-05-20T19:12:23.772Z,1558379543.772 [MassServo] Loaded 2019-05-20T19:12:23.773Z,1558379543.773 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-05-20T19:12:23.784Z,1558379543.784 [RudderServo] Loaded 2019-05-20T19:12:23.784Z,1558379543.784 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-05-20T19:12:23.804Z,1558379543.804 [ThrusterServo] Loaded 2019-05-20T19:12:23.804Z,1558379543.804 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-05-20T19:12:23.804Z,1558379543.804 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-05-20T19:12:23.817Z,1558379543.817 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-05-20T19:12:24.549Z,1558379544.549 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-05-20T19:12:24.549Z,1558379544.549 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-05-20T19:12:24.570Z,1558379544.570 [NavChart] Loaded 2019-05-20T19:12:24.570Z,1558379544.570 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-05-20T19:12:24.574Z,1558379544.574 [UniversalFixResidualReporter] Loaded 2019-05-20T19:12:24.575Z,1558379544.575 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-05-20T19:12:24.575Z,1558379544.575 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-05-20T19:12:24.576Z,1558379544.576 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-05-20T19:12:24.687Z,1558379544.687 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-05-20T19:12:24.688Z,1558379544.688 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-05-20T19:12:25.126Z,1558379545.126 [AHRS_M2] Loaded 2019-05-20T19:12:25.126Z,1558379545.126 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-05-20T19:12:25.202Z,1558379545.202 [DataOverHttps] Loaded 2019-05-20T19:12:25.203Z,1558379545.203 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-05-20T19:12:25.204Z,1558379545.204 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0 2019-05-20T19:12:25.204Z,1558379545.204 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4830 2019-05-20T19:12:25.218Z,1558379545.218 [Depth_Keller] Loaded 2019-05-20T19:12:25.218Z,1558379545.218 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-05-20T19:12:25.223Z,1558379545.223 [DropWeight] Loaded 2019-05-20T19:12:25.223Z,1558379545.223 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-05-20T19:12:25.354Z,1558379545.354 [DVL_micro] Loaded 2019-05-20T19:12:25.355Z,1558379545.355 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2019-05-20T19:12:25.453Z,1558379545.453 [NAL9602] Loaded 2019-05-20T19:12:25.453Z,1558379545.453 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-05-20T19:12:25.470Z,1558379545.470 [Onboard] Loaded 2019-05-20T19:12:25.470Z,1558379545.470 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-05-20T19:12:25.473Z,1558379545.473 [Radio_Surface] Loaded 2019-05-20T19:12:25.474Z,1558379545.474 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-05-20T19:12:25.475Z,1558379545.475 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0 2019-05-20T19:12:25.475Z,1558379545.475 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4831 2019-05-20T19:12:25.604Z,1558379545.604 [DAT] Loaded 2019-05-20T19:12:25.604Z,1558379545.604 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-05-20T19:12:27.133Z,1558379547.133 [BPC1] Loaded 2019-05-20T19:12:27.134Z,1558379547.134 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-05-20T19:12:27.134Z,1558379547.134 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-05-20T19:12:27.135Z,1558379547.135 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-05-20T19:12:27.191Z,1558379547.191 [DepthRateCalculator] Loaded 2019-05-20T19:12:27.191Z,1558379547.191 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-05-20T19:12:27.196Z,1558379547.196 [PitchRateCalculator] Loaded 2019-05-20T19:12:27.197Z,1558379547.197 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-05-20T19:12:27.208Z,1558379547.208 [SpeedCalculator] Loaded 2019-05-20T19:12:27.209Z,1558379547.209 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-05-20T19:12:27.230Z,1558379547.230 [TempGradientCalculator] Loaded 2019-05-20T19:12:27.231Z,1558379547.231 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-05-20T19:12:27.236Z,1558379547.236 [YawRateCalculator] Loaded 2019-05-20T19:12:27.236Z,1558379547.236 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-05-20T19:12:27.266Z,1558379547.266 [ElevatorOffsetCalculator] Loaded 2019-05-20T19:12:27.266Z,1558379547.266 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-05-20T19:12:27.267Z,1558379547.267 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-05-20T19:12:27.268Z,1558379547.268 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-05-20T19:12:27.523Z,1558379547.523 [Aanderaa_O2] Loaded 2019-05-20T19:12:27.524Z,1558379547.524 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-05-20T19:12:27.610Z,1558379547.610 [CTD_NeilBrown] Loaded 2019-05-20T19:12:27.610Z,1558379547.610 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-05-20T19:12:27.611Z,1558379547.611 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409AF4E0 2019-05-20T19:12:27.611Z,1558379547.611 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4832 2019-05-20T19:12:27.621Z,1558379547.621 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-05-20T19:12:27.626Z,1558379547.626 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-05-20T19:12:27.627Z,1558379547.627 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-05-20T19:12:27.632Z,1558379547.632 [CTD_Seabird](INFO): created writer for : depth 2019-05-20T19:12:27.633Z,1558379547.633 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-05-20T19:12:27.638Z,1558379547.638 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-05-20T19:12:27.638Z,1558379547.638 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-05-20T19:12:27.643Z,1558379547.643 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-05-20T19:12:27.644Z,1558379547.644 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-05-20T19:12:27.649Z,1558379547.649 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-05-20T19:12:27.650Z,1558379547.650 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-05-20T19:12:27.655Z,1558379547.655 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-05-20T19:12:27.655Z,1558379547.655 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-05-20T19:12:27.660Z,1558379547.660 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-05-20T19:12:27.688Z,1558379547.688 [CTD_Seabird] Loaded 2019-05-20T19:12:27.688Z,1558379547.688 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-05-20T19:12:27.689Z,1558379547.689 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DF4E0 2019-05-20T19:12:27.690Z,1558379547.690 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4833 2019-05-20T19:12:27.721Z,1558379547.721 [ESPComponent] Loaded 2019-05-20T19:12:27.721Z,1558379547.721 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-05-20T19:12:27.735Z,1558379547.735 [PAR_Licor] Loaded 2019-05-20T19:12:27.736Z,1558379547.736 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-05-20T19:12:27.785Z,1558379547.785 [WetLabsBB2FL] Loaded 2019-05-20T19:12:27.785Z,1558379547.785 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-05-20T19:12:27.786Z,1558379547.786 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0F4E0 2019-05-20T19:12:27.787Z,1558379547.787 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4834 2019-05-20T19:12:27.788Z,1558379547.788 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-05-20T19:12:27.788Z,1558379547.788 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-05-20T19:12:27.836Z,1558379547.836 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-05-20T19:12:27.837Z,1558379547.837 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-05-20T19:12:28.177Z,1558379548.177 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-05-20T19:12:28.178Z,1558379548.178 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-05-20T19:12:28.335Z,1558379548.335 [SBIT](DEBUG): Construct Startup Built In Test. 2019-05-20T19:12:28.346Z,1558379548.346 [SBIT] Loaded 2019-05-20T19:12:28.347Z,1558379548.347 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-05-20T19:12:28.347Z,1558379548.347 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-05-20T19:12:28.359Z,1558379548.359 [IBIT] Loaded 2019-05-20T19:12:28.359Z,1558379548.359 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-05-20T19:12:28.362Z,1558379548.362 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-05-20T19:12:28.504Z,1558379548.504 [CBIT] Loaded 2019-05-20T19:12:28.505Z,1558379548.505 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-05-20T19:12:28.505Z,1558379548.505 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-05-20T19:12:28.509Z,1558379548.509 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-05-20T19:12:28.510Z,1558379548.510 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-05-20T19:12:28.517Z,1558379548.517 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-05-20T19:12:28.518Z,1558379548.518 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B0E4E0 2019-05-20T19:12:28.518Z,1558379548.518 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4835 2019-05-20T19:12:28.523Z,1558379548.523 [Supervisor](INFO): Main Thread ID is 4154 2019-05-20T19:12:28.523Z,1558379548.523 [Supervisor](DEBUG): Running supervisor. 2019-05-20T19:12:28.523Z,1558379548.523 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4836 2019-05-20T19:12:28.526Z,1558379548.526 [controlThread ThreadHandler](INFO): Handler Thread ID is 4837 2019-05-20T19:12:28.526Z,1558379548.526 [controlThread](DEBUG): Initializing ControlThread 2019-05-20T19:12:28.527Z,1558379548.527 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-05-20T19:12:28.529Z,1558379548.529 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-05-20T19:12:28.529Z,1558379548.529 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-05-20T19:12:28.530Z,1558379548.530 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-05-20T19:12:28.532Z,1558379548.532 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-05-20T19:12:28.532Z,1558379548.532 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-05-20T19:12:28.540Z,1558379548.540 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-05-20T19:12:28.540Z,1558379548.540 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-05-20T19:12:28.540Z,1558379548.540 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-05-20T19:12:28.541Z,1558379548.541 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-05-20T19:12:28.541Z,1558379548.541 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-05-20T19:12:28.542Z,1558379548.542 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-05-20T19:12:28.545Z,1558379548.545 [SBIT](INFO): Initialize SBIT Component. 2019-05-20T19:12:28.545Z,1558379548.545 [SBIT](IMPORTANT): git: 2019-05-20 2019-05-20T19:12:28.546Z,1558379548.546 [SBIT](INFO): git hash: 2a8aa54ec5933316883a1fda1f3ce53eb72b62ac 2019-05-20T19:12:28.546Z,1558379548.546 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-05-20T19:12:28.547Z,1558379548.547 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 2019-05-20T19:12:28.548Z,1558379548.548 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2019-05-20T19:12:28.549Z,1558379548.549 [IBIT](INFO): Initialize IBIT Component. 2019-05-20T19:12:28.550Z,1558379548.550 [CBIT](DEBUG): Initialize CBIT Component. 2019-05-20T19:12:28.551Z,1558379548.551 [logger ThreadHandler](INFO): Handler Thread ID is 4838 2019-05-20T19:12:28.561Z,1558379548.561 [CBIT](DEBUG): Initialized mux pins. 2019-05-20T19:12:28.561Z,1558379548.561 [CBIT](DEBUG): Initializing the watchdog timer. 2019-05-20T19:12:28.569Z,1558379548.569 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4839 2019-05-20T19:12:28.570Z,1558379548.570 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-05-20T19:12:28.581Z,1558379548.581 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4840 2019-05-20T19:12:28.585Z,1558379548.585 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-05-20T19:12:28.585Z,1558379548.585 [CBIT](DEBUG): Initializing heartbeat. 2019-05-20T19:12:28.593Z,1558379548.593 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4841 2019-05-20T19:12:28.594Z,1558379548.594 [CTD_NeilBrown](INFO): Powering down 2019-05-20T19:12:28.621Z,1558379548.621 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4842 2019-05-20T19:12:28.622Z,1558379548.622 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-05-20T19:12:28.625Z,1558379548.625 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4844 2019-05-20T19:12:28.626Z,1558379548.626 [WetLabsBB2FL](INFO): Powering down 2019-05-20T19:12:28.645Z,1558379548.645 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-05-20T19:12:28.653Z,1558379548.653 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4845 2019-05-20T19:12:28.657Z,1558379548.657 [CBIT](DEBUG): Deactivating GF circuits. 2019-05-20T19:12:28.657Z,1558379548.657 [CBIT](DEBUG): Deactivating emergency mode. 2019-05-20T19:12:28.662Z,1558379548.662 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-05-20T19:12:28.662Z,1558379548.662 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-05-20T19:12:28.662Z,1558379548.662 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-05-20T19:12:28.662Z,1558379548.662 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-05-20T19:12:28.662Z,1558379548.662 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-05-20T19:12:28.663Z,1558379548.663 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-05-20T19:12:28.663Z,1558379548.663 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-05-20T19:12:28.663Z,1558379548.663 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-05-20T19:12:28.663Z,1558379548.663 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-05-20T19:12:28.663Z,1558379548.663 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-05-20T19:12:28.664Z,1558379548.664 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-05-20T19:12:28.664Z,1558379548.664 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-05-20T19:12:28.664Z,1558379548.664 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-05-20T19:12:28.664Z,1558379548.664 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-05-20T19:12:28.664Z,1558379548.664 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-05-20T19:12:28.664Z,1558379548.664 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-05-20T19:12:28.693Z,1558379548.693 [CBIT](DEBUG): Backplane powered. 2019-05-20T19:12:28.694Z,1558379548.694 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-05-20T19:12:28.722Z,1558379548.722 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-20T19:12:28.733Z,1558379548.733 [MissionManager](DEBUG): 2019-05-20T19:12:28.734Z,1558379548.734 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-05-20T19:12:28.796Z,1558379548.796 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-05-20T19:12:28.805Z,1558379548.805 [Default:A.Wait](DEBUG): Construct Wait. 2019-05-20T19:12:28.807Z,1558379548.807 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-05-20T19:12:28.837Z,1558379548.837 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-05-20T19:12:28.840Z,1558379548.840 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-05-20T19:12:28.867Z,1558379548.867 [Default:E.Execute](DEBUG): Construct Execute. 2019-05-20T19:12:28.871Z,1558379548.871 [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-20T19:12:28.889Z,1558379548.889 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-05-20T19:12:28.894Z,1558379548.894 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-05-20T19:12:28.959Z,1558379548.959 [DVL_micro](INFO): Initializing 2019-05-20T19:12:28.983Z,1558379548.983 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-05-20T19:12:28.984Z,1558379548.984 [DAT](INFO): Powering up 2019-05-20T19:12:28.984Z,1558379548.984 [DAT](DEBUG): Initializing DAT. 2019-05-20T19:12:28.985Z,1558379548.985 [Radio_Surface](INFO): Powering up 2019-05-20T19:12:29.029Z,1558379549.029 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-05-20T19:12:29.199Z,1558379549.199 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-05-20T19:12:29.206Z,1558379549.206 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-05-20T19:12:29.207Z,1558379549.207 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-05-20T19:12:29.213Z,1558379549.213 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-05-20T19:12:29.214Z,1558379549.214 [MassServo](DEBUG): Initializing EZServoServo. 2019-05-20T19:12:29.221Z,1558379549.221 [MassServo](DEBUG): Initializing MassServo. 2019-05-20T19:12:29.222Z,1558379549.222 [RudderServo](DEBUG): Initializing EZServoServo. 2019-05-20T19:12:29.229Z,1558379549.229 [RudderServo](DEBUG): Initializing RudderServo. 2019-05-20T19:12:29.230Z,1558379549.230 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-05-20T19:12:29.237Z,1558379549.237 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-05-20T19:12:29.410Z,1558379549.410 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-05-20T19:12:29.410Z,1558379549.410 [DropWeight] Hardware Fault, FailCount= 1 2019-05-20T19:12:29.410Z,1558379549.410 [DropWeight](ERROR): Hardware Fault 2019-05-20T19:12:29.484Z,1558379549.484 [CommandLine](FAULT): Scheduling is paused 2019-05-20T19:12:29.485Z,1558379549.485 [CBIT](INFO): Critical error at 20190520T191229 2019-05-20T19:12:29.485Z,1558379549.485 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-05-20T19:12:29.488Z,1558379549.488 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-05-20T19:12:29.488Z,1558379549.488 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-05-20T19:12:30.113Z,1558379550.113 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-05-20T19:12:30.113Z,1558379550.113 [RudderServo](FAULT): Rudder failed to initialize 2019-05-20T19:12:30.113Z,1558379550.113 [RudderServo] Communications Fault, FailCount= 1 2019-05-20T19:12:30.113Z,1558379550.113 [RudderServo](ERROR): Communications Fault 2019-05-20T19:12:30.222Z,1558379550.222 [CBIT](INFO): Critical error at 20190520T191229 2019-05-20T19:12:30.225Z,1558379550.225 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-05-20T19:12:30.431Z,1558379550.431 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-05-20T19:12:30.432Z,1558379550.432 [RudderServo](INFO): Powering down 2019-05-20T19:12:31.108Z,1558379551.108 [RudderServo](DEBUG): Initializing EZServoServo. 2019-05-20T19:12:31.242Z,1558379551.242 [RudderServo](DEBUG): Initializing RudderServo. 2019-05-20T19:12:31.246Z,1558379551.246 [CBIT](INFO): Clearing failed state for component RudderServo 2019-05-20T19:12:31.246Z,1558379551.246 [RudderServo] No Fault, FailCount= 1 2019-05-20T19:12:32.477Z,1558379552.477 [Aanderaa_O2](INFO): Powering down 2019-05-20T19:12:42.367Z,1558379562.367 [DAT](INFO): setting local address to 2 2019-05-20T19:12:42.770Z,1558379562.770 [DAT](INFO): set local address to 2 2019-05-20T19:12:52.206Z,1558379572.206 [SBIT](IMPORTANT): Beginning Startup BIT 2019-05-20T19:12:52.211Z,1558379572.211 [CBIT](IMPORTANT): Beginning ground fault scan 2019-05-20T19:12:55.683Z,1558379575.683 [NAL9602](INFO): Powering up NAL9602 2019-05-20T19:13:03.022Z,1558379583.022 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 4.657929 CHAN A1 (24V): 0.005102 CHAN A2 (12V): 0.001051 CHAN A3 (5V): -0.001503 CHAN B0 (3.3V): 4.766929 CHAN B1 (3.15aV): 0.000476 CHAN B2 (3.15bV): 0.000446 CHAN B3 (GND): -0.000500 OPEN: 0.006474 Full Scale Calc: 4.765 mA, -1.589 mA 2019-05-20T19:13:06.590Z,1558379586.590 [NAL9602](INFO): NAL9602 initialized 2019-05-20T19:13:07.413Z,1558379587.413 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:13:24.179Z,1558379604.179 [DVL_micro](ERROR): only read 43 of 46 data items 2019-05-20T19:13:24.179Z,1558379604.179 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 390 148 236 -444 3 3 3 3 205.5 790.1 89.0 2 736.4 -352.5 89.0 2 205 790 89 2 736 -352 89 2 -0.16 -2.20 259.0 -3.0 23.7 0.005 35.0 1489 108 2019-05-20T19:13:36.429Z,1558379616.429 [BPC1](ERROR): BPC1A: No match for serial number 02D0 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-05-20T19:13:36.430Z,1558379616.430 [BPC1](ERROR): BPC1B: No match for serial number 025F in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-05-20T19:13:36.430Z,1558379616.430 [BPC1](FAULT): Failed to parse data from all battery packs. 2019-05-20T19:13:36.430Z,1558379616.430 [BPC1] Data Fault, FailCount= 1 2019-05-20T19:13:36.430Z,1558379616.430 [BPC1](ERROR): Data Fault 2019-05-20T19:13:36.546Z,1558379616.546 [CBIT](ERROR): Data Fault in component: BPC1 2019-05-20T19:13:37.208Z,1558379617.208 [DVL_micro](ERROR): only read 43 of 46 data items 2019-05-20T19:13:37.208Z,1558379617.208 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 310 223 250 -511 3 3 3 3 80.1 979.6946.8 -263.9 73.3 2 80 979 73 2 946 -263 73 2 -0.17 -2.36 259.1 -3.0 05 35.01 2019-05-20T19:13:45.716Z,1558379625.716 [SBIT](IMPORTANT): SBIT PASSED 2019-05-20T19:13:45.806Z,1558379625.806 [CommandLine](IMPORTANT): got command configSet list 2019-05-20T19:13:45.806Z,1558379625.806 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-05-20T19:13:45.807Z,1558379625.807 [CommandLine](IMPORTANT): CBIT.gf24Offset=172 microampere; 2019-05-20T19:13:45.807Z,1558379625.807 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2019-05-20T19:13:45.807Z,1558379625.807 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool; 2019-05-20T19:13:45.807Z,1558379625.807 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft=-31 millimeter; 2019-05-20T19:13:45.807Z,1558379625.807 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=30 millimeter; 2019-05-20T19:13:46.128Z,1558379626.128 [MissionManager](IMPORTANT): Started mission Startup 2019-05-20T19:13:46.128Z,1558379626.128 [Startup] Running Loop=1 2019-05-20T19:13:46.128Z,1558379626.128 [Startup](DEBUG): Aggregate::initialize Startup 2019-05-20T19:13:46.128Z,1558379626.128 [Startup:A.GoToSurface] Running Loop=1 2019-05-20T19:13:46.128Z,1558379626.128 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-05-20T19:13:46.129Z,1558379626.129 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-05-20T19:13:46.130Z,1558379626.130 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-05-20T19:13:46.130Z,1558379626.130 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-05-20T19:13:46.130Z,1558379626.130 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-05-20T19:13:46.131Z,1558379626.131 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-05-20T19:13:46.132Z,1558379626.132 [Startup:StartupSatComms] Running Loop=1 2019-05-20T19:13:46.132Z,1558379626.132 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-05-20T19:13:46.132Z,1558379626.132 [Startup:StartupSatComms:A] Running Loop=1 2019-05-20T19:13:46.543Z,1558379626.543 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-05-20T19:14:46.311Z,1558379686.311 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-20T19:13:46.1Z 2019-05-20T19:14:46.311Z,1558379686.311 [Startup:StartupSatComms:A] Stopped 2019-05-20T19:14:46.311Z,1558379686.311 [Startup:StartupSatComms:B] Running Loop=1 2019-05-20T19:14:46.715Z,1558379686.715 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-05-20T19:15:07.487Z,1558379707.487 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004971 2019-05-20T19:15:13.884Z,1558379713.884 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190520T141654/Courier0061.lzma 2019-05-20T19:15:14.703Z,1558379714.703 [DataOverHttps](INFO): Moved sent file to Logs/20190520T141654/Courier0061.lzma.bak 2019-05-20T19:15:14.703Z,1558379714.703 [DataOverHttps](INFO): SBD MOMSN=11122287 2019-05-20T19:15:26.824Z,1558379726.824 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20190520T191217/Courier0000.lzma 2019-05-20T19:15:27.630Z,1558379727.630 [DataOverHttps](INFO): Moved sent file to Logs/20190520T191217/Courier0000.lzma.bak 2019-05-20T19:15:27.630Z,1558379727.630 [DataOverHttps](INFO): SBD MOMSN=11122290 2019-05-20T19:15:28.745Z,1558379728.745 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-05-20T19:15:28.745Z,1558379728.745 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T19:15:28.764Z,1558379728.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T19:15:29.174Z,1558379729.174 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T19:15:29.174Z,1558379729.174 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-05-20T19:15:33.150Z,1558379733.150 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-05-20T19:15:33.150Z,1558379733.150 [DVL_micro] Data Fault, FailCount= 1 2019-05-20T19:15:33.150Z,1558379733.150 [DVL_micro](ERROR): Data Fault 2019-05-20T19:15:33.203Z,1558379733.203 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-05-20T19:15:33.621Z,1558379733.621 [DVL_micro](INFO): uninitialize:Powering down 2019-05-20T19:15:34.418Z,1558379734.418 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-20T19:15:34.418Z,1558379734.418 [DVL_micro] No Fault, FailCount= 1 2019-05-20T19:15:34.768Z,1558379734.768 [DVL_micro](INFO): Initializing 2019-05-20T19:15:41.121Z,1558379741.121 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20190520T141654/Express0062.lzma 2019-05-20T19:15:41.926Z,1558379741.926 [DataOverHttps](INFO): Moved sent file to Logs/20190520T141654/Express0062.lzma.bak 2019-05-20T19:15:41.927Z,1558379741.927 [DataOverHttps](INFO): SBD MOMSN=11122295 2019-05-20T19:15:46.549Z,1558379746.549 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-20T19:14:46.3Z 2019-05-20T19:15:46.550Z,1558379746.550 [Startup:StartupSatComms:B] Stopped 2019-05-20T19:15:46.550Z,1558379746.550 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-05-20T19:15:46.550Z,1558379746.550 [Startup:StartupSatComms] Stopped 2019-05-20T19:15:46.550Z,1558379746.550 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-05-20T19:15:46.551Z,1558379746.551 [Startup](INFO): Completed Startup 2019-05-20T19:15:46.551Z,1558379746.551 [MissionManager](INFO): Startup is completed. 2019-05-20T19:15:46.551Z,1558379746.551 [MissionManager](INFO): Uninitializing Mission Startup 2019-05-20T19:15:46.551Z,1558379746.551 [Startup] Stopped 2019-05-20T19:15:46.551Z,1558379746.551 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-05-20T19:15:46.551Z,1558379746.551 [Startup:A.GoToSurface] Stopped 2019-05-20T19:15:46.551Z,1558379746.551 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-05-20T19:15:46.930Z,1558379746.930 [MissionManager](IMPORTANT): Started mission Default 2019-05-20T19:15:46.930Z,1558379746.930 [Default] Running Loop=1 2019-05-20T19:15:46.930Z,1558379746.930 [Default](DEBUG): Aggregate::initialize Default 2019-05-20T19:15:46.930Z,1558379746.930 [Default:B.GoToSurface] Running Loop=1 2019-05-20T19:15:46.930Z,1558379746.930 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-05-20T19:15:46.931Z,1558379746.931 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-05-20T19:15:46.931Z,1558379746.931 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-05-20T19:15:46.931Z,1558379746.931 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-05-20T19:15:46.932Z,1558379746.932 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-05-20T19:15:46.932Z,1558379746.932 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-05-20T19:15:46.932Z,1558379746.932 [Default:A.Wait] Running Loop=1 2019-05-20T19:15:46.932Z,1558379746.932 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-05-20T19:15:56.118Z,1558379756.118 [DataOverHttps](INFO): Sending 819 bytes from file Logs/20190520T191217/Express0001.lzma 2019-05-20T19:15:56.922Z,1558379756.922 [DataOverHttps](INFO): Moved sent file to Logs/20190520T191217/Express0001.lzma.bak 2019-05-20T19:15:56.922Z,1558379756.922 [DataOverHttps](INFO): SBD MOMSN=11122305 2019-05-20T19:16:00.237Z,1558379760.237 [Default:A.Wait](INFO): Done Waiting. 2019-05-20T19:16:00.237Z,1558379760.237 [Default:A.Wait] Stopped 2019-05-20T19:16:00.237Z,1558379760.237 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-05-20T19:16:00.656Z,1558379760.656 [Default:CheckIn] Running Loop=1 2019-05-20T19:16:00.656Z,1558379760.656 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-20T19:16:00.656Z,1558379760.656 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-20T19:16:01.051Z,1558379761.051 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-05-20T19:17:08.033Z,1558379828.033 [CommandLine](IMPORTANT): got command failComponent 2019-05-20T19:17:08.034Z,1558379828.034 [CommandLine](IMPORTANT): Failed components: 2019-05-20T19:17:08.034Z,1558379828.034 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2019-05-20T19:17:08.034Z,1558379828.034 [CommandLine](IMPORTANT): BPC1: Data Fault 2019-05-20T19:17:16.035Z,1558379836.035 [CommandLine](IMPORTANT): got command show variable DropWeight 2019-05-20T19:17:16.081Z,1558379836.081 [CommandLine](IMPORTANT): DropWeight.loadAtStartup (bool) 2019-05-20T19:17:16.081Z,1558379836.081 [CommandLine](IMPORTANT): DropWeight.simulateHardware (bool) 2019-05-20T19:17:16.137Z,1558379836.137 [CommandLine](IMPORTANT): DropWeight.dropWeightState (bool) 2019-05-20T19:17:20.853Z,1558379840.853 [CommandLine](IMPORTANT): got command get DropWeight.loadAtStartup 2019-05-20T19:17:20.854Z,1558379840.854 [CommandLine](IMPORTANT): DropWeight.loadAtStartup 1 bool 2019-05-20T19:18:06.919Z,1558379886.919 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter 2019-05-20T19:18:06.921Z,1558379886.921 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2019-05-20T19:18:07.132Z,1558379887.132 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-05-20T19:18:09.931Z,1558379889.931 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-05-20T19:18:10.725Z,1558379890.725 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:13.556Z,1558379893.556 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:16.797Z,1558379896.797 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:17.302Z,1558379897.302 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position 2019-05-20T19:18:17.655Z,1558379897.655 [Reporter](INFO): platform_buoyancy_position 883.146729 cc 2019-05-20T19:18:18.058Z,1558379898.058 [Reporter](INFO): platform_buoyancy_position 877.103271 cc 2019-05-20T19:18:18.447Z,1558379898.447 [Reporter](INFO): platform_buoyancy_position 872.671326 cc 2019-05-20T19:18:18.851Z,1558379898.851 [Reporter](INFO): platform_buoyancy_position 868.105164 cc 2019-05-20T19:18:19.263Z,1558379899.263 [Reporter](INFO): platform_buoyancy_position 861.927368 cc 2019-05-20T19:18:19.633Z,1558379899.633 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:19.711Z,1558379899.711 [Reporter](INFO): platform_buoyancy_position 857.361145 cc 2019-05-20T19:18:20.083Z,1558379900.083 [Reporter](INFO): platform_buoyancy_position 851.183350 cc 2019-05-20T19:18:20.491Z,1558379900.491 [Reporter](INFO): platform_buoyancy_position 846.751465 cc 2019-05-20T19:18:20.871Z,1558379900.871 [Reporter](INFO): platform_buoyancy_position 840.573669 cc 2019-05-20T19:18:21.279Z,1558379901.279 [Reporter](INFO): platform_buoyancy_position 836.141724 cc 2019-05-20T19:18:21.695Z,1558379901.695 [Reporter](INFO): platform_buoyancy_position 829.695312 cc 2019-05-20T19:18:22.087Z,1558379902.087 [Reporter](INFO): platform_buoyancy_position 825.397766 cc 2019-05-20T19:18:22.437Z,1558379902.437 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:22.499Z,1558379902.499 [Reporter](INFO): platform_buoyancy_position 819.085632 cc 2019-05-20T19:18:22.931Z,1558379902.931 [Reporter](INFO): platform_buoyancy_position 813.579346 cc 2019-05-20T19:18:23.323Z,1558379903.323 [Reporter](INFO): platform_buoyancy_position 808.207336 cc 2019-05-20T19:18:23.719Z,1558379903.719 [Reporter](INFO): platform_buoyancy_position 803.909729 cc 2019-05-20T19:18:24.139Z,1558379904.139 [Reporter](INFO): platform_buoyancy_position 797.597656 cc 2019-05-20T19:18:24.457Z,1558379904.457 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:24.551Z,1558379904.551 [Reporter](INFO): platform_buoyancy_position 792.897156 cc 2019-05-20T19:18:24.935Z,1558379904.935 [Reporter](INFO): platform_buoyancy_position 786.719360 cc 2019-05-20T19:18:25.348Z,1558379905.348 [Reporter](INFO): platform_buoyancy_position 782.421753 cc 2019-05-20T19:18:25.731Z,1558379905.731 [Reporter](INFO): platform_buoyancy_position 776.109619 cc 2019-05-20T19:18:26.155Z,1558379906.155 [Reporter](INFO): platform_buoyancy_position 771.677734 cc 2019-05-20T19:18:26.497Z,1558379906.497 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:26.594Z,1558379906.594 [Reporter](INFO): platform_buoyancy_position 765.499939 cc 2019-05-20T19:18:26.959Z,1558379906.959 [Reporter](INFO): platform_buoyancy_position 761.068054 cc 2019-05-20T19:18:27.359Z,1558379907.359 [Reporter](INFO): platform_buoyancy_position 756.367554 cc 2019-05-20T19:18:27.740Z,1558379907.740 [Reporter](INFO): platform_buoyancy_position 750.324036 cc 2019-05-20T19:18:28.154Z,1558379908.154 [Reporter](INFO): platform_buoyancy_position 745.892151 cc 2019-05-20T19:18:28.563Z,1558379908.563 [Reporter](INFO): platform_buoyancy_position 739.714355 cc 2019-05-20T19:18:28.955Z,1558379908.955 [Reporter](INFO): platform_buoyancy_position 735.148132 cc 2019-05-20T19:18:29.367Z,1558379909.367 [Reporter](INFO): platform_buoyancy_position 728.836060 cc 2019-05-20T19:18:29.717Z,1558379909.717 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:29.745Z,1558379909.745 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-05-20T19:18:29.745Z,1558379909.745 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T19:18:29.782Z,1558379909.782 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T19:18:29.784Z,1558379909.784 [Reporter](INFO): platform_buoyancy_position 724.135559 cc 2019-05-20T19:18:30.181Z,1558379910.181 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T19:18:30.181Z,1558379910.181 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-05-20T19:18:30.183Z,1558379910.183 [Reporter](INFO): platform_buoyancy_position 718.092041 cc 2019-05-20T19:18:30.588Z,1558379910.588 [Reporter](INFO): platform_buoyancy_position 713.660156 cc 2019-05-20T19:18:30.987Z,1558379910.987 [Reporter](INFO): platform_buoyancy_position 707.348022 cc 2019-05-20T19:18:31.387Z,1558379911.387 [Reporter](INFO): platform_buoyancy_position 703.050415 cc 2019-05-20T19:18:31.791Z,1558379911.791 [Reporter](INFO): platform_buoyancy_position 697.947021 cc 2019-05-20T19:18:32.183Z,1558379912.183 [Reporter](INFO): platform_buoyancy_position 692.306458 cc 2019-05-20T19:18:32.535Z,1558379912.535 [DVL_micro](ERROR): checksum mismatch: reported105 , calculated:117 2019-05-20T19:18:32.535Z,1558379912.535 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 351 121 389 -483 3 3 3 3 -50.7 806.1 102.7 -90.0 101.9 2 -50 806 101 2 802 -90 101 2 -0.16 -2.28 260.0 -3.0 23.8 0.005 35.0 1489 105 2019-05-20T19:18:32.549Z,1558379912.549 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:32.646Z,1558379912.646 [Reporter](INFO): platform_buoyancy_position 687.874512 cc 2019-05-20T19:18:33.003Z,1558379913.003 [Reporter](INFO): platform_buoyancy_position 681.428101 cc 2019-05-20T19:18:33.415Z,1558379913.415 [Reporter](INFO): platform_buoyancy_position 676.996216 cc 2019-05-20T19:18:33.807Z,1558379913.807 [Reporter](INFO): platform_buoyancy_position 670.684143 cc 2019-05-20T19:18:34.223Z,1558379914.223 [Reporter](INFO): platform_buoyancy_position 666.386536 cc 2019-05-20T19:18:34.620Z,1558379914.620 [Reporter](INFO): platform_buoyancy_position 660.074402 cc 2019-05-20T19:18:35.024Z,1558379915.024 [Reporter](INFO): platform_buoyancy_position 655.642517 cc 2019-05-20T19:18:35.431Z,1558379915.431 [Reporter](INFO): platform_buoyancy_position 649.330444 cc 2019-05-20T19:18:35.785Z,1558379915.785 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:35.859Z,1558379915.859 [Reporter](INFO): platform_buoyancy_position 644.495605 cc 2019-05-20T19:18:36.243Z,1558379916.243 [Reporter](INFO): platform_buoyancy_position 638.586426 cc 2019-05-20T19:18:36.647Z,1558379916.647 [Reporter](INFO): platform_buoyancy_position 634.288818 cc 2019-05-20T19:18:37.039Z,1558379917.039 [Reporter](INFO): platform_buoyancy_position 627.842407 cc 2019-05-20T19:18:37.451Z,1558379917.451 [Reporter](INFO): platform_buoyancy_position 623.410522 cc 2019-05-20T19:18:37.840Z,1558379917.840 [Reporter](INFO): platform_buoyancy_position 618.978638 cc 2019-05-20T19:18:38.247Z,1558379918.247 [Reporter](INFO): platform_buoyancy_position 612.666504 cc 2019-05-20T19:18:38.601Z,1558379918.601 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:38.688Z,1558379918.688 [Reporter](INFO): platform_buoyancy_position 606.757324 cc 2019-05-20T19:18:38.986Z,1558379918.986 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-05-20T19:18:38.986Z,1558379918.986 [DVL_micro] Data Fault, FailCount= 1 2019-05-20T19:18:38.986Z,1558379918.986 [DVL_micro](ERROR): Data Fault 2019-05-20T19:18:39.088Z,1558379919.088 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-05-20T19:18:39.098Z,1558379919.098 [Reporter](INFO): platform_buoyancy_position 601.922546 cc 2019-05-20T19:18:39.477Z,1558379919.477 [DVL_micro](INFO): uninitialize:Powering down 2019-05-20T19:18:39.552Z,1558379919.552 [Reporter](INFO): platform_buoyancy_position 595.476135 cc 2019-05-20T19:18:39.871Z,1558379919.871 [Reporter](INFO): platform_buoyancy_position 591.312805 cc 2019-05-20T19:18:40.282Z,1558379920.282 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-20T19:18:40.282Z,1558379920.282 [DVL_micro] No Fault, FailCount= 1 2019-05-20T19:18:40.284Z,1558379920.284 [Reporter](INFO): platform_buoyancy_position 587.015198 cc 2019-05-20T19:18:40.613Z,1558379920.613 [DVL_micro](INFO): Initializing 2019-05-20T19:18:40.687Z,1558379920.687 [Reporter](INFO): platform_buoyancy_position 581.240295 cc 2019-05-20T19:18:41.083Z,1558379921.083 [Reporter](INFO): platform_buoyancy_position 576.136902 cc 2019-05-20T19:18:41.503Z,1558379921.503 [Reporter](INFO): platform_buoyancy_position 569.690552 cc 2019-05-20T19:18:41.833Z,1558379921.833 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:41.899Z,1558379921.899 [Reporter](INFO): platform_buoyancy_position 565.392944 cc 2019-05-20T19:18:42.303Z,1558379922.303 [Reporter](INFO): platform_buoyancy_position 559.080811 cc 2019-05-20T19:18:42.719Z,1558379922.719 [Reporter](INFO): platform_buoyancy_position 554.648926 cc 2019-05-20T19:18:43.087Z,1558379923.087 [Reporter](INFO): platform_buoyancy_position 550.217041 cc 2019-05-20T19:18:43.503Z,1558379923.503 [Reporter](INFO): platform_buoyancy_position 543.904907 cc 2019-05-20T19:18:43.919Z,1558379923.919 [Reporter](INFO): platform_buoyancy_position 537.592834 cc 2019-05-20T19:18:44.311Z,1558379924.311 [Reporter](INFO): platform_buoyancy_position 533.160950 cc 2019-05-20T19:18:44.657Z,1558379924.657 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:44.739Z,1558379924.739 [Reporter](INFO): platform_buoyancy_position 526.714539 cc 2019-05-20T19:18:45.139Z,1558379925.139 [Reporter](INFO): platform_buoyancy_position 522.416931 cc 2019-05-20T19:18:45.531Z,1558379925.531 [Reporter](INFO): platform_buoyancy_position 517.313538 cc 2019-05-20T19:18:45.919Z,1558379925.919 [Reporter](INFO): platform_buoyancy_position 511.807220 cc 2019-05-20T19:18:46.323Z,1558379926.323 [Reporter](INFO): platform_buoyancy_position 507.375336 cc 2019-05-20T19:18:46.727Z,1558379926.727 [Reporter](INFO): platform_buoyancy_position 500.928894 cc 2019-05-20T19:18:47.179Z,1558379927.179 [Reporter](INFO): platform_buoyancy_position 494.885498 cc 2019-05-20T19:18:47.485Z,1558379927.485 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:47.559Z,1558379927.559 [Reporter](INFO): platform_buoyancy_position 490.184998 cc 2019-05-20T19:18:47.967Z,1558379927.967 [Reporter](INFO): platform_buoyancy_position 485.887360 cc 2019-05-20T19:18:48.363Z,1558379928.363 [Reporter](INFO): platform_buoyancy_position 479.709564 cc 2019-05-20T19:18:48.751Z,1558379928.751 [Reporter](INFO): platform_buoyancy_position 475.277618 cc 2019-05-20T19:18:49.166Z,1558379929.166 [Reporter](INFO): platform_buoyancy_position 470.845734 cc 2019-05-20T19:18:49.505Z,1558379929.505 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:49.583Z,1558379929.583 [Reporter](INFO): platform_buoyancy_position 464.533661 cc 2019-05-20T19:18:49.971Z,1558379929.971 [Reporter](INFO): platform_buoyancy_position 460.101715 cc 2019-05-20T19:18:50.391Z,1558379930.391 [Reporter](INFO): platform_buoyancy_position 453.789642 cc 2019-05-20T19:18:50.779Z,1558379930.779 [Reporter](INFO): platform_buoyancy_position 449.492035 cc 2019-05-20T19:18:51.187Z,1558379931.187 [Reporter](INFO): platform_buoyancy_position 444.522919 cc 2019-05-20T19:18:51.591Z,1558379931.591 [Reporter](INFO): platform_buoyancy_position 438.748016 cc 2019-05-20T19:18:51.983Z,1558379931.983 [Reporter](INFO): platform_buoyancy_position 432.973114 cc 2019-05-20T19:18:52.387Z,1558379932.387 [Reporter](INFO): platform_buoyancy_position 427.869720 cc 2019-05-20T19:18:52.737Z,1558379932.737 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:52.810Z,1558379932.810 [Reporter](INFO): platform_buoyancy_position 423.303558 cc 2019-05-20T19:18:53.225Z,1558379933.225 [Reporter](INFO): platform_buoyancy_position 417.260040 cc 2019-05-20T19:18:53.643Z,1558379933.643 [Reporter](INFO): platform_buoyancy_position 410.947937 cc 2019-05-20T19:18:54.011Z,1558379934.011 [Reporter](INFO): platform_buoyancy_position 406.381744 cc 2019-05-20T19:18:54.431Z,1558379934.431 [Reporter](INFO): platform_buoyancy_position 402.084137 cc 2019-05-20T19:18:54.827Z,1558379934.827 [Reporter](INFO): platform_buoyancy_position 395.637726 cc 2019-05-20T19:18:55.231Z,1558379935.231 [Reporter](INFO): platform_buoyancy_position 391.340149 cc 2019-05-20T19:18:55.565Z,1558379935.565 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:55.655Z,1558379935.655 [Reporter](INFO): platform_buoyancy_position 384.893738 cc 2019-05-20T19:18:56.071Z,1558379936.071 [Reporter](INFO): platform_buoyancy_position 378.715942 cc 2019-05-20T19:18:56.483Z,1558379936.483 [Reporter](INFO): platform_buoyancy_position 374.284027 cc 2019-05-20T19:18:56.863Z,1558379936.863 [Reporter](INFO): platform_buoyancy_position 369.583527 cc 2019-05-20T19:18:57.231Z,1558379937.231 [Reporter](INFO): platform_buoyancy_position 363.540039 cc 2019-05-20T19:18:57.639Z,1558379937.639 [Reporter](INFO): platform_buoyancy_position 359.108124 cc 2019-05-20T19:18:58.047Z,1558379938.047 [Reporter](INFO): platform_buoyancy_position 352.796051 cc 2019-05-20T19:18:58.455Z,1558379938.455 [Reporter](INFO): platform_buoyancy_position 348.364136 cc 2019-05-20T19:18:58.801Z,1558379938.801 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:18:58.875Z,1558379938.875 [Reporter](INFO): platform_buoyancy_position 342.320648 cc 2019-05-20T19:18:59.287Z,1558379939.287 [Reporter](INFO): platform_buoyancy_position 337.620148 cc 2019-05-20T19:18:59.687Z,1558379939.687 [Reporter](INFO): platform_buoyancy_position 333.053925 cc 2019-05-20T19:19:00.087Z,1558379940.087 [Reporter](INFO): platform_buoyancy_position 326.876129 cc 2019-05-20T19:19:00.463Z,1558379940.463 [Reporter](INFO): platform_buoyancy_position 322.578522 cc 2019-05-20T19:19:00.875Z,1558379940.875 [Reporter](INFO): platform_buoyancy_position 316.266449 cc 2019-05-20T19:19:01.279Z,1558379941.279 [Reporter](INFO): platform_buoyancy_position 311.968842 cc 2019-05-20T19:19:01.625Z,1558379941.625 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:01.699Z,1558379941.699 [Reporter](INFO): platform_buoyancy_position 305.522430 cc 2019-05-20T19:19:02.103Z,1558379942.103 [Reporter](INFO): platform_buoyancy_position 301.090546 cc 2019-05-20T19:19:02.499Z,1558379942.499 [Reporter](INFO): platform_buoyancy_position 295.449921 cc 2019-05-20T19:19:02.907Z,1558379942.907 [Reporter](INFO): platform_buoyancy_position 290.346527 cc 2019-05-20T19:19:03.322Z,1558379943.322 [Reporter](INFO): platform_buoyancy_position 285.646027 cc 2019-05-20T19:19:03.735Z,1558379943.735 [Reporter](INFO): platform_buoyancy_position 279.602539 cc 2019-05-20T19:19:04.111Z,1558379944.111 [Reporter](INFO): platform_buoyancy_position 273.290436 cc 2019-05-20T19:19:04.507Z,1558379944.507 [Reporter](INFO): platform_buoyancy_position 268.858521 cc 2019-05-20T19:19:04.857Z,1558379944.857 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:04.935Z,1558379944.935 [Reporter](INFO): platform_buoyancy_position 262.546417 cc 2019-05-20T19:19:05.335Z,1558379945.335 [Reporter](INFO): platform_buoyancy_position 258.114532 cc 2019-05-20T19:19:05.743Z,1558379945.743 [Reporter](INFO): platform_buoyancy_position 252.473923 cc 2019-05-20T19:19:06.123Z,1558379946.123 [Reporter](INFO): platform_buoyancy_position 247.504822 cc 2019-05-20T19:19:06.543Z,1558379946.543 [Reporter](INFO): platform_buoyancy_position 242.938629 cc 2019-05-20T19:19:06.927Z,1558379946.927 [Reporter](INFO): platform_buoyancy_position 236.760834 cc 2019-05-20T19:19:07.331Z,1558379947.331 [Reporter](INFO): platform_buoyancy_position 232.328918 cc 2019-05-20T19:19:07.689Z,1558379947.689 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:07.751Z,1558379947.751 [Reporter](INFO): platform_buoyancy_position 226.016815 cc 2019-05-20T19:19:08.155Z,1558379948.155 [Reporter](INFO): platform_buoyancy_position 219.704742 cc 2019-05-20T19:19:08.563Z,1558379948.563 [Reporter](INFO): platform_buoyancy_position 215.138519 cc 2019-05-20T19:19:10.513Z,1558379950.513 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:12.231Z,1558379952.231 [Reporter](INFO): platform_buoyancy_position 200.365540 cc 2019-05-20T19:19:12.533Z,1558379952.533 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:15.769Z,1558379955.769 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:18.593Z,1558379958.593 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:21.825Z,1558379961.825 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:24.677Z,1558379964.677 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:27.885Z,1558379967.885 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:30.717Z,1558379970.717 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:33.541Z,1558379973.541 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:35.577Z,1558379975.577 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:37.581Z,1558379977.581 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:40.813Z,1558379980.813 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:43.641Z,1558379983.641 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:46.873Z,1558379986.873 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:49.701Z,1558379989.701 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:52.941Z,1558379992.941 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:55.761Z,1558379995.761 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:19:58.585Z,1558379998.585 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:00.609Z,1558380000.609 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:03.841Z,1558380003.841 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:06.669Z,1558380006.669 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:09.901Z,1558380009.901 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:12.732Z,1558380012.732 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:15.961Z,1558380015.961 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:18.789Z,1558380018.789 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:21.617Z,1558380021.617 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:23.637Z,1558380023.637 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:25.657Z,1558380025.657 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:28.889Z,1558380028.889 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:31.721Z,1558380031.721 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:34.953Z,1558380034.953 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:37.781Z,1558380037.781 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:41.009Z,1558380041.009 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:43.837Z,1558380043.837 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:46.677Z,1558380046.677 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:48.685Z,1558380048.685 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:50.709Z,1558380050.709 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:53.941Z,1558380053.941 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:56.765Z,1558380056.765 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:20:59.997Z,1558380059.997 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:00.847Z,1558380060.847 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-20T19:16:00.7Z 2019-05-20T19:21:00.847Z,1558380060.847 [Default:CheckIn:Read_GPS] Stopped 2019-05-20T19:21:00.847Z,1558380060.847 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-20T19:21:01.236Z,1558380061.236 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-05-20T19:21:02.885Z,1558380062.885 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:05.701Z,1558380065.701 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:07.468Z,1558380067.468 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190520T191217/Courier0004.lzma 2019-05-20T19:21:07.721Z,1558380067.721 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:08.274Z,1558380068.274 [DataOverHttps](INFO): Moved sent file to Logs/20190520T191217/Courier0004.lzma.bak 2019-05-20T19:21:08.274Z,1558380068.274 [DataOverHttps](INFO): SBD MOMSN=11122360 2019-05-20T19:21:10.953Z,1558380070.953 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:13.781Z,1558380073.781 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:17.013Z,1558380077.013 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:19.841Z,1558380079.841 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:21.985Z,1558380081.985 [DataOverHttps](INFO): Sending 479 bytes from file Logs/20190520T191217/Express0005.lzma 2019-05-20T19:21:22.792Z,1558380082.792 [DataOverHttps](INFO): Moved sent file to Logs/20190520T191217/Express0005.lzma.bak 2019-05-20T19:21:22.792Z,1558380082.792 [DataOverHttps](INFO): SBD MOMSN=11122362 2019-05-20T19:21:23.073Z,1558380083.073 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:25.925Z,1558380085.925 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:26.354Z,1558380086.354 [Default:CheckIn:Read_Iridium] Stopped 2019-05-20T19:21:26.354Z,1558380086.354 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-20T19:21:26.354Z,1558380086.354 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-20T19:21:28.741Z,1558380088.741 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:30.749Z,1558380090.749 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:30.805Z,1558380090.805 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-05-20T19:21:30.805Z,1558380090.805 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T19:21:30.824Z,1558380090.824 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T19:21:31.264Z,1558380091.264 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T19:21:31.264Z,1558380091.264 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-05-20T19:21:32.781Z,1558380092.781 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:36.005Z,1558380096.005 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:38.833Z,1558380098.833 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:42.069Z,1558380102.069 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:44.874Z,1558380104.874 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-05-20T19:21:44.874Z,1558380104.874 [DVL_micro] Data Fault, FailCount= 1 2019-05-20T19:21:44.874Z,1558380104.874 [DVL_micro](ERROR): Data Fault 2019-05-20T19:21:44.889Z,1558380104.889 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:44.924Z,1558380104.924 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-05-20T19:21:45.357Z,1558380105.357 [DVL_micro](INFO): uninitialize:Powering down 2019-05-20T19:21:46.132Z,1558380106.132 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-20T19:21:46.132Z,1558380106.132 [DVL_micro] No Fault, FailCount= 1 2019-05-20T19:21:46.496Z,1558380106.496 [DVL_micro](INFO): Initializing 2019-05-20T19:21:48.121Z,1558380108.121 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:50.949Z,1558380110.949 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:53.781Z,1558380113.781 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:55.805Z,1558380115.805 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:21:59.029Z,1558380119.029 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:01.857Z,1558380121.857 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:05.089Z,1558380125.089 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:07.917Z,1558380127.917 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:11.153Z,1558380131.153 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:13.980Z,1558380133.980 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:16.809Z,1558380136.809 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:18.841Z,1558380138.841 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:20.848Z,1558380140.848 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:24.077Z,1558380144.077 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:26.905Z,1558380146.905 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:30.141Z,1558380150.141 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:32.965Z,1558380152.965 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:36.201Z,1558380156.201 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:39.029Z,1558380159.029 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:41.853Z,1558380161.853 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:43.873Z,1558380163.873 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:47.105Z,1558380167.105 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:49.933Z,1558380169.933 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:53.165Z,1558380173.165 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:55.993Z,1558380175.993 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:22:59.225Z,1558380179.225 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:23:02.053Z,1558380182.053 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:23:04.885Z,1558380184.885 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:23:05.825Z,1558380185.825 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-05-20T19:23:06.901Z,1558380186.901 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:23:10.119Z,1558380190.119 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-05-20T19:23:10.119Z,1558380190.119 [NAL9602] Data Fault, FailCount= 1 2019-05-20T19:23:10.119Z,1558380190.119 [NAL9602](ERROR): Data Fault 2019-05-20T19:23:10.156Z,1558380190.156 [CBIT](ERROR): Data Fault in component: NAL9602 2019-05-20T19:23:10.538Z,1558380190.538 [NAL9602](INFO): Powering down 2019-05-20T19:23:11.368Z,1558380191.368 [CBIT](INFO): Clearing failed state for component NAL9602 2019-05-20T19:23:11.368Z,1558380191.368 [NAL9602] No Fault, FailCount= 1 2019-05-20T19:23:36.529Z,1558380216.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-05-20T19:23:37.628Z,1558380217.628 [CBIT](INFO): Clearing failed state for component BPC1 2019-05-20T19:23:37.628Z,1558380217.628 [BPC1] No Fault, FailCount= 1 2019-05-20T19:23:40.823Z,1558380220.823 [NAL9602](INFO): Powering up NAL9602 2019-05-20T19:23:51.730Z,1558380231.730 [NAL9602](INFO): NAL9602 initialized 2019-05-20T19:23:52.577Z,1558380232.577 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:23:55.381Z,1558380235.381 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:23:58.209Z,1558380238.209 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:01.441Z,1558380241.441 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:04.269Z,1558380244.269 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:07.097Z,1558380247.097 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:07.233Z,1558380247.233 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-05-20T19:24:09.121Z,1558380249.121 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:11.137Z,1558380251.137 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:14.369Z,1558380254.369 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:17.197Z,1558380257.197 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:20.433Z,1558380260.433 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:23.259Z,1558380263.259 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:26.489Z,1558380266.489 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:29.317Z,1558380269.317 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:31.758Z,1558380271.758 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-05-20T19:24:31.758Z,1558380271.758 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T19:24:31.769Z,1558380271.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T19:24:32.149Z,1558380272.149 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:32.184Z,1558380272.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T19:24:32.184Z,1558380272.184 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-05-20T19:24:34.165Z,1558380274.165 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:36.189Z,1558380276.189 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:37.937Z,1558380277.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-05-20T19:24:39.417Z,1558380279.417 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:42.245Z,1558380282.245 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:44.678Z,1558380284.678 [BPC1](ERROR): BPC1A: No match for serial number 0287 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-05-20T19:24:45.477Z,1558380285.477 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:48.313Z,1558380288.313 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:50.722Z,1558380290.722 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-05-20T19:24:50.722Z,1558380290.722 [DVL_micro] Data Fault, FailCount= 1 2019-05-20T19:24:50.722Z,1558380290.722 [DVL_micro](ERROR): Data Fault 2019-05-20T19:24:50.783Z,1558380290.783 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-05-20T19:24:51.185Z,1558380291.185 [DVL_micro](INFO): uninitialize:Powering down 2019-05-20T19:24:51.537Z,1558380291.537 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:51.554Z,1558380291.554 [BPC1](ERROR): BPC1B: No match for serial number 01CE in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-05-20T19:24:51.988Z,1558380291.988 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-20T19:24:51.988Z,1558380291.988 [DVL_micro] No Fault, FailCount= 1 2019-05-20T19:24:52.340Z,1558380292.340 [DVL_micro](INFO): Initializing 2019-05-20T19:24:54.369Z,1558380294.369 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:56.795Z,1558380296.795 [BPC1](ERROR): BPC1A: No match for serial number 0287 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-05-20T19:24:57.197Z,1558380297.197 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:24:59.213Z,1558380299.213 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:01.237Z,1558380301.237 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:04.469Z,1558380304.469 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:05.341Z,1558380305.341 [BPC1](ERROR): BPC1B: No match for serial number 01CE in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-05-20T19:25:07.300Z,1558380307.300 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:08.649Z,1558380308.649 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-05-20T19:25:09.320Z,1558380309.320 [BPC1](ERROR): BPC1A: No match for serial number 0287 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-05-20T19:25:10.529Z,1558380310.529 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:11.073Z,1558380311.073 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.215775 2019-05-20T19:25:13.353Z,1558380313.353 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:16.585Z,1558380316.585 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:18.610Z,1558380318.610 [BPC1](ERROR): BPC1B: No match for serial number 01CE in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-05-20T19:25:19.413Z,1558380319.413 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:21.471Z,1558380321.471 [BPC1](ERROR): BPC1A: No match for serial number 0287 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-05-20T19:25:22.241Z,1558380322.241 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:24.265Z,1558380324.265 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:27.496Z,1558380327.496 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:30.321Z,1558380330.321 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:31.548Z,1558380331.548 [BPC1](ERROR): BPC1B: No match for serial number 01CE in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-05-20T19:25:33.557Z,1558380333.557 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:33.574Z,1558380333.574 [BPC1](ERROR): BPC1A: No match for serial number 0287 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-05-20T19:25:36.381Z,1558380336.381 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:39.613Z,1558380339.613 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:42.441Z,1558380342.441 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:44.926Z,1558380344.926 [BPC1](ERROR): BPC1B: No match for serial number 01CE in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-05-20T19:25:45.273Z,1558380345.273 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:45.289Z,1558380345.289 [BPC1](ERROR): BPC1A: No match for serial number 0287 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-05-20T19:25:45.301Z,1558380345.301 [BPC1](FAULT): Failed to parse data from all battery packs. 2019-05-20T19:25:45.301Z,1558380345.301 [BPC1] Data Fault, FailCount= 2 2019-05-20T19:25:45.301Z,1558380345.301 [BPC1](ERROR): Data Fault 2019-05-20T19:25:45.318Z,1558380345.318 [CBIT](ERROR): Data Fault in component: BPC1 2019-05-20T19:25:47.301Z,1558380347.301 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:49.309Z,1558380349.309 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:52.545Z,1558380352.545 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:55.372Z,1558380355.372 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:25:58.601Z,1558380358.601 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:01.429Z,1558380361.429 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:04.661Z,1558380364.661 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:07.489Z,1558380367.489 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:09.653Z,1558380369.653 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-05-20T19:26:10.317Z,1558380370.317 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:12.341Z,1558380372.341 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:15.573Z,1558380375.573 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:18.413Z,1558380378.413 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:21.629Z,1558380381.629 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:24.457Z,1558380384.457 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:26.905Z,1558380386.905 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-05-20T19:26:26.905Z,1558380386.905 [Default:CheckIn:C.Wait] Stopped 2019-05-20T19:26:26.905Z,1558380386.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-05-20T19:26:26.906Z,1558380386.906 [Default:CheckIn:D] Running Loop=1 2019-05-20T19:26:27.299Z,1558380387.299 [Default:CheckIn:D] Stopped 2019-05-20T19:26:27.299Z,1558380387.299 [Default:CheckIn:E] Running Loop=1 2019-05-20T19:26:27.689Z,1558380387.689 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:27.739Z,1558380387.739 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672810 min 2019-05-20T19:26:27.739Z,1558380387.739 [Default:CheckIn:E] Stopped 2019-05-20T19:26:27.739Z,1558380387.739 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-05-20T19:26:27.739Z,1558380387.739 [Default:CheckIn] Stopped 2019-05-20T19:26:27.739Z,1558380387.739 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-05-20T19:26:27.739Z,1558380387.739 [Default:CheckIn](INFO): Running loop #2 2019-05-20T19:26:27.740Z,1558380387.740 [Default:CheckIn] Running Loop=2 2019-05-20T19:26:27.740Z,1558380387.740 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-05-20T19:26:27.740Z,1558380387.740 [Default:CheckIn:Read_GPS] Running Loop=1 2019-05-20T19:26:30.517Z,1558380390.517 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:33.348Z,1558380393.348 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:35.365Z,1558380395.365 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:36.458Z,1558380396.458 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003728 2019-05-20T19:26:38.597Z,1558380398.597 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:41.425Z,1558380401.425 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:44.661Z,1558380404.661 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:47.485Z,1558380407.485 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:50.725Z,1558380410.725 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:53.561Z,1558380413.561 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:56.385Z,1558380416.385 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:26:58.413Z,1558380418.413 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:00.417Z,1558380420.417 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:03.641Z,1558380423.641 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:06.477Z,1558380426.477 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:09.709Z,1558380429.709 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:12.549Z,1558380432.549 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:15.769Z,1558380435.769 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:18.593Z,1558380438.593 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:21.421Z,1558380441.421 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:23.445Z,1558380443.445 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:26.673Z,1558380446.673 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:29.505Z,1558380449.505 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:29.544Z,1558380449.544 [CBIT](INFO): Clearing failed state for component DropWeight 2019-05-20T19:27:29.544Z,1558380449.544 [DropWeight] No Fault, FailCount= 1 2019-05-20T19:27:32.733Z,1558380452.733 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:32.758Z,1558380452.758 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-05-20T19:27:32.758Z,1558380452.758 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T19:27:32.769Z,1558380452.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T19:27:33.178Z,1558380453.178 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T19:27:33.178Z,1558380453.178 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-05-20T19:27:35.561Z,1558380455.561 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:38.793Z,1558380458.793 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:41.621Z,1558380461.621 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:44.449Z,1558380464.449 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:46.469Z,1558380466.469 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:48.489Z,1558380468.489 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:51.721Z,1558380471.721 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:54.549Z,1558380474.549 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:56.554Z,1558380476.554 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-05-20T19:27:56.554Z,1558380476.554 [DVL_micro] Data Fault, FailCount= 1 2019-05-20T19:27:56.554Z,1558380476.554 [DVL_micro](ERROR): Data Fault 2019-05-20T19:27:56.592Z,1558380476.592 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-05-20T19:27:57.037Z,1558380477.037 [DVL_micro](INFO): uninitialize:Powering down 2019-05-20T19:27:57.781Z,1558380477.781 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:27:57.827Z,1558380477.827 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-20T19:27:57.827Z,1558380477.827 [DVL_micro] No Fault, FailCount= 1 2019-05-20T19:27:58.177Z,1558380478.177 [DVL_micro](INFO): Initializing 2019-05-20T19:28:00.611Z,1558380480.611 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:03.861Z,1558380483.861 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:06.669Z,1558380486.669 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:09.497Z,1558380489.497 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:11.517Z,1558380491.517 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:14.749Z,1558380494.749 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:17.605Z,1558380497.605 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:20.821Z,1558380500.821 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:23.637Z,1558380503.637 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:26.869Z,1558380506.869 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:29.701Z,1558380509.701 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:32.529Z,1558380512.529 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:34.549Z,1558380514.549 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:36.565Z,1558380516.565 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:39.797Z,1558380519.797 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:42.625Z,1558380522.625 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:45.857Z,1558380525.857 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:48.689Z,1558380528.689 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:51.925Z,1558380531.925 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:54.745Z,1558380534.745 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:57.573Z,1558380537.573 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:28:59.605Z,1558380539.605 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:02.845Z,1558380542.845 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:05.657Z,1558380545.657 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:08.889Z,1558380548.889 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:11.717Z,1558380551.717 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:14.945Z,1558380554.945 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:17.773Z,1558380557.773 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:20.601Z,1558380560.601 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:22.621Z,1558380562.621 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:25.853Z,1558380565.853 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:28.701Z,1558380568.701 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:31.933Z,1558380571.933 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:34.741Z,1558380574.741 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:37.973Z,1558380577.973 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:40.801Z,1558380580.801 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:43.629Z,1558380583.629 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:45.653Z,1558380585.653 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:47.669Z,1558380587.669 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:50.917Z,1558380590.917 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:53.733Z,1558380593.733 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:56.961Z,1558380596.961 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:29:59.793Z,1558380599.793 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:03.021Z,1558380603.021 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:05.849Z,1558380605.849 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:08.677Z,1558380608.677 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:10.717Z,1558380610.717 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:13.945Z,1558380613.945 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:16.761Z,1558380616.761 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:19.993Z,1558380619.993 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:22.821Z,1558380622.821 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:26.049Z,1558380626.049 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:28.877Z,1558380628.877 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:31.709Z,1558380631.709 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:33.725Z,1558380633.725 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:33.746Z,1558380633.746 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2019-05-20T19:30:33.746Z,1558380633.746 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T19:30:33.757Z,1558380633.757 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T19:30:34.171Z,1558380634.171 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T19:30:34.171Z,1558380634.171 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2019-05-20T19:30:35.745Z,1558380635.745 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:38.977Z,1558380638.977 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:40.146Z,1558380640.146 [CommandLine](IMPORTANT): got command burn on 2019-05-20T19:30:40.146Z,1558380640.146 [CommandLine](IMPORTANT): Activating dropweight wire 2019-05-20T19:30:40.990Z,1558380640.990 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-05-20T19:30:40.990Z,1558380640.990 [DropWeight] Hardware Fault, FailCount= 1 2019-05-20T19:30:40.990Z,1558380640.990 [DropWeight](ERROR): Hardware Fault 2019-05-20T19:30:41.050Z,1558380641.050 [CBIT](INFO): Critical error at 20190520T193040 2019-05-20T19:30:41.053Z,1558380641.053 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-05-20T19:30:41.053Z,1558380641.053 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-05-20T19:30:41.441Z,1558380641.441 [CBIT](INFO): Critical error at 20190520T193041 2019-05-20T19:30:41.801Z,1558380641.801 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:45.037Z,1558380645.037 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:47.871Z,1558380647.871 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:49.226Z,1558380649.226 [CommandLine](IMPORTANT): got command burn off 2019-05-20T19:30:49.226Z,1558380649.226 [CommandLine](IMPORTANT): Deactivating dropweight wire 2019-05-20T19:30:51.100Z,1558380651.100 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:53.929Z,1558380653.929 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:54.266Z,1558380654.266 [CommandLine](IMPORTANT): got command burn on 2019-05-20T19:30:54.266Z,1558380654.266 [CommandLine](IMPORTANT): Activating dropweight wire 2019-05-20T19:30:56.753Z,1558380656.753 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:30:58.773Z,1558380658.773 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:02.002Z,1558380662.002 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:02.414Z,1558380662.414 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-05-20T19:31:02.414Z,1558380662.414 [DVL_micro] Data Fault, FailCount= 1 2019-05-20T19:31:02.414Z,1558380662.414 [DVL_micro](ERROR): Data Fault 2019-05-20T19:31:02.511Z,1558380662.511 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-05-20T19:31:02.873Z,1558380662.873 [DVL_micro](INFO): uninitialize:Powering down 2019-05-20T19:31:03.649Z,1558380663.649 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-20T19:31:03.650Z,1558380663.650 [DVL_micro] No Fault, FailCount= 1 2019-05-20T19:31:04.020Z,1558380664.020 [DVL_micro](INFO): Initializing 2019-05-20T19:31:04.833Z,1558380664.833 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:06.153Z,1558380666.153 [CommandLine](IMPORTANT): got command burn off 2019-05-20T19:31:06.153Z,1558380666.153 [CommandLine](IMPORTANT): Deactivating dropweight wire 2019-05-20T19:31:08.073Z,1558380668.073 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:10.889Z,1558380670.889 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:14.125Z,1558380674.125 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:16.969Z,1558380676.969 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:19.789Z,1558380679.789 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:21.809Z,1558380681.809 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:23.821Z,1558380683.821 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:24.757Z,1558380684.757 [CommandLine](IMPORTANT): got command report 2019-05-20T19:31:24.757Z,1558380684.757 [Reporter](INFO): platform_buoyancy_position reporting changes 2019-05-20T19:31:27.061Z,1558380687.061 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:27.902Z,1558380687.902 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-05-20T19:26:27.7Z 2019-05-20T19:31:27.902Z,1558380687.902 [Default:CheckIn:Read_GPS] Stopped 2019-05-20T19:31:27.902Z,1558380687.902 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-05-20T19:31:29.969Z,1558380689.969 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:30.261Z,1558380690.261 [CommandLine](IMPORTANT): got command get platform_buoyancy_position 2019-05-20T19:31:30.262Z,1558380690.262 [CommandLine](IMPORTANT): platform_buoyancy_position 200.365540 cc 2019-05-20T19:31:32.785Z,1558380692.785 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:34.724Z,1558380694.724 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20190520T191217/Courier0007.lzma 2019-05-20T19:31:34.809Z,1558380694.809 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:35.530Z,1558380695.530 [DataOverHttps](INFO): Moved sent file to Logs/20190520T191217/Courier0007.lzma.bak 2019-05-20T19:31:35.530Z,1558380695.530 [DataOverHttps](INFO): SBD MOMSN=11122415 2019-05-20T19:31:36.829Z,1558380696.829 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:37.214Z,1558380697.214 [DVL_micro](ERROR): No DVL communication! Re-initializing 2019-05-20T19:31:37.214Z,1558380697.214 [DVL_micro] Communications Fault, FailCount= 1 2019-05-20T19:31:37.214Z,1558380697.214 [DVL_micro](ERROR): Communications Fault 2019-05-20T19:31:37.307Z,1558380697.307 [CBIT](ERROR): Communications Fault in component: DVL_micro 2019-05-20T19:31:37.694Z,1558380697.694 [DVL_micro](INFO): uninitialize:Powering down 2019-05-20T19:31:38.500Z,1558380698.500 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-05-20T19:31:38.500Z,1558380698.500 [DVL_micro] No Fault, FailCount= 1 2019-05-20T19:31:38.844Z,1558380698.844 [DVL_micro](INFO): Initializing 2019-05-20T19:31:40.053Z,1558380700.053 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:42.885Z,1558380702.885 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:46.121Z,1558380706.121 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:48.945Z,1558380708.945 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:49.129Z,1558380709.129 [DataOverHttps](INFO): Sending 340 bytes from file Logs/20190520T191217/Express0008.lzma 2019-05-20T19:31:49.934Z,1558380709.934 [DataOverHttps](INFO): Moved sent file to Logs/20190520T191217/Express0008.lzma.bak 2019-05-20T19:31:49.934Z,1558380709.934 [DataOverHttps](INFO): SBD MOMSN=11122422 2019-05-20T19:31:52.177Z,1558380712.177 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:53.406Z,1558380713.406 [Default:CheckIn:Read_Iridium] Stopped 2019-05-20T19:31:53.406Z,1558380713.406 [Default:CheckIn:C.Wait] Running Loop=1 2019-05-20T19:31:53.406Z,1558380713.406 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-05-20T19:31:55.045Z,1558380715.045 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:57.833Z,1558380717.833 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:31:59.853Z,1558380719.853 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:01.873Z,1558380721.873 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:05.117Z,1558380725.117 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:07.993Z,1558380727.993 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:11.185Z,1558380731.185 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:13.997Z,1558380733.997 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:17.225Z,1558380737.225 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:20.057Z,1558380740.057 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:22.881Z,1558380742.881 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:24.901Z,1558380744.901 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:26.921Z,1558380746.921 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:30.153Z,1558380750.153 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:32.981Z,1558380752.981 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:36.213Z,1558380756.213 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:39.041Z,1558380759.041 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:42.273Z,1558380762.273 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:45.101Z,1558380765.101 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:47.929Z,1558380767.929 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:49.961Z,1558380769.961 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:53.197Z,1558380773.197 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:56.009Z,1558380776.009 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:32:59.241Z,1558380779.241 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:02.069Z,1558380782.069 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:05.301Z,1558380785.301 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:08.129Z,1558380788.129 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:10.961Z,1558380790.961 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:12.989Z,1558380792.989 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:16.225Z,1558380796.225 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:19.057Z,1558380799.057 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:22.281Z,1558380802.281 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:25.097Z,1558380805.097 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:28.329Z,1558380808.329 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:31.161Z,1558380811.161 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:33.985Z,1558380813.985 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:34.841Z,1558380814.841 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2019-05-20T19:33:34.841Z,1558380814.841 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-05-20T19:33:34.874Z,1558380814.874 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-05-20T19:33:35.267Z,1558380815.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-05-20T19:33:35.267Z,1558380815.267 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2019-05-20T19:33:36.001Z,1558380816.001 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:38.025Z,1558380818.025 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:41.261Z,1558380821.261 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:44.085Z,1558380824.085 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:47.317Z,1558380827.317 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:48.130Z,1558380828.130 [DVL_micro](ERROR): only read 45 of 46 data items 2019-05-20T19:33:48.130Z,1558380828.130 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 648 333 -301 -371 3 3 3 3 1266.6 939.6 83.3 2 695.4 -1415.4 83.3 2 123 2 695 -1415 83 2 -8.00 -1.51 259.6 -3.0 24.2 0.005 35.0 1489 64 2019-05-20T19:33:50.161Z,1558380830.161 [NAL9602](DEBUG): Fix Requested 2019-05-20T19:33:53.379Z,1558380833.379 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-05-20T19:33:53.379Z,1558380833.379 [NAL9602] Data Fault, FailCount= 2 2019-05-20T19:33:53.379Z,1558380833.379 [NAL9602](ERROR): Data Fault 2019-05-20T19:33:53.410Z,1558380833.410 [CBIT](ERROR): Data Fault in component: NAL9602 2019-05-20T19:33:53.766Z,1558380833.766 [NAL9602](INFO): Powering down 2019-05-20T19:33:54.637Z,1558380834.637 [CBIT](INFO): Clearing failed state for component NAL9602 2019-05-20T19:33:54.637Z,1558380834.637 [NAL9602] No Fault, FailCount= 2 2019-05-20T19:34:08.310Z,1558380848.310 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-05-20T19:34:08.310Z,1558380848.