@10:57:20.00PDT09-Aug-18
>BREAK< ESP3 Gateway v0.99 for core bus @10:57:20.02 >BREAK< ESP3 Gateway v0.99 for cartridge bus @10:57:21.27 Configuration for honu by brent@mbari.org /home/esp/esp2/type/auv/honu/configure.rb @10:57:22.41 Polling Can every 5:00 @10:57:22.70 Clients may connect on TCP port 9999 @10:57:22.71 Connecting to 10.89.5.2:9999 @10:57:22.75 Waiting for espclients... @10:57:23.20 Session Begun @10:57:25.16 -> showStatus @10:57:27.14 -> Cmd.loadCartridge @10:57:27.27 Gate.power :main,:ON,limit to 5000mA @10:57:27.28 Gate.power :analytic1,:ON,limit to 5000mA @10:57:27.30 Gate.power :analytic2,:ON,limit to 5000mA @10:57:27.32 Gate.power :analytic3,:ON,limit to 5000mA @10:57:27.46 Powering core bus @10:57:29.77 SPR Module 1, FW Rev. B, FPGA Rev. C @10:57:29.87 ArS.configure ArSconfig @10:57:29.96 LyS1.configure LyS1config @10:57:30.09 LyS2.configure LyS2config @10:57:30.19 AirS.configure AirSconfig @10:57:30.31 TV.configure TVconfig @10:57:30.43 SP.configure SPconfig @10:57:30.55 SC.configure SCconfig @10:57:30.67 PV.configure PVconfig @10:57:30.79 PinPort.configure PPconfig @10:57:30.91 Sea.configure SPconfig @10:57:30.96 All elf controllers running firmware version 4.5 @10:57:30.98 Gate.power :motor,:ON,limit to 5000mA @10:57:31.12 ArS.reconfigure ArSconfig @10:57:31.41 ArS.home.jog 0 @10:57:31.54 PV.reconfigure PVconfig @10:57:31.60 TV.reconfigure TVconfig @10:57:31.64 LyS2.reconfigure LyS2config @10:57:31.70 AirS.reconfigure AirSconfig @10:57:31.74 PinPort.reconfigure PPconfig @10:57:31.80 LyS1.reconfigure LyS1config @10:57:32.00 PV.home.jog 0 @10:57:32.06 LyS2.home.jog 0 @10:57:32.10 TV.home.jog 0 @10:57:32.12 PinPort.home.jog 0 @10:57:32.17 AirS.home.jog 0 @10:57:32.20 LyS1.home.jog 0 @10:57:32.58 ArS.reconfigure HomeMagSyringe @10:57:32.77 TV.reconfigure HomeTV @10:57:32.80 LyS2.reconfigure HomeSyringe @10:57:32.85 ArS.jog -3692 @10:57:32.88 PinPort.reconfigure HomePP @10:57:32.92 PV.reconfigure HomePV @10:57:33.12 TV.jog -545 @10:57:33.15 LyS2.jog -1449 @10:57:33.18 PV.jog -1490 @10:57:33.23 PinPort.jog -111 @10:57:33.42 LyS1.reconfigure HomeSyringe @10:57:33.43 AirS.reconfigure HomeMagSyringe @10:57:33.53 LyS1.jog -270 @10:57:33.55 AirS.jog -2070 @10:57:33.69 PinPort.setRawPosition! 0 @10:57:33.74 PinPort.seek :home @10:57:33.82 PinPort.reconfigure PPconfig @10:57:34.13 LyS1.setRawPosition! -50 @10:57:34.18 LyS1.seek :home @10:57:34.43 LyS2.setRawPosition! -50 @10:57:34.51 LyS2.seek :home @10:57:34.82 PV.setRawPosition! -50 @10:57:34.84 LyS1.reconfigure LyS1config @10:57:34.93 PV.seek :home @10:57:35.05 LyS2.reconfigure LyS2config @10:57:35.14 TV.setRawPosition! 0 @10:57:35.16 AirS.setRawPosition! -50 @10:57:35.19 ArS.setRawPosition! -50 @10:57:35.29 TV.seek :home @10:57:35.34 ArS.seek :home @10:57:35.38 AirS.seek :home @10:57:35.58 TV.reconfigure TVconfig @10:57:35.60 PV.reconfigure PVconfig @10:57:35.76 ArS.reconfigure ArSconfig @10:57:35.84 AirS.reconfigure AirSconfig @10:57:36.36 TV.seek :clear @10:57:37.98 TV.coast @10:57:38.03 SC.reconfigure HomeSC @10:57:38.09 SC.home.jog 0 @10:57:38.85 SC.reconfigure SCconfig @10:57:38.92 SC.seek 20 @10:57:39.01 Selecting Cartridge 19 @10:57:39.31 SC.seek 19 @10:57:39.81 LyS2.seek :locked @10:57:39.93 PV.seek :locked @10:57:40.06 AirS.seek :locked @10:57:44.15 Powering cartridge bus @10:57:46.27 Gate.setIR 1750,5000,0,6 @10:57:46.39 Gate.setIR 0,5000,0,6 @10:57:46.45 Loaded Lyse-n-Go Cartridge @10:57:46.46 PuckHeater.configure HeatConfig @10:57:46.52 Gate.power :heat,:ON,limit to 5000mA @10:58:05.90 -> Cmd.startFiltering @10:58:06.13 Duration of filtering limited to 1:05:00 @10:58:06.38 Priming sample loop w/100ml, bypass w/10ml @10:58:06.45 SP.setPosition! 0ml @10:58:06.49 Intake.open @10:58:06.51 Exhaust.open @10:58:07.33 Sea @-42.9psia @10:58:07.35 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi @10:58:12.88 Vacuum tare is 22% of full scale @10:58:12.89 PB.open @10:58:12.93 Exhaust.close @10:58:13.73 Sea @-42.9psia @10:58:14.07 SP.reconfigure SPprime @10:58:14.13 SP.seek 10ml,1:21 @10:58:24.99 SP.setPosition! 0ml @10:58:25.04 PB.close @10:58:25.06 Exhaust.open @10:58:25.88 Sea @-42.9psia @10:58:25.97 SP.reconfigure SPprime @10:58:26.03 SP.seek 100ml,8:51 @11:00:14.89 Intake.close @11:00:15.63 Pressurizing cartridge to 26psi (try #1 of 3) @11:00:15.65 SP.setPosition! 0ml @11:00:15.69 PB.open @11:00:16.45 TV.seek :crack @11:00:17.25 TV.seek :clear @11:00:18.04 Intake.open @11:00:19.53 PB.close @11:00:20.27 Exhaust.close @11:00:21.04 Sea @-42.9psia @11:00:21.13 SP.reconfigure SPcharge @11:00:21.19 SP.seek 2.3ml,42 seconds @11:00:35.22 Cartridge pressurized with 0.923ml to 26.0psi @11:00:35.59 delay 3 seconds @11:00:38.66 Cartridge at 23.0psi 3 seconds later @11:00:38.74 TV.seek :cartridge @11:00:40.56 TV.seek :clear @11:00:42.38 Sampling 1000.0ml @11:00:42.39 SP.setPosition! 0ml @11:00:42.43 Exhaust.open @11:00:43.23 Sea @-42.9psia @11:00:43.32 SP.reconfigure SPsample @11:00:43.39 SP.seek 1000ml,1:23:53 @11:00:44.35 Cartridge 19 sampling 0/1000ml at 0.17ml/s with 4.6psi vacuum @11:01:44.35 Cartridge 19 sampling 35/1000ml at 0.60ml/s with 24.5psi vacuum @11:02:44.34 Cartridge 19 sampling 70/1000ml at 0.60ml/s with 24.1psi vacuum @11:03:44.35 Cartridge 19 sampling 105/1000ml at 0.58ml/s with 21.6psi vacuum @11:04:44.35 Cartridge 19 sampling 140/1000ml at 0.59ml/s with 24.6psi vacuum @11:05:44.35 Cartridge 19 sampling 175/1000ml at 0.59ml/s with 23.1psi vacuum @11:06:44.35 Cartridge 19 sampling 210/1000ml at 0.58ml/s with 22.9psi vacuum @11:07:44.34 Cartridge 19 sampling 245/1000ml at 0.57ml/s with 21.5psi vacuum @11:08:44.35 Cartridge 19 sampling 280/1000ml at 0.58ml/s with 22.1psi vacuum @11:09:44.35 Cartridge 19 sampling 314/1000ml at 0.59ml/s with 23.5psi vacuum @11:10:44.35 Cartridge 19 sampling 349/1000ml at 0.58ml/s with 25.8psi vacuum @11:11:44.35 Cartridge 19 sampling 384/1000ml at 0.59ml/s with 23.2psi vacuum @11:12:44.35 Cartridge 19 sampling 419/1000ml at 0.59ml/s with 23.4psi vacuum @11:13:44.35 Cartridge 19 sampling 454/1000ml at 0.58ml/s with 21.8psi vacuum @11:14:44.35 Cartridge 19 sampling 490/1000ml at 0.57ml/s with 24.5psi vacuum @11:15:44.35 Cartridge 19 sampling 525/1000ml at 0.58ml/s with 25.4psi vacuum @11:16:44.35 Cartridge 19 sampling 560/1000ml at 0.59ml/s with 23.5psi vacuum @11:17:44.35 Cartridge 19 sampling 595/1000ml at 0.58ml/s with 21.5psi vacuum @11:18:44.61 Cartridge 19 sampling 630/1000ml at 0.58ml/s with 22.1psi vacuum @11:19:44.35 Cartridge 19 sampling 664/1000ml at 0.57ml/s with 21.1psi vacuum @11:20:44.36 Cartridge 19 sampling 699/1000ml at 0.58ml/s with 22.4psi vacuum @11:21:44.36 Cartridge 19 sampling 734/1000ml at 0.58ml/s with 23.0psi vacuum @11:22:44.36 Cartridge 19 sampling 769/1000ml at 0.57ml/s with 20.4psi vacuum @11:23:44.36 Cartridge 19 sampling 803/1000ml at 0.58ml/s with 24.3psi vacuum @11:24:44.35 Cartridge 19 sampling 838/1000ml at 0.59ml/s with 20.6psi vacuum @11:25:44.35 Cartridge 19 sampling 873/1000ml at 0.57ml/s with 22.1psi vacuum @11:26:44.35 Cartridge 19 sampling 908/1000ml at 0.57ml/s with 26.0psi vacuum @11:27:44.35 Cartridge 19 sampling 942/1000ml at 0.58ml/s with 21.8psi vacuum @11:28:44.35 Cartridge 19 sampling 977/1000ml at 0.57ml/s with 23.0psi vacuum @11:29:24.61 Intake.close @11:29:24.64 Exhaust.close @11:29:25.44 Sampled 1000.0ml @11:29:25.52 TV.seek :bypass @11:29:27.14 TV.seek :clear @11:29:29.44 -> Cmd.startProcessing @11:29:31.23 SPR.command "read_sequences" @11:29:31.60 SPR.command "stats_on" @11:29:31.64 SPR.command "start 0 1" @11:29:33.65 Begun log /var/log/esp/spr18aug0911h.spr @11:29:34.68 AnalyticModule::ReadTimeout in PROCESSING -- No response from SPR on AMspr (try 1 of 3) @11:29:36.34 Handoff.select :waste @11:29:36.46 ArS.seek empty @11:29:54.17 PinPort.seek :cartridge @11:29:59.88 LyS1.seek 1350ul @11:30:05.81 PV.seek :reagent @11:30:06.65 AirS.seek :tVent @11:30:08.71 delay 2 seconds @11:30:10.74 AirS.reconfigure EvacAirS @11:30:10.79 AirS.seek :evac @11:30:33.07 delay 15 seconds @11:30:48.10 AirS.reconfigure AirSconfig @11:30:48.16 AirS.seek :tVent @11:31:02.36 delay 2 seconds @11:31:04.39 AirS.reconfigure EvacAirS @11:31:04.45 AirS.seek :evac @11:31:26.74 delay 15 seconds @11:31:41.77 AirS.reconfigure AirSconfig @11:31:41.83 AirS.seek :tVent @11:31:56.03 delay 2 seconds @11:31:58.06 AirS.reconfigure EvacAirS @11:31:58.37 AirS.seek :evac @11:32:20.67 delay 15 seconds @11:32:35.70 AirS.reconfigure AirSconfig @11:32:35.76 AirS.seek :tVent @11:32:49.96 delay 2 seconds @11:32:51.99 AirS.reconfigure EvacAirS @11:32:52.06 AirS.seek :evac @11:33:14.36 delay 15 seconds @11:33:29.39 AirS.reconfigure AirSconfig @11:33:29.45 AirS.seek :tVent @11:33:43.67 delay 2 seconds @11:33:45.70 AirS.reconfigure EvacAirS @11:33:45.76 AirS.seek :evac @11:34:08.04 delay 15 seconds @11:34:23.07 delay 1:30 @11:35:53.10 AirS.reconfigure AirSconfig @11:35:53.17 AirS.seek :fVent @11:35:54.60 delay 15 seconds @11:36:09.63 LyS1.reconfigure SlowLyS1 @11:36:09.73 LyS1.seek 1050ul @11:36:39.75 delay 5 seconds @11:36:44.77 Handoff.select :blocked @11:36:44.94 LyS1.seek 450ul @11:37:44.88 delay 10 seconds @11:37:54.91 AirS.reconfigure SlowAirS @11:37:55.25 AirS.reconfigure to mix into 1.37806 parts LyS1 @11:37:55.59 AirS.seek evac @11:37:55.64 LyS1.seek 300ul @11:38:10.73 AirS.reconfigure AirSconfig @11:38:10.79 AirS.seek :full @11:38:25.00 LyS1.seek :empty @11:38:55.06 PuckHeater.seek 84.6C @11:39:54.20 PuckHeater.hold 15:00,84.6C @11:54:52.38 LyS1.reconfigure LyS1config @11:54:52.45 LyS1.seek 900ul @11:55:00.78 AirS.seek :tVent @11:55:00.86 delay 2 seconds @11:55:02.88 AirS.reconfigure HalfSpeedAirS @11:55:02.93 AirS.seek :evac @11:55:30.65 delay 15 seconds @11:55:45.68 AirS.reconfigure AirSconfig @11:55:46.01 AirS.seek :tVent @11:56:00.20 delay 2 seconds @11:56:02.23 AirS.reconfigure HalfSpeedAirS @11:56:02.28 AirS.seek :evac @11:56:30.00 delay 15 seconds @11:56:45.03 AirS.reconfigure AirSconfig @11:56:45.09 AirS.seek :tVent @11:56:59.29 delay 2 seconds @11:57:01.32 AirS.reconfigure HalfSpeedAirS @11:57:01.38 AirS.seek :evac @11:57:29.09 delay 15 seconds @11:57:44.12 delay 1:00 @11:58:44.13 Handoff.select :waste @11:58:44.24 delay 15 seconds @11:58:59.26 AirS.seek :fVent @11:59:01.45 delay 10 seconds @11:59:11.48 AirS.reconfigure AirSconfig @11:59:11.53 AirS.seek :evac @11:59:12.96 Lysis Recovered in LyS1 @11:59:12.97 Lysis Complete @11:59:12.98 Handoff.select :waste @11:59:13.01 LyS2.seek :full @11:59:13.11 LyS1.reconfigure DAmixLyS1 @11:59:13.17 LyS2.reconfigure DAmixLyS2 @11:59:13.55 LyS2.seek 1480ul @11:59:13.59 LyS1.seek 650ul @12:00:00.89 delay 5 seconds @12:00:05.90 Handoff.select :SPR @12:00:06.04 SPR.command "start 2 14" @12:00:07.46 Running Experiment 1 @12:00:07.47 delay 10 seconds @12:00:17.49 SPR.command "start 1 0" @12:00:20.83 Waiting up to 4:00 for SPR to enter state 1 @12:02:25.12 SPR.command "hold_on" @12:02:26.83 LyS2.seek 1130ul @12:02:26.87 LyS1.seek 300ul @12:03:33.02 delay 15 seconds @12:03:48.03 SPR.command "hold_off" @12:03:50.81 Waiting up to 25:00 for SPR to enter state 7 @12:23:55.27 Handoff.select :waste @12:23:55.39 LyS1.reconfigure LyS1config @12:23:55.45 LyS2.reconfigure LyS2config @12:23:55.51 Rep #1 SPR Analysis has Completed @12:23:56.16 Ended log /var/log/esp/spr18aug0911h.spr @12:23:56.18 SPR.command "stats_off" @12:23:58.49 -> Cmd.stop @12:23:58.58 Handoff.select :close @12:23:58.76 Gate.power :heat,:OFF @12:23:58.78 Gate.power :cartridge,:OFF @12:23:58.81 PV.seek :locked @12:23:59.70 PinPort.seek :clear @12:24:05.47 ArS.seek :clear @12:24:05.60 LyS1.seek :clear @12:24:05.73 LyS2.seek :clear @12:24:05.86 AirS.seek :clear @12:24:23.22 ArS.coast @12:24:23.27 LyS1.coast @12:24:23.32 LyS2.coast @12:24:23.36 AirS.coast @12:24:23.41 TV.coast @12:24:23.46 SP.coast @12:24:23.51 SC.coast @12:24:23.56 PV.coast @12:24:23.61 PinPort.coast @12:24:23.79 Gate.power :motor,:OFF @12:24:23.81 Gate.power :main,:OFF @12:24:23.83 Gate.power :core,:OFF @12:24:23.85 Gate.power :analytic1,:OFF @12:24:23.87 Gate.power :analytic2,:OFF @12:24:23.90 Gate.power :analytic3,:OFF @12:24:23.91 Safely stopped and ready to power off