ct(1) takes more than 4 sec, with minimum beamline devices and mpx detector on
The simple ct(1) scan time with only one mpx detector and basic devices of the beamline takes 4.5 sec! What can be the cause ?
EH1_EXP [582]: with bench(): s=ct(1)
Mon Nov 02 16:04:28 2020: Scan(name=ct, path='not saved')
>
> roi4_avg = 17068.1 ( 17068.1 /s) mpx_cdte_22_eh1
> roi1_avg = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi4_min = 95.0000 ( 95.0000 /s) mpx_cdte_22_eh1
> roi1_std = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi5_std = 7439.81 ( 7439.81 /s) mpx_cdte_22_eh1
> roi5_max = 168021. ( 168021. /s) mpx_cdte_22_eh1
> roi1_min = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi3_sum = 430573. ( 430573. /s) mpx_cdte_22_eh1
> roi2_avg = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi3_avg = 24.6098 ( 24.6098 /s) mpx_cdte_22_eh1
> roi1_max = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi3_max = 168021. ( 168021. /s) mpx_cdte_22_eh1
> roi1_sum = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi4_std = 41025.2 ( 41025.2 /s) mpx_cdte_22_eh1
> roi3_std = 1679.46 ( 1679.46 /s) mpx_cdte_22_eh1
> roi2_std = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi3_min = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi4_sum = 426702. ( 426702. /s) mpx_cdte_22_eh1
> roi4_max = 168021. ( 168021. /s) mpx_cdte_22_eh1
> roi2_max = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi5_sum = 430063. ( 430063. /s) mpx_cdte_22_eh1
> roi5_min = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi2_sum = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> roi5_avg = 484.305 ( 484.305 /s) mpx_cdte_22_eh1
> roi2_min = 0.00000 ( 0.00000 /s) mpx_cdte_22_eh1
> mon = 10162.0 ( 10162.0 /s) p201_cc1
> sec = 1.00000 ( 1.00000 /s) p201_cc1
> det = 0.00000 ( 0.00000 /s) p201_cc1
> ion2cnt = 3550.00 ( 3550.00 /s) p201_cc1
> position = 0.00380468 ( 0.00380468 /s) delta_eh1_enc
> current[mA] = 188.360 ( 188.360 /s) machinfo
> lifetime = 71033.1 ( 71033.1 /s) machinfo
> ionch2 = -2.17205e-13 (-2.17205e-13/s) keithley
> ionch1 = 1.99692e-07 (1.99692e-07 /s) keithley
> transm = 4.81789e-08 (4.81789e-08 /s) autof_eh1
> curratt = 14.0000 ( 14.0000 /s) autof_eh1
> ratio = 8.71542e+08 (8.71542e+08 /s) autof_eh1
> roi4_sum_corr = 8.85661e+12 (8.85661e+12 /s) autof_eh1
> position = 20.6978 ( 20.6978 /s) gam_enc
> position = 0.0750000 ( 0.0750000 /s) rhod_enc
>
> Took 0:00:04.228133[s]
>
> Execution time: 4s 525ms 720μs
>
>
> EH1_EXP [583]: s.statistics
> Out [583]: func_name min mean max std total
> ------------------------------------ --------- --------- --------- --------- ---------
> roi_counters.wait_reading 15.736us 759.511ms 1.519s 759.495ms 1.519s
> timer.start 1.001s 1.001s 1.001s 1.001s
> scan.prepare.devices 985.146ms 985.146ms 985.146ms 985.146ms
> scan.events.device 1.431us 6.166ms 43.291ms 7.196ms 277.455ms
> scan.prepare.node 93.628ms 93.628ms 93.628ms 93.628ms
> musst_cc1_acc_master.prepare 46.657ms 46.657ms 46.657ms 46.657ms
> ct2_counters_controller.wait_reading 11.444us 21.666ms 43.320ms 21.654ms 43.332ms
> mpx_cdte_22_eh1.prepare 39.907ms 39.907ms 39.907ms 39.907ms
> gam_enc.wait_ready 320.196us 17.787ms 35.253ms 17.466ms 35.573ms
> scan.events.channel 38.147us 465.451us 14.877ms 2.173ms 20.945ms
> roi_counters.prepare 19.707ms 19.707ms 19.707ms 19.707ms
> p201_cc1.prepare 19.450ms 19.450ms 19.450ms 19.450ms
> ct2_counters_controller.prepare 18.592ms 18.592ms 18.592ms 18.592ms
> scan.init.display 11.737ms 11.737ms 11.737ms 11.737ms
> scan.init.writer 10.917ms 10.917ms 10.917ms 10.917ms
> musst_cc1_acc_master.wait_ready 3.084ms 4.317ms 5.551ms 1.233ms 8.635ms
> scan.fill_metadata 541.449us 1.739ms 2.936ms 1.197ms 3.478ms
> mpx_cdte_22_eh1.wait_ready 1.542ms 1.613ms 1.684ms 71.406us 3.226ms
> musst_cc1_acc_master.start 2.893ms 2.893ms 2.893ms 2.893ms
> musst_cc1_acc_master.stop 2.802ms 2.802ms 2.802ms 2.802ms
> timer.wait_slaves 41.008us 913.858us 2.654ms 1.231ms 2.742ms
> scan.init.saving 2.709ms 2.709ms 2.709ms 2.709ms
> p201_cc1.trigger 2.259ms 2.259ms 2.259ms 2.259ms
> ct2_counters_controller.start 2.124ms 2.124ms 2.124ms 2.124ms
> scan.init.scan_info 1.568ms 1.568ms 1.568ms 1.568ms
> scan.run.start_data_watcher 1.427ms 1.427ms 1.427ms 1.427ms
> scan.init.flint 1.300ms 1.300ms 1.300ms 1.300ms
> autof_eh1.wait_ready 296.116us 324.786us 353.575us 25.765us 1.299ms
> mpx_cdte_22_eh1.start 1.185ms 1.185ms 1.185ms 1.185ms
> keithley.wait_ready 239.849us 272.393us 306.129us 27.923us 1.090ms
> machinfo.wait_ready 219.107us 248.194us 278.950us 24.793us 992.775us
> p201_cc1.wait_ready 235.081us 455.141us 675.201us 220.060us 910.282us
> mpx_cdte_22_eh1.stop 838.041us 838.041us 838.041us 838.041us
> p201_cc1.stop 725.031us 725.031us 725.031us 725.031us
> delta_eh1_enc.wait_ready 284.433us 308.394us 332.355us 23.961us 616.789us
> ct2_counters_controller.wait_ready 226.498us 236.869us 247.240us 10.371us 473.738us
> roi_counters.stop 433.445us 433.445us 433.445us 433.445us
> scan.prepare.writer 399.351us 399.351us 399.351us 399.351us
> rhod_enc.wait_ready 163.078us 185.251us 207.424us 22.173us 370.502us
> autof_eh1.prepare 27.895us 163.078us 298.262us 135.183us 326.157us
> scan.init.chain 265.360us 265.360us 265.360us 265.360us
> autof_eh1.stop 7.868us 128.031us 248.194us 120.163us 256.062us
> p201_cc1.wait_slaves 41.246us 89.526us 137.806us 48.280us 179.052us
> scan.finalize_writer 133.276us 133.276us 133.276us 133.276us
> roi_counters.wait_ready 46.492us 55.313us 64.135us 8.821us 110.626us
> ct2_counters_controller.stop 98.944us 98.944us 98.944us 98.944us
> timer.wait_ready 41.008us 43.154us 45.300us 2.146us 86.308us
> mpx_cdte_22_eh1.wait_slaves 34.571us 42.796us 51.022us 8.225us 85.592us
> machinfo.start 28.372us 29.922us 31.471us 1.550us 59.843us
> roi_counters.start 56.505us 56.505us 56.505us 56.505us
> keithley.start 27.180us 27.299us 27.418us 119.209ps 54.598us
> autof_eh1.start 26.703us 26.822us 26.941us 119.209ps 53.644us
> rhod_enc.start 51.975us 51.975us 51.975us 51.975us
> rhod_enc.prepare 47.445us 47.445us 47.445us 47.445us
> machinfo.prepare 17.881us 23.127us 28.372us 5.245us 46.253us
> autof_eh1.wait_reading 10.490us 11.504us 11.921us 593.059ps 46.015us
> machinfo.wait_reading 9.775us 11.325us 12.398us 1.046us 45.300us
> keithley.wait_reading 9.775us 10.848us 12.398us 1.125us 43.392us
> p201_cc1.start 41.485us 41.485us 41.485us 41.485us
> timer.trigger_slaves 40.293us 40.293us 40.293us 40.293us
> musst_cc1_acc_master.wait_slaves 19.312us 19.908us 20.504us 596.046ps 39.816us
> keithley.prepare 15.020us 16.570us 18.120us 1.550us 33.140us
> mpx_cdte_22_eh1.wait_reading 11.206us 14.186us 17.166us 2.980us 28.372us
> delta_eh1_enc.start 26.941us 26.941us 26.941us 26.941us
> gam_enc.start 26.464us 26.464us 26.464us 26.464us
> rhod_enc.wait_reading 10.490us 12.398us 14.305us 1.907us 24.796us
> delta_eh1_enc.wait_reading 10.967us 11.921us 12.875us 953.674ps 23.842us
> gam_enc.wait_reading 10.729us 11.683us 12.636us 953.674ps 23.365us
> scan.preset._stop 22.173us 22.173us 22.173us 22.173us
> scan.preset._prepare 19.312us 19.312us 19.312us 19.312us
> mpx_cdte_22_eh1.trigger_slaves 15.020us 15.020us 15.020us 15.020us
> gam_enc.prepare 14.305us 14.305us 14.305us 14.305us
> delta_eh1_enc.prepare 14.305us 14.305us 14.305us 14.305us
> scan.preset.start 13.590us 13.590us 13.590us 13.590us
> p201_cc1.trigger_slaves 11.683us 11.683us 11.683us 11.683us
> timer.stop 8.583us 8.583us 8.583us 8.583us
> machinfo.trigger 3.576us 3.815us 4.053us 238.419ps 7.629us
> delta_eh1_enc.trigger 6.914us 6.914us 6.914us 6.914us
> keithley.trigger 3.338us 3.457us 3.576us 119.209ps 6.914us
> keithley.stop 2.861us 2.980us 3.099us 119.209ps 5.960us
> timer.prepare EH1_EXP [584]: measurement_eh1_exp
> Out [584]: MeasurementGroup: measurement_eh1_exp (state='default')
> - Existing states : 'default'
>
> Enabled Disabled
> ---------------------------------------- ---------------------------------------- autof_eh1:curratt acedet:apdcnt
> autof_eh1:ratio acedet:apdcurr
> autof_eh1:roi4_sum_corr acedet:apdhvmon
> autof_eh1:transm acedet:apdtemp
> delta_eh1_enc:position mpx_cdte_22_eh1:bpm:acq_time
> gam_enc:position mpx_cdte_22_eh1:bpm:fwhm_x
> keithley:ionch1 mpx_cdte_22_eh1:bpm:fwhm_y
> keithley:ionch2 mpx_cdte_22_eh1:bpm:intensity
> machinfo:current mpx_cdte_22_eh1:bpm:x
> machinfo:lifetime mpx_cdte_22_eh1:bpm:y
> mpx_cdte_22_eh1:image musst_eh1:fb_A
> mpx_cdte_22_eh1:roi_counters:roi1_avg musst_eh1:fb_Pi
> mpx_cdte_22_eh1:roi_counters:roi1_max musst_eh1:timer
> mpx_cdte_22_eh1:roi_counters:roi1_min mythen1:spectrum
> mpx_cdte_22_eh1:roi_counters:roi1_std mythen2:roi1
> mpx_cdte_22_eh1:roi_counters:roi1_sum mythen2:roi2
> mpx_cdte_22_eh1:roi_counters:roi2_avg mythen2:roi3
> mpx_cdte_22_eh1:roi_counters:roi2_max mythen2:seb_roi
> mpx_cdte_22_eh1:roi_counters:roi2_min mythen2:spectrum
> mpx_cdte_22_eh1:roi_counters:roi2_std p201_cc1:ct2_counters_controller:apdcnt
> mpx_cdte_22_eh1:roi_counters:roi2_sum pilatus300k:bpm:acq_time
> mpx_cdte_22_eh1:roi_counters:roi3_avg pilatus300k:bpm:fwhm_x
> mpx_cdte_22_eh1:roi_counters:roi3_max pilatus300k:bpm:fwhm_y
> mpx_cdte_22_eh1:roi_counters:roi3_min pilatus300k:bpm:intensity
> mpx_cdte_22_eh1:roi_counters:roi3_std pilatus300k:bpm:x
> mpx_cdte_22_eh1:roi_counters:roi3_sum pilatus300k:bpm:y
> mpx_cdte_22_eh1:roi_counters:roi4_avg pilatus300k:image
> mpx_cdte_22_eh1:roi_counters:roi4_max pilatus300k:roi_counters:roi1_avg
> mpx_cdte_22_eh1:roi_counters:roi4_min pilatus300k:roi_counters:roi1_max
> mpx_cdte_22_eh1:roi_counters:roi4_std pilatus300k:roi_counters:roi1_min
> mpx_cdte_22_eh1:roi_counters:roi4_sum pilatus300k:roi_counters:roi1_std
> mpx_cdte_22_eh1:roi_counters:roi5_avg pilatus300k:roi_counters:roi1_sum
> mpx_cdte_22_eh1:roi_counters:roi5_max pilatus300k:roi_counters:roi2_avg
> mpx_cdte_22_eh1:roi_counters:roi5_min pilatus300k:roi_counters:roi2_max
> mpx_cdte_22_eh1:roi_counters:roi5_std pilatus300k:roi_counters:roi2_min
> mpx_cdte_22_eh1:roi_counters:roi5_sum pilatus300k:roi_counters:roi2_std
> p201_cc1:ct2_counters_controller:det pilatus300k:roi_counters:roi2_sum
> p201_cc1:ct2_counters_controller:ion2cnt pilatus300k:roi_counters:roi_all_avg
> p201_cc1:ct2_counters_controller:mon pilatus300k:roi_counters:roi_all_max
> p201_cc1:ct2_counters_controller:sec pilatus300k:roi_counters:roi_all_min
> rhod_enc:position pilatus300k:roi_counters:roi_all_std
> pilatus300k:roi_counters:roi_all_sum
> wcid10f:tlangm
> wcid10f:twago
> wcid10f:vO2
> xbpm_atten:bpm:acq_time
> 5.722us 5.722us 5.722us 5.722us
> machinfo.stop 2.623us 2.742us 2.861us 119.209ps 5.484us
> rhod_enc.stop 4.768us 4.768us 4.768us 4.768us
> delta_eh1_enc.stop 4.292us 4.292us 4.292us 4.292us
> roi_counters.trigger 4.053us 4.053us 4.053us 4.053us
> ct2_counters_controller.trigger 3.815us 3.815us 3.815us 3.815us
> autof_eh1.trigger 3.576us 3.576us 3.576us 3.576us
> gam_enc.trigger 3.576us 3.576us 3.576us 3.576us
> rhod_enc.trigger 3.338us 3.338us 3.338us 3.338us
> gam_enc.stop 3.338us 3.338us 3.338us 3.338us
>
>
> EH1_EXP [584]: measurement_eh1_exp
> Out [584]: MeasurementGroup: measurement_eh1_exp (state='default')
> - Existing states : 'default'
>
> Enabled Disabled
> ---------------------------------------- ---------------------------------------- autof_eh1:curratt acedet:apdcnt
> autof_eh1:ratio acedet:apdcurr
> autof_eh1:roi4_sum_corr acedet:apdhvmon
> autof_eh1:transm acedet:apdtemp
> delta_eh1_enc:position mpx_cdte_22_eh1:bpm:acq_time
> gam_enc:position mpx_cdte_22_eh1:bpm:fwhm_x
> keithley:ionch1 mpx_cdte_22_eh1:bpm:fwhm_y
> keithley:ionch2 mpx_cdte_22_eh1:bpm:intensity
> machinfo:current mpx_cdte_22_eh1:bpm:x
> machinfo:lifetime mpx_cdte_22_eh1:bpm:y
> mpx_cdte_22_eh1:image musst_eh1:fb_A
> mpx_cdte_22_eh1:roi_counters:roi1_avg musst_eh1:fb_Pi
> mpx_cdte_22_eh1:roi_counters:roi1_max musst_eh1:timer
> mpx_cdte_22_eh1:roi_counters:roi1_min mythen1:spectrum
> mpx_cdte_22_eh1:roi_counters:roi1_std mythen2:roi1
> mpx_cdte_22_eh1:roi_counters:roi1_sum mythen2:roi2
> mpx_cdte_22_eh1:roi_counters:roi2_avg mythen2:roi3
> mpx_cdte_22_eh1:roi_counters:roi2_max mythen2:seb_roi
> mpx_cdte_22_eh1:roi_counters:roi2_min mythen2:spectrum
> mpx_cdte_22_eh1:roi_counters:roi2_std p201_cc1:ct2_counters_controller:apdcnt
> mpx_cdte_22_eh1:roi_counters:roi2_sum pilatus300k:bpm:acq_time
> mpx_cdte_22_eh1:roi_counters:roi3_avg pilatus300k:bpm:fwhm_x
> mpx_cdte_22_eh1:roi_counters:roi3_max pilatus300k:bpm:fwhm_y
> mpx_cdte_22_eh1:roi_counters:roi3_min pilatus300k:bpm:intensity
> mpx_cdte_22_eh1:roi_counters:roi3_std pilatus300k:bpm:x
> mpx_cdte_22_eh1:roi_counters:roi3_sum pilatus300k:bpm:y
> mpx_cdte_22_eh1:roi_counters:roi4_avg pilatus300k:image
> mpx_cdte_22_eh1:roi_counters:roi4_max pilatus300k:roi_counters:roi1_avg
> mpx_cdte_22_eh1:roi_counters:roi4_min pilatus300k:roi_counters:roi1_max
> mpx_cdte_22_eh1:roi_counters:roi4_std pilatus300k:roi_counters:roi1_min
> mpx_cdte_22_eh1:roi_counters:roi4_sum pilatus300k:roi_counters:roi1_std
> mpx_cdte_22_eh1:roi_counters:roi5_avg pilatus300k:roi_counters:roi1_sum
> mpx_cdte_22_eh1:roi_counters:roi5_max pilatus300k:roi_counters:roi2_avg
> mpx_cdte_22_eh1:roi_counters:roi5_min pilatus300k:roi_counters:roi2_max
> mpx_cdte_22_eh1:roi_counters:roi5_std pilatus300k:roi_counters:roi2_min
> mpx_cdte_22_eh1:roi_counters:roi5_sum pilatus300k:roi_counters:roi2_std
> p201_cc1:ct2_counters_controller:det pilatus300k:roi_counters:roi2_sum
> p201_cc1:ct2_counters_controller:ion2cnt pilatus300k:roi_counters:roi_all_avg
> p201_cc1:ct2_counters_controller:mon pilatus300k:roi_counters:roi_all_max
> p201_cc1:ct2_counters_controller:sec pilatus300k:roi_counters:roi_all_min
> rhod_enc:position pilatus300k:roi_counters:roi_all_std
> pilatus300k:roi_counters:roi_all_sum
> wcid10f:tlangm
> wcid10f:twago
> wcid10f:vO2
> xbpm_atten:bpm:acq_time
>
>
>
>
Edited by Maciej Jankowski