2019-08-15T21:59:28.102Z,1565906368.102 [Supervisor](DEBUG): Initializing supervisor. 2019-08-15T21:59:28.105Z,1565906368.105 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-08-15T21:59:28.105Z,1565906368.105 [SyncHandler](INFO): Protected caller Thread ID is 2523 2019-08-15T21:59:28.106Z,1565906368.106 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-08-15T21:59:28.107Z,1565906368.107 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-08-15T21:59:28.107Z,1565906368.107 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2524 2019-08-15T21:59:28.110Z,1565906368.110 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-08-15T21:59:28.121Z,1565906368.121 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-08-15T21:59:28.122Z,1565906368.122 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-08-15T21:59:28.122Z,1565906368.122 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2525 2019-08-15T21:59:28.123Z,1565906368.123 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-08-15T21:59:28.124Z,1565906368.124 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-08-15T21:59:28.125Z,1565906368.125 [logger ThreadHandler](INFO): Protected caller Thread ID is 2526 2019-08-15T21:59:28.127Z,1565906368.127 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-08-15T21:59:28.127Z,1565906368.127 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-08-15T21:59:28.129Z,1565906368.129 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-08-15T21:59:28.224Z,1565906368.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-08-15T21:59:28.224Z,1565906368.224 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-08-15T21:59:28.425Z,1565906368.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-08-15T21:59:28.426Z,1565906368.426 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-08-15T21:59:28.563Z,1565906368.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-08-15T21:59:28.563Z,1565906368.563 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-08-15T21:59:29.126Z,1565906369.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-08-15T21:59:29.127Z,1565906369.127 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-08-15T21:59:29.721Z,1565906369.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-08-15T21:59:29.722Z,1565906369.722 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-08-15T21:59:30.194Z,1565906370.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-08-15T21:59:30.195Z,1565906370.195 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-08-15T21:59:30.499Z,1565906370.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-08-15T21:59:30.499Z,1565906370.499 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-08-15T21:59:30.830Z,1565906370.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-08-15T21:59:30.831Z,1565906370.831 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-08-15T21:59:31.224Z,1565906371.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-08-15T21:59:31.224Z,1565906371.224 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-08-15T21:59:31.373Z,1565906371.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-08-15T21:59:31.373Z,1565906371.373 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-08-15T21:59:31.478Z,1565906371.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-08-15T21:59:31.478Z,1565906371.478 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-08-15T21:59:31.559Z,1565906371.559 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-08-15T21:59:31.660Z,1565906371.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-08-15T21:59:31.661Z,1565906371.661 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-08-15T21:59:31.856Z,1565906371.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-08-15T21:59:31.856Z,1565906371.856 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-08-15T21:59:32.062Z,1565906372.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-08-15T21:59:32.064Z,1565906372.064 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-08-15T21:59:32.065Z,1565906372.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-08-15T21:59:32.148Z,1565906372.148 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-08-15T21:59:32.375Z,1565906372.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-08-15T21:59:32.376Z,1565906372.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-08-15T21:59:32.472Z,1565906372.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-08-15T21:59:32.644Z,1565906372.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-08-15T21:59:32.857Z,1565906372.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-08-15T21:59:32.943Z,1565906372.943 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-08-15T21:59:33.045Z,1565906373.046 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-08-15T21:59:33.140Z,1565906373.140 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-08-15T21:59:33.299Z,1565906373.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-08-15T21:59:33.406Z,1565906373.406 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-08-15T21:59:33.505Z,1565906373.505 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-08-15T21:59:33.514Z,1565906373.514 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-08-15T21:59:33.610Z,1565906373.610 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-08-15T21:59:33.611Z,1565906373.611 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-08-15T21:59:33.738Z,1565906373.738 [BuoyancyServo] Loaded 2019-08-15T21:59:33.739Z,1565906373.739 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-08-15T21:59:33.753Z,1565906373.753 [ElevatorServo] Loaded 2019-08-15T21:59:33.754Z,1565906373.754 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-08-15T21:59:33.768Z,1565906373.768 [MassServo] Loaded 2019-08-15T21:59:33.768Z,1565906373.768 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-08-15T21:59:33.783Z,1565906373.783 [RudderServo] Loaded 2019-08-15T21:59:33.783Z,1565906373.783 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-08-15T21:59:33.797Z,1565906373.797 [ThrusterServo] Loaded 2019-08-15T21:59:33.797Z,1565906373.797 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-08-15T21:59:33.798Z,1565906373.798 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-08-15T21:59:33.798Z,1565906373.798 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-08-15T21:59:33.930Z,1565906373.930 [SBIT](DEBUG): Construct Startup Built In Test. 2019-08-15T21:59:33.959Z,1565906373.959 [SBIT] Loaded 2019-08-15T21:59:33.960Z,1565906373.960 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-08-15T21:59:33.960Z,1565906373.960 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-08-15T21:59:33.987Z,1565906373.987 [IBIT] Loaded 2019-08-15T21:59:33.988Z,1565906373.988 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-08-15T21:59:33.991Z,1565906373.991 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-08-15T21:59:34.372Z,1565906374.372 [CBIT] Loaded 2019-08-15T21:59:34.372Z,1565906374.372 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-08-15T21:59:34.372Z,1565906374.372 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-08-15T21:59:34.373Z,1565906374.373 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-08-15T21:59:35.141Z,1565906375.141 [Aanderaa_O2] Loaded 2019-08-15T21:59:35.141Z,1565906375.141 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-08-15T21:59:35.150Z,1565906375.150 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-08-15T21:59:35.156Z,1565906375.156 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-08-15T21:59:35.157Z,1565906375.157 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-08-15T21:59:35.162Z,1565906375.162 [CTD_Seabird](INFO): created writer for : depth 2019-08-15T21:59:35.162Z,1565906375.162 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-08-15T21:59:35.167Z,1565906375.167 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-08-15T21:59:35.168Z,1565906375.168 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-08-15T21:59:35.173Z,1565906375.173 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-08-15T21:59:35.174Z,1565906375.174 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-08-15T21:59:35.179Z,1565906375.179 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-08-15T21:59:35.180Z,1565906375.180 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-08-15T21:59:35.185Z,1565906375.185 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-08-15T21:59:35.185Z,1565906375.185 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-08-15T21:59:35.190Z,1565906375.190 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-08-15T21:59:35.216Z,1565906375.216 [CTD_Seabird] Loaded 2019-08-15T21:59:35.216Z,1565906375.216 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-08-15T21:59:35.218Z,1565906375.218 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066B4E0 2019-08-15T21:59:35.218Z,1565906375.218 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2605 2019-08-15T21:59:35.248Z,1565906375.248 [ESPComponent] Loaded 2019-08-15T21:59:35.248Z,1565906375.248 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-08-15T21:59:35.328Z,1565906375.328 [PAR_Licor] Loaded 2019-08-15T21:59:35.328Z,1565906375.328 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-08-15T21:59:35.387Z,1565906375.387 [WetLabsBB2FL] Loaded 2019-08-15T21:59:35.387Z,1565906375.387 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-08-15T21:59:35.388Z,1565906375.388 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069B4E0 2019-08-15T21:59:35.389Z,1565906375.389 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2606 2019-08-15T21:59:35.389Z,1565906375.389 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-08-15T21:59:35.390Z,1565906375.390 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-08-15T21:59:35.431Z,1565906375.431 [DepthRateCalculator] Loaded 2019-08-15T21:59:35.431Z,1565906375.431 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-08-15T21:59:35.436Z,1565906375.436 [PitchRateCalculator] Loaded 2019-08-15T21:59:35.437Z,1565906375.437 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-08-15T21:59:35.452Z,1565906375.452 [SpeedCalculator] Loaded 2019-08-15T21:59:35.452Z,1565906375.452 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-08-15T21:59:35.473Z,1565906375.473 [TempGradientCalculator] Loaded 2019-08-15T21:59:35.473Z,1565906375.473 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-08-15T21:59:35.479Z,1565906375.479 [YawRateCalculator] Loaded 2019-08-15T21:59:35.479Z,1565906375.479 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-08-15T21:59:35.511Z,1565906375.511 [ElevatorOffsetCalculator] Loaded 2019-08-15T21:59:35.511Z,1565906375.511 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-08-15T21:59:35.512Z,1565906375.512 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-08-15T21:59:35.512Z,1565906375.512 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-08-15T21:59:35.544Z,1565906375.544 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-08-15T21:59:35.544Z,1565906375.544 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-08-15T21:59:35.645Z,1565906375.645 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-08-15T21:59:35.646Z,1565906375.646 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-08-15T21:59:35.667Z,1565906375.667 [NavChart] Loaded 2019-08-15T21:59:35.667Z,1565906375.667 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-08-15T21:59:35.671Z,1565906375.671 [UniversalFixResidualReporter] Loaded 2019-08-15T21:59:35.671Z,1565906375.671 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-08-15T21:59:35.672Z,1565906375.672 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-08-15T21:59:35.672Z,1565906375.672 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-08-15T21:59:36.016Z,1565906376.016 [AHRS_M2] Loaded 2019-08-15T21:59:36.016Z,1565906376.016 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-08-15T21:59:36.091Z,1565906376.091 [DataOverHttps] Loaded 2019-08-15T21:59:36.092Z,1565906376.092 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-08-15T21:59:36.093Z,1565906376.093 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409744E0 2019-08-15T21:59:36.093Z,1565906376.093 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2607 2019-08-15T21:59:36.106Z,1565906376.106 [Depth_Keller] Loaded 2019-08-15T21:59:36.106Z,1565906376.106 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-08-15T21:59:36.111Z,1565906376.111 [DropWeight] Loaded 2019-08-15T21:59:36.111Z,1565906376.111 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-08-15T21:59:36.238Z,1565906376.238 [DVL_micro] Loaded 2019-08-15T21:59:36.239Z,1565906376.239 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2019-08-15T21:59:36.328Z,1565906376.328 [NAL9602] Loaded 2019-08-15T21:59:36.328Z,1565906376.328 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-08-15T21:59:36.333Z,1565906376.333 [Onboard] Loaded 2019-08-15T21:59:36.334Z,1565906376.334 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-08-15T21:59:36.337Z,1565906376.337 [Radio_Surface] Loaded 2019-08-15T21:59:36.337Z,1565906376.337 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-08-15T21:59:36.338Z,1565906376.338 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0 2019-08-15T21:59:36.338Z,1565906376.338 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2608 2019-08-15T21:59:36.463Z,1565906376.463 [DAT] Loaded 2019-08-15T21:59:36.464Z,1565906376.464 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-08-15T21:59:37.736Z,1565906377.736 [BPC1] Loaded 2019-08-15T21:59:37.736Z,1565906377.736 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-08-15T21:59:37.736Z,1565906377.736 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-08-15T21:59:37.737Z,1565906377.737 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-08-15T21:59:37.806Z,1565906377.806 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-08-15T21:59:37.806Z,1565906377.806 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-08-15T21:59:37.872Z,1565906377.872 [VerticalControl](DEBUG): Construct VerticalControl. 2019-08-15T21:59:37.955Z,1565906377.955 [VerticalControl] Loaded 2019-08-15T21:59:37.955Z,1565906377.955 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-08-15T21:59:37.956Z,1565906377.956 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-08-15T21:59:38.012Z,1565906378.012 [HorizontalControl] Loaded 2019-08-15T21:59:38.012Z,1565906378.012 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-08-15T21:59:38.013Z,1565906378.013 [SpeedControl](DEBUG): Construct SpeedControl. 2019-08-15T21:59:38.015Z,1565906378.015 [SpeedControl] Loaded 2019-08-15T21:59:38.015Z,1565906378.015 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-08-15T21:59:38.016Z,1565906378.016 [LoopControl](DEBUG): Construct LoopControl. 2019-08-15T21:59:38.016Z,1565906378.016 [LoopControl] Loaded 2019-08-15T21:59:38.017Z,1565906378.017 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-08-15T21:59:38.017Z,1565906378.017 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-08-15T21:59:38.018Z,1565906378.018 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-08-15T21:59:38.030Z,1565906378.030 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-08-15T21:59:38.031Z,1565906378.031 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-08-15T21:59:38.315Z,1565906378.315 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-08-15T21:59:38.319Z,1565906378.319 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-08-15T21:59:38.320Z,1565906378.320 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-08-15T21:59:38.327Z,1565906378.327 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-08-15T21:59:38.328Z,1565906378.328 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0 2019-08-15T21:59:38.328Z,1565906378.328 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2609 2019-08-15T21:59:38.333Z,1565906378.333 [Supervisor](INFO): Main Thread ID is 803 2019-08-15T21:59:38.333Z,1565906378.333 [Supervisor](DEBUG): Running supervisor. 2019-08-15T21:59:38.334Z,1565906378.334 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2610 2019-08-15T21:59:38.336Z,1565906378.336 [controlThread ThreadHandler](INFO): Handler Thread ID is 2611 2019-08-15T21:59:38.336Z,1565906378.336 [controlThread](DEBUG): Initializing ControlThread 2019-08-15T21:59:38.338Z,1565906378.338 [SBIT](INFO): Initialize SBIT Component. 2019-08-15T21:59:38.339Z,1565906378.339 [SBIT](IMPORTANT): git: 2019-08-13 2019-08-15T21:59:38.339Z,1565906378.339 [SBIT](INFO): git hash: 6b080875362fb71081cecd990260e30fa66d1cc0 2019-08-15T21:59:38.339Z,1565906378.339 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-08-15T21:59:38.341Z,1565906378.341 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-08-15T21:59:38.342Z,1565906378.342 [SBIT](INFO): Beginning SBIT in 84.000000 seconds. 2019-08-15T21:59:38.342Z,1565906378.342 [IBIT](INFO): Initialize IBIT Component. 2019-08-15T21:59:38.343Z,1565906378.343 [CBIT](DEBUG): Initialize CBIT Component. 2019-08-15T21:59:38.344Z,1565906378.344 [logger ThreadHandler](INFO): Handler Thread ID is 2612 2019-08-15T21:59:38.357Z,1565906378.357 [CBIT](DEBUG): Initialized mux pins. 2019-08-15T21:59:38.357Z,1565906378.357 [CBIT](DEBUG): Initializing the watchdog timer. 2019-08-15T21:59:38.369Z,1565906378.369 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2613 2019-08-15T21:59:38.370Z,1565906378.370 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-08-15T21:59:38.373Z,1565906378.373 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-08-15T21:59:38.375Z,1565906378.375 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2615 2019-08-15T21:59:38.376Z,1565906378.376 [WetLabsBB2FL](INFO): Powering down 2019-08-15T21:59:38.381Z,1565906378.381 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-08-15T21:59:38.381Z,1565906378.381 [CBIT](DEBUG): Initializing heartbeat. 2019-08-15T21:59:38.405Z,1565906378.405 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2616 2019-08-15T21:59:38.406Z,1565906378.406 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-08-15T21:59:38.417Z,1565906378.417 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2617 2019-08-15T21:59:38.429Z,1565906378.429 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2618 2019-08-15T21:59:38.432Z,1565906378.432 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-08-15T21:59:38.432Z,1565906378.432 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-08-15T21:59:38.433Z,1565906378.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-08-15T21:59:38.433Z,1565906378.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-08-15T21:59:38.433Z,1565906378.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-08-15T21:59:38.433Z,1565906378.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-08-15T21:59:38.434Z,1565906378.434 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-08-15T21:59:38.434Z,1565906378.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-08-15T21:59:38.434Z,1565906378.434 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-08-15T21:59:38.434Z,1565906378.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-08-15T21:59:38.434Z,1565906378.434 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-08-15T21:59:38.434Z,1565906378.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-08-15T21:59:38.435Z,1565906378.435 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-08-15T21:59:38.435Z,1565906378.435 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-08-15T21:59:38.435Z,1565906378.435 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-08-15T21:59:38.435Z,1565906378.435 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-08-15T21:59:38.453Z,1565906378.453 [CBIT](DEBUG): Deactivating GF circuits. 2019-08-15T21:59:38.453Z,1565906378.453 [CBIT](DEBUG): Deactivating emergency mode. 2019-08-15T21:59:38.489Z,1565906378.489 [CBIT](DEBUG): Backplane powered. 2019-08-15T21:59:38.493Z,1565906378.493 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-08-15T21:59:38.494Z,1565906378.494 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-08-15T21:59:38.494Z,1565906378.494 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-08-15T21:59:38.494Z,1565906378.494 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-08-15T21:59:38.495Z,1565906378.495 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-08-15T21:59:38.495Z,1565906378.495 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-08-15T21:59:38.496Z,1565906378.496 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-08-15T21:59:38.496Z,1565906378.496 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-08-15T21:59:38.508Z,1565906378.508 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-08-15T21:59:38.518Z,1565906378.518 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-08-15T21:59:38.518Z,1565906378.518 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-08-15T21:59:38.519Z,1565906378.519 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-08-15T21:59:38.520Z,1565906378.520 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-08-15T21:59:38.554Z,1565906378.554 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-08-15T21:59:38.593Z,1565906378.593 [MissionManager](DEBUG): 2019-08-15T21:59:38.594Z,1565906378.594 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-08-15T21:59:38.668Z,1565906378.668 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-08-15T21:59:38.669Z,1565906378.669 [Default:A.Wait](DEBUG): Construct Wait. 2019-08-15T21:59:38.671Z,1565906378.671 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-08-15T21:59:38.730Z,1565906378.730 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-08-15T21:59:38.732Z,1565906378.732 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-08-15T21:59:38.744Z,1565906378.744 [Default:E.Execute](DEBUG): Construct Execute. 2019-08-15T21:59:38.763Z,1565906378.763 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-08-15T21:59:38.768Z,1565906378.768 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-08-15T21:59:38.818Z,1565906378.818 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-08-15T21:59:38.821Z,1565906378.821 [Radio_Surface](INFO): Powering up 2019-08-15T21:59:38.969Z,1565906378.969 [DVL_micro](INFO): Initializing 2019-08-15T21:59:38.994Z,1565906378.994 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-08-15T21:59:38.995Z,1565906378.995 [DAT](INFO): Powering up 2019-08-15T21:59:38.995Z,1565906378.995 [DAT](DEBUG): Initializing DAT. 2019-08-15T21:59:39.038Z,1565906379.038 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-08-15T21:59:39.099Z,1565906379.099 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-08-15T21:59:39.105Z,1565906379.105 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-08-15T21:59:39.106Z,1565906379.106 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-08-15T21:59:39.117Z,1565906379.117 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-08-15T21:59:39.118Z,1565906379.118 [MassServo](DEBUG): Initializing EZServoServo. 2019-08-15T21:59:39.129Z,1565906379.129 [MassServo](DEBUG): Initializing MassServo. 2019-08-15T21:59:39.130Z,1565906379.130 [RudderServo](DEBUG): Initializing EZServoServo. 2019-08-15T21:59:39.141Z,1565906379.141 [RudderServo](DEBUG): Initializing RudderServo. 2019-08-15T21:59:39.142Z,1565906379.142 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-08-15T21:59:39.153Z,1565906379.153 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-08-15T21:59:42.121Z,1565906382.121 [Aanderaa_O2](INFO): Powering down 2019-08-15T21:59:42.934Z,1565906382.934 [DVL_micro](ERROR): only read 45 of 46 data items 2019-08-15T21:59:42.934Z,1565906382.934 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 2.5 2.5 3.1 3.2 -43.7 -80.3 -214.6 -64.3 0 0 0 0 0 0 0 0 228.1 -21.4 -108.7 1 241.0 -10.3 -78.2 1 0 0 0 0 0 0 0 0.60 1.9 2.5 25.9 0.005 35.0 1489 68 2019-08-15T21:59:45.425Z,1565906385.425 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2019-08-15T21:59:51.007Z,1565906391.007 [DVL_micro](ERROR): only read 44 of 46 data items 2019-08-15T21:59:51.007Z,1565906391.007 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 0 1 2.9 2.6 0 3.6 -33.1 61.0 0 59.7 0 0 61 0 0 0 2 0 -249.4 1.7 65.1 1 -252.8 15.8 47.4 1 0 0 0 0 0 0 0 0 6.29 -3.99 355.4 2.6 26.0 0.005 35.0 2019-08-15T22:00:06.384Z,1565906406.384 [NAL9602](INFO): Powering up NAL9602 2019-08-15T22:00:17.261Z,1565906417.261 [NAL9602](INFO): NAL9602 initialized 2019-08-15T22:00:18.073Z,1565906418.073 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:32.615Z,1565906432.615 [NAL9602](INFO): SBD MO Status=0, MOMSN=31124, MT Status=0, MTMSN=0 2019-08-15T22:00:32.615Z,1565906432.615 [NAL9602](INFO): No messages in MT queue 2019-08-15T22:00:33.429Z,1565906433.429 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:35.849Z,1565906435.849 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:39.089Z,1565906439.089 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:39.114Z,1565906439.114 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: MGP user:1>, available: 2019-08-15T22:00:39.114Z,1565906439.114 [DAT] Communications Fault, FailCount= 1 2019-08-15T22:00:39.114Z,1565906439.114 [DAT](ERROR): Communications Fault 2019-08-15T22:00:39.139Z,1565906439.139 [CBIT](ERROR): Communications Fault in component: DAT 2019-08-15T22:00:39.535Z,1565906439.535 [DAT](INFO): Powering down 2019-08-15T22:00:40.390Z,1565906440.390 [CBIT](INFO): Clearing failed state for component DAT 2019-08-15T22:00:40.390Z,1565906440.390 [DAT] No Fault, FailCount= 1 2019-08-15T22:00:41.933Z,1565906441.933 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:42.767Z,1565906442.767 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-08-15T22:00:42.767Z,1565906442.767 [DAT](INFO): Powering up 2019-08-15T22:00:42.767Z,1565906442.767 [DAT](DEBUG): Initializing DAT. 2019-08-15T22:00:42.802Z,1565906442.802 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-08-15T22:00:44.749Z,1565906444.749 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:47.985Z,1565906447.985 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:50.813Z,1565906450.813 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:54.049Z,1565906454.049 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:56.865Z,1565906456.865 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:00:58.094Z,1565906458.094 [DAT](INFO): setting local address to 3 2019-08-15T22:00:58.495Z,1565906458.495 [DAT](INFO): set local address to 3 2019-08-15T22:01:00.093Z,1565906460.093 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:02.933Z,1565906462.933 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:02.988Z,1565906462.988 [SBIT](IMPORTANT): Beginning Startup BIT 2019-08-15T22:01:02.996Z,1565906462.996 [CBIT](IMPORTANT): Beginning ground fault scan 2019-08-15T22:01:05.969Z,1565906465.969 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:08.817Z,1565906468.817 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:12.061Z,1565906472.061 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:13.774Z,1565906473.774 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.001217 CHAN A1 (24V): -0.015737 CHAN A2 (12V): -0.008531 CHAN A3 (5V): -0.017221 CHAN B0 (3.3V): -0.007176 CHAN B1 (3.15aV): -0.004724 CHAN B2 (3.15bV): -0.002203 CHAN B3 (GND): -0.007039 OPEN: 0.012551 Full Scale Calc: 4.765 mA, -1.589 mA 2019-08-15T22:01:14.873Z,1565906474.873 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:18.097Z,1565906478.097 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:21.061Z,1565906481.061 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:23.469Z,1565906483.469 [DVL_micro](ERROR): only read 45 of 46 data items 2019-08-15T22:01:23.469Z,1565906483.469 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 3.1 2.7 3.3 3.6 19.6 17.1 184.3 -43 0 0 0 0 0 -219.8 80.2 48.0 1 -201.2 119.2 48.5 1 0 0 0 0 0 0 0 0 7.85 -2.60 347.7 2.7 26.2 0.005 35.0 1489 110 2019-08-15T22:01:23.869Z,1565906483.869 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:27.109Z,1565906487.109 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:29.949Z,1565906489.949 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:33.177Z,1565906493.177 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:35.997Z,1565906495.997 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:38.837Z,1565906498.837 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:42.069Z,1565906502.069 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:44.877Z,1565906504.877 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:47.865Z,1565906507.865 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:48.248Z,1565906508.248 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215926.00,A,4149.71074,N,08324.71645,W,0.467,0.00,150819,,,A*77 2019-08-15T22:01:48.251Z,1565906508.251 [NAL9602](INFO): GPS fix at 20190815T215926: (41.828512, -83.411941) 2019-08-15T22:01:56.811Z,1565906516.811 [SBIT](IMPORTANT): SBIT PASSED 2019-08-15T22:01:56.838Z,1565906516.838 [CommandLine](IMPORTANT): got command configSet list 2019-08-15T22:01:56.838Z,1565906516.838 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-08-15T22:01:56.839Z,1565906516.839 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2019-08-15T22:01:56.839Z,1565906516.839 [CommandLine](IMPORTANT): BuoyancyServo.fastPumpDepth=5 meter; 2019-08-15T22:01:56.839Z,1565906516.839 [CommandLine](IMPORTANT): CTD_Seabird.minSalinityBound=0.01 practical_salinity_unit; 2019-08-15T22:01:56.840Z,1565906516.840 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=1 bool; 2019-08-15T22:01:56.840Z,1565906516.840 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=1 bool; 2019-08-15T22:01:56.840Z,1565906516.840 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=310 cubic_centimeter; 2019-08-15T22:01:56.840Z,1565906516.840 [CommandLine](IMPORTANT): VerticalControl.elevDeadband=0.1 degree; 2019-08-15T22:01:56.840Z,1565906516.840 [CommandLine](IMPORTANT): VerticalControl.kiDepthBuoy=0.3 reciprocal_second; 2019-08-15T22:01:56.840Z,1565906516.840 [CommandLine](IMPORTANT): VerticalControl.massDefault=16 millimeter; 2019-08-15T22:01:57.157Z,1565906517.157 [MissionManager](IMPORTANT): Started mission Startup 2019-08-15T22:01:57.157Z,1565906517.157 [Startup] Running Loop=1 2019-08-15T22:01:57.158Z,1565906517.158 [Startup](DEBUG): Aggregate::initialize Startup 2019-08-15T22:01:57.158Z,1565906517.158 [Startup:A.GoToSurface] Running Loop=1 2019-08-15T22:01:57.158Z,1565906517.158 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-08-15T22:01:57.158Z,1565906517.158 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-08-15T22:01:57.159Z,1565906517.159 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-08-15T22:01:57.159Z,1565906517.159 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-08-15T22:01:57.159Z,1565906517.159 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-08-15T22:01:57.160Z,1565906517.160 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-08-15T22:01:57.161Z,1565906517.161 [Startup:StartupSatComms] Running Loop=1 2019-08-15T22:01:57.162Z,1565906517.162 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-08-15T22:01:57.162Z,1565906517.162 [Startup:StartupSatComms:A] Running Loop=1 2019-08-15T22:01:57.587Z,1565906517.587 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-08-15T22:01:58.773Z,1565906518.773 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:01:59.188Z,1565906519.188 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215936.00,A,4149.71075,N,08324.71591,W,1.225,355.93,150819,,,A*75 2019-08-15T22:01:59.190Z,1565906519.190 [NAL9602](INFO): GPS fix at 20190815T215936: (41.828513, -83.411932) 2019-08-15T22:01:59.214Z,1565906519.214 [Startup:StartupSatComms:A] Stopped 2019-08-15T22:01:59.214Z,1565906519.214 [Startup:StartupSatComms:B] Running Loop=1 2019-08-15T22:01:59.616Z,1565906519.616 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-08-15T22:02:15.440Z,1565906535.440 [NAL9602](INFO): SBD MO Status=1, MOMSN=31125, MT Status=0, MTMSN=0 2019-08-15T22:02:15.525Z,1565906535.525 [NAL9602](INFO): Sent 25 bytes from file Logs/20190815T185715/Courier0100.lzma 2019-08-15T22:02:15.525Z,1565906535.525 [NAL9602](INFO): Packets left to send: 0 2019-08-15T22:02:30.861Z,1565906550.861 [NAL9602](INFO): SBD MO Status=2, MOMSN=31126, MT Status=2, MTMSN=0 2019-08-15T22:02:30.861Z,1565906550.861 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-08-15T22:02:56.385Z,1565906576.385 [NAL9602](INFO): SBD MO Status=1, MOMSN=31126, MT Status=0, MTMSN=0 2019-08-15T22:02:56.433Z,1565906576.433 [NAL9602](INFO): Sent 212 bytes from file Logs/20190815T215928/Courier0000.lzma 2019-08-15T22:02:56.433Z,1565906576.433 [NAL9602](INFO): Packets left to send: 0 2019-08-15T22:02:59.778Z,1565906579.778 [Startup:StartupSatComms:B](INFO): Timed out from 2019-08-15T22:01:59.2Z 2019-08-15T22:02:59.778Z,1565906579.778 [Startup:StartupSatComms:B] Stopped 2019-08-15T22:02:59.778Z,1565906579.778 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-08-15T22:02:59.779Z,1565906579.779 [Startup:StartupSatComms] Stopped 2019-08-15T22:02:59.779Z,1565906579.779 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-08-15T22:02:59.779Z,1565906579.779 [Startup](INFO): Completed Startup 2019-08-15T22:02:59.780Z,1565906579.780 [MissionManager](INFO): Startup is completed. 2019-08-15T22:02:59.780Z,1565906579.780 [MissionManager](INFO): Uninitializing Mission Startup 2019-08-15T22:02:59.780Z,1565906579.780 [Startup] Stopped 2019-08-15T22:02:59.780Z,1565906579.780 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-08-15T22:02:59.780Z,1565906579.780 [Startup:A.GoToSurface] Stopped 2019-08-15T22:02:59.780Z,1565906579.780 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-08-15T22:02:59.922Z,1565906579.922 [MissionManager](IMPORTANT): Started mission Default 2019-08-15T22:02:59.922Z,1565906579.922 [Default] Running Loop=1 2019-08-15T22:02:59.922Z,1565906579.922 [Default](DEBUG): Aggregate::initialize Default 2019-08-15T22:02:59.922Z,1565906579.922 [Default:B.GoToSurface] Running Loop=1 2019-08-15T22:02:59.923Z,1565906579.923 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-08-15T22:02:59.923Z,1565906579.923 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-08-15T22:02:59.923Z,1565906579.923 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-08-15T22:02:59.923Z,1565906579.923 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-08-15T22:02:59.924Z,1565906579.924 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-08-15T22:02:59.924Z,1565906579.924 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-08-15T22:02:59.924Z,1565906579.924 [Default:A.Wait] Running Loop=1 2019-08-15T22:02:59.924Z,1565906579.924 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-08-15T22:03:13.192Z,1565906593.192 [Default:A.Wait](INFO): Done Waiting. 2019-08-15T22:03:13.192Z,1565906593.192 [Default:A.Wait] Stopped 2019-08-15T22:03:13.192Z,1565906593.192 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-08-15T22:03:13.648Z,1565906593.648 [Default:CheckIn] Running Loop=1 2019-08-15T22:03:13.648Z,1565906593.648 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-08-15T22:03:13.648Z,1565906593.648 [Default:CheckIn:Read_GPS] Running Loop=1 2019-08-15T22:03:14.098Z,1565906594.098 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-08-15T22:03:14.371Z,1565906594.371 [NAL9602](INFO): SBD MO Status=1, MOMSN=31127, MT Status=0, MTMSN=0 2019-08-15T22:03:14.441Z,1565906594.441 [NAL9602](INFO): Sent 332 bytes from file Logs/20190815T185715/Express0101.lzma 2019-08-15T22:03:14.441Z,1565906594.441 [NAL9602](INFO): Packets left to send: 1 2019-08-15T22:03:15.177Z,1565906595.177 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:03:15.603Z,1565906595.603 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220053.00,A,4149.70985,N,08324.71238,W,0.175,173.18,150819,,,A*78 2019-08-15T22:03:15.605Z,1565906595.605 [NAL9602](INFO): GPS fix at 20190815T220053: (41.828497, -83.411873) 2019-08-15T22:03:15.660Z,1565906595.660 [Default:CheckIn:Read_GPS] Stopped 2019-08-15T22:03:15.660Z,1565906595.660 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-08-15T22:03:16.035Z,1565906596.035 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-08-15T22:03:19.951Z,1565906599.951 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004992 2019-08-15T22:03:25.888Z,1565906605.888 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190815T215928/Courier0004.lzma 2019-08-15T22:03:26.694Z,1565906606.694 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Courier0004.lzma.bak 2019-08-15T22:03:26.694Z,1565906606.694 [DataOverHttps](INFO): SBD MOMSN=11579167 2019-08-15T22:03:29.971Z,1565906609.971 [NAL9602](INFO): SBD MO Status=1, MOMSN=31128, MT Status=0, MTMSN=0 2019-08-15T22:03:30.021Z,1565906610.021 [NAL9602](INFO): Sent 63 bytes from file Logs/20190815T215928/Courier0004.lzma 2019-08-15T22:03:30.021Z,1565906610.021 [NAL9602](INFO): Packets left to send: 0 2019-08-15T22:03:30.086Z,1565906610.086 [Default:CheckIn:Read_Iridium] Stopped 2019-08-15T22:03:30.086Z,1565906610.086 [Default:CheckIn:C.Wait] Running Loop=1 2019-08-15T22:03:30.086Z,1565906610.086 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-08-15T22:03:38.874Z,1565906618.874 [DataOverHttps](INFO): Sending 642 bytes from file Logs/20190815T185715/Express0101.lzma 2019-08-15T22:03:39.678Z,1565906619.678 [DataOverHttps](INFO): Moved sent file to Logs/20190815T185715/Express0101.lzma.bak 2019-08-15T22:03:39.679Z,1565906619.679 [DataOverHttps](INFO): SBD MOMSN=11579170 2019-08-15T22:03:53.682Z,1565906633.682 [CommandLine](IMPORTANT): got command get height_above_sea_floor 2019-08-15T22:03:53.682Z,1565906633.682 [CommandLine](FAULT): Element has no value 2019-08-15T22:04:00.668Z,1565906640.668 [NAL9602](INFO): Not Powering down - fast GPS 2019-08-15T22:04:07.837Z,1565906647.837 [CommandLine](IMPORTANT): got command show best height_above_sea_floor 2019-08-15T22:04:07.837Z,1565906647.837 [CommandLine](IMPORTANT): height_above_sea_floor best is null 2019-08-15T22:04:12.650Z,1565906652.650 [CommandLine](IMPORTANT): got command failComponent 2019-08-15T22:04:12.650Z,1565906652.650 [CommandLine](IMPORTANT): Failed components: 2019-08-15T22:04:12.650Z,1565906652.650 [CommandLine](IMPORTANT): No failed Components. 2019-08-15T22:04:27.214Z,1565906667.214 [CommandLine](IMPORTANT): got command get DVL_micro.loadAtStartup 2019-08-15T22:04:27.214Z,1565906667.214 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup 1 bool 2019-08-15T22:06:02.141Z,1565906762.141 [CommandLine](IMPORTANT): got command get DVL_micro.simulateHardware 2019-08-15T22:06:02.142Z,1565906762.142 [CommandLine](IMPORTANT): DVL_micro.simulateHardware 0 bool 2019-08-15T22:06:11.097Z,1565906771.097 [CommandLine](IMPORTANT): got command show variable dvl_micro 2019-08-15T22:06:11.121Z,1565906771.121 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup (bool) 2019-08-15T22:06:11.121Z,1565906771.121 [CommandLine](IMPORTANT): DVL_micro.simulateHardware (bool) 2019-08-15T22:06:11.122Z,1565906771.122 [CommandLine](IMPORTANT): DVL_micro.magDeviation (degree) 2019-08-15T22:06:11.122Z,1565906771.122 [CommandLine](IMPORTANT): DVL_micro.pitchOffset (degree) 2019-08-15T22:06:11.122Z,1565906771.122 [CommandLine](IMPORTANT): DVL_micro.power (watt) 2019-08-15T22:06:11.123Z,1565906771.123 [CommandLine](IMPORTANT): DVL_micro.rollOffset (degree) 2019-08-15T22:06:11.182Z,1565906771.182 [CommandLine](IMPORTANT): DVL_micro.loadControl (none) 2019-08-15T22:06:11.183Z,1565906771.183 [CommandLine](IMPORTANT): DVL_micro.uart (none) 2019-08-15T22:06:11.183Z,1565906771.183 [CommandLine](IMPORTANT): DVL_micro.baud (bit_per_second) 2019-08-15T22:06:11.385Z,1565906771.385 [CommandLine](IMPORTANT): DVL_micro.height_above_sea_floor (meter) 2019-08-15T22:06:11.385Z,1565906771.385 [CommandLine](IMPORTANT): DVL_micro.platform_orientation (radian) 2019-08-15T22:06:11.386Z,1565906771.386 [CommandLine](IMPORTANT): DVL_micro.platform_magnetic_orientation (radian) 2019-08-15T22:06:11.386Z,1565906771.386 [CommandLine](IMPORTANT): DVL_micro.platform_pitch_angle (radian) 2019-08-15T22:06:11.386Z,1565906771.386 [CommandLine](IMPORTANT): DVL_micro.platform_roll_angle (radian) 2019-08-15T22:06:11.387Z,1565906771.387 [CommandLine](IMPORTANT): DVL_micro.platform_orientation_matrix (none) 2019-08-15T22:06:11.387Z,1565906771.387 [CommandLine](IMPORTANT): DVL_micro.platform_velocity_wrt_ground (meter_per_second) 2019-08-15T22:06:11.388Z,1565906771.388 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_wrt_ground (meter_per_second) 2019-08-15T22:06:11.388Z,1565906771.388 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_wrt_ground (meter_per_second) 2019-08-15T22:06:11.388Z,1565906771.388 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_wrt_ground (meter_per_second) 2019-08-15T22:06:11.409Z,1565906771.409 [CommandLine](IMPORTANT): DVL_micro.platform_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:06:11.409Z,1565906771.409 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:06:11.410Z,1565906771.410 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:06:11.410Z,1565906771.410 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:06:11.410Z,1565906771.410 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_current (meter_per_second) 2019-08-15T22:06:11.411Z,1565906771.411 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_current (meter_per_second) 2019-08-15T22:06:11.411Z,1565906771.411 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_current (meter_per_second) 2019-08-15T22:06:11.412Z,1565906771.412 [CommandLine](IMPORTANT): DVL_micro.Status (count) 2019-08-15T22:06:11.412Z,1565906771.412 [CommandLine](IMPORTANT): DVL_micro.Beam1Good (count) 2019-08-15T22:06:11.412Z,1565906771.412 [CommandLine](IMPORTANT): DVL_micro.Beam2Good (count) 2019-08-15T22:06:11.414Z,1565906771.414 [CommandLine](IMPORTANT): DVL_micro.Beam3Good (count) 2019-08-15T22:06:11.415Z,1565906771.415 [CommandLine](IMPORTANT): DVL_micro.Beam4Good (count) 2019-08-15T22:06:11.415Z,1565906771.415 [CommandLine](IMPORTANT): DVL_micro.Altitude1 (meter) 2019-08-15T22:06:11.416Z,1565906771.416 [CommandLine](IMPORTANT): DVL_micro.Altitude2 (meter) 2019-08-15T22:06:11.416Z,1565906771.416 [CommandLine](IMPORTANT): DVL_micro.Altitude3 (meter) 2019-08-15T22:06:11.416Z,1565906771.416 [CommandLine](IMPORTANT): DVL_micro.Altitude4 (meter) 2019-08-15T22:06:11.417Z,1565906771.417 [CommandLine](IMPORTANT): DVL_micro.BottomVelocityFlag (count) 2019-08-15T22:06:11.417Z,1565906771.417 [CommandLine](IMPORTANT): DVL_micro.WaterVelocityFlag (meter) 2019-08-15T22:06:11.446Z,1565906771.446 [CommandLine](IMPORTANT): DVL_micro.component_voltage (volt) 2019-08-15T22:06:11.446Z,1565906771.446 [CommandLine](IMPORTANT): DVL_micro.component_avgVoltage (volt) 2019-08-15T22:06:11.447Z,1565906771.447 [CommandLine](IMPORTANT): DVL_micro.component_current (milliampere) 2019-08-15T22:06:11.447Z,1565906771.447 [CommandLine](IMPORTANT): DVL_micro.component_avgCurrent (milliampere) 2019-08-15T22:06:30.852Z,1565906790.852 [CommandLine](IMPORTANT): got command report touch DVL_micro.Altitude1 2019-08-15T22:06:31.425Z,1565906791.425 [Reporter](INFO): DVL_micro.Altitude1 0.600000 m 2019-08-15T22:06:32.211Z,1565906792.211 [Reporter](INFO): DVL_micro.Altitude1 3.500000 m 2019-08-15T22:06:33.030Z,1565906793.030 [Reporter](INFO): DVL_micro.Altitude1 3.500000 m 2019-08-15T22:06:33.867Z,1565906793.867 [Reporter](INFO): DVL_micro.Altitude1 3.600000 m 2019-08-15T22:06:34.658Z,1565906794.658 [Reporter](INFO): DVL_micro.Altitude1 3.500000 m 2019-08-15T22:06:35.562Z,1565906795.562 [Reporter](INFO): DVL_micro.Altitude1 3.500000 m 2019-08-15T22:06:36.358Z,1565906796.358 [Reporter](INFO): DVL_micro.Altitude1 3.600000 m 2019-08-15T22:06:37.165Z,1565906797.165 [Reporter](INFO): DVL_micro.Altitude1 3.500000 m 2019-08-15T22:06:37.938Z,1565906797.938 [Reporter](INFO): DVL_micro.Altitude1 3.600000 m 2019-08-15T22:06:38.721Z,1565906798.721 [Reporter](INFO): DVL_micro.Altitude1 3.600000 m 2019-08-15T22:06:39.557Z,1565906799.557 [Reporter](INFO): DVL_micro.Altitude1 3.500000 m 2019-08-15T22:06:40.321Z,1565906800.321 [Reporter](INFO): DVL_micro.Altitude1 3.500000 m 2019-08-15T22:06:40.398Z,1565906800.398 [CommandLine](IMPORTANT): got command report clear 2019-08-15T22:06:49.169Z,1565906809.169 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-08-15T22:06:57.134Z,1565906817.134 [CommandLine](IMPORTANT): got command show best height_above_sea_floor 2019-08-15T22:06:57.134Z,1565906817.134 [CommandLine](IMPORTANT): height_above_sea_floor best is null 2019-08-15T22:07:16.670Z,1565906836.670 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-08-15T22:07:16.671Z,1565906836.671 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-08-15T22:07:16.671Z,1565906836.671 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 5. 2019-08-15T22:07:16.674Z,1565906836.674 [BPC1](INFO): Received data from all battery sticks. 2019-08-15T22:07:18.595Z,1565906838.595 [CommandLine](IMPORTANT): got command report touch DVL_micro.Altitude2 2019-08-15T22:07:19.141Z,1565906839.141 [Reporter](INFO): DVL_micro.Altitude2 3.400000 m 2019-08-15T22:07:19.904Z,1565906839.904 [Reporter](INFO): DVL_micro.Altitude2 3.400000 m 2019-08-15T22:07:20.720Z,1565906840.720 [Reporter](INFO): DVL_micro.Altitude2 3.300000 m 2019-08-15T22:07:21.531Z,1565906841.531 [Reporter](INFO): DVL_micro.Altitude2 3.300000 m 2019-08-15T22:07:22.365Z,1565906842.365 [Reporter](INFO): DVL_micro.Altitude2 3.400000 m 2019-08-15T22:07:23.176Z,1565906843.176 [Reporter](INFO): DVL_micro.Altitude2 3.300000 m 2019-08-15T22:07:23.955Z,1565906843.955 [Reporter](INFO): DVL_micro.Altitude2 3.400000 m 2019-08-15T22:07:24.565Z,1565906844.565 [CommandLine](IMPORTANT): got command report clear 2019-08-15T22:07:33.018Z,1565906853.018 [CommandLine](IMPORTANT): got command report touch DVL_micro.Altitude3 2019-08-15T22:07:33.653Z,1565906853.653 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:34.455Z,1565906854.455 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:35.276Z,1565906855.276 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:36.101Z,1565906856.101 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:36.938Z,1565906856.938 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:37.723Z,1565906857.723 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:38.541Z,1565906858.541 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:39.389Z,1565906859.389 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:40.207Z,1565906860.207 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:40.933Z,1565906860.933 [Reporter](INFO): DVL_micro.Altitude3 0.200000 m 2019-08-15T22:07:41.324Z,1565906861.324 [CommandLine](IMPORTANT): got command report clear 2019-08-15T22:07:49.364Z,1565906869.364 [CommandLine](IMPORTANT): got command report touch DVL_micro.Altitude4 2019-08-15T22:07:49.849Z,1565906869.849 [Reporter](INFO): DVL_micro.Altitude4 3.800000 m 2019-08-15T22:07:50.616Z,1565906870.616 [Reporter](INFO): DVL_micro.Altitude4 3.800000 m 2019-08-15T22:07:51.418Z,1565906871.418 [Reporter](INFO): DVL_micro.Altitude4 3.800000 m 2019-08-15T22:07:52.247Z,1565906872.247 [Reporter](INFO): DVL_micro.Altitude4 3.800000 m 2019-08-15T22:07:53.085Z,1565906873.085 [Reporter](INFO): DVL_micro.Altitude4 3.900000 m 2019-08-15T22:07:53.844Z,1565906873.844 [Reporter](INFO): DVL_micro.Altitude4 3.800000 m 2019-08-15T22:07:54.656Z,1565906874.656 [Reporter](INFO): DVL_micro.Altitude4 3.800000 m 2019-08-15T22:07:55.464Z,1565906875.464 [CommandLine](IMPORTANT): got command report clear 2019-08-15T22:08:04.396Z,1565906884.396 [CommandLine](IMPORTANT): got command show variable dvl_micro 2019-08-15T22:08:04.431Z,1565906884.431 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup (bool) 2019-08-15T22:08:04.431Z,1565906884.431 [CommandLine](IMPORTANT): DVL_micro.simulateHardware (bool) 2019-08-15T22:08:04.431Z,1565906884.431 [CommandLine](IMPORTANT): DVL_micro.magDeviation (degree) 2019-08-15T22:08:04.432Z,1565906884.432 [CommandLine](IMPORTANT): DVL_micro.pitchOffset (degree) 2019-08-15T22:08:04.432Z,1565906884.432 [CommandLine](IMPORTANT): DVL_micro.power (watt) 2019-08-15T22:08:04.433Z,1565906884.433 [CommandLine](IMPORTANT): DVL_micro.rollOffset (degree) 2019-08-15T22:08:04.456Z,1565906884.456 [CommandLine](IMPORTANT): DVL_micro.loadControl (none) 2019-08-15T22:08:04.456Z,1565906884.456 [CommandLine](IMPORTANT): DVL_micro.uart (none) 2019-08-15T22:08:04.457Z,1565906884.457 [CommandLine](IMPORTANT): DVL_micro.baud (bit_per_second) 2019-08-15T22:08:04.604Z,1565906884.604 [CommandLine](IMPORTANT): DVL_micro.height_above_sea_floor (meter) 2019-08-15T22:08:04.604Z,1565906884.604 [CommandLine](IMPORTANT): DVL_micro.platform_orientation (radian) 2019-08-15T22:08:04.605Z,1565906884.605 [CommandLine](IMPORTANT): DVL_micro.platform_magnetic_orientation (radian) 2019-08-15T22:08:04.605Z,1565906884.605 [CommandLine](IMPORTANT): DVL_micro.platform_pitch_angle (radian) 2019-08-15T22:08:04.606Z,1565906884.606 [CommandLine](IMPORTANT): DVL_micro.platform_roll_angle (radian) 2019-08-15T22:08:04.606Z,1565906884.606 [CommandLine](IMPORTANT): DVL_micro.platform_orientation_matrix (none) 2019-08-15T22:08:04.606Z,1565906884.606 [CommandLine](IMPORTANT): DVL_micro.platform_velocity_wrt_ground (meter_per_second) 2019-08-15T22:08:04.607Z,1565906884.607 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_wrt_ground (meter_per_second) 2019-08-15T22:08:04.607Z,1565906884.607 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_wrt_ground (meter_per_second) 2019-08-15T22:08:04.608Z,1565906884.608 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_wrt_ground (meter_per_second) 2019-08-15T22:08:04.608Z,1565906884.608 [CommandLine](IMPORTANT): DVL_micro.platform_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:08:04.608Z,1565906884.608 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:08:04.625Z,1565906884.625 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:08:04.625Z,1565906884.625 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:08:04.626Z,1565906884.626 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_current (meter_per_second) 2019-08-15T22:08:04.626Z,1565906884.626 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_current (meter_per_second) 2019-08-15T22:08:04.627Z,1565906884.627 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_current (meter_per_second) 2019-08-15T22:08:04.627Z,1565906884.627 [CommandLine](IMPORTANT): DVL_micro.Status (count) 2019-08-15T22:08:04.627Z,1565906884.627 [CommandLine](IMPORTANT): DVL_micro.Beam1Good (count) 2019-08-15T22:08:04.628Z,1565906884.628 [CommandLine](IMPORTANT): DVL_micro.Beam2Good (count) 2019-08-15T22:08:04.628Z,1565906884.628 [CommandLine](IMPORTANT): DVL_micro.Beam3Good (count) 2019-08-15T22:08:04.628Z,1565906884.628 [CommandLine](IMPORTANT): DVL_micro.Beam4Good (count) 2019-08-15T22:08:04.629Z,1565906884.629 [CommandLine](IMPORTANT): DVL_micro.Altitude1 (meter) 2019-08-15T22:08:04.629Z,1565906884.629 [CommandLine](IMPORTANT): DVL_micro.Altitude2 (meter) 2019-08-15T22:08:04.630Z,1565906884.630 [CommandLine](IMPORTANT): DVL_micro.Altitude3 (meter) 2019-08-15T22:08:04.630Z,1565906884.630 [CommandLine](IMPORTANT): DVL_micro.Altitude4 (meter) 2019-08-15T22:08:04.630Z,1565906884.630 [CommandLine](IMPORTANT): DVL_micro.BottomVelocityFlag (count) 2019-08-15T22:08:04.631Z,1565906884.631 [CommandLine](IMPORTANT): DVL_micro.WaterVelocityFlag (meter) 2019-08-15T22:08:04.718Z,1565906884.718 [CommandLine](IMPORTANT): DVL_micro.component_voltage (volt) 2019-08-15T22:08:04.719Z,1565906884.719 [CommandLine](IMPORTANT): DVL_micro.component_avgVoltage (volt) 2019-08-15T22:08:04.720Z,1565906884.720 [CommandLine](IMPORTANT): DVL_micro.component_current (milliampere) 2019-08-15T22:08:04.720Z,1565906884.720 [CommandLine](IMPORTANT): DVL_micro.component_avgCurrent (milliampere) 2019-08-15T22:08:22.929Z,1565906902.929 [CommandLine](IMPORTANT): got command report touch DVL_micro.height_above_sea_floor 2019-08-15T22:08:23.787Z,1565906903.787 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:24.551Z,1565906904.551 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:25.352Z,1565906905.352 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:26.177Z,1565906906.177 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:27.017Z,1565906907.017 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:27.777Z,1565906907.777 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:28.592Z,1565906908.592 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:29.402Z,1565906909.402 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:30.239Z,1565906910.239 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:30.584Z,1565906910.584 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-08-15T22:08:30.584Z,1565906910.584 [Default:CheckIn:C.Wait] Stopped 2019-08-15T22:08:30.584Z,1565906910.584 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-08-15T22:08:30.584Z,1565906910.584 [Default:CheckIn:D] Running Loop=1 2019-08-15T22:08:31.006Z,1565906911.006 [Default:CheckIn:D] Stopped 2019-08-15T22:08:31.006Z,1565906911.006 [Default:CheckIn:E] Running Loop=1 2019-08-15T22:08:31.047Z,1565906911.047 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:31.389Z,1565906911.389 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.518058 min 2019-08-15T22:08:31.389Z,1565906911.389 [Default:CheckIn:E] Stopped 2019-08-15T22:08:31.390Z,1565906911.390 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-08-15T22:08:31.390Z,1565906911.390 [Default:CheckIn] Stopped 2019-08-15T22:08:31.390Z,1565906911.390 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-08-15T22:08:31.390Z,1565906911.390 [Default:CheckIn](INFO): Running loop #2 2019-08-15T22:08:31.390Z,1565906911.390 [Default:CheckIn] Running Loop=2 2019-08-15T22:08:31.390Z,1565906911.390 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-08-15T22:08:31.390Z,1565906911.390 [Default:CheckIn:Read_GPS] Running Loop=1 2019-08-15T22:08:31.832Z,1565906911.832 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:32.642Z,1565906912.642 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:33.005Z,1565906913.005 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:08:33.437Z,1565906913.437 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220611.00,A,4149.69694,N,08324.71246,W,0.078,173.18,150819,,,D*7F 2019-08-15T22:08:33.439Z,1565906913.439 [NAL9602](INFO): GPS fix at 20190815T220611: (41.828282, -83.411874) 2019-08-15T22:08:33.476Z,1565906913.476 [Default:CheckIn:Read_GPS] Stopped 2019-08-15T22:08:33.476Z,1565906913.476 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-08-15T22:08:33.506Z,1565906913.506 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:34.361Z,1565906914.361 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:35.154Z,1565906915.154 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:35.993Z,1565906915.993 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:36.821Z,1565906916.821 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:37.620Z,1565906917.620 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:38.414Z,1565906918.414 [Reporter](INFO): DVL_micro.height_above_sea_floor 0.200000 m 2019-08-15T22:08:38.567Z,1565906918.567 [CommandLine](IMPORTANT): got command report clear 2019-08-15T22:08:39.428Z,1565906919.428 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20190815T215928/Courier0007.lzma 2019-08-15T22:08:40.234Z,1565906920.234 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Courier0007.lzma.bak 2019-08-15T22:08:40.234Z,1565906920.234 [DataOverHttps](INFO): SBD MOMSN=11579216 2019-08-15T22:08:48.430Z,1565906928.430 [NAL9602](INFO): SBD MO Status=0, MOMSN=31129, MT Status=0, MTMSN=0 2019-08-15T22:08:48.430Z,1565906928.430 [NAL9602](INFO): No messages in MT queue 2019-08-15T22:08:53.786Z,1565906933.786 [DataOverHttps](INFO): Sending 1007 bytes from file Logs/20190815T215928/Express0001.lzma 2019-08-15T22:08:54.590Z,1565906934.590 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Express0001.lzma.bak 2019-08-15T22:08:54.590Z,1565906934.590 [DataOverHttps](INFO): SBD MOMSN=11579219 2019-08-15T22:09:10.160Z,1565906950.160 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20190815T215928/Express0005.lzma 2019-08-15T22:09:10.966Z,1565906950.966 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Express0005.lzma.bak 2019-08-15T22:09:10.967Z,1565906950.967 [DataOverHttps](INFO): SBD MOMSN=11579243 2019-08-15T22:09:19.158Z,1565906959.158 [NAL9602](INFO): Not Powering down - fast GPS 2019-08-15T22:09:24.446Z,1565906964.446 [DataOverHttps](INFO): Sending 1042 bytes from file Logs/20190815T215928/Express0008.lzma 2019-08-15T22:09:25.250Z,1565906965.250 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Express0008.lzma.bak 2019-08-15T22:09:25.250Z,1565906965.250 [DataOverHttps](INFO): SBD MOMSN=11579246 2019-08-15T22:09:26.069Z,1565906966.069 [Default:CheckIn:Read_Iridium] Stopped 2019-08-15T22:09:26.069Z,1565906966.069 [Default:CheckIn:C.Wait] Running Loop=1 2019-08-15T22:09:26.069Z,1565906966.069 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-08-15T22:14:26.724Z,1565907266.724 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-08-15T22:14:26.724Z,1565907266.724 [Default:CheckIn:C.Wait] Stopped 2019-08-15T22:14:26.724Z,1565907266.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-08-15T22:14:26.724Z,1565907266.724 [Default:CheckIn:D] Running Loop=1 2019-08-15T22:14:27.120Z,1565907267.120 [Default:CheckIn:D] Stopped 2019-08-15T22:14:27.120Z,1565907267.120 [Default:CheckIn:E] Running Loop=1 2019-08-15T22:14:27.537Z,1565907267.537 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.453289 min 2019-08-15T22:14:27.537Z,1565907267.537 [Default:CheckIn:E] Stopped 2019-08-15T22:14:27.538Z,1565907267.538 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-08-15T22:14:27.538Z,1565907267.538 [Default:CheckIn] Stopped 2019-08-15T22:14:27.538Z,1565907267.538 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-08-15T22:14:27.538Z,1565907267.538 [Default:CheckIn](INFO): Running loop #3 2019-08-15T22:14:27.538Z,1565907267.538 [Default:CheckIn] Running Loop=3 2019-08-15T22:14:27.538Z,1565907267.538 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-08-15T22:14:27.538Z,1565907267.538 [Default:CheckIn:Read_GPS] Running Loop=1 2019-08-15T22:14:29.133Z,1565907269.133 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:14:29.513Z,1565907269.513 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221207.00,A,4149.69795,N,08324.70624,W,0.233,33.90,150819,,,D*44 2019-08-15T22:14:29.515Z,1565907269.515 [NAL9602](INFO): GPS fix at 20190815T221207: (41.828299, -83.411771) 2019-08-15T22:14:29.547Z,1565907269.547 [Default:CheckIn:Read_GPS] Stopped 2019-08-15T22:14:29.547Z,1565907269.547 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-08-15T22:14:35.540Z,1565907275.540 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190815T215928/Courier0010.lzma 2019-08-15T22:14:36.346Z,1565907276.346 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Courier0010.lzma.bak 2019-08-15T22:14:36.346Z,1565907276.346 [DataOverHttps](INFO): SBD MOMSN=11579367 2019-08-15T22:14:44.478Z,1565907284.478 [NAL9602](INFO): SBD MO Status=0, MOMSN=31130, MT Status=0, MTMSN=0 2019-08-15T22:14:44.478Z,1565907284.478 [NAL9602](INFO): No messages in MT queue 2019-08-15T22:14:48.664Z,1565907288.664 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20190815T215928/Express0011.lzma 2019-08-15T22:14:49.470Z,1565907289.470 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Express0011.lzma.bak 2019-08-15T22:14:49.470Z,1565907289.470 [DataOverHttps](INFO): SBD MOMSN=11579370 2019-08-15T22:14:50.560Z,1565907290.560 [Default:CheckIn:Read_Iridium] Stopped 2019-08-15T22:14:50.560Z,1565907290.560 [Default:CheckIn:C.Wait] Running Loop=1 2019-08-15T22:14:50.560Z,1565907290.560 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-08-15T22:15:15.184Z,1565907315.184 [NAL9602](INFO): Not Powering down - fast GPS 2019-08-15T22:15:42.520Z,1565907342.520 [CommandLine](IMPORTANT): got command show variable dvl_micro 2019-08-15T22:15:42.541Z,1565907342.541 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup (bool) 2019-08-15T22:15:42.541Z,1565907342.541 [CommandLine](IMPORTANT): DVL_micro.simulateHardware (bool) 2019-08-15T22:15:42.541Z,1565907342.541 [CommandLine](IMPORTANT): DVL_micro.magDeviation (degree) 2019-08-15T22:15:42.542Z,1565907342.542 [CommandLine](IMPORTANT): DVL_micro.pitchOffset (degree) 2019-08-15T22:15:42.542Z,1565907342.542 [CommandLine](IMPORTANT): DVL_micro.power (watt) 2019-08-15T22:15:42.543Z,1565907342.543 [CommandLine](IMPORTANT): DVL_micro.rollOffset (degree) 2019-08-15T22:15:42.554Z,1565907342.554 [CommandLine](IMPORTANT): DVL_micro.loadControl (none) 2019-08-15T22:15:42.554Z,1565907342.554 [CommandLine](IMPORTANT): DVL_micro.uart (none) 2019-08-15T22:15:42.554Z,1565907342.554 [CommandLine](IMPORTANT): DVL_micro.baud (bit_per_second) 2019-08-15T22:15:42.736Z,1565907342.736 [CommandLine](IMPORTANT): DVL_micro.height_above_sea_floor (meter) 2019-08-15T22:15:42.737Z,1565907342.737 [CommandLine](IMPORTANT): DVL_micro.platform_orientation (radian) 2019-08-15T22:15:42.741Z,1565907342.741 [CommandLine](IMPORTANT): DVL_micro.platform_magnetic_orientation (radian) 2019-08-15T22:15:42.743Z,1565907342.743 [CommandLine](IMPORTANT): DVL_micro.platform_pitch_angle (radian) 2019-08-15T22:15:42.746Z,1565907342.746 [CommandLine](IMPORTANT): DVL_micro.platform_roll_angle (radian) 2019-08-15T22:15:42.746Z,1565907342.746 [CommandLine](IMPORTANT): DVL_micro.platform_orientation_matrix (none) 2019-08-15T22:15:42.747Z,1565907342.747 [CommandLine](IMPORTANT): DVL_micro.platform_velocity_wrt_ground (meter_per_second) 2019-08-15T22:15:42.747Z,1565907342.747 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_wrt_ground (meter_per_second) 2019-08-15T22:15:42.748Z,1565907342.748 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_wrt_ground (meter_per_second) 2019-08-15T22:15:42.748Z,1565907342.748 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_wrt_ground (meter_per_second) 2019-08-15T22:15:42.748Z,1565907342.748 [CommandLine](IMPORTANT): DVL_micro.platform_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:15:42.749Z,1565907342.749 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:15:42.749Z,1565907342.749 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:15:42.750Z,1565907342.750 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_wrt_sea_water (meter_per_second) 2019-08-15T22:15:42.750Z,1565907342.750 [CommandLine](IMPORTANT): DVL_micro.platform_x_velocity_current (meter_per_second) 2019-08-15T22:15:42.750Z,1565907342.750 [CommandLine](IMPORTANT): DVL_micro.platform_y_velocity_current (meter_per_second) 2019-08-15T22:15:42.751Z,1565907342.751 [CommandLine](IMPORTANT): DVL_micro.platform_z_velocity_current (meter_per_second) 2019-08-15T22:15:42.751Z,1565907342.751 [CommandLine](IMPORTANT): DVL_micro.Status (count) 2019-08-15T22:15:42.752Z,1565907342.752 [CommandLine](IMPORTANT): DVL_micro.Beam1Good (count) 2019-08-15T22:15:42.752Z,1565907342.752 [CommandLine](IMPORTANT): DVL_micro.Beam2Good (count) 2019-08-15T22:15:42.752Z,1565907342.752 [CommandLine](IMPORTANT): DVL_micro.Beam3Good (count) 2019-08-15T22:15:42.769Z,1565907342.769 [CommandLine](IMPORTANT): DVL_micro.Beam4Good (count) 2019-08-15T22:15:42.769Z,1565907342.769 [CommandLine](IMPORTANT): DVL_micro.Altitude1 (meter) 2019-08-15T22:15:42.770Z,1565907342.770 [CommandLine](IMPORTANT): DVL_micro.Altitude2 (meter) 2019-08-15T22:15:42.770Z,1565907342.770 [CommandLine](IMPORTANT): DVL_micro.Altitude3 (meter) 2019-08-15T22:15:42.771Z,1565907342.771 [CommandLine](IMPORTANT): DVL_micro.Altitude4 (meter) 2019-08-15T22:15:42.771Z,1565907342.771 [CommandLine](IMPORTANT): DVL_micro.BottomVelocityFlag (count) 2019-08-15T22:15:42.771Z,1565907342.771 [CommandLine](IMPORTANT): DVL_micro.WaterVelocityFlag (meter) 2019-08-15T22:15:42.822Z,1565907342.822 [CommandLine](IMPORTANT): DVL_micro.component_voltage (volt) 2019-08-15T22:15:42.822Z,1565907342.822 [CommandLine](IMPORTANT): DVL_micro.component_avgVoltage (volt) 2019-08-15T22:15:42.823Z,1565907342.823 [CommandLine](IMPORTANT): DVL_micro.component_current (milliampere) 2019-08-15T22:15:42.823Z,1565907342.823 [CommandLine](IMPORTANT): DVL_micro.component_avgCurrent (milliampere) 2019-08-15T22:16:03.905Z,1565907363.905 [CommandLine](IMPORTANT): got command report list 2019-08-15T22:16:21.593Z,1565907381.593 [CommandLine](IMPORTANT): got command report touch DVL_micro.BottomVelocityFlag 2019-08-15T22:16:22.279Z,1565907382.279 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:23.088Z,1565907383.088 [Reporter](INFO): DVL_micro.BottomVelocityFlag 0 count 2019-08-15T22:16:23.909Z,1565907383.909 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:24.727Z,1565907384.727 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:25.520Z,1565907385.520 [Reporter](INFO): DVL_micro.BottomVelocityFlag 0 count 2019-08-15T22:16:26.324Z,1565907386.324 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:27.145Z,1565907387.145 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:27.973Z,1565907387.973 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:28.748Z,1565907388.748 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:29.557Z,1565907389.557 [Reporter](INFO): DVL_micro.BottomVelocityFlag 0 count 2019-08-15T22:16:30.373Z,1565907390.373 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:31.237Z,1565907391.237 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:32.029Z,1565907392.029 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:32.837Z,1565907392.837 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:33.629Z,1565907393.629 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:34.441Z,1565907394.441 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:35.265Z,1565907395.265 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:36.020Z,1565907396.020 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:36.833Z,1565907396.833 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:37.677Z,1565907397.677 [Reporter](INFO): DVL_micro.BottomVelocityFlag 1 count 2019-08-15T22:16:38.462Z,1565907398.462 [CommandLine](IMPORTANT): got command report clear 2019-08-15T22:17:12.551Z,1565907432.551 [CommandLine](IMPORTANT): got command report touch height_above_sea_floor 2019-08-15T22:17:35.782Z,1565907455.782 [DVL_micro](ERROR): checksum mismatch: reported110 , calculated:117 2019-08-15T22:17:35.782Z,1565907455.782 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 1 1 1 0 3.2 0.2 3.0 0 36.3 108.0 -55.9 264 0 0 0 2 0 0 0 -314.-10.6 1 -250.1 226.8 -10.9 1 0 0 0 0 0 0 0 0 6.46 -2.60 339.4 0.2 26.5 0.005 35.0 1489 110 2019-08-15T22:19:32.125Z,1565907572.125 [DVL_micro](ERROR): only read 4 of 46 data items 2019-08-15T22:19:32.126Z,1565907572.126 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 6 2.5 0.2 3.7 -485.0 4.9 431.5 61.3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 9.26 -3.95 24.2 0.2 26.5 0.005 35.0 1489 96 2019-08-15T22:19:51.143Z,1565907591.143 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-08-15T22:19:51.143Z,1565907591.143 [Default:CheckIn:C.Wait] Stopped 2019-08-15T22:19:51.143Z,1565907591.143 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-08-15T22:19:51.143Z,1565907591.143 [Default:CheckIn:D] Running Loop=1 2019-08-15T22:19:51.600Z,1565907591.600 [Default:CheckIn:D] Stopped 2019-08-15T22:19:51.600Z,1565907591.600 [Default:CheckIn:E] Running Loop=1 2019-08-15T22:19:51.954Z,1565907591.954 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.861302 min 2019-08-15T22:19:51.954Z,1565907591.954 [Default:CheckIn:E] Stopped 2019-08-15T22:19:51.954Z,1565907591.954 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-08-15T22:19:51.954Z,1565907591.954 [Default:CheckIn] Stopped 2019-08-15T22:19:51.955Z,1565907591.955 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-08-15T22:19:51.955Z,1565907591.955 [Default:CheckIn](INFO): Running loop #4 2019-08-15T22:19:51.955Z,1565907591.955 [Default:CheckIn] Running Loop=4 2019-08-15T22:19:51.955Z,1565907591.955 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-08-15T22:19:51.955Z,1565907591.955 [Default:CheckIn:Read_GPS] Running Loop=1 2019-08-15T22:19:53.549Z,1565907593.549 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:19:53.956Z,1565907593.956 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221731.00,A,4149.69657,N,08324.70977,W,0.019,33.90,150819,,,D*48 2019-08-15T22:19:53.959Z,1565907593.959 [NAL9602](INFO): GPS fix at 20190815T221731: (41.828276, -83.411829) 2019-08-15T22:19:53.981Z,1565907593.981 [Default:CheckIn:Read_GPS] Stopped 2019-08-15T22:19:53.982Z,1565907593.982 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-08-15T22:20:05.862Z,1565907605.862 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190815T215928/Courier0013.lzma 2019-08-15T22:20:06.668Z,1565907606.668 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Courier0013.lzma.bak 2019-08-15T22:20:06.668Z,1565907606.668 [DataOverHttps](INFO): SBD MOMSN=11579390 2019-08-15T22:20:07.286Z,1565907607.286 [NAL9602](INFO): SBD MO Status=0, MOMSN=31131, MT Status=0, MTMSN=0 2019-08-15T22:20:07.287Z,1565907607.287 [NAL9602](INFO): No messages in MT queue 2019-08-15T22:20:19.235Z,1565907619.235 [DataOverHttps](INFO): Sending 716 bytes from file Logs/20190815T215928/Express0014.lzma 2019-08-15T22:20:20.038Z,1565907620.038 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Express0014.lzma.bak 2019-08-15T22:20:20.038Z,1565907620.038 [DataOverHttps](INFO): SBD MOMSN=11579393 2019-08-15T22:20:21.057Z,1565907621.057 [Default:CheckIn:Read_Iridium] Stopped 2019-08-15T22:20:21.057Z,1565907621.057 [Default:CheckIn:C.Wait] Running Loop=1 2019-08-15T22:20:21.057Z,1565907621.057 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-08-15T22:20:23.181Z,1565907623.181 [CommandLine](IMPORTANT): got command report touch DVL_micro.Beam3Good 2019-08-15T22:20:23.921Z,1565907623.921 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:24.766Z,1565907624.766 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:25.563Z,1565907625.563 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:26.311Z,1565907626.311 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:27.118Z,1565907627.118 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:27.957Z,1565907627.957 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:28.777Z,1565907628.777 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:29.585Z,1565907629.585 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:30.403Z,1565907630.403 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:31.245Z,1565907631.245 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:31.973Z,1565907631.973 [Reporter](INFO): DVL_micro.Beam3Good 1 count 2019-08-15T22:20:32.599Z,1565907632.599 [CommandLine](IMPORTANT): got command report clear 2019-08-15T22:20:37.973Z,1565907637.973 [NAL9602](INFO): Not Powering down - fast GPS 2019-08-15T22:22:24.469Z,1565907744.469 [CommandLine](IMPORTANT): got command report touch platform_buoyancy_position 2019-08-15T22:22:33.977Z,1565907753.977 [CommandLine](IMPORTANT): got command show variable oxygen 2019-08-15T22:22:33.993Z,1565907753.993 [CommandLine](IMPORTANT): mass_concentration_of_oxygen_in_sea_water (unknown) 2019-08-15T22:22:34.140Z,1565907754.140 [CommandLine](IMPORTANT): CTD_Seabird.oxygenCalCoeffFOffset (none) 2019-08-15T22:22:34.141Z,1565907754.141 [CommandLine](IMPORTANT): CTD_Seabird.oxygenCalCoeffSoc (none) 2019-08-15T22:22:34.141Z,1565907754.141 [CommandLine](IMPORTANT): CTD_Seabird.oxygenCalCoeffA (none) 2019-08-15T22:22:34.142Z,1565907754.142 [CommandLine](IMPORTANT): CTD_Seabird.oxygenCalCoeffB (none) 2019-08-15T22:22:34.142Z,1565907754.142 [CommandLine](IMPORTANT): CTD_Seabird.oxygenCalCoeffC (none) 2019-08-15T22:22:34.142Z,1565907754.142 [CommandLine](IMPORTANT): CTD_Seabird.oxygenCalCoeffE (none) 2019-08-15T22:22:34.167Z,1565907754.167 [CommandLine](IMPORTANT): Aanderaa_O2.mass_concentration_of_oxygen_in_sea_water (microgram_per_liter) 2019-08-15T22:23:02.111Z,1565907782.111 [CommandLine](IMPORTANT): got command report mod mass_concentration_of_oxygen_in_sea_water 2019-08-15T22:23:02.329Z,1565907782.329 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water no_value 2019-08-15T22:23:13.219Z,1565907793.219 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml 2019-08-15T22:23:13.220Z,1565907793.220 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml 2019-08-15T22:23:13.436Z,1565907793.436 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool 2019-08-15T22:23:13.439Z,1565907793.439 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg 2019-08-15T22:23:13.443Z,1565907793.443 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg 2019-08-15T22:23:13.446Z,1565907793.446 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool 2019-08-15T22:23:13.449Z,1565907793.449 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min 2019-08-15T22:23:13.452Z,1565907793.452 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool 2019-08-15T22:23:13.496Z,1565907793.496 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool 2019-08-15T22:23:13.499Z,1565907793.499 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count 2019-08-15T22:23:13.502Z,1565907793.502 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count 2019-08-15T22:23:13.556Z,1565907793.556 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 % 2019-08-15T22:23:13.559Z,1565907793.559 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool 2019-08-15T22:23:13.580Z,1565907793.580 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool 2019-08-15T22:23:13.582Z,1565907793.582 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool 2019-08-15T22:23:13.601Z,1565907793.601 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool 2019-08-15T22:23:13.613Z,1565907793.613 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 1.000000 bool 2019-08-15T22:23:13.621Z,1565907793.621 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool 2019-08-15T22:23:13.637Z,1565907793.637 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool 2019-08-15T22:23:13.657Z,1565907793.657 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool 2019-08-15T22:23:13.664Z,1565907793.664 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool 2019-08-15T22:23:13.668Z,1565907793.668 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool 2019-08-15T22:23:13.672Z,1565907793.672 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool 2019-08-15T22:23:13.707Z,1565907793.707 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool 2019-08-15T22:23:13.711Z,1565907793.711 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count 2019-08-15T22:23:13.730Z,1565907793.730 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count 2019-08-15T22:23:13.734Z,1565907793.734 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m 2019-08-15T22:23:13.746Z,1565907793.746 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m 2019-08-15T22:23:13.753Z,1565907793.753 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m 2019-08-15T22:23:13.756Z,1565907793.756 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m 2019-08-15T22:23:13.764Z,1565907793.764 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l 2019-08-15T22:23:13.767Z,1565907793.767 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m 2019-08-15T22:23:13.779Z,1565907793.779 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC 2019-08-15T22:23:13.786Z,1565907793.786 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg 2019-08-15T22:23:13.810Z,1565907793.810 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg 2019-08-15T22:23:13.814Z,1565907793.814 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l 2019-08-15T22:23:13.817Z,1565907793.817 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m 2019-08-15T22:23:13.820Z,1565907793.820 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg 2019-08-15T22:23:13.828Z,1565907793.828 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg 2019-08-15T22:23:13.836Z,1565907793.836 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m 2019-08-15T22:23:13.860Z,1565907793.860 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m 2019-08-15T22:23:13.864Z,1565907793.864 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg 2019-08-15T22:23:13.867Z,1565907793.867 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg 2019-08-15T22:23:13.895Z,1565907793.895 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m 2019-08-15T22:23:13.898Z,1565907793.898 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3 2019-08-15T22:23:13.901Z,1565907793.901 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m 2019-08-15T22:23:13.934Z,1565907793.934 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg 2019-08-15T22:23:13.967Z,1565907793.967 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg 2019-08-15T22:23:13.980Z,1565907793.980 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb 2019-08-15T22:23:13.988Z,1565907793.988 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m 2019-08-15T22:23:13.995Z,1565907793.995 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg 2019-08-15T22:23:14.007Z,1565907794.007 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg 2019-08-15T22:23:14.011Z,1565907794.011 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m 2019-08-15T22:23:14.069Z,1565907794.069 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-08-15T22:23:14.098Z,1565907794.098 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2019-08-15T22:23:14.145Z,1565907794.145 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-08-15T22:23:14.164Z,1565907794.164 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-08-15T22:23:14.204Z,1565907794.204 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2019-08-15T22:23:14.279Z,1565907794.279 [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-08-15T22:23:14.306Z,1565907794.306 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml 2019-08-15T22:23:14.420Z,1565907794.420 [Default] Stopped 2019-08-15T22:23:14.420Z,1565907794.420 [Default](DEBUG): Aggregate::uninitialize Default 2019-08-15T22:23:14.420Z,1565907794.420 [Default:B.GoToSurface] Stopped 2019-08-15T22:23:14.420Z,1565907794.420 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-08-15T22:23:14.420Z,1565907794.420 [Default:CheckIn] Stopped 2019-08-15T22:23:14.420Z,1565907794.420 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-08-15T22:23:14.420Z,1565907794.420 [Default:CheckIn:C.Wait] Stopped 2019-08-15T22:23:14.420Z,1565907794.420 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-08-15T22:23:14.420Z,1565907794.420 [MissionManager](IMPORTANT): Started mission Science 2019-08-15T22:23:14.420Z,1565907794.420 [Science] Running Loop=1 2019-08-15T22:23:14.433Z,1565907794.433 [Science](DEBUG): Aggregate::initialize Science 2019-08-15T22:23:14.433Z,1565907794.433 [Science:A] Running Loop=1 2019-08-15T22:23:14.433Z,1565907794.433 [Science:B] Running Loop=1 2019-08-15T22:23:14.433Z,1565907794.433 [Science:C] Running Loop=1 2019-08-15T22:23:14.433Z,1565907794.433 [Science:D] Running Loop=1 2019-08-15T22:23:14.433Z,1565907794.433 [Science:E] Running Loop=1 2019-08-15T22:23:14.433Z,1565907794.433 [Science:F] Running Loop=1 2019-08-15T22:23:14.433Z,1565907794.433 [Science:G] Running Loop=1 2019-08-15T22:23:14.433Z,1565907794.433 [Science:H] Running Loop=1 2019-08-15T22:23:14.433Z,1565907794.433 [Science:Read_Oil] Running Loop=1 2019-08-15T22:23:14.434Z,1565907794.434 [Science:PeakDetectChl] Running Loop=1 2019-08-15T22:23:14.434Z,1565907794.434 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl 2019-08-15T22:23:14.434Z,1565907794.434 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2019-08-15T22:23:14.434Z,1565907794.434 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-08-15T22:23:14.436Z,1565907794.436 [Science:HighestChlPeakReport] Running Loop=1 2019-08-15T22:23:14.436Z,1565907794.436 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport 2019-08-15T22:23:14.436Z,1565907794.436 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2019-08-15T22:23:14.436Z,1565907794.436 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2019-08-15T22:23:14.436Z,1565907794.436 [Science:PeakDetectNO3] Running Loop=1 2019-08-15T22:23:14.441Z,1565907794.441 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3 2019-08-15T22:23:14.441Z,1565907794.441 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2019-08-15T22:23:14.441Z,1565907794.441 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-08-15T22:23:14.443Z,1565907794.443 [Science:PeakDetectOil] Running Loop=1 2019-08-15T22:23:14.443Z,1565907794.443 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil 2019-08-15T22:23:14.444Z,1565907794.444 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2019-08-15T22:23:14.444Z,1565907794.444 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-08-15T22:23:14.450Z,1565907794.450 [Science:PeakDetectFDOM] Running Loop=1 2019-08-15T22:23:14.450Z,1565907794.450 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM 2019-08-15T22:23:14.450Z,1565907794.450 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2019-08-15T22:23:14.450Z,1565907794.450 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2019-08-15T22:23:14.465Z,1565907794.465 [Science:PeakDetectFDOM] Stopped 2019-08-15T22:23:14.465Z,1565907794.465 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectOil] Stopped 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectNO3] Stopped 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2019-08-15T22:23:14.466Z,1565907794.466 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-08-15T22:23:14.466Z,1565907794.466 [Science:HighestChlPeakReport] Stopped 2019-08-15T22:23:14.466Z,1565907794.466 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport 2019-08-15T22:23:14.467Z,1565907794.467 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2019-08-15T22:23:14.467Z,1565907794.467 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2019-08-15T22:23:14.467Z,1565907794.467 [Science:PeakDetectChl] Stopped 2019-08-15T22:23:14.467Z,1565907794.467 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl 2019-08-15T22:23:14.467Z,1565907794.467 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2019-08-15T22:23:14.467Z,1565907794.467 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2019-08-15T22:23:14.467Z,1565907794.467 [Science:Read_Oil] Stopped 2019-08-15T22:23:14.467Z,1565907794.467 [Science:H] Stopped 2019-08-15T22:23:14.467Z,1565907794.467 [Science:G] Running Loop=1 2019-08-15T22:23:14.468Z,1565907794.468 [Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2019-08-15T22:23:14.468Z,1565907794.468 [Science:F] Stopped 2019-08-15T22:23:14.468Z,1565907794.468 [Science:E] Stopped 2019-08-15T22:23:14.468Z,1565907794.468 [Science:D] Running Loop=1 2019-08-15T22:23:14.473Z,1565907794.473 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2019-08-15T22:23:14.474Z,1565907794.474 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2019-08-15T22:23:14.474Z,1565907794.474 [Science:C] Running Loop=1 2019-08-15T22:23:14.474Z,1565907794.474 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2019-08-15T22:23:14.475Z,1565907794.475 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2019-08-15T22:23:14.475Z,1565907794.475 [Science:B] Stopped 2019-08-15T22:23:14.475Z,1565907794.475 [Science:A] Running Loop=1 2019-08-15T22:23:14.476Z,1565907794.476 [Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water 2019-08-15T22:23:14.747Z,1565907794.747 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2 2019-08-15T22:23:14.747Z,1565907794.747 [WetLabsBB2FL](INFO): Powering up 2019-08-15T22:23:15.362Z,1565907795.362 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-08-15T22:23:17.668Z,1565907797.668 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water nan ug/l 2019-08-15T22:23:18.374Z,1565907798.374 [CTD_Seabird](ERROR): Failed to parse device response: 2019-08-15T22:23:19.638Z,1565907799.638 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7126.687988 ug/l 2019-08-15T22:23:21.698Z,1565907801.698 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7125.631836 ug/l 2019-08-15T22:23:25.709Z,1565907805.709 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7126.016113 ug/l 2019-08-15T22:23:27.714Z,1565907807.714 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7128.416016 ug/l 2019-08-15T22:23:28.527Z,1565907808.527 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7130.111816 ug/l 2019-08-15T22:23:29.838Z,1565907809.838 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7128.160156 ug/l 2019-08-15T22:23:30.643Z,1565907810.643 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7121.567871 ug/l 2019-08-15T22:23:31.787Z,1565907811.787 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7125.631836 ug/l 2019-08-15T22:23:33.054Z,1565907813.054 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7123.200195 ug/l 2019-08-15T22:23:33.862Z,1565907813.862 [Reporter](INFO): mass_concentration_of_oxygen_in_sea_water 7113.631836 ug/l 2019-08-15T22:23:34.412Z,1565907814.412 [CommandLine](IMPORTANT): got command report clear 2019-08-15T22:23:35.636Z,1565907815.636 [CommandLine](IMPORTANT): got command stop 2019-08-15T22:23:35.636Z,1565907815.636 [CommandLine](IMPORTANT): Scheduling is paused 2019-08-15T22:23:35.636Z,1565907815.636 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2019-08-15T22:23:35.810Z,1565907815.810 [MissionManager](INFO): MissionManager is completed. 2019-08-15T22:23:35.810Z,1565907815.810 [MissionManager](INFO): Uninitializing Mission Science 2019-08-15T22:23:35.810Z,1565907815.810 [Science] Stopped 2019-08-15T22:23:35.810Z,1565907815.810 [Science](DEBUG): Aggregate::uninitialize Science 2019-08-15T22:23:35.810Z,1565907815.810 [Science:A] Stopped 2019-08-15T22:23:35.810Z,1565907815.810 [Science:C] Stopped 2019-08-15T22:23:35.810Z,1565907815.810 [Science:D] Stopped 2019-08-15T22:23:35.810Z,1565907815.810 [Science:G] Stopped 2019-08-15T22:23:36.025Z,1565907816.025 [WetLabsBB2FL](INFO): Powering down 2019-08-15T22:23:36.220Z,1565907816.220 [MissionManager](IMPORTANT): Started mission Default 2019-08-15T22:23:36.220Z,1565907816.220 [Default] Running Loop=1 2019-08-15T22:23:36.220Z,1565907816.220 [Default](DEBUG): Aggregate::initialize Default 2019-08-15T22:23:36.220Z,1565907816.220 [Default:B.GoToSurface] Running Loop=1 2019-08-15T22:23:36.220Z,1565907816.220 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-08-15T22:23:36.225Z,1565907816.225 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-08-15T22:23:36.226Z,1565907816.226 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-08-15T22:23:36.226Z,1565907816.226 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-08-15T22:23:36.226Z,1565907816.226 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-08-15T22:23:36.227Z,1565907816.227 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-08-15T22:23:36.227Z,1565907816.227 [Default:A.Wait] Running Loop=1 2019-08-15T22:23:36.227Z,1565907816.227 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-08-15T22:23:36.661Z,1565907816.661 [Aanderaa_O2](INFO): Powering down 2019-08-15T22:23:49.516Z,1565907829.516 [Default:A.Wait](INFO): Done Waiting. 2019-08-15T22:23:49.516Z,1565907829.516 [Default:A.Wait] Stopped 2019-08-15T22:23:49.516Z,1565907829.516 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-08-15T22:23:49.935Z,1565907829.935 [Default:CheckIn] Running Loop=1 2019-08-15T22:23:49.935Z,1565907829.935 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-08-15T22:23:49.936Z,1565907829.936 [Default:CheckIn:Read_GPS] Running Loop=1 2019-08-15T22:23:51.549Z,1565907831.549 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:23:51.939Z,1565907831.939 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222128.00,A,4149.69570,N,08324.71133,W,0.097,33.90,150819,,,D*4C 2019-08-15T22:23:51.941Z,1565907831.941 [NAL9602](INFO): GPS fix at 20190815T222128: (41.828262, -83.411856) 2019-08-15T22:23:51.966Z,1565907831.966 [Default:CheckIn:Read_GPS] Stopped 2019-08-15T22:23:51.967Z,1565907831.967 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-08-15T22:23:57.780Z,1565907837.780 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190815T215928/Courier0016.lzma 2019-08-15T22:23:58.582Z,1565907838.582 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Courier0016.lzma.bak 2019-08-15T22:23:58.582Z,1565907838.582 [DataOverHttps](INFO): SBD MOMSN=11579444 2019-08-15T22:24:06.902Z,1565907846.902 [NAL9602](INFO): SBD MO Status=0, MOMSN=31132, MT Status=0, MTMSN=0 2019-08-15T22:24:06.903Z,1565907846.903 [NAL9602](INFO): No messages in MT queue 2019-08-15T22:24:11.249Z,1565907851.249 [DataOverHttps](INFO): Sending 737 bytes from file Logs/20190815T215928/Express0017.lzma 2019-08-15T22:24:12.054Z,1565907852.054 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Express0017.lzma.bak 2019-08-15T22:24:12.055Z,1565907852.055 [DataOverHttps](INFO): SBD MOMSN=11579447 2019-08-15T22:24:13.388Z,1565907853.388 [Default:CheckIn:Read_Iridium] Stopped 2019-08-15T22:24:13.388Z,1565907853.388 [Default:CheckIn:C.Wait] Running Loop=1 2019-08-15T22:24:13.388Z,1565907853.388 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-08-15T22:24:13.476Z,1565907853.476 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position 2019-08-15T22:24:13.770Z,1565907853.770 [Reporter](INFO): platform_buoyancy_position 953.922852 cc 2019-08-15T22:24:37.593Z,1565907877.593 [NAL9602](INFO): Not Powering down - fast GPS 2019-08-15T22:24:37.874Z,1565907877.874 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 500.000000 cubic_centimeter 2019-08-15T22:24:37.876Z,1565907877.876 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2019-08-15T22:24:38.035Z,1565907878.035 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-08-15T22:24:42.927Z,1565907882.927 [Reporter](INFO): platform_buoyancy_position 958.623291 cc 2019-08-15T22:24:43.691Z,1565907883.691 [Reporter](INFO): platform_buoyancy_position 956.608887 cc 2019-08-15T22:24:44.110Z,1565907884.110 [Reporter](INFO): platform_buoyancy_position 954.460083 cc 2019-08-15T22:24:44.507Z,1565907884.507 [Reporter](INFO): platform_buoyancy_position 951.774048 cc 2019-08-15T22:24:44.931Z,1565907884.931 [Reporter](INFO): platform_buoyancy_position 940.089966 cc 2019-08-15T22:24:45.319Z,1565907885.319 [Reporter](INFO): platform_buoyancy_position 937.135376 cc 2019-08-15T22:24:45.743Z,1565907885.743 [Reporter](INFO): platform_buoyancy_position 932.703491 cc 2019-08-15T22:24:46.122Z,1565907886.122 [Reporter](INFO): platform_buoyancy_position 928.540161 cc 2019-08-15T22:24:46.536Z,1565907886.536 [Reporter](INFO): platform_buoyancy_position 922.093750 cc 2019-08-15T22:24:46.919Z,1565907886.919 [Reporter](INFO): platform_buoyancy_position 917.796143 cc 2019-08-15T22:24:47.337Z,1565907887.337 [Reporter](INFO): platform_buoyancy_position 911.484070 cc 2019-08-15T22:24:47.735Z,1565907887.735 [Reporter](INFO): platform_buoyancy_position 907.052124 cc 2019-08-15T22:24:48.163Z,1565907888.163 [Reporter](INFO): platform_buoyancy_position 900.874329 cc 2019-08-15T22:24:48.547Z,1565907888.547 [Reporter](INFO): platform_buoyancy_position 896.308167 cc 2019-08-15T22:24:48.983Z,1565907888.983 [Reporter](INFO): platform_buoyancy_position 889.996033 cc 2019-08-15T22:24:49.347Z,1565907889.347 [Reporter](INFO): platform_buoyancy_position 885.698425 cc 2019-08-15T22:24:49.763Z,1565907889.763 [Reporter](INFO): platform_buoyancy_position 881.266541 cc 2019-08-15T22:24:50.151Z,1565907890.151 [Reporter](INFO): platform_buoyancy_position 874.954468 cc 2019-08-15T22:24:50.607Z,1565907890.607 [Reporter](INFO): platform_buoyancy_position 870.522522 cc 2019-08-15T22:24:50.975Z,1565907890.975 [Reporter](INFO): platform_buoyancy_position 865.956360 cc 2019-08-15T22:24:51.566Z,1565907891.566 [Reporter](INFO): platform_buoyancy_position 857.629761 cc 2019-08-15T22:24:51.887Z,1565907891.887 [Reporter](INFO): platform_buoyancy_position 853.332153 cc 2019-08-15T22:24:52.339Z,1565907892.339 [Reporter](INFO): platform_buoyancy_position 847.020020 cc 2019-08-15T22:24:52.751Z,1565907892.751 [Reporter](INFO): platform_buoyancy_position 842.588135 cc 2019-08-15T22:24:53.170Z,1565907893.170 [Reporter](INFO): platform_buoyancy_position 836.410339 cc 2019-08-15T22:24:53.482Z,1565907893.482 [Reporter](INFO): platform_buoyancy_position 831.844116 cc 2019-08-15T22:24:53.919Z,1565907893.919 [Reporter](INFO): platform_buoyancy_position 825.532043 cc 2019-08-15T22:24:54.290Z,1565907894.290 [Reporter](INFO): platform_buoyancy_position 821.234436 cc 2019-08-15T22:24:54.895Z,1565907894.895 [Reporter](INFO): platform_buoyancy_position 812.504944 cc 2019-08-15T22:24:55.129Z,1565907895.129 [Reporter](INFO): platform_buoyancy_position 810.490417 cc 2019-08-15T22:24:55.676Z,1565907895.676 [Reporter](INFO): platform_buoyancy_position 803.909729 cc 2019-08-15T22:24:55.914Z,1565907895.914 [Reporter](INFO): platform_buoyancy_position 799.746460 cc 2019-08-15T22:24:56.391Z,1565907896.391 [Reporter](INFO): platform_buoyancy_position 793.300049 cc 2019-08-15T22:24:56.703Z,1565907896.703 [Reporter](INFO): platform_buoyancy_position 789.002441 cc 2019-08-15T22:24:57.143Z,1565907897.143 [Reporter](INFO): platform_buoyancy_position 784.570557 cc 2019-08-15T22:24:57.479Z,1565907897.479 [Reporter](INFO): platform_buoyancy_position 780.272949 cc 2019-08-15T22:24:57.947Z,1565907897.947 [Reporter](INFO): platform_buoyancy_position 773.826538 cc 2019-08-15T22:24:58.279Z,1565907898.279 [Reporter](INFO): platform_buoyancy_position 769.528931 cc 2019-08-15T22:24:58.703Z,1565907898.703 [Reporter](INFO): platform_buoyancy_position 763.216858 cc 2019-08-15T22:24:59.095Z,1565907899.095 [Reporter](INFO): platform_buoyancy_position 758.784912 cc 2019-08-15T22:24:59.539Z,1565907899.539 [Reporter](INFO): platform_buoyancy_position 752.338562 cc 2019-08-15T22:24:59.871Z,1565907899.871 [Reporter](INFO): platform_buoyancy_position 748.175232 cc 2019-08-15T22:25:00.351Z,1565907900.351 [Reporter](INFO): platform_buoyancy_position 741.594543 cc 2019-08-15T22:25:00.698Z,1565907900.698 [Reporter](INFO): platform_buoyancy_position 737.565552 cc 2019-08-15T22:25:01.118Z,1565907901.118 [Reporter](INFO): platform_buoyancy_position 732.865051 cc 2019-08-15T22:25:01.491Z,1565907901.491 [Reporter](INFO): platform_buoyancy_position 727.090149 cc 2019-08-15T22:25:01.915Z,1565907901.915 [Reporter](INFO): platform_buoyancy_position 722.255310 cc 2019-08-15T22:25:02.303Z,1565907902.303 [Reporter](INFO): platform_buoyancy_position 716.211853 cc 2019-08-15T22:25:02.743Z,1565907902.743 [Reporter](INFO): platform_buoyancy_position 711.242737 cc 2019-08-15T22:25:03.095Z,1565907903.095 [Reporter](INFO): platform_buoyancy_position 705.467834 cc 2019-08-15T22:25:03.547Z,1565907903.547 [Reporter](INFO): platform_buoyancy_position 700.767334 cc 2019-08-15T22:25:03.926Z,1565907903.926 [Reporter](INFO): platform_buoyancy_position 695.529602 cc 2019-08-15T22:25:04.347Z,1565907904.347 [Reporter](INFO): platform_buoyancy_position 690.023315 cc 2019-08-15T22:25:04.723Z,1565907904.723 [Reporter](INFO): platform_buoyancy_position 684.382751 cc 2019-08-15T22:25:05.151Z,1565907905.151 [Reporter](INFO): platform_buoyancy_position 679.279358 cc 2019-08-15T22:25:05.539Z,1565907905.539 [Reporter](INFO): platform_buoyancy_position 674.444519 cc 2019-08-15T22:25:05.963Z,1565907905.963 [Reporter](INFO): platform_buoyancy_position 668.535339 cc 2019-08-15T22:25:06.339Z,1565907906.339 [Reporter](INFO): platform_buoyancy_position 663.834839 cc 2019-08-15T22:25:06.751Z,1565907906.751 [Reporter](INFO): platform_buoyancy_position 657.791321 cc 2019-08-15T22:25:07.143Z,1565907907.143 [Reporter](INFO): platform_buoyancy_position 653.628052 cc 2019-08-15T22:25:07.582Z,1565907907.582 [Reporter](INFO): platform_buoyancy_position 647.047302 cc 2019-08-15T22:25:07.959Z,1565907907.959 [Reporter](INFO): platform_buoyancy_position 642.884033 cc 2019-08-15T22:25:08.379Z,1565907908.379 [Reporter](INFO): platform_buoyancy_position 636.706238 cc 2019-08-15T22:25:08.775Z,1565907908.775 [Reporter](INFO): platform_buoyancy_position 632.005737 cc 2019-08-15T22:25:09.207Z,1565907909.207 [Reporter](INFO): platform_buoyancy_position 625.559326 cc 2019-08-15T22:25:09.567Z,1565907909.567 [Reporter](INFO): platform_buoyancy_position 621.395996 cc 2019-08-15T22:25:10.007Z,1565907910.007 [Reporter](INFO): platform_buoyancy_position 614.949646 cc 2019-08-15T22:25:10.394Z,1565907910.394 [Reporter](INFO): platform_buoyancy_position 610.517700 cc 2019-08-15T22:25:10.814Z,1565907910.814 [Reporter](INFO): platform_buoyancy_position 606.085815 cc 2019-08-15T22:25:11.187Z,1565907911.187 [Reporter](INFO): platform_buoyancy_position 599.773743 cc 2019-08-15T22:25:11.639Z,1565907911.639 [Reporter](INFO): platform_buoyancy_position 593.595947 cc 2019-08-15T22:25:11.999Z,1565907911.999 [Reporter](INFO): platform_buoyancy_position 589.164001 cc 2019-08-15T22:25:12.451Z,1565907912.451 [Reporter](INFO): platform_buoyancy_position 584.597839 cc 2019-08-15T22:25:12.803Z,1565907912.803 [Reporter](INFO): platform_buoyancy_position 578.420044 cc 2019-08-15T22:25:13.223Z,1565907913.223 [Reporter](INFO): platform_buoyancy_position 573.988098 cc 2019-08-15T22:25:13.630Z,1565907913.630 [Reporter](INFO): platform_buoyancy_position 567.676025 cc 2019-08-15T22:25:14.042Z,1565907914.042 [Reporter](INFO): platform_buoyancy_position 563.244141 cc 2019-08-15T22:25:14.419Z,1565907914.419 [Reporter](INFO): platform_buoyancy_position 556.932007 cc 2019-08-15T22:25:14.875Z,1565907914.875 [Reporter](INFO): platform_buoyancy_position 551.559998 cc 2019-08-15T22:25:15.227Z,1565907915.227 [Reporter](INFO): platform_buoyancy_position 546.322327 cc 2019-08-15T22:25:15.671Z,1565907915.671 [Reporter](INFO): platform_buoyancy_position 541.756104 cc 2019-08-15T22:25:16.035Z,1565907916.035 [Reporter](INFO): platform_buoyancy_position 536.249817 cc 2019-08-15T22:25:16.467Z,1565907916.467 [Reporter](INFO): platform_buoyancy_position 531.012146 cc 2019-08-15T22:25:16.858Z,1565907916.858 [Reporter](INFO): platform_buoyancy_position 525.102905 cc 2019-08-15T22:25:17.274Z,1565907917.274 [Reporter](INFO): platform_buoyancy_position 520.402405 cc 2019-08-15T22:25:17.651Z,1565907917.651 [Reporter](INFO): platform_buoyancy_position 513.955994 cc 2019-08-15T22:25:18.107Z,1565907918.107 [Reporter](INFO): platform_buoyancy_position 509.524139 cc 2019-08-15T22:26:43.463Z,1565908003.463 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 300.000000 cubic_centimeter 2019-08-15T22:26:44.583Z,1565908004.583 [Reporter](INFO): platform_buoyancy_position 503.077759 cc 2019-08-15T22:26:45.719Z,1565908005.719 [Reporter](INFO): platform_buoyancy_position 501.197540 cc 2019-08-15T22:26:46.163Z,1565908006.163 [Reporter](INFO): platform_buoyancy_position 496.765656 cc 2019-08-15T22:26:46.546Z,1565908006.546 [Reporter](INFO): platform_buoyancy_position 487.901825 cc 2019-08-15T22:26:46.967Z,1565908006.967 [Reporter](INFO): platform_buoyancy_position 481.724030 cc 2019-08-15T22:26:47.343Z,1565908007.343 [Reporter](INFO): platform_buoyancy_position 477.426422 cc 2019-08-15T22:26:47.771Z,1565908007.771 [Reporter](INFO): platform_buoyancy_position 473.128876 cc 2019-08-15T22:26:48.167Z,1565908008.167 [Reporter](INFO): platform_buoyancy_position 466.682465 cc 2019-08-15T22:26:48.583Z,1565908008.583 [Reporter](INFO): platform_buoyancy_position 462.250519 cc 2019-08-15T22:26:48.959Z,1565908008.959 [Reporter](INFO): platform_buoyancy_position 458.087250 cc 2019-08-15T22:26:49.371Z,1565908009.371 [Reporter](INFO): platform_buoyancy_position 451.775116 cc 2019-08-15T22:26:49.787Z,1565908009.787 [Reporter](INFO): platform_buoyancy_position 447.343231 cc 2019-08-15T22:26:50.202Z,1565908010.202 [Reporter](INFO): platform_buoyancy_position 442.777069 cc 2019-08-15T22:26:50.574Z,1565908010.574 [Reporter](INFO): platform_buoyancy_position 436.464935 cc 2019-08-15T22:26:51.027Z,1565908011.027 [Reporter](INFO): platform_buoyancy_position 430.421417 cc 2019-08-15T22:26:51.395Z,1565908011.395 [Reporter](INFO): platform_buoyancy_position 425.720917 cc 2019-08-15T22:26:51.866Z,1565908011.866 [Reporter](INFO): platform_buoyancy_position 419.274567 cc 2019-08-15T22:26:52.183Z,1565908012.183 [Reporter](INFO): platform_buoyancy_position 415.111237 cc 2019-08-15T22:26:52.675Z,1565908012.675 [Reporter](INFO): platform_buoyancy_position 408.530548 cc 2019-08-15T22:26:53.091Z,1565908013.091 [Reporter](INFO): platform_buoyancy_position 403.427155 cc 2019-08-15T22:26:53.507Z,1565908013.507 [Reporter](INFO): platform_buoyancy_position 397.786530 cc 2019-08-15T22:26:53.879Z,1565908013.879 [Reporter](INFO): platform_buoyancy_position 393.354645 cc 2019-08-15T22:26:54.351Z,1565908014.351 [Reporter](INFO): platform_buoyancy_position 385.296631 cc 2019-08-15T22:26:54.712Z,1565908014.712 [Reporter](INFO): platform_buoyancy_position 382.073425 cc 2019-08-15T22:26:55.236Z,1565908015.236 [Reporter](INFO): platform_buoyancy_position 374.149750 cc 2019-08-15T22:26:55.527Z,1565908015.527 [Reporter](INFO): platform_buoyancy_position 370.255035 cc 2019-08-15T22:26:55.943Z,1565908015.943 [Reporter](INFO): platform_buoyancy_position 365.420227 cc 2019-08-15T22:26:56.283Z,1565908016.283 [Reporter](INFO): platform_buoyancy_position 359.376740 cc 2019-08-15T22:26:56.731Z,1565908016.731 [Reporter](INFO): platform_buoyancy_position 354.273346 cc 2019-08-15T22:26:57.083Z,1565908017.083 [Reporter](INFO): platform_buoyancy_position 350.512939 cc 2019-08-15T22:26:57.591Z,1565908017.591 [Reporter](INFO): platform_buoyancy_position 341.917725 cc 2019-08-15T22:26:57.875Z,1565908017.875 [Reporter](INFO): platform_buoyancy_position 339.768951 cc 2019-08-15T22:26:58.318Z,1565908018.318 [Reporter](INFO): platform_buoyancy_position 334.396942 cc 2019-08-15T22:26:58.659Z,1565908018.659 [Reporter](INFO): platform_buoyancy_position 329.159241 cc 2019-08-15T22:26:59.199Z,1565908019.199 [Reporter](INFO): platform_buoyancy_position 322.578522 cc 2019-08-15T22:26:59.491Z,1565908019.491 [Reporter](INFO): platform_buoyancy_position 318.549530 cc 2019-08-15T22:26:59.931Z,1565908019.931 [Reporter](INFO): platform_buoyancy_position 313.983337 cc 2019-08-15T22:27:00.339Z,1565908020.339 [Reporter](INFO): platform_buoyancy_position 309.685730 cc 2019-08-15T22:27:18.719Z,1565908038.719 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 150.000000 cubic_centimeter 2019-08-15T22:27:19.695Z,1565908039.695 [Reporter](INFO): platform_buoyancy_position 301.224823 cc 2019-08-15T22:27:20.079Z,1565908040.079 [Reporter](INFO): platform_buoyancy_position 302.702148 cc 2019-08-15T22:27:20.463Z,1565908040.463 [Reporter](INFO): platform_buoyancy_position 301.090546 cc 2019-08-15T22:27:20.902Z,1565908040.902 [Reporter](INFO): platform_buoyancy_position 299.076019 cc 2019-08-15T22:27:21.275Z,1565908041.275 [Reporter](INFO): platform_buoyancy_position 294.778442 cc 2019-08-15T22:27:21.711Z,1565908041.711 [Reporter](INFO): platform_buoyancy_position 286.183228 cc 2019-08-15T22:27:22.111Z,1565908042.111 [Reporter](INFO): platform_buoyancy_position 281.751343 cc 2019-08-15T22:27:22.543Z,1565908042.543 [Reporter](INFO): platform_buoyancy_position 275.439240 cc 2019-08-15T22:27:22.923Z,1565908042.923 [Reporter](INFO): platform_buoyancy_position 271.007324 cc 2019-08-15T22:27:23.387Z,1565908043.387 [Reporter](INFO): platform_buoyancy_position 264.560944 cc 2019-08-15T22:27:23.717Z,1565908043.717 [Reporter](INFO): platform_buoyancy_position 260.397644 cc 2019-08-15T22:27:24.167Z,1565908044.167 [Reporter](INFO): platform_buoyancy_position 253.816925 cc 2019-08-15T22:27:24.515Z,1565908044.515 [Reporter](INFO): platform_buoyancy_position 249.519302 cc 2019-08-15T22:27:24.974Z,1565908044.974 [Reporter](INFO): platform_buoyancy_position 243.744415 cc 2019-08-15T22:27:25.339Z,1565908045.339 [Reporter](INFO): platform_buoyancy_position 238.775330 cc 2019-08-15T22:27:25.751Z,1565908045.751 [Reporter](INFO): platform_buoyancy_position 232.463226 cc 2019-08-15T22:27:26.127Z,1565908046.127 [Reporter](INFO): platform_buoyancy_position 228.031342 cc 2019-08-15T22:27:26.543Z,1565908046.543 [Reporter](INFO): platform_buoyancy_position 223.733734 cc 2019-08-15T22:27:26.931Z,1565908046.931 [Reporter](INFO): platform_buoyancy_position 218.093140 cc 2019-08-15T22:27:27.347Z,1565908047.347 [Reporter](INFO): platform_buoyancy_position 212.989716 cc 2019-08-15T22:27:27.803Z,1565908047.803 [Reporter](INFO): platform_buoyancy_position 206.409027 cc 2019-08-15T22:27:28.183Z,1565908048.183 [Reporter](INFO): platform_buoyancy_position 200.365540 cc 2019-08-15T22:27:28.562Z,1565908048.562 [Reporter](INFO): platform_buoyancy_position 196.470825 cc 2019-08-15T22:27:28.982Z,1565908048.982 [Reporter](INFO): platform_buoyancy_position 190.427338 cc 2019-08-15T22:27:29.359Z,1565908049.359 [Reporter](INFO): platform_buoyancy_position 185.055328 cc 2019-08-15T22:27:29.783Z,1565908049.783 [Reporter](INFO): platform_buoyancy_position 179.549011 cc 2019-08-15T22:27:30.171Z,1565908050.171 [Reporter](INFO): platform_buoyancy_position 174.445618 cc 2019-08-15T22:27:30.638Z,1565908050.638 [Reporter](INFO): platform_buoyancy_position 167.999237 cc 2019-08-15T22:27:30.978Z,1565908050.978 [Reporter](INFO): platform_buoyancy_position 164.910309 cc 2019-08-15T22:27:31.431Z,1565908051.431 [Reporter](INFO): platform_buoyancy_position 157.120911 cc 2019-08-15T22:28:21.369Z,1565908101.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-08-15T22:28:52.073Z,1565908132.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-08-15T22:29:14.004Z,1565908154.004 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-08-15T22:29:14.004Z,1565908154.004 [Default:CheckIn:C.Wait] Stopped 2019-08-15T22:29:14.004Z,1565908154.004 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-08-15T22:29:14.004Z,1565908154.004 [Default:CheckIn:D] Running Loop=1 2019-08-15T22:29:14.356Z,1565908154.356 [Default:CheckIn:D] Stopped 2019-08-15T22:29:14.356Z,1565908154.356 [Default:CheckIn:E] Running Loop=1 2019-08-15T22:29:14.775Z,1565908154.775 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.635593 min 2019-08-15T22:29:14.775Z,1565908154.775 [Default:CheckIn:E] Stopped 2019-08-15T22:29:14.775Z,1565908154.775 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-08-15T22:29:14.775Z,1565908154.775 [Default:CheckIn] Stopped 2019-08-15T22:29:14.775Z,1565908154.775 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-08-15T22:29:14.775Z,1565908154.775 [Default:CheckIn](INFO): Running loop #2 2019-08-15T22:29:14.775Z,1565908154.775 [Default:CheckIn] Running Loop=2 2019-08-15T22:29:14.776Z,1565908154.776 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-08-15T22:29:14.776Z,1565908154.776 [Default:CheckIn:Read_GPS] Running Loop=1 2019-08-15T22:29:16.377Z,1565908156.377 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:29:22.777Z,1565908162.777 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-08-15T22:29:53.123Z,1565908193.123 [NAL9602](INFO): SBD MO Status=2, MOMSN=31133, MT Status=2, MTMSN=0 2019-08-15T22:29:53.123Z,1565908193.123 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-08-15T22:30:23.015Z,1565908223.015 [NAL9602](INFO): SBD MO Status=2, MOMSN=31133, MT Status=2, MTMSN=0 2019-08-15T22:30:23.016Z,1565908223.016 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-08-15T22:30:26.290Z,1565908226.290 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 15.000000 2019-08-15T22:30:26.290Z,1565908226.290 [CommandLine](FAULT): Incomplete syntax. Try: help maintain 2019-08-15T22:30:29.825Z,1565908229.825 [CommandLine](IMPORTANT): got command maintain clear 2019-08-15T22:30:29.969Z,1565908229.969 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-08-15T22:30:31.199Z,1565908231.199 [Reporter](INFO): platform_buoyancy_position 149.465820 cc 2019-08-15T22:30:31.583Z,1565908231.583 [Reporter](INFO): platform_buoyancy_position 150.674530 cc 2019-08-15T22:30:32.378Z,1565908232.378 [Reporter](INFO): platform_buoyancy_position 150.808807 cc 2019-08-15T22:30:32.783Z,1565908232.783 [Reporter](INFO): platform_buoyancy_position 152.823303 cc 2019-08-15T22:30:33.211Z,1565908233.211 [Reporter](INFO): platform_buoyancy_position 159.269714 cc 2019-08-15T22:30:33.599Z,1565908233.599 [Reporter](INFO): platform_buoyancy_position 165.716125 cc 2019-08-15T22:30:34.042Z,1565908234.042 [Reporter](INFO): platform_buoyancy_position 172.162537 cc 2019-08-15T22:30:34.391Z,1565908234.391 [Reporter](INFO): platform_buoyancy_position 176.594421 cc 2019-08-15T22:30:34.811Z,1565908234.811 [Reporter](INFO): platform_buoyancy_position 182.906525 cc 2019-08-15T22:30:35.195Z,1565908235.195 [Reporter](INFO): platform_buoyancy_position 189.487213 cc 2019-08-15T22:30:35.611Z,1565908235.611 [Reporter](INFO): platform_buoyancy_position 193.650513 cc 2019-08-15T22:30:36.011Z,1565908236.011 [Reporter](INFO): platform_buoyancy_position 199.962616 cc 2019-08-15T22:30:36.455Z,1565908236.455 [Reporter](INFO): platform_buoyancy_position 204.528839 cc 2019-08-15T22:30:36.823Z,1565908236.823 [Reporter](INFO): platform_buoyancy_position 210.706635 cc 2019-08-15T22:30:37.255Z,1565908237.255 [Reporter](INFO): platform_buoyancy_position 215.272827 cc 2019-08-15T22:30:37.619Z,1565908237.619 [Reporter](INFO): platform_buoyancy_position 221.719238 cc 2019-08-15T22:30:38.039Z,1565908238.039 [Reporter](INFO): platform_buoyancy_position 226.016815 cc 2019-08-15T22:30:38.427Z,1565908238.427 [Reporter](INFO): platform_buoyancy_position 230.448730 cc 2019-08-15T22:30:38.847Z,1565908238.847 [Reporter](INFO): platform_buoyancy_position 236.760834 cc 2019-08-15T22:30:39.247Z,1565908239.247 [Reporter](INFO): platform_buoyancy_position 242.132843 cc 2019-08-15T22:30:39.614Z,1565908239.614 [NAL9602](INFO): SBD MO Status=2, MOMSN=31133, MT Status=2, MTMSN=0 2019-08-15T22:30:39.615Z,1565908239.615 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-08-15T22:30:39.675Z,1565908239.675 [Reporter](INFO): platform_buoyancy_position 247.370544 cc 2019-08-15T22:30:40.071Z,1565908240.071 [Reporter](INFO): platform_buoyancy_position 253.951233 cc 2019-08-15T22:30:40.475Z,1565908240.475 [Reporter](INFO): platform_buoyancy_position 258.114532 cc 2019-08-15T22:30:40.866Z,1565908240.866 [Reporter](INFO): platform_buoyancy_position 262.680725 cc 2019-08-15T22:30:41.279Z,1565908241.279 [Reporter](INFO): platform_buoyancy_position 268.858521 cc 2019-08-15T22:30:41.662Z,1565908241.662 [Reporter](INFO): platform_buoyancy_position 273.559021 cc 2019-08-15T22:30:42.075Z,1565908242.075 [Reporter](INFO): platform_buoyancy_position 279.468231 cc 2019-08-15T22:30:42.495Z,1565908242.495 [Reporter](INFO): platform_buoyancy_position 283.900146 cc 2019-08-15T22:30:42.899Z,1565908242.899 [Reporter](INFO): platform_buoyancy_position 290.346527 cc 2019-08-15T22:30:43.291Z,1565908243.291 [Reporter](INFO): platform_buoyancy_position 294.778442 cc 2019-08-15T22:30:43.727Z,1565908243.727 [Reporter](INFO): platform_buoyancy_position 301.224823 cc 2019-08-15T22:30:44.115Z,1565908244.115 [Reporter](INFO): platform_buoyancy_position 305.388123 cc 2019-08-15T22:30:44.558Z,1565908244.558 [Reporter](INFO): platform_buoyancy_position 311.834534 cc 2019-08-15T22:30:44.950Z,1565908244.950 [Reporter](INFO): platform_buoyancy_position 316.266449 cc 2019-08-15T22:30:45.375Z,1565908245.375 [Reporter](INFO): platform_buoyancy_position 322.444244 cc 2019-08-15T22:30:45.699Z,1565908245.699 [Reporter](INFO): platform_buoyancy_position 325.801727 cc 2019-08-15T22:30:46.127Z,1565908246.127 [Reporter](INFO): platform_buoyancy_position 331.308044 cc 2019-08-15T22:30:46.519Z,1565908246.519 [Reporter](INFO): platform_buoyancy_position 337.620148 cc 2019-08-15T22:30:46.951Z,1565908246.951 [Reporter](INFO): platform_buoyancy_position 344.066528 cc 2019-08-15T22:30:47.322Z,1565908247.322 [Reporter](INFO): platform_buoyancy_position 348.632751 cc 2019-08-15T22:30:47.755Z,1565908247.755 [Reporter](INFO): platform_buoyancy_position 354.407623 cc 2019-08-15T22:30:48.143Z,1565908248.143 [Reporter](INFO): platform_buoyancy_position 358.973846 cc 2019-08-15T22:30:48.559Z,1565908248.559 [Reporter](INFO): platform_buoyancy_position 363.540039 cc 2019-08-15T22:30:48.935Z,1565908248.935 [Reporter](INFO): platform_buoyancy_position 369.852142 cc 2019-08-15T22:30:49.406Z,1565908249.406 [Reporter](INFO): platform_buoyancy_position 374.284027 cc 2019-08-15T22:30:49.759Z,1565908249.759 [Reporter](INFO): platform_buoyancy_position 378.447327 cc 2019-08-15T22:30:50.199Z,1565908250.199 [Reporter](INFO): platform_buoyancy_position 385.028046 cc 2019-08-15T22:30:50.579Z,1565908250.579 [Reporter](INFO): platform_buoyancy_position 391.340149 cc 2019-08-15T22:30:50.975Z,1565908250.975 [Reporter](INFO): platform_buoyancy_position 395.637726 cc 2019-08-15T22:30:51.379Z,1565908251.379 [Reporter](INFO): platform_buoyancy_position 402.218445 cc 2019-08-15T22:30:51.810Z,1565908251.810 [Reporter](INFO): platform_buoyancy_position 406.650330 cc 2019-08-15T22:30:52.267Z,1565908252.267 [Reporter](INFO): platform_buoyancy_position 412.828156 cc 2019-08-15T22:30:52.692Z,1565908252.692 [Reporter](INFO): platform_buoyancy_position 417.260040 cc 2019-08-15T22:30:53.023Z,1565908253.023 [Reporter](INFO): platform_buoyancy_position 423.572113 cc 2019-08-15T22:30:53.435Z,1565908253.435 [Reporter](INFO): platform_buoyancy_position 428.004059 cc 2019-08-15T22:30:53.843Z,1565908253.843 [Reporter](INFO): platform_buoyancy_position 432.973114 cc 2019-08-15T22:30:54.302Z,1565908254.302 [Reporter](INFO): platform_buoyancy_position 439.150970 cc 2019-08-15T22:30:54.625Z,1565908254.625 [Reporter](INFO): platform_buoyancy_position 443.314240 cc 2019-08-15T22:30:55.131Z,1565908255.131 [Reporter](INFO): platform_buoyancy_position 449.357758 cc 2019-08-15T22:30:55.479Z,1565908255.479 [Reporter](INFO): platform_buoyancy_position 453.789642 cc 2019-08-15T22:30:55.931Z,1565908255.931 [Reporter](INFO): platform_buoyancy_position 460.101715 cc 2019-08-15T22:30:56.247Z,1565908256.247 [Reporter](INFO): platform_buoyancy_position 464.399323 cc 2019-08-15T22:30:56.683Z,1565908256.683 [Reporter](INFO): platform_buoyancy_position 470.980072 cc 2019-08-15T22:30:57.059Z,1565908257.059 [Reporter](INFO): platform_buoyancy_position 477.292145 cc 2019-08-15T22:30:57.515Z,1565908257.515 [Reporter](INFO): platform_buoyancy_position 481.724030 cc 2019-08-15T22:30:57.859Z,1565908257.859 [Reporter](INFO): platform_buoyancy_position 488.036163 cc 2019-08-15T22:30:58.367Z,1565908258.367 [Reporter](INFO): platform_buoyancy_position 494.079620 cc 2019-08-15T22:30:58.686Z,1565908258.686 [Reporter](INFO): platform_buoyancy_position 496.631378 cc 2019-08-15T22:30:59.164Z,1565908259.164 [Reporter](INFO): platform_buoyancy_position 503.077759 cc 2019-08-15T22:30:59.455Z,1565908259.455 [Reporter](INFO): platform_buoyancy_position 507.509674 cc 2019-08-15T22:30:59.918Z,1565908259.918 [Reporter](INFO): platform_buoyancy_position 513.821716 cc 2019-08-15T22:31:00.275Z,1565908260.275 [Reporter](INFO): platform_buoyancy_position 518.119324 cc 2019-08-15T22:31:00.727Z,1565908260.727 [Reporter](INFO): platform_buoyancy_position 524.565735 cc 2019-08-15T22:31:01.059Z,1565908261.059 [Reporter](INFO): platform_buoyancy_position 528.863342 cc 2019-08-15T22:31:01.475Z,1565908261.475 [Reporter](INFO): platform_buoyancy_position 533.295227 cc 2019-08-15T22:31:01.855Z,1565908261.855 [Reporter](INFO): platform_buoyancy_position 538.801514 cc 2019-08-15T22:31:02.279Z,1565908262.279 [Reporter](INFO): platform_buoyancy_position 543.904907 cc 2019-08-15T22:31:02.679Z,1565908262.679 [Reporter](INFO): platform_buoyancy_position 548.605408 cc 2019-08-15T22:31:03.111Z,1565908263.111 [Reporter](INFO): platform_buoyancy_position 554.783203 cc 2019-08-15T22:31:03.487Z,1565908263.487 [Reporter](INFO): platform_buoyancy_position 559.080811 cc 2019-08-15T22:31:03.915Z,1565908263.915 [Reporter](INFO): platform_buoyancy_position 565.392944 cc 2019-08-15T22:31:04.283Z,1565908264.283 [Reporter](INFO): platform_buoyancy_position 569.824829 cc 2019-08-15T22:31:04.707Z,1565908264.707 [Reporter](INFO): platform_buoyancy_position 576.136902 cc 2019-08-15T22:31:05.091Z,1565908265.091 [Reporter](INFO): platform_buoyancy_position 580.568848 cc 2019-08-15T22:31:05.511Z,1565908265.511 [Reporter](INFO): platform_buoyancy_position 586.880920 cc 2019-08-15T22:31:05.846Z,1565908265.846 [NAL9602](INFO): SBD MO Status=0, MOMSN=31133, MT Status=0, MTMSN=0 2019-08-15T22:31:05.846Z,1565908265.846 [NAL9602](INFO): No messages in MT queue 2019-08-15T22:31:05.931Z,1565908265.931 [Reporter](INFO): platform_buoyancy_position 591.312805 cc 2019-08-15T22:31:06.363Z,1565908266.363 [Reporter](INFO): platform_buoyancy_position 597.490601 cc 2019-08-15T22:31:06.661Z,1565908266.661 [NAL9602](DEBUG): Fix Requested 2019-08-15T22:31:06.735Z,1565908266.735 [Reporter](INFO): platform_buoyancy_position 601.922546 cc 2019-08-15T22:31:07.066Z,1565908267.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222843.00,A,4149.69622,N,08324.71095,W,0.117,191.38,150819,,,A*76 2019-08-15T22:31:07.068Z,1565908267.068 [NAL9602](INFO): GPS fix at 20190815T222843: (41.828270, -83.411849) 2019-08-15T22:31:07.119Z,1565908267.119 [Default:CheckIn:Read_GPS] Stopped 2019-08-15T22:31:07.120Z,1565908267.120 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-08-15T22:31:07.167Z,1565908267.167 [Reporter](INFO): platform_buoyancy_position 608.368896 cc 2019-08-15T22:31:07.547Z,1565908267.547 [Reporter](INFO): platform_buoyancy_position 612.666504 cc 2019-08-15T22:31:08.007Z,1565908268.007 [Reporter](INFO): platform_buoyancy_position 619.112915 cc 2019-08-15T22:31:08.379Z,1565908268.379 [Reporter](INFO): platform_buoyancy_position 623.544800 cc 2019-08-15T22:31:08.807Z,1565908268.807 [Reporter](INFO): platform_buoyancy_position 629.991211 cc 2019-08-15T22:31:09.223Z,1565908269.223 [Reporter](INFO): platform_buoyancy_position 634.288818 cc 2019-08-15T22:31:09.643Z,1565908269.643 [Reporter](INFO): platform_buoyancy_position 640.600952 cc 2019-08-15T22:31:10.014Z,1565908270.014 [Reporter](INFO): platform_buoyancy_position 644.898499 cc 2019-08-15T22:31:10.423Z,1565908270.423 [Reporter](INFO): platform_buoyancy_position 651.210632 cc 2019-08-15T22:31:10.807Z,1565908270.807 [Reporter](INFO): platform_buoyancy_position 655.776855 cc 2019-08-15T22:31:11.258Z,1565908271.258 [Reporter](INFO): platform_buoyancy_position 661.417419 cc 2019-08-15T22:31:11.682Z,1565908271.682 [Reporter](INFO): platform_buoyancy_position 666.520813 cc 2019-08-15T22:31:12.067Z,1565908272.067 [Reporter](INFO): platform_buoyancy_position 670.952759 cc 2019-08-15T22:31:12.447Z,1565908272.447 [Reporter](INFO): platform_buoyancy_position 676.996216 cc 2019-08-15T22:31:12.863Z,1565908272.863 [Reporter](INFO): platform_buoyancy_position 681.428101 cc 2019-08-15T22:31:13.230Z,1565908273.230 [Reporter](INFO): platform_buoyancy_position 686.128601 cc 2019-08-15T22:31:13.440Z,1565908273.440 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190815T215928/Courier0019.lzma 2019-08-15T22:31:13.650Z,1565908273.650 [Reporter](INFO): platform_buoyancy_position 692.306458 cc 2019-08-15T22:31:14.027Z,1565908274.027 [Reporter](INFO): platform_buoyancy_position 696.738342 cc 2019-08-15T22:31:14.246Z,1565908274.246 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Courier0019.lzma.bak 2019-08-15T22:31:14.246Z,1565908274.246 [DataOverHttps](INFO): SBD MOMSN=11579529 2019-08-15T22:31:14.443Z,1565908274.443 [Reporter](INFO): platform_buoyancy_position 703.050415 cc 2019-08-15T22:31:14.863Z,1565908274.863 [Reporter](INFO): platform_buoyancy_position 708.422424 cc 2019-08-15T22:31:15.267Z,1565908275.267 [Reporter](INFO): platform_buoyancy_position 713.660156 cc 2019-08-15T22:31:15.659Z,1565908275.659 [Reporter](INFO): platform_buoyancy_position 718.092041 cc 2019-08-15T22:31:16.123Z,1565908276.123 [Reporter](INFO): platform_buoyancy_position 724.538452 cc 2019-08-15T22:31:16.463Z,1565908276.463 [Reporter](INFO): platform_buoyancy_position 728.701721 cc 2019-08-15T22:31:16.793Z,1565908276.793 [DVL_micro](ERROR): only read 42 of 46 data items 2019-08-15T22:31:16.794Z,1565908276.794 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 1 1 1 1 0.1 3.2 0.2 2.9 314.1 -63.1 1846.5 3.0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 7.66 -1.97 346.5 0.1 26.5 0.005 9 110 2019-08-15T22:31:16.903Z,1565908276.903 [Reporter](INFO): platform_buoyancy_position 733.133606 cc 2019-08-15T22:31:17.291Z,1565908277.291 [Reporter](INFO): platform_buoyancy_position 739.445740 cc 2019-08-15T22:31:17.794Z,1565908277.794 [Reporter](INFO): platform_buoyancy_position 746.026428 cc 2019-08-15T22:31:18.083Z,1565908278.083 [Reporter](INFO): platform_buoyancy_position 750.324036 cc 2019-08-15T22:31:18.511Z,1565908278.511 [Reporter](INFO): platform_buoyancy_position 754.890259 cc 2019-08-15T22:31:18.887Z,1565908278.887 [Reporter](INFO): platform_buoyancy_position 760.933716 cc 2019-08-15T22:31:19.339Z,1565908279.339 [Reporter](INFO): platform_buoyancy_position 765.902832 cc 2019-08-15T22:31:19.711Z,1565908279.711 [Reporter](INFO): platform_buoyancy_position 771.677734 cc 2019-08-15T22:31:20.155Z,1565908280.155 [Reporter](INFO): platform_buoyancy_position 776.512512 cc 2019-08-15T22:31:20.498Z,1565908280.498 [Reporter](INFO): platform_buoyancy_position 780.407227 cc 2019-08-15T22:31:20.919Z,1565908280.919 [Reporter](INFO): platform_buoyancy_position 786.719360 cc 2019-08-15T22:31:21.307Z,1565908281.307 [Reporter](INFO): platform_buoyancy_position 793.165710 cc 2019-08-15T22:31:21.723Z,1565908281.723 [Reporter](INFO): platform_buoyancy_position 797.597656 cc 2019-08-15T22:31:22.114Z,1565908282.114 [Reporter](INFO): platform_buoyancy_position 802.029541 cc 2019-08-15T22:31:22.574Z,1565908282.574 [Reporter](INFO): platform_buoyancy_position 808.341614 cc 2019-08-15T22:31:22.951Z,1565908282.951 [Reporter](INFO): platform_buoyancy_position 812.639221 cc 2019-08-15T22:31:23.379Z,1565908283.379 [Reporter](INFO): platform_buoyancy_position 818.951355 cc 2019-08-15T22:31:23.719Z,1565908283.719 [Reporter](INFO): platform_buoyancy_position 823.383240 cc 2019-08-15T22:31:24.183Z,1565908284.183 [Reporter](INFO): platform_buoyancy_position 829.829651 cc 2019-08-15T22:31:24.543Z,1565908284.543 [Reporter](INFO): platform_buoyancy_position 834.127258 cc 2019-08-15T22:31:24.988Z,1565908284.988 [Reporter](INFO): platform_buoyancy_position 840.573669 cc 2019-08-15T22:31:25.347Z,1565908285.347 [Reporter](INFO): platform_buoyancy_position 844.736938 cc 2019-08-15T22:31:25.787Z,1565908285.787 [Reporter](INFO): platform_buoyancy_position 851.183350 cc 2019-08-15T22:31:26.163Z,1565908286.163 [Reporter](INFO): platform_buoyancy_position 855.480957 cc 2019-08-15T22:31:26.611Z,1565908286.611 [Reporter](INFO): platform_buoyancy_position 861.927368 cc 2019-08-15T22:31:26.959Z,1565908286.959 [Reporter](INFO): platform_buoyancy_position 866.224915 cc 2019-08-15T22:31:27.225Z,1565908287.225 [DataOverHttps](INFO): Sending 386 bytes from file Logs/20190815T215928/Express0020.lzma 2019-08-15T22:31:27.371Z,1565908287.371 [Reporter](INFO): platform_buoyancy_position 870.522522 cc 2019-08-15T22:31:27.759Z,1565908287.759 [Reporter](INFO): platform_buoyancy_position 877.103271 cc 2019-08-15T22:31:28.030Z,1565908288.030 [DataOverHttps](INFO): Moved sent file to Logs/20190815T215928/Express0020.lzma.bak 2019-08-15T22:31:28.031Z,1565908288.031 [DataOverHttps](INFO): SBD MOMSN=11579532 2019-08-15T22:31:28.222Z,1565908288.222 [Reporter](INFO): platform_buoyancy_position 881.400818 cc 2019-08-15T22:31:28.583Z,1565908288.583 [Reporter](INFO): platform_buoyancy_position 887.712952 cc 2019-08-15T22:31:28.980Z,1565908288.980 [Default:CheckIn:Read_Iridium] Stopped 2019-08-15T22:31:28.980Z,1565908288.980 [Default:CheckIn:C.Wait] Running Loop=1 2019-08-15T22:31:28.985Z,1565908288.985 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-08-15T22:31:29.035Z,1565908289.035 [Reporter](INFO): platform_buoyancy_position 892.547729 cc 2019-08-15T22:31:29.387Z,1565908289.386 [Reporter](INFO): platform_buoyancy_position 898.456970 cc 2019-08-15T22:31:29.874Z,1565908289.874 [Reporter](INFO): platform_buoyancy_position 904.903320 cc 2019-08-15T22:31:30.199Z,1565908290.199 [Reporter](INFO): platform_buoyancy_position 909.200928 cc 2019-08-15T22:31:30.687Z,1565908290.687 [Reporter](INFO): platform_buoyancy_position 915.647339 cc 2019-08-15T22:31:31.024Z,1565908291.024 [Reporter](INFO): platform_buoyancy_position 919.944946 cc 2019-08-15T22:31:31.442Z,1565908291.442 [Reporter](INFO): platform_buoyancy_position 924.376831 cc 2019-08-15T22:31:31.831Z,1565908291.831 [Reporter](INFO): platform_buoyancy_position 930.688965 cc 2019-08-15T22:31:32.282Z,1565908292.282 [Reporter](INFO): platform_buoyancy_position 935.389404 cc 2019-08-15T22:31:32.631Z,1565908292.631 [Reporter](INFO): platform_buoyancy_position 941.432983 cc 2019-08-15T22:31:33.131Z,1565908293.131 [Reporter](INFO): platform_buoyancy_position 945.864868 cc 2019-08-15T22:31:34.914Z,1565908294.914 [DataOverHttps](IMPORTANT): SBD MTMSN=20190815T222910 2019-08-15T22:31:39.816Z,1565908299.816 [NAL9602](INFO): Not Powering down - fast GPS 2019-08-15T22:31:40.827Z,1565908300.827 [DataOverHttps](INFO): Received command:Restart logs