@17:37:41.88EDT16-Aug-19
>BREAK< ESP3 Gateway v0.99 for core bus @17:37:41.89 >BREAK< ESP3 Gateway v0.99 for cartridge bus @17:37:43.47 Configuration for honu by brent@mbari.org 7/26/19 /home/esp/esp2/type/auv/honu/configure.rb @17:37:46.46 Polling Can every 5:00 @17:37:46.76 Clients may connect on TCP port 9999 @17:37:46.78 Connecting to 10.89.5.2:9999 @17:37:46.83 Waiting for espclients... @17:37:47.20 Session Begun @17:37:49.56 -> showStatus @17:37:51.17 -> Cmd.loadCartridge -15 @17:37:51.32 Gate.power :main,:ON,limit to 5000mA @17:37:51.33 Gate.power :analytic1,:ON,limit to 5000mA @17:37:51.35 Gate.power :analytic2,:ON,limit to 5000mA @17:37:51.38 Gate.power :analytic3,:ON,limit to 5000mA @17:37:51.51 Powering core bus @17:37:53.80 SPR Module 1, FW Rev. B, FPGA Rev. C @17:37:53.92 Archive.configure :default @17:37:54.05 Air.configure :default @17:37:54.15 Lysis1.configure :default @17:37:54.25 Lysis2.configure :default @17:37:54.37 Plunge.configure :default @17:37:54.49 SamplePump.configure :default @17:37:54.61 ExtraPump.configure :default @17:37:54.73 Toroid.configure :default @17:37:54.85 Carousel.configure :default @17:37:54.97 Pressure.configure :default @17:37:55.07 PinPort.configure :default @17:37:55.13 All elf controllers running firmware version 4.58 @17:37:55.15 Gate.power :motor,:ON,limit to 5000mA @17:37:55.35 Archive.reconfigure :default @17:37:55.63 Toroid.reconfigure :default @17:37:55.71 Plunge.reconfigure :default @17:37:55.74 Lysis1.reconfigure :default @17:37:55.78 PinPort.reconfigure :default @17:37:55.81 Lysis2.reconfigure :default @17:37:55.85 Air.reconfigure :default @17:37:56.10 Lysis2.home.jog 0 @17:37:56.15 Air.home.jog 0 @17:37:56.21 Toroid.home.jog 0 @17:37:56.29 Lysis1.home.jog 0 @17:37:56.36 PinPort.home.jog 0 @17:37:56.42 Archive.home.jog 0 @17:37:56.48 Plunge.home.jog 0 @17:37:57.03 Toroid.reconfigure :homing @17:37:57.07 Plunge.reconfigure :homing @17:37:57.11 Air.reconfigure :homing @17:37:57.14 Lysis1.reconfigure :homing @17:37:57.18 PinPort.reconfigure :homing @17:37:57.23 Archive.reconfigure :homing @17:37:57.26 Lysis2.reconfigure :homing @17:37:57.49 Archive.jog 1384 @17:37:57.55 Lysis1.jog 1399 @17:37:57.60 Plunge.jog 1549 @17:37:57.66 Lysis2.jog 1424 @17:37:57.71 Toroid.jog 634 @17:37:57.77 PinPort.jog -110 @17:37:57.83 Air.jog 1408 @17:37:58.01 PinPort.setRawPosition! 0 @17:37:58.05 PinPort.seek :home @17:37:58.14 PinPort.reconfigure :default @17:37:58.64 Archive.setRawPosition! 50 @17:37:58.69 Archive.seek :home @17:37:58.77 Lysis1.setRawPosition! 50 @17:37:58.84 Lysis1.seek :home @17:37:58.91 Plunge.setRawPosition! 50 @17:37:58.93 Lysis2.setRawPosition! 50 @17:37:59.03 Plunge.seek :home @17:37:59.05 Lysis2.seek :home @17:37:59.21 Lysis1.reconfigure :default @17:37:59.24 Air.setRawPosition! 50 @17:37:59.26 Archive.reconfigure :default @17:37:59.36 Air.seek :home @17:37:59.52 Plunge.reconfigure :default @17:37:59.54 Lysis2.reconfigure :default @17:37:59.72 Air.reconfigure :default @17:37:59.87 Toroid.setRawPosition! 0 @17:37:59.92 Toroid.seek :home @17:38:00.00 Toroid.reconfigure :default @17:38:00.14 Toroid.seek :clear @17:38:01.77 Carousel.reconfigure :default @17:38:01.84 Carousel.home.jog 0 @17:38:02.33 Carousel.seek 54 @17:38:02.50 Selecting Cartridge 52 @17:38:02.74 Carousel.seek 52 @17:38:03.43 Lysis1.seek :locked @17:38:07.57 Plunge.seek :locked @17:38:07.69 Air.seek :locked @17:38:11.82 Powering cartridge bus @17:38:13.94 Gate.setIR 1750,5000,0,6 @17:38:14.07 Gate.setIR 0,5000,0,6 @17:38:14.12 Loaded Lyse-n-Go Cartridge @17:38:14.14 PuckHeater.configure HeatConfig @17:38:14.19 Gate.power :heat,:ON,limit to 5000mA @17:38:30.04 -> Cmd.startFiltering @17:38:30.27 Duration of filtering limited to 2:05:00 @17:38:30.52 Priming sample loop w/100ml, bypass w/10ml @17:38:30.54 SamplePump.setPosition! 0ml @17:38:30.59 Bypass.open @17:38:32.03 Intake.open @17:38:33.22 Waiting up to 20 seconds for sea pressure to stabilize +/-0.50psi @17:38:36.06 Vacuum tare is 52% of full scale @17:38:36.19 Pressure.reconfigure :default @17:38:36.25 Pressure.hold @17:38:36.42 SamplePump.reconfigure :prime @17:38:36.50 SamplePump.seek 10ml,1:21 @17:38:45.45 Pressure.coast @17:38:45.50 SamplePump.setPosition! 0ml @17:38:45.55 Exhaust.open @17:38:46.96 Bypass.close @17:38:48.46 Pressure.reconfigure :default @17:38:48.52 Pressure.hold @17:38:48.74 SamplePump.reconfigure :prime @17:38:48.81 SamplePump.seek 100ml,8:51 @17:40:40.26 Pressure.coast @17:40:40.32 Bypass.open @17:40:41.01 Intake.close @17:40:41.84 Toroid.reconfigure :hold @17:40:41.90 Toroid.seek :crack @17:40:42.70 Toroid.reconfigure :default @17:40:42.76 Toroid.seek :clear @17:40:44.32 Toroid.reconfigure :hold @17:40:44.39 Toroid.seek :cartridge @17:40:46.20 Toroid.reconfigure :default @17:40:46.26 Toroid.seek :clear @17:40:48.24 Pressurizing cartridge to 26psi above 17.1psia @17:40:48.25 SamplePump.setPosition! 0ml @17:40:48.29 Intake.open @17:40:49.02 Bypass.close @17:40:49.74 Exhaust.close @17:40:50.53 Pressure.reconfigure :default @17:40:50.58 Pressure.hold @17:40:50.75 SamplePump.reconfigure :charge @17:40:50.82 SamplePump.seek 2.3ml,42 seconds @17:41:02.33 Pressure.coast @17:41:02.38 Intake.close @17:41:03.19 Pressurized cartridge with 0.620ml to 26.2psi @17:41:03.20 Isolated @39.2psia (22.1psi) @17:41:03.21 Checking for cartridge leaks from 39.2psia @17:41:03.24 Waiting up to 20 seconds for cartridge pressure to stabilize +/-0.75psi @17:41:08.29 Cartridge pressure fell 2% to 38.4psia @17:41:08.37 Sampling 1000.0ml @17:41:08.38 SamplePump.setPosition! 0ml @17:41:08.43 Exhaust.open @17:41:09.12 Intake.open @17:41:09.96 Pressure.reconfigure :default @17:41:10.02 Pressure.hold @17:41:10.24 SamplePump.reconfigure :sample @17:41:10.32 SamplePump.seek 1000ml,1:23:53 @17:41:13.00 Cartridge 52 sampling 1/1000ml at 0.54ml/s with 20.1psi vacuum @17:42:13.00 Cartridge 52 sampling 35/1000ml at 0.57ml/s with 25.5psi vacuum 3.4m deep (19.8psia) @17:43:13.00 Cartridge 52 sampling 67/1000ml at 0.53ml/s with 17.7psi vacuum 2.5m deep (18.4psia) @17:44:13.00 Cartridge 52 sampling 98/1000ml at 0.47ml/s with 23.3psi vacuum @17:45:13.00 Cartridge 52 sampling 128/1000ml at 0.50ml/s with 24.2psi vacuum 1.8m deep (17.4psia) @17:46:13.00 Cartridge 52 sampling 155/1000ml at 0.45ml/s with 24.9psi vacuum @17:47:13.00 Cartridge 52 sampling 181/1000ml at 0.43ml/s with 24.7psi vacuum @17:48:13.00 Cartridge 52 sampling 207/1000ml at 0.42ml/s with 27.8psi vacuum @17:49:13.00 Cartridge 52 sampling 230/1000ml at 0.37ml/s with 17.4psi vacuum 2.1m deep (17.8psia) @17:50:13.00 Cartridge 52 sampling 253/1000ml at 0.35ml/s with 28.9psi vacuum @17:51:13.00 Cartridge 52 sampling 273/1000ml at 0.30ml/s with 22.0psi vacuum @17:52:13.00 Cartridge 52 sampling 293/1000ml at 0.32ml/s with 24.0psi vacuum 2.1m deep (17.8psia) @17:53:13.00 Cartridge 52 sampling 311/1000ml at 0.27ml/s with 23.8psi vacuum 2.3m deep (18.1psia) @17:54:13.00 Cartridge 52 sampling 326/1000ml at 0.28ml/s with 25.9psi vacuum @17:55:13.00 Cartridge 52 sampling 341/1000ml at 0.25ml/s with 21.7psi vacuum 2.5m deep (18.4psia) @17:56:13.00 Cartridge 52 sampling 354/1000ml at 0.21ml/s with 18.4psi vacuum @17:57:13.00 Cartridge 52 sampling 367/1000ml at 0.21ml/s with 26.7psi vacuum @17:58:13.00 Cartridge 52 sampling 379/1000ml at 0.20ml/s with 27.7psi vacuum @17:59:13.00 Cartridge 52 sampling 392/1000ml at 0.20ml/s with 26.8psi vacuum @17:59:13.62 Flow averaged 0.36ml/s over 18:03 @17:59:13.65 Waiting up to 18 seconds for vacuum to reduce from 29.8 to 18.0psi @17:59:14.34 Relieved excess vacuum in 1.3 seconds @17:59:14.37 SamplePump.seek 1000ml,51:13 @17:59:30.36 Flow averaged 0.24ml/s over 14 seconds @17:59:30.39 Waiting up to 15 seconds for vacuum to reduce from 28.6 to 18.0psi @17:59:31.09 Relieved excess vacuum in 3.6 seconds @17:59:31.11 SamplePump.seek 1000ml,50:57 @18:00:04.35 Flow averaged 0.20ml/s over 34 seconds @18:00:04.38 Waiting up to 19 seconds for vacuum to reduce from 30.5 to 18.0psi @18:00:05.46 Relieved excess vacuum in 1.2 seconds @18:00:05.48 SamplePump.seek 1000ml,50:24 @18:00:12.09 Flow estimate reduced to 0.16ml/s @18:00:12.12 Intake.close @18:00:12.19 Exhaust.close @18:00:12.35 Toroid.reconfigure :hold @18:00:12.43 Toroid.seek :bypass @18:00:13.04 Cartridge 52 sampling 403/1000ml at 0.00ml/s with -0.2psi vacuum 2.8m deep (18.9psia) @18:00:14.05 Toroid.reconfigure :default @18:00:14.12 Toroid.seek :clear @18:00:15.74 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged @18:00:15.76 Pressure.coast @18:00:15.94 Sampled 402.9ml @18:00:18.00 -> Cmd.startProcessing @18:00:19.85 SPR.command "read_sequences" @18:00:20.55 SPR.command "stats_on" @18:00:20.59 SPR.command "start 0 1" @18:00:22.59 Begun log /var/log/esp/spr19aug1618h.spr @18:00:23.41 Handoff.select :waste @18:00:23.59 Archive.seek :empty @18:00:30.77 Syringe tare is 20% of full scale @18:00:41.33 PinPort.seek :cartridge @18:00:45.89 Lysis1.seek 1350ul @18:00:48.37 Lysis2.seek 1700ul @18:00:51.47 Plunge.seek :reagent @18:00:52.30 Evacuating puck with filter bubble point of 50psi @18:00:52.31 PinPort.seek :cartridge @18:00:52.39 Handoff.select :waste @18:01:00.83 Air pumping 1 strokes to reach 8psi @18:01:00.85 Air.reconfigure :gulp @18:01:00.93 Air.seek :tVent @18:01:02.74 0.5psi after gulp @18:01:02.75 Air stroke #1 of 1 @18:01:02.76 Air.reconfigure :pressurize @18:01:02.85 Air.seek :pressurized @18:01:15.70 1.0psi after pressurize @18:01:15.71 Air.reconfigure :compress @18:01:15.78 Air.seek :evac @18:01:18.45 1.4psi after compress @18:01:32.35 0.3psi after compress (stable) @18:01:32.47 at -0.0psi Opened to waste @18:01:32.48 Handoff.select :blocked @18:01:32.67 Air pumping 10 strokes to reach 50psi @18:01:32.68 Air.reconfigure :gulp @18:01:32.76 Air.seek :tVent @18:01:42.65 5.9psi after gulp @18:01:42.66 Air stroke #1 of 10 @18:01:42.68 Air.reconfigure :pressurize @18:01:42.76 Air.seek :pressurized @18:01:55.60 8.0psi after pressurize @18:01:55.63 Air.reconfigure :compress @18:01:55.71 Air.seek :evac @18:01:58.37 12.2psi after compress @18:02:20.93 24.6psi after compress (stable) @18:02:20.95 Air.reconfigure :gulp @18:02:21.03 Air.seek :tVent @18:02:30.93 24.6psi after gulp @18:02:30.94 Air stroke #2 of 10 @18:02:30.96 Air.reconfigure :pressurize @18:02:31.03 Air.seek :pressurized @18:02:43.89 24.7psi after pressurize @18:02:43.91 Air.reconfigure :compress @18:02:43.99 Air.seek :evac @18:02:46.66 29.9psi after compress @18:03:04.48 38.6psi after compress (stable) @18:03:04.50 Air.reconfigure :gulp @18:03:04.57 Air.seek :tVent @18:03:14.47 38.8psi after gulp @18:03:14.48 Air stroke #3 of 10 @18:03:14.50 Air.reconfigure :pressurize @18:03:14.58 Air.seek :pressurized @18:03:27.45 38.3psi after pressurize @18:03:27.46 Air.reconfigure :compress @18:03:27.54 Air.seek :evac @18:03:30.22 43.2psi after compress @18:03:35.37 Reached 50.0 of 50.0psi after pumping 6230ul (4.00 strokes) @18:03:35.38 Handoff.select :waste @18:03:52.11 delay 30 seconds @18:04:22.13 Handoff.select :blocked @18:04:47.23 Handoff.select :waste @18:04:47.35 PSI at @0.31psi at evacEnd @18:04:47.36 Handoff.select :blocked @18:04:47.48 Air.reconfigure :gulp @18:04:47.59 Air.seek :tVent @18:05:04.68 Stable @0.3psi after Handoff blocked @18:05:04.77 Air pumping 10 strokes to reach 30psi @18:05:04.79 Air stroke #1 of 10 @18:05:04.81 Air.reconfigure :pressurize @18:05:04.89 Air.seek :pressurized @18:05:17.74 2.6psi after pressurize @18:05:17.76 Air.reconfigure :compress @18:05:17.83 Air.seek :evac @18:05:20.50 8.7psi after compress @18:05:36.62 13.2psi after compress (stable) @18:05:36.64 Air.reconfigure :gulp @18:05:36.72 Air.seek :tVent @18:05:46.61 13.5psi after gulp @18:05:46.62 Air stroke #2 of 10 @18:05:46.64 Air.reconfigure :pressurize @18:05:46.72 Air.seek :pressurized @18:05:59.56 14.2psi after pressurize @18:05:59.57 Air.reconfigure :compress @18:05:59.67 Air.seek :evac @18:06:02.34 19.9psi after compress @18:06:18.25 24.7psi after compress (stable) @18:06:18.28 Air.reconfigure :gulp @18:06:18.36 Air.seek :tVent @18:06:28.25 25.0psi after gulp @18:06:28.26 Air stroke #3 of 10 @18:06:28.28 Air.reconfigure :pressurize @18:06:28.37 Air.seek :pressurized @18:06:41.22 25.0psi after pressurize @18:06:41.23 Air.reconfigure :compress @18:06:41.33 Air.seek :evac @18:06:44.00 30.1psi after compress @18:06:44.17 Reached 30.6 of 30.3psi after pumping 6230ul (4.00 strokes) @18:06:44.19 Handoff.select :waste @18:06:52.09 delay 30 seconds @18:07:22.11 Handoff.select :blocked @18:07:47.14 Handoff.select :waste @18:07:47.26 PSI at @0.41psi at evacEnd @18:07:47.27 Air.reconfigure :default @18:07:47.39 Air.seek :fVent @18:07:48.84 delay 5 seconds @18:07:53.86 Seawater evacuated, Presure above/below filter released. Lysis begins @18:07:53.94 Lysis1.reconfigure :slow @18:07:54.01 Lysis1.seek 1050ul @18:08:24.06 delay 5 seconds @18:08:29.07 Handoff.select :blocked @18:08:29.25 Lysis1.seek 450ul @18:09:29.24 delay 3 seconds @18:09:32.31 Air.reconfigure to mix into 1.37806 parts Lysis1 @18:09:33.07 Air.seek :evac @18:09:33.11 Lysis1.seek 300ul @18:09:48.23 Air.reconfigure :default @18:09:48.29 Air.seek :full @18:10:02.52 Lysis1.seek :empty @18:10:32.66 Lysis Delivered. Syringe pressure @45.2psi @18:10:32.70 PuckHeater.seek 84.1C @18:11:22.25 PuckHeater.hold 15:00,84.1C @18:26:21.89 Lysis complete. Syringe pressure @55.2psi @18:26:21.90 Handoff.select :blocked @18:26:22.00 Lysis1.reconfigure :halfSpeed @18:26:22.07 Lysis1.seek 200ul @18:26:25.84 delay 1 second @18:26:26.86 Lysis1.seek :empty @18:26:30.70 Initial AirS pressure @54.2psi @18:26:30.77 Lysis1.reconfigure :extract @18:26:30.86 Lysis1.seek 900ul,10:00 @18:26:33.13 Air pumping 10 strokes to reach 21psi @18:26:33.14 Air stroke #1 of 10 @18:26:33.16 Air.reconfigure :pressurize @18:26:33.24 Air.seek :pressurized @18:26:46.09 19.8psi after pressurize @18:26:46.10 Air.reconfigure :compress @18:26:46.18 Air.seek :evac @18:26:48.84 20.4psi after compress @18:26:56.35 Air.reconfigure :default @18:26:56.40 Air.hold @18:26:56.58 Lysis1 at 900ul @15.7psi @18:26:56.60 Lysis1.reconfigure :default @18:26:56.69 Lysis1.seek 925ul @18:26:57.45 Lysis1 at 925ul, bottom @14.4psi after recovery @18:26:58.58 Lysis1 at 925ul, bottom @15.1psi @18:26:58.60 Air.reconfigure :halfSpeed @18:26:58.68 Air.seek :fVent @18:27:00.93 Puck Top Vented, bottom @6.4psi @18:27:00.94 Handoff.select :waste @18:27:01.06 delay 5 seconds @18:27:06.08 delay 5 seconds @18:27:11.10 Handoff.select SPR @18:27:11.21 SPR.command "start 2 14" @18:27:12.87 delay 10 seconds @18:27:22.89 SPR.command "start 2 0" @18:27:26.13 Waiting up to 11:00 for SPR to enter state 1 @18:35:58.64 SPR.command "hold_on" @18:36:00.02 SPR Dilution 1 of 1 -- Running Experiment 2 @18:36:00.03 Lysis1.reconfigure :DAmix @18:36:00.15 Lysis2.reconfigure :DAmix @18:36:00.62 Lysis1.seek 675ul @18:36:00.70 Lysis2.seek 1450ul @18:36:36.08 Lysis1.seek 375ul @18:36:36.11 Lysis2.seek 1150ul @18:37:18.15 volL1 = 300.0 and volL2 = 300.0 @18:37:18.16 delay 15 seconds @18:37:33.19 SPR.command "hold_off" @18:37:36.63 Waiting up to 25:00 for SPR to enter state 7 @18:49:13.15 SPR Analysis has Completed @18:49:13.16 Handoff.select :waste @18:49:14.07 Ended log /var/log/esp/spr19aug1618h.spr @18:49:14.08 SPR.command "stats_off" @18:49:15.61 -> Cmd.stop @18:49:15.70 Handoff.select :close @18:49:15.83 Gate.power :heat,:OFF @18:49:15.85 Gate.power :cartridge,:OFF @18:49:15.93 Carousel.seek 52 @18:49:16.11 Plunge.seek :locked @18:49:16.95 PinPort.seek :clear @18:49:21.57 Archive.seek :clear @18:49:21.70 Air.reconfigure :default @18:49:21.84 Lysis1.reconfigure :default @18:49:21.95 Air.seek :clear @18:49:22.02 Lysis1.seek :clear @18:49:22.10 Lysis2.reconfigure :default @18:49:22.18 Lysis2.seek :clear @18:49:39.34 Archive.coast @18:49:39.39 Air.coast @18:49:39.44 Lysis1.coast @18:49:39.49 Lysis2.coast @18:49:39.54 Plunge.coast @18:49:39.59 SamplePump.coast @18:49:39.64 Toroid.coast @18:49:39.69 Carousel.coast @18:49:39.75 PinPort.coast @18:49:39.93 Gate.power :motor,:OFF @18:49:39.95 Gate.power :core,:OFF @18:49:39.97 Gate.power :analytic1,:OFF @18:49:39.99 Gate.power :analytic2,:OFF @18:49:40.01 Gate.power :analytic3,:OFF @18:49:40.03 Gate.power :main,:OFF @18:49:40.05 Safely stopped and ready to power off