@18:43:49.96PDT28-May-22
>BREAK< ESP3 Gateway v1.12 for core bus @18:43:49.98 >BREAK< ESP3 Gateway v1.12 for cartridge bus @18:43:52.58 Configuration for honu by methane@mbari.org 3/16/22 /home/esp/esp2/type/auv/honu/configure.rb @18:44:04.40 Polling Can every 2:00 @18:44:04.87 Clients may connect on TCP port 9999 @18:44:04.88 Connecting to 10.89.5.2:9999 @18:44:04.92 Waiting for espclients... @18:44:05.35 Session Begun @18:44:07.29 -> showStatus true @18:44:09.31 -> Cmd.loadCartridge -10 @18:44:09.38 Gate.power :main,:ON,limit to 5000mA @18:44:09.39 Gate.power :analytic1,:ON,limit to 5000mA @18:44:09.42 Gate.power :analytic2,:ON,limit to 5000mA @18:44:09.45 Gate.power :analytic3,:ON,limit to 5000mA @18:44:09.60 Powering core bus @18:44:11.85 SPR Module 1, FW Rev. B, FPGA Rev. C @18:44:12.37 Archive.configure :default @18:44:12.63 Air.configure :default @18:44:12.84 Lysis1.configure :default @18:44:13.09 Lysis2.configure :default @18:44:13.41 Plunge.configure :default @18:44:13.76 SamplePump.configure :default @18:44:13.92 Toroid.configure :default @18:44:14.05 Carousel.configure :default @18:44:14.18 Pressure.configure :default @18:44:14.30 ExtraPump.configure :default @18:44:14.41 PinPort.configure :default @18:44:14.47 All elf controllers running firmware version 4.79 @18:44:14.49 Gate.power :motor,:ON,limit to 5000mA @18:44:14.62 Archive.in :default @18:44:14.98 Archive.home.jog 0 @18:44:15.13 Toroid.in :default @18:44:15.20 Lysis2.in :default @18:44:15.23 Air.in :default @18:44:15.27 PinPort.in :default @18:44:15.30 Plunge.in :default @18:44:15.40 Lysis1.in :default @18:44:15.61 Toroid.home.jog 0 @18:44:15.66 Air.home.jog 0 @18:44:15.68 Lysis2.home.jog 0 @18:44:15.71 Plunge.home.jog 0 @18:44:15.73 PinPort.home.jog 0 @18:44:15.76 Lysis1.home.jog 0 @18:44:16.20 Archive.in :homing @18:44:16.37 Archive.jog 1391 @18:44:16.50 Toroid.in :homing @18:44:16.57 PinPort.in :homing @18:44:16.61 Plunge.in :homing @18:44:16.65 Lysis2.in :homing @18:44:16.69 Lysis1.in :homing @18:44:16.72 Air.in :homing @18:44:16.88 Toroid.jog 641 @18:44:16.97 PinPort.jog -107 @18:44:17.01 Lysis2.jog 1393 @18:44:17.05 Lysis1.jog 1382 @18:44:17.08 Plunge.jog 1552 @18:44:17.12 Air.jog 1385 @18:44:17.35 PinPort.setRawPosition! 0 @18:44:17.40 PinPort.seek :home @18:44:17.50 PinPort.in :default @18:44:17.58 Archive.setRawPosition! 50 @18:44:17.63 Archive.seek :home @18:44:17.91 Archive.in :default @18:44:18.31 Lysis2.setRawPosition! 50 @18:44:18.37 Lysis2.seek :home @18:44:18.47 Plunge.setRawPosition! 50 @18:44:18.50 Lysis1.setRawPosition! 50 @18:44:18.52 Air.setRawPosition! 50 @18:44:18.63 Plunge.seek :home @18:44:18.67 Lysis1.seek :home @18:44:18.74 Air.seek :home @18:44:18.90 Lysis2.in :default @18:44:18.99 Plunge.in :default @18:44:19.11 Lysis1.in :default @18:44:19.13 Air.in :default @18:44:19.26 Toroid.setRawPosition! 0 @18:44:19.31 Toroid.seek :home @18:44:19.41 Toroid.in :default @18:44:19.55 Toroid.seek :clear @18:44:21.17 Carousel.in :default @18:44:21.23 Carousel.home.jog 0 @18:44:21.72 Carousel.in :default @18:44:21.78 Carousel.seek 38 @18:44:21.95 Selecting Cartridge 37 @18:44:22.21 Carousel.seek 37 @18:44:22.70 Lysis1.in :fast @18:44:22.76 Lysis1.seek :locked @18:44:25.36 Plunge.seek :locked @18:44:25.49 Air.in :fast @18:44:25.54 Air.seek :locked @18:44:28.00 Powering cartridge bus @18:44:30.60 Gate.setIR 1750,5000,0,6 @18:44:30.73 Gate.setIR 0,5000,0,6 @18:44:30.79 PuckHeater.configure :default @18:44:30.85 Gate.power :heat,:ON,limit to 5000mA @18:44:31.83 Mounted lysis Cartridge board#Q05032 @18:44:31.86 Using default puck heat loss curve for HeatingAccelInCarousel 37 @18:44:49.71 -> Cmd.startFiltering @18:44:49.96 Preparing to filter cartridge 37 92.07 @18:44:50.25 Priming sample loop w/100ml, bypass w/10ml @18:44:50.26 SamplePump.setPosition! 0ml @18:44:50.31 Bypass.open @18:44:51.78 Intake.open @18:44:52.53 Sea 15.3m deep (37.2psia) @18:44:52.55 Waiting up to 20 seconds for sea pressure to stabilize +/-0.50psi @18:44:55.13 Vacuum tare is 54% of full scale @18:44:55.26 Intake.close @18:44:55.32 Bypass.close @18:44:56.07 Verifying toroid holds at least 0.6..0.85 of 37.2psia @18:44:56.09 Waiting up to 40 seconds for toroid pressure to stabilize +/-1.50psi @18:45:00.66 Bypass.open @18:45:01.33 Intake.open @18:45:02.74 Pressure.in :default @18:45:02.80 Pressure.hold @18:45:02.98 SamplePump.in :prime @18:45:03.05 SamplePump.seek 10ml,59 seconds @18:45:11.68 Pressure.stop @18:45:11.73 SamplePump.setPosition! 0ml @18:45:11.78 Exhaust.open @18:45:13.25 Bypass.close @18:45:14.71 Pressure.in :default @18:45:14.77 Pressure.hold @18:45:14.89 Sea 15.4m deep (37.5psia) @18:45:15.04 SamplePump.in :prime @18:45:15.11 SamplePump.seek 100ml,5:16 @18:46:49.08 Pressure.stop @18:46:49.14 Bypass.open @18:46:49.82 Intake.close @18:46:50.54 Toroid.in :hold @18:46:50.60 Toroid.seek :crack @18:46:52.10 Toroid.seek :cartridge @18:46:53.20 Toroid.in :default @18:46:53.26 Toroid.seek :clear @18:46:55.22 Exhaust.close @18:46:55.24 Bypass.close @18:46:56.04 Verifying cartridge holds at least 0.65..0.85 of 39.9psia @18:46:56.05 Waiting up to 40 seconds for cartridge pressure to stabilize +/-1.50psi @18:47:00.68 Sampling 1000.0ml @18:47:00.69 SamplePump.setPosition! 0ml @18:47:00.75 Exhaust.open @18:47:01.51 Intake.open @18:47:02.31 Pressure.in :default @18:47:02.37 Pressure.hold @18:47:02.49 Sea 16.9m deep (39.7psia) @18:47:02.63 SamplePump.in :sample @18:47:02.75 SamplePump.seek 1000ml,1:23:48 @18:47:05.35 Cartridge 37 filtering 1/1000ml at 0.41ml/s with 27.5psi vacuum 16.2m deep (38.6psia) @18:48:05.35 Cartridge 37 filtering 26/1000ml at 0.42ml/s with 25.7psi vacuum 17.1m deep (40.0psia) @18:49:05.35 Cartridge 37 filtering 50/1000ml at 0.36ml/s with 25.0psi vacuum 16.7m deep (39.4psia) @18:50:05.34 Cartridge 37 filtering 72/1000ml at 0.37ml/s with 25.5psi vacuum 17.8m deep (41.0psia) @18:51:05.35 Cartridge 37 filtering 92/1000ml at 0.35ml/s with 25.7psi vacuum 16.6m deep (39.2psia) @18:52:05.35 Cartridge 37 filtering 112/1000ml at 0.33ml/s with 21.3psi vacuum 17.2m deep (40.2psia) @18:53:05.35 Cartridge 37 filtering 131/1000ml at 0.32ml/s with 21.4psi vacuum 17.4m deep (40.4psia) @18:54:05.34 Cartridge 37 filtering 149/1000ml at 0.26ml/s with 22.7psi vacuum 18.3m deep (41.8psia) @18:55:05.35 Cartridge 37 filtering 166/1000ml at 0.26ml/s with 23.6psi vacuum 16.7m deep (39.4psia) @18:56:05.34 Cartridge 37 filtering 182/1000ml at 0.26ml/s with 25.3psi vacuum 17.7m deep (40.8psia) @18:57:05.35 Cartridge 37 filtering 198/1000ml at 0.27ml/s with 22.1psi vacuum 18.2m deep (41.6psia) @18:58:05.34 Cartridge 37 filtering 213/1000ml at 0.23ml/s with 23.7psi vacuum 16.9m deep (39.7psia) @18:59:05.35 Cartridge 37 filtering 227/1000ml at 0.23ml/s with 25.3psi vacuum 16.5m deep (39.1psia) @19:00:05.34 Cartridge 37 filtering 241/1000ml at 0.22ml/s with 23.1psi vacuum 19.8m deep (44.0psia) @19:01:05.35 Cartridge 37 filtering 254/1000ml at 0.23ml/s with 22.5psi vacuum 18.7m deep (42.4psia) @19:02:05.34 Cartridge 37 filtering 267/1000ml at 0.21ml/s with 23.4psi vacuum 17.6m deep (40.7psia) @19:03:05.35 Cartridge 37 filtering 279/1000ml at 0.20ml/s with 24.0psi vacuum 17.5m deep (40.5psia) @19:04:05.34 Cartridge 37 filtering 292/1000ml at 0.21ml/s with 22.9psi vacuum 17.2m deep (40.1psia) @19:04:35.67 Flow averaged 0.28ml/s over 17:34 @19:04:35.71 Waiting up to 14 seconds for vacuum to reduce from 28.5 to 18.0psi @19:04:36.29 Relieved excess vacuum in .6 seconds @19:04:36.31 SamplePump.seek 1000ml,59:01 @19:05:05.34 Cartridge 37 filtering 303/1000ml at 0.20ml/s with 26.5psi vacuum 15.5m deep (37.6psia) @19:05:07.45 Flow averaged 0.20ml/s over 32 seconds @19:05:07.48 Waiting up to 11 seconds for vacuum to reduce from 27.1 to 18.0psi @19:05:08.06 Relieved excess vacuum in .6 seconds @19:05:08.08 SamplePump.seek 1000ml,58:30 @19:05:37.60 Flow averaged 0.20ml/s over 30 seconds @19:05:37.63 Waiting up to 12 seconds for vacuum to reduce from 27.7 to 18.0psi @19:05:38.21 Relieved excess vacuum in .6 seconds @19:05:38.23 SamplePump.seek 1000ml,58:00 @19:05:40.09 Flow estimate reduced to 0.16ml/s @19:05:40.11 Intake.close @19:05:40.19 Exhaust.close @19:05:40.36 Toroid.in :hold @19:05:40.43 Toroid.seek :bypass @19:05:42.05 Toroid.in :default @19:05:42.11 Toroid.seek :clear @19:05:43.72 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged @19:05:43.74 Pressure.stop @19:05:43.93 Sampled 310.1ml @19:05:46.19 -> Cmd.startProcessing 120.0 @19:05:47.63 SPR.command "read_sequences" @19:05:47.67 SPR.command "stats_on" @19:05:48.52 SPR.command "start 0 1" @19:05:49.26 SPR.start /var/log/esp/spr22may2819h.spr.bz2 @19:05:52.25 Handoff.select :waste @19:05:58.92 Syringe tare is 18% of full scale @19:05:58.94 PinPort.in :locking @19:05:59.02 PinPort.seek :locked @19:05:59.50 Archive.in :fast @19:05:59.56 Lysis2.in :fast @19:05:59.60 Lysis1.seek 1350uL @19:05:59.74 Archive.seek :full @19:05:59.78 Lysis2.seek :full @19:06:01.74 Plunge.seek :reagent @19:06:02.61 Evacuating puck with filter bubble point of 50psi @19:06:02.65 PinPort.seek :locked @19:06:02.74 Handoff.select :waste @19:06:09.32 Air syringe pumping up to 1 stroke to reach 8psi @19:06:09.33 Air stroke #1 of 1 @19:06:09.42 Air.in :pressurize @19:06:09.48 Air.seek :pressurized @19:06:21.08 -0.0psi after pressurize @19:06:21.12 Air.in :compress @19:06:21.19 Air.seek :evac @19:06:25.29 0.7psi after compress @19:06:37.86 0.1psi after compress (stable) @19:06:37.87 Air.in :default @19:06:37.94 Air.hold @19:06:38.14 at 0.1psi Opened to waste @19:06:38.15 Handoff.select :blocked @19:06:38.29 Archive syringe pumping up to 8 strokes to reach 50psi @19:06:38.30 Archive stroke #1 of 8 @19:06:38.39 Archive.in :pressurize @19:06:38.45 Archive.seek :pressurized @19:06:51.33 10.0psi after pressurize @19:06:51.36 Archive.in :compress @19:06:51.45 Archive.seek :empty @19:07:06.14 30.6psi after compress @19:07:25.72 40.2psi after compress (stable) @19:07:25.74 Archive.in :gulp @19:07:25.82 Archive.seek :topVent @19:07:33.00 41.4psi after gulp @19:07:45.57 41.2psi after gulp (stable) @19:07:45.58 Archive stroke #2 of 8 @19:07:45.68 Archive.in :pressurize @19:07:45.74 Archive.seek :pressurized @19:07:58.60 40.1psi after pressurize @19:07:58.62 Archive.in :compress @19:07:58.70 Archive.seek :empty @19:08:11.45 Reached 50.3 of 50.0psi after pumping 3418uL (1.98 strokes) @19:08:11.47 Archive.in :default @19:08:11.54 Archive.hold @19:08:11.60 Handoff.select :waste @19:08:21.79 delay 1:00 @19:09:21.81 Handoff.select :blocked @19:09:53.07 Pressure too High, open to waste again (8.0psi) @19:09:53.08 Handoff.select :waste @19:09:53.21 delay 1:00 @19:10:53.22 Handoff.select :blocked @19:11:21.41 Handoff.select :waste @19:11:21.52 4.0psi at evacEnd @19:11:21.53 Handoff.select :blocked @19:11:21.67 Archive.in :fast @19:11:21.73 Archive.seek :topVent @19:11:35.24 Stable @3.0psi after Handoff blocked @19:11:35.26 Archive syringe pumping up to 8 strokes to reach 33psi @19:11:35.28 Archive stroke #1 of 8 @19:11:35.38 Archive.in :pressurize @19:11:35.44 Archive.seek :pressurized @19:11:48.32 4.4psi after pressurize @19:11:48.35 Archive.in :compress @19:11:48.45 Archive.seek :empty @19:12:03.14 7.7psi after compress @19:12:44.72 20.0psi after compress (stable) @19:12:44.73 Archive.in :gulp @19:12:44.81 Archive.seek :topVent @19:12:51.97 21.1psi after gulp @19:13:04.55 21.7psi after gulp (stable) @19:13:04.56 Archive stroke #2 of 8 @19:13:04.65 Archive.in :pressurize @19:13:04.72 Archive.seek :pressurized @19:13:17.59 22.3psi after pressurize @19:13:17.61 Archive.in :compress @19:13:17.69 Archive.seek :empty @19:13:32.41 24.9psi after compress @19:13:52.03 Reached 33.0 of 33.0psi after pumping 3460uL (2.00 strokes) @19:13:52.04 Archive.in :default @19:13:52.12 Archive.hold @19:13:52.18 Handoff.select :waste @19:13:59.36 delay 1:00 @19:14:59.39 Handoff.select :blocked @19:15:22.11 Handoff.select :waste @19:15:22.24 2.6psi at evacEnd @19:15:22.25 Air.seek :fullVent @19:15:23.69 Archive.in :fast @19:15:23.75 Archive.seek :topVent @19:15:30.86 Seawater evacuated, Presure above/below filter released. Lysis begins @19:15:30.95 Lysis1.in :slow @19:15:31.01 Lysis1.seek 1050uL @19:16:01.06 delay 5 seconds @19:16:06.07 Handoff.select :blocked @19:16:06.25 Lysis1.seek 450uL @19:17:06.25 delay 3 seconds @19:17:09.32 Air.in mix with 1.40048 parts Lysis1 @19:17:09.80 Air.seek :evac @19:17:09.86 Lysis1.seek 300uL @19:17:25.00 Lysis1.seek :empty @19:17:55.13 Lysis buffer delivered @29.6psi @19:17:55.15 Polling Can every 2:00 @19:17:55.69 PuckHeater.configure :default #ambient 14.4C @19:17:55.74 PuckHeater.seek 80.1C @19:19:06.09 PuckHeater.hold 2:00,80.1C @19:21:05.98 Polling Can every 2:00 @19:21:06.52 PuckHeater.hold 30 seconds,79.1C @19:21:36.66 Polling Can every 2:00 @19:21:37.22 PuckHeater.configure :default #ambient 14.4C @19:21:37.27 PuckHeater.hold 30 seconds,78.5C @19:22:07.40 Polling Can every 2:00 @19:22:07.94 PuckHeater.configure :default #ambient 14.4C @19:22:08.00 PuckHeater.hold 10:00,78C @19:32:07.05 Lysis complete. Syringe pressure @40.4psi @19:32:07.06 Handoff.select :blocked @19:32:07.11 Archive syringe pumping up to 5 strokes to reach 40psi @19:32:07.12 Archive stroke #1 of 5 @19:32:07.22 Archive.in :pressurize @19:32:07.28 Archive.seek :pressurized @19:32:07.45 Reached 40.4 of 40.0psi after pumping 0uL (0.00 strokes) @19:32:07.46 Archive.in :default @19:32:07.56 Archive.hold @19:32:07.64 delay 2 seconds @19:32:09.66 Archive.in :fast @19:32:09.72 Archive.seek :full @19:32:09.88 Lysis1.seek 138uL @19:32:23.77 delay 1 second @19:32:24.79 Archive.seek :full @19:32:24.83 Lysis1.seek :empty @19:32:38.82 Initial air pressure @40.0psi @19:32:38.89 Lysis1.in :extract @19:32:38.96 Lysis1.seek 600uL,nil @19:32:40.70 Archive syringe pumping up to 6 strokes to reach 25psi @19:32:40.71 Archive stroke #1 of 6 @19:32:40.81 Archive.in :pressurize @19:32:40.87 Archive.seek :pressurized @19:32:53.76 15.9psi after pressurize @19:32:53.79 Archive.in :compress @19:32:53.88 Archive.seek :empty @19:33:06.54 Syringe::LysatePlungerStuck in PROCESSING -- at < 401uL during Lysis Syringe #1 (LyS1) move @19:33:06.57 Lysis1.in :default @19:33:06.64 Lysis1.seek 366uL @19:33:07.35 Reached 30.2 of 25.0psi after pumping 1704uL (0.99 strokes) @19:33:07.37 Archive.in :default @19:33:07.44 Archive.hold @19:33:08.46 Lysis1.in :extract @19:33:08.52 Lysis1.seek 600uL,nil @19:33:12.09 Archive syringe pumping up to 6 strokes to reach 25psi @19:33:12.10 Archive stroke #1 of 6 @19:33:12.19 Archive.in :compress @19:33:12.26 Archive.seek :empty @19:33:13.09 Archive.in :default @19:33:13.14 Archive.hold @19:33:13.29 Initial 600uL lysate @17.5psi @19:33:13.31 Handoff.select :blocked @19:33:13.41 Initial air pressure @18.3psi @19:33:13.49 Lysis1.seek 825uL,nil @19:33:14.34 Archive syringe pumping up to 6 strokes to reach 17psi @19:33:14.35 Archive stroke #1 of 6 @19:33:14.51 Archive.in :compress @19:33:14.57 Archive.seek :empty @19:33:15.18 16.8psi after compress @19:33:27.06 Archive.in :default @19:33:27.12 Archive.hold @19:33:27.30 Recovered 825uL lysate @16.2psi @19:33:27.32 Air.in :halfSpeed @19:33:27.39 Air.seek :fullVent @19:33:29.61 Puck Top Vented, bottom @1.4psi @19:33:29.62 Handoff.select :waste @19:33:29.75 delay 5 seconds @19:33:34.82 Handoff open to waste, bottom @0.0psi @19:33:34.84 Air.in :default @19:33:34.91 Air.seek :evac @19:33:36.32 Lysis2.in :DAmix @19:33:36.45 Lysis1.in :DAmix @19:33:36.93 Lysis2.seek 1480uL @19:33:36.97 Lysis1.seek 575uL @19:34:12.03 delay 5 seconds @19:34:17.05 Handoff.select SPR @19:34:17.20 SPR.command "start 2 14" @19:34:19.02 SPR stabilized @19:34:19.15 delay 10 seconds @19:34:29.16 SPR.command "start 1 0" @19:34:31.89 Waiting up to 4:00 for SPR to enter state 1 @19:36:34.27 SPR.command "hold_on" @19:36:35.68 SPR Dilution 1 of 1 -- Experiment 1 with 300.0uL L1 + 300.0uL L2 @19:36:36.15 Lysis2.seek 1180uL @19:36:36.22 Lysis1.seek 275uL @19:37:18.25 delay 15 seconds @19:37:33.27 SPR.command "hold_off" @19:37:36.36 Waiting up to 38:00 for SPR to enter state 7 @19:58:26.16 SPRsummary:2.04133,137.263,128.524,137.9 after 3310s @20:05:03.38 SPR analysis completed @20:05:09.59 Ended log spr22may2819h.spr.bz2 @20:05:09.60 SPR.command "stats_off" @20:05:11.23 -> Cmd.stop @20:05:11.32 Handoff.select :close @20:05:11.46 Gate.power :heat,:OFF @20:05:11.47 Gate.power :cartridge,:OFF @20:05:11.56 Carousel.seek 37 @20:05:11.74 Plunge.seek :locked @20:05:12.61 PinPort.in :unlocking @20:05:12.67 PinPort.seek :clear @20:05:13.21 Archive.in :unlocking @20:05:13.34 Archive.seek :clear @20:05:13.43 Air.in :unlocking @20:05:13.54 Air.seek :clear @20:05:13.58 Lysis1.in :unlocking @20:05:13.75 Lysis2.in :unlocking @20:05:13.79 Lysis1.seek :clear @20:05:13.89 Lysis2.seek :clear @20:05:17.49 Gate.power :motor,:OFF @20:05:17.51 Gate.power :core,:OFF @20:05:17.53 Gate.power :analytic1,:OFF @20:05:17.56 Gate.power :analytic2,:OFF @20:05:17.58 Gate.power :analytic3,:OFF @20:05:17.60 Gate.power :main,:OFF @20:05:17.62 Safely stopped and ready to power off :STOPPED