[12:33:07.962] <TB1> INFO: *** Welcome to pxar ***
[12:33:07.963] <TB1> INFO: *** Today: 2015/09/10
[12:33:07.963] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C15.dat
[12:33:07.964] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:33:07.964] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//defaultMaskFile.dat
[12:33:07.964] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters_C15.dat
[12:33:08.052] <TB1> INFO: clk: 4
[12:33:08.052] <TB1> INFO: ctr: 4
[12:33:08.052] <TB1> INFO: sda: 19
[12:33:08.052] <TB1> INFO: tin: 9
[12:33:08.052] <TB1> INFO: level: 15
[12:33:08.052] <TB1> INFO: triggerdelay: 0
[12:33:08.052] <TB1> QUIET: Instanciating API for pxar prod-10+24~g09f6d2c
[12:33:08.052] <TB1> INFO: Log level: INFO
[12:33:08.060] <TB1> INFO: Found DTB DTB_WXBYFL
[12:33:08.069] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[12:33:08.072] <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:
------------------------------------------------------
[12:33:08.075] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[12:33:09.610] <TB1> INFO: DUT info:
[12:33:09.610] <TB1> INFO: The DUT currently contains the following objects:
[12:33:09.610] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:33:09.610] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:33:09.610] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:33:09.610] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:33:09.610] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.610] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.611] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.611] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.611] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.611] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:09.611] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:10.012] <TB1> INFO: enter 'restricted' command line mode
[12:33:10.012] <TB1> INFO: enter test to run
[12:33:10.012] <TB1> INFO: test: pretest no parameter change
[12:33:10.012] <TB1> INFO: running: pretest
[12:33:10.018] <TB1> INFO: ######################################################################
[12:33:10.018] <TB1> INFO: PixTestPretest::doTest()
[12:33:10.018] <TB1> INFO: ######################################################################
[12:33:10.020] <TB1> INFO: ----------------------------------------------------------------------
[12:33:10.020] <TB1> INFO: PixTestPretest::programROC()
[12:33:10.020] <TB1> INFO: ----------------------------------------------------------------------
[12:33:28.042] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:33:28.042] <TB1> INFO: IA differences per ROC: 18.5 18.5 21.7 17.7 19.3 20.1 20.1 18.5 16.9 17.7 18.5 20.1 17.7 18.5 16.9 17.7
[12:33:28.142] <TB1> INFO: ----------------------------------------------------------------------
[12:33:28.142] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:33:28.142] <TB1> INFO: ----------------------------------------------------------------------
[12:33:32.132] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[12:33:32.135] <TB1> INFO: ----------------------------------------------------------------------
[12:33:32.135] <TB1> INFO: PixTestPretest::findTiming()
[12:33:32.135] <TB1> INFO: ----------------------------------------------------------------------
[12:33:32.135] <TB1> INFO: PixTestCmd::init()

[12:36:14.727] <TB1> INFO: TBM phases: 160MHz: 3, 400MHz: 7, TBM delays: ROC(0/1):2, header/trailer: 1, token: 0
[12:36:14.727] <TB1> INFO: (success/tries = 100/100), width = 2
[12:36:14.729] <TB1> INFO: ----------------------------------------------------------------------
[12:36:14.729] <TB1> INFO: PixTestPretest::findWorkingPixel()
[12:36:14.729] <TB1> INFO: ----------------------------------------------------------------------
[12:36:14.869] <TB1> INFO: Expecting 231680 events.
[12:36:23.166] <TB1> INFO: 231680 events read in total (7580ms).
[12:36:23.170] <TB1> INFO: Test took 8438ms.
[12:36:23.485] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:36:23.533] <TB1> INFO: ----------------------------------------------------------------------
[12:36:23.533] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[12:36:23.533] <TB1> INFO: ----------------------------------------------------------------------
[12:36:23.671] <TB1> INFO: Expecting 231680 events.
[12:36:32.391] <TB1> INFO: 231680 events read in total (8004ms).
[12:36:32.395] <TB1> INFO: Test took 8856ms.
[12:36:32.733] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[12:36:32.733] <TB1> INFO: CalDel: 143 143 159 123 127 162 158 152 144 119 131 149 139 135 140 135
[12:36:32.733] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:36:32.736] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C0.dat
[12:36:32.736] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C1.dat
[12:36:32.736] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C2.dat
[12:36:32.736] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C3.dat
[12:36:32.736] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C4.dat
[12:36:32.737] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C5.dat
[12:36:32.737] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C6.dat
[12:36:32.737] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C7.dat
[12:36:32.737] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C8.dat
[12:36:32.737] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C9.dat
[12:36:32.738] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C10.dat
[12:36:32.738] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C11.dat
[12:36:32.738] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C12.dat
[12:36:32.738] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C13.dat
[12:36:32.738] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C14.dat
[12:36:32.739] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters_C15.dat
[12:36:32.739] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//tbmParameters_C0a.dat
[12:36:32.739] <TB1> INFO: write tbm parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:36:32.739] <TB1> INFO: PixTestPretest::doTest() done, duration: 202 seconds
[12:36:32.818] <TB1> INFO: enter test to run
[12:36:32.818] <TB1> INFO: test: fulltest no parameter change
[12:36:32.818] <TB1> INFO: running: fulltest
[12:36:32.818] <TB1> INFO: ######################################################################
[12:36:32.818] <TB1> INFO: PixTestFullTest::doTest()
[12:36:32.818] <TB1> INFO: ######################################################################
[12:36:32.819] <TB1> INFO: ######################################################################
[12:36:32.819] <TB1> INFO: PixTestAlive::doTest()
[12:36:32.819] <TB1> INFO: ######################################################################
[12:36:32.823] <TB1> INFO: ----------------------------------------------------------------------
[12:36:32.823] <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)
[12:36:32.823] <TB1> INFO: ----------------------------------------------------------------------
[12:36:33.158] <TB1> INFO: Expecting 41600 events.
[12:36:37.444] <TB1> INFO: 41600 events read in total (3570ms).
[12:36:37.445] <TB1> INFO: Test took 4619ms.
[12:36:37.451] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:37.754] <TB1> INFO: PixTestAlive::aliveTest() done
[12:36:37.754] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:36:37.755] <TB1> INFO: ----------------------------------------------------------------------
[12:36:37.756] <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)
[12:36:37.756] <TB1> INFO: ----------------------------------------------------------------------
[12:36:38.100] <TB1> INFO: Expecting 41600 events.
[12:36:41.320] <TB1> INFO: 41600 events read in total (2504ms).
[12:36:41.320] <TB1> INFO: Test took 3562ms.
[12:36:41.320] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:41.320] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:36:41.651] <TB1> INFO: PixTestAlive::maskTest() done
[12:36:41.651] <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
[12:36:41.653] <TB1> INFO: ----------------------------------------------------------------------
[12:36:41.653] <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)
[12:36:41.653] <TB1> INFO: ----------------------------------------------------------------------
[12:36:41.967] <TB1> INFO: Expecting 41600 events.
[12:36:46.425] <TB1> INFO: 41600 events read in total (3742ms).
[12:36:46.426] <TB1> INFO: Test took 4771ms.
[12:36:46.432] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:46.732] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:36:46.732] <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
[12:36:46.732] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[12:36:46.732] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:46.732] <TB1> INFO: Decoding statistics:
[12:36:46.732] <TB1> INFO: General information:
[12:36:46.732] <TB1> INFO: 16bit words read: 0
[12:36:46.732] <TB1> INFO: valid events total: 0
[12:36:46.732] <TB1> INFO: empty events: 0
[12:36:46.732] <TB1> INFO: valid events with pixels: 0
[12:36:46.732] <TB1> INFO: valid pixel hits: 0
[12:36:46.732] <TB1> INFO: Event errors: 0
[12:36:46.732] <TB1> INFO: start marker: 0
[12:36:46.732] <TB1> INFO: stop marker: 0
[12:36:46.732] <TB1> INFO: overflow: 0
[12:36:46.732] <TB1> INFO: invalid 5bit words: 0
[12:36:46.732] <TB1> INFO: invalid XOR eye diagram: 0
[12:36:46.732] <TB1> INFO: TBM errors: 0
[12:36:46.732] <TB1> INFO: flawed TBM headers: 0
[12:36:46.732] <TB1> INFO: flawed TBM trailers: 0
[12:36:46.732] <TB1> INFO: event ID mismatches: 0
[12:36:46.732] <TB1> INFO: ROC errors: 0
[12:36:46.733] <TB1> INFO: missing ROC header(s): 0
[12:36:46.733] <TB1> INFO: misplaced readback start: 0
[12:36:46.733] <TB1> INFO: Pixel decoding errors: 0
[12:36:46.733] <TB1> INFO: pixel data incomplete: 0
[12:36:46.733] <TB1> INFO: pixel address: 0
[12:36:46.733] <TB1> INFO: pulse height fill bit: 0
[12:36:46.733] <TB1> INFO: buffer corruption: 0
[12:36:46.742] <TB1> INFO: ######################################################################
[12:36:46.742] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:36:46.742] <TB1> INFO: ######################################################################
[12:36:46.746] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:36:46.757] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:36:46.757] <TB1> INFO: run 1 of 1
[12:36:47.068] <TB1> INFO: Expecting 3120000 events.
[12:37:28.394] <TB1> INFO: 850255 events read in total (40610ms).
[12:38:08.525] <TB1> INFO: 1687760 events read in total (80741ms).
[12:38:47.821] <TB1> INFO: 2536300 events read in total (120037ms).
[12:39:14.712] <TB1> INFO: 3120000 events read in total (146928ms).
[12:39:14.757] <TB1> INFO: Test took 148000ms.
[12:39:14.855] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:38.459] <TB1> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[12:39:38.459] <TB1> INFO: number of dead bumps (per ROC): 1 0 0 0 0 0 0 0 0 0 0 1 0 0 4 7
[12:39:38.460] <TB1> INFO: separation cut (per ROC): 85 95 95 83 89 71 77 82 89 65 89 82 69 78 72 80
[12:39:38.460] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:38.460] <TB1> INFO: Decoding statistics:
[12:39:38.460] <TB1> INFO: General information:
[12:39:38.460] <TB1> INFO: 16bit words read: 0
[12:39:38.460] <TB1> INFO: valid events total: 0
[12:39:38.460] <TB1> INFO: empty events: 0
[12:39:38.460] <TB1> INFO: valid events with pixels: 0
[12:39:38.460] <TB1> INFO: valid pixel hits: 0
[12:39:38.460] <TB1> INFO: Event errors: 0
[12:39:38.460] <TB1> INFO: start marker: 0
[12:39:38.460] <TB1> INFO: stop marker: 0
[12:39:38.460] <TB1> INFO: overflow: 0
[12:39:38.460] <TB1> INFO: invalid 5bit words: 0
[12:39:38.460] <TB1> INFO: invalid XOR eye diagram: 0
[12:39:38.460] <TB1> INFO: TBM errors: 0
[12:39:38.460] <TB1> INFO: flawed TBM headers: 0
[12:39:38.460] <TB1> INFO: flawed TBM trailers: 0
[12:39:38.460] <TB1> INFO: event ID mismatches: 0
[12:39:38.460] <TB1> INFO: ROC errors: 0
[12:39:38.460] <TB1> INFO: missing ROC header(s): 0
[12:39:38.460] <TB1> INFO: misplaced readback start: 0
[12:39:38.460] <TB1> INFO: Pixel decoding errors: 0
[12:39:38.460] <TB1> INFO: pixel data incomplete: 0
[12:39:38.460] <TB1> INFO: pixel address: 0
[12:39:38.460] <TB1> INFO: pulse height fill bit: 0
[12:39:38.460] <TB1> INFO: buffer corruption: 0
[12:39:38.540] <TB1> INFO: ######################################################################
[12:39:38.540] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:39:38.540] <TB1> INFO: ######################################################################
[12:39:38.540] <TB1> INFO: ----------------------------------------------------------------------
[12:39:38.540] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:39:38.540] <TB1> INFO: ----------------------------------------------------------------------
[12:39:38.541] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:39:38.550] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:39:38.550] <TB1> INFO: run 1 of 1
[12:39:38.887] <TB1> INFO: Expecting 26208000 events.
[12:40:10.097] <TB1> INFO: 880000 events read in total (30493ms).
[12:40:40.169] <TB1> INFO: 1748800 events read in total (60565ms).
[12:41:10.132] <TB1> INFO: 2613200 events read in total (90528ms).
[12:41:38.662] <TB1> INFO: 3476100 events read in total (119058ms).
[12:42:08.857] <TB1> INFO: 4339200 events read in total (149253ms).
[12:42:38.781] <TB1> INFO: 5202100 events read in total (179177ms).
[12:43:08.773] <TB1> INFO: 6067550 events read in total (209169ms).
[12:43:38.918] <TB1> INFO: 6928800 events read in total (239314ms).
[12:44:08.795] <TB1> INFO: 7790850 events read in total (269191ms).
[12:44:39.274] <TB1> INFO: 8652400 events read in total (299670ms).
[12:45:10.025] <TB1> INFO: 9514500 events read in total (330421ms).
[12:45:40.673] <TB1> INFO: 10374400 events read in total (361069ms).
[12:46:10.581] <TB1> INFO: 11235300 events read in total (390977ms).
[12:46:40.470] <TB1> INFO: 12094750 events read in total (420866ms).
[12:47:10.537] <TB1> INFO: 12951550 events read in total (450933ms).
[12:47:38.883] <TB1> INFO: 13802400 events read in total (479279ms).
[12:48:08.752] <TB1> INFO: 14651700 events read in total (509148ms).
[12:48:38.131] <TB1> INFO: 15500350 events read in total (538527ms).
[12:49:08.495] <TB1> INFO: 16347300 events read in total (568891ms).
[12:49:38.501] <TB1> INFO: 17196550 events read in total (598897ms).
[12:50:08.558] <TB1> INFO: 18041400 events read in total (628954ms).
[12:50:38.452] <TB1> INFO: 18888250 events read in total (658848ms).
[12:51:08.201] <TB1> INFO: 19734800 events read in total (688597ms).
[12:51:37.760] <TB1> INFO: 20579000 events read in total (718156ms).
[12:52:07.272] <TB1> INFO: 21425450 events read in total (747668ms).
[12:52:37.372] <TB1> INFO: 22271250 events read in total (777768ms).
[12:53:07.637] <TB1> INFO: 23117900 events read in total (808033ms).
[12:53:38.444] <TB1> INFO: 23964550 events read in total (838840ms).
[12:54:08.472] <TB1> INFO: 24814500 events read in total (868868ms).
[12:54:38.431] <TB1> INFO: 25663600 events read in total (898827ms).
[12:54:55.916] <TB1> INFO: 26208000 events read in total (916312ms).
[12:54:55.945] <TB1> INFO: Test took 917395ms.
[12:54:56.028] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:56.204] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:54:57.627] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:54:59.037] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:00.528] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:02.083] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:03.479] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:04.923] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:06.441] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:07.867] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:09.280] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:10.732] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:12.130] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:13.532] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:15.266] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:16.708] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:18.110] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:55:19.740] <TB1> INFO: PixTestScurves::scurves() done
[12:55:19.740] <TB1> INFO: Vcal mean: 82.85 89.52 88.78 86.08 90.47 77.50 80.40 84.89 88.52 74.28 90.43 89.47 75.07 73.67 87.09 89.78
[12:55:19.740] <TB1> INFO: Vcal RMS: 5.20 5.74 5.30 5.54 5.33 4.19 4.53 5.09 5.54 4.40 5.60 5.57 4.55 4.89 5.19 5.49
[12:55:19.740] <TB1> INFO: PixTestScurves::fullTest() done, duration: 941 seconds
[12:55:19.740] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:19.740] <TB1> INFO: Decoding statistics:
[12:55:19.740] <TB1> INFO: General information:
[12:55:19.740] <TB1> INFO: 16bit words read: 0
[12:55:19.740] <TB1> INFO: valid events total: 0
[12:55:19.740] <TB1> INFO: empty events: 0
[12:55:19.740] <TB1> INFO: valid events with pixels: 0
[12:55:19.740] <TB1> INFO: valid pixel hits: 0
[12:55:19.740] <TB1> INFO: Event errors: 0
[12:55:19.740] <TB1> INFO: start marker: 0
[12:55:19.740] <TB1> INFO: stop marker: 0
[12:55:19.740] <TB1> INFO: overflow: 0
[12:55:19.740] <TB1> INFO: invalid 5bit words: 0
[12:55:19.740] <TB1> INFO: invalid XOR eye diagram: 0
[12:55:19.740] <TB1> INFO: TBM errors: 0
[12:55:19.740] <TB1> INFO: flawed TBM headers: 0
[12:55:19.740] <TB1> INFO: flawed TBM trailers: 0
[12:55:19.740] <TB1> INFO: event ID mismatches: 0
[12:55:19.740] <TB1> INFO: ROC errors: 0
[12:55:19.740] <TB1> INFO: missing ROC header(s): 0
[12:55:19.740] <TB1> INFO: misplaced readback start: 0
[12:55:19.740] <TB1> INFO: Pixel decoding errors: 0
[12:55:19.740] <TB1> INFO: pixel data incomplete: 0
[12:55:19.740] <TB1> INFO: pixel address: 0
[12:55:19.740] <TB1> INFO: pulse height fill bit: 0
[12:55:19.740] <TB1> INFO: buffer corruption: 0
[12:55:19.812] <TB1> INFO: ######################################################################
[12:55:19.812] <TB1> INFO: PixTestTrim::doTest()
[12:55:19.812] <TB1> INFO: ######################################################################
[12:55:19.814] <TB1> INFO: ----------------------------------------------------------------------
[12:55:19.814] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:55:19.814] <TB1> INFO: ----------------------------------------------------------------------
[12:55:19.908] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:55:19.908] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:55:19.916] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:55:19.916] <TB1> INFO: run 1 of 1
[12:55:20.233] <TB1> INFO: Expecting 6281600 events.
[12:56:00.096] <TB1> INFO: 1069340 events read in total (39142ms).
[12:56:39.346] <TB1> INFO: 2133590 events read in total (78392ms).
[12:57:17.653] <TB1> INFO: 3192290 events read in total (116699ms).
[12:57:56.336] <TB1> INFO: 4256180 events read in total (155382ms).
[12:58:35.414] <TB1> INFO: 5324030 events read in total (194460ms).
[12:59:08.025] <TB1> INFO: 6281600 events read in total (227071ms).
[12:59:08.060] <TB1> INFO: Test took 228144ms.
[12:59:08.110] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:26.916] <TB1> INFO: ROC 0 VthrComp = 85
[12:59:26.916] <TB1> INFO: ROC 1 VthrComp = 96
[12:59:26.916] <TB1> INFO: ROC 2 VthrComp = 95
[12:59:26.916] <TB1> INFO: ROC 3 VthrComp = 88
[12:59:26.917] <TB1> INFO: ROC 4 VthrComp = 96
[12:59:26.917] <TB1> INFO: ROC 5 VthrComp = 81
[12:59:26.917] <TB1> INFO: ROC 6 VthrComp = 83
[12:59:26.917] <TB1> INFO: ROC 7 VthrComp = 88
[12:59:26.917] <TB1> INFO: ROC 8 VthrComp = 91
[12:59:26.918] <TB1> INFO: ROC 9 VthrComp = 77
[12:59:26.918] <TB1> INFO: ROC 10 VthrComp = 97
[12:59:26.918] <TB1> INFO: ROC 11 VthrComp = 94
[12:59:26.918] <TB1> INFO: ROC 12 VthrComp = 79
[12:59:26.918] <TB1> INFO: ROC 13 VthrComp = 79
[12:59:26.919] <TB1> INFO: ROC 14 VthrComp = 89
[12:59:26.919] <TB1> INFO: ROC 15 VthrComp = 93
[12:59:26.919] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:59:26.919] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:59:26.929] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:59:26.929] <TB1> INFO: run 1 of 1
[12:59:27.272] <TB1> INFO: Expecting 6281600 events.
[12:59:59.097] <TB1> INFO: 756220 events read in total (31109ms).
[13:00:32.525] <TB1> INFO: 1509900 events read in total (64537ms).
[13:01:05.928] <TB1> INFO: 2264020 events read in total (97940ms).
[13:01:38.984] <TB1> INFO: 3018330 events read in total (130996ms).
[13:02:12.464] <TB1> INFO: 3767440 events read in total (164476ms).
[13:02:45.994] <TB1> INFO: 4513710 events read in total (198006ms).
[13:03:19.768] <TB1> INFO: 5258370 events read in total (231780ms).
[13:03:52.157] <TB1> INFO: 6003560 events read in total (264169ms).
[13:04:04.492] <TB1> INFO: 6281600 events read in total (276504ms).
[13:04:04.535] <TB1> INFO: Test took 277606ms.
[13:04:04.680] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:29.477] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.4954 for pixel 11/68 mean/min/max = 46.1539/31.7203/60.5875
[13:04:29.477] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 58.9211 for pixel 0/67 mean/min/max = 45.065/31.1702/58.9599
[13:04:29.478] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.7295 for pixel 24/2 mean/min/max = 44.9597/32.1181/57.8013
[13:04:29.494] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.9309 for pixel 0/0 mean/min/max = 46.0102/31.7759/60.2444
[13:04:29.494] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 57.8467 for pixel 51/13 mean/min/max = 44.7064/31.5365/57.8764
[13:04:29.494] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 56.4997 for pixel 49/24 mean/min/max = 44.701/32.8769/56.5251
[13:04:29.494] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.4112 for pixel 13/75 mean/min/max = 45.6081/32.6448/58.5714
[13:04:29.495] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 59.7264 for pixel 0/39 mean/min/max = 46.2103/32.5892/59.8314
[13:04:29.495] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.8607 for pixel 21/79 mean/min/max = 45.559/32.1592/58.9589
[13:04:29.495] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 59.9634 for pixel 17/78 mean/min/max = 47.7808/35.5825/59.979
[13:04:29.495] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.8546 for pixel 26/4 mean/min/max = 44.8295/31.5184/58.1407
[13:04:29.496] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 59.2542 for pixel 16/2 mean/min/max = 45.4312/31.5576/59.3048
[13:04:29.496] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 58.2223 for pixel 0/38 mean/min/max = 46.4401/34.6533/58.2268
[13:04:29.496] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.6463 for pixel 21/74 mean/min/max = 46.1456/34.6001/57.6911
[13:04:29.496] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 58.4673 for pixel 3/17 mean/min/max = 45.844/32.6656/59.0224
[13:04:29.497] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.4747 for pixel 18/79 mean/min/max = 44.8714/32.1116/57.6313
[13:04:29.497] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:04:29.630] <TB1> INFO: Expecting 514560 events.
[13:04:41.018] <TB1> INFO: 514560 events read in total (10672ms).
[13:04:41.023] <TB1> INFO: Expecting 514560 events.
[13:04:52.448] <TB1> INFO: 514560 events read in total (10846ms).
[13:04:52.455] <TB1> INFO: Expecting 514560 events.
[13:05:03.299] <TB1> INFO: 514560 events read in total (10266ms).
[13:05:03.309] <TB1> INFO: Expecting 514560 events.
[13:05:14.159] <TB1> INFO: 514560 events read in total (10277ms).
[13:05:14.170] <TB1> INFO: Expecting 514560 events.
[13:05:25.312] <TB1> INFO: 514560 events read in total (10566ms).
[13:05:25.325] <TB1> INFO: Expecting 514560 events.
[13:05:36.835] <TB1> INFO: 514560 events read in total (10936ms).
[13:05:36.848] <TB1> INFO: Expecting 514560 events.
[13:05:48.581] <TB1> INFO: 514560 events read in total (11161ms).
[13:05:48.599] <TB1> INFO: Expecting 514560 events.
[13:06:00.150] <TB1> INFO: 514560 events read in total (10989ms).
[13:06:00.168] <TB1> INFO: Expecting 514560 events.
[13:06:11.782] <TB1> INFO: 514560 events read in total (11047ms).
[13:06:11.802] <TB1> INFO: Expecting 514560 events.
[13:06:23.474] <TB1> INFO: 514560 events read in total (11108ms).
[13:06:23.499] <TB1> INFO: Expecting 514560 events.
[13:06:34.841] <TB1> INFO: 514560 events read in total (10790ms).
[13:06:34.867] <TB1> INFO: Expecting 514560 events.
[13:06:47.316] <TB1> INFO: 514560 events read in total (11218ms).
[13:06:47.343] <TB1> INFO: Expecting 514560 events.
[13:06:58.973] <TB1> INFO: 514560 events read in total (11079ms).
[13:06:59.002] <TB1> INFO: Expecting 514560 events.
[13:07:10.638] <TB1> INFO: 514560 events read in total (11082ms).
[13:07:10.666] <TB1> INFO: Expecting 514560 events.
[13:07:22.063] <TB1> INFO: 514560 events read in total (10843ms).
[13:07:22.093] <TB1> INFO: Expecting 514560 events.
[13:07:33.281] <TB1> INFO: 514560 events read in total (10635ms).
[13:07:33.320] <TB1> INFO: Test took 183823ms.
[13:07:34.395] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:07:34.404] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:07:34.404] <TB1> INFO: run 1 of 1
[13:07:34.721] <TB1> INFO: Expecting 6281600 events.
[13:08:09.452] <TB1> INFO: 742350 events read in total (34014ms).
[13:08:43.588] <TB1> INFO: 1482560 events read in total (68150ms).
[13:09:16.899] <TB1> INFO: 2223300 events read in total (101461ms).
[13:09:49.958] <TB1> INFO: 2964100 events read in total (134520ms).
[13:10:23.509] <TB1> INFO: 3699490 events read in total (168071ms).
[13:10:56.517] <TB1> INFO: 4431520 events read in total (201079ms).
[13:11:29.548] <TB1> INFO: 5162190 events read in total (234110ms).
[13:12:00.215] <TB1> INFO: 5892490 events read in total (264777ms).
[13:12:17.481] <TB1> INFO: 6281600 events read in total (282043ms).
[13:12:17.532] <TB1> INFO: Test took 283128ms.
[13:12:17.687] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:40.703] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.024377 .. 255.000000
[13:12:40.783] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:12:40.792] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:12:40.792] <TB1> INFO: run 1 of 1
[13:12:41.099] <TB1> INFO: Expecting 10649600 events.
[13:13:14.399] <TB1> INFO: 712390 events read in total (32584ms).
[13:13:45.969] <TB1> INFO: 1425020 events read in total (64154ms).
[13:14:20.547] <TB1> INFO: 2137770 events read in total (98732ms).
[13:14:55.212] <TB1> INFO: 2850490 events read in total (133397ms).
[13:15:28.580] <TB1> INFO: 3562960 events read in total (166765ms).
[13:16:02.522] <TB1> INFO: 4275680 events read in total (200707ms).
[13:16:37.314] <TB1> INFO: 4988210 events read in total (235499ms).
[13:17:11.099] <TB1> INFO: 5700550 events read in total (269284ms).
[13:17:42.472] <TB1> INFO: 6412370 events read in total (300657ms).
[13:18:13.844] <TB1> INFO: 7123700 events read in total (332029ms).
[13:18:46.329] <TB1> INFO: 7835250 events read in total (364514ms).
[13:19:20.309] <TB1> INFO: 8546220 events read in total (398494ms).
[13:19:54.754] <TB1> INFO: 9256880 events read in total (432939ms).
[13:20:25.598] <TB1> INFO: 9967990 events read in total (463783ms).
[13:20:57.728] <TB1> INFO: 10649600 events read in total (495913ms).
[13:20:57.815] <TB1> INFO: Test took 497023ms.
[13:20:58.076] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:26.181] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 16.652249 .. 45.776102
[13:21:26.261] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:21:26.270] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:21:26.270] <TB1> INFO: run 1 of 1
[13:21:26.575] <TB1> INFO: Expecting 2080000 events.
[13:22:01.032] <TB1> INFO: 933290 events read in total (33737ms).
[13:22:38.442] <TB1> INFO: 1864180 events read in total (71147ms).
[13:22:47.051] <TB1> INFO: 2080000 events read in total (79757ms).
[13:22:47.069] <TB1> INFO: Test took 80800ms.
[13:22:47.106] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:02.132] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 20.879721 .. 42.311714
[13:23:02.236] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:23:02.244] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:23:02.244] <TB1> INFO: run 1 of 1
[13:23:02.585] <TB1> INFO: Expecting 1788800 events.
[13:23:40.510] <TB1> INFO: 936730 events read in total (37203ms).
[13:24:13.879] <TB1> INFO: 1788800 events read in total (70572ms).
[13:24:13.902] <TB1> INFO: Test took 71659ms.
[13:24:13.937] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:28.119] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 2.798335 .. 42.274812
[13:24:28.218] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:24:28.227] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:24:28.227] <TB1> INFO: run 1 of 1
[13:24:28.553] <TB1> INFO: Expecting 2121600 events.
[13:25:07.112] <TB1> INFO: 982280 events read in total (37842ms).
[13:25:42.805] <TB1> INFO: 1964090 events read in total (73535ms).
[13:25:48.757] <TB1> INFO: 2121600 events read in total (79487ms).
[13:25:48.767] <TB1> INFO: Test took 80540ms.
[13:25:48.793] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:01.880] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:26:01.880] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:26:01.889] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:26:01.889] <TB1> INFO: run 1 of 1
[13:26:02.206] <TB1> INFO: Expecting 1705600 events.
[13:26:38.358] <TB1> INFO: 879870 events read in total (35435ms).
[13:27:11.827] <TB1> INFO: 1705600 events read in total (68904ms).
[13:27:11.841] <TB1> INFO: Test took 69952ms.
[13:27:11.872] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:26.131] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:27:26.131] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:27:26.131] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:27:26.131] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:27:26.131] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:27:26.131] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:27:26.131] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:27:26.131] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:27:26.132] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:27:26.132] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:27:26.132] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:27:26.132] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:27:26.132] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:27:26.132] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:27:26.132] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:27:26.133] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:27:26.133] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C0.dat
[13:27:26.141] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C1.dat
[13:27:26.148] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C2.dat
[13:27:26.156] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C3.dat
[13:27:26.165] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C4.dat
[13:27:26.173] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C5.dat
[13:27:26.183] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C6.dat
[13:27:26.192] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C7.dat
[13:27:26.198] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C8.dat
[13:27:26.208] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C9.dat
[13:27:26.214] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C10.dat
[13:27:26.224] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C11.dat
[13:27:26.230] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C12.dat
[13:27:26.236] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C13.dat
[13:27:26.245] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C14.dat
[13:27:26.253] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//trimParameters35_C15.dat
[13:27:26.259] <TB1> INFO: PixTestTrim::trimTest() done
[13:27:26.259] <TB1> INFO: vtrim: 106 99 103 96 87 87 90 99 103 108 101 105 98 93 104 85
[13:27:26.259] <TB1> INFO: vthrcomp: 85 96 95 88 96 81 83 88 91 77 97 94 79 79 89 93
[13:27:26.259] <TB1> INFO: vcal mean: 35.00 34.98 34.98 34.98 34.95 35.02 35.00 34.99 35.06 34.97 34.98 34.99 35.01 34.97 34.99 34.98
[13:27:26.259] <TB1> INFO: vcal RMS: 0.88 0.86 0.84 0.82 0.85 0.77 0.81 0.93 0.85 0.79 0.86 0.93 0.79 0.79 0.85 0.83
[13:27:26.259] <TB1> INFO: bits mean: 9.53 9.47 9.78 9.02 9.63 9.86 9.07 8.94 9.33 8.45 9.73 9.70 8.89 9.12 9.39 9.50
[13:27:26.259] <TB1> INFO: bits RMS: 2.70 2.85 2.59 2.87 2.75 2.48 2.85 2.79 2.72 2.48 2.73 2.68 2.55 2.41 2.67 2.72
[13:27:26.268] <TB1> INFO: ----------------------------------------------------------------------
[13:27:26.268] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[13:27:26.268] <TB1> INFO: ----------------------------------------------------------------------
[13:27:26.272] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:27:26.281] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:27:26.281] <TB1> INFO: run 1 of 1
[13:27:26.587] <TB1> INFO: Expecting 8320000 events.
[13:28:02.024] <TB1> INFO: 917080 events read in total (34721ms).
[13:28:36.951] <TB1> INFO: 1827080 events read in total (69648ms).
[13:29:11.068] <TB1> INFO: 2735340 events read in total (103765ms).
[13:29:46.383] <TB1> INFO: 3641180 events read in total (139080ms).
[13:30:21.618] <TB1> INFO: 4542650 events read in total (174315ms).
[13:30:56.547] <TB1> INFO: 5439710 events read in total (209244ms).
[13:31:31.652] <TB1> INFO: 6335620 events read in total (244349ms).
[13:32:05.877] <TB1> INFO: 7231590 events read in total (278574ms).
[13:32:40.760] <TB1> INFO: 8129680 events read in total (313457ms).
[13:32:48.446] <TB1> INFO: 8320000 events read in total (321143ms).
[13:32:48.481] <TB1> INFO: Test took 322200ms.
[13:32:48.598] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:14.756] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[13:33:14.764] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:33:14.764] <TB1> INFO: run 1 of 1
[13:33:15.094] <TB1> INFO: Expecting 6614400 events.
[13:33:51.451] <TB1> INFO: 969700 events read in total (35641ms).
[13:34:27.199] <TB1> INFO: 1932130 events read in total (71389ms).
[13:35:02.703] <TB1> INFO: 2890420 events read in total (106893ms).
[13:35:38.413] <TB1> INFO: 3841040 events read in total (142603ms).
[13:36:14.007] <TB1> INFO: 4785320 events read in total (178197ms).
[13:36:50.280] <TB1> INFO: 5728900 events read in total (214470ms).
[13:37:23.642] <TB1> INFO: 6614400 events read in total (247832ms).
[13:37:23.674] <TB1> INFO: Test took 248910ms.
[13:37:23.764] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:46.004] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 148 (-1/-1) hits flags = 528 (plus default)
[13:37:46.012] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:37:46.013] <TB1> INFO: run 1 of 1
[13:37:46.318] <TB1> INFO: Expecting 6198400 events.
[13:38:22.057] <TB1> INFO: 1000060 events read in total (35023ms).
[13:38:56.533] <TB1> INFO: 1990950 events read in total (69499ms).
[13:39:34.742] <TB1> INFO: 2977820 events read in total (107708ms).
[13:40:12.827] <TB1> INFO: 3951470 events read in total (145793ms).
[13:40:50.542] <TB1> INFO: 4922710 events read in total (183508ms).
[13:41:27.723] <TB1> INFO: 5895250 events read in total (220689ms).
[13:41:39.242] <TB1> INFO: 6198400 events read in total (232208ms).
[13:41:39.271] <TB1> INFO: Test took 233258ms.
[13:41:39.335] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:00.640] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[13:42:00.650] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:42:00.650] <TB1> INFO: run 1 of 1
[13:42:00.973] <TB1> INFO: Expecting 6240000 events.
[13:42:38.636] <TB1> INFO: 995660 events read in total (36946ms).
[13:43:13.654] <TB1> INFO: 1982020 events read in total (71964ms).
[13:43:50.255] <TB1> INFO: 2964560 events read in total (108566ms).
[13:44:27.269] <TB1> INFO: 3934380 events read in total (145579ms).
[13:45:04.365] <TB1> INFO: 4901790 events read in total (182675ms).
[13:45:41.124] <TB1> INFO: 5870440 events read in total (219434ms).
[13:45:54.972] <TB1> INFO: 6240000 events read in total (233283ms).
[13:45:54.997] <TB1> INFO: Test took 234347ms.
[13:45:55.066] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:17.449] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 147 (-1/-1) hits flags = 528 (plus default)
[13:46:17.457] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:46:17.457] <TB1> INFO: run 1 of 1
[13:46:17.760] <TB1> INFO: Expecting 6156800 events.
[13:46:54.547] <TB1> INFO: 1001010 events read in total (36070ms).
[13:47:29.136] <TB1> INFO: 1992850 events read in total (70659ms).
[13:48:05.013] <TB1> INFO: 2980630 events read in total (106536ms).
[13:48:39.757] <TB1> INFO: 3954910 events read in total (141280ms).
[13:49:17.548] <TB1> INFO: 4927140 events read in total (179071ms).
[13:49:55.016] <TB1> INFO: 5901590 events read in total (216539ms).
[13:50:05.403] <TB1> INFO: 6156800 events read in total (226926ms).
[13:50:05.435] <TB1> INFO: Test took 227978ms.
[13:50:05.509] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:29.283] <TB1> INFO: PixTestTrim::trimBitTest() done
[13:50:29.285] <TB1> INFO: PixTestTrim::doTest() done, duration: 3309 seconds
[13:50:29.285] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:29.285] <TB1> INFO: Decoding statistics:
[13:50:29.285] <TB1> INFO: General information:
[13:50:29.285] <TB1> INFO: 16bit words read: 0
[13:50:29.285] <TB1> INFO: valid events total: 0
[13:50:29.285] <TB1> INFO: empty events: 0
[13:50:29.285] <TB1> INFO: valid events with pixels: 0
[13:50:29.285] <TB1> INFO: valid pixel hits: 0
[13:50:29.285] <TB1> INFO: Event errors: 0
[13:50:29.285] <TB1> INFO: start marker: 0
[13:50:29.285] <TB1> INFO: stop marker: 0
[13:50:29.285] <TB1> INFO: overflow: 0
[13:50:29.285] <TB1> INFO: invalid 5bit words: 0
[13:50:29.285] <TB1> INFO: invalid XOR eye diagram: 0
[13:50:29.285] <TB1> INFO: TBM errors: 0
[13:50:29.285] <TB1> INFO: flawed TBM headers: 0
[13:50:29.285] <TB1> INFO: flawed TBM trailers: 0
[13:50:29.285] <TB1> INFO: event ID mismatches: 0
[13:50:29.285] <TB1> INFO: ROC errors: 0
[13:50:29.285] <TB1> INFO: missing ROC header(s): 0
[13:50:29.285] <TB1> INFO: misplaced readback start: 0
[13:50:29.285] <TB1> INFO: Pixel decoding errors: 0
[13:50:29.285] <TB1> INFO: pixel data incomplete: 0
[13:50:29.285] <TB1> INFO: pixel address: 0
[13:50:29.285] <TB1> INFO: pulse height fill bit: 0
[13:50:29.285] <TB1> INFO: buffer corruption: 0
[13:50:29.971] <TB1> INFO: ######################################################################
[13:50:29.971] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:50:29.971] <TB1> INFO: ######################################################################
[13:50:30.304] <TB1> INFO: Expecting 41600 events.
[13:50:34.725] <TB1> INFO: 41600 events read in total (3704ms).
[13:50:34.726] <TB1> INFO: Test took 4753ms.
[13:50:34.733] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:35.307] <TB1> INFO: Expecting 41600 events.
[13:50:39.906] <TB1> INFO: 41600 events read in total (3882ms).
[13:50:39.906] <TB1> INFO: Test took 4914ms.
[13:50:39.912] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:40.264] <TB1> INFO: Expecting 41600 events.
[13:50:44.929] <TB1> INFO: 41600 events read in total (3948ms).
[13:50:44.929] <TB1> INFO: Test took 4989ms.
[13:50:44.935] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:45.291] <TB1> INFO: Expecting 2560 events.
[13:50:46.360] <TB1> INFO: 2560 events read in total (353ms).
[13:50:46.360] <TB1> INFO: Test took 1417ms.
[13:50:46.775] <TB1> INFO: Expecting 2560 events.
[13:50:47.841] <TB1> INFO: 2560 events read in total (349ms).
[13:50:47.842] <TB1> INFO: Test took 1481ms.
[13:50:48.256] <TB1> INFO: Expecting 2560 events.
[13:50:49.321] <TB1> INFO: 2560 events read in total (348ms).
[13:50:49.321] <TB1> INFO: Test took 1479ms.
[13:50:49.736] <TB1> INFO: Expecting 2560 events.
[13:50:50.805] <TB1> INFO: 2560 events read in total (352ms).
[13:50:50.805] <TB1> INFO: Test took 1484ms.
[13:50:51.220] <TB1> INFO: Expecting 2560 events.
[13:50:52.288] <TB1> INFO: 2560 events read in total (352ms).
[13:50:52.289] <TB1> INFO: Test took 1484ms.
[13:50:52.704] <TB1> INFO: Expecting 2560 events.
[13:50:53.770] <TB1> INFO: 2560 events read in total (350ms).
[13:50:53.771] <TB1> INFO: Test took 1482ms.
[13:50:54.186] <TB1> INFO: Expecting 2560 events.
[13:50:55.251] <TB1> INFO: 2560 events read in total (349ms).
[13:50:55.252] <TB1> INFO: Test took 1481ms.
[13:50:55.669] <TB1> INFO: Expecting 2560 events.
[13:50:56.733] <TB1> INFO: 2560 events read in total (347ms).
[13:50:56.734] <TB1> INFO: Test took 1482ms.
[13:50:57.149] <TB1> INFO: Expecting 2560 events.
[13:50:58.216] <TB1> INFO: 2560 events read in total (351ms).
[13:50:58.216] <TB1> INFO: Test took 1482ms.
[13:50:58.632] <TB1> INFO: Expecting 2560 events.
[13:50:59.700] <TB1> INFO: 2560 events read in total (352ms).
[13:50:59.700] <TB1> INFO: Test took 1484ms.
[13:51:00.116] <TB1> INFO: Expecting 2560 events.
[13:51:01.182] <TB1> INFO: 2560 events read in total (350ms).
[13:51:01.182] <TB1> INFO: Test took 1482ms.
[13:51:01.597] <TB1> INFO: Expecting 2560 events.
[13:51:02.661] <TB1> INFO: 2560 events read in total (348ms).
[13:51:02.661] <TB1> INFO: Test took 1478ms.
[13:51:03.077] <TB1> INFO: Expecting 2560 events.
[13:51:04.142] <TB1> INFO: 2560 events read in total (349ms).
[13:51:04.142] <TB1> INFO: Test took 1480ms.
[13:51:04.558] <TB1> INFO: Expecting 2560 events.
[13:51:05.624] <TB1> INFO: 2560 events read in total (350ms).
[13:51:05.625] <TB1> INFO: Test took 1482ms.
[13:51:06.040] <TB1> INFO: Expecting 2560 events.
[13:51:07.105] <TB1> INFO: 2560 events read in total (349ms).
[13:51:07.105] <TB1> INFO: Test took 1453ms.
[13:51:07.520] <TB1> INFO: Expecting 2560 events.
[13:51:08.589] <TB1> INFO: 2560 events read in total (352ms).
[13:51:08.589] <TB1> INFO: Test took 1483ms.
[13:51:08.594] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:51:09.006] <TB1> INFO: Expecting 655360 events.
[13:51:24.119] <TB1> INFO: 655360 events read in total (14396ms).
[13:51:24.130] <TB1> INFO: Expecting 655360 events.
[13:51:38.445] <TB1> INFO: 655360 events read in total (13787ms).
[13:51:38.456] <TB1> INFO: Expecting 655360 events.
[13:51:53.382] <TB1> INFO: 655360 events read in total (14398ms).
[13:51:53.399] <TB1> INFO: Expecting 655360 events.
[13:52:07.294] <TB1> INFO: 655360 events read in total (13367ms).
[13:52:07.313] <TB1> INFO: Expecting 655360 events.
[13:52:21.617] <TB1> INFO: 655360 events read in total (13776ms).
[13:52:21.639] <TB1> INFO: Expecting 655360 events.
[13:52:36.275] <TB1> INFO: 655360 events read in total (14108ms).
[13:52:36.301] <TB1> INFO: Expecting 655360 events.
[13:52:50.864] <TB1> INFO: 655360 events read in total (14035ms).
[13:52:50.894] <TB1> INFO: Expecting 655360 events.
[13:53:05.871] <TB1> INFO: 655360 events read in total (14449ms).
[13:53:05.909] <TB1> INFO: Expecting 655360 events.
[13:53:20.684] <TB1> INFO: 655360 events read in total (14246ms).
[13:53:20.723] <TB1> INFO: Expecting 655360 events.
[13:53:35.839] <TB1> INFO: 655360 events read in total (14588ms).
[13:53:35.882] <TB1> INFO: Expecting 655360 events.
[13:53:50.462] <TB1> INFO: 655360 events read in total (14052ms).
[13:53:50.506] <TB1> INFO: Expecting 655360 events.
[13:54:04.220] <TB1> INFO: 655360 events read in total (13186ms).
[13:54:04.270] <TB1> INFO: Expecting 655360 events.
[13:54:17.929] <TB1> INFO: 655360 events read in total (13131ms).
[13:54:17.981] <TB1> INFO: Expecting 655360 events.
[13:54:33.206] <TB1> INFO: 655360 events read in total (14697ms).
[13:54:33.274] <TB1> INFO: Expecting 655360 events.
[13:54:47.941] <TB1> INFO: 655360 events read in total (14139ms).
[13:54:48.007] <TB1> INFO: Expecting 655360 events.
[13:55:02.797] <TB1> INFO: 655360 events read in total (14262ms).
[13:55:02.860] <TB1> INFO: Test took 234266ms.
[13:55:02.947] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:55:03.254] <TB1> INFO: Expecting 655360 events.
[13:55:18.047] <TB1> INFO: 655360 events read in total (14077ms).
[13:55:18.056] <TB1> INFO: Expecting 655360 events.
[13:55:32.608] <TB1> INFO: 655360 events read in total (14024ms).
[13:55:32.620] <TB1> INFO: Expecting 655360 events.
[13:55:47.793] <TB1> INFO: 655360 events read in total (14645ms).
[13:55:47.809] <TB1> INFO: Expecting 655360 events.
[13:56:02.466] <TB1> INFO: 655360 events read in total (14129ms).
[13:56:02.484] <TB1> INFO: Expecting 655360 events.
[13:56:17.369] <TB1> INFO: 655360 events read in total (14357ms).
[13:56:17.391] <TB1> INFO: Expecting 655360 events.
[13:56:32.031] <TB1> INFO: 655360 events read in total (14112ms).
[13:56:32.057] <TB1> INFO: Expecting 655360 events.
[13:56:46.813] <TB1> INFO: 655360 events read in total (14228ms).
[13:56:46.844] <TB1> INFO: Expecting 655360 events.
[13:57:01.810] <TB1> INFO: 655360 events read in total (14438ms).
[13:57:01.850] <TB1> INFO: Expecting 655360 events.
[13:57:16.465] <TB1> INFO: 655360 events read in total (14087ms).
[13:57:16.504] <TB1> INFO: Expecting 655360 events.
[13:57:31.824] <TB1> INFO: 655360 events read in total (14792ms).
[13:57:31.869] <TB1> INFO: Expecting 655360 events.
[13:57:46.403] <TB1> INFO: 655360 events read in total (14005ms).
[13:57:46.445] <TB1> INFO: Expecting 655360 events.
[13:58:01.094] <TB1> INFO: 655360 events read in total (14120ms).
[13:58:01.149] <TB1> INFO: Expecting 655360 events.
[13:58:16.030] <TB1> INFO: 655360 events read in total (14353ms).
[13:58:16.078] <TB1> INFO: Expecting 655360 events.
[13:58:30.461] <TB1> INFO: 655360 events read in total (13855ms).
[13:58:30.513] <TB1> INFO: Expecting 655360 events.
[13:58:45.723] <TB1> INFO: 655360 events read in total (14682ms).
[13:58:45.789] <TB1> INFO: Expecting 655360 events.
[13:59:00.308] <TB1> INFO: 655360 events read in total (13990ms).
[13:59:00.366] <TB1> INFO: Test took 237419ms.
[13:59:00.556] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.565] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.574] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.583] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.592] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.601] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.610] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.619] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.626] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:59:00.635] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:59:00.644] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.651] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.658] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.665] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.672] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.678] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.685] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.692] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:59:00.747] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:59:00.748] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:59:00.748] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:59:00.748] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:59:00.748] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:59:00.748] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:59:00.758] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:59:00.758] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:59:00.758] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:59:00.758] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:59:00.758] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:59:00.758] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:59:00.759] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:59:00.759] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:59:00.759] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:59:00.759] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:59:01.068] <TB1> INFO: Expecting 41600 events.
[13:59:05.091] <TB1> INFO: 41600 events read in total (3306ms).
[13:59:05.091] <TB1> INFO: Test took 4328ms.
[13:59:05.637] <TB1> INFO: Expecting 41600 events.
[13:59:09.644] <TB1> INFO: 41600 events read in total (3290ms).
[13:59:09.644] <TB1> INFO: Test took 4317ms.
[13:59:10.205] <TB1> INFO: Expecting 41600 events.
[13:59:14.214] <TB1> INFO: 41600 events read in total (3293ms).
[13:59:14.215] <TB1> INFO: Test took 4320ms.
[13:59:14.459] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:14.592] <TB1> INFO: Expecting 2560 events.
[13:59:15.660] <TB1> INFO: 2560 events read in total (350ms).
[13:59:15.661] <TB1> INFO: Test took 1203ms.
[13:59:15.664] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:16.077] <TB1> INFO: Expecting 2560 events.
[13:59:17.150] <TB1> INFO: 2560 events read in total (357ms).
[13:59:17.151] <TB1> INFO: Test took 1487ms.
[13:59:17.154] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:17.568] <TB1> INFO: Expecting 2560 events.
[13:59:18.642] <TB1> INFO: 2560 events read in total (358ms).
[13:59:18.642] <TB1> INFO: Test took 1488ms.
[13:59:18.645] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:19.058] <TB1> INFO: Expecting 2560 events.
[13:59:20.130] <TB1> INFO: 2560 events read in total (356ms).
[13:59:20.131] <TB1> INFO: Test took 1486ms.
[13:59:20.144] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:20.547] <TB1> INFO: Expecting 2560 events.
[13:59:21.619] <TB1> INFO: 2560 events read in total (356ms).
[13:59:21.620] <TB1> INFO: Test took 1476ms.
[13:59:21.623] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:22.036] <TB1> INFO: Expecting 2560 events.
[13:59:23.108] <TB1> INFO: 2560 events read in total (356ms).
[13:59:23.108] <TB1> INFO: Test took 1485ms.
[13:59:23.111] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:23.523] <TB1> INFO: Expecting 2560 events.
[13:59:24.589] <TB1> INFO: 2560 events read in total (349ms).
[13:59:24.589] <TB1> INFO: Test took 1478ms.
[13:59:24.592] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:25.005] <TB1> INFO: Expecting 2560 events.
[13:59:26.071] <TB1> INFO: 2560 events read in total (349ms).
[13:59:26.072] <TB1> INFO: Test took 1480ms.
[13:59:26.076] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:26.487] <TB1> INFO: Expecting 2560 events.
[13:59:27.557] <TB1> INFO: 2560 events read in total (354ms).
[13:59:27.557] <TB1> INFO: Test took 1482ms.
[13:59:27.561] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:27.974] <TB1> INFO: Expecting 2560 events.
[13:59:29.039] <TB1> INFO: 2560 events read in total (349ms).
[13:59:29.040] <TB1> INFO: Test took 1480ms.
[13:59:29.042] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:29.455] <TB1> INFO: Expecting 2560 events.
[13:59:30.525] <TB1> INFO: 2560 events read in total (353ms).
[13:59:30.526] <TB1> INFO: Test took 1484ms.
[13:59:30.529] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:30.942] <TB1> INFO: Expecting 2560 events.
[13:59:32.007] <TB1> INFO: 2560 events read in total (349ms).
[13:59:32.007] <TB1> INFO: Test took 1478ms.
[13:59:32.011] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:32.423] <TB1> INFO: Expecting 2560 events.
[13:59:33.492] <TB1> INFO: 2560 events read in total (352ms).
[13:59:33.492] <TB1> INFO: Test took 1481ms.
[13:59:33.495] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:33.909] <TB1> INFO: Expecting 2560 events.
[13:59:34.979] <TB1> INFO: 2560 events read in total (354ms).
[13:59:34.979] <TB1> INFO: Test took 1484ms.
[13:59:34.982] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:35.396] <TB1> INFO: Expecting 2560 events.
[13:59:36.465] <TB1> INFO: 2560 events read in total (353ms).
[13:59:36.465] <TB1> INFO: Test took 1483ms.
[13:59:36.469] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:36.882] <TB1> INFO: Expecting 2560 events.
[13:59:37.947] <TB1> INFO: 2560 events read in total (349ms).
[13:59:37.947] <TB1> INFO: Test took 1478ms.
[13:59:37.950] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:38.364] <TB1> INFO: Expecting 2560 events.
[13:59:39.431] <TB1> INFO: 2560 events read in total (351ms).
[13:59:39.432] <TB1> INFO: Test took 1482ms.
[13:59:39.435] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:39.848] <TB1> INFO: Expecting 2560 events.
[13:59:40.914] <TB1> INFO: 2560 events read in total (351ms).
[13:59:40.915] <TB1> INFO: Test took 1480ms.
[13:59:40.917] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:41.331] <TB1> INFO: Expecting 2560 events.
[13:59:42.401] <TB1> INFO: 2560 events read in total (353ms).
[13:59:42.401] <TB1> INFO: Test took 1484ms.
[13:59:42.404] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:42.818] <TB1> INFO: Expecting 2560 events.
[13:59:43.886] <TB1> INFO: 2560 events read in total (352ms).
[13:59:43.887] <TB1> INFO: Test took 1483ms.
[13:59:43.890] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:44.302] <TB1> INFO: Expecting 2560 events.
[13:59:45.369] <TB1> INFO: 2560 events read in total (350ms).
[13:59:45.369] <TB1> INFO: Test took 1479ms.
[13:59:45.373] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:45.785] <TB1> INFO: Expecting 2560 events.
[13:59:46.855] <TB1> INFO: 2560 events read in total (354ms).
[13:59:46.855] <TB1> INFO: Test took 1483ms.
[13:59:46.858] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:47.271] <TB1> INFO: Expecting 2560 events.
[13:59:48.340] <TB1> INFO: 2560 events read in total (352ms).
[13:59:48.341] <TB1> INFO: Test took 1483ms.
[13:59:48.344] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:48.756] <TB1> INFO: Expecting 2560 events.
[13:59:49.829] <TB1> INFO: 2560 events read in total (356ms).
[13:59:49.829] <TB1> INFO: Test took 1485ms.
[13:59:49.833] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:50.245] <TB1> INFO: Expecting 2560 events.
[13:59:51.316] <TB1> INFO: 2560 events read in total (354ms).
[13:59:51.317] <TB1> INFO: Test took 1485ms.
[13:59:51.327] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:51.733] <TB1> INFO: Expecting 2560 events.
[13:59:52.805] <TB1> INFO: 2560 events read in total (356ms).
[13:59:52.806] <TB1> INFO: Test took 1479ms.
[13:59:52.809] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:53.221] <TB1> INFO: Expecting 2560 events.
[13:59:54.292] <TB1> INFO: 2560 events read in total (354ms).
[13:59:54.293] <TB1> INFO: Test took 1484ms.
[13:59:54.296] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:54.709] <TB1> INFO: Expecting 2560 events.
[13:59:55.779] <TB1> INFO: 2560 events read in total (353ms).
[13:59:55.779] <TB1> INFO: Test took 1484ms.
[13:59:55.784] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:56.195] <TB1> INFO: Expecting 2560 events.
[13:59:57.265] <TB1> INFO: 2560 events read in total (353ms).
[13:59:57.265] <TB1> INFO: Test took 1481ms.
[13:59:57.269] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:57.682] <TB1> INFO: Expecting 2560 events.
[13:59:58.752] <TB1> INFO: 2560 events read in total (354ms).
[13:59:58.752] <TB1> INFO: Test took 1484ms.
[13:59:58.756] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:59:59.169] <TB1> INFO: Expecting 2560 events.
[14:00:00.242] <TB1> INFO: 2560 events read in total (357ms).
[14:00:00.242] <TB1> INFO: Test took 1487ms.
[14:00:00.246] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:00:00.658] <TB1> INFO: Expecting 2560 events.
[14:00:01.729] <TB1> INFO: 2560 events read in total (354ms).
[14:00:01.729] <TB1> INFO: Test took 1484ms.
[14:00:02.401] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 572 seconds
[14:00:02.401] <TB1> INFO: PH scale (per ROC): 80 84 77 79 82 79 82 80 73 84 80 83 90 95 87 77
[14:00:02.401] <TB1> INFO: PH offset (per ROC): 174 144 163 161 148 158 156 161 166 145 163 155 140 140 158 156
[14:00:02.407] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:02.407] <TB1> INFO: Decoding statistics:
[14:00:02.407] <TB1> INFO: General information:
[14:00:02.407] <TB1> INFO: 16bit words read: 91928
[14:00:02.407] <TB1> INFO: valid events total: 10240
[14:00:02.407] <TB1> INFO: empty events: 7738
[14:00:02.407] <TB1> INFO: valid events with pixels: 2502
[14:00:02.407] <TB1> INFO: valid pixel hits: 2502
[14:00:02.407] <TB1> INFO: Event errors: 0
[14:00:02.407] <TB1> INFO: start marker: 0
[14:00:02.407] <TB1> INFO: stop marker: 0
[14:00:02.407] <TB1> INFO: overflow: 0
[14:00:02.407] <TB1> INFO: invalid 5bit words: 0
[14:00:02.407] <TB1> INFO: invalid XOR eye diagram: 0
[14:00:02.407] <TB1> INFO: TBM errors: 0
[14:00:02.407] <TB1> INFO: flawed TBM headers: 0
[14:00:02.407] <TB1> INFO: flawed TBM trailers: 0
[14:00:02.407] <TB1> INFO: event ID mismatches: 0
[14:00:02.407] <TB1> INFO: ROC errors: 0
[14:00:02.407] <TB1> INFO: missing ROC header(s): 0
[14:00:02.407] <TB1> INFO: misplaced readback start: 0
[14:00:02.407] <TB1> INFO: Pixel decoding errors: 0
[14:00:02.407] <TB1> INFO: pixel data incomplete: 0
[14:00:02.407] <TB1> INFO: pixel address: 0
[14:00:02.407] <TB1> INFO: pulse height fill bit: 0
[14:00:02.407] <TB1> INFO: buffer corruption: 0
[14:00:02.608] <TB1> INFO: ######################################################################
[14:00:02.608] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:00:02.608] <TB1> INFO: ######################################################################
[14:00:02.620] <TB1> INFO: scanning low vcal = 10
[14:00:02.927] <TB1> INFO: Expecting 41600 events.
[14:00:06.720] <TB1> INFO: 41600 events read in total (3076ms).
[14:00:06.720] <TB1> INFO: Test took 4100ms.
[14:00:06.722] <TB1> INFO: scanning low vcal = 20
[14:00:07.136] <TB1> INFO: Expecting 41600 events.
[14:00:10.915] <TB1> INFO: 41600 events read in total (3062ms).
[14:00:10.916] <TB1> INFO: Test took 4193ms.
[14:00:10.918] <TB1> INFO: scanning low vcal = 30
[14:00:11.332] <TB1> INFO: Expecting 41600 events.
[14:00:15.139] <TB1> INFO: 41600 events read in total (3090ms).
[14:00:15.139] <TB1> INFO: Test took 4221ms.
[14:00:15.142] <TB1> INFO: scanning low vcal = 40
[14:00:15.550] <TB1> INFO: Expecting 41600 events.
[14:00:19.906] <TB1> INFO: 41600 events read in total (3639ms).
[14:00:19.907] <TB1> INFO: Test took 4765ms.
[14:00:19.910] <TB1> INFO: scanning low vcal = 50
[14:00:20.242] <TB1> INFO: Expecting 41600 events.
[14:00:24.649] <TB1> INFO: 41600 events read in total (3691ms).
[14:00:24.649] <TB1> INFO: Test took 4739ms.
[14:00:24.653] <TB1> INFO: scanning low vcal = 60
[14:00:24.991] <TB1> INFO: Expecting 41600 events.
[14:00:29.449] <TB1> INFO: 41600 events read in total (3741ms).
[14:00:29.450] <TB1> INFO: Test took 4797ms.
[14:00:29.453] <TB1> INFO: scanning low vcal = 70
[14:00:29.799] <TB1> INFO: Expecting 41600 events.
[14:00:34.205] <TB1> INFO: 41600 events read in total (3690ms).
[14:00:34.206] <TB1> INFO: Test took 4753ms.
[14:00:34.210] <TB1> INFO: scanning low vcal = 80
[14:00:34.559] <TB1> INFO: Expecting 41600 events.
[14:00:38.977] <TB1> INFO: 41600 events read in total (3702ms).
[14:00:38.978] <TB1> INFO: Test took 4768ms.
[14:00:38.981] <TB1> INFO: scanning low vcal = 90
[14:00:39.312] <TB1> INFO: Expecting 41600 events.
[14:00:43.657] <TB1> INFO: 41600 events read in total (3629ms).
[14:00:43.658] <TB1> INFO: Test took 4677ms.
[14:00:43.662] <TB1> INFO: scanning low vcal = 100
[14:00:44.015] <TB1> INFO: Expecting 41600 events.
[14:00:48.561] <TB1> INFO: 41600 events read in total (3829ms).
[14:00:48.561] <TB1> INFO: Test took 4899ms.
[14:00:48.565] <TB1> INFO: scanning low vcal = 110
[14:00:48.922] <TB1> INFO: Expecting 41600 events.
[14:00:53.259] <TB1> INFO: 41600 events read in total (3620ms).
[14:00:53.259] <TB1> INFO: Test took 4694ms.
[14:00:53.263] <TB1> INFO: scanning low vcal = 120
[14:00:53.621] <TB1> INFO: Expecting 41600 events.
[14:00:57.989] <TB1> INFO: 41600 events read in total (3652ms).
[14:00:57.990] <TB1> INFO: Test took 4727ms.
[14:00:57.994] <TB1> INFO: scanning low vcal = 130
[14:00:58.346] <TB1> INFO: Expecting 41600 events.
[14:01:02.817] <TB1> INFO: 41600 events read in total (3754ms).
[14:01:02.817] <TB1> INFO: Test took 4823ms.
[14:01:02.821] <TB1> INFO: scanning low vcal = 140
[14:01:03.175] <TB1> INFO: Expecting 41600 events.
[14:01:07.627] <TB1> INFO: 41600 events read in total (3735ms).
[14:01:07.627] <TB1> INFO: Test took 4806ms.
[14:01:07.631] <TB1> INFO: scanning low vcal = 150
[14:01:07.968] <TB1> INFO: Expecting 41600 events.
[14:01:12.386] <TB1> INFO: 41600 events read in total (3701ms).
[14:01:12.386] <TB1> INFO: Test took 4755ms.
[14:01:12.390] <TB1> INFO: scanning low vcal = 160
[14:01:12.742] <TB1> INFO: Expecting 41600 events.
[14:01:17.135] <TB1> INFO: 41600 events read in total (3677ms).
[14:01:17.136] <TB1> INFO: Test took 4746ms.
[14:01:17.140] <TB1> INFO: scanning low vcal = 170
[14:01:17.487] <TB1> INFO: Expecting 41600 events.
[14:01:21.832] <TB1> INFO: 41600 events read in total (3628ms).
[14:01:21.832] <TB1> INFO: Test took 4692ms.
[14:01:21.837] <TB1> INFO: scanning low vcal = 180
[14:01:22.189] <TB1> INFO: Expecting 41600 events.
[14:01:26.538] <TB1> INFO: 41600 events read in total (3633ms).
[14:01:26.538] <TB1> INFO: Test took 4701ms.
[14:01:26.542] <TB1> INFO: scanning low vcal = 190
[14:01:26.895] <TB1> INFO: Expecting 41600 events.
[14:01:31.234] <TB1> INFO: 41600 events read in total (3622ms).
[14:01:31.234] <TB1> INFO: Test took 4692ms.
[14:01:31.238] <TB1> INFO: scanning low vcal = 200
[14:01:31.583] <TB1> INFO: Expecting 41600 events.
[14:01:35.953] <TB1> INFO: 41600 events read in total (3654ms).
[14:01:35.953] <TB1> INFO: Test took 4715ms.
[14:01:35.957] <TB1> INFO: scanning low vcal = 210
[14:01:36.310] <TB1> INFO: Expecting 41600 events.
[14:01:40.756] <TB1> INFO: 41600 events read in total (3730ms).
[14:01:40.756] <TB1> INFO: Test took 4799ms.
[14:01:40.760] <TB1> INFO: scanning low vcal = 220
[14:01:41.115] <TB1> INFO: Expecting 41600 events.
[14:01:45.537] <TB1> INFO: 41600 events read in total (3705ms).
[14:01:45.538] <TB1> INFO: Test took 4778ms.
[14:01:45.542] <TB1> INFO: scanning low vcal = 230
[14:01:45.883] <TB1> INFO: Expecting 41600 events.
[14:01:50.273] <TB1> INFO: 41600 events read in total (3674ms).
[14:01:50.273] <TB1> INFO: Test took 4731ms.
[14:01:50.277] <TB1> INFO: scanning low vcal = 240
[14:01:50.608] <TB1> INFO: Expecting 41600 events.
[14:01:54.969] <TB1> INFO: 41600 events read in total (3645ms).
[14:01:54.969] <TB1> INFO: Test took 4692ms.
[14:01:54.973] <TB1> INFO: scanning low vcal = 250
[14:01:55.325] <TB1> INFO: Expecting 41600 events.
[14:01:59.714] <TB1> INFO: 41600 events read in total (3672ms).
[14:01:59.715] <TB1> INFO: Test took 4742ms.
[14:01:59.721] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[14:02:00.067] <TB1> INFO: Expecting 41600 events.
[14:02:04.404] <TB1> INFO: 41600 events read in total (3620ms).
[14:02:04.405] <TB1> INFO: Test took 4684ms.
[14:02:04.408] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[14:02:04.762] <TB1> INFO: Expecting 41600 events.
[14:02:09.020] <TB1> INFO: 41600 events read in total (3541ms).
[14:02:09.021] <TB1> INFO: Test took 4613ms.
[14:02:09.024] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[14:02:09.381] <TB1> INFO: Expecting 41600 events.
[14:02:13.639] <TB1> INFO: 41600 events read in total (3542ms).
[14:02:13.640] <TB1> INFO: Test took 4616ms.
[14:02:13.644] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[14:02:13.995] <TB1> INFO: Expecting 41600 events.
[14:02:18.267] <TB1> INFO: 41600 events read in total (3556ms).
[14:02:18.267] <TB1> INFO: Test took 4623ms.
[14:02:18.271] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:02:18.612] <TB1> INFO: Expecting 41600 events.
[14:02:22.874] <TB1> INFO: 41600 events read in total (3546ms).
[14:02:22.875] <TB1> INFO: Test took 4604ms.
[14:02:23.372] <TB1> INFO: PixTestGainPedestal::measure() done
[14:02:56.631] <TB1> INFO: PixTestGainPedestal::fit() done
[14:02:56.631] <TB1> INFO: non-linearity mean: 0.955 0.955 0.951 0.959 0.960 0.955 0.958 0.965 0.951 0.964 0.958 0.955 0.953 0.952 0.956 0.952
[14:02:56.631] <TB1> INFO: non-linearity RMS: 0.005 0.005 0.007 0.005 0.006 0.006 0.005 0.005 0.005 0.005 0.005 0.005 0.006 0.005 0.006 0.005
[14:02:56.631] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[14:02:56.655] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[14:02:56.679] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[14:02:56.705] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[14:02:56.731] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[14:02:56.755] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[14:02:56.779] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[14:02:56.806] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[14:02:56.831] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[14:02:56.855] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[14:02:56.880] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[14:02:56.906] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[14:02:56.931] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[14:02:56.958] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[14:02:56.987] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[14:02:57.011] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[14:02:57.030] <TB1> INFO: PixTestGainPedestal::doTest() done, duration: 174 seconds
[14:02:57.030] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:57.030] <TB1> INFO: Decoding statistics:
[14:02:57.030] <TB1> INFO: General information:
[14:02:57.030] <TB1> INFO: 16bit words read: 2662400
[14:02:57.030] <TB1> INFO: valid events total: 166400
[14:02:57.030] <TB1> INFO: empty events: 0
[14:02:57.030] <TB1> INFO: valid events with pixels: 166400
[14:02:57.030] <TB1> INFO: valid pixel hits: 665589
[14:02:57.030] <TB1> INFO: Event errors: 0
[14:02:57.030] <TB1> INFO: start marker: 0
[14:02:57.030] <TB1> INFO: stop marker: 0
[14:02:57.030] <TB1> INFO: overflow: 0
[14:02:57.030] <TB1> INFO: invalid 5bit words: 0
[14:02:57.030] <TB1> INFO: invalid XOR eye diagram: 0
[14:02:57.030] <TB1> INFO: TBM errors: 0
[14:02:57.030] <TB1> INFO: flawed TBM headers: 0
[14:02:57.030] <TB1> INFO: flawed TBM trailers: 0
[14:02:57.030] <TB1> INFO: event ID mismatches: 0
[14:02:57.030] <TB1> INFO: ROC errors: 0
[14:02:57.030] <TB1> INFO: missing ROC header(s): 0
[14:02:57.030] <TB1> INFO: misplaced readback start: 0
[14:02:57.030] <TB1> INFO: Pixel decoding errors: 0
[14:02:57.030] <TB1> INFO: pixel data incomplete: 0
[14:02:57.030] <TB1> INFO: pixel address: 0
[14:02:57.030] <TB1> INFO: pulse height fill bit: 0
[14:02:57.030] <TB1> INFO: buffer corruption: 0
[14:02:57.036] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C15.dat
[14:02:57.037] <TB1> INFO: ######################################################################
[14:02:57.037] <TB1> INFO: PixTestTrim::doTest()
[14:02:57.037] <TB1> INFO: ######################################################################
[14:02:57.038] <TB1> INFO: PixTestReadback::RES sent once
[14:03:08.398] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C0.dat
[14:03:08.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C1.dat
[14:03:08.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C2.dat
[14:03:08.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C3.dat
[14:03:08.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C4.dat
[14:03:08.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C5.dat
[14:03:08.400] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C6.dat
[14:03:08.401] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C7.dat
[14:03:08.401] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C8.dat
[14:03:08.401] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C9.dat
[14:03:08.401] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C10.dat
[14:03:08.401] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C11.dat
[14:03:08.401] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C12.dat
[14:03:08.401] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C13.dat
[14:03:08.401] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C14.dat
[14:03:08.401] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C15.dat
[14:03:08.447] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:03:08.448] <TB1> INFO: PixTestReadback::RES sent once
[14:03:19.748] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C0.dat
[14:03:19.748] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C1.dat
[14:03:19.748] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C2.dat
[14:03:19.748] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C3.dat
[14:03:19.748] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C4.dat
[14:03:19.748] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C5.dat
[14:03:19.748] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C6.dat
[14:03:19.748] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C7.dat
[14:03:19.749] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C8.dat
[14:03:19.749] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C9.dat
[14:03:19.749] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C10.dat
[14:03:19.749] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C11.dat
[14:03:19.749] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C12.dat
[14:03:19.749] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C13.dat
[14:03:19.749] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C14.dat
[14:03:19.749] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C15.dat
[14:03:19.796] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:03:19.797] <TB1> INFO: PixTestReadback::RES sent once
[14:03:28.467] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:03:28.467] <TB1> INFO: Vbg will be calibrated using Vd calibration
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.6calibrated Vbg = 1.17499 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.2calibrated Vbg = 1.17731 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.7calibrated Vbg = 1.18075 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.9calibrated Vbg = 1.18857 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 158.2calibrated Vbg = 1.17984 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 145.3calibrated Vbg = 1.18638 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.8calibrated Vbg = 1.19888 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.8calibrated Vbg = 1.19562 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 161.4calibrated Vbg = 1.19045 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 155.1calibrated Vbg = 1.19577 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 157.5calibrated Vbg = 1.18459 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 158.9calibrated Vbg = 1.18802 :::*/*/*/*/
[14:03:28.467] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.6calibrated Vbg = 1.18291 :::*/*/*/*/
[14:03:28.468] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 150.5calibrated Vbg = 1.17333 :::*/*/*/*/
[14:03:28.468] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157calibrated Vbg = 1.17131 :::*/*/*/*/
[14:03:28.468] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 158.4calibrated Vbg = 1.16992 :::*/*/*/*/
[14:03:28.471] <TB1> INFO: PixTestReadback::RES sent once
[14:06:23.789] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C0.dat
[14:06:23.789] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C1.dat
[14:06:23.789] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C2.dat
[14:06:23.789] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C3.dat
[14:06:23.789] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C4.dat
[14:06:23.789] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C5.dat
[14:06:23.789] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C6.dat
[14:06:23.790] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C7.dat
[14:06:23.790] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C8.dat
[14:06:23.790] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C9.dat
[14:06:23.790] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C10.dat
[14:06:23.790] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C11.dat
[14:06:23.790] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C12.dat
[14:06:23.790] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C13.dat
[14:06:23.790] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C14.dat
[14:06:23.790] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2104_FullQualification_2015-09-10_11h04m_1441875843//002_FulltestPxar_m20//readbackCal_C15.dat
[14:06:23.836] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:06:23.837] <TB1> INFO: PixTestReadback::doTest() done
[14:06:23.837] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:23.837] <TB1> INFO: Decoding statistics:
[14:06:23.837] <TB1> INFO: General information:
[14:06:23.837] <TB1> INFO: 16bit words read: 1024
[14:06:23.837] <TB1> INFO: valid events total: 128
[14:06:23.837] <TB1> INFO: empty events: 128
[14:06:23.837] <TB1> INFO: valid events with pixels: 0
[14:06:23.837] <TB1> INFO: valid pixel hits: 0
[14:06:23.837] <TB1> INFO: Event errors: 0
[14:06:23.837] <TB1> INFO: start marker: 0
[14:06:23.837] <TB1> INFO: stop marker: 0
[14:06:23.837] <TB1> INFO: overflow: 0
[14:06:23.837] <TB1> INFO: invalid 5bit words: 0
[14:06:23.837] <TB1> INFO: invalid XOR eye diagram: 0
[14:06:23.837] <TB1> INFO: TBM errors: 0
[14:06:23.837] <TB1> INFO: flawed TBM headers: 0
[14:06:23.837] <TB1> INFO: flawed TBM trailers: 0
[14:06:23.837] <TB1> INFO: event ID mismatches: 0
[14:06:23.837] <TB1> INFO: ROC errors: 0
[14:06:23.837] <TB1> INFO: missing ROC header(s): 0
[14:06:23.837] <TB1> INFO: misplaced readback start: 0
[14:06:23.837] <TB1> INFO: Pixel decoding errors: 0
[14:06:23.837] <TB1> INFO: pixel data incomplete: 0
[14:06:23.837] <TB1> INFO: pixel address: 0
[14:06:23.837] <TB1> INFO: pulse height fill bit: 0
[14:06:23.837] <TB1> INFO: buffer corruption: 0
[14:06:23.853] <TB1> INFO: Decoding statistics:
[14:06:23.853] <TB1> INFO: General information:
[14:06:23.853] <TB1> INFO: 16bit words read: 2755352
[14:06:23.853] <TB1> INFO: valid events total: 176768
[14:06:23.853] <TB1> INFO: empty events: 7866
[14:06:23.853] <TB1> INFO: valid events with pixels: 168902
[14:06:23.853] <TB1> INFO: valid pixel hits: 668091
[14:06:23.853] <TB1> INFO: Event errors: 0
[14:06:23.853] <TB1> INFO: start marker: 0
[14:06:23.853] <TB1> INFO: stop marker: 0
[14:06:23.853] <TB1> INFO: overflow: 0
[14:06:23.853] <TB1> INFO: invalid 5bit words: 0
[14:06:23.853] <TB1> INFO: invalid XOR eye diagram: 0
[14:06:23.853] <TB1> INFO: TBM errors: 0
[14:06:23.853] <TB1> INFO: flawed TBM headers: 0
[14:06:23.853] <TB1> INFO: flawed TBM trailers: 0
[14:06:23.853] <TB1> INFO: event ID mismatches: 0
[14:06:23.853] <TB1> INFO: ROC errors: 0
[14:06:23.853] <TB1> INFO: missing ROC header(s): 0
[14:06:23.853] <TB1> INFO: misplaced readback start: 0
[14:06:23.853] <TB1> INFO: Pixel decoding errors: 0
[14:06:23.853] <TB1> INFO: pixel data incomplete: 0
[14:06:23.853] <TB1> INFO: pixel address: 0
[14:06:23.853] <TB1> INFO: pulse height fill bit: 0
[14:06:23.853] <TB1> INFO: buffer corruption: 0
[14:06:23.853] <TB1> INFO: enter test to run
[14:06:23.853] <TB1> INFO: test: exit no parameter change
[14:06:24.434] <TB1> QUIET: Connection to board 153 closed.
[14:06:24.513] <TB1> INFO: pXar: this is the end, my friend