[19:27:06.174] <TB1> INFO: *** Welcome to pxar ***
[19:27:06.174] <TB1> INFO: *** Today: 2016/01/18
[19:27:06.247] <TB1> INFO: *** Version: 3197-dirty
[19:27:06.248] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters_C15.dat
[19:27:06.248] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//tbmParameters_C0b.dat
[19:27:06.248] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//defaultMaskFile.dat
[19:27:06.248] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters_C15.dat
[19:27:06.315] <TB1> INFO: clk: 4
[19:27:06.315] <TB1> INFO: ctr: 4
[19:27:06.315] <TB1> INFO: sda: 19
[19:27:06.315] <TB1> INFO: tin: 9
[19:27:06.315] <TB1> INFO: level: 15
[19:27:06.315] <TB1> INFO: triggerdelay: 0
[19:27:06.315] <TB1> QUIET: Instanciating API for pxar prod-12
[19:27:06.315] <TB1> INFO: Log level: INFO
[19:27:06.321] <TB1> INFO: Found DTB DTB_WXBYFL
[19:27:06.331] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[19:27:06.334] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[19:27:06.337] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[19:27:07.854] <TB1> INFO: DUT info:
[19:27:07.854] <TB1> INFO: The DUT currently contains the following objects:
[19:27:07.854] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[19:27:07.854] <TB1> INFO: TBM Core alpha (0): 7 registers set
[19:27:07.855] <TB1> INFO: TBM Core beta (1): 7 registers set
[19:27:07.855] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[19:27:07.855] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:07.855] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[19:27:08.256] <TB1> INFO: enter 'restricted' command line mode
[19:27:08.256] <TB1> INFO: enter test to run
[19:27:08.256] <TB1> INFO: test: FullTest no parameter change
[19:27:08.256] <TB1> INFO: running: fulltest
[19:27:08.258] <TB1> INFO: ######################################################################
[19:27:08.258] <TB1> INFO: PixTestFullTest::doTest()
[19:27:08.258] <TB1> INFO: ######################################################################
[19:27:08.261] <TB1> INFO: ######################################################################
[19:27:08.261] <TB1> INFO: PixTestAlive::doTest()
[19:27:08.261] <TB1> INFO: ######################################################################
[19:27:08.263] <TB1> INFO: ----------------------------------------------------------------------
[19:27:08.263] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:27:08.263] <TB1> INFO: ----------------------------------------------------------------------
[19:27:08.586] <TB1> INFO: Expecting 41600 events.
[19:27:12.845] <TB1> INFO: 41600 events read in total (3539ms).
[19:27:12.917] <TB1> INFO: Test took 4652ms.
[19:27:12.924] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:27:13.208] <TB1> INFO: PixTestAlive::aliveTest() done
[19:27:13.208] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[19:27:13.210] <TB1> INFO: ----------------------------------------------------------------------
[19:27:13.210] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:27:13.210] <TB1> INFO: ----------------------------------------------------------------------
[19:27:13.537] <TB1> INFO: Expecting 41600 events.
[19:27:16.695] <TB1> INFO: 41600 events read in total (2442ms).
[19:27:16.695] <TB1> INFO: Test took 3483ms.
[19:27:16.695] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:27:16.695] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[19:27:17.023] <TB1> INFO: PixTestAlive::maskTest() done
[19:27:17.023] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:27:17.025] <TB1> INFO: ----------------------------------------------------------------------
[19:27:17.025] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:27:17.025] <TB1> INFO: ----------------------------------------------------------------------
[19:27:17.349] <TB1> INFO: Expecting 41600 events.
[19:27:21.621] <TB1> INFO: 41600 events read in total (3555ms).
[19:27:21.622] <TB1> INFO: Test took 4595ms.
[19:27:21.628] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:27:21.935] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[19:27:21.935] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:27:21.935] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[19:27:21.935] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:27:21.935] <TB1> INFO: Decoding statistics:
[19:27:21.935] <TB1> INFO: General information:
[19:27:21.935] <TB1> INFO: 16bit words read: 0
[19:27:21.935] <TB1> INFO: valid events total: 0
[19:27:21.935] <TB1> INFO: empty events: 0
[19:27:21.935] <TB1> INFO: valid events with pixels: 0
[19:27:21.935] <TB1> INFO: valid pixel hits: 0
[19:27:21.935] <TB1> INFO: Event errors: 0
[19:27:21.935] <TB1> INFO: start marker: 0
[19:27:21.935] <TB1> INFO: stop marker: 0
[19:27:21.935] <TB1> INFO: overflow: 0
[19:27:21.935] <TB1> INFO: invalid 5bit words: 0
[19:27:21.935] <TB1> INFO: invalid XOR eye diagram: 0
[19:27:21.935] <TB1> INFO: TBM errors: 0
[19:27:21.935] <TB1> INFO: flawed TBM headers: 0
[19:27:21.935] <TB1> INFO: flawed TBM trailers: 0
[19:27:21.935] <TB1> INFO: event ID mismatches: 0
[19:27:21.935] <TB1> INFO: ROC errors: 0
[19:27:21.935] <TB1> INFO: missing ROC header(s): 0
[19:27:21.935] <TB1> INFO: misplaced readback start: 0
[19:27:21.935] <TB1> INFO: Pixel decoding errors: 0
[19:27:21.935] <TB1> INFO: pixel data incomplete: 0
[19:27:21.935] <TB1> INFO: pixel address: 0
[19:27:21.935] <TB1> INFO: pulse height fill bit: 0
[19:27:21.935] <TB1> INFO: buffer corruption: 0
[19:27:21.966] <TB1> INFO: ######################################################################
[19:27:21.966] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[19:27:21.966] <TB1> INFO: ######################################################################
[19:27:21.970] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[19:27:21.981] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:27:21.981] <TB1> INFO: run 1 of 1
[19:27:22.288] <TB1> INFO: Expecting 3120000 events.
[19:28:01.753] <TB1> INFO: 870285 events read in total (38749ms).
[19:28:40.210] <TB1> INFO: 1730890 events read in total (77206ms).
[19:29:18.696] <TB1> INFO: 2602980 events read in total (115692ms).
[19:29:41.389] <TB1> INFO: 3120000 events read in total (138385ms).
[19:29:41.438] <TB1> INFO: Test took 139457ms.
[19:29:41.539] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:30:04.155] <TB1> INFO: PixTestBBMap::doTest() done, duration: 162 seconds
[19:30:04.155] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 3 0 1 1 0 0 0 0 0 0
[19:30:04.155] <TB1> INFO: separation cut (per ROC): 93 95 90 96 86 86 91 87 83 94 77 99 94 86 88 90
[19:30:04.155] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:30:04.155] <TB1> INFO: Decoding statistics:
[19:30:04.155] <TB1> INFO: General information:
[19:30:04.155] <TB1> INFO: 16bit words read: 0
[19:30:04.155] <TB1> INFO: valid events total: 0
[19:30:04.155] <TB1> INFO: empty events: 0
[19:30:04.155] <TB1> INFO: valid events with pixels: 0
[19:30:04.155] <TB1> INFO: valid pixel hits: 0
[19:30:04.155] <TB1> INFO: Event errors: 0
[19:30:04.155] <TB1> INFO: start marker: 0
[19:30:04.155] <TB1> INFO: stop marker: 0
[19:30:04.155] <TB1> INFO: overflow: 0
[19:30:04.155] <TB1> INFO: invalid 5bit words: 0
[19:30:04.155] <TB1> INFO: invalid XOR eye diagram: 0
[19:30:04.155] <TB1> INFO: TBM errors: 0
[19:30:04.155] <TB1> INFO: flawed TBM headers: 0
[19:30:04.155] <TB1> INFO: flawed TBM trailers: 0
[19:30:04.155] <TB1> INFO: event ID mismatches: 0
[19:30:04.156] <TB1> INFO: ROC errors: 0
[19:30:04.156] <TB1> INFO: missing ROC header(s): 0
[19:30:04.156] <TB1> INFO: misplaced readback start: 0
[19:30:04.156] <TB1> INFO: Pixel decoding errors: 0
[19:30:04.156] <TB1> INFO: pixel data incomplete: 0
[19:30:04.156] <TB1> INFO: pixel address: 0
[19:30:04.156] <TB1> INFO: pulse height fill bit: 0
[19:30:04.156] <TB1> INFO: buffer corruption: 0
[19:30:04.241] <TB1> INFO: ######################################################################
[19:30:04.241] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[19:30:04.241] <TB1> INFO: ######################################################################
[19:30:04.241] <TB1> INFO: ----------------------------------------------------------------------
[19:30:04.241] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[19:30:04.241] <TB1> INFO: ----------------------------------------------------------------------
[19:30:04.241] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[19:30:04.250] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[19:30:04.250] <TB1> INFO: run 1 of 1
[19:30:04.618] <TB1> INFO: Expecting 26208000 events.
[19:30:33.601] <TB1> INFO: 931800 events read in total (28267ms).
[19:31:02.168] <TB1> INFO: 1849000 events read in total (56834ms).
[19:31:29.973] <TB1> INFO: 2761000 events read in total (84639ms).
[19:31:58.375] <TB1> INFO: 3672350 events read in total (113041ms).
[19:32:26.453] <TB1> INFO: 4581500 events read in total (141119ms).
[19:32:54.761] <TB1> INFO: 5488950 events read in total (169427ms).
[19:33:22.762] <TB1> INFO: 6398500 events read in total (197428ms).
[19:33:50.629] <TB1> INFO: 7305250 events read in total (225295ms).
[19:34:18.592] <TB1> INFO: 8212250 events read in total (253258ms).
[19:34:46.516] <TB1> INFO: 9118500 events read in total (281182ms).
[19:35:14.399] <TB1> INFO: 10021000 events read in total (309065ms).
[19:35:42.195] <TB1> INFO: 10922750 events read in total (336861ms).
[19:36:10.171] <TB1> INFO: 11823950 events read in total (364837ms).
[19:36:38.230] <TB1> INFO: 12724750 events read in total (392896ms).
[19:37:06.261] <TB1> INFO: 13616500 events read in total (420927ms).
[19:37:34.224] <TB1> INFO: 14504400 events read in total (448890ms).
[19:38:01.809] <TB1> INFO: 15394200 events read in total (476475ms).
[19:38:30.089] <TB1> INFO: 16282150 events read in total (504755ms).
[19:38:58.574] <TB1> INFO: 17168200 events read in total (533240ms).
[19:39:26.886] <TB1> INFO: 18053550 events read in total (561552ms).
[19:39:54.878] <TB1> INFO: 18939250 events read in total (589544ms).
[19:40:22.392] <TB1> INFO: 19824350 events read in total (617058ms).
[19:40:49.908] <TB1> INFO: 20709850 events read in total (644574ms).
[19:41:17.782] <TB1> INFO: 21593500 events read in total (672448ms).
[19:41:45.294] <TB1> INFO: 22475850 events read in total (699960ms).
[19:42:13.278] <TB1> INFO: 23362450 events read in total (727944ms).
[19:42:41.077] <TB1> INFO: 24248750 events read in total (755743ms).
[19:43:09.044] <TB1> INFO: 25136500 events read in total (783710ms).
[19:43:37.026] <TB1> INFO: 26038300 events read in total (811692ms).
[19:43:42.667] <TB1> INFO: 26208000 events read in total (817333ms).
[19:43:42.693] <TB1> INFO: Test took 818443ms.
[19:43:42.767] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:43:42.925] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:44.353] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:45.785] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:47.249] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:48.670] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:50.076] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:51.507] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:53.005] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:54.406] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:55.803] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:57.190] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:43:58.642] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:44:00.029] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:44:01.470] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:44:02.887] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:44:04.339] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:44:05.780] <TB1> INFO: PixTestScurves::scurves() done
[19:44:05.780] <TB1> INFO: Vcal mean: 102.48 98.97 93.90 95.57 91.14 88.76 96.80 90.29 91.37 93.76 80.06 103.20 96.04 91.70 93.13 91.16
[19:44:05.780] <TB1> INFO: Vcal RMS: 6.50 5.71 4.92 6.22 5.30 5.23 5.76 5.09 5.58 6.17 4.44 5.77 5.86 4.76 5.26 4.82
[19:44:05.780] <TB1> INFO: PixTestScurves::fullTest() done, duration: 841 seconds
[19:44:05.780] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:44:05.780] <TB1> INFO: Decoding statistics:
[19:44:05.780] <TB1> INFO: General information:
[19:44:05.780] <TB1> INFO: 16bit words read: 0
[19:44:05.780] <TB1> INFO: valid events total: 0
[19:44:05.780] <TB1> INFO: empty events: 0
[19:44:05.780] <TB1> INFO: valid events with pixels: 0
[19:44:05.780] <TB1> INFO: valid pixel hits: 0
[19:44:05.780] <TB1> INFO: Event errors: 0
[19:44:05.780] <TB1> INFO: start marker: 0
[19:44:05.780] <TB1> INFO: stop marker: 0
[19:44:05.780] <TB1> INFO: overflow: 0
[19:44:05.780] <TB1> INFO: invalid 5bit words: 0
[19:44:05.780] <TB1> INFO: invalid XOR eye diagram: 0
[19:44:05.780] <TB1> INFO: TBM errors: 0
[19:44:05.780] <TB1> INFO: flawed TBM headers: 0
[19:44:05.780] <TB1> INFO: flawed TBM trailers: 0
[19:44:05.780] <TB1> INFO: event ID mismatches: 0
[19:44:05.781] <TB1> INFO: ROC errors: 0
[19:44:05.781] <TB1> INFO: missing ROC header(s): 0
[19:44:05.781] <TB1> INFO: misplaced readback start: 0
[19:44:05.781] <TB1> INFO: Pixel decoding errors: 0
[19:44:05.781] <TB1> INFO: pixel data incomplete: 0
[19:44:05.781] <TB1> INFO: pixel address: 0
[19:44:05.781] <TB1> INFO: pulse height fill bit: 0
[19:44:05.781] <TB1> INFO: buffer corruption: 0
[19:44:05.854] <TB1> INFO: ######################################################################
[19:44:05.854] <TB1> INFO: PixTestTrim::doTest()
[19:44:05.854] <TB1> INFO: ######################################################################
[19:44:05.855] <TB1> INFO: ----------------------------------------------------------------------
[19:44:05.855] <TB1> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[19:44:05.855] <TB1> INFO: ----------------------------------------------------------------------
[19:44:05.936] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[19:44:05.936] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:44:05.944] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:44:05.944] <TB1> INFO: run 1 of 1
[19:44:06.254] <TB1> INFO: Expecting 5025280 events.
[19:44:44.806] <TB1> INFO: 1086536 events read in total (37836ms).
[19:45:22.163] <TB1> INFO: 2167128 events read in total (75193ms).
[19:45:59.492] <TB1> INFO: 3247960 events read in total (112522ms).
[19:46:37.073] <TB1> INFO: 4335608 events read in total (150103ms).
[19:47:00.737] <TB1> INFO: 5025280 events read in total (173767ms).
[19:47:00.774] <TB1> INFO: Test took 174830ms.
[19:47:00.822] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:47:21.050] <TB1> INFO: ROC 0 VthrComp = 96
[19:47:21.050] <TB1> INFO: ROC 1 VthrComp = 100
[19:47:21.050] <TB1> INFO: ROC 2 VthrComp = 96
[19:47:21.051] <TB1> INFO: ROC 3 VthrComp = 98
[19:47:21.051] <TB1> INFO: ROC 4 VthrComp = 90
[19:47:21.051] <TB1> INFO: ROC 5 VthrComp = 92
[19:47:21.051] <TB1> INFO: ROC 6 VthrComp = 96
[19:47:21.051] <TB1> INFO: ROC 7 VthrComp = 92
[19:47:21.051] <TB1> INFO: ROC 8 VthrComp = 91
[19:47:21.051] <TB1> INFO: ROC 9 VthrComp = 97
[19:47:21.051] <TB1> INFO: ROC 10 VthrComp = 82
[19:47:21.051] <TB1> INFO: ROC 11 VthrComp = 102
[19:47:21.052] <TB1> INFO: ROC 12 VthrComp = 93
[19:47:21.052] <TB1> INFO: ROC 13 VthrComp = 94
[19:47:21.052] <TB1> INFO: ROC 14 VthrComp = 96
[19:47:21.052] <TB1> INFO: ROC 15 VthrComp = 96
[19:47:21.052] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[19:47:21.052] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:47:21.067] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:47:21.067] <TB1> INFO: run 1 of 1
[19:47:21.378] <TB1> INFO: Expecting 5025280 events.
[19:47:54.077] <TB1> INFO: 756712 events read in total (31982ms).
[19:48:26.006] <TB1> INFO: 1512008 events read in total (63911ms).
[19:48:58.076] <TB1> INFO: 2267416 events read in total (95981ms).
[19:49:30.699] <TB1> INFO: 3018208 events read in total (128604ms).
[19:50:02.703] <TB1> INFO: 3764040 events read in total (160608ms).
[19:50:34.072] <TB1> INFO: 4508888 events read in total (191977ms).
[19:50:56.118] <TB1> INFO: 5025280 events read in total (214023ms).
[19:50:56.167] <TB1> INFO: Test took 215100ms.
[19:50:56.304] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:51:20.381] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 64.4051 for pixel 6/61 mean/min/max = 48.1763/31.4518/64.9008
[19:51:20.381] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 58.7963 for pixel 0/9 mean/min/max = 45.4867/31.6667/59.3067
[19:51:20.382] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.0968 for pixel 17/14 mean/min/max = 44.8908/32.5952/57.1865
[19:51:20.382] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.2242 for pixel 21/6 mean/min/max = 45.1341/31.0347/59.2334
[19:51:20.382] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 59.9246 for pixel 4/79 mean/min/max = 46.8935/33.8309/59.9562
[19:51:20.382] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.7109 for pixel 2/76 mean/min/max = 45.6249/33.3697/57.8801
[19:51:20.383] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 60.8745 for pixel 1/50 mean/min/max = 46.3034/31.7275/60.8794
[19:51:20.383] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 57.8524 for pixel 22/2 mean/min/max = 46.0699/34.2194/57.9204
[19:51:20.383] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 60.4403 for pixel 7/2 mean/min/max = 47.1151/33.652/60.5781
[19:51:20.383] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 60.656 for pixel 1/9 mean/min/max = 46.3252/31.9657/60.6848
[19:51:20.384] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 56.9364 for pixel 9/3 mean/min/max = 45.045/32.7448/57.3452
[19:51:20.384] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.9656 for pixel 11/18 mean/min/max = 45.8349/32.4497/59.2202
[19:51:20.384] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 61.9426 for pixel 14/79 mean/min/max = 47.161/32.2164/62.1056
[19:51:20.384] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.5515 for pixel 16/4 mean/min/max = 44.8096/32.8804/56.7388
[19:51:20.385] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.6653 for pixel 1/62 mean/min/max = 44.3534/32.0149/56.692
[19:51:20.385] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 56.8074 for pixel 10/9 mean/min/max = 44.4974/32.174/56.8207
[19:51:20.385] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:20.518] <TB1> INFO: Expecting 411648 events.
[19:51:29.600] <TB1> INFO: 411648 events read in total (8365ms).
[19:51:29.605] <TB1> INFO: Expecting 411648 events.
[19:51:38.537] <TB1> INFO: 411648 events read in total (8345ms).
[19:51:38.544] <TB1> INFO: Expecting 411648 events.
[19:51:47.457] <TB1> INFO: 411648 events read in total (8332ms).
[19:51:47.465] <TB1> INFO: Expecting 411648 events.
[19:51:56.710] <TB1> INFO: 411648 events read in total (8667ms).
[19:51:56.724] <TB1> INFO: Expecting 411648 events.
[19:52:05.595] <TB1> INFO: 411648 events read in total (8308ms).
[19:52:05.610] <TB1> INFO: Expecting 411648 events.
[19:52:14.502] <TB1> INFO: 411648 events read in total (8319ms).
[19:52:14.519] <TB1> INFO: Expecting 411648 events.
[19:52:23.377] <TB1> INFO: 411648 events read in total (8283ms).
[19:52:23.395] <TB1> INFO: Expecting 411648 events.
[19:52:32.796] <TB1> INFO: 411648 events read in total (8829ms).
[19:52:32.813] <TB1> INFO: Expecting 411648 events.
[19:52:41.783] <TB1> INFO: 411648 events read in total (8403ms).
[19:52:41.802] <TB1> INFO: Expecting 411648 events.
[19:52:50.805] <TB1> INFO: 411648 events read in total (8429ms).
[19:52:50.827] <TB1> INFO: Expecting 411648 events.
[19:52:59.976] <TB1> INFO: 411648 events read in total (8581ms).
[19:53:00.002] <TB1> INFO: Expecting 411648 events.
[19:53:08.985] <TB1> INFO: 411648 events read in total (8427ms).
[19:53:09.011] <TB1> INFO: Expecting 411648 events.
[19:53:17.892] <TB1> INFO: 411648 events read in total (8316ms).
[19:53:17.918] <TB1> INFO: Expecting 411648 events.
[19:53:26.827] <TB1> INFO: 411648 events read in total (8340ms).
[19:53:26.861] <TB1> INFO: Expecting 411648 events.
[19:53:35.832] <TB1> INFO: 411648 events read in total (8416ms).
[19:53:35.863] <TB1> INFO: Expecting 411648 events.
[19:53:44.793] <TB1> INFO: 411648 events read in total (8366ms).
[19:53:44.824] <TB1> INFO: Test took 144439ms.
[19:53:45.794] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[19:53:45.802] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:53:45.802] <TB1> INFO: run 1 of 1
[19:53:46.108] <TB1> INFO: Expecting 5025280 events.
[19:54:18.765] <TB1> INFO: 742480 events read in total (31941ms).
[19:54:49.976] <TB1> INFO: 1483912 events read in total (63152ms).
[19:55:21.902] <TB1> INFO: 2225224 events read in total (95078ms).
[19:55:53.902] <TB1> INFO: 2961680 events read in total (127078ms).
[19:56:25.601] <TB1> INFO: 3693024 events read in total (158777ms).
[19:56:57.269] <TB1> INFO: 4421184 events read in total (190445ms).
[19:57:23.662] <TB1> INFO: 5025280 events read in total (216838ms).
[19:57:23.719] <TB1> INFO: Test took 217917ms.
[19:57:23.868] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:57:47.262] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.055753 .. 255.000000
[19:57:47.337] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[19:57:47.345] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:57:47.345] <TB1> INFO: run 1 of 1
[19:57:47.650] <TB1> INFO: Expecting 8519680 events.
[19:58:20.022] <TB1> INFO: 712312 events read in total (31656ms).
[19:58:51.098] <TB1> INFO: 1425288 events read in total (62732ms).
[19:59:21.932] <TB1> INFO: 2138248 events read in total (93566ms).
[19:59:53.241] <TB1> INFO: 2851336 events read in total (124875ms).
[20:00:24.957] <TB1> INFO: 3564480 events read in total (156591ms).
[20:00:55.896] <TB1> INFO: 4277608 events read in total (187530ms).
[20:01:26.829] <TB1> INFO: 4990064 events read in total (218463ms).
[20:01:57.679] <TB1> INFO: 5701832 events read in total (249313ms).
[20:02:29.109] <TB1> INFO: 6413184 events read in total (280743ms).
[20:03:00.425] <TB1> INFO: 7124240 events read in total (312059ms).
[20:03:31.528] <TB1> INFO: 7834752 events read in total (343162ms).
[20:04:01.010] <TB1> INFO: 8519680 events read in total (372644ms).
[20:04:01.094] <TB1> INFO: Test took 373750ms.
[20:04:01.370] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:04:31.261] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.940456 .. 47.646914
[20:04:31.336] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 5 .. 57 (-1/-1) hits flags = 528 (plus default)
[20:04:31.344] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[20:04:31.344] <TB1> INFO: run 1 of 1
[20:04:31.649] <TB1> INFO: Expecting 1763840 events.
[20:05:07.587] <TB1> INFO: 924952 events read in total (35222ms).
[20:05:39.514] <TB1> INFO: 1763840 events read in total (67150ms).
[20:05:39.531] <TB1> INFO: Test took 68188ms.
[20:05:39.571] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:05:55.289] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 1.172519 .. 46.634632
[20:05:55.366] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 1 .. 56 (-1/-1) hits flags = 528 (plus default)
[20:05:55.373] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[20:05:55.373] <TB1> INFO: run 1 of 1
[20:05:55.686] <TB1> INFO: Expecting 1863680 events.
[20:06:32.331] <TB1> INFO: 954040 events read in total (35928ms).
[20:07:05.752] <TB1> INFO: 1863680 events read in total (69350ms).
[20:07:05.768] <TB1> INFO: Test took 70396ms.
[20:07:05.802] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:07:20.813] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 1.172519 .. 46.634632
[20:07:20.888] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 1 .. 56 (-1/-1) hits flags = 528 (plus default)
[20:07:20.897] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[20:07:20.897] <TB1> INFO: run 1 of 1
[20:07:21.218] <TB1> INFO: Expecting 1863680 events.
[20:07:57.532] <TB1> INFO: 954208 events read in total (35597ms).
[20:08:31.682] <TB1> INFO: 1863680 events read in total (69747ms).
[20:08:31.700] <TB1> INFO: Test took 70803ms.
[20:08:31.732] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:08:45.636] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[20:08:45.636] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[20:08:45.644] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[20:08:45.644] <TB1> INFO: run 1 of 1
[20:08:45.955] <TB1> INFO: Expecting 1364480 events.
[20:09:21.456] <TB1> INFO: 879448 events read in total (34785ms).
[20:09:40.979] <TB1> INFO: 1364480 events read in total (54308ms).
[20:09:40.992] <TB1> INFO: Test took 55349ms.
[20:09:41.023] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:09:55.423] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C0.dat
[20:09:55.423] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C1.dat
[20:09:55.423] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C2.dat
[20:09:55.423] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C3.dat
[20:09:55.423] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C4.dat
[20:09:55.423] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C5.dat
[20:09:55.423] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C6.dat
[20:09:55.423] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C7.dat
[20:09:55.424] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C8.dat
[20:09:55.424] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C9.dat
[20:09:55.424] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C10.dat
[20:09:55.424] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C11.dat
[20:09:55.424] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C12.dat
[20:09:55.424] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C13.dat
[20:09:55.424] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C14.dat
[20:09:55.424] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C15.dat
[20:09:55.425] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C0.dat
[20:09:55.431] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C1.dat
[20:09:55.437] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C2.dat
[20:09:55.443] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C3.dat
[20:09:55.449] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C4.dat
[20:09:55.455] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C5.dat
[20:09:55.462] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C6.dat
[20:09:55.470] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C7.dat
[20:09:55.480] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C8.dat
[20:09:55.486] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C9.dat
[20:09:55.492] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C10.dat
[20:09:55.498] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C11.dat
[20:09:55.504] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C12.dat
[20:09:55.510] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C13.dat
[20:09:55.515] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C14.dat
[20:09:55.521] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//trimParameters35_C15.dat
[20:09:55.527] <TB1> INFO: PixTestTrim::trimTest() done
[20:09:55.527] <TB1> INFO: vtrim: 114 98 93 104 99 85 102 100 105 115 91 114 99 89 95 98
[20:09:55.527] <TB1> INFO: vthrcomp: 96 100 96 98 90 92 96 92 91 97 82 102 93 94 96 96
[20:09:55.527] <TB1> INFO: vcal mean: 34.99 34.99 34.97 34.98 34.96 34.99 34.96 35.01 34.99 35.00 35.04 34.97 35.02 34.95 35.00 34.99
[20:09:55.527] <TB1> INFO: vcal RMS: 1.16 0.94 0.90 0.90 1.03 0.88 1.18 1.06 0.94 0.95 0.90 1.09 0.91 1.05 0.92 1.05
[20:09:55.527] <TB1> INFO: bits mean: 9.33 9.38 9.82 9.72 9.10 9.51 9.57 9.33 8.95 9.14 9.84 9.72 8.74 9.81 10.15 9.84
[20:09:55.527] <TB1> INFO: bits RMS: 2.72 2.82 2.51 2.76 2.53 2.55 2.68 2.48 2.63 2.81 2.48 2.55 2.92 2.51 2.48 2.61
[20:09:55.537] <TB1> INFO: ----------------------------------------------------------------------
[20:09:55.537] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[20:09:55.537] <TB1> INFO: ----------------------------------------------------------------------
[20:09:55.542] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[20:09:55.550] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[20:09:55.550] <TB1> INFO: run 1 of 1
[20:09:55.881] <TB1> INFO: Expecting 4160000 events.
[20:10:36.429] <TB1> INFO: 954480 events read in total (39832ms).
[20:11:15.809] <TB1> INFO: 1898535 events read in total (79212ms).
[20:11:55.294] <TB1> INFO: 2831350 events read in total (118698ms).
[20:12:34.615] <TB1> INFO: 3761195 events read in total (158018ms).
[20:12:51.782] <TB1> INFO: 4160000 events read in total (175185ms).
[20:12:51.823] <TB1> INFO: Test took 176273ms.
[20:12:51.920] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:13:19.954] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 175 (-1/-1) hits flags = 528 (plus default)
[20:13:19.962] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[20:13:19.962] <TB1> INFO: run 1 of 1
[20:13:20.265] <TB1> INFO: Expecting 3660800 events.
[20:14:00.862] <TB1> INFO: 969755 events read in total (39881ms).
[20:14:41.426] <TB1> INFO: 1928210 events read in total (80445ms).
[20:15:21.255] <TB1> INFO: 2875285 events read in total (120274ms).
[20:15:54.301] <TB1> INFO: 3660800 events read in total (153320ms).
[20:15:54.336] <TB1> INFO: Test took 154374ms.
[20:15:54.417] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:16:20.500] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[20:16:20.508] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[20:16:20.508] <TB1> INFO: run 1 of 1
[20:16:20.809] <TB1> INFO: Expecting 3473600 events.
[20:17:02.232] <TB1> INFO: 993595 events read in total (40707ms).
[20:17:42.941] <TB1> INFO: 1973020 events read in total (81416ms).
[20:18:23.100] <TB1> INFO: 2941695 events read in total (121575ms).
[20:18:45.629] <TB1> INFO: 3473600 events read in total (144104ms).
[20:18:45.656] <TB1> INFO: Test took 145148ms.
[20:18:45.726] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:19:10.930] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[20:19:10.938] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[20:19:10.938] <TB1> INFO: run 1 of 1
[20:19:11.242] <TB1> INFO: Expecting 3369600 events.
[20:19:53.323] <TB1> INFO: 1008370 events read in total (41366ms).
[20:20:34.047] <TB1> INFO: 2000915 events read in total (82089ms).
[20:21:14.917] <TB1> INFO: 2982870 events read in total (122959ms).
[20:21:31.141] <TB1> INFO: 3369600 events read in total (139183ms).
[20:21:31.169] <TB1> INFO: Test took 140231ms.
[20:21:31.234] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:21:56.423] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[20:21:56.431] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[20:21:56.431] <TB1> INFO: run 1 of 1
[20:21:56.735] <TB1> INFO: Expecting 3390400 events.
[20:22:38.326] <TB1> INFO: 1004470 events read in total (40875ms).
[20:23:20.559] <TB1> INFO: 1993340 events read in total (83108ms).
[20:24:00.809] <TB1> INFO: 2971520 events read in total (123358ms).
[20:24:18.384] <TB1> INFO: 3390400 events read in total (140933ms).
[20:24:18.417] <TB1> INFO: Test took 141986ms.
[20:24:18.491] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:24:43.367] <TB1> INFO: PixTestTrim::trimBitTest() done
[20:24:43.369] <TB1> INFO: PixTestTrim::doTest() done, duration: 2437 seconds
[20:24:43.369] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:24:43.369] <TB1> INFO: Decoding statistics:
[20:24:43.369] <TB1> INFO: General information:
[20:24:43.369] <TB1> INFO: 16bit words read: 0
[20:24:43.369] <TB1> INFO: valid events total: 0
[20:24:43.369] <TB1> INFO: empty events: 0
[20:24:43.369] <TB1> INFO: valid events with pixels: 0
[20:24:43.369] <TB1> INFO: valid pixel hits: 0
[20:24:43.369] <TB1> INFO: Event errors: 0
[20:24:43.369] <TB1> INFO: start marker: 0
[20:24:43.369] <TB1> INFO: stop marker: 0
[20:24:43.369] <TB1> INFO: overflow: 0
[20:24:43.369] <TB1> INFO: invalid 5bit words: 0
[20:24:43.369] <TB1> INFO: invalid XOR eye diagram: 0
[20:24:43.369] <TB1> INFO: TBM errors: 0
[20:24:43.369] <TB1> INFO: flawed TBM headers: 0
[20:24:43.369] <TB1> INFO: flawed TBM trailers: 0
[20:24:43.369] <TB1> INFO: event ID mismatches: 0
[20:24:43.369] <TB1> INFO: ROC errors: 0
[20:24:43.369] <TB1> INFO: missing ROC header(s): 0
[20:24:43.369] <TB1> INFO: misplaced readback start: 0
[20:24:43.369] <TB1> INFO: Pixel decoding errors: 0
[20:24:43.369] <TB1> INFO: pixel data incomplete: 0
[20:24:43.369] <TB1> INFO: pixel address: 0
[20:24:43.369] <TB1> INFO: pulse height fill bit: 0
[20:24:43.369] <TB1> INFO: buffer corruption: 0
[20:24:44.090] <TB1> INFO: ######################################################################
[20:24:44.090] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[20:24:44.090] <TB1> INFO: ######################################################################
[20:24:44.424] <TB1> INFO: Expecting 41600 events.
[20:24:48.643] <TB1> INFO: 41600 events read in total (3503ms).
[20:24:48.643] <TB1> INFO: Test took 4552ms.
[20:24:48.650] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:24:49.236] <TB1> INFO: Expecting 41600 events.
[20:24:53.486] <TB1> INFO: 41600 events read in total (3534ms).
[20:24:53.487] <TB1> INFO: Test took 4576ms.
[20:24:53.493] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:24:53.862] <TB1> INFO: Expecting 41600 events.
[20:24:58.092] <TB1> INFO: 41600 events read in total (3514ms).
[20:24:58.093] <TB1> INFO: Test took 4570ms.
[20:24:58.099] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:24:58.451] <TB1> INFO: Expecting 2560 events.
[20:24:59.521] <TB1> INFO: 2560 events read in total (353ms).
[20:24:59.521] <TB1> INFO: Test took 1415ms.
[20:24:59.936] <TB1> INFO: Expecting 2560 events.
[20:25:01.002] <TB1> INFO: 2560 events read in total (349ms).
[20:25:01.002] <TB1> INFO: Test took 1481ms.
[20:25:01.417] <TB1> INFO: Expecting 2560 events.
[20:25:02.483] <TB1> INFO: 2560 events read in total (349ms).
[20:25:02.484] <TB1> INFO: Test took 1482ms.
[20:25:02.899] <TB1> INFO: Expecting 2560 events.
[20:25:03.962] <TB1> INFO: 2560 events read in total (347ms).
[20:25:03.962] <TB1> INFO: Test took 1478ms.
[20:25:04.378] <TB1> INFO: Expecting 2560 events.
[20:25:05.442] <TB1> INFO: 2560 events read in total (348ms).
[20:25:05.442] <TB1> INFO: Test took 1479ms.
[20:25:05.857] <TB1> INFO: Expecting 2560 events.
[20:25:06.921] <TB1> INFO: 2560 events read in total (347ms).
[20:25:06.921] <TB1> INFO: Test took 1479ms.
[20:25:07.337] <TB1> INFO: Expecting 2560 events.
[20:25:08.401] <TB1> INFO: 2560 events read in total (348ms).
[20:25:08.401] <TB1> INFO: Test took 1479ms.
[20:25:08.816] <TB1> INFO: Expecting 2560 events.
[20:25:09.882] <TB1> INFO: 2560 events read in total (350ms).
[20:25:09.882] <TB1> INFO: Test took 1481ms.
[20:25:10.297] <TB1> INFO: Expecting 2560 events.
[20:25:11.363] <TB1> INFO: 2560 events read in total (350ms).
[20:25:11.363] <TB1> INFO: Test took 1481ms.
[20:25:11.778] <TB1> INFO: Expecting 2560 events.
[20:25:12.840] <TB1> INFO: 2560 events read in total (345ms).
[20:25:12.841] <TB1> INFO: Test took 1478ms.
[20:25:13.256] <TB1> INFO: Expecting 2560 events.
[20:25:14.320] <TB1> INFO: 2560 events read in total (347ms).
[20:25:14.320] <TB1> INFO: Test took 1479ms.
[20:25:14.735] <TB1> INFO: Expecting 2560 events.
[20:25:15.800] <TB1> INFO: 2560 events read in total (349ms).
[20:25:15.800] <TB1> INFO: Test took 1480ms.
[20:25:16.215] <TB1> INFO: Expecting 2560 events.
[20:25:17.279] <TB1> INFO: 2560 events read in total (347ms).
[20:25:17.280] <TB1> INFO: Test took 1480ms.
[20:25:17.695] <TB1> INFO: Expecting 2560 events.
[20:25:18.757] <TB1> INFO: 2560 events read in total (346ms).
[20:25:18.757] <TB1> INFO: Test took 1477ms.
[20:25:19.173] <TB1> INFO: Expecting 2560 events.
[20:25:20.237] <TB1> INFO: 2560 events read in total (348ms).
[20:25:20.238] <TB1> INFO: Test took 1480ms.
[20:25:20.653] <TB1> INFO: Expecting 2560 events.
[20:25:21.717] <TB1> INFO: 2560 events read in total (347ms).
[20:25:21.717] <TB1> INFO: Test took 1479ms.
[20:25:21.722] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:25:22.134] <TB1> INFO: Expecting 655360 events.
[20:25:36.947] <TB1> INFO: 655360 events read in total (14097ms).
[20:25:36.955] <TB1> INFO: Expecting 655360 events.
[20:25:50.807] <TB1> INFO: 655360 events read in total (13323ms).
[20:25:50.819] <TB1> INFO: Expecting 655360 events.
[20:26:04.720] <TB1> INFO: 655360 events read in total (13373ms).
[20:26:04.735] <TB1> INFO: Expecting 655360 events.
[20:26:18.701] <TB1> INFO: 655360 events read in total (13437ms).
[20:26:18.720] <TB1> INFO: Expecting 655360 events.
[20:26:32.451] <TB1> INFO: 655360 events read in total (13203ms).
[20:26:32.473] <TB1> INFO: Expecting 655360 events.
[20:26:46.409] <TB1> INFO: 655360 events read in total (13408ms).
[20:26:46.435] <TB1> INFO: Expecting 655360 events.
[20:27:00.481] <TB1> INFO: 655360 events read in total (13517ms).
[20:27:00.514] <TB1> INFO: Expecting 655360 events.
[20:27:14.373] <TB1> INFO: 655360 events read in total (13331ms).
[20:27:14.408] <TB1> INFO: Expecting 655360 events.
[20:27:28.512] <TB1> INFO: 655360 events read in total (13576ms).
[20:27:28.549] <TB1> INFO: Expecting 655360 events.
[20:27:42.338] <TB1> INFO: 655360 events read in total (13261ms).
[20:27:42.380] <TB1> INFO: Expecting 655360 events.
[20:27:56.267] <TB1> INFO: 655360 events read in total (13359ms).
[20:27:56.311] <TB1> INFO: Expecting 655360 events.
[20:28:10.990] <TB1> INFO: 655360 events read in total (14151ms).
[20:28:11.047] <TB1> INFO: Expecting 655360 events.
[20:28:24.988] <TB1> INFO: 655360 events read in total (13413ms).
[20:28:25.038] <TB1> INFO: Expecting 655360 events.
[20:28:39.019] <TB1> INFO: 655360 events read in total (13453ms).
[20:28:39.073] <TB1> INFO: Expecting 655360 events.
[20:28:53.894] <TB1> INFO: 655360 events read in total (14293ms).
[20:28:53.962] <TB1> INFO: Expecting 655360 events.
[20:29:07.845] <TB1> INFO: 655360 events read in total (13355ms).
[20:29:07.905] <TB1> INFO: Test took 226183ms.
[20:29:07.990] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:29:08.296] <TB1> INFO: Expecting 655360 events.
[20:29:22.408] <TB1> INFO: 655360 events read in total (13396ms).
[20:29:22.417] <TB1> INFO: Expecting 655360 events.
[20:29:36.532] <TB1> INFO: 655360 events read in total (13587ms).
[20:29:36.544] <TB1> INFO: Expecting 655360 events.
[20:29:50.360] <TB1> INFO: 655360 events read in total (13288ms).
[20:29:50.375] <TB1> INFO: Expecting 655360 events.
[20:30:04.369] <TB1> INFO: 655360 events read in total (13466ms).
[20:30:04.387] <TB1> INFO: Expecting 655360 events.
[20:30:18.318] <TB1> INFO: 655360 events read in total (13403ms).
[20:30:18.341] <TB1> INFO: Expecting 655360 events.
[20:30:32.251] <TB1> INFO: 655360 events read in total (13382ms).
[20:30:32.276] <TB1> INFO: Expecting 655360 events.
[20:30:46.207] <TB1> INFO: 655360 events read in total (13402ms).
[20:30:46.238] <TB1> INFO: Expecting 655360 events.
[20:31:00.980] <TB1> INFO: 655360 events read in total (14214ms).
[20:31:01.020] <TB1> INFO: Expecting 655360 events.
[20:31:14.843] <TB1> INFO: 655360 events read in total (13295ms).
[20:31:14.882] <TB1> INFO: Expecting 655360 events.
[20:31:28.760] <TB1> INFO: 655360 events read in total (13350ms).
[20:31:28.811] <TB1> INFO: Expecting 655360 events.
[20:31:43.296] <TB1> INFO: 655360 events read in total (13957ms).
[20:31:43.338] <TB1> INFO: Expecting 655360 events.
[20:31:57.033] <TB1> INFO: 655360 events read in total (13167ms).
[20:31:57.089] <TB1> INFO: Expecting 655360 events.
[20:32:11.140] <TB1> INFO: 655360 events read in total (13523ms).
[20:32:11.189] <TB1> INFO: Expecting 655360 events.
[20:32:25.743] <TB1> INFO: 655360 events read in total (14026ms).
[20:32:25.808] <TB1> INFO: Expecting 655360 events.
[20:32:39.802] <TB1> INFO: 655360 events read in total (13466ms).
[20:32:39.859] <TB1> INFO: Expecting 655360 events.
[20:32:53.841] <TB1> INFO: 655360 events read in total (13454ms).
[20:32:53.904] <TB1> INFO: Test took 225914ms.
[20:32:54.102] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.110] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.117] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:32:54.124] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[20:32:54.131] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[20:32:54.138] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[20:32:54.145] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[20:32:54.153] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[20:32:54.160] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.167] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.174] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.181] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.187] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.194] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.201] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:32:54.209] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[20:32:54.216] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[20:32:54.223] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.230] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.237] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.244] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.252] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.258] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:32:54.265] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.273] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.280] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:32:54.338] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C0.dat
[20:32:54.338] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C1.dat
[20:32:54.338] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C2.dat
[20:32:54.338] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C3.dat
[20:32:54.338] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C4.dat
[20:32:54.338] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C5.dat
[20:32:54.338] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C6.dat
[20:32:54.339] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C7.dat
[20:32:54.339] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C8.dat
[20:32:54.339] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C9.dat
[20:32:54.339] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C10.dat
[20:32:54.339] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C11.dat
[20:32:54.339] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C12.dat
[20:32:54.339] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C13.dat
[20:32:54.339] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C14.dat
[20:32:54.339] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//dacParameters35_C15.dat
[20:32:54.654] <TB1> INFO: Expecting 41600 events.
[20:32:58.564] <TB1> INFO: 41600 events read in total (3194ms).
[20:32:58.564] <TB1> INFO: Test took 4220ms.
[20:32:59.125] <TB1> INFO: Expecting 41600 events.
[20:33:03.021] <TB1> INFO: 41600 events read in total (3180ms).
[20:33:03.021] <TB1> INFO: Test took 4213ms.
[20:33:03.588] <TB1> INFO: Expecting 41600 events.
[20:33:07.480] <TB1> INFO: 41600 events read in total (3176ms).
[20:33:07.481] <TB1> INFO: Test took 4217ms.
[20:33:07.716] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:07.848] <TB1> INFO: Expecting 2560 events.
[20:33:08.911] <TB1> INFO: 2560 events read in total (347ms).
[20:33:08.912] <TB1> INFO: Test took 1196ms.
[20:33:08.914] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:09.328] <TB1> INFO: Expecting 2560 events.
[20:33:10.391] <TB1> INFO: 2560 events read in total (347ms).
[20:33:10.392] <TB1> INFO: Test took 1478ms.
[20:33:10.394] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:10.808] <TB1> INFO: Expecting 2560 events.
[20:33:11.873] <TB1> INFO: 2560 events read in total (349ms).
[20:33:11.873] <TB1> INFO: Test took 1479ms.
[20:33:11.875] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:12.289] <TB1> INFO: Expecting 2560 events.
[20:33:13.356] <TB1> INFO: 2560 events read in total (351ms).
[20:33:13.357] <TB1> INFO: Test took 1482ms.
[20:33:13.359] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:13.773] <TB1> INFO: Expecting 2560 events.
[20:33:14.838] <TB1> INFO: 2560 events read in total (349ms).
[20:33:14.839] <TB1> INFO: Test took 1480ms.
[20:33:14.841] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:15.255] <TB1> INFO: Expecting 2560 events.
[20:33:16.318] <TB1> INFO: 2560 events read in total (347ms).
[20:33:16.318] <TB1> INFO: Test took 1477ms.
[20:33:16.321] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:16.735] <TB1> INFO: Expecting 2560 events.
[20:33:17.799] <TB1> INFO: 2560 events read in total (348ms).
[20:33:17.799] <TB1> INFO: Test took 1478ms.
[20:33:17.801] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:18.215] <TB1> INFO: Expecting 2560 events.
[20:33:19.279] <TB1> INFO: 2560 events read in total (348ms).
[20:33:19.279] <TB1> INFO: Test took 1478ms.
[20:33:19.282] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:19.695] <TB1> INFO: Expecting 2560 events.
[20:33:20.759] <TB1> INFO: 2560 events read in total (348ms).
[20:33:20.759] <TB1> INFO: Test took 1477ms.
[20:33:20.761] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:21.175] <TB1> INFO: Expecting 2560 events.
[20:33:22.242] <TB1> INFO: 2560 events read in total (351ms).
[20:33:22.242] <TB1> INFO: Test took 1481ms.
[20:33:22.245] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:22.658] <TB1> INFO: Expecting 2560 events.
[20:33:23.723] <TB1> INFO: 2560 events read in total (348ms).
[20:33:23.723] <TB1> INFO: Test took 1479ms.
[20:33:23.726] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:24.140] <TB1> INFO: Expecting 2560 events.
[20:33:25.212] <TB1> INFO: 2560 events read in total (355ms).
[20:33:25.212] <TB1> INFO: Test took 1486ms.
[20:33:25.215] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:25.630] <TB1> INFO: Expecting 2560 events.
[20:33:26.700] <TB1> INFO: 2560 events read in total (354ms).
[20:33:26.700] <TB1> INFO: Test took 1485ms.
[20:33:26.703] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:27.116] <TB1> INFO: Expecting 2560 events.
[20:33:28.184] <TB1> INFO: 2560 events read in total (351ms).
[20:33:28.184] <TB1> INFO: Test took 1481ms.
[20:33:28.187] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:28.601] <TB1> INFO: Expecting 2560 events.
[20:33:29.667] <TB1> INFO: 2560 events read in total (350ms).
[20:33:29.667] <TB1> INFO: Test took 1480ms.
[20:33:29.669] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:30.083] <TB1> INFO: Expecting 2560 events.
[20:33:31.150] <TB1> INFO: 2560 events read in total (350ms).
[20:33:31.151] <TB1> INFO: Test took 1482ms.
[20:33:31.153] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:31.565] <TB1> INFO: Expecting 2560 events.
[20:33:32.633] <TB1> INFO: 2560 events read in total (351ms).
[20:33:32.633] <TB1> INFO: Test took 1480ms.
[20:33:32.636] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:33.050] <TB1> INFO: Expecting 2560 events.
[20:33:34.114] <TB1> INFO: 2560 events read in total (348ms).
[20:33:34.114] <TB1> INFO: Test took 1478ms.
[20:33:34.117] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:34.530] <TB1> INFO: Expecting 2560 events.
[20:33:35.595] <TB1> INFO: 2560 events read in total (348ms).
[20:33:35.595] <TB1> INFO: Test took 1478ms.
[20:33:35.598] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:36.012] <TB1> INFO: Expecting 2560 events.
[20:33:37.076] <TB1> INFO: 2560 events read in total (348ms).
[20:33:37.077] <TB1> INFO: Test took 1479ms.
[20:33:37.080] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:37.493] <TB1> INFO: Expecting 2560 events.
[20:33:38.561] <TB1> INFO: 2560 events read in total (352ms).
[20:33:38.561] <TB1> INFO: Test took 1481ms.
[20:33:38.565] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:38.981] <TB1> INFO: Expecting 2560 events.
[20:33:40.054] <TB1> INFO: 2560 events read in total (357ms).
[20:33:40.055] <TB1> INFO: Test took 1491ms.
[20:33:40.058] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:40.472] <TB1> INFO: Expecting 2560 events.
[20:33:41.544] <TB1> INFO: 2560 events read in total (356ms).
[20:33:41.544] <TB1> INFO: Test took 1486ms.
[20:33:41.547] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:41.961] <TB1> INFO: Expecting 2560 events.
[20:33:43.032] <TB1> INFO: 2560 events read in total (355ms).
[20:33:43.032] <TB1> INFO: Test took 1485ms.
[20:33:43.035] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:43.448] <TB1> INFO: Expecting 2560 events.
[20:33:44.512] <TB1> INFO: 2560 events read in total (347ms).
[20:33:44.512] <TB1> INFO: Test took 1477ms.
[20:33:44.515] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:44.929] <TB1> INFO: Expecting 2560 events.
[20:33:45.993] <TB1> INFO: 2560 events read in total (348ms).
[20:33:45.993] <TB1> INFO: Test took 1478ms.
[20:33:45.996] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:46.409] <TB1> INFO: Expecting 2560 events.
[20:33:47.473] <TB1> INFO: 2560 events read in total (347ms).
[20:33:47.473] <TB1> INFO: Test took 1477ms.
[20:33:47.476] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:47.890] <TB1> INFO: Expecting 2560 events.
[20:33:48.954] <TB1> INFO: 2560 events read in total (348ms).
[20:33:48.954] <TB1> INFO: Test took 1478ms.
[20:33:48.957] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:49.370] <TB1> INFO: Expecting 2560 events.
[20:33:50.434] <TB1> INFO: 2560 events read in total (347ms).
[20:33:50.434] <TB1> INFO: Test took 1478ms.
[20:33:50.437] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:50.851] <TB1> INFO: Expecting 2560 events.
[20:33:51.915] <TB1> INFO: 2560 events read in total (348ms).
[20:33:51.915] <TB1> INFO: Test took 1478ms.
[20:33:51.918] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:52.331] <TB1> INFO: Expecting 2560 events.
[20:33:53.395] <TB1> INFO: 2560 events read in total (348ms).
[20:33:53.395] <TB1> INFO: Test took 1477ms.
[20:33:53.398] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:33:53.811] <TB1> INFO: Expecting 2560 events.
[20:33:54.876] <TB1> INFO: 2560 events read in total (349ms).
[20:33:54.876] <TB1> INFO: Test took 1478ms.
[20:33:55.539] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 551 seconds
[20:33:55.539] <TB1> INFO: PH scale (per ROC): 65 71 71 75 65 66 72 76 74 79 76 69 71 73 77 71
[20:33:55.539] <TB1> INFO: PH offset (per ROC): 181 176 167 171 176 174 189 176 175 171 156 177 176 158 165 171
[20:33:55.543] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:33:55.543] <TB1> INFO: Decoding statistics:
[20:33:55.543] <TB1> INFO: General information:
[20:33:55.543] <TB1> INFO: 16bit words read: 91940
[20:33:55.543] <TB1> INFO: valid events total: 10240
[20:33:55.543] <TB1> INFO: empty events: 7735
[20:33:55.543] <TB1> INFO: valid events with pixels: 2505
[20:33:55.543] <TB1> INFO: valid pixel hits: 2505
[20:33:55.543] <TB1> INFO: Event errors: 0
[20:33:55.543] <TB1> INFO: start marker: 0
[20:33:55.543] <TB1> INFO: stop marker: 0
[20:33:55.543] <TB1> INFO: overflow: 0
[20:33:55.543] <TB1> INFO: invalid 5bit words: 0
[20:33:55.543] <TB1> INFO: invalid XOR eye diagram: 0
[20:33:55.543] <TB1> INFO: TBM errors: 0
[20:33:55.543] <TB1> INFO: flawed TBM headers: 0
[20:33:55.543] <TB1> INFO: flawed TBM trailers: 0
[20:33:55.543] <TB1> INFO: event ID mismatches: 0
[20:33:55.543] <TB1> INFO: ROC errors: 0
[20:33:55.543] <TB1> INFO: missing ROC header(s): 0
[20:33:55.543] <TB1> INFO: misplaced readback start: 0
[20:33:55.543] <TB1> INFO: Pixel decoding errors: 0
[20:33:55.543] <TB1> INFO: pixel data incomplete: 0
[20:33:55.543] <TB1> INFO: pixel address: 0
[20:33:55.543] <TB1> INFO: pulse height fill bit: 0
[20:33:55.543] <TB1> INFO: buffer corruption: 0
[20:33:55.711] <TB1> INFO: ######################################################################
[20:33:55.711] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[20:33:55.711] <TB1> INFO: ######################################################################
[20:33:55.721] <TB1> INFO: scanning low vcal = 10
[20:33:56.037] <TB1> INFO: Expecting 41600 events.
[20:33:59.800] <TB1> INFO: 41600 events read in total (3047ms).
[20:33:59.801] <TB1> INFO: Test took 4080ms.
[20:33:59.803] <TB1> INFO: scanning low vcal = 20
[20:34:00.216] <TB1> INFO: Expecting 41600 events.
[20:34:03.968] <TB1> INFO: 41600 events read in total (3035ms).
[20:34:03.978] <TB1> INFO: Test took 4175ms.
[20:34:03.980] <TB1> INFO: scanning low vcal = 30
[20:34:04.384] <TB1> INFO: Expecting 41600 events.
[20:34:08.176] <TB1> INFO: 41600 events read in total (3076ms).
[20:34:08.177] <TB1> INFO: Test took 4197ms.
[20:34:08.180] <TB1> INFO: scanning low vcal = 40
[20:34:08.590] <TB1> INFO: Expecting 41600 events.
[20:34:12.925] <TB1> INFO: 41600 events read in total (3619ms).
[20:34:12.926] <TB1> INFO: Test took 4746ms.
[20:34:12.929] <TB1> INFO: scanning low vcal = 50
[20:34:13.279] <TB1> INFO: Expecting 41600 events.
[20:34:17.656] <TB1> INFO: 41600 events read in total (3661ms).
[20:34:17.656] <TB1> INFO: Test took 4727ms.
[20:34:17.659] <TB1> INFO: scanning low vcal = 60
[20:34:18.009] <TB1> INFO: Expecting 41600 events.
[20:34:22.363] <TB1> INFO: 41600 events read in total (3638ms).
[20:34:22.364] <TB1> INFO: Test took 4705ms.
[20:34:22.367] <TB1> INFO: scanning low vcal = 70
[20:34:22.715] <TB1> INFO: Expecting 41600 events.
[20:34:27.096] <TB1> INFO: 41600 events read in total (3665ms).
[20:34:27.096] <TB1> INFO: Test took 4729ms.
[20:34:27.100] <TB1> INFO: scanning low vcal = 80
[20:34:27.454] <TB1> INFO: Expecting 41600 events.
[20:34:31.738] <TB1> INFO: 41600 events read in total (3567ms).
[20:34:31.738] <TB1> INFO: Test took 4638ms.
[20:34:31.741] <TB1> INFO: scanning low vcal = 90
[20:34:32.091] <TB1> INFO: Expecting 41600 events.
[20:34:36.381] <TB1> INFO: 41600 events read in total (3573ms).
[20:34:36.381] <TB1> INFO: Test took 4640ms.
[20:34:36.385] <TB1> INFO: scanning low vcal = 100
[20:34:36.738] <TB1> INFO: Expecting 41600 events.
[20:34:41.129] <TB1> INFO: 41600 events read in total (3675ms).
[20:34:41.129] <TB1> INFO: Test took 4744ms.
[20:34:41.132] <TB1> INFO: scanning low vcal = 110
[20:34:41.486] <TB1> INFO: Expecting 41600 events.
[20:34:45.771] <TB1> INFO: 41600 events read in total (3569ms).
[20:34:45.771] <TB1> INFO: Test took 4638ms.
[20:34:45.775] <TB1> INFO: scanning low vcal = 120
[20:34:46.131] <TB1> INFO: Expecting 41600 events.
[20:34:50.434] <TB1> INFO: 41600 events read in total (3587ms).
[20:34:50.435] <TB1> INFO: Test took 4660ms.
[20:34:50.438] <TB1> INFO: scanning low vcal = 130
[20:34:50.772] <TB1> INFO: Expecting 41600 events.
[20:34:55.062] <TB1> INFO: 41600 events read in total (3574ms).
[20:34:55.063] <TB1> INFO: Test took 4624ms.
[20:34:55.066] <TB1> INFO: scanning low vcal = 140
[20:34:55.416] <TB1> INFO: Expecting 41600 events.
[20:34:59.717] <TB1> INFO: 41600 events read in total (3585ms).
[20:34:59.718] <TB1> INFO: Test took 4652ms.
[20:34:59.721] <TB1> INFO: scanning low vcal = 150
[20:35:00.077] <TB1> INFO: Expecting 41600 events.
[20:35:04.444] <TB1> INFO: 41600 events read in total (3651ms).
[20:35:04.445] <TB1> INFO: Test took 4724ms.
[20:35:04.448] <TB1> INFO: scanning low vcal = 160
[20:35:04.802] <TB1> INFO: Expecting 41600 events.
[20:35:09.140] <TB1> INFO: 41600 events read in total (3622ms).
[20:35:09.141] <TB1> INFO: Test took 4693ms.
[20:35:09.144] <TB1> INFO: scanning low vcal = 170
[20:35:09.497] <TB1> INFO: Expecting 41600 events.
[20:35:13.875] <TB1> INFO: 41600 events read in total (3661ms).
[20:35:13.876] <TB1> INFO: Test took 4732ms.
[20:35:13.880] <TB1> INFO: scanning low vcal = 180
[20:35:14.231] <TB1> INFO: Expecting 41600 events.
[20:35:18.501] <TB1> INFO: 41600 events read in total (3554ms).
[20:35:18.502] <TB1> INFO: Test took 4622ms.
[20:35:18.505] <TB1> INFO: scanning low vcal = 190
[20:35:18.863] <TB1> INFO: Expecting 41600 events.
[20:35:23.140] <TB1> INFO: 41600 events read in total (3560ms).
[20:35:23.141] <TB1> INFO: Test took 4636ms.
[20:35:23.144] <TB1> INFO: scanning low vcal = 200
[20:35:23.499] <TB1> INFO: Expecting 41600 events.
[20:35:27.791] <TB1> INFO: 41600 events read in total (3576ms).
[20:35:27.792] <TB1> INFO: Test took 4648ms.
[20:35:27.795] <TB1> INFO: scanning low vcal = 210
[20:35:28.133] <TB1> INFO: Expecting 41600 events.
[20:35:32.414] <TB1> INFO: 41600 events read in total (3565ms).
[20:35:32.414] <TB1> INFO: Test took 4619ms.
[20:35:32.418] <TB1> INFO: scanning low vcal = 220
[20:35:32.771] <TB1> INFO: Expecting 41600 events.
[20:35:37.060] <TB1> INFO: 41600 events read in total (3573ms).
[20:35:37.061] <TB1> INFO: Test took 4643ms.
[20:35:37.064] <TB1> INFO: scanning low vcal = 230
[20:35:37.420] <TB1> INFO: Expecting 41600 events.
[20:35:41.740] <TB1> INFO: 41600 events read in total (3603ms).
[20:35:41.741] <TB1> INFO: Test took 4677ms.
[20:35:41.744] <TB1> INFO: scanning low vcal = 240
[20:35:42.093] <TB1> INFO: Expecting 41600 events.
[20:35:46.447] <TB1> INFO: 41600 events read in total (3636ms).
[20:35:46.447] <TB1> INFO: Test took 4703ms.
[20:35:46.451] <TB1> INFO: scanning low vcal = 250
[20:35:46.804] <TB1> INFO: Expecting 41600 events.
[20:35:51.111] <TB1> INFO: 41600 events read in total (3590ms).
[20:35:51.111] <TB1> INFO: Test took 4660ms.
[20:35:51.116] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[20:35:51.461] <TB1> INFO: Expecting 41600 events.
[20:35:55.744] <TB1> INFO: 41600 events read in total (3567ms).
[20:35:55.744] <TB1> INFO: Test took 4628ms.
[20:35:55.748] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[20:35:56.099] <TB1> INFO: Expecting 41600 events.
[20:36:00.383] <TB1> INFO: 41600 events read in total (3568ms).
[20:36:00.383] <TB1> INFO: Test took 4635ms.
[20:36:00.387] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[20:36:00.744] <TB1> INFO: Expecting 41600 events.
[20:36:05.021] <TB1> INFO: 41600 events read in total (3560ms).
[20:36:05.021] <TB1> INFO: Test took 4634ms.
[20:36:05.025] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[20:36:05.360] <TB1> INFO: Expecting 41600 events.
[20:36:09.636] <TB1> INFO: 41600 events read in total (3559ms).
[20:36:09.636] <TB1> INFO: Test took 4611ms.
[20:36:09.640] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[20:36:09.983] <TB1> INFO: Expecting 41600 events.
[20:36:14.238] <TB1> INFO: 41600 events read in total (3539ms).
[20:36:14.238] <TB1> INFO: Test took 4598ms.
[20:36:14.823] <TB1> INFO: PixTestGainPedestal::measure() done
[20:36:49.087] <TB1> INFO: PixTestGainPedestal::fit() done
[20:36:49.087] <TB1> INFO: non-linearity mean: 0.953 0.966 0.963 0.965 0.955 0.962 0.952 0.960 0.956 0.947 0.953 0.952 0.958 0.963 0.962 0.955
[20:36:49.087] <TB1> INFO: non-linearity RMS: 0.008 0.004 0.005 0.005 0.007 0.006 0.009 0.006 0.007 0.008 0.006 0.006 0.006 0.006 0.006 0.007
[20:36:49.088] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C0.dat
[20:36:49.107] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C1.dat
[20:36:49.126] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C2.dat
[20:36:49.146] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C3.dat
[20:36:49.165] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C4.dat
[20:36:49.184] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C5.dat
[20:36:49.204] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C6.dat
[20:36:49.223] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C7.dat
[20:36:49.242] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C8.dat
[20:36:49.262] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C9.dat
[20:36:49.281] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C10.dat
[20:36:49.300] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C11.dat
[20:36:49.320] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C12.dat
[20:36:49.339] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C13.dat
[20:36:49.358] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C14.dat
[20:36:49.378] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//phCalibrationFitErr35_C15.dat
[20:36:49.397] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 173 seconds
[20:36:49.397] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:36:49.397] <TB1> INFO: Decoding statistics:
[20:36:49.397] <TB1> INFO: General information:
[20:36:49.397] <TB1> INFO: 16bit words read: 2662400
[20:36:49.397] <TB1> INFO: valid events total: 166400
[20:36:49.397] <TB1> INFO: empty events: 0
[20:36:49.397] <TB1> INFO: valid events with pixels: 166400
[20:36:49.397] <TB1> INFO: valid pixel hits: 665520
[20:36:49.397] <TB1> INFO: Event errors: 0
[20:36:49.397] <TB1> INFO: start marker: 0
[20:36:49.397] <TB1> INFO: stop marker: 0
[20:36:49.397] <TB1> INFO: overflow: 0
[20:36:49.397] <TB1> INFO: invalid 5bit words: 0
[20:36:49.397] <TB1> INFO: invalid XOR eye diagram: 0
[20:36:49.397] <TB1> INFO: TBM errors: 0
[20:36:49.397] <TB1> INFO: flawed TBM headers: 0
[20:36:49.397] <TB1> INFO: flawed TBM trailers: 0
[20:36:49.397] <TB1> INFO: event ID mismatches: 0
[20:36:49.397] <TB1> INFO: ROC errors: 0
[20:36:49.397] <TB1> INFO: missing ROC header(s): 0
[20:36:49.397] <TB1> INFO: misplaced readback start: 0
[20:36:49.397] <TB1> INFO: Pixel decoding errors: 0
[20:36:49.397] <TB1> INFO: pixel data incomplete: 0
[20:36:49.397] <TB1> INFO: pixel address: 0
[20:36:49.397] <TB1> INFO: pulse height fill bit: 0
[20:36:49.397] <TB1> INFO: buffer corruption: 0
[20:36:49.404] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C15.dat
[20:36:49.405] <TB1> INFO: ######################################################################
[20:36:49.405] <TB1> INFO: PixTestReadback::doTest()
[20:36:49.405] <TB1> INFO: ######################################################################
[20:36:49.406] <TB1> INFO: PixTestReadback::RES sent once
[20:37:11.217] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C0.dat
[20:37:11.217] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C1.dat
[20:37:11.217] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C2.dat
[20:37:11.217] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C3.dat
[20:37:11.217] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C4.dat
[20:37:11.217] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C5.dat
[20:37:11.217] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C6.dat
[20:37:11.218] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C7.dat
[20:37:11.218] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C8.dat
[20:37:11.218] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C9.dat
[20:37:11.218] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C10.dat
[20:37:11.218] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C11.dat
[20:37:11.218] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C12.dat
[20:37:11.218] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C13.dat
[20:37:11.218] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C14.dat
[20:37:11.218] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C15.dat
[20:37:11.265] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[20:37:11.265] <TB1> INFO: PixTestReadback::RES sent once
[20:37:33.070] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C0.dat
[20:37:33.070] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C1.dat
[20:37:33.070] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C2.dat
[20:37:33.070] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C3.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C4.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C5.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C6.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C7.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C8.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C9.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C10.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C11.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C12.dat
[20:37:33.071] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C13.dat
[20:37:33.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C14.dat
[20:37:33.072] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C15.dat
[20:37:33.119] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[20:37:33.120] <TB1> INFO: PixTestReadback::RES sent once
[20:37:50.014] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[20:37:50.014] <TB1> INFO: Vbg will be calibrated using Vd calibration
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 159.8calibrated Vbg = 1.19756 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159.7calibrated Vbg = 1.198 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.2calibrated Vbg = 1.19749 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 159.5calibrated Vbg = 1.21014 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 161.6calibrated Vbg = 1.21179 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.7calibrated Vbg = 1.21264 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.8calibrated Vbg = 1.2143 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.7calibrated Vbg = 1.20827 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 159.8calibrated Vbg = 1.2088 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 159.8calibrated Vbg = 1.20102 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 144calibrated Vbg = 1.20575 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 156calibrated Vbg = 1.20306 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 157.2calibrated Vbg = 1.19182 :::*/*/*/*/
[20:37:50.014] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.3calibrated Vbg = 1.1957 :::*/*/*/*/
[20:37:50.015] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 159.3calibrated Vbg = 1.20074 :::*/*/*/*/
[20:37:50.015] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 147.3calibrated Vbg = 1.19688 :::*/*/*/*/
[20:37:50.017] <TB1> INFO: PixTestReadback::RES sent once
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C0.dat
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C1.dat
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C2.dat
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C3.dat
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C4.dat
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C5.dat
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C6.dat
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C7.dat
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C8.dat
[20:42:30.934] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C9.dat
[20:42:30.935] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C10.dat
[20:42:30.935] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C11.dat
[20:42:30.935] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C12.dat
[20:42:30.935] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C13.dat
[20:42:30.935] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C14.dat
[20:42:30.935] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//004_Fulltest_p17//readbackCal_C15.dat
[20:42:30.980] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[20:42:30.980] <TB1> INFO: PixTestReadback::doTest() done
[20:42:30.981] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[20:42:30.981] <TB1> INFO: Decoding statistics:
[20:42:30.981] <TB1> INFO: General information:
[20:42:30.981] <TB1> INFO: 16bit words read: 1024
[20:42:30.981] <TB1> INFO: valid events total: 128
[20:42:30.981] <TB1> INFO: empty events: 128
[20:42:30.981] <TB1> INFO: valid events with pixels: 0
[20:42:30.981] <TB1> INFO: valid pixel hits: 0
[20:42:30.981] <TB1> INFO: Event errors: 0
[20:42:30.981] <TB1> INFO: start marker: 0
[20:42:30.981] <TB1> INFO: stop marker: 0
[20:42:30.981] <TB1> INFO: overflow: 0
[20:42:30.981] <TB1> INFO: invalid 5bit words: 0
[20:42:30.981] <TB1> INFO: invalid XOR eye diagram: 0
[20:42:30.981] <TB1> INFO: TBM errors: 0
[20:42:30.981] <TB1> INFO: flawed TBM headers: 0
[20:42:30.981] <TB1> INFO: flawed TBM trailers: 0
[20:42:30.981] <TB1> INFO: event ID mismatches: 0
[20:42:30.981] <TB1> INFO: ROC errors: 0
[20:42:30.981] <TB1> INFO: missing ROC header(s): 0
[20:42:30.981] <TB1> INFO: misplaced readback start: 0
[20:42:30.981] <TB1> INFO: Pixel decoding errors: 0
[20:42:30.981] <TB1> INFO: pixel data incomplete: 0
[20:42:30.981] <TB1> INFO: pixel address: 0
[20:42:30.981] <TB1> INFO: pulse height fill bit: 0
[20:42:30.981] <TB1> INFO: buffer corruption: 0
[20:42:30.993] <TB1> INFO: Decoding statistics:
[20:42:30.993] <TB1> INFO: General information:
[20:42:30.993] <TB1> INFO: 16bit words read: 2755364
[20:42:30.993] <TB1> INFO: valid events total: 176768
[20:42:30.993] <TB1> INFO: empty events: 7863
[20:42:30.993] <TB1> INFO: valid events with pixels: 168905
[20:42:30.993] <TB1> INFO: valid pixel hits: 668025
[20:42:30.993] <TB1> INFO: Event errors: 0
[20:42:30.993] <TB1> INFO: start marker: 0
[20:42:30.993] <TB1> INFO: stop marker: 0
[20:42:30.993] <TB1> INFO: overflow: 0
[20:42:30.993] <TB1> INFO: invalid 5bit words: 0
[20:42:30.993] <TB1> INFO: invalid XOR eye diagram: 0
[20:42:30.993] <TB1> INFO: TBM errors: 0
[20:42:30.993] <TB1> INFO: flawed TBM headers: 0
[20:42:30.993] <TB1> INFO: flawed TBM trailers: 0
[20:42:30.993] <TB1> INFO: event ID mismatches: 0
[20:42:30.993] <TB1> INFO: ROC errors: 0
[20:42:30.993] <TB1> INFO: missing ROC header(s): 0
[20:42:30.993] <TB1> INFO: misplaced readback start: 0
[20:42:30.993] <TB1> INFO: Pixel decoding errors: 0
[20:42:30.993] <TB1> INFO: pixel data incomplete: 0
[20:42:30.993] <TB1> INFO: pixel address: 0
[20:42:30.993] <TB1> INFO: pulse height fill bit: 0
[20:42:30.993] <TB1> INFO: buffer corruption: 0
[20:42:30.993] <TB1> INFO: enter test to run
[20:42:30.993] <TB1> INFO: test: exit no parameter change
[20:42:31.182] <TB1> QUIET: Connection to board 153 closed.
[20:42:31.262] <TB1> INFO: pXar: this is the end, my friend