@09:13:35.80EDT27-Aug-18 <MAIN> >BREAK< ESP3 Gateway v0.99 for core bus
@09:13:35.82 >BREAK< ESP3 Gateway v0.99 for cartridge bus
@09:13:37.05 
Configuration for honu by brent@mbari.org
/home/esp/esp2/type/auv/honu/configure.rb
@09:13:38.27 Polling Can every 5:00
@09:13:38.58 Clients may connect on TCP port 9999
@09:13:38.59 Connecting to 10.89.5.2:9999
@09:13:38.63 Waiting for espclients...
@09:13:38.85 <LRAUV> Session Begun
@09:13:40.78 -> showStatus
@09:13:42.79 -> Cmd.loadCartridge
@09:13:42.91 Gate.power :main,:ON,limit to 5000mA
@09:13:42.92 Gate.power :analytic1,:ON,limit to 5000mA
@09:13:42.94 Gate.power :analytic2,:ON,limit to 5000mA
@09:13:42.97 Gate.power :analytic3,:ON,limit to 5000mA
@09:13:43.11 Powering core bus
@09:13:45.42 <Probe-AMspr> SPR Module 1, FW Rev. B, FPGA Rev. C
@09:13:45.52 <LRAUV> ArS.configure ArSconfig
@09:13:45.62 LyS1.configure LyS1config
@09:13:45.74 LyS2.configure LyS2config
@09:13:45.84 AirS.configure AirSconfig
@09:13:45.96 TV.configure TVconfig
@09:13:46.08 SP.configure SPconfig
@09:13:46.20 SC.configure SCconfig
@09:13:46.32 PV.configure PVconfig
@09:13:46.44 PinPort.configure PPconfig
@09:13:46.56 Sea.configure SPconfig
@09:13:46.62 All elf controllers running firmware version 4.5
@09:13:46.64 Gate.power :motor,:ON,limit to 5000mA
@09:13:46.81 <ArS_home> ArS.reconfigure ArSconfig
@09:13:47.05 ArS.home.jog 0
@09:13:47.18 <PV_home> PV.reconfigure PVconfig
@09:13:47.25 <TV_home> TV.reconfigure TVconfig
@09:13:47.28 <AirS_home> AirS.reconfigure AirSconfig
@09:13:47.32 <LyS1_home> LyS1.reconfigure LyS1config
@09:13:47.35 <PinPort_home> PinPort.reconfigure PPconfig
@09:13:47.41 <LyS2_home> LyS2.reconfigure LyS2config
@09:13:47.62 <TV_home> TV.home.jog 0
@09:13:47.65 <PV_home> PV.home.jog 0
@09:13:47.67 <AirS_home> AirS.home.jog 0
@09:13:47.70 <LyS1_home> LyS1.home.jog 0
@09:13:47.72 <PinPort_home> PinPort.home.jog 0
@09:13:47.75 <LyS2_home> LyS2.home.jog 0
@09:13:48.21 <ArS_home> ArS.reconfigure HomeMagSyringe
@09:13:48.39 <PV_home> PV.reconfigure HomePV
@09:13:48.43 <TV_home> TV.reconfigure HomeTV
@09:13:48.50 <ArS_home> ArS.jog -239
@09:13:48.56 <PinPort_home> PinPort.reconfigure HomePP
@09:13:48.59 <AirS_home> AirS.reconfigure HomeMagSyringe
@09:13:48.74 <PV_home> PV.jog -1480
@09:13:48.78 <TV_home> TV.jog -544
@09:13:48.81 <PinPort_home> PinPort.jog -110
@09:13:48.85 <AirS_home> AirS.jog -320
@09:13:49.04 <LyS1_home> LyS1.reconfigure HomeSyringe
@09:13:49.06 <LyS2_home> LyS2.reconfigure HomeSyringe
@09:13:49.16 LyS2.jog -264
@09:13:49.19 <LyS1_home> LyS1.jog -266
@09:13:49.33 <PinPort_home> PinPort.setRawPosition! 0
@09:13:49.35 <ArS_home> ArS.setRawPosition! -50
@09:13:49.41 <PinPort_home> PinPort.seek :home
@09:13:49.46 <ArS_home> ArS.seek :home
@09:13:49.89 <PinPort_home> PinPort.reconfigure PPconfig
@09:13:49.90 <AirS_home> AirS.setRawPosition! -50
@09:13:49.92 <LyS2_home> LyS2.setRawPosition! -50
@09:13:49.96 <LyS1_home> LyS1.setRawPosition! -50
@09:13:50.08 <AirS_home> AirS.seek :home
@09:13:50.18 <LyS2_home> LyS2.seek :home
@09:13:50.23 <LyS1_home> LyS1.seek :home
@09:13:50.38 <PV_home> PV.setRawPosition! -50
@09:13:50.40 <ArS_home> ArS.reconfigure ArSconfig
@09:13:50.48 <PV_home> PV.seek :home
@09:13:50.63 <AirS_home> AirS.reconfigure AirSconfig
@09:13:50.66 <LyS2_home> LyS2.reconfigure LyS2config
@09:13:50.69 <LyS1_home> LyS1.reconfigure LyS1config
@09:13:50.91 <TV_home> TV.setRawPosition! 0
@09:13:50.93 <PV_home> PV.reconfigure PVconfig
@09:13:51.00 <TV_home> TV.seek :home
@09:13:51.12 TV.reconfigure TVconfig
@09:13:51.34 <LRAUV> TV.seek :clear
@09:13:52.96 TV.coast
@09:13:53.01 SC.reconfigure HomeSC
@09:13:53.07 SC.home.jog 0
@09:13:53.83 SC.reconfigure SCconfig
@09:13:53.89 SC.seek 38
@09:13:53.99 Selecting Cartridge 37
@09:13:54.30 SC.seek 37
@09:13:54.80 <LyS2> LyS2.seek :locked
@09:13:54.93 <PV> PV.seek :locked
@09:13:55.06 <AirS> AirS.seek :locked
@09:13:59.14 <LRAUV> Powering cartridge bus
@09:14:01.27 Gate.setIR 1750,5000,0,6
@09:14:01.39 Gate.setIR 0,5000,0,6
@09:14:01.44 Loaded Lyse-n-Go Cartridge
@09:14:01.45 PuckHeater.configure HeatConfig
@09:14:01.51 Gate.power :heat,:ON,limit to 5000mA
@09:14:21.45 -> Cmd.startFiltering
@09:14:21.68 <FILTERING> Duration of filtering limited to 1:05:00
@09:14:21.93 Priming sample loop w/100ml, bypass w/10ml
@09:14:22.01 SP.setPosition! 0ml
@09:14:22.05 <openIntake> Intake.open
@09:14:22.11 <FILTERING> Exhaust.open
@09:14:22.87 Sea @-42.9psia
@09:14:22.89 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi
@09:14:28.67 Vacuum tare is 24% of full scale
@09:14:28.68 <openPB> PB.open
@09:14:28.73 <FILTERING> Exhaust.close
@09:14:29.52 Sea @-42.9psia
@09:14:29.60 SP.reconfigure SPprime
@09:14:29.67 SP.seek 10ml,1:21
@09:14:40.52 SP.setPosition! 0ml
@09:14:40.56 <closePB> PB.close
@09:14:40.59 <FILTERING> Exhaust.open
@09:14:41.43 Sea @-42.9psia
@09:14:41.52 SP.reconfigure SPprime
@09:14:41.58 SP.seek 100ml,8:51
@09:16:35.83 Intake.close
@09:16:36.56 Pressurizing cartridge to 26psi (try #1 of 3)
@09:16:36.57 SP.setPosition! 0ml
@09:16:36.62 PB.open
@09:16:37.38 TV.seek :crack
@09:16:38.17 TV.seek :clear
@09:16:38.97 Intake.open
@09:16:40.42 PB.close
@09:16:41.14 Exhaust.close
@09:16:41.90 Sea @-42.9psia
@09:16:41.98 SP.reconfigure SPcharge
@09:16:42.05 SP.seek 2.3ml,42 seconds
@09:16:50.66 Cartridge pressurized with 0.345ml to 26.4psi
@09:16:51.04 delay 3 seconds
@09:16:54.11 Cartridge at 21.3psi 3 seconds later
@09:16:54.19 TV.seek :cartridge
@09:16:56.00 TV.seek :clear
@09:16:57.81 Sampling 1000.0ml
@09:16:57.82 SP.setPosition! 0ml
@09:16:57.87 Exhaust.open
@09:16:58.68 Sea @-42.9psia
@09:16:58.77 SP.reconfigure SPsample
@09:16:58.84 SP.seek 1000ml,1:23:53
@09:16:59.79 <sampling> Cartridge 37 sampling 0/1000ml at 0.17ml/s with 4.2psi vacuum
@09:17:59.80 Cartridge 37 sampling 32/1000ml at 0.54ml/s with 26.7psi vacuum
@09:18:59.79 Cartridge 37 sampling 64/1000ml at 0.51ml/s with 24.6psi vacuum
@09:19:59.80 Cartridge 37 sampling 95/1000ml at 0.50ml/s with 20.0psi vacuum
@09:20:59.80 Cartridge 37 sampling 125/1000ml at 0.50ml/s with 22.2psi vacuum
@09:21:59.80 Cartridge 37 sampling 153/1000ml at 0.45ml/s with 21.6psi vacuum
@09:22:59.80 Cartridge 37 sampling 178/1000ml at 0.38ml/s with 20.0psi vacuum
@09:23:59.79 Cartridge 37 sampling 200/1000ml at 0.31ml/s with 19.0psi vacuum
@09:24:59.80 Cartridge 37 sampling 217/1000ml at 0.26ml/s with 23.2psi vacuum
@09:25:59.80 Cartridge 37 sampling 232/1000ml at 0.27ml/s with 22.1psi vacuum
@09:26:59.80 Cartridge 37 sampling 245/1000ml at 0.20ml/s with 22.8psi vacuum
@09:27:59.80 Cartridge 37 sampling 257/1000ml at 0.20ml/s with 28.6psi vacuum
@09:28:17.21 <FILTERING> Flow averaged 0.39ml/s over 11:12
@09:28:17.23 Waiting up to 9 seconds for vacuum to reduce from 26.6 to 18.0psi
@09:28:17.86 Relieved excess vacuum in 8 seconds
@09:28:17.88 SP.seek 1000ml,1:02:08
@09:28:37.58 Flow averaged 0.20ml/s over 19 seconds
@09:28:37.60 Waiting up to 17 seconds for vacuum to reduce from 29.6 to 18.0psi
@09:28:38.23 Relieved excess vacuum in 1.9 seconds
@09:28:38.26 SP.seek 1000ml,1:01:49
@09:28:43.90 Flow estimate reduced to 0.16ml/s
@09:28:43.91 <closeIntake> Intake.close
@09:28:43.95 <closeExhaust> Exhaust.close
@09:28:44.76 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged
@09:28:44.86 <FILTERING> Sampled  265.6ml
@09:28:44.94 TV.seek :bypass
@09:28:46.56 TV.seek :clear
@09:28:48.95 <LRAUV> -> Cmd.startProcessing
@09:28:50.75 <PROCESSING> SPR.command "read_sequences"
@09:28:51.43 SPR.command "stats_on"
@09:28:51.47 SPR.command "start 0 1"
@09:28:53.47 <SPRlogger> Begun log /var/log/esp/spr18aug2709h.spr
@09:28:54.29 <PROCESSING> Handoff.select :waste
@09:28:54.42 ArS.seek empty
@09:29:12.13 PinPort.seek :cartridge
@09:29:17.85 LyS1.seek 1350ul
@09:29:23.79 PV.seek :reagent
@09:29:24.62 AirS.seek :tVent
@09:29:26.69 delay 2 seconds
@09:29:28.72 AirS.reconfigure EvacAirS
@09:29:28.77 AirS.seek :evac
@09:29:51.10 delay 15 seconds
@09:30:06.13 AirS.reconfigure AirSconfig
@09:30:06.18 AirS.seek :tVent
@09:30:20.40 delay 2 seconds
@09:30:22.43 AirS.reconfigure EvacAirS
@09:30:22.48 AirS.seek :evac
@09:30:44.79 delay 15 seconds
@09:30:59.82 AirS.reconfigure AirSconfig
@09:30:59.87 AirS.seek :tVent
@09:31:14.09 delay 2 seconds
@09:31:16.12 AirS.reconfigure EvacAirS
@09:31:16.17 AirS.seek :evac
@09:31:38.47 delay 15 seconds
@09:31:53.51 AirS.reconfigure AirSconfig
@09:31:53.57 AirS.seek :tVent
@09:32:07.79 delay 2 seconds
@09:32:09.82 AirS.reconfigure EvacAirS
@09:32:09.87 AirS.seek :evac
@09:32:32.17 delay 15 seconds
@09:32:47.20 AirS.reconfigure AirSconfig
@09:32:47.25 AirS.seek :tVent
@09:33:01.46 delay 2 seconds
@09:33:03.50 AirS.reconfigure EvacAirS
@09:33:03.58 AirS.seek :evac
@09:33:25.88 delay 15 seconds
@09:33:40.90 delay 1:30
@09:35:10.93 AirS.reconfigure AirSconfig
@09:35:10.98 AirS.seek :fVent
@09:35:12.42 delay 15 seconds
@09:35:27.44 LyS1.reconfigure SlowLyS1
@09:35:27.54 LyS1.seek 1050ul
@09:35:57.57 delay 5 seconds
@09:36:02.58 Handoff.select :blocked
@09:36:02.73 LyS1.seek 450ul
@09:37:02.68 delay 10 seconds
@09:37:12.70 AirS.reconfigure SlowAirS
@09:37:12.81 AirS.reconfigure to mix into 1.37806 parts LyS1
@09:37:13.20 <AirSmixer> AirS.seek evac
@09:37:13.28 <PROCESSING> LyS1.seek 300ul
@09:37:28.35 AirS.reconfigure AirSconfig
@09:37:28.41 AirS.seek :full
@09:37:42.62 LyS1.seek :empty
@09:38:03.44 Slide::Error in PROCESSING -- IGNORED Lysis Syringe #1 positionErr at 93ul (actually 149ul)
@09:38:03.46 LyS1.reconfigure SlowLyS1
@09:38:03.53 LyS1.home.jog 0
@09:38:03.67 PuckHeater.seek 84.3C
@09:39:02.67 PuckHeater.hold 15:00,84.3C
@09:54:00.73 LyS1.reconfigure LyS1config
@09:54:00.79 LyS1.seek 900ul
@09:54:07.82 AirS.seek :tVent
@09:54:07.91 delay 2 seconds
@09:54:09.93 AirS.reconfigure HalfSpeedAirS
@09:54:10.00 AirS.seek :evac
@09:54:37.75 delay 15 seconds
@09:54:52.77 AirS.reconfigure AirSconfig
@09:54:52.87 AirS.seek :tVent
@09:55:07.07 delay 2 seconds
@09:55:09.10 AirS.reconfigure HalfSpeedAirS
@09:55:09.15 AirS.seek :evac
@09:55:36.89 delay 15 seconds
@09:55:51.92 AirS.reconfigure AirSconfig
@09:55:51.97 AirS.seek :tVent
@09:56:06.18 delay 2 seconds
@09:56:08.21 AirS.reconfigure HalfSpeedAirS
@09:56:08.27 AirS.seek :evac
@09:56:36.02 delay 15 seconds
@09:56:51.04 delay 1:00
@09:57:51.06 Handoff.select :waste
@09:57:51.16 delay 15 seconds
@09:58:06.19 AirS.seek :fVent
@09:58:08.38 delay 10 seconds
@09:58:18.41 AirS.reconfigure AirSconfig
@09:58:18.46 AirS.seek :evac
@09:58:19.90 Lysis Recovered in LyS1
@09:58:19.91 Lysis Complete
@09:58:19.92 Handoff.select :waste
@09:58:19.95 LyS2.seek :full
@09:58:20.03 LyS1.reconfigure DAmixLyS1
@09:58:20.09 LyS2.reconfigure DAmixLyS2
@09:58:20.49 <LyS2mixer> LyS2.seek 1480ul
@09:58:20.52 <PROCESSING> LyS1.seek 650ul
@09:59:07.87 delay 5 seconds
@09:59:12.89 Handoff.select :SPR
@09:59:13.00 SPR.command "start 2 14"
@09:59:14.06 Running Experiment 2
@09:59:14.07 delay 10 seconds
@09:59:24.09 SPR.command "start 2 0"
@09:59:27.42 Waiting up to 11:00 for SPR to enter state 1
@10:06:45.71 SPR.command "hold_on"
@10:06:47.41 <LyS2mixer> LyS2.seek 1130ul
@10:06:47.44 <PROCESSING> LyS1.seek 300ul
@10:07:53.64 delay 15 seconds
@10:08:08.66 SPR.command "hold_off"
@10:08:11.91 Waiting up to 25:00 for SPR to enter state 7
@10:19:48.84 Handoff.select :waste
@10:19:48.96 LyS1.reconfigure LyS1config
@10:19:49.03 LyS2.reconfigure LyS2config
@10:19:49.09 Rep #1 SPR Analysis has Completed
@10:19:49.78 <SPRlogger> Ended log /var/log/esp/spr18aug2709h.spr
@10:19:49.79 <PROCESSING> SPR.command "stats_off"
@10:19:51.90 <LRAUV> -> Cmd.stop
@10:19:51.99 <closeHandoff> Handoff.select :close
@10:19:52.16 <LRAUV> Gate.power :heat,:OFF
@10:19:52.17 Gate.power :cartridge,:OFF
@10:19:52.20 PV.seek :locked
@10:19:53.10 PinPort.seek :clear
@10:19:59.10 <ArS> ArS.seek :clear
@10:19:59.22 <LyS1> LyS1.seek :clear
@10:19:59.36 <LyS2> LyS2.seek :clear
@10:19:59.49 <AirS> AirS.seek :clear
@10:20:16.86 <LRAUV> ArS.coast
@10:20:16.90 LyS1.coast
@10:20:16.95 LyS2.coast
@10:20:17.00 AirS.coast
@10:20:17.05 TV.coast
@10:20:17.10 SP.coast
@10:20:17.16 SC.coast
@10:20:17.21 PV.coast
@10:20:17.26 PinPort.coast
@10:20:17.43 Gate.power :motor,:OFF
@10:20:17.45 Gate.power :main,:OFF
@10:20:17.47 Gate.power :core,:OFF
@10:20:17.49 Gate.power :analytic1,:OFF
@10:20:17.51 Gate.power :analytic2,:OFF
@10:20:17.53 Gate.power :analytic3,:OFF
@10:20:17.55 Safely stopped and ready to power off