@01:45:05.02EDT31-Aug-18 <MAIN> >BREAK< ESP3 Gateway v0.99 for core bus
@01:45:05.04 >BREAK< ESP3 Gateway v0.99 for cartridge bus
@01:45:06.29 
Configuration for honu by brent@mbari.org
/home/esp/esp2/type/auv/honu/configure.rb
@01:45:07.60 Polling Can every 5:00
@01:45:07.90 Clients may connect on TCP port 9999
@01:45:07.91 Connecting to 10.89.5.2:9999
@01:45:07.95 Waiting for espclients...
@01:45:08.36 <LRAUV> Session Begun
@01:45:10.30 -> showStatus
@01:45:12.31 -> Cmd.loadCartridge
@01:45:12.43 Gate.power :main,:ON,limit to 5000mA
@01:45:12.45 Gate.power :analytic1,:ON,limit to 5000mA
@01:45:12.47 Gate.power :analytic2,:ON,limit to 5000mA
@01:45:12.49 Gate.power :analytic3,:ON,limit to 5000mA
@01:45:12.63 Powering core bus
@01:45:14.93 <Probe-AMspr> SPR Module 1, FW Rev. B, FPGA Rev. C
@01:45:15.03 <LRAUV> ArS.configure ArSconfig
@01:45:15.13 LyS1.configure LyS1config
@01:45:15.24 LyS2.configure LyS2config
@01:45:15.35 AirS.configure AirSconfig
@01:45:15.47 TV.configure TVconfig
@01:45:15.58 SP.configure SPconfig
@01:45:15.70 SC.configure SCconfig
@01:45:15.81 PV.configure PVconfig
@01:45:15.93 PinPort.configure PPconfig
@01:45:16.05 Sea.configure SPconfig
@01:45:16.11 All elf controllers running firmware version 4.5
@01:45:16.13 Gate.power :motor,:ON,limit to 5000mA
@01:45:16.38 <ArS_home> ArS.reconfigure ArSconfig
@01:45:16.43 <LyS1_home> LyS1.reconfigure LyS1config
@01:45:16.65 LyS1.home.jog 0
@01:45:16.67 <ArS_home> ArS.home.jog 0
@01:45:16.83 <PV_home> PV.reconfigure PVconfig
@01:45:16.90 <TV_home> TV.reconfigure TVconfig
@01:45:16.96 <LyS2_home> LyS2.reconfigure LyS2config
@01:45:17.02 <PinPort_home> PinPort.reconfigure PPconfig
@01:45:17.05 <AirS_home> AirS.reconfigure AirSconfig
@01:45:17.21 <TV_home> TV.home.jog 0
@01:45:17.25 <PV_home> PV.home.jog 0
@01:45:17.28 <LyS2_home> LyS2.home.jog 0
@01:45:17.31 <PinPort_home> PinPort.home.jog 0
@01:45:17.33 <AirS_home> AirS.home.jog 0
@01:45:17.98 <TV_home> TV.reconfigure HomeTV
@01:45:18.00 <LyS2_home> LyS2.reconfigure HomeSyringe
@01:45:18.03 <PinPort_home> PinPort.reconfigure HomePP
@01:45:18.08 <PV_home> PV.reconfigure HomePV
@01:45:18.11 <AirS_home> AirS.reconfigure HomeMagSyringe
@01:45:18.15 <ArS_home> ArS.reconfigure HomeMagSyringe
@01:45:18.20 <LyS1_home> LyS1.reconfigure HomeSyringe
@01:45:18.38 <LyS2_home> LyS2.jog -261
@01:45:18.42 <TV_home> TV.jog -545
@01:45:18.45 <PV_home> PV.jog -1477
@01:45:18.48 <PinPort_home> PinPort.jog -110
@01:45:18.53 <AirS_home> AirS.jog -320
@01:45:18.57 <LyS1_home> LyS1.jog -266
@01:45:18.60 <ArS_home> ArS.jog -242
@01:45:19.05 <PinPort_home> PinPort.setRawPosition! 0
@01:45:19.09 PinPort.seek :home
@01:45:19.46 PinPort.reconfigure PPconfig
@01:45:19.50 <AirS_home> AirS.setRawPosition! -50
@01:45:19.52 <LyS2_home> LyS2.setRawPosition! -50
@01:45:19.55 <ArS_home> ArS.setRawPosition! -50
@01:45:19.58 <LyS1_home> LyS1.setRawPosition! -50
@01:45:19.70 <AirS_home> AirS.seek :home
@01:45:19.80 <LyS2_home> LyS2.seek :home
@01:45:19.87 <LyS1_home> LyS1.seek :home
@01:45:19.90 <ArS_home> ArS.seek :home
@01:45:20.08 <AirS_home> AirS.reconfigure AirSconfig
@01:45:20.10 <PV_home> PV.setRawPosition! -50
@01:45:20.18 PV.seek :home
@01:45:20.36 <LyS1_home> LyS1.reconfigure LyS1config
@01:45:20.39 <LyS2_home> LyS2.reconfigure LyS2config
@01:45:20.43 <ArS_home> ArS.reconfigure ArSconfig
@01:45:20.62 <PV_home> PV.reconfigure PVconfig
@01:45:20.64 <TV_home> TV.setRawPosition! 0
@01:45:20.73 TV.seek :home
@01:45:20.82 TV.reconfigure TVconfig
@01:45:21.05 <LRAUV> TV.seek :clear
@01:45:22.68 TV.coast
@01:45:22.73 SC.reconfigure HomeSC
@01:45:22.80 SC.home.jog 0
@01:45:23.55 SC.reconfigure SCconfig
@01:45:23.60 SC.seek 21
@01:45:23.70 Selecting Cartridge 20
@01:45:24.01 SC.seek 20
@01:45:24.52 <LyS2> LyS2.seek :locked
@01:45:24.65 <PV> PV.seek :locked
@01:45:24.77 <AirS> AirS.seek :locked
@01:45:28.85 <LRAUV> Powering cartridge bus
@01:45:30.98 Gate.setIR 1750,5000,0,6
@01:45:31.11 Gate.setIR 0,5000,0,6
@01:45:31.16 Loaded Lyse-n-Go Cartridge
@01:45:31.17 PuckHeater.configure HeatConfig
@01:45:31.23 Gate.power :heat,:ON,limit to 5000mA
@01:45:51.07 -> Cmd.startFiltering
@01:45:51.29 <FILTERING> Duration of filtering limited to 1:05:00
@01:45:51.55 Priming sample loop w/100ml, bypass w/10ml
@01:45:51.62 SP.setPosition! 0ml
@01:45:51.66 <openIntake> Intake.open
@01:45:51.68 <FILTERING> Exhaust.open
@01:45:52.53 Sea @-42.9psia
@01:45:52.55 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi
@01:45:58.33 Vacuum tare is 25% of full scale
@01:45:58.35 <openPB> PB.open
@01:45:58.40 <FILTERING> Exhaust.close
@01:45:59.20 Sea @-42.9psia
@01:45:59.28 SP.reconfigure SPprime
@01:45:59.35 SP.seek 10ml,1:21
@01:46:10.21 SP.setPosition! 0ml
@01:46:10.25 <closePB> PB.close
@01:46:10.28 <FILTERING> Exhaust.open
@01:46:11.11 Sea @-42.9psia
@01:46:11.19 SP.reconfigure SPprime
@01:46:11.26 SP.seek 100ml,8:51
@01:48:05.12 Intake.close
@01:48:05.89 Pressurizing cartridge to 26psi (try #1 of 3)
@01:48:05.90 SP.setPosition! 0ml
@01:48:05.95 PB.open
@01:48:06.73 TV.seek :crack
@01:48:07.52 TV.seek :clear
@01:48:08.32 Intake.open
@01:48:09.79 PB.close
@01:48:10.50 Exhaust.close
@01:48:11.27 Sea @-42.9psia
@01:48:11.35 SP.reconfigure SPcharge
@01:48:11.42 SP.seek 2.3ml,42 seconds
@01:48:22.80 Cartridge pressurized with 0.606ml to 26.8psi
@01:48:23.03 delay 3 seconds
@01:48:26.10 Cartridge at 19.9psi 3 seconds later
@01:48:26.18 TV.seek :cartridge
@01:48:28.00 TV.seek :clear
@01:48:29.81 Sampling 1000.0ml
@01:48:29.82 SP.setPosition! 0ml
@01:48:29.87 Exhaust.open
@01:48:30.63 Sea @-42.9psia
@01:48:30.72 SP.reconfigure SPsample
@01:48:30.78 SP.seek 1000ml,1:23:53
@01:48:31.74 <sampling> Cartridge 20 sampling 0/1000ml at 0.17ml/s with 4.2psi vacuum
@01:49:31.75 Cartridge 20 sampling 33/1000ml at 0.54ml/s with 26.0psi vacuum
@01:50:31.75 Cartridge 20 sampling 65/1000ml at 0.53ml/s with 24.3psi vacuum
@01:51:31.75 Cartridge 20 sampling 98/1000ml at 0.52ml/s with 20.4psi vacuum
@01:52:31.75 Cartridge 20 sampling 129/1000ml at 0.53ml/s with 21.9psi vacuum
@01:53:31.75 Cartridge 20 sampling 161/1000ml at 0.53ml/s with 22.0psi vacuum
@01:54:31.75 Cartridge 20 sampling 192/1000ml at 0.51ml/s with 21.5psi vacuum
@01:55:31.75 Cartridge 20 sampling 223/1000ml at 0.51ml/s with 24.7psi vacuum
@01:56:31.75 Cartridge 20 sampling 255/1000ml at 0.53ml/s with 21.5psi vacuum
@01:57:31.75 Cartridge 20 sampling 285/1000ml at 0.49ml/s with 23.5psi vacuum
@01:58:31.75 Cartridge 20 sampling 316/1000ml at 0.48ml/s with 24.5psi vacuum
@01:59:31.78 Cartridge 20 sampling 346/1000ml at 0.50ml/s with 21.8psi vacuum
@02:00:31.75 Cartridge 20 sampling 375/1000ml at 0.49ml/s with 24.8psi vacuum
@02:01:31.75 Cartridge 20 sampling 403/1000ml at 0.45ml/s with 25.2psi vacuum
@02:02:32.01 Cartridge 20 sampling 431/1000ml at 0.44ml/s with 19.9psi vacuum
@02:03:31.75 Cartridge 20 sampling 458/1000ml at 0.45ml/s with 21.3psi vacuum
@02:04:31.75 Cartridge 20 sampling 484/1000ml at 0.45ml/s with 20.7psi vacuum
@02:05:31.75 Cartridge 20 sampling 509/1000ml at 0.39ml/s with 23.7psi vacuum
@02:06:31.75 Cartridge 20 sampling 534/1000ml at 0.41ml/s with 25.9psi vacuum
@02:07:31.75 Cartridge 20 sampling 558/1000ml at 0.41ml/s with 24.7psi vacuum
@02:08:31.75 Cartridge 20 sampling 580/1000ml at 0.40ml/s with 22.0psi vacuum
@02:09:31.76 Cartridge 20 sampling 602/1000ml at 0.33ml/s with 19.1psi vacuum
@02:10:31.75 Cartridge 20 sampling 622/1000ml at 0.38ml/s with 22.8psi vacuum
@02:11:31.75 Cartridge 20 sampling 642/1000ml at 0.36ml/s with 25.6psi vacuum
@02:12:31.75 Cartridge 20 sampling 660/1000ml at 0.32ml/s with 19.4psi vacuum
@02:13:31.75 Cartridge 20 sampling 678/1000ml at 0.30ml/s with 25.9psi vacuum
@02:14:31.75 Cartridge 20 sampling 695/1000ml at 0.30ml/s with 23.6psi vacuum
@02:15:31.75 Cartridge 20 sampling 711/1000ml at 0.28ml/s with 19.5psi vacuum
@02:16:31.75 Cartridge 20 sampling 726/1000ml at 0.25ml/s with 24.7psi vacuum
@02:17:31.75 Cartridge 20 sampling 741/1000ml at 0.25ml/s with 23.8psi vacuum
@02:18:31.75 Cartridge 20 sampling 756/1000ml at 0.23ml/s with 24.5psi vacuum
@02:19:31.75 Cartridge 20 sampling 770/1000ml at 0.21ml/s with 23.9psi vacuum
@02:20:31.74 Cartridge 20 sampling 783/1000ml at 0.23ml/s with 19.8psi vacuum
@02:21:31.75 Cartridge 20 sampling 796/1000ml at 0.22ml/s with 20.9psi vacuum
@02:22:31.75 Cartridge 20 sampling 808/1000ml at 0.20ml/s with 22.9psi vacuum
@02:23:31.75 Cartridge 20 sampling 821/1000ml at 0.20ml/s with 27.3psi vacuum
@02:24:31.75 Cartridge 20 sampling 833/1000ml at 0.20ml/s with 23.0psi vacuum
@02:25:31.74 Cartridge 20 sampling 846/1000ml at 0.21ml/s with 21.4psi vacuum
@02:26:31.75 Cartridge 20 sampling 858/1000ml at 0.20ml/s with 25.0psi vacuum
@02:27:31.75 Cartridge 20 sampling 870/1000ml at 0.20ml/s with 26.1psi vacuum
@02:28:24.45 <FILTERING> Flow averaged 0.37ml/s over 39:52
@02:28:24.47 Waiting up to 11 seconds for vacuum to reduce from 27.2 to 18.0psi
@02:28:25.10 Relieved excess vacuum in 3.2 seconds
@02:28:25.12 SP.seek 1000ml,10:27
@02:28:31.75 <sampling> Cartridge 20 sampling 882/1000ml at 0.20ml/s with 31.3psi vacuum
@02:29:19.40 <FILTERING> Flow averaged 0.23ml/s over 47 seconds
@02:29:19.42 Waiting up to 9 seconds for vacuum to reduce from 26.6 to 18.0psi
@02:29:20.05 Relieved excess vacuum in 8.1 seconds
@02:29:20.07 SP.seek 1000ml,9:33
@02:29:31.74 Flow averaged 0.19ml/s over 12 seconds
@02:29:31.75 <closeIntake> Intake.close
@02:29:31.79 <closeExhaust> Exhaust.close
@02:29:31.95 <sampling> Cartridge 20 sampling 894/1000ml at 0.00ml/s with 5.5psi vacuum
@02:29:32.59 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged
@02:29:32.69 <FILTERING> Sampled  893.8ml
@02:29:32.77 TV.seek :bypass
@02:29:34.38 TV.seek :clear
@02:29:36.67 <LRAUV> -> Cmd.startProcessing
@02:29:38.72 <PROCESSING> SPR.command "read_sequences"
@02:29:39.24 SPR.command "stats_on"
@02:29:39.28 SPR.command "start 0 1"
@02:29:41.29 <SPRlogger> Begun log /var/log/esp/spr18aug3102h.spr
@02:29:42.09 <PROCESSING> Handoff.select :waste
@02:29:42.21 ArS.seek empty
@02:29:59.91 PinPort.seek :cartridge
@02:30:05.62 LyS1.seek 1350ul
@02:30:11.56 PV.seek :reagent
@02:30:12.39 AirS.seek :tVent
@02:30:14.46 delay 2 seconds
@02:30:16.49 AirS.reconfigure EvacAirS
@02:30:16.54 AirS.seek :evac
@02:30:38.84 delay 15 seconds
@02:30:53.87 AirS.reconfigure AirSconfig
@02:30:53.92 AirS.seek :tVent
@02:31:08.13 delay 2 seconds
@02:31:10.16 AirS.reconfigure EvacAirS
@02:31:10.21 AirS.seek :evac
@02:31:32.49 delay 15 seconds
@02:31:47.52 AirS.reconfigure AirSconfig
@02:31:47.57 AirS.seek :tVent
@02:32:01.78 delay 2 seconds
@02:32:03.81 AirS.reconfigure EvacAirS
@02:32:03.87 AirS.seek :evac
@02:32:26.15 delay 15 seconds
@02:32:41.18 AirS.reconfigure AirSconfig
@02:32:41.23 AirS.seek :tVent
@02:32:55.43 delay 2 seconds
@02:32:57.46 AirS.reconfigure EvacAirS
@02:32:57.51 AirS.seek :evac
@02:33:19.79 delay 15 seconds
@02:33:34.82 AirS.reconfigure AirSconfig
@02:33:34.87 AirS.seek :tVent
@02:33:49.07 delay 2 seconds
@02:33:51.10 AirS.reconfigure EvacAirS
@02:33:51.15 AirS.seek :evac
@02:34:13.44 delay 15 seconds
@02:34:28.46 delay 1:30
@02:35:58.53 AirS.reconfigure AirSconfig
@02:35:58.59 AirS.seek :fVent
@02:36:00.03 delay 15 seconds
@02:36:15.05 LyS1.reconfigure SlowLyS1
@02:36:15.17 LyS1.seek 1050ul
@02:36:45.18 delay 5 seconds
@02:36:50.20 Handoff.select :blocked
@02:36:50.36 LyS1.seek 450ul
@02:37:50.31 delay 10 seconds
@02:38:00.33 AirS.reconfigure SlowAirS
@02:38:00.45 AirS.reconfigure to mix into 1.37806 parts LyS1
@02:38:00.83 <AirSmixer> AirS.seek evac
@02:38:00.87 <PROCESSING> LyS1.seek 300ul
@02:38:15.97 AirS.reconfigure AirSconfig
@02:38:16.03 AirS.seek :full
@02:38:30.24 LyS1.seek :empty
@02:39:00.33 PuckHeater.seek 84.1C
@02:40:01.65 PuckHeater.hold 15:00,84.1C
@02:54:58.30 LyS1.reconfigure LyS1config
@02:54:58.36 LyS1.seek 900ul
@02:55:06.70 AirS.seek :tVent
@02:55:06.78 delay 2 seconds
@02:55:08.81 AirS.reconfigure HalfSpeedAirS
@02:55:08.87 AirS.seek :evac
@02:55:36.81 delay 15 seconds
@02:55:51.89 AirS.reconfigure AirSconfig
@02:55:51.94 AirS.seek :tVent
@02:56:06.16 delay 2 seconds
@02:56:08.19 AirS.reconfigure HalfSpeedAirS
@02:56:08.24 AirS.seek :evac
@02:56:35.97 delay 15 seconds
@02:56:51.00 AirS.reconfigure AirSconfig
@02:56:51.05 AirS.seek :tVent
@02:57:05.27 delay 2 seconds
@02:57:07.30 AirS.reconfigure HalfSpeedAirS
@02:57:07.35 AirS.seek :evac
@02:57:35.12 delay 15 seconds
@02:57:50.13 delay 1:00
@02:58:50.15 Handoff.select :waste
@02:58:50.25 delay 15 seconds
@02:59:05.28 AirS.seek :fVent
@02:59:07.47 delay 10 seconds
@02:59:17.50 AirS.reconfigure AirSconfig
@02:59:17.57 AirS.seek :evac
@02:59:19.01 Lysis Recovered in LyS1
@02:59:19.02 Lysis Complete
@02:59:19.03 Handoff.select :waste
@02:59:19.05 LyS2.seek :full
@02:59:19.14 LyS1.reconfigure DAmixLyS1
@02:59:19.19 LyS2.reconfigure DAmixLyS2
@02:59:19.59 <LyS2mixer> LyS2.seek 1480ul
@02:59:19.62 <PROCESSING> LyS1.seek 650ul
@03:00:06.95 delay 5 seconds
@03:00:11.96 Handoff.select :SPR
@03:00:12.07 SPR.command "start 2 14"
@03:00:13.21 Running Experiment 2
@03:00:13.22 delay 10 seconds
@03:00:23.24 SPR.command "start 2 0"
@03:00:27.08 Waiting up to 11:00 for SPR to enter state 1
@03:07:45.82 SPR.command "hold_on"
@03:07:48.00 <LyS2mixer> LyS2.seek 1130ul
@03:07:48.03 <PROCESSING> LyS1.seek 300ul
@03:08:54.21 delay 15 seconds
@03:09:09.23 SPR.command "hold_off"
@03:09:12.97 Waiting up to 25:00 for SPR to enter state 7
@03:20:48.96 Handoff.select :waste
@03:20:49.08 LyS1.reconfigure LyS1config
@03:20:49.14 LyS2.reconfigure LyS2config
@03:20:49.21 Rep #1 SPR Analysis has Completed
@03:20:49.89 <SPRlogger> Ended log /var/log/esp/spr18aug3102h.spr
@03:20:49.90 <PROCESSING> SPR.command "stats_off"
@03:20:52.02 <LRAUV> -> Cmd.stop
@03:20:52.11 <closeHandoff> Handoff.select :close
@03:20:52.28 <LRAUV> Gate.power :heat,:OFF
@03:20:52.29 Gate.power :cartridge,:OFF
@03:20:52.32 PV.seek :locked
@03:20:53.21 PinPort.seek :clear
@03:20:59.22 <ArS> ArS.seek :clear
@03:20:59.35 <LyS1> LyS1.seek :clear
@03:20:59.48 <LyS2> LyS2.seek :clear
@03:20:59.61 <AirS> AirS.seek :clear
@03:21:16.97 <LRAUV> ArS.coast
@03:21:17.02 LyS1.coast
@03:21:17.07 LyS2.coast
@03:21:17.11 AirS.coast
@03:21:17.16 TV.coast
@03:21:17.21 SP.coast
@03:21:17.26 SC.coast
@03:21:17.32 PV.coast
@03:21:17.37 PinPort.coast
@03:21:17.54 Gate.power :motor,:OFF
@03:21:17.56 Gate.power :main,:OFF
@03:21:17.58 Gate.power :core,:OFF
@03:21:17.60 Gate.power :analytic1,:OFF
@03:21:17.62 Gate.power :analytic2,:OFF
@03:21:17.64 Gate.power :analytic3,:OFF
@03:21:17.66 Safely stopped and ready to power off