[19:42:41.744] INFO: *** Welcome to pxar ***
[19:42:41.745] INFO: *** Today: 2015/08/04
[19:42:41.745] INFO: readRocDacs: /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C15.dat
[19:42:41.745] INFO: readTbmDacs: /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0a.dat .. /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/tbmParameters_C0b.dat
[19:42:41.745] INFO: readMaskFile: /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/defaultMaskFile.dat
[19:42:41.745] INFO: readTrimFile: /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters_C15.dat
[19:42:41.842] INFO: clk: 4
[19:42:41.842] INFO: ctr: 4
[19:42:41.842] INFO: sda: 19
[19:42:41.842] INFO: tin: 9
[19:42:41.842] INFO: level: 15
[19:42:41.842] INFO: triggerdelay: 0
[19:42:41.842] QUIET: Instanciating API for pxar prod-01+36~geb5ac3f
[19:42:41.842] INFO: Log level: INFO
[19:42:41.858] INFO: Found DTB DTB_WZ4WY6
[19:42:41.869] QUIET: Connection to board DTB_WZ4WY6 opened.
[19:42:41.873] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 175
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WZ4WY6
MAC address: 40D8551180AF
Hostname: pixelDTB175
Comment:
------------------------------------------------------
[19:42:41.875] INFO: RPC call hashes of host and DTB match: 447413373
[19:42:43.662] INFO: DUT info:
[19:42:43.662] INFO: The DUT currently contains the following objects:
[19:42:43.662] INFO: 2 TBM Cores tbm08c (2 ON)
[19:42:43.662] INFO: TBM Core alpha (0): 7 registers set
[19:42:43.662] INFO: TBM Core beta (1): 7 registers set
[19:42:43.662] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[19:42:43.662] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:43.662] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:44.063] INFO: enter 'restricted' command line mode
[19:42:44.063] INFO: enter test to run
[19:42:44.063] INFO: test: pretest no parameter change
[19:42:44.063] INFO: running: pretest
[19:42:44.069] INFO: ######################################################################
[19:42:44.069] INFO: PixTestPretest::doTest()
[19:42:44.069] INFO: ######################################################################
[19:42:44.070] INFO: ----------------------------------------------------------------------
[19:42:44.070] INFO: PixTestPretest::programROC()
[19:42:44.070] INFO: ----------------------------------------------------------------------
[19:43:02.094] INFO: PixTestPretest::programROC() done: ROCs are all programmable
[19:43:02.094] INFO: IA differences per ROC: 16.9 17.7 18.5 18.5 19.3 16.9 20.1 19.3 19.3 21.7 16.9 19.3 16.1 18.5 18.5 19.3
[19:43:02.199] INFO: ----------------------------------------------------------------------
[19:43:02.199] INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[19:43:02.199] INFO: ----------------------------------------------------------------------
[19:43:21.853] INFO: PixTestPretest::setVana() done, Module Ia 381.9 mA = 23.8688 mA/ROC
[19:43:21.857] INFO: ----------------------------------------------------------------------
[19:43:21.857] INFO: PixTestPretest::findWorkingPixel()
[19:43:21.857] INFO: ----------------------------------------------------------------------
[19:43:29.731] INFO: Test took 7867ms.
[19:43:30.102] INFO: Found working pixel in all ROCs: col/row = 12/22
[19:43:30.157] INFO: ----------------------------------------------------------------------
[19:43:30.157] INFO: PixTestPretest::setVthrCompCalDel()
[19:43:30.157] INFO: ----------------------------------------------------------------------
[19:43:38.023] INFO: Test took 7858ms.
[19:43:38.413] INFO: PixTestPretest::setVthrCompCalDel() done
[19:43:38.413] INFO: CalDel: 103 141 117 116 133 127 120 99 118 128 125 123 115 105 123 126
[19:43:38.413] INFO: VthrComp: 51 51 52 51 51 51 51 51 51 51 51 51 54 51 51 51
[19:43:38.417] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C0.dat
[19:43:38.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C1.dat
[19:43:38.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C2.dat
[19:43:38.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C3.dat
[19:43:38.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C4.dat
[19:43:38.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C5.dat
[19:43:38.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C6.dat
[19:43:38.418] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C7.dat
[19:43:38.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C8.dat
[19:43:38.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C9.dat
[19:43:38.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C10.dat
[19:43:38.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C11.dat
[19:43:38.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C12.dat
[19:43:38.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C13.dat
[19:43:38.419] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C14.dat
[19:43:38.420] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters_C15.dat
[19:43:38.420] INFO: PixTestPretest::doTest() done, duration: 54 seconds
[19:43:38.533] INFO: enter test to run
[19:43:38.533] INFO: test: fulltest no parameter change
[19:43:38.533] INFO: running: fulltest
[19:43:38.533] INFO: ######################################################################
[19:43:38.533] INFO: PixTestFullTest::doTest()
[19:43:38.533] INFO: ######################################################################
[19:43:38.535] INFO: ######################################################################
[19:43:38.535] INFO: PixTestAlive::doTest()
[19:43:38.535] INFO: ######################################################################
[19:43:38.536] INFO: ----------------------------------------------------------------------
[19:43:38.536] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:43:38.536] INFO: ----------------------------------------------------------------------
[19:43:42.289] INFO: Test took 3750ms.
[19:43:42.325] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:43:42.583] INFO: PixTestAlive::aliveTest() done
[19:43:42.583] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 5 0 0 0 0 0 0
[19:43:42.585] INFO: ----------------------------------------------------------------------
[19:43:42.585] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:43:42.585] INFO: ----------------------------------------------------------------------
[19:43:45.524] INFO: Test took 2935ms.
[19:43:45.529] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:43:45.529] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[19:43:45.778] INFO: PixTestAlive::maskTest() done
[19:43:45.778] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:43:45.780] INFO: ----------------------------------------------------------------------
[19:43:45.780] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:43:45.780] INFO: ----------------------------------------------------------------------
[19:43:49.569] INFO: Test took 3786ms.
[19:43:49.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:43:49.881] INFO: PixTestAlive::addressDecodingTest() done
[19:43:49.881] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:43:49.882] INFO: PixTestAlive::doTest() done, duration: 11 seconds
[19:43:49.891] INFO: ######################################################################
[19:43:49.891] INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[19:43:49.891] INFO: ######################################################################
[19:43:49.895] INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[19:43:49.919] INFO: dacScan step from 0 .. 29
[19:43:49.919] INFO: dacScan split into 1 runs with ntrig = 5
[19:43:49.919] INFO: run 1 of 1
[19:44:09.726] INFO: Test took 19806ms.
[19:44:09.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:09.797] INFO: dacScan step from 30 .. 59
[19:44:09.797] INFO: dacScan split into 1 runs with ntrig = 5
[19:44:09.797] INFO: run 1 of 1
[19:44:30.445] INFO: Test took 20647ms.
[19:44:30.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:30.535] INFO: dacScan step from 60 .. 89
[19:44:30.535] INFO: dacScan split into 1 runs with ntrig = 5
[19:44:30.535] INFO: run 1 of 1
[19:44:56.048] INFO: Test took 25513ms.
[19:44:56.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:56.523] INFO: dacScan step from 90 .. 119
[19:44:56.523] INFO: dacScan split into 1 runs with ntrig = 5
[19:44:56.523] INFO: run 1 of 1
[19:45:24.948] INFO: Test took 28425ms.
[19:45:25.464] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:45:25.585] INFO: dacScan step from 120 .. 149
[19:45:25.585] INFO: dacScan split into 1 runs with ntrig = 5
[19:45:25.585] INFO: run 1 of 1
[19:45:51.190] INFO: Test took 25605ms.
[19:45:51.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:46:33.043] INFO: PixTestBBMap::doTest() done, duration: 163 seconds
[19:46:33.043] INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 3 0 0 0 0 0 0
[19:46:33.043] INFO: separation cut (per ROC): 115 102 113 117 106 104 118 142 116 105 97 106 104 110 108 133
[19:46:33.150] INFO: ######################################################################
[19:46:33.150] INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = 1
[19:46:33.150] INFO: ######################################################################
[19:46:33.150] INFO: ----------------------------------------------------------------------
[19:46:33.150] INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = 1
[19:46:33.150] INFO: ----------------------------------------------------------------------
[19:46:33.150] INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/1) hits flags = 16 (plus default)
[19:46:33.166] INFO: dacScan split into 50 runs with ntrig = 1
[19:46:33.166] INFO: run 1 of 50
[19:47:25.553] INFO: Test took 52387ms.
[19:47:25.935] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:47:26.137] INFO: run 2 of 50
[19:48:18.547] INFO: Test took 52410ms.
[19:48:18.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:48:19.079] INFO: run 3 of 50
[19:49:11.674] INFO: Test took 52595ms.
[19:49:12.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:49:12.229] INFO: run 4 of 50
[19:50:04.695] INFO: Test took 52466ms.
[19:50:05.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:50:05.236] INFO: run 5 of 50
[19:50:57.566] INFO: Test took 52330ms.
[19:50:57.927] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:50:58.125] INFO: run 6 of 50
[19:51:50.649] INFO: Test took 52524ms.
[19:51:51.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:51:51.194] INFO: run 7 of 50
[19:52:43.673] INFO: Test took 52479ms.
[19:52:44.006] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:52:44.179] INFO: run 8 of 50
[19:53:36.493] INFO: Test took 52314ms.
[19:53:36.853] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:53:37.030] INFO: run 9 of 50
[19:54:29.496] INFO: Test took 52466ms.
[19:54:29.853] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:54:30.033] INFO: run 10 of 50
[19:55:22.392] INFO: Test took 52359ms.
[19:55:22.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:55:22.932] INFO: run 11 of 50
[19:56:15.108] INFO: Test took 52176ms.
[19:56:15.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:56:15.607] INFO: run 12 of 50
[19:57:07.801] INFO: Test took 52194ms.
[19:57:08.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:57:08.349] INFO: run 13 of 50
[19:58:00.466] INFO: Test took 52117ms.
[19:58:00.819] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:58:00.999] INFO: run 14 of 50
[19:58:53.052] INFO: Test took 52052ms.
[19:58:53.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:58:53.658] INFO: run 15 of 50
[19:59:45.694] INFO: Test took 52036ms.
[19:59:46.058] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:59:46.231] INFO: run 16 of 50
[20:00:38.229] INFO: Test took 51998ms.
[20:00:38.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:00:38.789] INFO: run 17 of 50
[20:01:30.914] INFO: Test took 52125ms.
[20:01:31.237] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:01:31.396] INFO: run 18 of 50
[20:02:23.319] INFO: Test took 51923ms.
[20:02:23.680] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:02:23.856] INFO: run 19 of 50
[20:03:15.828] INFO: Test took 51972ms.
[20:03:16.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:03:16.324] INFO: run 20 of 50
[20:04:08.300] INFO: Test took 51976ms.
[20:04:08.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:04:08.859] INFO: run 21 of 50
[20:05:00.829] INFO: Test took 51970ms.
[20:05:01.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:05:01.361] INFO: run 22 of 50
[20:05:53.444] INFO: Test took 52083ms.
[20:05:53.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:05:53.965] INFO: run 23 of 50
[20:06:45.952] INFO: Test took 51987ms.
[20:06:46.255] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:06:46.420] INFO: run 24 of 50
[20:07:38.547] INFO: Test took 52127ms.
[20:07:38.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:07:39.079] INFO: run 25 of 50
[20:08:31.323] INFO: Test took 52244ms.
[20:08:31.687] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:08:31.857] INFO: run 26 of 50
[20:09:24.028] INFO: Test took 52171ms.
[20:09:24.346] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:09:24.510] INFO: run 27 of 50
[20:10:16.607] INFO: Test took 52097ms.
[20:10:16.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:10:17.121] INFO: run 28 of 50
[20:11:09.271] INFO: Test took 52149ms.
[20:11:09.608] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:09.782] INFO: run 29 of 50
[20:12:01.790] INFO: Test took 52008ms.
[20:12:02.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:02.329] INFO: run 30 of 50
[20:12:54.354] INFO: Test took 52025ms.
[20:12:54.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:54.901] INFO: run 31 of 50
[20:13:46.781] INFO: Test took 51880ms.
[20:13:47.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:13:47.330] INFO: run 32 of 50
[20:14:39.213] INFO: Test took 51882ms.
[20:14:39.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:14:39.831] INFO: run 33 of 50
[20:15:31.796] INFO: Test took 51965ms.
[20:15:32.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:15:32.328] INFO: run 34 of 50
[20:16:24.248] INFO: Test took 51920ms.
[20:16:24.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:16:24.748] INFO: run 35 of 50
[20:17:16.936] INFO: Test took 52188ms.
[20:17:17.302] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:17:17.478] INFO: run 36 of 50
[20:18:09.711] INFO: Test took 52233ms.
[20:18:10.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:18:10.212] INFO: run 37 of 50
[20:19:02.569] INFO: Test took 52357ms.
[20:19:02.944] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:19:03.148] INFO: run 38 of 50
[20:19:55.570] INFO: Test took 52421ms.
[20:19:55.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:19:56.093] INFO: run 39 of 50
[20:20:48.824] INFO: Test took 52731ms.
[20:20:49.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:20:49.355] INFO: run 40 of 50
[20:21:42.014] INFO: Test took 52659ms.
[20:21:42.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:21:42.559] INFO: run 41 of 50
[20:22:35.163] INFO: Test took 52604ms.
[20:22:35.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:22:35.680] INFO: run 42 of 50
[20:23:28.248] INFO: Test took 52568ms.
[20:23:28.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:23:28.769] INFO: run 43 of 50
[20:24:21.384] INFO: Test took 52615ms.
[20:24:21.718] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:24:21.879] INFO: run 44 of 50
[20:25:14.455] INFO: Test took 52576ms.
[20:25:14.830] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:25:14.003] INFO: run 45 of 50
[20:26:07.549] INFO: Test took 52546ms.
[20:26:07.904] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:26:08.082] INFO: run 46 of 50
[20:27:00.714] INFO: Test took 52632ms.
[20:27:01.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:27:01.232] INFO: run 47 of 50
[20:27:53.742] INFO: Test took 52510ms.
[20:27:54.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:27:54.294] INFO: run 48 of 50
[20:28:46.591] INFO: Test took 52297ms.
[20:28:46.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:28:47.156] INFO: run 49 of 50
[20:29:39.551] INFO: Test took 52394ms.
[20:29:39.926] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:29:40.083] INFO: run 50 of 50
[20:30:32.193] INFO: Test took 52110ms.
[20:30:32.567] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:30:32.768] INFO: dumping ASCII scurve output file: SCurveData
[20:30:35.237] INFO: dumping ASCII scurve output file: SCurveData
[20:30:37.668] INFO: dumping ASCII scurve output file: SCurveData
[20:30:39.979] INFO: dumping ASCII scurve output file: SCurveData
[20:30:42.307] INFO: dumping ASCII scurve output file: SCurveData
[20:30:44.713] INFO: dumping ASCII scurve output file: SCurveData
[20:30:47.040] INFO: dumping ASCII scurve output file: SCurveData
[20:30:49.479] INFO: dumping ASCII scurve output file: SCurveData
[20:30:51.834] INFO: dumping ASCII scurve output file: SCurveData
[20:30:54.294] INFO: dumping ASCII scurve output file: SCurveData
[20:30:56.792] INFO: dumping ASCII scurve output file: SCurveData
[20:30:59.448] INFO: dumping ASCII scurve output file: SCurveData
[20:31:01.934] INFO: dumping ASCII scurve output file: SCurveData
[20:31:04.508] INFO: dumping ASCII scurve output file: SCurveData
[20:31:07.385] INFO: dumping ASCII scurve output file: SCurveData
[20:31:10.191] INFO: dumping ASCII scurve output file: SCurveData
[20:31:12.944] INFO: PixTestScurves::scurves() done
[20:31:12.944] INFO: Vcal mean: 105.47 99.05 111.87 105.26 100.87 103.39 102.86 112.85 107.59 96.39 97.02 102.54 110.07 108.96 102.21 105.13
[20:31:12.944] INFO: Vcal RMS: 4.93 6.36 5.07 5.12 5.64 6.26 5.46 6.03 6.99 6.09 5.77 5.47 5.32 5.24 6.05 5.44
[20:31:12.944] INFO: PixTestScurves::fullTest() done, duration: 2679 seconds
[20:31:13.068] INFO: ######################################################################
[20:31:13.068] INFO: PixTestTrim::doTest()
[20:31:13.068] INFO: ######################################################################
[20:31:13.070] INFO: ----------------------------------------------------------------------
[20:31:13.070] INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[20:31:13.070] INFO: ----------------------------------------------------------------------
[20:31:13.262] INFO: ---> VthrComp thr map (minimal VthrComp)
[20:31:13.262] INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[20:31:13.278] INFO: dacScan step from 0 .. 19
[20:31:13.278] INFO: dacScan split into 1 runs with ntrig = 5
[20:31:13.278] INFO: run 1 of 1
[20:31:28.603] INFO: Test took 15324ms.
[20:31:28.671] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:31:28.671] INFO: dacScan step from 20 .. 39
[20:31:28.671] INFO: dacScan split into 1 runs with ntrig = 5
[20:31:28.671] INFO: run 1 of 1
[20:31:43.003] INFO: Test took 15332ms.
[20:31:44.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:31:44.052] INFO: dacScan step from 40 .. 59
[20:31:44.052] INFO: dacScan split into 1 runs with ntrig = 5
[20:31:44.052] INFO: run 1 of 1
[20:31:59.316] INFO: Test took 15264ms.
[20:31:59.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:31:59.364] INFO: dacScan step from 60 .. 79
[20:31:59.364] INFO: dacScan split into 1 runs with ntrig = 5
[20:31:59.364] INFO: run 1 of 1
[20:32:14.671] INFO: Test took 15307ms.
[20:32:14.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:32:14.712] INFO: dacScan step from 80 .. 99
[20:32:14.712] INFO: dacScan split into 1 runs with ntrig = 5
[20:32:14.712] INFO: run 1 of 1
[20:32:29.518] INFO: Test took 14806ms.
[20:32:29.561] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:32:29.563] INFO: dacScan step from 100 .. 119
[20:32:29.563] INFO: dacScan split into 1 runs with ntrig = 5
[20:32:29.563] INFO: run 1 of 1
[20:32:46.527] INFO: Test took 16964ms.
[20:32:46.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:32:46.792] INFO: dacScan step from 120 .. 139
[20:32:46.792] INFO: dacScan split into 1 runs with ntrig = 5
[20:32:46.792] INFO: run 1 of 1
[20:33:05.582] INFO: Test took 18790ms.
[20:33:05.883] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:33:05.955] INFO: dacScan step from 140 .. 159
[20:33:05.955] INFO: dacScan split into 1 runs with ntrig = 5
[20:33:05.955] INFO: run 1 of 1
[20:33:21.864] INFO: Test took 15909ms.
[20:33:21.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:33:58.586] INFO: ROC 0 VthrComp = 104
[20:33:58.587] INFO: ROC 1 VthrComp = 91
[20:33:58.587] INFO: ROC 2 VthrComp = 105
[20:33:58.587] INFO: ROC 3 VthrComp = 103
[20:33:58.587] INFO: ROC 4 VthrComp = 95
[20:33:58.588] INFO: ROC 5 VthrComp = 96
[20:33:58.588] INFO: ROC 6 VthrComp = 98
[20:33:58.588] INFO: ROC 7 VthrComp = 105
[20:33:58.588] INFO: ROC 8 VthrComp = 100
[20:33:58.589] INFO: ROC 9 VthrComp = 100
[20:33:58.589] INFO: ROC 10 VthrComp = 94
[20:33:58.589] INFO: ROC 11 VthrComp = 102
[20:33:58.589] INFO: ROC 12 VthrComp = 102
[20:33:58.590] INFO: ROC 13 VthrComp = 105
[20:33:58.590] INFO: ROC 14 VthrComp = 98
[20:33:58.590] INFO: ROC 15 VthrComp = 101
[20:33:58.590] INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[20:33:58.590] INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[20:33:58.605] INFO: dacScan step from 0 .. 19
[20:33:58.605] INFO: dacScan split into 1 runs with ntrig = 5
[20:33:58.605] INFO: run 1 of 1
[20:34:13.759] INFO: Test took 15154ms.
[20:34:13.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:34:13.803] INFO: dacScan step from 20 .. 39
[20:34:13.803] INFO: dacScan split into 1 runs with ntrig = 5
[20:34:13.803] INFO: run 1 of 1
[20:34:28.692] INFO: Test took 14888ms.
[20:34:28.755] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:34:28.759] INFO: dacScan step from 40 .. 59
[20:34:28.759] INFO: dacScan split into 1 runs with ntrig = 5
[20:34:28.759] INFO: run 1 of 1
[20:34:46.750] INFO: Test took 17991ms.
[20:34:47.015] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:34:47.080] INFO: dacScan step from 60 .. 79
[20:34:47.080] INFO: dacScan split into 1 runs with ntrig = 5
[20:34:47.080] INFO: run 1 of 1
[20:35:07.033] INFO: Test took 19953ms.
[20:35:07.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:35:07.403] INFO: dacScan step from 80 .. 99
[20:35:07.403] INFO: dacScan split into 1 runs with ntrig = 5
[20:35:07.403] INFO: run 1 of 1
[20:35:27.472] INFO: Test took 20069ms.
[20:35:27.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:35:27.879] INFO: dacScan step from 100 .. 119
[20:35:27.879] INFO: dacScan split into 1 runs with ntrig = 5
[20:35:27.879] INFO: run 1 of 1
[20:35:47.948] INFO: Test took 20069ms.
[20:35:48.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:35:48.363] INFO: dacScan step from 120 .. 139
[20:35:48.363] INFO: dacScan split into 1 runs with ntrig = 5
[20:35:48.363] INFO: run 1 of 1
[20:36:08.664] INFO: Test took 20301ms.
[20:36:08.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:36:09.045] INFO: dacScan step from 140 .. 159
[20:36:09.045] INFO: dacScan split into 1 runs with ntrig = 5
[20:36:09.045] INFO: run 1 of 1
[20:36:29.039] INFO: Test took 19993ms.
[20:36:29.340] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:37:12.946] INFO: roc 0 with ID = 0 has maximal Vcal 59.7 for pixel 45/72 mean/min/max = 47.296/34.828/59.764
[20:37:12.947] INFO: roc 1 with ID = 1 has maximal Vcal 66.3031 for pixel 7/4 mean/min/max = 49.2633/32.2141/66.3125
[20:37:12.947] INFO: roc 2 with ID = 2 has maximal Vcal 62.9394 for pixel 0/72 mean/min/max = 48.767/34.2561/63.2779
[20:37:12.948] INFO: roc 3 with ID = 3 has maximal Vcal 58.5042 for pixel 21/0 mean/min/max = 45.7683/32.9613/58.5753
[20:37:12.948] INFO: roc 4 with ID = 4 has maximal Vcal 62.3742 for pixel 10/2 mean/min/max = 47.2963/32.1442/62.4485
[20:37:12.949] INFO: roc 5 with ID = 5 has maximal Vcal 64.0847 for pixel 0/13 mean/min/max = 47.8197/31.5354/64.104
[20:37:12.949] INFO: roc 6 with ID = 6 has maximal Vcal 59.3873 for pixel 50/2 mean/min/max = 45.7369/31.8513/59.6225
[20:37:12.950] INFO: roc 7 with ID = 7 has maximal Vcal 67.1615 for pixel 1/79 mean/min/max = 50.7318/34.2667/67.197
[20:37:12.950] INFO: roc 8 with ID = 8 has maximal Vcal 64.3981 for pixel 3/78 mean/min/max = 47.792/31.1764/64.4077
[20:37:12.951] INFO: roc 9 with ID = 9 has maximal Vcal 57.5832 for pixel 0/38 mean/min/max = 44.4479/30.7993/58.0965
[20:37:12.951] INFO: roc 10 with ID = 10 has maximal Vcal 59.6232 for pixel 0/66 mean/min/max = 46.2727/32.8418/59.7035
[20:37:12.952] INFO: roc 11 with ID = 11 has maximal Vcal 60.2711 for pixel 0/7 mean/min/max = 45.9073/31.4777/60.337
[20:37:12.952] INFO: roc 12 with ID = 12 has maximal Vcal 62.4727 for pixel 3/15 mean/min/max = 47.4956/32.446/62.5452
[20:37:12.953] INFO: roc 13 with ID = 13 has maximal Vcal 62.3296 for pixel 11/79 mean/min/max = 48.3116/34.1752/62.448
[20:37:12.953] INFO: roc 14 with ID = 14 has maximal Vcal 60.3255 for pixel 3/77 mean/min/max = 46.0172/31.7062/60.3281
[20:37:12.954] INFO: roc 15 with ID = 15 has maximal Vcal 59.8184 for pixel 0/12 mean/min/max = 46.1189/32.4081/59.8298
[20:37:12.954] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:38:33.000] INFO: Test took 81046ms.
[20:38:36.051] INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[20:38:36.067] INFO: dacScan step from 0 .. 19
[20:38:36.067] INFO: dacScan split into 2 runs with ntrig = 5
[20:38:36.067] INFO: run 1 of 2
[20:38:50.882] INFO: Test took 14815ms.
[20:38:50.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:38:50.944] INFO: run 2 of 2
[20:39:05.710] INFO: Test took 14765ms.
[20:39:05.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:39:05.765] INFO: dacScan step from 20 .. 39
[20:39:05.765] INFO: dacScan split into 2 runs with ntrig = 5
[20:39:05.765] INFO: run 1 of 2
[20:39:23.134] INFO: Test took 17369ms.
[20:39:23.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:39:23.407] INFO: run 2 of 2
[20:39:40.692] INFO: Test took 17284ms.
[20:39:40.935] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:39:40.993] INFO: dacScan step from 40 .. 59
[20:39:40.993] INFO: dacScan split into 2 runs with ntrig = 5
[20:39:40.993] INFO: run 1 of 2
[20:40:01.015] INFO: Test took 20022ms.
[20:40:01.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:40:01.425] INFO: run 2 of 2
[20:40:21.317] INFO: Test took 19892ms.
[20:40:21.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:40:21.724] INFO: dacScan step from 60 .. 79
[20:40:21.724] INFO: dacScan split into 2 runs with ntrig = 5
[20:40:21.724] INFO: run 1 of 2
[20:40:41.809] INFO: Test took 20085ms.
[20:40:42.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:40:42.209] INFO: run 2 of 2
[20:41:02.101] INFO: Test took 19891ms.
[20:41:02.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:41:02.513] INFO: dacScan step from 80 .. 99
[20:41:02.513] INFO: dacScan split into 2 runs with ntrig = 5
[20:41:02.513] INFO: run 1 of 2
[20:41:22.465] INFO: Test took 19952ms.
[20:41:22.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:41:22.843] INFO: run 2 of 2
[20:41:42.650] INFO: Test took 19807ms.
[20:41:42.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:41:43.077] INFO: dacScan step from 100 .. 119
[20:41:43.078] INFO: dacScan split into 2 runs with ntrig = 5
[20:41:43.078] INFO: run 1 of 2
[20:42:02.967] INFO: Test took 19889ms.
[20:42:03.383] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:42:03.486] INFO: run 2 of 2
[20:42:23.562] INFO: Test took 20076ms.
[20:42:23.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:42:24.068] INFO: dacScan step from 120 .. 139
[20:42:24.068] INFO: dacScan split into 2 runs with ntrig = 5
[20:42:24.068] INFO: run 1 of 2
[20:42:44.086] INFO: Test took 20018ms.
[20:42:44.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:42:44.542] INFO: run 2 of 2
[20:43:04.547] INFO: Test took 20004ms.
[20:43:04.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:43:04.975] INFO: dacScan step from 140 .. 159
[20:43:04.975] INFO: dacScan split into 2 runs with ntrig = 5
[20:43:04.975] INFO: run 1 of 2
[20:43:24.965] INFO: Test took 19990ms.
[20:43:25.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:43:25.449] INFO: run 2 of 2
[20:43:45.395] INFO: Test took 19945ms.
[20:43:45.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:43:45.792] INFO: dacScan step from 160 .. 179
[20:43:45.792] INFO: dacScan split into 2 runs with ntrig = 5
[20:43:45.792] INFO: run 1 of 2
[20:44:05.790] INFO: Test took 19998ms.
[20:44:06.120] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:44:06.194] INFO: run 2 of 2
[20:44:26.262] INFO: Test took 20068ms.
[20:44:26.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:44:26.695] INFO: dacScan step from 180 .. 199
[20:44:26.695] INFO: dacScan split into 2 runs with ntrig = 5
[20:44:26.695] INFO: run 1 of 2
[20:44:47.455] INFO: Test took 20760ms.
[20:44:47.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:44:47.859] INFO: run 2 of 2
[20:45:08.618] INFO: Test took 20759ms.
[20:45:08.939] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:45:52.965] INFO: ---> TrimStepCorr4 extremal thresholds: 0.105941 .. 255.000000
[20:45:53.111] INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[20:45:53.126] INFO: dacScan step from 0 .. 19
[20:45:53.126] INFO: dacScan split into 1 runs with ntrig = 4
[20:45:53.126] INFO: run 1 of 1
[20:46:06.564] INFO: Test took 13438ms.
[20:46:06.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:46:06.606] INFO: dacScan step from 20 .. 39
[20:46:06.606] INFO: dacScan split into 1 runs with ntrig = 4
[20:46:06.606] INFO: run 1 of 1
[20:46:20.998] INFO: Test took 14392ms.
[20:46:21.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:46:21.175] INFO: dacScan step from 40 .. 59
[20:46:21.175] INFO: dacScan split into 1 runs with ntrig = 4
[20:46:21.175] INFO: run 1 of 1
[20:46:38.482] INFO: Test took 17306ms.
[20:46:38.787] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:46:38.883] INFO: dacScan step from 60 .. 79
[20:46:38.883] INFO: dacScan split into 1 runs with ntrig = 4
[20:46:38.883] INFO: run 1 of 1
[20:46:56.600] INFO: Test took 17717ms.
[20:46:56.896] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:46:57.007] INFO: dacScan step from 80 .. 99
[20:46:57.007] INFO: dacScan split into 1 runs with ntrig = 4
[20:46:57.007] INFO: run 1 of 1
[20:47:14.655] INFO: Test took 17648ms.
[20:47:15.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:15.107] INFO: dacScan step from 100 .. 119
[20:47:15.107] INFO: dacScan split into 1 runs with ntrig = 4
[20:47:15.107] INFO: run 1 of 1
[20:47:32.807] INFO: Test took 17700ms.
[20:47:33.092] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:33.188] INFO: dacScan step from 120 .. 139
[20:47:33.188] INFO: dacScan split into 1 runs with ntrig = 4
[20:47:33.188] INFO: run 1 of 1
[20:47:50.815] INFO: Test took 17627ms.
[20:47:51.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:47:51.254] INFO: dacScan step from 140 .. 159
[20:47:51.254] INFO: dacScan split into 1 runs with ntrig = 4
[20:47:51.254] INFO: run 1 of 1
[20:48:08.927] INFO: Test took 17672ms.
[20:48:09.216] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:48:09.299] INFO: dacScan step from 160 .. 179
[20:48:09.299] INFO: dacScan split into 1 runs with ntrig = 4
[20:48:09.299] INFO: run 1 of 1
[20:48:27.142] INFO: Test took 17844ms.
[20:48:27.437] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:48:27.519] INFO: dacScan step from 180 .. 199
[20:48:27.519] INFO: dacScan split into 1 runs with ntrig = 4
[20:48:27.519] INFO: run 1 of 1
[20:48:45.997] INFO: Test took 18478ms.
[20:48:46.294] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:48:46.376] INFO: dacScan step from 200 .. 219
[20:48:46.377] INFO: dacScan split into 1 runs with ntrig = 4
[20:48:46.377] INFO: run 1 of 1
[20:49:04.052] INFO: Test took 17675ms.
[20:49:04.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:49:04.437] INFO: dacScan step from 220 .. 239
[20:49:04.437] INFO: dacScan split into 1 runs with ntrig = 4
[20:49:04.437] INFO: run 1 of 1
[20:49:22.065] INFO: Test took 17628ms.
[20:49:22.359] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:49:22.443] INFO: dacScan step from 240 .. 255
[20:49:22.443] INFO: dacScan split into 1 runs with ntrig = 4
[20:49:22.443] INFO: run 1 of 1
[20:49:37.268] INFO: Test took 14824ms.
[20:49:37.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:34.309] INFO: ---> TrimStepCorr2 extremal thresholds: 1.007447 .. 93.066232
[20:50:34.447] INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 1 .. 103 (20/-1) hits flags = 16 (plus default)
[20:50:34.463] INFO: dacScan step from 1 .. 20
[20:50:34.463] INFO: dacScan split into 1 runs with ntrig = 4
[20:50:34.463] INFO: run 1 of 1
[20:50:47.981] INFO: Test took 13518ms.
[20:50:48.023] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:50:48.023] INFO: dacScan step from 21 .. 40
[20:50:48.023] INFO: dacScan split into 1 runs with ntrig = 4
[20:50:48.023] INFO: run 1 of 1
[20:51:02.558] INFO: Test took 14535ms.
[20:51:02.709] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:02.746] INFO: dacScan step from 41 .. 60
[20:51:02.746] INFO: dacScan split into 1 runs with ntrig = 4
[20:51:02.746] INFO: run 1 of 1
[20:51:20.285] INFO: Test took 17539ms.
[20:51:20.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:20.722] INFO: dacScan step from 61 .. 80
[20:51:20.722] INFO: dacScan split into 1 runs with ntrig = 4
[20:51:20.722] INFO: run 1 of 1
[20:51:38.265] INFO: Test took 17543ms.
[20:51:38.548] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:38.627] INFO: dacScan step from 81 .. 100
[20:51:38.627] INFO: dacScan split into 1 runs with ntrig = 4
[20:51:38.627] INFO: run 1 of 1
[20:51:56.223] INFO: Test took 17596ms.
[20:51:56.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:51:56.605] INFO: dacScan step from 101 .. 103
[20:51:56.605] INFO: dacScan split into 1 runs with ntrig = 4
[20:51:56.605] INFO: run 1 of 1
[20:52:02.101] INFO: Test took 5497ms.
[20:52:02.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:52:39.852] INFO: ---> TrimStepCorr1a extremal thresholds: 1.500000 .. 82.676562
[20:52:39.997] INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 1 .. 92 (20/-1) hits flags = 16 (plus default)
[20:52:40.010] INFO: dacScan step from 1 .. 20
[20:52:40.010] INFO: dacScan split into 1 runs with ntrig = 4
[20:52:40.010] INFO: run 1 of 1
[20:52:53.404] INFO: Test took 13394ms.
[20:52:53.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:52:53.446] INFO: dacScan step from 21 .. 40
[20:52:53.446] INFO: dacScan split into 1 runs with ntrig = 4
[20:52:53.446] INFO: run 1 of 1
[20:53:07.918] INFO: Test took 14471ms.
[20:53:08.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:53:08.087] INFO: dacScan step from 41 .. 60
[20:53:08.087] INFO: dacScan split into 1 runs with ntrig = 4
[20:53:08.087] INFO: run 1 of 1
[20:53:25.775] INFO: Test took 17688ms.
[20:53:26.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:53:26.162] INFO: dacScan step from 61 .. 80
[20:53:26.162] INFO: dacScan split into 1 runs with ntrig = 4
[20:53:26.162] INFO: run 1 of 1
[20:53:43.968] INFO: Test took 17805ms.
[20:53:44.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:53:44.338] INFO: dacScan step from 81 .. 92
[20:53:44.339] INFO: dacScan split into 1 runs with ntrig = 4
[20:53:44.339] INFO: run 1 of 1
[20:53:56.219] INFO: Test took 11880ms.
[20:53:56.396] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:54:29.293] INFO: ---> TrimStepCorr1b extremal thresholds: 0.223757 .. 72.989423
[20:54:29.432] INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 0 .. 82 (20/-1) hits flags = 16 (plus default)
[20:54:29.447] INFO: dacScan step from 0 .. 19
[20:54:29.447] INFO: dacScan split into 1 runs with ntrig = 4
[20:54:29.447] INFO: run 1 of 1
[20:54:43.044] INFO: Test took 13597ms.
[20:54:43.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:54:43.085] INFO: dacScan step from 20 .. 39
[20:54:43.085] INFO: dacScan split into 1 runs with ntrig = 4
[20:54:43.085] INFO: run 1 of 1
[20:54:57.377] INFO: Test took 14292ms.
[20:54:57.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:54:57.538] INFO: dacScan step from 40 .. 59
[20:54:57.538] INFO: dacScan split into 1 runs with ntrig = 4
[20:54:57.538] INFO: run 1 of 1
[20:55:15.274] INFO: Test took 17736ms.
[20:55:15.574] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:55:15.663] INFO: dacScan step from 60 .. 79
[20:55:15.663] INFO: dacScan split into 1 runs with ntrig = 4
[20:55:15.663] INFO: run 1 of 1
[20:55:33.474] INFO: Test took 17811ms.
[20:55:33.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:55:33.851] INFO: dacScan step from 80 .. 82
[20:55:33.851] INFO: dacScan split into 1 runs with ntrig = 4
[20:55:33.851] INFO: run 1 of 1
[20:55:39.357] INFO: Test took 5505ms.
[20:55:39.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:56:10.771] INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[20:56:10.771] INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[20:56:10.785] INFO: dacScan step from 15 .. 34
[20:56:10.785] INFO: dacScan split into 2 runs with ntrig = 5
[20:56:10.785] INFO: run 1 of 2
[20:56:25.715] INFO: Test took 14930ms.
[20:56:25.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:56:25.794] INFO: run 2 of 2
[20:56:40.762] INFO: Test took 14968ms.
[20:56:40.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:56:40.856] INFO: dacScan step from 35 .. 54
[20:56:40.856] INFO: dacScan split into 2 runs with ntrig = 5
[20:56:40.856] INFO: run 1 of 2
[20:57:00.266] INFO: Test took 19410ms.
[20:57:00.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:57:00.663] INFO: run 2 of 2
[20:57:20.227] INFO: Test took 19564ms.
[20:57:20.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:57:20.652] INFO: dacScan step from 55 .. 55
[20:57:20.652] INFO: dacScan split into 2 runs with ntrig = 5
[20:57:20.652] INFO: run 1 of 2
[20:57:24.784] INFO: Test took 4132ms.
[20:57:24.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:57:24.816] INFO: run 2 of 2
[20:57:28.910] INFO: Test took 4094ms.
[20:57:28.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:57:50.915] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat
[20:57:50.916] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C1.dat
[20:57:50.916] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C2.dat
[20:57:50.916] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C3.dat
[20:57:50.916] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C4.dat
[20:57:50.916] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C5.dat
[20:57:50.916] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C6.dat
[20:57:50.916] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C7.dat
[20:57:50.917] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C8.dat
[20:57:50.917] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C9.dat
[20:57:50.917] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C10.dat
[20:57:50.917] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C11.dat
[20:57:50.917] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C12.dat
[20:57:50.917] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C13.dat
[20:57:50.917] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C14.dat
[20:57:50.918] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[20:57:50.918] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C0.dat
[20:57:50.930] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C1.dat
[20:57:50.943] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C2.dat
[20:57:50.955] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C3.dat
[20:57:50.968] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C4.dat
[20:57:50.981] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C5.dat
[20:57:50.993] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C6.dat
[20:57:51.005] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C7.dat
[20:57:51.017] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C8.dat
[20:57:51.030] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C9.dat
[20:57:51.043] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C10.dat
[20:57:51.055] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C11.dat
[20:57:51.066] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C12.dat
[20:57:51.077] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C13.dat
[20:57:51.089] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C14.dat
[20:57:51.100] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/trimParameters35_C15.dat
[20:57:51.112] INFO: PixTestTrim::trimTest() done
[20:57:51.112] INFO: vtrim: 116 111 104 106 108 114 100 131 110 96 95 106 112 114 112 106
[20:57:51.112] INFO: vthrcomp: 104 91 105 103 95 96 98 105 100 100 94 102 102 105 98 101
[20:57:51.112] INFO: vcal mean: 35.19 35.09 35.17 35.09 35.12 35.18 35.08 35.13 35.15 35.05 35.15 35.04 35.16 35.15 35.07 35.11
[20:57:51.112] INFO: vcal RMS: 1.10 1.65 1.14 1.22 1.31 1.35 1.16 1.17 1.19 1.45 1.09 1.14 1.45 1.10 1.17 1.11
[20:57:51.112] INFO: bits mean: 9.33 9.37 8.89 10.05 9.81 9.88 9.93 8.58 9.83 9.74 9.45 9.33 9.93 8.88 10.20 9.88
[20:57:51.112] INFO: bits RMS: 2.31 2.47 2.45 2.34 2.41 2.45 2.50 2.43 2.49 2.72 2.60 2.81 2.33 2.46 2.45 2.44
[20:57:51.123] INFO: ----------------------------------------------------------------------
[20:57:51.123] INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[20:57:51.123] INFO: ----------------------------------------------------------------------
[20:57:51.127] INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[20:57:51.142] INFO: dacScan step from 0 .. 19
[20:57:51.142] INFO: dacScan split into 2 runs with ntrig = 5
[20:57:51.142] INFO: run 1 of 2
[20:58:05.228] INFO: Test took 14086ms.
[20:58:05.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:05.277] INFO: run 2 of 2
[20:58:19.459] INFO: Test took 14182ms.
[20:58:19.507] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:19.507] INFO: dacScan step from 20 .. 39
[20:58:19.507] INFO: dacScan split into 2 runs with ntrig = 5
[20:58:19.507] INFO: run 1 of 2
[20:58:33.701] INFO: Test took 14194ms.
[20:58:33.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:33.746] INFO: run 2 of 2
[20:58:47.895] INFO: Test took 14149ms.
[20:58:47.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:58:47.936] INFO: dacScan step from 40 .. 59
[20:58:47.936] INFO: dacScan split into 2 runs with ntrig = 5
[20:58:47.936] INFO: run 1 of 2
[20:59:02.095] INFO: Test took 14159ms.
[20:59:02.137] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:02.137] INFO: run 2 of 2
[20:59:16.266] INFO: Test took 14129ms.
[20:59:16.314] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:16.314] INFO: dacScan step from 60 .. 79
[20:59:16.314] INFO: dacScan split into 2 runs with ntrig = 5
[20:59:16.314] INFO: run 1 of 2
[20:59:30.503] INFO: Test took 14189ms.
[20:59:30.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:30.552] INFO: run 2 of 2
[20:59:44.773] INFO: Test took 14221ms.
[20:59:44.826] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:44.826] INFO: dacScan step from 80 .. 99
[20:59:44.826] INFO: dacScan split into 2 runs with ntrig = 5
[20:59:44.826] INFO: run 1 of 2
[20:59:58.944] INFO: Test took 14118ms.
[20:59:58.993] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:59:58.993] INFO: run 2 of 2
[21:00:13.250] INFO: Test took 14257ms.
[21:00:13.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:00:13.301] INFO: dacScan step from 100 .. 119
[21:00:13.301] INFO: dacScan split into 2 runs with ntrig = 5
[21:00:13.301] INFO: run 1 of 2
[21:00:28.199] INFO: Test took 14898ms.
[21:00:28.261] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:00:28.263] INFO: run 2 of 2
[21:00:43.046] INFO: Test took 14783ms.
[21:00:43.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:00:43.107] INFO: dacScan step from 120 .. 139
[21:00:43.107] INFO: dacScan split into 2 runs with ntrig = 5
[21:00:43.107] INFO: run 1 of 2
[21:00:59.477] INFO: Test took 16369ms.
[21:00:59.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:00:59.689] INFO: run 2 of 2
[21:01:16.222] INFO: Test took 16533ms.
[21:01:16.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:01:16.464] INFO: dacScan step from 140 .. 159
[21:01:16.464] INFO: dacScan split into 2 runs with ntrig = 5
[21:01:16.464] INFO: run 1 of 2
[21:01:36.048] INFO: Test took 19584ms.
[21:01:36.369] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:01:36.447] INFO: run 2 of 2
[21:01:55.955] INFO: Test took 19508ms.
[21:01:56.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:01:56.354] INFO: dacScan step from 160 .. 179
[21:01:56.354] INFO: dacScan split into 2 runs with ntrig = 5
[21:01:56.354] INFO: run 1 of 2
[21:02:16.494] INFO: Test took 20140ms.
[21:02:16.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:02:16.902] INFO: run 2 of 2
[21:02:37.116] INFO: Test took 20213ms.
[21:02:37.464] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:02:37.547] INFO: dacScan step from 180 .. 199
[21:02:37.547] INFO: dacScan split into 2 runs with ntrig = 5
[21:02:37.547] INFO: run 1 of 2
[21:02:58.561] INFO: Test took 21014ms.
[21:02:58.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:02:58.991] INFO: run 2 of 2
[21:03:20.016] INFO: Test took 21024ms.
[21:03:20.365] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:04:06.321] INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 187 (20/5) hits flags = 16 (plus default)
[21:04:06.337] INFO: dacScan step from 0 .. 19
[21:04:06.337] INFO: dacScan split into 2 runs with ntrig = 5
[21:04:06.337] INFO: run 1 of 2
[21:04:20.502] INFO: Test took 14165ms.
[21:04:20.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:04:20.556] INFO: run 2 of 2
[21:04:34.699] INFO: Test took 14143ms.
[21:04:34.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:04:34.748] INFO: dacScan step from 20 .. 39
[21:04:34.748] INFO: dacScan split into 2 runs with ntrig = 5
[21:04:34.748] INFO: run 1 of 2
[21:04:48.822] INFO: Test took 14073ms.
[21:04:48.868] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:04:48.868] INFO: run 2 of 2
[21:05:02.996] INFO: Test took 14128ms.
[21:05:03.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:03.046] INFO: dacScan step from 40 .. 59
[21:05:03.046] INFO: dacScan split into 2 runs with ntrig = 5
[21:05:03.046] INFO: run 1 of 2
[21:05:17.124] INFO: Test took 14077ms.
[21:05:17.174] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:17.174] INFO: run 2 of 2
[21:05:31.276] INFO: Test took 14102ms.
[21:05:31.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:31.326] INFO: dacScan step from 60 .. 79
[21:05:31.326] INFO: dacScan split into 2 runs with ntrig = 5
[21:05:31.326] INFO: run 1 of 2
[21:05:45.501] INFO: Test took 14175ms.
[21:05:45.560] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:45.560] INFO: run 2 of 2
[21:05:59.903] INFO: Test took 14343ms.
[21:05:59.942] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:05:59.942] INFO: dacScan step from 80 .. 99
[21:05:59.942] INFO: dacScan split into 2 runs with ntrig = 5
[21:05:59.942] INFO: run 1 of 2
[21:06:14.596] INFO: Test took 14654ms.
[21:06:14.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:06:14.639] INFO: run 2 of 2
[21:06:29.578] INFO: Test took 14938ms.
[21:06:29.628] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:06:29.628] INFO: dacScan step from 100 .. 119
[21:06:29.628] INFO: dacScan split into 2 runs with ntrig = 5
[21:06:29.628] INFO: run 1 of 2
[21:06:44.942] INFO: Test took 15313ms.
[21:06:45.079] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:06:45.099] INFO: run 2 of 2
[21:07:00.447] INFO: Test took 15348ms.
[21:07:00.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:07:00.586] INFO: dacScan step from 120 .. 139
[21:07:00.586] INFO: dacScan split into 2 runs with ntrig = 5
[21:07:00.586] INFO: run 1 of 2
[21:07:19.103] INFO: Test took 18517ms.
[21:07:19.383] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:07:19.461] INFO: run 2 of 2
[21:07:38.085] INFO: Test took 18624ms.
[21:07:38.374] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:07:38.443] INFO: dacScan step from 140 .. 159
[21:07:38.443] INFO: dacScan split into 2 runs with ntrig = 5
[21:07:38.443] INFO: run 1 of 2
[21:07:58.309] INFO: Test took 19867ms.
[21:07:58.663] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:07:58.756] INFO: run 2 of 2
[21:08:18.807] INFO: Test took 20051ms.
[21:08:19.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:08:19.224] INFO: dacScan step from 160 .. 179
[21:08:19.224] INFO: dacScan split into 2 runs with ntrig = 5
[21:08:19.224] INFO: run 1 of 2
[21:08:39.472] INFO: Test took 20248ms.
[21:08:39.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:08:39.927] INFO: run 2 of 2
[21:09:00.291] INFO: Test took 20364ms.
[21:09:00.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:09:00.747] INFO: dacScan step from 180 .. 187
[21:09:00.747] INFO: dacScan split into 2 runs with ntrig = 5
[21:09:00.747] INFO: run 1 of 2
[21:09:10.949] INFO: Test took 10203ms.
[21:09:11.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:09:11.199] INFO: run 2 of 2
[21:09:21.627] INFO: Test took 10428ms.
[21:09:21.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:03.905] INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 195 (20/5) hits flags = 16 (plus default)
[21:10:03.919] INFO: dacScan step from 0 .. 19
[21:10:03.919] INFO: dacScan split into 2 runs with ntrig = 5
[21:10:03.919] INFO: run 1 of 2
[21:10:18.072] INFO: Test took 14153ms.
[21:10:18.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:18.117] INFO: run 2 of 2
[21:10:32.321] INFO: Test took 14203ms.
[21:10:32.367] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:32.367] INFO: dacScan step from 20 .. 39
[21:10:32.367] INFO: dacScan split into 2 runs with ntrig = 5
[21:10:32.367] INFO: run 1 of 2
[21:10:46.653] INFO: Test took 14286ms.
[21:10:46.704] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:10:46.704] INFO: run 2 of 2
[21:11:00.948] INFO: Test took 14243ms.
[21:11:00.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:00.996] INFO: dacScan step from 40 .. 59
[21:11:00.996] INFO: dacScan split into 2 runs with ntrig = 5
[21:11:00.996] INFO: run 1 of 2
[21:11:15.238] INFO: Test took 14241ms.
[21:11:15.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:15.286] INFO: run 2 of 2
[21:11:29.508] INFO: Test took 14221ms.
[21:11:29.548] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:29.548] INFO: dacScan step from 60 .. 79
[21:11:29.548] INFO: dacScan split into 2 runs with ntrig = 5
[21:11:29.548] INFO: run 1 of 2
[21:11:43.721] INFO: Test took 14173ms.
[21:11:43.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:43.769] INFO: run 2 of 2
[21:11:57.934] INFO: Test took 14165ms.
[21:11:57.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:11:57.983] INFO: dacScan step from 80 .. 99
[21:11:57.983] INFO: dacScan split into 2 runs with ntrig = 5
[21:11:57.983] INFO: run 1 of 2
[21:12:12.808] INFO: Test took 14824ms.
[21:12:12.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:12:12.856] INFO: run 2 of 2
[21:12:27.597] INFO: Test took 14741ms.
[21:12:27.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:12:27.645] INFO: dacScan step from 100 .. 119
[21:12:27.645] INFO: dacScan split into 2 runs with ntrig = 5
[21:12:27.645] INFO: run 1 of 2
[21:12:43.047] INFO: Test took 15402ms.
[21:12:43.171] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:12:43.191] INFO: run 2 of 2
[21:12:58.695] INFO: Test took 15504ms.
[21:12:58.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:12:58.810] INFO: dacScan step from 120 .. 139
[21:12:58.811] INFO: dacScan split into 2 runs with ntrig = 5
[21:12:58.811] INFO: run 1 of 2
[21:13:17.419] INFO: Test took 18608ms.
[21:13:17.713] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:13:17.783] INFO: run 2 of 2
[21:13:36.208] INFO: Test took 18425ms.
[21:13:36.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:13:36.573] INFO: dacScan step from 140 .. 159
[21:13:36.573] INFO: dacScan split into 2 runs with ntrig = 5
[21:13:36.573] INFO: run 1 of 2
[21:13:56.448] INFO: Test took 19875ms.
[21:13:56.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:13:56.876] INFO: run 2 of 2
[21:14:16.639] INFO: Test took 19762ms.
[21:14:17.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:14:17.152] INFO: dacScan step from 160 .. 179
[21:14:17.152] INFO: dacScan split into 2 runs with ntrig = 5
[21:14:17.152] INFO: run 1 of 2
[21:14:37.487] INFO: Test took 20335ms.
[21:14:37.842] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:14:37.927] INFO: run 2 of 2
[21:14:58.245] INFO: Test took 20318ms.
[21:14:58.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:14:58.660] INFO: dacScan step from 180 .. 195
[21:14:58.660] INFO: dacScan split into 2 runs with ntrig = 5
[21:14:58.660] INFO: run 1 of 2
[21:15:16.341] INFO: Test took 17681ms.
[21:15:16.612] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:15:16.679] INFO: run 2 of 2
[21:15:34.227] INFO: Test took 17548ms.
[21:15:34.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:16:17.528] INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 174 (20/5) hits flags = 16 (plus default)
[21:16:17.548] INFO: dacScan step from 0 .. 19
[21:16:17.548] INFO: dacScan split into 2 runs with ntrig = 5
[21:16:17.548] INFO: run 1 of 2
[21:16:31.885] INFO: Test took 14337ms.
[21:16:31.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:16:31.933] INFO: run 2 of 2
[21:16:46.181] INFO: Test took 14248ms.
[21:16:46.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:16:46.226] INFO: dacScan step from 20 .. 39
[21:16:46.226] INFO: dacScan split into 2 runs with ntrig = 5
[21:16:46.226] INFO: run 1 of 2
[21:17:00.464] INFO: Test took 14237ms.
[21:17:00.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:00.514] INFO: run 2 of 2
[21:17:14.685] INFO: Test took 14171ms.
[21:17:14.731] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:14.731] INFO: dacScan step from 40 .. 59
[21:17:14.731] INFO: dacScan split into 2 runs with ntrig = 5
[21:17:14.731] INFO: run 1 of 2
[21:17:28.866] INFO: Test took 14135ms.
[21:17:28.910] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:28.910] INFO: run 2 of 2
[21:17:43.253] INFO: Test took 14343ms.
[21:17:43.299] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:43.299] INFO: dacScan step from 60 .. 79
[21:17:43.299] INFO: dacScan split into 2 runs with ntrig = 5
[21:17:43.299] INFO: run 1 of 2
[21:17:57.572] INFO: Test took 14273ms.
[21:17:57.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:17:57.616] INFO: run 2 of 2
[21:18:11.804] INFO: Test took 14188ms.
[21:18:11.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:11.852] INFO: dacScan step from 80 .. 99
[21:18:11.852] INFO: dacScan split into 2 runs with ntrig = 5
[21:18:11.852] INFO: run 1 of 2
[21:18:26.508] INFO: Test took 14656ms.
[21:18:26.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:26.549] INFO: run 2 of 2
[21:18:41.197] INFO: Test took 14648ms.
[21:18:41.243] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:41.243] INFO: dacScan step from 100 .. 119
[21:18:41.243] INFO: dacScan split into 2 runs with ntrig = 5
[21:18:41.243] INFO: run 1 of 2
[21:18:56.594] INFO: Test took 15351ms.
[21:18:56.710] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:18:56.732] INFO: run 2 of 2
[21:19:12.013] INFO: Test took 15281ms.
[21:19:12.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:19:12.143] INFO: dacScan step from 120 .. 139
[21:19:12.143] INFO: dacScan split into 2 runs with ntrig = 5
[21:19:12.143] INFO: run 1 of 2
[21:19:30.615] INFO: Test took 18471ms.
[21:19:30.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:19:31.045] INFO: run 2 of 2
[21:19:49.649] INFO: Test took 18604ms.
[21:19:49.942] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:19:50.014] INFO: dacScan step from 140 .. 159
[21:19:50.014] INFO: dacScan split into 2 runs with ntrig = 5
[21:19:50.015] INFO: run 1 of 2
[21:20:09.969] INFO: Test took 19954ms.
[21:20:10.324] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:20:10.406] INFO: run 2 of 2
[21:20:30.249] INFO: Test took 19843ms.
[21:20:30.575] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:20:30.658] INFO: dacScan step from 160 .. 174
[21:20:30.658] INFO: dacScan split into 2 runs with ntrig = 5
[21:20:30.658] INFO: run 1 of 2
[21:20:46.606] INFO: Test took 15948ms.
[21:20:46.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:20:46.924] INFO: run 2 of 2
[21:21:02.866] INFO: Test took 15942ms.
[21:21:03.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:21:42.712] INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 171 (20/5) hits flags = 16 (plus default)
[21:21:42.726] INFO: dacScan step from 0 .. 19
[21:21:42.726] INFO: dacScan split into 2 runs with ntrig = 5
[21:21:42.726] INFO: run 1 of 2
[21:21:57.892] INFO: Test took 15165ms.
[21:21:57.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:21:57.941] INFO: run 2 of 2
[21:22:13.114] INFO: Test took 15172ms.
[21:22:13.158] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:22:13.158] INFO: dacScan step from 20 .. 39
[21:22:13.158] INFO: dacScan split into 2 runs with ntrig = 5
[21:22:13.158] INFO: run 1 of 2
[21:22:28.319] INFO: Test took 15161ms.
[21:22:28.369] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:22:28.369] INFO: run 2 of 2
[21:22:43.630] INFO: Test took 15261ms.
[21:22:43.680] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:22:43.680] INFO: dacScan step from 40 .. 59
[21:22:43.680] INFO: dacScan split into 2 runs with ntrig = 5
[21:22:43.680] INFO: run 1 of 2
[21:22:58.762] INFO: Test took 15082ms.
[21:22:58.809] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:22:58.809] INFO: run 2 of 2
[21:23:13.884] INFO: Test took 15075ms.
[21:23:13.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:23:13.934] INFO: dacScan step from 60 .. 79
[21:23:13.934] INFO: dacScan split into 2 runs with ntrig = 5
[21:23:13.934] INFO: run 1 of 2
[21:23:29.119] INFO: Test took 15184ms.
[21:23:29.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:23:29.162] INFO: run 2 of 2
[21:23:44.273] INFO: Test took 15111ms.
[21:23:44.322] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:23:44.322] INFO: dacScan step from 80 .. 99
[21:23:44.322] INFO: dacScan split into 2 runs with ntrig = 5
[21:23:44.322] INFO: run 1 of 2
[21:23:59.145] INFO: Test took 14823ms.
[21:23:59.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:23:59.195] INFO: run 2 of 2
[21:24:13.771] INFO: Test took 14576ms.
[21:24:13.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:24:13.815] INFO: dacScan step from 100 .. 119
[21:24:13.815] INFO: dacScan split into 2 runs with ntrig = 5
[21:24:13.815] INFO: run 1 of 2
[21:24:29.077] INFO: Test took 15261ms.
[21:24:29.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:24:29.205] INFO: run 2 of 2
[21:24:44.637] INFO: Test took 15432ms.
[21:24:44.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:24:44.767] INFO: dacScan step from 120 .. 139
[21:24:44.767] INFO: dacScan split into 2 runs with ntrig = 5
[21:24:44.767] INFO: run 1 of 2
[21:25:03.228] INFO: Test took 18461ms.
[21:25:03.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:25:03.587] INFO: run 2 of 2
[21:25:22.172] INFO: Test took 18585ms.
[21:25:22.467] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:25:22.537] INFO: dacScan step from 140 .. 159
[21:25:22.537] INFO: dacScan split into 2 runs with ntrig = 5
[21:25:22.537] INFO: run 1 of 2
[21:25:42.375] INFO: Test took 19838ms.
[21:25:42.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:25:42.787] INFO: run 2 of 2
[21:26:02.710] INFO: Test took 19923ms.
[21:26:03.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:26:03.129] INFO: dacScan step from 160 .. 171
[21:26:03.130] INFO: dacScan split into 2 runs with ntrig = 5
[21:26:03.130] INFO: run 1 of 2
[21:26:16.595] INFO: Test took 13465ms.
[21:26:16.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:26:16.852] INFO: run 2 of 2
[21:26:30.452] INFO: Test took 13600ms.
[21:26:30.692] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:27:07.873] INFO: PixTestTrim::trimBitTest() done
[21:27:07.874] INFO: PixTestTrim::doTest() done, duration: 3354 seconds
[21:27:08.869] INFO: ######################################################################
[21:27:08.869] INFO: PixTestPhOptimization::doTest() Ntrig = 10
[21:27:08.869] INFO: ######################################################################
[21:27:12.612] INFO: Test took 3741ms.
[21:27:12.648] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:27:16.608] INFO: Test took 3757ms.
[21:27:16.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:27:20.587] INFO: Test took 3811ms.
[21:27:20.731] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:27:20.742] INFO: The DUT currently contains the following objects:
[21:27:20.742] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:20.742] INFO: TBM Core alpha (0): 7 registers set
[21:27:20.743] INFO: TBM Core beta (1): 7 registers set
[21:27:20.743] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:20.743] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:20.743] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.918] INFO: Test took 1175ms.
[21:27:21.920] INFO: The DUT currently contains the following objects:
[21:27:21.920] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:21.920] INFO: TBM Core alpha (0): 7 registers set
[21:27:21.920] INFO: TBM Core beta (1): 7 registers set
[21:27:21.920] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:21.920] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:21.920] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.104] INFO: Test took 1184ms.
[21:27:23.107] INFO: The DUT currently contains the following objects:
[21:27:23.107] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:23.107] INFO: TBM Core alpha (0): 7 registers set
[21:27:23.107] INFO: TBM Core beta (1): 7 registers set
[21:27:23.107] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:23.107] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:23.107] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.208] INFO: Test took 1101ms.
[21:27:24.210] INFO: The DUT currently contains the following objects:
[21:27:24.210] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:24.210] INFO: TBM Core alpha (0): 7 registers set
[21:27:24.210] INFO: TBM Core beta (1): 7 registers set
[21:27:24.210] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:24.210] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:24.210] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.390] INFO: Test took 1180ms.
[21:27:25.392] INFO: The DUT currently contains the following objects:
[21:27:25.392] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:25.392] INFO: TBM Core alpha (0): 7 registers set
[21:27:25.392] INFO: TBM Core beta (1): 7 registers set
[21:27:25.392] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:25.392] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:25.392] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.495] INFO: Test took 1103ms.
[21:27:26.497] INFO: The DUT currently contains the following objects:
[21:27:26.497] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:26.497] INFO: TBM Core alpha (0): 7 registers set
[21:27:26.497] INFO: TBM Core beta (1): 7 registers set
[21:27:26.497] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:26.497] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:26.497] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.598] INFO: Test took 1101ms.
[21:27:27.600] INFO: The DUT currently contains the following objects:
[21:27:27.600] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:27.600] INFO: TBM Core alpha (0): 7 registers set
[21:27:27.600] INFO: TBM Core beta (1): 7 registers set
[21:27:27.600] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:27.600] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.600] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.600] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.600] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:27.601] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.780] INFO: Test took 1179ms.
[21:27:28.782] INFO: The DUT currently contains the following objects:
[21:27:28.783] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:28.783] INFO: TBM Core alpha (0): 7 registers set
[21:27:28.783] INFO: TBM Core beta (1): 7 registers set
[21:27:28.783] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:28.783] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:28.783] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.883] INFO: Test took 1100ms.
[21:27:29.885] INFO: The DUT currently contains the following objects:
[21:27:29.885] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:29.885] INFO: TBM Core alpha (0): 7 registers set
[21:27:29.885] INFO: TBM Core beta (1): 7 registers set
[21:27:29.885] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:29.885] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.885] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.886] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:29.886] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.062] INFO: Test took 1176ms.
[21:27:31.064] INFO: The DUT currently contains the following objects:
[21:27:31.064] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:31.064] INFO: TBM Core alpha (0): 7 registers set
[21:27:31.064] INFO: TBM Core beta (1): 7 registers set
[21:27:31.064] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:31.064] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.064] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.064] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.064] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:31.065] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.167] INFO: Test took 1102ms.
[21:27:32.169] INFO: The DUT currently contains the following objects:
[21:27:32.169] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:32.169] INFO: TBM Core alpha (0): 7 registers set
[21:27:32.169] INFO: TBM Core beta (1): 7 registers set
[21:27:32.169] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:32.169] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:32.169] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.272] INFO: Test took 1103ms.
[21:27:33.274] INFO: The DUT currently contains the following objects:
[21:27:33.274] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:33.274] INFO: TBM Core alpha (0): 7 registers set
[21:27:33.274] INFO: TBM Core beta (1): 7 registers set
[21:27:33.274] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:33.274] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:33.275] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.372] INFO: Test took 1097ms.
[21:27:34.373] INFO: The DUT currently contains the following objects:
[21:27:34.373] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:34.373] INFO: TBM Core alpha (0): 7 registers set
[21:27:34.373] INFO: TBM Core beta (1): 7 registers set
[21:27:34.373] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:34.373] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.373] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.373] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.373] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:34.374] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.472] INFO: Test took 1098ms.
[21:27:35.473] INFO: The DUT currently contains the following objects:
[21:27:35.473] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:35.473] INFO: TBM Core alpha (0): 7 registers set
[21:27:35.473] INFO: TBM Core beta (1): 7 registers set
[21:27:35.473] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:35.473] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.473] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.473] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.473] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.473] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.473] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.473] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.474] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.474] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.474] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.474] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.474] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.474] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.474] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.474] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:35.474] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.575] INFO: Test took 1101ms.
[21:27:36.577] INFO: The DUT currently contains the following objects:
[21:27:36.577] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:36.577] INFO: TBM Core alpha (0): 7 registers set
[21:27:36.577] INFO: TBM Core beta (1): 7 registers set
[21:27:36.578] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:36.578] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:36.578] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.752] INFO: Test took 1174ms.
[21:27:37.753] INFO: The DUT currently contains the following objects:
[21:27:37.753] INFO: 2 TBM Cores tbm08c (2 ON)
[21:27:37.753] INFO: TBM Core alpha (0): 7 registers set
[21:27:37.753] INFO: TBM Core beta (1): 7 registers set
[21:27:37.753] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[21:27:37.753] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.753] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:37.754] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[21:27:38.852] INFO: Test took 1098ms.
[21:27:38.859] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:30:48.800] INFO: Test took 189941ms.
[21:30:52.021] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:03.106] INFO: Test took 191085ms.
[21:34:06.449] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.459] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.470] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.480] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.490] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.500] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.511] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.521] INFO: safety margin for low PH: adding 1, margin is now 21
[21:34:06.532] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.542] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.552] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.562] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.572] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.582] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.592] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.603] INFO: safety margin for low PH: adding 1, margin is now 21
[21:34:06.613] INFO: safety margin for low PH: adding 2, margin is now 22
[21:34:06.622] INFO: safety margin for low PH: adding 3, margin is now 23
[21:34:06.633] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.643] INFO: safety margin for low PH: adding 0, margin is now 20
[21:34:06.710] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C0.dat
[21:34:06.711] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C1.dat
[21:34:06.711] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C2.dat
[21:34:06.711] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C3.dat
[21:34:06.711] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C4.dat
[21:34:06.712] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C5.dat
[21:34:06.712] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C6.dat
[21:34:06.712] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C7.dat
[21:34:06.712] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C8.dat
[21:34:06.713] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C9.dat
[21:34:06.713] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C10.dat
[21:34:06.713] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C11.dat
[21:34:06.713] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C12.dat
[21:34:06.714] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C13.dat
[21:34:06.714] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C14.dat
[21:34:06.714] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/dacParameters35_C15.dat
[21:34:10.536] INFO: Test took 3817ms.
[21:34:14.587] INFO: Test took 3691ms.
[21:34:18.759] INFO: Test took 3816ms.
[21:34:19.127] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:20.043] INFO: Test took 916ms.
[21:34:20.047] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:21.153] INFO: Test took 1106ms.
[21:34:21.158] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:22.341] INFO: Test took 1183ms.
[21:34:22.347] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:23.528] INFO: Test took 1181ms.
[21:34:23.535] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:24.637] INFO: Test took 1102ms.
[21:34:24.644] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:25.826] INFO: Test took 1182ms.
[21:34:25.832] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:27.015] INFO: Test took 1183ms.
[21:34:27.020] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:28.122] INFO: Test took 1102ms.
[21:34:28.127] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:29.228] INFO: Test took 1101ms.
[21:34:29.235] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:30.414] INFO: Test took 1180ms.
[21:34:30.420] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:31.521] INFO: Test took 1102ms.
[21:34:31.526] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:32.709] INFO: Test took 1183ms.
[21:34:32.713] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:33.891] INFO: Test took 1178ms.
[21:34:33.895] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:34.997] INFO: Test took 1103ms.
[21:34:35.005] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:36.187] INFO: Test took 1183ms.
[21:34:36.193] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:37.375] INFO: Test took 1182ms.
[21:34:37.379] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:38.559] INFO: Test took 1181ms.
[21:34:38.565] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:39.747] INFO: Test took 1183ms.
[21:34:39.753] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:40.933] INFO: Test took 1180ms.
[21:34:40.938] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:42.039] INFO: Test took 1101ms.
[21:34:42.045] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:43.142] INFO: Test took 1097ms.
[21:34:43.146] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:44.247] INFO: Test took 1101ms.
[21:34:44.254] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:45.352] INFO: Test took 1099ms.
[21:34:45.358] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:46.456] INFO: Test took 1099ms.
[21:34:46.463] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:47.563] INFO: Test took 1100ms.
[21:34:47.570] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:48.672] INFO: Test took 1102ms.
[21:34:48.678] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:49.857] INFO: Test took 1179ms.
[21:34:49.864] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:51.043] INFO: Test took 1180ms.
[21:34:51.048] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:52.223] INFO: Test took 1176ms.
[21:34:52.226] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:53.407] INFO: Test took 1181ms.
[21:34:53.413] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:54.591] INFO: Test took 1179ms.
[21:34:54.598] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:55.778] INFO: Test took 1181ms.
[21:34:56.430] INFO: PixTestPhOptimization::doTest() done, duration: 467 seconds
[21:34:56.430] INFO: PH scale (per ROC): 70 58 63 65 65 67 62 63 67 72 66 74 63 69 65 66
[21:34:56.430] INFO: PH offset (per ROC): 178 184 188 187 189 184 187 174 184 173 193 173 191 176 184 189
[21:34:56.659] INFO: ######################################################################
[21:34:56.659] INFO: PixTestGainPedestal::fullTest() ntrig = 10
[21:34:56.659] INFO: ######################################################################
[21:34:56.676] INFO: scanning low vcal = 10
[21:35:00.857] INFO: Test took 4181ms.
[21:35:00.866] INFO: scanning low vcal = 20
[21:35:05.028] INFO: Test took 4162ms.
[21:35:05.035] INFO: scanning low vcal = 30
[21:35:09.160] INFO: Test took 4124ms.
[21:35:09.182] INFO: scanning low vcal = 40
[21:35:13.881] INFO: Test took 4699ms.
[21:35:13.999] INFO: scanning low vcal = 50
[21:35:18.786] INFO: Test took 4787ms.
[21:35:18.922] INFO: scanning low vcal = 60
[21:35:23.685] INFO: Test took 4763ms.
[21:35:23.828] INFO: scanning low vcal = 70
[21:35:28.648] INFO: Test took 4820ms.
[21:35:28.790] INFO: scanning low vcal = 80
[21:35:33.614] INFO: Test took 4824ms.
[21:35:33.751] INFO: scanning low vcal = 90
[21:35:38.630] INFO: Test took 4879ms.
[21:35:38.767] INFO: scanning low vcal = 100
[21:35:43.485] INFO: Test took 4718ms.
[21:35:43.631] INFO: scanning low vcal = 110
[21:35:48.453] INFO: Test took 4822ms.
[21:35:48.597] INFO: scanning low vcal = 120
[21:35:53.292] INFO: Test took 4695ms.
[21:35:53.435] INFO: scanning low vcal = 130
[21:35:58.273] INFO: Test took 4838ms.
[21:35:58.414] INFO: scanning low vcal = 140
[21:36:03.251] INFO: Test took 4837ms.
[21:36:03.396] INFO: scanning low vcal = 150
[21:36:08.154] INFO: Test took 4759ms.
[21:36:08.281] INFO: scanning low vcal = 160
[21:36:13.076] INFO: Test took 4795ms.
[21:36:13.214] INFO: scanning low vcal = 170
[21:36:17.945] INFO: Test took 4731ms.
[21:36:18.085] INFO: scanning low vcal = 180
[21:36:22.851] INFO: Test took 4766ms.
[21:36:22.989] INFO: scanning low vcal = 190
[21:36:27.910] INFO: Test took 4921ms.
[21:36:28.056] INFO: scanning low vcal = 200
[21:36:32.858] INFO: Test took 4802ms.
[21:36:33.005] INFO: scanning low vcal = 210
[21:36:37.649] INFO: Test took 4644ms.
[21:36:37.794] INFO: scanning low vcal = 220
[21:36:42.528] INFO: Test took 4734ms.
[21:36:42.677] INFO: scanning low vcal = 230
[21:36:47.411] INFO: Test took 4734ms.
[21:36:47.557] INFO: scanning low vcal = 240
[21:36:52.290] INFO: Test took 4733ms.
[21:36:52.433] INFO: scanning low vcal = 250
[21:36:57.139] INFO: Test took 4706ms.
[21:36:57.287] INFO: scanning high vcal = 30 (= 210 in low range)
[21:37:02.140] INFO: Test took 4853ms.
[21:37:02.289] INFO: scanning high vcal = 50 (= 350 in low range)
[21:37:07.125] INFO: Test took 4836ms.
[21:37:07.278] INFO: scanning high vcal = 70 (= 490 in low range)
[21:37:12.127] INFO: Test took 4849ms.
[21:37:12.271] INFO: scanning high vcal = 90 (= 630 in low range)
[21:37:17.156] INFO: Test took 4885ms.
[21:37:17.307] INFO: scanning high vcal = 200 (= 1400 in low range)
[21:37:22.052] INFO: Test took 4745ms.
[21:37:22.898] INFO: PixTestGainPedestal::measure() done
[21:38:17.257] INFO: PixTestGainPedestal::fit() done
[21:38:17.257] INFO: non-linearity mean: 0.954 0.950 0.964 0.956 0.954 0.950 0.958 0.954 0.951 0.954 0.957 0.962 0.955 0.961 0.959 0.962
[21:38:17.257] INFO: non-linearity RMS: 0.008 0.010 0.007 0.007 0.008 0.009 0.007 0.007 0.009 0.005 0.007 0.008 0.007 0.005 0.007 0.006
[21:38:17.257] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C0.dat
[21:38:17.285] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C1.dat
[21:38:17.310] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C2.dat
[21:38:17.338] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C3.dat
[21:38:17.364] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C4.dat
[21:38:17.388] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C5.dat
[21:38:17.412] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C6.dat
[21:38:17.437] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C7.dat
[21:38:17.462] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C8.dat
[21:38:17.486] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C9.dat
[21:38:17.510] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C10.dat
[21:38:17.536] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C11.dat
[21:38:17.561] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C12.dat
[21:38:17.586] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C13.dat
[21:38:17.611] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C14.dat
[21:38:17.635] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/phCalibrationFitErr35_C15.dat
[21:38:17.661] INFO: PixTestGainPedestal::doTest() done, duration: 201 seconds
[21:38:17.669] INFO: readReadbackCal: /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C15.dat
[21:38:17.671] INFO: PixTestReadback::doTest() start.
[21:38:17.672] INFO: PixTestReadback::RES sent once
[21:38:34.919] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C0.dat
[21:38:34.919] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C1.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C2.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C3.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C4.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C5.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C6.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C7.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C8.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C9.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C10.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C11.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C12.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C13.dat
[21:38:34.920] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C14.dat
[21:38:34.921] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C15.dat
[21:38:34.967] INFO: PixTestPattern:: pg_setup set to default.
[21:38:34.968] INFO: PixTestReadback::RES sent once
[21:38:51.926] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C0.dat
[21:38:51.926] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C1.dat
[21:38:51.926] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C2.dat
[21:38:51.926] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C3.dat
[21:38:51.927] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C4.dat
[21:38:51.927] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C5.dat
[21:38:51.927] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C6.dat
[21:38:51.927] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C7.dat
[21:38:51.927] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C8.dat
[21:38:51.927] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C9.dat
[21:38:51.927] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C10.dat
[21:38:51.927] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C11.dat
[21:38:51.927] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C12.dat
[21:38:51.928] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C13.dat
[21:38:51.928] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C14.dat
[21:38:51.928] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C15.dat
[21:38:51.977] INFO: PixTestPattern:: pg_setup set to default.
[21:38:51.978] INFO: PixTestReadback::RES sent once
[21:39:05.342] INFO: PixTestPattern:: pg_setup set to default.
[21:39:05.342] INFO: Vbg will be calibrated using Vd calibration
[21:39:05.342] INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.9calibrated Vbg = 1.21397 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157.6calibrated Vbg = 1.22248 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148.8calibrated Vbg = 1.22096 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.7calibrated Vbg = 1.23132 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.5calibrated Vbg = 1.22715 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 161.8calibrated Vbg = 1.22731 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 152.6calibrated Vbg = 1.23281 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 159.9calibrated Vbg = 1.227 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 161.2calibrated Vbg = 1.23228 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 143calibrated Vbg = 1.23097 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 148.7calibrated Vbg = 1.22552 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 155.7calibrated Vbg = 1.22512 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 158.9calibrated Vbg = 1.21667 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 159calibrated Vbg = 1.21565 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.2calibrated Vbg = 1.21882 :::*/*/*/*/
[21:39:05.342] INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 149.4calibrated Vbg = 1.22192 :::*/*/*/*/
[21:39:05.345] INFO: PixTestReadback::RES sent once
[21:43:06.138] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C0.dat
[21:43:06.138] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C1.dat
[21:43:06.138] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C2.dat
[21:43:06.138] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C3.dat
[21:43:06.138] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C4.dat
[21:43:06.138] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C5.dat
[21:43:06.138] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C6.dat
[21:43:06.138] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C7.dat
[21:43:06.139] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C8.dat
[21:43:06.139] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C9.dat
[21:43:06.139] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C10.dat
[21:43:06.139] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C11.dat
[21:43:06.139] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C12.dat
[21:43:06.139] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C13.dat
[21:43:06.139] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C14.dat
[21:43:06.139] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4562_FullQualification_2015-08-04_11h20m_1438683615/004_Fulltest_p17/readbackCal_C15.dat
[21:43:06.190] INFO: PixTestPattern:: pg_setup set to default.
[21:43:06.194] INFO: PixTestReadback::doTest() done
[21:43:06.213] INFO: enter test to run
[21:43:06.213] INFO: test: q no parameter change
[21:43:06.363] QUIET: Connection to board 175 closed.
[21:43:06.366] INFO: pXar: this is the end, my friend