@15:41:31.69PDT05-Aug-18 <MAIN> >BREAK< ESP3 Gateway v0.99 for core bus
@15:41:31.71 >BREAK< ESP3 Gateway v0.99 for cartridge bus
@15:41:32.94 
Configuration for honu by brent@mbari.org
/home/esp/esp2/type/auv/honu/configure.rb
@15:41:34.14 Polling Can every 5:00
@15:41:34.44 Clients may connect on TCP port 9999
@15:41:34.45 Connecting to 10.89.5.2:9999
@15:41:34.49 Waiting for espclients...
@15:41:34.61 <LRAUV> Session Begun
@15:41:36.53 -> showStatus
@15:41:38.52 -> Cmd.loadCartridge
@15:41:38.64 Gate.power :main,:ON,limit to 5000mA
@15:41:38.66 Gate.power :analytic1,:ON,limit to 5000mA
@15:41:38.67 Gate.power :analytic2,:ON,limit to 5000mA
@15:41:38.70 Gate.power :analytic3,:ON,limit to 5000mA
@15:41:38.84 Powering core bus
@15:41:41.13 <Probe-AMspr> SPR Module 1, FW Rev. B, FPGA Rev. C
@15:41:41.25 <LRAUV> ArS.configure ArSconfig
@15:41:41.35 LyS1.configure LyS1config
@15:41:41.47 LyS2.configure LyS2config
@15:41:41.58 AirS.configure AirSconfig
@15:41:41.70 TV.configure TVconfig
@15:41:41.83 SP.configure SPconfig
@15:41:41.94 SC.configure SCconfig
@15:41:42.06 PV.configure PVconfig
@15:41:42.18 PinPort.configure PPconfig
@15:41:42.30 Sea.configure SPconfig
@15:41:42.35 All elf controllers running firmware version 4.46
@15:41:42.37 Gate.power :motor,:ON,limit to 5000mA
@15:41:42.89 <TV_home> TV.reconfigure TVconfig
@15:41:42.94 <AirS_home> AirS.reconfigure AirSconfig
@15:41:42.99 <LyS1_home> LyS1.reconfigure LyS1config
@15:41:43.02 <PV_home> PV.reconfigure PVconfig
@15:41:43.06 <PinPort_home> PinPort.reconfigure PPconfig
@15:41:43.10 <ArS_home> ArS.reconfigure ArSconfig
@15:41:43.13 <LyS2_home> LyS2.reconfigure LyS2config
@15:41:43.34 <TV_home> TV.home.jog 0
@15:41:43.37 <AirS_home> AirS.home.jog 0
@15:41:43.40 <LyS1_home> LyS1.home.jog 0
@15:41:43.43 <PV_home> PV.home.jog 0
@15:41:43.45 <PinPort_home> PinPort.home.jog 0
@15:41:43.48 <LyS2_home> LyS2.home.jog 0
@15:41:43.52 <ArS_home> ArS.home.jog 0
@15:41:44.35 <TV_home> TV.reconfigure HomeTV
@15:41:44.38 <LyS2_home> LyS2.reconfigure HomeSyringe
@15:41:44.42 <PinPort_home> PinPort.reconfigure HomePP
@15:41:44.45 <AirS_home> AirS.reconfigure HomeMagSyringe
@15:41:44.50 <ArS_home> ArS.reconfigure HomeMagSyringe
@15:41:44.54 <PV_home> PV.reconfigure HomePV
@15:41:44.59 <LyS1_home> LyS1.reconfigure HomeSyringe
@15:41:44.76 <TV_home> TV.jog -543
@15:41:44.82 <PinPort_home> PinPort.jog -108
@15:41:44.85 <LyS2_home> LyS2.jog -274
@15:41:44.87 <PV_home> PV.jog -1500
@15:41:44.90 <AirS_home> AirS.jog -339
@15:41:44.94 <ArS_home> ArS.jog -288
@15:41:45.00 <LyS1_home> LyS1.jog -279
@15:41:45.36 <PinPort_home> PinPort.setRawPosition! 0
@15:41:45.41 PinPort.seek :home
@15:41:45.50 PinPort.reconfigure PPconfig
@15:41:45.84 <LyS2_home> LyS2.setRawPosition! -50
@15:41:45.87 <LyS1_home> LyS1.setRawPosition! -50
@15:41:45.89 <ArS_home> ArS.setRawPosition! -50
@15:41:45.92 <AirS_home> AirS.setRawPosition! -50
@15:41:46.04 <LyS2_home> LyS2.seek :home
@15:41:46.08 <LyS1_home> LyS1.seek :home
@15:41:46.11 <AirS_home> AirS.seek :home
@15:41:46.18 <ArS_home> ArS.seek :home
@15:41:46.39 <PV_home> PV.setRawPosition! -50
@15:41:46.43 PV.seek :home
@15:41:46.58 <LyS1_home> LyS1.reconfigure LyS1config
@15:41:46.60 <LyS2_home> LyS2.reconfigure LyS2config
@15:41:46.77 <PV_home> PV.reconfigure PVconfig
@15:41:46.79 <AirS_home> AirS.reconfigure AirSconfig
@15:41:46.83 <ArS_home> ArS.reconfigure ArSconfig
@15:41:46.97 <TV_home> TV.setRawPosition! 0
@15:41:47.02 TV.seek :home
@15:41:47.11 TV.reconfigure TVconfig
@15:41:47.45 <LRAUV> TV.seek :clear
@15:41:49.07 TV.coast
@15:41:49.13 SC.reconfigure HomeSC
@15:41:49.19 SC.home.jog 0
@15:41:49.95 SC.reconfigure SCconfig
@15:41:50.01 SC.seek 39
@15:41:50.11 Selecting Cartridge 38
@15:41:50.41 SC.seek 38
@15:41:50.90 <LyS2> LyS2.seek :locked
@15:41:51.03 <PV> PV.seek :locked
@15:41:51.16 <AirS> AirS.seek :locked
@15:41:55.25 <LRAUV> Powering cartridge bus
@15:41:57.37 Gate.setIR 1750,5000,0,6
@15:41:57.49 Gate.setIR 0,5000,0,6
@15:41:57.55 Loaded Lyse-n-Go Cartridge
@15:41:57.56 PuckHeater.configure HeatConfig
@15:41:57.61 Gate.power :heat,:ON,limit to 5000mA
@15:42:17.22 -> Cmd.startFiltering
@15:42:17.45 <FILTERING> Duration of filtering limited to 1:05:00
@15:42:17.71 Priming sample loop w/100ml, bypass w/10ml
@15:42:17.78 SP.setPosition! 0ml
@15:42:17.82 <openIntake> Intake.open
@15:42:17.85 <FILTERING> Exhaust.open
@15:42:18.67 Sea @-42.9psia
@15:42:18.69 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi
@15:42:24.46 Vacuum tare is 23% of full scale
@15:42:24.47 <openPB> PB.open
@15:42:24.49 <FILTERING> Exhaust.close
@15:42:25.32 Sea @-42.9psia
@15:42:25.41 SP.reconfigure SPprime
@15:42:25.47 SP.seek 10ml,1:21
@15:42:36.35 SP.setPosition! 0ml
@15:42:36.40 <closePB> PB.close
@15:42:36.42 <FILTERING> Exhaust.open
@15:42:37.23 Sea @-42.9psia
@15:42:37.31 SP.reconfigure SPprime
@15:42:37.38 SP.seek 100ml,8:51
@15:44:30.87 Pressurizing cartridge to 26psi
@15:44:30.88 Intake.close
@15:44:31.60 SP.setPosition! 0ml
@15:44:31.64 PB.open
@15:44:32.44 TV.seek :crack
@15:44:33.23 TV.seek :clear
@15:44:34.03 Intake.open
@15:44:35.49 PB.close
@15:44:36.24 Exhaust.close
@15:44:36.99 Sea @-42.9psia
@15:44:37.07 SP.reconfigure SPcharge
@15:44:37.14 SP.seek 7.5ml,1:08
@15:44:43.36 Cartridge pressurized with 0.179ml to 26.0psi
@15:44:43.60 delay 3 seconds
@15:44:46.67 Cartridge at 23.2psi 3 seconds later
@15:44:46.75 TV.seek :cartridge
@15:44:48.57 TV.seek :clear
@15:44:50.39 Sampling 1000.0ml
@15:44:50.41 SP.setPosition! 0ml
@15:44:50.45 Exhaust.open
@15:44:51.20 Sea @-42.9psia
@15:44:51.29 SP.reconfigure SPsample
@15:44:51.36 SP.seek 1000ml,1:23:53
@15:44:52.31 <sampling> Cartridge 38 sampling 0/1000ml at 0.17ml/s with 4.4psi vacuum
@15:45:52.32 Cartridge 38 sampling 31/1000ml at 0.54ml/s with 25.5psi vacuum
@15:46:52.32 Cartridge 38 sampling 63/1000ml at 0.50ml/s with 17.3psi vacuum
@15:47:52.32 Cartridge 38 sampling 94/1000ml at 0.54ml/s with 22.9psi vacuum
@15:48:52.32 Cartridge 38 sampling 125/1000ml at 0.49ml/s with 26.2psi vacuum
@15:49:52.32 Cartridge 38 sampling 155/1000ml at 0.51ml/s with 19.8psi vacuum
@15:50:52.32 Cartridge 38 sampling 185/1000ml at 0.51ml/s with 25.5psi vacuum
@15:51:52.32 Cartridge 38 sampling 215/1000ml at 0.47ml/s with 21.6psi vacuum
@15:52:52.32 Cartridge 38 sampling 244/1000ml at 0.48ml/s with 17.5psi vacuum
@15:53:52.32 Cartridge 38 sampling 273/1000ml at 0.47ml/s with 17.2psi vacuum
@15:54:52.32 Cartridge 38 sampling 302/1000ml at 0.45ml/s with 27.2psi vacuum
@15:55:52.32 Cartridge 38 sampling 330/1000ml at 0.48ml/s with 21.3psi vacuum
@15:56:52.32 Cartridge 38 sampling 358/1000ml at 0.48ml/s with 22.6psi vacuum
@15:57:52.32 Cartridge 38 sampling 385/1000ml at 0.44ml/s with 17.3psi vacuum
@15:58:52.32 Cartridge 38 sampling 412/1000ml at 0.49ml/s with 24.4psi vacuum
@15:59:52.32 Cartridge 38 sampling 439/1000ml at 0.45ml/s with 20.3psi vacuum
@16:00:52.34 Cartridge 38 sampling 465/1000ml at 0.44ml/s with 25.9psi vacuum
@16:01:52.33 Cartridge 38 sampling 490/1000ml at 0.43ml/s with 18.7psi vacuum
@16:02:52.32 Cartridge 38 sampling 515/1000ml at 0.43ml/s with 21.0psi vacuum
@16:03:52.32 Cartridge 38 sampling 540/1000ml at 0.37ml/s with 26.3psi vacuum
@16:04:52.33 Cartridge 38 sampling 563/1000ml at 0.38ml/s with 24.9psi vacuum
@16:05:52.32 Cartridge 38 sampling 587/1000ml at 0.40ml/s with 19.1psi vacuum
@16:06:52.32 Cartridge 38 sampling 610/1000ml at 0.39ml/s with 19.2psi vacuum
@16:07:52.32 Cartridge 38 sampling 632/1000ml at 0.36ml/s with 24.4psi vacuum
@16:08:52.32 Cartridge 38 sampling 654/1000ml at 0.33ml/s with 25.8psi vacuum
@16:09:52.32 Cartridge 38 sampling 676/1000ml at 0.38ml/s with 26.2psi vacuum
@16:10:52.32 Cartridge 38 sampling 697/1000ml at 0.33ml/s with 23.7psi vacuum
@16:11:52.32 Cartridge 38 sampling 718/1000ml at 0.37ml/s with 23.7psi vacuum
@16:12:52.32 Cartridge 38 sampling 738/1000ml at 0.33ml/s with 25.0psi vacuum
@16:13:52.32 Cartridge 38 sampling 758/1000ml at 0.36ml/s with 22.7psi vacuum
@16:14:52.32 Cartridge 38 sampling 777/1000ml at 0.30ml/s with 25.3psi vacuum
@16:15:52.32 Cartridge 38 sampling 796/1000ml at 0.28ml/s with 19.8psi vacuum
@16:16:52.32 Cartridge 38 sampling 814/1000ml at 0.33ml/s with 19.7psi vacuum
@16:17:52.33 Cartridge 38 sampling 833/1000ml at 0.30ml/s with 24.7psi vacuum
@16:18:52.32 Cartridge 38 sampling 851/1000ml at 0.29ml/s with 21.0psi vacuum
@16:19:52.32 Cartridge 38 sampling 868/1000ml at 0.31ml/s with 25.4psi vacuum
@16:20:52.32 Cartridge 38 sampling 886/1000ml at 0.26ml/s with 19.0psi vacuum
@16:21:52.32 Cartridge 38 sampling 903/1000ml at 0.29ml/s with 25.8psi vacuum
@16:22:52.32 Cartridge 38 sampling 920/1000ml at 0.29ml/s with 19.3psi vacuum
@16:23:52.32 Cartridge 38 sampling 936/1000ml at 0.25ml/s with 19.7psi vacuum
@16:24:52.32 Cartridge 38 sampling 952/1000ml at 0.26ml/s with 22.5psi vacuum
@16:25:52.32 Cartridge 38 sampling 968/1000ml at 0.24ml/s with 26.6psi vacuum
@16:26:52.32 Cartridge 38 sampling 984/1000ml at 0.22ml/s with 22.1psi vacuum
@16:27:52.32 Cartridge 38 sampling 1000/1000ml at 0.27ml/s with 24.0psi vacuum
@16:27:53.94 <closeIntake> Intake.close
@16:27:53.97 <closeExhaust> Exhaust.close
@16:27:54.74 <FILTERING> Sampled  1000.0ml
@16:27:54.82 TV.seek :bypass
@16:27:56.43 TV.seek :clear
@16:27:58.59 <LRAUV> -> Cmd.startProcessing
@16:28:00.37 <PROCESSING> SPR.command "read_sequences"
@16:28:00.83 SPR.command "stats_on"
@16:28:00.87 SPR.command "start 2 0"
@16:28:02.88 <SPRlogger> Begun log /var/log/esp/spr18aug0516h.spr
@16:28:03.91 AnalyticModule::ReadTimeout in PROCESSING -- No response from SPR on AMspr (try 1 of 3)
@16:28:05.57 <PROCESSING> Handoff.select :waste
@16:28:05.69 ArS.seek empty
@16:28:23.44 PinPort.seek :cartridge
@16:28:29.15 LyS1.seek 1350ul
@16:28:35.09 PV.seek :reagent
@16:28:35.93 AirS.seek :tVent
@16:28:37.99 delay 2 seconds
@16:28:40.02 AirS.reconfigure EvacAirS
@16:28:40.08 AirS.seek :evac
@16:29:02.40 delay 15 seconds
@16:29:17.43 AirS.reconfigure AirSconfig
@16:29:17.49 AirS.seek :tVent
@16:29:31.71 delay 2 seconds
@16:29:33.74 AirS.reconfigure EvacAirS
@16:29:33.81 AirS.seek :evac
@16:29:56.13 delay 15 seconds
@16:30:11.15 AirS.reconfigure AirSconfig
@16:30:11.20 AirS.seek :tVent
@16:30:25.42 delay 2 seconds
@16:30:27.45 AirS.reconfigure EvacAirS
@16:30:27.50 AirS.seek :evac
@16:30:49.81 delay 15 seconds
@16:31:04.84 AirS.reconfigure AirSconfig
@16:31:04.89 AirS.seek :tVent
@16:31:19.11 delay 2 seconds
@16:31:21.14 AirS.reconfigure EvacAirS
@16:31:21.19 AirS.seek :evac
@16:31:43.51 delay 15 seconds
@16:31:58.54 AirS.reconfigure AirSconfig
@16:31:58.59 AirS.seek :tVent
@16:32:12.82 delay 2 seconds
@16:32:14.85 AirS.reconfigure EvacAirS
@16:32:14.90 AirS.seek :evac
@16:32:37.22 delay 15 seconds
@16:32:52.25 delay 1:30
@16:34:22.28 AirS.reconfigure AirSconfig
@16:34:22.39 AirS.seek :fVent
@16:34:23.82 delay 15 seconds
@16:34:38.85 LyS1.reconfigure SlowLyS1
@16:34:38.94 LyS1.seek 1050ul
@16:35:09.00 delay 5 seconds
@16:35:14.02 Handoff.select :blocked
@16:35:14.19 LyS1.seek 450ul
@16:36:14.19 delay 10 seconds
@16:36:24.22 AirS.reconfigure SlowAirS
@16:36:24.34 AirS.reconfigure to mix into 1.37806 parts LyS1
@16:36:24.72 <AirSmixer> AirS.seek evac
@16:36:24.78 <PROCESSING> LyS1.seek 300ul
@16:36:39.87 AirS.reconfigure AirSconfig
@16:36:39.93 AirS.seek :full
@16:36:54.15 LyS1.seek :empty
@16:37:24.25 PuckHeater.seek 86.1C
@16:38:50.78 PuckHeater.hold 15:00,86.1C
@16:53:47.87 LyS1.reconfigure LyS1config
@16:53:47.93 LyS1.seek 900ul
@16:53:56.27 AirS.seek :tVent
@16:53:56.35 delay 2 seconds
@16:53:58.38 AirS.reconfigure HalfSpeedAirS
@16:53:58.43 AirS.seek :evac
@16:54:26.17 delay 15 seconds
@16:54:41.20 AirS.reconfigure AirSconfig
@16:54:41.26 AirS.seek :tVent
@16:54:55.47 delay 2 seconds
@16:54:57.50 AirS.reconfigure HalfSpeedAirS
@16:54:57.55 AirS.seek :evac
@16:55:25.29 delay 15 seconds
@16:55:40.32 AirS.reconfigure AirSconfig
@16:55:40.37 AirS.seek :tVent
@16:55:54.59 delay 2 seconds
@16:55:56.62 AirS.reconfigure HalfSpeedAirS
@16:55:56.67 AirS.seek :evac
@16:56:24.42 delay 15 seconds
@16:56:39.45 delay 1:00
@16:57:39.47 Handoff.select :waste
@16:57:39.58 delay 15 seconds
@16:57:54.60 AirS.seek :fVent
@16:57:56.79 delay 10 seconds
@16:58:06.82 AirS.reconfigure AirSconfig
@16:58:06.88 AirS.seek :evac
@16:58:08.32 Lysis Recovered in LyS1
@16:58:08.33 Lysis Complete
@16:58:08.34 Handoff.select :waste
@16:58:08.37 LyS2.seek :full
@16:58:08.46 LyS1.reconfigure DAmixLyS1
@16:58:08.51 LyS2.reconfigure DAmixLyS2
@16:58:08.93 <LyS2mixer> LyS2.seek 1480ul
@16:58:08.98 <PROCESSING> LyS1.seek 650ul
@16:58:56.30 delay 5 seconds
@16:59:01.32 Handoff.select :SPR
@16:59:01.43 SPR.command "start 1 14"
@16:59:02.24 Running Experiment-Rep #1
@16:59:02.25 delay 10 seconds
@16:59:12.26 SPR.command "start 1 0"
@16:59:15.59 Waiting up to 4:00 for SPR to enter state 1
@17:01:19.83 SPR.command "hold_on"
@17:01:22.05 <LyS2mixer> LyS2.seek 1130ul
@17:01:22.09 <PROCESSING> LyS1.seek 300ul
@17:02:28.27 delay 15 seconds
@17:02:43.28 SPR.command "hold_off"
@17:02:46.50 Waiting up to 25:00 for SPR to enter state 7
@17:22:49.94 Handoff.select :waste
@17:22:50.07 LyS1.reconfigure LyS1config
@17:22:50.13 LyS2.reconfigure LyS2config
@17:22:50.19 Rep #1 SPR Analysis has Completed
@17:22:51.10 <SPRlogger> Ended log /var/log/esp/spr18aug0516h.spr
@17:22:51.12 <PROCESSING> SPR.command "stats_off"
@17:22:52.99 <LRAUV> -> Cmd.stop
@17:22:53.08 <closeHandoff> Handoff.select :close
@17:22:53.25 <LRAUV> Gate.power :heat,:OFF
@17:22:53.27 Gate.power :cartridge,:OFF
@17:22:53.30 PV.seek :locked
@17:22:54.20 PinPort.seek :clear
@17:22:59.98 <ArS> ArS.seek :clear
@17:23:00.10 <LyS1> LyS1.seek :clear
@17:23:00.25 <LyS2> LyS2.seek :clear
@17:23:00.38 <AirS> AirS.seek :clear
@17:23:17.74 <LRAUV> ArS.coast
@17:23:17.79 LyS1.coast
@17:23:17.84 LyS2.coast
@17:23:17.89 AirS.coast
@17:23:17.94 TV.coast
@17:23:17.99 SP.coast
@17:23:18.04 SC.coast
@17:23:18.09 PV.coast
@17:23:18.14 PinPort.coast
@17:23:18.31 Gate.power :motor,:OFF
@17:23:18.33 Gate.power :main,:OFF
@17:23:18.35 Gate.power :core,:OFF
@17:23:18.37 Gate.power :analytic1,:OFF
@17:23:18.39 Gate.power :analytic2,:OFF
@17:23:18.41 Gate.power :analytic3,:OFF
@17:23:18.43 Safely stopped and ready to power off