@21:48:10.53EDT24-Aug-18
>BREAK< ESP3 Gateway v0.99 for core bus @21:48:10.55 >BREAK< ESP3 Gateway v0.99 for cartridge bus @21:48:11.82 Configuration for honu by brent@mbari.org /home/esp/esp2/type/auv/honu/configure.rb @21:48:13.02 Polling Can every 5:00 @21:48:13.32 Clients may connect on TCP port 9999 @21:48:13.33 Connecting to 10.89.5.2:9999 @21:48:13.37 Waiting for espclients... @21:48:13.43 Session Begun @21:48:15.78 -> showStatus @21:48:17.40 -> Cmd.loadCartridge @21:48:17.52 Gate.power :main,:ON,limit to 5000mA @21:48:17.53 Gate.power :analytic1,:ON,limit to 5000mA @21:48:17.55 Gate.power :analytic2,:ON,limit to 5000mA @21:48:17.58 Gate.power :analytic3,:ON,limit to 5000mA @21:48:17.71 Powering core bus @21:48:20.01 SPR Module 1, FW Rev. B, FPGA Rev. C @21:48:20.13 ArS.configure ArSconfig @21:48:20.23 LyS1.configure LyS1config @21:48:20.35 LyS2.configure LyS2config @21:48:20.46 AirS.configure AirSconfig @21:48:20.58 TV.configure TVconfig @21:48:20.70 SP.configure SPconfig @21:48:20.83 SC.configure SCconfig @21:48:20.94 PV.configure PVconfig @21:48:21.06 PinPort.configure PPconfig @21:48:21.18 Sea.configure SPconfig @21:48:21.23 All elf controllers running firmware version 4.5 @21:48:21.26 Gate.power :motor,:ON,limit to 5000mA @21:48:21.55 ArS.reconfigure ArSconfig @21:48:21.64 LyS1.reconfigure LyS1config @21:48:21.77 LyS2.reconfigure LyS2config @21:48:21.81 AirS.reconfigure AirSconfig @21:48:21.91 ArS.home.jog 0 @21:48:21.95 TV.reconfigure TVconfig @21:48:22.02 PV.reconfigure PVconfig @21:48:22.08 LyS1.home.jog 0 @21:48:22.12 PinPort.reconfigure PPconfig @21:48:22.20 LyS2.home.jog 0 @21:48:22.26 AirS.home.jog 0 @21:48:22.36 TV.home.jog 0 @21:48:22.38 PV.home.jog 0 @21:48:22.44 PinPort.home.jog 0 @21:48:23.08 LyS2.reconfigure HomeSyringe @21:48:23.11 LyS1.reconfigure HomeSyringe @21:48:23.16 PinPort.reconfigure HomePP @21:48:23.19 PV.reconfigure HomePV @21:48:23.23 TV.reconfigure HomeTV @21:48:23.26 AirS.reconfigure HomeMagSyringe @21:48:23.31 ArS.reconfigure HomeMagSyringe @21:48:23.48 LyS2.jog -270 @21:48:23.54 LyS1.jog -263 @21:48:23.57 PV.jog -1477 @21:48:23.61 PinPort.jog -109 @21:48:23.63 TV.jog -555 @21:48:23.67 AirS.jog -322 @21:48:23.71 ArS.jog -258 @21:48:24.16 PinPort.setRawPosition! 0 @21:48:24.20 PinPort.seek :home @21:48:24.32 LyS2.setRawPosition! -50 @21:48:24.34 PinPort.reconfigure PPconfig @21:48:24.36 LyS1.setRawPosition! -50 @21:48:24.48 LyS1.seek :home @21:48:24.52 LyS2.seek :home @21:48:24.89 AirS.setRawPosition! -50 @21:48:24.91 ArS.setRawPosition! -50 @21:48:25.00 AirS.seek :home @21:48:25.03 ArS.seek :home @21:48:25.20 LyS2.reconfigure LyS2config @21:48:25.22 LyS1.reconfigure LyS1config @21:48:25.26 PV.setRawPosition! -50 @21:48:25.39 PV.seek :home @21:48:25.50 ArS.reconfigure ArSconfig @21:48:25.52 AirS.reconfigure AirSconfig @21:48:25.69 PV.reconfigure PVconfig @21:48:25.77 TV.setRawPosition! 0 @21:48:25.83 TV.seek :home @21:48:25.93 TV.reconfigure TVconfig @21:48:26.10 TV.seek :clear @21:48:27.72 TV.coast @21:48:27.78 SC.reconfigure HomeSC @21:48:27.84 SC.home.jog 0 @21:48:28.60 SC.reconfigure SCconfig @21:48:28.66 SC.seek 50 @21:48:28.76 Selecting Cartridge 49 @21:48:29.07 SC.seek 49 @21:48:29.57 LyS2.seek :locked @21:48:29.70 PV.seek :locked @21:48:29.83 AirS.seek :locked @21:48:33.91 Powering cartridge bus @21:48:36.04 Gate.setIR 1750,5000,0,6 @21:48:36.16 Gate.setIR 0,5000,0,6 @21:48:36.21 Loaded Lyse-n-Go Cartridge @21:48:36.22 PuckHeater.configure HeatConfig @21:48:36.27 Gate.power :heat,:ON,limit to 5000mA @21:48:56.15 -> Cmd.startFiltering @21:48:56.37 Duration of filtering limited to 1:05:00 @21:48:56.63 Priming sample loop w/100ml, bypass w/10ml @21:48:56.70 SP.setPosition! 0ml @21:48:56.75 Intake.open @21:48:56.78 Exhaust.open @21:48:57.58 Sea @-42.9psia @21:48:57.60 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi @21:49:03.12 Vacuum tare is 24% of full scale @21:49:03.14 PB.open @21:49:03.17 Exhaust.close @21:49:04.26 Sea @-42.9psia @21:49:04.35 SP.reconfigure SPprime @21:49:04.41 SP.seek 10ml,1:21 @21:49:15.28 SP.setPosition! 0ml @21:49:15.32 PB.close @21:49:15.35 Exhaust.open @21:49:16.19 Sea @-42.9psia @21:49:16.28 SP.reconfigure SPprime @21:49:16.34 SP.seek 100ml,8:51 @21:51:11.23 Intake.close @21:51:11.95 Pressurizing cartridge to 26psi (try #1 of 3) @21:51:11.96 SP.setPosition! 0ml @21:51:12.01 PB.open @21:51:12.81 TV.seek :crack @21:51:13.60 TV.seek :clear @21:51:14.39 Intake.open @21:51:15.88 PB.close @21:51:16.56 Exhaust.close @21:51:17.32 Sea @-42.9psia @21:51:17.40 SP.reconfigure SPcharge @21:51:17.47 SP.seek 2.3ml,42 seconds @21:51:28.59 Cartridge pressurized with 0.578ml to 26.2psi @21:51:28.96 delay 3 seconds @21:51:32.03 Cartridge at 22.2psi 3 seconds later @21:51:32.11 TV.seek :cartridge @21:51:33.93 TV.seek :clear @21:51:35.75 Sampling 1000.0ml @21:51:35.76 SP.setPosition! 0ml @21:51:35.81 Exhaust.open @21:51:36.56 Sea @-42.9psia @21:51:36.65 SP.reconfigure SPsample @21:51:36.71 SP.seek 1000ml,1:23:53 @21:51:37.67 Cartridge 49 sampling 0/1000ml at 0.17ml/s with 5.7psi vacuum @21:52:37.68 Cartridge 49 sampling 32/1000ml at 0.52ml/s with 27.8psi vacuum @21:53:37.68 Cartridge 49 sampling 63/1000ml at 0.53ml/s with 23.1psi vacuum @21:54:37.68 Cartridge 49 sampling 95/1000ml at 0.52ml/s with 21.6psi vacuum @21:55:37.68 Cartridge 49 sampling 125/1000ml at 0.53ml/s with 24.0psi vacuum @21:56:37.68 Cartridge 49 sampling 156/1000ml at 0.49ml/s with 20.5psi vacuum @21:57:37.68 Cartridge 49 sampling 186/1000ml at 0.49ml/s with 24.9psi vacuum @21:58:37.68 Cartridge 49 sampling 216/1000ml at 0.51ml/s with 22.7psi vacuum @21:59:37.68 Cartridge 49 sampling 246/1000ml at 0.50ml/s with 21.5psi vacuum @22:00:37.68 Cartridge 49 sampling 275/1000ml at 0.47ml/s with 18.9psi vacuum @22:01:37.68 Cartridge 49 sampling 303/1000ml at 0.48ml/s with 24.1psi vacuum @22:02:37.72 Cartridge 49 sampling 330/1000ml at 0.42ml/s with 20.4psi vacuum @22:03:37.68 Cartridge 49 sampling 357/1000ml at 0.44ml/s with 21.9psi vacuum @22:04:37.68 Cartridge 49 sampling 382/1000ml at 0.43ml/s with 24.4psi vacuum @22:05:37.68 Cartridge 49 sampling 406/1000ml at 0.42ml/s with 25.7psi vacuum @22:06:37.68 Cartridge 49 sampling 429/1000ml at 0.39ml/s with 24.2psi vacuum @22:07:37.68 Cartridge 49 sampling 451/1000ml at 0.37ml/s with 22.0psi vacuum @22:08:37.68 Cartridge 49 sampling 471/1000ml at 0.35ml/s with 23.3psi vacuum @22:09:37.68 Cartridge 49 sampling 489/1000ml at 0.27ml/s with 23.3psi vacuum @22:10:37.68 Cartridge 49 sampling 507/1000ml at 0.27ml/s with 25.1psi vacuum @22:11:37.68 Cartridge 49 sampling 523/1000ml at 0.26ml/s with 18.6psi vacuum @22:12:37.68 Cartridge 49 sampling 538/1000ml at 0.26ml/s with 23.0psi vacuum @22:13:37.68 Cartridge 49 sampling 552/1000ml at 0.24ml/s with 24.7psi vacuum @22:14:37.68 Cartridge 49 sampling 565/1000ml at 0.20ml/s with 23.8psi vacuum @22:15:37.68 Cartridge 49 sampling 578/1000ml at 0.20ml/s with 23.2psi vacuum @22:16:37.68 Cartridge 49 sampling 590/1000ml at 0.21ml/s with 25.6psi vacuum @22:17:37.68 Cartridge 49 sampling 602/1000ml at 0.20ml/s with 24.4psi vacuum @22:18:37.68 Cartridge 49 sampling 614/1000ml at 0.20ml/s with 26.5psi vacuum @22:19:37.68 Cartridge 49 sampling 626/1000ml at 0.20ml/s with 24.9psi vacuum @22:19:45.14 Flow averaged 0.37ml/s over 28:02 @22:19:45.17 Waiting up to 15 seconds for vacuum to reduce from 28.8 to 18.0psi @22:19:45.79 Relieved excess vacuum in 8 seconds @22:19:45.82 SP.seek 1000ml,31:32 @22:20:08.65 Flow averaged 0.34ml/s over 14 seconds @22:20:08.67 Waiting up to 15 seconds for vacuum to reduce from 28.6 to 18.0psi @22:20:09.29 Relieved excess vacuum in 10.4 seconds @22:20:09.32 SP.seek 1000ml,31:10 @22:20:11.76 Flow estimate reduced to 0.27ml/s @22:20:11.79 Waiting up to 12 seconds for vacuum to reduce from 27.5 to 18.0psi @22:20:12.40 Relieved excess vacuum in 3 seconds @22:20:12.43 SP.seek 1000ml,31:08 @22:20:27.22 Flow averaged 0.22ml/s over 14 seconds @22:20:27.24 Waiting up to 11 seconds for vacuum to reduce from 27.2 to 18.0psi @22:20:27.85 Relieved excess vacuum in 2.3 seconds @22:20:27.88 SP.seek 1000ml,30:53 @22:20:37.68 Cartridge 49 sampling 637/1000ml at 0.20ml/s with 30.5psi vacuum @22:20:44.04 Flow averaged 0.32ml/s over 10 seconds @22:20:44.06 Waiting up to 14 seconds for vacuum to reduce from 28.5 to 18.0psi @22:20:44.67 Relieved excess vacuum in 6.9 seconds @22:20:44.70 SP.seek 1000ml,30:38 @22:20:46.97 Flow estimate reduced to 0.26ml/s @22:20:47.00 Waiting up to 10 seconds for vacuum to reduce from 27.0 to 18.0psi @22:20:47.61 Relieved excess vacuum in 2.8 seconds @22:20:47.64 SP.seek 1000ml,30:36 @22:20:50.06 Flow estimate reduced to 0.20ml/s @22:20:50.09 Waiting up to 10 seconds for vacuum to reduce from 26.6 to 18.0psi @22:20:50.70 Relieved excess vacuum in 2.9 seconds @22:20:50.73 SP.seek 1000ml,30:34 @22:21:02.35 Flow estimate reduced to 0.16ml/s @22:21:02.37 Intake.close @22:21:02.41 Exhaust.close @22:21:03.21 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged @22:21:03.31 Sampled 641.7ml @22:21:03.38 TV.seek :bypass @22:21:05.00 TV.seek :clear @22:21:07.40 -> Cmd.startProcessing @22:21:09.19 SPR.command "read_sequences" @22:21:09.22 SPR.command "stats_on" @22:21:09.26 SPR.command "start 0 1" @22:21:11.27 Begun log /var/log/esp/spr18aug2422h.spr @22:21:12.00 Handoff.select :waste @22:21:12.12 ArS.seek empty @22:21:30.11 PinPort.seek :cartridge @22:21:35.82 LyS1.seek 1350ul @22:21:41.77 PV.seek :reagent @22:21:42.61 AirS.seek :tVent @22:21:44.68 delay 2 seconds @22:21:46.70 AirS.reconfigure EvacAirS @22:21:46.76 AirS.seek :evac @22:22:09.08 delay 15 seconds @22:22:24.10 AirS.reconfigure AirSconfig @22:22:24.16 AirS.seek :tVent @22:22:38.38 delay 2 seconds @22:22:40.42 AirS.reconfigure EvacAirS @22:22:40.49 AirS.seek :evac @22:23:02.79 delay 15 seconds @22:23:17.82 AirS.reconfigure AirSconfig @22:23:17.88 AirS.seek :tVent @22:23:32.10 delay 2 seconds @22:23:34.12 AirS.reconfigure EvacAirS @22:23:34.19 AirS.seek :evac @22:23:56.50 delay 15 seconds @22:24:11.52 AirS.reconfigure AirSconfig @22:24:11.58 AirS.seek :tVent @22:24:25.79 delay 2 seconds @22:24:27.82 AirS.reconfigure EvacAirS @22:24:27.88 AirS.seek :evac @22:24:50.17 delay 15 seconds @22:25:05.20 AirS.reconfigure AirSconfig @22:25:05.26 AirS.seek :tVent @22:25:19.47 delay 2 seconds @22:25:21.50 AirS.reconfigure EvacAirS @22:25:21.57 AirS.seek :evac @22:25:43.87 delay 15 seconds @22:25:58.90 delay 1:30 @22:27:28.93 AirS.reconfigure AirSconfig @22:27:29.03 AirS.seek :fVent @22:27:30.46 delay 15 seconds @22:27:45.49 LyS1.reconfigure SlowLyS1 @22:27:45.59 LyS1.seek 1050ul @22:28:15.63 delay 5 seconds @22:28:20.65 Handoff.select :blocked @22:28:20.81 LyS1.seek 450ul @22:29:20.78 delay 10 seconds @22:29:30.81 AirS.reconfigure SlowAirS @22:29:30.93 AirS.reconfigure to mix into 1.37806 parts LyS1 @22:29:31.32 AirS.seek evac @22:29:31.35 LyS1.seek 300ul @22:29:46.45 AirS.reconfigure AirSconfig @22:29:46.51 AirS.seek :full @22:30:00.74 LyS1.seek :empty @22:30:30.82 PuckHeater.seek 84.2C @22:31:35.34 PuckHeater.hold 15:00,84.2C @22:46:35.47 LyS1.reconfigure LyS1config @22:46:35.53 LyS1.seek 900ul @22:46:43.87 AirS.seek :tVent @22:46:43.95 delay 2 seconds @22:46:45.97 AirS.reconfigure HalfSpeedAirS @22:46:46.03 AirS.seek :evac @22:47:13.83 delay 15 seconds @22:47:28.85 AirS.reconfigure AirSconfig @22:47:28.91 AirS.seek :tVent @22:47:43.13 delay 2 seconds @22:47:45.15 AirS.reconfigure HalfSpeedAirS @22:47:45.21 AirS.seek :evac @22:48:12.98 delay 15 seconds @22:48:28.01 AirS.reconfigure AirSconfig @22:48:28.07 AirS.seek :tVent @22:48:42.28 delay 2 seconds @22:48:44.31 AirS.reconfigure HalfSpeedAirS @22:48:44.37 AirS.seek :evac @22:49:12.12 delay 15 seconds @22:49:27.14 delay 1:00 @22:50:27.17 Handoff.select :waste @22:50:27.27 delay 15 seconds @22:50:42.29 AirS.seek :fVent @22:50:44.49 delay 10 seconds @22:50:54.51 AirS.reconfigure AirSconfig @22:50:54.56 AirS.seek :evac @22:50:56.01 Lysis Recovered in LyS1 Lysis Complete @22:50:56.03 Handoff.select :waste @22:50:56.06 LyS2.seek :full @22:50:56.14 LyS1.reconfigure DAmixLyS1 @22:50:56.20 LyS2.reconfigure DAmixLyS2 @22:50:56.61 LyS2.seek 1480ul @22:50:56.65 LyS1.seek 650ul @22:51:43.98 delay 5 seconds @22:51:49.00 Handoff.select :SPR @22:51:49.12 SPR.command "start 2 14" @22:51:49.74 Running Experiment 2 @22:51:49.75 delay 10 seconds @22:51:59.77 SPR.command "start 2 0" @22:52:03.10 Waiting up to 11:00 for SPR to enter state 1 @22:59:21.39 SPR.command "hold_on" @22:59:23.09 LyS2.seek 1130ul @22:59:23.12 LyS1.seek 300ul @23:00:29.32 delay 15 seconds @23:00:44.34 SPR.command "hold_off" @23:00:47.09 Waiting up to 25:00 for SPR to enter state 7 @23:12:23.59 Handoff.select :waste @23:12:23.71 LyS1.reconfigure LyS1config @23:12:23.77 LyS2.reconfigure LyS2config @23:12:23.83 Rep #1 SPR Analysis has Completed @23:12:24.52 Ended log /var/log/esp/spr18aug2422h.spr @23:12:24.54 SPR.command "stats_off" @23:12:30.27 -> Cmd.stop @23:12:30.36 Handoff.select :close @23:12:30.54 Gate.power :heat,:OFF @23:12:30.56 Gate.power :cartridge,:OFF @23:12:30.58 PV.seek :locked @23:12:31.48 PinPort.seek :clear @23:12:37.25 ArS.seek :clear @23:12:37.62 LyS1.seek :clear @23:12:37.75 LyS2.seek :clear @23:12:37.89 AirS.seek :clear @23:12:55.24 ArS.coast @23:12:55.29 LyS1.coast @23:12:55.34 LyS2.coast @23:12:55.39 AirS.coast @23:12:55.45 TV.coast @23:12:55.49 SP.coast @23:12:55.54 SC.coast @23:12:55.60 PV.coast @23:12:55.65 PinPort.coast @23:12:55.84 Gate.power :motor,:OFF @23:12:55.85 Gate.power :main,:OFF @23:12:55.88 Gate.power :core,:OFF @23:12:55.89 Gate.power :analytic1,:OFF @23:12:55.92 Gate.power :analytic2,:OFF @23:12:55.94 Gate.power :analytic3,:OFF @23:12:55.96 Safely stopped and ready to power off