[18:22:45.695] INFO: *** Welcome to pxar ***
[18:22:45.695] INFO: *** Today: 2015/08/10
[18:22:45.695] INFO: readRocDacs: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C15.dat
[18:22:45.696] INFO: readTbmDacs: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/tbmParameters_C0a.dat .. /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/tbmParameters_C0b.dat
[18:22:45.696] INFO: readMaskFile: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/defaultMaskFile.dat
[18:22:45.696] INFO: readTrimFile: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters_C15.dat
[18:22:45.794] INFO: clk: 4
[18:22:45.794] INFO: ctr: 4
[18:22:45.794] INFO: sda: 19
[18:22:45.794] INFO: tin: 9
[18:22:45.794] INFO: level: 15
[18:22:45.794] INFO: triggerdelay: 0
[18:22:45.794] QUIET: Instanciating API for pxar prod-01+36~geb5ac3f
[18:22:45.794] INFO: Log level: INFO
[18:22:45.805] INFO: Found DTB DTB_WZ4Y8V
[18:22:45.817] QUIET: Connection to board DTB_WZ4Y8V opened.
[18:22:45.820] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 176
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WZ4Y8V
MAC address: 40D8551180B0
Hostname: pixelDTB176
Comment:
------------------------------------------------------
[18:22:45.823] INFO: RPC call hashes of host and DTB match: 447413373
[18:22:47.560] INFO: DUT info:
[18:22:47.560] INFO: The DUT currently contains the following objects:
[18:22:47.560] INFO: 2 TBM Cores tbm08c (2 ON)
[18:22:47.560] INFO: TBM Core alpha (0): 7 registers set
[18:22:47.560] INFO: TBM Core beta (1): 7 registers set
[18:22:47.560] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[18:22:47.560] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.560] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.561] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.561] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.561] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.561] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:22:47.962] INFO: enter 'restricted' command line mode
[18:22:47.962] INFO: enter test to run
[18:22:47.962] INFO: test: pretest no parameter change
[18:22:47.962] INFO: running: pretest
[18:22:47.972] INFO: ######################################################################
[18:22:47.972] INFO: PixTestPretest::doTest()
[18:22:47.972] INFO: ######################################################################
[18:22:47.974] INFO: ----------------------------------------------------------------------
[18:22:47.974] INFO: PixTestPretest::programROC()
[18:22:47.974] INFO: ----------------------------------------------------------------------
[18:23:05.992] INFO: PixTestPretest::programROC() done: ROCs are all programmable
[18:23:05.992] INFO: IA differences per ROC: 18.5 20.9 18.5 18.5 18.5 19.3 20.1 19.3 17.7 20.1 18.5 16.1 19.3 20.1 17.7 19.3
[18:23:06.072] INFO: ----------------------------------------------------------------------
[18:23:06.072] INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[18:23:06.072] INFO: ----------------------------------------------------------------------
[18:23:25.702] INFO: PixTestPretest::setVana() done, Module Ia 381 mA = 23.8125 mA/ROC
[18:23:25.705] INFO: ----------------------------------------------------------------------
[18:23:25.705] INFO: PixTestPretest::findWorkingPixel()
[18:23:25.705] INFO: ----------------------------------------------------------------------
[18:23:33.154] INFO: Test took 7444ms.
[18:23:33.473] INFO: Found working pixel in all ROCs: col/row = 12/22
[18:23:33.519] INFO: ----------------------------------------------------------------------
[18:23:33.519] INFO: PixTestPretest::setVthrCompCalDel()
[18:23:33.519] INFO: ----------------------------------------------------------------------
[18:23:40.955] INFO: Test took 7431ms.
[18:23:41.299] INFO: PixTestPretest::setVthrCompCalDel() done
[18:23:41.299] INFO: CalDel: 118 113 120 112 126 143 141 143 125 114 103 123 137 131 106 136
[18:23:41.299] INFO: VthrComp: 51 51 51 53 51 51 51 51 51 51 51 51 51 51 51 51
[18:23:41.303] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C0.dat
[18:23:41.304] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C1.dat
[18:23:41.304] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C2.dat
[18:23:41.304] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C3.dat
[18:23:41.305] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C4.dat
[18:23:41.305] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C5.dat
[18:23:41.305] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C6.dat
[18:23:41.305] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C7.dat
[18:23:41.305] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C8.dat
[18:23:41.306] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C9.dat
[18:23:41.306] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C10.dat
[18:23:41.306] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C11.dat
[18:23:41.306] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C12.dat
[18:23:41.307] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C13.dat
[18:23:41.307] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C14.dat
[18:23:41.307] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters_C15.dat
[18:23:41.307] INFO: PixTestPretest::doTest() done, duration: 53 seconds
[18:23:41.399] INFO: enter test to run
[18:23:41.399] INFO: test: fulltest no parameter change
[18:23:41.399] INFO: running: fulltest
[18:23:41.399] INFO: ######################################################################
[18:23:41.399] INFO: PixTestFullTest::doTest()
[18:23:41.399] INFO: ######################################################################
[18:23:41.401] INFO: ######################################################################
[18:23:41.401] INFO: PixTestAlive::doTest()
[18:23:41.401] INFO: ######################################################################
[18:23:41.402] INFO: ----------------------------------------------------------------------
[18:23:41.402] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:23:41.402] INFO: ----------------------------------------------------------------------
[18:23:45.003] INFO: Test took 3598ms.
[18:23:45.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:23:45.279] INFO: PixTestAlive::aliveTest() done
[18:23:45.279] INFO: number of dead pixels (per ROC): 2 0 0 0 1 0 0 0 1 1 0 0 0 0 0 0
[18:23:45.281] INFO: ----------------------------------------------------------------------
[18:23:45.281] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:23:45.281] INFO: ----------------------------------------------------------------------
[18:23:48.134] INFO: Test took 2851ms.
[18:23:48.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:23:48.141] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[18:23:48.376] INFO: PixTestAlive::maskTest() done
[18:23:48.376] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:23:48.378] INFO: ----------------------------------------------------------------------
[18:23:48.378] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:23:48.378] INFO: ----------------------------------------------------------------------
[18:23:51.973] INFO: Test took 3593ms.
[18:23:52.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:23:52.244] INFO: PixTestAlive::addressDecodingTest() done
[18:23:52.244] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:23:52.244] INFO: PixTestAlive::doTest() done, duration: 10 seconds
[18:23:52.255] INFO: ######################################################################
[18:23:52.255] INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[18:23:52.255] INFO: ######################################################################
[18:23:52.258] INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[18:23:52.277] INFO: dacScan step from 0 .. 29
[18:23:52.277] INFO: dacScan split into 1 runs with ntrig = 5
[18:23:52.277] INFO: run 1 of 1
[18:24:11.856] INFO: Test took 19579ms.
[18:24:11.915] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:24:11.915] INFO: dacScan step from 30 .. 59
[18:24:11.915] INFO: dacScan split into 1 runs with ntrig = 5
[18:24:11.915] INFO: run 1 of 1
[18:24:31.825] INFO: Test took 19910ms.
[18:24:31.920] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:24:31.927] INFO: dacScan step from 60 .. 89
[18:24:31.927] INFO: dacScan split into 1 runs with ntrig = 5
[18:24:31.927] INFO: run 1 of 1
[18:24:57.419] INFO: Test took 25492ms.
[18:24:57.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:24:57.875] INFO: dacScan step from 90 .. 119
[18:24:57.875] INFO: dacScan split into 1 runs with ntrig = 5
[18:24:57.875] INFO: run 1 of 1
[18:25:25.266] INFO: Test took 27391ms.
[18:25:25.678] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:25:25.776] INFO: dacScan step from 120 .. 149
[18:25:25.776] INFO: dacScan split into 1 runs with ntrig = 5
[18:25:25.776] INFO: run 1 of 1
[18:25:49.252] INFO: Test took 23476ms.
[18:25:49.621] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:26:26.458] INFO: PixTestBBMap::doTest() done, duration: 154 seconds
[18:26:26.458] INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:26:26.458] INFO: separation cut (per ROC): 112 118 107 113 103 112 99 100 101 108 107 95 107 114 111 87
[18:26:26.557] INFO: ######################################################################
[18:26:26.557] INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = 1
[18:26:26.557] INFO: ######################################################################
[18:26:26.558] INFO: ----------------------------------------------------------------------
[18:26:26.558] INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = 1
[18:26:26.558] INFO: ----------------------------------------------------------------------
[18:26:26.558] INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/1) hits flags = 16 (plus default)
[18:26:26.573] INFO: dacScan split into 50 runs with ntrig = 1
[18:26:26.573] INFO: run 1 of 50
[18:27:16.125] INFO: Test took 49552ms.
[18:27:16.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:16.687] INFO: run 2 of 50
[18:28:06.260] INFO: Test took 49573ms.
[18:28:06.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:06.830] INFO: run 3 of 50
[18:28:56.263] INFO: Test took 49433ms.
[18:28:56.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:56.830] INFO: run 4 of 50
[18:29:46.282] INFO: Test took 49452ms.
[18:29:46.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:29:46.850] INFO: run 5 of 50
[18:30:36.231] INFO: Test took 49381ms.
[18:30:36.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:30:36.797] INFO: run 6 of 50
[18:31:26.189] INFO: Test took 49392ms.
[18:31:26.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:31:26.740] INFO: run 7 of 50
[18:32:16.114] INFO: Test took 49374ms.
[18:32:16.501] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:32:16.717] INFO: run 8 of 50
[18:33:06.111] INFO: Test took 49394ms.
[18:33:06.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:33:06.683] INFO: run 9 of 50
[18:33:56.089] INFO: Test took 49406ms.
[18:33:56.471] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:33:56.655] INFO: run 10 of 50
[18:34:46.052] INFO: Test took 49397ms.
[18:34:46.435] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:34:46.620] INFO: run 11 of 50
[18:35:35.959] INFO: Test took 49339ms.
[18:35:36.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:35:36.518] INFO: run 12 of 50
[18:36:25.853] INFO: Test took 49335ms.
[18:36:26.218] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:36:26.408] INFO: run 13 of 50
[18:37:15.858] INFO: Test took 49450ms.
[18:37:16.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:37:16.404] INFO: run 14 of 50
[18:38:05.695] INFO: Test took 49291ms.
[18:38:06.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:38:06.252] INFO: run 15 of 50
[18:38:55.573] INFO: Test took 49321ms.
[18:38:55.942] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:38:56.133] INFO: run 16 of 50
[18:39:45.379] INFO: Test took 49245ms.
[18:39:45.742] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:39:45.925] INFO: run 17 of 50
[18:40:35.149] INFO: Test took 49224ms.
[18:40:35.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:40:35.717] INFO: run 18 of 50
[18:41:24.890] INFO: Test took 49173ms.
[18:41:25.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:41:25.446] INFO: run 19 of 50
[18:42:14.665] INFO: Test took 49219ms.
[18:42:15.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:42:15.213] INFO: run 20 of 50
[18:43:04.522] INFO: Test took 49309ms.
[18:43:04.885] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:43:05.075] INFO: run 21 of 50
[18:43:54.397] INFO: Test took 49322ms.
[18:43:54.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:43:54.949] INFO: run 22 of 50
[18:44:44.337] INFO: Test took 49388ms.
[18:44:44.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:44:44.882] INFO: run 23 of 50
[18:45:34.267] INFO: Test took 49385ms.
[18:45:34.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:45:34.823] INFO: run 24 of 50
[18:46:24.250] INFO: Test took 49427ms.
[18:46:24.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:46:24.806] INFO: run 25 of 50
[18:47:14.177] INFO: Test took 49371ms.
[18:47:14.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:47:14.740] INFO: run 26 of 50
[18:48:04.118] INFO: Test took 49378ms.
[18:48:04.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:48:04.674] INFO: run 27 of 50
[18:48:53.001] INFO: Test took 49327ms.
[18:48:54.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:48:54.563] INFO: run 28 of 50
[18:49:43.892] INFO: Test took 49329ms.
[18:49:44.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:49:44.441] INFO: run 29 of 50
[18:50:33.753] INFO: Test took 49312ms.
[18:50:34.123] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:50:34.310] INFO: run 30 of 50
[18:51:23.631] INFO: Test took 49321ms.
[18:51:23.999] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:51:24.182] INFO: run 31 of 50
[18:52:13.423] INFO: Test took 49241ms.
[18:52:13.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:52:13.988] INFO: run 32 of 50
[18:53:03.415] INFO: Test took 49427ms.
[18:53:03.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:53:03.963] INFO: run 33 of 50
[18:53:53.199] INFO: Test took 49236ms.
[18:53:53.558] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:53:53.749] INFO: run 34 of 50
[18:54:43.113] INFO: Test took 49363ms.
[18:54:43.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:54:43.667] INFO: run 35 of 50
[18:55:32.865] INFO: Test took 49198ms.
[18:55:33.231] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:55:33.419] INFO: run 36 of 50
[18:56:22.545] INFO: Test took 49126ms.
[18:56:22.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:56:23.108] INFO: run 37 of 50
[18:57:12.255] INFO: Test took 49147ms.
[18:57:12.620] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:57:12.813] INFO: run 38 of 50
[18:58:01.897] INFO: Test took 49084ms.
[18:58:02.263] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:58:02.456] INFO: run 39 of 50
[18:58:51.679] INFO: Test took 49223ms.
[18:58:52.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:58:52.226] INFO: run 40 of 50
[18:59:41.428] INFO: Test took 49201ms.
[18:59:41.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:59:41.991] INFO: run 41 of 50
[19:00:31.262] INFO: Test took 49271ms.
[19:00:31.629] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:00:31.817] INFO: run 42 of 50
[19:01:21.211] INFO: Test took 49393ms.
[19:01:21.571] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:01:21.761] INFO: run 43 of 50
[19:02:11.127] INFO: Test took 49366ms.
[19:02:11.490] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:02:11.682] INFO: run 44 of 50
[19:03:00.950] INFO: Test took 49268ms.
[19:03:01.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:03:01.497] INFO: run 45 of 50
[19:03:50.779] INFO: Test took 49281ms.
[19:03:51.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:03:51.328] INFO: run 46 of 50
[19:04:40.613] INFO: Test took 49285ms.
[19:04:40.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:04:41.165] INFO: run 47 of 50
[19:05:30.369] INFO: Test took 49204ms.
[19:05:30.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:05:30.930] INFO: run 48 of 50
[19:06:20.180] INFO: Test took 49250ms.
[19:06:20.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:06:20.736] INFO: run 49 of 50
[19:07:10.025] INFO: Test took 49289ms.
[19:07:10.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:07:10.581] INFO: run 50 of 50
[19:07:59.665] INFO: Test took 49084ms.
[19:08:00.133] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:08:00.350] INFO: dumping ASCII scurve output file: SCurveData
[19:08:02.477] INFO: dumping ASCII scurve output file: SCurveData
[19:08:04.658] INFO: dumping ASCII scurve output file: SCurveData
[19:08:06.962] INFO: dumping ASCII scurve output file: SCurveData
[19:08:09.386] INFO: dumping ASCII scurve output file: SCurveData
[19:08:11.659] INFO: dumping ASCII scurve output file: SCurveData
[19:08:13.996] INFO: dumping ASCII scurve output file: SCurveData
[19:08:16.293] INFO: dumping ASCII scurve output file: SCurveData
[19:08:18.573] INFO: dumping ASCII scurve output file: SCurveData
[19:08:21.282] INFO: dumping ASCII scurve output file: SCurveData
[19:08:24.205] INFO: dumping ASCII scurve output file: SCurveData
[19:08:26.593] INFO: dumping ASCII scurve output file: SCurveData
[19:08:29.219] INFO: dumping ASCII scurve output file: SCurveData
[19:08:31.824] INFO: dumping ASCII scurve output file: SCurveData
[19:08:34.105] INFO: dumping ASCII scurve output file: SCurveData
[19:08:36.435] INFO: dumping ASCII scurve output file: SCurveData
[19:08:38.922] INFO: PixTestScurves::scurves() done
[19:08:38.922] INFO: Vcal mean: 94.95 100.13 87.56 104.41 91.46 104.87 83.59 89.79 94.63 84.06 91.05 91.74 95.47 98.43 93.62 70.37
[19:08:38.922] INFO: Vcal RMS: 5.98 6.24 5.21 5.78 6.16 6.05 4.79 5.99 5.80 5.00 5.28 5.71 6.47 5.88 5.39 4.80
[19:08:38.922] INFO: PixTestScurves::fullTest() done, duration: 2532 seconds
[19:08:39.025] INFO: ######################################################################
[19:08:39.025] INFO: PixTestTrim::doTest()
[19:08:39.025] INFO: ######################################################################
[19:08:39.027] INFO: ----------------------------------------------------------------------
[19:08:39.027] INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[19:08:39.027] INFO: ----------------------------------------------------------------------
[19:08:39.169] INFO: ---> VthrComp thr map (minimal VthrComp)
[19:08:39.170] INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[19:08:39.185] INFO: dacScan step from 0 .. 19
[19:08:39.185] INFO: dacScan split into 1 runs with ntrig = 5
[19:08:39.185] INFO: run 1 of 1
[19:08:53.187] INFO: Test took 14002ms.
[19:08:53.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:08:53.226] INFO: dacScan step from 20 .. 39
[19:08:53.226] INFO: dacScan split into 1 runs with ntrig = 5
[19:08:53.226] INFO: run 1 of 1
[19:09:07.215] INFO: Test took 13989ms.
[19:09:07.258] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:09:07.258] INFO: dacScan step from 40 .. 59
[19:09:07.258] INFO: dacScan split into 1 runs with ntrig = 5
[19:09:07.258] INFO: run 1 of 1
[19:09:21.207] INFO: Test took 13949ms.
[19:09:21.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:09:21.251] INFO: dacScan step from 60 .. 79
[19:09:21.251] INFO: dacScan split into 1 runs with ntrig = 5
[19:09:21.251] INFO: run 1 of 1
[19:09:35.363] INFO: Test took 14112ms.
[19:09:35.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:09:35.405] INFO: dacScan step from 80 .. 99
[19:09:35.405] INFO: dacScan split into 1 runs with ntrig = 5
[19:09:35.405] INFO: run 1 of 1
[19:09:49.985] INFO: Test took 14580ms.
[19:09:50.073] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:09:50.082] INFO: dacScan step from 100 .. 119
[19:09:50.082] INFO: dacScan split into 1 runs with ntrig = 5
[19:09:50.082] INFO: run 1 of 1
[19:10:07.193] INFO: Test took 17111ms.
[19:10:07.450] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:10:07.513] INFO: dacScan step from 120 .. 139
[19:10:07.513] INFO: dacScan split into 1 runs with ntrig = 5
[19:10:07.513] INFO: run 1 of 1
[19:10:24.718] INFO: Test took 17205ms.
[19:10:25.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:10:25.091] INFO: dacScan step from 140 .. 159
[19:10:25.091] INFO: dacScan split into 1 runs with ntrig = 5
[19:10:25.091] INFO: run 1 of 1
[19:10:39.789] INFO: Test took 14698ms.
[19:10:39.900] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:11:14.361] INFO: ROC 0 VthrComp = 96
[19:11:14.362] INFO: ROC 1 VthrComp = 101
[19:11:14.362] INFO: ROC 2 VthrComp = 92
[19:11:14.362] INFO: ROC 3 VthrComp = 103
[19:11:14.362] INFO: ROC 4 VthrComp = 94
[19:11:14.362] INFO: ROC 5 VthrComp = 101
[19:11:14.362] INFO: ROC 6 VthrComp = 86
[19:11:14.362] INFO: ROC 7 VthrComp = 93
[19:11:14.362] INFO: ROC 8 VthrComp = 94
[19:11:14.363] INFO: ROC 9 VthrComp = 89
[19:11:14.363] INFO: ROC 10 VthrComp = 95
[19:11:14.363] INFO: ROC 11 VthrComp = 88
[19:11:14.363] INFO: ROC 12 VthrComp = 93
[19:11:14.363] INFO: ROC 13 VthrComp = 100
[19:11:14.363] INFO: ROC 14 VthrComp = 94
[19:11:14.363] INFO: ROC 15 VthrComp = 75
[19:11:14.364] INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[19:11:14.364] INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[19:11:14.379] INFO: dacScan step from 0 .. 19
[19:11:14.379] INFO: dacScan split into 1 runs with ntrig = 5
[19:11:14.379] INFO: run 1 of 1
[19:11:28.506] INFO: Test took 14127ms.
[19:11:28.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:11:28.547] INFO: dacScan step from 20 .. 39
[19:11:28.547] INFO: dacScan split into 1 runs with ntrig = 5
[19:11:28.547] INFO: run 1 of 1
[19:11:42.791] INFO: Test took 14244ms.
[19:11:42.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:11:42.853] INFO: dacScan step from 40 .. 59
[19:11:42.853] INFO: dacScan split into 1 runs with ntrig = 5
[19:11:42.853] INFO: run 1 of 1
[19:12:00.404] INFO: Test took 17551ms.
[19:12:00.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:00.694] INFO: dacScan step from 60 .. 79
[19:12:00.694] INFO: dacScan split into 1 runs with ntrig = 5
[19:12:00.694] INFO: run 1 of 1
[19:12:19.993] INFO: Test took 19299ms.
[19:12:20.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:20.447] INFO: dacScan step from 80 .. 99
[19:12:20.447] INFO: dacScan split into 1 runs with ntrig = 5
[19:12:20.447] INFO: run 1 of 1
[19:12:39.694] INFO: Test took 19247ms.
[19:12:39.971] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:40.050] INFO: dacScan step from 100 .. 119
[19:12:40.050] INFO: dacScan split into 1 runs with ntrig = 5
[19:12:40.050] INFO: run 1 of 1
[19:12:59.104] INFO: Test took 19053ms.
[19:12:59.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:59.539] INFO: dacScan step from 120 .. 139
[19:12:59.539] INFO: dacScan split into 1 runs with ntrig = 5
[19:12:59.539] INFO: run 1 of 1
[19:13:18.683] INFO: Test took 19144ms.
[19:13:19.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:13:19.097] INFO: dacScan step from 140 .. 159
[19:13:19.097] INFO: dacScan split into 1 runs with ntrig = 5
[19:13:19.097] INFO: run 1 of 1
[19:13:38.347] INFO: Test took 19250ms.
[19:13:38.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:14:24.388] INFO: roc 0 with ID = 0 has maximal Vcal 58.8722 for pixel 51/1 mean/min/max = 45.3642/31.7225/59.0059
[19:14:24.389] INFO: roc 1 with ID = 1 has maximal Vcal 60.6206 for pixel 15/73 mean/min/max = 46.1949/31.7598/60.63
[19:14:24.390] INFO: roc 2 with ID = 2 has maximal Vcal 58.5927 for pixel 19/3 mean/min/max = 45.8286/32.9204/58.7367
[19:14:24.391] INFO: roc 3 with ID = 3 has maximal Vcal 62.0459 for pixel 2/76 mean/min/max = 47.3431/32.5118/62.1745
[19:14:24.392] INFO: roc 4 with ID = 4 has maximal Vcal 59.5178 for pixel 19/5 mean/min/max = 45.6611/31.7409/59.5813
[19:14:24.392] INFO: roc 5 with ID = 5 has maximal Vcal 63.2983 for pixel 12/3 mean/min/max = 47.6404/31.8991/63.3818
[19:14:24.393] INFO: roc 6 with ID = 6 has maximal Vcal 57.2817 for pixel 21/4 mean/min/max = 45.1731/32.6917/57.6544
[19:14:24.394] INFO: roc 7 with ID = 7 has maximal Vcal 57.8342 for pixel 8/79 mean/min/max = 45.3715/32.4057/58.3373
[19:14:24.394] INFO: roc 8 with ID = 8 has maximal Vcal 59.6854 for pixel 22/15 mean/min/max = 46.0079/32.33/59.6859
[19:14:24.395] INFO: roc 9 with ID = 9 has maximal Vcal 58.3757 for pixel 18/16 mean/min/max = 45.7371/32.8956/58.5786
[19:14:24.396] INFO: roc 10 with ID = 10 has maximal Vcal 58.2027 for pixel 9/1 mean/min/max = 44.9326/31.6254/58.2399
[19:14:24.396] INFO: roc 11 with ID = 11 has maximal Vcal 62.0562 for pixel 18/11 mean/min/max = 47.6831/33.1243/62.2419
[19:14:24.397] INFO: roc 12 with ID = 12 has maximal Vcal 63.2451 for pixel 42/1 mean/min/max = 47.3281/31.1377/63.5185
[19:14:24.397] INFO: roc 13 with ID = 13 has maximal Vcal 58.6413 for pixel 13/78 mean/min/max = 45.5387/31.8001/59.2773
[19:14:24.398] INFO: roc 14 with ID = 14 has maximal Vcal 58.5833 for pixel 18/8 mean/min/max = 45.423/32.2266/58.6195
[19:14:24.399] INFO: roc 15 with ID = 15 has maximal Vcal 56.5832 for pixel 26/12 mean/min/max = 45.4849/34.1712/56.7985
[19:14:24.399] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:15:45.007] INFO: Test took 80608ms.
[19:15:46.950] INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[19:15:46.971] INFO: dacScan step from 0 .. 19
[19:15:46.971] INFO: dacScan split into 2 runs with ntrig = 5
[19:15:46.971] INFO: run 1 of 2
[19:16:01.077] INFO: Test took 14106ms.
[19:16:01.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:16:01.140] INFO: run 2 of 2
[19:16:15.273] INFO: Test took 14133ms.
[19:16:15.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:16:15.336] INFO: dacScan step from 20 .. 39
[19:16:15.336] INFO: dacScan split into 2 runs with ntrig = 5
[19:16:15.336] INFO: run 1 of 2
[19:16:32.217] INFO: Test took 16881ms.
[19:16:32.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:16:32.540] INFO: run 2 of 2
[19:16:49.358] INFO: Test took 16818ms.
[19:16:49.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:16:49.627] INFO: dacScan step from 40 .. 59
[19:16:49.627] INFO: dacScan split into 2 runs with ntrig = 5
[19:16:49.627] INFO: run 1 of 2
[19:17:08.869] INFO: Test took 19242ms.
[19:17:09.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:17:09.262] INFO: run 2 of 2
[19:17:28.499] INFO: Test took 19236ms.
[19:17:28.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:17:28.860] INFO: dacScan step from 60 .. 79
[19:17:28.860] INFO: dacScan split into 2 runs with ntrig = 5
[19:17:28.860] INFO: run 1 of 2
[19:17:48.006] INFO: Test took 19146ms.
[19:17:48.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:17:48.378] INFO: run 2 of 2
[19:18:07.539] INFO: Test took 19160ms.
[19:18:07.810] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:18:07.880] INFO: dacScan step from 80 .. 99
[19:18:07.880] INFO: dacScan split into 2 runs with ntrig = 5
[19:18:07.880] INFO: run 1 of 2
[19:18:27.209] INFO: Test took 19329ms.
[19:18:27.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:18:27.570] INFO: run 2 of 2
[19:18:46.764] INFO: Test took 19194ms.
[19:18:47.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:18:47.125] INFO: dacScan step from 100 .. 119
[19:18:47.125] INFO: dacScan split into 2 runs with ntrig = 5
[19:18:47.125] INFO: run 1 of 2
[19:19:06.236] INFO: Test took 19111ms.
[19:19:06.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:19:06.571] INFO: run 2 of 2
[19:19:25.718] INFO: Test took 19146ms.
[19:19:26.015] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:19:26.093] INFO: dacScan step from 120 .. 139
[19:19:26.093] INFO: dacScan split into 2 runs with ntrig = 5
[19:19:26.093] INFO: run 1 of 2
[19:19:45.322] INFO: Test took 19228ms.
[19:19:45.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:19:45.673] INFO: run 2 of 2
[19:20:04.986] INFO: Test took 19313ms.
[19:20:05.296] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:20:05.371] INFO: dacScan step from 140 .. 159
[19:20:05.371] INFO: dacScan split into 2 runs with ntrig = 5
[19:20:05.371] INFO: run 1 of 2
[19:20:24.597] INFO: Test took 19226ms.
[19:20:24.904] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:20:24.978] INFO: run 2 of 2
[19:20:44.221] INFO: Test took 19243ms.
[19:20:44.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:20:44.587] INFO: dacScan step from 160 .. 179
[19:20:44.587] INFO: dacScan split into 2 runs with ntrig = 5
[19:20:44.587] INFO: run 1 of 2
[19:21:04.030] INFO: Test took 19443ms.
[19:21:04.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:21:04.390] INFO: run 2 of 2
[19:21:23.754] INFO: Test took 19364ms.
[19:21:24.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:21:24.166] INFO: dacScan step from 180 .. 199
[19:21:24.166] INFO: dacScan split into 2 runs with ntrig = 5
[19:21:24.167] INFO: run 1 of 2
[19:21:44.206] INFO: Test took 20039ms.
[19:21:44.542] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:21:44.633] INFO: run 2 of 2
[19:22:04.734] INFO: Test took 20101ms.
[19:22:05.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:22:46.885] INFO: ---> TrimStepCorr4 extremal thresholds: 0.005425 .. 255.000000
[19:22:47.044] INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[19:22:47.059] INFO: dacScan step from 0 .. 19
[19:22:47.059] INFO: dacScan split into 1 runs with ntrig = 4
[19:22:47.059] INFO: run 1 of 1
[19:22:59.886] INFO: Test took 12827ms.
[19:22:59.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:22:59.918] INFO: dacScan step from 20 .. 39
[19:22:59.918] INFO: dacScan split into 1 runs with ntrig = 4
[19:22:59.918] INFO: run 1 of 1
[19:23:13.803] INFO: Test took 13885ms.
[19:23:13.943] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:23:13.979] INFO: dacScan step from 40 .. 59
[19:23:13.979] INFO: dacScan split into 1 runs with ntrig = 4
[19:23:13.979] INFO: run 1 of 1
[19:23:30.827] INFO: Test took 16848ms.
[19:23:31.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:23:31.173] INFO: dacScan step from 60 .. 79
[19:23:31.173] INFO: dacScan split into 1 runs with ntrig = 4
[19:23:31.173] INFO: run 1 of 1
[19:23:48.123] INFO: Test took 16950ms.
[19:23:48.363] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:23:48.435] INFO: dacScan step from 80 .. 99
[19:23:48.435] INFO: dacScan split into 1 runs with ntrig = 4
[19:23:48.435] INFO: run 1 of 1
[19:24:05.413] INFO: Test took 16978ms.
[19:24:05.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:24:05.750] INFO: dacScan step from 100 .. 119
[19:24:05.750] INFO: dacScan split into 1 runs with ntrig = 4
[19:24:05.750] INFO: run 1 of 1
[19:24:22.774] INFO: Test took 17024ms.
[19:24:23.035] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:24:23.113] INFO: dacScan step from 120 .. 139
[19:24:23.113] INFO: dacScan split into 1 runs with ntrig = 4
[19:24:23.113] INFO: run 1 of 1
[19:24:39.999] INFO: Test took 16886ms.
[19:24:40.276] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:24:40.365] INFO: dacScan step from 140 .. 159
[19:24:40.365] INFO: dacScan split into 1 runs with ntrig = 4
[19:24:40.365] INFO: run 1 of 1
[19:24:57.288] INFO: Test took 16923ms.
[19:24:57.581] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:24:57.667] INFO: dacScan step from 160 .. 179
[19:24:57.667] INFO: dacScan split into 1 runs with ntrig = 4
[19:24:57.667] INFO: run 1 of 1
[19:25:14.779] INFO: Test took 17112ms.
[19:25:15.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:25:15.147] INFO: dacScan step from 180 .. 199
[19:25:15.147] INFO: dacScan split into 1 runs with ntrig = 4
[19:25:15.147] INFO: run 1 of 1
[19:25:32.979] INFO: Test took 17832ms.
[19:25:33.334] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:25:33.430] INFO: dacScan step from 200 .. 219
[19:25:33.430] INFO: dacScan split into 1 runs with ntrig = 4
[19:25:33.430] INFO: run 1 of 1
[19:25:50.451] INFO: Test took 17021ms.
[19:25:50.735] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:25:50.817] INFO: dacScan step from 220 .. 239
[19:25:50.817] INFO: dacScan split into 1 runs with ntrig = 4
[19:25:50.817] INFO: run 1 of 1
[19:26:07.868] INFO: Test took 17051ms.
[19:26:08.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:26:08.242] INFO: dacScan step from 240 .. 255
[19:26:08.242] INFO: dacScan split into 1 runs with ntrig = 4
[19:26:08.242] INFO: run 1 of 1
[19:26:22.565] INFO: Test took 14323ms.
[19:26:22.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:27:13.352] INFO: ---> TrimStepCorr2 extremal thresholds: 1.031729 .. 89.836842
[19:27:13.505] INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 1 .. 99 (20/-1) hits flags = 16 (plus default)
[19:27:13.519] INFO: dacScan step from 1 .. 20
[19:27:13.519] INFO: dacScan split into 1 runs with ntrig = 4
[19:27:13.519] INFO: run 1 of 1
[19:27:26.324] INFO: Test took 12805ms.
[19:27:26.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:27:26.356] INFO: dacScan step from 21 .. 40
[19:27:26.356] INFO: dacScan split into 1 runs with ntrig = 4
[19:27:26.356] INFO: run 1 of 1
[19:27:40.322] INFO: Test took 13966ms.
[19:27:40.449] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:27:40.485] INFO: dacScan step from 41 .. 60
[19:27:40.485] INFO: dacScan split into 1 runs with ntrig = 4
[19:27:40.485] INFO: run 1 of 1
[19:27:57.385] INFO: Test took 16900ms.
[19:27:57.670] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:27:57.757] INFO: dacScan step from 61 .. 80
[19:27:57.757] INFO: dacScan split into 1 runs with ntrig = 4
[19:27:57.757] INFO: run 1 of 1
[19:28:14.715] INFO: Test took 16958ms.
[19:28:14.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:28:15.078] INFO: dacScan step from 81 .. 99
[19:28:15.078] INFO: dacScan split into 1 runs with ntrig = 4
[19:28:15.078] INFO: run 1 of 1
[19:28:31.421] INFO: Test took 16343ms.
[19:28:31.668] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:29:07.210] INFO: ---> TrimStepCorr1a extremal thresholds: 1.617027 .. 89.836842
[19:29:07.399] INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 1 .. 99 (20/-1) hits flags = 16 (plus default)
[19:29:07.414] INFO: dacScan step from 1 .. 20
[19:29:07.414] INFO: dacScan split into 1 runs with ntrig = 4
[19:29:07.414] INFO: run 1 of 1
[19:29:20.313] INFO: Test took 12898ms.
[19:29:20.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:29:20.355] INFO: dacScan step from 21 .. 40
[19:29:20.355] INFO: dacScan split into 1 runs with ntrig = 4
[19:29:20.355] INFO: run 1 of 1
[19:29:34.278] INFO: Test took 13923ms.
[19:29:34.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:29:34.458] INFO: dacScan step from 41 .. 60
[19:29:34.458] INFO: dacScan split into 1 runs with ntrig = 4
[19:29:34.458] INFO: run 1 of 1
[19:29:51.763] INFO: Test took 17305ms.
[19:29:52.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:29:52.106] INFO: dacScan step from 61 .. 80
[19:29:52.107] INFO: dacScan split into 1 runs with ntrig = 4
[19:29:52.107] INFO: run 1 of 1
[19:30:09.175] INFO: Test took 17068ms.
[19:30:09.418] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:30:09.492] INFO: dacScan step from 81 .. 99
[19:30:09.492] INFO: dacScan split into 1 runs with ntrig = 4
[19:30:09.492] INFO: run 1 of 1
[19:30:25.834] INFO: Test took 16342ms.
[19:30:26.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:31:02.399] INFO: ---> TrimStepCorr1b extremal thresholds: 0.361688 .. 46.654958
[19:31:02.534] INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 0 .. 56 (20/-1) hits flags = 16 (plus default)
[19:31:02.549] INFO: dacScan step from 0 .. 19
[19:31:02.549] INFO: dacScan split into 1 runs with ntrig = 4
[19:31:02.549] INFO: run 1 of 1
[19:31:15.386] INFO: Test took 12837ms.
[19:31:15.421] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:31:15.421] INFO: dacScan step from 20 .. 39
[19:31:15.421] INFO: dacScan split into 1 runs with ntrig = 4
[19:31:15.421] INFO: run 1 of 1
[19:31:29.169] INFO: Test took 13748ms.
[19:31:29.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:31:29.299] INFO: dacScan step from 40 .. 56
[19:31:29.299] INFO: dacScan split into 1 runs with ntrig = 4
[19:31:29.299] INFO: run 1 of 1
[19:31:44.207] INFO: Test took 14908ms.
[19:31:44.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:32:09.578] INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[19:32:09.578] INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[19:32:09.596] INFO: dacScan step from 15 .. 34
[19:32:09.596] INFO: dacScan split into 2 runs with ntrig = 5
[19:32:09.596] INFO: run 1 of 2
[19:32:23.903] INFO: Test took 14307ms.
[19:32:23.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:32:23.996] INFO: run 2 of 2
[19:32:38.358] INFO: Test took 14362ms.
[19:32:38.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:32:38.446] INFO: dacScan step from 35 .. 54
[19:32:38.446] INFO: dacScan split into 2 runs with ntrig = 5
[19:32:38.446] INFO: run 1 of 2
[19:32:57.285] INFO: Test took 18839ms.
[19:32:57.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:32:57.697] INFO: run 2 of 2
[19:33:16.656] INFO: Test took 18958ms.
[19:33:16.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:33:17.042] INFO: dacScan step from 55 .. 55
[19:33:17.042] INFO: dacScan split into 2 runs with ntrig = 5
[19:33:17.042] INFO: run 1 of 2
[19:33:21.030] INFO: Test took 3988ms.
[19:33:21.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:33:21.059] INFO: run 2 of 2
[19:33:25.049] INFO: Test took 3989ms.
[19:33:25.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:33:45.336] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C0.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C1.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C2.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C3.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C4.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C5.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C6.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C7.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C8.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C9.dat
[19:33:45.337] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C10.dat
[19:33:45.338] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C11.dat
[19:33:45.338] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C12.dat
[19:33:45.338] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C13.dat
[19:33:45.338] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C14.dat
[19:33:45.338] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C15.dat
[19:33:45.338] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C0.dat
[19:33:45.350] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C1.dat
[19:33:45.362] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C2.dat
[19:33:45.374] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C3.dat
[19:33:45.385] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C4.dat
[19:33:45.397] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C5.dat
[19:33:45.408] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C6.dat
[19:33:45.420] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C7.dat
[19:33:45.432] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C8.dat
[19:33:45.443] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C9.dat
[19:33:45.454] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C10.dat
[19:33:45.466] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C11.dat
[19:33:45.477] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C12.dat
[19:33:45.489] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C13.dat
[19:33:45.500] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C14.dat
[19:33:45.512] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/trimParameters35_C15.dat
[19:33:45.523] INFO: PixTestTrim::trimTest() done
[19:33:45.523] INFO: vtrim: 92 105 100 123 108 125 92 94 119 110 185 117 113 105 113 104
[19:33:45.523] INFO: vthrcomp: 96 101 92 103 94 101 86 93 94 89 95 88 93 100 94 75
[19:33:45.524] INFO: vcal mean: 35.12 35.08 35.06 35.11 35.06 35.10 35.04 35.06 35.05 34.96 35.04 35.09 35.07 35.07 35.04 35.06
[19:33:45.524] INFO: vcal RMS: 1.36 1.07 1.07 1.29 1.26 1.27 1.08 1.09 1.28 1.30 1.70 1.17 1.14 1.16 1.10 0.97
[19:33:45.524] INFO: bits mean: 9.36 9.49 9.68 9.81 9.96 9.68 10.11 9.47 9.87 9.98 11.99 9.53 9.77 10.16 10.30 9.68
[19:33:45.524] INFO: bits RMS: 2.82 2.65 2.46 2.42 2.58 2.46 2.35 2.70 2.46 2.30 1.58 2.37 2.55 2.43 2.31 2.35
[19:33:45.534] INFO: ----------------------------------------------------------------------
[19:33:45.534] INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[19:33:45.534] INFO: ----------------------------------------------------------------------
[19:33:45.538] INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[19:33:45.554] INFO: dacScan step from 0 .. 19
[19:33:45.554] INFO: dacScan split into 2 runs with ntrig = 5
[19:33:45.554] INFO: run 1 of 2
[19:33:59.554] INFO: Test took 14000ms.
[19:33:59.595] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:33:59.595] INFO: run 2 of 2
[19:34:13.618] INFO: Test took 14023ms.
[19:34:13.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:34:13.658] INFO: dacScan step from 20 .. 39
[19:34:13.658] INFO: dacScan split into 2 runs with ntrig = 5
[19:34:13.658] INFO: run 1 of 2
[19:34:27.762] INFO: Test took 14103ms.
[19:34:27.805] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:34:27.805] INFO: run 2 of 2
[19:34:41.818] INFO: Test took 14013ms.
[19:34:41.869] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:34:41.869] INFO: dacScan step from 40 .. 59
[19:34:41.869] INFO: dacScan split into 2 runs with ntrig = 5
[19:34:41.869] INFO: run 1 of 2
[19:34:55.902] INFO: Test took 14033ms.
[19:34:55.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:34:55.942] INFO: run 2 of 2
[19:35:09.980] INFO: Test took 14038ms.
[19:35:10.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:35:10.017] INFO: dacScan step from 60 .. 79
[19:35:10.017] INFO: dacScan split into 2 runs with ntrig = 5
[19:35:10.017] INFO: run 1 of 2
[19:35:23.000] INFO: Test took 13982ms.
[19:35:24.042] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:35:24.042] INFO: run 2 of 2
[19:35:38.004] INFO: Test took 13962ms.
[19:35:38.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:35:38.043] INFO: dacScan step from 80 .. 99
[19:35:38.043] INFO: dacScan split into 2 runs with ntrig = 5
[19:35:38.043] INFO: run 1 of 2
[19:35:52.186] INFO: Test took 14142ms.
[19:35:52.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:35:52.246] INFO: run 2 of 2
[19:36:06.437] INFO: Test took 14191ms.
[19:36:06.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:36:06.497] INFO: dacScan step from 100 .. 119
[19:36:06.497] INFO: dacScan split into 2 runs with ntrig = 5
[19:36:06.497] INFO: run 1 of 2
[19:36:21.712] INFO: Test took 15215ms.
[19:36:21.861] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:36:21.884] INFO: run 2 of 2
[19:36:37.094] INFO: Test took 15210ms.
[19:36:37.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:36:37.284] INFO: dacScan step from 120 .. 139
[19:36:37.284] INFO: dacScan split into 2 runs with ntrig = 5
[19:36:37.284] INFO: run 1 of 2
[19:36:55.419] INFO: Test took 18135ms.
[19:36:55.727] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:36:55.801] INFO: run 2 of 2
[19:37:14.121] INFO: Test took 18320ms.
[19:37:14.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:37:14.480] INFO: dacScan step from 140 .. 159
[19:37:14.480] INFO: dacScan split into 2 runs with ntrig = 5
[19:37:14.480] INFO: run 1 of 2
[19:37:33.971] INFO: Test took 19491ms.
[19:37:34.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:37:34.343] INFO: run 2 of 2
[19:37:53.730] INFO: Test took 19387ms.
[19:37:54.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:37:54.080] INFO: dacScan step from 160 .. 179
[19:37:54.080] INFO: dacScan split into 2 runs with ntrig = 5
[19:37:54.080] INFO: run 1 of 2
[19:38:13.662] INFO: Test took 19582ms.
[19:38:13.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:38:14.041] INFO: run 2 of 2
[19:38:33.655] INFO: Test took 19614ms.
[19:38:33.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:38:33.990] INFO: dacScan step from 180 .. 199
[19:38:33.990] INFO: dacScan split into 2 runs with ntrig = 5
[19:38:33.990] INFO: run 1 of 2
[19:38:54.169] INFO: Test took 20178ms.
[19:38:54.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:38:54.539] INFO: run 2 of 2
[19:39:14.651] INFO: Test took 20111ms.
[19:39:14.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:55.406] INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 180 (20/5) hits flags = 16 (plus default)
[19:39:55.422] INFO: dacScan step from 0 .. 19
[19:39:55.422] INFO: dacScan split into 2 runs with ntrig = 5
[19:39:55.422] INFO: run 1 of 2
[19:40:09.504] INFO: Test took 14082ms.
[19:40:09.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:09.545] INFO: run 2 of 2
[19:40:23.583] INFO: Test took 14038ms.
[19:40:23.632] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:23.632] INFO: dacScan step from 20 .. 39
[19:40:23.632] INFO: dacScan split into 2 runs with ntrig = 5
[19:40:23.632] INFO: run 1 of 2
[19:40:37.608] INFO: Test took 13976ms.
[19:40:37.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:37.655] INFO: run 2 of 2
[19:40:51.542] INFO: Test took 13887ms.
[19:40:51.590] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:51.590] INFO: dacScan step from 40 .. 59
[19:40:51.590] INFO: dacScan split into 2 runs with ntrig = 5
[19:40:51.590] INFO: run 1 of 2
[19:41:05.634] INFO: Test took 14044ms.
[19:41:05.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:41:05.675] INFO: run 2 of 2
[19:41:19.684] INFO: Test took 14009ms.
[19:41:19.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:41:19.724] INFO: dacScan step from 60 .. 79
[19:41:19.724] INFO: dacScan split into 2 runs with ntrig = 5
[19:41:19.724] INFO: run 1 of 2
[19:41:33.744] INFO: Test took 14020ms.
[19:41:33.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:41:33.789] INFO: run 2 of 2
[19:41:47.872] INFO: Test took 14083ms.
[19:41:47.910] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:41:47.910] INFO: dacScan step from 80 .. 99
[19:41:47.911] INFO: dacScan split into 2 runs with ntrig = 5
[19:41:47.911] INFO: run 1 of 2
[19:42:02.365] INFO: Test took 14454ms.
[19:42:02.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:42:02.480] INFO: run 2 of 2
[19:42:16.902] INFO: Test took 14422ms.
[19:42:17.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:42:17.015] INFO: dacScan step from 100 .. 119
[19:42:17.015] INFO: dacScan split into 2 runs with ntrig = 5
[19:42:17.015] INFO: run 1 of 2
[19:42:33.814] INFO: Test took 16798ms.
[19:42:34.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:42:34.095] INFO: run 2 of 2
[19:42:50.893] INFO: Test took 16798ms.
[19:42:51.101] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:42:51.149] INFO: dacScan step from 120 .. 139
[19:42:51.149] INFO: dacScan split into 2 runs with ntrig = 5
[19:42:51.149] INFO: run 1 of 2
[19:43:10.444] INFO: Test took 19294ms.
[19:43:10.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:43:10.834] INFO: run 2 of 2
[19:43:29.849] INFO: Test took 19015ms.
[19:43:30.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:43:30.217] INFO: dacScan step from 140 .. 159
[19:43:30.217] INFO: dacScan split into 2 runs with ntrig = 5
[19:43:30.217] INFO: run 1 of 2
[19:43:49.677] INFO: Test took 19461ms.
[19:43:49.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:43:50.026] INFO: run 2 of 2
[19:44:09.510] INFO: Test took 19484ms.
[19:44:09.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:09.881] INFO: dacScan step from 160 .. 179
[19:44:09.881] INFO: dacScan split into 2 runs with ntrig = 5
[19:44:09.881] INFO: run 1 of 2
[19:44:29.483] INFO: Test took 19602ms.
[19:44:29.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:29.831] INFO: run 2 of 2
[19:44:49.450] INFO: Test took 19619ms.
[19:44:49.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:49.812] INFO: dacScan step from 180 .. 180
[19:44:49.812] INFO: dacScan split into 2 runs with ntrig = 5
[19:44:49.812] INFO: run 1 of 2
[19:44:53.790] INFO: Test took 3978ms.
[19:44:53.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:53.820] INFO: run 2 of 2
[19:44:57.767] INFO: Test took 3947ms.
[19:44:57.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:45:35.801] INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 185 (20/5) hits flags = 16 (plus default)
[19:45:35.819] INFO: dacScan step from 0 .. 19
[19:45:35.819] INFO: dacScan split into 2 runs with ntrig = 5
[19:45:35.819] INFO: run 1 of 2
[19:45:50.022] INFO: Test took 14203ms.
[19:45:50.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:45:50.059] INFO: run 2 of 2
[19:46:04.038] INFO: Test took 13979ms.
[19:46:04.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:46:04.075] INFO: dacScan step from 20 .. 39
[19:46:04.075] INFO: dacScan split into 2 runs with ntrig = 5
[19:46:04.075] INFO: run 1 of 2
[19:46:18.009] INFO: Test took 13934ms.
[19:46:18.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:46:18.051] INFO: run 2 of 2
[19:46:32.054] INFO: Test took 14003ms.
[19:46:32.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:46:32.111] INFO: dacScan step from 40 .. 59
[19:46:32.111] INFO: dacScan split into 2 runs with ntrig = 5
[19:46:32.111] INFO: run 1 of 2
[19:46:46.218] INFO: Test took 14106ms.
[19:46:46.261] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:46:46.261] INFO: run 2 of 2
[19:47:00.359] INFO: Test took 14098ms.
[19:47:00.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:47:00.406] INFO: dacScan step from 60 .. 79
[19:47:00.406] INFO: dacScan split into 2 runs with ntrig = 5
[19:47:00.406] INFO: run 1 of 2
[19:47:14.448] INFO: Test took 14042ms.
[19:47:14.494] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:47:14.494] INFO: run 2 of 2
[19:47:28.487] INFO: Test took 13992ms.
[19:47:28.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:47:28.537] INFO: dacScan step from 80 .. 99
[19:47:28.537] INFO: dacScan split into 2 runs with ntrig = 5
[19:47:28.537] INFO: run 1 of 2
[19:47:42.919] INFO: Test took 14382ms.
[19:47:43.020] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:47:43.030] INFO: run 2 of 2
[19:47:57.411] INFO: Test took 14381ms.
[19:47:57.510] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:47:57.516] INFO: dacScan step from 100 .. 119
[19:47:57.516] INFO: dacScan split into 2 runs with ntrig = 5
[19:47:57.516] INFO: run 1 of 2
[19:48:14.284] INFO: Test took 16768ms.
[19:48:14.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:48:14.559] INFO: run 2 of 2
[19:48:31.390] INFO: Test took 16831ms.
[19:48:31.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:48:31.644] INFO: dacScan step from 120 .. 139
[19:48:31.644] INFO: dacScan split into 2 runs with ntrig = 5
[19:48:31.644] INFO: run 1 of 2
[19:48:50.760] INFO: Test took 19116ms.
[19:48:51.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:48:51.111] INFO: run 2 of 2
[19:49:10.228] INFO: Test took 19117ms.
[19:49:10.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:49:10.587] INFO: dacScan step from 140 .. 159
[19:49:10.587] INFO: dacScan split into 2 runs with ntrig = 5
[19:49:10.587] INFO: run 1 of 2
[19:49:29.873] INFO: Test took 19286ms.
[19:49:30.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:49:30.256] INFO: run 2 of 2
[19:49:49.634] INFO: Test took 19378ms.
[19:49:49.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:49:50.008] INFO: dacScan step from 160 .. 179
[19:49:50.009] INFO: dacScan split into 2 runs with ntrig = 5
[19:49:50.009] INFO: run 1 of 2
[19:50:09.566] INFO: Test took 19557ms.
[19:50:09.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:50:09.903] INFO: run 2 of 2
[19:50:29.420] INFO: Test took 19517ms.
[19:50:29.713] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:50:29.788] INFO: dacScan step from 180 .. 185
[19:50:29.788] INFO: dacScan split into 2 runs with ntrig = 5
[19:50:29.788] INFO: run 1 of 2
[19:50:37.787] INFO: Test took 7999ms.
[19:50:37.870] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:50:37.899] INFO: run 2 of 2
[19:50:45.861] INFO: Test took 7961ms.
[19:50:45.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:51:24.383] INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 166 (20/5) hits flags = 16 (plus default)
[19:51:24.400] INFO: dacScan step from 0 .. 19
[19:51:24.400] INFO: dacScan split into 2 runs with ntrig = 5
[19:51:24.400] INFO: run 1 of 2
[19:51:38.547] INFO: Test took 14146ms.
[19:51:38.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:51:38.588] INFO: run 2 of 2
[19:51:52.554] INFO: Test took 13966ms.
[19:51:52.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:51:52.593] INFO: dacScan step from 20 .. 39
[19:51:52.593] INFO: dacScan split into 2 runs with ntrig = 5
[19:51:52.593] INFO: run 1 of 2
[19:52:06.671] INFO: Test took 14077ms.
[19:52:06.717] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:52:06.717] INFO: run 2 of 2
[19:52:20.787] INFO: Test took 14070ms.
[19:52:20.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:52:20.836] INFO: dacScan step from 40 .. 59
[19:52:20.836] INFO: dacScan split into 2 runs with ntrig = 5
[19:52:20.836] INFO: run 1 of 2
[19:52:34.844] INFO: Test took 14008ms.
[19:52:34.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:52:34.893] INFO: run 2 of 2
[19:52:48.991] INFO: Test took 14098ms.
[19:52:49.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:52:49.040] INFO: dacScan step from 60 .. 79
[19:52:49.040] INFO: dacScan split into 2 runs with ntrig = 5
[19:52:49.040] INFO: run 1 of 2
[19:53:03.092] INFO: Test took 14051ms.
[19:53:03.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:53:03.141] INFO: run 2 of 2
[19:53:17.316] INFO: Test took 14175ms.
[19:53:17.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:53:17.357] INFO: dacScan step from 80 .. 99
[19:53:17.357] INFO: dacScan split into 2 runs with ntrig = 5
[19:53:17.357] INFO: run 1 of 2
[19:53:31.915] INFO: Test took 14558ms.
[19:53:31.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:53:32.011] INFO: run 2 of 2
[19:53:46.522] INFO: Test took 14510ms.
[19:53:46.612] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:53:46.618] INFO: dacScan step from 100 .. 119
[19:53:46.618] INFO: dacScan split into 2 runs with ntrig = 5
[19:53:46.618] INFO: run 1 of 2
[19:54:03.429] INFO: Test took 16811ms.
[19:54:03.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:54:03.691] INFO: run 2 of 2
[19:54:20.502] INFO: Test took 16811ms.
[19:54:20.709] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:54:20.755] INFO: dacScan step from 120 .. 139
[19:54:20.755] INFO: dacScan split into 2 runs with ntrig = 5
[19:54:20.755] INFO: run 1 of 2
[19:54:39.848] INFO: Test took 19093ms.
[19:54:40.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:54:40.215] INFO: run 2 of 2
[19:54:59.333] INFO: Test took 19118ms.
[19:54:59.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:54:59.682] INFO: dacScan step from 140 .. 159
[19:54:59.682] INFO: dacScan split into 2 runs with ntrig = 5
[19:54:59.682] INFO: run 1 of 2
[19:55:19.080] INFO: Test took 19398ms.
[19:55:19.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:55:19.453] INFO: run 2 of 2
[19:55:38.721] INFO: Test took 19268ms.
[19:55:38.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:55:39.059] INFO: dacScan step from 160 .. 166
[19:55:39.059] INFO: dacScan split into 2 runs with ntrig = 5
[19:55:39.059] INFO: run 1 of 2
[19:55:47.837] INFO: Test took 8778ms.
[19:55:47.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:55:47.965] INFO: run 2 of 2
[19:55:56.791] INFO: Test took 8826ms.
[19:55:56.891] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:56:32.782] INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 164 (20/5) hits flags = 16 (plus default)
[19:56:32.798] INFO: dacScan step from 0 .. 19
[19:56:32.798] INFO: dacScan split into 2 runs with ntrig = 5
[19:56:32.798] INFO: run 1 of 2
[19:56:46.745] INFO: Test took 13947ms.
[19:56:46.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:56:46.791] INFO: run 2 of 2
[19:57:00.834] INFO: Test took 14043ms.
[19:57:00.872] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:57:00.872] INFO: dacScan step from 20 .. 39
[19:57:00.872] INFO: dacScan split into 2 runs with ntrig = 5
[19:57:00.872] INFO: run 1 of 2
[19:57:14.919] INFO: Test took 14047ms.
[19:57:14.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:57:14.963] INFO: run 2 of 2
[19:57:28.994] INFO: Test took 14031ms.
[19:57:29.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:57:29.040] INFO: dacScan step from 40 .. 59
[19:57:29.040] INFO: dacScan split into 2 runs with ntrig = 5
[19:57:29.040] INFO: run 1 of 2
[19:57:43.147] INFO: Test took 14106ms.
[19:57:43.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:57:43.191] INFO: run 2 of 2
[19:57:57.237] INFO: Test took 14045ms.
[19:57:57.288] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:57:57.288] INFO: dacScan step from 60 .. 79
[19:57:57.288] INFO: dacScan split into 2 runs with ntrig = 5
[19:57:57.288] INFO: run 1 of 2
[19:58:11.311] INFO: Test took 14023ms.
[19:58:11.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:58:11.355] INFO: run 2 of 2
[19:58:25.456] INFO: Test took 14101ms.
[19:58:25.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:58:25.503] INFO: dacScan step from 80 .. 99
[19:58:25.503] INFO: dacScan split into 2 runs with ntrig = 5
[19:58:25.503] INFO: run 1 of 2
[19:58:39.896] INFO: Test took 14393ms.
[19:58:39.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:58:40.010] INFO: run 2 of 2
[19:58:54.602] INFO: Test took 14592ms.
[19:58:54.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:58:54.706] INFO: dacScan step from 100 .. 119
[19:58:54.706] INFO: dacScan split into 2 runs with ntrig = 5
[19:58:54.706] INFO: run 1 of 2
[19:59:11.563] INFO: Test took 16857ms.
[19:59:11.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:59:11.813] INFO: run 2 of 2
[19:59:28.501] INFO: Test took 16687ms.
[19:59:28.733] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:59:28.777] INFO: dacScan step from 120 .. 139
[19:59:28.777] INFO: dacScan split into 2 runs with ntrig = 5
[19:59:28.777] INFO: run 1 of 2
[19:59:47.862] INFO: Test took 19085ms.
[19:59:48.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:59:48.224] INFO: run 2 of 2
[20:00:07.206] INFO: Test took 18982ms.
[20:00:07.494] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:00:07.563] INFO: dacScan step from 140 .. 159
[20:00:07.563] INFO: dacScan split into 2 runs with ntrig = 5
[20:00:07.563] INFO: run 1 of 2
[20:00:26.869] INFO: Test took 19305ms.
[20:00:27.155] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:00:27.229] INFO: run 2 of 2
[20:00:46.492] INFO: Test took 19263ms.
[20:00:46.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:00:46.856] INFO: dacScan step from 160 .. 164
[20:00:46.857] INFO: dacScan split into 2 runs with ntrig = 5
[20:00:46.857] INFO: run 1 of 2
[20:00:54.063] INFO: Test took 7206ms.
[20:00:54.136] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:00:54.161] INFO: run 2 of 2
[20:01:01.365] INFO: Test took 7204ms.
[20:01:01.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:01:35.547] INFO: PixTestTrim::trimBitTest() done
[20:01:35.549] INFO: PixTestTrim::doTest() done, duration: 3176 seconds
[20:01:36.553] INFO: ######################################################################
[20:01:36.553] INFO: PixTestPhOptimization::doTest() Ntrig = 10
[20:01:36.553] INFO: ######################################################################
[20:01:40.186] INFO: Test took 3632ms.
[20:01:40.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:01:44.129] INFO: Test took 3706ms.
[20:01:44.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:01:47.970] INFO: Test took 3674ms.
[20:01:48.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:01:48.129] INFO: The DUT currently contains the following objects:
[20:01:48.130] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:48.130] INFO: TBM Core alpha (0): 7 registers set
[20:01:48.130] INFO: TBM Core beta (1): 7 registers set
[20:01:48.130] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:48.130] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:48.130] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.228] INFO: Test took 1098ms.
[20:01:49.231] INFO: The DUT currently contains the following objects:
[20:01:49.231] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:49.231] INFO: TBM Core alpha (0): 7 registers set
[20:01:49.231] INFO: TBM Core beta (1): 7 registers set
[20:01:49.231] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:49.231] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:49.231] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.329] INFO: Test took 1098ms.
[20:01:50.331] INFO: The DUT currently contains the following objects:
[20:01:50.331] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:50.331] INFO: TBM Core alpha (0): 7 registers set
[20:01:50.331] INFO: TBM Core beta (1): 7 registers set
[20:01:50.331] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:50.331] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.331] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.332] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.332] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:50.332] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.429] INFO: Test took 1097ms.
[20:01:51.431] INFO: The DUT currently contains the following objects:
[20:01:51.431] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:51.431] INFO: TBM Core alpha (0): 7 registers set
[20:01:51.431] INFO: TBM Core beta (1): 7 registers set
[20:01:51.431] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:51.431] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:51.431] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.527] INFO: Test took 1096ms.
[20:01:52.529] INFO: The DUT currently contains the following objects:
[20:01:52.529] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:52.529] INFO: TBM Core alpha (0): 7 registers set
[20:01:52.529] INFO: TBM Core beta (1): 7 registers set
[20:01:52.529] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:52.529] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.529] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:52.530] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.628] INFO: Test took 1098ms.
[20:01:53.630] INFO: The DUT currently contains the following objects:
[20:01:53.630] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:53.630] INFO: TBM Core alpha (0): 7 registers set
[20:01:53.630] INFO: TBM Core beta (1): 7 registers set
[20:01:53.630] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:53.630] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.630] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.630] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.630] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.630] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.630] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.630] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.630] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.631] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.631] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.631] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.631] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.631] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.631] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.631] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:53.631] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.727] INFO: Test took 1096ms.
[20:01:54.729] INFO: The DUT currently contains the following objects:
[20:01:54.729] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:54.729] INFO: TBM Core alpha (0): 7 registers set
[20:01:54.729] INFO: TBM Core beta (1): 7 registers set
[20:01:54.729] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:54.729] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:54.729] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.827] INFO: Test took 1098ms.
[20:01:55.830] INFO: The DUT currently contains the following objects:
[20:01:55.830] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:55.830] INFO: TBM Core alpha (0): 7 registers set
[20:01:55.830] INFO: TBM Core beta (1): 7 registers set
[20:01:55.830] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:55.830] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:55.830] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.927] INFO: Test took 1097ms.
[20:01:56.929] INFO: The DUT currently contains the following objects:
[20:01:56.929] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:56.929] INFO: TBM Core alpha (0): 7 registers set
[20:01:56.929] INFO: TBM Core beta (1): 7 registers set
[20:01:56.929] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:56.929] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:56.929] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.031] INFO: Test took 1102ms.
[20:01:58.033] INFO: The DUT currently contains the following objects:
[20:01:58.033] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:58.033] INFO: TBM Core alpha (0): 7 registers set
[20:01:58.033] INFO: TBM Core beta (1): 7 registers set
[20:01:58.033] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:58.033] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:58.034] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.130] INFO: Test took 1096ms.
[20:01:59.131] INFO: The DUT currently contains the following objects:
[20:01:59.131] INFO: 2 TBM Cores tbm08c (2 ON)
[20:01:59.131] INFO: TBM Core alpha (0): 7 registers set
[20:01:59.131] INFO: TBM Core beta (1): 7 registers set
[20:01:59.131] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:01:59.131] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:01:59.131] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.231] INFO: Test took 1100ms.
[20:02:00.233] INFO: The DUT currently contains the following objects:
[20:02:00.233] INFO: 2 TBM Cores tbm08c (2 ON)
[20:02:00.233] INFO: TBM Core alpha (0): 7 registers set
[20:02:00.233] INFO: TBM Core beta (1): 7 registers set
[20:02:00.233] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:02:00.233] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.233] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.233] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.233] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.233] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:00.234] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.330] INFO: Test took 1096ms.
[20:02:01.331] INFO: The DUT currently contains the following objects:
[20:02:01.331] INFO: 2 TBM Cores tbm08c (2 ON)
[20:02:01.331] INFO: TBM Core alpha (0): 7 registers set
[20:02:01.331] INFO: TBM Core beta (1): 7 registers set
[20:02:01.331] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:02:01.331] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.331] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:01.332] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.428] INFO: Test took 1096ms.
[20:02:02.430] INFO: The DUT currently contains the following objects:
[20:02:02.430] INFO: 2 TBM Cores tbm08c (2 ON)
[20:02:02.430] INFO: TBM Core alpha (0): 7 registers set
[20:02:02.430] INFO: TBM Core beta (1): 7 registers set
[20:02:02.430] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:02:02.430] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.430] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.430] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.430] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.430] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.430] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.430] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.430] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.431] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.431] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.431] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.431] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.431] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.431] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.431] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:02.431] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.527] INFO: Test took 1096ms.
[20:02:03.529] INFO: The DUT currently contains the following objects:
[20:02:03.529] INFO: 2 TBM Cores tbm08c (2 ON)
[20:02:03.529] INFO: TBM Core alpha (0): 7 registers set
[20:02:03.529] INFO: TBM Core beta (1): 7 registers set
[20:02:03.529] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:02:03.529] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:03.529] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.627] INFO: Test took 1098ms.
[20:02:04.629] INFO: The DUT currently contains the following objects:
[20:02:04.629] INFO: 2 TBM Cores tbm08c (2 ON)
[20:02:04.629] INFO: TBM Core alpha (0): 7 registers set
[20:02:04.629] INFO: TBM Core beta (1): 7 registers set
[20:02:04.629] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[20:02:04.629] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.629] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.629] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.629] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.629] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.629] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.629] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.629] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.629] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.630] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.630] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.630] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.630] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.630] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.630] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:04.630] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[20:02:05.728] INFO: Test took 1098ms.
[20:02:05.734] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:05:14.653] INFO: Test took 188919ms.
[20:05:17.597] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:27.126] INFO: Test took 189529ms.
[20:08:30.648] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.658] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.669] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.679] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.690] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.699] INFO: safety margin for low PH: adding 1, margin is now 21
[20:08:30.710] INFO: safety margin for low PH: adding 2, margin is now 22
[20:08:30.720] INFO: safety margin for low PH: adding 3, margin is now 23
[20:08:30.730] INFO: safety margin for low PH: adding 4, margin is now 24
[20:08:30.741] INFO: safety margin for low PH: adding 5, margin is now 25
[20:08:30.750] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.761] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.771] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.782] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.791] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.801] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.811] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.822] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.833] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.843] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:30.853] INFO: safety margin for low PH: adding 1, margin is now 21
[20:08:30.862] INFO: safety margin for low PH: adding 2, margin is now 22
[20:08:30.873] INFO: safety margin for low PH: adding 3, margin is now 23
[20:08:30.884] INFO: safety margin for low PH: adding 4, margin is now 24
[20:08:30.894] INFO: safety margin for low PH: adding 5, margin is now 25
[20:08:30.904] INFO: safety margin for low PH: adding 6, margin is now 26
[20:08:30.914] INFO: safety margin for low PH: adding 7, margin is now 27
[20:08:30.923] INFO: safety margin for low PH: adding 8, margin is now 28
[20:08:30.933] INFO: safety margin for low PH: adding 9, margin is now 29
[20:08:30.943] INFO: safety margin for low PH: adding 10, margin is now 30
[20:08:30.953] INFO: safety margin for low PH: adding 11, margin is now 31
[20:08:30.963] INFO: safety margin for low PH: adding 0, margin is now 20
[20:08:31.013] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C0.dat
[20:08:31.013] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C1.dat
[20:08:31.013] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C2.dat
[20:08:31.014] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C3.dat
[20:08:31.014] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C4.dat
[20:08:31.014] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C5.dat
[20:08:31.014] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C6.dat
[20:08:31.014] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C7.dat
[20:08:31.014] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C8.dat
[20:08:31.015] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C9.dat
[20:08:31.015] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C10.dat
[20:08:31.015] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C11.dat
[20:08:31.015] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C12.dat
[20:08:31.016] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C13.dat
[20:08:31.016] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C14.dat
[20:08:31.016] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/dacParameters35_C15.dat
[20:08:34.804] INFO: Test took 3783ms.
[20:08:38.817] INFO: Test took 3646ms.
[20:08:42.813] INFO: Test took 3637ms.
[20:08:43.193] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:44.105] INFO: Test took 912ms.
[20:08:44.109] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:45.207] INFO: Test took 1098ms.
[20:08:45.213] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:46.308] INFO: Test took 1096ms.
[20:08:46.312] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:47.410] INFO: Test took 1098ms.
[20:08:47.413] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:48.511] INFO: Test took 1098ms.
[20:08:48.515] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:49.611] INFO: Test took 1096ms.
[20:08:49.616] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:50.715] INFO: Test took 1099ms.
[20:08:50.720] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:51.819] INFO: Test took 1099ms.
[20:08:51.825] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:52.922] INFO: Test took 1098ms.
[20:08:52.926] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:54.022] INFO: Test took 1096ms.
[20:08:54.028] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:55.126] INFO: Test took 1099ms.
[20:08:55.131] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:56.228] INFO: Test took 1097ms.
[20:08:56.233] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:57.326] INFO: Test took 1093ms.
[20:08:57.330] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:58.427] INFO: Test took 1097ms.
[20:08:58.433] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:08:59.531] INFO: Test took 1098ms.
[20:08:59.538] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:00.635] INFO: Test took 1098ms.
[20:09:00.639] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:01.739] INFO: Test took 1100ms.
[20:09:01.745] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:02.838] INFO: Test took 1094ms.
[20:09:02.842] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:03.941] INFO: Test took 1099ms.
[20:09:03.947] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:05.046] INFO: Test took 1100ms.
[20:09:05.052] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:06.150] INFO: Test took 1099ms.
[20:09:06.154] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:07.255] INFO: Test took 1101ms.
[20:09:07.258] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:08.357] INFO: Test took 1099ms.
[20:09:08.363] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:09.462] INFO: Test took 1100ms.
[20:09:09.466] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:10.566] INFO: Test took 1100ms.
[20:09:10.571] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:11.670] INFO: Test took 1100ms.
[20:09:11.674] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:12.769] INFO: Test took 1095ms.
[20:09:12.773] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:13.872] INFO: Test took 1099ms.
[20:09:13.878] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:14.973] INFO: Test took 1096ms.
[20:09:14.977] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:16.077] INFO: Test took 1100ms.
[20:09:16.083] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:17.181] INFO: Test took 1099ms.
[20:09:17.187] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:18.285] INFO: Test took 1099ms.
[20:09:18.839] INFO: PixTestPhOptimization::doTest() done, duration: 462 seconds
[20:09:18.839] INFO: PH scale (per ROC): 75 82 80 77 80 79 82 82 76 78 80 70 77 79 80 86
[20:09:18.839] INFO: PH offset (per ROC): 154 169 155 165 155 166 148 149 156 157 164 161 165 159 144 151
[20:09:19.078] INFO: ######################################################################
[20:09:19.078] INFO: PixTestGainPedestal::fullTest() ntrig = 10
[20:09:19.078] INFO: ######################################################################
[20:09:19.097] INFO: scanning low vcal = 10
[20:09:23.149] INFO: Test took 4052ms.
[20:09:23.156] INFO: scanning low vcal = 20
[20:09:27.213] INFO: Test took 4057ms.
[20:09:27.220] INFO: scanning low vcal = 30
[20:09:31.329] INFO: Test took 4109ms.
[20:09:31.351] INFO: scanning low vcal = 40
[20:09:35.897] INFO: Test took 4546ms.
[20:09:36.031] INFO: scanning low vcal = 50
[20:09:40.647] INFO: Test took 4616ms.
[20:09:40.788] INFO: scanning low vcal = 60
[20:09:45.376] INFO: Test took 4588ms.
[20:09:45.521] INFO: scanning low vcal = 70
[20:09:50.129] INFO: Test took 4608ms.
[20:09:50.258] INFO: scanning low vcal = 80
[20:09:54.835] INFO: Test took 4577ms.
[20:09:54.970] INFO: scanning low vcal = 90
[20:09:59.577] INFO: Test took 4607ms.
[20:09:59.711] INFO: scanning low vcal = 100
[20:10:04.322] INFO: Test took 4611ms.
[20:10:04.448] INFO: scanning low vcal = 110
[20:10:09.066] INFO: Test took 4618ms.
[20:10:09.193] INFO: scanning low vcal = 120
[20:10:13.781] INFO: Test took 4588ms.
[20:10:13.911] INFO: scanning low vcal = 130
[20:10:18.525] INFO: Test took 4614ms.
[20:10:18.660] INFO: scanning low vcal = 140
[20:10:23.236] INFO: Test took 4576ms.
[20:10:23.368] INFO: scanning low vcal = 150
[20:10:27.926] INFO: Test took 4558ms.
[20:10:28.042] INFO: scanning low vcal = 160
[20:10:32.594] INFO: Test took 4552ms.
[20:10:32.724] INFO: scanning low vcal = 170
[20:10:37.289] INFO: Test took 4565ms.
[20:10:37.410] INFO: scanning low vcal = 180
[20:10:42.003] INFO: Test took 4593ms.
[20:10:42.116] INFO: scanning low vcal = 190
[20:10:46.771] INFO: Test took 4655ms.
[20:10:46.886] INFO: scanning low vcal = 200
[20:10:51.464] INFO: Test took 4578ms.
[20:10:51.591] INFO: scanning low vcal = 210
[20:10:56.154] INFO: Test took 4562ms.
[20:10:56.269] INFO: scanning low vcal = 220
[20:11:00.861] INFO: Test took 4592ms.
[20:11:00.979] INFO: scanning low vcal = 230
[20:11:05.532] INFO: Test took 4553ms.
[20:11:05.647] INFO: scanning low vcal = 240
[20:11:10.200] INFO: Test took 4553ms.
[20:11:10.310] INFO: scanning low vcal = 250
[20:11:14.860] INFO: Test took 4550ms.
[20:11:14.979] INFO: scanning high vcal = 30 (= 210 in low range)
[20:11:19.550] INFO: Test took 4571ms.
[20:11:19.672] INFO: scanning high vcal = 50 (= 350 in low range)
[20:11:24.226] INFO: Test took 4554ms.
[20:11:24.361] INFO: scanning high vcal = 70 (= 490 in low range)
[20:11:28.939] INFO: Test took 4578ms.
[20:11:29.052] INFO: scanning high vcal = 90 (= 630 in low range)
[20:11:33.603] INFO: Test took 4551ms.
[20:11:33.720] INFO: scanning high vcal = 200 (= 1400 in low range)
[20:11:38.250] INFO: Test took 4530ms.
[20:11:39.049] INFO: PixTestGainPedestal::measure() done
[20:12:32.174] INFO: PixTestGainPedestal::fit() done
[20:12:32.175] INFO: non-linearity mean: 0.955 0.955 0.954 0.955 0.958 0.956 0.958 0.958 0.957 0.955 0.955 0.961 0.950 0.957 0.962 0.948
[20:12:32.175] INFO: non-linearity RMS: 0.006 0.005 0.006 0.007 0.004 0.007 0.005 0.006 0.007 0.005 0.005 0.005 0.007 0.005 0.003 0.006
[20:12:32.175] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C0.dat
[20:12:32.201] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C1.dat
[20:12:32.228] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C2.dat
[20:12:32.255] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C3.dat
[20:12:32.281] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C4.dat
[20:12:32.307] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C5.dat
[20:12:32.334] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C6.dat
[20:12:32.361] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C7.dat
[20:12:32.388] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C8.dat
[20:12:32.415] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C9.dat
[20:12:32.441] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C10.dat
[20:12:32.469] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C11.dat
[20:12:32.496] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C12.dat
[20:12:32.523] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C13.dat
[20:12:32.550] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C14.dat
[20:12:32.578] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/phCalibrationFitErr35_C15.dat
[20:12:32.606] INFO: PixTestGainPedestal::doTest() done, duration: 193 seconds
[20:12:32.615] INFO: readReadbackCal: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C15.dat
[20:12:32.617] INFO: PixTestReadback::doTest() start.
[20:12:32.618] INFO: PixTestReadback::RES sent once
[20:12:49.142] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C0.dat
[20:12:49.142] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C1.dat
[20:12:49.143] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C2.dat
[20:12:49.143] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C3.dat
[20:12:49.143] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C4.dat
[20:12:49.143] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C5.dat
[20:12:49.143] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C6.dat
[20:12:49.143] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C7.dat
[20:12:49.143] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C8.dat
[20:12:49.144] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C9.dat
[20:12:49.144] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C10.dat
[20:12:49.144] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C11.dat
[20:12:49.144] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C12.dat
[20:12:49.145] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C13.dat
[20:12:49.145] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C14.dat
[20:12:49.145] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C15.dat
[20:12:49.185] INFO: PixTestPattern:: pg_setup set to default.
[20:12:49.185] INFO: PixTestReadback::RES sent once
[20:13:05.620] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C0.dat
[20:13:05.621] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C1.dat
[20:13:05.621] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C2.dat
[20:13:05.621] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C3.dat
[20:13:05.622] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C4.dat
[20:13:05.622] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C5.dat
[20:13:05.622] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C6.dat
[20:13:05.622] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C7.dat
[20:13:05.623] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C8.dat
[20:13:05.623] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C9.dat
[20:13:05.623] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C10.dat
[20:13:05.623] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C11.dat
[20:13:05.624] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C12.dat
[20:13:05.624] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C13.dat
[20:13:05.624] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C14.dat
[20:13:05.624] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C15.dat
[20:13:05.666] INFO: PixTestPattern:: pg_setup set to default.
[20:13:05.667] INFO: PixTestReadback::RES sent once
[20:13:18.394] INFO: PixTestPattern:: pg_setup set to default.
[20:13:18.394] INFO: Vbg will be calibrated using Vd calibration
[20:13:18.394] INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 147.3calibrated Vbg = 1.24145 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152calibrated Vbg = 1.25236 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.3calibrated Vbg = 1.24754 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.5calibrated Vbg = 1.25774 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.2calibrated Vbg = 1.25784 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 159.2calibrated Vbg = 1.26604 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.3calibrated Vbg = 1.25865 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148.1calibrated Vbg = 1.26399 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 157.9calibrated Vbg = 1.26463 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.9calibrated Vbg = 1.2612 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.5calibrated Vbg = 1.26954 :::*/*/*/*/
[20:13:18.394] INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.6calibrated Vbg = 1.25905 :::*/*/*/*/
[20:13:18.395] INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 153.9calibrated Vbg = 1.24921 :::*/*/*/*/
[20:13:18.395] INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.6calibrated Vbg = 1.24566 :::*/*/*/*/
[20:13:18.395] INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 161.4calibrated Vbg = 1.24254 :::*/*/*/*/
[20:13:18.395] INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.7calibrated Vbg = 1.2499 :::*/*/*/*/
[20:13:18.399] INFO: PixTestReadback::RES sent once
[20:17:13.285] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C0.dat
[20:17:13.285] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C1.dat
[20:17:13.285] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C2.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C3.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C4.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C5.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C6.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C7.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C8.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C9.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C10.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C11.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C12.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C13.dat
[20:17:13.286] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C14.dat
[20:17:13.287] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/002_Fulltest_m20/readbackCal_C15.dat
[20:17:13.325] INFO: PixTestPattern:: pg_setup set to default.
[20:17:13.329] INFO: PixTestReadback::doTest() done
[20:17:13.348] INFO: enter test to run
[20:17:13.348] INFO: test: q no parameter change
[20:17:13.534] QUIET: Connection to board 176 closed.
[20:17:13.537] INFO: pXar: this is the end, my friend