2019-06-14T21:51:31.791Z,1560549091.791 [Supervisor](DEBUG): Initializing supervisor. 2019-06-14T21:51:31.794Z,1560549091.794 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-06-14T21:51:31.795Z,1560549091.795 [SyncHandler](INFO): Protected caller Thread ID is 1521 2019-06-14T21:51:31.795Z,1560549091.795 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-06-14T21:51:31.796Z,1560549091.796 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-06-14T21:51:31.797Z,1560549091.797 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1522 2019-06-14T21:51:31.800Z,1560549091.800 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-06-14T21:51:31.812Z,1560549091.812 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-06-14T21:51:31.813Z,1560549091.813 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-06-14T21:51:31.813Z,1560549091.813 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1523 2019-06-14T21:51:31.814Z,1560549091.814 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-06-14T21:51:31.815Z,1560549091.815 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-06-14T21:51:31.816Z,1560549091.816 [logger ThreadHandler](INFO): Protected caller Thread ID is 1524 2019-06-14T21:51:31.818Z,1560549091.818 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-06-14T21:51:31.818Z,1560549091.818 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-06-14T21:51:31.819Z,1560549091.819 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-06-14T21:51:31.918Z,1560549091.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-06-14T21:51:31.918Z,1560549091.918 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-06-14T21:51:32.511Z,1560549092.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-06-14T21:51:32.511Z,1560549092.511 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-06-14T21:51:32.612Z,1560549092.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-06-14T21:51:32.613Z,1560549092.613 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-06-14T21:51:32.766Z,1560549092.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-06-14T21:51:32.767Z,1560549092.767 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-06-14T21:51:32.964Z,1560549092.964 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-06-14T21:51:33.112Z,1560549093.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-06-14T21:51:33.113Z,1560549093.113 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-06-14T21:51:33.441Z,1560549093.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-06-14T21:51:33.441Z,1560549093.441 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-06-14T21:51:33.954Z,1560549093.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-06-14T21:51:33.954Z,1560549093.954 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-06-14T21:51:34.109Z,1560549094.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-06-14T21:51:34.110Z,1560549094.110 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-06-14T21:51:34.329Z,1560549094.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-06-14T21:51:34.329Z,1560549094.329 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-06-14T21:51:34.840Z,1560549094.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-06-14T21:51:34.840Z,1560549094.840 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-06-14T21:51:35.072Z,1560549095.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-06-14T21:51:35.073Z,1560549095.073 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-06-14T21:51:35.291Z,1560549095.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-06-14T21:51:35.292Z,1560549095.292 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-06-14T21:51:35.712Z,1560549095.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-06-14T21:51:35.712Z,1560549095.712 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-06-14T21:51:36.085Z,1560549096.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-06-14T21:51:36.087Z,1560549096.087 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2019-06-14T21:51:36.088Z,1560549096.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2019-06-14T21:51:36.173Z,1560549096.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2019-06-14T21:51:36.263Z,1560549096.263 [Config/Sensor](ERROR): Could not find unit: boo following DVL_micro.simulatedHardware = 0 2019-06-14T21:51:36.334Z,1560549096.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2019-06-14T21:51:36.448Z,1560549096.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2019-06-14T21:51:36.536Z,1560549096.536 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2019-06-14T21:51:36.635Z,1560549096.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2019-06-14T21:51:36.835Z,1560549096.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2019-06-14T21:51:37.075Z,1560549097.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-06-14T21:51:37.076Z,1560549097.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2019-06-14T21:51:37.174Z,1560549097.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2019-06-14T21:51:37.276Z,1560549097.276 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2019-06-14T21:51:37.400Z,1560549097.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2019-06-14T21:51:37.506Z,1560549097.506 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2019-06-14T21:51:37.507Z,1560549097.507 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-06-14T21:51:37.508Z,1560549097.508 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-06-14T21:51:37.579Z,1560549097.579 [VerticalControl](DEBUG): Construct VerticalControl. 2019-06-14T21:51:37.700Z,1560549097.700 [VerticalControl] Loaded 2019-06-14T21:51:37.700Z,1560549097.700 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-06-14T21:51:37.700Z,1560549097.700 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-06-14T21:51:37.774Z,1560549097.774 [HorizontalControl] Loaded 2019-06-14T21:51:37.774Z,1560549097.774 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-06-14T21:51:37.775Z,1560549097.775 [SpeedControl](DEBUG): Construct SpeedControl. 2019-06-14T21:51:37.781Z,1560549097.781 [SpeedControl] Loaded 2019-06-14T21:51:37.781Z,1560549097.781 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-06-14T21:51:37.782Z,1560549097.782 [LoopControl](DEBUG): Construct LoopControl. 2019-06-14T21:51:37.782Z,1560549097.782 [LoopControl] Loaded 2019-06-14T21:51:37.782Z,1560549097.782 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-06-14T21:51:37.783Z,1560549097.783 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-06-14T21:51:37.783Z,1560549097.783 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-06-14T21:51:37.910Z,1560549097.910 [DepthRateCalculator] Loaded 2019-06-14T21:51:37.911Z,1560549097.911 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-06-14T21:51:37.939Z,1560549097.939 [PitchRateCalculator] Loaded 2019-06-14T21:51:37.939Z,1560549097.939 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-06-14T21:51:37.969Z,1560549097.969 [SpeedCalculator] Loaded 2019-06-14T21:51:37.969Z,1560549097.969 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-06-14T21:51:37.991Z,1560549097.991 [TempGradientCalculator] Loaded 2019-06-14T21:51:37.992Z,1560549097.992 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-06-14T21:51:37.997Z,1560549097.997 [YawRateCalculator] Loaded 2019-06-14T21:51:37.997Z,1560549097.997 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-06-14T21:51:38.110Z,1560549098.110 [ElevatorOffsetCalculator] Loaded 2019-06-14T21:51:38.110Z,1560549098.110 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-06-14T21:51:38.110Z,1560549098.110 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-06-14T21:51:38.111Z,1560549098.111 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-06-14T21:51:38.216Z,1560549098.216 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-06-14T21:51:38.217Z,1560549098.217 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-06-14T21:51:38.313Z,1560549098.313 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-06-14T21:51:38.314Z,1560549098.314 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-06-14T21:51:38.909Z,1560549098.909 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-06-14T21:51:38.910Z,1560549098.910 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-06-14T21:51:39.014Z,1560549099.014 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-06-14T21:51:39.015Z,1560549099.015 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-06-14T21:51:39.415Z,1560549099.415 [AHRS_M2] Loaded 2019-06-14T21:51:39.416Z,1560549099.416 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-06-14T21:51:39.492Z,1560549099.492 [DataOverHttps] Loaded 2019-06-14T21:51:39.492Z,1560549099.492 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-06-14T21:51:39.494Z,1560549099.494 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C74E0 2019-06-14T21:51:39.494Z,1560549099.494 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1603 2019-06-14T21:51:39.509Z,1560549099.509 [Depth_Keller] Loaded 2019-06-14T21:51:39.509Z,1560549099.509 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-06-14T21:51:39.514Z,1560549099.514 [DropWeight] Loaded 2019-06-14T21:51:39.514Z,1560549099.514 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-06-14T21:51:39.653Z,1560549099.653 [DVL_micro] Loaded 2019-06-14T21:51:39.653Z,1560549099.653 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2019-06-14T21:51:39.759Z,1560549099.759 [NAL9602] Loaded 2019-06-14T21:51:39.759Z,1560549099.759 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-06-14T21:51:39.776Z,1560549099.776 [Onboard] Loaded 2019-06-14T21:51:39.776Z,1560549099.776 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-06-14T21:51:39.779Z,1560549099.779 [Radio_Surface] Loaded 2019-06-14T21:51:39.779Z,1560549099.779 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-06-14T21:51:39.781Z,1560549099.781 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F74E0 2019-06-14T21:51:39.781Z,1560549099.781 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1604 2019-06-14T21:51:41.382Z,1560549101.382 [BPC1] Loaded 2019-06-14T21:51:41.382Z,1560549101.382 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-06-14T21:51:41.382Z,1560549101.382 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-06-14T21:51:41.383Z,1560549101.383 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-06-14T21:51:41.492Z,1560549101.492 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-06-14T21:51:41.492Z,1560549101.492 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-06-14T21:51:41.514Z,1560549101.514 [NavChart] Loaded 2019-06-14T21:51:41.514Z,1560549101.514 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-06-14T21:51:41.518Z,1560549101.518 [UniversalFixResidualReporter] Loaded 2019-06-14T21:51:41.519Z,1560549101.519 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-06-14T21:51:41.519Z,1560549101.519 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-06-14T21:51:41.520Z,1560549101.520 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-06-14T21:51:41.665Z,1560549101.665 [SBIT](DEBUG): Construct Startup Built In Test. 2019-06-14T21:51:41.677Z,1560549101.677 [SBIT] Loaded 2019-06-14T21:51:41.677Z,1560549101.677 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-06-14T21:51:41.678Z,1560549101.678 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-06-14T21:51:41.690Z,1560549101.690 [IBIT] Loaded 2019-06-14T21:51:41.690Z,1560549101.690 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-06-14T21:51:41.693Z,1560549101.693 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-06-14T21:51:41.840Z,1560549101.840 [CBIT] Loaded 2019-06-14T21:51:41.840Z,1560549101.840 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-06-14T21:51:41.840Z,1560549101.840 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-06-14T21:51:41.841Z,1560549101.841 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-06-14T21:51:41.953Z,1560549101.953 [BuoyancyServo] Loaded 2019-06-14T21:51:41.953Z,1560549101.953 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-06-14T21:51:41.965Z,1560549101.965 [ElevatorServo] Loaded 2019-06-14T21:51:41.965Z,1560549101.965 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-06-14T21:51:41.977Z,1560549101.977 [MassServo] Loaded 2019-06-14T21:51:41.977Z,1560549101.977 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-06-14T21:51:41.989Z,1560549101.989 [RudderServo] Loaded 2019-06-14T21:51:41.989Z,1560549101.989 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-06-14T21:51:42.000Z,1560549102.000 [ThrusterServo] Loaded 2019-06-14T21:51:42.001Z,1560549102.001 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-06-14T21:51:42.001Z,1560549102.001 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-06-14T21:51:42.002Z,1560549102.002 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-06-14T21:51:42.015Z,1560549102.015 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-06-14T21:51:42.015Z,1560549102.015 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-06-14T21:51:42.284Z,1560549102.284 [CTD_NeilBrown] Loaded 2019-06-14T21:51:42.284Z,1560549102.284 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-06-14T21:51:42.286Z,1560549102.286 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 40A734E0 2019-06-14T21:51:42.286Z,1560549102.286 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1605 2019-06-14T21:51:42.296Z,1560549102.296 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-06-14T21:51:42.309Z,1560549102.309 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-06-14T21:51:42.311Z,1560549102.311 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-06-14T21:51:42.316Z,1560549102.316 [CTD_Seabird](INFO): created writer for : depth 2019-06-14T21:51:42.317Z,1560549102.317 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-06-14T21:51:42.322Z,1560549102.322 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-06-14T21:51:42.323Z,1560549102.323 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-06-14T21:51:42.328Z,1560549102.328 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-06-14T21:51:42.329Z,1560549102.329 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-06-14T21:51:42.334Z,1560549102.334 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-06-14T21:51:42.335Z,1560549102.335 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-06-14T21:51:42.340Z,1560549102.340 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-06-14T21:51:42.341Z,1560549102.341 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-06-14T21:51:42.346Z,1560549102.346 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-06-14T21:51:42.375Z,1560549102.375 [CTD_Seabird] Loaded 2019-06-14T21:51:42.375Z,1560549102.375 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-06-14T21:51:42.376Z,1560549102.376 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AA34E0 2019-06-14T21:51:42.376Z,1560549102.376 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1606 2019-06-14T21:51:42.392Z,1560549102.392 [PAR_Licor] Loaded 2019-06-14T21:51:42.392Z,1560549102.392 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-06-14T21:51:42.444Z,1560549102.444 [WetLabsBB2FL] Loaded 2019-06-14T21:51:42.444Z,1560549102.444 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-06-14T21:51:42.445Z,1560549102.445 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AD34E0 2019-06-14T21:51:42.446Z,1560549102.446 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1607 2019-06-14T21:51:42.447Z,1560549102.447 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-06-14T21:51:42.450Z,1560549102.450 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-06-14T21:51:42.451Z,1560549102.451 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-06-14T21:51:42.458Z,1560549102.458 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-06-14T21:51:42.459Z,1560549102.459 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0 2019-06-14T21:51:42.459Z,1560549102.459 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1608 2019-06-14T21:51:42.464Z,1560549102.464 [Supervisor](INFO): Main Thread ID is 802 2019-06-14T21:51:42.464Z,1560549102.464 [Supervisor](DEBUG): Running supervisor. 2019-06-14T21:51:42.464Z,1560549102.464 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1609 2019-06-14T21:51:42.467Z,1560549102.467 [controlThread ThreadHandler](INFO): Handler Thread ID is 1610 2019-06-14T21:51:42.467Z,1560549102.467 [controlThread](DEBUG): Initializing ControlThread 2019-06-14T21:51:42.468Z,1560549102.468 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-06-14T21:51:42.470Z,1560549102.470 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-06-14T21:51:42.470Z,1560549102.470 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-06-14T21:51:42.471Z,1560549102.471 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-06-14T21:51:42.471Z,1560549102.471 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-06-14T21:51:42.472Z,1560549102.472 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-06-14T21:51:42.472Z,1560549102.472 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-06-14T21:51:42.472Z,1560549102.472 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-06-14T21:51:42.473Z,1560549102.473 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-06-14T21:51:42.473Z,1560549102.473 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-06-14T21:51:42.480Z,1560549102.480 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-06-14T21:51:42.481Z,1560549102.481 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-06-14T21:51:42.481Z,1560549102.481 [SBIT](INFO): Initialize SBIT Component. 2019-06-14T21:51:42.482Z,1560549102.482 [SBIT](IMPORTANT): git: 2019-05-01-10-gcab12ce 2019-06-14T21:51:42.482Z,1560549102.482 [SBIT](INFO): git hash: cab12ce958be6da2bb158d8a74bde728d70637cd 2019-06-14T21:51:42.482Z,1560549102.482 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-06-14T21:51:42.482Z,1560549102.482 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-06-14T21:51:42.484Z,1560549102.484 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-06-14T21:51:42.484Z,1560549102.484 [IBIT](INFO): Initialize IBIT Component. 2019-06-14T21:51:42.485Z,1560549102.485 [CBIT](DEBUG): Initialize CBIT Component. 2019-06-14T21:51:42.486Z,1560549102.486 [logger ThreadHandler](INFO): Handler Thread ID is 1611 2019-06-14T21:51:42.498Z,1560549102.498 [CBIT](DEBUG): Initialized mux pins. 2019-06-14T21:51:42.498Z,1560549102.498 [CBIT](DEBUG): Initializing the watchdog timer. 2019-06-14T21:51:42.506Z,1560549102.506 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1612 2019-06-14T21:51:42.507Z,1560549102.507 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-06-14T21:51:42.518Z,1560549102.518 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1613 2019-06-14T21:51:42.522Z,1560549102.522 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-06-14T21:51:42.522Z,1560549102.522 [CBIT](DEBUG): Initializing heartbeat. 2019-06-14T21:51:42.530Z,1560549102.530 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1614 2019-06-14T21:51:42.542Z,1560549102.542 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1615 2019-06-14T21:51:42.543Z,1560549102.543 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-06-14T21:51:42.546Z,1560549102.546 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-06-14T21:51:42.547Z,1560549102.547 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1617 2019-06-14T21:51:42.549Z,1560549102.549 [WetLabsBB2FL](INFO): Powering down 2019-06-14T21:51:42.575Z,1560549102.575 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1618 2019-06-14T21:51:42.579Z,1560549102.579 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-06-14T21:51:42.579Z,1560549102.579 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-06-14T21:51:42.579Z,1560549102.579 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-06-14T21:51:42.579Z,1560549102.579 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-06-14T21:51:42.580Z,1560549102.580 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-06-14T21:51:42.580Z,1560549102.580 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-06-14T21:51:42.580Z,1560549102.580 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-06-14T21:51:42.580Z,1560549102.580 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-06-14T21:51:42.580Z,1560549102.580 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-06-14T21:51:42.581Z,1560549102.581 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-06-14T21:51:42.581Z,1560549102.581 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-06-14T21:51:42.581Z,1560549102.581 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-06-14T21:51:42.581Z,1560549102.581 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-06-14T21:51:42.581Z,1560549102.581 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-06-14T21:51:42.582Z,1560549102.582 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-06-14T21:51:42.582Z,1560549102.582 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-06-14T21:51:42.594Z,1560549102.594 [CBIT](DEBUG): Deactivating GF circuits. 2019-06-14T21:51:42.594Z,1560549102.594 [CBIT](DEBUG): Deactivating emergency mode. 2019-06-14T21:51:42.630Z,1560549102.630 [CBIT](DEBUG): Backplane powered. 2019-06-14T21:51:42.635Z,1560549102.635 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-06-14T21:51:42.652Z,1560549102.652 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-06-14T21:51:42.692Z,1560549102.692 [MissionManager](DEBUG): 2019-06-14T21:51:42.692Z,1560549102.692 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-06-14T21:51:42.760Z,1560549102.760 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-06-14T21:51:42.761Z,1560549102.761 [Default:A.Wait](DEBUG): Construct Wait. 2019-06-14T21:51:42.763Z,1560549102.763 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-06-14T21:51:42.818Z,1560549102.818 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-06-14T21:51:42.820Z,1560549102.820 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-06-14T21:51:42.842Z,1560549102.842 [Default:E.Execute](DEBUG): Construct Execute. 2019-06-14T21:51:42.845Z,1560549102.845 [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-06-14T21:51:42.866Z,1560549102.866 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,BPC1,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-06-14T21:51:42.878Z,1560549102.878 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-06-14T21:51:42.922Z,1560549102.922 [Radio_Surface](INFO): Powering up 2019-06-14T21:51:42.935Z,1560549102.935 [DVL_micro](INFO): Initializing 2019-06-14T21:51:43.024Z,1560549103.024 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-06-14T21:51:43.030Z,1560549103.030 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-06-14T21:51:43.031Z,1560549103.031 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-06-14T21:51:43.038Z,1560549103.038 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-06-14T21:51:43.039Z,1560549103.039 [MassServo](DEBUG): Initializing EZServoServo. 2019-06-14T21:51:43.046Z,1560549103.046 [MassServo](DEBUG): Initializing MassServo. 2019-06-14T21:51:43.047Z,1560549103.047 [RudderServo](DEBUG): Initializing EZServoServo. 2019-06-14T21:51:43.054Z,1560549103.054 [RudderServo](DEBUG): Initializing RudderServo. 2019-06-14T21:51:43.055Z,1560549103.055 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-06-14T21:51:43.062Z,1560549103.062 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-06-14T21:51:43.291Z,1560549103.291 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2019-06-14T21:51:43.291Z,1560549103.291 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-06-14T21:51:43.291Z,1560549103.291 [DropWeight] Hardware Fault, FailCount= 1 2019-06-14T21:51:43.291Z,1560549103.291 [DropWeight](ERROR): Hardware Fault 2019-06-14T21:51:43.362Z,1560549103.362 [CommandLine](FAULT): Scheduling is paused 2019-06-14T21:51:43.362Z,1560549103.362 [CBIT](INFO): Critical error at 20190614T215143 2019-06-14T21:51:43.363Z,1560549103.363 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-06-14T21:51:43.365Z,1560549103.365 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-06-14T21:51:43.382Z,1560549103.382 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-06-14T21:51:43.991Z,1560549103.991 [CBIT](INFO): Critical error at 20190614T215143 2019-06-14T21:51:52.720Z,1560549112.720 [CBIT](CRITICAL): Environmental Failure. Press:14.467259 PSI. Humidity:37%. Temp:26 C. ABORTING MISSION 2019-06-14T21:51:53.072Z,1560549113.072 [CBIT](INFO): Critical error at 20190614T215152 2019-06-14T21:52:09.988Z,1560549129.988 [NAL9602](INFO): Powering up NAL9602 2019-06-14T21:52:11.255Z,1560549131.255 [SBIT](IMPORTANT): Beginning Startup BIT 2019-06-14T21:52:11.260Z,1560549131.260 [CBIT](IMPORTANT): Beginning ground fault scan 2019-06-14T21:52:22.002Z,1560549142.002 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.001608 CHAN A1 (24V): 0.003305 CHAN A2 (12V): -0.007277 CHAN A3 (5V): -0.002015 CHAN B0 (3.3V): -0.000021 CHAN B1 (3.15aV): 0.000117 CHAN B2 (3.15bV): 0.000072 CHAN B3 (GND): 0.001863 OPEN: -0.000282 Full Scale Calc: 4.765 mA, -1.589 mA 2019-06-14T21:52:28.056Z,1560549148.056 [DVL_micro](ERROR): only read 45 of 46 data items 2019-06-14T21:52:28.057Z,1560549148.057 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -1421 -287 902 -112 2 3 2 2 -3100.4 -233.6 -247.6 2 -1965.3 2409.3 -247.6 2 -3100 -233 -247 2 -1965 2409 -247 2 0.69 -1.71 304.9 -3.0 25 35.0 1489 112 2019-06-14T21:52:30.883Z,1560549150.883 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T21:52:30.884Z,1560549150.884 [NAL9602] Communications Fault, FailCount= 1 2019-06-14T21:52:30.884Z,1560549150.884 [NAL9602](ERROR): Communications Fault 2019-06-14T21:52:31.023Z,1560549151.023 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T21:52:31.316Z,1560549151.316 [NAL9602](INFO): Powering down 2019-06-14T21:52:32.216Z,1560549152.216 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T21:52:32.216Z,1560549152.216 [NAL9602] No Fault, FailCount= 1 2019-06-14T21:53:01.711Z,1560549181.711 [NAL9602](INFO): Powering up NAL9602 2019-06-14T21:53:04.966Z,1560549184.966 [SBIT](IMPORTANT): SBIT PASSED 2019-06-14T21:53:05.024Z,1560549185.024 [CommandLine](IMPORTANT): got command configSet list 2019-06-14T21:53:05.024Z,1560549185.024 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-06-14T21:53:05.024Z,1560549185.024 [CommandLine](IMPORTANT): No configSet variables persisted 2019-06-14T21:53:05.376Z,1560549185.376 [MissionManager](IMPORTANT): Started mission Startup 2019-06-14T21:53:05.377Z,1560549185.377 [Startup] Running Loop=1 2019-06-14T21:53:05.377Z,1560549185.377 [Startup](DEBUG): Aggregate::initialize Startup 2019-06-14T21:53:05.377Z,1560549185.377 [Startup:A.GoToSurface] Running Loop=1 2019-06-14T21:53:05.377Z,1560549185.377 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-06-14T21:53:05.378Z,1560549185.378 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-06-14T21:53:05.378Z,1560549185.378 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-06-14T21:53:05.379Z,1560549185.379 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-06-14T21:53:05.379Z,1560549185.379 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-06-14T21:53:05.379Z,1560549185.379 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-06-14T21:53:05.381Z,1560549185.381 [Startup:StartupSatComms] Running Loop=1 2019-06-14T21:53:05.381Z,1560549185.381 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-06-14T21:53:05.381Z,1560549185.381 [Startup:StartupSatComms:A] Running Loop=1 2019-06-14T21:53:05.778Z,1560549185.778 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-06-14T21:53:17.951Z,1560549197.951 [CommandLine](IMPORTANT): got command show variable dvl_mi 2019-06-14T21:53:17.964Z,1560549197.964 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup (bool) 2019-06-14T21:53:17.965Z,1560549197.965 [CommandLine](IMPORTANT): DVL_micro.simulateHardware (bool) 2019-06-14T21:53:17.965Z,1560549197.965 [CommandLine](IMPORTANT): DVL_micro.magDeviation (degree) 2019-06-14T21:53:17.966Z,1560549197.966 [CommandLine](IMPORTANT): DVL_micro.pitchOffset (degree) 2019-06-14T21:53:17.966Z,1560549197.966 [CommandLine](IMPORTANT): DVL_micro.power (watt) 2019-06-14T21:53:17.966Z,1560549197.966 [CommandLine](IMPORTANT): DVL_micro.rollOffset (degree) 2019-06-14T21:53:17.989Z,1560549197.989 [CommandLine](IMPORTANT): DVL_micro.loadControl (none) 2019-06-14T21:53:17.990Z,1560549197.990 [CommandLine](IMPORTANT): DVL_micro.uart (none) 2019-06-14T21:53:17.990Z,1560549197.990 [CommandLine](IMPORTANT): DVL_micro.baud (bit_per_second) 2019-06-14T21:53:18.062Z,1560549198.062 [CommandLine](IMPORTANT): DVL_micro.height_above_sea_floor (meter) 2019-06-14T21:53:18.062Z,1560549198.062 [CommandLine](IMPORTANT): DVL_micro.platform_orientation (radian) 2019-06-14T21:53:18.063Z,1560549198.063 [CommandLine](IMPORTANT): DVL_micro.platform_magnetic_orientation (radian) 2019-06-14T21:53:18.063Z,1560549198.063 [CommandLine](IMPORTANT): DVL_micro.platform_pitch_angle (radian) 2019-06-14T21:53:18.063Z,1560549198.063 [CommandLine](IMPORTANT): DVL_micro.platform_roll_angle (radian) 2019-06-14T21:53:18.064Z,1560549198.064 [CommandLine](IMPORTANT): DVL_micro.platform_orientation_matrix (none) 2019-06-14T21:53:18.064Z,1560549198.064 [CommandLine](IMPORTANT): DVL_micro.platform_velocity_wrt_ground (meter_per_second) 2019-06-14T21:53:18.065Z,1560549198.065 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_wrt_ground (meter_per_second) 2019-06-14T21:53:18.065Z,1560549198.065 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_wrt_ground (meter_per_second) 2019-06-14T21:53:18.065Z,1560549198.065 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_wrt_ground (meter_per_second) 2019-06-14T21:53:18.078Z,1560549198.078 [CommandLine](IMPORTANT): DVL_micro.platform_velocity_wrt_sea_water (meter_per_second) 2019-06-14T21:53:18.078Z,1560549198.078 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_wrt_sea_water (meter_per_second) 2019-06-14T21:53:18.079Z,1560549198.079 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_wrt_sea_water (meter_per_second) 2019-06-14T21:53:18.079Z,1560549198.079 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_wrt_sea_water (meter_per_second) 2019-06-14T21:53:18.080Z,1560549198.080 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_current (meter_per_second) 2019-06-14T21:53:18.080Z,1560549198.080 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_current (meter_per_second) 2019-06-14T21:53:18.080Z,1560549198.080 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_current (meter_per_second) 2019-06-14T21:53:18.081Z,1560549198.081 [CommandLine](IMPORTANT): DVL_micro.Status (count) 2019-06-14T21:53:18.081Z,1560549198.081 [CommandLine](IMPORTANT): DVL_micro.Beam1Good (count) 2019-06-14T21:53:18.081Z,1560549198.081 [CommandLine](IMPORTANT): DVL_micro.Beam2Good (count) 2019-06-14T21:53:18.082Z,1560549198.082 [CommandLine](IMPORTANT): DVL_micro.Beam3Good (count) 2019-06-14T21:53:18.082Z,1560549198.082 [CommandLine](IMPORTANT): DVL_micro.Beam4Good (count) 2019-06-14T21:53:18.083Z,1560549198.083 [CommandLine](IMPORTANT): DVL_micro.Altitude1 (meter) 2019-06-14T21:53:18.083Z,1560549198.083 [CommandLine](IMPORTANT): DVL_micro.Altitude2 (meter) 2019-06-14T21:53:18.083Z,1560549198.083 [CommandLine](IMPORTANT): DVL_micro.Altitude3 (meter) 2019-06-14T21:53:18.084Z,1560549198.084 [CommandLine](IMPORTANT): DVL_micro.Altitude4 (meter) 2019-06-14T21:53:18.084Z,1560549198.084 [CommandLine](IMPORTANT): DVL_micro.BottomVelocityFlag (count) 2019-06-14T21:53:18.084Z,1560549198.084 [CommandLine](IMPORTANT): DVL_micro.WaterVelocityFlag (meter) 2019-06-14T21:53:18.133Z,1560549198.133 [CommandLine](IMPORTANT): DVL_micro.component_voltage (volt) 2019-06-14T21:53:18.133Z,1560549198.133 [CommandLine](IMPORTANT): DVL_micro.component_avgVoltage (volt) 2019-06-14T21:53:18.157Z,1560549198.157 [CommandLine](IMPORTANT): DVL_micro.component_current (milliampere) 2019-06-14T21:53:18.157Z,1560549198.157 [CommandLine](IMPORTANT): DVL_micro.component_avgCurrent (milliampere) 2019-06-14T21:53:22.329Z,1560549202.329 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T21:53:22.329Z,1560549202.329 [NAL9602] Communications Fault, FailCount= 2 2019-06-14T21:53:22.329Z,1560549202.329 [NAL9602](ERROR): Communications Fault 2019-06-14T21:53:22.362Z,1560549202.362 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T21:53:22.724Z,1560549202.724 [NAL9602](INFO): Powering down 2019-06-14T21:53:23.611Z,1560549203.611 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-06-14T21:53:23.706Z,1560549203.706 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T21:53:23.706Z,1560549203.706 [NAL9602] No Fault, FailCount= 2 2019-06-14T21:53:29.816Z,1560549209.816 [CommandLine](IMPORTANT): got command report mod DVL_micro.Altitude1 2019-06-14T21:53:30.030Z,1560549210.030 [Reporter](INFO): DVL_micro.Altitude1 0.000000 m 2019-06-14T21:53:45.352Z,1560549225.352 [CommandLine](IMPORTANT): got command report mod DVL_micro.platform_pitch_angle 2019-06-14T21:53:45.383Z,1560549225.383 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.029147 rad 2019-06-14T21:53:45.804Z,1560549225.804 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.029496 rad 2019-06-14T21:53:49.038Z,1560549229.038 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.029322 rad 2019-06-14T21:53:52.282Z,1560549232.282 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.028623 rad 2019-06-14T21:53:53.016Z,1560549233.016 [NAL9602](INFO): Powering up NAL9602 2019-06-14T21:53:55.486Z,1560549235.486 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.029845 rad 2019-06-14T21:53:58.724Z,1560549238.724 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.024609 rad 2019-06-14T21:54:01.952Z,1560549241.952 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.027402 rad 2019-06-14T21:54:05.387Z,1560549245.387 [Startup:StartupSatComms:A](INFO): Timed out from 2019-06-14T21:53:05.4Z 2019-06-14T21:54:05.387Z,1560549245.387 [Startup:StartupSatComms:A] Stopped 2019-06-14T21:54:05.387Z,1560549245.387 [Startup:StartupSatComms:B] Running Loop=1 2019-06-14T21:54:05.407Z,1560549245.407 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.030718 rad 2019-06-14T21:54:05.603Z,1560549245.603 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-06-14T21:54:08.180Z,1560549248.180 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.030369 rad 2019-06-14T21:54:09.056Z,1560549249.056 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004975 2019-06-14T21:54:11.462Z,1560549251.462 [Reporter](INFO): DVL_micro.platform_pitch_angle -0.029496 rad 2019-06-14T21:54:11.542Z,1560549251.542 [CommandLine](IMPORTANT): got command report clear 2019-06-14T21:54:13.763Z,1560549253.763 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T21:54:13.763Z,1560549253.763 [NAL9602] Communications Fault, FailCount= 3 2019-06-14T21:54:13.763Z,1560549253.763 [NAL9602](ERROR): Communications Fault 2019-06-14T21:54:13.812Z,1560549253.812 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T21:54:14.164Z,1560549254.164 [NAL9602](INFO): Powering down 2019-06-14T21:54:14.739Z,1560549254.739 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20190603T210951/Courier0025.lzma 2019-06-14T21:54:15.026Z,1560549255.026 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T21:54:15.026Z,1560549255.026 [NAL9602] No Fault, FailCount= 3 2019-06-14T21:54:15.543Z,1560549255.543 [DataOverHttps](INFO): Moved sent file to Logs/20190603T210951/Courier0025.lzma.bak 2019-06-14T21:54:15.544Z,1560549255.544 [DataOverHttps](INFO): SBD MOMSN=11378819 2019-06-14T21:54:27.205Z,1560549267.205 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20190603T210951/Courier0028.lzma 2019-06-14T21:54:28.010Z,1560549268.010 [DataOverHttps](INFO): Moved sent file to Logs/20190603T210951/Courier0028.lzma.bak 2019-06-14T21:54:28.010Z,1560549268.010 [DataOverHttps](INFO): SBD MOMSN=11378844 2019-06-14T21:54:42.508Z,1560549282.508 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-06-14T21:54:42.508Z,1560549282.508 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-06-14T21:54:42.543Z,1560549282.543 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-06-14T21:54:42.947Z,1560549282.947 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-06-14T21:54:42.947Z,1560549282.947 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-06-14T21:54:44.467Z,1560549284.467 [NAL9602](INFO): Powering up NAL9602 2019-06-14T21:54:45.860Z,1560549285.860 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20190603T210951/Courier0031.lzma 2019-06-14T21:54:46.663Z,1560549286.663 [DataOverHttps](INFO): Moved sent file to Logs/20190603T210951/Courier0031.lzma.bak 2019-06-14T21:54:46.663Z,1560549286.663 [DataOverHttps](INFO): SBD MOMSN=11378871 2019-06-14T21:54:47.719Z,1560549287.719 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-06-14T21:54:47.719Z,1560549287.719 [DVL_micro] Data Fault, FailCount= 1 2019-06-14T21:54:47.719Z,1560549287.719 [DVL_micro](ERROR): Data Fault 2019-06-14T21:54:47.783Z,1560549287.783 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-06-14T21:54:48.206Z,1560549288.206 [DVL_micro](INFO): uninitialize:Powering down 2019-06-14T21:54:48.996Z,1560549288.996 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-06-14T21:54:48.996Z,1560549288.996 [DVL_micro] No Fault, FailCount= 1 2019-06-14T21:54:49.341Z,1560549289.341 [DVL_micro](INFO): Initializing 2019-06-14T21:55:02.383Z,1560549302.383 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20190610T190906/Courier0000.lzma 2019-06-14T21:55:03.187Z,1560549303.187 [DataOverHttps](INFO): Moved sent file to Logs/20190610T190906/Courier0000.lzma.bak 2019-06-14T21:55:03.187Z,1560549303.187 [DataOverHttps](INFO): SBD MOMSN=11378888 2019-06-14T21:55:05.100Z,1560549305.100 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T21:55:05.100Z,1560549305.100 [NAL9602] Communications Fault, FailCount= 4 2019-06-14T21:55:05.100Z,1560549305.100 [NAL9602](ERROR): Communications Fault 2019-06-14T21:55:05.152Z,1560549305.152 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T21:55:05.534Z,1560549305.534 [NAL9602](INFO): Powering down 2019-06-14T21:55:05.572Z,1560549305.572 [Startup:StartupSatComms:B](INFO): Timed out from 2019-06-14T21:54:05.4Z 2019-06-14T21:55:05.572Z,1560549305.572 [Startup:StartupSatComms:B] Stopped 2019-06-14T21:55:05.572Z,1560549305.572 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-06-14T21:55:05.573Z,1560549305.573 [Startup:StartupSatComms] Stopped 2019-06-14T21:55:05.573Z,1560549305.573 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-06-14T21:55:05.573Z,1560549305.573 [Startup](INFO): Completed Startup 2019-06-14T21:55:05.582Z,1560549305.582 [MissionManager](INFO): Startup is completed. 2019-06-14T21:55:05.582Z,1560549305.582 [MissionManager](INFO): Uninitializing Mission Startup 2019-06-14T21:55:05.582Z,1560549305.582 [Startup] Stopped 2019-06-14T21:55:05.582Z,1560549305.582 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-06-14T21:55:05.582Z,1560549305.582 [Startup:A.GoToSurface] Stopped 2019-06-14T21:55:05.582Z,1560549305.582 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-06-14T21:55:05.944Z,1560549305.944 [MissionManager](IMPORTANT): Started mission Default 2019-06-14T21:55:05.945Z,1560549305.945 [Default] Running Loop=1 2019-06-14T21:55:05.945Z,1560549305.945 [Default](DEBUG): Aggregate::initialize Default 2019-06-14T21:55:05.945Z,1560549305.945 [Default:B.GoToSurface] Running Loop=1 2019-06-14T21:55:05.945Z,1560549305.945 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-06-14T21:55:05.945Z,1560549305.945 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-06-14T21:55:05.945Z,1560549305.945 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-06-14T21:55:05.946Z,1560549305.946 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-06-14T21:55:05.946Z,1560549305.946 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-06-14T21:55:05.946Z,1560549305.946 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-06-14T21:55:05.947Z,1560549305.947 [Default:A.Wait] Running Loop=1 2019-06-14T21:55:05.947Z,1560549305.947 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-06-14T21:55:06.369Z,1560549306.369 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T21:55:06.369Z,1560549306.369 [NAL9602] No Fault, FailCount= 4 2019-06-14T21:55:12.182Z,1560549312.182 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-06-14T21:55:14.530Z,1560549314.530 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20190611T173917/Courier0000.lzma 2019-06-14T21:55:15.331Z,1560549315.331 [DataOverHttps](INFO): Moved sent file to Logs/20190611T173917/Courier0000.lzma.bak 2019-06-14T21:55:15.331Z,1560549315.331 [DataOverHttps](INFO): SBD MOMSN=11378892 2019-06-14T21:55:19.259Z,1560549319.259 [Default:A.Wait](INFO): Done Waiting. 2019-06-14T21:55:19.260Z,1560549319.260 [Default:A.Wait] Stopped 2019-06-14T21:55:19.260Z,1560549319.260 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-06-14T21:55:19.654Z,1560549319.654 [Default:CheckIn] Running Loop=1 2019-06-14T21:55:19.654Z,1560549319.654 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-14T21:55:19.654Z,1560549319.654 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-14T21:55:20.064Z,1560549320.064 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-06-14T21:55:27.790Z,1560549327.790 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-06-14T21:55:35.791Z,1560549335.791 [NAL9602](INFO): Powering up NAL9602 2019-06-14T21:55:56.400Z,1560549356.400 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T21:55:56.400Z,1560549356.400 [NAL9602] Communications Fault, FailCount= 5 2019-06-14T21:55:56.400Z,1560549356.400 [NAL9602](ERROR): Communications Fault 2019-06-14T21:55:56.469Z,1560549356.469 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T21:55:56.469Z,1560549356.469 [CBIT](CRITICAL): Communications Fault in component: NAL9602 2019-06-14T21:55:56.799Z,1560549356.799 [NAL9602](INFO): Powering down 2019-06-14T21:55:56.861Z,1560549356.861 [CBIT](INFO): Critical error at 20190614T215556 2019-06-14T21:56:45.727Z,1560549405.727 [CBIT](FAULT): WATER ALARM BOW. 2019-06-14T21:56:45.728Z,1560549405.728 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1 2019-06-14T21:56:46.123Z,1560549406.123 [CBIT](FAULT): WATER ALARM BOW. 2019-06-14T21:56:46.124Z,1560549406.124 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2 2019-06-14T21:56:51.003Z,1560549411.003 [CBIT](FAULT): WATER ALARM STERN. 2019-06-14T21:56:51.004Z,1560549411.004 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1 2019-06-14T21:56:51.371Z,1560549411.371 [CBIT](FAULT): WATER ALARM STERN. 2019-06-14T21:56:51.372Z,1560549411.372 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2 2019-06-14T21:57:00.309Z,1560549420.309 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-06-14T21:57:10.098Z,1560549430.098 [CommandLine](IMPORTANT): got command failComponent 2019-06-14T21:57:10.099Z,1560549430.099 [CommandLine](IMPORTANT): Failed components: 2019-06-14T21:57:10.099Z,1560549430.099 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2019-06-14T21:57:10.099Z,1560549430.099 [CommandLine](IMPORTANT): NAL9602: Communications Fault 2019-06-14T21:57:43.483Z,1560549463.483 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-06-14T21:57:43.483Z,1560549463.483 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-06-14T21:57:43.493Z,1560549463.493 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-06-14T21:57:43.925Z,1560549463.925 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-06-14T21:57:43.925Z,1560549463.925 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-06-14T21:57:48.467Z,1560549468.467 [CommandLine](IMPORTANT): got command show variable dropweigh 2019-06-14T21:57:48.481Z,1560549468.481 [CommandLine](IMPORTANT): DropWeight.loadAtStartup (bool) 2019-06-14T21:57:48.482Z,1560549468.482 [CommandLine](IMPORTANT): DropWeight.simulateHardware (bool) 2019-06-14T21:57:48.535Z,1560549468.535 [CommandLine](IMPORTANT): DropWeight.dropWeightState (bool) 2019-06-14T21:57:53.559Z,1560549473.559 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-06-14T21:57:53.559Z,1560549473.559 [DVL_micro] Data Fault, FailCount= 1 2019-06-14T21:57:53.559Z,1560549473.559 [DVL_micro](ERROR): Data Fault 2019-06-14T21:57:53.590Z,1560549473.590 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-06-14T21:57:54.030Z,1560549474.030 [DVL_micro](INFO): uninitialize:Powering down 2019-06-14T21:57:54.815Z,1560549474.815 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-06-14T21:57:54.815Z,1560549474.815 [DVL_micro] No Fault, FailCount= 1 2019-06-14T21:57:55.177Z,1560549475.177 [DVL_micro](INFO): Initializing 2019-06-14T21:57:56.421Z,1560549476.421 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T21:57:56.421Z,1560549476.421 [NAL9602] No Fault, FailCount= 5 2019-06-14T21:57:56.787Z,1560549476.787 [NAL9602](INFO): Powering up NAL9602 2019-06-14T21:57:58.602Z,1560549478.602 [CommandLine](IMPORTANT): got command report mod DropWeight.dropWeightState 2019-06-14T21:57:58.890Z,1560549478.890 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-06-14T21:57:58.913Z,1560549478.913 [Reporter](INFO): DropWeight.dropWeightState no_value 2019-06-14T21:58:17.391Z,1560549497.391 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T21:58:17.391Z,1560549497.391 [NAL9602] Communications Fault, FailCount= 1 2019-06-14T21:58:17.392Z,1560549497.392 [NAL9602](ERROR): Communications Fault 2019-06-14T21:58:17.430Z,1560549497.430 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T21:58:17.815Z,1560549497.815 [NAL9602](INFO): Powering down 2019-06-14T21:58:18.653Z,1560549498.653 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T21:58:18.653Z,1560549498.653 [NAL9602] No Fault, FailCount= 1 2019-06-14T21:58:48.100Z,1560549528.100 [NAL9602](INFO): Powering up NAL9602 2019-06-14T21:58:59.979Z,1560549539.979 [CommandLine](IMPORTANT): got command failComponent none DropWeight 2019-06-14T21:58:59.979Z,1560549539.979 [DropWeight] No Fault, FailCount= 1 2019-06-14T21:58:59.979Z,1560549539.979 [CommandLine](IMPORTANT): DropWeight failureMode is No Fault 2019-06-14T21:59:00.272Z,1560549540.272 [Reporter](INFO): DropWeight.dropWeightState 0 bool 2019-06-14T21:59:08.700Z,1560549548.700 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T21:59:08.700Z,1560549548.700 [NAL9602] Communications Fault, FailCount= 2 2019-06-14T21:59:08.700Z,1560549548.700 [NAL9602](ERROR): Communications Fault 2019-06-14T21:59:08.822Z,1560549548.822 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T21:59:09.104Z,1560549549.104 [NAL9602](INFO): Powering down 2019-06-14T21:59:09.969Z,1560549549.969 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T21:59:09.969Z,1560549549.969 [NAL9602] No Fault, FailCount= 2 2019-06-14T21:59:37.457Z,1560549577.457 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2019-06-14T21:59:39.404Z,1560549579.404 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:00:00.008Z,1560549600.008 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:00:00.008Z,1560549600.008 [NAL9602] Communications Fault, FailCount= 3 2019-06-14T22:00:00.008Z,1560549600.008 [NAL9602](ERROR): Communications Fault 2019-06-14T22:00:00.050Z,1560549600.050 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:00:00.415Z,1560549600.415 [NAL9602](INFO): Powering down 2019-06-14T22:00:01.267Z,1560549601.267 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:00:01.267Z,1560549601.267 [NAL9602] No Fault, FailCount= 3 2019-06-14T22:00:15.785Z,1560549615.785 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-06-14T22:00:15.801Z,1560549615.801 [BPC1](INFO): Received data from all battery sticks. 2019-06-14T22:00:19.780Z,1560549619.780 [CommandLine](IMPORTANT): got command report mod Onboard.Humidity 2019-06-14T22:00:19.828Z,1560549619.828 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-14T21:55:19.7Z 2019-06-14T22:00:19.828Z,1560549619.828 [Default:CheckIn:Read_GPS] Stopped 2019-06-14T22:00:19.828Z,1560549619.828 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-14T22:00:19.838Z,1560549619.838 [Reporter](INFO): Onboard.Humidity 36.961300 % 2019-06-14T22:00:20.236Z,1560549620.236 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-06-14T22:00:21.064Z,1560549621.064 [Reporter](INFO): Onboard.Humidity 36.973507 % 2019-06-14T22:00:21.865Z,1560549621.865 [Reporter](INFO): Onboard.Humidity 36.961300 % 2019-06-14T22:00:24.297Z,1560549624.297 [Reporter](INFO): Onboard.Humidity 36.949089 % 2019-06-14T22:00:25.117Z,1560549625.117 [Reporter](INFO): Onboard.Humidity 36.936882 % 2019-06-14T22:00:26.003Z,1560549626.003 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20190611T173917/Courier0004.lzma 2019-06-14T22:00:26.807Z,1560549626.807 [DataOverHttps](INFO): Moved sent file to Logs/20190611T173917/Courier0004.lzma.bak 2019-06-14T22:00:26.807Z,1560549626.807 [DataOverHttps](INFO): SBD MOMSN=11378896 2019-06-14T22:00:27.589Z,1560549627.589 [Reporter](INFO): Onboard.Humidity 36.949089 % 2019-06-14T22:00:29.182Z,1560549629.182 [Reporter](INFO): Onboard.Humidity 36.924675 % 2019-06-14T22:00:30.713Z,1560549630.713 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:00:31.640Z,1560549631.640 [CommandLine](IMPORTANT): got command report mod Onboard.Pressure 2019-06-14T22:00:31.993Z,1560549631.993 [Reporter](INFO): Onboard.Pressure 99643.593750 Pa 2019-06-14T22:00:32.401Z,1560549632.401 [Reporter](INFO): Onboard.Humidity 36.912464 % 2019-06-14T22:00:32.402Z,1560549632.402 [Reporter](INFO): Onboard.Pressure 99812.140625 Pa 2019-06-14T22:00:32.785Z,1560549632.785 [Reporter](INFO): Onboard.Pressure 99893.406250 Pa 2019-06-14T22:00:33.166Z,1560549633.166 [Reporter](INFO): Onboard.Humidity 36.900257 % 2019-06-14T22:00:33.167Z,1560549633.167 [Reporter](INFO): Onboard.Pressure 99765.132812 Pa 2019-06-14T22:00:33.586Z,1560549633.586 [Reporter](INFO): Onboard.Pressure 99538.820312 Pa 2019-06-14T22:00:33.976Z,1560549633.976 [Reporter](INFO): Onboard.Humidity 36.924675 % 2019-06-14T22:00:33.977Z,1560549633.977 [Reporter](INFO): Onboard.Pressure 99643.593750 Pa 2019-06-14T22:00:34.389Z,1560549634.389 [Reporter](INFO): Onboard.Pressure 99684.093750 Pa 2019-06-14T22:00:34.787Z,1560549634.787 [Reporter](INFO): Onboard.Humidity 36.888046 % 2019-06-14T22:00:34.788Z,1560549634.788 [Reporter](INFO): Onboard.Pressure 99538.820312 Pa 2019-06-14T22:00:35.219Z,1560549635.219 [Reporter](INFO): Onboard.Pressure 99771.507812 Pa 2019-06-14T22:00:35.632Z,1560549635.632 [Reporter](INFO): Onboard.Humidity 36.900257 % 2019-06-14T22:00:35.633Z,1560549635.633 [Reporter](INFO): Onboard.Pressure 99910.539062 Pa 2019-06-14T22:00:36.040Z,1560549636.040 [Reporter](INFO): Onboard.Pressure 99724.609375 Pa 2019-06-14T22:00:36.431Z,1560549636.431 [Reporter](INFO): Onboard.Pressure 99684.093750 Pa 2019-06-14T22:00:36.877Z,1560549636.877 [Reporter](INFO): Onboard.Pressure 99812.140625 Pa 2019-06-14T22:00:37.255Z,1560549637.255 [Reporter](INFO): Onboard.Pressure 99619.710938 Pa 2019-06-14T22:00:37.662Z,1560549637.662 [Reporter](INFO): Onboard.Pressure 99498.367188 Pa 2019-06-14T22:00:37.934Z,1560549637.934 [CommandLine](IMPORTANT): got command report clear 2019-06-14T22:00:39.255Z,1560549639.255 [DataOverHttps](INFO): Sending 283 bytes from file Logs/20190611T173917/Courier0007.lzma 2019-06-14T22:00:40.059Z,1560549640.059 [DataOverHttps](INFO): Moved sent file to Logs/20190611T173917/Courier0007.lzma.bak 2019-06-14T22:00:40.059Z,1560549640.059 [DataOverHttps](INFO): SBD MOMSN=11378900 2019-06-14T22:00:44.482Z,1560549644.482 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-06-14T22:00:44.482Z,1560549644.482 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-06-14T22:00:44.498Z,1560549644.498 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-06-14T22:00:44.906Z,1560549644.906 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-06-14T22:00:44.906Z,1560549644.906 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-06-14T22:00:51.324Z,1560549651.324 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:00:51.324Z,1560549651.324 [NAL9602] Communications Fault, FailCount= 4 2019-06-14T22:00:51.324Z,1560549651.324 [NAL9602](ERROR): Communications Fault 2019-06-14T22:00:51.354Z,1560549651.354 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:00:51.399Z,1560549651.399 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190611T173917/Courier0010.lzma 2019-06-14T22:00:51.724Z,1560549651.724 [NAL9602](INFO): Powering down 2019-06-14T22:00:52.203Z,1560549652.203 [DataOverHttps](INFO): Moved sent file to Logs/20190611T173917/Courier0010.lzma.bak 2019-06-14T22:00:52.203Z,1560549652.203 [DataOverHttps](INFO): SBD MOMSN=11378925 2019-06-14T22:00:52.568Z,1560549652.568 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:00:52.568Z,1560549652.568 [NAL9602] No Fault, FailCount= 4 2019-06-14T22:00:59.399Z,1560549659.399 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-06-14T22:00:59.399Z,1560549659.399 [DVL_micro] Data Fault, FailCount= 1 2019-06-14T22:00:59.399Z,1560549659.399 [DVL_micro](ERROR): Data Fault 2019-06-14T22:00:59.453Z,1560549659.453 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-06-14T22:00:59.866Z,1560549659.866 [DVL_micro](INFO): uninitialize:Powering down 2019-06-14T22:01:00.680Z,1560549660.680 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-06-14T22:01:00.680Z,1560549660.680 [DVL_micro] No Fault, FailCount= 1 2019-06-14T22:01:01.017Z,1560549661.017 [DVL_micro](INFO): Initializing 2019-06-14T22:01:03.575Z,1560549663.575 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20190611T204724/Courier0000.lzma 2019-06-14T22:01:04.387Z,1560549664.387 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0000.lzma.bak 2019-06-14T22:01:04.387Z,1560549664.387 [DataOverHttps](INFO): SBD MOMSN=11378935 2019-06-14T22:01:16.159Z,1560549676.159 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20190611T204724/Courier0004.lzma 2019-06-14T22:01:16.963Z,1560549676.963 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0004.lzma.bak 2019-06-14T22:01:16.963Z,1560549676.963 [DataOverHttps](INFO): SBD MOMSN=11378942 2019-06-14T22:01:22.023Z,1560549682.023 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:01:28.223Z,1560549688.223 [DataOverHttps](INFO): Sending 437 bytes from file Logs/20190611T204724/Courier0007.lzma 2019-06-14T22:01:29.027Z,1560549689.027 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0007.lzma.bak 2019-06-14T22:01:29.027Z,1560549689.027 [DataOverHttps](INFO): SBD MOMSN=11378949 2019-06-14T22:01:40.415Z,1560549700.415 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20190611T204724/Courier0010.lzma 2019-06-14T22:01:41.219Z,1560549701.219 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0010.lzma.bak 2019-06-14T22:01:41.219Z,1560549701.219 [DataOverHttps](INFO): SBD MOMSN=11378978 2019-06-14T22:01:42.628Z,1560549702.628 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:01:42.628Z,1560549702.628 [NAL9602] Communications Fault, FailCount= 5 2019-06-14T22:01:42.628Z,1560549702.628 [NAL9602](ERROR): Communications Fault 2019-06-14T22:01:42.658Z,1560549702.658 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:01:42.659Z,1560549702.659 [CBIT](CRITICAL): Communications Fault in component: NAL9602 2019-06-14T22:01:43.047Z,1560549703.047 [NAL9602](INFO): Powering down 2019-06-14T22:01:43.091Z,1560549703.091 [CBIT](INFO): Critical error at 20190614T220142 2019-06-14T22:01:53.875Z,1560549713.875 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20190611T204724/Courier0013.lzma 2019-06-14T22:01:54.679Z,1560549714.679 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0013.lzma.bak 2019-06-14T22:01:54.679Z,1560549714.679 [DataOverHttps](INFO): SBD MOMSN=11379012 2019-06-14T22:02:06.027Z,1560549726.027 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20190611T204724/Courier0016.lzma 2019-06-14T22:02:06.831Z,1560549726.831 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0016.lzma.bak 2019-06-14T22:02:06.832Z,1560549726.832 [DataOverHttps](INFO): SBD MOMSN=11379037 2019-06-14T22:02:18.099Z,1560549738.099 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20190611T204724/Courier0019.lzma 2019-06-14T22:02:18.903Z,1560549738.903 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0019.lzma.bak 2019-06-14T22:02:18.904Z,1560549738.904 [DataOverHttps](INFO): SBD MOMSN=11379063 2019-06-14T22:02:30.167Z,1560549750.167 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20190611T204724/Courier0022.lzma 2019-06-14T22:02:30.971Z,1560549750.971 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0022.lzma.bak 2019-06-14T22:02:30.971Z,1560549750.971 [DataOverHttps](INFO): SBD MOMSN=11379088 2019-06-14T22:02:42.339Z,1560549762.339 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20190611T204724/Courier0025.lzma 2019-06-14T22:02:43.143Z,1560549763.143 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0025.lzma.bak 2019-06-14T22:02:43.144Z,1560549763.144 [DataOverHttps](INFO): SBD MOMSN=11379114 2019-06-14T22:02:54.463Z,1560549774.463 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20190611T204724/Courier0028.lzma 2019-06-14T22:02:55.267Z,1560549775.267 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0028.lzma.bak 2019-06-14T22:02:55.267Z,1560549775.267 [DataOverHttps](INFO): SBD MOMSN=11379139 2019-06-14T22:03:42.665Z,1560549822.665 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:03:42.665Z,1560549822.665 [NAL9602] No Fault, FailCount= 5 2019-06-14T22:03:43.015Z,1560549823.015 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:03:45.474Z,1560549825.474 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-06-14T22:03:45.474Z,1560549825.474 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-06-14T22:03:45.509Z,1560549825.509 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-06-14T22:03:45.986Z,1560549825.986 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-06-14T22:03:45.986Z,1560549825.986 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-06-14T22:04:03.620Z,1560549843.620 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:04:03.620Z,1560549843.620 [NAL9602] Communications Fault, FailCount= 1 2019-06-14T22:04:03.620Z,1560549843.620 [NAL9602](ERROR): Communications Fault 2019-06-14T22:04:03.700Z,1560549843.700 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:04:04.024Z,1560549844.024 [NAL9602](INFO): Powering down 2019-06-14T22:04:04.868Z,1560549844.868 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:04:04.868Z,1560549844.868 [NAL9602] No Fault, FailCount= 1 2019-06-14T22:04:05.235Z,1560549845.235 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-06-14T22:04:05.235Z,1560549845.235 [DVL_micro] Data Fault, FailCount= 1 2019-06-14T22:04:05.235Z,1560549845.235 [DVL_micro](ERROR): Data Fault 2019-06-14T22:04:05.298Z,1560549845.298 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-06-14T22:04:05.710Z,1560549845.710 [DVL_micro](INFO): uninitialize:Powering down 2019-06-14T22:04:06.480Z,1560549846.480 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-06-14T22:04:06.480Z,1560549846.480 [DVL_micro] No Fault, FailCount= 1 2019-06-14T22:04:06.857Z,1560549846.857 [DVL_micro](INFO): Initializing 2019-06-14T22:04:10.223Z,1560549850.223 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20190611T204724/Courier0031.lzma 2019-06-14T22:04:11.027Z,1560549851.027 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0031.lzma.bak 2019-06-14T22:04:11.028Z,1560549851.028 [DataOverHttps](INFO): SBD MOMSN=11379170 2019-06-14T22:04:22.351Z,1560549862.351 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20190611T204724/Courier0034.lzma 2019-06-14T22:04:23.156Z,1560549863.156 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0034.lzma.bak 2019-06-14T22:04:23.156Z,1560549863.156 [DataOverHttps](INFO): SBD MOMSN=11379196 2019-06-14T22:04:34.340Z,1560549874.340 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:04:37.395Z,1560549877.395 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20190611T204724/Courier0037.lzma 2019-06-14T22:04:38.199Z,1560549878.199 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0037.lzma.bak 2019-06-14T22:04:38.200Z,1560549878.200 [DataOverHttps](INFO): SBD MOMSN=11379221 2019-06-14T22:04:50.179Z,1560549890.179 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20190611T204724/Courier0040.lzma 2019-06-14T22:04:50.983Z,1560549890.983 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0040.lzma.bak 2019-06-14T22:04:50.984Z,1560549890.984 [DataOverHttps](INFO): SBD MOMSN=11379247 2019-06-14T22:04:54.944Z,1560549894.944 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:04:54.944Z,1560549894.944 [NAL9602] Communications Fault, FailCount= 2 2019-06-14T22:04:54.944Z,1560549894.944 [NAL9602](ERROR): Communications Fault 2019-06-14T22:04:55.020Z,1560549895.020 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:04:55.386Z,1560549895.386 [NAL9602](INFO): Powering down 2019-06-14T22:04:56.227Z,1560549896.227 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:04:56.227Z,1560549896.227 [NAL9602] No Fault, FailCount= 2 2019-06-14T22:05:02.747Z,1560549902.747 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20190611T204724/Courier0043.lzma 2019-06-14T22:05:03.235Z,1560549903.235 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0043.lzma.bak 2019-06-14T22:05:03.235Z,1560549903.235 [DataOverHttps](INFO): SBD MOMSN=11379272 2019-06-14T22:05:14.607Z,1560549914.607 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20190611T204724/Courier0046.lzma 2019-06-14T22:05:15.411Z,1560549915.411 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0046.lzma.bak 2019-06-14T22:05:15.411Z,1560549915.411 [DataOverHttps](INFO): SBD MOMSN=11379298 2019-06-14T22:05:25.664Z,1560549925.664 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:05:26.339Z,1560549926.339 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20190611T204724/Courier0049.lzma 2019-06-14T22:05:27.143Z,1560549927.143 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0049.lzma.bak 2019-06-14T22:05:27.144Z,1560549927.144 [DataOverHttps](INFO): SBD MOMSN=11379323 2019-06-14T22:05:38.479Z,1560549938.479 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20190611T204724/Courier0052.lzma 2019-06-14T22:05:39.283Z,1560549939.283 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0052.lzma.bak 2019-06-14T22:05:39.284Z,1560549939.284 [DataOverHttps](INFO): SBD MOMSN=11379349 2019-06-14T22:05:46.252Z,1560549946.252 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:05:46.252Z,1560549946.252 [NAL9602] Communications Fault, FailCount= 3 2019-06-14T22:05:46.252Z,1560549946.252 [NAL9602](ERROR): Communications Fault 2019-06-14T22:05:46.282Z,1560549946.282 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:05:46.660Z,1560549946.660 [NAL9602](INFO): Powering down 2019-06-14T22:05:47.532Z,1560549947.532 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:05:47.532Z,1560549947.532 [NAL9602] No Fault, FailCount= 3 2019-06-14T22:05:50.623Z,1560549950.623 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20190611T204724/Courier0055.lzma 2019-06-14T22:05:51.427Z,1560549951.427 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0055.lzma.bak 2019-06-14T22:05:51.427Z,1560549951.427 [DataOverHttps](INFO): SBD MOMSN=11379374 2019-06-14T22:06:02.743Z,1560549962.743 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20190611T204724/Courier0058.lzma 2019-06-14T22:06:03.547Z,1560549963.547 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0058.lzma.bak 2019-06-14T22:06:03.548Z,1560549963.548 [DataOverHttps](INFO): SBD MOMSN=11379400 2019-06-14T22:06:14.803Z,1560549974.803 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20190611T204724/Courier0061.lzma 2019-06-14T22:06:15.607Z,1560549975.607 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0061.lzma.bak 2019-06-14T22:06:15.608Z,1560549975.608 [DataOverHttps](INFO): SBD MOMSN=11379425 2019-06-14T22:06:16.985Z,1560549976.985 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:06:26.983Z,1560549986.983 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20190611T204724/Courier0064.lzma 2019-06-14T22:06:27.787Z,1560549987.787 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0064.lzma.bak 2019-06-14T22:06:27.788Z,1560549987.788 [DataOverHttps](INFO): SBD MOMSN=11379451 2019-06-14T22:06:37.576Z,1560549997.576 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:06:37.576Z,1560549997.576 [NAL9602] Communications Fault, FailCount= 4 2019-06-14T22:06:37.576Z,1560549997.576 [NAL9602](ERROR): Communications Fault 2019-06-14T22:06:37.652Z,1560549997.652 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:06:37.983Z,1560549997.983 [NAL9602](INFO): Powering down 2019-06-14T22:06:38.912Z,1560549998.912 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:06:38.912Z,1560549998.912 [NAL9602] No Fault, FailCount= 4 2019-06-14T22:06:39.395Z,1560549999.395 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20190611T204724/Courier0067.lzma 2019-06-14T22:06:40.199Z,1560550000.199 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0067.lzma.bak 2019-06-14T22:06:40.200Z,1560550000.200 [DataOverHttps](INFO): SBD MOMSN=11379476 2019-06-14T22:06:46.491Z,1560550006.491 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-06-14T22:06:46.491Z,1560550006.491 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-06-14T22:06:46.500Z,1560550006.500 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-06-14T22:06:46.903Z,1560550006.903 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-06-14T22:06:46.904Z,1560550006.904 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-06-14T22:06:51.138Z,1560550011.138 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20190611T204724/Courier0070.lzma 2019-06-14T22:06:51.943Z,1560550011.943 [DataOverHttps](INFO): Moved sent file to Logs/20190611T204724/Courier0070.lzma.bak 2019-06-14T22:06:51.944Z,1560550011.944 [DataOverHttps](INFO): SBD MOMSN=11379502 2019-06-14T22:07:03.229Z,1560550023.229 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20190614T211703/Courier0000.lzma 2019-06-14T22:07:04.035Z,1560550024.035 [DataOverHttps](INFO): Moved sent file to Logs/20190614T211703/Courier0000.lzma.bak 2019-06-14T22:07:04.035Z,1560550024.035 [DataOverHttps](INFO): SBD MOMSN=11379517 2019-06-14T22:07:08.281Z,1560550028.281 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:07:11.107Z,1560550031.107 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-06-14T22:07:11.107Z,1560550031.107 [DVL_micro] Data Fault, FailCount= 1 2019-06-14T22:07:11.107Z,1560550031.107 [DVL_micro](ERROR): Data Fault 2019-06-14T22:07:11.169Z,1560550031.169 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-06-14T22:07:11.582Z,1560550031.582 [DVL_micro](INFO): uninitialize:Powering down 2019-06-14T22:07:12.397Z,1560550032.397 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-06-14T22:07:12.397Z,1560550032.397 [DVL_micro] No Fault, FailCount= 1 2019-06-14T22:07:12.729Z,1560550032.729 [DVL_micro](INFO): Initializing 2019-06-14T22:07:15.046Z,1560550035.046 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20190614T211703/Courier0004.lzma 2019-06-14T22:07:15.851Z,1560550035.851 [DataOverHttps](INFO): Moved sent file to Logs/20190614T211703/Courier0004.lzma.bak 2019-06-14T22:07:15.851Z,1560550035.851 [DataOverHttps](INFO): SBD MOMSN=11379523 2019-06-14T22:07:27.157Z,1560550047.157 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20190614T211703/Courier0007.lzma 2019-06-14T22:07:27.963Z,1560550047.963 [DataOverHttps](INFO): Moved sent file to Logs/20190614T211703/Courier0007.lzma.bak 2019-06-14T22:07:27.963Z,1560550047.963 [DataOverHttps](INFO): SBD MOMSN=11379527 2019-06-14T22:07:28.908Z,1560550048.908 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:07:28.908Z,1560550048.908 [NAL9602] Communications Fault, FailCount= 5 2019-06-14T22:07:28.908Z,1560550048.908 [NAL9602](ERROR): Communications Fault 2019-06-14T22:07:28.969Z,1560550048.969 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:07:28.969Z,1560550048.969 [CBIT](CRITICAL): Communications Fault in component: NAL9602 2019-06-14T22:07:29.287Z,1560550049.287 [NAL9602](INFO): Powering down 2019-06-14T22:07:29.323Z,1560550049.323 [CBIT](INFO): Critical error at 20190614T220728 2019-06-14T22:07:39.301Z,1560550059.301 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190614T211703/Courier0010.lzma 2019-06-14T22:07:40.107Z,1560550060.107 [DataOverHttps](INFO): Moved sent file to Logs/20190614T211703/Courier0010.lzma.bak 2019-06-14T22:07:40.107Z,1560550060.107 [DataOverHttps](INFO): SBD MOMSN=11379532 2019-06-14T22:07:51.405Z,1560550071.405 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20190614T213821/Courier0000.lzma 2019-06-14T22:07:52.211Z,1560550072.211 [DataOverHttps](INFO): Moved sent file to Logs/20190614T213821/Courier0000.lzma.bak 2019-06-14T22:07:52.211Z,1560550072.211 [DataOverHttps](INFO): SBD MOMSN=11379534 2019-06-14T22:08:03.505Z,1560550083.505 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20190614T213821/Courier0004.lzma 2019-06-14T22:08:04.311Z,1560550084.311 [DataOverHttps](INFO): Moved sent file to Logs/20190614T213821/Courier0004.lzma.bak 2019-06-14T22:08:04.311Z,1560550084.311 [DataOverHttps](INFO): SBD MOMSN=11379538 2019-06-14T22:08:07.675Z,1560550087.675 [DVL_micro](ERROR): only read 44 of 46 data items 2019-06-14T22:08:07.675Z,1560550087.675 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 -1262 -235 999 2 2 -3017.6 114.8 -220.8 2 -1636.7 2537.7 -220.8 2 -3017 114 -220 2 -1636 2537 -220 2 0.65 -1.64 305.0 -3.0 23.1 .0 1489 124 2019-06-14T22:08:15.565Z,1560550095.565 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20190614T214427/Courier0000.lzma 2019-06-14T22:08:16.371Z,1560550096.371 [DataOverHttps](INFO): Moved sent file to Logs/20190614T214427/Courier0000.lzma.bak 2019-06-14T22:08:16.371Z,1560550096.371 [DataOverHttps](INFO): SBD MOMSN=11379541 2019-06-14T22:08:27.797Z,1560550107.797 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190614T214427/Courier0004.lzma 2019-06-14T22:08:28.603Z,1560550108.603 [DataOverHttps](INFO): Moved sent file to Logs/20190614T214427/Courier0004.lzma.bak 2019-06-14T22:08:28.603Z,1560550108.603 [DataOverHttps](INFO): SBD MOMSN=11379545 2019-06-14T22:08:39.469Z,1560550119.469 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20190614T214734/Courier0000.lzma 2019-06-14T22:08:40.275Z,1560550120.275 [DataOverHttps](INFO): Moved sent file to Logs/20190614T214734/Courier0000.lzma.bak 2019-06-14T22:08:40.275Z,1560550120.275 [DataOverHttps](INFO): SBD MOMSN=11379547 2019-06-14T22:08:51.573Z,1560550131.573 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190614T214734/Courier0004.lzma 2019-06-14T22:08:52.379Z,1560550132.379 [DataOverHttps](INFO): Moved sent file to Logs/20190614T214734/Courier0004.lzma.bak 2019-06-14T22:08:52.379Z,1560550132.379 [DataOverHttps](INFO): SBD MOMSN=11379551 2019-06-14T22:09:03.754Z,1560550143.754 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20190614T215131/Courier0000.lzma 2019-06-14T22:09:04.559Z,1560550144.559 [DataOverHttps](INFO): Moved sent file to Logs/20190614T215131/Courier0000.lzma.bak 2019-06-14T22:09:04.559Z,1560550144.559 [DataOverHttps](INFO): SBD MOMSN=11379553 2019-06-14T22:09:15.886Z,1560550155.886 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20190614T215131/Courier0004.lzma 2019-06-14T22:09:16.691Z,1560550156.691 [DataOverHttps](INFO): Moved sent file to Logs/20190614T215131/Courier0004.lzma.bak 2019-06-14T22:09:16.691Z,1560550156.691 [DataOverHttps](INFO): SBD MOMSN=11379559 2019-06-14T22:09:28.732Z,1560550168.732 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20190527T062207/Express0100.lzma 2019-06-14T22:09:28.926Z,1560550168.926 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:09:28.926Z,1560550168.926 [NAL9602] No Fault, FailCount= 5 2019-06-14T22:09:29.291Z,1560550169.291 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:09:29.536Z,1560550169.536 [DataOverHttps](INFO): Moved sent file to Logs/20190527T062207/Express0100.lzma.bak 2019-06-14T22:09:29.536Z,1560550169.536 [DataOverHttps](INFO): SBD MOMSN=11379562 2019-06-14T22:09:44.428Z,1560550184.428 [DataOverHttps](INFO): Sending 905 bytes from file Logs/20190527T122829/Express0001.lzma 2019-06-14T22:09:45.231Z,1560550185.231 [DataOverHttps](INFO): Moved sent file to Logs/20190527T122829/Express0001.lzma.bak 2019-06-14T22:09:45.231Z,1560550185.231 [DataOverHttps](INFO): SBD MOMSN=11379573 2019-06-14T22:09:47.495Z,1560550187.495 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2019-06-14T22:09:47.495Z,1560550187.495 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-06-14T22:09:47.505Z,1560550187.505 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-06-14T22:09:47.940Z,1560550187.940 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-06-14T22:09:47.940Z,1560550187.940 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2019-06-14T22:09:49.895Z,1560550189.895 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:09:49.896Z,1560550189.896 [NAL9602] Communications Fault, FailCount= 1 2019-06-14T22:09:49.896Z,1560550189.896 [NAL9602](ERROR): Communications Fault 2019-06-14T22:09:49.944Z,1560550189.944 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:09:50.296Z,1560550190.296 [NAL9602](INFO): Powering down 2019-06-14T22:09:51.184Z,1560550191.184 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:09:51.185Z,1560550191.185 [NAL9602] No Fault, FailCount= 1 2019-06-14T22:09:57.111Z,1560550197.111 [DataOverHttps](INFO): Sending 368 bytes from file Logs/20190527T122829/Express0005.lzma 2019-06-14T22:09:57.915Z,1560550197.915 [DataOverHttps](INFO): Moved sent file to Logs/20190527T122829/Express0005.lzma.bak 2019-06-14T22:09:57.915Z,1560550197.915 [DataOverHttps](INFO): SBD MOMSN=11379601 2019-06-14T22:10:10.127Z,1560550210.127 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20190527T122829/Express0008.lzma 2019-06-14T22:10:10.931Z,1560550210.931 [DataOverHttps](INFO): Moved sent file to Logs/20190527T122829/Express0008.lzma.bak 2019-06-14T22:10:10.931Z,1560550210.931 [DataOverHttps](INFO): SBD MOMSN=11379614 2019-06-14T22:10:16.959Z,1560550216.959 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2019-06-14T22:10:16.959Z,1560550216.959 [DVL_micro] Data Fault, FailCount= 1 2019-06-14T22:10:16.959Z,1560550216.959 [DVL_micro](ERROR): Data Fault 2019-06-14T22:10:16.990Z,1560550216.990 [CBIT](ERROR): Data Fault in component: DVL_micro 2019-06-14T22:10:17.434Z,1560550217.434 [DVL_micro](INFO): uninitialize:Powering down 2019-06-14T22:10:18.210Z,1560550218.210 [CBIT](INFO): Clearing failed state for component DVL_micro 2019-06-14T22:10:18.210Z,1560550218.210 [DVL_micro] No Fault, FailCount= 1 2019-06-14T22:10:18.581Z,1560550218.581 [DVL_micro](INFO): Initializing 2019-06-14T22:10:20.595Z,1560550220.595 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:10:22.740Z,1560550222.740 [DataOverHttps](INFO): Sending 972 bytes from file Logs/20190527T124111/Express0001.lzma 2019-06-14T22:10:23.543Z,1560550223.543 [DataOverHttps](INFO): Moved sent file to Logs/20190527T124111/Express0001.lzma.bak 2019-06-14T22:10:23.543Z,1560550223.543 [DataOverHttps](INFO): SBD MOMSN=11379689 2019-06-14T22:10:34.978Z,1560550234.978 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20190527T124111/Express0005.lzma 2019-06-14T22:10:35.783Z,1560550235.783 [DataOverHttps](INFO): Moved sent file to Logs/20190527T124111/Express0005.lzma.bak 2019-06-14T22:10:35.783Z,1560550235.783 [DataOverHttps](INFO): SBD MOMSN=11379731 2019-06-14T22:10:41.231Z,1560550241.231 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:10:41.231Z,1560550241.231 [NAL9602] Communications Fault, FailCount= 2 2019-06-14T22:10:41.231Z,1560550241.231 [NAL9602](ERROR): Communications Fault 2019-06-14T22:10:41.273Z,1560550241.273 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:10:41.603Z,1560550241.603 [NAL9602](INFO): Powering down 2019-06-14T22:10:42.466Z,1560550242.466 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:10:42.466Z,1560550242.466 [NAL9602] No Fault, FailCount= 2 2019-06-14T22:10:47.709Z,1560550247.709 [DataOverHttps](INFO): Sending 845 bytes from file Logs/20190528T143350/Express0001.lzma 2019-06-14T22:10:48.451Z,1560550248.451 [DataOverHttps](INFO): Moved sent file to Logs/20190528T143350/Express0001.lzma.bak 2019-06-14T22:10:48.451Z,1560550248.451 [DataOverHttps](INFO): SBD MOMSN=11379739 2019-06-14T22:11:00.363Z,1560550260.363 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20190528T143350/Express0005.lzma 2019-06-14T22:11:01.167Z,1560550261.167 [DataOverHttps](INFO): Moved sent file to Logs/20190528T143350/Express0005.lzma.bak 2019-06-14T22:11:01.167Z,1560550261.167 [DataOverHttps](INFO): SBD MOMSN=11379760 2019-06-14T22:11:11.904Z,1560550271.904 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:11:12.996Z,1560550272.996 [DataOverHttps](INFO): Sending 962 bytes from file Logs/20190528T144719/Express0001.lzma 2019-06-14T22:11:13.799Z,1560550273.799 [DataOverHttps](INFO): Moved sent file to Logs/20190528T144719/Express0001.lzma.bak 2019-06-14T22:11:13.799Z,1560550273.799 [DataOverHttps](INFO): SBD MOMSN=11379766 2019-06-14T22:11:25.706Z,1560550285.706 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190528T144719/Express0005.lzma 2019-06-14T22:11:26.499Z,1560550286.499 [DataOverHttps](INFO): Moved sent file to Logs/20190528T144719/Express0005.lzma.bak 2019-06-14T22:11:26.499Z,1560550286.499 [DataOverHttps](INFO): SBD MOMSN=11379793 2019-06-14T22:11:32.508Z,1560550292.508 [NAL9602](ERROR): NAL9602 initialization error. 2019-06-14T22:11:32.508Z,1560550292.508 [NAL9602] Communications Fault, FailCount= 3 2019-06-14T22:11:32.508Z,1560550292.508 [NAL9602](ERROR): Communications Fault 2019-06-14T22:11:32.578Z,1560550292.578 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-06-14T22:11:32.927Z,1560550292.927 [NAL9602](INFO): Powering down 2019-06-14T22:11:33.761Z,1560550293.761 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-14T22:11:33.761Z,1560550293.761 [NAL9602] No Fault, FailCount= 3 2019-06-14T22:11:38.420Z,1560550298.420 [DataOverHttps](INFO): Sending 850 bytes from file Logs/20190528T151411/Express0001.lzma 2019-06-14T22:11:39.223Z,1560550299.223 [DataOverHttps](INFO): Moved sent file to Logs/20190528T151411/Express0001.lzma.bak 2019-06-14T22:11:39.223Z,1560550299.223 [DataOverHttps](INFO): SBD MOMSN=11379796 2019-06-14T22:11:51.037Z,1560550311.037 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190528T151411/Express0005.lzma 2019-06-14T22:11:51.843Z,1560550311.843 [DataOverHttps](INFO): Moved sent file to Logs/20190528T151411/Express0005.lzma.bak 2019-06-14T22:11:51.843Z,1560550311.843 [DataOverHttps](INFO): SBD MOMSN=11379817 2019-06-14T22:12:03.215Z,1560550323.215 [NAL9602](INFO): Powering up NAL9602 2019-06-14T22:12:04.189Z,1560550324.189 [DataOverHttps](INFO): Sending 1412 bytes from file Logs/20190529T103714/Express0001.lzma 2019-06-14T22:12:04.991Z,1560550324.991 [DataOverHttps](INFO): Moved sent file to Logs/20190529T103714/Express0001.lzma.bak 2019-06-14T22:12:04.991Z,1560550324.991 [DataOverHttps](INFO): SBD MOMSN=11379819 2019-06-14T22:12:10.835Z,1560550330.835 [CommandLine](IMPORTANT): got command quit 2019-06-14T22:12:11.842Z,1560550331.842 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-06-14T22:12:11.842Z,1560550331.842 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:11.954Z,1560550331.954 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-06-14T22:12:11.954Z,1560550331.954 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:11.954Z,1560550331.954 [CommandLine](INFO): Join timeout helper Thread ID is 1666 2019-06-14T22:12:11.955Z,1560550331.955 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-06-14T22:12:11.955Z,1560550331.955 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:11.955Z,1560550331.955 [NavChartDb](INFO): Join timeout helper Thread ID is 1667 2019-06-14T22:12:12.298Z,1560550332.298 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-06-14T22:12:12.298Z,1560550332.298 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:12.314Z,1560550332.314 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-06-14T22:12:12.314Z,1560550332.314 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:12.314Z,1560550332.314 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1668 2019-06-14T22:12:12.318Z,1560550332.318 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-06-14T22:12:12.318Z,1560550332.318 [WetLabsBB2FL](INFO): Powering down 2019-06-14T22:12:12.319Z,1560550332.319 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:12.334Z,1560550332.334 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-06-14T22:12:12.334Z,1560550332.334 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:12.334Z,1560550332.334 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1669 2019-06-14T22:12:12.686Z,1560550332.686 [CTD_Seabird](INFO): Powering down 2019-06-14T22:12:12.698Z,1560550332.698 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-06-14T22:12:12.698Z,1560550332.698 [CTD_Seabird](INFO): Powering down 2019-06-14T22:12:12.710Z,1560550332.710 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:12.722Z,1560550332.722 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-06-14T22:12:12.722Z,1560550332.722 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:12.722Z,1560550332.722 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1670 2019-06-14T22:12:12.830Z,1560550332.830 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-06-14T22:12:12.830Z,1560550332.830 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:12.847Z,1560550332.847 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-06-14T22:12:12.847Z,1560550332.847 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:12.847Z,1560550332.847 [Radio_Surface](INFO): Join timeout helper Thread ID is 1671 2019-06-14T22:12:13.162Z,1560550333.162 [Radio_Surface](INFO): Powering down 2019-06-14T22:12:13.163Z,1560550333.163 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-06-14T22:12:13.163Z,1560550333.163 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:13.174Z,1560550333.174 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-06-14T22:12:13.175Z,1560550333.175 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:13.175Z,1560550333.175 [DataOverHttps](INFO): Join timeout helper Thread ID is 1672 2019-06-14T22:12:16.342Z,1560550336.342 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-06-14T22:12:16.345Z,1560550336.345 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.347Z,1560550336.347 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-06-14T22:12:16.347Z,1560550336.347 [logger ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.347Z,1560550336.347 [logger](INFO): Join timeout helper Thread ID is 1673 2019-06-14T22:12:16.426Z,1560550336.426 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-06-14T22:12:16.426Z,1560550336.426 [logger ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.446Z,1560550336.446 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-06-14T22:12:16.446Z,1560550336.446 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.447Z,1560550336.447 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-06-14T22:12:16.447Z,1560550336.447 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.447Z,1560550336.447 [controlThread](INFO): Join timeout helper Thread ID is 1674 2019-06-14T22:12:16.534Z,1560550336.534 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-06-14T22:12:16.534Z,1560550336.534 [controlThread](DEBUG): Uninitializing ControlThread 2019-06-14T22:12:16.534Z,1560550336.534 [AHRS_M2](INFO): Powering down 2019-06-14T22:12:16.678Z,1560550336.678 [DVL_micro](INFO): uninitialize:Powering down 2019-06-14T22:12:16.679Z,1560550336.679 [NAL9602](INFO): Powering down 2019-06-14T22:12:16.680Z,1560550336.681 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-06-14T22:12:16.681Z,1560550336.681 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-06-14T22:12:16.682Z,1560550336.682 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-06-14T22:12:16.683Z,1560550336.683 [MissionManager](INFO): Uninitializing Mission Default 2019-06-14T22:12:16.683Z,1560550336.683 [Default] Stopped 2019-06-14T22:12:16.683Z,1560550336.683 [Default](DEBUG): Aggregate::uninitialize Default 2019-06-14T22:12:16.683Z,1560550336.683 [Default:B.GoToSurface] Stopped 2019-06-14T22:12:16.683Z,1560550336.683 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-06-14T22:12:16.683Z,1560550336.683 [Default:CheckIn] Stopped 2019-06-14T22:12:16.683Z,1560550336.683 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-14T22:12:16.683Z,1560550336.683 [Default:CheckIn:Read_Iridium] Stopped 2019-06-14T22:12:16.686Z,1560550336.686 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-06-14T22:12:16.686Z,1560550336.686 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-06-14T22:12:16.686Z,1560550336.686 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-06-14T22:12:16.687Z,1560550336.687 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-06-14T22:12:16.687Z,1560550336.687 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-06-14T22:12:16.687Z,1560550336.687 [BuoyancyServo](INFO): Powering down 2019-06-14T22:12:16.702Z,1560550336.702 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-06-14T22:12:16.702Z,1560550336.702 [ElevatorServo](INFO): Powering down 2019-06-14T22:12:16.703Z,1560550336.703 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-06-14T22:12:16.703Z,1560550336.703 [MassServo](INFO): Powering down 2019-06-14T22:12:16.704Z,1560550336.704 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-06-14T22:12:16.704Z,1560550336.704 [RudderServo](INFO): Powering down 2019-06-14T22:12:16.705Z,1560550336.705 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-06-14T22:12:16.705Z,1560550336.705 [ThrusterServo](INFO): Powering down 2019-06-14T22:12:16.706Z,1560550336.706 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-06-14T22:12:16.706Z,1560550336.706 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-06-14T22:12:16.706Z,1560550336.706 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-06-14T22:12:16.706Z,1560550336.706 [CBIT](DEBUG): Powering off loads. 2019-06-14T22:12:16.718Z,1560550336.718 [CBIT](DEBUG): Disabling WDT. 2019-06-14T22:12:16.730Z,1560550336.730 [CBIT](DEBUG): Opening all GF detection circuits. 2019-06-14T22:12:16.730Z,1560550336.730 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.815Z,1560550336.815 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.824Z,1560550336.824 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.876Z,1560550336.876 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.879Z,1560550336.879 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.882Z,1560550336.882 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.891Z,1560550336.891 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-06-14T22:12:16.950Z,1560550336.950 [logger ThreadHandler](INFO): Thread cancelled.