[16:57:45.810] <TB1> INFO: *** Welcome to pxar ***
[16:57:45.810] <TB1> INFO: *** Today: 2016/01/18
[16:57:45.881] <TB1> INFO: *** Version: 3197-dirty
[16:57:45.881] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters_C15.dat
[16:57:45.882] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//tbmParameters_C0b.dat
[16:57:45.882] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//defaultMaskFile.dat
[16:57:45.882] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters_C15.dat
[16:57:45.982] <TB1> INFO: clk: 4
[16:57:45.982] <TB1> INFO: ctr: 4
[16:57:45.982] <TB1> INFO: sda: 19
[16:57:45.982] <TB1> INFO: tin: 9
[16:57:45.982] <TB1> INFO: level: 15
[16:57:45.982] <TB1> INFO: triggerdelay: 0
[16:57:45.982] <TB1> QUIET: Instanciating API for pxar prod-12
[16:57:45.982] <TB1> INFO: Log level: INFO
[16:57:45.988] <TB1> INFO: Found DTB DTB_WXBYFL
[16:57:45.998] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[16:57:46.001] <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:
------------------------------------------------------
[16:57:46.004] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[16:57:47.557] <TB1> INFO: DUT info:
[16:57:47.557] <TB1> INFO: The DUT currently contains the following objects:
[16:57:47.557] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:57:47.557] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:57:47.557] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:57:47.557] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:57:47.557] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.557] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.557] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.557] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.557] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.557] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.558] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:57:47.958] <TB1> INFO: enter 'restricted' command line mode
[16:57:47.958] <TB1> INFO: enter test to run
[16:57:47.959] <TB1> INFO: test: FullTest no parameter change
[16:57:47.959] <TB1> INFO: running: fulltest
[16:57:47.962] <TB1> INFO: ######################################################################
[16:57:47.962] <TB1> INFO: PixTestFullTest::doTest()
[16:57:47.962] <TB1> INFO: ######################################################################
[16:57:47.965] <TB1> INFO: ######################################################################
[16:57:47.965] <TB1> INFO: PixTestAlive::doTest()
[16:57:47.965] <TB1> INFO: ######################################################################
[16:57:47.967] <TB1> INFO: ----------------------------------------------------------------------
[16:57:47.967] <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)
[16:57:47.967] <TB1> INFO: ----------------------------------------------------------------------
[16:57:48.296] <TB1> INFO: Expecting 41600 events.
[16:57:52.635] <TB1> INFO: 41600 events read in total (3620ms).
[16:57:52.707] <TB1> INFO: Test took 4738ms.
[16:57:52.714] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:52.985] <TB1> INFO: PixTestAlive::aliveTest() done
[16:57:52.985] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[16:57:52.987] <TB1> INFO: ----------------------------------------------------------------------
[16:57:52.987] <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)
[16:57:52.987] <TB1> INFO: ----------------------------------------------------------------------
[16:57:53.291] <TB1> INFO: Expecting 41600 events.
[16:57:56.432] <TB1> INFO: 41600 events read in total (2424ms).
[16:57:56.433] <TB1> INFO: Test took 3443ms.
[16:57:56.433] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:56.433] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:57:56.761] <TB1> INFO: PixTestAlive::maskTest() done
[16:57:56.761] <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
[16:57:56.763] <TB1> INFO: ----------------------------------------------------------------------
[16:57:56.763] <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)
[16:57:56.763] <TB1> INFO: ----------------------------------------------------------------------
[16:57:57.074] <TB1> INFO: Expecting 41600 events.
[16:58:01.290] <TB1> INFO: 41600 events read in total (3500ms).
[16:58:01.291] <TB1> INFO: Test took 4526ms.
[16:58:01.298] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:01.606] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[16:58:01.606] <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
[16:58:01.607] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:58:01.607] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:01.607] <TB1> INFO: Decoding statistics:
[16:58:01.607] <TB1> INFO: General information:
[16:58:01.607] <TB1> INFO: 16bit words read: 0
[16:58:01.607] <TB1> INFO: valid events total: 0
[16:58:01.607] <TB1> INFO: empty events: 0
[16:58:01.607] <TB1> INFO: valid events with pixels: 0
[16:58:01.607] <TB1> INFO: valid pixel hits: 0
[16:58:01.607] <TB1> INFO: Event errors: 0
[16:58:01.607] <TB1> INFO: start marker: 0
[16:58:01.607] <TB1> INFO: stop marker: 0
[16:58:01.607] <TB1> INFO: overflow: 0
[16:58:01.607] <TB1> INFO: invalid 5bit words: 0
[16:58:01.607] <TB1> INFO: invalid XOR eye diagram: 0
[16:58:01.607] <TB1> INFO: TBM errors: 0
[16:58:01.607] <TB1> INFO: flawed TBM headers: 0
[16:58:01.607] <TB1> INFO: flawed TBM trailers: 0
[16:58:01.607] <TB1> INFO: event ID mismatches: 0
[16:58:01.607] <TB1> INFO: ROC errors: 0
[16:58:01.607] <TB1> INFO: missing ROC header(s): 0
[16:58:01.607] <TB1> INFO: misplaced readback start: 0
[16:58:01.607] <TB1> INFO: Pixel decoding errors: 0
[16:58:01.607] <TB1> INFO: pixel data incomplete: 0
[16:58:01.607] <TB1> INFO: pixel address: 0
[16:58:01.607] <TB1> INFO: pulse height fill bit: 0
[16:58:01.607] <TB1> INFO: buffer corruption: 0
[16:58:01.653] <TB1> INFO: ######################################################################
[16:58:01.653] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[16:58:01.653] <TB1> INFO: ######################################################################
[16:58:01.657] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[16:58:01.672] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:58:01.672] <TB1> INFO: run 1 of 1
[16:58:02.011] <TB1> INFO: Expecting 3120000 events.
[16:58:40.705] <TB1> INFO: 845865 events read in total (37977ms).
[16:59:18.920] <TB1> INFO: 1684585 events read in total (76192ms).
[16:59:57.541] <TB1> INFO: 2535460 events read in total (114814ms).
[17:00:24.582] <TB1> INFO: 3120000 events read in total (141854ms).
[17:00:24.634] <TB1> INFO: Test took 142963ms.
[17:00:24.762] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:48.417] <TB1> INFO: PixTestBBMap::doTest() done, duration: 166 seconds
[17:00:48.417] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 3 0 1 1 0 0 0 0 0 0
[17:00:48.417] <TB1> INFO: separation cut (per ROC): 89 92 82 91 76 74 79 77 74 92 69 100 89 83 78 82
[17:00:48.417] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:48.417] <TB1> INFO: Decoding statistics:
[17:00:48.417] <TB1> INFO: General information:
[17:00:48.417] <TB1> INFO: 16bit words read: 0
[17:00:48.417] <TB1> INFO: valid events total: 0
[17:00:48.417] <TB1> INFO: empty events: 0
[17:00:48.417] <TB1> INFO: valid events with pixels: 0
[17:00:48.417] <TB1> INFO: valid pixel hits: 0
[17:00:48.417] <TB1> INFO: Event errors: 0
[17:00:48.417] <TB1> INFO: start marker: 0
[17:00:48.417] <TB1> INFO: stop marker: 0
[17:00:48.417] <TB1> INFO: overflow: 0
[17:00:48.417] <TB1> INFO: invalid 5bit words: 0
[17:00:48.417] <TB1> INFO: invalid XOR eye diagram: 0
[17:00:48.417] <TB1> INFO: TBM errors: 0
[17:00:48.417] <TB1> INFO: flawed TBM headers: 0
[17:00:48.417] <TB1> INFO: flawed TBM trailers: 0
[17:00:48.417] <TB1> INFO: event ID mismatches: 0
[17:00:48.417] <TB1> INFO: ROC errors: 0
[17:00:48.417] <TB1> INFO: missing ROC header(s): 0
[17:00:48.417] <TB1> INFO: misplaced readback start: 0
[17:00:48.417] <TB1> INFO: Pixel decoding errors: 0
[17:00:48.417] <TB1> INFO: pixel data incomplete: 0
[17:00:48.417] <TB1> INFO: pixel address: 0
[17:00:48.417] <TB1> INFO: pulse height fill bit: 0
[17:00:48.417] <TB1> INFO: buffer corruption: 0
[17:00:48.489] <TB1> INFO: ######################################################################
[17:00:48.489] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:00:48.489] <TB1> INFO: ######################################################################
[17:00:48.489] <TB1> INFO: ----------------------------------------------------------------------
[17:00:48.489] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:00:48.489] <TB1> INFO: ----------------------------------------------------------------------
[17:00:48.489] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:00:48.497] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[17:00:48.497] <TB1> INFO: run 1 of 1
[17:00:48.802] <TB1> INFO: Expecting 26208000 events.
[17:01:17.599] <TB1> INFO: 875650 events read in total (28077ms).
[17:01:45.783] <TB1> INFO: 1738650 events read in total (56261ms).
[17:02:13.699] <TB1> INFO: 2598750 events read in total (84177ms).
[17:02:41.308] <TB1> INFO: 3453000 events read in total (111786ms).
[17:03:08.899] <TB1> INFO: 4309050 events read in total (139377ms).
[17:03:36.378] <TB1> INFO: 5165750 events read in total (166856ms).
[17:04:04.393] <TB1> INFO: 6022200 events read in total (194871ms).
[17:04:32.391] <TB1> INFO: 6878000 events read in total (222869ms).
[17:04:59.964] <TB1> INFO: 7733750 events read in total (250442ms).
[17:05:28.000] <TB1> INFO: 8586850 events read in total (278478ms).
[17:05:56.443] <TB1> INFO: 9442250 events read in total (306921ms).
[17:06:24.393] <TB1> INFO: 10297250 events read in total (334871ms).
[17:06:52.205] <TB1> INFO: 11150550 events read in total (362683ms).
[17:07:19.977] <TB1> INFO: 12000850 events read in total (390455ms).
[17:07:47.795] <TB1> INFO: 12854450 events read in total (418273ms).
[17:08:15.280] <TB1> INFO: 13700150 events read in total (445758ms).
[17:08:42.385] <TB1> INFO: 14542400 events read in total (472863ms).
[17:09:09.718] <TB1> INFO: 15386100 events read in total (500196ms).
[17:09:36.699] <TB1> INFO: 16228400 events read in total (527177ms).
[17:10:04.149] <TB1> INFO: 17068700 events read in total (554627ms).
[17:10:31.774] <TB1> INFO: 17909300 events read in total (582252ms).
[17:10:59.144] <TB1> INFO: 18751400 events read in total (609622ms).
[17:11:26.210] <TB1> INFO: 19589700 events read in total (636688ms).
[17:11:53.587] <TB1> INFO: 20430450 events read in total (664065ms).
[17:12:20.997] <TB1> INFO: 21271900 events read in total (691475ms).
[17:12:48.419] <TB1> INFO: 22109800 events read in total (718897ms).
[17:13:16.069] <TB1> INFO: 22949950 events read in total (746547ms).
[17:13:43.400] <TB1> INFO: 23793550 events read in total (773878ms).
[17:14:10.692] <TB1> INFO: 24634400 events read in total (801170ms).
[17:14:37.809] <TB1> INFO: 25479250 events read in total (828287ms).
[17:15:00.905] <TB1> INFO: 26208000 events read in total (851383ms).
[17:15:00.934] <TB1> INFO: Test took 852437ms.
[17:15:01.019] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:01.199] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:02.797] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:04.270] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:05.750] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:07.336] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:08.908] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:10.485] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:12.068] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:13.514] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:14.939] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:16.339] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:17.782] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:19.181] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:20.588] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:22.030] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:23.599] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:15:25.084] <TB1> INFO: PixTestScurves::scurves() done
[17:15:25.084] <TB1> INFO: Vcal mean: 93.53 89.46 84.08 86.72 79.76 78.82 85.41 79.23 81.40 86.35 67.74 92.22 85.28 83.56 80.82 79.88
[17:15:25.084] <TB1> INFO: Vcal RMS: 6.40 5.78 4.67 5.92 4.50 4.23 5.52 4.28 4.91 6.22 4.88 5.95 5.72 4.64 4.46 4.43
[17:15:25.084] <TB1> INFO: PixTestScurves::fullTest() done, duration: 876 seconds
[17:15:25.084] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:25.084] <TB1> INFO: Decoding statistics:
[17:15:25.084] <TB1> INFO: General information:
[17:15:25.084] <TB1> INFO: 16bit words read: 0
[17:15:25.084] <TB1> INFO: valid events total: 0
[17:15:25.084] <TB1> INFO: empty events: 0
[17:15:25.084] <TB1> INFO: valid events with pixels: 0
[17:15:25.084] <TB1> INFO: valid pixel hits: 0
[17:15:25.084] <TB1> INFO: Event errors: 0
[17:15:25.084] <TB1> INFO: start marker: 0
[17:15:25.084] <TB1> INFO: stop marker: 0
[17:15:25.084] <TB1> INFO: overflow: 0
[17:15:25.084] <TB1> INFO: invalid 5bit words: 0
[17:15:25.084] <TB1> INFO: invalid XOR eye diagram: 0
[17:15:25.084] <TB1> INFO: TBM errors: 0
[17:15:25.084] <TB1> INFO: flawed TBM headers: 0
[17:15:25.084] <TB1> INFO: flawed TBM trailers: 0
[17:15:25.084] <TB1> INFO: event ID mismatches: 0
[17:15:25.084] <TB1> INFO: ROC errors: 0
[17:15:25.084] <TB1> INFO: missing ROC header(s): 0
[17:15:25.084] <TB1> INFO: misplaced readback start: 0
[17:15:25.085] <TB1> INFO: Pixel decoding errors: 0
[17:15:25.085] <TB1> INFO: pixel data incomplete: 0
[17:15:25.085] <TB1> INFO: pixel address: 0
[17:15:25.085] <TB1> INFO: pulse height fill bit: 0
[17:15:25.085] <TB1> INFO: buffer corruption: 0
[17:15:25.156] <TB1> INFO: ######################################################################
[17:15:25.156] <TB1> INFO: PixTestTrim::doTest()
[17:15:25.156] <TB1> INFO: ######################################################################
[17:15:25.157] <TB1> INFO: ----------------------------------------------------------------------
[17:15:25.157] <TB1> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[17:15:25.157] <TB1> INFO: ----------------------------------------------------------------------
[17:15:25.238] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[17:15:25.238] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:15:25.246] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[17:15:25.246] <TB1> INFO: run 1 of 1
[17:15:25.550] <TB1> INFO: Expecting 5025280 events.
[17:16:03.405] <TB1> INFO: 1063152 events read in total (37138ms).
[17:16:40.839] <TB1> INFO: 2122152 events read in total (74572ms).
[17:17:17.932] <TB1> INFO: 3181544 events read in total (111665ms).
[17:17:55.311] <TB1> INFO: 4248320 events read in total (149044ms).
[17:18:22.069] <TB1> INFO: 5025280 events read in total (175802ms).
[17:18:22.101] <TB1> INFO: Test took 176855ms.
[17:18:22.152] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:41.663] <TB1> INFO: ROC 0 VthrComp = 91
[17:18:41.664] <TB1> INFO: ROC 1 VthrComp = 94
[17:18:41.664] <TB1> INFO: ROC 2 VthrComp = 92
[17:18:41.664] <TB1> INFO: ROC 3 VthrComp = 93
[17:18:41.664] <TB1> INFO: ROC 4 VthrComp = 84
[17:18:41.664] <TB1> INFO: ROC 5 VthrComp = 86
[17:18:41.664] <TB1> INFO: ROC 6 VthrComp = 89
[17:18:41.664] <TB1> INFO: ROC 7 VthrComp = 86
[17:18:41.664] <TB1> INFO: ROC 8 VthrComp = 86
[17:18:41.665] <TB1> INFO: ROC 9 VthrComp = 93
[17:18:41.665] <TB1> INFO: ROC 10 VthrComp = 74
[17:18:41.665] <TB1> INFO: ROC 11 VthrComp = 98
[17:18:41.665] <TB1> INFO: ROC 12 VthrComp = 86
[17:18:41.665] <TB1> INFO: ROC 13 VthrComp = 89
[17:18:41.665] <TB1> INFO: ROC 14 VthrComp = 88
[17:18:41.665] <TB1> INFO: ROC 15 VthrComp = 87
[17:18:41.665] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[17:18:41.665] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:18:41.673] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[17:18:41.673] <TB1> INFO: run 1 of 1
[17:18:41.985] <TB1> INFO: Expecting 5025280 events.
[17:19:14.904] <TB1> INFO: 756096 events read in total (32202ms).
[17:19:46.571] <TB1> INFO: 1510864 events read in total (63869ms).
[17:20:18.523] <TB1> INFO: 2265768 events read in total (95821ms).
[17:20:50.012] <TB1> INFO: 3015776 events read in total (127310ms).
[17:21:21.824] <TB1> INFO: 3761592 events read in total (159122ms).
[17:21:52.923] <TB1> INFO: 4506344 events read in total (190221ms).
[17:22:15.543] <TB1> INFO: 5025280 events read in total (212841ms).
[17:22:15.593] <TB1> INFO: Test took 213920ms.
[17:22:15.745] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:39.992] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 64.367 for pixel 51/79 mean/min/max = 47.5327/30.6942/64.3711
[17:22:39.992] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 59.8134 for pixel 23/75 mean/min/max = 45.6524/31.3551/59.9497
[17:22:39.992] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.0911 for pixel 12/79 mean/min/max = 44.5165/31.8607/57.1724
[17:22:39.992] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 60.043 for pixel 21/6 mean/min/max = 45.8258/31.5503/60.1014
[17:22:39.993] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.5613 for pixel 51/16 mean/min/max = 45.2588/31.9399/58.5776
[17:22:39.993] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 56.9349 for pixel 21/78 mean/min/max = 44.6176/32.1678/57.0674
[17:22:39.993] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 61.0762 for pixel 3/67 mean/min/max = 46.7743/32.3637/61.1849
[17:22:39.994] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 56.97 for pixel 0/60 mean/min/max = 44.4668/31.9421/56.9915
[17:22:39.994] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 59.3835 for pixel 7/2 mean/min/max = 45.6688/31.9162/59.4213
[17:22:39.994] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 61.4694 for pixel 0/12 mean/min/max = 46.4894/31.2785/61.7002
[17:22:39.995] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 58.1308 for pixel 24/7 mean/min/max = 46.2932/34.3731/58.2133
[17:22:39.995] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 59.0404 for pixel 18/8 mean/min/max = 45.0677/31.0009/59.1346
[17:22:39.996] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 62.1851 for pixel 20/22 mean/min/max = 46.7438/31.2827/62.205
[17:22:39.996] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.3597 for pixel 13/11 mean/min/max = 45.1844/32.94/57.4287
[17:22:39.996] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.0051 for pixel 43/3 mean/min/max = 44.6529/32.1882/57.1176
[17:22:39.997] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.6114 for pixel 47/5 mean/min/max = 44.9568/32.0482/57.8654
[17:22:39.997] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:22:40.129] <TB1> INFO: Expecting 411648 events.
[17:22:49.152] <TB1> INFO: 411648 events read in total (8306ms).
[17:22:49.157] <TB1> INFO: Expecting 411648 events.
[17:22:58.228] <TB1> INFO: 411648 events read in total (8479ms).
[17:22:58.235] <TB1> INFO: Expecting 411648 events.
[17:23:07.322] <TB1> INFO: 411648 events read in total (8507ms).
[17:23:07.331] <TB1> INFO: Expecting 411648 events.
[17:23:16.421] <TB1> INFO: 411648 events read in total (8513ms).
[17:23:16.431] <TB1> INFO: Expecting 411648 events.
[17:23:25.433] <TB1> INFO: 411648 events read in total (8431ms).
[17:23:25.444] <TB1> INFO: Expecting 411648 events.
[17:23:34.370] <TB1> INFO: 411648 events read in total (8341ms).
[17:23:34.384] <TB1> INFO: Expecting 411648 events.
[17:23:43.440] <TB1> INFO: 411648 events read in total (8476ms).
[17:23:43.458] <TB1> INFO: Expecting 411648 events.
[17:23:52.579] <TB1> INFO: 411648 events read in total (8550ms).
[17:23:52.599] <TB1> INFO: Expecting 411648 events.
[17:24:01.590] <TB1> INFO: 411648 events read in total (8421ms).
[17:24:01.614] <TB1> INFO: Expecting 411648 events.
[17:24:10.577] <TB1> INFO: 411648 events read in total (8397ms).
[17:24:10.599] <TB1> INFO: Expecting 411648 events.
[17:24:19.745] <TB1> INFO: 411648 events read in total (8576ms).
[17:24:19.769] <TB1> INFO: Expecting 411648 events.
[17:24:29.071] <TB1> INFO: 411648 events read in total (8740ms).
[17:24:29.101] <TB1> INFO: Expecting 411648 events.
[17:24:38.081] <TB1> INFO: 411648 events read in total (8430ms).
[17:24:38.111] <TB1> INFO: Expecting 411648 events.
[17:24:47.022] <TB1> INFO: 411648 events read in total (8362ms).
[17:24:47.055] <TB1> INFO: Expecting 411648 events.
[17:24:56.255] <TB1> INFO: 411648 events read in total (8642ms).
[17:24:56.291] <TB1> INFO: Expecting 411648 events.
[17:25:05.383] <TB1> INFO: 411648 events read in total (8543ms).
[17:25:05.423] <TB1> INFO: Test took 145426ms.
[17:25:06.432] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:25:06.440] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[17:25:06.440] <TB1> INFO: run 1 of 1
[17:25:06.745] <TB1> INFO: Expecting 5025280 events.
[17:25:38.973] <TB1> INFO: 737296 events read in total (31511ms).
[17:26:10.509] <TB1> INFO: 1473256 events read in total (63047ms).
[17:26:42.596] <TB1> INFO: 2209048 events read in total (95134ms).
[17:27:14.697] <TB1> INFO: 2941208 events read in total (127235ms).
[17:27:46.517] <TB1> INFO: 3668936 events read in total (159055ms).
[17:28:18.251] <TB1> INFO: 4395392 events read in total (190789ms).
[17:28:45.550] <TB1> INFO: 5025280 events read in total (218088ms).
[17:28:45.607] <TB1> INFO: Test took 219167ms.
[17:28:45.764] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:29:08.602] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.027519 .. 255.000000
[17:29:08.678] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[17:29:08.687] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[17:29:08.687] <TB1> INFO: run 1 of 1
[17:29:08.992] <TB1> INFO: Expecting 8519680 events.
[17:29:41.477] <TB1> INFO: 712184 events read in total (31769ms).
[17:30:13.057] <TB1> INFO: 1424392 events read in total (63349ms).
[17:30:44.444] <TB1> INFO: 2136760 events read in total (94736ms).
[17:31:15.451] <TB1> INFO: 2849288 events read in total (125743ms).
[17:31:46.736] <TB1> INFO: 3561656 events read in total (157028ms).
[17:32:18.055] <TB1> INFO: 4273968 events read in total (188347ms).
[17:32:49.321] <TB1> INFO: 4985280 events read in total (219613ms).
[17:33:20.297] <TB1> INFO: 5696000 events read in total (250589ms).
[17:33:51.494] <TB1> INFO: 6406136 events read in total (281786ms).
[17:34:22.538] <TB1> INFO: 7116224 events read in total (312830ms).
[17:34:53.911] <TB1> INFO: 7826080 events read in total (344203ms).
[17:35:24.392] <TB1> INFO: 8519680 events read in total (374684ms).
[17:35:24.494] <TB1> INFO: Test took 375808ms.
[17:35:24.788] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:53.168] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 2.013880 .. 50.076042
[17:35:53.244] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 2 .. 60 (-1/-1) hits flags = 528 (plus default)
[17:35:53.252] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[17:35:53.252] <TB1> INFO: run 1 of 1
[17:35:53.556] <TB1> INFO: Expecting 1963520 events.
[17:36:29.590] <TB1> INFO: 920016 events read in total (35317ms).
[17:37:04.833] <TB1> INFO: 1837008 events read in total (70560ms).
[17:37:10.102] <TB1> INFO: 1963520 events read in total (75829ms).
[17:37:10.122] <TB1> INFO: Test took 76871ms.
[17:37:10.163] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:24.315] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 18.048266 .. 47.342951
[17:37:24.390] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 8 .. 57 (-1/-1) hits flags = 528 (plus default)
[17:37:24.398] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[17:37:24.398] <TB1> INFO: run 1 of 1
[17:37:24.703] <TB1> INFO: Expecting 1664000 events.
[17:38:00.600] <TB1> INFO: 909704 events read in total (35181ms).
[17:38:30.066] <TB1> INFO: 1664000 events read in total (64647ms).
[17:38:30.083] <TB1> INFO: Test took 65686ms.
[17:38:30.120] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:45.533] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 1.968208 .. 46.905500
[17:38:45.622] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 1 .. 56 (-1/-1) hits flags = 528 (plus default)
[17:38:45.632] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[17:38:45.632] <TB1> INFO: run 1 of 1
[17:38:45.947] <TB1> INFO: Expecting 1863680 events.
[17:39:22.514] <TB1> INFO: 953800 events read in total (35850ms).
[17:39:56.845] <TB1> INFO: 1863680 events read in total (70182ms).
[17:39:56.867] <TB1> INFO: Test took 71236ms.
[17:39:56.903] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:11.814] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:40:11.814] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[17:40:11.823] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[17:40:11.823] <TB1> INFO: run 1 of 1
[17:40:12.151] <TB1> INFO: Expecting 1364480 events.
[17:40:47.669] <TB1> INFO: 879624 events read in total (34801ms).
[17:41:06.971] <TB1> INFO: 1364480 events read in total (54103ms).
[17:41:06.986] <TB1> INFO: Test took 55164ms.
[17:41:07.020] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:21.330] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C0.dat
[17:41:21.331] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C1.dat
[17:41:21.331] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C2.dat
[17:41:21.331] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C3.dat
[17:41:21.331] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C4.dat
[17:41:21.331] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C5.dat
[17:41:21.331] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C6.dat
[17:41:21.331] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C7.dat
[17:41:21.331] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C8.dat
[17:41:21.332] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C9.dat
[17:41:21.332] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C10.dat
[17:41:21.332] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C11.dat
[17:41:21.332] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C12.dat
[17:41:21.332] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C13.dat
[17:41:21.332] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C14.dat
[17:41:21.332] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C15.dat
[17:41:21.332] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C0.dat
[17:41:21.339] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C1.dat
[17:41:21.348] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C2.dat
[17:41:21.355] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C3.dat
[17:41:21.363] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C4.dat
[17:41:21.370] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C5.dat
[17:41:21.379] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C6.dat
[17:41:21.385] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C7.dat
[17:41:21.394] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C8.dat
[17:41:21.401] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C9.dat
[17:41:21.410] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C10.dat
[17:41:21.417] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C11.dat
[17:41:21.424] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C12.dat
[17:41:21.430] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C13.dat
[17:41:21.436] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C14.dat
[17:41:21.442] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//trimParameters35_C15.dat
[17:41:21.447] <TB1> INFO: PixTestTrim::trimTest() done
[17:41:21.447] <TB1> INFO: vtrim: 87 106 89 108 91 85 103 93 102 114 94 195 117 91 95 104
[17:41:21.447] <TB1> INFO: vthrcomp: 91 94 92 93 84 86 89 86 86 93 74 98 86 89 88 87
[17:41:21.447] <TB1> INFO: vcal mean: 35.00 34.97 34.94 35.00 34.95 34.95 35.01 34.94 34.97 34.99 35.01 34.92 34.97 35.01 34.95 34.97
[17:41:21.447] <TB1> INFO: vcal RMS: 1.02 0.97 0.87 0.89 0.98 0.85 0.94 1.04 0.91 0.95 0.82 4.01 0.96 0.89 0.89 0.87
[17:41:21.448] <TB1> INFO: bits mean: 8.05 9.59 9.60 9.48 9.39 9.93 9.31 9.67 9.39 8.86 9.10 11.95 9.84 9.75 9.88 9.71
[17:41:21.448] <TB1> INFO: bits RMS: 3.42 2.72 2.73 2.72 2.75 2.55 2.65 2.70 2.73 2.99 2.46 1.64 2.53 2.50 2.53 2.62
[17:41:21.456] <TB1> INFO: ----------------------------------------------------------------------
[17:41:21.456] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[17:41:21.456] <TB1> INFO: ----------------------------------------------------------------------
[17:41:21.461] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[17:41:21.469] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:41:21.469] <TB1> INFO: run 1 of 1
[17:41:21.773] <TB1> INFO: Expecting 4160000 events.
[17:42:01.542] <TB1> INFO: 904665 events read in total (39053ms).
[17:42:40.761] <TB1> INFO: 1803480 events read in total (78272ms).
[17:43:19.108] <TB1> INFO: 2695000 events read in total (116620ms).
[17:43:57.972] <TB1> INFO: 3583575 events read in total (155483ms).
[17:44:22.812] <TB1> INFO: 4160000 events read in total (180323ms).
[17:44:22.855] <TB1> INFO: Test took 181387ms.
[17:44:22.972] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:44:50.948] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 163 (-1/-1) hits flags = 528 (plus default)
[17:44:50.956] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:44:50.956] <TB1> INFO: run 1 of 1
[17:44:51.262] <TB1> INFO: Expecting 3411200 events.
[17:45:31.892] <TB1> INFO: 943100 events read in total (39913ms).
[17:46:11.874] <TB1> INFO: 1877210 events read in total (79895ms).
[17:46:51.231] <TB1> INFO: 2801840 events read in total (119253ms).
[17:47:17.267] <TB1> INFO: 3411200 events read in total (145288ms).
[17:47:17.302] <TB1> INFO: Test took 146347ms.
[17:47:17.383] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:42.672] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:47:42.680] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:47:42.680] <TB1> INFO: run 1 of 1
[17:47:42.984] <TB1> INFO: Expecting 3140800 events.
[17:48:24.221] <TB1> INFO: 977460 events read in total (40522ms).
[17:49:04.481] <TB1> INFO: 1942150 events read in total (80781ms).
[17:49:44.733] <TB1> INFO: 2898865 events read in total (121034ms).
[17:49:55.302] <TB1> INFO: 3140800 events read in total (131602ms).
[17:49:55.336] <TB1> INFO: Test took 132656ms.
[17:49:55.415] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:50:19.653] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[17:50:19.661] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:50:19.661] <TB1> INFO: run 1 of 1
[17:50:19.970] <TB1> INFO: Expecting 3182400 events.
[17:51:00.990] <TB1> INFO: 970530 events read in total (40303ms).
[17:51:40.929] <TB1> INFO: 1929190 events read in total (80242ms).
[17:52:20.694] <TB1> INFO: 2879565 events read in total (120007ms).
[17:52:34.029] <TB1> INFO: 3182400 events read in total (133342ms).
[17:52:34.066] <TB1> INFO: Test took 134405ms.
[17:52:34.142] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:52:58.203] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[17:52:58.211] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:52:58.211] <TB1> INFO: run 1 of 1
[17:52:58.515] <TB1> INFO: Expecting 3161600 events.
[17:53:39.835] <TB1> INFO: 972690 events read in total (40603ms).
[17:54:20.456] <TB1> INFO: 1933070 events read in total (81224ms).
[17:55:01.127] <TB1> INFO: 2885570 events read in total (121895ms).
[17:55:12.996] <TB1> INFO: 3161600 events read in total (133764ms).
[17:55:13.028] <TB1> INFO: Test took 134817ms.
[17:55:13.100] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:55:39.560] <TB1> INFO: PixTestTrim::trimBitTest() done
[17:55:39.562] <TB1> INFO: PixTestTrim::doTest() done, duration: 2414 seconds
[17:55:39.562] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:55:39.562] <TB1> INFO: Decoding statistics:
[17:55:39.562] <TB1> INFO: General information:
[17:55:39.562] <TB1> INFO: 16bit words read: 0
[17:55:39.562] <TB1> INFO: valid events total: 0
[17:55:39.562] <TB1> INFO: empty events: 0
[17:55:39.562] <TB1> INFO: valid events with pixels: 0
[17:55:39.562] <TB1> INFO: valid pixel hits: 0
[17:55:39.562] <TB1> INFO: Event errors: 0
[17:55:39.562] <TB1> INFO: start marker: 0
[17:55:39.562] <TB1> INFO: stop marker: 0
[17:55:39.562] <TB1> INFO: overflow: 0
[17:55:39.562] <TB1> INFO: invalid 5bit words: 0
[17:55:39.562] <TB1> INFO: invalid XOR eye diagram: 0
[17:55:39.562] <TB1> INFO: TBM errors: 0
[17:55:39.562] <TB1> INFO: flawed TBM headers: 0
[17:55:39.562] <TB1> INFO: flawed TBM trailers: 0
[17:55:39.562] <TB1> INFO: event ID mismatches: 0
[17:55:39.562] <TB1> INFO: ROC errors: 0
[17:55:39.562] <TB1> INFO: missing ROC header(s): 0
[17:55:39.562] <TB1> INFO: misplaced readback start: 0
[17:55:39.562] <TB1> INFO: Pixel decoding errors: 0
[17:55:39.562] <TB1> INFO: pixel data incomplete: 0
[17:55:39.562] <TB1> INFO: pixel address: 0
[17:55:39.562] <TB1> INFO: pulse height fill bit: 0
[17:55:39.562] <TB1> INFO: buffer corruption: 0
[17:55:40.287] <TB1> INFO: ######################################################################
[17:55:40.287] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:55:40.287] <TB1> INFO: ######################################################################
[17:55:40.609] <TB1> INFO: Expecting 41600 events.
[17:55:44.791] <TB1> INFO: 41600 events read in total (3465ms).
[17:55:44.792] <TB1> INFO: Test took 4504ms.
[17:55:44.798] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:55:45.368] <TB1> INFO: Expecting 41600 events.
[17:55:49.629] <TB1> INFO: 41600 events read in total (3546ms).
[17:55:49.630] <TB1> INFO: Test took 4566ms.
[17:55:49.636] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:55:49.975] <TB1> INFO: Expecting 41600 events.
[17:55:54.161] <TB1> INFO: 41600 events read in total (3469ms).
[17:55:54.161] <TB1> INFO: Test took 4510ms.
[17:55:54.168] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:55:54.510] <TB1> INFO: Expecting 2560 events.
[17:55:55.576] <TB1> INFO: 2560 events read in total (350ms).
[17:55:55.576] <TB1> INFO: Test took 1399ms.
[17:55:55.991] <TB1> INFO: Expecting 2560 events.
[17:55:57.057] <TB1> INFO: 2560 events read in total (349ms).
[17:55:57.058] <TB1> INFO: Test took 1482ms.
[17:55:57.473] <TB1> INFO: Expecting 2560 events.
[17:55:58.536] <TB1> INFO: 2560 events read in total (347ms).
[17:55:58.537] <TB1> INFO: Test took 1479ms.
[17:55:58.952] <TB1> INFO: Expecting 2560 events.
[17:56:00.017] <TB1> INFO: 2560 events read in total (349ms).
[17:56:00.017] <TB1> INFO: Test took 1480ms.
[17:56:00.433] <TB1> INFO: Expecting 2560 events.
[17:56:01.499] <TB1> INFO: 2560 events read in total (350ms).
[17:56:01.499] <TB1> INFO: Test took 1481ms.
[17:56:01.914] <TB1> INFO: Expecting 2560 events.
[17:56:02.980] <TB1> INFO: 2560 events read in total (349ms).
[17:56:02.980] <TB1> INFO: Test took 1481ms.
[17:56:03.395] <TB1> INFO: Expecting 2560 events.
[17:56:04.462] <TB1> INFO: 2560 events read in total (350ms).
[17:56:04.462] <TB1> INFO: Test took 1482ms.
[17:56:04.878] <TB1> INFO: Expecting 2560 events.
[17:56:05.944] <TB1> INFO: 2560 events read in total (350ms).
[17:56:05.944] <TB1> INFO: Test took 1482ms.
[17:56:06.359] <TB1> INFO: Expecting 2560 events.
[17:56:07.425] <TB1> INFO: 2560 events read in total (349ms).
[17:56:07.425] <TB1> INFO: Test took 1480ms.
[17:56:07.841] <TB1> INFO: Expecting 2560 events.
[17:56:08.907] <TB1> INFO: 2560 events read in total (350ms).
[17:56:08.907] <TB1> INFO: Test took 1481ms.
[17:56:09.323] <TB1> INFO: Expecting 2560 events.
[17:56:10.389] <TB1> INFO: 2560 events read in total (350ms).
[17:56:10.389] <TB1> INFO: Test took 1481ms.
[17:56:10.805] <TB1> INFO: Expecting 2560 events.
[17:56:11.869] <TB1> INFO: 2560 events read in total (348ms).
[17:56:11.870] <TB1> INFO: Test took 1481ms.
[17:56:12.285] <TB1> INFO: Expecting 2560 events.
[17:56:13.349] <TB1> INFO: 2560 events read in total (348ms).
[17:56:13.349] <TB1> INFO: Test took 1479ms.
[17:56:13.764] <TB1> INFO: Expecting 2560 events.
[17:56:14.828] <TB1> INFO: 2560 events read in total (347ms).
[17:56:14.828] <TB1> INFO: Test took 1478ms.
[17:56:15.243] <TB1> INFO: Expecting 2560 events.
[17:56:16.308] <TB1> INFO: 2560 events read in total (349ms).
[17:56:16.308] <TB1> INFO: Test took 1480ms.
[17:56:16.723] <TB1> INFO: Expecting 2560 events.
[17:56:17.786] <TB1> INFO: 2560 events read in total (346ms).
[17:56:17.787] <TB1> INFO: Test took 1479ms.
[17:56:17.791] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:56:18.207] <TB1> INFO: Expecting 655360 events.
[17:56:32.118] <TB1> INFO: 655360 events read in total (13194ms).
[17:56:32.127] <TB1> INFO: Expecting 655360 events.
[17:56:45.828] <TB1> INFO: 655360 events read in total (13173ms).
[17:56:45.841] <TB1> INFO: Expecting 655360 events.
[17:56:59.852] <TB1> INFO: 655360 events read in total (13483ms).
[17:56:59.868] <TB1> INFO: Expecting 655360 events.
[17:57:13.989] <TB1> INFO: 655360 events read in total (13593ms).
[17:57:14.009] <TB1> INFO: Expecting 655360 events.
[17:57:28.025] <TB1> INFO: 655360 events read in total (13488ms).
[17:57:28.047] <TB1> INFO: Expecting 655360 events.
[17:57:42.096] <TB1> INFO: 655360 events read in total (13521ms).
[17:57:42.122] <TB1> INFO: Expecting 655360 events.
[17:57:56.080] <TB1> INFO: 655360 events read in total (13430ms).
[17:57:56.117] <TB1> INFO: Expecting 655360 events.
[17:58:10.032] <TB1> INFO: 655360 events read in total (13386ms).
[17:58:10.065] <TB1> INFO: Expecting 655360 events.
[17:58:23.863] <TB1> INFO: 655360 events read in total (13269ms).
[17:58:23.904] <TB1> INFO: Expecting 655360 events.
[17:58:38.554] <TB1> INFO: 655360 events read in total (14122ms).
[17:58:38.595] <TB1> INFO: Expecting 655360 events.
[17:58:52.700] <TB1> INFO: 655360 events read in total (13577ms).
[17:58:52.761] <TB1> INFO: Expecting 655360 events.
[17:59:06.621] <TB1> INFO: 655360 events read in total (13331ms).
[17:59:06.668] <TB1> INFO: Expecting 655360 events.
[17:59:21.486] <TB1> INFO: 655360 events read in total (14290ms).
[17:59:21.540] <TB1> INFO: Expecting 655360 events.
[17:59:35.592] <TB1> INFO: 655360 events read in total (13524ms).
[17:59:35.647] <TB1> INFO: Expecting 655360 events.
[17:59:49.732] <TB1> INFO: 655360 events read in total (13557ms).
[17:59:49.792] <TB1> INFO: Expecting 655360 events.
[18:00:04.523] <TB1> INFO: 655360 events read in total (14200ms).
[18:00:04.586] <TB1> INFO: Test took 226795ms.
[18:00:04.669] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:00:04.976] <TB1> INFO: Expecting 655360 events.
[18:00:18.962] <TB1> INFO: 655360 events read in total (13269ms).
[18:00:18.971] <TB1> INFO: Expecting 655360 events.
[18:00:32.941] <TB1> INFO: 655360 events read in total (13442ms).
[18:00:32.953] <TB1> INFO: Expecting 655360 events.
[18:00:46.739] <TB1> INFO: 655360 events read in total (13258ms).
[18:00:46.755] <TB1> INFO: Expecting 655360 events.
[18:01:00.653] <TB1> INFO: 655360 events read in total (13370ms).
[18:01:00.672] <TB1> INFO: Expecting 655360 events.
[18:01:15.042] <TB1> INFO: 655360 events read in total (13842ms).
[18:01:15.065] <TB1> INFO: Expecting 655360 events.
[18:01:29.176] <TB1> INFO: 655360 events read in total (13583ms).
[18:01:29.203] <TB1> INFO: Expecting 655360 events.
[18:01:43.541] <TB1> INFO: 655360 events read in total (13810ms).
[18:01:43.575] <TB1> INFO: Expecting 655360 events.
[18:01:57.748] <TB1> INFO: 655360 events read in total (13645ms).
[18:01:57.780] <TB1> INFO: Expecting 655360 events.
[18:02:12.154] <TB1> INFO: 655360 events read in total (13845ms).
[18:02:12.194] <TB1> INFO: Expecting 655360 events.
[18:02:26.289] <TB1> INFO: 655360 events read in total (13567ms).
[18:02:26.333] <TB1> INFO: Expecting 655360 events.
[18:02:40.911] <TB1> INFO: 655360 events read in total (14050ms).
[18:02:40.960] <TB1> INFO: Expecting 655360 events.
[18:02:55.121] <TB1> INFO: 655360 events read in total (13633ms).
[18:02:55.168] <TB1> INFO: Expecting 655360 events.
[18:03:09.531] <TB1> INFO: 655360 events read in total (13835ms).
[18:03:09.594] <TB1> INFO: Expecting 655360 events.
[18:03:23.946] <TB1> INFO: 655360 events read in total (13824ms).
[18:03:24.010] <TB1> INFO: Expecting 655360 events.
[18:03:37.952] <TB1> INFO: 655360 events read in total (13413ms).
[18:03:38.013] <TB1> INFO: Expecting 655360 events.
[18:03:51.780] <TB1> INFO: 655360 events read in total (13238ms).
[18:03:51.840] <TB1> INFO: Test took 227171ms.
[18:03:52.033] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.039] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.046] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.053] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.059] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.066] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.072] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.079] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.086] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.095] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.101] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.108] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.115] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.121] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.128] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.135] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[18:03:52.142] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[18:03:52.150] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:03:52.206] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C0.dat
[18:03:52.206] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C1.dat
[18:03:52.206] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C2.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C3.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C4.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C5.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C6.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C7.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C8.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C9.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C10.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C11.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C12.dat
[18:03:52.207] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C13.dat
[18:03:52.208] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C14.dat
[18:03:52.208] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//dacParameters35_C15.dat
[18:03:52.523] <TB1> INFO: Expecting 41600 events.
[18:03:56.404] <TB1> INFO: 41600 events read in total (3165ms).
[18:03:56.405] <TB1> INFO: Test took 4193ms.
[18:03:56.954] <TB1> INFO: Expecting 41600 events.
[18:04:00.967] <TB1> INFO: 41600 events read in total (3296ms).
[18:04:00.967] <TB1> INFO: Test took 4323ms.
[18:04:01.551] <TB1> INFO: Expecting 41600 events.
[18:04:05.494] <TB1> INFO: 41600 events read in total (3226ms).
[18:04:05.494] <TB1> INFO: Test took 4282ms.
[18:04:05.739] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:05.872] <TB1> INFO: Expecting 2560 events.
[18:04:06.936] <TB1> INFO: 2560 events read in total (347ms).
[18:04:06.936] <TB1> INFO: Test took 1197ms.
[18:04:06.940] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:07.353] <TB1> INFO: Expecting 2560 events.
[18:04:08.423] <TB1> INFO: 2560 events read in total (353ms).
[18:04:08.424] <TB1> INFO: Test took 1484ms.
[18:04:08.427] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:08.839] <TB1> INFO: Expecting 2560 events.
[18:04:09.909] <TB1> INFO: 2560 events read in total (353ms).
[18:04:09.910] <TB1> INFO: Test took 1483ms.
[18:04:09.913] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:10.325] <TB1> INFO: Expecting 2560 events.
[18:04:11.394] <TB1> INFO: 2560 events read in total (353ms).
[18:04:11.395] <TB1> INFO: Test took 1482ms.
[18:04:11.397] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:11.812] <TB1> INFO: Expecting 2560 events.
[18:04:12.882] <TB1> INFO: 2560 events read in total (354ms).
[18:04:12.883] <TB1> INFO: Test took 1486ms.
[18:04:12.885] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:13.300] <TB1> INFO: Expecting 2560 events.
[18:04:14.364] <TB1> INFO: 2560 events read in total (347ms).
[18:04:14.365] <TB1> INFO: Test took 1480ms.
[18:04:14.367] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:14.781] <TB1> INFO: Expecting 2560 events.
[18:04:15.845] <TB1> INFO: 2560 events read in total (348ms).
[18:04:15.845] <TB1> INFO: Test took 1478ms.
[18:04:15.848] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:16.262] <TB1> INFO: Expecting 2560 events.
[18:04:17.335] <TB1> INFO: 2560 events read in total (357ms).
[18:04:17.335] <TB1> INFO: Test took 1487ms.
[18:04:17.338] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:17.754] <TB1> INFO: Expecting 2560 events.
[18:04:18.820] <TB1> INFO: 2560 events read in total (349ms).
[18:04:18.821] <TB1> INFO: Test took 1483ms.
[18:04:18.824] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:19.237] <TB1> INFO: Expecting 2560 events.
[18:04:20.310] <TB1> INFO: 2560 events read in total (357ms).
[18:04:20.310] <TB1> INFO: Test took 1486ms.
[18:04:20.313] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:20.726] <TB1> INFO: Expecting 2560 events.
[18:04:21.790] <TB1> INFO: 2560 events read in total (347ms).
[18:04:21.790] <TB1> INFO: Test took 1477ms.
[18:04:21.793] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:22.207] <TB1> INFO: Expecting 2560 events.
[18:04:23.276] <TB1> INFO: 2560 events read in total (353ms).
[18:04:23.277] <TB1> INFO: Test took 1484ms.
[18:04:23.280] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:23.693] <TB1> INFO: Expecting 2560 events.
[18:04:24.765] <TB1> INFO: 2560 events read in total (355ms).
[18:04:24.765] <TB1> INFO: Test took 1485ms.
[18:04:24.768] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:25.180] <TB1> INFO: Expecting 2560 events.
[18:04:26.247] <TB1> INFO: 2560 events read in total (350ms).
[18:04:26.247] <TB1> INFO: Test took 1479ms.
[18:04:26.250] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:26.663] <TB1> INFO: Expecting 2560 events.
[18:04:27.733] <TB1> INFO: 2560 events read in total (353ms).
[18:04:27.733] <TB1> INFO: Test took 1483ms.
[18:04:27.736] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:28.149] <TB1> INFO: Expecting 2560 events.
[18:04:29.218] <TB1> INFO: 2560 events read in total (353ms).
[18:04:29.219] <TB1> INFO: Test took 1483ms.
[18:04:29.221] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:29.634] <TB1> INFO: Expecting 2560 events.
[18:04:30.706] <TB1> INFO: 2560 events read in total (355ms).
[18:04:30.707] <TB1> INFO: Test took 1486ms.
[18:04:30.709] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:31.123] <TB1> INFO: Expecting 2560 events.
[18:04:32.189] <TB1> INFO: 2560 events read in total (349ms).
[18:04:32.189] <TB1> INFO: Test took 1480ms.
[18:04:32.191] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:32.605] <TB1> INFO: Expecting 2560 events.
[18:04:33.670] <TB1> INFO: 2560 events read in total (349ms).
[18:04:33.670] <TB1> INFO: Test took 1479ms.
[18:04:33.674] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:34.087] <TB1> INFO: Expecting 2560 events.
[18:04:35.155] <TB1> INFO: 2560 events read in total (352ms).
[18:04:35.155] <TB1> INFO: Test took 1481ms.
[18:04:35.158] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:35.571] <TB1> INFO: Expecting 2560 events.
[18:04:36.646] <TB1> INFO: 2560 events read in total (353ms).
[18:04:36.646] <TB1> INFO: Test took 1488ms.
[18:04:36.649] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:37.063] <TB1> INFO: Expecting 2560 events.
[18:04:38.133] <TB1> INFO: 2560 events read in total (354ms).
[18:04:38.133] <TB1> INFO: Test took 1484ms.
[18:04:38.137] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:38.549] <TB1> INFO: Expecting 2560 events.
[18:04:39.620] <TB1> INFO: 2560 events read in total (353ms).
[18:04:39.620] <TB1> INFO: Test took 1483ms.
[18:04:39.623] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:40.036] <TB1> INFO: Expecting 2560 events.
[18:04:41.099] <TB1> INFO: 2560 events read in total (347ms).
[18:04:41.100] <TB1> INFO: Test took 1477ms.
[18:04:41.102] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:41.516] <TB1> INFO: Expecting 2560 events.
[18:04:42.580] <TB1> INFO: 2560 events read in total (348ms).
[18:04:42.580] <TB1> INFO: Test took 1478ms.
[18:04:42.583] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:42.997] <TB1> INFO: Expecting 2560 events.
[18:04:44.067] <TB1> INFO: 2560 events read in total (354ms).
[18:04:44.067] <TB1> INFO: Test took 1484ms.
[18:04:44.069] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:44.483] <TB1> INFO: Expecting 2560 events.
[18:04:45.548] <TB1> INFO: 2560 events read in total (348ms).
[18:04:45.548] <TB1> INFO: Test took 1479ms.
[18:04:45.551] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:45.964] <TB1> INFO: Expecting 2560 events.
[18:04:47.030] <TB1> INFO: 2560 events read in total (349ms).
[18:04:47.031] <TB1> INFO: Test took 1480ms.
[18:04:47.034] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:47.447] <TB1> INFO: Expecting 2560 events.
[18:04:48.514] <TB1> INFO: 2560 events read in total (351ms).
[18:04:48.514] <TB1> INFO: Test took 1480ms.
[18:04:48.517] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:48.931] <TB1> INFO: Expecting 2560 events.
[18:04:49.997] <TB1> INFO: 2560 events read in total (349ms).
[18:04:49.997] <TB1> INFO: Test took 1480ms.
[18:04:50.000] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:50.413] <TB1> INFO: Expecting 2560 events.
[18:04:51.478] <TB1> INFO: 2560 events read in total (348ms).
[18:04:51.478] <TB1> INFO: Test took 1478ms.
[18:04:51.482] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:04:51.894] <TB1> INFO: Expecting 2560 events.
[18:04:52.965] <TB1> INFO: 2560 events read in total (355ms).
[18:04:52.965] <TB1> INFO: Test took 1484ms.
[18:04:53.646] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 553 seconds
[18:04:53.646] <TB1> INFO: PH scale (per ROC): 75 78 80 80 77 74 80 83 80 87 85 78 78 85 80 82
[18:04:53.646] <TB1> INFO: PH offset (per ROC): 162 155 145 150 157 155 171 156 155 150 135 159 158 137 146 147
[18:04:53.654] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:04:53.654] <TB1> INFO: Decoding statistics:
[18:04:53.654] <TB1> INFO: General information:
[18:04:53.654] <TB1> INFO: 16bit words read: 91924
[18:04:53.654] <TB1> INFO: valid events total: 10240
[18:04:53.654] <TB1> INFO: empty events: 7739
[18:04:53.654] <TB1> INFO: valid events with pixels: 2501
[18:04:53.654] <TB1> INFO: valid pixel hits: 2501
[18:04:53.654] <TB1> INFO: Event errors: 0
[18:04:53.654] <TB1> INFO: start marker: 0
[18:04:53.654] <TB1> INFO: stop marker: 0
[18:04:53.654] <TB1> INFO: overflow: 0
[18:04:53.654] <TB1> INFO: invalid 5bit words: 0
[18:04:53.654] <TB1> INFO: invalid XOR eye diagram: 0
[18:04:53.654] <TB1> INFO: TBM errors: 0
[18:04:53.654] <TB1> INFO: flawed TBM headers: 0
[18:04:53.654] <TB1> INFO: flawed TBM trailers: 0
[18:04:53.654] <TB1> INFO: event ID mismatches: 0
[18:04:53.654] <TB1> INFO: ROC errors: 0
[18:04:53.654] <TB1> INFO: missing ROC header(s): 0
[18:04:53.654] <TB1> INFO: misplaced readback start: 0
[18:04:53.654] <TB1> INFO: Pixel decoding errors: 0
[18:04:53.654] <TB1> INFO: pixel data incomplete: 0
[18:04:53.654] <TB1> INFO: pixel address: 0
[18:04:53.654] <TB1> INFO: pulse height fill bit: 0
[18:04:53.655] <TB1> INFO: buffer corruption: 0
[18:04:53.834] <TB1> INFO: ######################################################################
[18:04:53.834] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[18:04:53.834] <TB1> INFO: ######################################################################
[18:04:53.845] <TB1> INFO: scanning low vcal = 10
[18:04:54.158] <TB1> INFO: Expecting 41600 events.
[18:04:57.952] <TB1> INFO: 41600 events read in total (3077ms).
[18:04:57.953] <TB1> INFO: Test took 4108ms.
[18:04:57.956] <TB1> INFO: scanning low vcal = 20
[18:04:58.368] <TB1> INFO: Expecting 41600 events.
[18:05:02.163] <TB1> INFO: 41600 events read in total (3079ms).
[18:05:02.164] <TB1> INFO: Test took 4208ms.
[18:05:02.167] <TB1> INFO: scanning low vcal = 30
[18:05:02.579] <TB1> INFO: Expecting 41600 events.
[18:05:06.391] <TB1> INFO: 41600 events read in total (3096ms).
[18:05:06.391] <TB1> INFO: Test took 4224ms.
[18:05:06.394] <TB1> INFO: scanning low vcal = 40
[18:05:06.803] <TB1> INFO: Expecting 41600 events.
[18:05:11.070] <TB1> INFO: 41600 events read in total (3551ms).
[18:05:11.071] <TB1> INFO: Test took 4677ms.
[18:05:11.075] <TB1> INFO: scanning low vcal = 50
[18:05:11.426] <TB1> INFO: Expecting 41600 events.
[18:05:15.751] <TB1> INFO: 41600 events read in total (3609ms).
[18:05:15.751] <TB1> INFO: Test took 4676ms.
[18:05:15.755] <TB1> INFO: scanning low vcal = 60
[18:05:16.109] <TB1> INFO: Expecting 41600 events.
[18:05:20.471] <TB1> INFO: 41600 events read in total (3645ms).
[18:05:20.472] <TB1> INFO: Test took 4717ms.
[18:05:20.475] <TB1> INFO: scanning low vcal = 70
[18:05:20.830] <TB1> INFO: Expecting 41600 events.
[18:05:25.151] <TB1> INFO: 41600 events read in total (3605ms).
[18:05:25.152] <TB1> INFO: Test took 4677ms.
[18:05:25.156] <TB1> INFO: scanning low vcal = 80
[18:05:25.503] <TB1> INFO: Expecting 41600 events.
[18:05:29.872] <TB1> INFO: 41600 events read in total (3653ms).
[18:05:29.873] <TB1> INFO: Test took 4717ms.
[18:05:29.876] <TB1> INFO: scanning low vcal = 90
[18:05:30.225] <TB1> INFO: Expecting 41600 events.
[18:05:34.571] <TB1> INFO: 41600 events read in total (3630ms).
[18:05:34.571] <TB1> INFO: Test took 4695ms.
[18:05:34.575] <TB1> INFO: scanning low vcal = 100
[18:05:34.927] <TB1> INFO: Expecting 41600 events.
[18:05:39.392] <TB1> INFO: 41600 events read in total (3749ms).
[18:05:39.393] <TB1> INFO: Test took 4818ms.
[18:05:39.396] <TB1> INFO: scanning low vcal = 110
[18:05:39.748] <TB1> INFO: Expecting 41600 events.
[18:05:44.066] <TB1> INFO: 41600 events read in total (3601ms).
[18:05:44.066] <TB1> INFO: Test took 4670ms.
[18:05:44.069] <TB1> INFO: scanning low vcal = 120
[18:05:44.424] <TB1> INFO: Expecting 41600 events.
[18:05:48.743] <TB1> INFO: 41600 events read in total (3603ms).
[18:05:48.744] <TB1> INFO: Test took 4675ms.
[18:05:48.747] <TB1> INFO: scanning low vcal = 130
[18:05:49.085] <TB1> INFO: Expecting 41600 events.
[18:05:53.409] <TB1> INFO: 41600 events read in total (3608ms).
[18:05:53.410] <TB1> INFO: Test took 4663ms.
[18:05:53.414] <TB1> INFO: scanning low vcal = 140
[18:05:53.762] <TB1> INFO: Expecting 41600 events.
[18:05:58.066] <TB1> INFO: 41600 events read in total (3587ms).
[18:05:58.066] <TB1> INFO: Test took 4652ms.
[18:05:58.070] <TB1> INFO: scanning low vcal = 150
[18:05:58.423] <TB1> INFO: Expecting 41600 events.
[18:06:02.777] <TB1> INFO: 41600 events read in total (3638ms).
[18:06:02.777] <TB1> INFO: Test took 4707ms.
[18:06:02.781] <TB1> INFO: scanning low vcal = 160
[18:06:03.120] <TB1> INFO: Expecting 41600 events.
[18:06:07.484] <TB1> INFO: 41600 events read in total (3647ms).
[18:06:07.485] <TB1> INFO: Test took 4704ms.
[18:06:07.488] <TB1> INFO: scanning low vcal = 170
[18:06:07.844] <TB1> INFO: Expecting 41600 events.
[18:06:12.175] <TB1> INFO: 41600 events read in total (3614ms).
[18:06:12.175] <TB1> INFO: Test took 4687ms.
[18:06:12.180] <TB1> INFO: scanning low vcal = 180
[18:06:12.528] <TB1> INFO: Expecting 41600 events.
[18:06:16.857] <TB1> INFO: 41600 events read in total (3612ms).
[18:06:16.857] <TB1> INFO: Test took 4677ms.
[18:06:16.860] <TB1> INFO: scanning low vcal = 190
[18:06:17.213] <TB1> INFO: Expecting 41600 events.
[18:06:21.585] <TB1> INFO: 41600 events read in total (3655ms).
[18:06:21.585] <TB1> INFO: Test took 4725ms.
[18:06:21.589] <TB1> INFO: scanning low vcal = 200
[18:06:21.925] <TB1> INFO: Expecting 41600 events.
[18:06:26.267] <TB1> INFO: 41600 events read in total (3625ms).
[18:06:26.267] <TB1> INFO: Test took 4678ms.
[18:06:26.270] <TB1> INFO: scanning low vcal = 210
[18:06:26.622] <TB1> INFO: Expecting 41600 events.
[18:06:30.937] <TB1> INFO: 41600 events read in total (3599ms).
[18:06:30.938] <TB1> INFO: Test took 4667ms.
[18:06:30.941] <TB1> INFO: scanning low vcal = 220
[18:06:31.287] <TB1> INFO: Expecting 41600 events.
[18:06:35.575] <TB1> INFO: 41600 events read in total (3572ms).
[18:06:35.576] <TB1> INFO: Test took 4635ms.
[18:06:35.579] <TB1> INFO: scanning low vcal = 230
[18:06:35.928] <TB1> INFO: Expecting 41600 events.
[18:06:40.265] <TB1> INFO: 41600 events read in total (3620ms).
[18:06:40.265] <TB1> INFO: Test took 4686ms.
[18:06:40.269] <TB1> INFO: scanning low vcal = 240
[18:06:40.624] <TB1> INFO: Expecting 41600 events.
[18:06:44.944] <TB1> INFO: 41600 events read in total (3604ms).
[18:06:44.944] <TB1> INFO: Test took 4675ms.
[18:06:44.948] <TB1> INFO: scanning low vcal = 250
[18:06:45.303] <TB1> INFO: Expecting 41600 events.
[18:06:49.604] <TB1> INFO: 41600 events read in total (3585ms).
[18:06:49.604] <TB1> INFO: Test took 4656ms.
[18:06:49.610] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[18:06:49.954] <TB1> INFO: Expecting 41600 events.
[18:06:54.233] <TB1> INFO: 41600 events read in total (3562ms).
[18:06:54.233] <TB1> INFO: Test took 4623ms.
[18:06:54.237] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[18:06:54.587] <TB1> INFO: Expecting 41600 events.
[18:06:58.883] <TB1> INFO: 41600 events read in total (3580ms).
[18:06:58.883] <TB1> INFO: Test took 4646ms.
[18:06:58.887] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[18:06:59.243] <TB1> INFO: Expecting 41600 events.
[18:07:03.534] <TB1> INFO: 41600 events read in total (3575ms).
[18:07:03.535] <TB1> INFO: Test took 4648ms.
[18:07:03.538] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[18:07:03.893] <TB1> INFO: Expecting 41600 events.
[18:07:08.243] <TB1> INFO: 41600 events read in total (3634ms).
[18:07:08.243] <TB1> INFO: Test took 4705ms.
[18:07:08.246] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[18:07:08.596] <TB1> INFO: Expecting 41600 events.
[18:07:12.950] <TB1> INFO: 41600 events read in total (3637ms).
[18:07:12.951] <TB1> INFO: Test took 4705ms.
[18:07:13.547] <TB1> INFO: PixTestGainPedestal::measure() done
[18:07:48.955] <TB1> INFO: PixTestGainPedestal::fit() done
[18:07:48.955] <TB1> INFO: non-linearity mean: 0.952 0.958 0.961 0.959 0.957 0.957 0.954 0.952 0.951 0.946 0.951 0.950 0.953 0.964 0.956 0.952
[18:07:48.955] <TB1> INFO: non-linearity RMS: 0.007 0.006 0.004 0.006 0.005 0.006 0.006 0.007 0.006 0.007 0.005 0.009 0.006 0.005 0.006 0.007
[18:07:48.955] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C0.dat
[18:07:48.975] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C1.dat
[18:07:48.994] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C2.dat
[18:07:49.014] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C3.dat
[18:07:49.034] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C4.dat
[18:07:49.053] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C5.dat
[18:07:49.073] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C6.dat
[18:07:49.092] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C7.dat
[18:07:49.112] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C8.dat
[18:07:49.132] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C9.dat
[18:07:49.151] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C10.dat
[18:07:49.171] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C11.dat
[18:07:49.191] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C12.dat
[18:07:49.210] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C13.dat
[18:07:49.230] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C14.dat
[18:07:49.250] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//phCalibrationFitErr35_C15.dat
[18:07:49.269] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 175 seconds
[18:07:49.269] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:07:49.269] <TB1> INFO: Decoding statistics:
[18:07:49.269] <TB1> INFO: General information:
[18:07:49.269] <TB1> INFO: 16bit words read: 2662400
[18:07:49.269] <TB1> INFO: valid events total: 166400
[18:07:49.269] <TB1> INFO: empty events: 0
[18:07:49.269] <TB1> INFO: valid events with pixels: 166400
[18:07:49.269] <TB1> INFO: valid pixel hits: 665460
[18:07:49.269] <TB1> INFO: Event errors: 0
[18:07:49.269] <TB1> INFO: start marker: 0
[18:07:49.269] <TB1> INFO: stop marker: 0
[18:07:49.269] <TB1> INFO: overflow: 0
[18:07:49.269] <TB1> INFO: invalid 5bit words: 0
[18:07:49.269] <TB1> INFO: invalid XOR eye diagram: 0
[18:07:49.269] <TB1> INFO: TBM errors: 0
[18:07:49.269] <TB1> INFO: flawed TBM headers: 0
[18:07:49.269] <TB1> INFO: flawed TBM trailers: 0
[18:07:49.269] <TB1> INFO: event ID mismatches: 0
[18:07:49.269] <TB1> INFO: ROC errors: 0
[18:07:49.269] <TB1> INFO: missing ROC header(s): 0
[18:07:49.269] <TB1> INFO: misplaced readback start: 0
[18:07:49.269] <TB1> INFO: Pixel decoding errors: 0
[18:07:49.269] <TB1> INFO: pixel data incomplete: 0
[18:07:49.269] <TB1> INFO: pixel address: 0
[18:07:49.269] <TB1> INFO: pulse height fill bit: 0
[18:07:49.269] <TB1> INFO: buffer corruption: 0
[18:07:49.276] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C15.dat
[18:07:49.277] <TB1> INFO: ######################################################################
[18:07:49.277] <TB1> INFO: PixTestReadback::doTest()
[18:07:49.277] <TB1> INFO: ######################################################################
[18:07:49.278] <TB1> INFO: PixTestReadback::RES sent once
[18:08:11.100] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C0.dat
[18:08:11.101] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C1.dat
[18:08:11.101] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C2.dat
[18:08:11.101] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C3.dat
[18:08:11.101] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C4.dat
[18:08:11.101] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C5.dat
[18:08:11.101] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C6.dat
[18:08:11.101] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C7.dat
[18:08:11.102] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C8.dat
[18:08:11.102] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C9.dat
[18:08:11.102] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C10.dat
[18:08:11.102] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C11.dat
[18:08:11.102] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C12.dat
[18:08:11.102] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C13.dat
[18:08:11.102] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C14.dat
[18:08:11.102] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C15.dat
[18:08:11.150] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:08:11.151] <TB1> INFO: PixTestReadback::RES sent once
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C0.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C1.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C2.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C3.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C4.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C5.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C6.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C7.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C8.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C9.dat
[18:08:32.940] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C10.dat
[18:08:32.941] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C11.dat
[18:08:32.941] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C12.dat
[18:08:32.941] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C13.dat
[18:08:32.941] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C14.dat
[18:08:32.941] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C15.dat
[18:08:32.987] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:08:32.988] <TB1> INFO: PixTestReadback::RES sent once
[18:08:49.891] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:08:49.891] <TB1> INFO: Vbg will be calibrated using Vd calibration
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 159.3calibrated Vbg = 1.17868 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159.7calibrated Vbg = 1.17584 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148.3calibrated Vbg = 1.17343 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.9calibrated Vbg = 1.1858 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 161.3calibrated Vbg = 1.1917 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 149calibrated Vbg = 1.18809 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 149.6calibrated Vbg = 1.19074 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.3calibrated Vbg = 1.18544 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 159.8calibrated Vbg = 1.18608 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 158.8calibrated Vbg = 1.18119 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 142.6calibrated Vbg = 1.18505 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 155.1calibrated Vbg = 1.18419 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 157.1calibrated Vbg = 1.17183 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 148calibrated Vbg = 1.1729 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 159.3calibrated Vbg = 1.18059 :::*/*/*/*/
[18:08:49.891] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 145.9calibrated Vbg = 1.17925 :::*/*/*/*/
[18:08:49.894] <TB1> INFO: PixTestReadback::RES sent once
[18:13:30.824] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C0.dat
[18:13:30.824] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C1.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C2.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C3.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C4.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C5.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C6.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C7.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C8.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C9.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C10.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C11.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C12.dat
[18:13:30.825] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C13.dat
[18:13:30.826] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C14.dat
[18:13:30.826] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2134_FullQualification_2016-01-18_13h39m_1453120747//002_Fulltest_m20//readbackCal_C15.dat
[18:13:30.871] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:13:30.872] <TB1> INFO: PixTestReadback::doTest() done
[18:13:30.872] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:30.872] <TB1> INFO: Decoding statistics:
[18:13:30.872] <TB1> INFO: General information:
[18:13:30.872] <TB1> INFO: 16bit words read: 1024
[18:13:30.872] <TB1> INFO: valid events total: 128
[18:13:30.872] <TB1> INFO: empty events: 128
[18:13:30.872] <TB1> INFO: valid events with pixels: 0
[18:13:30.872] <TB1> INFO: valid pixel hits: 0
[18:13:30.872] <TB1> INFO: Event errors: 0
[18:13:30.872] <TB1> INFO: start marker: 0
[18:13:30.872] <TB1> INFO: stop marker: 0
[18:13:30.872] <TB1> INFO: overflow: 0
[18:13:30.872] <TB1> INFO: invalid 5bit words: 0
[18:13:30.872] <TB1> INFO: invalid XOR eye diagram: 0
[18:13:30.872] <TB1> INFO: TBM errors: 0
[18:13:30.872] <TB1> INFO: flawed TBM headers: 0
[18:13:30.873] <TB1> INFO: flawed TBM trailers: 0
[18:13:30.873] <TB1> INFO: event ID mismatches: 0
[18:13:30.873] <TB1> INFO: ROC errors: 0
[18:13:30.873] <TB1> INFO: missing ROC header(s): 0
[18:13:30.873] <TB1> INFO: misplaced readback start: 0
[18:13:30.873] <TB1> INFO: Pixel decoding errors: 0
[18:13:30.873] <TB1> INFO: pixel data incomplete: 0
[18:13:30.873] <TB1> INFO: pixel address: 0
[18:13:30.873] <TB1> INFO: pulse height fill bit: 0
[18:13:30.873] <TB1> INFO: buffer corruption: 0
[18:13:30.890] <TB1> INFO: Decoding statistics:
[18:13:30.890] <TB1> INFO: General information:
[18:13:30.890] <TB1> INFO: 16bit words read: 2755348
[18:13:30.890] <TB1> INFO: valid events total: 176768
[18:13:30.890] <TB1> INFO: empty events: 7867
[18:13:30.890] <TB1> INFO: valid events with pixels: 168901
[18:13:30.890] <TB1> INFO: valid pixel hits: 667961
[18:13:30.890] <TB1> INFO: Event errors: 0
[18:13:30.890] <TB1> INFO: start marker: 0
[18:13:30.890] <TB1> INFO: stop marker: 0
[18:13:30.890] <TB1> INFO: overflow: 0
[18:13:30.890] <TB1> INFO: invalid 5bit words: 0
[18:13:30.890] <TB1> INFO: invalid XOR eye diagram: 0
[18:13:30.890] <TB1> INFO: TBM errors: 0
[18:13:30.890] <TB1> INFO: flawed TBM headers: 0
[18:13:30.890] <TB1> INFO: flawed TBM trailers: 0
[18:13:30.890] <TB1> INFO: event ID mismatches: 0
[18:13:30.890] <TB1> INFO: ROC errors: 0
[18:13:30.890] <TB1> INFO: missing ROC header(s): 0
[18:13:30.890] <TB1> INFO: misplaced readback start: 0
[18:13:30.890] <TB1> INFO: Pixel decoding errors: 0
[18:13:30.890] <TB1> INFO: pixel data incomplete: 0
[18:13:30.890] <TB1> INFO: pixel address: 0
[18:13:30.890] <TB1> INFO: pulse height fill bit: 0
[18:13:30.890] <TB1> INFO: buffer corruption: 0
[18:13:30.890] <TB1> INFO: enter test to run
[18:13:30.890] <TB1> INFO: test: exit no parameter change
[18:13:31.106] <TB1> QUIET: Connection to board 153 closed.
[18:13:31.185] <TB1> INFO: pXar: this is the end, my friend