@22:00:23.12EDT26-Aug-18 <MAIN> >BREAK< ESP3 Gateway v0.99 for core bus
@22:00:23.13 >BREAK< ESP3 Gateway v0.99 for cartridge bus
@22:00:24.39 
Configuration for honu by brent@mbari.org
/home/esp/esp2/type/auv/honu/configure.rb
@22:00:25.62 Polling Can every 5:00
@22:00:25.92 Clients may connect on TCP port 9999
@22:00:25.93 Connecting to 10.89.5.2:9999
@22:00:25.97 Waiting for espclients...
@22:00:26.43 <LRAUV> Session Begun
@22:00:28.39 -> showStatus
@22:00:30.37 -> Cmd.loadCartridge
@22:00:30.49 Gate.power :main,:ON,limit to 5000mA
@22:00:30.50 Gate.power :analytic1,:ON,limit to 5000mA
@22:00:30.52 Gate.power :analytic2,:ON,limit to 5000mA
@22:00:30.55 Gate.power :analytic3,:ON,limit to 5000mA
@22:00:30.69 Powering core bus
@22:00:33.00 <Probe-AMspr> SPR Module 1, FW Rev. B, FPGA Rev. C
@22:00:33.09 <LRAUV> ArS.configure ArSconfig
@22:00:33.19 LyS1.configure LyS1config
@22:00:33.31 LyS2.configure LyS2config
@22:00:33.42 AirS.configure AirSconfig
@22:00:33.54 TV.configure TVconfig
@22:00:33.66 SP.configure SPconfig
@22:00:33.78 SC.configure SCconfig
@22:00:33.89 PV.configure PVconfig
@22:00:34.01 PinPort.configure PPconfig
@22:00:34.13 Sea.configure SPconfig
@22:00:34.19 All elf controllers running firmware version 4.5
@22:00:34.21 Gate.power :motor,:ON,limit to 5000mA
@22:00:34.33 <ArS_home> ArS.reconfigure ArSconfig
@22:00:34.61 ArS.home.jog 0
@22:00:34.76 <PV_home> PV.reconfigure PVconfig
@22:00:34.84 <TV_home> TV.reconfigure TVconfig
@22:00:34.92 <LyS2_home> LyS2.reconfigure LyS2config
@22:00:34.97 <LyS1_home> LyS1.reconfigure LyS1config
@22:00:35.03 <PinPort_home> PinPort.reconfigure PPconfig
@22:00:35.07 <AirS_home> AirS.reconfigure AirSconfig
@22:00:35.22 <PV_home> PV.home.jog 0
@22:00:35.30 <TV_home> TV.home.jog 0
@22:00:35.33 <PinPort_home> PinPort.home.jog 0
@22:00:35.36 <LyS2_home> LyS2.home.jog 0
@22:00:35.40 <LyS1_home> LyS1.home.jog 0
@22:00:35.44 <AirS_home> AirS.home.jog 0
@22:00:35.79 <ArS_home> ArS.reconfigure HomeMagSyringe
@22:00:35.97 <TV_home> TV.reconfigure HomeTV
@22:00:36.05 <PinPort_home> PinPort.reconfigure HomePP
@22:00:36.09 <PV_home> PV.reconfigure HomePV
@22:00:36.12 <LyS2_home> LyS2.reconfigure HomeSyringe
@22:00:36.16 <ArS_home> ArS.jog -242
@22:00:36.32 <PV_home> PV.jog -1477
@22:00:36.34 <TV_home> TV.jog -546
@22:00:36.38 <LyS2_home> LyS2.jog -265
@22:00:36.41 <PinPort_home> PinPort.jog -110
@22:00:36.61 <LyS1_home> LyS1.reconfigure HomeSyringe
@22:00:36.63 <AirS_home> AirS.reconfigure HomeMagSyringe
@22:00:36.71 <LyS1_home> LyS1.jog -265
@22:00:36.75 <AirS_home> AirS.jog -321
@22:00:36.89 <ArS_home> ArS.setRawPosition! -50
@22:00:36.91 <PinPort_home> PinPort.setRawPosition! 0
@22:00:36.97 <ArS_home> ArS.seek :home
@22:00:37.02 <PinPort_home> PinPort.seek :home
@22:00:37.38 PinPort.reconfigure PPconfig
@22:00:37.44 <LyS2_home> LyS2.setRawPosition! -50
@22:00:37.52 LyS2.seek :home
@22:00:37.62 <AirS_home> AirS.setRawPosition! -50
@22:00:37.66 <ArS_home> ArS.reconfigure ArSconfig
@22:00:37.68 <LyS1_home> LyS1.setRawPosition! -50
@22:00:37.77 <AirS_home> AirS.seek :home
@22:00:37.85 <LyS1_home> LyS1.seek :home
@22:00:37.99 <PV_home> PV.setRawPosition! -50
@22:00:38.02 <LyS2_home> LyS2.reconfigure LyS2config
@22:00:38.10 <PV_home> PV.seek :home
@22:00:38.24 <AirS_home> AirS.reconfigure AirSconfig
@22:00:38.26 <LyS1_home> LyS1.reconfigure LyS1config
@22:00:38.39 <TV_home> TV.setRawPosition! 0
@22:00:38.45 TV.seek :home
@22:00:38.55 TV.reconfigure TVconfig
@22:00:38.57 <PV_home> PV.reconfigure PVconfig
@22:00:38.83 <LRAUV> TV.seek :clear
@22:00:40.45 TV.coast
@22:00:40.50 SC.reconfigure HomeSC
@22:00:40.56 SC.home.jog 0
@22:00:41.32 SC.reconfigure SCconfig
@22:00:41.38 SC.seek 41
@22:00:41.48 Selecting Cartridge 40
@22:00:41.78 SC.seek 40
@22:00:42.30 <LyS2> LyS2.seek :locked
@22:00:42.42 <PV> PV.seek :locked
@22:00:42.55 <AirS> AirS.seek :locked
@22:00:46.63 <LRAUV> Powering cartridge bus
@22:00:48.76 Gate.setIR 1750,5000,0,6
@22:00:48.88 Gate.setIR 0,5000,0,6
@22:00:48.93 Loaded Lyse-n-Go Cartridge
@22:00:48.94 PuckHeater.configure HeatConfig
@22:00:49.00 Gate.power :heat,:ON,limit to 5000mA
@22:01:09.11 -> Cmd.startFiltering
@22:01:09.34 <FILTERING> Duration of filtering limited to 1:05:00
@22:01:09.59 Priming sample loop w/100ml, bypass w/10ml
@22:01:09.66 SP.setPosition! 0ml
@22:01:09.71 <openIntake> Intake.open
@22:01:09.73 <FILTERING> Exhaust.open
@22:01:10.58 Sea @-42.9psia
@22:01:10.59 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi
@22:01:16.37 Vacuum tare is 24% of full scale
@22:01:16.39 <openPB> PB.open
@22:01:16.42 <FILTERING> Exhaust.close
@22:01:17.25 Sea @-42.9psia
@22:01:17.33 SP.reconfigure SPprime
@22:01:17.40 SP.seek 10ml,1:21
@22:01:28.25 SP.setPosition! 0ml
@22:01:28.29 <closePB> PB.close
@22:01:28.32 <FILTERING> Exhaust.open
@22:01:29.16 Sea @-42.9psia
@22:01:29.24 SP.reconfigure SPprime
@22:01:29.31 SP.seek 100ml,8:51
@22:03:23.71 Intake.close
@22:03:24.48 Pressurizing cartridge to 26psi (try #1 of 3)
@22:03:24.49 SP.setPosition! 0ml
@22:03:24.54 PB.open
@22:03:25.33 TV.seek :crack
@22:03:26.12 TV.seek :clear
@22:03:26.92 Intake.open
@22:03:28.38 PB.close
@22:03:29.10 Exhaust.close
@22:03:29.86 Sea @-42.9psia
@22:03:29.94 SP.reconfigure SPcharge
@22:03:30.00 SP.seek 2.3ml,42 seconds
@22:03:40.56 Cartridge pressurized with 0.521ml to 26.2psi
@22:03:40.94 delay 3 seconds
@22:03:44.01 Cartridge at 24.2psi 3 seconds later
@22:03:44.09 TV.seek :cartridge
@22:03:45.91 TV.seek :clear
@22:03:47.72 Sampling 1000.0ml
@22:03:47.73 SP.setPosition! 0ml
@22:03:47.78 Exhaust.open
@22:03:48.56 Sea @-42.9psia
@22:03:48.65 SP.reconfigure SPsample
@22:03:48.71 SP.seek 1000ml,1:23:53
@22:03:49.67 <sampling> Cartridge 40 sampling 0/1000ml at 0.17ml/s with 3.9psi vacuum
@22:04:49.68 Cartridge 40 sampling 32/1000ml at 0.54ml/s with 24.3psi vacuum
@22:05:49.68 Cartridge 40 sampling 63/1000ml at 0.52ml/s with 24.0psi vacuum
@22:06:49.68 Cartridge 40 sampling 93/1000ml at 0.50ml/s with 20.8psi vacuum
@22:07:49.68 Cartridge 40 sampling 122/1000ml at 0.48ml/s with 22.6psi vacuum
@22:08:49.68 Cartridge 40 sampling 149/1000ml at 0.40ml/s with 21.8psi vacuum
@22:09:49.68 Cartridge 40 sampling 174/1000ml at 0.35ml/s with 21.9psi vacuum
@22:10:49.68 Cartridge 40 sampling 194/1000ml at 0.34ml/s with 23.7psi vacuum
@22:11:49.68 Cartridge 40 sampling 211/1000ml at 0.24ml/s with 25.1psi vacuum
@22:12:49.68 Cartridge 40 sampling 225/1000ml at 0.20ml/s with 22.2psi vacuum
@22:13:49.68 Cartridge 40 sampling 237/1000ml at 0.20ml/s with 26.3psi vacuum
@22:14:44.46 <FILTERING> Flow averaged 0.38ml/s over 10:52
@22:14:44.48 Waiting up to 13 seconds for vacuum to reduce from 27.9 to 18.0psi
@22:14:45.11 Relieved excess vacuum in 5.3 seconds
@22:14:45.13 SP.seek 1000ml,1:03:10
@22:14:49.75 <sampling> Cartridge 40 sampling 249/1000ml at 0.20ml/s with 26.0psi vacuum
@22:14:56.72 <FILTERING> Flow estimate reduced to 0.31ml/s
@22:14:56.75 Waiting up to 14 seconds for vacuum to reduce from 28.3 to 18.0psi
@22:14:57.37 Relieved excess vacuum in 7.5 seconds
@22:14:57.65 SP.seek 1000ml,1:02:58
@22:14:59.90 Flow estimate reduced to 0.24ml/s
@22:14:59.93 Waiting up to 11 seconds for vacuum to reduce from 27.0 to 18.0psi
@22:15:00.55 Relieved excess vacuum in 2.8 seconds
@22:15:00.57 SP.seek 1000ml,1:02:57
@22:15:10.57 Flow estimate reduced to 0.20ml/s
@22:15:10.59 <closeIntake> Intake.close
@22:15:10.65 <closeExhaust> Exhaust.close
@22:15:11.43 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged
@22:15:11.54 <FILTERING> Sampled  253.0ml
@22:15:11.62 TV.seek :bypass
@22:15:13.23 TV.seek :clear
@22:15:15.43 <LRAUV> -> Cmd.startProcessing
@22:15:17.22 <PROCESSING> SPR.command "read_sequences"
@22:15:17.25 SPR.command "stats_on"
@22:15:17.29 SPR.command "start 0 1"
@22:15:19.30 <SPRlogger> Begun log /var/log/esp/spr18aug2622h.spr
@22:15:20.10 <PROCESSING> Handoff.select :waste
@22:15:20.22 ArS.seek empty
@22:15:37.93 PinPort.seek :cartridge
@22:15:43.64 LyS1.seek 1350ul
@22:15:49.59 PV.seek :reagent
@22:15:50.42 AirS.seek :tVent
@22:15:52.49 delay 2 seconds
@22:15:54.52 AirS.reconfigure EvacAirS
@22:15:54.57 AirS.seek :evac
@22:16:16.89 delay 15 seconds
@22:16:31.92 AirS.reconfigure AirSconfig
@22:16:31.97 AirS.seek :tVent
@22:16:46.19 delay 2 seconds
@22:16:48.22 AirS.reconfigure EvacAirS
@22:16:48.27 AirS.seek :evac
@22:17:10.57 delay 15 seconds
@22:17:25.60 AirS.reconfigure AirSconfig
@22:17:25.65 AirS.seek :tVent
@22:17:39.87 delay 2 seconds
@22:17:41.90 AirS.reconfigure EvacAirS
@22:17:41.95 AirS.seek :evac
@22:18:04.24 delay 15 seconds
@22:18:19.27 AirS.reconfigure AirSconfig
@22:18:19.33 AirS.seek :tVent
@22:18:33.55 delay 2 seconds
@22:18:35.59 AirS.reconfigure EvacAirS
@22:18:35.64 AirS.seek :evac
@22:18:57.94 delay 15 seconds
@22:19:12.97 AirS.reconfigure AirSconfig
@22:19:13.02 AirS.seek :tVent
@22:19:27.24 delay 2 seconds
@22:19:29.27 AirS.reconfigure EvacAirS
@22:19:29.32 AirS.seek :evac
@22:19:51.64 delay 15 seconds
@22:20:06.66 delay 1:30
@22:21:36.69 AirS.reconfigure AirSconfig
@22:21:36.74 AirS.seek :fVent
@22:21:38.18 delay 15 seconds
@22:21:53.20 LyS1.reconfigure SlowLyS1
@22:21:53.31 LyS1.seek 1050ul
@22:22:23.34 delay 5 seconds
@22:22:28.37 Handoff.select :blocked
@22:22:28.53 LyS1.seek 450ul
@22:23:28.49 delay 10 seconds
@22:23:38.51 AirS.reconfigure SlowAirS
@22:23:38.62 AirS.reconfigure to mix into 1.37806 parts LyS1
@22:23:39.03 <AirSmixer> AirS.seek evac
@22:23:39.07 <PROCESSING> LyS1.seek 300ul
@22:23:54.18 AirS.reconfigure AirSconfig
@22:23:54.25 AirS.seek :full
@22:24:08.46 LyS1.seek :empty
@22:24:38.56 PuckHeater.seek 84.3C
@22:25:46.23 PuckHeater.hold 15:00,84.3C
@22:40:41.08 LyS1.reconfigure LyS1config
@22:40:41.14 LyS1.seek 900ul
@22:40:49.49 AirS.seek :tVent
@22:40:49.57 delay 2 seconds
@22:40:51.60 AirS.reconfigure HalfSpeedAirS
@22:40:51.65 AirS.seek :evac
@22:41:19.68 delay 15 seconds
@22:41:34.71 AirS.reconfigure AirSconfig
@22:41:34.76 AirS.seek :tVent
@22:41:48.99 delay 2 seconds
@22:41:51.02 AirS.reconfigure HalfSpeedAirS
@22:41:51.07 AirS.seek :evac
@22:42:18.84 delay 15 seconds
@22:42:33.87 AirS.reconfigure AirSconfig
@22:42:33.92 AirS.seek :tVent
@22:42:48.15 delay 2 seconds
@22:42:50.18 AirS.reconfigure HalfSpeedAirS
@22:42:50.23 AirS.seek :evac
@22:43:17.99 delay 15 seconds
@22:43:33.01 delay 1:00
@22:44:33.03 Handoff.select :waste
@22:44:33.13 delay 15 seconds
@22:44:48.16 AirS.seek :fVent
@22:44:50.35 delay 10 seconds
@22:45:00.37 AirS.reconfigure AirSconfig
@22:45:00.43 AirS.seek :evac
@22:45:01.87 Lysis Recovered in LyS1
@22:45:01.88 Lysis Complete
@22:45:01.89 Handoff.select :waste
@22:45:01.92 LyS2.seek :full
@22:45:02.01 LyS1.reconfigure DAmixLyS1
@22:45:02.06 LyS2.reconfigure DAmixLyS2
@22:45:02.46 <LyS2mixer> LyS2.seek 1480ul
@22:45:02.50 <PROCESSING> LyS1.seek 650ul
@22:45:49.87 delay 5 seconds
@22:45:54.89 Handoff.select :SPR
@22:45:55.01 SPR.command "start 2 14"
@22:45:55.95 Running Experiment 2
@22:45:55.96 delay 10 seconds
@22:46:05.98 SPR.command "start 2 0"
@22:46:09.32 Waiting up to 11:00 for SPR to enter state 1
@22:53:27.61 SPR.command "hold_on"
@22:53:29.31 <LyS2mixer> LyS2.seek 1130ul
@22:53:29.35 <PROCESSING> LyS1.seek 300ul
@22:54:35.55 delay 15 seconds
@22:54:50.57 SPR.command "hold_off"
@22:54:53.81 Waiting up to 25:00 for SPR to enter state 7
@23:06:30.75 Handoff.select :waste
@23:06:30.87 LyS1.reconfigure LyS1config
@23:06:30.93 LyS2.reconfigure LyS2config
@23:06:30.99 Rep #1 SPR Analysis has Completed
@23:06:31.68 <SPRlogger> Ended log /var/log/esp/spr18aug2622h.spr
@23:06:31.69 <PROCESSING> SPR.command "stats_off"
@23:06:33.71 <LRAUV> -> Cmd.stop
@23:06:33.80 <closeHandoff> Handoff.select :close
@23:06:33.97 <LRAUV> Gate.power :heat,:OFF
@23:06:33.99 Gate.power :cartridge,:OFF
@23:06:34.02 PV.seek :locked
@23:06:34.92 PinPort.seek :clear
@23:06:40.92 <ArS> ArS.seek :clear
@23:06:41.05 <LyS1> LyS1.seek :clear
@23:06:41.18 <LyS2> LyS2.seek :clear
@23:06:41.33 <AirS> AirS.seek :clear
@23:06:58.68 <LRAUV> ArS.coast
@23:06:58.72 LyS1.coast
@23:06:58.77 LyS2.coast
@23:06:58.82 AirS.coast
@23:06:58.88 TV.coast
@23:06:58.93 SP.coast
@23:06:58.98 SC.coast
@23:06:59.03 PV.coast
@23:06:59.08 PinPort.coast
@23:06:59.26 Gate.power :motor,:OFF
@23:06:59.28 Gate.power :main,:OFF
@23:06:59.30 Gate.power :core,:OFF
@23:06:59.32 Gate.power :analytic1,:OFF
@23:06:59.34 Gate.power :analytic2,:OFF
@23:06:59.36 Gate.power :analytic3,:OFF
@23:06:59.38 Safely stopped and ready to power off