MORE-Web

Test Date: 2015-11-20 08:13
Analysis date: 2015-11-23 18:13
Logfile
LogfileView
[07:27:13.105] <TB3>     INFO: *** Welcome to pxar ***

[07:27:13.105] <TB3>     INFO: *** Today: 2015/11/20

[07:27:13.509] <TB3>     INFO: *** Version: 9da6

[07:27:13.509] <TB3>     INFO: readRocDacs: /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C15.dat

[07:27:13.511] <TB3>     INFO: readTbmDacs: /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0b.dat

[07:27:13.511] <TB3>     INFO: readMaskFile: /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//defaultMaskFile.dat

[07:27:13.511] <TB3>     INFO: readTrimFile: /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters_C15.dat

[07:27:13.587] <TB3>     INFO:         clk: 4

[07:27:13.587] <TB3>     INFO:         ctr: 4

[07:27:13.587] <TB3>     INFO:         sda: 19

[07:27:13.587] <TB3>     INFO:         tin: 9

[07:27:13.587] <TB3>     INFO:         level: 15

[07:27:13.587] <TB3>     INFO:         triggerdelay: 0

[07:27:13.587] <TB3>    QUIET: Instanciating API for pxar v2.6.0+26~g89693ff

[07:27:13.587] <TB3>     INFO: Log level: INFO

[07:27:13.597] <TB3>     INFO: Found DTB DTB_WWXJGB

[07:27:13.604] <TB3>    QUIET: Connection to board DTB_WWXJGB opened.

[07:27:13.607] <TB3>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    137

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WWXJGB

MAC address: 40D855118089

Hostname:    pixelDTB137

Comment:     

------------------------------------------------------

[07:27:13.609] <TB3>     INFO: RPC call hashes of host and DTB match: 398089610

[07:27:15.163] <TB3>     INFO: DUT info: 

[07:27:15.164] <TB3>     INFO: The DUT currently contains the following objects:

[07:27:15.164] <TB3>     INFO:  2 TBM Cores tbm08c (2 ON)

[07:27:15.164] <TB3>     INFO: 	TBM Core alpha (0): 7 registers set

[07:27:15.164] <TB3>     INFO: 	TBM Core beta  (1): 7 registers set

[07:27:15.164] <TB3>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[07:27:15.164] <TB3>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.164] <TB3>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[07:27:15.565] <TB3>     INFO: enter 'restricted' command line mode

[07:27:15.565] <TB3>     INFO: enter test to run

[07:27:15.565] <TB3>     INFO:   test: pretest no parameter change

[07:27:15.565] <TB3>     INFO:   running: pretest

[07:27:15.575] <TB3>     INFO: ######################################################################

[07:27:15.575] <TB3>     INFO: PixTestPretest::doTest()

[07:27:15.575] <TB3>     INFO: ######################################################################

[07:27:15.577] <TB3>     INFO:    ----------------------------------------------------------------------

[07:27:15.577] <TB3>     INFO:    PixTestPretest::programROC() 

[07:27:15.577] <TB3>     INFO:    ----------------------------------------------------------------------

[07:27:33.594] <TB3>     INFO: PixTestPretest::programROC() done: ROCs are all programmable

[07:27:33.594] <TB3>     INFO: IA differences per ROC:  18.5 20.9 18.5 18.5 18.5 18.5 20.9 20.1 18.5 20.1 18.5 16.1 20.1 20.9 17.7 20.1

[07:27:33.662] <TB3>     INFO:    ----------------------------------------------------------------------

[07:27:33.662] <TB3>     INFO:    PixTestPretest::setVana() target Ia = 24 mA/ROC

[07:27:33.662] <TB3>     INFO:    ----------------------------------------------------------------------

[07:27:54.938] <TB3>     INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC

[07:27:54.938] <TB3>     INFO: i(loss) [mA/ROC]:     19.3  19.3  19.3  19.3  19.3  18.5  19.3  18.5  19.3  18.5  19.3  18.5  17.7  18.5  19.3  19.3

[07:27:54.970] <TB3>     INFO:    ----------------------------------------------------------------------

[07:27:54.970] <TB3>     INFO:    PixTestPretest::findTiming() 

[07:27:54.970] <TB3>     INFO:    ----------------------------------------------------------------------

[07:27:54.970] <TB3>     INFO: PixTestCmd::init()

[07:27:55.565] <TB3>  WARNING: Not unmasking DUT, not setting Calibrate bits!

[07:29:42.625] <TB3>     INFO: TBM phases:  160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1

[07:29:42.625] <TB3>     INFO: (success/tries = 100/100), width = 6

[07:29:42.626] <TB3>     INFO:    ----------------------------------------------------------------------

[07:29:42.626] <TB3>     INFO:    PixTestPretest::findWorkingPixel()

[07:29:42.626] <TB3>     INFO:    ----------------------------------------------------------------------

[07:29:42.762] <TB3>     INFO: Expecting 231680 events.

[07:29:47.371] <TB3>    ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (1) != Token Chain Length (8)

[07:29:47.374] <TB3>    ERROR: <datapipe.cc/CheckEventID:L461> Channel 0 Event ID mismatch:  local ID (21) !=  TBM ID (2)

[07:29:50.468] <TB3>     INFO: 231680 events read in total (6991ms).

[07:29:50.472] <TB3>     INFO: Test took 7844ms.

[07:29:50.869] <TB3>     INFO: Found working pixel in all ROCs: col/row = 12/22

[07:29:50.905] <TB3>     INFO:    ----------------------------------------------------------------------

[07:29:50.905] <TB3>     INFO:    PixTestPretest::setVthrCompCalDel()

[07:29:50.906] <TB3>     INFO:    ----------------------------------------------------------------------

[07:29:51.043] <TB3>     INFO: Expecting 231680 events.

[07:29:59.781] <TB3>     INFO: 231680 events read in total (8023ms).

[07:29:59.785] <TB3>     INFO: Test took 8874ms.

[07:30:00.199] <TB3>     INFO: PixTestPretest::setVthrCompCalDel() done

[07:30:00.199] <TB3>     INFO: CalDel:      118   112   119   112   125   142   140   142   124   115   102   122   136   132   105   136

[07:30:00.199] <TB3>     INFO: VthrComp:     51    51    51    53    51    51    51    51    51    51    51    51    51    51    51    51

[07:30:00.203] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C0.dat

[07:30:00.203] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C1.dat

[07:30:00.203] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C2.dat

[07:30:00.204] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C3.dat

[07:30:00.204] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C4.dat

[07:30:00.204] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C5.dat

[07:30:00.204] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C6.dat

[07:30:00.204] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C7.dat

[07:30:00.205] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C8.dat

[07:30:00.205] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C9.dat

[07:30:00.205] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C10.dat

[07:30:00.205] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C11.dat

[07:30:00.206] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C12.dat

[07:30:00.213] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C13.dat

[07:30:00.213] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C14.dat

[07:30:00.213] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters_C15.dat

[07:30:00.213] <TB3>     INFO: write tbm parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0a.dat

[07:30:00.213] <TB3>     INFO: write tbm parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//tbmParameters_C0b.dat

[07:30:00.214] <TB3>     INFO: PixTestPretest::doTest() done, duration: 164 seconds

[07:30:00.290] <TB3>     INFO: enter test to run

[07:30:00.290] <TB3>     INFO:   test: fulltest no parameter change

[07:30:00.290] <TB3>     INFO:   running: fulltest

[07:30:00.290] <TB3>     INFO: ######################################################################

[07:30:00.290] <TB3>     INFO: PixTestFullTest::doTest()

[07:30:00.290] <TB3>     INFO: ######################################################################

[07:30:00.292] <TB3>     INFO: ######################################################################

[07:30:00.292] <TB3>     INFO: PixTestAlive::doTest()

[07:30:00.292] <TB3>     INFO: ######################################################################

[07:30:00.293] <TB3>     INFO:    ----------------------------------------------------------------------

[07:30:00.293] <TB3>     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[07:30:00.293] <TB3>     INFO:    ----------------------------------------------------------------------

[07:30:00.628] <TB3>     INFO: Expecting 41600 events.

[07:30:04.907] <TB3>     INFO: 41600 events read in total (3564ms).

[07:30:04.908] <TB3>     INFO: Test took 4614ms.

[07:30:04.915] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:30:05.292] <TB3>     INFO: PixTestAlive::aliveTest() done

[07:30:05.292] <TB3>     INFO: number of dead pixels (per ROC):     2    0    0    0    1    0    0    0    1    1    0    0    0    0    0    0

[07:30:05.294] <TB3>     INFO:    ----------------------------------------------------------------------

[07:30:05.294] <TB3>     INFO:    PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[07:30:05.294] <TB3>     INFO:    ----------------------------------------------------------------------

[07:30:05.605] <TB3>     INFO: Expecting 41600 events.

[07:30:08.626] <TB3>     INFO: 41600 events read in total (2306ms).

[07:30:08.626] <TB3>     INFO: Test took 3331ms.

[07:30:08.626] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:30:08.627] <TB3>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[07:30:09.025] <TB3>     INFO: PixTestAlive::maskTest() done

[07:30:09.026] <TB3>     INFO: number of mask-defect pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[07:30:09.027] <TB3>     INFO:    ----------------------------------------------------------------------

[07:30:09.027] <TB3>     INFO:    PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[07:30:09.027] <TB3>     INFO:    ----------------------------------------------------------------------

[07:30:09.334] <TB3>     INFO: Expecting 41600 events.

[07:30:13.507] <TB3>     INFO: 41600 events read in total (3458ms).

[07:30:13.507] <TB3>     INFO: Test took 4479ms.

[07:30:13.514] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:30:13.898] <TB3>     INFO: PixTestAlive::addressDecodingTest() done

[07:30:13.898] <TB3>     INFO: number of address-decoding pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[07:30:13.898] <TB3>     INFO: PixTestAlive::doTest() done, duration: 13 seconds

[07:30:13.898] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:30:13.898] <TB3>     INFO: Decoding statistics:

[07:30:13.898] <TB3>     INFO:   General information:

[07:30:13.898] <TB3>     INFO: 	 16bit words read:         0

[07:30:13.898] <TB3>     INFO: 	 valid events total:       0

[07:30:13.898] <TB3>     INFO: 	 empty events:             0

[07:30:13.898] <TB3>     INFO: 	 valid events with pixels: 0

[07:30:13.898] <TB3>     INFO: 	 valid pixel hits:         0

[07:30:13.899] <TB3>     INFO:   Event errors: 	           0

[07:30:13.899] <TB3>     INFO: 	 start marker:             0

[07:30:13.899] <TB3>     INFO: 	 stop marker:              0

[07:30:13.899] <TB3>     INFO: 	 overflow:                 0

[07:30:13.899] <TB3>     INFO: 	 invalid 5bit words:       0

[07:30:13.899] <TB3>     INFO: 	 invalid XOR eye diagram:  0

[07:30:13.899] <TB3>     INFO:   TBM errors: 		           0

[07:30:13.899] <TB3>     INFO: 	 flawed TBM headers:       0

[07:30:13.899] <TB3>     INFO: 	 flawed TBM trailers:      0

[07:30:13.899] <TB3>     INFO: 	 event ID mismatches:      0

[07:30:13.899] <TB3>     INFO:   ROC errors: 		           0

[07:30:13.899] <TB3>     INFO: 	 missing ROC header(s):    0

[07:30:13.899] <TB3>     INFO: 	 misplaced readback start: 0

[07:30:13.899] <TB3>     INFO:   Pixel decoding errors:	   0

[07:30:13.899] <TB3>     INFO: 	 pixel data incomplete:    0

[07:30:13.899] <TB3>     INFO: 	 pixel address:            0

[07:30:13.899] <TB3>     INFO: 	 pulse height fill bit:    0

[07:30:13.899] <TB3>     INFO: 	 buffer corruption:        0

[07:30:13.910] <TB3>     INFO: ######################################################################

[07:30:13.910] <TB3>     INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)

[07:30:13.910] <TB3>     INFO: ######################################################################

[07:30:13.912] <TB3>     INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)

[07:30:13.978] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 5

[07:30:13.978] <TB3>     INFO:     run 1 of 1

[07:30:14.303] <TB3>     INFO: Expecting 3120000 events.

[07:31:03.947] <TB3>     INFO: 1086375 events read in total (48929ms).

[07:31:53.082] <TB3>     INFO: 2169280 events read in total (98064ms).

[07:32:34.844] <TB3>     INFO: 3120000 events read in total (139827ms).

[07:32:34.911] <TB3>     INFO: Test took 140934ms.

[07:32:35.007] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:32:57.801] <TB3>     INFO: PixTestBBMap::doTest() done, duration: 163 seconds

[07:32:57.801] <TB3>     INFO: number of dead bumps (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0

[07:32:57.801] <TB3>     INFO: separation cut       (per ROC):   117  128  108  115  102  114  105  102  105  108  103   94  107  114  113  120

[07:32:57.801] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:32:57.801] <TB3>     INFO: Decoding statistics:

[07:32:57.801] <TB3>     INFO:   General information:

[07:32:57.801] <TB3>     INFO: 	 16bit words read:         0

[07:32:57.801] <TB3>     INFO: 	 valid events total:       0

[07:32:57.801] <TB3>     INFO: 	 empty events:             0

[07:32:57.801] <TB3>     INFO: 	 valid events with pixels: 0

[07:32:57.801] <TB3>     INFO: 	 valid pixel hits:         0

[07:32:57.801] <TB3>     INFO:   Event errors: 	           0

[07:32:57.801] <TB3>     INFO: 	 start marker:             0

[07:32:57.801] <TB3>     INFO: 	 stop marker:              0

[07:32:57.801] <TB3>     INFO: 	 overflow:                 0

[07:32:57.801] <TB3>     INFO: 	 invalid 5bit words:       0

[07:32:57.801] <TB3>     INFO: 	 invalid XOR eye diagram:  0

[07:32:57.801] <TB3>     INFO:   TBM errors: 		           0

[07:32:57.801] <TB3>     INFO: 	 flawed TBM headers:       0

[07:32:57.801] <TB3>     INFO: 	 flawed TBM trailers:      0

[07:32:57.801] <TB3>     INFO: 	 event ID mismatches:      0

[07:32:57.801] <TB3>     INFO:   ROC errors: 		           0

[07:32:57.801] <TB3>     INFO: 	 missing ROC header(s):    0

[07:32:57.801] <TB3>     INFO: 	 misplaced readback start: 0

[07:32:57.801] <TB3>     INFO:   Pixel decoding errors:	   0

[07:32:57.801] <TB3>     INFO: 	 pixel data incomplete:    0

[07:32:57.801] <TB3>     INFO: 	 pixel address:            0

[07:32:57.801] <TB3>     INFO: 	 pulse height fill bit:    0

[07:32:57.801] <TB3>     INFO: 	 buffer corruption:        0

[07:32:57.887] <TB3>     INFO: ######################################################################

[07:32:57.887] <TB3>     INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1

[07:32:57.887] <TB3>     INFO: ######################################################################

[07:32:57.887] <TB3>     INFO:    ----------------------------------------------------------------------

[07:32:57.887] <TB3>     INFO:    PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1

[07:32:57.887] <TB3>     INFO:    ----------------------------------------------------------------------

[07:32:57.887] <TB3>     INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)

[07:32:57.895] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 50

[07:32:57.895] <TB3>     INFO:     run 1 of 1

[07:32:58.223] <TB3>     INFO: Expecting 26208000 events.

[07:33:30.265] <TB3>     INFO: 1131300 events read in total (31323ms).

[07:34:00.822] <TB3>     INFO: 2239750 events read in total (61880ms).

[07:34:31.847] <TB3>     INFO: 3344650 events read in total (92905ms).

[07:35:02.086] <TB3>     INFO: 4447450 events read in total (123144ms).

[07:35:33.094] <TB3>     INFO: 5549650 events read in total (154152ms).

[07:36:04.198] <TB3>     INFO: 6650800 events read in total (185256ms).

[07:36:36.111] <TB3>     INFO: 7747500 events read in total (217169ms).

[07:37:07.443] <TB3>     INFO: 8843300 events read in total (248501ms).

[07:37:38.465] <TB3>     INFO: 9939750 events read in total (279523ms).

[07:38:08.937] <TB3>     INFO: 11036050 events read in total (309995ms).

[07:38:39.203] <TB3>     INFO: 12132550 events read in total (340261ms).

[07:39:10.645] <TB3>     INFO: 13222250 events read in total (371703ms).

[07:39:42.106] <TB3>     INFO: 14300900 events read in total (403164ms).

[07:40:13.256] <TB3>     INFO: 15377950 events read in total (434314ms).

[07:40:44.437] <TB3>     INFO: 16453350 events read in total (465495ms).

[07:41:15.949] <TB3>     INFO: 17525550 events read in total (497007ms).

[07:41:46.712] <TB3>     INFO: 18600300 events read in total (527770ms).

[07:42:17.458] <TB3>     INFO: 19669350 events read in total (558516ms).

[07:42:48.274] <TB3>     INFO: 20742500 events read in total (589332ms).

[07:43:19.506] <TB3>     INFO: 21815600 events read in total (620564ms).

[07:43:50.673] <TB3>     INFO: 22886150 events read in total (651731ms).

[07:44:21.679] <TB3>     INFO: 23958300 events read in total (682737ms).

[07:44:52.843] <TB3>     INFO: 25034950 events read in total (713901ms).

[07:45:23.719] <TB3>     INFO: 26132300 events read in total (744778ms).

[07:45:26.065] <TB3>     INFO: 26208000 events read in total (747123ms).

[07:45:26.100] <TB3>     INFO: Test took 748205ms.

[07:45:26.177] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:45:26.329] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:27.719] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:29.084] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:30.492] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:31.892] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:33.357] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:35.158] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:36.587] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:37.998] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:39.402] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:40.978] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:42.722] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:44.479] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:46.229] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:47.686] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:49.117] <TB3>     INFO: dumping ASCII scurve output file: SCurveData

[07:45:50.584] <TB3>     INFO: PixTestScurves::scurves() done 

[07:45:50.584] <TB3>     INFO: Vcal mean: 102.44 107.30  88.00 106.78  89.81 106.76  87.12  90.22  98.82  84.19  87.08  92.44  94.81  98.53  95.70  74.96 

[07:45:50.584] <TB3>     INFO: Vcal RMS:    6.04   5.95   5.24   5.63   6.02   5.98   5.05   5.96   5.80   5.02   5.19   5.75   6.53   5.92   5.40   4.32 

[07:45:50.584] <TB3>     INFO: PixTestScurves::fullTest() done, duration: 772 seconds

[07:45:50.584] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:45:50.584] <TB3>     INFO: Decoding statistics:

[07:45:50.585] <TB3>     INFO:   General information:

[07:45:50.585] <TB3>     INFO: 	 16bit words read:         0

[07:45:50.585] <TB3>     INFO: 	 valid events total:       0

[07:45:50.585] <TB3>     INFO: 	 empty events:             0

[07:45:50.585] <TB3>     INFO: 	 valid events with pixels: 0

[07:45:50.585] <TB3>     INFO: 	 valid pixel hits:         0

[07:45:50.585] <TB3>     INFO:   Event errors: 	           0

[07:45:50.585] <TB3>     INFO: 	 start marker:             0

[07:45:50.585] <TB3>     INFO: 	 stop marker:              0

[07:45:50.585] <TB3>     INFO: 	 overflow:                 0

[07:45:50.585] <TB3>     INFO: 	 invalid 5bit words:       0

[07:45:50.585] <TB3>     INFO: 	 invalid XOR eye diagram:  0

[07:45:50.585] <TB3>     INFO:   TBM errors: 		           0

[07:45:50.585] <TB3>     INFO: 	 flawed TBM headers:       0

[07:45:50.585] <TB3>     INFO: 	 flawed TBM trailers:      0

[07:45:50.585] <TB3>     INFO: 	 event ID mismatches:      0

[07:45:50.585] <TB3>     INFO:   ROC errors: 		           0

[07:45:50.585] <TB3>     INFO: 	 missing ROC header(s):    0

[07:45:50.585] <TB3>     INFO: 	 misplaced readback start: 0

[07:45:50.585] <TB3>     INFO:   Pixel decoding errors:	   0

[07:45:50.585] <TB3>     INFO: 	 pixel data incomplete:    0

[07:45:50.585] <TB3>     INFO: 	 pixel address:            0

[07:45:50.585] <TB3>     INFO: 	 pulse height fill bit:    0

[07:45:50.585] <TB3>     INFO: 	 buffer corruption:        0

[07:45:50.661] <TB3>     INFO: ######################################################################

[07:45:50.661] <TB3>     INFO: PixTestTrim::doTest()

[07:45:50.661] <TB3>     INFO: ######################################################################

[07:45:50.663] <TB3>     INFO:    ----------------------------------------------------------------------

[07:45:50.663] <TB3>     INFO:    PixTestTrim::trimTest() ntrig = 10, vcal = 35

[07:45:50.663] <TB3>     INFO:    ----------------------------------------------------------------------

[07:45:50.759] <TB3>     INFO: ---> VthrComp thr map (minimal VthrComp)

[07:45:50.759] <TB3>     INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)

[07:45:50.768] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[07:45:50.768] <TB3>     INFO:     run 1 of 1

[07:45:51.071] <TB3>     INFO: Expecting 6281600 events.

[07:46:34.873] <TB3>     INFO: 1421800 events read in total (43087ms).

[07:47:17.621] <TB3>     INFO: 2830420 events read in total (85835ms).

[07:48:01.635] <TB3>     INFO: 4238360 events read in total (129849ms).

[07:48:45.628] <TB3>     INFO: 5653100 events read in total (173842ms).

[07:49:04.805] <TB3>     INFO: 6281600 events read in total (193019ms).

[07:49:04.838] <TB3>     INFO: Test took 194070ms.

[07:49:04.895] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:49:25.297] <TB3>     INFO: ROC 0 VthrComp = 103

[07:49:25.297] <TB3>     INFO: ROC 1 VthrComp = 107

[07:49:25.297] <TB3>     INFO: ROC 2 VthrComp = 93

[07:49:25.297] <TB3>     INFO: ROC 3 VthrComp = 105

[07:49:25.297] <TB3>     INFO: ROC 4 VthrComp = 93

[07:49:25.297] <TB3>     INFO: ROC 5 VthrComp = 102

[07:49:25.298] <TB3>     INFO: ROC 6 VthrComp = 92

[07:49:25.298] <TB3>     INFO: ROC 7 VthrComp = 94

[07:49:25.298] <TB3>     INFO: ROC 8 VthrComp = 98

[07:49:25.298] <TB3>     INFO: ROC 9 VthrComp = 90

[07:49:25.298] <TB3>     INFO: ROC 10 VthrComp = 90

[07:49:25.298] <TB3>     INFO: ROC 11 VthrComp = 89

[07:49:25.298] <TB3>     INFO: ROC 12 VthrComp = 92

[07:49:25.298] <TB3>     INFO: ROC 13 VthrComp = 100

[07:49:25.298] <TB3>     INFO: ROC 14 VthrComp = 97

[07:49:25.298] <TB3>     INFO: ROC 15 VthrComp = 80

[07:49:25.298] <TB3>     INFO: ---> Vcal thr map (pixel with maximum Vcal thr)

[07:49:25.298] <TB3>     INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)

[07:49:25.306] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[07:49:25.306] <TB3>     INFO:     run 1 of 1

[07:49:25.615] <TB3>     INFO: Expecting 6281600 events.

[07:50:02.215] <TB3>     INFO: 890100 events read in total (35885ms).

[07:50:37.405] <TB3>     INFO: 1776710 events read in total (71075ms).

[07:51:12.991] <TB3>     INFO: 2663780 events read in total (106661ms).

[07:51:48.807] <TB3>     INFO: 3547720 events read in total (142477ms).

[07:52:25.434] <TB3>     INFO: 4424650 events read in total (179104ms).

[07:53:01.151] <TB3>     INFO: 5298560 events read in total (214821ms).

[07:53:37.528] <TB3>     INFO: 6173350 events read in total (251198ms).

[07:53:42.088] <TB3>     INFO: 6281600 events read in total (255758ms).

[07:53:42.146] <TB3>     INFO: Test took 256839ms.

[07:53:42.287] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[07:54:09.937] <TB3>     INFO:    roc 0 with ID = 0  has maximal Vcal 59.791 for pixel 12/58 mean/min/max = 46.2881/32.7231/59.8532

[07:54:09.937] <TB3>     INFO:    roc 1 with ID = 1  has maximal Vcal 62.6172 for pixel 7/66 mean/min/max = 48.0073/33.2948/62.7198

[07:54:09.943] <TB3>     INFO:    roc 2 with ID = 2  has maximal Vcal 58.2766 for pixel 8/29 mean/min/max = 45.3361/32.366/58.3062

[07:54:09.943] <TB3>     INFO:    roc 3 with ID = 3  has maximal Vcal 63.0566 for pixel 22/26 mean/min/max = 48.2591/33.3887/63.1294

[07:54:09.943] <TB3>     INFO:    roc 4 with ID = 4  has maximal Vcal 59.2373 for pixel 1/1 mean/min/max = 45.4388/31.6338/59.2439

[07:54:09.944] <TB3>     INFO:    roc 5 with ID = 5  has maximal Vcal 64.0003 for pixel 8/70 mean/min/max = 48.2265/32.4087/64.0443

[07:54:09.944] <TB3>     INFO:    roc 6 with ID = 6  has maximal Vcal 56.7921 for pixel 14/71 mean/min/max = 44.6988/32.3169/57.0806

[07:54:09.944] <TB3>     INFO:    roc 7 with ID = 7  has maximal Vcal 57.874 for pixel 15/70 mean/min/max = 45.081/32.1794/57.9825

[07:54:09.944] <TB3>     INFO:    roc 8 with ID = 8  has maximal Vcal 59.8505 for pixel 47/4 mean/min/max = 46.1879/32.5129/59.8628

[07:54:09.945] <TB3>     INFO:    roc 9 with ID = 9  has maximal Vcal 57.5977 for pixel 15/19 mean/min/max = 45.0352/32.0803/57.9901

[07:54:09.945] <TB3>     INFO:    roc 10 with ID = 10  has maximal Vcal 58.304 for pixel 4/76 mean/min/max = 45.536/32.5272/58.5448

[07:54:09.945] <TB3>     INFO:    roc 11 with ID = 11  has maximal Vcal 62.0552 for pixel 4/34 mean/min/max = 47.474/32.8249/62.1231

[07:54:09.945] <TB3>     INFO:    roc 12 with ID = 12  has maximal Vcal 63.6694 for pixel 0/1 mean/min/max = 47.7491/31.4387/64.0596

[07:54:09.946] <TB3>     INFO:    roc 13 with ID = 13  has maximal Vcal 59.1171 for pixel 17/1 mean/min/max = 45.5957/31.8319/59.3595

[07:54:09.946] <TB3>     INFO:    roc 14 with ID = 14  has maximal Vcal 58.2614 for pixel 8/28 mean/min/max = 44.9856/31.6479/58.3233

[07:54:09.946] <TB3>     INFO:    roc 15 with ID = 15  has maximal Vcal 55.7131 for pixel 8/62 mean/min/max = 44.0018/32.2067/55.7969

[07:54:09.947] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[07:54:10.078] <TB3>     INFO: Expecting 514560 events.

[07:54:19.875] <TB3>     INFO: 514560 events read in total (9082ms).

[07:54:19.880] <TB3>     INFO: Expecting 514560 events.

[07:54:29.981] <TB3>     INFO: 514560 events read in total (9429ms).

[07:54:29.988] <TB3>     INFO: Expecting 514560 events.

[07:54:40.228] <TB3>     INFO: 514560 events read in total (9574ms).

[07:54:40.237] <TB3>     INFO: Expecting 514560 events.

[07:54:50.829] <TB3>     INFO: 514560 events read in total (9923ms).

[07:54:50.840] <TB3>     INFO: Expecting 514560 events.

[07:55:00.969] <TB3>     INFO: 514560 events read in total (9469ms).

[07:55:00.982] <TB3>     INFO: Expecting 514560 events.

[07:55:11.419] <TB3>     INFO: 514560 events read in total (9774ms).

[07:55:11.436] <TB3>     INFO: Expecting 514560 events.

[07:55:21.704] <TB3>     INFO: 514560 events read in total (9618ms).

[07:55:21.723] <TB3>     INFO: Expecting 514560 events.

[07:55:32.297] <TB3>     INFO: 514560 events read in total (9921ms).

[07:55:32.319] <TB3>     INFO: Expecting 514560 events.

[07:55:42.824] <TB3>     INFO: 514560 events read in total (9860ms).

[07:55:42.849] <TB3>     INFO: Expecting 514560 events.

[07:55:53.282] <TB3>     INFO: 514560 events read in total (9791ms).

[07:55:53.304] <TB3>     INFO: Expecting 514560 events.

[07:56:03.539] <TB3>     INFO: 514560 events read in total (9576ms).

[07:56:03.563] <TB3>     INFO: Expecting 514560 events.

[07:56:13.628] <TB3>     INFO: 514560 events read in total (9416ms).

[07:56:13.659] <TB3>     INFO: Expecting 514560 events.

[07:56:23.439] <TB3>     INFO: 514560 events read in total (9137ms).

[07:56:23.467] <TB3>     INFO: Expecting 514560 events.

[07:56:33.040] <TB3>     INFO: 514560 events read in total (8919ms).

[07:56:33.069] <TB3>     INFO: Expecting 514560 events.

[07:56:43.280] <TB3>     INFO: 514560 events read in total (9560ms).

[07:56:43.314] <TB3>     INFO: Expecting 514560 events.

[07:56:53.838] <TB3>     INFO: 514560 events read in total (9887ms).

[07:56:53.883] <TB3>     INFO: Test took 163937ms.

[07:56:55.173] <TB3>     INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)

[07:56:55.181] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[07:56:55.181] <TB3>     INFO:     run 1 of 1

[07:56:55.487] <TB3>     INFO: Expecting 6281600 events.

[07:57:31.850] <TB3>     INFO: 869970 events read in total (35648ms).

[07:58:08.607] <TB3>     INFO: 1738330 events read in total (72405ms).

[07:58:44.666] <TB3>     INFO: 2606700 events read in total (108464ms).

[07:59:20.724] <TB3>     INFO: 3471350 events read in total (144522ms).

[07:59:57.907] <TB3>     INFO: 4328640 events read in total (181705ms).

[08:00:33.904] <TB3>     INFO: 5182810 events read in total (217702ms).

[08:01:08.424] <TB3>     INFO: 6036960 events read in total (252222ms).

[08:01:19.037] <TB3>     INFO: 6281600 events read in total (262835ms).

[08:01:19.111] <TB3>     INFO: Test took 263930ms.

[08:01:19.271] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:01:44.840] <TB3>     INFO: ---> TrimStepCorr4 extremal thresholds: 0.126333 .. 255.000000

[08:01:44.934] <TB3>     INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)

[08:01:44.943] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:01:44.943] <TB3>     INFO:     run 1 of 1

[08:01:45.271] <TB3>     INFO: Expecting 10649600 events.

[08:02:21.514] <TB3>     INFO: 826750 events read in total (35529ms).

[08:02:56.340] <TB3>     INFO: 1653440 events read in total (70355ms).

[08:03:31.261] <TB3>     INFO: 2480210 events read in total (105276ms).

[08:04:05.538] <TB3>     INFO: 3307020 events read in total (139553ms).

[08:04:39.623] <TB3>     INFO: 4133980 events read in total (173638ms).

[08:05:14.178] <TB3>     INFO: 4960810 events read in total (208193ms).

[08:05:49.484] <TB3>     INFO: 5787530 events read in total (243499ms).

[08:06:23.953] <TB3>     INFO: 6613690 events read in total (277968ms).

[08:06:58.426] <TB3>     INFO: 7439020 events read in total (312441ms).

[08:07:32.896] <TB3>     INFO: 8264170 events read in total (346911ms).

[08:08:07.646] <TB3>     INFO: 9088690 events read in total (381661ms).

[08:08:42.943] <TB3>     INFO: 9913560 events read in total (416958ms).

[08:09:14.060] <TB3>     INFO: 10649600 events read in total (448075ms).

[08:09:14.196] <TB3>     INFO: Test took 449254ms.

[08:09:14.491] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:09:47.258] <TB3>     INFO: ---> TrimStepCorr2 extremal thresholds: 15.126605 .. 68.361848

[08:09:47.352] <TB3>     INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 78 (-1/-1) hits flags = 528 (plus default)

[08:09:47.361] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:09:47.361] <TB3>     INFO:     run 1 of 1

[08:09:47.664] <TB3>     INFO: Expecting 3078400 events.

[08:10:25.480] <TB3>     INFO: 999150 events read in total (37101ms).

[08:11:02.954] <TB3>     INFO: 1997600 events read in total (74576ms).

[08:11:39.905] <TB3>     INFO: 2994630 events read in total (111527ms).

[08:11:43.503] <TB3>     INFO: 3078400 events read in total (115124ms).

[08:11:43.530] <TB3>     INFO: Test took 116170ms.

[08:11:43.594] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:12:00.539] <TB3>     INFO: ---> TrimStepCorr1a extremal thresholds: 1.198878 .. 68.361848

[08:12:00.635] <TB3>     INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 78 (-1/-1) hits flags = 528 (plus default)

[08:12:00.643] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:12:00.644] <TB3>     INFO:     run 1 of 1

[08:12:00.966] <TB3>     INFO: Expecting 3244800 events.

[08:12:38.262] <TB3>     INFO: 1022350 events read in total (36580ms).

[08:13:17.472] <TB3>     INFO: 2044300 events read in total (75790ms).

[08:13:55.455] <TB3>     INFO: 3065780 events read in total (113774ms).

[08:14:01.976] <TB3>     INFO: 3244800 events read in total (120294ms).

[08:14:02.005] <TB3>     INFO: Test took 121362ms.

[08:14:02.069] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:14:18.301] <TB3>     INFO: ---> TrimStepCorr1b extremal thresholds: 1.198878 .. 68.361848

[08:14:18.381] <TB3>     INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 78 (-1/-1) hits flags = 528 (plus default)

[08:14:18.389] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:14:18.389] <TB3>     INFO:     run 1 of 1

[08:14:18.693] <TB3>     INFO: Expecting 3244800 events.

[08:14:56.421] <TB3>     INFO: 1022600 events read in total (37013ms).

[08:15:34.246] <TB3>     INFO: 2045410 events read in total (74838ms).

[08:16:11.031] <TB3>     INFO: 3067830 events read in total (111623ms).

[08:16:17.828] <TB3>     INFO: 3244800 events read in total (118420ms).

[08:16:17.857] <TB3>     INFO: Test took 119469ms.

[08:16:17.922] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:16:34.904] <TB3>     INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55

[08:16:34.904] <TB3>     INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)

[08:16:34.912] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:16:34.912] <TB3>     INFO:     run 1 of 1

[08:16:35.218] <TB3>     INFO: Expecting 1705600 events.

[08:17:14.457] <TB3>     INFO: 1076630 events read in total (38524ms).

[08:17:37.033] <TB3>     INFO: 1705600 events read in total (61101ms).

[08:17:37.051] <TB3>     INFO: Test took 62139ms.

[08:17:37.085] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:17:50.825] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C0.dat

[08:17:50.825] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C1.dat

[08:17:50.825] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C2.dat

[08:17:50.825] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C3.dat

[08:17:50.826] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C4.dat

[08:17:50.826] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C5.dat

[08:17:50.826] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C6.dat

[08:17:50.826] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C7.dat

[08:17:50.826] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C8.dat

[08:17:50.826] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C9.dat

[08:17:50.826] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C10.dat

[08:17:50.827] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C11.dat

[08:17:50.827] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C12.dat

[08:17:50.827] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C13.dat

[08:17:50.827] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C14.dat

[08:17:50.827] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C15.dat

[08:17:50.827] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C0.dat

[08:17:50.835] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C1.dat

[08:17:50.843] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C2.dat

[08:17:50.850] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C3.dat

[08:17:50.858] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C4.dat

[08:17:50.865] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C5.dat

[08:17:50.872] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C6.dat

[08:17:50.880] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C7.dat

[08:17:50.887] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C8.dat

[08:17:50.894] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C9.dat

[08:17:50.903] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C10.dat

[08:17:50.910] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C11.dat

[08:17:50.918] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C12.dat

[08:17:50.927] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C13.dat

[08:17:50.935] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C14.dat

[08:17:50.977] <TB3>     INFO: write trim parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//trimParameters35_C15.dat

[08:17:50.986] <TB3>     INFO: PixTestTrim::trimTest() done

[08:17:50.986] <TB3>     INFO: vtrim:     105 111  98 113  95 120  90  93 111  94 103 115  94  91 100  96 

[08:17:50.987] <TB3>     INFO: vthrcomp:  103 107  93 105  93 102  92  94  98  90  90  89  92 100  97  80 

[08:17:50.987] <TB3>     INFO: vcal mean:  35.04  34.98  35.00  35.00  34.95  35.03  35.00  35.01  34.98  35.00  35.00  34.99  35.02  35.01  35.00  35.04 

[08:17:50.987] <TB3>     INFO: vcal RMS:    1.17   0.89   0.92   0.95   1.07   1.11   0.95   0.87   1.05   1.15   0.88   1.35   0.94   0.90   0.88   0.83 

[08:17:50.987] <TB3>     INFO: bits mean:   9.12   8.62   9.86   8.87   9.47   9.09  10.01   9.55   9.08   9.58   9.49   9.47   8.48   9.47   9.74   9.85 

[08:17:50.987] <TB3>     INFO: bits RMS:    2.67   2.65   2.47   2.62   2.81   2.59   2.46   2.70   2.77   2.63   2.62   2.41   3.03   2.75   2.66   2.60 

[08:17:50.993] <TB3>     INFO:    ----------------------------------------------------------------------

[08:17:50.993] <TB3>     INFO:    PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32

[08:17:50.993] <TB3>     INFO:    ----------------------------------------------------------------------

[08:17:50.995] <TB3>     INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)

[08:17:51.003] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:17:51.003] <TB3>     INFO:     run 1 of 1

[08:17:51.309] <TB3>     INFO: Expecting 8320000 events.

[08:18:30.938] <TB3>     INFO: 1167350 events read in total (38914ms).

[08:19:09.761] <TB3>     INFO: 2321530 events read in total (77738ms).

[08:19:49.002] <TB3>     INFO: 3472010 events read in total (116978ms).

[08:20:25.996] <TB3>     INFO: 4615070 events read in total (153972ms).

[08:21:04.863] <TB3>     INFO: 5749310 events read in total (192839ms).

[08:21:43.664] <TB3>     INFO: 6882380 events read in total (231640ms).

[08:22:22.709] <TB3>     INFO: 8017280 events read in total (270685ms).

[08:22:33.444] <TB3>     INFO: 8320000 events read in total (281420ms).

[08:22:33.490] <TB3>     INFO: Test took 282487ms.

[08:22:33.601] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:23:02.993] <TB3>     INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 181 (-1/-1) hits flags = 528 (plus default)

[08:23:03.001] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:23:03.001] <TB3>     INFO:     run 1 of 1

[08:23:03.310] <TB3>     INFO: Expecting 7571200 events.

[08:23:43.197] <TB3>     INFO: 1171550 events read in total (39172ms).

[08:24:21.431] <TB3>     INFO: 2330840 events read in total (77406ms).

[08:24:59.692] <TB3>     INFO: 3485900 events read in total (115667ms).

[08:25:37.966] <TB3>     INFO: 4629610 events read in total (153941ms).

[08:26:14.867] <TB3>     INFO: 5768000 events read in total (190842ms).

[08:26:55.061] <TB3>     INFO: 6906970 events read in total (231036ms).

[08:27:17.645] <TB3>     INFO: 7571200 events read in total (253620ms).

[08:27:17.680] <TB3>     INFO: Test took 254679ms.

[08:27:17.770] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:27:45.355] <TB3>     INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)

[08:27:45.363] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:27:45.363] <TB3>     INFO:     run 1 of 1

[08:27:45.670] <TB3>     INFO: Expecting 7113600 events.

[08:28:27.791] <TB3>     INFO: 1212760 events read in total (41406ms).

[08:29:07.118] <TB3>     INFO: 2411300 events read in total (80733ms).

[08:29:45.481] <TB3>     INFO: 3605070 events read in total (119096ms).

[08:30:24.874] <TB3>     INFO: 4782860 events read in total (158489ms).

[08:31:04.261] <TB3>     INFO: 5959400 events read in total (197876ms).

[08:31:42.513] <TB3>     INFO: 7113600 events read in total (236128ms).

[08:31:42.548] <TB3>     INFO: Test took 237185ms.

[08:31:42.627] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:32:08.524] <TB3>     INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)

[08:32:08.531] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:32:08.531] <TB3>     INFO:     run 1 of 1

[08:32:08.834] <TB3>     INFO: Expecting 7030400 events.

[08:32:48.699] <TB3>     INFO: 1220750 events read in total (39150ms).

[08:33:29.088] <TB3>     INFO: 2426600 events read in total (79539ms).

[08:34:07.129] <TB3>     INFO: 3626140 events read in total (117581ms).

[08:34:46.652] <TB3>     INFO: 4811130 events read in total (157103ms).

[08:35:25.926] <TB3>     INFO: 5994270 events read in total (196378ms).

[08:36:00.412] <TB3>     INFO: 7030400 events read in total (230863ms).

[08:36:00.445] <TB3>     INFO: Test took 231914ms.

[08:36:00.519] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:36:23.612] <TB3>     INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)

[08:36:23.620] <TB3>     INFO:   dacScan split into 1 runs with ntrig = 10

[08:36:23.620] <TB3>     INFO:     run 1 of 1

[08:36:23.926] <TB3>     INFO: Expecting 7072000 events.

[08:37:06.018] <TB3>     INFO: 1215720 events read in total (41378ms).

[08:37:45.394] <TB3>     INFO: 2417170 events read in total (80754ms).

[08:38:25.206] <TB3>     INFO: 3612960 events read in total (120566ms).

[08:39:03.951] <TB3>     INFO: 4793960 events read in total (159311ms).

[08:39:44.405] <TB3>     INFO: 5972980 events read in total (199765ms).

[08:40:22.479] <TB3>     INFO: 7072000 events read in total (237839ms).

[08:40:22.513] <TB3>     INFO: Test took 238894ms.

[08:40:22.588] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:40:46.005] <TB3>     INFO: PixTestTrim::trimBitTest() done 

[08:40:46.006] <TB3>     INFO: PixTestTrim::doTest() done, duration: 3295 seconds

[08:40:46.006] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:40:46.006] <TB3>     INFO: Decoding statistics:

[08:40:46.006] <TB3>     INFO:   General information:

[08:40:46.006] <TB3>     INFO: 	 16bit words read:         0

[08:40:46.006] <TB3>     INFO: 	 valid events total:       0

[08:40:46.006] <TB3>     INFO: 	 empty events:             0

[08:40:46.006] <TB3>     INFO: 	 valid events with pixels: 0

[08:40:46.006] <TB3>     INFO: 	 valid pixel hits:         0

[08:40:46.006] <TB3>     INFO:   Event errors: 	           0

[08:40:46.006] <TB3>     INFO: 	 start marker:             0

[08:40:46.006] <TB3>     INFO: 	 stop marker:              0

[08:40:46.006] <TB3>     INFO: 	 overflow:                 0

[08:40:46.006] <TB3>     INFO: 	 invalid 5bit words:       0

[08:40:46.006] <TB3>     INFO: 	 invalid XOR eye diagram:  0

[08:40:46.006] <TB3>     INFO:   TBM errors: 		           0

[08:40:46.006] <TB3>     INFO: 	 flawed TBM headers:       0

[08:40:46.006] <TB3>     INFO: 	 flawed TBM trailers:      0

[08:40:46.006] <TB3>     INFO: 	 event ID mismatches:      0

[08:40:46.006] <TB3>     INFO:   ROC errors: 		           0

[08:40:46.006] <TB3>     INFO: 	 missing ROC header(s):    0

[08:40:46.006] <TB3>     INFO: 	 misplaced readback start: 0

[08:40:46.006] <TB3>     INFO:   Pixel decoding errors:	   0

[08:40:46.006] <TB3>     INFO: 	 pixel data incomplete:    0

[08:40:46.006] <TB3>     INFO: 	 pixel address:            0

[08:40:46.006] <TB3>     INFO: 	 pulse height fill bit:    0

[08:40:46.006] <TB3>     INFO: 	 buffer corruption:        0

[08:40:46.712] <TB3>     INFO: ######################################################################

[08:40:46.712] <TB3>     INFO: PixTestPhOptimization::doTest() Ntrig = 10

[08:40:46.712] <TB3>     INFO: ######################################################################

[08:40:47.020] <TB3>     INFO: Expecting 41600 events.

[08:40:51.261] <TB3>     INFO: 41600 events read in total (3527ms).

[08:40:51.261] <TB3>     INFO: Test took 4548ms.

[08:40:51.267] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:40:51.937] <TB3>     INFO: Expecting 41600 events.

[08:40:56.276] <TB3>     INFO: 41600 events read in total (3624ms).

[08:40:56.276] <TB3>     INFO: Test took 4650ms.

[08:40:56.282] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:40:56.736] <TB3>     INFO: Expecting 41600 events.

[08:41:00.940] <TB3>     INFO: 41600 events read in total (3489ms).

[08:41:00.941] <TB3>     INFO: Test took 4540ms.

[08:41:00.947] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:01.413] <TB3>     INFO: Expecting 41600 events.

[08:41:05.625] <TB3>     INFO: 41600 events read in total (3497ms).

[08:41:05.626] <TB3>     INFO: Test took 4557ms.

[08:41:05.632] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:06.079] <TB3>     INFO: Expecting 41600 events.

[08:41:10.274] <TB3>     INFO: 41600 events read in total (3480ms).

[08:41:10.274] <TB3>     INFO: Test took 4518ms.

[08:41:10.281] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:10.779] <TB3>     INFO: Expecting 41600 events.

[08:41:14.927] <TB3>     INFO: 41600 events read in total (3433ms).

[08:41:14.928] <TB3>     INFO: Test took 4529ms.

[08:41:14.934] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:15.378] <TB3>     INFO: Expecting 41600 events.

[08:41:19.712] <TB3>     INFO: 41600 events read in total (3620ms).

[08:41:19.712] <TB3>     INFO: Test took 4658ms.

[08:41:19.718] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:20.165] <TB3>     INFO: Expecting 41600 events.

[08:41:24.349] <TB3>     INFO: 41600 events read in total (3469ms).

[08:41:24.349] <TB3>     INFO: Test took 4507ms.

[08:41:24.356] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:24.783] <TB3>     INFO: Expecting 41600 events.

[08:41:29.313] <TB3>     INFO: 41600 events read in total (3815ms).

[08:41:29.314] <TB3>     INFO: Test took 4854ms.

[08:41:29.320] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:29.749] <TB3>     INFO: Expecting 41600 events.

[08:41:34.043] <TB3>     INFO: 41600 events read in total (3579ms).

[08:41:34.044] <TB3>     INFO: Test took 4619ms.

[08:41:34.050] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:34.492] <TB3>     INFO: Expecting 41600 events.

[08:41:38.707] <TB3>     INFO: 41600 events read in total (3501ms).

[08:41:38.708] <TB3>     INFO: Test took 4539ms.

[08:41:38.715] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:39.139] <TB3>     INFO: Expecting 41600 events.

[08:41:43.429] <TB3>     INFO: 41600 events read in total (3575ms).

[08:41:43.430] <TB3>     INFO: Test took 4615ms.

[08:41:43.437] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:43.881] <TB3>     INFO: Expecting 41600 events.

[08:41:48.202] <TB3>     INFO: 41600 events read in total (3606ms).

[08:41:48.202] <TB3>     INFO: Test took 4653ms.

[08:41:48.209] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:48.646] <TB3>     INFO: Expecting 41600 events.

[08:41:52.857] <TB3>     INFO: 41600 events read in total (3496ms).

[08:41:52.857] <TB3>     INFO: Test took 4535ms.

[08:41:52.864] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:53.319] <TB3>     INFO: Expecting 41600 events.

[08:41:57.765] <TB3>     INFO: 41600 events read in total (3731ms).

[08:41:57.766] <TB3>     INFO: Test took 4780ms.

[08:41:57.774] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:41:58.242] <TB3>     INFO: Expecting 41600 events.

[08:42:02.717] <TB3>     INFO: 41600 events read in total (3760ms).

[08:42:02.717] <TB3>     INFO: Test took 4858ms.

[08:42:02.725] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:03.191] <TB3>     INFO: Expecting 41600 events.

[08:42:07.326] <TB3>     INFO: 41600 events read in total (3420ms).

[08:42:07.326] <TB3>     INFO: Test took 4516ms.

[08:42:07.332] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:07.784] <TB3>     INFO: Expecting 41600 events.

[08:42:12.042] <TB3>     INFO: 41600 events read in total (3543ms).

[08:42:12.042] <TB3>     INFO: Test took 4587ms.

[08:42:12.049] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:12.478] <TB3>     INFO: Expecting 41600 events.

[08:42:16.602] <TB3>     INFO: 41600 events read in total (3409ms).

[08:42:16.603] <TB3>     INFO: Test took 4448ms.

[08:42:16.610] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:17.038] <TB3>     INFO: Expecting 41600 events.

[08:42:21.394] <TB3>     INFO: 41600 events read in total (3641ms).

[08:42:21.395] <TB3>     INFO: Test took 4680ms.

[08:42:21.401] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:21.835] <TB3>     INFO: Expecting 41600 events.

[08:42:26.059] <TB3>     INFO: 41600 events read in total (3509ms).

[08:42:26.059] <TB3>     INFO: Test took 4547ms.

[08:42:26.066] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:26.505] <TB3>     INFO: Expecting 41600 events.

[08:42:30.613] <TB3>     INFO: 41600 events read in total (3393ms).

[08:42:30.613] <TB3>     INFO: Test took 4431ms.

[08:42:30.619] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:31.065] <TB3>     INFO: Expecting 41600 events.

[08:42:35.243] <TB3>     INFO: 41600 events read in total (3463ms).

[08:42:35.243] <TB3>     INFO: Test took 4501ms.

[08:42:35.250] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:35.694] <TB3>     INFO: Expecting 41600 events.

[08:42:39.905] <TB3>     INFO: 41600 events read in total (3496ms).

[08:42:39.906] <TB3>     INFO: Test took 4534ms.

[08:42:39.912] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:40.352] <TB3>     INFO: Expecting 41600 events.

[08:42:44.410] <TB3>     INFO: 41600 events read in total (3343ms).

[08:42:44.410] <TB3>     INFO: Test took 4381ms.

[08:42:44.417] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:44.873] <TB3>     INFO: Expecting 41600 events.

[08:42:49.202] <TB3>     INFO: 41600 events read in total (3614ms).

[08:42:49.202] <TB3>     INFO: Test took 4660ms.

[08:42:49.208] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:49.655] <TB3>     INFO: Expecting 41600 events.

[08:42:54.007] <TB3>     INFO: 41600 events read in total (3637ms).

[08:42:54.007] <TB3>     INFO: Test took 4676ms.

[08:42:54.014] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:54.457] <TB3>     INFO: Expecting 41600 events.

[08:42:58.794] <TB3>     INFO: 41600 events read in total (3622ms).

[08:42:58.795] <TB3>     INFO: Test took 4661ms.

[08:42:58.802] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:42:59.238] <TB3>     INFO: Expecting 41600 events.

[08:43:03.611] <TB3>     INFO: 41600 events read in total (3658ms).

[08:43:03.611] <TB3>     INFO: Test took 4697ms.

[08:43:03.618] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:04.058] <TB3>     INFO: Expecting 41600 events.

[08:43:08.338] <TB3>     INFO: 41600 events read in total (3565ms).

[08:43:08.338] <TB3>     INFO: Test took 4603ms.

[08:43:08.344] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:08.777] <TB3>     INFO: Expecting 41600 events.

[08:43:13.050] <TB3>     INFO: 41600 events read in total (3559ms).

[08:43:13.050] <TB3>     INFO: Test took 4596ms.

[08:43:13.056] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:13.492] <TB3>     INFO: Expecting 41600 events.

[08:43:17.712] <TB3>     INFO: 41600 events read in total (3505ms).

[08:43:17.712] <TB3>     INFO: Test took 4543ms.

[08:43:17.719] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:18.160] <TB3>     INFO: Expecting 41600 events.

[08:43:22.310] <TB3>     INFO: 41600 events read in total (3435ms).

[08:43:22.310] <TB3>     INFO: Test took 4476ms.

[08:43:22.317] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:22.755] <TB3>     INFO: Expecting 41600 events.

[08:43:27.080] <TB3>     INFO: 41600 events read in total (3610ms).

[08:43:27.081] <TB3>     INFO: Test took 4649ms.

[08:43:27.087] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:27.522] <TB3>     INFO: Expecting 41600 events.

[08:43:31.776] <TB3>     INFO: 41600 events read in total (3539ms).

[08:43:31.777] <TB3>     INFO: Test took 4578ms.

[08:43:31.783] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:32.229] <TB3>     INFO: Expecting 41600 events.

[08:43:36.670] <TB3>     INFO: 41600 events read in total (3726ms).

[08:43:36.671] <TB3>     INFO: Test took 4766ms.

[08:43:36.677] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:37.110] <TB3>     INFO: Expecting 41600 events.

[08:43:41.452] <TB3>     INFO: 41600 events read in total (3628ms).

[08:43:41.452] <TB3>     INFO: Test took 4666ms.

[08:43:41.458] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:41.900] <TB3>     INFO: Expecting 41600 events.

[08:43:46.313] <TB3>     INFO: 41600 events read in total (3698ms).

[08:43:46.314] <TB3>     INFO: Test took 4737ms.

[08:43:46.320] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:46.756] <TB3>     INFO: Expecting 41600 events.

[08:43:50.941] <TB3>     INFO: 41600 events read in total (3470ms).

[08:43:50.941] <TB3>     INFO: Test took 4507ms.

[08:43:50.947] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:51.393] <TB3>     INFO: Expecting 41600 events.

[08:43:55.564] <TB3>     INFO: 41600 events read in total (3456ms).

[08:43:55.565] <TB3>     INFO: Test took 4496ms.

[08:43:55.571] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:43:56.018] <TB3>     INFO: Expecting 41600 events.

[08:44:00.307] <TB3>     INFO: 41600 events read in total (3574ms).

[08:44:00.308] <TB3>     INFO: Test took 4613ms.

[08:44:00.314] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:00.745] <TB3>     INFO: Expecting 41600 events.

[08:44:05.050] <TB3>     INFO: 41600 events read in total (3590ms).

[08:44:05.051] <TB3>     INFO: Test took 4629ms.

[08:44:05.057] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:05.508] <TB3>     INFO: Expecting 41600 events.

[08:44:09.712] <TB3>     INFO: 41600 events read in total (3489ms).

[08:44:09.713] <TB3>     INFO: Test took 4533ms.

[08:44:09.720] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:10.156] <TB3>     INFO: Expecting 41600 events.

[08:44:14.338] <TB3>     INFO: 41600 events read in total (3468ms).

[08:44:14.338] <TB3>     INFO: Test took 4505ms.

[08:44:14.344] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:14.789] <TB3>     INFO: Expecting 41600 events.

[08:44:19.180] <TB3>     INFO: 41600 events read in total (3676ms).

[08:44:19.180] <TB3>     INFO: Test took 4716ms.

[08:44:19.187] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:19.628] <TB3>     INFO: Expecting 41600 events.

[08:44:24.042] <TB3>     INFO: 41600 events read in total (3699ms).

[08:44:24.043] <TB3>     INFO: Test took 4738ms.

[08:44:24.049] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:24.493] <TB3>     INFO: Expecting 41600 events.

[08:44:28.804] <TB3>     INFO: 41600 events read in total (3596ms).

[08:44:28.805] <TB3>     INFO: Test took 4634ms.

[08:44:28.811] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:29.252] <TB3>     INFO: Expecting 41600 events.

[08:44:33.580] <TB3>     INFO: 41600 events read in total (3613ms).

[08:44:33.581] <TB3>     INFO: Test took 4652ms.

[08:44:33.587] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:34.021] <TB3>     INFO: Expecting 41600 events.

[08:44:38.201] <TB3>     INFO: 41600 events read in total (3465ms).

[08:44:38.201] <TB3>     INFO: Test took 4503ms.

[08:44:38.207] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:38.645] <TB3>     INFO: Expecting 41600 events.

[08:44:42.863] <TB3>     INFO: 41600 events read in total (3503ms).

[08:44:42.863] <TB3>     INFO: Test took 4541ms.

[08:44:42.869] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:43.325] <TB3>     INFO: Expecting 41600 events.

[08:44:47.569] <TB3>     INFO: 41600 events read in total (3529ms).

[08:44:47.570] <TB3>     INFO: Test took 4576ms.

[08:44:47.576] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:48.016] <TB3>     INFO: Expecting 41600 events.

[08:44:52.389] <TB3>     INFO: 41600 events read in total (3658ms).

[08:44:52.390] <TB3>     INFO: Test took 4697ms.

[08:44:52.397] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:52.821] <TB3>     INFO: Expecting 41600 events.

[08:44:57.130] <TB3>     INFO: 41600 events read in total (3594ms).

[08:44:57.131] <TB3>     INFO: Test took 4633ms.

[08:44:57.137] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:44:57.579] <TB3>     INFO: Expecting 41600 events.

[08:45:02.020] <TB3>     INFO: 41600 events read in total (3727ms).

[08:45:02.020] <TB3>     INFO: Test took 4764ms.

[08:45:02.026] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:45:02.468] <TB3>     INFO: Expecting 2560 events.

[08:45:03.429] <TB3>     INFO: 2560 events read in total (246ms).

[08:45:03.430] <TB3>     INFO: Test took 1397ms.

[08:45:03.938] <TB3>     INFO: Expecting 2560 events.

[08:45:04.909] <TB3>     INFO: 2560 events read in total (256ms).

[08:45:04.910] <TB3>     INFO: Test took 1480ms.

[08:45:05.418] <TB3>     INFO: Expecting 2560 events.

[08:45:06.380] <TB3>     INFO: 2560 events read in total (247ms).

[08:45:06.380] <TB3>     INFO: Test took 1469ms.

[08:45:06.888] <TB3>     INFO: Expecting 2560 events.

[08:45:07.844] <TB3>     INFO: 2560 events read in total (241ms).

[08:45:07.844] <TB3>     INFO: Test took 1464ms.

[08:45:08.353] <TB3>     INFO: Expecting 2560 events.

[08:45:09.310] <TB3>     INFO: 2560 events read in total (243ms).

[08:45:09.310] <TB3>     INFO: Test took 1466ms.

[08:45:09.818] <TB3>     INFO: Expecting 2560 events.

[08:45:10.782] <TB3>     INFO: 2560 events read in total (249ms).

[08:45:10.782] <TB3>     INFO: Test took 1472ms.

[08:45:11.289] <TB3>     INFO: Expecting 2560 events.

[08:45:12.250] <TB3>     INFO: 2560 events read in total (246ms).

[08:45:12.250] <TB3>     INFO: Test took 1467ms.

[08:45:12.758] <TB3>     INFO: Expecting 2560 events.

[08:45:13.716] <TB3>     INFO: 2560 events read in total (243ms).

[08:45:13.716] <TB3>     INFO: Test took 1458ms.

[08:45:14.223] <TB3>     INFO: Expecting 2560 events.

[08:45:15.199] <TB3>     INFO: 2560 events read in total (261ms).

[08:45:15.199] <TB3>     INFO: Test took 1483ms.

[08:45:15.707] <TB3>     INFO: Expecting 2560 events.

[08:45:16.681] <TB3>     INFO: 2560 events read in total (259ms).

[08:45:16.682] <TB3>     INFO: Test took 1483ms.

[08:45:17.189] <TB3>     INFO: Expecting 2560 events.

[08:45:18.145] <TB3>     INFO: 2560 events read in total (241ms).

[08:45:18.145] <TB3>     INFO: Test took 1463ms.

[08:45:18.653] <TB3>     INFO: Expecting 2560 events.

[08:45:19.611] <TB3>     INFO: 2560 events read in total (243ms).

[08:45:19.611] <TB3>     INFO: Test took 1465ms.

[08:45:20.118] <TB3>     INFO: Expecting 2560 events.

[08:45:21.090] <TB3>     INFO: 2560 events read in total (257ms).

[08:45:21.091] <TB3>     INFO: Test took 1479ms.

[08:45:21.597] <TB3>     INFO: Expecting 2560 events.

[08:45:22.559] <TB3>     INFO: 2560 events read in total (247ms).

[08:45:22.559] <TB3>     INFO: Test took 1468ms.

[08:45:23.067] <TB3>     INFO: Expecting 2560 events.

[08:45:24.030] <TB3>     INFO: 2560 events read in total (248ms).

[08:45:24.030] <TB3>     INFO: Test took 1471ms.

[08:45:24.538] <TB3>     INFO: Expecting 2560 events.

[08:45:25.514] <TB3>     INFO: 2560 events read in total (261ms).

[08:45:25.514] <TB3>     INFO: Test took 1483ms.

[08:45:25.517] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:45:26.023] <TB3>     INFO: Expecting 655360 events.

[08:45:39.132] <TB3>     INFO: 655360 events read in total (12394ms).

[08:45:39.144] <TB3>     INFO: Expecting 655360 events.

[08:45:52.429] <TB3>     INFO: 655360 events read in total (12726ms).

[08:45:52.444] <TB3>     INFO: Expecting 655360 events.

[08:46:05.286] <TB3>     INFO: 655360 events read in total (12267ms).

[08:46:05.302] <TB3>     INFO: Expecting 655360 events.

[08:46:18.174] <TB3>     INFO: 655360 events read in total (12295ms).

[08:46:18.198] <TB3>     INFO: Expecting 655360 events.

[08:46:31.202] <TB3>     INFO: 655360 events read in total (12443ms).

[08:46:31.230] <TB3>     INFO: Expecting 655360 events.

[08:46:43.984] <TB3>     INFO: 655360 events read in total (12205ms).

[08:46:44.010] <TB3>     INFO: Expecting 655360 events.

[08:46:57.259] <TB3>     INFO: 655360 events read in total (12666ms).

[08:46:57.289] <TB3>     INFO: Expecting 655360 events.

[08:47:10.509] <TB3>     INFO: 655360 events read in total (12642ms).

[08:47:10.549] <TB3>     INFO: Expecting 655360 events.

[08:47:23.069] <TB3>     INFO: 655360 events read in total (11948ms).

[08:47:23.110] <TB3>     INFO: Expecting 655360 events.

[08:47:35.356] <TB3>     INFO: 655360 events read in total (11685ms).

[08:47:35.425] <TB3>     INFO: Expecting 655360 events.

[08:47:47.733] <TB3>     INFO: 655360 events read in total (11781ms).

[08:47:47.779] <TB3>     INFO: Expecting 655360 events.

[08:48:00.429] <TB3>     INFO: 655360 events read in total (12088ms).

[08:48:00.481] <TB3>     INFO: Expecting 655360 events.

[08:48:13.434] <TB3>     INFO: 655360 events read in total (12395ms).

[08:48:13.486] <TB3>     INFO: Expecting 655360 events.

[08:48:26.351] <TB3>     INFO: 655360 events read in total (12312ms).

[08:48:26.412] <TB3>     INFO: Expecting 655360 events.

[08:48:39.433] <TB3>     INFO: 655360 events read in total (12475ms).

[08:48:39.502] <TB3>     INFO: Expecting 655360 events.

[08:48:52.456] <TB3>     INFO: 655360 events read in total (12420ms).

[08:48:52.523] <TB3>     INFO: Test took 207006ms.

[08:48:52.605] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:48:52.915] <TB3>     INFO: Expecting 655360 events.

[08:49:06.011] <TB3>     INFO: 655360 events read in total (12381ms).

[08:49:06.021] <TB3>     INFO: Expecting 655360 events.

[08:49:19.162] <TB3>     INFO: 655360 events read in total (12535ms).

[08:49:19.175] <TB3>     INFO: Expecting 655360 events.

[08:49:32.342] <TB3>     INFO: 655360 events read in total (12577ms).

[08:49:32.359] <TB3>     INFO: Expecting 655360 events.

[08:49:44.530] <TB3>     INFO: 655360 events read in total (11582ms).

[08:49:44.549] <TB3>     INFO: Expecting 655360 events.

[08:49:56.671] <TB3>     INFO: 655360 events read in total (11529ms).

[08:49:56.701] <TB3>     INFO: Expecting 655360 events.

[08:50:09.085] <TB3>     INFO: 655360 events read in total (11828ms).

[08:50:09.117] <TB3>     INFO: Expecting 655360 events.

[08:50:22.149] <TB3>     INFO: 655360 events read in total (12473ms).

[08:50:22.195] <TB3>     INFO: Expecting 655360 events.

[08:50:35.281] <TB3>     INFO: 655360 events read in total (12560ms).

[08:50:35.316] <TB3>     INFO: Expecting 655360 events.

[08:50:48.212] <TB3>     INFO: 655360 events read in total (12346ms).

[08:50:48.251] <TB3>     INFO: Expecting 655360 events.

[08:51:01.307] <TB3>     INFO: 655360 events read in total (12458ms).

[08:51:01.354] <TB3>     INFO: Expecting 655360 events.

[08:51:14.362] <TB3>     INFO: 655360 events read in total (12449ms).

[08:51:14.408] <TB3>     INFO: Expecting 655360 events.

[08:51:27.605] <TB3>     INFO: 655360 events read in total (12632ms).

[08:51:27.658] <TB3>     INFO: Expecting 655360 events.

[08:51:40.633] <TB3>     INFO: 655360 events read in total (12420ms).

[08:51:40.686] <TB3>     INFO: Expecting 655360 events.

[08:51:53.692] <TB3>     INFO: 655360 events read in total (12451ms).

[08:51:53.746] <TB3>     INFO: Expecting 655360 events.

[08:52:07.106] <TB3>     INFO: 655360 events read in total (12797ms).

[08:52:07.166] <TB3>     INFO: Expecting 655360 events.

[08:52:20.343] <TB3>     INFO: 655360 events read in total (12634ms).

[08:52:20.408] <TB3>     INFO: Test took 207803ms.

[08:52:20.604] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.611] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.618] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.625] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.632] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.640] <TB3>     INFO: safety margin for low PH: adding 1, margin is now 21

[08:52:20.647] <TB3>     INFO: safety margin for low PH: adding 2, margin is now 22

[08:52:20.655] <TB3>     INFO: safety margin for low PH: adding 3, margin is now 23

[08:52:20.662] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.669] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.676] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.683] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.690] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.697] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.703] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.710] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.717] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.724] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.731] <TB3>     INFO: safety margin for low PH: adding 0, margin is now 20

[08:52:20.765] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C0.dat

[08:52:20.766] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C1.dat

[08:52:20.766] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C2.dat

[08:52:20.766] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C3.dat

[08:52:20.766] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C4.dat

[08:52:20.766] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C5.dat

[08:52:20.766] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C6.dat

[08:52:20.766] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C7.dat

[08:52:20.766] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C8.dat

[08:52:20.767] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C9.dat

[08:52:20.767] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C10.dat

[08:52:20.767] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C11.dat

[08:52:20.767] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C12.dat

[08:52:20.767] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C13.dat

[08:52:20.767] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C14.dat

[08:52:20.767] <TB3>     INFO: write dac parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//dacParameters35_C15.dat

[08:52:21.077] <TB3>     INFO: Expecting 41600 events.

[08:52:25.161] <TB3>     INFO: 41600 events read in total (3369ms).

[08:52:25.162] <TB3>     INFO: Test took 4392ms.

[08:52:25.797] <TB3>     INFO: Expecting 41600 events.

[08:52:29.766] <TB3>     INFO: 41600 events read in total (3254ms).

[08:52:29.766] <TB3>     INFO: Test took 4281ms.

[08:52:30.419] <TB3>     INFO: Expecting 41600 events.

[08:52:34.452] <TB3>     INFO: 41600 events read in total (3318ms).

[08:52:34.452] <TB3>     INFO: Test took 4360ms.

[08:52:34.776] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:34.907] <TB3>     INFO: Expecting 2560 events.

[08:52:35.882] <TB3>     INFO: 2560 events read in total (260ms).

[08:52:35.883] <TB3>     INFO: Test took 1107ms.

[08:52:35.885] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:36.391] <TB3>     INFO: Expecting 2560 events.

[08:52:37.354] <TB3>     INFO: 2560 events read in total (248ms).

[08:52:37.354] <TB3>     INFO: Test took 1469ms.

[08:52:37.356] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:37.863] <TB3>     INFO: Expecting 2560 events.

[08:52:38.825] <TB3>     INFO: 2560 events read in total (247ms).

[08:52:38.825] <TB3>     INFO: Test took 1469ms.

[08:52:38.828] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:39.333] <TB3>     INFO: Expecting 2560 events.

[08:52:40.290] <TB3>     INFO: 2560 events read in total (242ms).

[08:52:40.290] <TB3>     INFO: Test took 1462ms.

[08:52:40.293] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:40.799] <TB3>     INFO: Expecting 2560 events.

[08:52:41.762] <TB3>     INFO: 2560 events read in total (247ms).

[08:52:41.762] <TB3>     INFO: Test took 1470ms.

[08:52:41.764] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:42.271] <TB3>     INFO: Expecting 2560 events.

[08:52:43.233] <TB3>     INFO: 2560 events read in total (247ms).

[08:52:43.234] <TB3>     INFO: Test took 1470ms.

[08:52:43.236] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:43.743] <TB3>     INFO: Expecting 2560 events.

[08:52:44.705] <TB3>     INFO: 2560 events read in total (247ms).

[08:52:44.706] <TB3>     INFO: Test took 1470ms.

[08:52:44.708] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:45.215] <TB3>     INFO: Expecting 2560 events.

[08:52:46.171] <TB3>     INFO: 2560 events read in total (241ms).

[08:52:46.172] <TB3>     INFO: Test took 1464ms.

[08:52:46.174] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:46.681] <TB3>     INFO: Expecting 2560 events.

[08:52:47.669] <TB3>     INFO: 2560 events read in total (273ms).

[08:52:47.669] <TB3>     INFO: Test took 1495ms.

[08:52:47.670] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:48.178] <TB3>     INFO: Expecting 2560 events.

[08:52:49.140] <TB3>     INFO: 2560 events read in total (247ms).

[08:52:49.141] <TB3>     INFO: Test took 1471ms.

[08:52:49.143] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:49.648] <TB3>     INFO: Expecting 2560 events.

[08:52:50.638] <TB3>     INFO: 2560 events read in total (275ms).

[08:52:50.638] <TB3>     INFO: Test took 1496ms.

[08:52:50.641] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:51.148] <TB3>     INFO: Expecting 2560 events.

[08:52:52.109] <TB3>     INFO: 2560 events read in total (246ms).

[08:52:52.109] <TB3>     INFO: Test took 1469ms.

[08:52:52.112] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:52.618] <TB3>     INFO: Expecting 2560 events.

[08:52:53.579] <TB3>     INFO: 2560 events read in total (246ms).

[08:52:53.580] <TB3>     INFO: Test took 1468ms.

[08:52:53.582] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:54.088] <TB3>     INFO: Expecting 2560 events.

[08:52:55.046] <TB3>     INFO: 2560 events read in total (243ms).

[08:52:55.046] <TB3>     INFO: Test took 1464ms.

[08:52:55.048] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:55.555] <TB3>     INFO: Expecting 2560 events.

[08:52:56.530] <TB3>     INFO: 2560 events read in total (260ms).

[08:52:56.530] <TB3>     INFO: Test took 1482ms.

[08:52:56.532] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:57.039] <TB3>     INFO: Expecting 2560 events.

[08:52:58.000] <TB3>     INFO: 2560 events read in total (246ms).

[08:52:58.000] <TB3>     INFO: Test took 1468ms.

[08:52:58.002] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:58.509] <TB3>     INFO: Expecting 2560 events.

[08:52:59.485] <TB3>     INFO: 2560 events read in total (261ms).

[08:52:59.486] <TB3>     INFO: Test took 1484ms.

[08:52:59.488] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:52:59.994] <TB3>     INFO: Expecting 2560 events.

[08:53:00.956] <TB3>     INFO: 2560 events read in total (247ms).

[08:53:00.956] <TB3>     INFO: Test took 1469ms.

[08:53:00.959] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:01.465] <TB3>     INFO: Expecting 2560 events.

[08:53:02.427] <TB3>     INFO: 2560 events read in total (247ms).

[08:53:02.427] <TB3>     INFO: Test took 1468ms.

[08:53:02.430] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:02.936] <TB3>     INFO: Expecting 2560 events.

[08:53:03.898] <TB3>     INFO: 2560 events read in total (247ms).

[08:53:03.899] <TB3>     INFO: Test took 1470ms.

[08:53:03.902] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:04.407] <TB3>     INFO: Expecting 2560 events.

[08:53:05.370] <TB3>     INFO: 2560 events read in total (248ms).

[08:53:05.370] <TB3>     INFO: Test took 1469ms.

[08:53:05.373] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:05.880] <TB3>     INFO: Expecting 2560 events.

[08:53:06.842] <TB3>     INFO: 2560 events read in total (247ms).

[08:53:06.842] <TB3>     INFO: Test took 1469ms.

[08:53:06.845] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:07.351] <TB3>     INFO: Expecting 2560 events.

[08:53:08.313] <TB3>     INFO: 2560 events read in total (246ms).

[08:53:08.314] <TB3>     INFO: Test took 1470ms.

[08:53:08.316] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:08.823] <TB3>     INFO: Expecting 2560 events.

[08:53:09.785] <TB3>     INFO: 2560 events read in total (248ms).

[08:53:09.785] <TB3>     INFO: Test took 1469ms.

[08:53:09.788] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:10.294] <TB3>     INFO: Expecting 2560 events.

[08:53:11.257] <TB3>     INFO: 2560 events read in total (248ms).

[08:53:11.257] <TB3>     INFO: Test took 1469ms.

[08:53:11.259] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:11.766] <TB3>     INFO: Expecting 2560 events.

[08:53:12.727] <TB3>     INFO: 2560 events read in total (246ms).

[08:53:12.727] <TB3>     INFO: Test took 1468ms.

[08:53:12.730] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:13.236] <TB3>     INFO: Expecting 2560 events.

[08:53:14.211] <TB3>     INFO: 2560 events read in total (260ms).

[08:53:14.211] <TB3>     INFO: Test took 1482ms.

[08:53:14.213] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:14.720] <TB3>     INFO: Expecting 2560 events.

[08:53:15.682] <TB3>     INFO: 2560 events read in total (247ms).

[08:53:15.682] <TB3>     INFO: Test took 1469ms.

[08:53:15.684] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:16.192] <TB3>     INFO: Expecting 2560 events.

[08:53:17.154] <TB3>     INFO: 2560 events read in total (248ms).

[08:53:17.154] <TB3>     INFO: Test took 1470ms.

[08:53:17.157] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:17.663] <TB3>     INFO: Expecting 2560 events.

[08:53:18.625] <TB3>     INFO: 2560 events read in total (247ms).

[08:53:18.625] <TB3>     INFO: Test took 1469ms.

[08:53:18.627] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:19.134] <TB3>     INFO: Expecting 2560 events.

[08:53:20.093] <TB3>     INFO: 2560 events read in total (245ms).

[08:53:20.094] <TB3>     INFO: Test took 1467ms.

[08:53:20.096] <TB3>     INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.

[08:53:20.602] <TB3>     INFO: Expecting 2560 events.

[08:53:21.579] <TB3>     INFO: 2560 events read in total (262ms).

[08:53:21.579] <TB3>     INFO: Test took 1483ms.

[08:53:22.299] <TB3>     INFO: PixTestPhOptimization::doTest() done, duration: 755 seconds

[08:53:22.299] <TB3>     INFO: PH scale (per ROC):    78  82  86  76  80  79  83  85  77  76  80  69  79  80  81  86

[08:53:22.299] <TB3>     INFO: PH offset (per ROC):  150 167 150 165 154 168 147 148 156 158 163 161 163 157 141 150

[08:53:22.306] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:53:22.306] <TB3>     INFO: Decoding statistics:

[08:53:22.306] <TB3>     INFO:   General information:

[08:53:22.306] <TB3>     INFO: 	 16bit words read:         66450

[08:53:22.306] <TB3>     INFO: 	 valid events total:       5120

[08:53:22.306] <TB3>     INFO: 	 empty events:             2615

[08:53:22.306] <TB3>     INFO: 	 valid events with pixels: 2505

[08:53:22.306] <TB3>     INFO: 	 valid pixel hits:         2505

[08:53:22.306] <TB3>     INFO:   Event errors: 	           0

[08:53:22.307] <TB3>     INFO: 	 start marker:             0

[08:53:22.307] <TB3>     INFO: 	 stop marker:              0

[08:53:22.307] <TB3>     INFO: 	 overflow:                 0

[08:53:22.307] <TB3>     INFO: 	 invalid 5bit words:       0

[08:53:22.307] <TB3>     INFO: 	 invalid XOR eye diagram:  0

[08:53:22.307] <TB3>     INFO:   TBM errors: 		           0

[08:53:22.307] <TB3>     INFO: 	 flawed TBM headers:       0

[08:53:22.307] <TB3>     INFO: 	 flawed TBM trailers:      0

[08:53:22.307] <TB3>     INFO: 	 event ID mismatches:      0

[08:53:22.307] <TB3>     INFO:   ROC errors: 		           0

[08:53:22.307] <TB3>     INFO: 	 missing ROC header(s):    0

[08:53:22.307] <TB3>     INFO: 	 misplaced readback start: 0

[08:53:22.307] <TB3>     INFO:   Pixel decoding errors:	   0

[08:53:22.307] <TB3>     INFO: 	 pixel data incomplete:    0

[08:53:22.307] <TB3>     INFO: 	 pixel address:            0

[08:53:22.307] <TB3>     INFO: 	 pulse height fill bit:    0

[08:53:22.307] <TB3>     INFO: 	 buffer corruption:        0

[08:53:22.479] <TB3>     INFO: ######################################################################

[08:53:22.479] <TB3>     INFO: PixTestGainPedestal::fullTest() ntrig = 10

[08:53:22.479] <TB3>     INFO: ######################################################################

[08:53:22.489] <TB3>     INFO: scanning low vcal = 10

[08:53:22.793] <TB3>     INFO: Expecting 41600 events.

[08:53:26.663] <TB3>     INFO: 41600 events read in total (3155ms).

[08:53:26.663] <TB3>     INFO: Test took 4174ms.

[08:53:26.665] <TB3>     INFO: scanning low vcal = 20

[08:53:27.170] <TB3>     INFO: Expecting 41600 events.

[08:53:30.974] <TB3>     INFO: 41600 events read in total (3089ms).

[08:53:30.975] <TB3>     INFO: Test took 4310ms.

[08:53:30.977] <TB3>     INFO: scanning low vcal = 30

[08:53:31.483] <TB3>     INFO: Expecting 41600 events.

[08:53:35.353] <TB3>     INFO: 41600 events read in total (3155ms).

[08:53:35.354] <TB3>     INFO: Test took 4377ms.

[08:53:35.356] <TB3>     INFO: scanning low vcal = 40

[08:53:35.850] <TB3>     INFO: Expecting 41600 events.

[08:53:40.147] <TB3>     INFO: 41600 events read in total (3582ms).

[08:53:40.148] <TB3>     INFO: Test took 4792ms.

[08:53:40.151] <TB3>     INFO: scanning low vcal = 50

[08:53:40.576] <TB3>     INFO: Expecting 41600 events.

[08:53:44.998] <TB3>     INFO: 41600 events read in total (3708ms).

[08:53:44.998] <TB3>     INFO: Test took 4847ms.

[08:53:45.001] <TB3>     INFO: scanning low vcal = 60

[08:53:45.444] <TB3>     INFO: Expecting 41600 events.

[08:53:49.939] <TB3>     INFO: 41600 events read in total (3780ms).

[08:53:49.939] <TB3>     INFO: Test took 4938ms.

[08:53:49.941] <TB3>     INFO: scanning low vcal = 70

[08:53:50.387] <TB3>     INFO: Expecting 41600 events.

[08:53:54.795] <TB3>     INFO: 41600 events read in total (3693ms).

[08:53:54.796] <TB3>     INFO: Test took 4855ms.

[08:53:54.798] <TB3>     INFO: scanning low vcal = 80

[08:53:55.247] <TB3>     INFO: Expecting 41600 events.

[08:53:59.638] <TB3>     INFO: 41600 events read in total (3676ms).

[08:53:59.638] <TB3>     INFO: Test took 4840ms.

[08:53:59.641] <TB3>     INFO: scanning low vcal = 90

[08:54:00.079] <TB3>     INFO: Expecting 41600 events.

[08:54:04.460] <TB3>     INFO: 41600 events read in total (3666ms).

[08:54:04.460] <TB3>     INFO: Test took 4819ms.

[08:54:04.463] <TB3>     INFO: scanning low vcal = 100

[08:54:04.909] <TB3>     INFO: Expecting 41600 events.

[08:54:09.415] <TB3>     INFO: 41600 events read in total (3791ms).

[08:54:09.415] <TB3>     INFO: Test took 4952ms.

[08:54:09.418] <TB3>     INFO: scanning low vcal = 110

[08:54:09.835] <TB3>     INFO: Expecting 41600 events.

[08:54:14.193] <TB3>     INFO: 41600 events read in total (3643ms).

[08:54:14.194] <TB3>     INFO: Test took 4776ms.

[08:54:14.196] <TB3>     INFO: scanning low vcal = 120

[08:54:14.647] <TB3>     INFO: Expecting 41600 events.

[08:54:19.004] <TB3>     INFO: 41600 events read in total (3642ms).

[08:54:19.004] <TB3>     INFO: Test took 4808ms.

[08:54:19.007] <TB3>     INFO: scanning low vcal = 130

[08:54:19.453] <TB3>     INFO: Expecting 41600 events.

[08:54:23.913] <TB3>     INFO: 41600 events read in total (3745ms).

[08:54:23.913] <TB3>     INFO: Test took 4906ms.

[08:54:23.916] <TB3>     INFO: scanning low vcal = 140

[08:54:24.332] <TB3>     INFO: Expecting 41600 events.

[08:54:28.780] <TB3>     INFO: 41600 events read in total (3733ms).

[08:54:28.781] <TB3>     INFO: Test took 4865ms.

[08:54:28.783] <TB3>     INFO: scanning low vcal = 150

[08:54:29.227] <TB3>     INFO: Expecting 41600 events.

[08:54:33.729] <TB3>     INFO: 41600 events read in total (3787ms).

[08:54:33.730] <TB3>     INFO: Test took 4947ms.

[08:54:33.732] <TB3>     INFO: scanning low vcal = 160

[08:54:34.176] <TB3>     INFO: Expecting 41600 events.

[08:54:38.590] <TB3>     INFO: 41600 events read in total (3699ms).

[08:54:38.590] <TB3>     INFO: Test took 4858ms.

[08:54:38.592] <TB3>     INFO: scanning low vcal = 170

[08:54:39.039] <TB3>     INFO: Expecting 41600 events.

[08:54:43.529] <TB3>     INFO: 41600 events read in total (3775ms).

[08:54:43.529] <TB3>     INFO: Test took 4937ms.

[08:54:43.533] <TB3>     INFO: scanning low vcal = 180

[08:54:43.976] <TB3>     INFO: Expecting 41600 events.

[08:54:48.369] <TB3>     INFO: 41600 events read in total (3678ms).

[08:54:48.369] <TB3>     INFO: Test took 4836ms.

[08:54:48.372] <TB3>     INFO: scanning low vcal = 190

[08:54:48.819] <TB3>     INFO: Expecting 41600 events.

[08:54:53.188] <TB3>     INFO: 41600 events read in total (3654ms).

[08:54:53.189] <TB3>     INFO: Test took 4817ms.

[08:54:53.191] <TB3>     INFO: scanning low vcal = 200

[08:54:53.637] <TB3>     INFO: Expecting 41600 events.

[08:54:58.076] <TB3>     INFO: 41600 events read in total (3725ms).

[08:54:58.076] <TB3>     INFO: Test took 4885ms.

[08:54:58.079] <TB3>     INFO: scanning low vcal = 210

[08:54:58.518] <TB3>     INFO: Expecting 41600 events.

[08:55:02.988] <TB3>     INFO: 41600 events read in total (3756ms).

[08:55:02.988] <TB3>     INFO: Test took 4909ms.

[08:55:02.991] <TB3>     INFO: scanning low vcal = 220

[08:55:03.436] <TB3>     INFO: Expecting 41600 events.

[08:55:07.832] <TB3>     INFO: 41600 events read in total (3681ms).

[08:55:07.833] <TB3>     INFO: Test took 4842ms.

[08:55:07.836] <TB3>     INFO: scanning low vcal = 230

[08:55:08.285] <TB3>     INFO: Expecting 41600 events.

[08:55:12.798] <TB3>     INFO: 41600 events read in total (3798ms).

[08:55:12.799] <TB3>     INFO: Test took 4963ms.

[08:55:12.801] <TB3>     INFO: scanning low vcal = 240

[08:55:13.245] <TB3>     INFO: Expecting 41600 events.

[08:55:17.595] <TB3>     INFO: 41600 events read in total (3635ms).

[08:55:17.596] <TB3>     INFO: Test took 4795ms.

[08:55:17.599] <TB3>     INFO: scanning low vcal = 250

[08:55:18.029] <TB3>     INFO: Expecting 41600 events.

[08:55:22.450] <TB3>     INFO: 41600 events read in total (3706ms).

[08:55:22.451] <TB3>     INFO: Test took 4852ms.

[08:55:22.454] <TB3>     INFO: scanning high vcal = 30 (= 210 in low range)

[08:55:22.896] <TB3>     INFO: Expecting 41600 events.

[08:55:27.323] <TB3>     INFO: 41600 events read in total (3713ms).

[08:55:27.324] <TB3>     INFO: Test took 4870ms.

[08:55:27.327] <TB3>     INFO: scanning high vcal = 50 (= 350 in low range)

[08:55:27.772] <TB3>     INFO: Expecting 41600 events.

[08:55:32.299] <TB3>     INFO: 41600 events read in total (3812ms).

[08:55:32.300] <TB3>     INFO: Test took 4973ms.

[08:55:32.302] <TB3>     INFO: scanning high vcal = 70 (= 490 in low range)

[08:55:32.745] <TB3>     INFO: Expecting 41600 events.

[08:55:37.170] <TB3>     INFO: 41600 events read in total (3710ms).

[08:55:37.171] <TB3>     INFO: Test took 4869ms.

[08:55:37.173] <TB3>     INFO: scanning high vcal = 90 (= 630 in low range)

[08:55:37.588] <TB3>     INFO: Expecting 41600 events.

[08:55:42.050] <TB3>     INFO: 41600 events read in total (3747ms).

[08:55:42.051] <TB3>     INFO: Test took 4878ms.

[08:55:42.053] <TB3>     INFO: scanning high vcal = 200 (= 1400 in low range)

[08:55:42.497] <TB3>     INFO: Expecting 41600 events.

[08:55:46.934] <TB3>     INFO: 41600 events read in total (3722ms).

[08:55:46.935] <TB3>     INFO: Test took 4882ms.

[08:55:47.385] <TB3>     INFO: PixTestGainPedestal::measure() done 

[08:56:20.642] <TB3>     INFO: PixTestGainPedestal::fit() done

[08:56:20.642] <TB3>     INFO: non-linearity mean:  0.956 0.954 0.958 0.953 0.957 0.958 0.959 0.960 0.959 0.954 0.955 0.960 0.952 0.959 0.958 0.947

[08:56:20.642] <TB3>     INFO: non-linearity RMS:   0.006 0.006 0.005 0.007 0.004 0.006 0.006 0.006 0.006 0.005 0.005 0.005 0.007 0.005 0.005 0.006

[08:56:20.643] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat

[08:56:20.678] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat

[08:56:20.696] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat

[08:56:20.713] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat

[08:56:20.731] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat

[08:56:20.748] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat

[08:56:20.766] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat

[08:56:20.783] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat

[08:56:20.801] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat

[08:56:20.818] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat

[08:56:20.836] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat

[08:56:20.853] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat

[08:56:20.870] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat

[08:56:20.888] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat

[08:56:20.905] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat

[08:56:20.922] <TB3>     INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat

[08:56:20.939] <TB3>     INFO: PixTestGainPedestal::fullTest() done, duration: 178 seconds

[08:56:20.939] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:56:20.939] <TB3>     INFO: Decoding statistics:

[08:56:20.939] <TB3>     INFO:   General information:

[08:56:20.939] <TB3>     INFO: 	 16bit words read:         2329418

[08:56:20.939] <TB3>     INFO: 	 valid events total:       83200

[08:56:20.940] <TB3>     INFO: 	 empty events:             0

[08:56:20.940] <TB3>     INFO: 	 valid events with pixels: 83200

[08:56:20.940] <TB3>     INFO: 	 valid pixel hits:         665509

[08:56:20.940] <TB3>     INFO:   Event errors: 	           0

[08:56:20.940] <TB3>     INFO: 	 start marker:             0

[08:56:20.940] <TB3>     INFO: 	 stop marker:              0

[08:56:20.940] <TB3>     INFO: 	 overflow:                 0

[08:56:20.940] <TB3>     INFO: 	 invalid 5bit words:       0

[08:56:20.940] <TB3>     INFO: 	 invalid XOR eye diagram:  0

[08:56:20.940] <TB3>     INFO:   TBM errors: 		           0

[08:56:20.940] <TB3>     INFO: 	 flawed TBM headers:       0

[08:56:20.940] <TB3>     INFO: 	 flawed TBM trailers:      0

[08:56:20.940] <TB3>     INFO: 	 event ID mismatches:      0

[08:56:20.940] <TB3>     INFO:   ROC errors: 		           0

[08:56:20.940] <TB3>     INFO: 	 missing ROC header(s):    0

[08:56:20.940] <TB3>     INFO: 	 misplaced readback start: 0

[08:56:20.940] <TB3>     INFO:   Pixel decoding errors:	   0

[08:56:20.940] <TB3>     INFO: 	 pixel data incomplete:    0

[08:56:20.940] <TB3>     INFO: 	 pixel address:            0

[08:56:20.940] <TB3>     INFO: 	 pulse height fill bit:    0

[08:56:20.940] <TB3>     INFO: 	 buffer corruption:        0

[08:56:20.946] <TB3>     INFO: readReadbackCal: /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat

[08:56:20.948] <TB3>     INFO: ######################################################################

[08:56:20.948] <TB3>     INFO: PixTestReadback::doTest()

[08:56:20.948] <TB3>     INFO: ######################################################################

[08:56:20.949] <TB3>     INFO: PixTestReadback::RES sent once 

[08:56:32.190] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat

[08:56:32.190] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C1.dat

[08:56:32.190] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C2.dat

[08:56:32.190] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C3.dat

[08:56:32.190] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C4.dat

[08:56:32.190] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C5.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C6.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C7.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C8.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C9.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C10.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C11.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C12.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C13.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C14.dat

[08:56:32.191] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat

[08:56:32.216] <TB3>     INFO: PixTestPattern::       pg_setup set to default.

[08:56:32.217] <TB3>     INFO: PixTestReadback::RES sent once 

[08:56:43.369] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat

[08:56:43.370] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C1.dat

[08:56:43.371] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C2.dat

[08:56:43.371] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C3.dat

[08:56:43.371] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C4.dat

[08:56:43.371] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C5.dat

[08:56:43.371] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C6.dat

[08:56:43.371] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C7.dat

[08:56:43.371] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C8.dat

[08:56:43.372] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C9.dat

[08:56:43.372] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C10.dat

[08:56:43.372] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C11.dat

[08:56:43.372] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C12.dat

[08:56:43.372] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C13.dat

[08:56:43.372] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C14.dat

[08:56:43.372] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat

[08:56:43.400] <TB3>     INFO: PixTestPattern::       pg_setup set to default.

[08:56:43.401] <TB3>     INFO: PixTestReadback::RES sent once 

[08:56:51.977] <TB3>     INFO: PixTestPattern::       pg_setup set to default.

[08:56:51.977] <TB3>     INFO: Vbg will be calibrated using Vd calibration

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 147.3calibrated Vbg = 1.22386 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.7calibrated Vbg = 1.23147 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.3calibrated Vbg = 1.22629 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155calibrated Vbg = 1.23643 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.1calibrated Vbg = 1.23845 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 160calibrated Vbg = 1.25049 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 146.9calibrated Vbg = 1.23893 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148.2calibrated Vbg = 1.24435 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 157.9calibrated Vbg = 1.24693 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 157.1calibrated Vbg = 1.2443 :::*/*/*/*/

[08:56:51.977] <TB3>     INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.4calibrated Vbg = 1.24724 :::*/*/*/*/

[08:56:51.978] <TB3>     INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 155calibrated Vbg = 1.24281 :::*/*/*/*/

[08:56:51.978] <TB3>     INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.1calibrated Vbg = 1.23171 :::*/*/*/*/

[08:56:51.978] <TB3>     INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.1calibrated Vbg = 1.2252 :::*/*/*/*/

[08:56:51.978] <TB3>     INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 161.7calibrated Vbg = 1.22377 :::*/*/*/*/

[08:56:51.978] <TB3>     INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.4calibrated Vbg = 1.23047 :::*/*/*/*/

[08:56:51.981] <TB3>     INFO: PixTestReadback::RES sent once 

[08:59:45.823] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C0.dat

[08:59:45.823] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C1.dat

[08:59:45.823] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C2.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C3.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C4.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C5.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C6.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C7.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C8.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C9.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C10.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C11.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C12.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C13.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C14.dat

[08:59:45.824] <TB3>     INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4570_FullQualification_2015-11-20_08h13m_1448003617//000_FulltestPxar_m20//readbackCal_C15.dat

[08:59:45.848] <TB3>     INFO: PixTestPattern::       pg_setup set to default.

[08:59:45.849] <TB3>     INFO: PixTestReadback::doTest() done

[08:59:45.849] <TB3>     INFO: Fetched DAQ statistics. Counters are being reset now.

[08:59:45.849] <TB3>     INFO: Decoding statistics:

[08:59:45.849] <TB3>     INFO:   General information:

[08:59:45.849] <TB3>     INFO: 	 16bit words read:         768

[08:59:45.849] <TB3>     INFO: 	 valid events total:       64

[08:59:45.849] <TB3>     INFO: 	 empty events:             64

[08:59:45.849] <TB3>     INFO: 	 valid events with pixels: 0

[08:59:45.849] <TB3>     INFO: 	 valid pixel hits:         0

[08:59:45.849] <TB3>     INFO:   Event errors: 	           0

[08:59:45.849] <TB3>     INFO: 	 start marker:             0

[08:59:45.849] <TB3>     INFO: 	 stop marker:              0

[08:59:45.849] <TB3>     INFO: 	 overflow:                 0

[08:59:45.849] <TB3>     INFO: 	 invalid 5bit words:       0

[08:59:45.849] <TB3>     INFO: 	 invalid XOR eye diagram:  0

[08:59:45.849] <TB3>     INFO:   TBM errors: 		           0

[08:59:45.849] <TB3>     INFO: 	 flawed TBM headers:       0

[08:59:45.849] <TB3>     INFO: 	 flawed TBM trailers:      0

[08:59:45.849] <TB3>     INFO: 	 event ID mismatches:      0

[08:59:45.849] <TB3>     INFO:   ROC errors: 		           0

[08:59:45.849] <TB3>     INFO: 	 missing ROC header(s):    0

[08:59:45.849] <TB3>     INFO: 	 misplaced readback start: 0

[08:59:45.849] <TB3>     INFO:   Pixel decoding errors:	   0

[08:59:45.849] <TB3>     INFO: 	 pixel data incomplete:    0

[08:59:45.850] <TB3>     INFO: 	 pixel address:            0

[08:59:45.850] <TB3>     INFO: 	 pulse height fill bit:    0

[08:59:45.850] <TB3>     INFO: 	 buffer corruption:        0

[08:59:45.862] <TB3>     INFO: Decoding statistics:

[08:59:45.862] <TB3>     INFO:   General information:

[08:59:45.862] <TB3>     INFO: 	 16bit words read:         2396636

[08:59:45.862] <TB3>     INFO: 	 valid events total:       88384

[08:59:45.862] <TB3>     INFO: 	 empty events:             2679

[08:59:45.862] <TB3>     INFO: 	 valid events with pixels: 85705

[08:59:45.862] <TB3>     INFO: 	 valid pixel hits:         668014

[08:59:45.862] <TB3>     INFO:   Event errors: 	           0

[08:59:45.862] <TB3>     INFO: 	 start marker:             0

[08:59:45.863] <TB3>     INFO: 	 stop marker:              0

[08:59:45.863] <TB3>     INFO: 	 overflow:                 0

[08:59:45.863] <TB3>     INFO: 	 invalid 5bit words:       0

[08:59:45.863] <TB3>     INFO: 	 invalid XOR eye diagram:  0

[08:59:45.863] <TB3>     INFO:   TBM errors: 		           0

[08:59:45.863] <TB3>     INFO: 	 flawed TBM headers:       0

[08:59:45.863] <TB3>     INFO: 	 flawed TBM trailers:      0

[08:59:45.863] <TB3>     INFO: 	 event ID mismatches:      0

[08:59:45.863] <TB3>     INFO:   ROC errors: 		           0

[08:59:45.863] <TB3>     INFO: 	 missing ROC header(s):    0

[08:59:45.863] <TB3>     INFO: 	 misplaced readback start: 0

[08:59:45.863] <TB3>     INFO:   Pixel decoding errors:	   0

[08:59:45.863] <TB3>     INFO: 	 pixel data incomplete:    0

[08:59:45.863] <TB3>     INFO: 	 pixel address:            0

[08:59:45.863] <TB3>     INFO: 	 pulse height fill bit:    0

[08:59:45.863] <TB3>     INFO: 	 buffer corruption:        0

[08:59:45.863] <TB3>     INFO: enter test to run

[08:59:45.863] <TB3>     INFO:   test: exit no parameter change

[08:59:46.134] <TB3>    QUIET: Connection to board 137 closed.

[08:59:46.222] <TB3>     INFO: pXar: this is the end, my friend
MoReWeb-v0.6.7-73-g24fed82 on branch dev-v0.7.0