Updated by
Snippets
Updated by
Modified
snippet.markdown- Ignore whitespace
-**Note**: `exit` issued above once 1st sample is completed, to prepare and then (manually again) re-launch `esp` (same command) for the 2nd sample per the tethyscode output captured below.
+**Note**: `exit` issued at the ESP prompt below once 1st sample is completed, to prepare and then (manually again) re-launch `esp` (same command) for the 2nd sample per the tethyscode output captured below.
Updated by
Modified
snippet.markdown- Ignore whitespace
+ ESPComponent.upsync = "rsync -azPq --timeout=60 carueda@bufflehead:'/var/log/carueda/simrapid.* /var/log/carueda/*.spr' /home/carueda/lrauv-application/ESPlogs";
- Port 9998 on tethyscode is where the ESPComponent will listen as a server and wait for the ESP to connect.
-- Alternatively, instead of having the LRAUV running as the server waiting for the ESP, you can set:
+- Created the `ESPlogs` subdir for the rsync command above, as well as added my tethyscode ssh pub key to bufflehead.
+- Alternatively (but much less frequently used), instead of having the LRAUV running as the server waiting for the ESP, you can set:
+**Note**: `exit` issued above once 1st sample is completed, to prepare and then (manually again) re-launch `esp` (same command) for the 2nd sample per the tethyscode output captured below.
+@13:40:16.47 <sampling> Cartridge 58 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
-@11:59:23.75 <sampling> Cartridge 60 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
+@14:00:34.81 <sampling> Cartridge 57 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
-Note: `exit` after 1st sample completed, to prepare and then re-launch for the 2nd sample as signaled from the tethyscode output captured below.
+[carueda@tethyscode lrauv-application]$ ESPCOMPONENT_MANUAL_ESP=1 bin/LRAUV -r -x "load Maintenance/sample.xml;set sample.NumberOfSamples 2 count;set sample.Depth 1 centimeter;set sample.WaitBeforeSample 3 second;set sample:SampleAtDepth.TargetDepth 1 centimeter;run"
-2020-08-12T18:53:24.749Z,1597258404.749 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 106472
-2020-08-12T18:53:24.749Z,1597258404.749 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 106473
-2020-08-12T18:53:24.750Z,1597258404.750 [logger ThreadHandler](INFO): Protected caller Thread ID is 106474
-2020-08-12T18:53:24.750Z,1597258404.750 [Supervisor](INFO): Looking for Config files in directory: Config/
-2020-08-12T18:53:24.750Z,1597258404.750 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
-2020-08-12T18:53:24.754Z,1597258404.754 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
-2020-08-12T18:53:24.759Z,1597258404.759 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
-2020-08-12T18:53:24.764Z,1597258404.764 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
-tethyscode>2020-08-12T18:53:24.770Z,1597258404.770 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
-2020-08-12T18:53:24.773Z,1597258404.773 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
-2020-08-12T18:53:24.776Z,1597258404.776 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
-2020-08-12T18:53:24.780Z,1597258404.780 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
-2020-08-12T18:53:24.785Z,1597258404.785 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
-2020-08-12T18:53:24.788Z,1597258404.788 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
-2020-08-12T18:53:24.791Z,1597258404.791 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
-2020-08-12T18:53:24.795Z,1597258404.795 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
-2020-08-12T18:53:24.802Z,1597258404.802 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
-2020-08-12T18:53:24.805Z,1597258404.805 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
-2020-08-12T18:53:24.812Z,1597258404.812 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
-2020-08-12T18:53:24.816Z,1597258404.816 [Supervisor](INFO): Looking for Config files in directory: Config/tethyscode/
-2020-08-12T18:53:24.816Z,1597258404.816 [Supervisor](INFO): Looking for Config files in directory: Config/tethyscode/carueda/
-2020-08-12T18:53:24.816Z,1597258404.816 [Supervisor](INFO): Looking for Config files in directory: Config/sim/
-2020-08-12T18:53:24.816Z,1597258404.816 [Supervisor](INFO): Opening Config file at: Config/sim/secure.cfg
-2020-08-12T18:53:24.818Z,1597258404.818 [Supervisor](INFO): Opening Config file at: Config/sim/Science.cfg
-2020-08-12T18:53:24.821Z,1597258404.821 [Supervisor](INFO): Opening Config file at: Config/sim/Simulator.cfg
-2020-08-12T18:53:24.824Z,1597258404.824 [Supervisor](INFO): Opening Config file at: Config/sim/vehicle.cfg
-2020-08-12T18:53:24.827Z,1597258404.827 [Supervisor](INFO): Opening Config file at: Config/sim/Servo.cfg
-2020-08-12T18:53:24.830Z,1597258404.830 [Supervisor](INFO): Opening Config file at: Config/sim/Sensor.cfg
-2020-08-12T18:53:24.833Z,1597258404.833 [Supervisor](INFO): Opening Config file at: Config/sim/Derivation.cfg
-2020-08-12T18:53:24.836Z,1597258404.836 [Supervisor](INFO): Looking for Config files in directory: Config/sim/carueda/
-2020-08-12T18:53:24.836Z,1597258404.836 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/secure.cfg
-2020-08-12T18:53:24.839Z,1597258404.839 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/Science.cfg
-2020-08-12T18:53:24.842Z,1597258404.842 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/vehicle.cfg
-2020-08-12T18:53:24.844Z,1597258404.844 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/Sensor.cfg
-2020-08-12T18:53:24.847Z,1597258404.847 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
-2020-08-12T18:53:24.853Z,1597258404.853 [ESPComponent](ERROR): Could not open device '/dev/loadA6' at ESPComponent.loadControl
-2020-08-12T18:53:24.873Z,1597258404.873 [ESPComponent](ERROR): Could not open device '/dev/loadA7' at ESPComponent.loadControl2
-2020-08-12T18:53:24.895Z,1597258404.895 [ESPComponent](IMPORTANT): ESPCOMPONENT_MANUAL_ESP INDICATED.
-2020-08-12T18:53:24.897Z,1597258404.897 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
-2020-08-12T18:53:24.898Z,1597258404.898 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 106556
-2020-08-12T18:53:24.911Z,1597258404.911 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
-2020-08-12T18:53:24.914Z,1597258404.914 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 106557
-2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
-2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
-2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
-2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
-2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
-2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
-2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
-2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
-2020-08-12T18:53:24.916Z,1597258404.916 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 106558
-2020-08-12T18:53:24.916Z,1597258404.916 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 106559
-2020-08-12T18:53:24.924Z,1597258404.924 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 106560
-2020-08-12T18:53:24.924Z,1597258404.924 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 106566
-2020-08-12T18:53:24.925Z,1597258404.925 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 106568
-2020-08-12T18:53:24.927Z,1597258404.927 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 106565
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 106569
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
-2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
-2020-08-12T18:53:24.930Z,1597258404.930 [CommandLine ThreadHandler](INFO): Handler Thread ID is 106561
-2020-08-12T18:53:24.930Z,1597258404.930 [CommandLine ThreadHandler](INFO): Handler Thread ID is 106563
-2020-08-12T18:53:24.933Z,1597258404.933 [controlThread ThreadHandler](INFO): Handler Thread ID is 106562
-2020-08-12T18:53:24.951Z,1597258404.951 [SBIT](INFO): git hash: f73e8ad95b55d34bdb49d5e5edbbb75e6888861e
-2020-08-12T18:53:24.951Z,1597258404.951 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
+2020-08-12T20:34:22.848Z,1597264462.848 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 106986
+2020-08-12T20:34:22.848Z,1597264462.848 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 106987
+2020-08-12T20:34:22.848Z,1597264462.848 [logger ThreadHandler](INFO): Protected caller Thread ID is 106988
+2020-08-12T20:34:22.848Z,1597264462.848 [Supervisor](INFO): Looking for Config files in directory: Config/
+2020-08-12T20:34:22.849Z,1597264462.849 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
+2020-08-12T20:34:22.853Z,1597264462.853 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
+2020-08-12T20:34:22.858Z,1597264462.858 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
+2020-08-12T20:34:22.864Z,1597264462.864 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
+tethyscode>2020-08-12T20:34:22.869Z,1597264462.869 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
+2020-08-12T20:34:22.873Z,1597264462.873 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
+2020-08-12T20:34:22.876Z,1597264462.876 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
+2020-08-12T20:34:22.880Z,1597264462.880 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
+2020-08-12T20:34:22.884Z,1597264462.884 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
+2020-08-12T20:34:22.887Z,1597264462.887 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
+2020-08-12T20:34:22.890Z,1597264462.890 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
+2020-08-12T20:34:22.894Z,1597264462.894 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
+2020-08-12T20:34:22.901Z,1597264462.901 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
+2020-08-12T20:34:22.904Z,1597264462.904 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
+2020-08-12T20:34:22.911Z,1597264462.911 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
+2020-08-12T20:34:22.915Z,1597264462.915 [Supervisor](INFO): Looking for Config files in directory: Config/tethyscode/
+2020-08-12T20:34:22.915Z,1597264462.915 [Supervisor](INFO): Looking for Config files in directory: Config/tethyscode/carueda/
+2020-08-12T20:34:22.915Z,1597264462.915 [Supervisor](INFO): Looking for Config files in directory: Config/sim/
+2020-08-12T20:34:22.915Z,1597264462.915 [Supervisor](INFO): Opening Config file at: Config/sim/secure.cfg
+2020-08-12T20:34:22.918Z,1597264462.918 [Supervisor](INFO): Opening Config file at: Config/sim/Science.cfg
+2020-08-12T20:34:22.921Z,1597264462.921 [Supervisor](INFO): Opening Config file at: Config/sim/Simulator.cfg
+2020-08-12T20:34:22.923Z,1597264462.923 [Supervisor](INFO): Opening Config file at: Config/sim/vehicle.cfg
+2020-08-12T20:34:22.926Z,1597264462.926 [Supervisor](INFO): Opening Config file at: Config/sim/Servo.cfg
+2020-08-12T20:34:22.929Z,1597264462.929 [Supervisor](INFO): Opening Config file at: Config/sim/Sensor.cfg
+2020-08-12T20:34:22.932Z,1597264462.932 [Supervisor](INFO): Opening Config file at: Config/sim/Derivation.cfg
+2020-08-12T20:34:22.935Z,1597264462.935 [Supervisor](INFO): Looking for Config files in directory: Config/sim/carueda/
+2020-08-12T20:34:22.935Z,1597264462.935 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/secure.cfg
+2020-08-12T20:34:22.937Z,1597264462.937 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/Science.cfg
+2020-08-12T20:34:22.940Z,1597264462.940 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/vehicle.cfg
+2020-08-12T20:34:22.943Z,1597264462.943 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/Sensor.cfg
+2020-08-12T20:34:22.946Z,1597264462.946 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
+2020-08-12T20:34:22.951Z,1597264462.951 [ESPComponent](ERROR): Could not open device '/dev/loadA6' at ESPComponent.loadControl
+2020-08-12T20:34:22.971Z,1597264462.971 [ESPComponent](ERROR): Could not open device '/dev/loadA7' at ESPComponent.loadControl2
+2020-08-12T20:34:22.992Z,1597264462.992 [ESPComponent](IMPORTANT): ESPCOMPONENT_MANUAL_ESP INDICATED.
+2020-08-12T20:34:22.994Z,1597264462.994 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
+2020-08-12T20:34:22.995Z,1597264462.995 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 107070
+2020-08-12T20:34:23.008Z,1597264463.008 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
+2020-08-12T20:34:23.010Z,1597264463.010 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 107071
+2020-08-12T20:34:23.011Z,1597264463.011 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
+2020-08-12T20:34:23.011Z,1597264463.011 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
+2020-08-12T20:34:23.011Z,1597264463.011 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
+2020-08-12T20:34:23.011Z,1597264463.011 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
+2020-08-12T20:34:23.011Z,1597264463.011 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
+2020-08-12T20:34:23.011Z,1597264463.011 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
+2020-08-12T20:34:23.011Z,1597264463.011 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
+2020-08-12T20:34:23.011Z,1597264463.011 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
+2020-08-12T20:34:23.011Z,1597264463.011 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 107072
+2020-08-12T20:34:23.012Z,1597264463.012 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 107073
+2020-08-12T20:34:23.020Z,1597264463.020 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 107074
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 107083
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
+2020-08-12T20:34:23.021Z,1597264463.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
+2020-08-12T20:34:23.024Z,1597264463.024 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 107079
+2020-08-12T20:34:23.024Z,1597264463.024 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 107080
+2020-08-12T20:34:23.024Z,1597264463.024 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 107082
+2020-08-12T20:34:23.025Z,1597264463.025 [controlThread ThreadHandler](INFO): Handler Thread ID is 107076
+2020-08-12T20:34:23.025Z,1597264463.025 [CommandLine ThreadHandler](INFO): Handler Thread ID is 107075
+2020-08-12T20:34:23.032Z,1597264463.032 [SBIT](INFO): git hash: f73e8ad95b55d34bdb49d5e5edbbb75e6888861e
+2020-08-12T20:34:23.032Z,1597264463.032 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
-2020-08-12T18:53:24.951Z,1597258404.951 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
+2020-08-12T20:34:23.032Z,1597264463.032 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
-tethyscode>load Maintenance2020-08-12T18:53:24.975Z,1597258404.975 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml
-2020-08-12T18:53:24.975Z,1597258404.975 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml
-2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min
-2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m
-2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.CartridgeType = -4.000000 count
-2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count
-2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min
-2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 10.000000 s
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 0.000000 bool
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 1.000000 bool
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.ESPCartridgeType = nan count
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.BuoyancyNeutral = 500.000024 cc
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
-2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](ERROR): Slate does not contain CTD_Seabird.bin_mean_sea_water_temperature
-2020-08-12T18:53:24.978Z,1597258404.978 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml
-2020-08-12T18:53:24.981Z,1597258404.981 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
-2020-08-12T18:53:24.982Z,1597258404.982 [MissionManager](INFO): Loading Mission: Missions/Default.xml
-2020-08-12T18:53:24.982Z,1597258404.982 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
+2020-08-12T20:34:23.033Z,1597264463.033 [CommandLine ThreadHandler](INFO): Handler Thread ID is 107077
+2020-08-12T20:34:23.062Z,1597264463.062 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
+2020-08-12T20:34:23.062Z,1597264463.062 [MissionManager](INFO): Loading Mission: Missions/Default.xml
+2020-08-12T20:34:23.062Z,1597264463.062 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
+tethyscode>load Maintenance2020-08-12T20:34:23.072Z,1597264463.072 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml
+2020-08-12T20:34:23.072Z,1597264463.072 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml
+2020-08-12T20:34:23.072Z,1597264463.072 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min
+2020-08-12T20:34:23.072Z,1597264463.072 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m
+2020-08-12T20:34:23.072Z,1597264463.072 [MissionManager](INFO): DefineArg sample.CartridgeType = -4.000000 count
+2020-08-12T20:34:23.072Z,1597264463.072 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count
+2020-08-12T20:34:23.072Z,1597264463.072 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min
+2020-08-12T20:34:23.072Z,1597264463.072 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 10.000000 s
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 0.000000 bool
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 1.000000 bool
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.ESPCartridgeType = nan count
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](INFO): DefineArg sample:SampleAtDepth.BuoyancyNeutral = 500.000024 cc
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
+2020-08-12T20:34:23.073Z,1597264463.073 [MissionManager](ERROR): Slate does not contain CTD_Seabird.bin_mean_sea_water_temperature
+2020-08-12T20:34:23.073Z,1597264463.073 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml
tethyscode>set sample.NumberOfSamples 2 count;set sample.Depth 1 centimeter;set sample.WaitBeforeSample 3 second;set sample:SampleAtDepth.TargetDepth 1 centimeter;run
-2020-08-12T18:53:25.030Z,1597258405.030 [CommandLine](IMPORTANT): got command set sample.NumberOfSamples 2.000000 count
-2020-08-12T18:53:25.030Z,1597258405.030 [CommandLine](IMPORTANT): got command set sample.Depth 1.000000 centimeter
-2020-08-12T18:53:25.030Z,1597258405.030 [CommandLine](IMPORTANT): got command set sample.WaitBeforeSample 3.000000 second
-2020-08-12T18:53:25.030Z,1597258405.030 [CommandLine](IMPORTANT): got command set sample:SampleAtDepth.TargetDepth 1.000000 centimeter
-2020-08-12T18:53:28.925Z,1597258408.925 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.000125
-2020-08-12T18:53:29.925Z,1597258409.925 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
-2020-08-12T18:53:59.399Z,1597258439.399 [NAL9602](INFO): GPS fix at 20200812T185359: (36.803404, -121.822207)
-2020-08-12T18:54:39.011Z,1597258479.011 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
-2020-08-12T18:54:39.411Z,1597258479.411 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
-2020-08-12T18:54:44.212Z,1597258484.212 [NAL9602](INFO): GPS fix at 20200812T185444: (36.803409, -121.822216)
-2020-08-12T18:54:47.941Z,1597258487.941 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20200812T183714/Courier0004.lzma
-2020-08-12T18:54:48.941Z,1597258488.941 [DataOverHttps](INFO): Moved sent file to Logs/20200812T183714/Courier0004.lzma.bak
-2020-08-12T18:54:57.942Z,1597258497.942 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20200812T185324/Courier0000.lzma
-2020-08-12T18:54:58.942Z,1597258498.942 [DataOverHttps](INFO): Moved sent file to Logs/20200812T185324/Courier0000.lzma.bak
-2020-08-12T18:55:07.943Z,1597258507.943 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20200812T083346/Express0005.lzma
-2020-08-12T18:55:08.943Z,1597258508.943 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083346/Express0005.lzma.bak
-2020-08-12T18:55:17.944Z,1597258517.944 [DataOverHttps](INFO): Sending 580 bytes from file Logs/20200812T083353/Express0001.lzma
-2020-08-12T18:55:18.944Z,1597258518.944 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083353/Express0001.lzma.bak
-2020-08-12T18:55:27.945Z,1597258527.945 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200812T083353/Express0005.lzma
-2020-08-12T18:55:28.944Z,1597258528.944 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083353/Express0005.lzma.bak
-2020-08-12T18:55:37.946Z,1597258537.946 [DataOverHttps](INFO): Sending 584 bytes from file Logs/20200812T083428/Express0001.lzma
-2020-08-12T18:55:38.945Z,1597258538.945 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083428/Express0001.lzma.bak
-2020-08-12T18:55:44.225Z,1597258544.225 [Startup:StartupSatComms:B](INFO): Timed out from 2020-08-12T18:54:44.2Z
-2020-08-12T18:55:44.226Z,1597258544.226 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
-2020-08-12T18:55:45.827Z,1597258545.827 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 0.010000 m
-2020-08-12T18:55:47.946Z,1597258547.946 [DataOverHttps](INFO): Sending 646 bytes from file Logs/20200812T083428/Express0005.lzma
-2020-08-12T18:55:48.946Z,1597258548.946 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083428/Express0005.lzma.bak
-2020-08-12T18:57:55.456Z,1597258675.456 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
-2020-08-12T18:57:55.856Z,1597258675.856 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 0.012371 m , settling for 0.050000 min
-2020-08-12T18:57:59.457Z,1597258679.457 [sample:SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
-2020-08-12T18:57:59.857Z,1597258679.857 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Initializing ESPCartridgeSelect.
-2020-08-12T18:58:00.257Z,1597258680.257 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Issuing request for ESP cartridge type: -4.
-2020-08-12T18:58:00.257Z,1597258680.257 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Uninitializing ESPCartridgeSelect.
-2020-08-12T18:58:00.257Z,1597258680.257 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
-2020-08-12T18:58:01.456Z,1597258681.456 [ESPComponent](IMPORTANT): Setting regex for ESP Log summary: 'Selecting Cartridge|Sampled|Error|Underpressure|Overpressure|Fail|Retry|Cmd::|Sampler::'
-2020-08-12T18:58:01.456Z,1597258681.456 [ESPComponent](INFO): compiled regex: "Selecting Cartridge|Sampled|Error|Underpressure|Overpressure|Fail|Retry|Cmd::|Sampler::"
-2020-08-12T18:58:01.856Z,1597258681.856 [ESPComponent](IMPORTANT): powerUpESP IGNORED because manualEsp_
-2020-08-12T18:58:01.856Z,1597258681.856 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=30.0000)
-2020-08-12T18:58:09.458Z,1597258689.458 [ESPComponent](IMPORTANT): [sample #1] Setting cartridge argument to -4
-2020-08-12T18:58:09.458Z,1597258689.458 [ESPComponent](IMPORTANT): [sample #1] ESP sampling sequence starting. Sampling state: S_WAITING_INITIAL_PROMPT
-2020-08-12T18:58:09.859Z,1597258689.859 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](ERROR): data element is not active.
-2020-08-12T18:58:09.859Z,1597258689.859 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](IMPORTANT): ESP sampling at 0.189350 m inwater with nan ug/l chlorophyll fluorescence.
-2020-08-12T18:58:10.658Z,1597258690.658 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PREPARING_SHOW_LOG
-2020-08-12T18:58:11.058Z,1597258691.058 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PREPARING_SHOW_STATUS
-2020-08-12T18:58:12.658Z,1597258692.658 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_LOADING_CARTRIDGE
-2020-08-12T18:58:49.467Z,1597258729.467 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PRIMING (@60,archiveHiBiomass_bac)
-2020-08-12T18:58:51.067Z,1597258731.067 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PRIMING (@5.0)
-2020-08-12T18:58:51.867Z,1597258731.867 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_FILTERING (@5.0)
-2020-08-12T18:59:22.274Z,1597258762.274 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_FILTERING (@0.0)
-2020-08-12T18:59:22.674Z,1597258762.674 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PAUSED (@0.0)
-2020-08-12T18:59:38.277Z,1597258778.277 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PAUSED (@10.0)
-2020-08-12T18:59:38.677Z,1597258778.677 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PROCESSING (@10.0)
-2020-08-12T18:59:41.078Z,1597258781.078 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PROCESSED (@60,archiveHiBiomass_bac)
-2020-08-12T19:03:13.136Z,1597258993.136 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_STOPPING (@60,archiveHiBiomass_bac)
-2020-08-12T19:03:39.941Z,1597259019.941 [ESPComponent](IMPORTANT): Syncing ESP logs with command: rsync -azPq --timeout=60 esp@esp:'/var/log/esp/real /var/log/esp/real.out /var/log/esp/real.slot /var/log/esp/*.spr' /LRAUV/ESPlogs &
-2020-08-12T19:03:40.342Z,1597259020.342 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_STOPPED
-2020-08-12T19:08:40.026Z,1597259320.026 [ESPComponent](IMPORTANT): [sample #1] ESP sampling sequence completed normally.
-2020-08-12T19:08:40.027Z,1597259320.027 [sample:SampleAtDepth:SampleWrapper:SampleESP](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP
-2020-08-12T19:08:40.027Z,1597259320.027 [sample:SampleAtDepth:SampleWrapper](INFO): Completed sample:SampleAtDepth:SampleWrapper
-2020-08-12T19:08:40.027Z,1597259320.027 [sample:SampleAtDepth](INFO): Completed sample:SampleAtDepth
-2020-08-12T19:08:40.426Z,1597259320.426 [ESPComponent](IMPORTANT): [sample #1] ESP log summary report (2 messages):
+2020-08-12T20:34:23.133Z,1597264463.133 [CommandLine](IMPORTANT): got command set sample.NumberOfSamples 2.000000 count
+2020-08-12T20:34:23.133Z,1597264463.133 [CommandLine](IMPORTANT): got command set sample.Depth 1.000000 centimeter
+2020-08-12T20:34:23.134Z,1597264463.134 [CommandLine](IMPORTANT): got command set sample.WaitBeforeSample 3.000000 second
+2020-08-12T20:34:23.134Z,1597264463.134 [CommandLine](IMPORTANT): got command set sample:SampleAtDepth.TargetDepth 1.000000 centimeter
+2020-08-12T20:34:27.025Z,1597264467.025 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.000142
+2020-08-12T20:34:28.025Z,1597264468.025 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
+2020-08-12T20:34:57.473Z,1597264497.473 [NAL9602](INFO): GPS fix at 20200812T203457: (36.803404, -121.822207)
+2020-08-12T20:35:37.096Z,1597264537.096 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
+2020-08-12T20:35:37.496Z,1597264537.496 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
+2020-08-12T20:35:42.296Z,1597264542.296 [NAL9602](INFO): GPS fix at 20200812T203542: (36.803409, -121.822216)
+2020-08-12T20:35:46.037Z,1597264546.037 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20200812T203422/Courier0000.lzma
+2020-08-12T20:35:47.036Z,1597264547.036 [DataOverHttps](INFO): Moved sent file to Logs/20200812T203422/Courier0000.lzma.bak
+2020-08-12T20:35:56.038Z,1597264556.038 [DataOverHttps](INFO): Sending 664 bytes from file Logs/20200812T183714/Express0001.lzma
+2020-08-12T20:35:57.037Z,1597264557.037 [DataOverHttps](INFO): Moved sent file to Logs/20200812T183714/Express0001.lzma.bak
+2020-08-12T20:36:06.038Z,1597264566.038 [DataOverHttps](INFO): Sending 901 bytes from file Logs/20200812T183714/Express0005.lzma
+2020-08-12T20:36:07.038Z,1597264567.038 [DataOverHttps](INFO): Moved sent file to Logs/20200812T183714/Express0005.lzma.bak
+2020-08-12T20:36:16.042Z,1597264576.042 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20200812T183714/Express0008.lzma
+2020-08-12T20:36:17.042Z,1597264577.042 [DataOverHttps](INFO): Moved sent file to Logs/20200812T183714/Express0008.lzma.bak
+2020-08-12T20:36:26.044Z,1597264586.044 [DataOverHttps](INFO): Sending 663 bytes from file Logs/20200812T185324/Express0001.lzma
+2020-08-12T20:36:27.044Z,1597264587.044 [DataOverHttps](INFO): Moved sent file to Logs/20200812T185324/Express0001.lzma.bak
+2020-08-12T20:36:36.045Z,1597264596.045 [DataOverHttps](INFO): Sending 1303 bytes from file Logs/20200812T185324/Express0005.lzma
+2020-08-12T20:36:37.045Z,1597264597.045 [DataOverHttps](INFO): Moved sent file to Logs/20200812T185324/Express0005.lzma.bak
+2020-08-12T20:36:42.311Z,1597264602.311 [Startup:StartupSatComms:B](INFO): Timed out from 2020-08-12T20:35:42.3Z
+2020-08-12T20:36:42.311Z,1597264602.311 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
+2020-08-12T20:36:43.913Z,1597264603.913 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 0.010000 m
+2020-08-12T20:36:46.046Z,1597264606.046 [DataOverHttps](INFO): Sending 125 bytes from file Logs/20200812T185324/Express0008.lzma
+2020-08-12T20:36:47.046Z,1597264607.046 [DataOverHttps](INFO): Moved sent file to Logs/20200812T185324/Express0008.lzma.bak
+2020-08-12T20:38:53.561Z,1597264733.561 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
+2020-08-12T20:38:53.961Z,1597264733.961 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 0.012480 m , settling for 0.050000 min
+2020-08-12T20:38:57.561Z,1597264737.561 [sample:SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
+2020-08-12T20:38:57.961Z,1597264737.961 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Initializing ESPCartridgeSelect.
+2020-08-12T20:38:58.361Z,1597264738.361 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Issuing request for ESP cartridge type: -4.
+2020-08-12T20:38:58.361Z,1597264738.361 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Uninitializing ESPCartridgeSelect.
+2020-08-12T20:38:58.361Z,1597264738.361 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
+2020-08-12T20:38:59.560Z,1597264739.560 [ESPComponent](IMPORTANT): Setting regex for ESP Log summary: 'Selecting Cartridge|Sampled|Error|Underpressure|Overpressure|Fail|Retry|Cmd::|Sampler::'
+2020-08-12T20:38:59.561Z,1597264739.561 [ESPComponent](INFO): compiled regex: "Selecting Cartridge|Sampled|Error|Underpressure|Overpressure|Fail|Retry|Cmd::|Sampler::"
+2020-08-12T20:38:59.960Z,1597264739.960 [ESPComponent](IMPORTANT): powerUpESP IGNORED because manualEsp_
+2020-08-12T20:38:59.960Z,1597264739.960 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=30.0000)
+2020-08-12T20:39:02.362Z,1597264742.362 [ESPComponent](IMPORTANT): [sample #1] Setting cartridge argument to -4
+2020-08-12T20:39:02.362Z,1597264742.362 [ESPComponent](IMPORTANT): [sample #1] ESP sampling sequence starting. Sampling state: S_WAITING_INITIAL_PROMPT
+2020-08-12T20:39:02.762Z,1597264742.762 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](ERROR): data element is not active.
+2020-08-12T20:39:02.762Z,1597264742.762 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](IMPORTANT): ESP sampling at 0.092633 m inwater with nan ug/l chlorophyll fluorescence.
+2020-08-12T20:39:03.561Z,1597264743.561 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PREPARING_SHOW_LOG
+2020-08-12T20:39:03.961Z,1597264743.961 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PREPARING_SHOW_STATUS
+2020-08-12T20:39:05.562Z,1597264745.562 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_LOADING_CARTRIDGE
+2020-08-12T20:39:42.372Z,1597264782.372 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PRIMING (@58,archiveHiBiomass_bac)
+2020-08-12T20:39:43.972Z,1597264783.972 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PRIMING (@5.0)
+2020-08-12T20:39:44.772Z,1597264784.772 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_FILTERING (@5.0)
+2020-08-12T20:40:15.179Z,1597264815.179 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_FILTERING (@0.0)
+2020-08-12T20:40:15.580Z,1597264815.580 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PAUSED (@0.0)
+2020-08-12T20:40:31.183Z,1597264831.183 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PAUSED (@10.0)
+2020-08-12T20:40:31.583Z,1597264831.583 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PROCESSING (@10.0)
+2020-08-12T20:40:33.983Z,1597264833.983 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PROCESSED (@58,archiveHiBiomass_bac)
+2020-08-12T20:44:06.038Z,1597265046.038 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_STOPPING (@58,archiveHiBiomass_bac)
+2020-08-12T20:44:32.844Z,1597265072.844 [ESPComponent](IMPORTANT): Syncing ESP logs with command: rsync -azPq --timeout=60 carueda@bufflehead:'/var/log/carueda/simrapid.* /var/log/carueda/*.spr' /home/carueda/lrauv-application/ESPlogs &
+2020-08-12T20:44:33.246Z,1597265073.246 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_STOPPED
+2020-08-12T20:49:32.914Z,1597265372.914 [ESPComponent](IMPORTANT): [sample #1] ESP sampling sequence completed normally.
+2020-08-12T20:49:32.916Z,1597265372.916 [sample:SampleAtDepth:SampleWrapper:SampleESP](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP
+2020-08-12T20:49:32.916Z,1597265372.916 [sample:SampleAtDepth:SampleWrapper](INFO): Completed sample:SampleAtDepth:SampleWrapper
+2020-08-12T20:49:32.916Z,1597265372.916 [sample:SampleAtDepth](INFO): Completed sample:SampleAtDepth
+2020-08-12T20:49:33.314Z,1597265373.314 [ESPComponent](IMPORTANT): [sample #1] ESP log summary report (2 messages):
-2020-08-12T19:08:40.426Z,1597259320.426 [ESPComponent](IMPORTANT): powerDownESP IGNORED because manualEsp_
-2020-08-12T19:08:40.427Z,1597259320.427 [sample:SampleRepeater:Sample](INFO): Completed sample:SampleRepeater:Sample
-2020-08-12T19:08:40.428Z,1597259320.428 [sample:SampleRepeater](INFO): Completed sample:SampleRepeater
-2020-08-12T19:08:40.827Z,1597259320.827 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 0.010000 m
-2020-08-12T19:18:18.969Z,1597259898.969 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
-2020-08-12T19:18:19.367Z,1597259899.367 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 0.008993 m , settling for 0.050000 min
-2020-08-12T19:18:22.968Z,1597259902.968 [sample:SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
-2020-08-12T19:18:23.368Z,1597259903.368 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Initializing ESPCartridgeSelect.
-2020-08-12T19:18:23.768Z,1597259903.768 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Issuing request for ESP cartridge type: -4.
-2020-08-12T19:18:23.768Z,1597259903.768 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Uninitializing ESPCartridgeSelect.
-2020-08-12T19:18:23.768Z,1597259903.768 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
-2020-08-12T19:18:25.368Z,1597259905.368 [ESPComponent](IMPORTANT): powerUpESP IGNORED because manualEsp_
-2020-08-12T19:18:25.368Z,1597259905.368 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=30.0000)
-2020-08-12T19:18:29.369Z,1597259909.369 [ESPComponent](IMPORTANT): [sample #2] Setting cartridge argument to -4
-2020-08-12T19:18:29.369Z,1597259909.369 [ESPComponent](IMPORTANT): [sample #2] ESP sampling sequence starting. Sampling state: S_WAITING_INITIAL_PROMPT
-2020-08-12T19:18:29.769Z,1597259909.769 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](ERROR): data element is not active.
-2020-08-12T19:18:29.769Z,1597259909.769 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](IMPORTANT): ESP sampling at 0.001501 m inwater with nan ug/l chlorophyll fluorescence.
-2020-08-12T19:18:30.169Z,1597259910.169 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PREPARING_SHOW_LOG
-2020-08-12T19:18:30.569Z,1597259910.569 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PREPARING_SHOW_STATUS
-2020-08-12T19:18:32.569Z,1597259912.569 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_LOADING_CARTRIDGE
-2020-08-12T19:19:09.377Z,1597259949.377 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PRIMING (@59,archiveHiBiomass_bac)
-2020-08-12T19:19:10.977Z,1597259950.977 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PRIMING (@5.0)
-2020-08-12T19:19:11.777Z,1597259951.777 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_FILTERING (@5.0)
-2020-08-12T19:19:42.183Z,1597259982.183 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_FILTERING (@0.0)
-2020-08-12T19:19:42.583Z,1597259982.583 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PAUSED (@0.0)
-2020-08-12T19:19:58.187Z,1597259998.187 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PAUSED (@10.0)
-2020-08-12T19:19:58.587Z,1597259998.587 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PROCESSING (@10.0)
-2020-08-12T19:20:00.987Z,1597260000.987 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PROCESSED (@59,archiveHiBiomass_bac)
-2020-08-12T19:23:33.049Z,1597260213.049 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_STOPPING (@59,archiveHiBiomass_bac)
-2020-08-12T19:23:59.855Z,1597260239.855 [ESPComponent](IMPORTANT): Syncing ESP logs with command: rsync -azPq --timeout=60 esp@esp:'/var/log/esp/real /var/log/esp/real.out /var/log/esp/real.slot /var/log/esp/*.spr' /LRAUV/ESPlogs &
-2020-08-12T19:24:00.255Z,1597260240.255 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_STOPPED
-2020-08-12T19:28:59.919Z,1597260539.919 [ESPComponent](IMPORTANT): [sample #2] ESP sampling sequence completed normally.
-2020-08-12T19:28:59.920Z,1597260539.920 [sample:SampleAtDepth:SampleWrapper:SampleESP](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP
-2020-08-12T19:28:59.920Z,1597260539.920 [sample:SampleAtDepth:SampleWrapper](INFO): Completed sample:SampleAtDepth:SampleWrapper
-2020-08-12T19:28:59.920Z,1597260539.920 [sample:SampleAtDepth](INFO): Completed sample:SampleAtDepth
-2020-08-12T19:29:00.319Z,1597260540.319 [ESPComponent](IMPORTANT): [sample #2] ESP log summary report (2 messages):
+2020-08-12T20:49:33.314Z,1597265373.314 [ESPComponent](IMPORTANT): powerDownESP IGNORED because manualEsp_
+2020-08-12T20:49:33.316Z,1597265373.316 [sample:SampleRepeater:Sample](INFO): Completed sample:SampleRepeater:Sample
+2020-08-12T20:49:33.316Z,1597265373.316 [sample:SampleRepeater](INFO): Completed sample:SampleRepeater
+2020-08-12T20:49:33.715Z,1597265373.715 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 0.010000 m
+2020-08-12T20:59:11.891Z,1597265951.891 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
+2020-08-12T20:59:12.291Z,1597265952.291 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 0.008833 m , settling for 0.050000 min
+2020-08-12T20:59:15.892Z,1597265955.892 [sample:SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
+2020-08-12T20:59:16.293Z,1597265956.293 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Initializing ESPCartridgeSelect.
+2020-08-12T20:59:16.692Z,1597265956.692 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Issuing request for ESP cartridge type: -4.
+2020-08-12T20:59:16.692Z,1597265956.692 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Uninitializing ESPCartridgeSelect.
+2020-08-12T20:59:16.692Z,1597265956.692 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
+2020-08-12T20:59:18.292Z,1597265958.292 [ESPComponent](IMPORTANT): powerUpESP IGNORED because manualEsp_
+2020-08-12T20:59:18.292Z,1597265958.292 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=30.0000)
+2020-08-12T20:59:20.693Z,1597265960.693 [ESPComponent](IMPORTANT): [sample #2] Setting cartridge argument to -4
+2020-08-12T20:59:20.693Z,1597265960.693 [ESPComponent](IMPORTANT): [sample #2] ESP sampling sequence starting. Sampling state: S_WAITING_INITIAL_PROMPT
+2020-08-12T20:59:21.093Z,1597265961.093 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](ERROR): data element is not active.
+2020-08-12T20:59:21.093Z,1597265961.093 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](IMPORTANT): ESP sampling at 0.000588 m inwater with nan ug/l chlorophyll fluorescence.
+2020-08-12T20:59:21.492Z,1597265961.492 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PREPARING_SHOW_LOG
+2020-08-12T20:59:21.892Z,1597265961.892 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PREPARING_SHOW_STATUS
+2020-08-12T20:59:23.892Z,1597265963.892 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_LOADING_CARTRIDGE
+2020-08-12T21:00:00.700Z,1597266000.700 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PRIMING (@57,archiveHiBiomass_bac)
+2020-08-12T21:00:02.301Z,1597266002.301 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PRIMING (@5.0)
+2020-08-12T21:00:03.101Z,1597266003.101 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_FILTERING (@5.0)
+2020-08-12T21:00:33.511Z,1597266033.511 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_FILTERING (@0.0)
+2020-08-12T21:00:33.911Z,1597266033.911 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PAUSED (@0.0)
+2020-08-12T21:00:49.514Z,1597266049.514 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PAUSED (@10.0)
+2020-08-12T21:00:49.914Z,1597266049.914 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PROCESSING (@10.0)
+2020-08-12T21:00:52.315Z,1597266052.315 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PROCESSED (@57,archiveHiBiomass_bac)
+2020-08-12T21:04:24.361Z,1597266264.361 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_STOPPING (@57,archiveHiBiomass_bac)
+2020-08-12T21:04:51.167Z,1597266291.167 [ESPComponent](IMPORTANT): Syncing ESP logs with command: rsync -azPq --timeout=60 carueda@bufflehead:'/var/log/carueda/simrapid.* /var/log/carueda/*.spr' /home/carueda/lrauv-application/ESPlogs &
+2020-08-12T21:04:51.568Z,1597266291.568 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_STOPPED
+2020-08-12T21:09:51.236Z,1597266591.236 [ESPComponent](IMPORTANT): [sample #2] ESP sampling sequence completed normally.
+2020-08-12T21:09:51.237Z,1597266591.237 [sample:SampleAtDepth:SampleWrapper:SampleESP](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP
+2020-08-12T21:09:51.237Z,1597266591.237 [sample:SampleAtDepth:SampleWrapper](INFO): Completed sample:SampleAtDepth:SampleWrapper
+2020-08-12T21:09:51.237Z,1597266591.237 [sample:SampleAtDepth](INFO): Completed sample:SampleAtDepth
+2020-08-12T21:09:51.636Z,1597266591.636 [ESPComponent](IMPORTANT): [sample #2] ESP log summary report (2 messages):
-2020-08-12T19:29:00.319Z,1597260540.319 [ESPComponent](IMPORTANT): powerDownESP IGNORED because manualEsp_
-2020-08-12T19:29:00.321Z,1597260540.321 [sample:SampleRepeater:Sample](INFO): Completed sample:SampleRepeater:Sample
-2020-08-12T19:29:00.321Z,1597260540.321 [sample:SampleRepeater](INFO): Completed sample:SampleRepeater
+2020-08-12T21:09:51.636Z,1597266591.636 [ESPComponent](IMPORTANT): powerDownESP IGNORED because manualEsp_
+2020-08-12T21:09:51.637Z,1597266591.637 [sample:SampleRepeater:Sample](INFO): Completed sample:SampleRepeater:Sample
+2020-08-12T21:09:51.637Z,1597266591.637 [sample:SampleRepeater](INFO): Completed sample:SampleRepeater
- <log> @11:59:23.75 <sampling> Cartridge 60 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
+ <log> @13:40:16.47 <sampling> Cartridge 58 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
- <log> @12:19:43.46 <sampling> Cartridge 59 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
+ <log> @14:00:34.81 <sampling> Cartridge 57 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
Updated by
Modified
snippet.markdown- Ignore whitespace
-**Note**: the config parameter `ESPComponent.simulateHardware` is false for these tests: the ESP simulator
-Using [trackSlots branch](https://github.com/MBARI-ESP/ESP2Gscript/tree/trackSlots) of the ESP software (commit cf51a340f91 / Tue Apr 30 10:07:49 2019 -0700).
+> If you replace simreal with simrapid in your existing instructions, you should get the accelerated sim.
->I've checked in a version that also reduced the processing time from 10:00 to 45 seconds when rapid = true
+- `archiveHiBiomass_bac` is the default ESP cartridge type code in the the mission used below, `Maintenance/sample.xml`
-Parameters that can be adjusted for testing convenience, for example `Depth = 1`, `NumberOfSamples = 2`.
+There, as usual, I have populated the following under Config/sim/carueda/ to override relevant configs for the **sim** vehicle:
+- Port 9998 on tethyscode is where the ESPComponent will listen as a server and wait for the ESP to connect.
- Alternatively, instead of having the LRAUV running as the server waiting for the ESP, you can set:
+ and immediately run the ESP command above. In this case, the LRAUV would immediately connect to the already running ESP server.
+Parameters that can be adjusted for testing convenience are, for example `Depth = 1`, `NumberOfSamples = 2`.
We need to tell the ESPComponent that we are running the ESP manually, so we set the `ESPCOMPONENT_MANUAL_ESP` environment variable for this purpose:
- "load Maintenance/sample.xml;set sample.NumberOfSamples 2 count;set sample.Depth 1 centimeter;set sample.WaitBeforeSample 3 second;set sample:SampleAtDepth.TargetDepth 1 centimeter;run"
+ "load Maintenance/sample.xml;set sample.NumberOfSamples 2 count;set sample.Depth 1 centimeter;set sample.WaitBeforeSample 3 second;set sample:SampleAtDepth.TargetDepth 1 centimeter;run"
-... [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=30.0000) <<<<<<<<<<<<< HERE <<<<<<<<<<<<<<
-@16:11:24.74 <sampling> Cartridge 59 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
+@11:59:23.75 <sampling> Cartridge 60 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
+Note: `exit` after 1st sample completed, to prepare and then re-launch for the 2nd sample as signaled from the tethyscode output captured below.
-2019-04-30T23:08:43.013Z,1556665723.013 [ESPComponent](IMPORTANT): [sample #1] Setting cartridge argument to :archive
-2019-04-30T23:08:43.013Z,1556665723.013 [ESPComponent](IMPORTANT): [sample #1] ESP sampling sequence starting. Sampling state: S_WAITING_INITIAL_PROMPT
-2019-04-30T23:08:43.414Z,1556665723.414 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](ERROR): data element is not active.
-2019-04-30T23:08:43.414Z,1556665723.414 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](ERROR): data element is not active.
-2019-04-30T23:08:43.414Z,1556665723.414 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](IMPORTANT): ESP sampling at 0.152651 m in nan degC water with nan ug/l chlorophyll fluorescence.
-2019-04-30T23:08:44.213Z,1556665724.213 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PREPARING_SHOW_LOG
-2019-04-30T23:08:44.613Z,1556665724.613 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PREPARING_SHOW_STATUS
-2019-04-30T23:08:46.213Z,1556665726.213 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_LOADING_CARTRIDGE
-2019-04-30T23:09:21.819Z,1556665761.819 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PRIMING (@59,archive)
-2019-04-30T23:09:23.419Z,1556665763.419 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PRIMING (@100.0)
-2019-04-30T23:09:24.219Z,1556665764.219 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_FILTERING (@100.0)
-2019-04-30T23:11:23.038Z,1556665883.038 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_FILTERING (@0.0)
-2019-04-30T23:11:23.438Z,1556665883.438 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PAUSED (@0.0)
-2019-04-30T23:11:39.440Z,1556665899.440 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PAUSED (@10.0)
-2019-04-30T23:11:39.840Z,1556665899.840 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PROCESSING (@10.0)
-2019-04-30T23:11:42.241Z,1556665902.241 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PROCESSED (@59,archive)
-2019-04-30T23:13:51.860Z,1556666031.860 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_STOPPING (@59,archive)
-2019-04-30T23:14:17.864Z,1556666057.864 [ESPComponent](IMPORTANT): [sample #1] ESP sampling sequence completed normally.
-2019-04-30T23:14:17.865Z,1556666057.865 [sample:SampleAtDepth:SampleWrapper:SampleESP](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP
-2019-04-30T23:14:17.865Z,1556666057.865 [sample:SampleAtDepth:SampleWrapper](INFO): Completed sample:SampleAtDepth:SampleWrapper
-2019-04-30T23:14:17.865Z,1556666057.865 [sample:SampleAtDepth](INFO): Completed sample:SampleAtDepth
-2019-04-30T23:14:18.264Z,1556666058.264 [ESPComponent](IMPORTANT): [sample #1] ESP log summary report (2 messages):
-2019-04-30T23:14:18.264Z,1556666058.264 [ESPComponent](IMPORTANT): powerDownESP IGNORED because manualEsp_
-2019-04-30T23:14:18.265Z,1556666058.265 [sample:SampleRepeater:Sample](INFO): Completed sample:SampleRepeater:Sample
-2019-04-30T23:14:18.265Z,1556666058.265 [sample:SampleRepeater](INFO): Completed sample:SampleRepeater
+2020-08-12T18:53:24.749Z,1597258404.749 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 106472
+2020-08-12T18:53:24.749Z,1597258404.749 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 106473
+2020-08-12T18:53:24.750Z,1597258404.750 [logger ThreadHandler](INFO): Protected caller Thread ID is 106474
+2020-08-12T18:53:24.750Z,1597258404.750 [Supervisor](INFO): Looking for Config files in directory: Config/
+2020-08-12T18:53:24.750Z,1597258404.750 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
+2020-08-12T18:53:24.754Z,1597258404.754 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
+2020-08-12T18:53:24.759Z,1597258404.759 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
+2020-08-12T18:53:24.764Z,1597258404.764 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
+tethyscode>2020-08-12T18:53:24.770Z,1597258404.770 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
+2020-08-12T18:53:24.773Z,1597258404.773 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
+2020-08-12T18:53:24.776Z,1597258404.776 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
+2020-08-12T18:53:24.780Z,1597258404.780 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
+2020-08-12T18:53:24.785Z,1597258404.785 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
+2020-08-12T18:53:24.788Z,1597258404.788 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
+2020-08-12T18:53:24.791Z,1597258404.791 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
+2020-08-12T18:53:24.795Z,1597258404.795 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
+2020-08-12T18:53:24.802Z,1597258404.802 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
+2020-08-12T18:53:24.805Z,1597258404.805 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
+2020-08-12T18:53:24.812Z,1597258404.812 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
+2020-08-12T18:53:24.816Z,1597258404.816 [Supervisor](INFO): Looking for Config files in directory: Config/tethyscode/
+2020-08-12T18:53:24.816Z,1597258404.816 [Supervisor](INFO): Looking for Config files in directory: Config/tethyscode/carueda/
+2020-08-12T18:53:24.816Z,1597258404.816 [Supervisor](INFO): Looking for Config files in directory: Config/sim/
+2020-08-12T18:53:24.816Z,1597258404.816 [Supervisor](INFO): Opening Config file at: Config/sim/secure.cfg
+2020-08-12T18:53:24.818Z,1597258404.818 [Supervisor](INFO): Opening Config file at: Config/sim/Science.cfg
+2020-08-12T18:53:24.821Z,1597258404.821 [Supervisor](INFO): Opening Config file at: Config/sim/Simulator.cfg
+2020-08-12T18:53:24.824Z,1597258404.824 [Supervisor](INFO): Opening Config file at: Config/sim/vehicle.cfg
+2020-08-12T18:53:24.827Z,1597258404.827 [Supervisor](INFO): Opening Config file at: Config/sim/Servo.cfg
+2020-08-12T18:53:24.830Z,1597258404.830 [Supervisor](INFO): Opening Config file at: Config/sim/Sensor.cfg
+2020-08-12T18:53:24.833Z,1597258404.833 [Supervisor](INFO): Opening Config file at: Config/sim/Derivation.cfg
+2020-08-12T18:53:24.836Z,1597258404.836 [Supervisor](INFO): Looking for Config files in directory: Config/sim/carueda/
+2020-08-12T18:53:24.836Z,1597258404.836 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/secure.cfg
+2020-08-12T18:53:24.839Z,1597258404.839 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/Science.cfg
+2020-08-12T18:53:24.842Z,1597258404.842 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/vehicle.cfg
+2020-08-12T18:53:24.844Z,1597258404.844 [Supervisor](INFO): Opening Config file at: Config/sim/carueda/Sensor.cfg
+2020-08-12T18:53:24.847Z,1597258404.847 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
+2020-08-12T18:53:24.853Z,1597258404.853 [ESPComponent](ERROR): Could not open device '/dev/loadA6' at ESPComponent.loadControl
+2020-08-12T18:53:24.873Z,1597258404.873 [ESPComponent](ERROR): Could not open device '/dev/loadA7' at ESPComponent.loadControl2
+2020-08-12T18:53:24.895Z,1597258404.895 [ESPComponent](IMPORTANT): ESPCOMPONENT_MANUAL_ESP INDICATED.
+2020-08-12T18:53:24.897Z,1597258404.897 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
+2020-08-12T18:53:24.898Z,1597258404.898 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 106556
+2020-08-12T18:53:24.911Z,1597258404.911 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
+2020-08-12T18:53:24.914Z,1597258404.914 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 106557
+2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
+2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
+2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
+2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
+2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
+2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
+2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
+2020-08-12T18:53:24.915Z,1597258404.915 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
+2020-08-12T18:53:24.916Z,1597258404.916 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 106558
+2020-08-12T18:53:24.916Z,1597258404.916 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 106559
+2020-08-12T18:53:24.924Z,1597258404.924 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 106560
+2020-08-12T18:53:24.924Z,1597258404.924 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 106566
+2020-08-12T18:53:24.925Z,1597258404.925 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 106568
+2020-08-12T18:53:24.927Z,1597258404.927 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 106565
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 106569
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
+2020-08-12T18:53:24.927Z,1597258404.927 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
+2020-08-12T18:53:24.930Z,1597258404.930 [CommandLine ThreadHandler](INFO): Handler Thread ID is 106561
+2020-08-12T18:53:24.930Z,1597258404.930 [CommandLine ThreadHandler](INFO): Handler Thread ID is 106563
+2020-08-12T18:53:24.933Z,1597258404.933 [controlThread ThreadHandler](INFO): Handler Thread ID is 106562
+2020-08-12T18:53:24.951Z,1597258404.951 [SBIT](INFO): git hash: f73e8ad95b55d34bdb49d5e5edbbb75e6888861e
+2020-08-12T18:53:24.951Z,1597258404.951 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
+2020-08-12T18:53:24.951Z,1597258404.951 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
+tethyscode>load Maintenance2020-08-12T18:53:24.975Z,1597258404.975 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml
+2020-08-12T18:53:24.975Z,1597258404.975 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml
+2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min
+2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m
+2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.CartridgeType = -4.000000 count
+2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count
+2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min
+2020-08-12T18:53:24.976Z,1597258404.976 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 10.000000 s
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.MaxWaitNotReachingDepth = 3.000000 h
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 0.000000 bool
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 1.000000 bool
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.ESPCartridgeType = nan count
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](INFO): DefineArg sample:SampleAtDepth.BuoyancyNeutral = 500.000024 cc
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](ERROR): Slate does not contain CTD_NeilBrown.bin_mean_sea_water_temperature
+2020-08-12T18:53:24.977Z,1597258404.977 [MissionManager](ERROR): Slate does not contain CTD_Seabird.bin_mean_sea_water_temperature
+2020-08-12T18:53:24.978Z,1597258404.978 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml
+2020-08-12T18:53:24.981Z,1597258404.981 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
+2020-08-12T18:53:24.982Z,1597258404.982 [MissionManager](INFO): Loading Mission: Missions/Default.xml
+2020-08-12T18:53:24.982Z,1597258404.982 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
+tethyscode>set sample.NumberOfSamples 2 count;set sample.Depth 1 centimeter;set sample.WaitBeforeSample 3 second;set sample:SampleAtDepth.TargetDepth 1 centimeter;run
+2020-08-12T18:53:25.030Z,1597258405.030 [CommandLine](IMPORTANT): got command set sample.NumberOfSamples 2.000000 count
+2020-08-12T18:53:25.030Z,1597258405.030 [CommandLine](IMPORTANT): got command set sample.Depth 1.000000 centimeter
+2020-08-12T18:53:25.030Z,1597258405.030 [CommandLine](IMPORTANT): got command set sample.WaitBeforeSample 3.000000 second
+2020-08-12T18:53:25.030Z,1597258405.030 [CommandLine](IMPORTANT): got command set sample:SampleAtDepth.TargetDepth 1.000000 centimeter
+2020-08-12T18:53:28.925Z,1597258408.925 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.000125
+2020-08-12T18:53:29.925Z,1597258409.925 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
+2020-08-12T18:53:59.399Z,1597258439.399 [NAL9602](INFO): GPS fix at 20200812T185359: (36.803404, -121.822207)
+2020-08-12T18:54:39.011Z,1597258479.011 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
+2020-08-12T18:54:39.411Z,1597258479.411 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
+2020-08-12T18:54:44.212Z,1597258484.212 [NAL9602](INFO): GPS fix at 20200812T185444: (36.803409, -121.822216)
+2020-08-12T18:54:47.941Z,1597258487.941 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20200812T183714/Courier0004.lzma
+2020-08-12T18:54:48.941Z,1597258488.941 [DataOverHttps](INFO): Moved sent file to Logs/20200812T183714/Courier0004.lzma.bak
+2020-08-12T18:54:57.942Z,1597258497.942 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20200812T185324/Courier0000.lzma
+2020-08-12T18:54:58.942Z,1597258498.942 [DataOverHttps](INFO): Moved sent file to Logs/20200812T185324/Courier0000.lzma.bak
+2020-08-12T18:55:07.943Z,1597258507.943 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20200812T083346/Express0005.lzma
+2020-08-12T18:55:08.943Z,1597258508.943 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083346/Express0005.lzma.bak
+2020-08-12T18:55:17.944Z,1597258517.944 [DataOverHttps](INFO): Sending 580 bytes from file Logs/20200812T083353/Express0001.lzma
+2020-08-12T18:55:18.944Z,1597258518.944 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083353/Express0001.lzma.bak
+2020-08-12T18:55:27.945Z,1597258527.945 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20200812T083353/Express0005.lzma
+2020-08-12T18:55:28.944Z,1597258528.944 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083353/Express0005.lzma.bak
+2020-08-12T18:55:37.946Z,1597258537.946 [DataOverHttps](INFO): Sending 584 bytes from file Logs/20200812T083428/Express0001.lzma
+2020-08-12T18:55:38.945Z,1597258538.945 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083428/Express0001.lzma.bak
+2020-08-12T18:55:44.225Z,1597258544.225 [Startup:StartupSatComms:B](INFO): Timed out from 2020-08-12T18:54:44.2Z
+2020-08-12T18:55:44.226Z,1597258544.226 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
+2020-08-12T18:55:45.827Z,1597258545.827 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 0.010000 m
+2020-08-12T18:55:47.946Z,1597258547.946 [DataOverHttps](INFO): Sending 646 bytes from file Logs/20200812T083428/Express0005.lzma
+2020-08-12T18:55:48.946Z,1597258548.946 [DataOverHttps](INFO): Moved sent file to Logs/20200812T083428/Express0005.lzma.bak
+2020-08-12T18:57:55.456Z,1597258675.456 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
+2020-08-12T18:57:55.856Z,1597258675.856 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 0.012371 m , settling for 0.050000 min
+2020-08-12T18:57:59.457Z,1597258679.457 [sample:SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
+2020-08-12T18:57:59.857Z,1597258679.857 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Initializing ESPCartridgeSelect.
+2020-08-12T18:58:00.257Z,1597258680.257 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Issuing request for ESP cartridge type: -4.
+2020-08-12T18:58:00.257Z,1597258680.257 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Uninitializing ESPCartridgeSelect.
+2020-08-12T18:58:00.257Z,1597258680.257 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
+2020-08-12T18:58:01.456Z,1597258681.456 [ESPComponent](IMPORTANT): Setting regex for ESP Log summary: 'Selecting Cartridge|Sampled|Error|Underpressure|Overpressure|Fail|Retry|Cmd::|Sampler::'
+2020-08-12T18:58:01.456Z,1597258681.456 [ESPComponent](INFO): compiled regex: "Selecting Cartridge|Sampled|Error|Underpressure|Overpressure|Fail|Retry|Cmd::|Sampler::"
+2020-08-12T18:58:01.856Z,1597258681.856 [ESPComponent](IMPORTANT): powerUpESP IGNORED because manualEsp_
+2020-08-12T18:58:01.856Z,1597258681.856 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=30.0000)
+2020-08-12T18:58:09.458Z,1597258689.458 [ESPComponent](IMPORTANT): [sample #1] Setting cartridge argument to -4
+2020-08-12T18:58:09.458Z,1597258689.458 [ESPComponent](IMPORTANT): [sample #1] ESP sampling sequence starting. Sampling state: S_WAITING_INITIAL_PROMPT
+2020-08-12T18:58:09.859Z,1597258689.859 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](ERROR): data element is not active.
+2020-08-12T18:58:09.859Z,1597258689.859 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](IMPORTANT): ESP sampling at 0.189350 m inwater with nan ug/l chlorophyll fluorescence.
+2020-08-12T18:58:10.658Z,1597258690.658 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PREPARING_SHOW_LOG
+2020-08-12T18:58:11.058Z,1597258691.058 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PREPARING_SHOW_STATUS
+2020-08-12T18:58:12.658Z,1597258692.658 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_LOADING_CARTRIDGE
+2020-08-12T18:58:49.467Z,1597258729.467 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PRIMING (@60,archiveHiBiomass_bac)
+2020-08-12T18:58:51.067Z,1597258731.067 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PRIMING (@5.0)
+2020-08-12T18:58:51.867Z,1597258731.867 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_FILTERING (@5.0)
+2020-08-12T18:59:22.274Z,1597258762.274 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_FILTERING (@0.0)
+2020-08-12T18:59:22.674Z,1597258762.674 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PAUSED (@0.0)
+2020-08-12T18:59:38.277Z,1597258778.277 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PAUSED (@10.0)
+2020-08-12T18:59:38.677Z,1597258778.677 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_PROCESSING (@10.0)
+2020-08-12T18:59:41.078Z,1597258781.078 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_WAITING_FOR_PROCESSED (@60,archiveHiBiomass_bac)
+2020-08-12T19:03:13.136Z,1597258993.136 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_STOPPING (@60,archiveHiBiomass_bac)
+2020-08-12T19:03:39.941Z,1597259019.941 [ESPComponent](IMPORTANT): Syncing ESP logs with command: rsync -azPq --timeout=60 esp@esp:'/var/log/esp/real /var/log/esp/real.out /var/log/esp/real.slot /var/log/esp/*.spr' /LRAUV/ESPlogs &
+2020-08-12T19:03:40.342Z,1597259020.342 [ESPComponent](IMPORTANT): [sample #1] ESP sampling state: S_STOPPED
+2020-08-12T19:08:40.026Z,1597259320.026 [ESPComponent](IMPORTANT): [sample #1] ESP sampling sequence completed normally.
+2020-08-12T19:08:40.027Z,1597259320.027 [sample:SampleAtDepth:SampleWrapper:SampleESP](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP
+2020-08-12T19:08:40.027Z,1597259320.027 [sample:SampleAtDepth:SampleWrapper](INFO): Completed sample:SampleAtDepth:SampleWrapper
+2020-08-12T19:08:40.027Z,1597259320.027 [sample:SampleAtDepth](INFO): Completed sample:SampleAtDepth
+2020-08-12T19:08:40.426Z,1597259320.426 [ESPComponent](IMPORTANT): [sample #1] ESP log summary report (2 messages):
+2020-08-12T19:08:40.426Z,1597259320.426 [ESPComponent](IMPORTANT): powerDownESP IGNORED because manualEsp_
+2020-08-12T19:08:40.427Z,1597259320.427 [sample:SampleRepeater:Sample](INFO): Completed sample:SampleRepeater:Sample
+2020-08-12T19:08:40.428Z,1597259320.428 [sample:SampleRepeater](INFO): Completed sample:SampleRepeater
+2020-08-12T19:08:40.827Z,1597259320.827 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 0.010000 m
+2020-08-12T19:18:18.969Z,1597259898.969 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
+2020-08-12T19:18:19.367Z,1597259899.367 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 0.008993 m , settling for 0.050000 min
+2020-08-12T19:18:22.968Z,1597259902.968 [sample:SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
+2020-08-12T19:18:23.368Z,1597259903.368 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Initializing ESPCartridgeSelect.
+2020-08-12T19:18:23.768Z,1597259903.768 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Issuing request for ESP cartridge type: -4.
+2020-08-12T19:18:23.768Z,1597259903.768 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge:A.ESPCartridgeSelect](INFO): Uninitializing ESPCartridgeSelect.
+2020-08-12T19:18:23.768Z,1597259903.768 [sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP:SelectESPCartridge
+2020-08-12T19:18:25.368Z,1597259905.368 [ESPComponent](IMPORTANT): powerUpESP IGNORED because manualEsp_
+2020-08-12T19:18:25.368Z,1597259905.368 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=30.0000)
+2020-08-12T19:18:29.369Z,1597259909.369 [ESPComponent](IMPORTANT): [sample #2] Setting cartridge argument to -4
+2020-08-12T19:18:29.369Z,1597259909.369 [ESPComponent](IMPORTANT): [sample #2] ESP sampling sequence starting. Sampling state: S_WAITING_INITIAL_PROMPT
+2020-08-12T19:18:29.769Z,1597259909.769 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](ERROR): data element is not active.
+2020-08-12T19:18:29.769Z,1597259909.769 [sample:SampleAtDepth:SampleWrapper:SampleESP:C](IMPORTANT): ESP sampling at 0.001501 m inwater with nan ug/l chlorophyll fluorescence.
+2020-08-12T19:18:30.169Z,1597259910.169 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PREPARING_SHOW_LOG
+2020-08-12T19:18:30.569Z,1597259910.569 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PREPARING_SHOW_STATUS
+2020-08-12T19:18:32.569Z,1597259912.569 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_LOADING_CARTRIDGE
+2020-08-12T19:19:09.377Z,1597259949.377 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PRIMING (@59,archiveHiBiomass_bac)
+2020-08-12T19:19:10.977Z,1597259950.977 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PRIMING (@5.0)
+2020-08-12T19:19:11.777Z,1597259951.777 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_FILTERING (@5.0)
+2020-08-12T19:19:42.183Z,1597259982.183 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_FILTERING (@0.0)
+2020-08-12T19:19:42.583Z,1597259982.583 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PAUSED (@0.0)
+2020-08-12T19:19:58.187Z,1597259998.187 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PAUSED (@10.0)
+2020-08-12T19:19:58.587Z,1597259998.587 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_PROCESSING (@10.0)
+2020-08-12T19:20:00.987Z,1597260000.987 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_WAITING_FOR_PROCESSED (@59,archiveHiBiomass_bac)
+2020-08-12T19:23:33.049Z,1597260213.049 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_STOPPING (@59,archiveHiBiomass_bac)
+2020-08-12T19:23:59.855Z,1597260239.855 [ESPComponent](IMPORTANT): Syncing ESP logs with command: rsync -azPq --timeout=60 esp@esp:'/var/log/esp/real /var/log/esp/real.out /var/log/esp/real.slot /var/log/esp/*.spr' /LRAUV/ESPlogs &
+2020-08-12T19:24:00.255Z,1597260240.255 [ESPComponent](IMPORTANT): [sample #2] ESP sampling state: S_STOPPED
+2020-08-12T19:28:59.919Z,1597260539.919 [ESPComponent](IMPORTANT): [sample #2] ESP sampling sequence completed normally.
+2020-08-12T19:28:59.920Z,1597260539.920 [sample:SampleAtDepth:SampleWrapper:SampleESP](INFO): Completed sample:SampleAtDepth:SampleWrapper:SampleESP
+2020-08-12T19:28:59.920Z,1597260539.920 [sample:SampleAtDepth:SampleWrapper](INFO): Completed sample:SampleAtDepth:SampleWrapper
+2020-08-12T19:28:59.920Z,1597260539.920 [sample:SampleAtDepth](INFO): Completed sample:SampleAtDepth
+2020-08-12T19:29:00.319Z,1597260540.319 [ESPComponent](IMPORTANT): [sample #2] ESP log summary report (2 messages):
+2020-08-12T19:29:00.319Z,1597260540.319 [ESPComponent](IMPORTANT): powerDownESP IGNORED because manualEsp_
+2020-08-12T19:29:00.321Z,1597260540.321 [sample:SampleRepeater:Sample](INFO): Completed sample:SampleRepeater:Sample
+2020-08-12T19:29:00.321Z,1597260540.321 [sample:SampleRepeater](INFO): Completed sample:SampleRepeater
+ <log> @11:59:23.75 <sampling> Cartridge 60 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
- <log> @16:11:24.74 <sampling> Cartridge 59 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
+ <log> @12:19:43.46 <sampling> Cartridge 59 sampling 2/10ml at 0.83ml/s with 0.0psi vacuum 5.7m deep (23.1psia)
Updated by
Modified
snippet.markdown- Ignore whitespace
>I've checked in a version that also reduced the processing time from 10:00 to 45 seconds when rapid = true
-> Cartridge.identify tests all loaded cartridges and changes their type from :generic to :lysis or :archive.
-> The way we worked out last week relied on the fact that the simulator currently assumes all odd numbered slots contain an archive cartridge. This method works regardless of how the simulator allocates cartridge types.
-**Note**: Port 9998 on tethyssim is where the ESPComponent will listen and wait for the ESP to connect.
+- Alternatively, instead of having the LRAUV running as the server waiting for the ESP, you can set:
We need to tell the ESPComponent that we are running the ESP manually, so we set the `ESPCOMPONENT_MANUAL_ESP` environment variable for this purpose:
- "load Maintenance/sample.xml;set sample.Depth 1 centimeter;set sample.WaitBeforeSample 3 second;set sample:SampleAtDepth.TargetDepth 1 centimeter;run"
+ "load Maintenance/sample.xml;set sample.NumberOfSamples 2 count;set sample.Depth 1 centimeter;set sample.WaitBeforeSample 3 second;set sample:SampleAtDepth.TargetDepth 1 centimeter;run"
-2019-04-30T23:06:20.992Z,1556665580.992 [sample:SampleAtDepth:ApproachDepth:A](INFO): Moving to 0.010000 m
-2019-04-30T23:08:30.611Z,1556665710.611 [sample:SampleAtDepth:ApproachDepth](INFO): Completed sample:SampleAtDepth:ApproachDepth
-2019-04-30T23:08:31.011Z,1556665711.011 [sample:SampleAtDepth:SampleWrapper:B](IMPORTANT): At 0.011770 m , settling for 0.050000 min
-2019-04-30T23:08:34.612Z,1556665714.612 [sample:SampleAtDepth:SampleWrapper:C.Wait](INFO): Done Waiting.
-2019-04-30T23:08:36.612Z,1556665716.612 [ESPComponent](IMPORTANT): Setting regex for ESP Log summary: 'Selecting Cartridge|Sampled|Error|Fail|Retry|Cmd::|Sampler::'
-2019-04-30T23:08:36.612Z,1556665716.612 [ESPComponent](INFO): compiled regex: "Selecting Cartridge|Sampled|Error|Fail|Retry|Cmd::|Sampler::"
-2019-04-30T23:08:37.012Z,1556665717.012 [ESPComponent](IMPORTANT): powerUpESP IGNORED because manualEsp_
-2019-04-30T23:08:37.012Z,1556665717.012 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=30.0000) <<<<<<<<<<<<< HERE <<<<<<<<<<<<<<
+... [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=30.0000) <<<<<<<<<<<<< HERE <<<<<<<<<<<<<<
You can clone a snippet to your computer for local editing. Learn more.