@14:14:41.07PDT21-Aug-18 <MAIN> >BREAK< ESP3 Gateway v0.99 for core bus
@14:14:41.09 >BREAK< ESP3 Gateway v0.99 for cartridge bus
@14:14:42.37 
Configuration for honu by brent@mbari.org
/home/esp/esp2/type/auv/honu/configure.rb
@14:14:43.52 Polling Can every 5:00
@14:14:43.82 Clients may connect on TCP port 9999
Connecting to 10.89.5.2:9999
@14:14:43.86 Waiting for espclients...
@14:14:44.32 <LRAUV> Session Begun
@14:14:46.26 -> showStatus
@14:14:48.25 -> Cmd.loadCartridge
@14:14:48.38 Gate.power :main,:ON,limit to 5000mA
@14:14:48.39 Gate.power :analytic1,:ON,limit to 5000mA
@14:14:48.41 Gate.power :analytic2,:ON,limit to 5000mA
@14:14:48.44 Gate.power :analytic3,:ON,limit to 5000mA
@14:14:48.58 Powering core bus
@14:14:50.88 <Probe-AMspr> SPR Module 1, FW Rev. B, FPGA Rev. C
@14:14:50.97 <LRAUV> ArS.configure ArSconfig
@14:14:51.07 LyS1.configure LyS1config
@14:14:51.19 LyS2.configure LyS2config
@14:14:51.30 AirS.configure AirSconfig
@14:14:51.41 TV.configure TVconfig
@14:14:51.54 SP.configure SPconfig
@14:14:51.66 SC.configure SCconfig
@14:14:51.78 PV.configure PVconfig
@14:14:51.89 PinPort.configure PPconfig
@14:14:52.01 Sea.configure SPconfig
@14:14:52.07 All elf controllers running firmware version 4.5
@14:14:52.09 Gate.power :motor,:ON,limit to 5000mA
@14:14:52.28 <ArS_home> ArS.reconfigure ArSconfig
@14:14:52.55 ArS.home.jog 0
@14:14:52.68 <PV_home> PV.reconfigure PVconfig
@14:14:52.75 <TV_home> TV.reconfigure TVconfig
@14:14:52.79 <LyS2_home> LyS2.reconfigure LyS2config
@14:14:52.85 <LyS1_home> LyS1.reconfigure LyS1config
@14:14:52.94 <AirS_home> AirS.reconfigure AirSconfig
@14:14:52.99 <PinPort_home> PinPort.reconfigure PPconfig
@14:14:53.18 <LyS2_home> LyS2.home.jog 0
@14:14:53.22 <PV_home> PV.home.jog 0
@14:14:53.24 <TV_home> TV.home.jog 0
@14:14:53.27 <PinPort_home> PinPort.home.jog 0
@14:14:53.29 <LyS1_home> LyS1.home.jog 0
@14:14:53.35 <AirS_home> AirS.home.jog 0
@14:14:53.68 <ArS_home> ArS.reconfigure HomeMagSyringe
@14:14:53.92 <TV_home> TV.reconfigure HomeTV
@14:14:54.00 <PinPort_home> PinPort.reconfigure HomePP
@14:14:54.03 <PV_home> PV.reconfigure HomePV
@14:14:54.06 <LyS2_home> LyS2.reconfigure HomeSyringe
@14:14:54.10 <ArS_home> ArS.jog -273
@14:14:54.26 <TV_home> TV.jog -547
@14:14:54.29 <PV_home> PV.jog -1485
@14:14:54.36 <PinPort_home> PinPort.jog -109
@14:14:54.42 <LyS2_home> LyS2.jog -273
@14:14:54.58 <AirS_home> AirS.reconfigure HomeMagSyringe
@14:14:54.60 <LyS1_home> LyS1.reconfigure HomeSyringe
@14:14:54.68 <AirS_home> AirS.jog -309
@14:14:54.71 <LyS1_home> LyS1.jog -267
@14:14:54.85 <PinPort_home> PinPort.setRawPosition! 0
@14:14:54.87 <ArS_home> ArS.setRawPosition! -50
@14:14:54.93 <PinPort_home> PinPort.seek :home
@14:14:54.96 <ArS_home> ArS.seek :home
@14:14:55.11 <LyS2_home> LyS2.setRawPosition! -50
@14:14:55.13 <PinPort_home> PinPort.reconfigure PPconfig
@14:14:55.22 <LyS2_home> LyS2.seek :home
@14:14:55.60 <PV_home> PV.setRawPosition! -50
@14:14:55.62 <AirS_home> AirS.setRawPosition! -50
@14:14:55.65 <LyS1_home> LyS1.setRawPosition! -50
@14:14:55.67 <ArS_home> ArS.reconfigure ArSconfig
@14:14:55.81 <PV_home> PV.seek :home
@14:14:55.86 <AirS_home> AirS.seek :home
@14:14:55.89 <LyS1_home> LyS1.seek :home
@14:14:56.06 <LyS2_home> LyS2.reconfigure LyS2config
@14:14:56.22 <PV_home> PV.reconfigure PVconfig
@14:14:56.34 <TV_home> TV.setRawPosition! 0
@14:14:56.36 <AirS_home> AirS.reconfigure AirSconfig
@14:14:56.38 <LyS1_home> LyS1.reconfigure LyS1config
@14:14:56.50 <TV_home> TV.seek :home
@14:14:56.62 TV.reconfigure TVconfig
@14:14:56.98 <LRAUV> TV.seek :clear
@14:14:58.60 TV.coast
@14:14:58.65 SC.reconfigure HomeSC
@14:14:58.71 SC.home.jog 0
@14:14:59.47 SC.reconfigure SCconfig
@14:14:59.53 SC.seek 60
@14:14:59.64 Selecting Cartridge 59
@14:14:59.94 SC.seek 59
@14:15:00.45 <LyS2> LyS2.seek :locked
@14:15:00.57 <PV> PV.seek :locked
@14:15:00.70 <AirS> AirS.seek :locked
@14:15:04.78 <LRAUV> Powering cartridge bus
@14:15:06.91 Gate.setIR 1750,5000,0,6
@14:15:07.03 Gate.setIR 0,5000,0,6
@14:15:07.09 Loaded Lyse-n-Go Cartridge
@14:15:07.10 PuckHeater.configure HeatConfig
@14:15:07.15 Gate.power :heat,:ON,limit to 5000mA
@14:15:29.38 -> Cmd.startFiltering
@14:15:29.61 <FILTERING> Duration of filtering limited to 1:05:00
@14:15:29.86 Priming sample loop w/100ml, bypass w/10ml
@14:15:29.93 SP.setPosition! 0ml
@14:15:29.97 <openIntake> Intake.open
@14:15:29.99 <FILTERING> Exhaust.open
@14:15:30.83 Sea @-42.9psia
@14:15:30.85 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi
@14:15:36.38 Vacuum tare is 23% of full scale
@14:15:36.39 <openPB> PB.open
@14:15:36.42 <FILTERING> Exhaust.close
@14:15:37.46 Sea @-42.9psia
@14:15:37.55 SP.reconfigure SPprime
@14:15:37.61 SP.seek 10ml,1:21
@14:15:48.46 SP.setPosition! 0ml
@14:15:48.51 <closePB> PB.close
@14:15:48.53 <FILTERING> Exhaust.open
@14:15:49.34 Sea @-42.9psia
@14:15:49.42 SP.reconfigure SPprime
@14:15:49.49 SP.seek 100ml,8:51
@14:17:40.19 Intake.close
@14:17:40.94 Pressurizing cartridge to 26psi (try #1 of 3)
@14:17:40.96 SP.setPosition! 0ml
@14:17:41.00 PB.open
@14:17:41.79 TV.seek :crack
@14:17:42.59 TV.seek :clear
@14:17:43.38 Intake.open
@14:17:44.85 PB.close
@14:17:45.60 Exhaust.close
@14:17:46.37 Sea @-42.9psia
@14:17:46.46 SP.reconfigure SPcharge
@14:17:46.52 SP.seek 2.3ml,42 seconds
@14:18:00.08 Cartridge pressurized with 0.864ml to 26.6psi
@14:18:00.46 delay 3 seconds
@14:18:03.53 Cartridge at 23.4psi 3 seconds later
@14:18:03.61 TV.seek :cartridge
@14:18:05.42 TV.seek :clear
@14:18:07.23 Sampling 1000.0ml
@14:18:07.25 SP.setPosition! 0ml
@14:18:07.29 Exhaust.open
@14:18:08.03 Sea @-42.9psia
@14:18:08.12 SP.reconfigure SPsample
@14:18:08.18 SP.seek 1000ml,1:23:53
@14:18:09.14 <sampling> Cartridge 59 sampling 0/1000ml at 0.17ml/s with 3.0psi vacuum
@14:19:09.15 Cartridge 59 sampling 31/1000ml at 0.53ml/s with 20.6psi vacuum
@14:20:09.15 Cartridge 59 sampling 62/1000ml at 0.48ml/s with 17.0psi vacuum
@14:21:09.15 Cartridge 59 sampling 91/1000ml at 0.50ml/s with 23.6psi vacuum
@14:22:09.15 Cartridge 59 sampling 117/1000ml at 0.40ml/s with 17.6psi vacuum
@14:23:09.15 Cartridge 59 sampling 141/1000ml at 0.33ml/s with 22.7psi vacuum
@14:24:09.15 Cartridge 59 sampling 161/1000ml at 0.31ml/s with 20.3psi vacuum
@14:25:09.15 Cartridge 59 sampling 177/1000ml at 0.23ml/s with 23.7psi vacuum
@14:26:09.15 Cartridge 59 sampling 190/1000ml at 0.22ml/s with 21.5psi vacuum
@14:27:09.15 Cartridge 59 sampling 203/1000ml at 0.20ml/s with 27.8psi vacuum
@14:27:48.69 <FILTERING> Flow averaged 0.37ml/s over 9:32
@14:27:48.72 Waiting up to 13 seconds for vacuum to reduce from 28.0 to 18.0psi
@14:27:49.33 Relieved excess vacuum in 10 seconds
@14:27:49.36 SP.seek 1000ml,1:06:18
@14:27:53.10 Flow estimate reduced to 0.30ml/s
@14:27:53.12 Waiting up to 12 seconds for vacuum to reduce from 27.6 to 18.0psi
@14:27:53.75 Relieved excess vacuum in 1.3 seconds
@14:27:53.78 SP.seek 1000ml,1:06:15
@14:27:57.52 Flow estimate reduced to 0.24ml/s
@14:27:57.54 Waiting up to 15 seconds for vacuum to reduce from 29.0 to 18.0psi
@14:27:58.16 Relieved excess vacuum in 1.3 seconds
@14:27:58.19 SP.seek 1000ml,1:06:12
@14:28:01.93 Flow estimate reduced to 0.19ml/s
@14:28:01.95 <closeIntake> Intake.close
@14:28:01.99 <closeExhaust> Exhaust.close
@14:28:03.04 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged
@14:28:03.13 <FILTERING> Sampled  212.8ml
@14:28:03.21 TV.seek :bypass
@14:28:04.83 TV.seek :clear
@14:28:07.91 <LRAUV> -> Cmd.startProcessing
@14:28:09.69 <PROCESSING> SPR.command "read_sequences"
@14:28:10.02 SPR.command "stats_on"
@14:28:10.07 SPR.command "start 0 1"
@14:28:12.07 <SPRlogger> Begun log /var/log/esp/spr18aug2114h.spr
@14:28:12.87 <PROCESSING> Handoff.select :waste
@14:28:12.99 ArS.seek empty
@14:28:30.73 PinPort.seek :cartridge
@14:28:36.45 LyS1.seek 1350ul
@14:28:42.40 PV.seek :reagent
@14:28:43.24 AirS.seek :tVent
@14:28:45.30 delay 2 seconds
@14:28:47.33 AirS.reconfigure EvacAirS
@14:28:47.39 AirS.seek :evac
@14:29:09.70 delay 15 seconds
@14:29:24.73 AirS.reconfigure AirSconfig
@14:29:24.79 AirS.seek :tVent
@14:29:38.99 delay 2 seconds
@14:29:41.02 AirS.reconfigure EvacAirS
@14:29:41.08 AirS.seek :evac
@14:30:03.36 delay 15 seconds
@14:30:18.39 AirS.reconfigure AirSconfig
@14:30:18.45 AirS.seek :tVent
@14:30:32.65 delay 2 seconds
@14:30:34.68 AirS.reconfigure EvacAirS
@14:30:34.74 AirS.seek :evac
@14:30:57.02 delay 15 seconds
@14:31:12.05 AirS.reconfigure AirSconfig
@14:31:12.12 AirS.seek :tVent
@14:31:26.34 delay 2 seconds
@14:31:28.36 AirS.reconfigure EvacAirS
@14:31:28.41 AirS.seek :evac
@14:31:50.69 delay 15 seconds
@14:32:05.72 AirS.reconfigure AirSconfig
@14:32:05.78 AirS.seek :tVent
@14:32:19.98 delay 2 seconds
@14:32:22.01 AirS.reconfigure EvacAirS
@14:32:22.08 AirS.seek :evac
@14:32:44.40 delay 15 seconds
@14:32:59.42 delay 1:30
@14:34:29.44 AirS.reconfigure AirSconfig
@14:34:29.49 AirS.seek :fVent
@14:34:30.93 delay 15 seconds
@14:34:45.96 LyS1.reconfigure SlowLyS1
@14:34:46.06 LyS1.seek 1050ul
@14:35:16.08 delay 5 seconds
@14:35:21.10 Handoff.select :blocked
@14:35:21.27 LyS1.seek 450ul
@14:36:21.21 delay 10 seconds
@14:36:31.24 AirS.reconfigure SlowAirS
@14:36:31.34 AirS.reconfigure to mix into 1.37806 parts LyS1
@14:36:31.72 <AirSmixer> AirS.seek evac
@14:36:31.76 <PROCESSING> LyS1.seek 300ul
@14:36:46.90 AirS.reconfigure AirSconfig
@14:36:46.98 AirS.seek :full
@14:37:01.20 LyS1.seek :empty
@14:37:31.27 PuckHeater.seek 84.7C
@14:38:38.97 PuckHeater.hold 15:00,84.7C
@14:53:38.59 LyS1.reconfigure LyS1config
@17:53:38.66 LyS1.seek 900ul
@17:53:47.01 AirS.seek :tVent
@17:53:47.09 delay 2 seconds
@17:53:49.11 AirS.reconfigure HalfSpeedAirS
@17:53:49.43 AirS.seek :evac
@17:54:17.17 delay 15 seconds
@17:54:32.20 AirS.reconfigure AirSconfig
@17:54:32.26 AirS.seek :tVent
@17:54:46.46 delay 2 seconds
@17:54:48.49 AirS.reconfigure HalfSpeedAirS
@17:54:48.57 AirS.seek :evac
@17:55:16.30 delay 15 seconds
@17:55:31.33 AirS.reconfigure AirSconfig
@17:55:31.39 AirS.seek :tVent
@17:55:45.59 delay 2 seconds
@17:55:47.63 AirS.reconfigure HalfSpeedAirS
@17:55:47.69 AirS.seek :evac
@17:56:15.42 delay 15 seconds
@17:56:30.45 delay 1:00
@17:57:30.46 Handoff.select :waste
@17:57:30.57 delay 15 seconds
@17:57:45.59 AirS.seek :fVent
@17:57:47.78 delay 10 seconds
@17:57:57.80 AirS.reconfigure AirSconfig
@17:57:57.86 AirS.seek :evac
@17:57:59.29 Lysis Recovered in LyS1
@17:57:59.30 Lysis Complete
@17:57:59.31 Handoff.select :waste
@17:57:59.34 LyS2.seek :full
@17:57:59.42 LyS1.reconfigure DAmixLyS1
@17:57:59.48 LyS2.reconfigure DAmixLyS2
@17:57:59.87 <LyS2mixer> LyS2.seek 1480ul
@17:57:59.90 <PROCESSING> LyS1.seek 650ul
@17:58:47.24 delay 5 seconds
@17:58:52.25 Handoff.select :SPR
@17:58:52.37 SPR.command "start 2 14"
@17:58:53.97 Running Experiment 2
@17:58:53.98 delay 10 seconds
@17:59:04.00 SPR.command "start 2 0"
@17:59:06.82 Waiting up to 11:00 for SPR to enter state 1
@18:06:25.13 SPR.command "hold_on"
@18:06:26.80 <LyS2mixer> LyS2.seek 1130ul
@18:06:26.86 <PROCESSING> LyS1.seek 300ul
@18:07:33.02 delay 15 seconds
@18:07:48.04 SPR.command "hold_off"
@18:07:50.81 Waiting up to 25:00 for SPR to enter state 7
@18:19:27.32 Handoff.select :waste
@18:19:27.45 LyS1.reconfigure LyS1config
@18:19:27.51 LyS2.reconfigure LyS2config
@18:19:27.57 Rep #1 SPR Analysis has Completed
@18:19:28.25 <SPRlogger> Ended log /var/log/esp/spr18aug2114h.spr
@18:19:28.27 <PROCESSING> SPR.command "stats_off"
@18:19:30.55 <LRAUV> -> Cmd.stop
@18:19:30.64 <closeHandoff> Handoff.select :close
@18:19:30.81 <LRAUV> Gate.power :heat,:OFF
@18:19:30.83 Gate.power :cartridge,:OFF
@18:19:30.86 PV.seek :locked
@18:19:31.75 PinPort.seek :clear
@18:19:37.52 <ArS> ArS.seek :clear
@18:19:37.88 <LyS1> LyS1.seek :clear
@18:19:38.01 <LyS2> LyS2.seek :clear
@18:19:38.15 <AirS> AirS.seek :clear
@18:19:55.50 <LRAUV> ArS.coast
@18:19:55.55 LyS1.coast
@18:19:55.60 LyS2.coast
@18:19:55.66 AirS.coast
@18:19:55.71 TV.coast
@18:19:55.76 SP.coast
@18:19:55.81 SC.coast
@18:19:55.86 PV.coast
@18:19:55.92 PinPort.coast
@18:19:56.09 Gate.power :motor,:OFF
@18:19:56.11 Gate.power :main,:OFF
@18:19:56.13 Gate.power :core,:OFF
@18:19:56.15 Gate.power :analytic1,:OFF
@18:19:56.18 Gate.power :analytic2,:OFF
@18:19:56.19 Gate.power :analytic3,:OFF
@18:19:56.21 Safely stopped and ready to power off