@20:52:00.66EDT29-Aug-18
>BREAK< ESP3 Gateway v0.99 for core bus @20:52:00.68 >BREAK< ESP3 Gateway v0.99 for cartridge bus @20:52:01.93 Configuration for honu by brent@mbari.org /home/esp/esp2/type/auv/honu/configure.rb @20:52:03.21 Polling Can every 5:00 @20:52:03.51 Clients may connect on TCP port 9999 Connecting to 10.89.5.2:9999 @20:52:03.55 Waiting for espclients... @20:52:03.96 Session Begun @20:52:05.89 -> showStatus @20:52:07.89 -> Cmd.loadCartridge @20:52:08.02 Gate.power :main,:ON,limit to 5000mA @20:52:08.03 Gate.power :analytic1,:ON,limit to 5000mA @20:52:08.05 Gate.power :analytic2,:ON,limit to 5000mA @20:52:08.07 Gate.power :analytic3,:ON,limit to 5000mA @20:52:08.22 Powering core bus @20:52:10.52 SPR Module 1, FW Rev. B, FPGA Rev. C @20:52:10.61 ArS.configure ArSconfig @20:52:10.71 LyS1.configure LyS1config @20:52:10.83 LyS2.configure LyS2config @20:52:10.94 AirS.configure AirSconfig @20:52:11.05 TV.configure TVconfig @20:52:11.17 SP.configure SPconfig @20:52:11.29 SC.configure SCconfig @20:52:11.41 PV.configure PVconfig @20:52:11.54 PinPort.configure PPconfig @20:52:11.66 Sea.configure SPconfig @20:52:11.71 All elf controllers running firmware version 4.5 @20:52:11.73 Gate.power :motor,:ON,limit to 5000mA @20:52:12.23 TV.reconfigure TVconfig @20:52:12.28 AirS.reconfigure AirSconfig @20:52:12.32 LyS1.reconfigure LyS1config @20:52:12.35 PinPort.reconfigure PPconfig @20:52:12.39 PV.reconfigure PVconfig @20:52:12.42 ArS.reconfigure ArSconfig @20:52:12.48 LyS2.reconfigure LyS2config @20:52:12.64 TV.home.jog 0 @20:52:12.66 AirS.home.jog 0 @20:52:12.71 LyS1.home.jog 0 @20:52:12.73 PV.home.jog 0 @20:52:12.81 ArS.home.jog 0 @20:52:12.87 PinPort.home.jog 0 @20:52:13.00 LyS2.home.jog 0 @20:52:13.52 TV.reconfigure HomeTV @20:52:13.62 PV.reconfigure HomePV @20:52:13.65 AirS.reconfigure HomeMagSyringe @20:52:13.68 PinPort.reconfigure HomePP @20:52:13.73 LyS1.reconfigure HomeSyringe @20:52:13.90 TV.jog -541 @20:52:13.93 AirS.jog -315 @20:52:13.96 PinPort.jog -111 @20:52:13.99 PV.jog -1475 @20:52:14.02 LyS1.jog -269 @20:52:14.24 LyS2.reconfigure HomeSyringe @20:52:14.26 ArS.reconfigure HomeMagSyringe @20:52:14.34 LyS2.jog -261 @20:52:14.38 ArS.jog -241 @20:52:14.51 PinPort.setRawPosition! 0 @20:52:14.56 PinPort.seek :home @20:52:14.90 AirS.setRawPosition! -50 @20:52:14.93 PinPort.reconfigure PPconfig @20:52:14.96 LyS1.setRawPosition! -50 @20:52:15.07 AirS.seek :home @20:52:15.12 LyS1.seek :home @20:52:15.24 LyS2.setRawPosition! -50 @20:52:15.26 ArS.setRawPosition! -50 @20:52:15.35 LyS2.seek :home @20:52:15.38 ArS.seek :home @20:52:15.54 PV.setRawPosition! -50 @20:52:15.57 AirS.reconfigure AirSconfig @20:52:15.60 LyS1.reconfigure LyS1config @20:52:15.73 PV.seek :home @20:52:15.86 ArS.reconfigure ArSconfig @20:52:15.89 LyS2.reconfigure LyS2config @20:52:16.05 TV.setRawPosition! 0 @20:52:16.10 TV.seek :home @20:52:16.20 PV.reconfigure PVconfig @20:52:16.22 TV.reconfigure TVconfig @20:52:16.47 TV.seek :clear @20:52:18.09 TV.coast @20:52:18.14 SC.reconfigure HomeSC @20:52:18.21 SC.home.jog 0 @20:52:18.97 SC.reconfigure SCconfig @20:52:19.03 SC.seek 27 @20:52:19.11 Selecting Cartridge 26 @20:52:19.42 SC.seek 26 @20:52:19.93 LyS2.seek :locked @20:52:20.05 PV.seek :locked @20:52:20.19 AirS.seek :locked @20:52:24.26 Powering cartridge bus @20:52:26.39 Gate.setIR 1750,5000,0,6 @20:52:26.51 Gate.setIR 0,5000,0,6 @20:52:26.57 Loaded Lyse-n-Go Cartridge @20:52:26.58 PuckHeater.configure HeatConfig @20:52:26.63 Gate.power :heat,:ON,limit to 5000mA @20:52:46.63 -> Cmd.startFiltering @20:52:46.85 Duration of filtering limited to 1:05:00 @20:52:47.11 Priming sample loop w/100ml, bypass w/10ml @20:52:47.18 SP.setPosition! 0ml @20:52:47.22 Intake.open @20:52:47.27 Exhaust.open @20:52:48.06 Sea @-42.9psia @20:52:48.08 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi @20:52:53.61 Vacuum tare is 24% of full scale @20:52:53.62 PB.open @20:52:53.67 Exhaust.close @20:52:54.45 Sea @-42.9psia @20:52:54.53 SP.reconfigure SPprime @20:52:54.60 SP.seek 10ml,1:21 @20:53:05.46 SP.setPosition! 0ml @20:53:05.50 PB.close @20:53:05.53 Exhaust.open @20:53:06.36 Sea @-42.9psia @20:53:06.44 SP.reconfigure SPprime @20:53:06.50 SP.seek 100ml,8:51 @20:55:00.07 Intake.close @20:55:00.83 Pressurizing cartridge to 26psi (try #1 of 3) @20:55:00.84 SP.setPosition! 0ml @20:55:00.89 PB.open @20:55:01.69 TV.seek :crack @20:55:02.48 TV.seek :clear @20:55:03.28 Intake.open @20:55:04.73 PB.close @20:55:05.47 Exhaust.close @20:55:06.26 Sea @-42.9psia @20:55:06.34 SP.reconfigure SPcharge @20:55:06.41 SP.seek 2.3ml,42 seconds @20:55:14.83 Cartridge pressurized with 0.330ml to 26.1psi @20:55:15.06 delay 3 seconds @20:55:18.13 Cartridge at 19.6psi 3 seconds later @20:55:18.21 TV.seek :cartridge @20:55:20.03 TV.seek :clear @20:55:21.85 Sampling 1000.0ml @20:55:21.86 SP.setPosition! 0ml @20:55:21.91 Exhaust.open @20:55:22.66 Sea @-42.9psia @20:55:22.76 SP.reconfigure SPsample @20:55:22.82 SP.seek 1000ml,1:23:53 @20:55:23.77 Cartridge 26 sampling 0/1000ml at 0.17ml/s with 3.2psi vacuum @20:56:23.78 Cartridge 26 sampling 33/1000ml at 0.55ml/s with 23.9psi vacuum @20:57:23.77 Cartridge 26 sampling 65/1000ml at 0.52ml/s with 19.8psi vacuum @20:58:23.78 Cartridge 26 sampling 97/1000ml at 0.51ml/s with 23.0psi vacuum @20:59:23.78 Cartridge 26 sampling 128/1000ml at 0.51ml/s with 22.4psi vacuum @21:00:23.78 Cartridge 26 sampling 157/1000ml at 0.44ml/s with 23.4psi vacuum @21:01:23.78 Cartridge 26 sampling 184/1000ml at 0.41ml/s with 23.2psi vacuum @21:02:23.77 Cartridge 26 sampling 209/1000ml at 0.40ml/s with 21.7psi vacuum @21:03:23.78 Cartridge 26 sampling 230/1000ml at 0.27ml/s with 23.3psi vacuum @21:04:23.78 Cartridge 26 sampling 247/1000ml at 0.22ml/s with 24.9psi vacuum @21:05:23.78 Cartridge 26 sampling 261/1000ml at 0.20ml/s with 23.0psi vacuum @21:06:23.78 Cartridge 26 sampling 274/1000ml at 0.20ml/s with 27.3psi vacuum @21:07:23.78 Cartridge 26 sampling 286/1000ml at 0.20ml/s with 27.1psi vacuum @21:07:26.92 Flow averaged 0.40ml/s over 12:02 @21:07:26.94 Waiting up to 9 seconds for vacuum to reduce from 26.5 to 18.0psi @21:07:27.83 Relieved excess vacuum in 4 seconds @21:07:27.85 SP.seek 1000ml,59:57 @21:07:47.23 Flow averaged 0.29ml/s over 14 seconds @21:07:47.25 Waiting up to 18 seconds for vacuum to reduce from 30.0 to 18.0psi @21:07:47.87 Relieved excess vacuum in 6.9 seconds @21:07:47.90 SP.seek 1000ml,59:38 @21:07:54.83 Flow estimate reduced to 0.23ml/s @21:07:54.85 Waiting up to 14 seconds for vacuum to reduce from 28.4 to 18.0psi @21:07:55.47 Relieved excess vacuum in 4.5 seconds @21:07:55.50 SP.seek 1000ml,59:32 @21:08:02.40 Flow estimate reduced to 0.18ml/s @21:08:02.42 Intake.close @21:08:02.46 Exhaust.close @21:08:03.20 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged @21:08:03.29 Sampled 293.3ml @21:08:03.38 TV.seek :bypass @21:08:04.99 TV.seek :clear @21:08:07.25 -> Cmd.startProcessing @21:08:09.04 SPR.command "read_sequences" @21:08:09.50 SPR.command "stats_on" @21:08:09.54 SPR.command "start 0 1" @21:08:11.55 Begun log /var/log/esp/spr18aug2921h.spr @21:08:12.58 AnalyticModule::ReadTimeout in PROCESSING -- No response from SPR on AMspr (try 1 of 3) @21:08:14.24 Handoff.select :waste @21:08:14.36 ArS.seek empty @21:08:32.08 PinPort.seek :cartridge @21:08:37.79 LyS1.seek 1350ul @21:08:43.72 PV.seek :reagent @21:08:44.57 AirS.seek :tVent @21:08:46.65 delay 2 seconds @21:08:48.67 AirS.reconfigure EvacAirS @21:08:48.72 AirS.seek :evac @21:09:11.03 delay 15 seconds @21:09:26.06 AirS.reconfigure AirSconfig @21:09:26.11 AirS.seek :tVent @21:09:40.32 delay 2 seconds @21:09:42.35 AirS.reconfigure EvacAirS @21:09:42.40 AirS.seek :evac @21:10:04.69 delay 15 seconds @21:10:19.72 AirS.reconfigure AirSconfig @21:10:19.77 AirS.seek :tVent @21:10:33.98 delay 2 seconds @21:10:36.01 AirS.reconfigure EvacAirS @21:10:36.07 AirS.seek :evac @21:10:58.38 delay 15 seconds @21:11:13.41 AirS.reconfigure AirSconfig @21:11:13.73 AirS.seek :tVent @21:11:27.93 delay 2 seconds @21:11:29.97 AirS.reconfigure EvacAirS @21:11:30.02 AirS.seek :evac @21:11:52.31 delay 15 seconds @21:12:07.34 AirS.reconfigure AirSconfig @21:12:07.41 AirS.seek :tVent @21:12:21.61 delay 2 seconds @21:12:23.64 AirS.reconfigure EvacAirS @21:12:23.69 AirS.seek :evac @21:12:45.98 delay 15 seconds @21:13:01.00 delay 1:30 @21:14:31.03 AirS.reconfigure AirSconfig @21:14:31.08 AirS.seek :fVent @21:14:32.52 delay 15 seconds @21:14:47.54 LyS1.reconfigure SlowLyS1 @21:14:47.65 LyS1.seek 1050ul @21:15:17.67 delay 5 seconds @21:15:22.69 Handoff.select :blocked @21:15:22.85 LyS1.seek 450ul @21:16:22.80 delay 10 seconds @21:16:32.82 AirS.reconfigure SlowAirS @21:16:32.93 AirS.reconfigure to mix into 1.37806 parts LyS1 @21:16:33.36 AirS.seek evac @21:16:33.39 LyS1.seek 300ul @21:16:48.50 AirS.reconfigure AirSconfig @21:16:48.56 AirS.seek :full @21:17:02.76 LyS1.seek :empty @21:17:32.84 PuckHeater.seek 84C @21:18:31.87 PuckHeater.hold 15:00,84C @21:33:27.64 LyS1.reconfigure LyS1config @21:33:27.70 LyS1.seek 900ul @21:33:36.03 AirS.seek :tVent @21:33:36.12 delay 2 seconds @21:33:38.14 AirS.reconfigure HalfSpeedAirS @21:33:38.19 AirS.seek :evac @21:34:05.91 delay 15 seconds @21:34:20.94 AirS.reconfigure AirSconfig @21:34:21.07 AirS.seek :tVent @21:34:35.27 delay 2 seconds @21:34:37.30 AirS.reconfigure HalfSpeedAirS @21:34:37.35 AirS.seek :evac @21:35:05.09 delay 15 seconds @21:35:20.12 AirS.reconfigure AirSconfig @21:35:20.19 AirS.seek :tVent @21:35:34.39 delay 2 seconds @21:35:36.42 AirS.reconfigure HalfSpeedAirS @21:35:36.48 AirS.seek :evac @21:36:04.20 delay 15 seconds @21:36:19.23 delay 1:00 @21:37:19.25 Handoff.select :waste @21:37:19.37 delay 15 seconds @21:37:34.39 AirS.seek :fVent @21:37:36.58 delay 10 seconds @21:37:46.61 AirS.reconfigure AirSconfig @21:37:46.67 AirS.seek :evac @21:37:48.11 Lysis Recovered in LyS1 @21:37:48.13 Lysis Complete @21:37:48.15 Handoff.select :waste @21:37:48.18 LyS2.seek :full @21:37:48.27 LyS1.reconfigure DAmixLyS1 @21:37:48.33 LyS2.reconfigure DAmixLyS2 @21:37:48.71 LyS1.seek 650ul @21:37:48.74 LyS2.seek 1480ul @21:38:36.03 delay 5 seconds @21:38:41.05 Handoff.select :SPR @21:38:41.17 SPR.command "start 2 14" @21:38:42.53 Running Experiment 2 @21:38:42.54 delay 10 seconds @21:38:52.57 SPR.command "start 2 0" @21:38:55.89 Waiting up to 11:00 for SPR to enter state 1 @21:46:14.18 SPR.command "hold_on" @21:46:15.87 LyS1.seek 300ul @21:46:15.93 LyS2.seek 1130ul @21:47:22.06 delay 15 seconds @21:47:37.08 SPR.command "hold_off" @21:47:39.88 Waiting up to 25:00 for SPR to enter state 7 @21:59:16.37 Handoff.select :waste @21:59:16.50 LyS1.reconfigure LyS1config @21:59:16.56 LyS2.reconfigure LyS2config @21:59:16.62 Rep #1 SPR Analysis has Completed @21:59:17.31 Ended log /var/log/esp/spr18aug2921h.spr @21:59:17.32 SPR.command "stats_off" @21:59:19.38 -> Cmd.stop @21:59:19.47 Handoff.select :close @21:59:19.65 Gate.power :heat,:OFF @21:59:19.66 Gate.power :cartridge,:OFF @21:59:19.69 PV.seek :locked @21:59:20.59 PinPort.seek :clear @21:59:26.59 ArS.seek :clear @21:59:26.72 LyS1.seek :clear @21:59:26.85 LyS2.seek :clear @21:59:26.98 AirS.seek :clear @21:59:44.34 ArS.coast @21:59:44.39 LyS1.coast @21:59:44.44 LyS2.coast @21:59:44.48 AirS.coast @21:59:44.54 TV.coast @21:59:44.58 SP.coast @21:59:44.63 SC.coast @21:59:44.68 PV.coast @21:59:44.73 PinPort.coast @21:59:44.91 Gate.power :motor,:OFF @21:59:44.93 Gate.power :main,:OFF @21:59:44.95 Gate.power :core,:OFF @21:59:44.97 Gate.power :analytic1,:OFF @21:59:44.99 Gate.power :analytic2,:OFF @21:59:45.01 Gate.power :analytic3,:OFF @21:59:45.03 Safely stopped and ready to power off