@21:06:37.87EDT15-Aug-19 <MAIN> >BREAK< ESP3 Gateway v0.99 for core bus
@21:06:37.88 >BREAK< ESP3 Gateway v0.99 for cartridge bus
@21:06:39.46 
Configuration for honu by brent@mbari.org 7/26/19
/home/esp/esp2/type/auv/honu/configure.rb
@21:06:42.18 Polling Can every 5:00
@21:06:42.48 Clients may connect on TCP port 9999
@21:06:42.49 Connecting to 10.89.5.2:9999
@21:06:42.52 Errno::ECONNREFUSED in MAIN -- Could not connect to initial client because Connection refused - connect(2)
@21:06:42.53 Waiting for espclients...
@21:06:50.80 <preston> Session Begun
@21:08:10.85 -> SC
@21:08:10.87 I2C::NodeOffline in preston -- carousel0 microcontroller never signed on while reading Storage Carousel (SC)'s status
@21:08:40.96 -> ESP.ready!
@21:08:41.08 Gate.power :main,:ON,limit to 5000mA
@21:08:41.09 Gate.power :analytic1,:ON,limit to 5000mA
@21:08:41.12 Gate.power :analytic2,:ON,limit to 5000mA
@21:08:41.14 Gate.power :analytic3,:ON,limit to 5000mA
@21:08:41.28 Powering core bus
@21:08:43.55 <Probe-AMspr> SPR Module 1, FW Rev. B, FPGA Rev. C
@21:08:43.68 <preston> Archive.configure :default
@21:08:43.81 Air.configure :default
@21:08:43.91 Lysis1.configure :default
@21:08:44.01 Lysis2.configure :default
@21:08:44.13 Plunge.configure :default
@21:08:44.25 SamplePump.configure :default
@21:08:44.38 ExtraPump.configure :default
@21:08:44.50 Toroid.configure :default
@21:08:44.62 Carousel.configure :default
@21:08:44.74 Pressure.configure :default
@21:08:44.84 PinPort.configure :default
@21:08:44.90 All elf controllers running firmware version 4.58
@21:08:44.93 Gate.power :motor,:ON,limit to 5000mA
@21:08:45.41 <Toroid_home> Toroid.reconfigure :default
@21:08:45.46 <Plunge_home> Plunge.reconfigure :default
@21:08:45.49 <Lysis1_home> Lysis1.reconfigure :default
@21:08:45.53 <Archive_home> Archive.reconfigure :default
@21:08:45.57 <PinPort_home> PinPort.reconfigure :default
@21:08:45.61 <Lysis2_home> Lysis2.reconfigure :default
@21:08:45.69 <Air_home> Air.reconfigure :default
@21:08:45.90 Air.home.jog 0
@21:08:45.96 <Lysis1_home> Lysis1.home.jog 0
@21:08:46.03 <Archive_home> Archive.home.jog 0
@21:08:46.08 <PinPort_home> PinPort.home.jog 0
@21:08:46.14 <Lysis2_home> Lysis2.home.jog 0
@21:08:46.20 <Plunge_home> Plunge.home.jog 0
@21:08:46.26 <Toroid_home> Toroid.home.jog 0
@21:08:46.80 Toroid.reconfigure :homing
@21:08:46.82 <Plunge_home> Plunge.reconfigure :homing
@21:08:46.85 <Air_home> Air.reconfigure :homing
@21:08:46.89 <Archive_home> Archive.reconfigure :homing
@21:08:46.93 <PinPort_home> PinPort.reconfigure :homing
@21:08:46.98 <Lysis1_home> Lysis1.reconfigure :homing
@21:08:47.02 <Lysis2_home> Lysis2.reconfigure :homing
@21:08:47.26 Lysis2.jog 1422
@21:08:47.32 <Archive_home> Archive.jog 1383
@21:08:47.38 <Plunge_home> Plunge.jog 1551
@21:08:47.44 <Lysis1_home> Lysis1.jog 1399
@21:08:47.49 <Toroid_home> Toroid.jog 632
@21:08:47.56 <PinPort_home> PinPort.jog -105
@21:08:47.62 <Air_home> Air.jog 1408
@21:08:47.78 <PinPort_home> PinPort.setRawPosition! 0
@21:08:47.83 PinPort.seek :home
@21:08:47.92 PinPort.reconfigure :default
@21:08:48.43 <Lysis2_home> Lysis2.setRawPosition! 50
@21:08:48.49 Lysis2.seek :home
@21:08:48.58 <Archive_home> Archive.setRawPosition! 50
@21:08:48.63 Archive.seek :home
@21:08:48.72 <Plunge_home> Plunge.setRawPosition! 50
@21:08:48.73 <Lysis1_home> Lysis1.setRawPosition! 50
@21:08:48.82 <Plunge_home> Plunge.seek :home
@21:08:48.86 <Lysis1_home> Lysis1.seek :home
@21:08:49.01 <Lysis2_home> Lysis2.reconfigure :default
@21:08:49.04 <Air_home> Air.setRawPosition! 50
@21:08:49.06 <Archive_home> Archive.reconfigure :default
@21:08:49.21 <Air_home> Air.seek :home
@21:08:49.35 <Plunge_home> Plunge.reconfigure :default
@21:08:49.37 <Lysis1_home> Lysis1.reconfigure :default
@21:08:49.54 <Air_home> Air.reconfigure :default
@21:08:49.64 <Toroid_home> Toroid.setRawPosition! 0
@21:08:49.69 Toroid.seek :home
@21:08:49.78 Toroid.reconfigure :default
@21:08:49.92 <preston> Toroid.seek :clear
@21:08:51.55 Carousel.reconfigure :default
@21:08:51.63 Carousel.home.jog 0
@21:08:52.12 Carousel.seek 58
@21:09:11.09 -> SC
@21:09:15.41 -> to 58
@21:09:15.65 Carousel.seek 58
@21:09:15.82 Lysis1.seek :locked
@21:09:19.95 <Plunge> Plunge.seek :locked
@21:09:20.08 <Air> Air.seek :locked
@21:09:24.20 <preston> Powering cartridge bus
@21:09:26.33 Gate.setIR 1750,5000,0,6
@21:09:26.46 Gate.setIR 0,5000,0,6
@21:09:26.52 Loaded Lyse-n-Go Cartridge
@21:09:26.53 PuckHeater.configure HeatConfig
@21:09:26.58 Gate.power :heat,:ON,limit to 5000mA
@21:11:15.89 -> Handoff
@21:11:41.73 -> PP.cartridge
@21:11:41.75 PinPort.seek :cartridge
@21:13:04.15 -> LyS1.to 80
@21:13:04.17 Lysis1.seek 80ul
@21:13:30.25 -> lyS2.to 1700
@21:13:30.27 NoMethodError in preston -- private method `to' called for :lyS2:Symbol
@21:14:00.38 -> LyS2.to 1700
@21:14:00.40 Lysis2.seek 1700ul
@21:14:55.42 -> Syringe.PSI
@21:15:30.80 -> AirS.fvent
@21:15:30.83 Air.seek :fvent
@21:16:01.13 -> Syringe.PSI
@21:17:31.04 -> AirS.full
@21:17:31.06 Air.seek :full
@21:18:01.03 -> Syringe.PSI
@21:19:37.46 -> AirS.evac; delay 2; Syringe.PSI; AirS.full
@21:19:37.49 Air.seek :evac
@21:19:51.71 delay 2 seconds
@21:19:53.78 Air.seek :full
@21:20:01.68 Air.hold
@21:20:01.72 Errno::EPIPE in preston aborter-'s_reader -- Broken pipe
@21:20:01.75 Errno::EPIPE in preston's_reader -- Broken pipe
@21:20:01.76 Session Ended
@21:20:07.97 I2C::UnexpectedReply in core -- I2C::Servo::AbsMove3Reply[5D:43->20]
@21:20:37.46 <preston> Session Begun
@21:21:01.90 -> Syringe.PSI
@21:21:32.02 -> AirS
@21:21:53.08 -> Cartridge.sampler.recoverLysate
@21:21:53.09 Handoff.select :blocked
@21:21:53.28 Lysis1.reconfigure :halfSpeed
@21:21:53.35 Lysis1.seek 280ul
@21:21:57.12 delay 1 second
@21:21:58.14 Lysis1.seek :empty
@21:22:03.36 Initial AirS pressure @27.6psi
@21:22:03.43 Lysis1.reconfigure :extract
@21:22:03.53 Lysis1.seek 900ul,10:00
@21:22:04.68 <pump> Air pumping 10 strokes to reach 24psi
@21:22:04.69 Air stroke #1 of 10
@21:22:04.70 Air.reconfigure :pressurize
@21:22:04.79 Air.seek :pressurized
@21:22:17.62 20.3psi after pressurize
@21:22:17.64 Air.reconfigure :compress
@21:22:17.72 Air.seek :evac
@21:22:20.38 19.4psi after compress
@21:22:34.23 18.7psi after compress (stable)
@21:22:34.25 Air.reconfigure :gulp
@21:22:34.32 Air.seek :tVent
@21:22:44.20 18.7psi after gulp
@21:22:44.22 Air stroke #2 of 10
@21:22:44.24 Air.reconfigure :pressurize
@21:22:44.32 Air.seek :pressurized
@21:22:57.14 19.7psi after pressurize
@21:22:57.16 Air.reconfigure :compress
@21:22:57.24 Air.seek :evac
@21:22:59.90 21.2psi after compress
@21:23:00.52 Reached 24.5 of 24.0psi after pumping 4672ul (3.00 strokes)
@21:23:02.37 <preston> Lysis1 at 900ul @21.0psi
@21:23:02.39 Lysis1.reconfigure :default
@21:23:02.47 Lysis1.seek 925ul
@21:23:03.18 Syringe::Overpressure in preston -- 21.9psi after recovery pull
@21:23:26.27 -> AirS.fVent
@21:23:26.29 Air.reconfigure :default
@21:23:26.41 Air.seek :fVent
@21:23:51.21 -> Handoff.waste
@21:23:51.22 Handoff.select :waste
@21:24:21.21 -> Syringe.psi
@21:24:51.23 -> LyS1
@21:24:55.69 -> AirS.full
@21:24:55.71 Air.seek :full
@21:25:11.30 -> LyS2
@21:25:27.60 -> HaNDOFF
@21:25:27.61 NameError in preston -- uninitialized constant HaNDOFF
@21:25:31.38 -> HANDOFF
@21:25:31.39 NameError in preston -- uninitialized constant HANDOFF
@21:25:51.81 -> Handoff
@21:26:26.09 -> microSPRmanual
@21:26:27.71 SPR.command "read_sequences"
@21:26:28.46 SPR.command "stats_on"
@21:26:28.50 SPR.command "start 0 1"
@21:26:30.51 <SPRlogger> Begun log /var/log/esp/spr19aug1521h.spr
@21:26:31.31 <preston> Wait 10 minutes for baseline stabilization
@21:26:31.32 delay 10:00
@21:36:31.35 Handoff.select :waste
@21:36:31.37 Lysis1.reconfigure :DAmix
@21:36:31.51 Lysis2.reconfigure :DAmix
@21:36:31.90 <Lysis1mixer> Lysis1.seek 675ul
@21:36:31.98 <preston> Lysis2.seek 1450ul
@21:37:07.03 delay 5 seconds
@21:37:12.05 Handoff.select SPR
@21:37:12.17 SPR.command "start 2 14"
@21:37:13.63 delay 10 seconds
@21:37:23.65 SPR.command "start 2 0"
@21:37:26.89 Waiting up to 11:00 for SPR to enter state 1
@21:45:58.96 SPR.command "hold_on"
@21:46:00.35 SPR Dilution 1 of 1 -- Running Experiment 2
@21:46:00.84 <Lysis1mixer> Lysis1.seek 375ul
@21:46:00.88 <preston> Lysis2.seek 1150ul
@21:46:42.92 volL1 = 300.0 and volL2 = 300.0
@21:46:42.93 delay 15 seconds
@21:46:57.96 SPR.command "hold_off"
@21:47:01.00 Waiting up to 25:00 for SPR to enter state 7
@21:58:37.52 SPR Analysis has Completed
@21:58:37.53 Handoff.select :waste
@21:58:38.45 <SPRlogger> Ended log /var/log/esp/spr19aug1521h.spr
@21:58:38.46 <preston> SPR.command "stats_off"
@21:59:26.98 -> exitexit
@21:59:29.35 -> exit
@21:59:29.36 Session Ended
@22:11:54.03 <preston> Session Begun
@22:12:18.19 -> slots
@22:13:25.25 -> Cartridge.release 57..3
@22:13:48.59 -> s
@22:14:18.56 -> slots
@22:14:45.95 -> exit
@22:14:45.96 Session Ended