MORE-Web

Test Date: 1973-11-29 16:33
Analysis date: 2016-05-31 19:42

Logfile

hrData_40.log

[16:32:36.794]     INFO: *** Welcome to pxar ***

[16:32:36.794]     INFO: *** Today: 2016/05/27

[16:32:36.817]     INFO: *** Version: v1.9.0-805-geb36

[16:32:36.817]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C15.dat

[16:32:36.818]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0b.dat

[16:32:36.818]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/defaultMaskFile.dat

[16:32:36.818]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C15.dat

[16:32:36.966]     INFO:         clk: 4

[16:32:36.967]     INFO:         ctr: 4

[16:32:36.967]     INFO:         sda: 19

[16:32:36.967]     INFO:         tin: 9

[16:32:36.967]     INFO:         level: 15

[16:32:36.967]     INFO:         triggerdelay: 0

[16:32:36.967]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7

[16:32:36.967]     INFO: Log level: INFO

[16:32:36.978]    QUIET: Connection to board DTB_WRPRHI opened.

[16:32:36.982]     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    58

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WRPRHI

MAC address: 40D85511803A

Hostname:    pixelDTB058

Comment:     

------------------------------------------------------

[16:32:36.984]     INFO: RPC call hashes of host and DTB match: 398089610

[16:32:38.584]     INFO: DUT info: 

[16:32:38.584]     INFO: The DUT currently contains the following objects:

[16:32:38.584]     INFO:  2 TBM Cores tbm08c (2 ON)

[16:32:38.584]     INFO: 	TBM Core alpha (0): 7 registers set

[16:32:38.584]     INFO: 	TBM Core beta  (1): 7 registers set

[16:32:38.584]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[16:32:38.584]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.584]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.585]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.585]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:38.585]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[16:32:39.200]     INFO: PixTestCmd::init()

[16:32:39.204]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C15.dat

[16:32:39.510]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C15.dat

[16:33:57.208]     INFO: ######################################################################

[16:33:57.208]     INFO: PixTestXray::doTest()

[16:33:57.208]     INFO: ######################################################################

[16:33:57.208]     INFO:    ----------------------------------------------------------------------

[16:33:57.208]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 60

[16:33:57.208]     INFO:    ----------------------------------------------------------------------

[16:33:58.181]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds,  fEventsMax = 6000000

[16:34:10.388]     INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.

[16:34:41.227]     INFO: Resuming triggers.

[16:34:53.434]     INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.

[16:35:03.995]    ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found

[16:35:03.995]    ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[16:35:03.995]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a014 80b1 4e38 4e38 4e38 4e38 4e38 4e38 4e38 4e38 20c 2045 70d 204c 80a 2447 e000 c000 

[16:35:03.995]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00e 8000 4e38 4e38 4e38 4e38 4e38 80c 2a25 4e38 4e38 4e38 e000 c000 

[16:35:03.995]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a00f 8040 4e3a 6d9 2662 4e3a 4e3a 4e3a 4e3a 4e3a 4e3a 4e3a e000 c000 

[16:35:03.995]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a010 80b1 4e38 4e38 4e38 4e38 4e38 360 2262 41a 2445 818 2a40 4e38 552 226d 612 202b 4e38 4e38 e000 c000 

[16:35:03.995]    ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a011 80c0 4e38 4e38 4e38 4e38 4e39 418 2a44 4e39 4e39 4e39 54 280f 7ff 2000 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 

[16:35:03.995]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a012 8000 4e38 4e38 4e39 4e39 4e38 4e38 4e39 251 224c 4e39 e000 c000 

[16:35:03.995]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a013 8040 4e38 4e39 4e38 40a 2222 658 2680 4e39 4e38 4e39 4e38 212 2448 4e39 e000 c000 

[16:35:24.229]     INFO: Resuming triggers.

[16:35:36.435]     INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.

[16:36:07.173]     INFO: Resuming triggers.

[16:36:19.379]     INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.

[16:36:50.162]     INFO: Resuming triggers.

[16:37:01.416]     INFO: data taking finished, elapsed time: 60 seconds.

[16:37:29.969]     INFO: PixTest::       pg_setup set to default.

[16:37:30.009]     INFO: PixTestXray::doPhRun() done

[16:37:30.009]     INFO: PixTestXray::doTest() done 

[16:38:40.911]    QUIET: Connection to board 58 closed.

hrData_120.log

[16:39:10.676]     INFO: *** Welcome to pxar ***

[16:39:10.676]     INFO: *** Today: 2016/05/27

[16:39:10.698]     INFO: *** Version: v1.9.0-805-geb36

[16:39:10.698]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C15.dat

[16:39:10.699]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0b.dat

[16:39:10.699]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/defaultMaskFile.dat

[16:39:10.699]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C15.dat

[16:39:10.848]     INFO:         clk: 4

[16:39:10.848]     INFO:         ctr: 4

[16:39:10.848]     INFO:         sda: 19

[16:39:10.848]     INFO:         tin: 9

[16:39:10.848]     INFO:         level: 15

[16:39:10.848]     INFO:         triggerdelay: 0

[16:39:10.848]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7

[16:39:10.848]     INFO: Log level: INFO

[16:39:10.859]    QUIET: Connection to board DTB_WRPRHI opened.

[16:39:10.862]     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    58

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WRPRHI

MAC address: 40D85511803A

Hostname:    pixelDTB058

Comment:     

------------------------------------------------------

[16:39:10.865]     INFO: RPC call hashes of host and DTB match: 398089610

[16:39:12.464]     INFO: DUT info: 

[16:39:12.465]     INFO: The DUT currently contains the following objects:

[16:39:12.465]     INFO:  2 TBM Cores tbm08c (2 ON)

[16:39:12.465]     INFO: 	TBM Core alpha (0): 7 registers set

[16:39:12.465]     INFO: 	TBM Core beta  (1): 7 registers set

[16:39:12.465]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[16:39:12.465]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:12.465]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[16:39:13.058]     INFO: PixTestCmd::init()

[16:39:13.071]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C15.dat

[16:39:13.381]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C15.dat

[16:39:24.864]     INFO: ######################################################################

[16:39:24.864]     INFO: PixTestXray::doTest()

[16:39:24.864]     INFO: ######################################################################

[16:39:24.864]     INFO:    ----------------------------------------------------------------------

[16:39:24.864]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 60

[16:39:24.864]     INFO:    ----------------------------------------------------------------------

[16:39:25.837]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds,  fEventsMax = 6000000

[16:39:33.288]     INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.

[16:40:08.844]     INFO: Resuming triggers.

[16:40:16.291]     INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.

[16:40:52.878]     INFO: Resuming triggers.

[16:41:00.331]     INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.

[16:41:36.109]     INFO: Resuming triggers.

[16:41:43.563]     INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.

[16:42:19.315]     INFO: Resuming triggers.

[16:42:26.762]     INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.

[16:43:02.514]     INFO: Resuming triggers.

[16:43:09.965]     INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.

[16:43:16.462]    ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found

[16:43:16.462]    ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[16:43:16.462]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07f 8040 4e3a 4e3a 4e3a 4e3a 655 224e 4e3a 4e3a 319 226c 45c 2a4c 849 2064 4e3a 8d 2648 4e3a 15b 204a 300 266c 348 284f 850 2a4a e000 c000 

[16:43:16.462]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a079 80c0 4e38 4e38 4e38 550 2a4f 859 2448 4e38 4e38 2ca 2046 81d 2865 4e38 4e38 d 284f 610 2a48 4e38 53 202f e000 c000 

[16:43:16.462]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07a 8000 4e38 454 2a2e 4e38 54d 2884 4e38 4e38 481 2062 4e38 2cc 284f 4e38 4e38 90 2426 4e38 280 244f 2d4 2044 31b 286b 64a 244b e000 c000 

[16:43:16.462]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07b 8040 4e38 4e38 159 2646 4e38 600 264f 4e38 0 244f 3 2a6b 1a 2268 4e38 15a 2046 609 2240 4e38 4e38 4e38 c3 2845 e000 c000 

[16:43:16.462]    ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a07c 80b1 4e38 4e38 6da 2a4f 4e38 4e38 722 284e 4e38 4e38 749 2241 4e38 512 204e 4e38 31b 2a4b 7ff 2000 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 

[16:43:16.462]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07d 80c0 4e38 4e38 4e38 4e38 4e38 4e38 4e38 4e38 2a0 2a47 e000 c000 

[16:43:16.462]    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07e 8000 4e38 4e38 4e38 61a 2aa2 4e38 64d 2646 4e38 443 2440 4e38 0 2a4a 458 2a4f 4e38 4e38 e000 c000 

[16:43:45.070]     INFO: Resuming triggers.

[16:43:52.517]     INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.

[16:44:27.660]     INFO: Resuming triggers.

[16:44:35.113]     INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.

[16:45:10.187]     INFO: Resuming triggers.

[16:45:10.666]     INFO: data taking finished, elapsed time: 60 seconds.

[16:45:13.302]     INFO: PixTest::       pg_setup set to default.

[16:45:13.341]     INFO: PixTestXray::doPhRun() done

[16:45:13.342]     INFO: PixTestXray::doTest() done 

[16:45:30.470]    QUIET: Connection to board 58 closed.

hrEff_40.log

[16:25:34.562]     INFO: *** Welcome to pxar ***

[16:25:34.562]     INFO: *** Today: 2016/05/27

[16:25:34.584]     INFO: *** Version: v1.9.0-805-geb36

[16:25:34.585]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C15.dat

[16:25:34.586]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0b.dat

[16:25:34.586]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/defaultMaskFile.dat

[16:25:34.586]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C15.dat

[16:25:34.734]     INFO:         clk: 4

[16:25:34.734]     INFO:         ctr: 4

[16:25:34.734]     INFO:         sda: 19

[16:25:34.734]     INFO:         tin: 9

[16:25:34.734]     INFO:         level: 15

[16:25:34.734]     INFO:         triggerdelay: 0

[16:25:34.734]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7

[16:25:34.734]     INFO: Log level: INFO

[16:25:34.745]    QUIET: Connection to board DTB_WRPRHI opened.

[16:25:34.748]     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    58

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WRPRHI

MAC address: 40D85511803A

Hostname:    pixelDTB058

Comment:     

------------------------------------------------------

[16:25:34.751]     INFO: RPC call hashes of host and DTB match: 398089610

[16:25:36.350]     INFO: DUT info: 

[16:25:36.350]     INFO: The DUT currently contains the following objects:

[16:25:36.350]     INFO:  2 TBM Cores tbm08c (2 ON)

[16:25:36.350]     INFO: 	TBM Core alpha (0): 7 registers set

[16:25:36.351]     INFO: 	TBM Core beta  (1): 7 registers set

[16:25:36.351]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[16:25:36.351]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.351]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[16:25:36.942]     INFO: PixTestCmd::init()

[16:25:36.951]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C15.dat

[16:25:37.254]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C15.dat

[16:25:41.819]     INFO: ######################################################################

[16:25:41.819]     INFO: PixTestAlive::doTest()

[16:25:41.819]     INFO: ######################################################################

[16:25:41.823]     INFO:    ----------------------------------------------------------------------

[16:25:41.823]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:25:41.823]     INFO:    ----------------------------------------------------------------------

[16:25:42.200]     INFO: Expecting 41600 events.

[16:25:46.464]     INFO: 41600 events read in total (3546ms).

[16:25:46.612]     INFO: Test took 4787ms.

[16:25:46.622]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:25:46.894]     INFO: PixTestAlive::aliveTest() done

[16:25:46.894]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    1    0    0    0    0    0    0    1    0    0

[16:25:46.916]     INFO:    ----------------------------------------------------------------------

[16:25:46.916]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:25:46.916]     INFO:    ----------------------------------------------------------------------

[16:25:47.292]     INFO: Expecting 41600 events.

[16:25:50.345]     INFO: 41600 events read in total (2337ms).

[16:25:50.345]     INFO: Test took 3427ms.

[16:25:50.345]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:25:50.346]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[16:25:50.771]     INFO: PixTestAlive::maskTest() done

[16:25:50.771]     INFO: number of mask-defect pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[16:25:50.790]     INFO:    ----------------------------------------------------------------------

[16:25:50.790]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:25:50.790]     INFO:    ----------------------------------------------------------------------

[16:25:51.166]     INFO: Expecting 41600 events.

[16:25:55.426]     INFO: 41600 events read in total (3545ms).

[16:25:55.427]     INFO: Test took 4635ms.

[16:25:55.437]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:25:55.809]     INFO: PixTestAlive::addressDecodingTest() done

[16:25:55.809]     INFO: number of address-decoding pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[16:25:55.809]     INFO: PixTestAlive::doTest() done, duration: 13 seconds

[16:26:17.256]     INFO:    ----------------------------------------------------------------------

[16:26:17.256]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200

[16:26:17.256]     INFO:    ----------------------------------------------------------------------

[16:26:17.395]     INFO: Expecting 768 events.

[16:26:18.527]     INFO: 768 events read in total (417ms).

[16:26:18.527]     INFO: Test took 1265ms.

[16:26:19.331]     INFO: Expecting 41600 events.

[16:26:22.455]     INFO: 41600 events read in total (2598ms).

[16:26:22.458]     INFO: Test took 3925ms.

[16:26:22.536]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:26:23.240]     INFO: Expecting 41600 events.

[16:26:26.524]     INFO: 41600 events read in total (2757ms).

[16:26:26.528]     INFO: Test took 3954ms.

[16:26:26.608]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:26:27.313]     INFO: Expecting 41600 events.

[16:26:30.650]     INFO: 41600 events read in total (2811ms).

[16:26:30.654]     INFO: Test took 4007ms.

[16:26:30.734]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:26:31.438]     INFO: Expecting 41600 events.

[16:26:34.792]     INFO: 41600 events read in total (2827ms).

[16:26:34.796]     INFO: Test took 4023ms.

[16:26:34.877]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:26:35.583]     INFO: Expecting 41600 events.

[16:26:38.951]     INFO: 41600 events read in total (2841ms).

[16:26:38.955]     INFO: Test took 4037ms.

[16:26:39.034]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:26:39.739]     INFO: Expecting 41600 events.

[16:26:43.102]     INFO: 41600 events read in total (2836ms).

[16:26:43.105]     INFO: Test took 4031ms.

[16:26:43.185]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:26:43.890]     INFO: Expecting 41600 events.

[16:26:47.252]     INFO: 41600 events read in total (2836ms).

[16:26:47.256]     INFO: Test took 4031ms.

[16:26:47.335]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:26:48.040]     INFO: Expecting 41600 events.

[16:26:51.403]     INFO: 41600 events read in total (2836ms).

[16:26:51.407]     INFO: Test took 4032ms.

[16:26:51.485]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:26:52.190]     INFO: Expecting 41600 events.

[16:26:55.552]     INFO: 41600 events read in total (2835ms).

[16:26:55.556]     INFO: Test took 4031ms.

[16:26:55.635]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:26:56.340]     INFO: Expecting 41600 events.

[16:26:59.707]     INFO: 41600 events read in total (2840ms).

[16:26:59.710]     INFO: Test took 4035ms.

[16:26:59.791]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:00.497]     INFO: Expecting 41600 events.

[16:27:03.863]     INFO: 41600 events read in total (2839ms).

[16:27:03.867]     INFO: Test took 4035ms.

[16:27:03.946]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:04.652]     INFO: Expecting 41600 events.

[16:27:08.034]     INFO: 41600 events read in total (2856ms).

[16:27:08.037]     INFO: Test took 4050ms.

[16:27:08.118]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:08.825]     INFO: Expecting 41600 events.

[16:27:12.182]     INFO: 41600 events read in total (2830ms).

[16:27:12.186]     INFO: Test took 4026ms.

[16:27:12.267]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:12.974]     INFO: Expecting 41600 events.

[16:27:16.327]     INFO: 41600 events read in total (2826ms).

[16:27:16.330]     INFO: Test took 4021ms.

[16:27:16.411]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:17.117]     INFO: Expecting 41600 events.

[16:27:20.478]     INFO: 41600 events read in total (2834ms).

[16:27:20.482]     INFO: Test took 4030ms.

[16:27:20.562]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:21.268]     INFO: Expecting 41600 events.

[16:27:24.634]     INFO: 41600 events read in total (2839ms).

[16:27:24.637]     INFO: Test took 4033ms.

[16:27:24.717]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:25.424]     INFO: Expecting 41600 events.

[16:27:28.799]     INFO: 41600 events read in total (2848ms).

[16:27:28.802]     INFO: Test took 4043ms.

[16:27:28.882]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:29.587]     INFO: Expecting 41600 events.

[16:27:32.959]     INFO: 41600 events read in total (2845ms).

[16:27:32.963]     INFO: Test took 4041ms.

[16:27:33.043]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:33.750]     INFO: Expecting 41600 events.

[16:27:37.097]     INFO: 41600 events read in total (2820ms).

[16:27:37.101]     INFO: Test took 4016ms.

[16:27:37.183]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:37.894]     INFO: Expecting 41600 events.

[16:27:41.131]     INFO: 41600 events read in total (2710ms).

[16:27:41.134]     INFO: Test took 3909ms.

[16:27:41.213]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:27:55.584]     INFO:    ----------------------------------------------------------------------

[16:27:55.584]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[16:27:55.584]     INFO:    ----------------------------------------------------------------------

[16:27:56.266]     INFO: Expecting 208000 events.

[16:28:08.979]     INFO: 208000 events read in total (12186ms).

[16:28:08.992]     INFO: Test took 13391ms.

[16:28:09.370]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:28:09.738]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    1    0    0    0    0    0    0    1    0    0

[16:28:09.738]     INFO: number of red-efficiency pixels:    92   86  192  259  292  303  242  217  260  258  259  301  227  166   87   76

[16:28:09.738]     INFO: number of X-ray hits detected:    65879 60261 102053 155424 165253 161890 164956 149340 160180 171451 166820 157066 155071 103183 62205 60079

[16:28:09.738]     INFO: number of triggers sent (total per ROC):  208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000

[16:28:09.738]     INFO: number of Vcal hits detected:  207906 207914 207802 207729 207691 207685 207703 207773 207737 207734 207729 207681 207763 207779 207910 207920

[16:28:09.738]     INFO: Vcal hit fiducial efficiency (%):  100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0

[16:28:09.738]     INFO: Vcal hit overall efficiency (%):  100.0 100.0 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 100.0 100.0

[16:28:09.738]     INFO: X-ray hit rate [MHz/cm2]:  19.3 17.7 29.9 45.6 48.4 47.5 48.3 43.8 46.9 50.3 48.9 46.0 45.5 30.2 18.2 17.6

[16:28:09.738]     INFO: PixTestHighRate::doXPixelAlive() done

[16:28:09.785]     INFO: PixTest::       pg_setup set to default.

[16:28:23.496]     INFO:    ----------------------------------------------------------------------

[16:28:23.496]     INFO:    PixTestHighRate::runDaq() running for 2 seconds

[16:28:23.497]     INFO:    ----------------------------------------------------------------------

[16:28:24.636]     INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds

[16:28:26.639]     INFO: data taking finished, elapsed time: 2 seconds.

[16:28:28.064]     INFO: PixTest::       pg_setup set to default.

[16:28:28.204]     INFO: Pixels without X-ray hits (per ROC):  1110  1297   798   201   187   226   191   164   177   181   211   299   219   799  1297  1419 

[16:28:28.204]     INFO: PixTestHighRate::doRunDaq() done

[16:28:35.801]    QUIET: Connection to board 58 closed.

hrEff_80.log

[16:21:52.134]     INFO: *** Welcome to pxar ***

[16:21:52.134]     INFO: *** Today: 2016/05/27

[16:21:52.157]     INFO: *** Version: v1.9.0-805-geb36

[16:21:52.157]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C15.dat

[16:21:52.158]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0b.dat

[16:21:52.158]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/defaultMaskFile.dat

[16:21:52.158]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C15.dat

[16:21:52.309]     INFO:         clk: 4

[16:21:52.309]     INFO:         ctr: 4

[16:21:52.309]     INFO:         sda: 19

[16:21:52.309]     INFO:         tin: 9

[16:21:52.309]     INFO:         level: 15

[16:21:52.309]     INFO:         triggerdelay: 0

[16:21:52.309]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7

[16:21:52.309]     INFO: Log level: INFO

[16:21:52.320]    QUIET: Connection to board DTB_WRPRHI opened.

[16:21:52.323]     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    58

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WRPRHI

MAC address: 40D85511803A

Hostname:    pixelDTB058

Comment:     

------------------------------------------------------

[16:21:52.326]     INFO: RPC call hashes of host and DTB match: 398089610

[16:21:53.925]     INFO: DUT info: 

[16:21:53.925]     INFO: The DUT currently contains the following objects:

[16:21:53.925]     INFO:  2 TBM Cores tbm08c (2 ON)

[16:21:53.925]     INFO: 	TBM Core alpha (0): 7 registers set

[16:21:53.925]     INFO: 	TBM Core beta  (1): 7 registers set

[16:21:53.925]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[16:21:53.925]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.925]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:53.926]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[16:21:54.521]     INFO: PixTestCmd::init()

[16:21:54.528]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C15.dat

[16:21:54.828]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C15.dat

[16:22:04.835]     INFO: ######################################################################

[16:22:04.835]     INFO: PixTestAlive::doTest()

[16:22:04.835]     INFO: ######################################################################

[16:22:04.839]     INFO:    ----------------------------------------------------------------------

[16:22:04.839]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:22:04.839]     INFO:    ----------------------------------------------------------------------

[16:22:05.216]     INFO: Expecting 41600 events.

[16:22:09.474]     INFO: 41600 events read in total (3540ms).

[16:22:09.621]     INFO: Test took 4780ms.

[16:22:09.630]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:09.899]     INFO: PixTestAlive::aliveTest() done

[16:22:09.899]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    1    0    0    0    0    0    0    1    0    0

[16:22:09.921]     INFO:    ----------------------------------------------------------------------

[16:22:09.921]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:22:09.921]     INFO:    ----------------------------------------------------------------------

[16:22:10.297]     INFO: Expecting 41600 events.

[16:22:13.361]     INFO: 41600 events read in total (2348ms).

[16:22:13.361]     INFO: Test took 3438ms.

[16:22:13.362]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:13.363]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[16:22:13.784]     INFO: PixTestAlive::maskTest() done

[16:22:13.784]     INFO: number of mask-defect pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[16:22:13.805]     INFO:    ----------------------------------------------------------------------

[16:22:13.805]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:22:13.805]     INFO:    ----------------------------------------------------------------------

[16:22:14.181]     INFO: Expecting 41600 events.

[16:22:18.434]     INFO: 41600 events read in total (3538ms).

[16:22:18.436]     INFO: Test took 4629ms.

[16:22:18.447]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:18.818]     INFO: PixTestAlive::addressDecodingTest() done

[16:22:18.818]     INFO: number of address-decoding pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[16:22:18.818]     INFO: PixTestAlive::doTest() done, duration: 13 seconds

[16:22:24.528]     INFO:    ----------------------------------------------------------------------

[16:22:24.528]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200

[16:22:24.528]     INFO:    ----------------------------------------------------------------------

[16:22:24.667]     INFO: Expecting 768 events.

[16:22:25.800]     INFO: 768 events read in total (417ms).

[16:22:25.800]     INFO: Test took 1266ms.

[16:22:26.604]     INFO: Expecting 41600 events.

[16:22:30.046]     INFO: 41600 events read in total (2915ms).

[16:22:30.051]     INFO: Test took 4244ms.

[16:22:30.175]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:30.895]     INFO: Expecting 41600 events.

[16:22:34.469]     INFO: 41600 events read in total (3047ms).

[16:22:34.474]     INFO: Test took 4244ms.

[16:22:34.596]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:35.319]     INFO: Expecting 41600 events.

[16:22:38.943]     INFO: 41600 events read in total (3098ms).

[16:22:38.948]     INFO: Test took 4295ms.

[16:22:39.072]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:39.794]     INFO: Expecting 41600 events.

[16:22:43.415]     INFO: 41600 events read in total (3094ms).

[16:22:43.420]     INFO: Test took 4291ms.

[16:22:43.543]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:44.266]     INFO: Expecting 41600 events.

[16:22:47.901]     INFO: 41600 events read in total (3108ms).

[16:22:47.905]     INFO: Test took 4304ms.

[16:22:48.031]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:48.752]     INFO: Expecting 41600 events.

[16:22:52.400]     INFO: 41600 events read in total (3121ms).

[16:22:52.405]     INFO: Test took 4318ms.

[16:22:52.530]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:53.251]     INFO: Expecting 41600 events.

[16:22:56.902]     INFO: 41600 events read in total (3124ms).

[16:22:56.907]     INFO: Test took 4321ms.

[16:22:57.029]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:22:57.751]     INFO: Expecting 41600 events.

[16:23:01.393]     INFO: 41600 events read in total (3115ms).

[16:23:01.397]     INFO: Test took 4311ms.

[16:23:01.520]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:02.241]     INFO: Expecting 41600 events.

[16:23:05.890]     INFO: 41600 events read in total (3122ms).

[16:23:05.895]     INFO: Test took 4319ms.

[16:23:06.017]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:06.741]     INFO: Expecting 41600 events.

[16:23:10.391]     INFO: 41600 events read in total (3124ms).

[16:23:10.396]     INFO: Test took 4321ms.

[16:23:10.521]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:11.243]     INFO: Expecting 41600 events.

[16:23:14.909]     INFO: 41600 events read in total (3139ms).

[16:23:14.913]     INFO: Test took 4335ms.

[16:23:15.035]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:15.757]     INFO: Expecting 41600 events.

[16:23:19.411]     INFO: 41600 events read in total (3127ms).

[16:23:19.416]     INFO: Test took 4324ms.

[16:23:19.539]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:20.261]     INFO: Expecting 41600 events.

[16:23:23.904]     INFO: 41600 events read in total (3116ms).

[16:23:23.908]     INFO: Test took 4312ms.

[16:23:24.028]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:24.751]     INFO: Expecting 41600 events.

[16:23:28.411]     INFO: 41600 events read in total (3133ms).

[16:23:28.416]     INFO: Test took 4330ms.

[16:23:28.539]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:29.263]     INFO: Expecting 41600 events.

[16:23:32.913]     INFO: 41600 events read in total (3123ms).

[16:23:32.917]     INFO: Test took 4321ms.

[16:23:33.041]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:33.763]     INFO: Expecting 41600 events.

[16:23:37.415]     INFO: 41600 events read in total (3125ms).

[16:23:37.422]     INFO: Test took 4324ms.

[16:23:37.545]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:38.267]     INFO: Expecting 41600 events.

[16:23:41.891]     INFO: 41600 events read in total (3097ms).

[16:23:41.896]     INFO: Test took 4294ms.

[16:23:42.021]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:42.744]     INFO: Expecting 41600 events.

[16:23:46.384]     INFO: 41600 events read in total (3113ms).

[16:23:46.389]     INFO: Test took 4310ms.

[16:23:46.513]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:47.236]     INFO: Expecting 41600 events.

[16:23:50.851]     INFO: 41600 events read in total (3089ms).

[16:23:50.855]     INFO: Test took 4285ms.

[16:23:50.978]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:51.701]     INFO: Expecting 41600 events.

[16:23:55.206]     INFO: 41600 events read in total (2978ms).

[16:23:55.210]     INFO: Test took 4174ms.

[16:23:55.330]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:23:58.696]     INFO:    ----------------------------------------------------------------------

[16:23:58.696]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[16:23:58.696]     INFO:    ----------------------------------------------------------------------

[16:23:59.378]     INFO: Expecting 208000 events.

[16:24:14.065]     INFO: 208000 events read in total (14160ms).

[16:24:14.083]     INFO: Test took 15371ms.

[16:24:14.669]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:24:15.119]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    1    0    0    0    0    0    0    1    0    0

[16:24:15.119]     INFO: number of red-efficiency pixels:   138  147  298  447  563  492  538  460  470  607  539  549  512  293  159  120

[16:24:15.119]     INFO: number of X-ray hits detected:    105708 97913 164482 249952 266123 260423 266154 240318 257046 275193 267351 253291 250466 165640 100880 96908

[16:24:15.119]     INFO: number of triggers sent (total per ROC):  208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000

[16:24:15.119]     INFO: number of Vcal hits detected:  207855 207841 207671 207510 207389 207462 207363 207505 207490 207337 207405 207340 207443 207630 207833 207872

[16:24:15.119]     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.9 99.8 99.7 99.8 99.7 99.8 99.8 99.7 99.7 99.7 99.8 99.9 99.9 99.9

[16:24:15.119]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.8 99.7 99.7 99.7 99.7 99.8 99.9 99.9

[16:24:15.119]     INFO: X-ray hit rate [MHz/cm2]:  31.0 28.7 48.2 73.3 78.0 76.3 78.0 70.4 75.3 80.7 78.4 74.2 73.4 48.6 29.6 28.4

[16:24:15.119]     INFO: PixTestHighRate::doXPixelAlive() done

[16:24:15.165]     INFO: PixTest::       pg_setup set to default.

[16:25:17.205]    QUIET: Connection to board 58 closed.

hrEff_120.log

[16:17:00.283]     INFO: *** Welcome to pxar ***

[16:17:00.283]     INFO: *** Today: 2016/05/27

[16:17:00.306]     INFO: *** Version: v1.9.0-805-geb36

[16:17:00.306]     INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/dacParameters35_C15.dat

[16:17:00.307]     INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/tbmParameters_C0b.dat

[16:17:00.307]     INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/defaultMaskFile.dat

[16:17:00.307]     INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/trimParameters35_C15.dat

[16:17:00.455]     INFO:         clk: 4

[16:17:00.455]     INFO:         ctr: 4

[16:17:00.455]     INFO:         sda: 19

[16:17:00.455]     INFO:         tin: 9

[16:17:00.456]     INFO:         level: 15

[16:17:00.456]     INFO:         triggerdelay: 0

[16:17:00.456]    QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7

[16:17:00.456]     INFO: Log level: INFO

[16:17:00.467]    QUIET: Connection to board DTB_WRPRHI opened.

[16:17:00.470]     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    58

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WRPRHI

MAC address: 40D85511803A

Hostname:    pixelDTB058

Comment:     

------------------------------------------------------

[16:17:00.473]     INFO: RPC call hashes of host and DTB match: 398089610

[16:17:02.073]     INFO: DUT info: 

[16:17:02.073]     INFO: The DUT currently contains the following objects:

[16:17:02.073]     INFO:  2 TBM Cores tbm08c (2 ON)

[16:17:02.073]     INFO: 	TBM Core alpha (0): 7 registers set

[16:17:02.073]     INFO: 	TBM Core beta  (1): 7 registers set

[16:17:02.073]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[16:17:02.073]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.073]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[16:17:02.675]     INFO: PixTestCmd::init()

[16:17:02.680]     INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/phCalibrationFitErr35_C15.dat

[16:17:02.985]     INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/ml449/readbackCal_C15.dat

[16:17:06.707]     INFO: ######################################################################

[16:17:06.707]     INFO: PixTestAlive::doTest()

[16:17:06.707]     INFO: ######################################################################

[16:17:06.710]     INFO:    ----------------------------------------------------------------------

[16:17:06.710]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:17:06.710]     INFO:    ----------------------------------------------------------------------

[16:17:07.088]     INFO: Expecting 41600 events.

[16:17:11.346]     INFO: 41600 events read in total (3540ms).

[16:17:11.493]     INFO: Test took 4780ms.

[16:17:11.503]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:17:11.771]     INFO: PixTestAlive::aliveTest() done

[16:17:11.771]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    1    0    0    0    0    0    0    1    0    0

[16:17:11.793]     INFO:    ----------------------------------------------------------------------

[16:17:11.793]     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:17:11.793]     INFO:    ----------------------------------------------------------------------

[16:17:12.170]     INFO: Expecting 41600 events.

[16:17:15.236]     INFO: 41600 events read in total (2351ms).

[16:17:15.236]     INFO: Test took 3440ms.

[16:17:15.236]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:17:15.237]     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[16:17:15.659]     INFO: PixTestAlive::maskTest() done

[16:17:15.659]     INFO: number of mask-defect pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[16:17:15.678]     INFO:    ----------------------------------------------------------------------

[16:17:15.678]     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[16:17:15.678]     INFO:    ----------------------------------------------------------------------

[16:17:16.055]     INFO: Expecting 41600 events.

[16:17:20.307]     INFO: 41600 events read in total (3537ms).

[16:17:20.308]     INFO: Test took 4628ms.

[16:17:20.319]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:17:20.689]     INFO: PixTestAlive::addressDecodingTest() done

[16:17:20.689]     INFO: number of address-decoding pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[16:17:20.689]     INFO: PixTestAlive::doTest() done, duration: 13 seconds

[16:17:25.400]     INFO:    ----------------------------------------------------------------------

[16:17:25.400]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200

[16:17:25.400]     INFO:    ----------------------------------------------------------------------

[16:17:25.539]     INFO: Expecting 768 events.

[16:17:26.671]     INFO: 768 events read in total (417ms).

[16:17:26.672]     INFO: Test took 1266ms.

[16:17:27.475]     INFO: Expecting 41600 events.

[16:17:31.183]     INFO: 41600 events read in total (3181ms).

[16:17:31.188]     INFO: Test took 4511ms.

[16:17:31.354]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:17:32.087]     INFO: Expecting 41600 events.

[16:17:35.888]     INFO: 41600 events read in total (3274ms).

[16:17:35.894]     INFO: Test took 4472ms.

[16:17:36.052]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:17:36.794]     INFO: Expecting 41600 events.

[16:17:40.675]     INFO: 41600 events read in total (3354ms).

[16:17:40.681]     INFO: Test took 4558ms.

[16:17:40.837]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:17:41.574]     INFO: Expecting 41600 events.

[16:17:45.461]     INFO: 41600 events read in total (3361ms).

[16:17:45.466]     INFO: Test took 4557ms.

[16:17:45.621]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:17:46.358]     INFO: Expecting 41600 events.

[16:17:50.240]     INFO: 41600 events read in total (3356ms).

[16:17:50.246]     INFO: Test took 4553ms.

[16:17:50.402]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:17:51.138]     INFO: Expecting 41600 events.

[16:17:55.018]     INFO: 41600 events read in total (3353ms).

[16:17:55.024]     INFO: Test took 4550ms.

[16:17:55.182]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:17:55.917]     INFO: Expecting 41600 events.

[16:17:59.806]     INFO: 41600 events read in total (3363ms).

[16:17:59.812]     INFO: Test took 4560ms.

[16:17:59.969]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:00.703]     INFO: Expecting 41600 events.

[16:18:04.599]     INFO: 41600 events read in total (3369ms).

[16:18:04.605]     INFO: Test took 4567ms.

[16:18:04.764]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:05.501]     INFO: Expecting 41600 events.

[16:18:09.394]     INFO: 41600 events read in total (3367ms).

[16:18:09.399]     INFO: Test took 4563ms.

[16:18:09.559]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:10.293]     INFO: Expecting 41600 events.

[16:18:14.217]     INFO: 41600 events read in total (3397ms).

[16:18:14.223]     INFO: Test took 4595ms.

[16:18:14.382]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:15.119]     INFO: Expecting 41600 events.

[16:18:19.028]     INFO: 41600 events read in total (3382ms).

[16:18:19.034]     INFO: Test took 4580ms.

[16:18:19.195]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:19.930]     INFO: Expecting 41600 events.

[16:18:23.860]     INFO: 41600 events read in total (3403ms).

[16:18:23.865]     INFO: Test took 4600ms.

[16:18:24.024]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:24.758]     INFO: Expecting 41600 events.

[16:18:28.687]     INFO: 41600 events read in total (3402ms).

[16:18:28.693]     INFO: Test took 4600ms.

[16:18:28.852]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:29.587]     INFO: Expecting 41600 events.

[16:18:33.492]     INFO: 41600 events read in total (3378ms).

[16:18:33.498]     INFO: Test took 4576ms.

[16:18:33.658]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:34.395]     INFO: Expecting 41600 events.

[16:18:38.286]     INFO: 41600 events read in total (3365ms).

[16:18:38.292]     INFO: Test took 4563ms.

[16:18:38.451]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:39.187]     INFO: Expecting 41600 events.

[16:18:43.087]     INFO: 41600 events read in total (3373ms).

[16:18:43.093]     INFO: Test took 4571ms.

[16:18:43.252]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:43.986]     INFO: Expecting 41600 events.

[16:18:47.884]     INFO: 41600 events read in total (3371ms).

[16:18:47.890]     INFO: Test took 4569ms.

[16:18:48.049]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:48.783]     INFO: Expecting 41600 events.

[16:18:52.684]     INFO: 41600 events read in total (3374ms).

[16:18:52.690]     INFO: Test took 4572ms.

[16:18:52.852]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:53.588]     INFO: Expecting 41600 events.

[16:18:57.469]     INFO: 41600 events read in total (3354ms).

[16:18:57.475]     INFO: Test took 4553ms.

[16:18:57.633]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:18:58.368]     INFO: Expecting 41600 events.

[16:19:02.118]     INFO: 41600 events read in total (3223ms).

[16:19:02.124]     INFO: Test took 4421ms.

[16:19:02.279]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:20:56.096]     INFO:    ----------------------------------------------------------------------

[16:20:56.096]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[16:20:56.096]     INFO:    ----------------------------------------------------------------------

[16:20:56.777]     INFO: Expecting 208000 events.

[16:21:13.503]     INFO: 208000 events read in total (16200ms).

[16:21:13.526]     INFO: Test took 17414ms.

[16:21:14.313]     INFO: Fetched DAQ statistics. Counters are being reset now.

[16:21:14.837]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    1    0    0    0    0    0    0    1    0    0

[16:21:14.837]     INFO: number of red-efficiency pixels:   209  201  530  865 1055  937  915  791  919 1007  996  901  823  526  263  188

[16:21:14.837]     INFO: number of X-ray hits detected:    140841 130640 219935 333311 355598 347755 352719 320932 342795 368170 356634 338959 334272 221739 134917 129539

[16:21:14.837]     INFO: number of triggers sent (total per ROC):  208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000

[16:21:14.837]     INFO: number of Vcal hits detected:  207778 207784 207383 206988 206703 206915 206842 207071 206933 206774 206825 206908 207004 207356 207718 207802

[16:21:14.837]     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.7 99.6 99.4 99.5 99.5 99.6 99.5 99.5 99.5 99.5 99.6 99.7 99.9 99.9

[16:21:14.837]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.7 99.5 99.4 99.5 99.4 99.6 99.5 99.4 99.4 99.5 99.5 99.7 99.9 99.9

[16:21:14.837]     INFO: X-ray hit rate [MHz/cm2]:  41.3 38.3 64.5 97.7 104.2 101.9 103.4 94.1 100.5 107.9 104.5 99.4 98.0 65.0 39.5 38.0

[16:21:14.837]     INFO: PixTestHighRate::doXPixelAlive() done

[16:21:14.883]     INFO: PixTest::       pg_setup set to default.

[16:21:30.582]    QUIET: Connection to board 58 closed.

MoReWeb-v0.5.1-895-gd4c3d74 on branch master