@06:52:09.64EDT24-Aug-18 <MAIN> >BREAK< ESP3 Gateway v0.99 for core bus
@06:52:09.66 >BREAK< ESP3 Gateway v0.99 for cartridge bus
@06:52:10.92 
Configuration for honu by brent@mbari.org
/home/esp/esp2/type/auv/honu/configure.rb
@06:52:12.10 Polling Can every 5:00
@06:52:12.40 Clients may connect on TCP port 9999
@06:52:12.41 Connecting to 10.89.5.2:9999
@06:52:12.45 Waiting for espclients...
@06:52:12.69 <LRAUV> Session Begun
@06:52:14.65 -> showStatus
@06:52:16.65 -> Cmd.loadCartridge
@06:52:16.77 Gate.power :main,:ON,limit to 5000mA
@06:52:16.79 Gate.power :analytic1,:ON,limit to 5000mA
@06:52:16.81 Gate.power :analytic2,:ON,limit to 5000mA
@06:52:16.83 Gate.power :analytic3,:ON,limit to 5000mA
@06:52:16.97 Powering core bus
@06:52:19.27 <Probe-AMspr> SPR Module 1, FW Rev. B, FPGA Rev. C
@06:52:19.39 <LRAUV> ArS.configure ArSconfig
@06:52:19.49 LyS1.configure LyS1config
@06:52:19.61 LyS2.configure LyS2config
@06:52:19.72 AirS.configure AirSconfig
@06:52:19.84 TV.configure TVconfig
@06:52:19.95 SP.configure SPconfig
@06:52:20.07 SC.configure SCconfig
@06:52:20.19 PV.configure PVconfig
@06:52:20.31 PinPort.configure PPconfig
@06:52:20.43 Sea.configure SPconfig
@06:52:20.49 All elf controllers running firmware version 4.5
@06:52:20.51 Gate.power :motor,:ON,limit to 5000mA
@06:52:20.66 <ArS_home> ArS.reconfigure ArSconfig
@06:52:20.91 ArS.home.jog 0
@06:52:21.06 <PV_home> PV.reconfigure PVconfig
@06:52:21.14 <TV_home> TV.reconfigure TVconfig
@06:52:21.18 <LyS2_home> LyS2.reconfigure LyS2config
@06:52:21.23 <AirS_home> AirS.reconfigure AirSconfig
@06:52:21.28 <PinPort_home> PinPort.reconfigure PPconfig
@06:52:21.31 <LyS1_home> LyS1.reconfigure LyS1config
@06:52:21.50 <PV_home> PV.home.jog 0
@06:52:21.53 <TV_home> TV.home.jog 0
@06:52:21.56 <LyS2_home> LyS2.home.jog 0
@06:52:21.59 <AirS_home> AirS.home.jog 0
@06:52:21.61 <PinPort_home> PinPort.home.jog 0
@06:52:21.64 <LyS1_home> LyS1.home.jog 0
@06:52:22.11 <ArS_home> ArS.reconfigure HomeMagSyringe
@06:52:22.33 ArS.jog -245
@06:52:22.49 <TV_home> TV.reconfigure HomeTV
@06:52:22.53 <AirS_home> AirS.reconfigure HomeMagSyringe
@06:52:22.56 <PinPort_home> PinPort.reconfigure HomePP
@06:52:22.59 <PV_home> PV.reconfigure HomePV
@06:52:22.63 <LyS2_home> LyS2.reconfigure HomeSyringe
@06:52:22.67 <LyS1_home> LyS1.reconfigure HomeSyringe
@06:52:22.87 <TV_home> TV.jog -549
@06:52:22.90 <PinPort_home> PinPort.jog -110
@06:52:22.93 <AirS_home> AirS.jog -320
@06:52:22.96 <LyS2_home> LyS2.jog -265
@06:52:22.99 <PV_home> PV.jog -1475
@06:52:23.02 <LyS1_home> LyS1.jog -266
@06:52:23.27 <ArS_home> ArS.setRawPosition! -50
@06:52:23.32 ArS.seek :home
@06:52:23.45 <PinPort_home> PinPort.setRawPosition! 0
@06:52:23.50 PinPort.seek :home
@06:52:23.60 PinPort.reconfigure PPconfig
@06:52:23.94 <AirS_home> AirS.setRawPosition! -50
@06:52:23.97 <LyS2_home> LyS2.setRawPosition! -50
@06:52:23.99 <LyS1_home> LyS1.setRawPosition! -50
@06:52:24.01 <ArS_home> ArS.reconfigure ArSconfig
@06:52:24.12 <AirS_home> AirS.seek :home
@06:52:24.16 <LyS2_home> LyS2.seek :home
@06:52:24.22 <LyS1_home> LyS1.seek :home
@06:52:24.42 <PV_home> PV.setRawPosition! -50
@06:52:24.47 PV.seek :home
@06:52:24.57 <AirS_home> AirS.reconfigure AirSconfig
@06:52:24.68 <LyS1_home> LyS1.reconfigure LyS1config
@06:52:24.70 <LyS2_home> LyS2.reconfigure LyS2config
@06:52:24.84 <PV_home> PV.reconfigure PVconfig
@06:52:24.93 <TV_home> TV.setRawPosition! 0
@06:52:24.98 TV.seek :home
@06:52:25.08 TV.reconfigure TVconfig
@06:52:25.30 <LRAUV> TV.seek :clear
@06:52:26.92 TV.coast
@06:52:26.97 SC.reconfigure HomeSC
@06:52:27.04 SC.home.jog 0
@06:52:27.80 SC.reconfigure SCconfig
@06:52:27.86 SC.seek 53
@06:52:27.96 Selecting Cartridge 52
@06:52:28.26 SC.seek 52
@06:52:28.76 <LyS2> LyS2.seek :locked
@06:52:28.89 <PV> PV.seek :locked
@06:52:29.02 <AirS> AirS.seek :locked
@06:52:33.09 <LRAUV> Powering cartridge bus
@06:52:35.22 Gate.setIR 1750,5000,0,6
@06:52:35.34 Gate.setIR 0,5000,0,6
@06:52:35.39 Loaded Lyse-n-Go Cartridge
@06:52:35.40 PuckHeater.configure HeatConfig
@06:52:35.46 Gate.power :heat,:ON,limit to 5000mA
@06:52:55.39 -> Cmd.startFiltering
@06:52:55.61 <FILTERING> Duration of filtering limited to 1:05:00
@06:52:55.86 Priming sample loop w/100ml, bypass w/10ml
@06:52:55.94 SP.setPosition! 0ml
@06:52:55.98 <openIntake> Intake.open
@06:52:56.01 <FILTERING> Exhaust.open
@06:52:56.81 Sea @-42.9psia
@06:52:56.82 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi
@06:53:02.35 Vacuum tare is 24% of full scale
@06:53:02.62 <openPB> PB.open
@06:53:02.65 <FILTERING> Exhaust.close
@06:53:03.46 Sea @-42.9psia
@06:53:03.54 SP.reconfigure SPprime
@06:53:03.61 SP.seek 10ml,1:21
@06:53:14.47 SP.setPosition! 0ml
@06:53:14.51 <closePB> PB.close
@06:53:14.54 <FILTERING> Exhaust.open
@06:53:15.39 Sea @-42.9psia
@06:53:15.47 SP.reconfigure SPprime
@06:53:15.54 SP.seek 100ml,8:51
@06:55:10.98 Intake.close
@06:55:11.68 Pressurizing cartridge to 26psi (try #1 of 3)
@06:55:11.69 SP.setPosition! 0ml
@06:55:11.74 PB.open
@06:55:12.52 TV.seek :crack
@06:55:13.31 TV.seek :clear
@06:55:14.11 Intake.open
@06:55:15.57 PB.close
@06:55:16.28 Exhaust.close
@06:55:17.07 Sea @-42.9psia
@06:55:17.15 SP.reconfigure SPcharge
@06:55:17.22 SP.seek 2.3ml,42 seconds
@06:55:25.51 Cartridge pressurized with 0.319ml to 26.3psi
@06:55:25.89 delay 3 seconds
@06:55:28.96 Cartridge at 22.0psi 3 seconds later
@06:55:29.04 TV.seek :cartridge
@06:55:30.85 TV.seek :clear
@06:55:32.66 Sampling 1000.0ml
@06:55:32.67 SP.setPosition! 0ml
@06:55:32.72 Exhaust.open
@06:55:33.48 Sea @-42.9psia
@06:55:33.57 SP.reconfigure SPsample
@06:55:33.63 SP.seek 1000ml,1:23:53
@06:55:34.59 <sampling> Cartridge 52 sampling 0/1000ml at 0.17ml/s with 3.7psi vacuum
@06:56:34.60 Cartridge 52 sampling 32/1000ml at 0.51ml/s with 27.7psi vacuum
@06:57:34.59 Cartridge 52 sampling 64/1000ml at 0.54ml/s with 22.3psi vacuum
@06:58:34.60 Cartridge 52 sampling 96/1000ml at 0.53ml/s with 18.5psi vacuum
@06:59:34.60 Cartridge 52 sampling 128/1000ml at 0.53ml/s with 21.8psi vacuum
@07:00:34.60 Cartridge 52 sampling 159/1000ml at 0.50ml/s with 21.1psi vacuum
@07:01:34.60 Cartridge 52 sampling 190/1000ml at 0.50ml/s with 21.1psi vacuum
@07:02:34.60 Cartridge 52 sampling 221/1000ml at 0.53ml/s with 21.4psi vacuum
@07:03:34.60 Cartridge 52 sampling 252/1000ml at 0.49ml/s with 21.8psi vacuum
@07:04:34.60 Cartridge 52 sampling 283/1000ml at 0.51ml/s with 26.1psi vacuum
@07:05:34.60 Cartridge 52 sampling 313/1000ml at 0.52ml/s with 24.2psi vacuum
@07:06:34.60 Cartridge 52 sampling 343/1000ml at 0.52ml/s with 24.4psi vacuum
@07:07:34.60 Cartridge 52 sampling 373/1000ml at 0.51ml/s with 25.2psi vacuum
@07:08:34.60 Cartridge 52 sampling 402/1000ml at 0.48ml/s with 18.0psi vacuum
@07:09:34.60 Cartridge 52 sampling 431/1000ml at 0.48ml/s with 23.6psi vacuum
@07:10:34.60 Cartridge 52 sampling 459/1000ml at 0.48ml/s with 19.1psi vacuum
@07:11:34.86 Cartridge 52 sampling 488/1000ml at 0.47ml/s with 23.9psi vacuum
@07:12:34.60 Cartridge 52 sampling 515/1000ml at 0.43ml/s with 26.8psi vacuum
@07:13:34.60 Cartridge 52 sampling 543/1000ml at 0.45ml/s with 25.3psi vacuum
@07:14:34.60 Cartridge 52 sampling 570/1000ml at 0.45ml/s with 18.4psi vacuum
@07:15:34.60 Cartridge 52 sampling 596/1000ml at 0.45ml/s with 19.3psi vacuum
@07:16:34.60 Cartridge 52 sampling 622/1000ml at 0.40ml/s with 26.5psi vacuum
@07:17:34.60 Cartridge 52 sampling 648/1000ml at 0.39ml/s with 26.3psi vacuum
@07:18:34.60 Cartridge 52 sampling 673/1000ml at 0.41ml/s with 18.3psi vacuum
@07:19:34.60 Cartridge 52 sampling 697/1000ml at 0.41ml/s with 24.9psi vacuum
@07:20:34.60 Cartridge 52 sampling 721/1000ml at 0.40ml/s with 18.7psi vacuum
@07:21:34.60 Cartridge 52 sampling 744/1000ml at 0.35ml/s with 22.4psi vacuum
@07:22:34.60 Cartridge 52 sampling 767/1000ml at 0.39ml/s with 25.6psi vacuum
@07:23:34.60 Cartridge 52 sampling 789/1000ml at 0.34ml/s with 22.2psi vacuum
@07:24:34.60 Cartridge 52 sampling 810/1000ml at 0.35ml/s with 19.2psi vacuum
@07:25:34.60 Cartridge 52 sampling 830/1000ml at 0.31ml/s with 27.6psi vacuum
@07:26:34.60 Cartridge 52 sampling 850/1000ml at 0.28ml/s with 25.6psi vacuum
@07:27:34.60 Cartridge 52 sampling 868/1000ml at 0.30ml/s with 23.0psi vacuum
@07:28:34.60 Cartridge 52 sampling 887/1000ml at 0.29ml/s with 25.0psi vacuum
@07:29:34.60 Cartridge 52 sampling 904/1000ml at 0.28ml/s with 24.5psi vacuum
@07:30:34.60 Cartridge 52 sampling 921/1000ml at 0.31ml/s with 21.3psi vacuum
@07:31:34.60 Cartridge 52 sampling 938/1000ml at 0.25ml/s with 22.5psi vacuum
@07:32:34.60 Cartridge 52 sampling 954/1000ml at 0.24ml/s with 20.9psi vacuum
@07:33:34.60 Cartridge 52 sampling 969/1000ml at 0.28ml/s with 27.3psi vacuum
@07:34:34.60 Cartridge 52 sampling 984/1000ml at 0.28ml/s with 20.5psi vacuum
@07:35:34.60 Cartridge 52 sampling 999/1000ml at 0.22ml/s with 25.8psi vacuum
@07:35:40.66 <closeIntake> Intake.close
@07:35:40.69 <closeExhaust> Exhaust.close
@07:35:41.49 <FILTERING> Sampled  1000.0ml
@07:35:41.57 TV.seek :bypass
@07:35:43.19 TV.seek :clear
@07:35:45.59 <LRAUV> -> Cmd.startProcessing
@07:35:47.38 <PROCESSING> SPR.command "read_sequences"
@07:35:47.78 SPR.command "stats_on"
@07:35:47.82 SPR.command "start 0 1"
@07:35:49.82 <SPRlogger> Begun log /var/log/esp/spr18aug2407h.spr
@07:35:50.84 AnalyticModule::ReadTimeout in PROCESSING -- No response from SPR on AMspr (try 1 of 3)
@07:35:52.52 <PROCESSING> Handoff.select :waste
@07:35:52.64 ArS.seek empty
@07:36:10.36 PinPort.seek :cartridge
@07:36:16.08 LyS1.seek 1350ul
@07:36:22.02 PV.seek :reagent
@07:36:22.85 AirS.seek :tVent
@07:36:25.17 delay 2 seconds
@07:36:27.21 AirS.reconfigure EvacAirS
@07:36:27.26 AirS.seek :evac
@07:36:49.57 delay 15 seconds
@07:37:04.60 AirS.reconfigure AirSconfig
@07:37:04.66 AirS.seek :tVent
@07:37:18.88 delay 2 seconds
@07:37:20.91 AirS.reconfigure EvacAirS
@07:37:20.97 AirS.seek :evac
@07:37:43.30 delay 15 seconds
@07:37:58.32 AirS.reconfigure AirSconfig
@07:37:58.37 AirS.seek :tVent
@07:38:12.59 delay 2 seconds
@07:38:14.62 AirS.reconfigure EvacAirS
@07:38:14.67 AirS.seek :evac
@07:38:36.97 delay 15 seconds
@07:38:52.00 AirS.reconfigure AirSconfig
@07:38:52.07 AirS.seek :tVent
@07:39:06.29 delay 2 seconds
@07:39:08.32 AirS.reconfigure EvacAirS
@07:39:08.37 AirS.seek :evac
@07:39:30.67 delay 15 seconds
@07:39:45.69 AirS.reconfigure AirSconfig
@07:39:45.75 AirS.seek :tVent
@07:39:59.97 delay 2 seconds
@07:40:02.00 AirS.reconfigure EvacAirS
@07:40:02.05 AirS.seek :evac
@07:40:24.36 delay 15 seconds
@07:40:39.37 delay 1:30
@07:42:09.40 AirS.reconfigure AirSconfig
@07:42:09.71 AirS.seek :fVent
@07:42:11.15 delay 15 seconds
@07:42:26.17 LyS1.reconfigure SlowLyS1
@07:42:26.28 LyS1.seek 1050ul
@07:42:56.33 delay 5 seconds
@07:43:01.35 Handoff.select :blocked
@07:43:01.52 LyS1.seek 450ul
@07:44:01.50 delay 10 seconds
@07:44:11.52 AirS.reconfigure SlowAirS
@07:44:11.63 AirS.reconfigure to mix into 1.37806 parts LyS1
@07:44:11.98 <AirSmixer> AirS.seek evac
@07:44:12.02 <PROCESSING> LyS1.seek 300ul
@07:44:27.12 AirS.reconfigure AirSconfig
@07:44:27.17 AirS.seek :full
@07:44:41.39 LyS1.seek :empty
@07:45:11.48 PuckHeater.seek 84.2C
@07:46:17.01 PuckHeater.hold 15:00,84.2C
@08:01:12.80 LyS1.reconfigure LyS1config
@08:01:12.86 LyS1.seek 900ul
@08:01:21.20 AirS.seek :tVent
@08:01:21.29 delay 2 seconds
@08:01:23.31 AirS.reconfigure HalfSpeedAirS
@08:01:23.38 AirS.seek :evac
@08:01:51.18 delay 15 seconds
@08:02:06.21 AirS.reconfigure AirSconfig
@08:02:06.26 AirS.seek :tVent
@08:02:20.48 delay 2 seconds
@08:02:22.51 AirS.reconfigure HalfSpeedAirS
@08:02:22.56 AirS.seek :evac
@08:02:50.30 delay 15 seconds
@08:03:05.33 AirS.reconfigure AirSconfig
@08:03:05.38 AirS.seek :tVent
@08:03:19.60 delay 2 seconds
@08:03:21.63 AirS.reconfigure HalfSpeedAirS
@08:03:21.68 AirS.seek :evac
@08:03:49.43 delay 15 seconds
@08:04:04.45 delay 1:00
@08:05:04.47 Handoff.select :waste
@08:05:04.58 delay 15 seconds
@08:05:19.61 AirS.seek :fVent
@08:05:21.80 delay 10 seconds
@08:05:31.83 AirS.reconfigure AirSconfig
@08:05:31.88 AirS.seek :evac
@08:05:33.32 Lysis Recovered in LyS1
@08:05:33.33 Lysis Complete
@08:05:33.34 Handoff.select :waste
@08:05:33.37 LyS2.seek :full
@08:05:33.46 LyS1.reconfigure DAmixLyS1
@08:05:33.51 LyS2.reconfigure DAmixLyS2
@08:05:33.91 <LyS1mixer> LyS1.seek 650ul
@08:05:33.95 <PROCESSING> LyS2.seek 1480ul
@08:06:21.31 delay 5 seconds
@08:06:26.33 Handoff.select :SPR
@08:06:26.44 SPR.command "start 2 14"
@08:06:27.43 Running Experiment 2
@08:06:27.44 delay 10 seconds
@08:06:37.46 SPR.command "start 2 0"
@08:06:40.80 Waiting up to 11:00 for SPR to enter state 1
@08:13:59.10 SPR.command "hold_on"
@08:14:01.28 <LyS1mixer> LyS1.seek 300ul
@08:14:01.35 <PROCESSING> LyS2.seek 1130ul
@08:15:07.50 delay 15 seconds
@08:15:22.52 SPR.command "hold_off"
@08:15:25.73 Waiting up to 25:00 for SPR to enter state 7
@08:27:01.29 Handoff.select :waste
@08:27:01.41 LyS1.reconfigure LyS1config
@08:27:01.48 LyS2.reconfigure LyS2config
@08:27:01.54 Rep #1 SPR Analysis has Completed
@08:27:02.23 <SPRlogger> Ended log /var/log/esp/spr18aug2407h.spr
@08:27:02.24 <PROCESSING> SPR.command "stats_off"
@08:27:04.28 <LRAUV> -> Cmd.stop
@08:27:04.37 <closeHandoff> Handoff.select :close
@08:27:04.54 <LRAUV> Gate.power :heat,:OFF
@08:27:04.55 Gate.power :cartridge,:OFF
@08:27:04.58 PV.seek :locked
@08:27:05.49 PinPort.seek :clear
@08:27:11.25 <ArS> ArS.seek :clear
@08:27:11.62 <LyS1> LyS1.seek :clear
@08:27:11.77 <LyS2> LyS2.seek :clear
@08:27:11.89 <AirS> AirS.seek :clear
@08:27:29.25 <LRAUV> ArS.coast
@08:27:29.29 LyS1.coast
@08:27:29.35 LyS2.coast
@08:27:29.40 AirS.coast
@08:27:29.45 TV.coast
@08:27:29.50 SP.coast
@08:27:29.54 SC.coast
@08:27:29.60 PV.coast
@08:27:29.65 PinPort.coast
@08:27:29.83 Gate.power :motor,:OFF
@08:27:29.85 Gate.power :main,:OFF
@08:27:29.87 Gate.power :core,:OFF
@08:27:29.89 Gate.power :analytic1,:OFF
@08:27:29.91 Gate.power :analytic2,:OFF
@08:27:29.93 Gate.power :analytic3,:OFF
@08:27:29.95 Safely stopped and ready to power off