@14:48:08.23EDT16-Aug-19
>BREAK< ESP3 Gateway v0.99 for core bus @14:48:08.24 >BREAK< ESP3 Gateway v0.99 for cartridge bus @14:48:09.82 Configuration for honu by brent@mbari.org 7/26/19 /home/esp/esp2/type/auv/honu/configure.rb @14:48:12.77 Polling Can every 5:00 @14:48:13.07 Clients may connect on TCP port 9999 @14:48:13.09 Connecting to 10.89.5.2:9999 @14:48:13.13 Waiting for espclients... @14:48:13.47 Session Begun @14:48:15.42 -> showStatus @14:48:17.44 -> Cmd.loadCartridge -15 @14:48:17.59 Gate.power :main,:ON,limit to 5000mA @14:48:17.60 Gate.power :analytic1,:ON,limit to 5000mA @14:48:17.62 Gate.power :analytic2,:ON,limit to 5000mA @14:48:17.64 Gate.power :analytic3,:ON,limit to 5000mA @14:48:17.77 Powering core bus @14:48:20.05 SPR Module 1, FW Rev. B, FPGA Rev. C @14:48:20.18 Archive.configure :default @14:48:20.30 Air.configure :default @14:48:20.40 Lysis1.configure :default @14:48:20.50 Lysis2.configure :default @14:48:20.62 Plunge.configure :default @14:48:20.74 SamplePump.configure :default @14:48:20.86 ExtraPump.configure :default @14:48:20.98 Toroid.configure :default @14:48:21.10 Carousel.configure :default @14:48:21.22 Pressure.configure :default @14:48:21.32 PinPort.configure :default @14:48:21.38 All elf controllers running firmware version 4.58 @14:48:21.40 Gate.power :motor,:ON,limit to 5000mA @14:48:21.59 Archive.reconfigure :default @14:48:21.65 Air.reconfigure :default @14:48:21.95 Air.home.jog 0 @14:48:22.03 Archive.home.jog 0 @14:48:22.17 Toroid.reconfigure :default @14:48:22.24 Plunge.reconfigure :default @14:48:22.28 Lysis1.reconfigure :default @14:48:22.33 PinPort.reconfigure :default @14:48:22.41 Lysis2.reconfigure :default @14:48:22.62 PinPort.home.jog 0 @14:48:22.68 Plunge.home.jog 0 @14:48:22.74 Lysis1.home.jog 0 @14:48:22.80 Toroid.home.jog 0 @14:48:22.86 Lysis2.home.jog 0 @14:48:23.09 Archive.reconfigure :homing @14:48:23.18 Air.reconfigure :homing @14:48:23.32 Archive.jog 1382 @14:48:23.37 Air.jog 1408 @14:48:23.52 Toroid.reconfigure :homing @14:48:23.55 Plunge.reconfigure :homing @14:48:23.58 PinPort.reconfigure :homing @14:48:23.63 Lysis2.reconfigure :homing @14:48:23.66 Lysis1.reconfigure :homing @14:48:23.85 Plunge.jog 1542 @14:48:23.91 Lysis2.jog 1428 @14:48:23.96 Toroid.jog 623 @14:48:24.02 PinPort.jog -108 @14:48:24.08 Lysis1.jog 1398 @14:48:24.26 PinPort.setRawPosition! 0 @14:48:24.30 PinPort.seek :home @14:48:24.39 PinPort.reconfigure :default @14:48:24.47 Archive.setRawPosition! 50 @14:48:24.52 Archive.seek :home @14:48:24.60 Air.setRawPosition! 50 @14:48:24.64 Air.seek :home @14:48:24.80 Archive.reconfigure :default @14:48:24.93 Air.reconfigure :default @14:48:25.09 Plunge.setRawPosition! 50 @14:48:25.14 Plunge.seek :home @14:48:25.23 Lysis2.setRawPosition! 50 @14:48:25.28 Lysis2.seek :home @14:48:25.36 Lysis1.setRawPosition! 50 @14:48:25.42 Lysis1.seek :home @14:48:25.51 Plunge.reconfigure :default @14:48:25.59 Lysis2.reconfigure :default @14:48:25.71 Lysis1.reconfigure :default @14:48:26.08 Toroid.setRawPosition! 0 @14:48:26.13 Toroid.seek :home @14:48:26.22 Toroid.reconfigure :default @14:48:26.34 Toroid.seek :clear @14:48:27.97 Carousel.reconfigure :default @14:48:28.04 Carousel.home.jog 0 @14:48:28.54 Carousel.seek 55 @14:48:28.70 Selecting Cartridge 54 @14:48:28.95 Carousel.seek 54 @14:48:29.44 Lysis1.seek :locked @14:48:33.56 Plunge.seek :locked @14:48:33.69 Air.seek :locked @14:48:37.81 Powering cartridge bus @14:48:39.93 Gate.setIR 1750,5000,0,6 @14:48:40.06 Gate.setIR 0,5000,0,6 @14:48:40.12 Loaded Lyse-n-Go Cartridge @14:48:40.13 PuckHeater.configure HeatConfig @14:48:40.18 Gate.power :heat,:ON,limit to 5000mA @14:48:56.58 -> Cmd.startFiltering @14:48:56.81 Duration of filtering limited to 2:05:00 @14:48:57.08 Priming sample loop w/100ml, bypass w/10ml @14:48:57.09 SamplePump.setPosition! 0ml @14:48:57.14 Bypass.open @14:48:58.63 Intake.open @14:48:59.78 Waiting up to 20 seconds for sea pressure to stabilize +/-0.50psi @14:49:02.62 Vacuum tare is 52% of full scale @14:49:02.75 Pressure.reconfigure :default @14:49:02.81 Pressure.hold @14:49:02.98 SamplePump.reconfigure :prime @14:49:03.05 SamplePump.seek 10ml,1:21 @14:49:12.00 Pressure.coast @14:49:12.05 SamplePump.setPosition! 0ml @14:49:12.10 Exhaust.open @14:49:13.50 Bypass.close @14:49:15.04 Pressure.reconfigure :default @14:49:15.10 Pressure.hold @14:49:15.32 SamplePump.reconfigure :prime @14:49:15.40 SamplePump.seek 100ml,8:51 @14:51:07.26 Pressure.coast @14:51:07.32 Bypass.open @14:51:08.06 Intake.close @14:51:08.85 Toroid.reconfigure :hold @14:51:08.91 Toroid.seek :crack @14:51:09.71 Toroid.reconfigure :default @14:51:09.77 Toroid.seek :clear @14:51:11.33 Toroid.reconfigure :hold @14:51:11.40 Toroid.seek :cartridge @14:51:13.21 Toroid.reconfigure :default @14:51:13.27 Toroid.seek :clear @14:51:15.24 Pressurizing cartridge to 26psi above 16.3psia @14:51:15.25 SamplePump.setPosition! 0ml @14:51:15.29 Intake.open @14:51:15.99 Bypass.close @14:51:16.71 Exhaust.close @14:51:17.52 Pressure.reconfigure :default @14:51:17.57 Pressure.hold @14:51:17.74 SamplePump.reconfigure :charge @14:51:17.81 SamplePump.seek 2.3ml,42 seconds @14:51:27.66 Pressure.coast @14:51:27.72 Intake.close @14:51:28.52 Pressurized cartridge with 0.453ml to 26.6psi @14:51:28.53 Isolated @39.5psia (23.2psi) @14:51:28.54 Checking for cartridge leaks from 39.5psia @14:51:28.57 Waiting up to 20 seconds for cartridge pressure to stabilize +/-0.75psi @14:51:33.62 Cartridge pressure fell 1% to 38.9psia @14:51:33.70 Sampling 1000.0ml @14:51:33.71 SamplePump.setPosition! 0ml @14:51:33.76 Exhaust.open @14:51:34.46 Intake.open @14:51:35.35 Pressure.reconfigure :default @14:51:35.41 Pressure.hold @14:51:35.52 Sea 1.7m deep (17.1psia) @14:51:35.65 SamplePump.reconfigure :sample @14:51:35.72 SamplePump.seek 1000ml,1:23:53 @14:51:38.39 Cartridge 54 sampling 1/1000ml at 0.58ml/s with 24.6psi vacuum @14:52:38.39 Cartridge 54 sampling 35/1000ml at 0.54ml/s with 19.3psi vacuum @14:53:38.39 Cartridge 54 sampling 67/1000ml at 0.52ml/s with 23.7psi vacuum @14:54:38.39 Cartridge 54 sampling 98/1000ml at 0.51ml/s with 23.7psi vacuum @14:55:38.39 Cartridge 54 sampling 128/1000ml at 0.48ml/s with 21.5psi vacuum @14:56:38.39 Cartridge 54 sampling 157/1000ml at 0.43ml/s with 23.0psi vacuum @14:57:38.39 Cartridge 54 sampling 184/1000ml at 0.47ml/s with 27.2psi vacuum @14:58:38.39 Cartridge 54 sampling 210/1000ml at 0.43ml/s with 26.5psi vacuum 1.7m deep (17.3psia) @14:59:38.39 Cartridge 54 sampling 235/1000ml at 0.40ml/s with 22.4psi vacuum @15:00:38.39 Cartridge 54 sampling 259/1000ml at 0.42ml/s with 24.1psi vacuum @15:01:38.39 Cartridge 54 sampling 281/1000ml at 0.35ml/s with 23.5psi vacuum @15:02:38.39 Cartridge 54 sampling 302/1000ml at 0.33ml/s with 21.9psi vacuum @15:03:38.39 Cartridge 54 sampling 321/1000ml at 0.31ml/s with 25.4psi vacuum @15:04:38.39 Cartridge 54 sampling 338/1000ml at 0.29ml/s with 25.3psi vacuum @15:05:38.39 Cartridge 54 sampling 354/1000ml at 0.27ml/s with 25.2psi vacuum @15:06:38.39 Cartridge 54 sampling 369/1000ml at 0.21ml/s with 24.1psi vacuum @15:07:38.39 Cartridge 54 sampling 383/1000ml at 0.23ml/s with 24.8psi vacuum @15:08:38.39 Cartridge 54 sampling 396/1000ml at 0.20ml/s with 24.2psi vacuum @15:09:38.39 Cartridge 54 sampling 408/1000ml at 0.21ml/s with 25.7psi vacuum @15:10:38.39 Cartridge 54 sampling 420/1000ml at 0.20ml/s with 26.9psi vacuum @15:11:22.03 Flow averaged 0.36ml/s over 19:43 @15:11:22.06 Waiting up to 12 seconds for vacuum to reduce from 27.4 to 18.0psi @15:11:22.76 Relieved excess vacuum in 4.2 seconds @15:11:22.78 SamplePump.seek 1000ml,48:07 @15:11:32.50 Flow estimate reduced to 0.29ml/s @15:11:32.53 Waiting up to 12 seconds for vacuum to reduce from 27.4 to 18.0psi @15:11:33.23 Relieved excess vacuum in 7.4 seconds @15:11:33.25 SamplePump.seek 1000ml,47:58 @15:11:38.38 Cartridge 54 sampling 432/1000ml at 0.20ml/s with 29.2psi vacuum @15:12:05.51 Flow averaged 0.25ml/s over 26 seconds @15:12:05.54 Waiting up to 14 seconds for vacuum to reduce from 28.5 to 18.0psi @15:12:06.24 Relieved excess vacuum in 7.7 seconds @15:12:06.26 SamplePump.seek 1000ml,47:26 @15:12:15.99 Flow estimate reduced to 0.20ml/s @15:12:16.03 Waiting up to 13 seconds for vacuum to reduce from 28.1 to 18.0psi @15:12:16.73 Relieved excess vacuum in 7.4 seconds @15:12:16.75 SamplePump.seek 1000ml,47:16 @15:12:23.32 Flow estimate reduced to 0.16ml/s @15:12:23.34 Intake.close @15:12:23.38 Exhaust.close @15:12:23.55 Toroid.reconfigure :hold @15:12:23.63 Toroid.seek :bypass @15:12:25.25 Toroid.reconfigure :default @15:12:25.32 Toroid.seek :clear @15:12:26.94 Cartridge::Sampler::Clogged in FILTERING -- Cartridge clogged @15:12:26.95 Pressure.coast @15:12:27.13 Sampled 440.4ml @15:12:29.23 -> Cmd.startProcessing @15:12:31.08 SPR.command "read_sequences" @15:12:31.81 SPR.command "stats_on" @15:12:31.85 SPR.command "start 0 1" @15:12:33.86 Begun log /var/log/esp/spr19aug1615h.spr @15:12:34.66 Handoff.select :waste @15:12:34.84 Archive.seek :empty @15:12:42.01 Syringe tare is 20% of full scale @15:12:52.56 PinPort.seek :cartridge @15:12:57.12 Lysis1.seek 1350ul @15:12:59.59 Lysis2.seek 1700ul @15:13:02.69 Plunge.seek :reagent @15:13:03.54 Evacuating puck with filter bubble point of 50psi @15:13:03.56 PinPort.seek :cartridge @15:13:03.64 Handoff.select :waste @15:13:10.97 Air pumping 1 strokes to reach 8psi @15:13:10.99 Air.reconfigure :gulp @15:13:11.08 Air.seek :tVent @15:13:12.88 0.7psi after gulp @15:13:12.89 Air stroke #1 of 1 @15:13:12.91 Air.reconfigure :pressurize @15:13:12.99 Air.seek :pressurized @15:13:25.83 0.5psi after pressurize @15:13:25.84 Air.reconfigure :compress @15:13:25.92 Air.seek :evac @15:13:28.58 1.2psi after compress @15:13:42.46 0.2psi after compress (stable) @15:13:42.58 at 0.2psi Opened to waste @15:13:42.59 Handoff.select :blocked @15:13:42.76 Air pumping 10 strokes to reach 50psi @15:13:42.78 Air.reconfigure :gulp @15:13:42.86 Air.seek :tVent @15:13:52.77 3.1psi after gulp @15:13:52.78 Air stroke #1 of 10 @15:13:52.80 Air.reconfigure :pressurize @15:13:52.88 Air.seek :pressurized @15:14:05.72 4.5psi after pressurize @15:14:05.73 Air.reconfigure :compress @15:14:05.81 Air.seek :evac @15:14:08.48 4.8psi after compress @15:14:55.44 26.8psi after compress (stable) @15:14:55.47 Air.reconfigure :gulp @15:14:55.55 Air.seek :tVent @15:15:05.44 29.1psi after gulp @15:15:05.45 Air stroke #2 of 10 @15:15:05.47 Air.reconfigure :pressurize @15:15:05.56 Air.seek :pressurized @15:15:18.40 28.0psi after pressurize @15:15:18.41 Air.reconfigure :compress @15:15:18.49 Air.seek :evac @15:15:21.17 26.7psi after compress @15:16:05.35 43.3psi after compress (stable) @15:16:05.37 Air.reconfigure :gulp @15:16:05.45 Air.seek :tVent @15:16:15.36 47.0psi after gulp @15:16:15.38 Air stroke #3 of 10 @15:16:15.40 Air.reconfigure :pressurize @15:16:15.48 Air.seek :pressurized @15:16:28.33 45.4psi after pressurize @15:16:28.34 Air.reconfigure :compress @15:16:28.42 Air.seek :evac @15:16:31.09 42.4psi after compress @15:16:49.18 Reached 50.0 of 50.0psi after pumping 6230ul (4.00 strokes) @15:16:49.19 Handoff.select :waste @15:17:15.89 delay 30 seconds @15:17:45.92 Handoff.select :blocked @15:18:34.86 Handoff.select :waste @15:18:34.98 PSI at @7.11psi at evacEnd @15:18:34.99 Handoff.select :blocked @15:18:35.11 Air.reconfigure :gulp @15:18:35.22 Air.seek :tVent @15:18:52.32 Stable @4.5psi after Handoff blocked @15:18:52.40 Air pumping 10 strokes to reach 34psi @15:18:52.41 Air stroke #1 of 10 @15:18:52.44 Air.reconfigure :pressurize @15:18:52.53 Air.seek :pressurized @15:19:05.38 5.6psi after pressurize @15:19:05.40 Air.reconfigure :compress @15:19:05.47 Air.seek :evac @15:19:08.14 5.7psi after compress @15:19:41.23 18.8psi after compress (stable) @15:19:41.25 Air.reconfigure :gulp @15:19:41.33 Air.seek :tVent @15:19:51.24 20.4psi after gulp @15:19:51.25 Air stroke #2 of 10 @15:19:51.27 Air.reconfigure :pressurize @15:19:51.35 Air.seek :pressurized @15:20:04.19 20.3psi after pressurize @15:20:04.21 Air.reconfigure :compress @15:20:04.28 Air.seek :evac @15:20:06.95 19.8psi after compress @15:20:53.03 27.2psi after compress (stable) @15:20:53.06 Air.reconfigure :gulp @15:20:53.13 Air.seek :tVent @15:21:03.03 29.6psi after gulp @15:21:03.06 Air stroke #3 of 10 @15:21:03.08 Air.reconfigure :pressurize @15:21:03.17 Air.seek :pressurized @15:21:16.01 29.4psi after pressurize @15:21:16.02 Air.reconfigure :compress @15:21:16.10 Air.seek :evac @15:21:18.77 28.1psi after compress @15:21:54.91 Reached 34.5 of 34.5psi after pumping 6230ul (4.00 strokes) @15:21:54.92 Handoff.select :waste @15:22:13.72 delay 30 seconds @15:22:43.75 Handoff.select :blocked @15:23:17.59 Handoff.select :waste @15:23:17.70 PSI at @6.71psi at evacEnd @15:23:17.72 Air.reconfigure :default @15:23:17.84 Air.seek :fVent @15:23:19.27 delay 5 seconds @15:23:24.30 Seawater evacuated, Presure above/below filter released. Lysis begins @15:23:24.37 Lysis1.reconfigure :slow @15:23:24.44 Lysis1.seek 1050ul @15:23:54.48 delay 5 seconds @15:23:59.50 Handoff.select :blocked @15:23:59.66 Lysis1.seek 450ul @15:24:59.65 delay 3 seconds @15:25:02.72 Air.reconfigure to mix into 1.37806 parts Lysis1 @15:25:03.21 Air.seek :evac @15:25:03.25 Lysis1.seek 300ul @15:25:18.39 Air.reconfigure :default @15:25:18.48 Air.seek :full @15:25:32.70 Lysis1.seek :empty @15:26:02.81 Lysis Delivered. Syringe pressure @36.4psi @15:26:02.85 PuckHeater.seek 84.1C @15:26:50.32 PuckHeater.hold 15:00,84.1C @15:41:49.16 Lysis complete. Syringe pressure @40.2psi @15:41:49.17 Handoff.select :blocked @15:41:49.27 Lysis1.reconfigure :halfSpeed @15:41:49.33 Lysis1.seek 200ul @15:41:53.14 delay 1 second @15:41:54.16 Lysis1.seek :empty @15:41:57.98 Initial AirS pressure @43.7psi @15:41:58.04 Lysis1.reconfigure :extract @15:41:58.18 Lysis1.seek 900ul,10:00 @15:41:59.32 Air pumping 10 strokes to reach 24psi @15:41:59.33 Air stroke #1 of 10 @15:41:59.35 Air.reconfigure :pressurize @15:41:59.43 Air.seek :pressurized @15:42:12.27 12.9psi after pressurize @15:42:12.28 Air.reconfigure :compress @15:42:12.38 Air.seek :evac @15:42:15.05 20.7psi after compress @15:42:29.30 18.7psi after compress (stable) @15:42:29.32 Air.reconfigure :gulp @15:42:29.40 Air.seek :tVent @15:42:39.30 19.0psi after gulp @15:42:39.31 Air stroke #2 of 10 @15:42:39.33 Air.reconfigure :pressurize @15:42:39.41 Air.seek :pressurized @15:42:52.24 18.4psi after pressurize @15:42:52.25 Air.reconfigure :compress @15:42:52.33 Air.seek :evac @15:42:55.01 20.1psi after compress @15:43:08.92 18.6psi after compress (stable) @15:43:08.94 Air.reconfigure :gulp @15:43:09.01 Air.seek :tVent @15:43:18.92 18.7psi after gulp @15:43:18.93 Air stroke #3 of 10 @15:43:18.95 Air.reconfigure :pressurize @15:43:19.02 Air.seek :pressurized @15:43:31.88 18.7psi after pressurize @15:43:31.89 Air.reconfigure :compress @15:43:31.97 Air.seek :evac @15:43:34.65 19.3psi after compress @15:43:48.55 18.5psi after compress (stable) @15:43:48.56 Air.reconfigure :gulp @15:43:48.64 Air.seek :tVent @15:43:58.53 19.2psi after gulp @15:43:58.54 Air stroke #4 of 10 @15:43:58.56 Air.reconfigure :pressurize @15:43:58.65 Air.seek :pressurized @15:44:11.48 18.5psi after pressurize @15:44:11.49 Air.reconfigure :compress @15:44:11.58 Air.seek :evac @15:44:14.25 19.1psi after compress @15:44:28.19 18.8psi after compress (stable) @15:44:28.21 Air.reconfigure :gulp @15:44:28.28 Air.seek :tVent @15:44:38.55 18.5psi after gulp @15:44:38.56 Air stroke #5 of 10 @15:44:38.57 Air.reconfigure :pressurize @15:44:38.65 Air.seek :pressurized @15:44:51.49 18.5psi after pressurize @15:44:51.51 Air.reconfigure :compress @15:44:51.59 Air.seek :evac @15:44:54.25 19.0psi after compress @15:45:08.15 18.8psi after compress (stable) @15:45:08.17 Air.reconfigure :gulp @15:45:08.26 Air.seek :tVent @15:45:18.17 19.2psi after gulp @15:45:18.18 Air stroke #6 of 10 @15:45:18.20 Air.reconfigure :pressurize @15:45:18.28 Air.seek :pressurized @15:45:31.11 18.8psi after pressurize @15:45:31.12 Air.reconfigure :compress @15:45:31.20 Air.seek :evac @15:45:33.88 18.8psi after compress @15:45:47.81 18.7psi after compress (stable) @15:45:47.82 Air.reconfigure :gulp @15:45:47.90 Air.seek :tVent @15:45:48.66 Reached 26.1 of 24.0psi after pumping 9345ul (6.00 strokes) @15:45:48.96 Lysis1 at 900ul @27.9psi @15:45:48.98 Syringe::PressureError in PROCESSING -- 27.9psi is out of 14.0..25.5 range -- Skipping SPR @15:45:49.00 SPR.command "start 2 14" @15:45:50.36 Handoff.select :waste @15:45:50.85 Ended log /var/log/esp/spr19aug1615h.spr @15:45:50.86 SPR.command "stats_off" @15:45:52.40 -> Cmd.stop @15:45:52.49 Handoff.select :close @15:45:52.62 Gate.power :heat,:OFF @15:45:52.64 Gate.power :cartridge,:OFF @15:45:52.72 Carousel.seek 54 @15:45:52.89 Plunge.seek :locked @15:45:53.73 PinPort.seek :clear @15:45:58.34 Archive.seek :clear @15:45:58.47 Air.reconfigure :default @15:45:58.60 Lysis1.reconfigure :default @15:45:58.72 Air.seek :clear @15:45:58.78 Lysis1.seek :clear @15:45:58.86 Lysis2.seek :clear @15:46:16.09 Archive.coast @15:46:16.14 Air.coast @15:46:16.19 Lysis1.coast @15:46:16.24 Lysis2.coast @15:46:16.29 Plunge.coast @15:46:16.34 SamplePump.coast @15:46:16.39 Toroid.coast @15:46:16.45 Carousel.coast @15:46:16.50 PinPort.coast @15:46:16.68 Gate.power :motor,:OFF @15:46:16.70 Gate.power :core,:OFF @15:46:16.72 Gate.power :analytic1,:OFF @15:46:16.74 Gate.power :analytic2,:OFF @15:46:16.76 Gate.power :analytic3,:OFF @15:46:16.78 Gate.power :main,:OFF @15:46:16.80 Safely stopped and ready to power off