@15:41:31.69PDT05-Aug-18 <MAIN> >BREAK< ESP3 Gateway v0.99 for core bus @15:41:31.71 >BREAK< ESP3 Gateway v0.99 for cartridge bus @15:41:32.94 Configuration for honu by brent@mbari.org /home/esp/esp2/type/auv/honu/configure.rb @15:41:34.14 Polling Can every 5:00 @15:41:34.44 Clients may connect on TCP port 9999 @15:41:34.45 Connecting to 10.89.5.2:9999 @15:41:34.49 Waiting for espclients... @15:41:34.61 <LRAUV> Session Begun @15:41:36.53 -> showStatus @15:41:38.52 -> Cmd.loadCartridge @15:41:38.64 Gate.power :main,:ON,limit to 5000mA @15:41:38.66 Gate.power :analytic1,:ON,limit to 5000mA @15:41:38.67 Gate.power :analytic2,:ON,limit to 5000mA @15:41:38.70 Gate.power :analytic3,:ON,limit to 5000mA @15:41:38.84 Powering core bus @15:41:41.13 <Probe-AMspr> SPR Module 1, FW Rev. B, FPGA Rev. C @15:41:41.25 <LRAUV> ArS.configure ArSconfig @15:41:41.35 LyS1.configure LyS1config @15:41:41.47 LyS2.configure LyS2config @15:41:41.58 AirS.configure AirSconfig @15:41:41.70 TV.configure TVconfig @15:41:41.83 SP.configure SPconfig @15:41:41.94 SC.configure SCconfig @15:41:42.06 PV.configure PVconfig @15:41:42.18 PinPort.configure PPconfig @15:41:42.30 Sea.configure SPconfig @15:41:42.35 All elf controllers running firmware version 4.46 @15:41:42.37 Gate.power :motor,:ON,limit to 5000mA @15:41:42.89 <TV_home> TV.reconfigure TVconfig @15:41:42.94 <AirS_home> AirS.reconfigure AirSconfig @15:41:42.99 <LyS1_home> LyS1.reconfigure LyS1config @15:41:43.02 <PV_home> PV.reconfigure PVconfig @15:41:43.06 <PinPort_home> PinPort.reconfigure PPconfig @15:41:43.10 <ArS_home> ArS.reconfigure ArSconfig @15:41:43.13 <LyS2_home> LyS2.reconfigure LyS2config @15:41:43.34 <TV_home> TV.home.jog 0 @15:41:43.37 <AirS_home> AirS.home.jog 0 @15:41:43.40 <LyS1_home> LyS1.home.jog 0 @15:41:43.43 <PV_home> PV.home.jog 0 @15:41:43.45 <PinPort_home> PinPort.home.jog 0 @15:41:43.48 <LyS2_home> LyS2.home.jog 0 @15:41:43.52 <ArS_home> ArS.home.jog 0 @15:41:44.35 <TV_home> TV.reconfigure HomeTV @15:41:44.38 <LyS2_home> LyS2.reconfigure HomeSyringe @15:41:44.42 <PinPort_home> PinPort.reconfigure HomePP @15:41:44.45 <AirS_home> AirS.reconfigure HomeMagSyringe @15:41:44.50 <ArS_home> ArS.reconfigure HomeMagSyringe @15:41:44.54 <PV_home> PV.reconfigure HomePV @15:41:44.59 <LyS1_home> LyS1.reconfigure HomeSyringe @15:41:44.76 <TV_home> TV.jog -543 @15:41:44.82 <PinPort_home> PinPort.jog -108 @15:41:44.85 <LyS2_home> LyS2.jog -274 @15:41:44.87 <PV_home> PV.jog -1500 @15:41:44.90 <AirS_home> AirS.jog -339 @15:41:44.94 <ArS_home> ArS.jog -288 @15:41:45.00 <LyS1_home> LyS1.jog -279 @15:41:45.36 <PinPort_home> PinPort.setRawPosition! 0 @15:41:45.41 PinPort.seek :home @15:41:45.50 PinPort.reconfigure PPconfig @15:41:45.84 <LyS2_home> LyS2.setRawPosition! -50 @15:41:45.87 <LyS1_home> LyS1.setRawPosition! -50 @15:41:45.89 <ArS_home> ArS.setRawPosition! -50 @15:41:45.92 <AirS_home> AirS.setRawPosition! -50 @15:41:46.04 <LyS2_home> LyS2.seek :home @15:41:46.08 <LyS1_home> LyS1.seek :home @15:41:46.11 <AirS_home> AirS.seek :home @15:41:46.18 <ArS_home> ArS.seek :home @15:41:46.39 <PV_home> PV.setRawPosition! -50 @15:41:46.43 PV.seek :home @15:41:46.58 <LyS1_home> LyS1.reconfigure LyS1config @15:41:46.60 <LyS2_home> LyS2.reconfigure LyS2config @15:41:46.77 <PV_home> PV.reconfigure PVconfig @15:41:46.79 <AirS_home> AirS.reconfigure AirSconfig @15:41:46.83 <ArS_home> ArS.reconfigure ArSconfig @15:41:46.97 <TV_home> TV.setRawPosition! 0 @15:41:47.02 TV.seek :home @15:41:47.11 TV.reconfigure TVconfig @15:41:47.45 <LRAUV> TV.seek :clear @15:41:49.07 TV.coast @15:41:49.13 SC.reconfigure HomeSC @15:41:49.19 SC.home.jog 0 @15:41:49.95 SC.reconfigure SCconfig @15:41:50.01 SC.seek 39 @15:41:50.11 Selecting Cartridge 38 @15:41:50.41 SC.seek 38 @15:41:50.90 <LyS2> LyS2.seek :locked @15:41:51.03 <PV> PV.seek :locked @15:41:51.16 <AirS> AirS.seek :locked @15:41:55.25 <LRAUV> Powering cartridge bus @15:41:57.37 Gate.setIR 1750,5000,0,6 @15:41:57.49 Gate.setIR 0,5000,0,6 @15:41:57.55 Loaded Lyse-n-Go Cartridge @15:41:57.56 PuckHeater.configure HeatConfig @15:41:57.61 Gate.power :heat,:ON,limit to 5000mA @15:42:17.22 -> Cmd.startFiltering @15:42:17.45 <FILTERING> Duration of filtering limited to 1:05:00 @15:42:17.71 Priming sample loop w/100ml, bypass w/10ml @15:42:17.78 SP.setPosition! 0ml @15:42:17.82 <openIntake> Intake.open @15:42:17.85 <FILTERING> Exhaust.open @15:42:18.67 Sea @-42.9psia @15:42:18.69 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi @15:42:24.46 Vacuum tare is 23% of full scale @15:42:24.47 <openPB> PB.open @15:42:24.49 <FILTERING> Exhaust.close @15:42:25.32 Sea @-42.9psia @15:42:25.41 SP.reconfigure SPprime @15:42:25.47 SP.seek 10ml,1:21 @15:42:36.35 SP.setPosition! 0ml @15:42:36.40 <closePB> PB.close @15:42:36.42 <FILTERING> Exhaust.open @15:42:37.23 Sea @-42.9psia @15:42:37.31 SP.reconfigure SPprime @15:42:37.38 SP.seek 100ml,8:51 @15:44:30.87 Pressurizing cartridge to 26psi @15:44:30.88 Intake.close @15:44:31.60 SP.setPosition! 0ml @15:44:31.64 PB.open @15:44:32.44 TV.seek :crack @15:44:33.23 TV.seek :clear @15:44:34.03 Intake.open @15:44:35.49 PB.close @15:44:36.24 Exhaust.close @15:44:36.99 Sea @-42.9psia @15:44:37.07 SP.reconfigure SPcharge @15:44:37.14 SP.seek 7.5ml,1:08 @15:44:43.36 Cartridge pressurized with 0.179ml to 26.0psi @15:44:43.60 delay 3 seconds @15:44:46.67 Cartridge at 23.2psi 3 seconds later @15:44:46.75 TV.seek :cartridge @15:44:48.57 TV.seek :clear @15:44:50.39 Sampling 1000.0ml @15:44:50.41 SP.setPosition! 0ml @15:44:50.45 Exhaust.open @15:44:51.20 Sea @-42.9psia @15:44:51.29 SP.reconfigure SPsample @15:44:51.36 SP.seek 1000ml,1:23:53 @15:44:52.31 <sampling> Cartridge 38 sampling 0/1000ml at 0.17ml/s with 4.4psi vacuum @15:45:52.32 Cartridge 38 sampling 31/1000ml at 0.54ml/s with 25.5psi vacuum @15:46:52.32 Cartridge 38 sampling 63/1000ml at 0.50ml/s with 17.3psi vacuum @15:47:52.32 Cartridge 38 sampling 94/1000ml at 0.54ml/s with 22.9psi vacuum @15:48:52.32 Cartridge 38 sampling 125/1000ml at 0.49ml/s with 26.2psi vacuum @15:49:52.32 Cartridge 38 sampling 155/1000ml at 0.51ml/s with 19.8psi vacuum @15:50:52.32 Cartridge 38 sampling 185/1000ml at 0.51ml/s with 25.5psi vacuum @15:51:52.32 Cartridge 38 sampling 215/1000ml at 0.47ml/s with 21.6psi vacuum @15:52:52.32 Cartridge 38 sampling 244/1000ml at 0.48ml/s with 17.5psi vacuum @15:53:52.32 Cartridge 38 sampling 273/1000ml at 0.47ml/s with 17.2psi vacuum @15:54:52.32 Cartridge 38 sampling 302/1000ml at 0.45ml/s with 27.2psi vacuum @15:55:52.32 Cartridge 38 sampling 330/1000ml at 0.48ml/s with 21.3psi vacuum @15:56:52.32 Cartridge 38 sampling 358/1000ml at 0.48ml/s with 22.6psi vacuum @15:57:52.32 Cartridge 38 sampling 385/1000ml at 0.44ml/s with 17.3psi vacuum @15:58:52.32 Cartridge 38 sampling 412/1000ml at 0.49ml/s with 24.4psi vacuum @15:59:52.32 Cartridge 38 sampling 439/1000ml at 0.45ml/s with 20.3psi vacuum @16:00:52.34 Cartridge 38 sampling 465/1000ml at 0.44ml/s with 25.9psi vacuum @16:01:52.33 Cartridge 38 sampling 490/1000ml at 0.43ml/s with 18.7psi vacuum @16:02:52.32 Cartridge 38 sampling 515/1000ml at 0.43ml/s with 21.0psi vacuum @16:03:52.32 Cartridge 38 sampling 540/1000ml at 0.37ml/s with 26.3psi vacuum @16:04:52.33 Cartridge 38 sampling 563/1000ml at 0.38ml/s with 24.9psi vacuum @16:05:52.32 Cartridge 38 sampling 587/1000ml at 0.40ml/s with 19.1psi vacuum @16:06:52.32 Cartridge 38 sampling 610/1000ml at 0.39ml/s with 19.2psi vacuum @16:07:52.32 Cartridge 38 sampling 632/1000ml at 0.36ml/s with 24.4psi vacuum @16:08:52.32 Cartridge 38 sampling 654/1000ml at 0.33ml/s with 25.8psi vacuum @16:09:52.32 Cartridge 38 sampling 676/1000ml at 0.38ml/s with 26.2psi vacuum @16:10:52.32 Cartridge 38 sampling 697/1000ml at 0.33ml/s with 23.7psi vacuum @16:11:52.32 Cartridge 38 sampling 718/1000ml at 0.37ml/s with 23.7psi vacuum @16:12:52.32 Cartridge 38 sampling 738/1000ml at 0.33ml/s with 25.0psi vacuum @16:13:52.32 Cartridge 38 sampling 758/1000ml at 0.36ml/s with 22.7psi vacuum @16:14:52.32 Cartridge 38 sampling 777/1000ml at 0.30ml/s with 25.3psi vacuum @16:15:52.32 Cartridge 38 sampling 796/1000ml at 0.28ml/s with 19.8psi vacuum @16:16:52.32 Cartridge 38 sampling 814/1000ml at 0.33ml/s with 19.7psi vacuum @16:17:52.33 Cartridge 38 sampling 833/1000ml at 0.30ml/s with 24.7psi vacuum @16:18:52.32 Cartridge 38 sampling 851/1000ml at 0.29ml/s with 21.0psi vacuum @16:19:52.32 Cartridge 38 sampling 868/1000ml at 0.31ml/s with 25.4psi vacuum @16:20:52.32 Cartridge 38 sampling 886/1000ml at 0.26ml/s with 19.0psi vacuum @16:21:52.32 Cartridge 38 sampling 903/1000ml at 0.29ml/s with 25.8psi vacuum @16:22:52.32 Cartridge 38 sampling 920/1000ml at 0.29ml/s with 19.3psi vacuum @16:23:52.32 Cartridge 38 sampling 936/1000ml at 0.25ml/s with 19.7psi vacuum @16:24:52.32 Cartridge 38 sampling 952/1000ml at 0.26ml/s with 22.5psi vacuum @16:25:52.32 Cartridge 38 sampling 968/1000ml at 0.24ml/s with 26.6psi vacuum @16:26:52.32 Cartridge 38 sampling 984/1000ml at 0.22ml/s with 22.1psi vacuum @16:27:52.32 Cartridge 38 sampling 1000/1000ml at 0.27ml/s with 24.0psi vacuum @16:27:53.94 <closeIntake> Intake.close @16:27:53.97 <closeExhaust> Exhaust.close @16:27:54.74 <FILTERING> Sampled 1000.0ml @16:27:54.82 TV.seek :bypass @16:27:56.43 TV.seek :clear @16:27:58.59 <LRAUV> -> Cmd.startProcessing @16:28:00.37 <PROCESSING> SPR.command "read_sequences" @16:28:00.83 SPR.command "stats_on" @16:28:00.87 SPR.command "start 2 0" @16:28:02.88 <SPRlogger> Begun log /var/log/esp/spr18aug0516h.spr @16:28:03.91 AnalyticModule::ReadTimeout in PROCESSING -- No response from SPR on AMspr (try 1 of 3) @16:28:05.57 <PROCESSING> Handoff.select :waste @16:28:05.69 ArS.seek empty @16:28:23.44 PinPort.seek :cartridge @16:28:29.15 LyS1.seek 1350ul @16:28:35.09 PV.seek :reagent @16:28:35.93 AirS.seek :tVent @16:28:37.99 delay 2 seconds @16:28:40.02 AirS.reconfigure EvacAirS @16:28:40.08 AirS.seek :evac @16:29:02.40 delay 15 seconds @16:29:17.43 AirS.reconfigure AirSconfig @16:29:17.49 AirS.seek :tVent @16:29:31.71 delay 2 seconds @16:29:33.74 AirS.reconfigure EvacAirS @16:29:33.81 AirS.seek :evac @16:29:56.13 delay 15 seconds @16:30:11.15 AirS.reconfigure AirSconfig @16:30:11.20 AirS.seek :tVent @16:30:25.42 delay 2 seconds @16:30:27.45 AirS.reconfigure EvacAirS @16:30:27.50 AirS.seek :evac @16:30:49.81 delay 15 seconds @16:31:04.84 AirS.reconfigure AirSconfig @16:31:04.89 AirS.seek :tVent @16:31:19.11 delay 2 seconds @16:31:21.14 AirS.reconfigure EvacAirS @16:31:21.19 AirS.seek :evac @16:31:43.51 delay 15 seconds @16:31:58.54 AirS.reconfigure AirSconfig @16:31:58.59 AirS.seek :tVent @16:32:12.82 delay 2 seconds @16:32:14.85 AirS.reconfigure EvacAirS @16:32:14.90 AirS.seek :evac @16:32:37.22 delay 15 seconds @16:32:52.25 delay 1:30 @16:34:22.28 AirS.reconfigure AirSconfig @16:34:22.39 AirS.seek :fVent @16:34:23.82 delay 15 seconds @16:34:38.85 LyS1.reconfigure SlowLyS1 @16:34:38.94 LyS1.seek 1050ul @16:35:09.00 delay 5 seconds @16:35:14.02 Handoff.select :blocked @16:35:14.19 LyS1.seek 450ul @16:36:14.19 delay 10 seconds @16:36:24.22 AirS.reconfigure SlowAirS @16:36:24.34 AirS.reconfigure to mix into 1.37806 parts LyS1 @16:36:24.72 <AirSmixer> AirS.seek evac @16:36:24.78 <PROCESSING> LyS1.seek 300ul @16:36:39.87 AirS.reconfigure AirSconfig @16:36:39.93 AirS.seek :full @16:36:54.15 LyS1.seek :empty @16:37:24.25 PuckHeater.seek 86.1C @16:38:50.78 PuckHeater.hold 15:00,86.1C @16:53:47.87 LyS1.reconfigure LyS1config @16:53:47.93 LyS1.seek 900ul @16:53:56.27 AirS.seek :tVent @16:53:56.35 delay 2 seconds @16:53:58.38 AirS.reconfigure HalfSpeedAirS @16:53:58.43 AirS.seek :evac @16:54:26.17 delay 15 seconds @16:54:41.20 AirS.reconfigure AirSconfig @16:54:41.26 AirS.seek :tVent @16:54:55.47 delay 2 seconds @16:54:57.50 AirS.reconfigure HalfSpeedAirS @16:54:57.55 AirS.seek :evac @16:55:25.29 delay 15 seconds @16:55:40.32 AirS.reconfigure AirSconfig @16:55:40.37 AirS.seek :tVent @16:55:54.59 delay 2 seconds @16:55:56.62 AirS.reconfigure HalfSpeedAirS @16:55:56.67 AirS.seek :evac @16:56:24.42 delay 15 seconds @16:56:39.45 delay 1:00 @16:57:39.47 Handoff.select :waste @16:57:39.58 delay 15 seconds @16:57:54.60 AirS.seek :fVent @16:57:56.79 delay 10 seconds @16:58:06.82 AirS.reconfigure AirSconfig @16:58:06.88 AirS.seek :evac @16:58:08.32 Lysis Recovered in LyS1 @16:58:08.33 Lysis Complete @16:58:08.34 Handoff.select :waste @16:58:08.37 LyS2.seek :full @16:58:08.46 LyS1.reconfigure DAmixLyS1 @16:58:08.51 LyS2.reconfigure DAmixLyS2 @16:58:08.93 <LyS2mixer> LyS2.seek 1480ul @16:58:08.98 <PROCESSING> LyS1.seek 650ul @16:58:56.30 delay 5 seconds @16:59:01.32 Handoff.select :SPR @16:59:01.43 SPR.command "start 1 14" @16:59:02.24 Running Experiment-Rep #1 @16:59:02.25 delay 10 seconds @16:59:12.26 SPR.command "start 1 0" @16:59:15.59 Waiting up to 4:00 for SPR to enter state 1 @17:01:19.83 SPR.command "hold_on" @17:01:22.05 <LyS2mixer> LyS2.seek 1130ul @17:01:22.09 <PROCESSING> LyS1.seek 300ul @17:02:28.27 delay 15 seconds @17:02:43.28 SPR.command "hold_off" @17:02:46.50 Waiting up to 25:00 for SPR to enter state 7 @17:22:49.94 Handoff.select :waste @17:22:50.07 LyS1.reconfigure LyS1config @17:22:50.13 LyS2.reconfigure LyS2config @17:22:50.19 Rep #1 SPR Analysis has Completed @17:22:51.10 <SPRlogger> Ended log /var/log/esp/spr18aug0516h.spr @17:22:51.12 <PROCESSING> SPR.command "stats_off" @17:22:52.99 <LRAUV> -> Cmd.stop @17:22:53.08 <closeHandoff> Handoff.select :close @17:22:53.25 <LRAUV> Gate.power :heat,:OFF @17:22:53.27 Gate.power :cartridge,:OFF @17:22:53.30 PV.seek :locked @17:22:54.20 PinPort.seek :clear @17:22:59.98 <ArS> ArS.seek :clear @17:23:00.10 <LyS1> LyS1.seek :clear @17:23:00.25 <LyS2> LyS2.seek :clear @17:23:00.38 <AirS> AirS.seek :clear @17:23:17.74 <LRAUV> ArS.coast @17:23:17.79 LyS1.coast @17:23:17.84 LyS2.coast @17:23:17.89 AirS.coast @17:23:17.94 TV.coast @17:23:17.99 SP.coast @17:23:18.04 SC.coast @17:23:18.09 PV.coast @17:23:18.14 PinPort.coast @17:23:18.31 Gate.power :motor,:OFF @17:23:18.33 Gate.power :main,:OFF @17:23:18.35 Gate.power :core,:OFF @17:23:18.37 Gate.power :analytic1,:OFF @17:23:18.39 Gate.power :analytic2,:OFF @17:23:18.41 Gate.power :analytic3,:OFF @17:23:18.43 Safely stopped and ready to power off