[12:56:57.644] INFO: *** Welcome to pxar ***
[12:56:57.644] INFO: *** Today: 2015/08/10
[12:56:57.644] INFO: readRocDacs: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C15.dat
[12:56:57.645] INFO: readTbmDacs: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/tbmParameters_C0a.dat .. /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/tbmParameters_C0b.dat
[12:56:57.645] INFO: readMaskFile: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/defaultMaskFile.dat
[12:56:57.645] INFO: readTrimFile: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters_C15.dat
[12:56:57.744] INFO: clk: 4
[12:56:57.744] INFO: ctr: 4
[12:56:57.744] INFO: sda: 19
[12:56:57.744] INFO: tin: 9
[12:56:57.744] INFO: level: 15
[12:56:57.744] INFO: triggerdelay: 0
[12:56:57.744] QUIET: Instanciating API for pxar prod-01+36~geb5ac3f
[12:56:57.744] INFO: Log level: INFO
[12:56:57.756] INFO: Found DTB DTB_WZ4Y8V
[12:56:57.769] QUIET: Connection to board DTB_WZ4Y8V opened.
[12:56:57.772] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 176
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WZ4Y8V
MAC address: 40D8551180B0
Hostname: pixelDTB176
Comment:
------------------------------------------------------
[12:56:57.775] INFO: RPC call hashes of host and DTB match: 447413373
[12:56:59.516] INFO: DUT info:
[12:56:59.516] INFO: The DUT currently contains the following objects:
[12:56:59.516] INFO: 2 TBM Cores tbm08c (2 ON)
[12:56:59.516] INFO: TBM Core alpha (0): 7 registers set
[12:56:59.516] INFO: TBM Core beta (1): 7 registers set
[12:56:59.516] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:56:59.516] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.516] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.516] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.516] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.517] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:59.918] INFO: enter 'restricted' command line mode
[12:56:59.918] INFO: enter test to run
[12:56:59.918] INFO: test: pretest no parameter change
[12:56:59.918] INFO: running: pretest
[12:56:59.926] INFO: ######################################################################
[12:56:59.926] INFO: PixTestPretest::doTest()
[12:56:59.926] INFO: ######################################################################
[12:56:59.928] INFO: ----------------------------------------------------------------------
[12:56:59.928] INFO: PixTestPretest::programROC()
[12:56:59.928] INFO: ----------------------------------------------------------------------
[12:57:17.947] INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:57:17.947] INFO: IA differences per ROC: 18.5 20.9 19.3 18.5 18.5 18.5 20.9 19.3 17.7 20.1 18.5 16.1 19.3 20.1 17.7 20.1
[12:57:18.030] INFO: ----------------------------------------------------------------------
[12:57:18.030] INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:57:18.030] INFO: ----------------------------------------------------------------------
[12:57:24.051] INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[12:57:24.055] INFO: ----------------------------------------------------------------------
[12:57:24.055] INFO: PixTestPretest::findWorkingPixel()
[12:57:24.055] INFO: ----------------------------------------------------------------------
[12:57:31.607] INFO: Test took 7545ms.
[12:57:31.922] INFO: Found working pixel in all ROCs: col/row = 12/22
[12:57:31.974] INFO: ----------------------------------------------------------------------
[12:57:31.974] INFO: PixTestPretest::setVthrCompCalDel()
[12:57:31.974] INFO: ----------------------------------------------------------------------
[12:57:39.449] INFO: Test took 7469ms.
[12:57:39.777] INFO: PixTestPretest::setVthrCompCalDel() done
[12:57:39.777] INFO: CalDel: 118 112 119 113 125 143 141 142 124 114 102 122 136 131 105 137
[12:57:39.777] INFO: VthrComp: 51 51 51 54 51 51 51 51 51 51 51 51 51 51 51 51
[12:57:39.780] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C0.dat
[12:57:39.780] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C1.dat
[12:57:39.780] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C2.dat
[12:57:39.781] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C3.dat
[12:57:39.781] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C4.dat
[12:57:39.781] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C5.dat
[12:57:39.781] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C6.dat
[12:57:39.781] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C7.dat
[12:57:39.781] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C8.dat
[12:57:39.781] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C9.dat
[12:57:39.782] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C10.dat
[12:57:39.782] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C11.dat
[12:57:39.782] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C12.dat
[12:57:39.782] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C13.dat
[12:57:39.782] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C14.dat
[12:57:39.782] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters_C15.dat
[12:57:39.782] INFO: PixTestPretest::doTest() done, duration: 39 seconds
[12:57:39.867] INFO: enter test to run
[12:57:39.867] INFO: test: fulltest no parameter change
[12:57:39.867] INFO: running: fulltest
[12:57:39.867] INFO: ######################################################################
[12:57:39.867] INFO: PixTestFullTest::doTest()
[12:57:39.867] INFO: ######################################################################
[12:57:39.868] INFO: ######################################################################
[12:57:39.868] INFO: PixTestAlive::doTest()
[12:57:39.868] INFO: ######################################################################
[12:57:39.870] INFO: ----------------------------------------------------------------------
[12:57:39.870] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:57:39.870] INFO: ----------------------------------------------------------------------
[12:57:43.416] INFO: Test took 3544ms.
[12:57:43.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:43.687] INFO: PixTestAlive::aliveTest() done
[12:57:43.687] INFO: number of dead pixels (per ROC): 2 0 0 0 1 0 0 0 1 1 0 0 0 0 0 0
[12:57:43.690] INFO: ----------------------------------------------------------------------
[12:57:43.690] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:57:43.690] INFO: ----------------------------------------------------------------------
[12:57:46.483] INFO: Test took 2791ms.
[12:57:46.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:46.489] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:57:46.727] INFO: PixTestAlive::maskTest() done
[12:57:46.727] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:57:46.730] INFO: ----------------------------------------------------------------------
[12:57:46.730] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:57:46.730] INFO: ----------------------------------------------------------------------
[12:57:50.305] INFO: Test took 3573ms.
[12:57:50.340] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:50.576] INFO: PixTestAlive::addressDecodingTest() done
[12:57:50.576] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:57:50.576] INFO: PixTestAlive::doTest() done, duration: 10 seconds
[12:57:50.588] INFO: ######################################################################
[12:57:50.588] INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:57:50.588] INFO: ######################################################################
[12:57:50.592] INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (30/5) hits flags = 2 (plus default)
[12:57:50.613] INFO: dacScan step from 0 .. 29
[12:57:50.613] INFO: dacScan split into 1 runs with ntrig = 5
[12:57:50.613] INFO: run 1 of 1
[12:58:10.352] INFO: Test took 19739ms.
[12:58:10.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:10.410] INFO: dacScan step from 30 .. 59
[12:58:10.410] INFO: dacScan split into 1 runs with ntrig = 5
[12:58:10.410] INFO: run 1 of 1
[12:58:30.289] INFO: Test took 19879ms.
[12:58:30.375] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:30.380] INFO: dacScan step from 60 .. 89
[12:58:30.380] INFO: dacScan split into 1 runs with ntrig = 5
[12:58:30.380] INFO: run 1 of 1
[12:58:55.618] INFO: Test took 25238ms.
[12:58:55.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:56.050] INFO: dacScan step from 90 .. 119
[12:58:56.050] INFO: dacScan split into 1 runs with ntrig = 5
[12:58:56.050] INFO: run 1 of 1
[12:59:23.648] INFO: Test took 27598ms.
[12:59:24.053] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:24.151] INFO: dacScan step from 120 .. 149
[12:59:24.152] INFO: dacScan split into 1 runs with ntrig = 5
[12:59:24.152] INFO: run 1 of 1
[12:59:48.581] INFO: Test took 24429ms.
[12:59:48.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:17.697] INFO: PixTestBBMap::doTest() done, duration: 147 seconds
[13:00:17.697] INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[13:00:17.697] INFO: separation cut (per ROC): 118 125 107 115 106 113 106 102 107 109 104 98 116 117 113 116
[13:00:17.781] INFO: ######################################################################
[13:00:17.781] INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = 1
[13:00:17.781] INFO: ######################################################################
[13:00:17.781] INFO: ----------------------------------------------------------------------
[13:00:17.781] INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = 1
[13:00:17.781] INFO: ----------------------------------------------------------------------
[13:00:17.781] INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 0 .. 149 (-1/1) hits flags = 16 (plus default)
[13:00:17.794] INFO: dacScan split into 50 runs with ntrig = 1
[13:00:17.794] INFO: run 1 of 50
[13:01:06.915] INFO: Test took 49120ms.
[13:01:07.273] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:07.452] INFO: run 2 of 50
[13:01:56.570] INFO: Test took 49118ms.
[13:01:56.930] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:57.104] INFO: run 3 of 50
[13:02:46.209] INFO: Test took 49104ms.
[13:02:46.569] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:46.746] INFO: run 4 of 50
[13:03:35.887] INFO: Test took 49141ms.
[13:03:36.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:36.426] INFO: run 5 of 50
[13:04:25.513] INFO: Test took 49087ms.
[13:04:25.871] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:26.044] INFO: run 6 of 50
[13:05:15.180] INFO: Test took 49135ms.
[13:05:15.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:15.712] INFO: run 7 of 50
[13:06:04.851] INFO: Test took 49139ms.
[13:06:05.209] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:05.384] INFO: run 8 of 50
[13:06:54.548] INFO: Test took 49164ms.
[13:06:54.898] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:55.071] INFO: run 9 of 50
[13:07:44.220] INFO: Test took 49149ms.
[13:07:44.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:44.754] INFO: run 10 of 50
[13:08:33.901] INFO: Test took 49147ms.
[13:08:34.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:34.431] INFO: run 11 of 50
[13:09:23.568] INFO: Test took 49137ms.
[13:09:23.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:24.107] INFO: run 12 of 50
[13:10:13.174] INFO: Test took 49067ms.
[13:10:13.542] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:13.715] INFO: run 13 of 50
[13:11:02.752] INFO: Test took 49037ms.
[13:11:03.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:03.301] INFO: run 14 of 50
[13:11:52.444] INFO: Test took 49143ms.
[13:11:52.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:52.971] INFO: run 15 of 50
[13:12:42.164] INFO: Test took 49193ms.
[13:12:42.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:42.708] INFO: run 16 of 50
[13:13:31.830] INFO: Test took 49122ms.
[13:13:32.189] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:32.361] INFO: run 17 of 50
[13:14:21.575] INFO: Test took 49214ms.
[13:14:21.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:22.114] INFO: run 18 of 50
[13:15:11.410] INFO: Test took 49296ms.
[13:15:11.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:11.944] INFO: run 19 of 50
[13:16:01.184] INFO: Test took 49240ms.
[13:16:01.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:01.715] INFO: run 20 of 50
[13:16:50.985] INFO: Test took 49270ms.
[13:16:51.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:51.522] INFO: run 21 of 50
[13:17:40.785] INFO: Test took 49263ms.
[13:17:41.152] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:41.328] INFO: run 22 of 50
[13:18:30.628] INFO: Test took 49300ms.
[13:18:30.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:31.160] INFO: run 23 of 50
[13:19:20.429] INFO: Test took 49269ms.
[13:19:20.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:20.956] INFO: run 24 of 50
[13:20:10.280] INFO: Test took 49324ms.
[13:20:10.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:10.811] INFO: run 25 of 50
[13:21:00.109] INFO: Test took 49298ms.
[13:21:00.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:00.649] INFO: run 26 of 50
[13:21:49.915] INFO: Test took 49265ms.
[13:21:50.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:50.453] INFO: run 27 of 50
[13:22:39.671] INFO: Test took 49218ms.
[13:22:40.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:40.212] INFO: run 28 of 50
[13:23:29.367] INFO: Test took 49155ms.
[13:23:29.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:29.898] INFO: run 29 of 50
[13:24:19.215] INFO: Test took 49317ms.
[13:24:19.573] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:19.748] INFO: run 30 of 50
[13:25:09.078] INFO: Test took 49330ms.
[13:25:09.453] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:09.627] INFO: run 31 of 50
[13:25:58.884] INFO: Test took 49257ms.
[13:25:59.243] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:59.423] INFO: run 32 of 50
[13:26:48.577] INFO: Test took 49154ms.
[13:26:48.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:49.117] INFO: run 33 of 50
[13:27:38.214] INFO: Test took 49097ms.
[13:27:38.571] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:38.748] INFO: run 34 of 50
[13:28:27.893] INFO: Test took 49145ms.
[13:28:28.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:28.430] INFO: run 35 of 50
[13:29:17.660] INFO: Test took 49230ms.
[13:29:18.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:18.209] INFO: run 36 of 50
[13:30:07.506] INFO: Test took 49297ms.
[13:30:07.865] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:08.039] INFO: run 37 of 50
[13:30:57.310] INFO: Test took 49271ms.
[13:30:57.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:57.846] INFO: run 38 of 50
[13:31:47.140] INFO: Test took 49294ms.
[13:31:47.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:47.664] INFO: run 39 of 50
[13:32:36.880] INFO: Test took 49216ms.
[13:32:37.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:37.407] INFO: run 40 of 50
[13:33:26.683] INFO: Test took 49275ms.
[13:33:27.035] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:27.208] INFO: run 41 of 50
[13:34:16.536] INFO: Test took 49327ms.
[13:34:16.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:17.066] INFO: run 42 of 50
[13:35:06.340] INFO: Test took 49274ms.
[13:35:06.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:06.870] INFO: run 43 of 50
[13:35:56.245] INFO: Test took 49375ms.
[13:35:56.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:56.777] INFO: run 44 of 50
[13:36:46.072] INFO: Test took 49295ms.
[13:36:46.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:46.598] INFO: run 45 of 50
[13:37:35.885] INFO: Test took 49287ms.
[13:37:36.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:36.419] INFO: run 46 of 50
[13:38:25.797] INFO: Test took 49378ms.
[13:38:26.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:26.341] INFO: run 47 of 50
[13:39:15.585] INFO: Test took 49244ms.
[13:39:15.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:16.114] INFO: run 48 of 50
[13:40:05.430] INFO: Test took 49316ms.
[13:40:05.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:05.962] INFO: run 49 of 50
[13:40:55.160] INFO: Test took 49198ms.
[13:40:55.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:55.694] INFO: run 50 of 50
[13:41:44.682] INFO: Test took 48988ms.
[13:41:45.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:45.218] INFO: dumping ASCII scurve output file: SCurveData
[13:41:46.971] INFO: dumping ASCII scurve output file: SCurveData
[13:41:48.708] INFO: dumping ASCII scurve output file: SCurveData
[13:41:50.470] INFO: dumping ASCII scurve output file: SCurveData
[13:41:52.208] INFO: dumping ASCII scurve output file: SCurveData
[13:41:53.955] INFO: dumping ASCII scurve output file: SCurveData
[13:41:55.705] INFO: dumping ASCII scurve output file: SCurveData
[13:41:57.494] INFO: dumping ASCII scurve output file: SCurveData
[13:41:59.273] INFO: dumping ASCII scurve output file: SCurveData
[13:42:01.018] INFO: dumping ASCII scurve output file: SCurveData
[13:42:02.779] INFO: dumping ASCII scurve output file: SCurveData
[13:42:04.547] INFO: dumping ASCII scurve output file: SCurveData
[13:42:06.333] INFO: dumping ASCII scurve output file: SCurveData
[13:42:08.258] INFO: dumping ASCII scurve output file: SCurveData
[13:42:10.169] INFO: dumping ASCII scurve output file: SCurveData
[13:42:12.068] INFO: dumping ASCII scurve output file: SCurveData
[13:42:14.082] INFO: PixTestScurves::scurves() done
[13:42:14.082] INFO: Vcal mean: 102.12 106.66 87.57 106.79 93.01 106.09 88.36 90.23 100.44 85.10 87.91 95.39 103.27 100.99 95.22 72.82
[13:42:14.082] INFO: Vcal RMS: 6.04 5.97 5.21 5.65 6.17 5.99 5.16 5.89 5.76 5.12 5.22 5.75 6.36 5.84 5.38 4.63
[13:42:14.082] INFO: PixTestScurves::fullTest() done, duration: 2516 seconds
[13:42:14.178] INFO: ######################################################################
[13:42:14.178] INFO: PixTestTrim::doTest()
[13:42:14.178] INFO: ######################################################################
[13:42:14.180] INFO: ----------------------------------------------------------------------
[13:42:14.180] INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:42:14.180] INFO: ----------------------------------------------------------------------
[13:42:14.309] INFO: ---> VthrComp thr map (minimal VthrComp)
[13:42:14.309] INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[13:42:14.323] INFO: dacScan step from 0 .. 19
[13:42:14.323] INFO: dacScan split into 1 runs with ntrig = 5
[13:42:14.323] INFO: run 1 of 1
[13:42:28.318] INFO: Test took 13995ms.
[13:42:28.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:28.358] INFO: dacScan step from 20 .. 39
[13:42:28.358] INFO: dacScan split into 1 runs with ntrig = 5
[13:42:28.358] INFO: run 1 of 1
[13:42:42.258] INFO: Test took 13900ms.
[13:42:42.299] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:42.299] INFO: dacScan step from 40 .. 59
[13:42:42.299] INFO: dacScan split into 1 runs with ntrig = 5
[13:42:42.299] INFO: run 1 of 1
[13:42:56.274] INFO: Test took 13975ms.
[13:42:56.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:56.311] INFO: dacScan step from 60 .. 79
[13:42:56.311] INFO: dacScan split into 1 runs with ntrig = 5
[13:42:56.311] INFO: run 1 of 1
[13:43:10.333] INFO: Test took 14022ms.
[13:43:10.374] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:10.374] INFO: dacScan step from 80 .. 99
[13:43:10.374] INFO: dacScan split into 1 runs with ntrig = 5
[13:43:10.374] INFO: run 1 of 1
[13:43:24.624] INFO: Test took 14250ms.
[13:43:24.707] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:24.716] INFO: dacScan step from 100 .. 119
[13:43:24.716] INFO: dacScan split into 1 runs with ntrig = 5
[13:43:24.716] INFO: run 1 of 1
[13:43:41.394] INFO: Test took 16678ms.
[13:43:41.598] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:41.644] INFO: dacScan step from 120 .. 139
[13:43:41.644] INFO: dacScan split into 1 runs with ntrig = 5
[13:43:41.644] INFO: run 1 of 1
[13:43:59.428] INFO: Test took 17784ms.
[13:43:59.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:59.722] INFO: dacScan step from 140 .. 159
[13:43:59.722] INFO: dacScan split into 1 runs with ntrig = 5
[13:43:59.722] INFO: run 1 of 1
[13:44:14.712] INFO: Test took 14990ms.
[13:44:14.834] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:47.276] INFO: ROC 0 VthrComp = 103
[13:44:47.276] INFO: ROC 1 VthrComp = 107
[13:44:47.276] INFO: ROC 2 VthrComp = 92
[13:44:47.276] INFO: ROC 3 VthrComp = 106
[13:44:47.276] INFO: ROC 4 VthrComp = 96
[13:44:47.277] INFO: ROC 5 VthrComp = 102
[13:44:47.277] INFO: ROC 6 VthrComp = 93
[13:44:47.277] INFO: ROC 7 VthrComp = 94
[13:44:47.277] INFO: ROC 8 VthrComp = 100
[13:44:47.277] INFO: ROC 9 VthrComp = 90
[13:44:47.277] INFO: ROC 10 VthrComp = 91
[13:44:47.277] INFO: ROC 11 VthrComp = 93
[13:44:47.277] INFO: ROC 12 VthrComp = 100
[13:44:47.277] INFO: ROC 13 VthrComp = 102
[13:44:47.277] INFO: ROC 14 VthrComp = 97
[13:44:47.278] INFO: ROC 15 VthrComp = 76
[13:44:47.278] INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:44:47.278] INFO: ---> dac: vcal name: TrimThr1 ntrig: 5 dacrange: 0 .. 159 (20/-1) hits flags = 16 (plus default)
[13:44:47.292] INFO: dacScan step from 0 .. 19
[13:44:47.293] INFO: dacScan split into 1 runs with ntrig = 5
[13:44:47.293] INFO: run 1 of 1
[13:45:01.465] INFO: Test took 14172ms.
[13:45:01.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:01.505] INFO: dacScan step from 20 .. 39
[13:45:01.505] INFO: dacScan split into 1 runs with ntrig = 5
[13:45:01.505] INFO: run 1 of 1
[13:45:15.767] INFO: Test took 14261ms.
[13:45:15.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:15.831] INFO: dacScan step from 40 .. 59
[13:45:15.831] INFO: dacScan split into 1 runs with ntrig = 5
[13:45:15.831] INFO: run 1 of 1
[13:45:33.336] INFO: Test took 17505ms.
[13:45:33.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:33.623] INFO: dacScan step from 60 .. 79
[13:45:33.623] INFO: dacScan split into 1 runs with ntrig = 5
[13:45:33.623] INFO: run 1 of 1
[13:45:52.942] INFO: Test took 19319ms.
[13:45:53.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:53.281] INFO: dacScan step from 80 .. 99
[13:45:53.281] INFO: dacScan split into 1 runs with ntrig = 5
[13:45:53.281] INFO: run 1 of 1
[13:46:12.622] INFO: Test took 19341ms.
[13:46:12.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:12.962] INFO: dacScan step from 100 .. 119
[13:46:12.962] INFO: dacScan split into 1 runs with ntrig = 5
[13:46:12.962] INFO: run 1 of 1
[13:46:32.408] INFO: Test took 19446ms.
[13:46:32.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:32.736] INFO: dacScan step from 120 .. 139
[13:46:32.736] INFO: dacScan split into 1 runs with ntrig = 5
[13:46:32.736] INFO: run 1 of 1
[13:46:51.853] INFO: Test took 19118ms.
[13:46:52.121] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:52.188] INFO: dacScan step from 140 .. 159
[13:46:52.188] INFO: dacScan split into 1 runs with ntrig = 5
[13:46:52.188] INFO: run 1 of 1
[13:47:11.325] INFO: Test took 19137ms.
[13:47:11.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:50.477] INFO: roc 0 with ID = 0 has maximal Vcal 59.4204 for pixel 51/20 mean/min/max = 45.7848/32.0866/59.4831
[13:47:50.478] INFO: roc 1 with ID = 1 has maximal Vcal 62.1494 for pixel 18/61 mean/min/max = 47.5688/32.8697/62.2679
[13:47:50.479] INFO: roc 2 with ID = 2 has maximal Vcal 58.7417 for pixel 17/62 mean/min/max = 45.8438/32.9355/58.7521
[13:47:50.480] INFO: roc 3 with ID = 3 has maximal Vcal 63.6376 for pixel 23/3 mean/min/max = 48.4848/33.3217/63.648
[13:47:50.481] INFO: roc 4 with ID = 4 has maximal Vcal 59.4456 for pixel 23/10 mean/min/max = 45.6044/31.5325/59.6762
[13:47:50.482] INFO: roc 5 with ID = 5 has maximal Vcal 63.3935 for pixel 0/42 mean/min/max = 47.7294/32.0518/63.4071
[13:47:50.483] INFO: roc 6 with ID = 6 has maximal Vcal 57.1929 for pixel 18/73 mean/min/max = 44.7563/32.1803/57.3324
[13:47:50.484] INFO: roc 7 with ID = 7 has maximal Vcal 57.9906 for pixel 3/77 mean/min/max = 45.2316/32.3236/58.1396
[13:47:50.485] INFO: roc 8 with ID = 8 has maximal Vcal 59.7508 for pixel 0/18 mean/min/max = 46.0117/32.1947/59.8287
[13:47:50.486] INFO: roc 9 with ID = 9 has maximal Vcal 58.4358 for pixel 7/0 mean/min/max = 45.8295/33.0682/58.5909
[13:47:50.487] INFO: roc 10 with ID = 10 has maximal Vcal 58.4457 for pixel 15/5 mean/min/max = 45.4201/32.3649/58.4752
[13:47:50.487] INFO: roc 11 with ID = 11 has maximal Vcal 60.9731 for pixel 5/38 mean/min/max = 46.3335/31.6695/60.9974
[13:47:50.488] INFO: roc 12 with ID = 12 has maximal Vcal 64.206 for pixel 0/15 mean/min/max = 47.6261/31.0341/64.2182
[13:47:50.489] INFO: roc 13 with ID = 13 has maximal Vcal 59.6841 for pixel 1/69 mean/min/max = 46.0069/32.2866/59.7271
[13:47:50.490] INFO: roc 14 with ID = 14 has maximal Vcal 58.101 for pixel 48/38 mean/min/max = 44.8271/31.4789/58.1753
[13:47:50.491] INFO: roc 15 with ID = 15 has maximal Vcal 57.6841 for pixel 2/8 mean/min/max = 46.5493/35.3578/57.7408
[13:47:50.492] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:11.350] INFO: Test took 80859ms.
[13:49:13.232] INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[13:49:13.251] INFO: dacScan step from 0 .. 19
[13:49:13.251] INFO: dacScan split into 2 runs with ntrig = 5
[13:49:13.251] INFO: run 1 of 2
[13:49:27.331] INFO: Test took 14080ms.
[13:49:27.377] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:27.379] INFO: run 2 of 2
[13:49:41.517] INFO: Test took 14138ms.
[13:49:41.566] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:41.567] INFO: dacScan step from 20 .. 39
[13:49:41.567] INFO: dacScan split into 2 runs with ntrig = 5
[13:49:41.567] INFO: run 1 of 2
[13:49:58.201] INFO: Test took 16633ms.
[13:49:58.403] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:58.454] INFO: run 2 of 2
[13:50:14.976] INFO: Test took 16522ms.
[13:50:15.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:15.223] INFO: dacScan step from 40 .. 59
[13:50:15.223] INFO: dacScan split into 2 runs with ntrig = 5
[13:50:15.223] INFO: run 1 of 2
[13:50:34.605] INFO: Test took 19382ms.
[13:50:34.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:34.954] INFO: run 2 of 2
[13:50:54.377] INFO: Test took 19423ms.
[13:50:54.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:54.717] INFO: dacScan step from 60 .. 79
[13:50:54.717] INFO: dacScan split into 2 runs with ntrig = 5
[13:50:54.717] INFO: run 1 of 2
[13:51:14.003] INFO: Test took 19286ms.
[13:51:14.271] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:14.339] INFO: run 2 of 2
[13:51:33.557] INFO: Test took 19218ms.
[13:51:33.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:33.932] INFO: dacScan step from 80 .. 99
[13:51:33.932] INFO: dacScan split into 2 runs with ntrig = 5
[13:51:33.932] INFO: run 1 of 2
[13:51:53.446] INFO: Test took 19514ms.
[13:51:53.716] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:53.818] INFO: run 2 of 2
[13:52:13.096] INFO: Test took 19277ms.
[13:52:13.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:13.432] INFO: dacScan step from 100 .. 119
[13:52:13.432] INFO: dacScan split into 2 runs with ntrig = 5
[13:52:13.432] INFO: run 1 of 2
[13:52:32.799] INFO: Test took 19367ms.
[13:52:33.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:33.127] INFO: run 2 of 2
[13:52:52.543] INFO: Test took 19415ms.
[13:52:52.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:52.878] INFO: dacScan step from 120 .. 139
[13:52:52.878] INFO: dacScan split into 2 runs with ntrig = 5
[13:52:52.878] INFO: run 1 of 2
[13:53:12.374] INFO: Test took 19496ms.
[13:53:12.646] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:12.718] INFO: run 2 of 2
[13:53:32.151] INFO: Test took 19433ms.
[13:53:32.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:32.490] INFO: dacScan step from 140 .. 159
[13:53:32.490] INFO: dacScan split into 2 runs with ntrig = 5
[13:53:32.490] INFO: run 1 of 2
[13:53:51.903] INFO: Test took 19413ms.
[13:53:52.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:52.250] INFO: run 2 of 2
[13:54:11.712] INFO: Test took 19462ms.
[13:54:11.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:12.053] INFO: dacScan step from 160 .. 179
[13:54:12.053] INFO: dacScan split into 2 runs with ntrig = 5
[13:54:12.053] INFO: run 1 of 2
[13:54:31.636] INFO: Test took 19583ms.
[13:54:31.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:31.969] INFO: run 2 of 2
[13:54:51.569] INFO: Test took 19600ms.
[13:54:51.838] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:51.907] INFO: dacScan step from 180 .. 199
[13:54:51.907] INFO: dacScan split into 2 runs with ntrig = 5
[13:54:51.907] INFO: run 1 of 2
[13:55:12.260] INFO: Test took 20353ms.
[13:55:12.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:12.646] INFO: run 2 of 2
[13:55:33.023] INFO: Test took 20377ms.
[13:55:33.302] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:11.535] INFO: ---> TrimStepCorr4 extremal thresholds: 0.029879 .. 255.000000
[13:56:11.670] INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 4 dacrange: 0 .. 255 (20/-1) hits flags = 16 (plus default)
[13:56:11.684] INFO: dacScan step from 0 .. 19
[13:56:11.684] INFO: dacScan split into 1 runs with ntrig = 4
[13:56:11.684] INFO: run 1 of 1
[13:56:24.382] INFO: Test took 12698ms.
[13:56:24.424] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:24.424] INFO: dacScan step from 20 .. 39
[13:56:24.424] INFO: dacScan split into 1 runs with ntrig = 4
[13:56:24.424] INFO: run 1 of 1
[13:56:38.106] INFO: Test took 13681ms.
[13:56:38.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:38.250] INFO: dacScan step from 40 .. 59
[13:56:38.250] INFO: dacScan split into 1 runs with ntrig = 4
[13:56:38.250] INFO: run 1 of 1
[13:56:55.030] INFO: Test took 16779ms.
[13:56:55.273] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:55.343] INFO: dacScan step from 60 .. 79
[13:56:55.343] INFO: dacScan split into 1 runs with ntrig = 4
[13:56:55.343] INFO: run 1 of 1
[13:57:12.279] INFO: Test took 16936ms.
[13:57:12.509] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:12.577] INFO: dacScan step from 80 .. 99
[13:57:12.577] INFO: dacScan split into 1 runs with ntrig = 4
[13:57:12.577] INFO: run 1 of 1
[13:57:29.534] INFO: Test took 16957ms.
[13:57:29.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:29.826] INFO: dacScan step from 100 .. 119
[13:57:29.826] INFO: dacScan split into 1 runs with ntrig = 4
[13:57:29.826] INFO: run 1 of 1
[13:57:46.759] INFO: Test took 16933ms.
[13:57:46.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:47.060] INFO: dacScan step from 120 .. 139
[13:57:47.060] INFO: dacScan split into 1 runs with ntrig = 4
[13:57:47.060] INFO: run 1 of 1
[13:58:04.009] INFO: Test took 16949ms.
[13:58:04.248] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:04.317] INFO: dacScan step from 140 .. 159
[13:58:04.317] INFO: dacScan split into 1 runs with ntrig = 4
[13:58:04.317] INFO: run 1 of 1
[13:58:21.307] INFO: Test took 16990ms.
[13:58:21.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:21.612] INFO: dacScan step from 160 .. 179
[13:58:21.612] INFO: dacScan split into 1 runs with ntrig = 4
[13:58:21.612] INFO: run 1 of 1
[13:58:38.801] INFO: Test took 17189ms.
[13:58:39.031] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:39.099] INFO: dacScan step from 180 .. 199
[13:58:39.099] INFO: dacScan split into 1 runs with ntrig = 4
[13:58:39.099] INFO: run 1 of 1
[13:58:56.926] INFO: Test took 17827ms.
[13:58:57.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:57.272] INFO: dacScan step from 200 .. 219
[13:58:57.272] INFO: dacScan split into 1 runs with ntrig = 4
[13:58:57.272] INFO: run 1 of 1
[13:59:14.237] INFO: Test took 16965ms.
[13:59:14.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:14.592] INFO: dacScan step from 220 .. 239
[13:59:14.592] INFO: dacScan split into 1 runs with ntrig = 4
[13:59:14.592] INFO: run 1 of 1
[13:59:31.443] INFO: Test took 16851ms.
[13:59:31.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:31.806] INFO: dacScan step from 240 .. 255
[13:59:31.806] INFO: dacScan split into 1 runs with ntrig = 4
[13:59:31.806] INFO: run 1 of 1
[13:59:45.876] INFO: Test took 14070ms.
[13:59:46.101] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:33.732] INFO: ---> TrimStepCorr2 extremal thresholds: 12.320451 .. 107.000955
[14:00:33.874] INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 4 dacrange: 2 .. 117 (20/-1) hits flags = 16 (plus default)
[14:00:33.889] INFO: dacScan step from 2 .. 21
[14:00:33.889] INFO: dacScan split into 1 runs with ntrig = 4
[14:00:33.889] INFO: run 1 of 1
[14:00:46.686] INFO: Test took 12796ms.
[14:00:46.728] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:46.728] INFO: dacScan step from 22 .. 41
[14:00:46.728] INFO: dacScan split into 1 runs with ntrig = 4
[14:00:46.728] INFO: run 1 of 1
[14:01:00.871] INFO: Test took 14143ms.
[14:01:01.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:01.080] INFO: dacScan step from 42 .. 61
[14:01:01.080] INFO: dacScan split into 1 runs with ntrig = 4
[14:01:01.080] INFO: run 1 of 1
[14:01:18.104] INFO: Test took 17024ms.
[14:01:18.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:18.435] INFO: dacScan step from 62 .. 81
[14:01:18.435] INFO: dacScan split into 1 runs with ntrig = 4
[14:01:18.435] INFO: run 1 of 1
[14:01:35.374] INFO: Test took 16939ms.
[14:01:35.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:35.718] INFO: dacScan step from 82 .. 101
[14:01:35.718] INFO: dacScan split into 1 runs with ntrig = 4
[14:01:35.719] INFO: run 1 of 1
[14:01:52.838] INFO: Test took 17119ms.
[14:01:53.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:53.149] INFO: dacScan step from 102 .. 117
[14:01:53.149] INFO: dacScan split into 1 runs with ntrig = 4
[14:01:53.149] INFO: run 1 of 1
[14:02:07.328] INFO: Test took 14179ms.
[14:02:07.523] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:42.100] INFO: ---> TrimStepCorr1a extremal thresholds: 16.894749 .. 75.769693
[14:02:42.241] INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 4 dacrange: 6 .. 85 (20/-1) hits flags = 16 (plus default)
[14:02:42.256] INFO: dacScan step from 6 .. 25
[14:02:42.256] INFO: dacScan split into 1 runs with ntrig = 4
[14:02:42.256] INFO: run 1 of 1
[14:02:55.118] INFO: Test took 12862ms.
[14:02:55.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:55.158] INFO: dacScan step from 26 .. 45
[14:02:55.158] INFO: dacScan split into 1 runs with ntrig = 4
[14:02:55.158] INFO: run 1 of 1
[14:03:10.139] INFO: Test took 14981ms.
[14:03:10.346] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:10.401] INFO: dacScan step from 46 .. 65
[14:03:10.401] INFO: dacScan split into 1 runs with ntrig = 4
[14:03:10.401] INFO: run 1 of 1
[14:03:27.523] INFO: Test took 17123ms.
[14:03:27.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:27.893] INFO: dacScan step from 66 .. 85
[14:03:27.893] INFO: dacScan split into 1 runs with ntrig = 4
[14:03:27.893] INFO: run 1 of 1
[14:03:44.922] INFO: Test took 17029ms.
[14:03:45.206] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:15.402] INFO: ---> TrimStepCorr1b extremal thresholds: 7.369126 .. 41.938208
[14:04:15.553] INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 4 dacrange: 7 .. 51 (20/-1) hits flags = 16 (plus default)
[14:04:15.570] INFO: dacScan step from 7 .. 26
[14:04:15.570] INFO: dacScan split into 1 runs with ntrig = 4
[14:04:15.570] INFO: run 1 of 1
[14:04:28.310] INFO: Test took 12740ms.
[14:04:28.349] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:28.349] INFO: dacScan step from 27 .. 46
[14:04:28.349] INFO: dacScan split into 1 runs with ntrig = 4
[14:04:28.349] INFO: run 1 of 1
[14:04:43.561] INFO: Test took 15212ms.
[14:04:43.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:43.815] INFO: dacScan step from 47 .. 51
[14:04:43.815] INFO: dacScan split into 1 runs with ntrig = 4
[14:04:43.815] INFO: run 1 of 1
[14:04:50.521] INFO: Test took 6706ms.
[14:04:50.581] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:11.414] INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[14:05:11.414] INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (20/5) hits flags = 16 (plus default)
[14:05:11.429] INFO: dacScan step from 15 .. 34
[14:05:11.429] INFO: dacScan split into 2 runs with ntrig = 5
[14:05:11.429] INFO: run 1 of 2
[14:05:25.872] INFO: Test took 14443ms.
[14:05:25.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:25.971] INFO: run 2 of 2
[14:05:40.454] INFO: Test took 14483ms.
[14:05:40.530] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:40.546] INFO: dacScan step from 35 .. 54
[14:05:40.546] INFO: dacScan split into 2 runs with ntrig = 5
[14:05:40.546] INFO: run 1 of 2
[14:05:59.576] INFO: Test took 19030ms.
[14:05:59.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:59.972] INFO: run 2 of 2
[14:06:19.040] INFO: Test took 19068ms.
[14:06:19.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:19.455] INFO: dacScan step from 55 .. 55
[14:06:19.455] INFO: dacScan split into 2 runs with ntrig = 5
[14:06:19.455] INFO: run 1 of 2
[14:06:23.470] INFO: Test took 4015ms.
[14:06:23.490] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:23.500] INFO: run 2 of 2
[14:06:27.515] INFO: Test took 4015ms.
[14:06:27.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:51.880] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C0.dat
[14:06:51.880] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C1.dat
[14:06:51.880] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C2.dat
[14:06:51.880] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C3.dat
[14:06:51.880] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C4.dat
[14:06:51.880] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C5.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C6.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C7.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C8.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C9.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C10.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C11.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C12.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C13.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C14.dat
[14:06:51.881] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C15.dat
[14:06:51.882] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C0.dat
[14:06:51.896] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C1.dat
[14:06:51.910] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C2.dat
[14:06:51.923] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C3.dat
[14:06:51.936] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C4.dat
[14:06:51.950] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C5.dat
[14:06:51.963] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C6.dat
[14:06:51.977] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C7.dat
[14:06:51.990] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C8.dat
[14:06:52.003] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C9.dat
[14:06:52.017] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C10.dat
[14:06:52.030] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C11.dat
[14:06:52.044] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C12.dat
[14:06:52.057] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C13.dat
[14:06:52.072] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C14.dat
[14:06:52.086] INFO: write trim parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/trimParameters35_C15.dat
[14:06:52.099] INFO: PixTestTrim::trimTest() done
[14:06:52.099] INFO: vtrim: 108 122 106 120 118 118 94 99 112 104 100 115 113 107 106 109
[14:06:52.099] INFO: vthrcomp: 103 107 92 106 96 102 93 94 100 90 91 93 100 102 97 76
[14:06:52.099] INFO: vcal mean: 35.09 35.09 35.07 35.12 35.08 35.05 34.99 35.00 35.03 35.13 35.08 35.11 35.13 35.08 35.03 35.04
[14:06:52.099] INFO: vcal RMS: 1.35 1.09 1.07 1.13 1.27 1.29 1.09 1.10 1.09 1.20 1.05 1.16 1.16 1.11 1.08 1.00
[14:06:52.099] INFO: bits mean: 9.68 9.50 9.94 9.03 10.40 9.32 10.19 9.73 9.27 9.64 9.36 9.92 9.34 9.93 10.10 9.22
[14:06:52.099] INFO: bits RMS: 2.60 2.41 2.35 2.54 2.36 2.62 2.41 2.58 2.74 2.48 2.76 2.48 2.74 2.44 2.56 2.30
[14:06:52.110] INFO: ----------------------------------------------------------------------
[14:06:52.110] INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[14:06:52.110] INFO: ----------------------------------------------------------------------
[14:06:52.114] INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (20/5) hits flags = 16 (plus default)
[14:06:52.133] INFO: dacScan step from 0 .. 19
[14:06:52.133] INFO: dacScan split into 2 runs with ntrig = 5
[14:06:52.133] INFO: run 1 of 2
[14:07:06.160] INFO: Test took 14027ms.
[14:07:06.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:06.204] INFO: run 2 of 2
[14:07:20.181] INFO: Test took 13976ms.
[14:07:20.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:20.222] INFO: dacScan step from 20 .. 39
[14:07:20.222] INFO: dacScan split into 2 runs with ntrig = 5
[14:07:20.222] INFO: run 1 of 2
[14:07:34.172] INFO: Test took 13950ms.
[14:07:34.214] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:34.214] INFO: run 2 of 2
[14:07:48.220] INFO: Test took 14006ms.
[14:07:48.260] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:48.261] INFO: dacScan step from 40 .. 59
[14:07:48.261] INFO: dacScan split into 2 runs with ntrig = 5
[14:07:48.261] INFO: run 1 of 2
[14:08:02.214] INFO: Test took 13953ms.
[14:08:02.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:02.249] INFO: run 2 of 2
[14:08:16.164] INFO: Test took 13915ms.
[14:08:16.203] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:16.203] INFO: dacScan step from 60 .. 79
[14:08:16.203] INFO: dacScan split into 2 runs with ntrig = 5
[14:08:16.203] INFO: run 1 of 2
[14:08:30.201] INFO: Test took 13998ms.
[14:08:30.248] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:30.248] INFO: run 2 of 2
[14:08:44.368] INFO: Test took 14119ms.
[14:08:44.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:44.406] INFO: dacScan step from 80 .. 99
[14:08:44.406] INFO: dacScan split into 2 runs with ntrig = 5
[14:08:44.406] INFO: run 1 of 2
[14:08:58.543] INFO: Test took 14137ms.
[14:08:58.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:58.593] INFO: run 2 of 2
[14:09:12.701] INFO: Test took 14108ms.
[14:09:12.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:12.754] INFO: dacScan step from 100 .. 119
[14:09:12.754] INFO: dacScan split into 2 runs with ntrig = 5
[14:09:12.754] INFO: run 1 of 2
[14:09:27.807] INFO: Test took 15053ms.
[14:09:27.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:27.944] INFO: run 2 of 2
[14:09:43.071] INFO: Test took 15127ms.
[14:09:43.189] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:43.205] INFO: dacScan step from 120 .. 139
[14:09:43.205] INFO: dacScan split into 2 runs with ntrig = 5
[14:09:43.205] INFO: run 1 of 2
[14:10:01.153] INFO: Test took 17948ms.
[14:10:01.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:01.490] INFO: run 2 of 2
[14:10:19.336] INFO: Test took 17846ms.
[14:10:19.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:19.627] INFO: dacScan step from 140 .. 159
[14:10:19.628] INFO: dacScan split into 2 runs with ntrig = 5
[14:10:19.628] INFO: run 1 of 2
[14:10:38.863] INFO: Test took 19235ms.
[14:10:39.176] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:39.252] INFO: run 2 of 2
[14:10:58.594] INFO: Test took 19342ms.
[14:10:58.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:58.980] INFO: dacScan step from 160 .. 179
[14:10:58.980] INFO: dacScan split into 2 runs with ntrig = 5
[14:10:58.980] INFO: run 1 of 2
[14:11:18.764] INFO: Test took 19784ms.
[14:11:19.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:19.086] INFO: run 2 of 2
[14:11:38.843] INFO: Test took 19756ms.
[14:11:39.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:39.169] INFO: dacScan step from 180 .. 199
[14:11:39.169] INFO: dacScan split into 2 runs with ntrig = 5
[14:11:39.169] INFO: run 1 of 2
[14:11:59.190] INFO: Test took 20021ms.
[14:11:59.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:59.522] INFO: run 2 of 2
[14:12:19.667] INFO: Test took 20144ms.
[14:12:19.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:56.963] INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 184 (20/5) hits flags = 16 (plus default)
[14:12:56.977] INFO: dacScan step from 0 .. 19
[14:12:56.977] INFO: dacScan split into 2 runs with ntrig = 5
[14:12:56.977] INFO: run 1 of 2
[14:13:11.124] INFO: Test took 14147ms.
[14:13:11.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:11.165] INFO: run 2 of 2
[14:13:25.240] INFO: Test took 14075ms.
[14:13:25.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:25.278] INFO: dacScan step from 20 .. 39
[14:13:25.278] INFO: dacScan split into 2 runs with ntrig = 5
[14:13:25.278] INFO: run 1 of 2
[14:13:39.251] INFO: Test took 13973ms.
[14:13:39.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:39.289] INFO: run 2 of 2
[14:13:53.321] INFO: Test took 14032ms.
[14:13:53.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:53.356] INFO: dacScan step from 40 .. 59
[14:13:53.356] INFO: dacScan split into 2 runs with ntrig = 5
[14:13:53.357] INFO: run 1 of 2
[14:14:07.436] INFO: Test took 14079ms.
[14:14:07.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:07.477] INFO: run 2 of 2
[14:14:21.533] INFO: Test took 14056ms.
[14:14:21.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:21.572] INFO: dacScan step from 60 .. 79
[14:14:21.572] INFO: dacScan split into 2 runs with ntrig = 5
[14:14:21.572] INFO: run 1 of 2
[14:14:35.610] INFO: Test took 14038ms.
[14:14:35.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:35.652] INFO: run 2 of 2
[14:14:49.835] INFO: Test took 14183ms.
[14:14:49.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:49.874] INFO: dacScan step from 80 .. 99
[14:14:49.874] INFO: dacScan split into 2 runs with ntrig = 5
[14:14:49.874] INFO: run 1 of 2
[14:15:04.191] INFO: Test took 14316ms.
[14:15:04.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:04.270] INFO: run 2 of 2
[14:15:18.548] INFO: Test took 14277ms.
[14:15:18.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:18.635] INFO: dacScan step from 100 .. 119
[14:15:18.635] INFO: dacScan split into 2 runs with ntrig = 5
[14:15:18.635] INFO: run 1 of 2
[14:15:35.133] INFO: Test took 16498ms.
[14:15:35.343] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:35.387] INFO: run 2 of 2
[14:15:51.871] INFO: Test took 16484ms.
[14:15:52.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:52.082] INFO: dacScan step from 120 .. 139
[14:15:52.082] INFO: dacScan split into 2 runs with ntrig = 5
[14:15:52.082] INFO: run 1 of 2
[14:16:11.042] INFO: Test took 18960ms.
[14:16:11.307] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:11.375] INFO: run 2 of 2
[14:16:30.391] INFO: Test took 19016ms.
[14:16:30.653] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:30.719] INFO: dacScan step from 140 .. 159
[14:16:30.719] INFO: dacScan split into 2 runs with ntrig = 5
[14:16:30.719] INFO: run 1 of 2
[14:16:50.040] INFO: Test took 19321ms.
[14:16:50.297] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:50.365] INFO: run 2 of 2
[14:17:09.809] INFO: Test took 19444ms.
[14:17:10.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:10.145] INFO: dacScan step from 160 .. 179
[14:17:10.145] INFO: dacScan split into 2 runs with ntrig = 5
[14:17:10.145] INFO: run 1 of 2
[14:17:29.635] INFO: Test took 19490ms.
[14:17:29.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:29.968] INFO: run 2 of 2
[14:17:49.410] INFO: Test took 19442ms.
[14:17:49.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:49.763] INFO: dacScan step from 180 .. 184
[14:17:49.763] INFO: dacScan split into 2 runs with ntrig = 5
[14:17:49.763] INFO: run 1 of 2
[14:17:56.922] INFO: Test took 7158ms.
[14:17:56.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:57.022] INFO: run 2 of 2
[14:18:04.281] INFO: Test took 7258ms.
[14:18:04.361] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:41.227] INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 171 (20/5) hits flags = 16 (plus default)
[14:18:41.242] INFO: dacScan step from 0 .. 19
[14:18:41.242] INFO: dacScan split into 2 runs with ntrig = 5
[14:18:41.242] INFO: run 1 of 2
[14:18:55.422] INFO: Test took 14180ms.
[14:18:55.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:55.461] INFO: run 2 of 2
[14:19:09.510] INFO: Test took 14048ms.
[14:19:09.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:09.549] INFO: dacScan step from 20 .. 39
[14:19:09.550] INFO: dacScan split into 2 runs with ntrig = 5
[14:19:09.550] INFO: run 1 of 2
[14:19:23.509] INFO: Test took 13959ms.
[14:19:23.548] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:23.548] INFO: run 2 of 2
[14:19:37.546] INFO: Test took 13998ms.
[14:19:37.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:37.585] INFO: dacScan step from 40 .. 59
[14:19:37.585] INFO: dacScan split into 2 runs with ntrig = 5
[14:19:37.585] INFO: run 1 of 2
[14:19:51.649] INFO: Test took 14063ms.
[14:19:51.687] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:51.687] INFO: run 2 of 2
[14:20:05.712] INFO: Test took 14025ms.
[14:20:05.752] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:05.752] INFO: dacScan step from 60 .. 79
[14:20:05.752] INFO: dacScan split into 2 runs with ntrig = 5
[14:20:05.752] INFO: run 1 of 2
[14:20:19.761] INFO: Test took 14009ms.
[14:20:19.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:19.804] INFO: run 2 of 2
[14:20:33.879] INFO: Test took 14075ms.
[14:20:33.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:33.923] INFO: dacScan step from 80 .. 99
[14:20:33.923] INFO: dacScan split into 2 runs with ntrig = 5
[14:20:33.923] INFO: run 1 of 2
[14:20:48.424] INFO: Test took 14501ms.
[14:20:48.510] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:48.515] INFO: run 2 of 2
[14:21:02.002] INFO: Test took 14487ms.
[14:21:03.079] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:03.089] INFO: dacScan step from 100 .. 119
[14:21:03.089] INFO: dacScan split into 2 runs with ntrig = 5
[14:21:03.089] INFO: run 1 of 2
[14:21:19.385] INFO: Test took 16295ms.
[14:21:19.567] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:19.602] INFO: run 2 of 2
[14:21:35.841] INFO: Test took 16239ms.
[14:21:36.021] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:36.062] INFO: dacScan step from 120 .. 139
[14:21:36.062] INFO: dacScan split into 2 runs with ntrig = 5
[14:21:36.062] INFO: run 1 of 2
[14:21:54.830] INFO: Test took 18768ms.
[14:21:55.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:55.156] INFO: run 2 of 2
[14:22:14.015] INFO: Test took 18859ms.
[14:22:14.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:14.343] INFO: dacScan step from 140 .. 159
[14:22:14.343] INFO: dacScan split into 2 runs with ntrig = 5
[14:22:14.343] INFO: run 1 of 2
[14:22:33.584] INFO: Test took 19241ms.
[14:22:33.848] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:33.917] INFO: run 2 of 2
[14:22:53.236] INFO: Test took 19319ms.
[14:22:53.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:53.564] INFO: dacScan step from 160 .. 171
[14:22:53.564] INFO: dacScan split into 2 runs with ntrig = 5
[14:22:53.564] INFO: run 1 of 2
[14:23:06.511] INFO: Test took 12947ms.
[14:23:06.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:06.708] INFO: run 2 of 2
[14:23:19.567] INFO: Test took 12859ms.
[14:23:19.727] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:53.458] INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 167 (20/5) hits flags = 16 (plus default)
[14:23:53.476] INFO: dacScan step from 0 .. 19
[14:23:53.476] INFO: dacScan split into 2 runs with ntrig = 5
[14:23:53.476] INFO: run 1 of 2
[14:24:07.614] INFO: Test took 14138ms.
[14:24:07.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:07.656] INFO: run 2 of 2
[14:24:21.807] INFO: Test took 14151ms.
[14:24:21.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:21.844] INFO: dacScan step from 20 .. 39
[14:24:21.844] INFO: dacScan split into 2 runs with ntrig = 5
[14:24:21.844] INFO: run 1 of 2
[14:24:35.898] INFO: Test took 14054ms.
[14:24:35.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:35.941] INFO: run 2 of 2
[14:24:49.885] INFO: Test took 13944ms.
[14:24:49.925] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:49.925] INFO: dacScan step from 40 .. 59
[14:24:49.925] INFO: dacScan split into 2 runs with ntrig = 5
[14:24:49.925] INFO: run 1 of 2
[14:25:03.949] INFO: Test took 14024ms.
[14:25:03.987] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:03.987] INFO: run 2 of 2
[14:25:17.990] INFO: Test took 14003ms.
[14:25:18.027] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:18.027] INFO: dacScan step from 60 .. 79
[14:25:18.027] INFO: dacScan split into 2 runs with ntrig = 5
[14:25:18.027] INFO: run 1 of 2
[14:25:32.086] INFO: Test took 14059ms.
[14:25:32.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:32.129] INFO: run 2 of 2
[14:25:46.320] INFO: Test took 14191ms.
[14:25:46.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:46.360] INFO: dacScan step from 80 .. 99
[14:25:46.360] INFO: dacScan split into 2 runs with ntrig = 5
[14:25:46.360] INFO: run 1 of 2
[14:26:00.756] INFO: Test took 14396ms.
[14:26:00.833] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:00.843] INFO: run 2 of 2
[14:26:15.212] INFO: Test took 14369ms.
[14:26:15.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:15.294] INFO: dacScan step from 100 .. 119
[14:26:15.294] INFO: dacScan split into 2 runs with ntrig = 5
[14:26:15.295] INFO: run 1 of 2
[14:26:31.622] INFO: Test took 16327ms.
[14:26:31.832] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:31.875] INFO: run 2 of 2
[14:26:48.244] INFO: Test took 16369ms.
[14:26:48.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:48.455] INFO: dacScan step from 120 .. 139
[14:26:48.455] INFO: dacScan split into 2 runs with ntrig = 5
[14:26:48.455] INFO: run 1 of 2
[14:27:07.383] INFO: Test took 18927ms.
[14:27:07.650] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:07.719] INFO: run 2 of 2
[14:27:26.464] INFO: Test took 18745ms.
[14:27:26.728] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:26.797] INFO: dacScan step from 140 .. 159
[14:27:26.797] INFO: dacScan split into 2 runs with ntrig = 5
[14:27:26.797] INFO: run 1 of 2
[14:27:46.023] INFO: Test took 19226ms.
[14:27:46.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:46.355] INFO: run 2 of 2
[14:28:05.816] INFO: Test took 19460ms.
[14:28:06.082] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:06.150] INFO: dacScan step from 160 .. 167
[14:28:06.150] INFO: dacScan split into 2 runs with ntrig = 5
[14:28:06.150] INFO: run 1 of 2
[14:28:15.729] INFO: Test took 9579ms.
[14:28:15.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:15.864] INFO: run 2 of 2
[14:28:25.415] INFO: Test took 9551ms.
[14:28:25.535] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:00.390] INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 168 (20/5) hits flags = 16 (plus default)
[14:29:00.410] INFO: dacScan step from 0 .. 19
[14:29:00.410] INFO: dacScan split into 2 runs with ntrig = 5
[14:29:00.410] INFO: run 1 of 2
[14:29:14.547] INFO: Test took 14137ms.
[14:29:14.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:14.584] INFO: run 2 of 2
[14:29:28.560] INFO: Test took 13976ms.
[14:29:28.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:28.600] INFO: dacScan step from 20 .. 39
[14:29:28.600] INFO: dacScan split into 2 runs with ntrig = 5
[14:29:28.600] INFO: run 1 of 2
[14:29:42.555] INFO: Test took 13954ms.
[14:29:42.594] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:42.594] INFO: run 2 of 2
[14:29:56.617] INFO: Test took 14023ms.
[14:29:56.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:56.655] INFO: dacScan step from 40 .. 59
[14:29:56.655] INFO: dacScan split into 2 runs with ntrig = 5
[14:29:56.655] INFO: run 1 of 2
[14:30:10.730] INFO: Test took 14075ms.
[14:30:10.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:10.769] INFO: run 2 of 2
[14:30:24.753] INFO: Test took 13984ms.
[14:30:24.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:24.798] INFO: dacScan step from 60 .. 79
[14:30:24.798] INFO: dacScan split into 2 runs with ntrig = 5
[14:30:24.798] INFO: run 1 of 2
[14:30:38.855] INFO: Test took 14057ms.
[14:30:38.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:38.897] INFO: run 2 of 2
[14:30:53.181] INFO: Test took 14283ms.
[14:30:53.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:30:53.225] INFO: dacScan step from 80 .. 99
[14:30:53.225] INFO: dacScan split into 2 runs with ntrig = 5
[14:30:53.225] INFO: run 1 of 2
[14:31:07.604] INFO: Test took 14378ms.
[14:31:07.683] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:07.691] INFO: run 2 of 2
[14:31:21.999] INFO: Test took 14308ms.
[14:31:22.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:22.080] INFO: dacScan step from 100 .. 119
[14:31:22.080] INFO: dacScan split into 2 runs with ntrig = 5
[14:31:22.080] INFO: run 1 of 2
[14:31:38.356] INFO: Test took 16275ms.
[14:31:38.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:38.570] INFO: run 2 of 2
[14:31:54.766] INFO: Test took 16196ms.
[14:31:54.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:54.981] INFO: dacScan step from 120 .. 139
[14:31:54.981] INFO: dacScan split into 2 runs with ntrig = 5
[14:31:54.981] INFO: run 1 of 2
[14:32:13.648] INFO: Test took 18667ms.
[14:32:13.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:14.051] INFO: run 2 of 2
[14:32:32.929] INFO: Test took 18878ms.
[14:32:33.248] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:33.322] INFO: dacScan step from 140 .. 159
[14:32:33.322] INFO: dacScan split into 2 runs with ntrig = 5
[14:32:33.322] INFO: run 1 of 2
[14:32:52.772] INFO: Test took 19450ms.
[14:32:53.034] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:53.102] INFO: run 2 of 2
[14:33:12.457] INFO: Test took 19355ms.
[14:33:12.719] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:12.786] INFO: dacScan step from 160 .. 168
[14:33:12.786] INFO: dacScan split into 2 runs with ntrig = 5
[14:33:12.786] INFO: run 1 of 2
[14:33:23.189] INFO: Test took 10403ms.
[14:33:23.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:23.347] INFO: run 2 of 2
[14:33:33.797] INFO: Test took 10450ms.
[14:33:33.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:09.528] INFO: PixTestTrim::trimBitTest() done
[14:34:09.529] INFO: PixTestTrim::doTest() done, duration: 3115 seconds
[14:34:10.445] INFO: ######################################################################
[14:34:10.445] INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:34:10.445] INFO: ######################################################################
[14:34:14.102] INFO: Test took 3656ms.
[14:34:14.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:18.017] INFO: Test took 3683ms.
[14:34:18.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:21.839] INFO: Test took 3664ms.
[14:34:21.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:25.525] INFO: Test took 3550ms.
[14:34:25.664] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:25.676] INFO: The DUT currently contains the following objects:
[14:34:25.676] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:25.676] INFO: TBM Core alpha (0): 7 registers set
[14:34:25.676] INFO: TBM Core beta (1): 7 registers set
[14:34:25.676] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:25.676] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:25.676] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.773] INFO: Test took 1097ms.
[14:34:26.774] INFO: The DUT currently contains the following objects:
[14:34:26.774] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:26.775] INFO: TBM Core alpha (0): 7 registers set
[14:34:26.775] INFO: TBM Core beta (1): 7 registers set
[14:34:26.775] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:26.775] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:26.775] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.869] INFO: Test took 1094ms.
[14:34:27.871] INFO: The DUT currently contains the following objects:
[14:34:27.871] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:27.871] INFO: TBM Core alpha (0): 7 registers set
[14:34:27.871] INFO: TBM Core beta (1): 7 registers set
[14:34:27.871] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:27.871] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.871] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:27.872] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.970] INFO: Test took 1098ms.
[14:34:28.972] INFO: The DUT currently contains the following objects:
[14:34:28.972] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:28.972] INFO: TBM Core alpha (0): 7 registers set
[14:34:28.972] INFO: TBM Core beta (1): 7 registers set
[14:34:28.972] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:28.972] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.972] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.972] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.972] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.972] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:28.973] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.071] INFO: Test took 1098ms.
[14:34:30.073] INFO: The DUT currently contains the following objects:
[14:34:30.073] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:30.074] INFO: TBM Core alpha (0): 7 registers set
[14:34:30.074] INFO: TBM Core beta (1): 7 registers set
[14:34:30.074] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:30.074] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:30.074] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.172] INFO: Test took 1098ms.
[14:34:31.174] INFO: The DUT currently contains the following objects:
[14:34:31.174] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:31.174] INFO: TBM Core alpha (0): 7 registers set
[14:34:31.174] INFO: TBM Core beta (1): 7 registers set
[14:34:31.174] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:31.174] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:31.174] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.273] INFO: Test took 1099ms.
[14:34:32.275] INFO: The DUT currently contains the following objects:
[14:34:32.275] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:32.275] INFO: TBM Core alpha (0): 7 registers set
[14:34:32.275] INFO: TBM Core beta (1): 7 registers set
[14:34:32.275] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:32.275] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.275] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.275] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.275] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.275] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:32.276] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.374] INFO: Test took 1098ms.
[14:34:33.376] INFO: The DUT currently contains the following objects:
[14:34:33.377] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:33.377] INFO: TBM Core alpha (0): 7 registers set
[14:34:33.377] INFO: TBM Core beta (1): 7 registers set
[14:34:33.377] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:33.377] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:33.377] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.475] INFO: Test took 1098ms.
[14:34:34.477] INFO: The DUT currently contains the following objects:
[14:34:34.477] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:34.477] INFO: TBM Core alpha (0): 7 registers set
[14:34:34.477] INFO: TBM Core beta (1): 7 registers set
[14:34:34.477] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:34.477] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.477] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.477] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.477] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.477] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:34.478] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.577] INFO: Test took 1099ms.
[14:34:35.579] INFO: The DUT currently contains the following objects:
[14:34:35.580] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:35.580] INFO: TBM Core alpha (0): 7 registers set
[14:34:35.580] INFO: TBM Core beta (1): 7 registers set
[14:34:35.580] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:35.580] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:35.580] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.678] INFO: Test took 1098ms.
[14:34:36.680] INFO: The DUT currently contains the following objects:
[14:34:36.680] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:36.680] INFO: TBM Core alpha (0): 7 registers set
[14:34:36.680] INFO: TBM Core beta (1): 7 registers set
[14:34:36.680] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:36.680] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:36.680] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.779] INFO: Test took 1099ms.
[14:34:37.781] INFO: The DUT currently contains the following objects:
[14:34:37.781] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:37.781] INFO: TBM Core alpha (0): 7 registers set
[14:34:37.781] INFO: TBM Core beta (1): 7 registers set
[14:34:37.781] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:37.781] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.781] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.782] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.782] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.782] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:37.782] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.880] INFO: Test took 1098ms.
[14:34:38.882] INFO: The DUT currently contains the following objects:
[14:34:38.882] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:38.882] INFO: TBM Core alpha (0): 7 registers set
[14:34:38.882] INFO: TBM Core beta (1): 7 registers set
[14:34:38.882] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:38.882] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.882] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.883] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.883] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.883] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:38.883] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.979] INFO: Test took 1096ms.
[14:34:39.981] INFO: The DUT currently contains the following objects:
[14:34:39.981] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:39.981] INFO: TBM Core alpha (0): 7 registers set
[14:34:39.981] INFO: TBM Core beta (1): 7 registers set
[14:34:39.981] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:39.981] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:39.981] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.079] INFO: Test took 1098ms.
[14:34:41.081] INFO: The DUT currently contains the following objects:
[14:34:41.081] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:41.081] INFO: TBM Core alpha (0): 7 registers set
[14:34:41.081] INFO: TBM Core beta (1): 7 registers set
[14:34:41.081] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:41.081] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:41.081] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.178] INFO: Test took 1097ms.
[14:34:42.180] INFO: The DUT currently contains the following objects:
[14:34:42.180] INFO: 2 TBM Cores tbm08c (2 ON)
[14:34:42.180] INFO: TBM Core alpha (0): 7 registers set
[14:34:42.180] INFO: TBM Core beta (1): 7 registers set
[14:34:42.180] INFO: 16 ROCs psi46digv21respin (1 ON) with 4160 pixelConfigs
[14:34:42.180] INFO: ROC 0: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 1: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 2: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 3: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 4: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 5: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 6: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 7: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 8: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 9: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 10: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 11: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 12: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 13: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 14: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:42.180] INFO: ROC 15: 19 DACs set, Pixels: 4159 masked, 1 active.
[14:34:43.278] INFO: Test took 1098ms.
[14:34:43.283] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:37:51.797] INFO: Test took 188514ms.
[14:37:54.652] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:02.759] INFO: Test took 188107ms.
[14:41:06.104] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.114] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.124] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.134] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.143] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.153] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.163] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.173] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.182] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.193] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.202] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.212] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.222] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.233] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.242] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.252] INFO: safety margin for low PH: adding 1, margin is now 21
[14:41:06.262] INFO: safety margin for low PH: adding 2, margin is now 22
[14:41:06.272] INFO: safety margin for low PH: adding 3, margin is now 23
[14:41:06.281] INFO: safety margin for low PH: adding 4, margin is now 24
[14:41:06.291] INFO: safety margin for low PH: adding 5, margin is now 25
[14:41:06.301] INFO: safety margin for low PH: adding 6, margin is now 26
[14:41:06.311] INFO: safety margin for low PH: adding 7, margin is now 27
[14:41:06.320] INFO: safety margin for low PH: adding 8, margin is now 28
[14:41:06.330] INFO: safety margin for low PH: adding 0, margin is now 20
[14:41:06.378] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C0.dat
[14:41:06.379] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C1.dat
[14:41:06.379] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C2.dat
[14:41:06.379] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C3.dat
[14:41:06.380] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C4.dat
[14:41:06.380] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C5.dat
[14:41:06.380] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C6.dat
[14:41:06.381] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C7.dat
[14:41:06.381] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C8.dat
[14:41:06.381] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C9.dat
[14:41:06.382] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C10.dat
[14:41:06.382] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C11.dat
[14:41:06.383] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C12.dat
[14:41:06.383] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C13.dat
[14:41:06.383] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C14.dat
[14:41:06.384] INFO: write dac parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/dacParameters35_C15.dat
[14:41:10.024] INFO: Test took 3636ms.
[14:41:13.985] INFO: Test took 3602ms.
[14:41:18.006] INFO: Test took 3666ms.
[14:41:18.382] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:19.292] INFO: Test took 910ms.
[14:41:19.297] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:20.394] INFO: Test took 1097ms.
[14:41:20.398] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:21.494] INFO: Test took 1096ms.
[14:41:21.498] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:22.595] INFO: Test took 1097ms.
[14:41:22.600] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:23.698] INFO: Test took 1099ms.
[14:41:23.701] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:24.799] INFO: Test took 1098ms.
[14:41:24.803] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:25.898] INFO: Test took 1095ms.
[14:41:25.902] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:26.000] INFO: Test took 1098ms.
[14:41:27.006] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:28.103] INFO: Test took 1097ms.
[14:41:28.108] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:29.206] INFO: Test took 1098ms.
[14:41:29.210] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:30.309] INFO: Test took 1100ms.
[14:41:30.314] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:31.413] INFO: Test took 1099ms.
[14:41:31.418] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:32.515] INFO: Test took 1097ms.
[14:41:32.519] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:33.615] INFO: Test took 1097ms.
[14:41:33.620] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:34.716] INFO: Test took 1097ms.
[14:41:34.720] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:35.816] INFO: Test took 1096ms.
[14:41:35.822] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:36.920] INFO: Test took 1098ms.
[14:41:36.925] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:38.021] INFO: Test took 1097ms.
[14:41:38.026] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:39.123] INFO: Test took 1098ms.
[14:41:39.129] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:40.230] INFO: Test took 1101ms.
[14:41:40.235] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:41.330] INFO: Test took 1096ms.
[14:41:41.335] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:42.430] INFO: Test took 1095ms.
[14:41:42.434] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:43.533] INFO: Test took 1099ms.
[14:41:43.538] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:44.638] INFO: Test took 1100ms.
[14:41:44.644] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:45.741] INFO: Test took 1098ms.
[14:41:45.746] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:46.842] INFO: Test took 1096ms.
[14:41:46.847] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:47.943] INFO: Test took 1097ms.
[14:41:47.947] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:49.045] INFO: Test took 1098ms.
[14:41:49.049] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:50.147] INFO: Test took 1098ms.
[14:41:50.153] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:51.249] INFO: Test took 1097ms.
[14:41:51.253] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:52.352] INFO: Test took 1100ms.
[14:41:52.358] INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:41:53.456] INFO: Test took 1098ms.
[14:41:54.009] INFO: PixTestPhOptimization::doTest() done, duration: 463 seconds
[14:41:54.009] INFO: PH scale (per ROC): 75 81 80 76 79 79 82 83 77 77 80 69 77 78 79 86
[14:41:54.009] INFO: PH offset (per ROC): 153 169 155 166 154 167 148 148 157 158 164 161 165 160 144 150
[14:41:54.206] INFO: ######################################################################
[14:41:54.206] INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:41:54.206] INFO: ######################################################################
[14:41:54.222] INFO: scanning low vcal = 10
[14:41:58.207] INFO: Test took 3985ms.
[14:41:58.217] INFO: scanning low vcal = 20
[14:42:02.158] INFO: Test took 3941ms.
[14:42:02.168] INFO: scanning low vcal = 30
[14:42:06.145] INFO: Test took 3977ms.
[14:42:06.164] INFO: scanning low vcal = 40
[14:42:10.670] INFO: Test took 4506ms.
[14:42:10.777] INFO: scanning low vcal = 50
[14:42:15.267] INFO: Test took 4489ms.
[14:42:15.381] INFO: scanning low vcal = 60
[14:42:19.921] INFO: Test took 4540ms.
[14:42:20.032] INFO: scanning low vcal = 70
[14:42:24.516] INFO: Test took 4484ms.
[14:42:24.625] INFO: scanning low vcal = 80
[14:42:29.119] INFO: Test took 4494ms.
[14:42:29.231] INFO: scanning low vcal = 90
[14:42:33.696] INFO: Test took 4465ms.
[14:42:33.809] INFO: scanning low vcal = 100
[14:42:38.281] INFO: Test took 4472ms.
[14:42:38.393] INFO: scanning low vcal = 110
[14:42:42.825] INFO: Test took 4432ms.
[14:42:42.935] INFO: scanning low vcal = 120
[14:42:47.418] INFO: Test took 4483ms.
[14:42:47.530] INFO: scanning low vcal = 130
[14:42:52.034] INFO: Test took 4504ms.
[14:42:52.148] INFO: scanning low vcal = 140
[14:42:56.637] INFO: Test took 4489ms.
[14:42:56.747] INFO: scanning low vcal = 150
[14:43:01.224] INFO: Test took 4477ms.
[14:43:01.340] INFO: scanning low vcal = 160
[14:43:05.806] INFO: Test took 4466ms.
[14:43:05.915] INFO: scanning low vcal = 170
[14:43:10.408] INFO: Test took 4493ms.
[14:43:10.523] INFO: scanning low vcal = 180
[14:43:14.985] INFO: Test took 4462ms.
[14:43:15.096] INFO: scanning low vcal = 190
[14:43:19.718] INFO: Test took 4622ms.
[14:43:19.829] INFO: scanning low vcal = 200
[14:43:24.306] INFO: Test took 4477ms.
[14:43:24.420] INFO: scanning low vcal = 210
[14:43:28.877] INFO: Test took 4457ms.
[14:43:28.989] INFO: scanning low vcal = 220
[14:43:33.427] INFO: Test took 4438ms.
[14:43:33.542] INFO: scanning low vcal = 230
[14:43:37.994] INFO: Test took 4452ms.
[14:43:38.108] INFO: scanning low vcal = 240
[14:43:42.614] INFO: Test took 4506ms.
[14:43:42.728] INFO: scanning low vcal = 250
[14:43:47.229] INFO: Test took 4501ms.
[14:43:47.346] INFO: scanning high vcal = 30 (= 210 in low range)
[14:43:51.842] INFO: Test took 4496ms.
[14:43:51.956] INFO: scanning high vcal = 50 (= 350 in low range)
[14:43:56.405] INFO: Test took 4449ms.
[14:43:56.515] INFO: scanning high vcal = 70 (= 490 in low range)
[14:44:00.990] INFO: Test took 4475ms.
[14:44:01.106] INFO: scanning high vcal = 90 (= 630 in low range)
[14:44:05.572] INFO: Test took 4466ms.
[14:44:05.685] INFO: scanning high vcal = 200 (= 1400 in low range)
[14:44:10.163] INFO: Test took 4478ms.
[14:44:10.871] INFO: PixTestGainPedestal::measure() done
[14:44:56.566] INFO: PixTestGainPedestal::fit() done
[14:44:56.566] INFO: non-linearity mean: 0.953 0.954 0.955 0.954 0.952 0.958 0.958 0.958 0.961 0.954 0.955 0.959 0.950 0.956 0.958 0.947
[14:44:56.566] INFO: non-linearity RMS: 0.007 0.006 0.006 0.006 0.005 0.006 0.005 0.006 0.006 0.005 0.005 0.005 0.007 0.005 0.004 0.006
[14:44:56.566] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C0.dat
[14:44:56.591] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C1.dat
[14:44:56.617] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C2.dat
[14:44:56.643] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C3.dat
[14:44:56.669] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C4.dat
[14:44:56.695] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C5.dat
[14:44:56.720] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C6.dat
[14:44:56.745] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C7.dat
[14:44:56.772] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C8.dat
[14:44:56.797] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C9.dat
[14:44:56.822] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C10.dat
[14:44:56.848] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C11.dat
[14:44:56.874] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C12.dat
[14:44:56.899] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C13.dat
[14:44:56.926] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C14.dat
[14:44:56.951] INFO: write gain/ped parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/phCalibrationFitErr35_C15.dat
[14:44:56.976] INFO: PixTestGainPedestal::doTest() done, duration: 182 seconds
[14:44:56.983] INFO: readReadbackCal: /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C0.dat .. /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C15.dat
[14:44:56.985] INFO: PixTestReadback::doTest() start.
[14:44:56.986] INFO: PixTestReadback::RES sent once
[14:45:13.502] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C0.dat
[14:45:13.502] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C1.dat
[14:45:13.502] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C2.dat
[14:45:13.502] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C3.dat
[14:45:13.502] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C4.dat
[14:45:13.502] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C5.dat
[14:45:13.502] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C6.dat
[14:45:13.502] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C7.dat
[14:45:13.502] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C8.dat
[14:45:13.503] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C9.dat
[14:45:13.503] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C10.dat
[14:45:13.503] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C11.dat
[14:45:13.503] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C12.dat
[14:45:13.503] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C13.dat
[14:45:13.503] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C14.dat
[14:45:13.503] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C15.dat
[14:45:13.543] INFO: PixTestPattern:: pg_setup set to default.
[14:45:13.543] INFO: PixTestReadback::RES sent once
[14:45:29.963] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C0.dat
[14:45:29.964] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C1.dat
[14:45:29.964] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C2.dat
[14:45:29.964] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C3.dat
[14:45:29.964] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C4.dat
[14:45:29.964] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C5.dat
[14:45:29.964] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C6.dat
[14:45:29.964] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C7.dat
[14:45:29.964] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C8.dat
[14:45:29.964] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C9.dat
[14:45:29.965] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C10.dat
[14:45:29.965] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C11.dat
[14:45:29.965] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C12.dat
[14:45:29.965] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C13.dat
[14:45:29.965] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C14.dat
[14:45:29.965] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C15.dat
[14:45:30.004] INFO: PixTestPattern:: pg_setup set to default.
[14:45:30.005] INFO: PixTestReadback::RES sent once
[14:45:42.730] INFO: PixTestPattern:: pg_setup set to default.
[14:45:42.731] INFO: Vbg will be calibrated using Vd calibration
[14:45:42.731] INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 147.3calibrated Vbg = 1.2443 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.7calibrated Vbg = 1.24977 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.5calibrated Vbg = 1.24831 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.1calibrated Vbg = 1.25671 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.3calibrated Vbg = 1.25934 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 159.5calibrated Vbg = 1.26741 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147calibrated Vbg = 1.25756 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148calibrated Vbg = 1.26324 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 158calibrated Vbg = 1.26798 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 157.2calibrated Vbg = 1.26615 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.6calibrated Vbg = 1.27048 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.6calibrated Vbg = 1.26133 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.2calibrated Vbg = 1.25223 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.2calibrated Vbg = 1.24139 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 161.1calibrated Vbg = 1.24139 :::*/*/*/*/
[14:45:42.731] INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.6calibrated Vbg = 1.24956 :::*/*/*/*/
[14:45:42.734] INFO: PixTestReadback::RES sent once
[14:49:37.573] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C0.dat
[14:49:37.573] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C1.dat
[14:49:37.573] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C2.dat
[14:49:37.573] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C3.dat
[14:49:37.573] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C4.dat
[14:49:37.573] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C5.dat
[14:49:37.573] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C6.dat
[14:49:37.574] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C7.dat
[14:49:37.574] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C8.dat
[14:49:37.574] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C9.dat
[14:49:37.574] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C10.dat
[14:49:37.574] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C11.dat
[14:49:37.574] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C12.dat
[14:49:37.574] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C13.dat
[14:49:37.574] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C14.dat
[14:49:37.574] INFO: write readback calibration parameters into /home/l_tester/ColdBox/ModuleTests/M4570_FullQualification_2015-08-10_12h35m_1439206531/001_Fulltest_m20/readbackCal_C15.dat
[14:49:37.608] INFO: PixTestPattern:: pg_setup set to default.
[14:49:37.610] INFO: PixTestReadback::doTest() done
[14:49:37.624] INFO: enter test to run
[14:49:37.624] INFO: test: q no parameter change
[14:49:37.776] QUIET: Connection to board 176 closed.
[14:49:37.779] INFO: pXar: this is the end, my friend