2019-09-10T00:08:19.684Z,1568074099.684 [Supervisor](DEBUG): Initializing supervisor. 2019-09-10T00:08:19.687Z,1568074099.687 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-09-10T00:08:19.687Z,1568074099.687 [SyncHandler](INFO): Protected caller Thread ID is 7132 2019-09-10T00:08:19.688Z,1568074099.688 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-09-10T00:08:19.689Z,1568074099.689 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-09-10T00:08:19.689Z,1568074099.689 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7133 2019-09-10T00:08:19.692Z,1568074099.692 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-09-10T00:08:19.704Z,1568074099.704 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-09-10T00:08:19.705Z,1568074099.705 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-09-10T00:08:19.705Z,1568074099.705 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7134 2019-09-10T00:08:19.706Z,1568074099.706 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-09-10T00:08:19.707Z,1568074099.707 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-09-10T00:08:19.707Z,1568074099.707 [logger ThreadHandler](INFO): Protected caller Thread ID is 7135 2019-09-10T00:08:19.710Z,1568074099.710 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-09-10T00:08:19.710Z,1568074099.710 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-09-10T00:08:19.711Z,1568074099.711 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-09-10T00:08:19.872Z,1568074099.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-09-10T00:08:19.873Z,1568074099.873 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-09-10T00:08:20.424Z,1568074100.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-09-10T00:08:20.425Z,1568074100.425 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-09-10T00:08:20.525Z,1568074100.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-09-10T00:08:20.526Z,1568074100.526 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-09-10T00:08:20.630Z,1568074100.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-09-10T00:08:20.631Z,1568074100.631 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-09-10T00:08:20.713Z,1568074100.713 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-09-10T00:08:20.853Z,1568074100.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-09-10T00:08:20.854Z,1568074100.854 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-09-10T00:08:21.156Z,1568074101.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-09-10T00:08:21.156Z,1568074101.156 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-09-10T00:08:21.627Z,1568074101.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-09-10T00:08:21.628Z,1568074101.628 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-09-10T00:08:21.778Z,1568074101.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-09-10T00:08:21.779Z,1568074101.779 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-09-10T00:08:22.010Z,1568074102.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-09-10T00:08:22.011Z,1568074102.011 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-09-10T00:08:22.525Z,1568074102.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-09-10T00:08:22.526Z,1568074102.526 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-09-10T00:08:22.742Z,1568074102.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-09-10T00:08:22.743Z,1568074102.743 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-09-10T00:08:22.947Z,1568074102.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-09-10T00:08:22.947Z,1568074102.947 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-09-10T00:08:23.341Z,1568074103.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-09-10T00:08:23.342Z,1568074103.342 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-09-10T00:08:23.678Z,1568074103.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-09-10T00:08:23.680Z,1568074103.680 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2019-09-10T00:08:23.681Z,1568074103.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2019-09-10T00:08:23.767Z,1568074103.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2019-09-10T00:08:23.926Z,1568074103.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2019-09-10T00:08:24.038Z,1568074104.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2019-09-10T00:08:24.125Z,1568074104.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2019-09-10T00:08:24.221Z,1568074104.221 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2019-09-10T00:08:24.456Z,1568074104.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2019-09-10T00:08:25.326Z,1568074105.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-09-10T00:08:25.327Z,1568074105.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2019-09-10T00:08:25.423Z,1568074105.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2019-09-10T00:08:25.524Z,1568074105.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2019-09-10T00:08:25.663Z,1568074105.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2019-09-10T00:08:25.766Z,1568074105.766 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2019-09-10T00:08:25.767Z,1568074105.767 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2019-09-10T00:08:25.768Z,1568074105.768 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-09-10T00:08:25.836Z,1568074105.836 [VerticalControl](DEBUG): Construct VerticalControl. 2019-09-10T00:08:25.953Z,1568074105.953 [VerticalControl] Loaded 2019-09-10T00:08:25.954Z,1568074105.954 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-09-10T00:08:25.954Z,1568074105.954 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-09-10T00:08:26.025Z,1568074106.025 [HorizontalControl] Loaded 2019-09-10T00:08:26.025Z,1568074106.025 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-09-10T00:08:26.026Z,1568074106.026 [SpeedControl](DEBUG): Construct SpeedControl. 2019-09-10T00:08:26.032Z,1568074106.032 [SpeedControl] Loaded 2019-09-10T00:08:26.032Z,1568074106.032 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-09-10T00:08:26.033Z,1568074106.033 [LoopControl](DEBUG): Construct LoopControl. 2019-09-10T00:08:26.033Z,1568074106.033 [LoopControl] Loaded 2019-09-10T00:08:26.034Z,1568074106.034 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-09-10T00:08:26.034Z,1568074106.034 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-09-10T00:08:26.035Z,1568074106.035 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-09-10T00:08:26.075Z,1568074106.075 [DepthRateCalculator] Loaded 2019-09-10T00:08:26.076Z,1568074106.076 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-09-10T00:08:26.081Z,1568074106.081 [PitchRateCalculator] Loaded 2019-09-10T00:08:26.081Z,1568074106.081 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-09-10T00:08:26.097Z,1568074106.097 [SpeedCalculator] Loaded 2019-09-10T00:08:26.097Z,1568074106.097 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-09-10T00:08:26.119Z,1568074106.119 [TempGradientCalculator] Loaded 2019-09-10T00:08:26.119Z,1568074106.119 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-09-10T00:08:26.124Z,1568074106.124 [YawRateCalculator] Loaded 2019-09-10T00:08:26.124Z,1568074106.124 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-09-10T00:08:26.155Z,1568074106.155 [ElevatorOffsetCalculator] Loaded 2019-09-10T00:08:26.155Z,1568074106.155 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-09-10T00:08:26.155Z,1568074106.155 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-09-10T00:08:26.156Z,1568074106.156 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-09-10T00:08:26.189Z,1568074106.189 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-09-10T00:08:26.189Z,1568074106.189 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-09-10T00:08:26.259Z,1568074106.259 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-09-10T00:08:26.260Z,1568074106.260 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-09-10T00:08:26.550Z,1568074106.550 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-09-10T00:08:26.551Z,1568074106.551 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-09-10T00:08:26.649Z,1568074106.649 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-09-10T00:08:26.650Z,1568074106.650 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-09-10T00:08:26.991Z,1568074106.991 [AHRS_M2] Loaded 2019-09-10T00:08:26.991Z,1568074106.991 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-09-10T00:08:27.064Z,1568074107.064 [DataOverHttps] Loaded 2019-09-10T00:08:27.064Z,1568074107.064 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-09-10T00:08:27.065Z,1568074107.065 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B84E0 2019-09-10T00:08:27.066Z,1568074107.066 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7215 2019-09-10T00:08:27.079Z,1568074107.079 [Depth_Keller] Loaded 2019-09-10T00:08:27.079Z,1568074107.079 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-09-10T00:08:27.084Z,1568074107.084 [DropWeight] Loaded 2019-09-10T00:08:27.085Z,1568074107.085 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-09-10T00:08:27.183Z,1568074107.183 [NAL9602] Loaded 2019-09-10T00:08:27.184Z,1568074107.184 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-09-10T00:08:27.200Z,1568074107.200 [Onboard] Loaded 2019-09-10T00:08:27.200Z,1568074107.200 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-09-10T00:08:27.203Z,1568074107.203 [Radio_Surface] Loaded 2019-09-10T00:08:27.204Z,1568074107.204 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-09-10T00:08:27.205Z,1568074107.205 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E84E0 2019-09-10T00:08:27.205Z,1568074107.205 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7216 2019-09-10T00:08:27.251Z,1568074107.251 [RDI_Pathfinder] Loaded 2019-09-10T00:08:27.252Z,1568074107.252 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-09-10T00:08:28.762Z,1568074108.762 [BPC1] Loaded 2019-09-10T00:08:28.763Z,1568074108.763 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-09-10T00:08:28.763Z,1568074108.763 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-09-10T00:08:28.764Z,1568074108.764 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-09-10T00:08:28.867Z,1568074108.867 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-09-10T00:08:28.868Z,1568074108.868 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-09-10T00:08:28.889Z,1568074108.889 [NavChart] Loaded 2019-09-10T00:08:28.890Z,1568074108.890 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-09-10T00:08:28.894Z,1568074108.894 [UniversalFixResidualReporter] Loaded 2019-09-10T00:08:28.894Z,1568074108.894 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-09-10T00:08:28.894Z,1568074108.894 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-09-10T00:08:28.895Z,1568074108.895 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-09-10T00:08:29.044Z,1568074109.044 [SBIT](DEBUG): Construct Startup Built In Test. 2019-09-10T00:08:29.056Z,1568074109.056 [SBIT] Loaded 2019-09-10T00:08:29.056Z,1568074109.056 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-09-10T00:08:29.057Z,1568074109.057 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-09-10T00:08:29.068Z,1568074109.068 [IBIT] Loaded 2019-09-10T00:08:29.069Z,1568074109.069 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-09-10T00:08:29.072Z,1568074109.072 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-09-10T00:08:29.213Z,1568074109.213 [CBIT] Loaded 2019-09-10T00:08:29.213Z,1568074109.213 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-09-10T00:08:29.214Z,1568074109.214 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-09-10T00:08:29.214Z,1568074109.214 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-09-10T00:08:29.317Z,1568074109.317 [BuoyancyServo] Loaded 2019-09-10T00:08:29.317Z,1568074109.317 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-09-10T00:08:29.329Z,1568074109.329 [ElevatorServo] Loaded 2019-09-10T00:08:29.329Z,1568074109.329 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-09-10T00:08:29.340Z,1568074109.340 [MassServo] Loaded 2019-09-10T00:08:29.341Z,1568074109.341 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-09-10T00:08:29.352Z,1568074109.352 [RudderServo] Loaded 2019-09-10T00:08:29.352Z,1568074109.352 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-09-10T00:08:29.363Z,1568074109.363 [ThrusterServo] Loaded 2019-09-10T00:08:29.363Z,1568074109.363 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-09-10T00:08:29.364Z,1568074109.364 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-09-10T00:08:29.365Z,1568074109.365 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-09-10T00:08:29.377Z,1568074109.377 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-09-10T00:08:29.378Z,1568074109.378 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-09-10T00:08:29.563Z,1568074109.563 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-09-10T00:08:29.568Z,1568074109.568 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-09-10T00:08:29.570Z,1568074109.570 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-09-10T00:08:29.575Z,1568074109.575 [CTD_Seabird](INFO): created writer for : depth 2019-09-10T00:08:29.575Z,1568074109.575 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-09-10T00:08:29.580Z,1568074109.580 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-09-10T00:08:29.581Z,1568074109.581 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-09-10T00:08:29.586Z,1568074109.586 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-09-10T00:08:29.587Z,1568074109.587 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-09-10T00:08:29.592Z,1568074109.592 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-09-10T00:08:29.593Z,1568074109.593 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-09-10T00:08:29.598Z,1568074109.598 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-09-10T00:08:29.599Z,1568074109.599 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-09-10T00:08:29.604Z,1568074109.604 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-09-10T00:08:29.631Z,1568074109.631 [CTD_Seabird] Loaded 2019-09-10T00:08:29.632Z,1568074109.632 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-09-10T00:08:29.633Z,1568074109.633 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A6D4E0 2019-09-10T00:08:29.633Z,1568074109.633 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7217 2019-09-10T00:08:29.683Z,1568074109.683 [WetLabsBB2FL] Loaded 2019-09-10T00:08:29.683Z,1568074109.683 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-09-10T00:08:29.684Z,1568074109.684 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A9D4E0 2019-09-10T00:08:29.685Z,1568074109.685 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7218 2019-09-10T00:08:29.686Z,1568074109.686 [WetLabsUBAT](CRITICAL): Could not find element WetLabsUBAT.loadControl 2019-09-10T00:08:29.687Z,1568074109.687 [WetLabsUBAT](ERROR): Could not open device '/dev/invalid' at WetLabsUBAT.loadControl 2019-09-10T00:08:30.167Z,1568074110.167 [WetLabsUBAT..SerialDriver](INFO): Created logger 2019-09-10T00:08:30.168Z,1568074110.168 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2019-09-10T00:08:30.169Z,1568074110.169 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2019-09-10T00:08:30.267Z,1568074110.267 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2019-09-10T00:08:30.267Z,1568074110.267 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2019-09-10T00:08:30.268Z,1568074110.268 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2019-09-10T00:08:30.272Z,1568074110.272 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2019-09-10T00:08:30.272Z,1568074110.272 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2019-09-10T00:08:30.277Z,1568074110.277 [WetLabsUBAT] Loaded 2019-09-10T00:08:30.277Z,1568074110.277 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2019-09-10T00:08:30.278Z,1568074110.278 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40ACD4E0 2019-09-10T00:08:30.278Z,1568074110.278 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 7219 2019-09-10T00:08:30.279Z,1568074110.279 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-09-10T00:08:30.283Z,1568074110.283 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-09-10T00:08:30.284Z,1568074110.284 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-09-10T00:08:30.290Z,1568074110.290 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-09-10T00:08:30.291Z,1568074110.291 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFD4E0 2019-09-10T00:08:30.292Z,1568074110.292 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7220 2019-09-10T00:08:30.296Z,1568074110.296 [Supervisor](INFO): Main Thread ID is 7131 2019-09-10T00:08:30.297Z,1568074110.297 [Supervisor](DEBUG): Running supervisor. 2019-09-10T00:08:30.297Z,1568074110.297 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7221 2019-09-10T00:08:30.302Z,1568074110.302 [controlThread ThreadHandler](INFO): Handler Thread ID is 7222 2019-09-10T00:08:30.303Z,1568074110.303 [controlThread](DEBUG): Initializing ControlThread 2019-09-10T00:08:30.304Z,1568074110.304 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-09-10T00:08:30.305Z,1568074110.305 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-09-10T00:08:30.306Z,1568074110.306 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-09-10T00:08:30.306Z,1568074110.306 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-09-10T00:08:30.307Z,1568074110.307 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-09-10T00:08:30.307Z,1568074110.307 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-09-10T00:08:30.307Z,1568074110.307 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-09-10T00:08:30.308Z,1568074110.308 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-09-10T00:08:30.308Z,1568074110.308 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-09-10T00:08:30.309Z,1568074110.309 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-09-10T00:08:30.315Z,1568074110.315 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-09-10T00:08:30.316Z,1568074110.316 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-09-10T00:08:30.316Z,1568074110.316 [SBIT](INFO): Initialize SBIT Component. 2019-09-10T00:08:30.317Z,1568074110.317 [SBIT](IMPORTANT): git: 2019-08-21a 2019-09-10T00:08:30.317Z,1568074110.317 [SBIT](INFO): git hash: 91726968a91bb65c09438a13bfa0101a916cbc64 2019-09-10T00:08:30.317Z,1568074110.317 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-09-10T00:08:30.317Z,1568074110.317 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-09-10T00:08:30.318Z,1568074110.318 [SBIT](INFO): Beginning SBIT in 44.000000 seconds. 2019-09-10T00:08:30.319Z,1568074110.319 [IBIT](INFO): Initialize IBIT Component. 2019-09-10T00:08:30.320Z,1568074110.320 [CBIT](DEBUG): Initialize CBIT Component. 2019-09-10T00:08:30.321Z,1568074110.321 [logger ThreadHandler](INFO): Handler Thread ID is 7223 2019-09-10T00:08:30.333Z,1568074110.333 [CBIT](DEBUG): Initialized mux pins. 2019-09-10T00:08:30.333Z,1568074110.333 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2019-09-10T00:08:30.333Z,1568074110.333 [CBIT](DEBUG): Initializing the watchdog timer. 2019-09-10T00:08:30.341Z,1568074110.341 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7224 2019-09-10T00:08:30.342Z,1568074110.342 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-09-10T00:08:30.353Z,1568074110.353 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7225 2019-09-10T00:08:30.357Z,1568074110.357 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-09-10T00:08:30.357Z,1568074110.357 [CBIT](DEBUG): Initializing heartbeat. 2019-09-10T00:08:30.365Z,1568074110.365 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7226 2019-09-10T00:08:30.366Z,1568074110.366 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-09-10T00:08:30.369Z,1568074110.369 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-09-10T00:08:30.370Z,1568074110.370 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7228 2019-09-10T00:08:30.372Z,1568074110.372 [WetLabsBB2FL](INFO): Powering down 2019-09-10T00:08:30.401Z,1568074110.401 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 7229 2019-09-10T00:08:30.413Z,1568074110.413 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7230 2019-09-10T00:08:30.419Z,1568074110.419 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-09-10T00:08:30.419Z,1568074110.419 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-09-10T00:08:30.419Z,1568074110.419 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-09-10T00:08:30.419Z,1568074110.419 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-09-10T00:08:30.420Z,1568074110.420 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-09-10T00:08:30.420Z,1568074110.420 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-09-10T00:08:30.420Z,1568074110.420 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-09-10T00:08:30.420Z,1568074110.420 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-09-10T00:08:30.420Z,1568074110.420 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-09-10T00:08:30.420Z,1568074110.420 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-09-10T00:08:30.421Z,1568074110.421 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-09-10T00:08:30.421Z,1568074110.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-09-10T00:08:30.421Z,1568074110.421 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-09-10T00:08:30.421Z,1568074110.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-09-10T00:08:30.421Z,1568074110.421 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-09-10T00:08:30.421Z,1568074110.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-09-10T00:08:30.429Z,1568074110.429 [CBIT](DEBUG): Deactivating GF circuits. 2019-09-10T00:08:30.429Z,1568074110.429 [CBIT](DEBUG): Deactivating emergency mode. 2019-09-10T00:08:30.465Z,1568074110.465 [CBIT](DEBUG): Backplane powered. 2019-09-10T00:08:30.467Z,1568074110.467 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-09-10T00:08:30.478Z,1568074110.478 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-09-10T00:08:30.501Z,1568074110.501 [MissionManager](DEBUG): 2019-09-10T00:08:30.501Z,1568074110.501 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-09-10T00:08:30.563Z,1568074110.563 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-09-10T00:08:30.564Z,1568074110.564 [Default:A.Wait](DEBUG): Construct Wait. 2019-09-10T00:08:30.578Z,1568074110.578 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-09-10T00:08:30.608Z,1568074110.608 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-09-10T00:08:30.627Z,1568074110.627 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-09-10T00:08:30.642Z,1568074110.642 [Default:E.Execute](DEBUG): Construct Execute. 2019-09-10T00:08:30.645Z,1568074110.645 [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-09-10T00:08:30.676Z,1568074110.676 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-09-10T00:08:30.682Z,1568074110.682 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-09-10T00:08:30.718Z,1568074110.718 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2019-09-10T00:08:30.757Z,1568074110.757 [Radio_Surface](INFO): Powering up 2019-09-10T00:08:30.814Z,1568074110.814 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-09-10T00:08:30.824Z,1568074110.824 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-09-10T00:08:30.839Z,1568074110.839 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-09-10T00:08:30.840Z,1568074110.840 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-09-10T00:08:30.845Z,1568074110.845 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-09-10T00:08:30.846Z,1568074110.846 [MassServo](DEBUG): Initializing EZServoServo. 2019-09-10T00:08:30.853Z,1568074110.853 [MassServo](DEBUG): Initializing MassServo. 2019-09-10T00:08:30.854Z,1568074110.854 [RudderServo](DEBUG): Initializing EZServoServo. 2019-09-10T00:08:30.861Z,1568074110.861 [RudderServo](DEBUG): Initializing RudderServo. 2019-09-10T00:08:30.862Z,1568074110.862 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-09-10T00:08:30.869Z,1568074110.869 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-09-10T00:08:30.871Z,1568074110.871 [CommandLine](FAULT): Scheduling is paused 2019-09-10T00:08:30.872Z,1568074110.872 [CBIT](INFO): Critical error at 20190910T000829 2019-09-10T00:08:30.872Z,1568074110.872 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-09-10T00:08:31.121Z,1568074111.121 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-09-10T00:08:31.825Z,1568074111.825 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-09-10T00:08:31.825Z,1568074111.825 [RudderServo](FAULT): Rudder failed to initialize 2019-09-10T00:08:31.825Z,1568074111.825 [RudderServo] Communications Fault, FailCount= 1 2019-09-10T00:08:31.825Z,1568074111.825 [RudderServo](ERROR): Communications Fault 2019-09-10T00:08:31.936Z,1568074111.936 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-09-10T00:08:32.110Z,1568074112.110 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-09-10T00:08:32.110Z,1568074112.110 [RudderServo](INFO): Powering down 2019-09-10T00:08:32.781Z,1568074112.781 [RudderServo](DEBUG): Initializing EZServoServo. 2019-09-10T00:08:32.902Z,1568074112.902 [RudderServo](DEBUG): Initializing RudderServo. 2019-09-10T00:08:32.906Z,1568074112.906 [CBIT](INFO): Clearing failed state for component RudderServo 2019-09-10T00:08:32.906Z,1568074112.906 [RudderServo] No Fault, FailCount= 1 2019-09-10T00:08:37.397Z,1568074117.397 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2019-09-10T00:08:42.434Z,1568074122.434 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004470 2019-09-10T00:08:53.005Z,1568074133.005 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-09-10T00:08:53.005Z,1568074133.005 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-09-10T00:08:53.005Z,1568074133.005 [RDI_Pathfinder](ERROR): Communications Fault 2019-09-10T00:08:53.057Z,1568074133.057 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-09-10T00:08:53.481Z,1568074133.481 [RDI_Pathfinder](INFO): Powering down 2019-09-10T00:08:54.289Z,1568074134.289 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-09-10T00:08:54.289Z,1568074134.289 [RDI_Pathfinder] No Fault, FailCount= 1 2019-09-10T00:08:57.434Z,1568074137.434 [NAL9602](INFO): Powering up NAL9602 2019-09-10T00:09:08.783Z,1568074148.783 [CommandLine](IMPORTANT): got command failComponent 2019-09-10T00:09:08.783Z,1568074148.783 [CommandLine](IMPORTANT): Failed components: 2019-09-10T00:09:08.783Z,1568074148.783 [CommandLine](IMPORTANT): No failed Components. 2019-09-10T00:09:12.452Z,1568074152.452 [CommandLine](IMPORTANT): got command show variable limitFwd 2019-09-10T00:09:12.650Z,1568074152.650 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd (millimeter) 2019-09-10T00:09:14.842Z,1568074154.842 [SBIT](IMPORTANT): Beginning Startup BIT 2019-09-10T00:09:14.846Z,1568074154.846 [CBIT](IMPORTANT): Beginning ground fault scan 2019-09-10T00:09:16.845Z,1568074156.845 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-09-10T00:09:16.845Z,1568074156.845 [RDI_Pathfinder] Communications Fault, FailCount= 2 2019-09-10T00:09:16.845Z,1568074156.845 [RDI_Pathfinder](ERROR): Communications Fault 2019-09-10T00:09:16.966Z,1568074156.966 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-09-10T00:09:17.325Z,1568074157.325 [RDI_Pathfinder](INFO): Powering down 2019-09-10T00:09:18.294Z,1568074158.294 [NAL9602](ERROR): NAL9602 initialization error. 2019-09-10T00:09:18.294Z,1568074158.294 [NAL9602] Communications Fault, FailCount= 1 2019-09-10T00:09:18.294Z,1568074158.294 [NAL9602](ERROR): Communications Fault 2019-09-10T00:09:18.502Z,1568074158.502 [CBIT](ERROR): Communications Fault in component: NAL9602 2019-09-10T00:09:18.502Z,1568074158.502 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-09-10T00:09:18.502Z,1568074158.502 [RDI_Pathfinder] No Fault, FailCount= 2 2019-09-10T00:09:18.702Z,1568074158.702 [NAL9602](INFO): Powering down 2019-09-10T00:09:19.681Z,1568074159.681 [CBIT](INFO): Clearing failed state for component NAL9602 2019-09-10T00:09:19.681Z,1568074159.681 [NAL9602] No Fault, FailCount= 1 2019-09-10T00:09:20.840Z,1568074160.840 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd 2019-09-10T00:09:20.861Z,1568074160.861 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 40.000000 mm 2019-09-10T00:09:26.037Z,1568074166.037 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.016683 CHAN A1 (24V): -0.129248 CHAN A2 (12V): -0.007238 CHAN A3 (5V): -0.001951 CHAN B0 (3.3V): 0.000720 CHAN B1 (3.15aV): -0.000345 CHAN B2 (3.15bV): -0.000010 CHAN B3 (GND): 0.002022 OPEN: 0.004988 Full Scale Calc: 4.765 mA, -1.589 mA 2019-09-10T00:09:29.651Z,1568074169.651 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft 2019-09-10T00:09:29.652Z,1568074169.652 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -20.000000 mm 2019-09-10T00:09:35.540Z,1568074175.540 [CommandLine](IMPORTANT): got command show variable massDefault 2019-09-10T00:09:35.623Z,1568074175.623 [CommandLine](IMPORTANT): VerticalControl.massDefault (centimeter) 2019-09-10T00:09:40.904Z,1568074180.904 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault 2019-09-10T00:09:40.909Z,1568074180.908 [CommandLine](IMPORTANT): VerticalControl.massDefault 0.600000 cm 2019-09-10T00:09:41.025Z,1568074181.025 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-09-10T00:09:41.025Z,1568074181.025 [RDI_Pathfinder] Communications Fault, FailCount= 3 2019-09-10T00:09:41.025Z,1568074181.025 [RDI_Pathfinder](ERROR): Communications Fault 2019-09-10T00:09:41.173Z,1568074181.173 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-09-10T00:09:41.174Z,1568074181.174 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2019-09-10T00:09:41.493Z,1568074181.493 [RDI_Pathfinder](INFO): Powering down 2019-09-10T00:09:41.615Z,1568074181.615 [CBIT](INFO): Critical error at 20190910T000941 2019-09-10T00:09:49.090Z,1568074189.090 [NAL9602](INFO): Powering up NAL9602 2019-09-10T00:09:54.920Z,1568074194.920 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2019-09-10T00:09:54.921Z,1568074194.921 [CommandLine](IMPORTANT): platform_mass_position 0.200022 cm 2019-09-10T00:10:00.074Z,1568074200.074 [NAL9602](INFO): NAL9602 initialized 2019-09-10T00:10:00.893Z,1568074200.893 [NAL9602](DEBUG): Fix Requested 2019-09-10T00:10:09.063Z,1568074209.063 [SBIT](IMPORTANT): SBIT PASSED 2019-09-10T00:10:09.140Z,1568074209.140 [CommandLine](IMPORTANT): got command configSet list 2019-09-10T00:10:09.140Z,1568074209.140 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-09-10T00:10:09.143Z,1568074209.143 [CommandLine](IMPORTANT): Micromodem.loadAtStartup=0 bool; 2019-09-10T00:10:09.143Z,1568074209.143 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter; 2019-09-10T00:10:09.402Z,1568074209.402 [MissionManager](IMPORTANT): Started mission Startup 2019-09-10T00:10:09.402Z,1568074209.402 [Startup] Running Loop=1 2019-09-10T00:10:09.402Z,1568074209.402 [Startup](DEBUG): Aggregate::initialize Startup 2019-09-10T00:10:09.402Z,1568074209.402 [Startup:A.GoToSurface] Running Loop=1 2019-09-10T00:10:09.402Z,1568074209.402 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-09-10T00:10:09.403Z,1568074209.403 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-09-10T00:10:09.403Z,1568074209.403 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-09-10T00:10:09.403Z,1568074209.403 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-09-10T00:10:09.404Z,1568074209.404 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-09-10T00:10:09.404Z,1568074209.404 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-09-10T00:10:09.406Z,1568074209.406 [Startup:StartupSatComms] Running Loop=1 2019-09-10T00:10:09.406Z,1568074209.406 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-09-10T00:10:09.406Z,1568074209.406 [Startup:StartupSatComms:A] Running Loop=1 2019-09-10T00:10:09.798Z,1568074209.798 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-09-10T00:10:20.015Z,1568074220.015 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault 10.000000 millimeter persist 2019-09-10T00:10:27.337Z,1568074227.337 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter 2019-09-10T00:10:27.338Z,1568074227.338 [CommandLine](IMPORTANT): platform_mass_position 0.952713 cm 2019-09-10T00:10:31.127Z,1568074231.127 [CommandLine](IMPORTANT): got command failComponent 2019-09-10T00:10:31.127Z,1568074231.127 [CommandLine](IMPORTANT): Failed components: 2019-09-10T00:10:31.127Z,1568074231.127 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault 2019-09-10T00:11:09.626Z,1568074269.626 [Startup:StartupSatComms:A](INFO): Timed out from 2019-09-10T00:10:09.4Z 2019-09-10T00:11:09.626Z,1568074269.626 [Startup:StartupSatComms:A] Stopped 2019-09-10T00:11:09.626Z,1568074269.626 [Startup:StartupSatComms:B] Running Loop=1 2019-09-10T00:11:10.029Z,1568074270.029 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-09-10T00:11:17.320Z,1568074277.320 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190909T214605/Courier0025.lzma 2019-09-10T00:11:18.126Z,1568074278.126 [DataOverHttps](INFO): Moved sent file to Logs/20190909T214605/Courier0025.lzma.bak 2019-09-10T00:11:18.126Z,1568074278.126 [DataOverHttps](INFO): SBD MOMSN=11758783 2019-09-10T00:11:30.508Z,1568074290.508 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20190910T000819/Courier0000.lzma 2019-09-10T00:11:30.620Z,1568074290.620 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-09-10T00:11:30.621Z,1568074290.621 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-09-10T00:11:30.630Z,1568074290.630 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-09-10T00:11:31.045Z,1568074291.045 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-09-10T00:11:31.045Z,1568074291.045 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-09-10T00:11:31.314Z,1568074291.314 [DataOverHttps](INFO): Moved sent file to Logs/20190910T000819/Courier0000.lzma.bak 2019-09-10T00:11:31.314Z,1568074291.314 [DataOverHttps](INFO): SBD MOMSN=11758785 2019-09-10T00:11:45.476Z,1568074305.476 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190909T214605/Express0026.lzma 2019-09-10T00:11:46.282Z,1568074306.282 [DataOverHttps](INFO): Moved sent file to Logs/20190909T214605/Express0026.lzma.bak 2019-09-10T00:11:46.282Z,1568074306.282 [DataOverHttps](INFO): SBD MOMSN=11758790 2019-09-10T00:12:01.386Z,1568074321.386 [DataOverHttps](INFO): Sending 1065 bytes from file Logs/20190910T000819/Express0001.lzma 2019-09-10T00:12:02.196Z,1568074322.196 [DataOverHttps](INFO): Moved sent file to Logs/20190910T000819/Express0001.lzma.bak 2019-09-10T00:12:02.196Z,1568074322.196 [DataOverHttps](INFO): SBD MOMSN=11758792 2019-09-10T00:12:07.006Z,1568074327.006 [Startup:StartupSatComms:B] Stopped 2019-09-10T00:12:07.006Z,1568074327.006 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-09-10T00:12:07.006Z,1568074327.006 [Startup:StartupSatComms] Stopped 2019-09-10T00:12:07.006Z,1568074327.006 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-09-10T00:12:07.007Z,1568074327.007 [Startup](INFO): Completed Startup 2019-09-10T00:12:07.007Z,1568074327.007 [MissionManager](INFO): Startup is completed. 2019-09-10T00:12:07.007Z,1568074327.007 [MissionManager](INFO): Uninitializing Mission Startup 2019-09-10T00:12:07.007Z,1568074327.007 [Startup] Stopped 2019-09-10T00:12:07.007Z,1568074327.007 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-09-10T00:12:07.007Z,1568074327.007 [Startup:A.GoToSurface] Stopped 2019-09-10T00:12:07.007Z,1568074327.007 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-09-10T00:12:07.402Z,1568074327.402 [MissionManager](IMPORTANT): Started mission Default 2019-09-10T00:12:07.402Z,1568074327.402 [Default] Running Loop=1 2019-09-10T00:12:07.402Z,1568074327.402 [Default](DEBUG): Aggregate::initialize Default 2019-09-10T00:12:07.402Z,1568074327.402 [Default:B.GoToSurface] Running Loop=1 2019-09-10T00:12:07.402Z,1568074327.402 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-09-10T00:12:07.402Z,1568074327.402 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-09-10T00:12:07.403Z,1568074327.403 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-09-10T00:12:07.403Z,1568074327.403 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-09-10T00:12:07.403Z,1568074327.403 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-09-10T00:12:07.404Z,1568074327.404 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-09-10T00:12:07.404Z,1568074327.404 [Default:A.Wait] Running Loop=1 2019-09-10T00:12:07.404Z,1568074327.404 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-09-10T00:12:20.742Z,1568074340.742 [Default:A.Wait](INFO): Done Waiting. 2019-09-10T00:12:20.742Z,1568074340.742 [Default:A.Wait] Stopped 2019-09-10T00:12:20.742Z,1568074340.742 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-09-10T00:12:21.138Z,1568074341.138 [Default:CheckIn] Running Loop=1 2019-09-10T00:12:21.138Z,1568074341.138 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-09-10T00:12:21.138Z,1568074341.138 [Default:CheckIn:Read_GPS] Running Loop=1 2019-09-10T00:12:21.535Z,1568074341.535 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-09-10T00:12:41.362Z,1568074361.362 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-09-10T00:12:41.362Z,1568074361.362 [RDI_Pathfinder] No Fault, FailCount= 3 2019-09-10T00:12:54.241Z,1568074374.241 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2019-09-10T00:12:54.241Z,1568074374.241 [RDI_Pathfinder] Data Fault, FailCount= 1 2019-09-10T00:12:54.241Z,1568074374.241 [RDI_Pathfinder](ERROR): Data Fault 2019-09-10T00:12:54.294Z,1568074374.294 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2019-09-10T00:12:54.645Z,1568074374.645 [RDI_Pathfinder](INFO): Powering down 2019-09-10T00:12:55.475Z,1568074375.475 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-09-10T00:12:55.475Z,1568074375.475 [RDI_Pathfinder] No Fault, FailCount= 1 2019-09-10T00:13:02.308Z,1568074382.308 [NAL9602](INFO): SBD MO Status=2, MOMSN=647, MT Status=2, MTMSN=0 2019-09-10T00:13:02.308Z,1568074382.308 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-09-10T00:13:12.701Z,1568074392.701 [CommandLine](IMPORTANT): got command failComponent 2019-09-10T00:13:12.701Z,1568074392.701 [CommandLine](IMPORTANT): Failed components: 2019-09-10T00:13:12.701Z,1568074392.701 [CommandLine](IMPORTANT): No failed Components. 2019-09-10T00:13:17.908Z,1568074397.908 [CommandLine](IMPORTANT): got command failComponent 2019-09-10T00:13:17.908Z,1568074397.908 [CommandLine](IMPORTANT): Failed components: 2019-09-10T00:13:17.908Z,1568074397.908 [CommandLine](IMPORTANT): No failed Components. 2019-09-10T00:13:18.080Z,1568074398.080 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-09-10T00:13:18.080Z,1568074398.080 [RDI_Pathfinder] Communications Fault, FailCount= 2 2019-09-10T00:13:18.081Z,1568074398.081 [RDI_Pathfinder](ERROR): Communications Fault 2019-09-10T00:13:18.112Z,1568074398.112 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-09-10T00:13:18.561Z,1568074398.561 [RDI_Pathfinder](INFO): Powering down 2019-09-10T00:13:19.309Z,1568074399.309 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-09-10T00:13:19.310Z,1568074399.310 [RDI_Pathfinder] No Fault, FailCount= 2 2019-09-10T00:13:26.652Z,1568074406.652 [CommandLine](IMPORTANT): got command show variable ubat 2019-09-10T00:13:26.693Z,1568074406.693 [CommandLine](IMPORTANT): WetLabsUBAT.loadContorl (none) 2019-09-10T00:13:26.694Z,1568074406.694 [CommandLine](IMPORTANT): WetLabsUBAT.uart (none) 2019-09-10T00:13:26.694Z,1568074406.694 [CommandLine](IMPORTANT): WetLabsUBAT.baud (bit_per_second) 2019-09-10T00:13:26.704Z,1568074406.704 [CommandLine](IMPORTANT): WetLabsUBAT.loadAtStartup (bool) 2019-09-10T00:13:26.704Z,1568074406.704 [CommandLine](IMPORTANT): WetLabsUBAT.simulateHardware (bool) 2019-09-10T00:13:26.705Z,1568074406.705 [CommandLine](IMPORTANT): WetLabsUBAT.emulateHardware (bool) 2019-09-10T00:13:26.705Z,1568074406.705 [CommandLine](IMPORTANT): WetLabsUBAT.serial (none) 2019-09-10T00:13:26.705Z,1568074406.705 [CommandLine](IMPORTANT): WetLabsUBAT.optionalArgs (none) 2019-09-10T00:13:26.711Z,1568074406.711 [CommandLine](IMPORTANT): WetLabsUBAT.lcmAppName (none) 2019-09-10T00:13:26.711Z,1568074406.711 [CommandLine](IMPORTANT): WetLabsUBAT.flowrateCalibCoeff (none_float) 2019-09-10T00:13:26.711Z,1568074406.711 [CommandLine](IMPORTANT): WetLabsUBAT.minFlowrate (liter_per_second) 2019-09-10T00:13:26.822Z,1568074406.822 [CommandLine](IMPORTANT): WetLabsUBAT.biolum_potential (none) 2019-09-10T00:13:26.823Z,1568074406.823 [CommandLine](IMPORTANT): WetLabsUBAT.flow_rate (liter_per_second) 2019-09-10T00:13:33.038Z,1568074413.038 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-09-10T00:13:35.077Z,1568074415.077 [RDI_Pathfinder](INFO): Failure count cleared after critical for RDI_Pathfinder 2019-09-10T00:13:38.931Z,1568074418.931 [CommandLine](IMPORTANT): got command report touch WetLabsUBAT.flow_rate 2019-09-10T00:13:41.660Z,1568074421.660 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml 2019-09-10T00:13:41.663Z,1568074421.663 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml 2019-09-10T00:13:41.844Z,1568074421.844 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool 2019-09-10T00:13:41.847Z,1568074421.847 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg 2019-09-10T00:13:41.850Z,1568074421.850 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg 2019-09-10T00:13:41.853Z,1568074421.853 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool 2019-09-10T00:13:41.856Z,1568074421.856 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min 2019-09-10T00:13:41.859Z,1568074421.859 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool 2019-09-10T00:13:41.862Z,1568074421.862 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool 2019-09-10T00:13:41.865Z,1568074421.865 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count 2019-09-10T00:13:41.868Z,1568074421.868 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count 2019-09-10T00:13:41.872Z,1568074421.872 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 % 2019-09-10T00:13:41.875Z,1568074421.875 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool 2019-09-10T00:13:41.878Z,1568074421.878 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool 2019-09-10T00:13:41.881Z,1568074421.881 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool 2019-09-10T00:13:41.884Z,1568074421.884 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool 2019-09-10T00:13:41.888Z,1568074421.888 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool 2019-09-10T00:13:41.915Z,1568074421.915 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool 2019-09-10T00:13:41.920Z,1568074421.920 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool 2019-09-10T00:13:41.924Z,1568074421.924 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool 2019-09-10T00:13:41.967Z,1568074421.967 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool 2019-09-10T00:13:41.994Z,1568074421.994 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool 2019-09-10T00:13:42.009Z,1568074422.009 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 1.000000 bool 2019-09-10T00:13:42.036Z,1568074422.036 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool 2019-09-10T00:13:42.040Z,1568074422.040 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count 2019-09-10T00:13:42.047Z,1568074422.047 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count 2019-09-10T00:13:42.050Z,1568074422.050 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m 2019-09-10T00:13:42.066Z,1568074422.066 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m 2019-09-10T00:13:42.073Z,1568074422.073 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m 2019-09-10T00:13:42.098Z,1568074422.098 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m 2019-09-10T00:13:42.105Z,1568074422.105 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l 2019-09-10T00:13:42.108Z,1568074422.108 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m 2019-09-10T00:13:42.116Z,1568074422.116 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC 2019-09-10T00:13:42.131Z,1568074422.131 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg 2019-09-10T00:13:42.136Z,1568074422.136 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg 2019-09-10T00:13:42.156Z,1568074422.156 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l 2019-09-10T00:13:42.163Z,1568074422.163 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m 2019-09-10T00:13:42.168Z,1568074422.168 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg 2019-09-10T00:13:42.183Z,1568074422.183 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg 2019-09-10T00:13:42.187Z,1568074422.187 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m 2019-09-10T00:13:42.198Z,1568074422.198 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m 2019-09-10T00:13:42.211Z,1568074422.211 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg 2019-09-10T00:13:42.222Z,1568074422.222 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg 2019-09-10T00:13:42.247Z,1568074422.247 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m 2019-09-10T00:13:42.251Z,1568074422.251 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3 2019-09-10T00:13:42.254Z,1568074422.254 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m 2019-09-10T00:13:42.257Z,1568074422.257 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg 2019-09-10T00:13:42.273Z,1568074422.273 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg 2019-09-10T00:13:42.277Z,1568074422.277 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb 2019-09-10T00:13:42.280Z,1568074422.280 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m 2019-09-10T00:13:42.283Z,1568074422.283 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg 2019-09-10T00:13:42.335Z,1568074422.335 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg 2019-09-10T00:13:42.351Z,1568074422.351 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m 2019-09-10T00:13:42.433Z,1568074422.433 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-09-10T00:13:42.458Z,1568074422.458 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2019-09-10T00:13:42.495Z,1568074422.495 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-09-10T00:13:42.533Z,1568074422.533 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-09-10T00:13:42.556Z,1568074422.556 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-09-10T00:13:42.630Z,1568074422.630 [MissionManager](DEBUG): Set either this or PeakDetectNO3Active to True in order to sample Nitrate. EnabledISUS must also be true. Maximum pitch to sample ISUS. (EnabledISUS must also be true.) 60.0 Minimum pitch to sample ISUS. (EnabledISUS must also be true.) -60.0 Turns on peak detection of Cholorphyll. If greater than zero, report a peak every window. If NaN or zero, this variable is ignored. NaN Turns on reporting of the highest peak value on yo-yo profiles in a horizontal sliding window (of length numProfilesSlidingwindow) If tracking a patch Width of boxcar filter applied to yoyo-wise chl peaks to pick out the highest peak. 3 Length of horizontal sliding window. The highest yoyo-wise chl peak (after low-pass filtering by a filter of length FilterWidthHorizontal) within this window. 100 When filtered horizontal value is this fraction of the peak, consider it outside the patch. 80 Turns on peak detection of Nitrate (and turns on ISUS). Turns on peak detection of Dissolved Oil. Turns on peak detection of concentration_of_chromophoric_dissolved_organic_matter_in_sea_water from FDOM sensor. Turns on seawater temperature derivative. Automatically set to True if the Aanderaa O2 sensor is installed. Set to false to disable reading Aandera O2. Automatically set to true if the Neil Brown CTD is enabled. Set to false to disable reading from the Neil Brown. Automatically set to true if the CTD is enabled. Set to false to disable reading from the CTD. Automatically set to true if the NPS Turbulence sensor is enabled. Set to false to disable reading from the NPS Turbulence sensor. Automatically set to true if the the WetLabs BB2FL is enabled. Set to false to disable the WetLabs BB2FL. Automatically set to true if the the WetLabs SeaOWL UV-A is enabled. Set to false to disable the WetLabs SeaOWL UV-A. Automatically set to true if the the WetLabs UBAT is enabled. Set to false to disable the WetLabs UBAT. Automatically set to True if ISUS is enabled. Set to false to disable ISUS. Low-pass window length (based on depth sensor sampling interval 0.4 second) for low-pass filtering. 20 Median filter length (only for chlorophyll fluorescence which tends to have spikes) 5 Shallow depth bound for detecting chl peak on each descent or ascent profile. NaN Deep depth bound for detecting chl peak on each descent or ascent profile. NaN Shallow depth bound for detecting any peak on each descent or ascent profile. NaN Deep depth bound for detecting any peak on each descent or ascent profile. NaN 0 0 0 NaN NaN 0 0 NaN NaN 0 0 NaN NaN NaN 0 0 NaN NaN 0 0 NaN NaN NaN 2019-09-10T00:13:42.633Z,1568074422.633 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml 2019-09-10T00:13:42.734Z,1568074422.734 [Default] Stopped 2019-09-10T00:13:42.734Z,1568074422.734 [Default](DEBUG): Aggregate::uninitialize Default 2019-09-10T00:13:42.734Z,1568074422.734 [Default:B.GoToSurface] Stopped 2019-09-10T00:13:42.734Z,1568074422.734 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-09-10T00:13:42.734Z,1568074422.734 [Default:CheckIn] Stopped 2019-09-10T00:13:42.734Z,1568074422.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-09-10T00:13:42.734Z,1568074422.734 [Default:CheckIn:Read_GPS] Stopped 2019-09-10T00:13:42.734Z,1568074422.734 [MissionManager](IMPORTANT): Started mission Science 2019-09-10T00:13:42.735Z,1568074422.735 [Science] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science](DEBUG): Aggregate::initialize Science 2019-09-10T00:13:42.735Z,1568074422.735 [Science:A] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science:B] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science:C] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science:D] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science:E] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science:F] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science:G] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science:H] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science:Read_Oil] Running Loop=1 2019-09-10T00:13:42.735Z,1568074422.735 [Science:PeakDetectChl] Running Loop=1 2019-09-10T00:13:42.736Z,1568074422.736 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl 2019-09-10T00:13:42.736Z,1568074422.736 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2019-09-10T00:13:42.736Z,1568074422.736 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-09-10T00:13:42.738Z,1568074422.738 [Science:HighestChlPeakReport] Running Loop=1 2019-09-10T00:13:42.738Z,1568074422.738 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport 2019-09-10T00:13:42.738Z,1568074422.738 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2019-09-10T00:13:42.738Z,1568074422.738 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2019-09-10T00:13:42.739Z,1568074422.739 [Science:PeakDetectNO3] Running Loop=1 2019-09-10T00:13:42.739Z,1568074422.739 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3 2019-09-10T00:13:42.739Z,1568074422.739 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2019-09-10T00:13:42.739Z,1568074422.739 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-09-10T00:13:42.742Z,1568074422.742 [Science:PeakDetectOil] Running Loop=1 2019-09-10T00:13:42.742Z,1568074422.742 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil 2019-09-10T00:13:42.743Z,1568074422.743 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2019-09-10T00:13:42.743Z,1568074422.743 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-09-10T00:13:42.761Z,1568074422.761 [Science:PeakDetectFDOM] Running Loop=1 2019-09-10T00:13:42.761Z,1568074422.761 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM 2019-09-10T00:13:42.761Z,1568074422.761 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2019-09-10T00:13:42.761Z,1568074422.761 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-09-10T00:13:42.764Z,1568074422.764 [Science:PeakDetectFDOM] Stopped 2019-09-10T00:13:42.764Z,1568074422.764 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM 2019-09-10T00:13:42.764Z,1568074422.764 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2019-09-10T00:13:42.764Z,1568074422.764 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-09-10T00:13:42.764Z,1568074422.764 [Science:PeakDetectOil] Stopped 2019-09-10T00:13:42.769Z,1568074422.769 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil 2019-09-10T00:13:42.769Z,1568074422.769 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2019-09-10T00:13:42.769Z,1568074422.769 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-09-10T00:13:42.769Z,1568074422.769 [Science:PeakDetectNO3] Stopped 2019-09-10T00:13:42.769Z,1568074422.769 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3 2019-09-10T00:13:42.769Z,1568074422.769 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2019-09-10T00:13:42.769Z,1568074422.769 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-09-10T00:13:42.769Z,1568074422.769 [Science:HighestChlPeakReport] Stopped 2019-09-10T00:13:42.769Z,1568074422.769 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport 2019-09-10T00:13:42.769Z,1568074422.769 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2019-09-10T00:13:42.770Z,1568074422.770 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2019-09-10T00:13:42.770Z,1568074422.770 [Science:PeakDetectChl] Stopped 2019-09-10T00:13:42.770Z,1568074422.770 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl 2019-09-10T00:13:42.770Z,1568074422.770 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2019-09-10T00:13:42.770Z,1568074422.770 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-09-10T00:13:42.770Z,1568074422.770 [Science:Read_Oil] Stopped 2019-09-10T00:13:42.770Z,1568074422.770 [Science:H] Running Loop=1 2019-09-10T00:13:42.771Z,1568074422.771 [Science:H](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.biolum_potential 2019-09-10T00:13:42.771Z,1568074422.771 [Science:G] Running Loop=1 2019-09-10T00:13:42.771Z,1568074422.771 [Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2019-09-10T00:13:42.771Z,1568074422.771 [Science:F] Stopped 2019-09-10T00:13:42.771Z,1568074422.771 [Science:E] Stopped 2019-09-10T00:13:42.772Z,1568074422.772 [Science:D] Running Loop=1 2019-09-10T00:13:42.772Z,1568074422.772 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2019-09-10T00:13:42.783Z,1568074422.783 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2019-09-10T00:13:42.783Z,1568074422.783 [Science:C] Running Loop=1 2019-09-10T00:13:42.784Z,1568074422.784 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2019-09-10T00:13:42.784Z,1568074422.784 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2019-09-10T00:13:42.793Z,1568074422.793 [Science:B] Stopped 2019-09-10T00:13:42.793Z,1568074422.793 [Science:A] Stopped 2019-09-10T00:13:42.926Z,1568074422.926 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2 2019-09-10T00:13:42.926Z,1568074422.926 [WetLabsBB2FL](INFO): Powering up 2019-09-10T00:13:43.245Z,1568074423.245 [WetLabsUBAT](INFO): Initializing 2019-09-10T00:13:43.247Z,1568074423.247 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2019-09-10T00:13:43.247Z,1568074423.247 [WetLabsUBAT](INFO): Powering up 2019-09-10T00:13:43.247Z,1568074423.247 [WetLabsUBAT](INFO): Checking LCM 2019-09-10T00:13:43.247Z,1568074423.247 [WetLabsUBAT](INFO): LCM OK 2019-09-10T00:13:43.253Z,1568074423.253 [WetLabsUBAT](INFO): setup() - setLineFormat() 2019-09-10T00:13:43.662Z,1568074423.662 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-09-10T00:13:46.674Z,1568074426.674 [CTD_Seabird](ERROR): Failed to parse device response: 2019-09-10T00:13:53.215Z,1568074433.215 [NAL9602](INFO): SBD MO Status=2, MOMSN=647, MT Status=2, MTMSN=0 2019-09-10T00:13:53.215Z,1568074433.215 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-09-10T00:14:20.693Z,1568074460.693 [CommandLine](IMPORTANT): got command report 2019-09-10T00:14:20.693Z,1568074460.693 [Reporter](INFO): WetLabsUBAT.flow_rate reporting when touched 2019-09-10T00:14:21.912Z,1568074461.912 [NAL9602](INFO): SBD MO Status=2, MOMSN=647, MT Status=2, MTMSN=0 2019-09-10T00:14:21.912Z,1568074461.912 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-09-10T00:14:27.605Z,1568074467.605 [CommandLine](IMPORTANT): got command show variable ubat 2019-09-10T00:14:27.673Z,1568074467.673 [CommandLine](IMPORTANT): WetLabsUBAT.loadContorl (none) 2019-09-10T00:14:27.673Z,1568074467.673 [CommandLine](IMPORTANT): WetLabsUBAT.uart (none) 2019-09-10T00:14:27.674Z,1568074467.674 [CommandLine](IMPORTANT): WetLabsUBAT.baud (bit_per_second) 2019-09-10T00:14:27.684Z,1568074467.684 [CommandLine](IMPORTANT): WetLabsUBAT.loadAtStartup (bool) 2019-09-10T00:14:27.685Z,1568074467.685 [CommandLine](IMPORTANT): WetLabsUBAT.simulateHardware (bool) 2019-09-10T00:14:27.685Z,1568074467.685 [CommandLine](IMPORTANT): WetLabsUBAT.emulateHardware (bool) 2019-09-10T00:14:27.686Z,1568074467.686 [CommandLine](IMPORTANT): WetLabsUBAT.serial (none) 2019-09-10T00:14:27.686Z,1568074467.686 [CommandLine](IMPORTANT): WetLabsUBAT.optionalArgs (none) 2019-09-10T00:14:27.691Z,1568074467.691 [CommandLine](IMPORTANT): WetLabsUBAT.lcmAppName (none) 2019-09-10T00:14:27.692Z,1568074467.692 [CommandLine](IMPORTANT): WetLabsUBAT.flowrateCalibCoeff (none_float) 2019-09-10T00:14:27.692Z,1568074467.692 [CommandLine](IMPORTANT): WetLabsUBAT.minFlowrate (liter_per_second) 2019-09-10T00:14:27.714Z,1568074467.714 [CommandLine](IMPORTANT): WetLabsUBAT.biolum_potential (none) 2019-09-10T00:14:27.714Z,1568074467.714 [CommandLine](IMPORTANT): WetLabsUBAT.flow_rate (liter_per_second) 2019-09-10T00:14:27.768Z,1568074467.768 [CommandLine](IMPORTANT): Science.EnabledWetLabsUBAT (bool) 2019-09-10T00:14:31.625Z,1568074471.625 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-09-10T00:14:31.625Z,1568074471.625 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-09-10T00:14:31.635Z,1568074471.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-09-10T00:14:32.060Z,1568074472.060 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-09-10T00:14:32.060Z,1568074472.060 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-09-10T00:14:40.243Z,1568074480.243 [CommandLine](IMPORTANT): got command report touch WetLabsUBAT.biolum_potential 2019-09-10T00:14:43.116Z,1568074483.116 [CommandLine](IMPORTANT): got command show stack 2019-09-10T00:14:43.116Z,1568074483.116 [CommandLine](IMPORTANT): Behavior Stack: 2019-09-10T00:14:43.117Z,1568074483.117 [Science](IMPORTANT): Priority 0: Science:C 2019-09-10T00:14:43.118Z,1568074483.118 [Science](IMPORTANT): Priority 1: Science:D 2019-09-10T00:14:43.118Z,1568074483.118 [Science](IMPORTANT): Priority 2: Science:G 2019-09-10T00:14:43.118Z,1568074483.118 [Science](IMPORTANT): Priority 3: Science:H 2019-09-10T00:14:49.405Z,1568074489.405 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-09-10T00:14:49.405Z,1568074489.405 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 93.43, 100.41, 90.93 2019-09-10T00:14:51.031Z,1568074491.031 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2019-09-10T00:14:51.031Z,1568074491.031 [RDI_Pathfinder](ERROR): Failed to parse: :BS, -2 +3,A 2019-09-10T00:14:59.883Z,1568074499.883 [NAL9602](INFO): SBD MO Status=2, MOMSN=647, MT Status=2, MTMSN=0 2019-09-10T00:14:59.883Z,1568074499.883 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-09-10T00:15:03.518Z,1568074503.518 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-09-10T00:15:04.337Z,1568074504.337 [NAL9602](DEBUG): Fix Requested 2019-09-10T00:15:04.744Z,1568074504.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001309.00,A,3648.16414,N,12147.28655,W,0.350,16.15,100919,,,A*40 2019-09-10T00:15:04.747Z,1568074504.747 [NAL9602](INFO): GPS fix at 20190910T001309: (36.802736, -121.788109) 2019-09-10T00:15:09.705Z,1568074509.705 [WetLabsUBAT](ERROR): Couldn't get instrument's attention 2019-09-10T00:15:10.821Z,1568074510.821 [CommandLine](IMPORTANT): got command get WetLabsUBAT.loadAtStartup 2019-09-10T00:15:10.822Z,1568074510.822 [CommandLine](IMPORTANT): WetLabsUBAT.loadAtStartup 1 bool 2019-09-10T00:15:20.724Z,1568074520.724 [CommandLine](IMPORTANT): got command get WetLabsUBAT.simulateHardware 2019-09-10T00:15:20.724Z,1568074520.724 [CommandLine](IMPORTANT): WetLabsUBAT.simulateHardware 0 bool 2019-09-10T00:15:31.325Z,1568074531.325 [CommandLine](IMPORTANT): got command failComponent hardware WetLabsUBAT 2019-09-10T00:15:31.325Z,1568074531.325 [WetLabsUBAT] Hardware Fault, FailCount= 1 2019-09-10T00:15:31.326Z,1568074531.326 [WetLabsUBAT](ERROR): Hardware Fault 2019-09-10T00:15:31.326Z,1568074531.326 [CommandLine](IMPORTANT): WetLabsUBAT failureMode is Hardware Fault 2019-09-10T00:15:31.458Z,1568074531.458 [CBIT](ERROR): Hardware Fault in component: WetLabsUBAT 2019-09-10T00:15:33.433Z,1568074533.433 [CommandLine](IMPORTANT): got command failComponent 2019-09-10T00:15:33.434Z,1568074533.434 [CommandLine](IMPORTANT): Failed components: 2019-09-10T00:15:33.434Z,1568074533.434 [CommandLine](IMPORTANT): WetLabsUBAT: Hardware Fault 2019-09-10T00:15:37.082Z,1568074537.082 [NAL9602](INFO): Not Powering down - fast GPS 2019-09-10T00:15:39.045Z,1568074539.045 [CommandLine](IMPORTANT): got command failComponent none WetLabsUBAT 2019-09-10T00:15:39.045Z,1568074539.045 [WetLabsUBAT] No Fault, FailCount= 1 2019-09-10T00:15:39.046Z,1568074539.046 [CommandLine](IMPORTANT): WetLabsUBAT failureMode is No Fault 2019-09-10T00:15:49.234Z,1568074549.234 [RDI_Pathfinder](ERROR): Failed to parse: :SA, +0.00, +0.00, 0.00 2019-09-10T00:16:06.214Z,1568074566.214 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-09-10T00:16:06.218Z,1568074566.218 [BPC1](INFO): Received data from all battery sticks. 2019-09-10T00:16:10.089Z,1568074570.089 [CommandLine](IMPORTANT): got command show best height_above_sea_floor 2019-09-10T00:16:10.090Z,1568074570.090 [CommandLine](IMPORTANT): height_above_sea_floor best is RDI_Pathfinder.height_above_sea_floor 2019-09-10T00:16:15.641Z,1568074575.641 [CommandLine](IMPORTANT): got command get height_above_sea_floor 2019-09-10T00:16:15.642Z,1568074575.642 [CommandLine](IMPORTANT): height_above_sea_floor 9.520000 m 2019-09-10T00:16:18.645Z,1568074578.645 [CommandLine](IMPORTANT): got command get height_above_sea_floor 2019-09-10T00:16:18.646Z,1568074578.646 [CommandLine](IMPORTANT): height_above_sea_floor 9.540000 m 2019-09-10T00:16:21.023Z,1568074581.023 [CommandLine](IMPORTANT): got command get height_above_sea_floor 2019-09-10T00:16:21.025Z,1568074581.025 [CommandLine](IMPORTANT): height_above_sea_floor 9.500000 m 2019-09-10T00:16:23.568Z,1568074583.568 [CommandLine](IMPORTANT): got command get height_above_sea_floor 2019-09-10T00:16:23.568Z,1568074583.568 [CommandLine](IMPORTANT): height_above_sea_floor 9.460000 m 2019-09-10T00:16:31.129Z,1568074591.129 [WetLabsUBAT](ERROR): Couldn't get instrument's attention 2019-09-10T00:17:22.091Z,1568074642.091 [WetLabsUBAT](INFO): Got instrument prompt 2019-09-10T00:17:24.227Z,1568074644.227 [WetLabsUBAT](INFO): Got instrument prompt 2019-09-10T00:17:25.297Z,1568074645.297 [WetLabsUBAT..StreamSerialDriver](INFO): device metadata: Override SerialDriver::getDeviceMetadata() 2019-09-10T00:17:30.641Z,1568074650.641 [Reporter](INFO): WetLabsUBAT.flow_rate 0.327669 l/s 2019-09-10T00:17:30.641Z,1568074650.641 [Reporter](INFO): WetLabsUBAT.biolum_potential 0.000000 n/a 2019-09-10T00:17:31.443Z,1568074651.443 [Reporter](INFO): WetLabsUBAT.flow_rate 0.327212 l/s 2019-09-10T00:17:31.444Z,1568074651.444 [Reporter](INFO): WetLabsUBAT.biolum_potential 16359425024.000000 n/a 2019-09-10T00:17:32.698Z,1568074652.698 [Reporter](INFO): WetLabsUBAT.flow_rate 0.328126 l/s 2019-09-10T00:17:32.699Z,1568074652.699 [Reporter](INFO): WetLabsUBAT.biolum_potential 462931936.000000 n/a 2019-09-10T00:17:33.490Z,1568074653.490 [Reporter](INFO): WetLabsUBAT.flow_rate 0.328583 l/s 2019-09-10T00:17:33.491Z,1568074653.491 [Reporter](INFO): WetLabsUBAT.biolum_potential 62297804.000000 n/a 2019-09-10T00:17:34.698Z,1568074654.698 [Reporter](INFO): WetLabsUBAT.flow_rate 0.327669 l/s 2019-09-10T00:17:34.699Z,1568074654.699 [Reporter](INFO): WetLabsUBAT.biolum_potential 2258071.500000 n/a 2019-09-10T00:17:35.484Z,1568074655.484 [WetLabsUBAT](ERROR): Invalid data record: got 70 tokens 2019-09-10T00:17:35.518Z,1568074655.518 [Reporter](INFO): WetLabsUBAT.flow_rate 0.327669 l/s 2019-09-10T00:17:35.518Z,1568074655.518 [Reporter](INFO): WetLabsUBAT.biolum_potential 2258071.500000 n/a 2019-09-10T00:17:36.729Z,1568074656.729 [Reporter](INFO): WetLabsUBAT.flow_rate 0.330868 l/s 2019-09-10T00:17:36.730Z,1568074656.730 [Reporter](INFO): WetLabsUBAT.biolum_potential 1490926.875000 n/a 2019-09-10T00:17:37.546Z,1568074657.546 [Reporter](INFO): WetLabsUBAT.flow_rate 0.330868 l/s 2019-09-10T00:17:37.547Z,1568074657.547 [Reporter](INFO): WetLabsUBAT.biolum_potential 2236239.250000 n/a 2019-09-10T00:17:38.732Z,1568074658.732 [Reporter](INFO): WetLabsUBAT.flow_rate 0.329954 l/s 2019-09-10T00:17:38.733Z,1568074658.733 [Reporter](INFO): WetLabsUBAT.biolum_potential 1495056.875000 n/a 2019-09-10T00:17:39.959Z,1568074659.959 [Reporter](INFO): WetLabsUBAT.flow_rate 0.331325 l/s 2019-09-10T00:17:39.960Z,1568074659.960 [Reporter](INFO): WetLabsUBAT.biolum_potential 2977741.000000 n/a 2019-09-10T00:17:40.737Z,1568074660.737 [Reporter](INFO): WetLabsUBAT.flow_rate 0.331782 l/s 2019-09-10T00:17:40.738Z,1568074660.738 [Reporter](INFO): WetLabsUBAT.biolum_potential 743259.187500 n/a 2019-09-10T00:17:41.586Z,1568074661.586 [CommandLine](IMPORTANT): got command report clear 2019-09-10T00:17:49.647Z,1568074669.647 [CommandLine](IMPORTANT): got command show variable WetlabsUBAT 2019-09-10T00:17:49.720Z,1568074669.720 [CommandLine](IMPORTANT): WetLabsUBAT.loadContorl (none) 2019-09-10T00:17:49.720Z,1568074669.720 [CommandLine](IMPORTANT): WetLabsUBAT.uart (none) 2019-09-10T00:17:49.721Z,1568074669.721 [CommandLine](IMPORTANT): WetLabsUBAT.baud (bit_per_second) 2019-09-10T00:17:49.731Z,1568074669.731 [CommandLine](IMPORTANT): WetLabsUBAT.loadAtStartup (bool) 2019-09-10T00:17:49.732Z,1568074669.732 [CommandLine](IMPORTANT): WetLabsUBAT.simulateHardware (bool) 2019-09-10T00:17:49.736Z,1568074669.736 [CommandLine](IMPORTANT): WetLabsUBAT.emulateHardware (bool) 2019-09-10T00:17:49.736Z,1568074669.736 [CommandLine](IMPORTANT): WetLabsUBAT.serial (none) 2019-09-10T00:17:49.737Z,1568074669.737 [CommandLine](IMPORTANT): WetLabsUBAT.optionalArgs (none) 2019-09-10T00:17:49.742Z,1568074669.742 [CommandLine](IMPORTANT): WetLabsUBAT.lcmAppName (none) 2019-09-10T00:17:49.742Z,1568074669.742 [CommandLine](IMPORTANT): WetLabsUBAT.flowrateCalibCoeff (none_float) 2019-09-10T00:17:49.742Z,1568074669.742 [CommandLine](IMPORTANT): WetLabsUBAT.minFlowrate (liter_per_second) 2019-09-10T00:17:49.760Z,1568074669.760 [CommandLine](IMPORTANT): WetLabsUBAT.biolum_potential (none) 2019-09-10T00:17:49.760Z,1568074669.760 [CommandLine](IMPORTANT): WetLabsUBAT.flow_rate (liter_per_second) 2019-09-10T00:17:49.763Z,1568074669.763 [CommandLine](IMPORTANT): Science.EnabledWetLabsUBAT (bool) 2019-09-10T00:17:58.803Z,1568074678.803 [CommandLine](IMPORTANT): got command get WetLabsUBAT.loadContorl 2019-09-10T00:17:58.804Z,1568074678.804 [CommandLine](IMPORTANT): WetLabsUBAT.loadContorl /dev/loadC1 2019-09-10T00:18:08.722Z,1568074688.722 [CommandLine](IMPORTANT): got command get WetLabsUBAT.uart 2019-09-10T00:18:08.722Z,1568074688.722 [CommandLine](IMPORTANT): WetLabsUBAT.uart /dev/ttyC1 2019-09-10T00:19:10.044Z,1568074750.044 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-09-10T00:19:10.045Z,1568074750.045 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 95.42, 98.92, 0.00 2019-09-10T00:19:16.024Z,1568074756.024 [CommandLine](IMPORTANT): got command stop 2019-09-10T00:19:16.024Z,1568074756.024 [CommandLine](IMPORTANT): Scheduling is paused 2019-09-10T00:19:16.024Z,1568074756.024 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2019-09-10T00:19:16.189Z,1568074756.189 [MissionManager](INFO): MissionManager is completed. 2019-09-10T00:19:16.189Z,1568074756.189 [MissionManager](INFO): Uninitializing Mission Science 2019-09-10T00:19:16.189Z,1568074756.189 [Science] Stopped 2019-09-10T00:19:16.190Z,1568074756.190 [Science](DEBUG): Aggregate::uninitialize Science 2019-09-10T00:19:16.190Z,1568074756.190 [Science:C] Stopped 2019-09-10T00:19:16.190Z,1568074756.190 [Science:D] Stopped 2019-09-10T00:19:16.190Z,1568074756.190 [Science:G] Stopped 2019-09-10T00:19:16.190Z,1568074756.190 [Science:H] Stopped 2019-09-10T00:19:16.305Z,1568074756.305 [WetLabsBB2FL](INFO): Powering down 2019-09-10T00:19:16.490Z,1568074756.490 [MissionManager](IMPORTANT): Started mission Default 2019-09-10T00:19:16.490Z,1568074756.490 [Default] Running Loop=1 2019-09-10T00:19:16.490Z,1568074756.490 [Default](DEBUG): Aggregate::initialize Default 2019-09-10T00:19:16.490Z,1568074756.490 [Default:B.GoToSurface] Running Loop=1 2019-09-10T00:19:16.490Z,1568074756.490 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-09-10T00:19:16.491Z,1568074756.491 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-09-10T00:19:16.491Z,1568074756.491 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-09-10T00:19:16.491Z,1568074756.491 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-09-10T00:19:16.491Z,1568074756.491 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-09-10T00:19:16.492Z,1568074756.492 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-09-10T00:19:16.492Z,1568074756.492 [Default:A.Wait] Running Loop=1 2019-09-10T00:19:16.492Z,1568074756.492 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-09-10T00:19:17.569Z,1568074757.569 [WetLabsUBAT](INFO): Powering down 2019-09-10T00:19:18.211Z,1568074758.211 [CommandLine](IMPORTANT): got command report 2019-09-10T00:19:26.079Z,1568074766.079 [CommandLine](IMPORTANT): got command report touch WetLabsUBAT.biolum_potential 2019-09-10T00:19:29.809Z,1568074769.809 [Default:A.Wait](INFO): Done Waiting. 2019-09-10T00:19:29.809Z,1568074769.809 [Default:A.Wait] Stopped 2019-09-10T00:19:29.809Z,1568074769.809 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-09-10T00:19:30.225Z,1568074770.225 [Default:CheckIn] Running Loop=1 2019-09-10T00:19:30.225Z,1568074770.225 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-09-10T00:19:30.225Z,1568074770.225 [Default:CheckIn:Read_GPS] Running Loop=1 2019-09-10T00:19:31.813Z,1568074771.813 [NAL9602](DEBUG): Fix Requested 2019-09-10T00:19:31.939Z,1568074771.939 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml 2019-09-10T00:19:31.940Z,1568074771.940 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml 2019-09-10T00:19:32.156Z,1568074772.156 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool 2019-09-10T00:19:32.159Z,1568074772.159 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg 2019-09-10T00:19:32.161Z,1568074772.161 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg 2019-09-10T00:19:32.163Z,1568074772.163 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool 2019-09-10T00:19:32.166Z,1568074772.166 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min 2019-09-10T00:19:32.167Z,1568074772.167 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool 2019-09-10T00:19:32.170Z,1568074772.170 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool 2019-09-10T00:19:32.172Z,1568074772.172 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count 2019-09-10T00:19:32.174Z,1568074772.174 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count 2019-09-10T00:19:32.176Z,1568074772.176 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 % 2019-09-10T00:19:32.178Z,1568074772.178 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool 2019-09-10T00:19:32.180Z,1568074772.180 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool 2019-09-10T00:19:32.183Z,1568074772.183 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool 2019-09-10T00:19:32.185Z,1568074772.185 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool 2019-09-10T00:19:32.187Z,1568074772.187 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool 2019-09-10T00:19:32.207Z,1568074772.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001737.00,A,3648.16521,N,12147.28381,W,0.272,16.15,100919,,,A*43 2019-09-10T00:19:32.210Z,1568074772.210 [NAL9602](INFO): GPS fix at 20190910T001737: (36.802754, -121.788064) 2019-09-10T00:19:32.220Z,1568074772.220 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool 2019-09-10T00:19:32.235Z,1568074772.235 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool 2019-09-10T00:19:32.245Z,1568074772.245 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool 2019-09-10T00:19:32.248Z,1568074772.248 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool 2019-09-10T00:19:32.303Z,1568074772.303 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool 2019-09-10T00:19:32.311Z,1568074772.311 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 1.000000 bool 2019-09-10T00:19:32.331Z,1568074772.331 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool 2019-09-10T00:19:32.344Z,1568074772.344 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count 2019-09-10T00:19:32.346Z,1568074772.346 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count 2019-09-10T00:19:32.373Z,1568074772.373 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m 2019-09-10T00:19:32.375Z,1568074772.375 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m 2019-09-10T00:19:32.390Z,1568074772.390 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m 2019-09-10T00:19:32.392Z,1568074772.392 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m 2019-09-10T00:19:32.410Z,1568074772.410 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l 2019-09-10T00:19:32.412Z,1568074772.412 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m 2019-09-10T00:19:32.435Z,1568074772.435 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC 2019-09-10T00:19:32.453Z,1568074772.453 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg 2019-09-10T00:19:32.455Z,1568074772.455 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg 2019-09-10T00:19:32.458Z,1568074772.458 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l 2019-09-10T00:19:32.460Z,1568074772.460 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m 2019-09-10T00:19:32.478Z,1568074772.478 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg 2019-09-10T00:19:32.481Z,1568074772.481 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg 2019-09-10T00:19:32.484Z,1568074772.484 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m 2019-09-10T00:19:32.487Z,1568074772.487 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m 2019-09-10T00:19:32.511Z,1568074772.511 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg 2019-09-10T00:19:32.513Z,1568074772.513 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg 2019-09-10T00:19:32.515Z,1568074772.515 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m 2019-09-10T00:19:32.522Z,1568074772.522 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3 2019-09-10T00:19:32.524Z,1568074772.524 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m 2019-09-10T00:19:32.526Z,1568074772.526 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg 2019-09-10T00:19:32.529Z,1568074772.529 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg 2019-09-10T00:19:32.531Z,1568074772.531 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb 2019-09-10T00:19:32.561Z,1568074772.561 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m 2019-09-10T00:19:32.564Z,1568074772.564 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg 2019-09-10T00:19:32.567Z,1568074772.567 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg 2019-09-10T00:19:32.569Z,1568074772.569 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m 2019-09-10T00:19:32.677Z,1568074772.677 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-09-10T00:19:32.714Z,1568074772.714 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2019-09-10T00:19:32.752Z,1568074772.752 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-09-10T00:19:32.800Z,1568074772.800 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-09-10T00:19:32.833Z,1568074772.833 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-09-10T00:19:32.945Z,1568074772.945 [MissionManager](DEBUG): Set either this or PeakDetectNO3Active to True in order to sample Nitrate. EnabledISUS must also be true. Maximum pitch to sample ISUS. (EnabledISUS must also be true.) 60.0 Minimum pitch to sample ISUS. (EnabledISUS must also be true.) -60.0 Turns on peak detection of Cholorphyll. If greater than zero, report a peak every window. If NaN or zero, this variable is ignored. NaN Turns on reporting of the highest peak value on yo-yo profiles in a horizontal sliding window (of length numProfilesSlidingwindow) If tracking a patch Width of boxcar filter applied to yoyo-wise chl peaks to pick out the highest peak. 3 Length of horizontal sliding window. The highest yoyo-wise chl peak (after low-pass filtering by a filter of length FilterWidthHorizontal) within this window. 100 When filtered horizontal value is this fraction of the peak, consider it outside the patch. 80 Turns on peak detection of Nitrate (and turns on ISUS). Turns on peak detection of Dissolved Oil. Turns on peak detection of concentration_of_chromophoric_dissolved_organic_matter_in_sea_water from FDOM sensor. Turns on seawater temperature derivative. Automatically set to True if the Aanderaa O2 sensor is installed. Set to false to disable reading Aandera O2. Automatically set to true if the Neil Brown CTD is enabled. Set to false to disable reading from the Neil Brown. Automatically set to true if the CTD is enabled. Set to false to disable reading from the CTD. Automatically set to true if the NPS Turbulence sensor is enabled. Set to false to disable reading from the NPS Turbulence sensor. Automatically set to true if the the WetLabs BB2FL is enabled. Set to false to disable the WetLabs BB2FL. Automatically set to true if the the WetLabs SeaOWL UV-A is enabled. Set to false to disable the WetLabs SeaOWL UV-A. Automatically set to true if the the WetLabs UBAT is enabled. Set to false to disable the WetLabs UBAT. Automatically set to True if ISUS is enabled. Set to false to disable ISUS. Low-pass window length (based on depth sensor sampling interval 0.4 second) for low-pass filtering. 20 Median filter length (only for chlorophyll fluorescence which tends to have spikes) 5 Shallow depth bound for detecting chl peak on each descent or ascent profile. NaN Deep depth bound for detecting chl peak on each descent or ascent profile. NaN Shallow depth bound for detecting any peak on each descent or ascent profile. NaN Deep depth bound for detecting any peak on each descent or ascent profile. NaN 0 0 0 NaN NaN 0 0 NaN NaN 0 0 NaN NaN NaN 0 0 NaN NaN 0 0 NaN NaN NaN 2019-09-10T00:19:32.947Z,1568074772.947 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml 2019-09-10T00:19:33.124Z,1568074773.124 [Default] Stopped 2019-09-10T00:19:33.124Z,1568074773.124 [Default](DEBUG): Aggregate::uninitialize Default 2019-09-10T00:19:33.124Z,1568074773.124 [Default:B.GoToSurface] Stopped 2019-09-10T00:19:33.124Z,1568074773.124 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-09-10T00:19:33.124Z,1568074773.124 [Default:CheckIn] Stopped 2019-09-10T00:19:33.133Z,1568074773.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-09-10T00:19:33.133Z,1568074773.133 [Default:CheckIn:Read_GPS] Stopped 2019-09-10T00:19:33.134Z,1568074773.134 [MissionManager](IMPORTANT): Started mission Science 2019-09-10T00:19:33.134Z,1568074773.134 [Science] Running Loop=1 2019-09-10T00:19:33.134Z,1568074773.134 [Science](DEBUG): Aggregate::initialize Science 2019-09-10T00:19:33.134Z,1568074773.134 [Science:A] Running Loop=1 2019-09-10T00:19:33.134Z,1568074773.134 [Science:B] Running Loop=1 2019-09-10T00:19:33.134Z,1568074773.134 [Science:C] Running Loop=1 2019-09-10T00:19:33.134Z,1568074773.134 [Science:D] Running Loop=1 2019-09-10T00:19:33.134Z,1568074773.134 [Science:E] Running Loop=1 2019-09-10T00:19:33.134Z,1568074773.134 [Science:F] Running Loop=1 2019-09-10T00:19:33.134Z,1568074773.134 [Science:G] Running Loop=1 2019-09-10T00:19:33.134Z,1568074773.134 [Science:H] Running Loop=1 2019-09-10T00:19:33.134Z,1568074773.134 [Science:Read_Oil] Running Loop=1 2019-09-10T00:19:33.135Z,1568074773.135 [Science:PeakDetectChl] Running Loop=1 2019-09-10T00:19:33.135Z,1568074773.135 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl 2019-09-10T00:19:33.135Z,1568074773.135 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2019-09-10T00:19:33.135Z,1568074773.135 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-09-10T00:19:33.136Z,1568074773.136 [Science:HighestChlPeakReport] Running Loop=1 2019-09-10T00:19:33.136Z,1568074773.136 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport 2019-09-10T00:19:33.145Z,1568074773.145 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2019-09-10T00:19:33.145Z,1568074773.145 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2019-09-10T00:19:33.145Z,1568074773.145 [Science:PeakDetectNO3] Running Loop=1 2019-09-10T00:19:33.146Z,1568074773.146 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3 2019-09-10T00:19:33.146Z,1568074773.146 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2019-09-10T00:19:33.146Z,1568074773.146 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-09-10T00:19:33.147Z,1568074773.147 [Science:PeakDetectOil] Running Loop=1 2019-09-10T00:19:33.147Z,1568074773.147 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil 2019-09-10T00:19:33.147Z,1568074773.147 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2019-09-10T00:19:33.147Z,1568074773.147 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-09-10T00:19:33.161Z,1568074773.161 [Science:PeakDetectFDOM] Running Loop=1 2019-09-10T00:19:33.161Z,1568074773.161 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM 2019-09-10T00:19:33.161Z,1568074773.161 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2019-09-10T00:19:33.161Z,1568074773.161 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-09-10T00:19:33.163Z,1568074773.163 [Science:PeakDetectFDOM] Stopped 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectOil] Stopped 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectNO3] Stopped 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2019-09-10T00:19:33.164Z,1568074773.164 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-09-10T00:19:33.164Z,1568074773.164 [Science:HighestChlPeakReport] Stopped 2019-09-10T00:19:33.168Z,1568074773.168 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport 2019-09-10T00:19:33.168Z,1568074773.168 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2019-09-10T00:19:33.168Z,1568074773.168 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2019-09-10T00:19:33.177Z,1568074773.177 [Science:PeakDetectChl] Stopped 2019-09-10T00:19:33.177Z,1568074773.177 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl 2019-09-10T00:19:33.177Z,1568074773.177 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2019-09-10T00:19:33.177Z,1568074773.177 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-09-10T00:19:33.177Z,1568074773.177 [Science:Read_Oil] Stopped 2019-09-10T00:19:33.177Z,1568074773.177 [Science:H] Running Loop=1 2019-09-10T00:19:33.178Z,1568074773.178 [Science:H](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.biolum_potential 2019-09-10T00:19:33.178Z,1568074773.178 [Science:G] Running Loop=1 2019-09-10T00:19:33.179Z,1568074773.179 [Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2019-09-10T00:19:33.179Z,1568074773.179 [Science:F] Stopped 2019-09-10T00:19:33.179Z,1568074773.179 [Science:E] Stopped 2019-09-10T00:19:33.179Z,1568074773.179 [Science:D] Running Loop=1 2019-09-10T00:19:33.179Z,1568074773.179 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2019-09-10T00:19:33.180Z,1568074773.180 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2019-09-10T00:19:33.180Z,1568074773.180 [Science:C] Running Loop=1 2019-09-10T00:19:33.180Z,1568074773.180 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2019-09-10T00:19:33.194Z,1568074773.194 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2019-09-10T00:19:33.194Z,1568074773.194 [Science:B] Stopped 2019-09-10T00:19:33.194Z,1568074773.194 [Science:A] Stopped 2019-09-10T00:19:33.346Z,1568074773.346 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2 2019-09-10T00:19:33.346Z,1568074773.346 [WetLabsBB2FL](INFO): Powering up 2019-09-10T00:19:33.729Z,1568074773.729 [WetLabsUBAT](INFO): Initializing 2019-09-10T00:19:33.731Z,1568074773.731 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2019-09-10T00:19:33.731Z,1568074773.731 [WetLabsUBAT](INFO): Powering up 2019-09-10T00:19:33.731Z,1568074773.731 [WetLabsUBAT](INFO): Checking LCM 2019-09-10T00:19:33.731Z,1568074773.731 [WetLabsUBAT](INFO): LCM OK 2019-09-10T00:19:33.830Z,1568074773.830 [WetLabsUBAT](INFO): setup() - setLineFormat() 2019-09-10T00:19:34.138Z,1568074774.138 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-09-10T00:19:35.976Z,1568074775.976 [WetLabsUBAT](INFO): Got instrument prompt 2019-09-10T00:19:37.151Z,1568074777.151 [CTD_Seabird](ERROR): Failed to parse device response: 2019-09-10T00:19:38.135Z,1568074778.135 [WetLabsUBAT](INFO): Got instrument prompt 2019-09-10T00:19:39.197Z,1568074779.197 [WetLabsUBAT..StreamSerialDriver](INFO): device metadata: Override SerialDriver::getDeviceMetadata() 2019-09-10T00:19:44.384Z,1568074784.384 [Reporter](INFO): WetLabsUBAT.biolum_potential 0.000000 n/a 2019-09-10T00:19:45.591Z,1568074785.591 [Reporter](INFO): WetLabsUBAT.biolum_potential 13274389504.000000 n/a 2019-09-10T00:19:46.449Z,1568074786.449 [Reporter](INFO): WetLabsUBAT.biolum_potential 425561984.000000 n/a 2019-09-10T00:19:47.603Z,1568074787.603 [Reporter](INFO): WetLabsUBAT.biolum_potential 57758564.000000 n/a 2019-09-10T00:19:48.245Z,1568074788.245 [CommandLine](IMPORTANT): got command stop 2019-09-10T00:19:48.245Z,1568074788.245 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2019-09-10T00:19:48.414Z,1568074788.414 [MissionManager](INFO): MissionManager is completed. 2019-09-10T00:19:48.415Z,1568074788.415 [MissionManager](INFO): Uninitializing Mission Science 2019-09-10T00:19:48.415Z,1568074788.415 [Science] Stopped 2019-09-10T00:19:48.415Z,1568074788.415 [Science](DEBUG): Aggregate::uninitialize Science 2019-09-10T00:19:48.415Z,1568074788.415 [Science:C] Stopped 2019-09-10T00:19:48.415Z,1568074788.415 [Science:D] Stopped 2019-09-10T00:19:48.415Z,1568074788.415 [Science:G] Stopped 2019-09-10T00:19:48.415Z,1568074788.415 [Science:H] Stopped 2019-09-10T00:19:48.447Z,1568074788.447 [Reporter](INFO): WetLabsUBAT.biolum_potential 0.000000 n/a 2019-09-10T00:19:48.569Z,1568074788.569 [WetLabsBB2FL](INFO): Powering down 2019-09-10T00:19:48.810Z,1568074788.810 [MissionManager](IMPORTANT): Started mission Default 2019-09-10T00:19:48.810Z,1568074788.810 [Default] Running Loop=1 2019-09-10T00:19:48.811Z,1568074788.811 [Default](DEBUG): Aggregate::initialize Default 2019-09-10T00:19:48.811Z,1568074788.811 [Default:B.GoToSurface] Running Loop=1 2019-09-10T00:19:48.811Z,1568074788.811 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-09-10T00:19:48.811Z,1568074788.811 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-09-10T00:19:48.811Z,1568074788.811 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-09-10T00:19:48.812Z,1568074788.812 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-09-10T00:19:48.812Z,1568074788.812 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-09-10T00:19:48.812Z,1568074788.812 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-09-10T00:19:48.813Z,1568074788.813 [Default:A.Wait] Running Loop=1 2019-09-10T00:19:48.813Z,1568074788.813 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-09-10T00:19:49.177Z,1568074789.177 [CommandLine](IMPORTANT): got command report 2019-09-10T00:19:49.177Z,1568074789.177 [Reporter](INFO): WetLabsUBAT.biolum_potential reporting when touched 2019-09-10T00:19:49.628Z,1568074789.628 [Reporter](INFO): WetLabsUBAT.biolum_potential no_value 2019-09-10T00:19:49.789Z,1568074789.789 [WetLabsUBAT](INFO): Powering down 2019-09-10T00:19:50.026Z,1568074790.026 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2019-09-10T00:19:50.026Z,1568074790.026 [RDI_Pathfinder](ERROR): Failed to parse: :BS, +6, +3,A 2019-09-10T00:19:51.003Z,1568074791.003 [CommandLine](IMPORTANT): got command report clear 2019-09-10T00:19:58.907Z,1568074798.907 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position 2019-09-10T00:19:58.918Z,1568074798.918 [Reporter](INFO): platform_buoyancy_position 958.068970 cc 2019-09-10T00:20:02.135Z,1568074802.135 [Default:A.Wait](INFO): Done Waiting. 2019-09-10T00:20:02.135Z,1568074802.135 [Default:A.Wait] Stopped 2019-09-10T00:20:02.135Z,1568074802.135 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-09-10T00:20:02.537Z,1568074802.537 [Default:CheckIn] Running Loop=1 2019-09-10T00:20:02.537Z,1568074802.537 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-09-10T00:20:02.537Z,1568074802.537 [Default:CheckIn:Read_GPS] Running Loop=1 2019-09-10T00:20:02.910Z,1568074802.910 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2019-09-10T00:20:02.985Z,1568074802.985 [NAL9602](FAULT): received: +SBDI: 2, 647, 2, 0, 0, 0 OK 2019-09-10T00:20:02.985Z,1568074802.985 [NAL9602] Data Fault, FailCount= 2 2019-09-10T00:20:02.986Z,1568074802.986 [NAL9602](ERROR): Data Fault 2019-09-10T00:20:03.125Z,1568074803.125 [CBIT](ERROR): Data Fault in component: NAL9602 2019-09-10T00:20:03.318Z,1568074803.318 [NAL9602](INFO): Powering down 2019-09-10T00:20:04.185Z,1568074804.185 [CBIT](INFO): Clearing failed state for component NAL9602 2019-09-10T00:20:04.185Z,1568074804.185 [NAL9602] No Fault, FailCount= 2 2019-09-10T00:20:09.159Z,1568074809.159 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter 2019-09-10T00:20:09.160Z,1568074809.160 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2019-09-10T00:20:09.426Z,1568074809.426 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-09-10T00:20:14.703Z,1568074814.703 [Reporter](INFO): platform_buoyancy_position 958.203247 cc 2019-09-10T00:20:15.107Z,1568074815.107 [Reporter](INFO): platform_buoyancy_position 956.054443 cc 2019-09-10T00:20:15.511Z,1568074815.511 [Reporter](INFO): platform_buoyancy_position 945.847656 cc 2019-09-10T00:20:15.899Z,1568074815.899 [Reporter](INFO): platform_buoyancy_position 945.176147 cc 2019-09-10T00:20:16.315Z,1568074816.315 [Reporter](INFO): platform_buoyancy_position 941.012939 cc 2019-09-10T00:20:16.706Z,1568074816.706 [Reporter](INFO): platform_buoyancy_position 938.864136 cc 2019-09-10T00:20:17.127Z,1568074817.127 [Reporter](INFO): platform_buoyancy_position 932.417725 cc 2019-09-10T00:20:17.526Z,1568074817.526 [Reporter](INFO): platform_buoyancy_position 927.985840 cc 2019-09-10T00:20:17.915Z,1568074817.915 [Reporter](INFO): platform_buoyancy_position 923.688232 cc 2019-09-10T00:20:18.335Z,1568074818.335 [Reporter](INFO): platform_buoyancy_position 917.376099 cc 2019-09-10T00:20:18.742Z,1568074818.742 [Reporter](INFO): platform_buoyancy_position 912.944214 cc 2019-09-10T00:20:19.159Z,1568074819.159 [Reporter](INFO): platform_buoyancy_position 906.497803 cc 2019-09-10T00:20:19.535Z,1568074819.535 [Reporter](INFO): platform_buoyancy_position 900.991455 cc 2019-09-10T00:20:19.938Z,1568074819.938 [Reporter](INFO): platform_buoyancy_position 895.753784 cc 2019-09-10T00:20:20.378Z,1568074820.378 [Reporter](INFO): platform_buoyancy_position 889.441650 cc 2019-09-10T00:20:20.758Z,1568074820.758 [Reporter](INFO): platform_buoyancy_position 885.144043 cc 2019-09-10T00:20:21.194Z,1568074821.194 [Reporter](INFO): platform_buoyancy_position 880.712158 cc 2019-09-10T00:20:21.559Z,1568074821.559 [Reporter](INFO): platform_buoyancy_position 874.400024 cc 2019-09-10T00:20:21.978Z,1568074821.978 [Reporter](INFO): platform_buoyancy_position 870.102539 cc 2019-09-10T00:20:22.394Z,1568074822.394 [Reporter](INFO): platform_buoyancy_position 863.790405 cc 2019-09-10T00:20:22.771Z,1568074822.771 [Reporter](INFO): platform_buoyancy_position 859.224121 cc 2019-09-10T00:20:23.191Z,1568074823.191 [Reporter](INFO): platform_buoyancy_position 853.046387 cc 2019-09-10T00:20:23.587Z,1568074823.587 [Reporter](INFO): platform_buoyancy_position 848.480225 cc 2019-09-10T00:20:23.994Z,1568074823.994 [Reporter](INFO): platform_buoyancy_position 844.182617 cc 2019-09-10T00:20:24.438Z,1568074824.438 [Reporter](INFO): platform_buoyancy_position 837.736206 cc 2019-09-10T00:20:24.791Z,1568074824.791 [Reporter](INFO): platform_buoyancy_position 833.304321 cc 2019-09-10T00:20:25.206Z,1568074825.206 [Reporter](INFO): platform_buoyancy_position 827.126465 cc 2019-09-10T00:20:25.619Z,1568074825.619 [Reporter](INFO): platform_buoyancy_position 822.560303 cc 2019-09-10T00:20:26.039Z,1568074826.039 [Reporter](INFO): platform_buoyancy_position 816.248169 cc 2019-09-10T00:20:26.419Z,1568074826.419 [Reporter](INFO): platform_buoyancy_position 812.084900 cc 2019-09-10T00:20:26.820Z,1568074826.820 [Reporter](INFO): platform_buoyancy_position 805.638489 cc 2019-09-10T00:20:27.230Z,1568074827.230 [Reporter](INFO): platform_buoyancy_position 801.206543 cc 2019-09-10T00:20:27.639Z,1568074827.639 [Reporter](INFO): platform_buoyancy_position 794.894470 cc 2019-09-10T00:20:28.082Z,1568074828.082 [Reporter](INFO): platform_buoyancy_position 790.328247 cc 2019-09-10T00:20:28.442Z,1568074828.442 [Reporter](INFO): platform_buoyancy_position 784.150452 cc 2019-09-10T00:20:28.827Z,1568074828.827 [Reporter](INFO): platform_buoyancy_position 779.718567 cc 2019-09-10T00:20:29.252Z,1568074829.252 [Reporter](INFO): platform_buoyancy_position 775.286682 cc 2019-09-10T00:20:29.655Z,1568074829.655 [Reporter](INFO): platform_buoyancy_position 769.108887 cc 2019-09-10T00:20:30.091Z,1568074830.091 [Reporter](INFO): platform_buoyancy_position 764.811279 cc 2019-09-10T00:20:30.475Z,1568074830.475 [Reporter](INFO): platform_buoyancy_position 758.364868 cc 2019-09-10T00:20:30.871Z,1568074830.871 [Reporter](INFO): platform_buoyancy_position 753.798645 cc 2019-09-10T00:20:31.292Z,1568074831.292 [Reporter](INFO): platform_buoyancy_position 747.486572 cc 2019-09-10T00:20:31.671Z,1568074831.671 [Reporter](INFO): platform_buoyancy_position 743.323242 cc 2019-09-10T00:20:32.099Z,1568074832.099 [Reporter](INFO): platform_buoyancy_position 736.876892 cc 2019-09-10T00:20:32.506Z,1568074832.506 [Reporter](INFO): platform_buoyancy_position 732.444946 cc 2019-09-10T00:20:32.887Z,1568074832.887 [Reporter](INFO): platform_buoyancy_position 726.132874 cc 2019-09-10T00:20:33.320Z,1568074833.320 [Reporter](INFO): platform_buoyancy_position 721.432373 cc 2019-09-10T00:20:33.614Z,1568074833.614 [NAL9602](INFO): Powering up NAL9602 2019-09-10T00:20:33.695Z,1568074833.695 [Reporter](INFO): platform_buoyancy_position 715.388855 cc 2019-09-10T00:20:34.115Z,1568074834.115 [Reporter](INFO): platform_buoyancy_position 710.956970 cc 2019-09-10T00:20:34.487Z,1568074834.487 [Reporter](INFO): platform_buoyancy_position 704.644836 cc 2019-09-10T00:20:34.903Z,1568074834.903 [Reporter](INFO): platform_buoyancy_position 700.212952 cc 2019-09-10T00:20:35.319Z,1568074835.319 [Reporter](INFO): platform_buoyancy_position 693.900879 cc 2019-09-10T00:20:35.699Z,1568074835.699 [Reporter](INFO): platform_buoyancy_position 689.468994 cc 2019-09-10T00:20:36.107Z,1568074836.107 [Reporter](INFO): platform_buoyancy_position 684.634155 cc 2019-09-10T00:20:36.515Z,1568074836.515 [Reporter](INFO): platform_buoyancy_position 678.724976 cc 2019-09-10T00:20:36.947Z,1568074836.947 [Reporter](INFO): platform_buoyancy_position 672.278564 cc 2019-09-10T00:20:37.348Z,1568074837.348 [Reporter](INFO): platform_buoyancy_position 668.115234 cc 2019-09-10T00:20:37.722Z,1568074837.722 [Reporter](INFO): platform_buoyancy_position 663.549072 cc 2019-09-10T00:20:38.167Z,1568074838.167 [Reporter](INFO): platform_buoyancy_position 657.236938 cc 2019-09-10T00:20:38.555Z,1568074838.555 [Reporter](INFO): platform_buoyancy_position 652.402161 cc 2019-09-10T00:20:38.942Z,1568074838.942 [Reporter](INFO): platform_buoyancy_position 646.492981 cc 2019-09-10T00:20:39.351Z,1568074839.351 [Reporter](INFO): platform_buoyancy_position 642.061035 cc 2019-09-10T00:20:39.762Z,1568074839.762 [Reporter](INFO): platform_buoyancy_position 635.883240 cc 2019-09-10T00:20:40.171Z,1568074840.171 [Reporter](INFO): platform_buoyancy_position 631.451355 cc 2019-09-10T00:20:40.558Z,1568074840.558 [Reporter](INFO): platform_buoyancy_position 627.288086 cc 2019-09-10T00:20:40.951Z,1568074840.951 [Reporter](INFO): platform_buoyancy_position 620.707336 cc 2019-09-10T00:20:41.243Z,1568074841.243 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 400.000000 cubic_centimeter 2019-09-10T00:20:41.426Z,1568074841.426 [Reporter](INFO): platform_buoyancy_position 614.260986 cc 2019-09-10T00:20:41.806Z,1568074841.806 [Reporter](INFO): platform_buoyancy_position 610.097656 cc 2019-09-10T00:20:42.175Z,1568074842.175 [Reporter](INFO): platform_buoyancy_position 606.874451 cc 2019-09-10T00:20:42.571Z,1568074842.571 [Reporter](INFO): platform_buoyancy_position 602.845459 cc 2019-09-10T00:20:43.006Z,1568074843.006 [Reporter](INFO): platform_buoyancy_position 597.204834 cc 2019-09-10T00:20:43.415Z,1568074843.415 [Reporter](INFO): platform_buoyancy_position 592.907288 cc 2019-09-10T00:20:43.787Z,1568074843.787 [Reporter](INFO): platform_buoyancy_position 588.475342 cc 2019-09-10T00:20:44.203Z,1568074844.203 [Reporter](INFO): platform_buoyancy_position 582.163269 cc 2019-09-10T00:20:44.530Z,1568074844.530 [NAL9602](INFO): NAL9602 initialized 2019-09-10T00:20:44.591Z,1568074844.591 [Reporter](INFO): platform_buoyancy_position 577.731384 cc 2019-09-10T00:20:45.001Z,1568074845.001 [Reporter](INFO): platform_buoyancy_position 571.553528 cc 2019-09-10T00:20:45.353Z,1568074845.353 [NAL9602](DEBUG): Fix Requested 2019-09-10T00:20:45.424Z,1568074845.424 [Reporter](INFO): platform_buoyancy_position 566.987366 cc 2019-09-10T00:20:45.819Z,1568074845.819 [Reporter](INFO): platform_buoyancy_position 560.675232 cc 2019-09-10T00:20:46.231Z,1568074846.231 [Reporter](INFO): platform_buoyancy_position 556.243347 cc 2019-09-10T00:20:46.603Z,1568074846.603 [Reporter](INFO): platform_buoyancy_position 551.811462 cc 2019-09-10T00:20:47.026Z,1568074847.026 [Reporter](INFO): platform_buoyancy_position 545.499329 cc 2019-09-10T00:20:47.436Z,1568074847.436 [Reporter](INFO): platform_buoyancy_position 541.067444 cc 2019-09-10T00:20:47.858Z,1568074847.858 [Reporter](INFO): platform_buoyancy_position 534.889648 cc 2019-09-10T00:20:48.227Z,1568074848.227 [Reporter](INFO): platform_buoyancy_position 530.457764 cc 2019-09-10T00:20:48.635Z,1568074848.635 [Reporter](INFO): platform_buoyancy_position 524.145630 cc 2019-09-10T00:20:49.075Z,1568074849.075 [Reporter](INFO): platform_buoyancy_position 519.713745 cc 2019-09-10T00:20:49.455Z,1568074849.455 [Reporter](INFO): platform_buoyancy_position 513.267334 cc 2019-09-10T00:20:49.867Z,1568074849.867 [Reporter](INFO): platform_buoyancy_position 508.969696 cc 2019-09-10T00:20:50.251Z,1568074850.251 [Reporter](INFO): platform_buoyancy_position 502.523376 cc 2019-09-10T00:20:50.662Z,1568074850.662 [Reporter](INFO): platform_buoyancy_position 498.225800 cc 2019-09-10T00:20:51.083Z,1568074851.083 [Reporter](INFO): platform_buoyancy_position 491.913635 cc 2019-09-10T00:20:51.451Z,1568074851.451 [Reporter](INFO): platform_buoyancy_position 487.616028 cc 2019-09-10T00:20:51.874Z,1568074851.874 [Reporter](INFO): platform_buoyancy_position 481.169678 cc 2019-09-10T00:20:52.283Z,1568074852.283 [Reporter](INFO): platform_buoyancy_position 476.737732 cc 2019-09-10T00:20:52.683Z,1568074852.683 [Reporter](INFO): platform_buoyancy_position 470.291321 cc 2019-09-10T00:20:53.110Z,1568074853.110 [Reporter](INFO): platform_buoyancy_position 465.993774 cc 2019-09-10T00:20:53.487Z,1568074853.487 [Reporter](INFO): platform_buoyancy_position 461.293274 cc 2019-09-10T00:20:53.903Z,1568074853.903 [Reporter](INFO): platform_buoyancy_position 455.249756 cc 2019-09-10T00:20:54.315Z,1568074854.315 [Reporter](INFO): platform_buoyancy_position 450.952148 cc 2019-09-10T00:20:54.687Z,1568074854.687 [Reporter](INFO): platform_buoyancy_position 444.505737 cc 2019-09-10T00:20:55.115Z,1568074855.115 [Reporter](INFO): platform_buoyancy_position 440.208130 cc 2019-09-10T00:20:55.510Z,1568074855.510 [Reporter](INFO): platform_buoyancy_position 434.030334 cc 2019-09-10T00:20:55.911Z,1568074855.911 [Reporter](INFO): platform_buoyancy_position 429.464172 cc 2019-09-10T00:20:56.310Z,1568074856.310 [Reporter](INFO): platform_buoyancy_position 423.152039 cc 2019-09-10T00:20:56.715Z,1568074856.715 [Reporter](INFO): platform_buoyancy_position 418.720154 cc 2019-09-10T00:20:57.131Z,1568074857.131 [Reporter](INFO): platform_buoyancy_position 412.408020 cc 2019-09-10T00:20:57.558Z,1568074857.558 [Reporter](INFO): platform_buoyancy_position 407.976135 cc 2019-09-10T00:21:28.487Z,1568074888.487 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 400.000000 cubic_centimeter 2019-09-10T00:21:34.366Z,1568074894.366 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter 2019-09-10T00:21:35.547Z,1568074895.547 [Reporter](INFO): platform_buoyancy_position 399.515259 cc 2019-09-10T00:21:36.356Z,1568074896.356 [Reporter](INFO): platform_buoyancy_position 397.232117 cc 2019-09-10T00:21:36.715Z,1568074896.715 [Reporter](INFO): platform_buoyancy_position 390.785767 cc 2019-09-10T00:21:37.142Z,1568074897.142 [Reporter](INFO): platform_buoyancy_position 386.622437 cc 2019-09-10T00:21:37.515Z,1568074897.515 [Reporter](INFO): platform_buoyancy_position 382.324829 cc 2019-09-10T00:21:37.911Z,1568074897.911 [Reporter](INFO): platform_buoyancy_position 377.892944 cc 2019-09-10T00:21:38.334Z,1568074898.334 [Reporter](INFO): platform_buoyancy_position 373.729614 cc 2019-09-10T00:21:38.755Z,1568074898.755 [Reporter](INFO): platform_buoyancy_position 367.283264 cc 2019-09-10T00:21:39.155Z,1568074899.155 [Reporter](INFO): platform_buoyancy_position 362.851318 cc 2019-09-10T00:21:39.575Z,1568074899.575 [Reporter](INFO): platform_buoyancy_position 358.553711 cc 2019-09-10T00:21:39.966Z,1568074899.966 [Reporter](INFO): platform_buoyancy_position 352.241638 cc 2019-09-10T00:21:40.391Z,1568074900.391 [Reporter](INFO): platform_buoyancy_position 347.809753 cc 2019-09-10T00:21:40.751Z,1568074900.751 [Reporter](INFO): platform_buoyancy_position 343.512146 cc 2019-09-10T00:21:41.171Z,1568074901.171 [Reporter](INFO): platform_buoyancy_position 337.065735 cc 2019-09-10T00:21:41.571Z,1568074901.571 [Reporter](INFO): platform_buoyancy_position 332.365234 cc 2019-09-10T00:21:41.963Z,1568074901.963 [Reporter](INFO): platform_buoyancy_position 326.456055 cc 2019-09-10T00:21:42.395Z,1568074902.395 [Reporter](INFO): platform_buoyancy_position 322.024109 cc 2019-09-10T00:21:42.763Z,1568074902.763 [Reporter](INFO): platform_buoyancy_position 315.712036 cc 2019-09-10T00:21:43.207Z,1568074903.207 [Reporter](INFO): platform_buoyancy_position 310.742950 cc 2019-09-10T00:21:43.602Z,1568074903.602 [Reporter](INFO): platform_buoyancy_position 305.102325 cc 2019-09-10T00:21:43.985Z,1568074903.985 [Reporter](INFO): platform_buoyancy_position 300.401825 cc 2019-09-10T00:21:44.426Z,1568074904.426 [Reporter](INFO): platform_buoyancy_position 294.089722 cc 2019-09-10T00:21:44.799Z,1568074904.799 [Reporter](INFO): platform_buoyancy_position 289.657837 cc 2019-09-10T00:21:45.203Z,1568074905.203 [Reporter](INFO): platform_buoyancy_position 283.345734 cc 2019-09-10T00:21:45.610Z,1568074905.610 [Reporter](INFO): platform_buoyancy_position 279.048126 cc 2019-09-10T00:21:46.017Z,1568074906.017 [Reporter](INFO): platform_buoyancy_position 273.004639 cc 2019-09-10T00:21:46.412Z,1568074906.412 [Reporter](INFO): platform_buoyancy_position 268.304138 cc 2019-09-10T00:21:46.799Z,1568074906.799 [Reporter](INFO): platform_buoyancy_position 261.992035 cc 2019-09-10T00:21:47.211Z,1568074907.211 [Reporter](INFO): platform_buoyancy_position 257.560120 cc 2019-09-10T00:21:47.619Z,1568074907.619 [Reporter](INFO): platform_buoyancy_position 251.650925 cc 2019-09-10T00:21:48.051Z,1568074908.051 [Reporter](INFO): platform_buoyancy_position 246.950424 cc 2019-09-10T00:21:48.447Z,1568074908.447 [Reporter](INFO): platform_buoyancy_position 240.772644 cc 2019-09-10T00:21:48.846Z,1568074908.846 [Reporter](INFO): platform_buoyancy_position 236.206421 cc 2019-09-10T00:21:49.271Z,1568074909.271 [Reporter](INFO): platform_buoyancy_position 229.894318 cc 2019-09-10T00:21:49.647Z,1568074909.647 [Reporter](INFO): platform_buoyancy_position 225.462433 cc 2019-09-10T00:21:50.071Z,1568074910.071 [Reporter](INFO): platform_buoyancy_position 220.896240 cc 2019-09-10T00:21:50.446Z,1568074910.446 [Reporter](INFO): platform_buoyancy_position 216.464325 cc 2019-09-10T00:21:54.251Z,1568074914.251 [Reporter](INFO): platform_buoyancy_position 199.676819 cc 2019-09-10T00:22:04.143Z,1568074924.143 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-09-10T00:22:17.359Z,1568074937.359 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100.000000 cubic_centimeter 2019-09-10T00:22:22.378Z,1568074942.378 [Reporter](INFO): platform_buoyancy_position 197.528015 cc 2019-09-10T00:22:22.758Z,1568074942.758 [Reporter](INFO): platform_buoyancy_position 191.081635 cc 2019-09-10T00:22:23.174Z,1568074943.174 [Reporter](INFO): platform_buoyancy_position 187.052643 cc 2019-09-10T00:22:23.574Z,1568074943.574 [Reporter](INFO): platform_buoyancy_position 183.157928 cc 2019-09-10T00:22:24.010Z,1568074944.010 [Reporter](INFO): platform_buoyancy_position 178.054535 cc 2019-09-10T00:22:24.410Z,1568074944.410 [Reporter](INFO): platform_buoyancy_position 172.548218 cc 2019-09-10T00:22:24.815Z,1568074944.815 [Reporter](INFO): platform_buoyancy_position 167.310516 cc 2019-09-10T00:22:25.199Z,1568074945.199 [Reporter](INFO): platform_buoyancy_position 163.012939 cc 2019-09-10T00:22:25.607Z,1568074945.607 [Reporter](INFO): platform_buoyancy_position 157.640930 cc 2019-09-10T00:22:26.001Z,1568074946.001 [Reporter](INFO): platform_buoyancy_position 152.403229 cc 2019-09-10T00:22:26.399Z,1568074946.399 [Reporter](INFO): platform_buoyancy_position 147.837036 cc 2019-09-10T00:22:26.819Z,1568074946.819 [Reporter](INFO): platform_buoyancy_position 141.659210 cc 2019-09-10T00:22:27.231Z,1568074947.231 [Reporter](INFO): platform_buoyancy_position 136.824432 cc 2019-09-10T00:22:27.631Z,1568074947.631 [Reporter](INFO): platform_buoyancy_position 130.915222 cc 2019-09-10T00:22:28.047Z,1568074948.047 [Reporter](INFO): platform_buoyancy_position 126.483337 cc 2019-09-10T00:22:28.470Z,1568074948.470 [Reporter](INFO): platform_buoyancy_position 120.171234 cc 2019-09-10T00:22:28.842Z,1568074948.842 [Reporter](INFO): platform_buoyancy_position 115.739319 cc 2019-09-10T00:22:29.283Z,1568074949.283 [Reporter](INFO): platform_buoyancy_position 109.561523 cc 2019-09-10T00:23:04.347Z,1568074984.347 [CommandLine](IMPORTANT): got command maintain clear 2019-09-10T00:23:04.363Z,1568074984.363 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-09-10T00:23:05.642Z,1568074985.642 [Reporter](INFO): platform_buoyancy_position 100.697723 cc 2019-09-10T00:23:05.995Z,1568074985.995 [Reporter](INFO): platform_buoyancy_position 102.712219 cc 2019-09-10T00:23:06.415Z,1568074986.415 [Reporter](INFO): platform_buoyancy_position 102.846527 cc 2019-09-10T00:23:06.818Z,1568074986.818 [Reporter](INFO): platform_buoyancy_position 104.995331 cc 2019-09-10T00:23:07.202Z,1568074987.202 [Reporter](INFO): platform_buoyancy_position 109.427216 cc 2019-09-10T00:23:07.615Z,1568074987.615 [Reporter](INFO): platform_buoyancy_position 115.739319 cc 2019-09-10T00:23:08.007Z,1568074988.007 [Reporter](INFO): platform_buoyancy_position 120.171234 cc 2019-09-10T00:23:08.422Z,1568074988.422 [Reporter](INFO): platform_buoyancy_position 126.483337 cc 2019-09-10T00:23:08.818Z,1568074988.818 [Reporter](INFO): platform_buoyancy_position 130.780914 cc 2019-09-10T00:23:09.235Z,1568074989.235 [Reporter](INFO): platform_buoyancy_position 137.361633 cc 2019-09-10T00:23:09.631Z,1568074989.631 [Reporter](INFO): platform_buoyancy_position 141.793518 cc 2019-09-10T00:23:10.070Z,1568074990.070 [Reporter](INFO): platform_buoyancy_position 148.105621 cc 2019-09-10T00:23:10.431Z,1568074990.431 [Reporter](INFO): platform_buoyancy_position 152.403229 cc 2019-09-10T00:23:10.847Z,1568074990.847 [Reporter](INFO): platform_buoyancy_position 158.715332 cc 2019-09-10T00:23:11.262Z,1568074991.262 [Reporter](INFO): platform_buoyancy_position 163.147217 cc 2019-09-10T00:23:11.647Z,1568074991.647 [Reporter](INFO): platform_buoyancy_position 169.459320 cc 2019-09-10T00:23:12.071Z,1568074992.071 [Reporter](INFO): platform_buoyancy_position 174.025513 cc 2019-09-10T00:23:12.459Z,1568074992.459 [Reporter](INFO): platform_buoyancy_position 180.203339 cc 2019-09-10T00:23:12.872Z,1568074992.872 [Reporter](INFO): platform_buoyancy_position 184.635223 cc 2019-09-10T00:23:13.278Z,1568074993.278 [Reporter](INFO): platform_buoyancy_position 190.813019 cc 2019-09-10T00:23:13.659Z,1568074993.659 [Reporter](INFO): platform_buoyancy_position 195.244934 cc 2019-09-10T00:23:14.116Z,1568074994.116 [Reporter](INFO): platform_buoyancy_position 201.825623 cc 2019-09-10T00:23:14.486Z,1568074994.486 [Reporter](INFO): platform_buoyancy_position 206.123230 cc 2019-09-10T00:23:14.872Z,1568074994.872 [Reporter](INFO): platform_buoyancy_position 211.360931 cc 2019-09-10T00:23:15.299Z,1568074995.299 [Reporter](INFO): platform_buoyancy_position 216.732941 cc 2019-09-10T00:23:15.703Z,1568074995.703 [Reporter](INFO): platform_buoyancy_position 221.299133 cc 2019-09-10T00:23:16.103Z,1568074996.103 [Reporter](INFO): platform_buoyancy_position 227.476929 cc 2019-09-10T00:23:16.506Z,1568074996.506 [Reporter](INFO): platform_buoyancy_position 231.908844 cc 2019-09-10T00:23:16.891Z,1568074996.891 [Reporter](INFO): platform_buoyancy_position 238.220947 cc 2019-09-10T00:23:17.308Z,1568074997.308 [Reporter](INFO): platform_buoyancy_position 242.518524 cc 2019-09-10T00:23:17.719Z,1568074997.719 [Reporter](INFO): platform_buoyancy_position 248.830627 cc 2019-09-10T00:23:18.114Z,1568074998.114 [Reporter](INFO): platform_buoyancy_position 253.396805 cc 2019-09-10T00:23:18.514Z,1568074998.514 [Reporter](INFO): platform_buoyancy_position 259.708923 cc 2019-09-10T00:23:18.915Z,1568074998.915 [Reporter](INFO): platform_buoyancy_position 264.140839 cc 2019-09-10T00:23:19.338Z,1568074999.338 [Reporter](INFO): platform_buoyancy_position 270.587250 cc 2019-09-10T00:23:19.747Z,1568074999.747 [Reporter](INFO): platform_buoyancy_position 274.884827 cc 2019-09-10T00:23:20.150Z,1568075000.150 [Reporter](INFO): platform_buoyancy_position 280.928345 cc 2019-09-10T00:23:20.551Z,1568075000.551 [Reporter](INFO): platform_buoyancy_position 285.628845 cc 2019-09-10T00:23:20.947Z,1568075000.947 [Reporter](INFO): platform_buoyancy_position 289.792145 cc 2019-09-10T00:23:21.390Z,1568075001.390 [Reporter](INFO): platform_buoyancy_position 296.238525 cc 2019-09-10T00:23:21.743Z,1568075001.743 [Reporter](INFO): platform_buoyancy_position 300.670441 cc 2019-09-10T00:23:22.170Z,1568075002.170 [Reporter](INFO): platform_buoyancy_position 307.116852 cc 2019-09-10T00:23:22.555Z,1568075002.555 [Reporter](INFO): platform_buoyancy_position 311.548737 cc 2019-09-10T00:23:22.965Z,1568075002.965 [Reporter](INFO): platform_buoyancy_position 317.860840 cc 2019-09-10T00:23:23.375Z,1568075003.375 [Reporter](INFO): platform_buoyancy_position 322.024109 cc 2019-09-10T00:23:23.791Z,1568075003.791 [Reporter](INFO): platform_buoyancy_position 328.470520 cc 2019-09-10T00:23:24.194Z,1568075004.194 [Reporter](INFO): platform_buoyancy_position 332.768127 cc 2019-09-10T00:23:24.586Z,1568075004.586 [Reporter](INFO): platform_buoyancy_position 337.200012 cc 2019-09-10T00:23:24.975Z,1568075004.975 [Reporter](INFO): platform_buoyancy_position 343.646423 cc 2019-09-10T00:23:25.383Z,1568075005.383 [Reporter](INFO): platform_buoyancy_position 348.346924 cc 2019-09-10T00:23:25.799Z,1568075005.799 [Reporter](INFO): platform_buoyancy_position 354.256165 cc 2019-09-10T00:23:26.180Z,1568075006.180 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 400.000000 cubic_centimeter 2019-09-10T00:23:26.181Z,1568075006.181 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2019-09-10T00:23:26.270Z,1568075006.270 [Reporter](INFO): platform_buoyancy_position 358.822327 cc 2019-09-10T00:23:26.274Z,1568075006.274 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-09-10T00:23:26.634Z,1568075006.634 [Reporter](INFO): platform_buoyancy_position 365.000122 cc 2019-09-10T00:23:30.667Z,1568075010.667 [Reporter](INFO): platform_buoyancy_position 375.609863 cc 2019-09-10T00:23:31.062Z,1568075011.062 [Reporter](INFO): platform_buoyancy_position 375.878418 cc 2019-09-10T00:23:31.843Z,1568075011.843 [Reporter](INFO): platform_buoyancy_position 376.012756 cc 2019-09-10T00:23:32.251Z,1568075012.251 [Reporter](INFO): platform_buoyancy_position 378.027222 cc 2019-09-10T00:23:32.658Z,1568075012.658 [Reporter](INFO): platform_buoyancy_position 378.161560 cc 2019-09-10T00:23:33.091Z,1568075013.091 [Reporter](INFO): platform_buoyancy_position 380.041748 cc 2019-09-10T00:23:33.479Z,1568075013.479 [Reporter](INFO): platform_buoyancy_position 381.653320 cc 2019-09-10T00:23:33.859Z,1568075013.859 [Reporter](INFO): platform_buoyancy_position 382.324829 cc 2019-09-10T00:23:34.279Z,1568075014.279 [Reporter](INFO): platform_buoyancy_position 384.473633 cc 2019-09-10T00:23:34.670Z,1568075014.670 [Reporter](INFO): platform_buoyancy_position 384.607971 cc 2019-09-10T00:23:35.118Z,1568075015.118 [Reporter](INFO): platform_buoyancy_position 386.488159 cc 2019-09-10T00:23:35.480Z,1568075015.480 [Reporter](INFO): platform_buoyancy_position 388.636963 cc 2019-09-10T00:23:36.307Z,1568075016.307 [Reporter](INFO): platform_buoyancy_position 390.785767 cc 2019-09-10T00:24:25.521Z,1568075065.521 [NAL9602](INFO): SBD MO Status=2, MOMSN=647, MT Status=2, MTMSN=0 2019-09-10T00:24:25.521Z,1568075065.521 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-09-10T00:24:34.012Z,1568075074.012 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-09-10T00:24:34.817Z,1568075074.817 [NAL9602](DEBUG): Fix Requested 2019-09-10T00:24:44.109Z,1568075084.109 [NAL9602](DEBUG): Fix Requested 2019-09-10T00:24:44.503Z,1568075084.503 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002249.00,A,3648.16478,N,12147.27828,W,0.447,0.00,100919,,,A*75 2019-09-10T00:24:44.506Z,1568075084.506 [NAL9602](INFO): GPS fix at 20190910T002249: (36.802746, -121.787971) 2019-09-10T00:24:44.536Z,1568075084.536 [Default:CheckIn:Read_GPS] Stopped 2019-09-10T00:24:44.536Z,1568075084.536 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-09-10T00:24:44.945Z,1568075084.945 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-09-10T00:24:52.481Z,1568075092.481 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20190910T000819/Courier0004.lzma 2019-09-10T00:24:53.286Z,1568075093.286 [DataOverHttps](INFO): Moved sent file to Logs/20190910T000819/Courier0004.lzma.bak 2019-09-10T00:24:53.286Z,1568075093.286 [DataOverHttps](INFO): SBD MOMSN=11758891 2019-09-10T00:25:09.490Z,1568075109.490 [DataOverHttps](INFO): Sending 1723 bytes from file Logs/20190910T000819/Express0005.lzma 2019-09-10T00:25:10.158Z,1568075110.158 [DataOverHttps](INFO): Moved sent file to Logs/20190910T000819/Express0005.lzma.bak 2019-09-10T00:25:10.158Z,1568075110.158 [DataOverHttps](INFO): SBD MOMSN=11758894 2019-09-10T00:25:14.536Z,1568075114.536 [Default:CheckIn:Read_Iridium] Stopped 2019-09-10T00:25:14.536Z,1568075114.536 [Default:CheckIn:C.Wait] Running Loop=1 2019-09-10T00:25:14.536Z,1568075114.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-09-10T00:25:16.938Z,1568075116.938 [NAL9602](INFO): Not Powering down - fast GPS 2019-09-10T00:27:38.350Z,1568075258.350 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2019-09-10T00:28:58.851Z,1568075338.851 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200.000000 cubic_centimeter 2019-09-10T00:29:03.695Z,1568075343.695 [Reporter](INFO): platform_buoyancy_position 397.232117 cc 2019-09-10T00:29:04.467Z,1568075344.467 [Reporter](INFO): platform_buoyancy_position 395.217651 cc 2019-09-10T00:29:04.879Z,1568075344.879 [Reporter](INFO): platform_buoyancy_position 388.771240 cc 2019-09-10T00:29:05.291Z,1568075345.291 [Reporter](INFO): platform_buoyancy_position 384.339355 cc 2019-09-10T00:29:05.675Z,1568075345.675 [Reporter](INFO): platform_buoyancy_position 382.056213 cc 2019-09-10T00:29:06.095Z,1568075346.095 [Reporter](INFO): platform_buoyancy_position 375.878418 cc 2019-09-10T00:29:06.487Z,1568075346.487 [Reporter](INFO): platform_buoyancy_position 371.580872 cc 2019-09-10T00:29:06.891Z,1568075346.891 [Reporter](INFO): platform_buoyancy_position 367.148926 cc 2019-09-10T00:29:07.310Z,1568075347.310 [Reporter](INFO): platform_buoyancy_position 360.836853 cc 2019-09-10T00:29:07.691Z,1568075347.691 [Reporter](INFO): platform_buoyancy_position 356.539246 cc 2019-09-10T00:29:08.110Z,1568075348.110 [Reporter](INFO): platform_buoyancy_position 349.958557 cc 2019-09-10T00:29:08.495Z,1568075348.495 [Reporter](INFO): platform_buoyancy_position 345.929565 cc 2019-09-10T00:29:08.907Z,1568075348.907 [Reporter](INFO): platform_buoyancy_position 341.229065 cc 2019-09-10T00:29:09.311Z,1568075349.311 [Reporter](INFO): platform_buoyancy_position 335.051270 cc 2019-09-10T00:29:09.719Z,1568075349.719 [Reporter](INFO): platform_buoyancy_position 330.485046 cc 2019-09-10T00:29:10.135Z,1568075350.135 [Reporter](INFO): platform_buoyancy_position 324.172913 cc 2019-09-10T00:29:10.542Z,1568075350.542 [Reporter](INFO): platform_buoyancy_position 318.800964 cc 2019-09-10T00:29:10.923Z,1568075350.923 [Reporter](INFO): platform_buoyancy_position 313.697510 cc 2019-09-10T00:29:11.343Z,1568075351.343 [Reporter](INFO): platform_buoyancy_position 308.459839 cc 2019-09-10T00:29:11.750Z,1568075351.750 [Reporter](INFO): platform_buoyancy_position 302.684937 cc 2019-09-10T00:29:12.147Z,1568075352.147 [Reporter](INFO): platform_buoyancy_position 298.253052 cc 2019-09-10T00:29:12.543Z,1568075352.543 [Reporter](INFO): platform_buoyancy_position 292.075226 cc 2019-09-10T00:29:12.947Z,1568075352.947 [Reporter](INFO): platform_buoyancy_position 287.643341 cc 2019-09-10T00:29:13.363Z,1568075353.363 [Reporter](INFO): platform_buoyancy_position 281.331238 cc 2019-09-10T00:29:13.767Z,1568075353.767 [Reporter](INFO): platform_buoyancy_position 276.765045 cc 2019-09-10T00:29:14.186Z,1568075354.186 [Reporter](INFO): platform_buoyancy_position 270.452942 cc 2019-09-10T00:29:14.563Z,1568075354.563 [Reporter](INFO): platform_buoyancy_position 266.289642 cc 2019-09-10T00:29:14.992Z,1568075354.992 [Reporter](INFO): platform_buoyancy_position 260.246124 cc 2019-09-10T00:29:15.399Z,1568075355.399 [Reporter](INFO): platform_buoyancy_position 255.411346 cc 2019-09-10T00:29:15.775Z,1568075355.775 [Reporter](INFO): platform_buoyancy_position 249.367813 cc 2019-09-10T00:29:16.193Z,1568075356.193 [Reporter](INFO): platform_buoyancy_position 244.801636 cc 2019-09-10T00:29:16.588Z,1568075356.588 [Reporter](INFO): platform_buoyancy_position 240.369720 cc 2019-09-10T00:29:17.009Z,1568075357.009 [Reporter](INFO): platform_buoyancy_position 234.057617 cc 2019-09-10T00:29:17.423Z,1568075357.423 [Reporter](INFO): platform_buoyancy_position 227.745544 cc 2019-09-10T00:29:17.794Z,1568075357.794 [Reporter](INFO): platform_buoyancy_position 223.313629 cc 2019-09-10T00:29:18.207Z,1568075358.207 [Reporter](INFO): platform_buoyancy_position 218.881744 cc 2019-09-10T00:29:18.626Z,1568075358.626 [Reporter](INFO): platform_buoyancy_position 212.569641 cc 2019-09-10T00:29:19.062Z,1568075359.062 [Reporter](INFO): platform_buoyancy_position 208.003418 cc 2019-09-10T00:30:15.135Z,1568075415.135 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-09-10T00:30:15.135Z,1568075415.135 [Default:CheckIn:C.Wait] Stopped 2019-09-10T00:30:15.135Z,1568075415.135 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-09-10T00:30:15.135Z,1568075415.135 [Default:CheckIn:D] Running Loop=1 2019-09-10T00:30:15.524Z,1568075415.524 [Default:CheckIn:D] Stopped 2019-09-10T00:30:15.524Z,1568075415.524 [Default:CheckIn:E] Running Loop=1 2019-09-10T00:30:15.940Z,1568075415.940 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.445228 min 2019-09-10T00:30:15.940Z,1568075415.940 [Default:CheckIn:E] Stopped 2019-09-10T00:30:15.940Z,1568075415.940 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-09-10T00:30:15.940Z,1568075415.940 [Default:CheckIn] Stopped 2019-09-10T00:30:15.940Z,1568075415.940 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-09-10T00:30:15.940Z,1568075415.940 [Default:CheckIn](INFO): Running loop #2 2019-09-10T00:30:15.941Z,1568075415.941 [Default:CheckIn] Running Loop=2 2019-09-10T00:30:15.941Z,1568075415.941 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-09-10T00:30:15.941Z,1568075415.941 [Default:CheckIn:Read_GPS] Running Loop=1 2019-09-10T00:30:17.537Z,1568075417.537 [NAL9602](DEBUG): Fix Requested 2019-09-10T00:30:17.931Z,1568075417.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002822.00,A,3648.16429,N,12147.28311,W,0.058,0.00,100919,,,D*77 2019-09-10T00:30:17.934Z,1568075417.934 [NAL9602](INFO): GPS fix at 20190910T002822: (36.802738, -121.788052) 2019-09-10T00:30:17.961Z,1568075417.961 [Default:CheckIn:Read_GPS] Stopped 2019-09-10T00:30:17.961Z,1568075417.961 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-09-10T00:30:25.904Z,1568075425.904 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190910T000819/Courier0007.lzma 2019-09-10T00:30:26.710Z,1568075426.710 [DataOverHttps](INFO): Moved sent file to Logs/20190910T000819/Courier0007.lzma.bak 2019-09-10T00:30:26.710Z,1568075426.710 [DataOverHttps](INFO): SBD MOMSN=11759013 2019-09-10T00:30:42.053Z,1568075442.053 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20190910T000819/Express0008.lzma 2019-09-10T00:30:42.858Z,1568075442.858 [DataOverHttps](INFO): Moved sent file to Logs/20190910T000819/Express0008.lzma.bak 2019-09-10T00:30:42.858Z,1568075442.858 [DataOverHttps](INFO): SBD MOMSN=11759016 2019-09-10T00:30:47.075Z,1568075447.075 [Default:CheckIn:Read_Iridium] Stopped 2019-09-10T00:30:47.075Z,1568075447.075 [Default:CheckIn:C.Wait] Running Loop=1 2019-09-10T00:30:47.075Z,1568075447.075 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-09-10T00:30:48.630Z,1568075448.630 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2019-09-10T00:30:48.713Z,1568075448.713 [NAL9602](FAULT): received: +CSQ:1 OK7, 2, 0, 0, 0 OK 2019-09-10T00:30:48.713Z,1568075448.713 [NAL9602] Data Fault, FailCount= 3 2019-09-10T00:30:48.713Z,1568075448.713 [NAL9602](ERROR): Data Fault 2019-09-10T00:30:48.780Z,1568075448.780 [CBIT](ERROR): Data Fault in component: NAL9602 2019-09-10T00:30:49.034Z,1568075449.034 [NAL9602](INFO): Powering down 2019-09-10T00:30:49.897Z,1568075449.897 [CBIT](INFO): Clearing failed state for component NAL9602 2019-09-10T00:30:49.897Z,1568075449.897 [NAL9602] No Fault, FailCount= 3 2019-09-10T00:31:19.344Z,1568075479.344 [NAL9602](INFO): Powering up NAL9602 2019-09-10T00:31:30.246Z,1568075490.246 [NAL9602](INFO): NAL9602 initialized 2019-09-10T00:32:01.354Z,1568075521.354 [NAL9602](INFO): Not Powering down - fast GPS 2019-09-10T00:32:55.405Z,1568075575.405 [CommandLine](IMPORTANT): got command maintain clear 2019-09-10T00:32:55.522Z,1568075575.522 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-09-10T00:32:56.791Z,1568075576.791 [Reporter](INFO): platform_buoyancy_position 201.825623 cc 2019-09-10T00:32:57.583Z,1568075577.583 [Reporter](INFO): platform_buoyancy_position 203.974426 cc 2019-09-10T00:32:57.967Z,1568075577.967 [Reporter](INFO): platform_buoyancy_position 205.988922 cc 2019-09-10T00:32:58.387Z,1568075578.387 [Reporter](INFO): platform_buoyancy_position 210.555145 cc 2019-09-10T00:32:58.795Z,1568075578.795 [Reporter](INFO): platform_buoyancy_position 216.598633 cc 2019-09-10T00:32:59.206Z,1568075579.206 [Reporter](INFO): platform_buoyancy_position 221.164825 cc 2019-09-10T00:32:59.587Z,1568075579.587 [Reporter](INFO): platform_buoyancy_position 225.462433 cc 2019-09-10T00:33:00.012Z,1568075580.012 [Reporter](INFO): platform_buoyancy_position 231.774536 cc 2019-09-10T00:33:00.415Z,1568075580.415 [Reporter](INFO): platform_buoyancy_position 237.549438 cc 2019-09-10T00:33:00.806Z,1568075580.806 [Reporter](INFO): platform_buoyancy_position 242.518524 cc 2019-09-10T00:33:01.230Z,1568075581.230 [Reporter](INFO): platform_buoyancy_position 247.084732 cc 2019-09-10T00:33:01.607Z,1568075581.607 [Reporter](INFO): platform_buoyancy_position 253.396805 cc 2019-09-10T00:33:02.054Z,1568075582.054 [Reporter](INFO): platform_buoyancy_position 259.708923 cc 2019-09-10T00:33:02.435Z,1568075582.435 [Reporter](INFO): platform_buoyancy_position 264.006531 cc 2019-09-10T00:33:02.819Z,1568075582.819 [Reporter](INFO): platform_buoyancy_position 268.707031 cc 2019-09-10T00:33:03.239Z,1568075583.239 [Reporter](INFO): platform_buoyancy_position 274.750549 cc 2019-09-10T00:33:03.639Z,1568075583.639 [Reporter](INFO): platform_buoyancy_position 279.048126 cc 2019-09-10T00:33:04.063Z,1568075584.063 [Reporter](INFO): platform_buoyancy_position 285.494537 cc 2019-09-10T00:33:04.451Z,1568075584.451 [Reporter](INFO): platform_buoyancy_position 289.926422 cc 2019-09-10T00:33:04.859Z,1568075584.859 [Reporter](INFO): platform_buoyancy_position 296.372833 cc 2019-09-10T00:33:05.259Z,1568075585.259 [Reporter](INFO): platform_buoyancy_position 300.670441 cc 2019-09-10T00:33:05.647Z,1568075585.647 [Reporter](INFO): platform_buoyancy_position 306.848236 cc 2019-09-10T00:33:06.091Z,1568075586.091 [Reporter](INFO): platform_buoyancy_position 311.414429 cc 2019-09-10T00:33:06.455Z,1568075586.455 [Reporter](INFO): platform_buoyancy_position 316.517822 cc 2019-09-10T00:33:06.867Z,1568075586.867 [Reporter](INFO): platform_buoyancy_position 322.024109 cc 2019-09-10T00:33:07.279Z,1568075587.279 [Reporter](INFO): platform_buoyancy_position 326.456055 cc 2019-09-10T00:33:07.683Z,1568075587.683 [Reporter](INFO): platform_buoyancy_position 332.768127 cc 2019-09-10T00:33:08.110Z,1568075588.110 [Reporter](INFO): platform_buoyancy_position 337.468628 cc 2019-09-10T00:33:08.491Z,1568075588.491 [Reporter](INFO): platform_buoyancy_position 343.512146 cc 2019-09-10T00:33:08.879Z,1568075588.879 [Reporter](INFO): platform_buoyancy_position 347.809753 cc 2019-09-10T00:33:09.287Z,1568075589.287 [Reporter](INFO): platform_buoyancy_position 353.853210 cc 2019-09-10T00:33:09.686Z,1568075589.686 [Reporter](INFO): platform_buoyancy_position 358.688049 cc 2019-09-10T00:33:10.103Z,1568075590.103 [Reporter](INFO): platform_buoyancy_position 365.000122 cc 2019-09-10T00:33:10.507Z,1568075590.507 [Reporter](INFO): platform_buoyancy_position 369.566345 cc 2019-09-10T00:33:10.923Z,1568075590.923 [Reporter](INFO): platform_buoyancy_position 375.475525 cc 2019-09-10T00:33:11.319Z,1568075591.319 [Reporter](INFO): platform_buoyancy_position 380.176025 cc 2019-09-10T00:33:11.722Z,1568075591.722 [Reporter](INFO): platform_buoyancy_position 384.473633 cc 2019-09-10T00:33:12.142Z,1568075592.142 [Reporter](INFO): platform_buoyancy_position 390.920044 cc 2019-09-10T00:33:12.515Z,1568075592.515 [Reporter](INFO): platform_buoyancy_position 395.351929 cc 2019-09-10T00:33:12.919Z,1568075592.919 [Reporter](INFO): platform_buoyancy_position 401.529724 cc 2019-09-10T00:33:13.338Z,1568075593.338 [Reporter](INFO): platform_buoyancy_position 407.976135 cc 2019-09-10T00:33:13.735Z,1568075593.735 [Reporter](INFO): platform_buoyancy_position 412.273743 cc 2019-09-10T00:33:14.158Z,1568075594.158 [Reporter](INFO): platform_buoyancy_position 417.108521 cc 2019-09-10T00:33:14.543Z,1568075594.543 [Reporter](INFO): platform_buoyancy_position 422.883423 cc 2019-09-10T00:33:14.951Z,1568075594.951 [Reporter](INFO): platform_buoyancy_position 427.583923 cc 2019-09-10T00:33:15.366Z,1568075595.366 [Reporter](INFO): platform_buoyancy_position 433.493164 cc 2019-09-10T00:33:15.751Z,1568075595.751 [Reporter](INFO): platform_buoyancy_position 438.193665 cc 2019-09-10T00:33:16.159Z,1568075596.159 [Reporter](INFO): platform_buoyancy_position 444.371460 cc 2019-09-10T00:33:16.563Z,1568075596.563 [Reporter](INFO): platform_buoyancy_position 448.937622 cc 2019-09-10T00:33:16.979Z,1568075596.979 [Reporter](INFO): platform_buoyancy_position 455.249756 cc 2019-09-10T00:33:17.382Z,1568075597.382 [Reporter](INFO): platform_buoyancy_position 459.547363 cc 2019-09-10T00:33:17.767Z,1568075597.767 [Reporter](INFO): platform_buoyancy_position 463.979248 cc 2019-09-10T00:33:18.187Z,1568075598.187 [Reporter](INFO): platform_buoyancy_position 470.291321 cc 2019-09-10T00:33:18.579Z,1568075598.579 [Reporter](INFO): platform_buoyancy_position 475.260437 cc 2019-09-10T00:33:18.991Z,1568075598.991 [Reporter](INFO): platform_buoyancy_position 481.169678 cc 2019-09-10T00:33:19.399Z,1568075599.399 [Reporter](INFO): platform_buoyancy_position 486.810242 cc 2019-09-10T00:33:19.799Z,1568075599.799 [Reporter](INFO): platform_buoyancy_position 491.779358 cc 2019-09-10T00:33:20.235Z,1568075600.235 [Reporter](INFO): platform_buoyancy_position 496.345581 cc 2019-09-10T00:33:20.631Z,1568075600.631 [Reporter](INFO): platform_buoyancy_position 502.791962 cc 2019-09-10T00:33:21.063Z,1568075601.063 [Reporter](INFO): platform_buoyancy_position 508.969696 cc 2019-09-10T00:33:21.430Z,1568075601.430 [Reporter](INFO): platform_buoyancy_position 513.401672 cc 2019-09-10T00:33:21.807Z,1568075601.807 [Reporter](INFO): platform_buoyancy_position 517.564941 cc 2019-09-10T00:33:22.271Z,1568075602.271 [Reporter](INFO): platform_buoyancy_position 524.011353 cc 2019-09-10T00:33:22.631Z,1568075602.631 [Reporter](INFO): platform_buoyancy_position 528.308960 cc 2019-09-10T00:33:23.067Z,1568075603.067 [Reporter](INFO): platform_buoyancy_position 534.755371 cc 2019-09-10T00:33:23.463Z,1568075603.463 [Reporter](INFO): platform_buoyancy_position 539.187256 cc 2019-09-10T00:33:23.834Z,1568075603.834 [Reporter](INFO): platform_buoyancy_position 543.619141 cc 2019-09-10T00:33:24.243Z,1568075604.243 [Reporter](INFO): platform_buoyancy_position 549.796936 cc 2019-09-10T00:33:24.631Z,1568075604.631 [Reporter](INFO): platform_buoyancy_position 554.363159 cc 2019-09-10T00:33:25.071Z,1568075605.071 [Reporter](INFO): platform_buoyancy_position 560.675232 cc 2019-09-10T00:33:25.439Z,1568075605.439 [Reporter](INFO): platform_buoyancy_position 564.972839 cc 2019-09-10T00:33:25.855Z,1568075605.855 [Reporter](INFO): platform_buoyancy_position 571.419250 cc 2019-09-10T00:33:26.271Z,1568075606.271 [Reporter](INFO): platform_buoyancy_position 575.716858 cc 2019-09-10T00:33:26.671Z,1568075606.671 [Reporter](INFO): platform_buoyancy_position 582.028931 cc 2019-09-10T00:33:27.095Z,1568075607.095 [Reporter](INFO): platform_buoyancy_position 586.460876 cc 2019-09-10T00:33:27.479Z,1568075607.479 [Reporter](INFO): platform_buoyancy_position 592.907288 cc 2019-09-10T00:33:27.867Z,1568075607.867 [Reporter](INFO): platform_buoyancy_position 597.070557 cc 2019-09-10T00:33:28.279Z,1568075608.279 [Reporter](INFO): platform_buoyancy_position 603.382629 cc 2019-09-10T00:33:28.679Z,1568075608.679 [Reporter](INFO): platform_buoyancy_position 607.814575 cc 2019-09-10T00:33:29.103Z,1568075609.103 [Reporter](INFO): platform_buoyancy_position 614.260986 cc 2019-09-10T00:33:29.491Z,1568075609.491 [Reporter](INFO): platform_buoyancy_position 618.827148 cc 2019-09-10T00:33:29.903Z,1568075609.903 [Reporter](INFO): platform_buoyancy_position 625.004944 cc 2019-09-10T00:33:30.307Z,1568075610.307 [Reporter](INFO): platform_buoyancy_position 629.302551 cc 2019-09-10T00:33:30.710Z,1568075610.710 [Reporter](INFO): platform_buoyancy_position 633.600159 cc 2019-09-10T00:33:31.122Z,1568075611.122 [Reporter](INFO): platform_buoyancy_position 640.046570 cc 2019-09-10T00:33:31.503Z,1568075611.503 [Reporter](INFO): platform_buoyancy_position 644.478455 cc 2019-09-10T00:33:31.903Z,1568075611.903 [Reporter](INFO): platform_buoyancy_position 650.924866 cc 2019-09-10T00:33:32.331Z,1568075612.331 [Reporter](INFO): platform_buoyancy_position 655.356750 cc 2019-09-10T00:33:32.723Z,1568075612.723 [Reporter](INFO): platform_buoyancy_position 661.534546 cc 2019-09-10T00:33:33.131Z,1568075613.131 [Reporter](INFO): platform_buoyancy_position 665.966492 cc 2019-09-10T00:33:33.527Z,1568075613.527 [Reporter](INFO): platform_buoyancy_position 672.278564 cc 2019-09-10T00:33:33.942Z,1568075613.942 [Reporter](INFO): platform_buoyancy_position 676.710449 cc 2019-09-10T00:33:34.358Z,1568075614.358 [Reporter](INFO): platform_buoyancy_position 683.156860 cc 2019-09-10T00:33:34.735Z,1568075614.735 [Reporter](INFO): platform_buoyancy_position 687.454468 cc 2019-09-10T00:33:35.159Z,1568075615.159 [Reporter](INFO): platform_buoyancy_position 693.766541 cc 2019-09-10T00:33:35.551Z,1568075615.551 [Reporter](INFO): platform_buoyancy_position 698.198486 cc 2019-09-10T00:33:35.951Z,1568075615.951 [Reporter](INFO): platform_buoyancy_position 704.510559 cc 2019-09-10T00:33:36.371Z,1568075616.371 [Reporter](INFO): platform_buoyancy_position 708.808167 cc 2019-09-10T00:33:36.751Z,1568075616.751 [Reporter](INFO): platform_buoyancy_position 713.240051 cc 2019-09-10T00:33:37.175Z,1568075617.175 [Reporter](INFO): platform_buoyancy_position 719.686462 cc 2019-09-10T00:33:37.559Z,1568075617.559 [Reporter](INFO): platform_buoyancy_position 724.252686 cc 2019-09-10T00:33:37.971Z,1568075617.971 [Reporter](INFO): platform_buoyancy_position 730.296143 cc 2019-09-10T00:33:38.383Z,1568075618.383 [Reporter](INFO): platform_buoyancy_position 734.862366 cc 2019-09-10T00:33:38.787Z,1568075618.787 [Reporter](INFO): platform_buoyancy_position 741.040161 cc 2019-09-10T00:33:39.199Z,1568075619.199 [Reporter](INFO): platform_buoyancy_position 745.472046 cc 2019-09-10T00:33:39.595Z,1568075619.595 [Reporter](INFO): platform_buoyancy_position 751.784180 cc 2019-09-10T00:33:40.031Z,1568075620.031 [Reporter](INFO): platform_buoyancy_position 756.216064 cc 2019-09-10T00:33:40.407Z,1568075620.407 [Reporter](INFO): platform_buoyancy_position 762.528198 cc 2019-09-10T00:33:40.810Z,1568075620.810 [Reporter](INFO): platform_buoyancy_position 766.825745 cc 2019-09-10T00:33:41.226Z,1568075621.226 [Reporter](INFO): platform_buoyancy_position 771.257690 cc 2019-09-10T00:33:41.603Z,1568075621.603 [Reporter](INFO): platform_buoyancy_position 777.704102 cc 2019-09-10T00:33:42.058Z,1568075622.058 [Reporter](INFO): platform_buoyancy_position 782.538879 cc 2019-09-10T00:33:42.423Z,1568075622.423 [Reporter](INFO): platform_buoyancy_position 788.313782 cc 2019-09-10T00:33:42.823Z,1568075622.823 [Reporter](INFO): platform_buoyancy_position 792.611389 cc 2019-09-10T00:33:43.246Z,1568075623.246 [Reporter](INFO): platform_buoyancy_position 799.057800 cc 2019-09-10T00:33:43.623Z,1568075623.623 [Reporter](INFO): platform_buoyancy_position 803.489685 cc 2019-09-10T00:33:44.071Z,1568075624.071 [Reporter](INFO): platform_buoyancy_position 809.936096 cc 2019-09-10T00:33:44.455Z,1568075624.455 [Reporter](INFO): platform_buoyancy_position 814.099365 cc 2019-09-10T00:33:44.839Z,1568075624.839 [Reporter](INFO): platform_buoyancy_position 820.545776 cc 2019-09-10T00:33:45.251Z,1568075625.251 [Reporter](INFO): platform_buoyancy_position 824.977661 cc 2019-09-10T00:33:45.643Z,1568075625.643 [Reporter](INFO): platform_buoyancy_position 831.155518 cc 2019-09-10T00:33:46.079Z,1568075626.079 [Reporter](INFO): platform_buoyancy_position 835.587402 cc 2019-09-10T00:33:46.459Z,1568075626.459 [Reporter](INFO): platform_buoyancy_position 840.690796 cc 2019-09-10T00:33:46.871Z,1568075626.871 [Reporter](INFO): platform_buoyancy_position 846.331421 cc 2019-09-10T00:33:47.263Z,1568075627.263 [Reporter](INFO): platform_buoyancy_position 851.031860 cc 2019-09-10T00:33:47.674Z,1568075627.674 [Reporter](INFO): platform_buoyancy_position 857.075317 cc 2019-09-10T00:33:48.095Z,1568075628.095 [Reporter](INFO): platform_buoyancy_position 862.581665 cc 2019-09-10T00:33:48.475Z,1568075628.475 [Reporter](INFO): platform_buoyancy_position 867.819336 cc 2019-09-10T00:33:48.871Z,1568075628.871 [Reporter](INFO): platform_buoyancy_position 872.251221 cc 2019-09-10T00:33:49.299Z,1568075629.299 [Reporter](INFO): platform_buoyancy_position 878.563354 cc 2019-09-10T00:33:49.691Z,1568075629.691 [Reporter](INFO): platform_buoyancy_position 882.995239 cc 2019-09-10T00:33:50.122Z,1568075630.122 [Reporter](INFO): platform_buoyancy_position 889.307373 cc 2019-09-10T00:33:50.495Z,1568075630.495 [Reporter](INFO): platform_buoyancy_position 893.739258 cc 2019-09-10T00:33:50.906Z,1568075630.906 [Reporter](INFO): platform_buoyancy_position 900.051392 cc 2019-09-10T00:33:51.331Z,1568075631.331 [Reporter](INFO): platform_buoyancy_position 906.497803 cc 2019-09-10T00:33:51.707Z,1568075631.707 [Reporter](INFO): platform_buoyancy_position 910.795410 cc 2019-09-10T00:33:52.115Z,1568075632.115 [Reporter](INFO): platform_buoyancy_position 915.227295 cc 2019-09-10T00:33:52.519Z,1568075632.519 [Reporter](INFO): platform_buoyancy_position 919.793457 cc 2019-09-10T00:33:52.923Z,1568075632.923 [Reporter](INFO): platform_buoyancy_position 925.971313 cc 2019-09-10T00:33:53.355Z,1568075633.355 [Reporter](INFO): platform_buoyancy_position 930.134644 cc 2019-09-10T00:33:53.723Z,1568075633.723 [Reporter](INFO): platform_buoyancy_position 934.566528 cc 2019-09-10T00:33:54.151Z,1568075634.151 [Reporter](INFO): platform_buoyancy_position 940.878540 cc 2019-09-10T00:33:54.575Z,1568075634.575 [Reporter](INFO): platform_buoyancy_position 945.310425 cc 2019-09-10T00:33:59.563Z,1568075639.563 [CommandLine](IMPORTANT): got command show variable potential 2019-09-10T00:33:59.573Z,1568075639.573 [CommandLine](IMPORTANT): sea_water_potential_density (unknown) 2019-09-10T00:33:59.573Z,1568075639.573 [CommandLine](IMPORTANT): sea_water_potential_temperature (unknown) 2019-09-10T00:33:59.687Z,1568075639.687 [DataOverHttps](IMPORTANT): SBD MTMSN=20190910T003205 2019-09-10T00:33:59.902Z,1568075639.902 [CommandLine](IMPORTANT): WetLabsUBAT.biolum_potential (none) 2019-09-10T00:34:05.251Z,1568075645.251 [DataOverHttps](INFO): Received command:sched asap "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 30 minute;set ballast_and_trim.SettleTime 5 minute;set ballast_and_trim.EstimationTimeout 20 minute;set ballast_and_trim.MinEstimationTime 7 minute" 2is4y 1 2 2019-09-10T00:34:05.350Z,1568075645.350 [CommandLine](IMPORTANT): got command schedule asap "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 30 minute;set ballast_and_trim.SettleTime 5 minute;set ballast_and_trim.EstimationTimeout 20 minute;set ballast_and_trim.MinEstimationTime 7 minute" 2is4y 1 2.000000 2019-09-10T00:34:05.350Z,1568075645.350 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=2is4y 2019-09-10T00:34:05.355Z,1568075645.355 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='2is4y'): "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 30 minute;set ballast_and_trim.SettleTime 5 minute;set ballast_and_trim.EstimationTimeout 20 minute;set ballast_and_trim.MinEstimationTime 7 minute" ASAP 2019-09-10T00:34:06.046Z,1568075646.046 [DataOverHttps](IMPORTANT): SBD MTMSN=20190910T003211 2019-09-10T00:34:10.599Z,1568075650.599 [CommandLine](IMPORTANT): got command exit 2019-09-10T00:34:11.564Z,1568075651.564 [DataOverHttps](INFO): Received command:sched asap "set ballast_and_trim.WaitToFloat 1 minute;run " 2is4y 2 2 2019-09-10T00:34:11.601Z,1568075651.601 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-10T00:34:11.601Z,1568075651.601 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:11.701Z,1568075651.701 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-09-10T00:34:11.701Z,1568075651.701 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:11.701Z,1568075651.701 [CommandLine](INFO): Join timeout helper Thread ID is 7355 2019-09-10T00:34:11.703Z,1568075651.703 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-09-10T00:34:11.703Z,1568075651.703 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:11.703Z,1568075651.703 [NavChartDb](INFO): Join timeout helper Thread ID is 7356 2019-09-10T00:34:12.097Z,1568075652.097 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-10T00:34:12.097Z,1568075652.097 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.113Z,1568075652.113 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2019-09-10T00:34:12.113Z,1568075652.113 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.113Z,1568075652.113 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7357 2019-09-10T00:34:12.421Z,1568075652.421 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-10T00:34:12.421Z,1568075652.421 [WetLabsUBAT](INFO): Powering down 2019-09-10T00:34:12.422Z,1568075652.422 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.441Z,1568075652.441 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-09-10T00:34:12.441Z,1568075652.441 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.441Z,1568075652.441 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7358 2019-09-10T00:34:12.609Z,1568075652.609 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-10T00:34:12.609Z,1568075652.609 [WetLabsBB2FL](INFO): Powering down 2019-09-10T00:34:12.610Z,1568075652.610 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.621Z,1568075652.621 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-09-10T00:34:12.621Z,1568075652.621 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.621Z,1568075652.621 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7359 2019-09-10T00:34:12.745Z,1568075652.745 [CTD_Seabird](INFO): Powering down 2019-09-10T00:34:12.757Z,1568075652.757 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-10T00:34:12.757Z,1568075652.757 [CTD_Seabird](INFO): Powering down 2019-09-10T00:34:12.769Z,1568075652.769 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.786Z,1568075652.786 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-09-10T00:34:12.786Z,1568075652.786 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.786Z,1568075652.786 [Radio_Surface](INFO): Join timeout helper Thread ID is 7360 2019-09-10T00:34:12.893Z,1568075652.893 [Radio_Surface](INFO): Powering down 2019-09-10T00:34:12.894Z,1568075652.894 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-10T00:34:12.894Z,1568075652.894 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.913Z,1568075652.913 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-09-10T00:34:12.913Z,1568075652.913 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:12.914Z,1568075652.914 [DataOverHttps](INFO): Join timeout helper Thread ID is 7361 2019-09-10T00:34:13.177Z,1568075653.177 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-10T00:34:13.177Z,1568075653.177 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.185Z,1568075653.185 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-09-10T00:34:13.186Z,1568075653.186 [logger ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.186Z,1568075653.186 [logger](INFO): Join timeout helper Thread ID is 7362 2019-09-10T00:34:13.193Z,1568075653.193 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-10T00:34:13.193Z,1568075653.193 [logger ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.205Z,1568075653.205 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-09-10T00:34:13.206Z,1568075653.206 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.206Z,1568075653.206 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-09-10T00:34:13.206Z,1568075653.206 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.206Z,1568075653.206 [controlThread](INFO): Join timeout helper Thread ID is 7363 2019-09-10T00:34:13.453Z,1568075653.453 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-09-10T00:34:13.453Z,1568075653.453 [controlThread](DEBUG): Uninitializing ControlThread 2019-09-10T00:34:13.453Z,1568075653.453 [AHRS_M2](INFO): Powering down 2019-09-10T00:34:13.525Z,1568075653.525 [NAL9602](INFO): Powering down 2019-09-10T00:34:13.597Z,1568075653.597 [RDI_Pathfinder](INFO): Powering down 2019-09-10T00:34:13.598Z,1568075653.598 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-09-10T00:34:13.599Z,1568075653.599 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-09-10T00:34:13.599Z,1568075653.599 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-09-10T00:34:13.600Z,1568075653.600 [MissionManager](INFO): Uninitializing Mission Default 2019-09-10T00:34:13.600Z,1568075653.600 [Default] Stopped 2019-09-10T00:34:13.600Z,1568075653.600 [Default](DEBUG): Aggregate::uninitialize Default 2019-09-10T00:34:13.600Z,1568075653.600 [Default:B.GoToSurface] Stopped 2019-09-10T00:34:13.600Z,1568075653.600 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-09-10T00:34:13.600Z,1568075653.600 [Default:CheckIn] Stopped 2019-09-10T00:34:13.601Z,1568075653.601 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-09-10T00:34:13.601Z,1568075653.601 [Default:CheckIn:C.Wait] Stopped 2019-09-10T00:34:13.601Z,1568075653.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-09-10T00:34:13.603Z,1568075653.603 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-09-10T00:34:13.603Z,1568075653.603 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-09-10T00:34:13.603Z,1568075653.603 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-09-10T00:34:13.604Z,1568075653.604 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-09-10T00:34:13.604Z,1568075653.604 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-09-10T00:34:13.604Z,1568075653.604 [BuoyancyServo](INFO): Powering down 2019-09-10T00:34:13.617Z,1568075653.617 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-09-10T00:34:13.617Z,1568075653.617 [ElevatorServo](INFO): Powering down 2019-09-10T00:34:13.618Z,1568075653.618 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-09-10T00:34:13.618Z,1568075653.618 [MassServo](INFO): Powering down 2019-09-10T00:34:13.619Z,1568075653.619 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-09-10T00:34:13.619Z,1568075653.619 [RudderServo](INFO): Powering down 2019-09-10T00:34:13.619Z,1568075653.619 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-09-10T00:34:13.619Z,1568075653.619 [ThrusterServo](INFO): Powering down 2019-09-10T00:34:13.620Z,1568075653.620 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-09-10T00:34:13.621Z,1568075653.621 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-09-10T00:34:13.621Z,1568075653.621 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-09-10T00:34:13.621Z,1568075653.621 [CBIT](DEBUG): Powering off loads. 2019-09-10T00:34:13.633Z,1568075653.633 [CBIT](DEBUG): Disabling WDT. 2019-09-10T00:34:13.645Z,1568075653.645 [CBIT](DEBUG): Opening all GF detection circuits. 2019-09-10T00:34:13.645Z,1568075653.645 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.739Z,1568075653.739 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.745Z,1568075653.745 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.798Z,1568075653.798 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.799Z,1568075653.799 [WetLabsUBAT](INFO): WetLabs destructor: dataLog is open - close it 2019-09-10T00:34:13.799Z,1568075653.799 [WetLabsUBAT](INFO): WetLabs destructor: done 2019-09-10T00:34:13.811Z,1568075653.811 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.813Z,1568075653.813 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.823Z,1568075653.823 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-09-10T00:34:13.896Z,1568075653.896 [logger ThreadHandler](INFO): Thread cancelled.