@14:05:17.21PDT08-Aug-18 <MAIN> >BREAK< ESP3 Gateway v0.99 for core bus
@14:05:17.23 >BREAK< ESP3 Gateway v0.99 for cartridge bus
@14:05:18.46 
Configuration for honu by brent@mbari.org
/home/esp/esp2/type/auv/honu/configure.rb
@14:05:19.72 Polling Can every 5:00
@14:05:20.02 Clients may connect on TCP port 9999
@14:05:20.03 Connecting to 10.89.5.2:9999
@14:05:20.06 Errno::ECONNREFUSED in MAIN -- Could not connect to initial client because Connection refused - connect(2)
@14:05:20.07 Waiting for espclients...
@14:06:12.26 <bill> Session Begun
@14:06:19.83 -> to 20
@14:06:19.86 I2C::NodeOffline in bill -- carousel0 microcontroller never signed on while reading Storage Carousel's status
@14:06:30.06 -> ESP.ready!
@14:06:30.18 Gate.power :main,:ON,limit to 5000mA
@14:06:30.19 Gate.power :analytic1,:ON,limit to 5000mA
@14:06:30.21 Gate.power :analytic2,:ON,limit to 5000mA
@14:06:30.24 Gate.power :analytic3,:ON,limit to 5000mA
@14:06:30.39 Powering core bus
@14:06:32.68 <Probe-AMspr> SPR Module 1, FW Rev. B, FPGA Rev. C
@14:06:32.78 <bill> ArS.configure ArSconfig
@14:06:32.88 LyS1.configure LyS1config
@14:06:33.00 LyS2.configure LyS2config
@14:06:33.10 AirS.configure AirSconfig
@14:06:33.22 TV.configure TVconfig
@14:06:33.34 SP.configure SPconfig
@14:06:33.45 SC.configure SCconfig
@14:06:33.57 PV.configure PVconfig
@14:06:33.69 PinPort.configure PPconfig
@14:06:33.81 Sea.configure SPconfig
@14:06:33.87 All elf controllers running firmware version 4.48
@14:06:33.89 Gate.power :motor,:ON,limit to 5000mA
@14:06:34.11 <ArS_home> ArS.reconfigure ArSconfig
@14:06:34.15 <LyS1_home> LyS1.reconfigure LyS1config
@14:06:34.41 <ArS_home> ArS.home.jog 0
@14:06:34.42 <LyS1_home> LyS1.home.jog 0
@14:06:34.60 <PinPort_home> PinPort.reconfigure PPconfig
@14:06:34.63 <PV_home> PV.reconfigure PVconfig
@14:06:34.66 <AirS_home> AirS.reconfigure AirSconfig
@14:06:34.70 <LyS2_home> LyS2.reconfigure LyS2config
@14:06:34.74 <TV_home> TV.reconfigure TVconfig
@14:06:34.90 <PinPort_home> PinPort.home.jog 0
@14:06:34.94 <AirS_home> AirS.home.jog 0
@14:06:34.97 <PV_home> PV.home.jog 0
@14:06:35.00 <TV_home> TV.home.jog 0
@14:06:35.02 <LyS2_home> LyS2.home.jog 0
@14:06:35.63 <PV_home> PV.reconfigure HomePV
@14:06:35.70 <AirS_home> AirS.reconfigure HomeMagSyringe
@14:06:35.75 <LyS1_home> LyS1.reconfigure HomeSyringe
@14:06:35.78 <PinPort_home> PinPort.reconfigure HomePP
@14:06:35.82 <LyS2_home> LyS2.reconfigure HomeSyringe
@14:06:35.86 <TV_home> TV.reconfigure HomeTV
@14:06:35.90 <ArS_home> ArS.reconfigure HomeMagSyringe
@14:06:36.12 <PV_home> PV.jog -1482
@14:06:36.15 <PinPort_home> PinPort.jog -111
@14:06:36.19 <LyS1_home> LyS1.jog -268
@14:06:36.22 <AirS_home> AirS.jog -2076
@14:06:36.25 <LyS2_home> LyS2.jog -1440
@14:06:36.29 <TV_home> TV.jog -531
@14:06:36.32 <ArS_home> ArS.jog -273
@14:06:36.76 <PinPort_home> PinPort.setRawPosition! 0
@14:06:36.81 PinPort.seek :home
@14:06:36.90 PinPort.reconfigure PPconfig
@14:06:37.23 <ArS_home> ArS.setRawPosition! -50
@14:06:37.25 <LyS1_home> LyS1.setRawPosition! -50
@14:06:37.34 <ArS_home> ArS.seek :home
@14:06:37.37 <LyS1_home> LyS1.seek :home
@14:06:37.56 <PV_home> PV.setRawPosition! -50
@14:06:37.61 PV.seek :home
@14:06:37.75 <LyS2_home> LyS2.setRawPosition! -50
@14:06:37.76 <LyS1_home> LyS1.reconfigure LyS1config
@14:06:37.79 <ArS_home> ArS.reconfigure ArSconfig
@14:06:37.92 <LyS2_home> LyS2.seek :home
@14:06:38.07 <AirS_home> AirS.setRawPosition! -50
@14:06:38.09 <PV_home> PV.reconfigure PVconfig
@14:06:38.17 <AirS_home> AirS.seek :home
@14:06:38.28 <LyS2_home> LyS2.reconfigure LyS2config
@14:06:38.37 <TV_home> TV.setRawPosition! 0
@14:06:38.42 TV.seek :home
@14:06:38.53 <AirS_home> AirS.reconfigure AirSconfig
@14:06:38.56 <TV_home> TV.reconfigure TVconfig
@14:06:39.10 <bill> TV.seek :clear
@14:06:40.72 TV.coast
@14:06:40.78 SC.reconfigure HomeSC
@14:06:40.84 SC.home.jog 0
@14:06:41.61 SC.reconfigure SCconfig
@14:06:41.67 SC.seek 28
@14:06:45.43 -> to 20
@14:06:45.73 SC.seek 20
@14:06:47.65 <LyS2> LyS2.seek :locked
@14:06:47.78 <PV> PV.seek :locked
@14:06:47.91 <AirS> AirS.seek :locked
@14:06:51.98 <bill> Powering cartridge bus
@14:06:54.10 Gate.setIR 1750,5000,0,6
@14:06:54.23 Gate.setIR 0,5000,0,6
@14:06:54.27 Loaded Archive Cartridge
@14:06:54.28 Gate.power :cartridge,:OFF
@14:07:01.92 -> Power
@14:13:40.69 <bill#2> Session Begun
@14:13:59.73 -> esp
@14:13:59.75 NameError in bill#2 -- undefined local variable or method `esp' for #<Object "bill#2">
@14:14:15.45 -> ESP.ready!
@14:14:16.05 SC.seek 20
@14:14:21.04 -> to 30
@14:14:21.40 <AirS> AirS.seek :clear
@14:14:25.58 <LyS2> LyS2.seek :clear
@14:14:25.73 <PV> PV.seek :clear
@14:14:28.47 <bill#2> SC.seek 30
@14:14:30.81 <LyS2> LyS2.seek :locked
@14:14:30.94 <PV> PV.seek :locked
@14:14:31.08 <AirS> AirS.seek :locked
@14:14:35.16 <bill#2> Powering cartridge bus
@14:14:37.29 Gate.setIR 1750,5000,0,6
@14:14:37.42 Gate.setIR 0,5000,0,6
@14:14:37.47 Loaded Archive Cartridge
Gate.power :cartridge,:OFF
@14:14:58.48 -> Cmd.map
@14:15:29.70 -> Cmd.load! [26, 27]=>:dry
@14:15:33.03 -> Cmd.map
@14:15:52.98 -> Cmd.slots
@14:16:46.42 Session Ended
@14:17:12.22 <methane> Session Begun
@14:19:27.82 -> exit
@14:19:27.83 Session Ended
@14:20:05.92 <espclient> Session Begun
@14:20:11.40 -> exit
@14:20:11.41 Session Ended
@14:20:27.75 <espclient> Session Begun
@14:20:29.04 Session Ended
@14:25:14.82 <espclient> Session Begun
@14:26:00.02 -> Cmd.slot
@14:28:34.58 -> Cmd.filter 250
@14:28:34.59 Cmd::Error in espclient -- Cannot filter while in STOPPED state
@14:28:59.63 -> Cmd.loadCartridge
@14:29:00.24 SC.seek 30
@14:29:00.33 Selecting Cartridge 27
@14:29:00.69 <AirS> AirS.seek :clear
@14:29:04.87 <LyS2> LyS2.seek :clear
@14:29:05.01 <PV> PV.seek :clear
@14:29:07.76 <espclient> SC.seek 27
@14:29:08.67 <LyS2> LyS2.seek :locked
@14:29:08.80 <PV> PV.seek :locked
@14:29:08.94 <AirS> AirS.seek :locked
@14:29:13.28 <espclient> Powering cartridge bus
@14:29:15.42 Gate.setIR 1750,5000,0,6
@14:29:15.55 Gate.setIR 0,5000,0,6
@14:29:15.60 Loaded Archive Cartridge
@14:29:15.61 Gate.power :cartridge,:OFF
@14:29:24.74 -> Cmd.filter 250
@14:29:24.99 <FILTERING> Duration of filtering limited to 1:05:00
@14:29:25.24 Priming sample loop w/100ml, bypass w/10ml
@14:29:25.33 SP.setPosition! 0ml
@14:29:25.41 <openIntake> Intake.open
@14:29:25.47 <FILTERING> Exhaust.open
@14:29:26.30 Sea @-42.9psia
@14:29:26.32 Waiting 4 - 300s for filter pressure to stabilize +/-0.5psi
@14:29:31.85 Vacuum tare is 24% of full scale
@14:29:31.87 <openPB> PB.open
@14:29:31.91 <FILTERING> Exhaust.close
@14:29:32.73 Sea @-42.9psia
@14:29:32.83 SP.reconfigure SPprime
@14:29:32.90 SP.seek 10ml,1:21
@14:29:43.77 SP.setPosition! 0ml
@14:29:43.81 <closePB> PB.close
@14:29:43.85 <FILTERING> Exhaust.open
@14:29:44.69 Sea @-42.9psia
@14:29:44.78 SP.reconfigure SPprime
@14:29:44.86 SP.seek 100ml,8:51
@14:31:31.74 Intake.close
@14:31:32.47 Pressurizing cartridge to 26psi (try #1 of 3)
@14:31:32.48 SP.setPosition! 0ml
@14:31:32.55 PB.open
@14:31:33.34 TV.seek :crack
@14:31:34.15 TV.seek :clear
@14:31:34.96 Intake.open
@14:31:36.38 PB.close
@14:31:36.81 Slide::Error in FILTERING -- deferring Sample Pump pressureOutOfBounds at 0ml
@14:31:36.83 until Pump Bypass Valve finishes closing
@14:31:37.09 SP.coast
@14:31:37.15 <closeIntake> Intake.close
@14:31:37.20 <closeExhaust> Exhaust.close
@14:31:38.02 <FILTERING> TV.seek :bypass
@14:31:39.65 TV.seek :clear
@14:32:28.98 <espclient> -> WCRsampler.filter 250
@14:32:29.22 Pressurizing cartridge to 26psi (try #1 of 3)
@14:32:29.24 SP.setPosition! 0ml
@14:32:29.31 PB.open
@14:32:30.10 TV.seek :crack
@14:32:30.91 TV.seek :clear
@14:32:31.72 Exhaust.open
@14:32:32.42 Intake.open
@14:32:33.20 PB.close
@14:32:33.94 Exhaust.close
@14:32:34.72 Sea @-42.9psia
@14:32:34.82 SP.reconfigure SPcharge
@14:32:34.90 SP.seek 7.5ml,1:08
@14:32:49.31 Cartridge pressurized with 0.975ml to 26.6psi
@14:32:49.82 delay 3 seconds
@14:32:52.89 Cartridge at 20.0psi 3 seconds later
@14:32:52.98 TV.seek :cartridge
@14:32:54.80 TV.seek :clear
@14:32:56.62 Sampling 250.0ml
@14:32:56.63 SP.setPosition! 0ml
@14:32:56.71 Exhaust.open
@14:32:57.48 Sea @-42.9psia
@14:32:57.59 SP.reconfigure SPsample
@14:32:57.66 SP.seek 250ml,21:21
@14:32:58.59 <sampling> Cartridge 27 sampling 0/250ml at 0.16ml/s with 1.4psi vacuum
@14:33:58.60 Cartridge 27 sampling 26/250ml at 0.45ml/s with 20.1psi vacuum
@14:34:58.60 Cartridge 27 sampling 54/250ml at 0.44ml/s with 17.7psi vacuum
@14:35:58.60 Cartridge 27 sampling 81/250ml at 0.43ml/s with 24.1psi vacuum
@14:36:58.60 Cartridge 27 sampling 108/250ml at 0.47ml/s with 26.7psi vacuum
@14:37:49.13 <espclient#2> Session Begun
@14:37:58.60 <sampling> Cartridge 27 sampling 135/250ml at 0.48ml/s with 19.2psi vacuum
@14:38:12.39 <espclient#2> -> Thread[:FILTERING].exceptions
@14:38:12.49 NoMethodError in espclient#2 -- undefined method `exceptions' for #<ScheduleThread::FILTERING priority=1 false>
@14:38:17.26 -> Thread[:FILTERING].exception
@14:38:24.93 -> e=Thread[:FILTERING].exception
@14:38:31.39 -> e.reply.status
@14:38:31.40 NoMethodError in espclient#2 -- undefined method `reply' for [#<Slide::Error: Sample Pump pressureOutOfBounds at 0ml>]:Array
@14:38:49.86 -> e=e.first
@14:38:52.48 -> e.reply.status
@14:38:58.60 <sampling> Cartridge 27 sampling 162/250ml at 0.44ml/s with 16.9psi vacuum
@14:39:02.81 <espclient#2> -> hidelog
@14:39:49.01 -> list Sampler.method :PSI
@14:39:58.60 <sampling> Cartridge 27 sampling 189/250ml at 0.43ml/s with 23.3psi vacuum
@14:40:06.57 <espclient#2> -> list Sampler.method :deltaPress
@14:40:15.08 -> e.reply.status
@14:40:29.22 -> SP.deltaPress
@14:40:29.24 NoMethodError in espclient#2 -- undefined method `deltaPress' for SP:Pump
@14:40:36.19 -> SP.status.deltaPress
@14:40:58.60 <sampling> Cartridge 27 sampling 216/250ml at 0.44ml/s with 26.4psi vacuum
@14:41:58.60 Cartridge 27 sampling 243/250ml at 0.48ml/s with 25.0psi vacuum
@14:42:14.50 <closeIntake> Intake.close
@14:42:14.54 <closeExhaust> Exhaust.close
@14:42:15.37 <espclient> Sampled  250.0ml
@14:42:15.47 TV.seek :bypass
@14:42:17.09 TV.seek :clear
@14:42:19.45 Slide::Error in espclient -- Sample Pump pressureOutOfBounds at 250ml
@14:44:22.41 <espclient#2> -> exit
@14:44:22.43 Session Ended
@15:04:08.40 <espclient> -> ESP.main.exit
@15:04:08.54 Session Ended
@15:04:08.56 <bill> Session Ended