@11:30:15.69EDT16-Aug-19
>BREAK< ESP3 Gateway v0.99 for core bus @11:30:15.71 >BREAK< ESP3 Gateway v0.99 for cartridge bus @11:30:17.31 Configuration for honu by brent@mbari.org 7/26/19 /home/esp/esp2/type/auv/honu/configure.rb @11:30:20.29 Polling Can every 5:00 @11:30:20.60 Clients may connect on TCP port 9999 @11:30:20.61 Connecting to 10.89.5.2:9999 @11:30:20.65 Waiting for espclients... @11:30:20.78 Session Begun @11:30:22.72 -> showStatus @11:30:24.80 -> Cmd.loadCartridge -15 @11:30:24.95 Gate.power :main,:ON,limit to 5000mA @11:30:24.96 Gate.power :analytic1,:ON,limit to 5000mA @11:30:24.98 Gate.power :analytic2,:ON,limit to 5000mA @11:30:25.01 Gate.power :analytic3,:ON,limit to 5000mA @11:30:25.14 Powering core bus @11:30:27.42 SPR Module 1, FW Rev. B, FPGA Rev. C @11:30:27.55 Archive.configure :default @11:30:27.67 Air.configure :default @11:30:27.77 Lysis1.configure :default @11:30:27.87 Lysis2.configure :default @11:30:27.99 Plunge.configure :default @11:30:28.11 SamplePump.configure :default @11:30:28.23 ExtraPump.configure :default @11:30:28.35 Toroid.configure :default @11:30:28.47 Carousel.configure :default @11:30:28.59 Pressure.configure :default @11:30:28.69 PinPort.configure :default @11:30:28.75 All elf controllers running firmware version 4.58 @11:30:28.77 Gate.power :motor,:ON,limit to 5000mA @11:30:28.93 Archive.reconfigure :default @11:30:29.27 Archive.home.jog 0 @11:30:29.44 Toroid.reconfigure :default @11:30:29.49 Plunge.reconfigure :default @11:30:29.52 Lysis1.reconfigure :default @11:30:29.55 Air.reconfigure :default @11:30:29.59 PinPort.reconfigure :default @11:30:29.63 Lysis2.reconfigure :default @11:30:29.89 Air.home.jog 0 @11:30:29.94 Lysis2.home.jog 0 @11:30:30.00 Toroid.home.jog 0 @11:30:30.06 PinPort.home.jog 0 @11:30:30.12 Plunge.home.jog 0 @11:30:30.17 Lysis1.home.jog 0 @11:30:30.41 Archive.reconfigure :homing @11:30:30.68 Toroid.reconfigure :homing @11:30:30.70 Plunge.reconfigure :homing @11:30:30.75 PinPort.reconfigure :homing @11:30:30.80 Lysis2.reconfigure :homing @11:30:30.84 Air.reconfigure :homing @11:30:31.03 Air.jog 1399 @11:30:31.09 Archive.jog 1381 @11:30:31.14 Plunge.jog 1562 @11:30:31.20 Lysis2.jog 1427 @11:30:31.25 PinPort.jog -106 @11:30:31.31 Toroid.jog 634 @11:30:31.39 Lysis1.reconfigure :homing @11:30:31.45 Lysis1.jog 1397 @11:30:31.53 PinPort.setRawPosition! 0 @11:30:31.58 PinPort.seek :home @11:30:31.66 PinPort.reconfigure :default @11:30:32.19 Air.setRawPosition! 50 @11:30:32.24 Air.seek :home @11:30:32.33 Archive.setRawPosition! 50 @11:30:32.39 Archive.seek :home @11:30:32.48 Plunge.setRawPosition! 50 @11:30:32.50 Lysis2.setRawPosition! 50 @11:30:32.57 Plunge.seek :home @11:30:32.62 Lysis2.seek :home @11:30:32.79 Lysis1.setRawPosition! 50 @11:30:32.82 Archive.reconfigure :default @11:30:32.85 Air.reconfigure :default @11:30:32.92 Lysis1.seek :home @11:30:33.11 Plunge.reconfigure :default @11:30:33.14 Lysis2.reconfigure :default @11:30:33.30 Lysis1.reconfigure :default @11:30:33.46 Toroid.setRawPosition! 0 @11:30:33.51 Toroid.seek :home @11:30:33.60 Toroid.reconfigure :default @11:30:33.73 Toroid.seek :clear @11:30:35.36 Carousel.reconfigure :default @11:30:35.43 Carousel.home.jog 0 @11:30:35.92 Carousel.seek 57 @11:30:36.07 Selecting Cartridge 56 @11:30:36.32 Carousel.seek 56 @11:30:36.82 Lysis1.seek :locked @11:30:40.94 Plunge.seek :locked @11:30:41.06 Air.seek :locked @11:30:45.19 Powering cartridge bus @11:30:47.31 Gate.setIR 1750,5000,0,6 @11:30:47.44 Gate.setIR 0,5000,0,6 @11:30:47.49 Loaded Lyse-n-Go Cartridge @11:30:47.51 PuckHeater.configure HeatConfig @11:30:47.56 Gate.power :heat,:ON,limit to 5000mA @11:31:03.57 -> Cmd.startFiltering @11:31:03.80 Duration of filtering limited to 2:05:00 @11:31:04.07 Priming sample loop w/100ml, bypass w/10ml @11:31:04.09 SamplePump.setPosition! 0ml @11:31:04.13 Bypass.open @11:31:05.60 Intake.open @11:31:06.44 Waiting up to 20 seconds for sea pressure to stabilize +/-0.50psi @11:31:09.65 Vacuum tare is 52% of full scale @11:31:09.78 Pressure.reconfigure :default @11:31:09.84 Pressure.hold @11:31:10.02 SamplePump.reconfigure :prime @11:31:10.10 SamplePump.seek 10ml,1:21 @11:31:19.06 Pressure.coast @11:31:19.12 SamplePump.setPosition! 0ml @11:31:19.17 Exhaust.open @11:31:20.62 Bypass.close @11:31:22.14 Pressure.reconfigure :default @11:31:22.20 Pressure.hold @11:31:22.42 SamplePump.reconfigure :prime @11:31:22.49 SamplePump.seek 100ml,8:51 @11:33:13.17 Pressure.coast @11:33:13.23 Bypass.open @11:33:13.97 Intake.close @11:33:14.82 Toroid.reconfigure :hold @11:33:14.89 Toroid.seek :crack @11:33:15.68 Toroid.reconfigure :default @11:33:15.74 Toroid.seek :clear @11:33:17.30 Toroid.reconfigure :hold @11:33:17.37 Toroid.seek :cartridge @11:33:19.18 Toroid.reconfigure :default @11:33:19.24 Toroid.seek :clear @11:33:21.22 Pressurizing cartridge to 26psi above 14.4psia @11:33:21.23 SamplePump.setPosition! 0ml @11:33:21.28 Intake.open @11:33:22.00 Bypass.close @11:33:22.77 Exhaust.close @11:33:23.61 Pressure.reconfigure :default @11:33:23.67 Pressure.hold @11:33:23.83 SamplePump.reconfigure :charge @11:33:23.90 SamplePump.seek 2.3ml,42 seconds @11:33:35.80 Pressure.coast @11:33:35.85 Intake.close @11:33:36.66 Pressurized cartridge with 0.665ml to 26.3psi @11:33:36.67 Isolated @37.0psia (22.7psi) @11:33:36.68 Checking for cartridge leaks from 37.0psia @11:33:36.71 Waiting up to 20 seconds for cartridge pressure to stabilize +/-0.75psi @11:33:41.86 Sampling 1000.0ml @11:33:41.87 SamplePump.setPosition! 0ml @11:33:41.92 Exhaust.open @11:33:42.66 Intake.open @11:33:43.53 Pressure.reconfigure :default @11:33:43.59 Pressure.hold @11:33:43.81 SamplePump.reconfigure :sample @11:33:43.89 SamplePump.seek 1000ml,1:23:53 @11:33:46.57 Cartridge 56 sampling 1/1000ml at 0.56ml/s with 22.8psi vacuum @11:34:46.56 Cartridge 56 sampling 34/1000ml at 0.56ml/s with 24.2psi vacuum 2.6m deep (18.5psia) @11:35:46.56 Cartridge 56 sampling 66/1000ml at 0.52ml/s with 23.2psi vacuum 3.7m deep (20.1psia) @11:36:46.56 Cartridge 56 sampling 96/1000ml at 0.50ml/s with 22.4psi vacuum 3.5m deep (19.9psia) @11:37:46.56 Cartridge 56 sampling 126/1000ml at 0.50ml/s with 24.3psi vacuum @11:38:46.56 Cartridge 56 sampling 154/1000ml at 0.45ml/s with 30.3psi vacuum @11:39:46.56 Cartridge 56 sampling 182/1000ml at 0.42ml/s with 24.6psi vacuum @11:40:46.56 Cartridge 56 sampling 208/1000ml at 0.43ml/s with 30.3psi vacuum @11:41:46.56 Cartridge 56 sampling 234/1000ml at 0.41ml/s with 20.6psi vacuum @11:42:46.56 Cartridge 56 sampling 260/1000ml at 0.38ml/s with 25.3psi vacuum @11:43:46.56 Cartridge 56 sampling 284/1000ml at 0.37ml/s with 25.1psi vacuum @11:44:46.56 Cartridge 56 sampling 307/1000ml at 0.38ml/s with 23.1psi vacuum @11:45:46.56 Cartridge 56 sampling 330/1000ml at 0.39ml/s with 18.9psi vacuum 4.2m deep (20.9psia) @11:46:46.56 Cartridge 56 sampling 351/1000ml at 0.36ml/s with 24.2psi vacuum 5.6m deep (22.9psia) @11:47:46.56 Cartridge 56 sampling 371/1000ml at 0.34ml/s with 25.3psi vacuum 4.5m deep (21.3psia) @11:48:46.56 Cartridge 56 sampling 391/1000ml at 0.34ml/s with 20.0psi vacuum 3.0m deep (19.1psia) @11:49:46.56 Cartridge 56 sampling 409/1000ml at 0.26ml/s with 23.2psi vacuum @11:50:46.56 Cartridge 56 sampling 426/1000ml at 0.29ml/s with 24.6psi vacuum @11:51:46.56 Cartridge 56 sampling 442/1000ml at 0.27ml/s with 24.7psi vacuum @11:52:46.56 Cartridge 56 sampling 457/1000ml at 0.22ml/s with 23.3psi vacuum @11:53:46.56 Cartridge 56 sampling 472/1000ml at 0.21ml/s with 22.9psi vacuum @11:54:46.56 Cartridge 56 sampling 485/1000ml at 0.24ml/s with 22.9psi vacuum @11:55:46.56 Cartridge 56 sampling 498/1000ml at 0.20ml/s with 27.0psi vacuum @11:56:46.56 Cartridge 56 sampling 511/1000ml at 0.22ml/s with 23.9psi vacuum @11:57:46.56 Cartridge 56 sampling 523/1000ml at 0.20ml/s with 26.8psi vacuum @11:58:46.56 Cartridge 56 sampling 535/1000ml at 0.20ml/s with 28.7psi vacuum @11:59:46.56 Cartridge 56 sampling 547/1000ml at 0.20ml/s with 26.0psi vacuum @11:59:55.93 Flow averaged 0.35ml/s over 26:03 @11:59:55.96 Waiting up to 13 seconds for vacuum to reduce from 28.0 to 18.0psi @11:59:57.05 Relieved excess vacuum in 10.4 seconds @11:59:57.07 SamplePump.seek 1000ml,38:05 @12:00:20.98 Flow averaged 0.24ml/s over 20 seconds @12:00:21.01 Waiting up to 10 seconds for vacuum to reduce from 26.8 to 18.0psi @12:00:21.71 Relieved excess vacuum in 4.9 seconds @12:00:21.73 SamplePump.seek 1000ml,37:41 @12:00:39.37 Flow averaged 0.27ml/s over 14 seconds @12:00:39.40 Waiting up to 13 seconds for vacuum to reduce from 27.9 to 18.0psi @12:00:40.10 Relieved excess vacuum in 5.3 seconds @12:00:40.12 SamplePump.seek 1000ml,37:23 @12:00:46.56 Cartridge 56 sampling 559/1000ml at 0.20ml/s with 29.0psi vacuum @12:00:54.60 Flow estimate reduced to 0.21ml/s @12:00:54.63 Waiting up to 13 seconds for vacuum to reduce from 28.1 to 18.0psi @12:00:55.33 Relieved excess vacuum in 8.7 seconds @12:00:55.35 SamplePump.seek 1000ml,37:09 @12:01:08.29 Flow estimate reduced to 0.17ml/s @12:01:08.30 Intake.close @12:01:08.38 Exhaust.close @12:01:08.53 Toroid.reconfigure :hold @12:01:08.60 Toroid.seek :bypass @12:01:10.22 Toroid.reconfigure :default @12:01:10.29 Toroid.seek :clear @12:01:11.91 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged @12:01:11.93 Pressure.coast @12:01:12.11 Sampled 563.1ml @12:01:14.01 -> Cmd.startProcessing @12:01:15.87 SPR.command "read_sequences" @12:01:16.41 SPR.command "stats_on" @12:01:16.45 SPR.command "start 0 1" @12:01:18.46 Begun log /var/log/esp/spr19aug1612h.spr @12:01:19.48 AnalyticModule::ReadTimeout in PROCESSING -- No response from SPR on AMspr (try 1 of 3) @12:01:21.15 Handoff.select :waste @12:01:21.33 Archive.seek :empty @12:01:28.49 Syringe tare is 20% of full scale @12:01:39.05 PinPort.seek :cartridge @12:01:43.61 Lysis1.seek 1350ul @12:01:46.09 Lysis2.seek 1700ul @12:01:49.18 Plunge.seek :reagent @12:01:50.02 Evacuating puck with filter bubble point of 50psi @12:01:50.04 PinPort.seek :cartridge @12:01:50.12 Handoff.select :waste @12:01:57.51 Air pumping 1 strokes to reach 8psi @12:01:57.53 Air.reconfigure :gulp @12:01:57.62 Air.seek :tVent @12:01:59.41 -0.0psi after gulp @12:01:59.42 Air stroke #1 of 1 @12:01:59.44 Air.reconfigure :pressurize @12:01:59.54 Air.seek :pressurized @12:02:12.38 -0.0psi after pressurize @12:02:12.39 Air.reconfigure :compress @12:02:12.47 Air.seek :evac @12:02:15.13 0.1psi after compress @12:02:29.10 0.3psi after compress (stable) @12:02:29.22 at 0.3psi Opened to waste @12:02:29.23 Handoff.select :blocked @12:02:29.40 Air pumping 10 strokes to reach 50psi @12:02:29.42 Air.reconfigure :gulp @12:02:29.50 Air.seek :tVent @12:02:39.39 7.9psi after gulp @12:02:39.40 Air stroke #1 of 10 @12:02:39.42 Air.reconfigure :pressurize @12:02:39.50 Air.seek :pressurized @12:02:52.76 11.2psi after pressurize @12:02:52.78 Air.reconfigure :compress @12:02:52.86 Air.seek :evac @12:02:55.53 13.0psi after compress @12:03:31.66 35.2psi after compress (stable) @12:03:31.68 Air.reconfigure :gulp @12:03:31.76 Air.seek :tVent @12:03:41.67 36.9psi after gulp @12:03:41.68 Air stroke #2 of 10 @12:03:41.70 Air.reconfigure :pressurize @12:03:41.79 Air.seek :pressurized @12:03:54.63 34.6psi after pressurize @12:03:54.64 Air.reconfigure :compress @12:03:54.73 Air.seek :evac @12:03:57.39 33.2psi after compress @12:04:20.25 Reached 50.0 of 50.0psi after pumping 4672ul (3.00 strokes) @12:04:20.27 Handoff.select :waste @12:04:40.19 delay 30 seconds @12:05:10.21 Handoff.select :blocked @12:05:44.13 Handoff.select :waste @12:05:44.25 PSI at @6.66psi at evacEnd @12:05:44.26 Handoff.select :blocked @12:05:44.38 Air.reconfigure :gulp @12:05:44.49 Air.seek :tVent @12:06:01.57 Stable @4.6psi after Handoff blocked @12:06:01.68 Air pumping 10 strokes to reach 35psi @12:06:01.69 Air stroke #1 of 10 @12:06:01.71 Air.reconfigure :pressurize @12:06:01.79 Air.seek :pressurized @12:06:14.63 5.2psi after pressurize @12:06:14.64 Air.reconfigure :compress @12:06:14.72 Air.seek :evac @12:06:17.39 7.3psi after compress @12:06:43.82 21.6psi after compress (stable) @12:06:43.84 Air.reconfigure :gulp @12:06:43.91 Air.seek :tVent @12:06:53.81 22.2psi after gulp @12:06:53.82 Air stroke #2 of 10 @12:06:53.84 Air.reconfigure :pressurize @12:06:53.92 Air.seek :pressurized @12:07:06.76 22.1psi after pressurize @12:07:06.77 Air.reconfigure :compress @12:07:06.86 Air.seek :evac @12:07:09.54 23.1psi after compress @12:07:29.55 32.0psi after compress (stable) @12:07:29.58 Air.reconfigure :gulp @12:07:29.66 Air.seek :tVent @12:07:39.55 32.6psi after gulp @12:07:39.56 Air stroke #3 of 10 @12:07:39.58 Air.reconfigure :pressurize @12:07:39.66 Air.seek :pressurized @12:07:52.49 32.4psi after pressurize @12:07:52.51 Air.reconfigure :compress @12:07:52.58 Air.seek :evac @12:07:55.25 32.4psi after compress @12:08:07.30 Reached 34.8 of 34.6psi after pumping 6230ul (4.00 strokes) @12:08:07.31 Handoff.select :waste @12:08:21.64 delay 30 seconds @12:08:51.66 Handoff.select :blocked @12:09:16.67 Handoff.select :waste @12:09:16.79 PSI at @0.55psi at evacEnd @12:09:16.81 Air.reconfigure :default @12:09:16.92 Air.seek :fVent @12:09:18.35 delay 5 seconds @12:09:23.38 Seawater evacuated, Presure above/below filter released. Lysis begins @12:09:23.45 Lysis1.reconfigure :slow @12:09:23.51 Lysis1.seek 1050ul @12:09:53.56 delay 5 seconds @12:09:58.58 Handoff.select :blocked @12:09:58.74 Lysis1.seek 450ul @12:10:58.73 delay 3 seconds @12:11:01.80 Air.reconfigure to mix into 1.37806 parts Lysis1 @12:11:02.30 Air.seek :evac @12:11:02.34 Lysis1.seek 300ul @12:11:17.47 Air.reconfigure :default @12:11:17.56 Air.seek :full @12:11:31.77 Lysis1.seek :empty @12:12:01.88 Lysis Delivered. Syringe pressure @75.1psi @12:12:01.92 PuckHeater.seek 84.2C @12:12:55.62 PuckHeater.hold 15:00,84.2C @12:27:55.79 Lysis complete. Syringe pressure @79.9psi @12:27:55.80 Handoff.select :blocked @12:27:55.91 Lysis1.reconfigure :halfSpeed @12:27:55.97 Lysis1.seek 200ul @12:27:59.75 delay 1 second @12:28:00.77 Lysis1.seek :empty @12:28:04.60 Initial AirS pressure @80.0psi @12:28:04.66 Lysis1.reconfigure :extract @12:28:04.75 Lysis1.seek 900ul,10:00 @12:28:06.45 Air pumping 10 strokes to reach 24psi @12:28:06.46 Air stroke #1 of 10 @12:28:06.48 Air.reconfigure :pressurize @12:28:06.56 Air.seek :pressurized @12:28:19.41 15.2psi after pressurize @12:28:19.43 Air.reconfigure :compress @12:28:19.51 Air.seek :evac @12:28:22.17 20.6psi after compress @12:28:36.46 18.8psi after compress (stable) @12:28:36.48 Air.reconfigure :gulp @12:28:36.55 Air.seek :tVent @12:28:46.48 18.8psi after gulp @12:28:46.49 Air stroke #2 of 10 @12:28:46.51 Air.reconfigure :pressurize @12:28:46.60 Air.seek :pressurized @12:28:59.44 18.8psi after pressurize @12:28:59.45 Air.reconfigure :compress @12:28:59.53 Air.seek :evac @12:29:02.21 19.6psi after compress @12:29:04.41 Air.reconfigure :default @12:29:04.47 Air.hold @12:29:04.66 Lysis1 at 900ul @20.5psi @12:29:04.67 Lysis1.reconfigure :default @12:29:04.75 Lysis1.seek 925ul @12:29:05.46 Syringe::Overpressure in PROCESSING -- 22.2psi after recovery pull -- Skipping SPR @12:29:05.48 SPR.command "start 2 14" @12:29:06.76 Handoff.select :waste @12:29:07.25 Ended log /var/log/esp/spr19aug1612h.spr @12:29:07.27 SPR.command "stats_off" @12:29:09.19 -> Cmd.stop @12:29:09.28 Handoff.select :close @12:29:09.40 Gate.power :heat,:OFF @12:29:09.42 Gate.power :cartridge,:OFF @12:29:09.50 Carousel.seek 56 @12:29:09.67 Plunge.seek :locked @12:29:10.51 PinPort.seek :clear @12:29:15.13 Archive.seek :clear @12:29:15.26 Air.seek :clear @12:29:15.39 Lysis1.seek :clear @12:29:15.52 Lysis2.seek :clear @12:29:32.89 Archive.coast @12:29:32.94 Air.coast @12:29:32.99 Lysis1.coast @12:29:33.04 Lysis2.coast @12:29:33.09 Plunge.coast @12:29:33.14 SamplePump.coast @12:29:33.19 Toroid.coast @12:29:33.24 Carousel.coast @12:29:33.30 PinPort.coast @12:29:33.48 Gate.power :motor,:OFF @12:29:33.49 Gate.power :core,:OFF @12:29:33.52 Gate.power :analytic1,:OFF @12:29:33.54 Gate.power :analytic2,:OFF @12:29:33.56 Gate.power :analytic3,:OFF @12:29:33.58 Gate.power :main,:OFF @12:29:33.60 Safely stopped and ready to power off