@11:25:53.93EDT29-Aug-18
>BREAK< ESP3 Gateway v0.99 for core bus @11:25:53.95 >BREAK< ESP3 Gateway v0.99 for cartridge bus @11:25:55.21 Configuration for honu by brent@mbari.org /home/esp/esp2/type/auv/honu/configure.rb @11:25:56.49 Polling Can every 5:00 @11:25:56.79 Clients may connect on TCP port 9999 @11:25:56.80 Connecting to 10.89.5.2:9999 @11:25:56.84 Waiting for espclients... @11:25:57.28 Session Begun @11:25:59.25 -> showStatus @11:26:01.22 -> Cmd.loadCartridge @11:26:01.35 Gate.power :main,:ON,limit to 5000mA @11:26:01.36 Gate.power :analytic1,:ON,limit to 5000mA @11:26:01.39 Gate.power :analytic2,:ON,limit to 5000mA @11:26:01.41 Gate.power :analytic3,:ON,limit to 5000mA @11:26:01.55 Powering core bus @11:26:03.86 SPR Module 1, FW Rev. B, FPGA Rev. C @11:26:03.95 ArS.configure ArSconfig @11:26:04.05 LyS1.configure LyS1config @11:26:04.17 LyS2.configure LyS2config @11:26:04.27 AirS.configure AirSconfig @11:26:04.39 TV.configure TVconfig @11:26:04.50 SP.configure SPconfig @11:26:04.62 SC.configure SCconfig @11:26:04.74 PV.configure PVconfig @11:26:04.85 PinPort.configure PPconfig @11:26:04.97 Sea.configure SPconfig @11:26:05.03 All elf controllers running firmware version 4.5 @11:26:05.05 Gate.power :motor,:ON,limit to 5000mA @11:26:05.20 ArS.reconfigure ArSconfig @11:26:05.47 ArS.home.jog 0 @11:26:05.62 PV.reconfigure PVconfig @11:26:05.68 TV.reconfigure TVconfig @11:26:05.71 LyS2.reconfigure LyS2config @11:26:05.74 LyS1.reconfigure LyS1config @11:26:05.79 PinPort.reconfigure PPconfig @11:26:05.90 AirS.reconfigure AirSconfig @11:26:06.06 PV.home.jog 0 @11:26:06.13 LyS2.home.jog 0 @11:26:06.17 PinPort.home.jog 0 @11:26:06.21 TV.home.jog 0 @11:26:06.24 LyS1.home.jog 0 @11:26:06.29 AirS.home.jog 0 @11:26:06.57 ArS.reconfigure HomeMagSyringe @11:26:06.82 PV.reconfigure HomePV @11:26:06.85 TV.reconfigure HomeTV @11:26:06.90 PinPort.reconfigure HomePP @11:26:06.97 LyS2.reconfigure HomeSyringe @11:26:07.01 ArS.jog -225 @11:26:07.16 TV.jog -534 @11:26:07.22 PV.jog -1477 @11:26:07.25 PinPort.jog -111 @11:26:07.28 LyS2.jog -257 @11:26:07.49 LyS1.reconfigure HomeSyringe @11:26:07.50 AirS.reconfigure HomeMagSyringe @11:26:07.59 LyS1.jog -263 @11:26:07.62 AirS.jog -320 @11:26:07.76 PinPort.setRawPosition! 0 @11:26:07.77 ArS.setRawPosition! -50 @11:26:07.84 PinPort.seek :home @11:26:08.10 ArS.seek :home @11:26:08.28 AirS.setRawPosition! -50 @11:26:08.31 LyS1.setRawPosition! -50 @11:26:08.34 PinPort.reconfigure PPconfig @11:26:08.36 LyS2.setRawPosition! -50 @11:26:08.52 AirS.seek :home @11:26:08.56 LyS1.seek :home @11:26:08.59 LyS2.seek :home @11:26:08.81 ArS.reconfigure ArSconfig @11:26:08.82 PV.setRawPosition! -50 @11:26:08.91 PV.seek :home @11:26:09.04 AirS.reconfigure AirSconfig @11:26:09.06 LyS2.reconfigure LyS2config @11:26:09.09 LyS1.reconfigure LyS1config @11:26:09.33 TV.setRawPosition! 0 @11:26:09.35 PV.reconfigure PVconfig @11:26:09.43 TV.seek :home @11:26:09.56 TV.reconfigure TVconfig @11:26:09.75 TV.seek :clear @11:26:11.36 TV.coast @11:26:11.42 SC.reconfigure HomeSC @11:26:11.48 SC.home.jog 0 @11:26:12.22 SC.reconfigure SCconfig @11:26:12.28 SC.seek 30 @11:26:12.39 Selecting Cartridge 29 @11:26:12.69 SC.seek 29 @11:26:13.19 LyS2.seek :locked @11:26:13.31 PV.seek :locked @11:26:13.45 AirS.seek :locked @11:26:17.53 Powering cartridge bus @11:26:19.66 Gate.setIR 1750,5000,0,6 @11:26:19.78 Gate.setIR 0,5000,0,6 @11:26:19.83 Loaded Lyse-n-Go Cartridge @11:26:19.84 PuckHeater.configure HeatConfig @11:26:19.89 Gate.power :heat,:ON,limit to 5000mA @11:26:39.96 -> Cmd.startFiltering @11:26:40.20 Duration of filtering limited to 1:05:00 @11:26:40.46 Priming sample loop w/100ml, bypass w/10ml @11:26:40.53 SP.setPosition! 0ml @11:26:40.57 Intake.open @11:26:40.59 Exhaust.open @11:26:41.41 Sea @-42.9psia @11:26:41.43 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi @11:26:47.21 Vacuum tare is 24% of full scale @11:26:47.22 PB.open @11:26:47.27 Exhaust.close @11:26:48.05 Sea @-42.9psia @11:26:48.14 SP.reconfigure SPprime @11:26:48.20 SP.seek 10ml,1:21 @11:26:59.05 SP.setPosition! 0ml @11:26:59.10 PB.close @11:26:59.12 Exhaust.open @11:26:59.96 Sea @-42.9psia @11:27:00.05 SP.reconfigure SPprime @11:27:00.12 SP.seek 100ml,8:51 @11:28:53.79 Intake.close @11:28:54.49 Pressurizing cartridge to 26psi (try #1 of 3) @11:28:54.51 SP.setPosition! 0ml @11:28:54.56 PB.open @11:28:55.37 TV.seek :crack @11:28:56.16 TV.seek :clear @11:28:56.95 Intake.open @11:28:58.39 PB.close @11:28:59.13 Exhaust.close @11:28:59.92 Sea @-42.9psia @11:29:00.01 SP.reconfigure SPcharge @11:29:00.07 SP.seek 2.3ml,42 seconds @11:29:08.49 Cartridge pressurized with 0.329ml to 26.3psi @11:29:08.87 delay 3 seconds @11:29:11.94 Cartridge at 20.0psi 3 seconds later @11:29:12.02 TV.seek :cartridge @11:29:13.85 TV.seek :clear @11:29:15.67 Sampling 1000.0ml @11:29:15.68 SP.setPosition! 0ml @11:29:15.72 Exhaust.open @11:29:16.51 Sea @-42.9psia @11:29:16.60 SP.reconfigure SPsample @11:29:16.67 SP.seek 1000ml,1:23:53 @11:29:17.63 Cartridge 29 sampling 0/1000ml at 0.17ml/s with 4.9psi vacuum @11:30:17.63 Cartridge 29 sampling 33/1000ml at 0.53ml/s with 24.7psi vacuum @11:31:17.62 Cartridge 29 sampling 66/1000ml at 0.56ml/s with 22.9psi vacuum @11:32:17.63 Cartridge 29 sampling 99/1000ml at 0.56ml/s with 23.6psi vacuum @11:33:17.64 Cartridge 29 sampling 131/1000ml at 0.52ml/s with 20.9psi vacuum @11:34:17.63 Cartridge 29 sampling 162/1000ml at 0.50ml/s with 19.6psi vacuum @11:35:17.63 Cartridge 29 sampling 193/1000ml at 0.52ml/s with 22.5psi vacuum @11:36:17.62 Cartridge 29 sampling 224/1000ml at 0.49ml/s with 23.4psi vacuum @11:37:17.63 Cartridge 29 sampling 253/1000ml at 0.49ml/s with 23.7psi vacuum @11:38:17.63 Cartridge 29 sampling 282/1000ml at 0.45ml/s with 19.4psi vacuum @11:39:17.63 Cartridge 29 sampling 309/1000ml at 0.42ml/s with 23.3psi vacuum @11:40:17.64 Cartridge 29 sampling 335/1000ml at 0.40ml/s with 23.3psi vacuum @11:41:17.63 Cartridge 29 sampling 360/1000ml at 0.39ml/s with 26.7psi vacuum @11:42:17.63 Cartridge 29 sampling 384/1000ml at 0.37ml/s with 24.8psi vacuum @11:43:17.89 Cartridge 29 sampling 405/1000ml at 0.32ml/s with 26.9psi vacuum @11:44:17.64 Cartridge 29 sampling 425/1000ml at 0.27ml/s with 24.3psi vacuum @11:45:17.64 Cartridge 29 sampling 443/1000ml at 0.28ml/s with 25.8psi vacuum @11:46:17.64 Cartridge 29 sampling 459/1000ml at 0.23ml/s with 22.3psi vacuum @11:47:17.64 Cartridge 29 sampling 474/1000ml at 0.25ml/s with 18.7psi vacuum @11:48:17.64 Cartridge 29 sampling 488/1000ml at 0.21ml/s with 23.7psi vacuum @11:49:17.64 Cartridge 29 sampling 501/1000ml at 0.20ml/s with 23.0psi vacuum @11:50:17.64 Cartridge 29 sampling 514/1000ml at 0.20ml/s with 25.2psi vacuum @11:51:17.64 Cartridge 29 sampling 526/1000ml at 0.20ml/s with 23.9psi vacuum @11:52:17.64 Cartridge 29 sampling 538/1000ml at 0.20ml/s with 26.4psi vacuum @11:53:06.82 Flow averaged 0.39ml/s over 23:42 @11:53:06.85 Waiting up to 8 seconds for vacuum to reduce from 26.2 to 18.0psi @11:53:07.47 Relieved excess vacuum in 9.7 seconds @11:53:07.50 SP.seek 1000ml,38:11 @11:53:17.64 Cartridge 29 sampling 550/1000ml at 0.20ml/s with 24.1psi vacuum @11:53:42.49 Flow averaged 0.23ml/s over 31 seconds @11:53:42.51 Waiting up to 11 seconds for vacuum to reduce from 27.3 to 18.0psi @11:53:43.14 Relieved excess vacuum in 5.4 seconds @11:53:43.17 SP.seek 1000ml,37:36 @11:53:58.04 Flow averaged 0.22ml/s over 14 seconds @11:53:58.07 Waiting up to 11 seconds for vacuum to reduce from 27.1 to 18.0psi @11:53:58.68 Relieved excess vacuum in 2.4 seconds @11:53:58.71 SP.seek 1000ml,37:22 @11:54:17.64 Cartridge 29 sampling 562/1000ml at 0.20ml/s with 26.5psi vacuum @11:54:18.44 Flow averaged 0.20ml/s over 19 seconds @11:54:18.48 Waiting up to 14 seconds for vacuum to reduce from 28.6 to 18.0psi @11:54:19.10 Relieved excess vacuum in 1.4 seconds @11:54:19.13 SP.seek 1000ml,37:03 @11:54:32.52 Flow averaged 0.20ml/s over 14 seconds @11:54:32.54 Intake.close @11:54:32.59 Exhaust.close @11:54:33.37 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged @11:54:33.47 Sampled 564.3ml @11:54:33.55 TV.seek :bypass @11:54:35.17 TV.seek :clear @11:54:37.43 -> Cmd.startProcessing @11:54:39.23 SPR.command "read_sequences" @11:54:39.49 SPR.command "stats_on" @11:54:39.53 SPR.command "start 0 1" @11:54:41.53 Begun log /var/log/esp/spr18aug2911h.spr @11:54:42.34 Handoff.select :waste @11:54:42.46 ArS.seek empty @11:55:00.20 PinPort.seek :cartridge @11:55:05.91 LyS1.seek 1350ul @11:55:11.86 PV.seek :reagent @11:55:12.70 AirS.seek :tVent @11:55:14.76 delay 2 seconds @11:55:16.79 AirS.reconfigure EvacAirS @11:55:16.87 AirS.seek :evac @11:55:39.18 delay 15 seconds @11:55:54.20 AirS.reconfigure AirSconfig @11:55:54.26 AirS.seek :tVent @11:56:08.48 delay 2 seconds @11:56:10.50 AirS.reconfigure EvacAirS @11:56:10.56 AirS.seek :evac @11:56:32.85 delay 15 seconds @11:56:47.88 AirS.reconfigure AirSconfig @11:56:47.94 AirS.seek :tVent @11:57:02.14 delay 2 seconds @11:57:04.17 AirS.reconfigure EvacAirS @11:57:04.23 AirS.seek :evac @11:57:26.51 delay 15 seconds @11:57:41.54 AirS.reconfigure AirSconfig @11:57:41.62 AirS.seek :tVent @11:57:55.82 delay 2 seconds @11:57:57.85 AirS.reconfigure EvacAirS @11:57:57.90 AirS.seek :evac @11:58:20.19 delay 15 seconds @11:58:35.22 AirS.reconfigure AirSconfig @11:58:35.28 AirS.seek :tVent @11:58:49.51 delay 2 seconds @11:58:51.54 AirS.reconfigure EvacAirS @11:58:51.62 AirS.seek :evac @11:59:13.92 delay 15 seconds @11:59:28.95 delay 1:30 @12:00:58.97 AirS.reconfigure AirSconfig @12:00:59.03 AirS.seek :fVent @12:01:00.46 delay 15 seconds @12:01:15.49 LyS1.reconfigure SlowLyS1 @12:01:15.59 LyS1.seek 1050ul @12:01:45.85 delay 5 seconds @12:01:50.87 Handoff.select :blocked @12:01:51.04 LyS1.seek 450ul @12:02:51.00 delay 10 seconds @12:03:01.03 AirS.reconfigure SlowAirS @12:03:01.13 AirS.reconfigure to mix into 1.37806 parts LyS1 @12:03:01.50 AirS.seek evac @12:03:01.55 LyS1.seek 300ul @12:03:16.63 AirS.reconfigure AirSconfig @12:03:16.69 AirS.seek :full @12:03:30.91 LyS1.seek :empty @12:04:00.99 PuckHeater.seek 84.1C @12:05:02.09 PuckHeater.hold 15:00,84.1C @12:19:56.20 LyS1.reconfigure LyS1config @12:19:56.27 LyS1.seek 900ul @12:20:04.61 AirS.seek :tVent @12:20:04.71 delay 2 seconds @12:20:06.73 AirS.reconfigure HalfSpeedAirS @12:20:06.78 AirS.seek :evac @12:20:34.52 delay 15 seconds @12:20:49.55 AirS.reconfigure AirSconfig @12:20:49.61 AirS.seek :tVent @12:21:03.81 delay 2 seconds @12:21:05.84 AirS.reconfigure HalfSpeedAirS @12:21:05.89 AirS.seek :evac @12:21:33.62 delay 15 seconds @12:21:48.65 AirS.reconfigure AirSconfig @12:21:48.73 AirS.seek :tVent @12:22:02.94 delay 2 seconds @12:22:04.97 AirS.reconfigure HalfSpeedAirS @12:22:05.03 AirS.seek :evac @12:22:32.77 delay 15 seconds @12:22:47.79 delay 1:00 @12:23:47.80 Handoff.select :waste @12:23:47.91 delay 15 seconds @12:24:02.93 AirS.seek :fVent @12:24:05.12 delay 10 seconds @12:24:15.15 AirS.reconfigure AirSconfig @12:24:15.21 AirS.seek :evac @12:24:16.64 Lysis Recovered in LyS1 @12:24:16.65 Lysis Complete @12:24:16.66 Handoff.select :waste @12:24:16.69 LyS2.seek :full @12:24:16.78 LyS1.reconfigure DAmixLyS1 @12:24:16.84 LyS2.reconfigure DAmixLyS2 @12:24:17.23 LyS1.seek 650ul @12:24:17.27 LyS2.seek 1480ul @12:25:04.58 delay 5 seconds @12:25:09.60 Handoff.select :SPR @12:25:09.72 SPR.command "start 2 14" @12:25:10.64 Running Experiment 2 @12:25:10.65 delay 10 seconds @12:25:20.67 SPR.command "start 2 0" @12:25:24.00 Waiting up to 11:00 for SPR to enter state 1 @12:32:42.29 SPR.command "hold_on" @12:32:44.47 LyS1.seek 300ul @12:32:44.51 LyS2.seek 1130ul @12:33:50.67 delay 15 seconds @12:34:05.69 SPR.command "hold_off" @12:34:08.93 Waiting up to 25:00 for SPR to enter state 7 @12:45:44.48 Handoff.select :waste @12:45:44.61 LyS1.reconfigure LyS1config @12:45:44.67 LyS2.reconfigure LyS2config @12:45:44.73 Rep #1 SPR Analysis has Completed @12:45:45.41 Ended log /var/log/esp/spr18aug2911h.spr @12:45:45.43 SPR.command "stats_off" @12:45:47.59 -> Cmd.stop @12:45:47.70 Handoff.select :close @12:45:47.90 Gate.power :heat,:OFF @12:45:47.93 Gate.power :cartridge,:OFF @12:45:47.99 PV.seek :locked @12:45:48.88 PinPort.seek :clear @12:45:54.88 ArS.seek :clear @12:45:55.01 LyS1.seek :clear @12:45:55.14 LyS2.seek :clear @12:45:55.27 AirS.seek :clear @12:46:12.63 ArS.coast @12:46:12.68 LyS1.coast @12:46:12.73 LyS2.coast @12:46:12.78 AirS.coast @12:46:12.83 TV.coast @12:46:12.88 SP.coast @12:46:12.93 SC.coast @12:46:12.98 PV.coast @12:46:13.03 PinPort.coast @12:46:13.22 Gate.power :motor,:OFF @12:46:13.23 Gate.power :main,:OFF @12:46:13.25 Gate.power :core,:OFF @12:46:13.27 Gate.power :analytic1,:OFF @12:46:13.29 Gate.power :analytic2,:OFF @12:46:13.31 Gate.power :analytic3,:OFF @12:46:13.33 Safely stopped and ready to power off