@06:33:11.43EDT26-Aug-18
>BREAK< ESP3 Gateway v0.99 for core bus @06:33:11.45 >BREAK< ESP3 Gateway v0.99 for cartridge bus @06:33:12.64 Configuration for honu by brent@mbari.org /home/esp/esp2/type/auv/honu/configure.rb @06:33:13.83 Polling Can every 5:00 @06:33:14.13 Clients may connect on TCP port 9999 @06:33:14.14 Connecting to 10.89.5.2:9999 @06:33:14.18 Waiting for espclients... @06:33:14.41 Session Begun @06:33:16.36 -> showStatus @06:33:18.35 -> Cmd.loadCartridge @06:33:18.47 Gate.power :main,:ON,limit to 5000mA @06:33:18.49 Gate.power :analytic1,:ON,limit to 5000mA @06:33:18.51 Gate.power :analytic2,:ON,limit to 5000mA @06:33:18.53 Gate.power :analytic3,:ON,limit to 5000mA @06:33:18.67 Powering core bus @06:33:20.97 SPR Module 1, FW Rev. B, FPGA Rev. C @06:33:21.07 ArS.configure ArSconfig @06:33:21.17 LyS1.configure LyS1config @06:33:21.29 LyS2.configure LyS2config @06:33:21.39 AirS.configure AirSconfig @06:33:21.51 TV.configure TVconfig @06:33:21.63 SP.configure SPconfig @06:33:21.75 SC.configure SCconfig @06:33:21.89 PV.configure PVconfig @06:33:22.05 PinPort.configure PPconfig @06:33:22.17 Sea.configure SPconfig @06:33:22.23 All elf controllers running firmware version 4.5 @06:33:22.25 Gate.power :motor,:ON,limit to 5000mA @06:33:22.69 LyS2.reconfigure LyS2config @06:33:22.74 AirS.reconfigure AirSconfig @06:33:22.77 LyS1.reconfigure LyS1config @06:33:22.80 TV.reconfigure TVconfig @06:33:22.84 ArS.reconfigure ArSconfig @06:33:23.01 LyS2.home.jog 0 @06:33:23.04 LyS1.home.jog 0 @06:33:23.07 TV.home.jog 0 @06:33:23.09 AirS.home.jog 0 @06:33:23.12 ArS.home.jog 0 @06:33:23.41 PinPort.reconfigure PPconfig @06:33:23.44 PV.reconfigure PVconfig @06:33:23.67 PinPort.home.jog 0 @06:33:23.70 PV.home.jog 0 @06:33:23.93 LyS2.reconfigure HomeSyringe @06:33:24.02 AirS.reconfigure HomeMagSyringe @06:33:24.07 TV.reconfigure HomeTV @06:33:24.10 LyS1.reconfigure HomeSyringe @06:33:24.24 LyS2.jog -275 @06:33:24.27 AirS.jog -328 @06:33:24.31 TV.jog -557 @06:33:24.34 LyS1.jog -271 @06:33:24.56 ArS.reconfigure HomeMagSyringe @06:33:24.58 PinPort.reconfigure HomePP @06:33:24.61 PV.reconfigure HomePV @06:33:24.72 PinPort.jog -110 @06:33:24.77 PV.jog -1487 @06:33:24.83 ArS.jog -253 @06:33:24.99 AirS.setRawPosition! -50 @06:33:25.01 LyS2.setRawPosition! -50 @06:33:25.04 LyS1.setRawPosition! -50 @06:33:25.14 AirS.seek :home @06:33:25.17 LyS2.seek :home @06:33:25.25 LyS1.seek :home @06:33:25.66 PinPort.setRawPosition! 0 @06:33:25.69 ArS.setRawPosition! -50 @06:33:25.72 AirS.reconfigure AirSconfig @06:33:25.78 PinPort.seek :home @06:33:25.87 ArS.seek :home @06:33:26.06 PinPort.reconfigure PPconfig @06:33:26.08 PV.setRawPosition! -50 @06:33:26.10 LyS2.reconfigure LyS2config @06:33:26.14 LyS1.reconfigure LyS1config @06:33:26.31 PV.seek :home @06:33:26.43 TV.setRawPosition! 0 @06:33:26.45 ArS.reconfigure ArSconfig @06:33:26.52 TV.seek :home @06:33:26.67 TV.reconfigure TVconfig @06:33:26.69 PV.reconfigure PVconfig @06:33:27.01 TV.seek :clear @06:33:28.63 TV.coast @06:33:28.69 SC.reconfigure HomeSC @06:33:28.75 SC.home.jog 0 @06:33:29.51 SC.reconfigure SCconfig @06:33:29.57 SC.seek 47 @06:33:29.64 Selecting Cartridge 46 @06:33:29.93 SC.seek 46 @06:33:30.44 LyS2.seek :locked @06:33:30.57 PV.seek :locked @06:33:30.71 AirS.seek :locked @06:33:34.79 Powering cartridge bus @06:33:36.92 Gate.setIR 1750,5000,0,6 @06:33:37.04 Gate.setIR 0,5000,0,6 @06:33:37.09 Loaded Lyse-n-Go Cartridge @06:33:37.10 PuckHeater.configure HeatConfig @06:33:37.16 Gate.power :heat,:ON,limit to 5000mA @06:33:57.54 -> Cmd.startFiltering @06:33:57.77 Duration of filtering limited to 1:05:00 @06:33:58.03 Priming sample loop w/100ml, bypass w/10ml @06:33:58.11 SP.setPosition! 0ml @06:33:58.16 Intake.open @06:33:58.20 Exhaust.open @06:33:58.99 Sea @-42.9psia @06:33:59.01 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi @06:34:04.79 Vacuum tare is 24% of full scale @06:34:04.80 PB.open @06:34:04.84 Exhaust.close @06:34:05.65 Sea @-42.9psia @06:34:05.73 SP.reconfigure SPprime @06:34:05.80 SP.seek 10ml,1:21 @06:34:16.66 SP.setPosition! 0ml @06:34:16.70 PB.close @06:34:16.73 Exhaust.open @06:34:17.54 Sea @-42.9psia @06:34:17.62 SP.reconfigure SPprime @06:34:17.69 SP.seek 100ml,8:51 @06:36:12.74 Intake.close @06:36:13.47 Pressurizing cartridge to 26psi (try #1 of 3) @06:36:13.48 SP.setPosition! 0ml @06:36:13.53 PB.open @06:36:14.30 TV.seek :crack @06:36:15.10 TV.seek :clear @06:36:15.90 Intake.open @06:36:17.35 PB.close @06:36:18.07 Exhaust.close @06:36:18.84 Sea @-42.9psia @06:36:18.92 SP.reconfigure SPcharge @06:36:18.99 SP.seek 2.3ml,42 seconds @06:36:28.06 Cartridge pressurized with 0.383ml to 27.3psi @06:36:28.45 delay 3 seconds @06:36:31.51 Cartridge at 22.0psi 3 seconds later @06:36:31.60 TV.seek :cartridge @06:36:33.42 TV.seek :clear @06:36:35.24 Sampling 1000.0ml @06:36:35.25 SP.setPosition! 0ml @06:36:35.30 Exhaust.open @06:36:36.03 Sea @-42.9psia @06:36:36.12 SP.reconfigure SPsample @06:36:36.18 SP.seek 1000ml,1:23:53 @06:36:37.14 Cartridge 46 sampling 0/1000ml at 0.17ml/s with 3.6psi vacuum @06:37:37.15 Cartridge 46 sampling 31/1000ml at 0.55ml/s with 24.7psi vacuum @06:38:37.15 Cartridge 46 sampling 63/1000ml at 0.52ml/s with 21.2psi vacuum @06:39:37.15 Cartridge 46 sampling 93/1000ml at 0.47ml/s with 21.2psi vacuum @06:40:37.15 Cartridge 46 sampling 123/1000ml at 0.49ml/s with 18.2psi vacuum @06:41:37.15 Cartridge 46 sampling 153/1000ml at 0.48ml/s with 18.5psi vacuum @06:42:37.15 Cartridge 46 sampling 182/1000ml at 0.47ml/s with 26.6psi vacuum @06:43:37.15 Cartridge 46 sampling 210/1000ml at 0.45ml/s with 17.4psi vacuum @06:44:37.15 Cartridge 46 sampling 238/1000ml at 0.47ml/s with 21.8psi vacuum @06:45:37.15 Cartridge 46 sampling 264/1000ml at 0.42ml/s with 27.0psi vacuum @06:46:37.15 Cartridge 46 sampling 289/1000ml at 0.43ml/s with 22.1psi vacuum @06:47:37.15 Cartridge 46 sampling 313/1000ml at 0.36ml/s with 24.8psi vacuum @06:48:37.15 Cartridge 46 sampling 335/1000ml at 0.36ml/s with 25.7psi vacuum @06:49:37.15 Cartridge 46 sampling 356/1000ml at 0.34ml/s with 24.7psi vacuum @06:50:37.15 Cartridge 46 sampling 374/1000ml at 0.31ml/s with 26.1psi vacuum @06:51:37.15 Cartridge 46 sampling 391/1000ml at 0.29ml/s with 24.2psi vacuum @06:52:37.17 Cartridge 46 sampling 407/1000ml at 0.23ml/s with 21.9psi vacuum @06:53:37.15 Cartridge 46 sampling 421/1000ml at 0.26ml/s with 21.1psi vacuum @06:54:37.15 Cartridge 46 sampling 434/1000ml at 0.23ml/s with 20.8psi vacuum @06:55:37.15 Cartridge 46 sampling 446/1000ml at 0.20ml/s with 25.4psi vacuum @06:56:37.15 Cartridge 46 sampling 459/1000ml at 0.20ml/s with 27.7psi vacuum @06:57:37.15 Cartridge 46 sampling 471/1000ml at 0.20ml/s with 24.3psi vacuum @06:58:06.97 Flow averaged 0.37ml/s over 21:22 @06:58:06.99 Waiting up to 12 seconds for vacuum to reduce from 27.7 to 18.0psi @06:58:07.62 Relieved excess vacuum in 10.4 seconds @06:58:07.64 SP.seek 1000ml,44:07 @06:58:37.15 Cartridge 46 sampling 483/1000ml at 0.20ml/s with 27.5psi vacuum @06:58:39.62 Flow averaged 0.21ml/s over 30 seconds @06:58:39.64 Waiting up to 16 seconds for vacuum to reduce from 29.4 to 18.0psi @06:58:40.27 Relieved excess vacuum in 3 seconds @06:58:40.29 SP.seek 1000ml,43:36 @06:58:55.36 Flow averaged 0.22ml/s over 14 seconds @06:58:55.38 Waiting up to 14 seconds for vacuum to reduce from 28.3 to 18.0psi @06:58:56.00 Relieved excess vacuum in 2.6 seconds @06:58:56.02 SP.seek 1000ml,43:21 @06:59:10.58 Flow averaged 0.22ml/s over 14 seconds @06:59:10.60 Waiting up to 16 seconds for vacuum to reduce from 29.3 to 18.0psi @06:59:11.23 Relieved excess vacuum in 2.1 seconds @06:59:11.25 SP.seek 1000ml,43:07 @06:59:13.51 Flow estimate reduced to 0.17ml/s @06:59:13.53 Intake.close @06:59:13.58 Exhaust.close @06:59:14.36 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged @06:59:14.46 Sampled 489.3ml @06:59:14.53 TV.seek :bypass @06:59:16.16 TV.seek :clear @06:59:19.06 -> Cmd.startProcessing @06:59:20.84 SPR.command "read_sequences" @06:59:21.50 SPR.command "stats_on" @06:59:21.54 SPR.command "start 0 1" @06:59:23.55 Begun log /var/log/esp/spr18aug2606h.spr @06:59:24.35 Handoff.select :waste @06:59:24.47 ArS.seek empty @06:59:42.18 PinPort.seek :cartridge @06:59:47.89 LyS1.seek 1350ul @06:59:53.84 PV.seek :reagent @06:59:54.69 AirS.seek :tVent @06:59:56.76 delay 2 seconds @06:59:58.78 AirS.reconfigure EvacAirS @06:59:58.84 AirS.seek :evac @07:00:21.16 delay 15 seconds @07:00:36.18 AirS.reconfigure AirSconfig @07:00:36.24 AirS.seek :tVent @07:00:50.46 delay 2 seconds @07:00:52.48 AirS.reconfigure EvacAirS @07:00:52.54 AirS.seek :evac @07:01:15.11 delay 15 seconds @07:01:30.13 AirS.reconfigure AirSconfig @07:01:30.19 AirS.seek :tVent @07:01:44.41 delay 2 seconds @07:01:46.43 AirS.reconfigure EvacAirS @07:01:46.49 AirS.seek :evac @07:02:08.80 delay 15 seconds @07:02:23.82 AirS.reconfigure AirSconfig @07:02:23.88 AirS.seek :tVent @07:02:38.11 delay 2 seconds @07:02:40.13 AirS.reconfigure EvacAirS @07:02:40.19 AirS.seek :evac @07:03:02.50 delay 15 seconds @07:03:17.52 AirS.reconfigure AirSconfig @07:03:17.58 AirS.seek :tVent @07:03:31.81 delay 2 seconds @07:03:33.83 AirS.reconfigure EvacAirS @07:03:33.89 AirS.seek :evac @07:03:56.20 delay 15 seconds @07:04:11.22 delay 1:30 @07:05:41.25 AirS.reconfigure AirSconfig @07:05:41.32 AirS.seek :fVent @07:05:42.76 delay 15 seconds @07:05:57.78 LyS1.reconfigure SlowLyS1 @07:05:57.89 LyS1.seek 1050ul @07:06:27.93 delay 5 seconds @07:06:32.96 Handoff.select :blocked @07:06:33.13 LyS1.seek 450ul @07:07:33.17 delay 10 seconds @07:07:43.19 AirS.reconfigure SlowAirS @07:07:43.32 AirS.reconfigure to mix into 1.37806 parts LyS1 @07:07:43.66 AirS.seek evac @07:07:43.70 LyS1.seek 300ul @07:07:58.82 AirS.reconfigure AirSconfig @07:07:58.88 AirS.seek :full @07:08:13.09 LyS1.seek :empty @07:08:43.17 PuckHeater.seek 84.3C @07:09:40.02 PuckHeater.hold 15:00,84.3C @07:24:39.41 LyS1.reconfigure LyS1config @07:24:39.47 LyS1.seek 900ul @07:24:47.81 AirS.seek :tVent @07:24:47.89 delay 2 seconds @07:24:49.91 AirS.reconfigure HalfSpeedAirS @07:24:49.97 AirS.seek :evac @07:25:17.71 delay 15 seconds @07:25:32.73 AirS.reconfigure AirSconfig @07:25:32.79 AirS.seek :tVent @07:25:47.27 delay 2 seconds @07:25:49.29 AirS.reconfigure HalfSpeedAirS @07:25:49.35 AirS.seek :evac @07:26:17.08 delay 15 seconds @07:26:32.11 AirS.reconfigure AirSconfig @07:26:32.17 AirS.seek :tVent @07:26:46.39 delay 2 seconds @07:26:48.41 AirS.reconfigure HalfSpeedAirS @07:26:48.47 AirS.seek :evac @07:27:16.22 delay 15 seconds @07:27:31.25 delay 1:00 @07:28:31.27 Handoff.select :waste @07:28:31.38 delay 15 seconds @07:28:46.40 AirS.seek :fVent @07:28:48.60 delay 10 seconds @07:28:58.62 AirS.reconfigure AirSconfig @07:28:58.67 AirS.seek :evac @07:29:00.11 Lysis Recovered in LyS1 @07:29:00.13 Lysis Complete @07:29:00.15 Handoff.select :waste @07:29:00.18 LyS2.seek :full @07:29:00.26 LyS1.reconfigure DAmixLyS1 @07:29:00.32 LyS2.reconfigure DAmixLyS2 @07:29:00.71 LyS2.seek 1480ul @07:29:00.75 LyS1.seek 650ul @07:29:48.06 delay 5 seconds @07:29:53.08 Handoff.select :SPR @07:29:53.21 SPR.command "start 2 14" @07:29:54.53 Running Experiment 2 @07:29:54.54 delay 10 seconds @07:30:04.56 SPR.command "start 2 0" @07:30:07.89 Waiting up to 11:00 for SPR to enter state 1